You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@zookeeper.apache.org by Apache Hudson Server <hu...@hudson.zones.apache.org> on 2010/03/09 22:06:47 UTC
Build failed in Hudson: ZooKeeper-trunk #723
See <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/723/changes>
Changes:
[mahadev] ZOOKEEPER-691. Interface changed for NIOServer.Factory (breed via mahadev)
------------------------------------------
[...truncated 131904 lines...]
[junit] at sun.nio.ch.Net.connect(Native Method)
[junit] at sun.nio.ch.SocketChannelImpl.connect(SocketChannelImpl.java:507)
[junit] at java.nio.channels.SocketChannel.open(SocketChannel.java:146)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.connectOne(QuorumCnxManager.java:347)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.toSend(QuorumCnxManager.java:320)
[junit] at org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.process(FastLeaderElection.java:353)
[junit] at org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.run(FastLeaderElection.java:326)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 2010-03-09 21:06:22,135 - WARN [WorkerSender Thread:QuorumCnxManager@361] - Cannot open channel to 4 at election address /127.0.0.1:12231
[junit] java.net.ConnectException: Connection refused
[junit] at sun.nio.ch.Net.connect(Native Method)
[junit] at sun.nio.ch.SocketChannelImpl.connect(SocketChannelImpl.java:507)
[junit] at java.nio.channels.SocketChannel.open(SocketChannel.java:146)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.connectOne(QuorumCnxManager.java:347)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.toSend(QuorumCnxManager.java:320)
[junit] at org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.process(FastLeaderElection.java:353)
[junit] at org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.run(FastLeaderElection.java:326)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 2010-03-09 21:06:22,137 - WARN [WorkerSender Thread:QuorumCnxManager@361] - Cannot open channel to 5 at election address /127.0.0.1:12232
[junit] java.net.ConnectException: Connection refused
[junit] at sun.nio.ch.Net.connect(Native Method)
[junit] at sun.nio.ch.SocketChannelImpl.connect(SocketChannelImpl.java:507)
[junit] at java.nio.channels.SocketChannel.open(SocketChannel.java:146)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.connectOne(QuorumCnxManager.java:347)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.toSend(QuorumCnxManager.java:320)
[junit] at org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.process(FastLeaderElection.java:353)
[junit] at org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.run(FastLeaderElection.java:326)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 2010-03-09 21:06:22,183 - INFO [Thread-103:QuorumCnxManager$Listener@436] - My election bind port: 12231
[junit] 2010-03-09 21:06:22,187 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11226:QuorumPeer@598] - LOOKING
[junit] 2010-03-09 21:06:22,187 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11226:FastLeaderElection@649] - New election. My id = 4, Proposed zxid = 4294969298
[junit] 2010-03-09 21:06:22,191 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11226:FastLeaderElection@689] - Notification: 1, 4294969298, 1, 4, LOOKING, LOOKING, 1
[junit] 2010-03-09 21:06:22,192 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11226:FastLeaderElection@689] - Notification: 2, 4294969298, 1, 4, LOOKING, LOOKING, 1
[junit] 2010-03-09 21:06:22,192 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11223:FastLeaderElection@689] - Notification: 4, 4294969298, 1, 1, LOOKING, LOOKING, 4
[junit] 2010-03-09 21:06:22,192 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11226:FastLeaderElection@689] - Notification: 3, 4294969298, 1, 4, LOOKING, LOOKING, 1
[junit] 2010-03-09 21:06:22,192 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11223:FastLeaderElection@711] - Updating proposal
[junit] 2010-03-09 21:06:22,193 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11226:FastLeaderElection@689] - Notification: 2, 4294969298, 1, 4, LOOKING, LOOKING, 2
[junit] 2010-03-09 21:06:22,193 - WARN [WorkerSender Thread:QuorumCnxManager@361] - Cannot open channel to 5 at election address /127.0.0.1:12232
[junit] java.net.ConnectException: Connection refused
[junit] at sun.nio.ch.Net.connect(Native Method)
[junit] at sun.nio.ch.SocketChannelImpl.connect(SocketChannelImpl.java:507)
[junit] at java.nio.channels.SocketChannel.open(SocketChannel.java:146)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.connectOne(QuorumCnxManager.java:347)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.toSend(QuorumCnxManager.java:320)
[junit] at org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.process(FastLeaderElection.java:353)
[junit] at org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.run(FastLeaderElection.java:326)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 2010-03-09 21:06:22,193 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11224:FastLeaderElection@689] - Notification: 4, 4294969298, 1, 2, LOOKING, LOOKING, 1
[junit] 2010-03-09 21:06:22,193 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11224:FastLeaderElection@711] - Updating proposal
[junit] 2010-03-09 21:06:22,194 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11224:FastLeaderElection@689] - Notification: 4, 4294969298, 1, 2, LOOKING, LOOKING, 4
[junit] 2010-03-09 21:06:22,194 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11225:FastLeaderElection@689] - Notification: 4, 4294969298, 1, 3, LOOKING, LOOKING, 2
[junit] 2010-03-09 21:06:22,194 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11225:FastLeaderElection@711] - Updating proposal
[junit] 2010-03-09 21:06:22,193 - WARN [WorkerSender Thread:QuorumCnxManager@361] - Cannot open channel to 5 at election address /127.0.0.1:12232
[junit] java.net.ConnectException: Connection refused
[junit] at sun.nio.ch.Net.connect(Native Method)
[junit] at sun.nio.ch.SocketChannelImpl.connect(SocketChannelImpl.java:507)
[junit] at java.nio.channels.SocketChannel.open(SocketChannel.java:146)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.connectOne(QuorumCnxManager.java:347)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.toSend(QuorumCnxManager.java:320)
[junit] at org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.process(FastLeaderElection.java:353)
[junit] at org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.run(FastLeaderElection.java:326)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 2010-03-09 21:06:22,195 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11224:FastLeaderElection@689] - Notification: 4, 4294969298, 1, 2, LOOKING, LOOKING, 2
[junit] 2010-03-09 21:06:22,193 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11226:FastLeaderElection@689] - Notification: 3, 4294969298, 1, 4, LOOKING, LOOKING, 2
[junit] 2010-03-09 21:06:22,199 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11226:FastLeaderElection@689] - Notification: 4, 4294969298, 1, 4, LOOKING, LOOKING, 4
[junit] 2010-03-09 21:06:22,199 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11226:FastLeaderElection@689] - Notification: 4, 4294969298, 1, 4, LOOKING, LOOKING, 2
[junit] 2010-03-09 21:06:22,195 - WARN [WorkerSender Thread:QuorumCnxManager@361] - Cannot open channel to 5 at election address /127.0.0.1:12232
[junit] java.net.ConnectException: Connection refused
[junit] at sun.nio.ch.Net.connect(Native Method)
[junit] at sun.nio.ch.SocketChannelImpl.connect(SocketChannelImpl.java:507)
[junit] at java.nio.channels.SocketChannel.open(SocketChannel.java:146)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.connectOne(QuorumCnxManager.java:347)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.toSend(QuorumCnxManager.java:320)
[junit] at org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.process(FastLeaderElection.java:353)
[junit] at org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.run(FastLeaderElection.java:326)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 2010-03-09 21:06:22,194 - WARN [WorkerSender Thread:QuorumCnxManager@361] - Cannot open channel to 5 at election address /127.0.0.1:12232
[junit] java.net.ConnectException: Connection refused
[junit] at sun.nio.ch.Net.connect(Native Method)
[junit] at sun.nio.ch.SocketChannelImpl.connect(SocketChannelImpl.java:507)
[junit] at java.nio.channels.SocketChannel.open(SocketChannel.java:146)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.connectOne(QuorumCnxManager.java:347)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.toSend(QuorumCnxManager.java:320)
[junit] at org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.process(FastLeaderElection.java:353)
[junit] at org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.run(FastLeaderElection.java:326)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 2010-03-09 21:06:22,194 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11225:FastLeaderElection@689] - Notification: 4, 4294969298, 1, 3, LOOKING, LOOKING, 1
[junit] 2010-03-09 21:06:22,194 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11223:FastLeaderElection@689] - Notification: 4, 4294969298, 1, 1, LOOKING, LOOKING, 1
[junit] 2010-03-09 21:06:22,201 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11225:FastLeaderElection@689] - Notification: 4, 4294969298, 1, 3, LOOKING, LOOKING, 3
[junit] 2010-03-09 21:06:22,201 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11223:FastLeaderElection@689] - Notification: 4, 4294969298, 1, 1, LOOKING, LOOKING, 2
[junit] 2010-03-09 21:06:22,201 - INFO [main:FileSnap@81] - Reading snapshot <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test4941648592058179663.junit.dir/version-2/snapshot.1000007d2>
[junit] 2010-03-09 21:06:22,203 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11226:FastLeaderElection@689] - Notification: 4, 4294969298, 1, 4, LOOKING, LOOKING, 1
[junit] 2010-03-09 21:06:22,225 - INFO [Thread-116:QuorumCnxManager$Listener@436] - My election bind port: 12232
[junit] 2010-03-09 21:06:22,229 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:QuorumPeer@598] - LOOKING
[junit] 2010-03-09 21:06:22,229 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:FastLeaderElection@649] - New election. My id = 5, Proposed zxid = 4294969298
[junit] 2010-03-09 21:06:22,231 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:FastLeaderElection@689] - Notification: 1, 4294969298, 1, 5, LOOKING, LOOKING, 1
[junit] 2010-03-09 21:06:22,232 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:FastLeaderElection@689] - Notification: 2, 4294969298, 1, 5, LOOKING, LOOKING, 1
[junit] 2010-03-09 21:06:22,232 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:FastLeaderElection@689] - Notification: 3, 4294969298, 1, 5, LOOKING, LOOKING, 1
[junit] 2010-03-09 21:06:22,232 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:FastLeaderElection@689] - Notification: 4, 4294969298, 1, 5, LOOKING, LOOKING, 1
[junit] 2010-03-09 21:06:22,232 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11223:FastLeaderElection@689] - Notification: 5, 4294969298, 1, 1, LOOKING, LOOKING, 5
[junit] 2010-03-09 21:06:22,235 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:FastLeaderElection@689] - Notification: 2, 4294969298, 1, 5, LOOKING, LOOKING, 2
[junit] 2010-03-09 21:06:22,235 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11223:FastLeaderElection@711] - Updating proposal
[junit] 2010-03-09 21:06:22,236 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11223:QuorumPeer@620] - FOLLOWING
[junit] 2010-03-09 21:06:22,236 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:FastLeaderElection@689] - Notification: 3, 4294969298, 1, 5, LOOKING, LOOKING, 2
[junit] 2010-03-09 21:06:22,236 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11223:ZooKeeperServer@144] - Created server with tickTime 2000 datadir <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test8663832451575232621.junit.dir/version-2> snapdir <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test8663832451575232621.junit.dir/version-2>
[junit] 2010-03-09 21:06:22,237 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:FastLeaderElection@689] - Notification: 4, 4294969298, 1, 5, LOOKING, LOOKING, 2
[junit] 2010-03-09 21:06:22,237 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:FastLeaderElection@689] - Notification: 3, 4294969298, 1, 5, LOOKING, LOOKING, 3
[junit] 2010-03-09 21:06:22,237 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:FastLeaderElection@689] - Notification: 4, 4294969298, 1, 5, LOOKING, LOOKING, 3
[junit] 2010-03-09 21:06:22,237 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11223:Learner@220] - Unexpected exception, tries=0, connecting to /127.0.0.1:12227
[junit] java.net.ConnectException: Connection refused
[junit] at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit] at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:333)
[junit] at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:195)
[junit] at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:182)
[junit] at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:366)
[junit] at java.net.Socket.connect(Socket.java:519)
[junit] at org.apache.zookeeper.server.quorum.Learner.connectToLeader(Learner.java:212)
[junit] at org.apache.zookeeper.server.quorum.Follower.followLeader(Follower.java:65)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:622)
[junit] 2010-03-09 21:06:22,237 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:FastLeaderElection@689] - Notification: 5, 4294969298, 1, 5, LOOKING, LOOKING, 5
[junit] 2010-03-09 21:06:22,238 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11225:FastLeaderElection@689] - Notification: 5, 4294969298, 1, 3, LOOKING, LOOKING, 1
[junit] 2010-03-09 21:06:22,238 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11225:FastLeaderElection@711] - Updating proposal
[junit] Tests run: 1, Failures: 1, Errors: 0, Time elapsed: 17.058 sec
[junit] 2010-03-09 21:06:22,239 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11225:FastLeaderElection@689] - Notification: 5, 4294969298, 1, 3, LOOKING, LOOKING, 3
[junit] 2010-03-09 21:06:22,239 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11226:FastLeaderElection@689] - Notification: 5, 4294969298, 1, 4, LOOKING, LOOKING, 1
[junit] 2010-03-09 21:06:22,239 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11226:FastLeaderElection@711] - Updating proposal
[junit] 2010-03-09 21:06:22,241 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11226:FastLeaderElection@689] - Notification: 5, 4294969298, 1, 4, LOOKING, LOOKING, 5
[junit] 2010-03-09 21:06:22,241 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11226:FastLeaderElection@689] - Notification: 5, 4294969298, 1, 4, LOOKING, LOOKING, 3
[junit] 2010-03-09 21:06:22,241 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11226:QuorumPeer@620] - FOLLOWING
[junit] 2010-03-09 21:06:22,242 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11226:ZooKeeperServer@144] - Created server with tickTime 2000 datadir <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test3968278138610288883.junit.dir/version-2> snapdir <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test3968278138610288883.junit.dir/version-2>
[junit] 2010-03-09 21:06:22,242 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11226:Learner@220] - Unexpected exception, tries=0, connecting to /127.0.0.1:12227
[junit] java.net.ConnectException: Connection refused
[junit] at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit] at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:333)
[junit] at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:195)
[junit] at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:182)
[junit] at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:366)
[junit] at java.net.Socket.connect(Socket.java:519)
[junit] at org.apache.zookeeper.server.quorum.Learner.connectToLeader(Learner.java:212)
[junit] at org.apache.zookeeper.server.quorum.Follower.followLeader(Follower.java:65)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:622)
[junit] 2010-03-09 21:06:22,241 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11224:FastLeaderElection@689] - Notification: 5, 4294969298, 1, 2, LOOKING, LOOKING, 1
[junit] 2010-03-09 21:06:22,243 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11224:FastLeaderElection@711] - Updating proposal
[junit] 2010-03-09 21:06:22,243 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11224:FastLeaderElection@689] - Notification: 5, 4294969298, 1, 2, LOOKING, LOOKING, 3
[junit] 2010-03-09 21:06:22,243 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11224:FastLeaderElection@689] - Notification: 5, 4294969298, 1, 2, LOOKING, LOOKING, 5
[junit] 2010-03-09 21:06:22,243 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11224:QuorumPeer@620] - FOLLOWING
[junit] 2010-03-09 21:06:22,244 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11224:ZooKeeperServer@144] - Created server with tickTime 2000 datadir <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test7959177454717617270.junit.dir/version-2> snapdir <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test7959177454717617270.junit.dir/version-2>
[junit] 2010-03-09 21:06:22,244 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11224:Learner@220] - Unexpected exception, tries=0, connecting to /127.0.0.1:12227
[junit] java.net.ConnectException: Connection refused
[junit] at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit] at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:333)
[junit] at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:195)
[junit] at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:182)
[junit] at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:366)
[junit] at java.net.Socket.connect(Socket.java:519)
[junit] at org.apache.zookeeper.server.quorum.Learner.connectToLeader(Learner.java:212)
[junit] at org.apache.zookeeper.server.quorum.Follower.followLeader(Follower.java:65)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:622)
[junit] 2010-03-09 21:06:22,245 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11225:FastLeaderElection@689] - Notification: 5, 4294969298, 1, 3, LOOKING, LOOKING, 5
[junit] 2010-03-09 21:06:22,245 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:FastLeaderElection@689] - Notification: 4, 4294969298, 1, 5, LOOKING, LOOKING, 4
[junit] 2010-03-09 21:06:22,245 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:QuorumPeer@632] - LEADING
[junit] 2010-03-09 21:06:22,245 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:ZooKeeperServer@144] - Created server with tickTime 2000 datadir <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test4941648592058179663.junit.dir/version-2> snapdir <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test4941648592058179663.junit.dir/version-2>
[junit] 2010-03-09 21:06:22,249 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:FileSnap@81] - Reading snapshot <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test4941648592058179663.junit.dir/version-2/snapshot.1000007d2>
[junit] 2010-03-09 21:06:22,274 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:FileSnap@81] - Reading snapshot <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test4941648592058179663.junit.dir/version-2/snapshot.1000007d2>
[junit] 2010-03-09 21:06:22,295 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11227:FileTxnSnapLog@208] - Snapshotting: 1000007d2
[junit] Test org.apache.zookeeper.test.ZkDatabaseCorruptionTest FAILED
[junit] 2010-03-09 21:06:22,950 - INFO [main:PortAssignment@31] - assigning port 11221
[junit] Running org.apache.zookeeper.test.ZooKeeperQuotaTest
[junit] 2010-03-09 21:06:22,999 - INFO [main:ClientBase@377] - STARTING testQuota
[junit] 2010-03-09 21:06:23,168 - INFO [main:ClientBase@406] - STARTING server
[junit] 2010-03-09 21:06:23,179 - INFO [main:Environment@97] - Server environment:zookeeper.version=3.3.0-921121, built on 03/09/2010 20:54 GMT
[junit] 2010-03-09 21:06:23,180 - INFO [main:Environment@97] - Server environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2010-03-09 21:06:23,180 - INFO [main:Environment@97] - Server environment:java.version=1.6.0_11
[junit] 2010-03-09 21:06:23,181 - INFO [main:Environment@97] - Server environment:java.vendor=Sun Microsystems Inc.
[junit] 2010-03-09 21:06:23,181 - INFO [main:Environment@97] - Server environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] 2010-03-09 21:06:23,182 - INFO [main:Environment@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.7.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-03-09 21:06:23,183 - INFO [main:Environment@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-03-09 21:06:23,183 - INFO [main:Environment@97] - Server environment:java.io.tmpdir=/tmp
[junit] 2010-03-09 21:06:23,184 - INFO [main:Environment@97] - Server environment:java.compiler=<NA>
[junit] 2010-03-09 21:06:23,184 - INFO [main:Environment@97] - Server environment:os.name=Linux
[junit] 2010-03-09 21:06:23,185 - INFO [main:Environment@97] - Server environment:os.arch=i386
[junit] 2010-03-09 21:06:23,185 - INFO [main:Environment@97] - Server environment:os.version=2.6.28-17-generic
[junit] 2010-03-09 21:06:23,186 - INFO [main:Environment@97] - Server environment:user.name=hudson
[junit] 2010-03-09 21:06:23,186 - INFO [main:Environment@97] - Server environment:user.home=/homes/hudson
[junit] 2010-03-09 21:06:23,187 - INFO [main:Environment@97] - Server environment:user.dir=<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2010-03-09 21:06:23,212 - INFO [main:ZooKeeperServer@144] - Created server with tickTime 3000 datadir <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test7127756696940432664.junit.dir/version-2> snapdir <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test7127756696940432664.junit.dir/version-2>
[junit] 2010-03-09 21:06:23,233 - INFO [main:NIOServerCnxn$Factory@145] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2010-03-09 21:06:23,241 - INFO [main:FileTxnSnapLog@208] - Snapshotting: 0
[junit] 2010-03-09 21:06:23,275 - INFO [main:ClientBase@219] - connecting to 127.0.0.1 11221
[junit] 2010-03-09 21:06:23,278 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn$Factory@248] - Accepted socket connection from /127.0.0.1:51528
[junit] 2010-03-09 21:06:23,281 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@948] - Processing stat command from /127.0.0.1:51528
[junit] 2010-03-09 21:06:23,284 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@1232] - Closed socket connection for client /127.0.0.1:51528 (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-03-09 21:06:23,290 - INFO [main:ClientBase@398] - Initial fdcount is: 32
[junit] 2010-03-09 21:06:23,290 - INFO [main:ClientBase@402] - Client test setup finished
[junit] 2010-03-09 21:06:23,298 - INFO [main:Environment@97] - Client environment:zookeeper.version=3.3.0-921121, built on 03/09/2010 20:54 GMT
[junit] 2010-03-09 21:06:23,298 - INFO [main:Environment@97] - Client environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2010-03-09 21:06:23,299 - INFO [main:Environment@97] - Client environment:java.version=1.6.0_11
[junit] 2010-03-09 21:06:23,299 - INFO [main:Environment@97] - Client environment:java.vendor=Sun Microsystems Inc.
[junit] 2010-03-09 21:06:23,300 - INFO [main:Environment@97] - Client environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] 2010-03-09 21:06:23,300 - INFO [main:Environment@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.7.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-03-09 21:06:23,301 - INFO [main:Environment@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-03-09 21:06:23,301 - INFO [main:Environment@97] - Client environment:java.io.tmpdir=/tmp
[junit] 2010-03-09 21:06:23,301 - INFO [main:Environment@97] - Client environment:java.compiler=<NA>
[junit] 2010-03-09 21:06:23,302 - INFO [main:Environment@97] - Client environment:os.name=Linux
[junit] 2010-03-09 21:06:23,302 - INFO [main:Environment@97] - Client environment:os.arch=i386
[junit] 2010-03-09 21:06:23,303 - INFO [main:Environment@97] - Client environment:os.version=2.6.28-17-generic
[junit] 2010-03-09 21:06:23,303 - INFO [main:Environment@97] - Client environment:user.name=hudson
[junit] 2010-03-09 21:06:23,304 - INFO [main:Environment@97] - Client environment:user.home=/homes/hudson
[junit] 2010-03-09 21:06:23,304 - INFO [main:Environment@97] - Client environment:user.dir=<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2010-03-09 21:06:23,306 - INFO [main:ZooKeeper@373] - Initiating client connection, connectString=127.0.0.1:11221 sessionTimeout=30000 watcher=org.apache.zookeeper.test.ClientBase$CountdownWatcher@1f01a29
[junit] 2010-03-09 21:06:23,322 - INFO [main-SendThread():ClientCnxn$SendThread@1000] - Opening socket connection to server /127.0.0.1:11221
[junit] 2010-03-09 21:06:23,323 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn$Factory@248] - Accepted socket connection from /127.0.0.1:51529
[junit] 2010-03-09 21:06:23,323 - INFO [main-SendThread(localhost:11221):ClientCnxn$SendThread@908] - Socket connection established to localhost/127.0.0.1:11221, initiating session
[junit] 2010-03-09 21:06:23,327 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@737] - Client attempting to establish new session at /127.0.0.1:51529
[junit] 2010-03-09 21:06:23,354 - INFO [SyncThread:0:NIOServerCnxn@1387] - Established session 0x12744bff5920000 with negotiated timeout 30000 for client /127.0.0.1:51529
[junit] 2010-03-09 21:06:23,354 - INFO [main-SendThread(localhost:11221):ClientCnxn$SendThread@701] - Session establishment complete on server localhost/127.0.0.1:11221, sessionid = 0x12744bff5920000, negotiated timeout = 30000
[junit] expect:0x12744bff5920000
[junit] found:0x12744bff5920000 org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x12744bff5920000
[junit] 2010-03-09 21:06:23,415 - INFO [main:ClientBase@436] - tearDown starting
[junit] 2010-03-09 21:06:23,416 - INFO [main:ClientBase@448] - fdcount after test is: 38
[junit] 2010-03-09 21:06:23,416 - INFO [ProcessThread:-1:PrepRequestProcessor@385] - Processed session termination for sessionid: 0x12744bff5920000
[junit] 2010-03-09 21:06:23,417 - INFO [main:ZooKeeper@538] - Session: 0x12744bff5920000 closed
[junit] 2010-03-09 21:06:23,418 - INFO [main:ClientBase@413] - STOPPING server
[junit] 2010-03-09 21:06:23,418 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@1232] - Closed socket connection for client /127.0.0.1:51529 which had sessionid 0x12744bff5920000
[junit] 2010-03-09 21:06:23,418 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn$Factory@275] - NIOServerCnxn factory exited run method
[junit] 2010-03-09 21:06:23,419 - INFO [ProcessThread:-1:PrepRequestProcessor@119] - PrepRequestProcessor exited loop!
[junit] 2010-03-09 21:06:23,419 - INFO [main:FinalRequestProcessor@366] - shutdown of request processor complete
[junit] 2010-03-09 21:06:23,419 - INFO [SyncThread:0:SyncRequestProcessor@151] - SyncRequestProcessor exited!
[junit] 2010-03-09 21:06:23,420 - INFO [main:ClientBase@219] - connecting to 127.0.0.1 11221
[junit] ensureOnly:[]
[junit] 2010-03-09 21:06:23,423 - INFO [main:ClientBase@467] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.47 sec
BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:907: The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:818: Tests failed!
Total time: 11 minutes 47 seconds
Publishing Javadoc
Archiving artifacts
Recording test results
Recording fingerprints
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
Hudson build is back to normal : ZooKeeper-trunk #726
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/726/changes>
Build failed in Hudson: ZooKeeper-trunk #725
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/725/>
------------------------------------------
[...truncated 130347 lines...]
[junit] 2010-03-09 22:06:02,898 - INFO [Snapshot Thread:FileTxnSnapLog@208] - Snapshotting: 20000076d
[junit] 2010-03-09 22:06:02,951 - INFO [Snapshot Thread:FileTxnSnapLog@208] - Snapshotting: 20000077b
[junit] 2010-03-09 22:06:03,002 - INFO [Snapshot Thread:FileTxnSnapLog@208] - Snapshotting: 200000792
[junit] 2010-03-09 22:06:03,121 - INFO [Snapshot Thread:FileTxnSnapLog@208] - Snapshotting: 2000007b3
[junit] 2010-03-09 22:06:03,206 - INFO [ProcessThread:-1:PrepRequestProcessor@385] - Processed session termination for sessionid: 0x32744f6756b0000
[junit] 2010-03-09 22:06:03,207 - INFO [Snapshot Thread:FileTxnSnapLog@208] - Snapshotting: 2000007d1
[junit] 2010-03-09 22:06:03,209 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11225:NIOServerCnxn@1232] - Closed socket connection for client /127.0.0.1:50005 which had sessionid 0x32744f6756b0000
[junit] 2010-03-09 22:06:03,209 - INFO [main:ZooKeeper@538] - Session: 0x32744f6756b0000 closed
[junit] 2010-03-09 22:06:03,209 - INFO [main:Follower@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:667)
[junit] at org.apache.zookeeper.test.ZkDatabaseCorruptionTest.testCorruption(ZkDatabaseCorruptionTest.java:125)
[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 junit.framework.TestCase.runTest(TestCase.java:168)
[junit] at junit.framework.TestCase.runBare(TestCase.java:134)
[junit] at junit.framework.TestResult$1.protect(TestResult.java:110)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:128)
[junit] at junit.framework.TestResult.run(TestResult.java:113)
[junit] at junit.framework.TestCase.run(TestCase.java:124)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:232)
[junit] at junit.framework.TestSuite.run(TestSuite.java:227)
[junit] at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:83)
[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-03-09 22:06:03,209 - INFO [main:FinalRequestProcessor@366] - shutdown of request processor complete
[junit] 2010-03-09 22:06:03,210 - INFO [FollowerRequestProcessor:1:FollowerRequestProcessor@93] - FollowerRequestProcessor exited loop!
[junit] 2010-03-09 22:06:03,209 - INFO [CommitProcessor:1:CommitProcessor@148] - CommitProcessor exited loop!
[junit] 2010-03-09 22:06:03,210 - INFO [SyncThread:1:SyncRequestProcessor@151] - SyncRequestProcessor exited!
[junit] 2010-03-09 22:06:03,210 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11223:NIOServerCnxn$Factory@275] - NIOServerCnxn factory exited run method
[junit] 2010-03-09 22:06:03,211 - INFO [main:Follower@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:667)
[junit] at org.apache.zookeeper.test.ZkDatabaseCorruptionTest.testCorruption(ZkDatabaseCorruptionTest.java:126)
[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 junit.framework.TestCase.runTest(TestCase.java:168)
[junit] at junit.framework.TestCase.runBare(TestCase.java:134)
[junit] at junit.framework.TestResult$1.protect(TestResult.java:110)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:128)
[junit] at junit.framework.TestResult.run(TestResult.java:113)
[junit] at junit.framework.TestCase.run(TestCase.java:124)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:232)
[junit] at junit.framework.TestSuite.run(TestSuite.java:227)
[junit] at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:83)
[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-03-09 22:06:03,211 - INFO [FollowerRequestProcessor:2:FollowerRequestProcessor@93] - FollowerRequestProcessor exited loop!
[junit] 2010-03-09 22:06:03,211 - INFO [main:FinalRequestProcessor@366] - shutdown of request processor complete
[junit] 2010-03-09 22:06:03,211 - INFO [CommitProcessor:2:CommitProcessor@148] - CommitProcessor exited loop!
[junit] 2010-03-09 22:06:03,212 - INFO [SyncThread:2:SyncRequestProcessor@151] - SyncRequestProcessor exited!
[junit] 2010-03-09 22:06:03,213 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11224:NIOServerCnxn$Factory@275] - NIOServerCnxn factory exited run method
[junit] 2010-03-09 22:06:03,213 - INFO [main:Follower@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:667)
[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 junit.framework.TestCase.runTest(TestCase.java:168)
[junit] at junit.framework.TestCase.runBare(TestCase.java:134)
[junit] at junit.framework.TestResult$1.protect(TestResult.java:110)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:128)
[junit] at junit.framework.TestResult.run(TestResult.java:113)
[junit] at junit.framework.TestCase.run(TestCase.java:124)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:232)
[junit] at junit.framework.TestSuite.run(TestSuite.java:227)
[junit] at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:83)
[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-03-09 22:06:03,213 - INFO [main:FinalRequestProcessor@366] - shutdown of request processor complete
[junit] 2010-03-09 22:06:03,214 - INFO [SyncThread:3:SyncRequestProcessor@151] - SyncRequestProcessor exited!
[junit] 2010-03-09 22:06:03,214 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11225:NIOServerCnxn$Factory@275] - NIOServerCnxn factory exited run method
[junit] 2010-03-09 22:06:03,214 - INFO [CommitProcessor:3:CommitProcessor@148] - CommitProcessor exited loop!
[junit] 2010-03-09 22:06:03,214 - INFO [FollowerRequestProcessor:3:FollowerRequestProcessor@93] - FollowerRequestProcessor exited loop!
[junit] 2010-03-09 22:06:03,215 - INFO [main:Leader@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:664)
[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 junit.framework.TestCase.runTest(TestCase.java:168)
[junit] at junit.framework.TestCase.runBare(TestCase.java:134)
[junit] at junit.framework.TestResult$1.protect(TestResult.java:110)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:128)
[junit] at junit.framework.TestResult.run(TestResult.java:113)
[junit] at junit.framework.TestCase.run(TestCase.java:124)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:232)
[junit] at junit.framework.TestSuite.run(TestSuite.java:227)
[junit] at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:83)
[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-03-09 22:06:03,216 - INFO [main:FinalRequestProcessor@366] - shutdown of request processor complete
[junit] 2010-03-09 22:06:03,216 - INFO [CommitProcessor:0:CommitProcessor@148] - CommitProcessor exited loop!
[junit] 2010-03-09 22:06:03,216 - INFO [ProcessThread:-1:PrepRequestProcessor@119] - PrepRequestProcessor exited loop!
[junit] 2010-03-09 22:06:03,216 - INFO [Thread-152:Leader$LearnerCnxAcceptor@243] - exception while shutting down acceptor: java.net.SocketException: Socket closed
[junit] 2010-03-09 22:06:03,216 - INFO [SyncThread:0:SyncRequestProcessor@151] - SyncRequestProcessor exited!
[junit] 2010-03-09 22:06:03,217 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11223:Follower@82] - Exception when following the leader
[junit] java.io.EOFException
[junit] at java.io.DataInputStream.readInt(DataInputStream.java:375)
[junit] at org.apache.jute.BinaryInputArchive.readInt(BinaryInputArchive.java:63)
[junit] at org.apache.zookeeper.server.quorum.QuorumPacket.deserialize(QuorumPacket.java:84)
[junit] at org.apache.jute.BinaryInputArchive.readRecord(BinaryInputArchive.java:108)
[junit] at org.apache.zookeeper.server.quorum.Learner.readPacket(Learner.java:140)
[junit] at org.apache.zookeeper.server.quorum.Follower.followLeader(Follower.java:78)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:622)
[junit] 2010-03-09 22:06:03,217 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11224:Follower@82] - Exception when following the leader
[junit] java.io.EOFException
[junit] at java.io.DataInputStream.readInt(DataInputStream.java:375)
[junit] at org.apache.jute.BinaryInputArchive.readInt(BinaryInputArchive.java:63)
[junit] at org.apache.zookeeper.server.quorum.QuorumPacket.deserialize(QuorumPacket.java:84)
[junit] at org.apache.jute.BinaryInputArchive.readRecord(BinaryInputArchive.java:108)
[junit] at org.apache.zookeeper.server.quorum.Learner.readPacket(Learner.java:140)
[junit] at org.apache.zookeeper.server.quorum.Follower.followLeader(Follower.java:78)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:622)
[junit] 2010-03-09 22:06:03,217 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11223:Follower@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:626)
[junit] 2010-03-09 22:06:03,217 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11224:Follower@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:626)
[junit] 2010-03-09 22:06:03,216 - WARN [LearnerHandler-/127.0.0.1:33258:LearnerHandler@449] - ******* GOODBYE /127.0.0.1:33258 ********
[junit] 2010-03-09 22:06:03,218 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11224:FinalRequestProcessor@366] - shutdown of request processor complete
[junit] 2010-03-09 22:06:03,218 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11224:QuorumPeer@650] - QuorumPeer main thread exited
[junit] 2010-03-09 22:06:03,217 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11223:FinalRequestProcessor@366] - shutdown of request processor complete
[junit] 2010-03-09 22:06:03,217 - WARN [LearnerHandler-/127.0.0.1:33257:LearnerHandler@449] - ******* GOODBYE /127.0.0.1:33257 ********
[junit] 2010-03-09 22:06:03,217 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11225:Follower@82] - Exception when following the leader
[junit] java.io.EOFException
[junit] at java.io.DataInputStream.readInt(DataInputStream.java:375)
[junit] at org.apache.jute.BinaryInputArchive.readInt(BinaryInputArchive.java:63)
[junit] at org.apache.zookeeper.server.quorum.QuorumPacket.deserialize(QuorumPacket.java:84)
[junit] at org.apache.jute.BinaryInputArchive.readRecord(BinaryInputArchive.java:108)
[junit] at org.apache.zookeeper.server.quorum.Learner.readPacket(Learner.java:140)
[junit] at org.apache.zookeeper.server.quorum.Follower.followLeader(Follower.java:78)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:622)
[junit] 2010-03-09 22:06:03,217 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11226:NIOServerCnxn$Factory@275] - NIOServerCnxn factory exited run method
[junit] 2010-03-09 22:06:03,219 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11225:Follower@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:626)
[junit] 2010-03-09 22:06:03,220 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11225:FinalRequestProcessor@366] - shutdown of request processor complete
[junit] 2010-03-09 22:06:03,217 - WARN [LearnerHandler-/127.0.0.1:33256:LearnerHandler@449] - ******* GOODBYE /127.0.0.1:33256 ********
[junit] 2010-03-09 22:06:03,220 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11225:QuorumPeer@650] - QuorumPeer main thread exited
[junit] 2010-03-09 22:06:03,219 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11223:QuorumPeer@650] - QuorumPeer main thread exited
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 24.572 sec
[junit] 2010-03-09 22:06:03,900 - INFO [main:PortAssignment@31] - assigning port 11221
[junit] Running org.apache.zookeeper.test.ZooKeeperQuotaTest
[junit] 2010-03-09 22:06:03,951 - INFO [main:ClientBase@377] - STARTING testQuota
[junit] 2010-03-09 22:06:04,140 - INFO [main:ClientBase@406] - STARTING server
[junit] 2010-03-09 22:06:04,151 - INFO [main:Environment@97] - Server environment:zookeeper.version=3.3.0-921147, built on 03/09/2010 21:54 GMT
[junit] 2010-03-09 22:06:04,151 - INFO [main:Environment@97] - Server environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2010-03-09 22:06:04,152 - INFO [main:Environment@97] - Server environment:java.version=1.6.0_11
[junit] 2010-03-09 22:06:04,153 - INFO [main:Environment@97] - Server environment:java.vendor=Sun Microsystems Inc.
[junit] 2010-03-09 22:06:04,153 - INFO [main:Environment@97] - Server environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] 2010-03-09 22:06:04,154 - INFO [main:Environment@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.7.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-03-09 22:06:04,154 - INFO [main:Environment@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-03-09 22:06:04,155 - INFO [main:Environment@97] - Server environment:java.io.tmpdir=/tmp
[junit] 2010-03-09 22:06:04,156 - INFO [main:Environment@97] - Server environment:java.compiler=<NA>
[junit] 2010-03-09 22:06:04,156 - INFO [main:Environment@97] - Server environment:os.name=Linux
[junit] 2010-03-09 22:06:04,157 - INFO [main:Environment@97] - Server environment:os.arch=i386
[junit] 2010-03-09 22:06:04,157 - INFO [main:Environment@97] - Server environment:os.version=2.6.28-17-generic
[junit] 2010-03-09 22:06:04,158 - INFO [main:Environment@97] - Server environment:user.name=hudson
[junit] 2010-03-09 22:06:04,158 - INFO [main:Environment@97] - Server environment:user.home=/homes/hudson
[junit] 2010-03-09 22:06:04,159 - INFO [main:Environment@97] - Server environment:user.dir=<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2010-03-09 22:06:04,184 - INFO [main:ZooKeeperServer@144] - Created server with tickTime 3000 datadir <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test2010792034511829354.junit.dir/version-2> snapdir <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test2010792034511829354.junit.dir/version-2>
[junit] 2010-03-09 22:06:04,207 - INFO [main:NIOServerCnxn$Factory@145] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2010-03-09 22:06:04,214 - INFO [main:FileTxnSnapLog@208] - Snapshotting: 0
[junit] 2010-03-09 22:06:04,247 - INFO [main:ClientBase@219] - connecting to 127.0.0.1 11221
[junit] 2010-03-09 22:06:04,250 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn$Factory@248] - Accepted socket connection from /127.0.0.1:42154
[junit] 2010-03-09 22:06:04,253 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@948] - Processing stat command from /127.0.0.1:42154
[junit] 2010-03-09 22:06:04,257 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@1232] - Closed socket connection for client /127.0.0.1:42154 (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-03-09 22:06:04,263 - INFO [main:ClientBase@398] - Initial fdcount is: 32
[junit] 2010-03-09 22:06:04,263 - INFO [main:ClientBase@402] - Client test setup finished
[junit] 2010-03-09 22:06:04,270 - INFO [main:Environment@97] - Client environment:zookeeper.version=3.3.0-921147, built on 03/09/2010 21:54 GMT
[junit] 2010-03-09 22:06:04,271 - INFO [main:Environment@97] - Client environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2010-03-09 22:06:04,271 - INFO [main:Environment@97] - Client environment:java.version=1.6.0_11
[junit] 2010-03-09 22:06:04,272 - INFO [main:Environment@97] - Client environment:java.vendor=Sun Microsystems Inc.
[junit] 2010-03-09 22:06:04,272 - INFO [main:Environment@97] - Client environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] 2010-03-09 22:06:04,273 - INFO [main:Environment@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.7.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-03-09 22:06:04,273 - INFO [main:Environment@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-03-09 22:06:04,274 - INFO [main:Environment@97] - Client environment:java.io.tmpdir=/tmp
[junit] 2010-03-09 22:06:04,274 - INFO [main:Environment@97] - Client environment:java.compiler=<NA>
[junit] 2010-03-09 22:06:04,275 - INFO [main:Environment@97] - Client environment:os.name=Linux
[junit] 2010-03-09 22:06:04,275 - INFO [main:Environment@97] - Client environment:os.arch=i386
[junit] 2010-03-09 22:06:04,275 - INFO [main:Environment@97] - Client environment:os.version=2.6.28-17-generic
[junit] 2010-03-09 22:06:04,276 - INFO [main:Environment@97] - Client environment:user.name=hudson
[junit] 2010-03-09 22:06:04,276 - INFO [main:Environment@97] - Client environment:user.home=/homes/hudson
[junit] 2010-03-09 22:06:04,277 - INFO [main:Environment@97] - Client environment:user.dir=<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2010-03-09 22:06:04,279 - INFO [main:ZooKeeper@373] - Initiating client connection, connectString=127.0.0.1:11221 sessionTimeout=30000 watcher=org.apache.zookeeper.test.ClientBase$CountdownWatcher@1f01a29
[junit] 2010-03-09 22:06:04,294 - INFO [main-SendThread():ClientCnxn$SendThread@1000] - Opening socket connection to server /127.0.0.1:11221
[junit] 2010-03-09 22:06:04,295 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn$Factory@248] - Accepted socket connection from /127.0.0.1:42155
[junit] 2010-03-09 22:06:04,295 - INFO [main-SendThread(localhost:11221):ClientCnxn$SendThread@908] - Socket connection established to localhost/127.0.0.1:11221, initiating session
[junit] 2010-03-09 22:06:04,299 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@737] - Client attempting to establish new session at /127.0.0.1:42155
[junit] 2010-03-09 22:06:04,329 - INFO [SyncThread:0:NIOServerCnxn@1387] - Established session 0x12744f699bf0000 with negotiated timeout 30000 for client /127.0.0.1:42155
[junit] 2010-03-09 22:06:04,329 - INFO [main-SendThread(localhost:11221):ClientCnxn$SendThread@701] - Session establishment complete on server localhost/127.0.0.1:11221, sessionid = 0x12744f699bf0000, negotiated timeout = 30000
[junit] expect:0x12744f699bf0000
[junit] found:0x12744f699bf0000 org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x12744f699bf0000
[junit] 2010-03-09 22:06:04,391 - INFO [main:ClientBase@436] - tearDown starting
[junit] 2010-03-09 22:06:04,392 - INFO [main:ClientBase@448] - fdcount after test is: 38
[junit] 2010-03-09 22:06:04,392 - INFO [ProcessThread:-1:PrepRequestProcessor@385] - Processed session termination for sessionid: 0x12744f699bf0000
[junit] 2010-03-09 22:06:04,394 - INFO [main:ZooKeeper@538] - Session: 0x12744f699bf0000 closed
[junit] 2010-03-09 22:06:04,394 - INFO [main:ClientBase@413] - STOPPING server
[junit] 2010-03-09 22:06:04,394 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@1232] - Closed socket connection for client /127.0.0.1:42155 which had sessionid 0x12744f699bf0000
[junit] 2010-03-09 22:06:04,395 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn$Factory@275] - NIOServerCnxn factory exited run method
[junit] 2010-03-09 22:06:04,395 - INFO [main:FinalRequestProcessor@366] - shutdown of request processor complete
[junit] 2010-03-09 22:06:04,395 - INFO [ProcessThread:-1:PrepRequestProcessor@119] - PrepRequestProcessor exited loop!
[junit] 2010-03-09 22:06:04,395 - INFO [SyncThread:0:SyncRequestProcessor@151] - SyncRequestProcessor exited!
[junit] 2010-03-09 22:06:04,396 - INFO [main:ClientBase@219] - connecting to 127.0.0.1 11221
[junit] ensureOnly:[]
[junit] 2010-03-09 22:06:04,399 - INFO [main:ClientBase@467] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.497 sec
BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:907: The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:818: Tests failed!
Total time: 11 minutes 59 seconds
Publishing Javadoc
Archiving artifacts
Recording test results
Recording fingerprints
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
Build failed in Hudson: ZooKeeper-trunk #724
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/724/>
------------------------------------------
[...truncated 292289 lines...]
[junit] at junit.framework.TestResult$1.protect(TestResult.java:110)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:128)
[junit] at junit.framework.TestResult.run(TestResult.java:113)
[junit] at junit.framework.TestCase.run(TestCase.java:124)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:232)
[junit] at junit.framework.TestSuite.run(TestSuite.java:227)
[junit] at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:83)
[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-03-09 21:31:36,984 - INFO [main:FinalRequestProcessor@366] - shutdown of request processor complete
[junit] 2010-03-09 21:31:36,984 - INFO [CommitProcessor:0:CommitProcessor@148] - CommitProcessor exited loop!
[junit] 2010-03-09 21:31:36,984 - INFO [SyncThread:0:SyncRequestProcessor@151] - SyncRequestProcessor exited!
[junit] 2010-03-09 21:31:36,984 - INFO [Thread-128:Leader$LearnerCnxAcceptor@243] - exception while shutting down acceptor: java.net.SocketException: Socket closed
[junit] 2010-03-09 21:31:36,984 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11224:Follower@82] - Exception when following the leader
[junit] java.io.EOFException
[junit] at java.io.DataInputStream.readInt(DataInputStream.java:375)
[junit] at org.apache.jute.BinaryInputArchive.readInt(BinaryInputArchive.java:63)
[junit] at org.apache.zookeeper.server.quorum.QuorumPacket.deserialize(QuorumPacket.java:84)
[junit] at org.apache.jute.BinaryInputArchive.readRecord(BinaryInputArchive.java:108)
[junit] at org.apache.zookeeper.server.quorum.Learner.readPacket(Learner.java:140)
[junit] at org.apache.zookeeper.server.quorum.Follower.followLeader(Follower.java:78)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:622)
[junit] 2010-03-09 21:31:36,984 - WARN [LearnerHandler-/127.0.0.1:53736:LearnerHandler@449] - ******* GOODBYE /127.0.0.1:53736 ********
[junit] 2010-03-09 21:31:36,984 - WARN [LearnerHandler-/127.0.0.1:53738:LearnerHandler@449] - ******* GOODBYE /127.0.0.1:53738 ********
[junit] 2010-03-09 21:31:36,985 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11224:Follower@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:626)
[junit] 2010-03-09 21:31:36,984 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11223:Follower@82] - Exception when following the leader
[junit] java.io.EOFException
[junit] at java.io.DataInputStream.readInt(DataInputStream.java:375)
[junit] at org.apache.jute.BinaryInputArchive.readInt(BinaryInputArchive.java:63)
[junit] at org.apache.zookeeper.server.quorum.QuorumPacket.deserialize(QuorumPacket.java:84)
[junit] at org.apache.jute.BinaryInputArchive.readRecord(BinaryInputArchive.java:108)
[junit] at org.apache.zookeeper.server.quorum.Learner.readPacket(Learner.java:140)
[junit] at org.apache.zookeeper.server.quorum.Follower.followLeader(Follower.java:78)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:622)
[junit] 2010-03-09 21:31:36,985 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11224:FinalRequestProcessor@366] - shutdown of request processor complete
[junit] 2010-03-09 21:31:36,986 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11226:NIOServerCnxn$Factory@275] - NIOServerCnxn factory exited run method
[junit] 2010-03-09 21:31:36,985 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11225:Follower@82] - Exception when following the leader
[junit] java.io.EOFException
[junit] at java.io.DataInputStream.readInt(DataInputStream.java:375)
[junit] at org.apache.jute.BinaryInputArchive.readInt(BinaryInputArchive.java:63)
[junit] at org.apache.zookeeper.server.quorum.QuorumPacket.deserialize(QuorumPacket.java:84)
[junit] at org.apache.jute.BinaryInputArchive.readRecord(BinaryInputArchive.java:108)
[junit] at org.apache.zookeeper.server.quorum.Learner.readPacket(Learner.java:140)
[junit] at org.apache.zookeeper.server.quorum.Follower.followLeader(Follower.java:78)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:622)
[junit] 2010-03-09 21:31:36,987 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11225:Follower@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:626)
[junit] 2010-03-09 21:31:36,988 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11225:FinalRequestProcessor@366] - shutdown of request processor complete
[junit] 2010-03-09 21:31:36,985 - INFO [ProcessThread:-1:PrepRequestProcessor@119] - PrepRequestProcessor exited loop!
[junit] 2010-03-09 21:31:36,985 - WARN [LearnerHandler-/127.0.0.1:53737:LearnerHandler@449] - ******* GOODBYE /127.0.0.1:53737 ********
[junit] 2010-03-09 21:31:36,988 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11225:QuorumPeer@650] - QuorumPeer main thread exited
[junit] 2010-03-09 21:31:36,986 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11224:QuorumPeer@650] - QuorumPeer main thread exited
[junit] 2010-03-09 21:31:36,986 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11223:Follower@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:626)
[junit] 2010-03-09 21:31:36,989 - INFO [QuorumPeer:/0:0:0:0:0:0:0:0:11223:FinalRequestProcessor@366] - shutdown of request processor complete
[junit] 2010-03-09 21:31:36,989 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11223:QuorumPeer@650] - QuorumPeer main thread exited
[junit] 2010-03-09 21:31:36,989 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11224:MBeanRegistry@96] - Failed to unregister MBean replica.3
[junit] 2010-03-09 21:31:36,989 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11223:MBeanRegistry@96] - Failed to unregister MBean replica.3
[junit] 2010-03-09 21:31:36,990 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11223:MBeanRegistry@126] - Error during unregister
[junit] javax.management.InstanceNotFoundException: org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.3
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregister(MBeanRegistry.java:94)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregisterAll(MBeanRegistry.java:124)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:652)
[junit] 2010-03-09 21:31:36,990 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11223:MBeanRegistry@96] - Failed to unregister MBean replica.2
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 24.084 sec
[junit] 2010-03-09 21:31:36,990 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11223:MBeanRegistry@126] - Error during unregister
[junit] javax.management.InstanceNotFoundException: org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregister(MBeanRegistry.java:94)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregisterAll(MBeanRegistry.java:124)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:652)
[junit] 2010-03-09 21:31:36,990 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11223:MBeanRegistry@96] - Failed to unregister MBean replica.1
[junit] 2010-03-09 21:31:36,990 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11223:MBeanRegistry@126] - Error during unregister
[junit] javax.management.InstanceNotFoundException: org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregister(MBeanRegistry.java:94)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregisterAll(MBeanRegistry.java:124)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:652)
[junit] 2010-03-09 21:31:36,989 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11224:MBeanRegistry@126] - Error during unregister
[junit] javax.management.InstanceNotFoundException: org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.3
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregister(MBeanRegistry.java:94)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregisterAll(MBeanRegistry.java:124)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:652)
[junit] 2010-03-09 21:31:36,991 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11224:MBeanRegistry@96] - Failed to unregister MBean replica.2
[junit] 2010-03-09 21:31:36,991 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11224:MBeanRegistry@126] - Error during unregister
[junit] javax.management.InstanceNotFoundException: org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregister(MBeanRegistry.java:94)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregisterAll(MBeanRegistry.java:124)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:652)
[junit] 2010-03-09 21:31:36,991 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11224:MBeanRegistry@96] - Failed to unregister MBean replica.1
[junit] 2010-03-09 21:31:36,992 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11224:MBeanRegistry@126] - Error during unregister
[junit] javax.management.InstanceNotFoundException: org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregister(MBeanRegistry.java:94)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregisterAll(MBeanRegistry.java:124)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:652)
[junit] 2010-03-09 21:31:36,992 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11224:MBeanRegistry@96] - Failed to unregister MBean replica.5
[junit] 2010-03-09 21:31:36,992 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11225:MBeanRegistry@96] - Failed to unregister MBean replica.5
[junit] 2010-03-09 21:31:36,992 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11225:MBeanRegistry@126] - Error during unregister
[junit] javax.management.InstanceNotFoundException: org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.5
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregister(MBeanRegistry.java:94)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregisterAll(MBeanRegistry.java:124)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:652)
[junit] 2010-03-09 21:31:36,992 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11225:MBeanRegistry@96] - Failed to unregister MBean ReplicatedServer_id1
[junit] 2010-03-09 21:31:36,992 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11225:MBeanRegistry@126] - Error during unregister
[junit] javax.management.InstanceNotFoundException: org.apache.ZooKeeperService:name0=ReplicatedServer_id1
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregister(MBeanRegistry.java:94)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregisterAll(MBeanRegistry.java:124)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:652)
[junit] 2010-03-09 21:31:36,992 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11224:MBeanRegistry@126] - Error during unregister
[junit] javax.management.InstanceNotFoundException: org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.5
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregister(MBeanRegistry.java:94)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregisterAll(MBeanRegistry.java:124)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:652)
[junit] 2010-03-09 21:31:36,993 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11224:MBeanRegistry@96] - Failed to unregister MBean ReplicatedServer_id1
[junit] 2010-03-09 21:31:36,993 - WARN [QuorumPeer:/0:0:0:0:0:0:0:0:11224:MBeanRegistry@126] - Error during unregister
[junit] javax.management.InstanceNotFoundException: org.apache.ZooKeeperService:name0=ReplicatedServer_id1
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregister(MBeanRegistry.java:94)
[junit] at org.apache.zookeeper.jmx.MBeanRegistry.unregisterAll(MBeanRegistry.java:124)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:652)
[junit] 2010-03-09 21:31:37,676 - INFO [main:PortAssignment@31] - assigning port 11221
[junit] Running org.apache.zookeeper.test.ZooKeeperQuotaTest
[junit] 2010-03-09 21:31:37,728 - INFO [main:ClientBase@377] - STARTING testQuota
[junit] 2010-03-09 21:31:37,907 - INFO [main:ClientBase@406] - STARTING server
[junit] 2010-03-09 21:31:37,918 - INFO [main:Environment@97] - Server environment:zookeeper.version=3.3.0-921130, built on 03/09/2010 21:19 GMT
[junit] 2010-03-09 21:31:37,919 - INFO [main:Environment@97] - Server environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2010-03-09 21:31:37,920 - INFO [main:Environment@97] - Server environment:java.version=1.6.0_11
[junit] 2010-03-09 21:31:37,920 - INFO [main:Environment@97] - Server environment:java.vendor=Sun Microsystems Inc.
[junit] 2010-03-09 21:31:37,921 - INFO [main:Environment@97] - Server environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] 2010-03-09 21:31:37,921 - INFO [main:Environment@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.7.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-03-09 21:31:37,922 - INFO [main:Environment@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-03-09 21:31:37,923 - INFO [main:Environment@97] - Server environment:java.io.tmpdir=/tmp
[junit] 2010-03-09 21:31:37,923 - INFO [main:Environment@97] - Server environment:java.compiler=<NA>
[junit] 2010-03-09 21:31:37,924 - INFO [main:Environment@97] - Server environment:os.name=Linux
[junit] 2010-03-09 21:31:37,924 - INFO [main:Environment@97] - Server environment:os.arch=i386
[junit] 2010-03-09 21:31:37,925 - INFO [main:Environment@97] - Server environment:os.version=2.6.28-17-generic
[junit] 2010-03-09 21:31:37,925 - INFO [main:Environment@97] - Server environment:user.name=hudson
[junit] 2010-03-09 21:31:37,926 - INFO [main:Environment@97] - Server environment:user.home=/homes/hudson
[junit] 2010-03-09 21:31:37,926 - INFO [main:Environment@97] - Server environment:user.dir=<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2010-03-09 21:31:37,951 - INFO [main:ZooKeeperServer@144] - Created server with tickTime 3000 datadir <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test5066931134549552606.junit.dir/version-2> snapdir <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test5066931134549552606.junit.dir/version-2>
[junit] 2010-03-09 21:31:37,973 - INFO [main:NIOServerCnxn$Factory@145] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2010-03-09 21:31:37,980 - INFO [main:FileTxnSnapLog@208] - Snapshotting: 0
[junit] 2010-03-09 21:31:38,014 - INFO [main:ClientBase@219] - connecting to 127.0.0.1 11221
[junit] 2010-03-09 21:31:38,017 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn$Factory@248] - Accepted socket connection from /127.0.0.1:47134
[junit] 2010-03-09 21:31:38,020 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@948] - Processing stat command from /127.0.0.1:47134
[junit] 2010-03-09 21:31:38,023 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@1232] - Closed socket connection for client /127.0.0.1:47134 (no session established for client)
[junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port]
[junit] 2010-03-09 21:31:38,029 - INFO [main:ClientBase@398] - Initial fdcount is: 32
[junit] 2010-03-09 21:31:38,029 - INFO [main:ClientBase@402] - Client test setup finished
[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-03-09 21:31:38,037 - INFO [main:Environment@97] - Client environment:zookeeper.version=3.3.0-921130, built on 03/09/2010 21:19 GMT
[junit] 2010-03-09 21:31:38,037 - INFO [main:Environment@97] - Client environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2010-03-09 21:31:38,038 - INFO [main:Environment@97] - Client environment:java.version=1.6.0_11
[junit] 2010-03-09 21:31:38,038 - INFO [main:Environment@97] - Client environment:java.vendor=Sun Microsystems Inc.
[junit] 2010-03-09 21:31:38,039 - INFO [main:Environment@97] - Client environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] 2010-03-09 21:31:38,039 - INFO [main:Environment@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.7.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-03-09 21:31:38,040 - INFO [main:Environment@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-03-09 21:31:38,040 - INFO [main:Environment@97] - Client environment:java.io.tmpdir=/tmp
[junit] 2010-03-09 21:31:38,041 - INFO [main:Environment@97] - Client environment:java.compiler=<NA>
[junit] 2010-03-09 21:31:38,041 - INFO [main:Environment@97] - Client environment:os.name=Linux
[junit] 2010-03-09 21:31:38,042 - INFO [main:Environment@97] - Client environment:os.arch=i386
[junit] 2010-03-09 21:31:38,042 - INFO [main:Environment@97] - Client environment:os.version=2.6.28-17-generic
[junit] 2010-03-09 21:31:38,043 - INFO [main:Environment@97] - Client environment:user.name=hudson
[junit] 2010-03-09 21:31:38,043 - INFO [main:Environment@97] - Client environment:user.home=/homes/hudson
[junit] 2010-03-09 21:31:38,043 - INFO [main:Environment@97] - Client environment:user.dir=<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2010-03-09 21:31:38,045 - INFO [main:ZooKeeper@373] - Initiating client connection, connectString=127.0.0.1:11221 sessionTimeout=30000 watcher=org.apache.zookeeper.test.ClientBase$CountdownWatcher@189c036
[junit] 2010-03-09 21:31:38,061 - INFO [main-SendThread():ClientCnxn$SendThread@1000] - Opening socket connection to server /127.0.0.1:11221
[junit] 2010-03-09 21:31:38,062 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn$Factory@248] - Accepted socket connection from /127.0.0.1:47135
[junit] 2010-03-09 21:31:38,062 - INFO [main-SendThread(localhost:11221):ClientCnxn$SendThread@908] - Socket connection established to localhost/127.0.0.1:11221, initiating session
[junit] 2010-03-09 21:31:38,066 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@737] - Client attempting to establish new session at /127.0.0.1:47135
[junit] 2010-03-09 21:31:38,092 - INFO [SyncThread:0:NIOServerCnxn@1387] - Established session 0x12744d712860000 with negotiated timeout 30000 for client /127.0.0.1:47135
[junit] 2010-03-09 21:31:38,092 - INFO [main-SendThread(localhost:11221):ClientCnxn$SendThread@701] - Session establishment complete on server localhost/127.0.0.1:11221, sessionid = 0x12744d712860000, negotiated timeout = 30000
[junit] expect:0x12744d712860000
[junit] found:0x12744d712860000 org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x12744d712860000
[junit] 2010-03-09 21:31:38,153 - INFO [main:ClientBase@436] - tearDown starting
[junit] 2010-03-09 21:31:38,153 - INFO [main:ClientBase@448] - fdcount after test is: 38
[junit] 2010-03-09 21:31:38,154 - INFO [ProcessThread:-1:PrepRequestProcessor@385] - Processed session termination for sessionid: 0x12744d712860000
[junit] 2010-03-09 21:31:38,155 - INFO [main:ZooKeeper@538] - Session: 0x12744d712860000 closed
[junit] 2010-03-09 21:31:38,155 - INFO [main:ClientBase@413] - STOPPING server
[junit] 2010-03-09 21:31:38,156 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@1232] - Closed socket connection for client /127.0.0.1:47135 which had sessionid 0x12744d712860000
[junit] 2010-03-09 21:31:38,156 - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn$Factory@275] - NIOServerCnxn factory exited run method
[junit] 2010-03-09 21:31:38,156 - INFO [ProcessThread:-1:PrepRequestProcessor@119] - PrepRequestProcessor exited loop!
[junit] 2010-03-09 21:31:38,157 - INFO [SyncThread:0:SyncRequestProcessor@151] - SyncRequestProcessor exited!
[junit] 2010-03-09 21:31:38,156 - INFO [main:FinalRequestProcessor@366] - shutdown of request processor complete
[junit] 2010-03-09 21:31:38,158 - INFO [main:ClientBase@219] - connecting to 127.0.0.1 11221
[junit] ensureOnly:[]
[junit] 2010-03-09 21:31:38,161 - INFO [main:ClientBase@467] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.482 sec
BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:907: The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:818: Tests failed!
Total time: 12 minutes 9 seconds
Publishing Javadoc
Archiving artifacts
Recording test results
Recording fingerprints
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure