See <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/818/>
------------------------------------------ [...truncated 129695 lines...] [junit] 2010-05-09 10:45:10,096 - INFO [main:follo...@166] - shutdown called [junit] java.lang.Exception: shutdown Follower [junit] at org.apache.zookeeper.server.quorum.Follower.shutdown(Follower.java:166) [junit] at org.apache.zookeeper.server.quorum.QuorumPeer.shutdown(QuorumPeer.java:689) [junit] at org.apache.zookeeper.test.QuorumBase.shutdown(QuorumBase.java:293) [junit] at org.apache.zookeeper.test.ZkDatabaseCorruptionTest.testCorruption(ZkDatabaseCorruptionTest.java:127) [junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [junit] at java.lang.reflect.Method.invoke(Method.java:597) [junit] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44) [junit] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) [junit] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41) [junit] at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20) [junit] at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:51) [junit] at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28) [junit] at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31) [junit] at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:48) [junit] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76) [junit] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit] at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193) [junit] at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52) [junit] at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191) [junit] at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42) [junit] at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184) [junit] at org.junit.runners.ParentRunner.run(ParentRunner.java:236) [junit] at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:39) [junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:420) [junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:911) [junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:768) [junit] 2010-05-09 10:45:10,097 - INFO [main:finalrequestproces...@378] - shutdown of request processor complete [junit] 2010-05-09 10:45:10,097 - INFO [CommitProcessor:3:commitproces...@148] - CommitProcessor exited loop! [junit] 2010-05-09 10:45:10,097 - INFO [FollowerRequestProcessor:3:followerrequestproces...@93] - FollowerRequestProcessor exited loop! [junit] 2010-05-09 10:45:10,097 - INFO [SyncThread:3:syncrequestproces...@151] - SyncRequestProcessor exited! [junit] 2010-05-09 10:45:10,105 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11225:nioservercnxn$fact...@277] - NIOServerCnxn factory exited run method [junit] 2010-05-09 10:45:10,105 - ERROR [Thread-175:quorumcnxmanager$liste...@453] - Exception while listening [junit] java.nio.channels.AsynchronousCloseException [junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185) [junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152) [junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager$Listener.run(QuorumCnxManager.java:441) [junit] 2010-05-09 10:45:10,106 - INFO [main:quorumb...@296] - Shutting down leader election QuorumPeer:/0:0:0:0:0:0:0:0:11225 [junit] 2010-05-09 10:45:10,106 - INFO [main:quorumb...@301] - Waiting for QuorumPeer:/0:0:0:0:0:0:0:0:11225 to exit thread [junit] 2010-05-09 10:45:10,106 - INFO [Thread-175:quorumcnxmanager$liste...@457] - Leaving listener [junit] 2010-05-09 10:45:10,106 - WARN [Thread-194:quorumcnxmanager$recvwor...@658] - Connection broken: [junit] java.io.IOException: Channel eof [junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager$RecvWorker.run(QuorumCnxManager.java:630) [junit] 2010-05-09 10:45:10,105 - WARN [Thread-182:quorumcnxmanager$sendwor...@581] - Interrupted while waiting for message on queue [junit] java.lang.InterruptedException [junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:1899) [junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:1976) [junit] at java.util.concurrent.ArrayBlockingQueue.poll(ArrayBlockingQueue.java:342) [junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager$SendWorker.run(QuorumCnxManager.java:570) [junit] 2010-05-09 10:45:10,107 - WARN [Thread-176:quorumcnxmanager$sendwor...@581] - Interrupted while waiting for message on queue [junit] java.lang.InterruptedException [junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:1899) [junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:1976) [junit] at java.util.concurrent.ArrayBlockingQueue.poll(ArrayBlockingQueue.java:342) [junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager$SendWorker.run(QuorumCnxManager.java:570) [junit] 2010-05-09 10:45:10,106 - WARN [Thread-196:quorumcnxmanager$recvwor...@658] - Connection broken: [junit] java.nio.channels.AsynchronousCloseException [junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185) [junit] at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:263) [junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager$RecvWorker.run(QuorumCnxManager.java:629) [junit] 2010-05-09 10:45:10,106 - WARN [Thread-195:quorumcnxmanager$sendwor...@581] - Interrupted while waiting for message on queue [junit] java.lang.InterruptedException [junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:1899) [junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:1976) [junit] at java.util.concurrent.ArrayBlockingQueue.poll(ArrayBlockingQueue.java:342) [junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager$SendWorker.run(QuorumCnxManager.java:570) [junit] 2010-05-09 10:45:10,107 - WARN [Thread-176:quorumcnxmanager$sendwor...@589] - Send worker leaving thread [junit] 2010-05-09 10:45:10,107 - WARN [Thread-182:quorumcnxmanager$sendwor...@589] - Send worker leaving thread [junit] 2010-05-09 10:45:10,108 - WARN [Thread-195:quorumcnxmanager$sendwor...@589] - Send worker leaving thread [junit] 2010-05-09 10:45:11,095 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11225:follo...@166] - shutdown called [junit] java.lang.Exception: shutdown Follower [junit] at org.apache.zookeeper.server.quorum.Follower.shutdown(Follower.java:166) [junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:648) [junit] 2010-05-09 10:45:11,096 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11225:finalrequestproces...@378] - shutdown of request processor complete [junit] 2010-05-09 10:45:11,096 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11225:quorump...@672] - QuorumPeer main thread exited [junit] 2010-05-09 10:45:11,096 - INFO [main:quorumb...@292] - Shutting down quorum peer QuorumPeer:/0:0:0:0:0:0:0:0:11226 [junit] 2010-05-09 10:45:11,096 - INFO [main:lea...@390] - Shutdown called [junit] java.lang.Exception: shutdown Leader! reason: quorum Peer shutdown [junit] at org.apache.zookeeper.server.quorum.Leader.shutdown(Leader.java:390) [junit] at org.apache.zookeeper.server.quorum.QuorumPeer.shutdown(QuorumPeer.java:686) [junit] at org.apache.zookeeper.test.QuorumBase.shutdown(QuorumBase.java:293) [junit] at org.apache.zookeeper.test.ZkDatabaseCorruptionTest.testCorruption(ZkDatabaseCorruptionTest.java:128) [junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [junit] at java.lang.reflect.Method.invoke(Method.java:597) [junit] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44) [junit] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) [junit] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41) [junit] at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20) [junit] at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:51) [junit] at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28) [junit] at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:31) [junit] at org.junit.rules.TestWatchman$1.evaluate(TestWatchman.java:48) [junit] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:76) [junit] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit] at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193) [junit] at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52) [junit] at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191) [junit] at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42) [junit] at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184) [junit] at org.junit.runners.ParentRunner.run(ParentRunner.java:236) [junit] at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:39) [junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:420) [junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:911) [junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:768) [junit] 2010-05-09 10:45:11,097 - INFO [main:finalrequestproces...@378] - shutdown of request processor complete [junit] 2010-05-09 10:45:11,097 - INFO [CommitProcessor:4:commitproces...@148] - CommitProcessor exited loop! [junit] 2010-05-09 10:45:11,097 - INFO [Thread-198:leader$learnercnxaccep...@243] - exception while shutting down acceptor: java.net.SocketException: Socket closed [junit] 2010-05-09 10:45:11,097 - INFO [ProcessThread:-1:preprequestproces...@119] - PrepRequestProcessor exited loop! [junit] 2010-05-09 10:45:11,097 - INFO [SyncThread:4:syncrequestproces...@151] - SyncRequestProcessor exited! [junit] 2010-05-09 10:45:11,099 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11226:nioservercnxn$fact...@277] - NIOServerCnxn factory exited run method [junit] 2010-05-09 10:45:11,099 - ERROR [Thread-184:quorumcnxmanager$liste...@453] - Exception while listening [junit] java.nio.channels.AsynchronousCloseException [junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185) [junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152) [junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager$Listener.run(QuorumCnxManager.java:441) [junit] 2010-05-09 10:45:11,099 - WARN [LearnerHandler-/127.0.0.1:39005:learnerhand...@457] - ******* GOODBYE /127.0.0.1:39005 ******** [junit] 2010-05-09 10:45:11,100 - WARN [LearnerHandler-/127.0.0.1:39000:learnerhand...@457] - ******* GOODBYE /127.0.0.1:39000 ******** [junit] 2010-05-09 10:45:11,100 - WARN [Thread-185:quorumcnxmanager$sendwor...@581] - Interrupted while waiting for message on queue [junit] java.lang.InterruptedException [junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:1899) [junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:1976) [junit] at java.util.concurrent.ArrayBlockingQueue.poll(ArrayBlockingQueue.java:342) [junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager$SendWorker.run(QuorumCnxManager.java:570) [junit] 2010-05-09 10:45:11,099 - WARN [Thread-189:quorumcnxmanager$sendwor...@581] - Interrupted while waiting for message on queue [junit] java.lang.InterruptedException [junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:1899) [junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:1976) [junit] at java.util.concurrent.ArrayBlockingQueue.poll(ArrayBlockingQueue.java:342) [junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager$SendWorker.run(QuorumCnxManager.java:570) [junit] 2010-05-09 10:45:11,099 - WARN [Thread-193:quorumcnxmanager$sendwor...@581] - Interrupted while waiting for message on queue [junit] java.lang.InterruptedException [junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:1899) [junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:1976) [junit] at java.util.concurrent.ArrayBlockingQueue.poll(ArrayBlockingQueue.java:342) [junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager$SendWorker.run(QuorumCnxManager.java:570) [junit] 2010-05-09 10:45:11,099 - INFO [main:quorumb...@296] - Shutting down leader election QuorumPeer:/0:0:0:0:0:0:0:0:11226 [junit] 2010-05-09 10:45:11,101 - INFO [main:quorumb...@301] - Waiting for QuorumPeer:/0:0:0:0:0:0:0:0:11226 to exit thread [junit] 2010-05-09 10:45:11,099 - WARN [LearnerHandler-/127.0.0.1:39006:learnerhand...@457] - ******* GOODBYE /127.0.0.1:39006 ******** [junit] 2010-05-09 10:45:11,101 - WARN [Thread-193:quorumcnxmanager$sendwor...@589] - Send worker leaving thread [junit] 2010-05-09 10:45:11,101 - WARN [Thread-189:quorumcnxmanager$sendwor...@589] - Send worker leaving thread [junit] 2010-05-09 10:45:11,100 - WARN [Thread-185:quorumcnxmanager$sendwor...@589] - Send worker leaving thread [junit] 2010-05-09 10:45:11,100 - INFO [Thread-184:quorumcnxmanager$liste...@457] - Leaving listener [junit] 2010-05-09 10:45:11,766 - INFO [WorkerSender Thread:fastleaderelection$messenger$workersen...@331] - WorkerSender is down [junit] 2010-05-09 10:45:11,766 - INFO [WorkerSender Thread:fastleaderelection$messenger$workersen...@331] - WorkerSender is down [junit] 2010-05-09 10:45:11,767 - INFO [WorkerSender Thread:fastleaderelection$messenger$workersen...@331] - WorkerSender is down [junit] 2010-05-09 10:45:11,768 - INFO [WorkerReceiver Thread:fastleaderelection$messenger$workerrecei...@301] - WorkerReceiver is down [junit] 2010-05-09 10:45:11,768 - INFO [WorkerReceiver Thread:fastleaderelection$messenger$workerrecei...@301] - WorkerReceiver is down [junit] 2010-05-09 10:45:11,768 - INFO [WorkerReceiver Thread:fastleaderelection$messenger$workerrecei...@301] - WorkerReceiver is down [junit] 2010-05-09 10:45:11,768 - INFO [WorkerReceiver Thread:fastleaderelection$messenger$workerrecei...@301] - WorkerReceiver is down [junit] 2010-05-09 10:45:11,770 - INFO [WorkerSender Thread:fastleaderelection$messenger$workersen...@331] - WorkerSender is down [junit] 2010-05-09 10:45:12,000 - INFO [SessionTracker:sessiontrackeri...@158] - SessionTrackerImpl exited loop! [junit] 2010-05-09 10:45:12,095 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11226:quorump...@672] - QuorumPeer main thread exited [junit] 2010-05-09 10:45:12,096 - INFO [main:junit4zktestrunner$loggedinvokemet...@56] - FINISHED TEST METHOD testCorruption [junit] 2010-05-09 10:45:12,096 - INFO [main:zkdatabasecorruptiont...@56] - STOPPING quorum org.apache.zookeeper.test.ZkDatabaseCorruptionTest [junit] 2010-05-09 10:45:12,097 - INFO [main:zktestcas...@59] - SUCCEEDED testCorruption [junit] 2010-05-09 10:45:12,097 - INFO [main:zktestcas...@54] - FINISHED testCorruption [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 33.141 sec [junit] Running org.apache.zookeeper.test.ZooKeeperQuotaTest [junit] 2010-05-09 10:45:12,921 - INFO [main:portassignm...@31] - assigning port 11221 [junit] 2010-05-09 10:45:12,924 - INFO [main:zktestcas...@49] - STARTING testQuota [junit] 2010-05-09 10:45:13,112 - INFO [main:clientb...@416] - STARTING server [junit] 2010-05-09 10:45:13,123 - INFO [main:environm...@97] - Server environment:zookeeper.version=3.4.0-942518, built on 05/09/2010 10:32 GMT [junit] 2010-05-09 10:45:13,124 - INFO [main:environm...@97] - Server environment:host.name=h8.grid.sp2.yahoo.net [junit] 2010-05-09 10:45:13,124 - INFO [main:environm...@97] - Server environment:java.version=1.6.0_11 [junit] 2010-05-09 10:45:13,125 - INFO [main:environm...@97] - Server environment:java.vendor=Sun Microsystems Inc. [junit] 2010-05-09 10:45:13,125 - INFO [main:environm...@97] - Server environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre [junit] 2010-05-09 10:45:13,126 - INFO [main:environm...@97] - Server environment:java.class.path=<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/classes>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/antlr-2.7.6.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/checkstyle-5.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-beanutils-core-1.7.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-cli-1.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-collections-2.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-lang-1.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-logging-1.0.3.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/google-collections-0.9.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/junit-4.8.1.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/classes>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/ivy-2.1.0.jar>:/homes/hudson/tools/ant/latest/lib/ant.jar:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/jline-0.9.94.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/log4j-1.2.15.jar>:/homes/hudson/tools/clover/latest/lib/clover.jar:/homes/hudson/tools/ant/apache-ant-1.7.1/lib/ant-launcher.jar:/homes/hudson/tools/ant/latest/lib/ant-junit.jar [junit] 2010-05-09 10:45:13,127 - INFO [main:environm...@97] - Server environment:java.library.path=/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386/server:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib [junit] 2010-05-09 10:45:13,127 - INFO [main:environm...@97] - Server environment:java.io.tmpdir=/tmp [junit] 2010-05-09 10:45:13,128 - INFO [main:environm...@97] - Server environment:java.compiler=<NA> [junit] 2010-05-09 10:45:13,128 - INFO [main:environm...@97] - Server environment:os.name=Linux [junit] 2010-05-09 10:45:13,129 - INFO [main:environm...@97] - Server environment:os.arch=i386 [junit] 2010-05-09 10:45:13,129 - INFO [main:environm...@97] - Server environment:os.version=2.6.28-17-generic [junit] 2010-05-09 10:45:13,130 - INFO [main:environm...@97] - Server environment:user.name=hudson [junit] 2010-05-09 10:45:13,130 - INFO [main:environm...@97] - Server environment:user.home=/homes/hudson [junit] 2010-05-09 10:45:13,131 - INFO [main:environm...@97] - Server environment:user.dir=<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk> [junit] 2010-05-09 10:45:13,156 - INFO [main:zookeeperser...@151] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test1279248403580456015.junit.dir/version-2> snapdir <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test1279248403580456015.junit.dir/version-2> [junit] 2010-05-09 10:45:13,186 - INFO [main:nioservercnxn$fact...@143] - binding to port 0.0.0.0/0.0.0.0:11221 [junit] 2010-05-09 10:45:13,194 - INFO [main:filetxnsnap...@208] - Snapshotting: 0 [junit] 2010-05-09 10:45:13,229 - INFO [main:clientb...@222] - connecting to 127.0.0.1 11221 [junit] 2010-05-09 10:45:13,232 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:nioservercnxn$fact...@250] - Accepted socket connection from /127.0.0.1:51687 [junit] 2010-05-09 10:45:13,235 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:nioserverc...@1236] - Processing stat command from /127.0.0.1:51687 [junit] 2010-05-09 10:45:13,237 - INFO [Thread-3:nioservercnxn$statcomm...@1152] - Stat command output [junit] 2010-05-09 10:45:13,242 - INFO [Thread-3:nioserverc...@1434] - Closed socket connection for client /127.0.0.1:51687 (no session established for client) [junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port] [junit] expect:InMemoryDataTree [junit] found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree [junit] expect:StandaloneServer_port [junit] found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1 [junit] 2010-05-09 10:45:13,260 - INFO [main:clientb...@408] - Initial fdcount is: 32 [junit] 2010-05-09 10:45:13,260 - INFO [main:clientb...@412] - Client test setup finished [junit] 2010-05-09 10:45:13,261 - INFO [main:junit4zktestrunner$loggedinvokemet...@49] - RUNNING TEST METHOD testQuota [junit] 2010-05-09 10:45:13,268 - INFO [main:environm...@97] - Client environment:zookeeper.version=3.4.0-942518, built on 05/09/2010 10:32 GMT [junit] 2010-05-09 10:45:13,269 - INFO [main:environm...@97] - Client environment:host.name=h8.grid.sp2.yahoo.net [junit] 2010-05-09 10:45:13,269 - INFO [main:environm...@97] - Client environment:java.version=1.6.0_11 [junit] 2010-05-09 10:45:13,270 - INFO [main:environm...@97] - Client environment:java.vendor=Sun Microsystems Inc. [junit] 2010-05-09 10:45:13,270 - INFO [main:environm...@97] - Client environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre [junit] 2010-05-09 10:45:13,271 - INFO [main:environm...@97] - Client environment:java.class.path=<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/classes>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/antlr-2.7.6.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/checkstyle-5.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-beanutils-core-1.7.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-cli-1.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-collections-2.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-lang-1.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-logging-1.0.3.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/google-collections-0.9.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/junit-4.8.1.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/classes>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/ivy-2.1.0.jar>:/homes/hudson/tools/ant/latest/lib/ant.jar:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/jline-0.9.94.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/log4j-1.2.15.jar>:/homes/hudson/tools/clover/latest/lib/clover.jar:/homes/hudson/tools/ant/apache-ant-1.7.1/lib/ant-launcher.jar:/homes/hudson/tools/ant/latest/lib/ant-junit.jar [junit] 2010-05-09 10:45:13,271 - INFO [main:environm...@97] - Client environment:java.library.path=/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386/server:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib [junit] 2010-05-09 10:45:13,272 - INFO [main:environm...@97] - Client environment:java.io.tmpdir=/tmp [junit] 2010-05-09 10:45:13,272 - INFO [main:environm...@97] - Client environment:java.compiler=<NA> [junit] 2010-05-09 10:45:13,273 - INFO [main:environm...@97] - Client environment:os.name=Linux [junit] 2010-05-09 10:45:13,273 - INFO [main:environm...@97] - Client environment:os.arch=i386 [junit] 2010-05-09 10:45:13,274 - INFO [main:environm...@97] - Client environment:os.version=2.6.28-17-generic [junit] 2010-05-09 10:45:13,274 - INFO [main:environm...@97] - Client environment:user.name=hudson [junit] 2010-05-09 10:45:13,275 - INFO [main:environm...@97] - Client environment:user.home=/homes/hudson [junit] 2010-05-09 10:45:13,275 - INFO [main:environm...@97] - Client environment:user.dir=<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk> [junit] 2010-05-09 10:45:13,277 - INFO [main:zookee...@375] - Initiating client connection, connectString=127.0.0.1:11221 sessionTimeout=30000 watcher=org.apache.zookeeper.test.clientbase$countdownwatc...@125d06e [junit] 2010-05-09 10:45:13,292 - INFO [main-SendThread():clientcnxn$sendthr...@1000] - Opening socket connection to server /127.0.0.1:11221 [junit] 2010-05-09 10:45:13,293 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:nioservercnxn$fact...@250] - Accepted socket connection from /127.0.0.1:51688 [junit] 2010-05-09 10:45:13,293 - INFO [main-SendThread(localhost:11221):clientcnxn$sendthr...@908] - Socket connection established to localhost/127.0.0.1:11221, initiating session [junit] 2010-05-09 10:45:13,298 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:nioserverc...@776] - Client attempting to establish new session at /127.0.0.1:51688 [junit] 2010-05-09 10:45:13,335 - INFO [SyncThread:0:nioserverc...@1579] - Established session 0x1287cab2f730000 with negotiated timeout 30000 for client /127.0.0.1:51688 [junit] 2010-05-09 10:45:13,336 - INFO [main-SendThread(localhost:11221):clientcnxn$sendthr...@701] - Session establishment complete on server localhost/127.0.0.1:11221, sessionid = 0x1287cab2f730000, negotiated timeout = 30000 [junit] expect:0x1287cab2f730000 [junit] found:0x1287cab2f730000 org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x1287cab2f730000 [junit] 2010-05-09 10:45:13,394 - INFO [main:junit4zktestrunner$loggedinvokemet...@56] - FINISHED TEST METHOD testQuota [junit] 2010-05-09 10:45:13,395 - INFO [main:clientb...@446] - tearDown starting [junit] 2010-05-09 10:45:13,395 - INFO [main:clientb...@458] - fdcount after test is: 38 [junit] 2010-05-09 10:45:13,396 - INFO [ProcessThread:-1:preprequestproces...@385] - Processed session termination for sessionid: 0x1287cab2f730000 [junit] 2010-05-09 10:45:13,397 - INFO [main:zookee...@540] - Session: 0x1287cab2f730000 closed [junit] 2010-05-09 10:45:13,397 - INFO [main:clientb...@423] - STOPPING server [junit] 2010-05-09 10:45:13,397 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:nioserverc...@1434] - Closed socket connection for client /127.0.0.1:51688 which had sessionid 0x1287cab2f730000 [junit] 2010-05-09 10:45:13,398 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:nioservercnxn$fact...@277] - NIOServerCnxn factory exited run method [junit] 2010-05-09 10:45:13,398 - INFO [ProcessThread:-1:preprequestproces...@119] - PrepRequestProcessor exited loop! [junit] 2010-05-09 10:45:13,398 - INFO [SyncThread:0:syncrequestproces...@151] - SyncRequestProcessor exited! [junit] 2010-05-09 10:45:13,399 - INFO [main:finalrequestproces...@378] - shutdown of request processor complete [junit] 2010-05-09 10:45:13,400 - INFO [main:clientb...@222] - connecting to 127.0.0.1 11221 [junit] ensureOnly:[] [junit] 2010-05-09 10:45:13,403 - INFO [main:zktestcas...@59] - SUCCEEDED testQuota [junit] 2010-05-09 10:45:13,403 - INFO [main:zktestcas...@54] - FINISHED testQuota [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.538 sec BUILD FAILED <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:930: The following error occurred while executing this line: <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:841: Tests failed! Total time: 12 minutes 36 seconds Publishing Javadoc Recording fingerprints Archiving artifacts Recording test results Publishing Clover coverage report... No Clover report will be published due to a Build Failure