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-4.x-Windows (32bit/jdk1.6.0_41) - Build # 2675 - Failure!
Date Sat, 30 Mar 2013 19:34:10 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/2675/
Java: 32bit/jdk1.6.0_41 -client -XX:+UseConcMarkSweepGC

4 tests failed.
REGRESSION:  org.apache.solr.cloud.OverseerTest.testShardAssignmentBigger

Error Message:
KeeperErrorCode = NodeExists for /live_nodes/node67

Stack Trace:
org.apache.zookeeper.KeeperException$NodeExistsException: KeeperErrorCode = NodeExists for /live_nodes/node67
	at __randomizedtesting.SeedInfo.seed([F7657DE02D9E5CB5:648B839BC7F7A3D6]:0)
	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:407)
	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
	at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:404)
	at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:361)
	at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:348)
	at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:325)
	at org.apache.solr.cloud.OverseerTest$MockZKController.<init>(OverseerTest.java:79)
	at org.apache.solr.cloud.OverseerTest.testShardAssignmentBigger(OverseerTest.java:263)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	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:70)
	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:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	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 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:70)
	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:358)
	at java.lang.Thread.run(Thread.java:662)


FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.OverseerTest

Error Message:
ERROR: SolrZkClient opens=121 closes=120

Stack Trace:
java.lang.AssertionError: ERROR: SolrZkClient opens=121 closes=120
	at __randomizedtesting.SeedInfo.seed([F7657DE02D9E5CB5]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.SolrTestCaseJ4.endTrackingZkClients(SolrTestCaseJ4.java:266)
	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:101)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:700)
	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 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:70)
	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:358)
	at java.lang.Thread.run(Thread.java:662)


FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.OverseerTest

Error Message:
2 threads leaked from SUITE scope at org.apache.solr.cloud.OverseerTest:     1) Thread[id=4703, name=TEST-OverseerTest.testShardAssignmentBigger-seed#[F7657DE02D9E5CB5]-SendThread(127.0.0.1:61113), state=RUNNABLE, group=TGRP-OverseerTest]         at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0(Native Method)         at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll(WindowsSelectorImpl.java:273)         at sun.nio.ch.WindowsSelectorImpl$SubSelector.access$400(WindowsSelectorImpl.java:255)         at sun.nio.ch.WindowsSelectorImpl.doSelect(WindowsSelectorImpl.java:136)         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)         at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:338)         at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1068)    2) Thread[id=4704, name=TEST-OverseerTest.testShardAssignmentBigger-seed#[F7657DE02D9E5CB5]-EventThread, state=WAITING, group=TGRP-OverseerTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1987)         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:399)         at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:491)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE scope at org.apache.solr.cloud.OverseerTest: 
   1) Thread[id=4703, name=TEST-OverseerTest.testShardAssignmentBigger-seed#[F7657DE02D9E5CB5]-SendThread(127.0.0.1:61113), state=RUNNABLE, group=TGRP-OverseerTest]
        at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0(Native Method)
        at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll(WindowsSelectorImpl.java:273)
        at sun.nio.ch.WindowsSelectorImpl$SubSelector.access$400(WindowsSelectorImpl.java:255)
        at sun.nio.ch.WindowsSelectorImpl.doSelect(WindowsSelectorImpl.java:136)
        at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
        at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
        at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:338)
        at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1068)
   2) Thread[id=4704, name=TEST-OverseerTest.testShardAssignmentBigger-seed#[F7657DE02D9E5CB5]-EventThread, state=WAITING, group=TGRP-OverseerTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1987)
        at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:399)
        at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:491)
	at __randomizedtesting.SeedInfo.seed([F7657DE02D9E5CB5]:0)


FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.OverseerTest

Error Message:
There are still zombie threads that couldn't be terminated:    1) Thread[id=4703, name=TEST-OverseerTest.testShardAssignmentBigger-seed#[F7657DE02D9E5CB5]-SendThread(127.0.0.1:61113), state=TIMED_WAITING, group=TGRP-OverseerTest]         at java.lang.Thread.sleep(Native Method)         at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:86)         at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:937)         at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:993)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
   1) Thread[id=4703, name=TEST-OverseerTest.testShardAssignmentBigger-seed#[F7657DE02D9E5CB5]-SendThread(127.0.0.1:61113), state=TIMED_WAITING, group=TGRP-OverseerTest]
        at java.lang.Thread.sleep(Native Method)
        at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:86)
        at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:937)
        at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:993)
	at __randomizedtesting.SeedInfo.seed([F7657DE02D9E5CB5]:0)




Build Log:
[...truncated 8768 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.OverseerTest
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:47.894; org.apache.solr.SolrTestCaseJ4; ####initCore
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:47.896; org.apache.solr.SolrTestCaseJ4; ####initCore end
[junit4:junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-OverseerTest-1364671907894
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:47.899; org.apache.solr.SolrTestCaseJ4; ###Starting testShardAssignment
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:47.900; org.apache.solr.cloud.ZkTestServer; STARTING ZK TEST SERVER
[junit4:junit4]   1> client port:0.0.0.0/0.0.0.0:0
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:47.900; org.apache.solr.cloud.ZkTestServer$ZKServerMain; Starting server
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:47.904; org.apache.zookeeper.server.ZooKeeperServer; tickTime set to 1000
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:47.904; org.apache.zookeeper.server.ZooKeeperServer; minSessionTimeout set to -1
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:47.904; org.apache.zookeeper.server.ZooKeeperServer; maxSessionTimeout set to -1
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:47.905; org.apache.zookeeper.server.NIOServerCnxnFactory; binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:47.906; org.apache.zookeeper.server.persistence.FileTxnSnapLog; Snapshotting: 0x0 to C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-OverseerTest-1364671907894\zookeeper\server1\data\version-2\snapshot.0
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.003; org.apache.solr.cloud.ZkTestServer; start zk server on port:61095
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.003; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61095 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1a70768
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.005; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.006; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61095. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.007; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61095, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.007; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61098
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.008; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61098
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.008; org.apache.zookeeper.server.persistence.FileTxnLog; Creating new log file: log.1
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.027; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8c8440000 with negotiated timeout 10000 for client /127.0.0.1:61098
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.027; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61095, sessionid = 0x13dbcc8c8440000, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.028; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@1a70768 name:ZooKeeperConnection Watcher:127.0.0.1:61095 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.029; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.031; org.apache.zookeeper.server.PrepRequestProcessor; Processed session termination for sessionid: 0x13dbcc8c8440000
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.033; org.apache.zookeeper.ZooKeeper; Session: 0x13dbcc8c8440000 closed
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.033; org.apache.zookeeper.ClientCnxn$EventThread; EventThread shut down
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.033; org.apache.zookeeper.server.NIOServerCnxn; Closed socket connection for client /127.0.0.1:61098 which had sessionid 0x13dbcc8c8440000
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.034; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61095 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@19a44bb
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.036; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.037; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61095. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.038; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61095, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.038; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61101
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.038; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61101
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.040; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8c8440001 with negotiated timeout 10000 for client /127.0.0.1:61101
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.040; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61095, sessionid = 0x13dbcc8c8440001, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.041; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@19a44bb name:ZooKeeperConnection Watcher:127.0.0.1:61095 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.041; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.041; org.apache.solr.common.cloud.SolrZkClient; makePath: /solr
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.047; org.apache.zookeeper.server.PrepRequestProcessor; Processed session termination for sessionid: 0x13dbcc8c8440001
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.049; org.apache.zookeeper.ZooKeeper; Session: 0x13dbcc8c8440001 closed
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.049; org.apache.zookeeper.ClientCnxn$EventThread; EventThread shut down
[junit4:junit4]   1> WARN  - 2013-03-30 19:31:48.049; org.apache.zookeeper.server.NIOServerCnxn; caught end of stream exception
[junit4:junit4]   1> EndOfStreamException: Unable to read additional data from client sessionid 0x13dbcc8c8440001, likely client has closed socket
[junit4:junit4]   1> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4:junit4]   1> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4:junit4]   1> 	at java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.050; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61095/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@134c377
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.051; org.apache.zookeeper.server.NIOServerCnxn; Closed socket connection for client /127.0.0.1:61101 which had sessionid 0x13dbcc8c8440001
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.052; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.055; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61095. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.055; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61095, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.056; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61104
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.057; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61104
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.058; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8c8440002 with negotiated timeout 10000 for client /127.0.0.1:61104
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.058; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61095, sessionid = 0x13dbcc8c8440002, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.059; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@134c377 name:ZooKeeperConnection Watcher:127.0.0.1:61095/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.060; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.060; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.065; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61095/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@158c623
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.066; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.067; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61095. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.068; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61095, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.068; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61107
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.069; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61107
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.071; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8c8440003 with negotiated timeout 10000 for client /127.0.0.1:61107
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.071; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61095, sessionid = 0x13dbcc8c8440003, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.072; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@158c623 name:ZooKeeperConnection Watcher:127.0.0.1:61095/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.072; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.074; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer_elect/election
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.085; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440003 type:delete cxid:0x9 zxid:0xc txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.086; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer_elect/leader
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.091; org.apache.solr.cloud.Overseer; Overseer (id=89435138156658691-127.0.0.1:61095_solr-n_0000000000) starting
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.093; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440003 type:create cxid:0xf zxid:0xf txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.096; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer/queue
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.114; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440003 type:create cxid:0x15 zxid:0x11 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.117; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer/queue-work
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.123; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440003 type:create cxid:0x1b zxid:0x13 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.125; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer/collection-queue-work
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.131; org.apache.solr.cloud.OverseerCollectionProcessor; Process current queue of collection creations
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.131; org.apache.solr.common.cloud.SolrZkClient; makePath: /clusterstate.json
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.133; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Starting to work on the main queue
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.136; org.apache.solr.common.cloud.SolrZkClient; makePath: /aliases.json
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.141; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.145; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61095/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1fd7821
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.147; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.148; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61095. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.149; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61095, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.149; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61110
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.150; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61110
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.152; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8c8440004 with negotiated timeout 10000 for client /127.0.0.1:61110
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.152; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61095, sessionid = 0x13dbcc8c8440004, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.152; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@1fd7821 name:ZooKeeperConnection Watcher:127.0.0.1:61095/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.153; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.155; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.159; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/127.0.0.1
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.165; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (1)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.165; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (1)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:48.166; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440004 type:create cxid:0xc zxid:0x19 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:49.685; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:49.686; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=3 message={
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "numShards":"3",
[junit4:junit4]   1>   "state":"active",
[junit4:junit4]   1>   "core":"core1",
[junit4:junit4]   1>   "collection":"collection1",
[junit4:junit4]   1>   "node_name":"127.0.0.1",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1/solr/"}
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:49.686; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Create collection collection1 with numShards 3
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:49.687; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Assigning new node to shard shard=shard1
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:49.698; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:49.698; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:49.717; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:49.735; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440004 type:delete cxid:0x1e zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:49.737; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:49.745; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440004 type:create cxid:0x26 zxid:0x28 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:49.750; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440004 type:create cxid:0x29 zxid:0x2a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:51.252; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:51.259; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=3 message={
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "numShards":"3",
[junit4:junit4]   1>   "state":"active",
[junit4:junit4]   1>   "core":"core2",
[junit4:junit4]   1>   "collection":"collection1",
[junit4:junit4]   1>   "node_name":"127.0.0.1",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1/solr/"}
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:51.259; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Collection already exists with numShards=3
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:51.260; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Assigning new node to shard shard=shard2
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:51.267; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:51.267; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:51.300; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:51.310; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440004 type:delete cxid:0x38 zxid:0x36 txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard2
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:51.313; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:51.318; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440004 type:create cxid:0x3f zxid:0x38 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:51.323; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440004 type:create cxid:0x42 zxid:0x3a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:52.823; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:52.831; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=3 message={
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "numShards":"3",
[junit4:junit4]   1>   "state":"active",
[junit4:junit4]   1>   "core":"core3",
[junit4:junit4]   1>   "collection":"collection1",
[junit4:junit4]   1>   "node_name":"127.0.0.1",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1/solr/"}
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:52.831; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Collection already exists with numShards=3
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:52.831; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Assigning new node to shard shard=shard3
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:52.841; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:52.841; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:52.873; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/leader_elect/shard3/election
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:52.885; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440004 type:delete cxid:0x51 zxid:0x46 txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard3 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard3
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:52.887; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/leaders/shard3
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:52.893; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440004 type:create cxid:0x58 zxid:0x48 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:52.899; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440004 type:create cxid:0x5b zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:54.398; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:54.416; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=3 message={
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "numShards":"3",
[junit4:junit4]   1>   "state":"active",
[junit4:junit4]   1>   "core":"core4",
[junit4:junit4]   1>   "collection":"collection1",
[junit4:junit4]   1>   "node_name":"127.0.0.1",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1/solr/"}
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:54.416; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Collection already exists with numShards=3
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:54.416; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Assigning new node to shard shard=shard1
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:54.424; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:54.425; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:54.448; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:54.452; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440004 type:create cxid:0x65 zxid:0x53 txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leader_elect/shard1/election Error:KeeperErrorCode = NodeExists for /solr/collections/collection1/leader_elect/shard1/election
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:54.457; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440004 type:create cxid:0x69 zxid:0x55 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:55.980; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:55.981; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=3 message={
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "numShards":"3",
[junit4:junit4]   1>   "state":"active",
[junit4:junit4]   1>   "core":"core5",
[junit4:junit4]   1>   "collection":"collection1",
[junit4:junit4]   1>   "node_name":"127.0.0.1",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1/solr/"}
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:55.982; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Collection already exists with numShards=3
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:55.982; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Assigning new node to shard shard=shard2
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:55.993; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:55.993; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:56.007; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:56.011; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440004 type:create cxid:0x73 zxid:0x5b txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leader_elect/shard2/election Error:KeeperErrorCode = NodeExists for /solr/collections/collection1/leader_elect/shard2/election
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:56.019; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440004 type:create cxid:0x77 zxid:0x5d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.501; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.503; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=3 message={
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "numShards":"3",
[junit4:junit4]   1>   "state":"active",
[junit4:junit4]   1>   "core":"core6",
[junit4:junit4]   1>   "collection":"collection1",
[junit4:junit4]   1>   "node_name":"127.0.0.1",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1/solr/"}
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.504; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Collection already exists with numShards=3
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.505; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Assigning new node to shard shard=shard3
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.514; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.514; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.518; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/leader_elect/shard3/election
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.521; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8c8440004 type:create cxid:0x81 zxid:0x63 txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leader_elect/shard3/election Error:KeeperErrorCode = NodeExists for /solr/collections/collection1/leader_elect/shard3/election
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.526; org.apache.zookeeper.server.PrepRequestProcessor; Processed session termination for sessionid: 0x13dbcc8c8440002
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.528; org.apache.zookeeper.ZooKeeper; Session: 0x13dbcc8c8440002 closed
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.528; org.apache.zookeeper.ClientCnxn$EventThread; EventThread shut down
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.528; org.apache.zookeeper.server.NIOServerCnxn; Closed socket connection for client /127.0.0.1:61104 which had sessionid 0x13dbcc8c8440002
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.532; org.apache.zookeeper.server.PrepRequestProcessor; Processed session termination for sessionid: 0x13dbcc8c8440004
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.532; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (0)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.532; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 0)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.534; org.apache.zookeeper.ZooKeeper; Session: 0x13dbcc8c8440004 closed
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.534; org.apache.zookeeper.server.NIOServerCnxn; Closed socket connection for client /127.0.0.1:61110 which had sessionid 0x13dbcc8c8440004
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.535; org.apache.zookeeper.server.PrepRequestProcessor; Processed session termination for sessionid: 0x13dbcc8c8440003
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.537; org.apache.zookeeper.ZooKeeper; Session: 0x13dbcc8c8440003 closed
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.537; org.apache.zookeeper.ClientCnxn$EventThread; EventThread shut down
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.537; org.apache.zookeeper.server.ZooKeeperServer; shutting down
[junit4:junit4]   1> WARN  - 2013-03-30 19:31:57.537; org.apache.zookeeper.server.NIOServerCnxn; caught end of stream exception
[junit4:junit4]   1> EndOfStreamException: Unable to read additional data from client sessionid 0x13dbcc8c8440003, likely client has closed socket
[junit4:junit4]   1> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4:junit4]   1> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4:junit4]   1> 	at java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.539; org.apache.zookeeper.server.SessionTrackerImpl; Shutting down
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.539; org.apache.zookeeper.server.PrepRequestProcessor; Shutting down
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.539; org.apache.zookeeper.server.NIOServerCnxn; Closed socket connection for client /127.0.0.1:61107 which had sessionid 0x13dbcc8c8440003
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.539; org.apache.zookeeper.server.PrepRequestProcessor; PrepRequestProcessor exited loop!
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.539; org.apache.zookeeper.server.SyncRequestProcessor; Shutting down
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.540; org.apache.zookeeper.server.SyncRequestProcessor; SyncRequestProcessor exited!
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.540; org.apache.zookeeper.server.FinalRequestProcessor; shutdown of request processor complete
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.542; org.apache.solr.cloud.ZkTestServer; connecting to 127.0.0.1:61095 61095
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:57.933; org.apache.zookeeper.server.SessionTrackerImpl; SessionTrackerImpl exited loop!
[junit4:junit4]   1> WARN  - 2013-03-30 19:31:59.629; org.apache.zookeeper.server.NIOServerCnxnFactory; Ignoring exception
[junit4:junit4]   1> java.nio.channels.ClosedChannelException
[junit4:junit4]   1> 	at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:135)
[junit4:junit4]   1> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:188)
[junit4:junit4]   1> 	at java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.632; org.apache.zookeeper.server.NIOServerCnxnFactory; NIOServerCnxn factory exited run method
[junit4:junit4]   1> WARN  - 2013-03-30 19:31:58.909; org.apache.solr.cloud.Overseer$ClusterStateUpdater; 
[junit4:junit4]   1> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4:junit4]   1> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4:junit4]   1> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4:junit4]   1> 	at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1151)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:252)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:249)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:249)
[junit4:junit4]   1> 	at org.apache.solr.cloud.Overseer$ClusterStateUpdater.amILeader(Overseer.java:202)
[junit4:junit4]   1> 	at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:125)
[junit4:junit4]   1> 	at java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.633; org.apache.solr.cloud.Overseer$ClusterStateUpdater; According to ZK I (id=89435138156658691-127.0.0.1:61095_solr-n_0000000000) am no longer a leader.
[junit4:junit4]   1> WARN  - 2013-03-30 19:31:58.739; org.apache.solr.common.cloud.ZkStateReader$2; ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.633; org.apache.zookeeper.server.ZooKeeperServer; shutting down
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.635; org.apache.zookeeper.server.SessionTrackerImpl; Shutting down
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.636; org.apache.zookeeper.server.PrepRequestProcessor; Shutting down
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.636; org.apache.zookeeper.server.SyncRequestProcessor; Shutting down
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.636; org.apache.zookeeper.server.FinalRequestProcessor; shutdown of request processor complete
[junit4:junit4]   1> WARN  - 2013-03-30 19:31:59.635; org.apache.solr.cloud.LeaderElector$1; 
[junit4:junit4]   1> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   1> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4:junit4]   1> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4:junit4]   1> 	at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1468)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:235)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:232)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:232)
[junit4:junit4]   1> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:84)
[junit4:junit4]   1> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4:junit4]   1> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:129)
[junit4:junit4]   1> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4:junit4]   1> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.637; org.apache.solr.SolrTestCaseJ4; ###Ending testShardAssignment
[junit4:junit4]   1> WARN  - 2013-03-30 19:31:59.637; org.apache.solr.cloud.LeaderElector$1; 
[junit4:junit4]   1> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection1/leader_elect/shard3/election
[junit4:junit4]   1> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4:junit4]   1> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4:junit4]   1> 	at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1468)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:235)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:232)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:232)
[junit4:junit4]   1> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:84)
[junit4:junit4]   1> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4:junit4]   1> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:129)
[junit4:junit4]   1> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4:junit4]   1> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4:junit4]   1> WARN  - 2013-03-30 19:31:59.637; org.apache.solr.cloud.LeaderElector$1; 
[junit4:junit4]   1> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   1> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4:junit4]   1> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4:junit4]   1> 	at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1468)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:235)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:232)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:232)
[junit4:junit4]   1> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:84)
[junit4:junit4]   1> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4:junit4]   1> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:129)
[junit4:junit4]   1> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4:junit4]   1> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.639; org.apache.zookeeper.ClientCnxn$EventThread; EventThread shut down
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.642; org.apache.solr.SolrTestCaseJ4; ###Starting testShardAssignmentBigger
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.642; org.apache.solr.cloud.ZkTestServer; STARTING ZK TEST SERVER
[junit4:junit4]   1> client port:0.0.0.0/0.0.0.0:0
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.643; org.apache.solr.cloud.ZkTestServer$ZKServerMain; Starting server
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.643; org.apache.zookeeper.server.ZooKeeperServer; tickTime set to 1000
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.643; org.apache.zookeeper.server.ZooKeeperServer; minSessionTimeout set to -1
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.643; org.apache.zookeeper.server.ZooKeeperServer; maxSessionTimeout set to -1
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.645; org.apache.zookeeper.server.NIOServerCnxnFactory; binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.646; org.apache.zookeeper.server.persistence.FileSnap; Reading snapshot C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-OverseerTest-1364671907894\zookeeper\server1\data\version-2\snapshot.0
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.649; org.apache.zookeeper.server.persistence.FileTxnSnapLog; Snapshotting: 0x68 to C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-OverseerTest-1364671907894\zookeeper\server1\data\version-2\snapshot.68
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.736; org.apache.solr.cloud.ZkTestServer; start zk server on port:61113
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.736; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1beb5b9
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.739; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.740; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.741; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.741; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61116
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.741; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61116
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.742; org.apache.zookeeper.server.persistence.FileTxnLog; Creating new log file: log.69
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.747; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230000 with negotiated timeout 10000 for client /127.0.0.1:61116
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.747; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230000, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.748; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@1beb5b9 name:ZooKeeperConnection Watcher:127.0.0.1:61113 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.748; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.791; org.apache.zookeeper.server.PrepRequestProcessor; Processed session termination for sessionid: 0x13dbcc8f6230000
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.793; org.apache.zookeeper.ZooKeeper; Session: 0x13dbcc8f6230000 closed
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.793; org.apache.zookeeper.ClientCnxn$EventThread; EventThread shut down
[junit4:junit4]   1> WARN  - 2013-03-30 19:31:59.793; org.apache.zookeeper.server.NIOServerCnxn; caught end of stream exception
[junit4:junit4]   1> EndOfStreamException: Unable to read additional data from client sessionid 0x13dbcc8f6230000, likely client has closed socket
[junit4:junit4]   1> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4:junit4]   1> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4:junit4]   1> 	at java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.794; org.apache.zookeeper.server.NIOServerCnxn; Closed socket connection for client /127.0.0.1:61116 which had sessionid 0x13dbcc8f6230000
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.793; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1e4dd92
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.796; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.797; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.798; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.798; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61119
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.798; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61119
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.800; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230001 with negotiated timeout 10000 for client /127.0.0.1:61119
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.800; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230001, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.800; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@1e4dd92 name:ZooKeeperConnection Watcher:127.0.0.1:61113 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.800; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.800; org.apache.solr.common.cloud.SolrZkClient; makePath: /solr
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.804; org.apache.zookeeper.server.PrepRequestProcessor; Processed session termination for sessionid: 0x13dbcc8f6230001
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.806; org.apache.zookeeper.ZooKeeper; Session: 0x13dbcc8f6230001 closed
[junit4:junit4]   1> WARN  - 2013-03-30 19:31:59.806; org.apache.zookeeper.server.NIOServerCnxn; caught end of stream exception
[junit4:junit4]   1> EndOfStreamException: Unable to read additional data from client sessionid 0x13dbcc8f6230001, likely client has closed socket
[junit4:junit4]   1> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4:junit4]   1> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4:junit4]   1> 	at java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.806; org.apache.zookeeper.ClientCnxn$EventThread; EventThread shut down
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.806; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1908c8d
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.807; org.apache.zookeeper.server.NIOServerCnxn; Closed socket connection for client /127.0.0.1:61119 which had sessionid 0x13dbcc8f6230001
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.809; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.810; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.810; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.810; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61122
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.810; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61122
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.812; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230002 with negotiated timeout 10000 for client /127.0.0.1:61122
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.812; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230002, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.812; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@1908c8d name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.813; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.813; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.817; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@11a324d
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.818; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.824; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.824; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.825; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61125
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.825; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61125
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.827; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230003 with negotiated timeout 10000 for client /127.0.0.1:61125
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.827; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230003, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.828; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@11a324d name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.828; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.830; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer_elect/election
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.838; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8f6230003 type:delete cxid:0x9 zxid:0x88 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.838; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer_elect/leader
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.842; org.apache.solr.cloud.Overseer; Overseer (id=89435138926247939-127.0.0.1:61113_solr-n_0000000000) starting
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.845; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8f6230003 type:create cxid:0xf zxid:0x8b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.847; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer/queue
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.851; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8f6230003 type:create cxid:0x15 zxid:0x8d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.862; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer/queue-work
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.886; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dbcc8f6230003 type:create cxid:0x1b zxid:0x8f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.896; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer/collection-queue-work
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.915; org.apache.solr.cloud.OverseerCollectionProcessor; Process current queue of collection creations
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.916; org.apache.solr.common.cloud.SolrZkClient; makePath: /clusterstate.json
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.923; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Starting to work on the main queue
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.924; org.apache.solr.common.cloud.SolrZkClient; makePath: /aliases.json
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.938; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.944; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@7260d9
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.945; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.946; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.947; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.949; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61128
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.950; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61128
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.955; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230004 with negotiated timeout 10000 for client /127.0.0.1:61128
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.956; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230004, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.957; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@7260d9 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.957; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.961; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.966; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node0
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.972; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (1)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.973; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@d822a7
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.974; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (1)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.975; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.976; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.977; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.977; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61131
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.978; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61131
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.981; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230005 with negotiated timeout 10000 for client /127.0.0.1:61131
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.981; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230005, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.982; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@d822a7 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.982; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.985; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.992; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node1
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.999; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (2)
[junit4:junit4]   1> INFO  - 2013-03-30 19:31:59.999; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (2)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.000; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (2)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.001; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@16c2902
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.003; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.004; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.005; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.005; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61134
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.006; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61134
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.010; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230006 with negotiated timeout 10000 for client /127.0.0.1:61134
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.010; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230006, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.011; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@16c2902 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.011; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.014; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.018; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node2
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.023; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (3)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.024; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (3)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.024; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@154c126
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.025; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (3)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.025; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (3)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.026; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.027; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.028; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.028; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61137
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.030; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61137
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.031; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230007 with negotiated timeout 10000 for client /127.0.0.1:61137
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.031; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230007, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.032; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@154c126 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.032; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.035; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.038; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node3
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.046; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (4)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.047; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (4)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.047; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (4)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.047; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@21cd61
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.048; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (4)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.048; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (4)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.049; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.050; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.051; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.051; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61140
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.051; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61140
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.054; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230008 with negotiated timeout 10000 for client /127.0.0.1:61140
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.054; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230008, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.055; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@21cd61 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.056; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.058; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.061; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node4
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.066; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (5)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.066; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (5)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.067; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (5)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.068; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (5)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.068; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1be972c
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.068; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (5)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.069; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (5)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.069; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.070; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.072; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.072; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61143
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.072; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61143
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.074; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230009 with negotiated timeout 10000 for client /127.0.0.1:61143
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.074; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230009, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.075; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@1be972c name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.076; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.078; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.080; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node5
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.086; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (6)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.086; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (6)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.087; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (6)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.087; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (6)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.087; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (6)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.087; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (6)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.087; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@47e414
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.087; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (6)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.089; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.090; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.092; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.092; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61146
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.092; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61146
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.094; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f623000a with negotiated timeout 10000 for client /127.0.0.1:61146
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.094; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f623000a, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.095; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@47e414 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.095; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.097; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.100; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node6
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.106; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (7)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.107; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (7)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.107; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (7)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.107; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (7)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.107; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (7)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.107; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (7)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.108; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (7)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.108; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@19f8fe
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.108; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (7)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.110; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.111; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.113; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.113; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61149
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.113; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61149
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.116; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f623000b with negotiated timeout 10000 for client /127.0.0.1:61149
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.116; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f623000b, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.116; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@19f8fe name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.116; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.118; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.121; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node7
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.126; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (8)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.126; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (8)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.127; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (8)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.127; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (8)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.127; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (8)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.128; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@60217b
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.129; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (8)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.130; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (8)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.131; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (8)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.131; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (8)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.130; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.131; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.133; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.133; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61152
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.133; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61152
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.135; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f623000c with negotiated timeout 10000 for client /127.0.0.1:61152
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.135; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f623000c, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.135; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@60217b name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.136; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.139; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.142; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node8
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.147; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (9)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.149; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (9)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.149; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (9)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.149; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@3d839d
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.150; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (9)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.149; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (9)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.149; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (9)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.149; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (9)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.149; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (9)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.151; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.149; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (9)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.149; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (9)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.152; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.154; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.155; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61155
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.155; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61155
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.156; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f623000d with negotiated timeout 10000 for client /127.0.0.1:61155
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.156; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f623000d, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.157; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@3d839d name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.158; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.160; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.164; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node9
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.174; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@d5f605
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.174; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (10)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.175; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.176; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (10)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.176; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (10)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.176; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (10)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.176; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (10)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.176; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (10)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.177; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (10)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.178; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (10)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.178; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (10)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.178; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (10)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.179; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (10)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.180; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.180; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.181; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61158
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.181; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61158
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.183; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f623000e with negotiated timeout 10000 for client /127.0.0.1:61158
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.183; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f623000e, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.184; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@d5f605 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.184; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.187; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.192; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node10
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.200; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (11)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.200; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (11)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.201; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (11)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.201; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (11)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.201; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (11)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.202; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (11)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.202; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (11)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.202; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (11)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.202; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (11)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.202; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (11)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.202; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (11)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.202; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (11)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.202; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@13fc16
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.205; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.206; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.206; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.207; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61161
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.207; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61161
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.209; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f623000f with negotiated timeout 10000 for client /127.0.0.1:61161
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.209; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f623000f, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.210; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@13fc16 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.210; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.212; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.215; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node11
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.221; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (12)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.221; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (12)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.222; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (12)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.222; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (12)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.222; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (12)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.222; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (12)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.222; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (12)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.223; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (12)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.223; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (12)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.223; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (12)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.223; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (12)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.223; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (12)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.223; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (12)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.223; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1dc2fac
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.226; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.227; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.227; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.228; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61164
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.228; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61164
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.230; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230010 with negotiated timeout 10000 for client /127.0.0.1:61164
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.230; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230010, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.230; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@1dc2fac name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.231; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.231; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.234; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node12
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.239; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (13)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.239; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (13)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.241; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (13)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.241; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (13)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.241; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@2e89f4
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.242; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (13)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.242; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.243; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (13)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.243; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (13)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.243; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (13)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.244; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (13)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.244; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (13)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.244; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (13)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.244; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (13)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.244; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (13)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.244; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (13)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.245; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.246; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.247; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61167
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.247; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61167
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.249; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230011 with negotiated timeout 10000 for client /127.0.0.1:61167
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.249; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230011, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.250; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@2e89f4 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.250; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.251; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.254; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node13
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.259; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (14)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.259; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (14)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.267; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@127abe6
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.268; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.269; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (14)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.269; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (14)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.269; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (14)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.270; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (14)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.270; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (14)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.269; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (14)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.269; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (14)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.269; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (14)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.271; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.270; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (14)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.270; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (14)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.270; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (14)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.270; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (14)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.270; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (14)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.272; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61170
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.272; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.274; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61170
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.276; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230012 with negotiated timeout 10000 for client /127.0.0.1:61170
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.276; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230012, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.277; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@127abe6 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.277; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.279; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.281; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node14
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.286; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (15)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.287; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (15)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.288; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (15)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.288; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (15)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.289; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (15)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.289; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1ae34b6
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.289; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (15)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.289; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.290; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (15)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.290; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (15)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.290; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (15)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.290; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (15)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.291; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (15)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.291; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (15)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.291; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (15)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.291; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (15)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.291; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (15)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.291; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (15)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.292; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.293; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.294; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61173
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.294; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61173
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.296; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230013 with negotiated timeout 10000 for client /127.0.0.1:61173
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.296; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230013, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.297; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@1ae34b6 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.297; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.298; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.301; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node15
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.306; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (16)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.306; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (16)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.307; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (16)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.308; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (16)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.309; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (16)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.309; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@8ed70d
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.310; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.311; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (16)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.311; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (16)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.311; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (16)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.311; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (16)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.312; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (16)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.312; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (16)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.312; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (16)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.312; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (16)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.313; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (16)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.313; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (16)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.313; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (16)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.313; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (16)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.314; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.315; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.316; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61176
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.316; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61176
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.317; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230014 with negotiated timeout 10000 for client /127.0.0.1:61176
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.317; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230014, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.318; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@8ed70d name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.318; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.319; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.322; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node16
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.327; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.327; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.328; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.329; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1ee111f
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.330; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.330; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.331; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.331; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.331; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.331; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.331; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.332; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.332; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.332; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.332; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.333; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.333; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.333; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.333; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.334; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (17)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.334; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.336; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.336; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61179
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.336; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61179
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.338; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230015 with negotiated timeout 10000 for client /127.0.0.1:61179
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.338; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230015, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.338; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@1ee111f name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.339; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.340; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.343; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node17
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.349; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.350; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.350; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.350; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1b5fdb6
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.351; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.352; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.352; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.352; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.353; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.353; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.353; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.353; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.354; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.354; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.355; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.355; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.355; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.355; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.356; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.355; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.356; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.355; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.355; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (18)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.357; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61182
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.357; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61182
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.359; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230016 with negotiated timeout 10000 for client /127.0.0.1:61182
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.359; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230016, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.360; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@1b5fdb6 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.361; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.362; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.365; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node18
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.369; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.370; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.372; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1a02bcc
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.373; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.374; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.374; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.374; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.374; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.375; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.375; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.375; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.375; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.375; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.375; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.375; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.375; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.375; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.375; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.375; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.375; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.375; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.375; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (19)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.375; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.378; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.378; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61185
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.378; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61185
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.380; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230017 with negotiated timeout 10000 for client /127.0.0.1:61185
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.380; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230017, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.381; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@1a02bcc name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.382; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.383; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.385; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node19
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.390; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.390; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.390; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.394; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1fa1c01
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.394; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.394; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.395; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.395; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.395; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.395; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.395; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.396; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.396; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.397; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.397; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.397; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.398; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.398; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.398; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.398; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.398; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.398; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.398; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (20)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.399; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.401; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.401; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61188
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.401; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61188
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.403; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230018, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.403; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230018 with negotiated timeout 10000 for client /127.0.0.1:61188
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.404; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@1fa1c01 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.404; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.405; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.408; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node20
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.413; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.413; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.413; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.416; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@c8f27f
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.418; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.418; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.419; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.419; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.419; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.419; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.419; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.420; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.420; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.420; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.420; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.420; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.420; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.420; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.420; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.420; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.420; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.420; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.420; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.420; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (21)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.421; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.423; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.424; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61191
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.424; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61191
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.427; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f6230019 with negotiated timeout 10000 for client /127.0.0.1:61191
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.427; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f6230019, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.427; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@c8f27f name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.428; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.429; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.432; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node21
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.448; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.448; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.449; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.449; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.450; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.450; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.451; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.452; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.452; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.452; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.452; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.452; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.453; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.454; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.454; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.454; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.455; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.455; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.455; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.456; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.456; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.457; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.457; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (22)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.457; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@17aa83e
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.459; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.461; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.461; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.461; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61194
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.461; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61194
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.464; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f623001a, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.464; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f623001a with negotiated timeout 10000 for client /127.0.0.1:61194
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.465; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@17aa83e name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.465; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.467; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.471; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node22
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.476; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.476; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.479; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.479; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.479; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.479; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.480; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.480; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1030063
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.480; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.482; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.482; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.482; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.482; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.482; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.483; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.483; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.483; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.483; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.484; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.484; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.484; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.484; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.484; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.484; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.484; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.484; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (23)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.485; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.486; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.487; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61197
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.487; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61197
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.489; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f623001b with negotiated timeout 10000 for client /127.0.0.1:61197
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.489; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f623001b, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.490; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@1030063 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.498; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.499; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.501; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node23
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.508; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.509; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.511; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@14e2d1
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.512; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.512; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.513; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.512; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.512; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (24)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.514; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.519; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.519; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61200
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.519; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61200
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.521; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f623001c with negotiated timeout 10000 for client /127.0.0.1:61200
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.521; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f623001c, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.522; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@14e2d1 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.522; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.525; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.528; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node24
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.534; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.535; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.535; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.537; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.537; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.538; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.538; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.538; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.538; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.538; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.538; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.538; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.538; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.538; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.539; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.539; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.539; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.539; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.539; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.540; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.540; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1fb3d91
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.540; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.540; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.540; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.540; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.540; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.540; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (25)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.542; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.543; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.544; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.544; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61203
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.545; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61203
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.547; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f623001d with negotiated timeout 10000 for client /127.0.0.1:61203
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.547; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f623001d, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.547; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@1fb3d91 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.547; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.548; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.551; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node25
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.557; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.560; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.560; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.558; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.561; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.557; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.561; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.557; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.557; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.562; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.557; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.562; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.557; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.557; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@794da9
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.564; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.565; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.565; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.557; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.565; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.565; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.566; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.566; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.566; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.566; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.566; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.567; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.567; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.567; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.567; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (26)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.568; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.568; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.569; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61206
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.569; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61206
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.571; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f623001e with negotiated timeout 10000 for client /127.0.0.1:61206
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.571; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f623001e, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.572; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@794da9 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.572; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.574; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.576; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node26
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.583; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.583; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.586; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.586; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.586; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@136ce57
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.589; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.589; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.589; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.588; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (27)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.590; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server 127.0.0.1/127.0.0.1:61113. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.595; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to 127.0.0.1/127.0.0.1:61113, initiating session
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.596; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:61209
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.596; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:61209
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.598; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dbcc8f623001f with negotiated timeout 10000 for client /127.0.0.1:61209
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.598; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server 127.0.0.1/127.0.0.1:61113, sessionid = 0x13dbcc8f623001f, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.599; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@136ce57 name:ZooKeeperConnection Watcher:127.0.0.1:61113/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.599; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.601; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.604; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/node27
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.612; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.612; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.613; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.613; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:61113/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@30c2b9
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.614; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.614; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.615; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.615; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.615; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.615; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.616; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.616; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.616; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.616; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.618; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.618; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.618; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.618; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.619; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.619; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.619; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (28)
[junit4:junit4]   1> INFO  - 2013-03-30 19:32:00.619; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes...

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

sing socket connection and attempting reconnect
[junit4:junit4]   1> java.nio.channels.ClosedByInterruptException
[junit4:junit4]   1> 	at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:184)
[junit4:junit4]   1> 	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:635)
[junit4:junit4]   1> 	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350)
[junit4:junit4]   1> 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1068)
[junit4:junit4]   1> WARN  - 2013-03-30 19:33:23.601; org.apache.zookeeper.ClientCnxn$SendThread; Unexpected exception
[junit4:junit4]   1> java.lang.InterruptedException: sleep interrupted
[junit4:junit4]   1> 	at java.lang.Thread.sleep(Native Method)
[junit4:junit4]   1> 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:984)
[junit4:junit4]   2> 96374 T4535 ccr.ThreadLeakControl.tryToInterruptAll SEVERE There are still zombie threads that couldn't be terminated:
[junit4:junit4]   2> 	   1) Thread[id=4703, name=TEST-OverseerTest.testShardAssignmentBigger-seed#[F7657DE02D9E5CB5]-SendThread(127.0.0.1:61113), state=TIMED_WAITING, group=TGRP-OverseerTest]
[junit4:junit4]   2> 	        at java.lang.Thread.sleep(Native Method)
[junit4:junit4]   2> 	        at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:86)
[junit4:junit4]   2> 	        at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:937)
[junit4:junit4]   2> 	        at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:993)
[junit4:junit4]   2> NOTE: test params are: codec=Lucene3x, sim=DefaultSimilarity, locale=es_PY, timezone=Arctic/Longyearbyen
[junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Sun Microsystems Inc. 1.6.0_41 (32-bit)/cpus=2,threads=15,free=152433664,total=260427776
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestFieldTypeResource, TestZkChroot, TestDistributedSearch, ConvertedLegacyTest, TestFaceting, TestQuerySenderNoQuery, UpdateRequestProcessorFactoryTest, TestRangeQuery, DirectSolrConnectionTest, SimpleFacetsTest, TestLMDirichletSimilarityFactory, SolrRequestParserTest, TestSweetSpotSimilarityFactory, TestSearchPerf, TestFastLRUCache, LeaderElectionIntegrationTest, TestQueryTypes, BadCopyFieldTest, TestDocumentBuilder, TestWriterPerf, TestXIncludeConfig, TestJoin, TestArbitraryIndexDir, BadComponentTest, OverseerCollectionProcessorTest, TestFastWriter, BasicDistributedZk2Test, SuggesterTest, TimeZoneUtilsTest, AlternateDirectoryTest, TestCSVResponseWriter, DebugComponentTest, ZkControllerTest, SimplePostToolTest, TestSchemaVersionResource, TestCoreContainer, SuggesterWFSTTest, TestSolrQueryParser, TestRemoteStreaming, LegacyHTMLStripCharFilterTest, SampleTest, TestQuerySenderListener, TestSolrDiscoveryProperties, ShardRoutingTest, TestCollationKeyRangeQueries, TestLuceneMatchVersion, TestExtendedDismaxParser, ReturnFieldsTest, PingRequestHandlerTest, TestCSVLoader, SolrCoreTest, TestBinaryField, FastVectorHighlighterTest, HighlighterConfigTest, PluginInfoTest, CopyFieldTest, SolrIndexConfigTest, QueryResultKeyTest, RegexBoostProcessorTest, TestMergePolicyConfig, TestJmxIntegration, TestPHPSerializedResponseWriter, TestSchemaResource, TestStressLucene, AnalysisAfterCoreReloadTest, TestIBSimilarityFactory, RecoveryZkTest, TestUniqueKeyFieldResource, TestNumberUtils, XsltUpdateRequestHandlerTest, ResourceLoaderTest, RequiredFieldsTest, TestSolrQueryParserDefaultOperatorResource, TestPartialUpdateDeduplication, DistributedSpellCheckComponentTest, TestRealTimeGet, StatsComponentTest, TestPostingsSolrHighlighter, CacheHeaderTest, ShowFileRequestHandlerTest, DOMUtilTest, TestHashPartitioner, TestValueSourceCache, TestQueryUtils, SchemaVersionSpecificBehaviorTest, TestPhraseSuggestions, SolrCmdDistributorTest, TestElisionMultitermQuery, TestFuzzyAnalyzedSuggestions, TestComponentsName, SpellingQueryConverterTest, TestSort, TestIndexSearcher, CoreContainerCoreInitFailuresTest, DistributedQueryElevationComponentTest, TestPropInject, TestUtils, NumericFieldsTest, DocumentAnalysisRequestHandlerTest, DocumentBuilderTest, EchoParamsTest, NoCacheHeaderTest, ClusterStateUpdateTest, SyncSliceTest, ZkNodePropsTest, SliceStateTest, HardAutoCommitTest, LoggingHandlerTest, SpellCheckCollatorTest, TestFiltering, SolrInfoMBeanTest, TestSuggestSpellingConverter, HighlighterTest, RequestHandlersTest, FieldMutatingUpdateProcessorTest, TermVectorComponentTest, FieldAnalysisRequestHandlerTest, IndexSchemaRuntimeFieldTest, TestSolrCoreProperties, TestSurroundQueryParser, StandardRequestHandlerTest, TestStressVersions, LeaderElectionTest, TestShardHandlerFactory, UniqFieldsUpdateProcessorFactoryTest, SolrPluginUtilsTest, ShardRoutingCustomTest, TestSchemaSimilarityResource, FileUtilsTest, TestCollationField, StatelessScriptUpdateProcessorFactoryTest, SolrIndexSplitterTest, CSVRequestHandlerTest, DirectUpdateHandlerOptimizeTest, DistributedTermsComponentTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, OpenCloseCoreStressTest, OverseerTest]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=OverseerTest -Dtests.seed=F7657DE02D9E5CB5 -Dtests.slow=true -Dtests.locale=es_PY -Dtests.timezone=Arctic/Longyearbyen -Dtests.file.encoding=Cp1252
[junit4:junit4] ERROR   0.00s | OverseerTest (suite) <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: ERROR: SolrZkClient opens=121 closes=120
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([F7657DE02D9E5CB5]:0)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.endTrackingZkClients(SolrTestCaseJ4.java:266)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:101)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:662)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE scope at org.apache.solr.cloud.OverseerTest: 
[junit4:junit4]    >    1) Thread[id=4703, name=TEST-OverseerTest.testShardAssignmentBigger-seed#[F7657DE02D9E5CB5]-SendThread(127.0.0.1:61113), state=RUNNABLE, group=TGRP-OverseerTest]
[junit4:junit4]    >         at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0(Native Method)
[junit4:junit4]    >         at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll(WindowsSelectorImpl.java:273)
[junit4:junit4]    >         at sun.nio.ch.WindowsSelectorImpl$SubSelector.access$400(WindowsSelectorImpl.java:255)
[junit4:junit4]    >         at sun.nio.ch.WindowsSelectorImpl.doSelect(WindowsSelectorImpl.java:136)
[junit4:junit4]    >         at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
[junit4:junit4]    >         at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
[junit4:junit4]    >         at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:338)
[junit4:junit4]    >         at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1068)
[junit4:junit4]    >    2) Thread[id=4704, name=TEST-OverseerTest.testShardAssignmentBigger-seed#[F7657DE02D9E5CB5]-EventThread, state=WAITING, group=TGRP-OverseerTest]
[junit4:junit4]    >         at sun.misc.Unsafe.park(Native Method)
[junit4:junit4]    >         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)
[junit4:junit4]    >         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1987)
[junit4:junit4]    >         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:399)
[junit4:junit4]    >         at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:491)
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([F7657DE02D9E5CB5]:0)Throwable #3: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4:junit4]    >    1) Thread[id=4703, name=TEST-OverseerTest.testShardAssignmentBigger-seed#[F7657DE02D9E5CB5]-SendThread(127.0.0.1:61113), state=TIMED_WAITING, group=TGRP-OverseerTest]
[junit4:junit4]    >         at java.lang.Thread.sleep(Native Method)
[junit4:junit4]    >         at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:86)
[junit4:junit4]    >         at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:937)
[junit4:junit4]    >         at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:993)
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([F7657DE02D9E5CB5]:0)
[junit4:junit4] Completed in 96.45s, 8 tests, 1 failure, 3 errors <<< FAILURES!

[...truncated 415 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:381: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:361: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:183: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:448: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1234: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:878: There were test failures: 283 suites, 1167 tests, 3 suite-level errors, 1 error, 584 ignored (4 assumptions)

Total time: 57 minutes 14 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.6.0_41 -client -XX:+UseConcMarkSweepGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message