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 2008/11/15 12:16:54 UTC
Build failed in Hudson: ZooKeeper-trunk #144
See http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/144/changes
------------------------------------------
[...truncated 40564 lines...]
[junit] 2008-11-15 11:16:05,789 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:57379 lastZxid 5
[junit] 2008-11-15 11:16:05,790 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11d9fdd485f0000: true
[junit] 2008-11-15 11:16:05,790 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11d9fdd485f0000
[junit] 2008-11-15 11:16:06,065 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-15 11:16:06,066 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-15 11:16:06,067 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11d9fdd485f0000 to sun.nio.ch.SelectionKeyImpl@1315d34
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-15 11:16:06,069 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-15 11:16:06,069 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-15 11:16:06,176 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-15 11:16:06,177 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-15 11:16:06,179 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test14947.junit.dir/version-2/snapshot.5
[junit] 2008-11-15 11:16:06,270 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-15 11:16:07,856 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-15 11:16:07,857 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:57884 remote=/127.0.0.1:33221]
[junit] 2008-11-15 11:16:07,857 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:57884 lastZxid 6
[junit] 2008-11-15 11:16:07,858 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11d9fdd485f0000: true
[junit] 2008-11-15 11:16:07,858 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11d9fdd485f0000
[junit] 2008-11-15 11:16:07,895 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-15 11:16:08,926 - INFO [main:ZooKeeper@398] - Closing session: 0x11d9fdd485f0000
[junit] 2008-11-15 11:16:08,927 - INFO [main:ClientCnxn@961] - Closing ClientCnxn for session: 0x11d9fdd485f0000
[junit] 2008-11-15 11:16:08,928 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11d9fdd485f0000
[junit] 2008-11-15 11:16:08,958 - INFO [main-SendThread:ClientCnxn$SendThread@854] - Exception while closing send thread for session 0x11d9fdd485f0000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-15 11:16:09,066 - INFO [main:ClientCnxn@947] - Disconnecting ClientCnxn for session: 0x11d9fdd485f0000
[junit] 2008-11-15 11:16:09,067 - INFO [main:ZooKeeper@406] - Session: 0x11d9fdd485f0000 closed
[junit] 2008-11-15 11:16:09,067 - INFO [main:ClientBase@314] - tearDown starting
[junit] 2008-11-15 11:16:09,069 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-15 11:16:09,069 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-15 11:16:09,073 - INFO [main-EventThread:ClientCnxn$EventThread@426] - EventThread shut down
[junit] 2008-11-15 11:16:09,110 - INFO [main:ClientBase@326] - FINISHED testWatcherAutoResetWithGlobal
[junit] 2008-11-15 11:16:09,111 - INFO [main:ClientBase@288] - STARTING testWatcherAutoResetWithLocal
[junit] 2008-11-15 11:16:09,112 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-15 11:16:09,113 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 0
[junit] 2008-11-15 11:16:09,115 - INFO [main:ClientBase@298] - Client test setup finished
[junit] 2008-11-15 11:16:09,115 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-15 11:16:09,116 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:58193 remote=/127.0.0.1:33221]
[junit] 2008-11-15 11:16:09,117 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:58193 lastZxid 0
[junit] 2008-11-15 11:16:09,117 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@514] - Creating new session 0x11d9fdd71190000
[junit] 2008-11-15 11:16:09,158 - WARN [SyncThread:0:NIOServerCnxn@796] - Finished init of 0x11d9fdd71190000: true
[junit] 2008-11-15 11:16:09,245 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-15 11:16:09,246 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11d9fdd71190000 to sun.nio.ch.SelectionKeyImpl@12d96f2
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-15 11:16:09,247 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-15 11:16:09,247 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-15 11:16:09,346 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-15 11:16:09,347 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-15 11:16:09,348 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test14948.junit.dir/version-2/snapshot.0
[junit] 2008-11-15 11:16:09,349 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 3
[junit] 2008-11-15 11:16:10,546 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-15 11:16:10,547 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:58561 remote=/127.0.0.1:33221]
[junit] 2008-11-15 11:16:10,547 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:58561 lastZxid 3
[junit] 2008-11-15 11:16:10,548 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11d9fdd71190000: true
[junit] 2008-11-15 11:16:10,548 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11d9fdd71190000
[junit] 2008-11-15 11:16:14,646 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-15 11:16:14,648 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-15 11:16:14,648 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-15 11:16:14,649 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11d9fdd71190000 to sun.nio.ch.SelectionKeyImpl@75e4fc
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-15 11:16:15,266 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-15 11:16:15,267 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-15 11:16:15,268 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test14948.junit.dir/version-2/snapshot.3
[junit] 2008-11-15 11:16:15,270 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 5
[junit] 2008-11-15 11:16:16,166 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-15 11:16:16,167 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:59893 remote=/127.0.0.1:33221]
[junit] 2008-11-15 11:16:16,168 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:59893 lastZxid 5
[junit] 2008-11-15 11:16:16,168 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11d9fdd71190000: true
[junit] 2008-11-15 11:16:16,168 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11d9fdd71190000
[junit] 2008-11-15 11:16:16,507 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-15 11:16:16,509 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-15 11:16:16,510 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11d9fdd71190000 to sun.nio.ch.SelectionKeyImpl@1ff0dde
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-15 11:16:16,511 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-15 11:16:16,511 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-15 11:16:16,616 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-15 11:16:16,617 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-15 11:16:16,618 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test14948.junit.dir/version-2/snapshot.5
[junit] 2008-11-15 11:16:16,828 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-15 11:16:18,426 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-15 11:16:18,427 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:60432 remote=/127.0.0.1:33221]
[junit] 2008-11-15 11:16:18,428 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:60432 lastZxid 6
[junit] 2008-11-15 11:16:18,428 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11d9fdd71190000: true
[junit] 2008-11-15 11:16:18,428 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11d9fdd71190000
[junit] 2008-11-15 11:16:18,474 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-15 11:16:19,506 - INFO [main:ZooKeeper@398] - Closing session: 0x11d9fdd71190000
[junit] 2008-11-15 11:16:19,506 - INFO [main:ClientCnxn@961] - Closing ClientCnxn for session: 0x11d9fdd71190000
[junit] 2008-11-15 11:16:19,507 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11d9fdd71190000
[junit] 2008-11-15 11:16:19,534 - INFO [main-SendThread:ClientCnxn$SendThread@854] - Exception while closing send thread for session 0x11d9fdd71190000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-15 11:16:19,636 - INFO [main:ClientCnxn@947] - Disconnecting ClientCnxn for session: 0x11d9fdd71190000
[junit] 2008-11-15 11:16:19,636 - INFO [main:ZooKeeper@406] - Session: 0x11d9fdd71190000 closed
[junit] 2008-11-15 11:16:19,637 - INFO [main:ClientBase@314] - tearDown starting
[junit] 2008-11-15 11:16:19,637 - INFO [main-EventThread:ClientCnxn$EventThread@426] - EventThread shut down
[junit] 2008-11-15 11:16:19,638 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-15 11:16:19,638 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-15 11:16:19,687 - INFO [main:ClientBase@326] - FINISHED testWatcherAutoResetWithLocal
[junit] 2008-11-15 11:16:19,688 - INFO [main:ClientBase@288] - STARTING testWatcherAutoResetDisabledWithGlobal
[junit] 2008-11-15 11:16:19,689 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-15 11:16:19,690 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 0
[junit] 2008-11-15 11:16:19,692 - INFO [main:ClientBase@298] - Client test setup finished
[junit] 2008-11-15 11:16:19,692 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-15 11:16:19,693 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:60751 remote=/127.0.0.1:33221]
[junit] 2008-11-15 11:16:19,693 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:60751 lastZxid 0
[junit] 2008-11-15 11:16:19,694 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@514] - Creating new session 0x11d9fdd9a6a0000
[junit] 2008-11-15 11:16:19,737 - WARN [SyncThread:0:NIOServerCnxn@796] - Finished init of 0x11d9fdd9a6a0000: true
[junit] 2008-11-15 11:16:19,823 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-15 11:16:19,824 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11d9fdd9a6a0000 to sun.nio.ch.SelectionKeyImpl@18f1d7e
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-15 11:16:19,824 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-15 11:16:19,825 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-15 11:16:19,926 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-15 11:16:19,927 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-15 11:16:19,928 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test14949.junit.dir/version-2/snapshot.0
[junit] 2008-11-15 11:16:19,929 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 3
[junit] 2008-11-15 11:16:21,416 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-15 11:16:21,417 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:61147 remote=/127.0.0.1:33221]
[junit] 2008-11-15 11:16:21,417 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:61147 lastZxid 3
[junit] 2008-11-15 11:16:21,417 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11d9fdd9a6a0000: true
[junit] 2008-11-15 11:16:21,419 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11d9fdd9a6a0000
[junit] 2008-11-15 11:16:28,476 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-15 11:16:28,477 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11d9fdd9a6a0000 to sun.nio.ch.SelectionKeyImpl@153f67e
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-15 11:16:28,477 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-15 11:16:28,478 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-15 11:16:28,586 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-15 11:16:28,587 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-15 11:16:28,588 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test14949.junit.dir/version-2/snapshot.3
[junit] 2008-11-15 11:16:28,590 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 5
[junit] 2008-11-15 11:16:30,586 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-15 11:16:30,586 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:63404 remote=/127.0.0.1:33221]
[junit] 2008-11-15 11:16:30,587 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:63404 lastZxid 5
[junit] 2008-11-15 11:16:30,587 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11d9fdd9a6a0000: true
[junit] 2008-11-15 11:16:30,588 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11d9fdd9a6a0000
[junit] 2008-11-15 11:16:30,639 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-15 11:16:30,640 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-15 11:16:30,641 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11d9fdd9a6a0000 to sun.nio.ch.SelectionKeyImpl@1b15692
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-15 11:16:30,641 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-15 11:16:30,642 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-15 11:16:30,746 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-15 11:16:30,746 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-15 11:16:30,748 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test14949.junit.dir/version-2/snapshot.5
[junit] 2008-11-15 11:16:30,783 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-15 11:16:32,086 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-15 11:16:32,086 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:63725 remote=/127.0.0.1:33221]
[junit] 2008-11-15 11:16:32,087 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:63725 lastZxid 6
[junit] 2008-11-15 11:16:32,087 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11d9fdd9a6a0000: true
[junit] 2008-11-15 11:16:32,087 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11d9fdd9a6a0000
[junit] 2008-11-15 11:16:33,506 - INFO [main:ZooKeeper@398] - Closing session: 0x11d9fdd9a6a0000
[junit] 2008-11-15 11:16:33,506 - INFO [main:ClientCnxn@961] - Closing ClientCnxn for session: 0x11d9fdd9a6a0000
[junit] 2008-11-15 11:16:33,507 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11d9fdd9a6a0000
[junit] 2008-11-15 11:16:33,554 - INFO [main-SendThread:ClientCnxn$SendThread@854] - Exception while closing send thread for session 0x11d9fdd9a6a0000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-15 11:16:33,656 - INFO [main:ClientCnxn@947] - Disconnecting ClientCnxn for session: 0x11d9fdd9a6a0000
[junit] 2008-11-15 11:16:33,656 - INFO [main:ZooKeeper@406] - Session: 0x11d9fdd9a6a0000 closed
[junit] 2008-11-15 11:16:33,657 - INFO [main:ClientBase@314] - tearDown starting
[junit] 2008-11-15 11:16:33,656 - INFO [main-EventThread:ClientCnxn$EventThread@426] - EventThread shut down
[junit] 2008-11-15 11:16:33,660 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-15 11:16:33,660 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-15 11:16:33,700 - INFO [main:ClientBase@326] - FINISHED testWatcherAutoResetDisabledWithGlobal
[junit] 2008-11-15 11:16:33,701 - INFO [main:ClientBase@288] - STARTING testWatcherAutoResetDisabledWithLocal
[junit] 2008-11-15 11:16:33,701 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-15 11:16:33,703 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 0
[junit] 2008-11-15 11:16:33,705 - INFO [main:ClientBase@298] - Client test setup finished
[junit] 2008-11-15 11:16:33,706 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-15 11:16:33,706 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:64132 remote=/127.0.0.1:33221]
[junit] 2008-11-15 11:16:33,707 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:64132 lastZxid 0
[junit] 2008-11-15 11:16:33,708 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@514] - Creating new session 0x11d9fddd1270000
[junit] 2008-11-15 11:16:33,745 - WARN [SyncThread:0:NIOServerCnxn@796] - Finished init of 0x11d9fddd1270000: true
[junit] 2008-11-15 11:16:33,819 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-15 11:16:33,820 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11d9fddd1270000 to sun.nio.ch.SelectionKeyImpl@1c80b01
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-15 11:16:33,821 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-15 11:16:33,823 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-15 11:16:33,926 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-15 11:16:33,926 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-15 11:16:33,927 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test14950.junit.dir/version-2/snapshot.0
[junit] 2008-11-15 11:16:33,929 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 3
[junit] 2008-11-15 11:16:35,166 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-15 11:16:35,166 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:64496 remote=/127.0.0.1:33221]
[junit] 2008-11-15 11:16:35,167 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:64496 lastZxid 3
[junit] 2008-11-15 11:16:35,167 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11d9fddd1270000: true
[junit] 2008-11-15 11:16:35,167 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11d9fddd1270000
[junit] 2008-11-15 11:16:41,286 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-15 11:16:41,286 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11d9fddd1270000 to sun.nio.ch.SelectionKeyImpl@145f0e3
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-15 11:16:41,287 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-15 11:16:41,287 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-15 11:16:41,396 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-15 11:16:41,396 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-15 11:16:41,398 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test14950.junit.dir/version-2/snapshot.3
[junit] 2008-11-15 11:16:41,400 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 5
[junit] 2008-11-15 11:16:43,276 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-15 11:16:43,277 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:33639 remote=/127.0.0.1:33221]
[junit] 2008-11-15 11:16:43,278 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:33639 lastZxid 5
[junit] 2008-11-15 11:16:43,279 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11d9fddd1270000: true
[junit] 2008-11-15 11:16:43,279 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11d9fddd1270000
[junit] 2008-11-15 11:16:43,316 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-15 11:16:43,317 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-15 11:16:43,318 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11d9fddd1270000 to sun.nio.ch.SelectionKeyImpl@b8f8eb
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-15 11:16:43,319 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-15 11:16:43,320 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-15 11:16:43,426 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-15 11:16:43,426 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-15 11:16:43,428 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test14950.junit.dir/version-2/snapshot.5
[junit] 2008-11-15 11:16:43,430 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-15 11:16:45,026 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-15 11:16:45,026 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:34080 remote=/127.0.0.1:33221]
[junit] 2008-11-15 11:16:45,027 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:34080 lastZxid 6
[junit] 2008-11-15 11:16:45,027 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11d9fddd1270000: true
[junit] 2008-11-15 11:16:45,027 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11d9fddd1270000
[junit] 2008-11-15 11:16:46,105 - INFO [main:ZooKeeper@398] - Closing session: 0x11d9fddd1270000
[junit] 2008-11-15 11:16:46,106 - INFO [main:ClientCnxn@961] - Closing ClientCnxn for session: 0x11d9fddd1270000
[junit] 2008-11-15 11:16:46,107 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11d9fddd1270000
[junit] 2008-11-15 11:16:46,363 - INFO [main-SendThread:ClientCnxn$SendThread@854] - Exception while closing send thread for session 0x11d9fddd1270000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-15 11:16:46,465 - INFO [main:ClientCnxn@947] - Disconnecting ClientCnxn for session: 0x11d9fddd1270000
[junit] 2008-11-15 11:16:46,466 - INFO [main:ZooKeeper@406] - Session: 0x11d9fddd1270000 closed
[junit] 2008-11-15 11:16:46,466 - INFO [main-EventThread:ClientCnxn$EventThread@426] - EventThread shut down
[junit] 2008-11-15 11:16:46,466 - INFO [main:ClientBase@314] - tearDown starting
[junit] 2008-11-15 11:16:46,468 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-15 11:16:46,469 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-15 11:16:46,623 - INFO [main:ClientBase@326] - FINISHED testWatcherAutoResetDisabledWithLocal
[junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 50.046 sec
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build.xml:547: Tests failed!
Total time: 27 minutes 39 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Hudson build is back to normal: ZooKeeper-trunk #149
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/149/changes
Build failed in Hudson: ZooKeeper-trunk #148
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/148/changes
Changes:
[mahadev] ZOOKEEPER-204. SetWatches needs to be the first message after auth messages to the server (ben via mahadev)
[phunt] ZOOKEEPER-211 Not all Mock tests are working
[mahadev] ZOOKEEPER-226. fix exists calls that fail on server if node has null data. (mahadev)
[mahadev] ZOOKEEPER-206. documentation tab should contain the version number and other small site changes. (pat via mahadev)
[mahadev] ZOOKEEPER-218. fix the error in the barrier example code. (pat via mahadev)
[mahadev] ZOOKEEPER-228. fix apache header missing in DBTest. (mahadev)
[mahadev] ZOOKEEPER-217. Fix errors in config to be thrown as Exceptions. (mahadev)
------------------------------------------
[...truncated 43649 lines...]
[junit] 2008-11-19 10:46:58,279 - INFO [main:ClientBase@309] - STARTING server
[junit] 2008-11-19 10:46:58,280 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-19 10:46:58,282 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test23400.junit.dir/version-2/snapshot.3
[junit] 2008-11-19 10:46:58,285 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 5
[junit] 2008-11-19 10:46:59,789 - INFO [main-SendThread:ClientCnxn$SendThread@776] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-19 10:46:59,790 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:58571 remote=/127.0.0.1:33221]
[junit] 2008-11-19 10:46:59,790 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:58571 lastZxid 5
[junit] 2008-11-19 10:46:59,791 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11db45c15010000: true
[junit] 2008-11-19 10:46:59,791 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11db45c15010000
[junit] 2008-11-19 10:46:59,834 - INFO [main:ClientBase@303] - STOPPING server
[junit] 2008-11-19 10:46:59,835 - WARN [main-SendThread:ClientCnxn$SendThread@874] - Exception closing session 0x11db45c15010000 to sun.nio.ch.SelectionKeyImpl@142bece
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:852)
[junit] 2008-11-19 10:46:59,835 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-19 10:46:59,836 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-19 10:46:59,939 - INFO [main:ClientBase@309] - STARTING server
[junit] 2008-11-19 10:46:59,939 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-19 10:46:59,941 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test23400.junit.dir/version-2/snapshot.5
[junit] 2008-11-19 10:46:59,943 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-19 10:47:01,159 - INFO [main-SendThread:ClientCnxn$SendThread@776] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-19 10:47:01,159 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:58576 remote=/127.0.0.1:33221]
[junit] 2008-11-19 10:47:01,160 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:58576 lastZxid 6
[junit] 2008-11-19 10:47:01,160 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11db45c15010000: true
[junit] 2008-11-19 10:47:01,161 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11db45c15010000
[junit] 2008-11-19 10:47:02,199 - INFO [main:ZooKeeper@401] - Closing session: 0x11db45c15010000
[junit] 2008-11-19 10:47:02,199 - INFO [main:ClientCnxn@975] - Closing ClientCnxn for session: 0x11db45c15010000
[junit] 2008-11-19 10:47:02,200 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11db45c15010000
[junit] 2008-11-19 10:47:02,225 - INFO [main-SendThread:ClientCnxn$SendThread@868] - Exception while closing send thread for session 0x11db45c15010000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-19 10:47:02,329 - INFO [main:ClientCnxn@961] - Disconnecting ClientCnxn for session: 0x11db45c15010000
[junit] 2008-11-19 10:47:02,329 - INFO [main:ZooKeeper@409] - Session: 0x11db45c15010000 closed
[junit] 2008-11-19 10:47:02,330 - INFO [main:ClientBase@315] - tearDown starting
[junit] 2008-11-19 10:47:02,330 - INFO [main-EventThread:ClientCnxn$EventThread@435] - EventThread shut down
[junit] 2008-11-19 10:47:02,331 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-19 10:47:02,331 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-19 10:47:02,333 - INFO [main:ClientBase@327] - FINISHED testWatcherAutoResetWithGlobal
[junit] 2008-11-19 10:47:02,334 - INFO [main:ClientBase@289] - STARTING testWatcherAutoResetWithLocal
[junit] 2008-11-19 10:47:02,334 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-19 10:47:02,335 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 0
[junit] 2008-11-19 10:47:02,337 - INFO [main:ClientBase@299] - Client test setup finished
[junit] 2008-11-19 10:47:02,338 - INFO [main-SendThread:ClientCnxn$SendThread@776] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-19 10:47:02,338 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:58579 remote=/127.0.0.1:33221]
[junit] 2008-11-19 10:47:02,339 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:58579 lastZxid 0
[junit] 2008-11-19 10:47:02,340 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@514] - Creating new session 0x11db45c39c00000
[junit] 2008-11-19 10:47:02,353 - WARN [SyncThread:0:NIOServerCnxn@796] - Finished init of 0x11db45c39c00000: true
[junit] 2008-11-19 10:47:02,389 - INFO [main:ClientBase@303] - STOPPING server
[junit] 2008-11-19 10:47:02,390 - WARN [main-SendThread:ClientCnxn$SendThread@874] - Exception closing session 0x11db45c39c00000 to sun.nio.ch.SelectionKeyImpl@11a75a2
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:852)
[junit] 2008-11-19 10:47:02,390 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-19 10:47:02,391 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-19 10:47:02,499 - INFO [main:ClientBase@309] - STARTING server
[junit] 2008-11-19 10:47:02,499 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-19 10:47:02,501 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test23401.junit.dir/version-2/snapshot.0
[junit] 2008-11-19 10:47:02,502 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 3
[junit] 2008-11-19 10:47:04,189 - INFO [main-SendThread:ClientCnxn$SendThread@776] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-19 10:47:04,189 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:58582 remote=/127.0.0.1:33221]
[junit] 2008-11-19 10:47:04,190 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:58582 lastZxid 3
[junit] 2008-11-19 10:47:04,190 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11db45c39c00000: true
[junit] 2008-11-19 10:47:04,191 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11db45c39c00000
[junit] 2008-11-19 10:47:08,229 - INFO [main:ClientBase@303] - STOPPING server
[junit] 2008-11-19 10:47:08,229 - WARN [main-SendThread:ClientCnxn$SendThread@874] - Exception closing session 0x11db45c39c00000 to sun.nio.ch.SelectionKeyImpl@1fe1feb
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:852)
[junit] 2008-11-19 10:47:08,230 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-19 10:47:08,231 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-19 10:47:08,849 - INFO [main:ClientBase@309] - STARTING server
[junit] 2008-11-19 10:47:08,849 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-19 10:47:08,851 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test23401.junit.dir/version-2/snapshot.3
[junit] 2008-11-19 10:47:08,853 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 5
[junit] 2008-11-19 10:47:09,499 - INFO [main-SendThread:ClientCnxn$SendThread@776] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-19 10:47:09,499 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:58585 remote=/127.0.0.1:33221]
[junit] 2008-11-19 10:47:09,500 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:58585 lastZxid 5
[junit] 2008-11-19 10:47:09,500 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11db45c39c00000: true
[junit] 2008-11-19 10:47:09,500 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11db45c39c00000
[junit] 2008-11-19 10:47:09,523 - INFO [main:ClientBase@303] - STOPPING server
[junit] 2008-11-19 10:47:09,524 - WARN [main-SendThread:ClientCnxn$SendThread@874] - Exception closing session 0x11db45c39c00000 to sun.nio.ch.SelectionKeyImpl@1e845c2
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:852)
[junit] 2008-11-19 10:47:09,524 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-19 10:47:09,526 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-19 10:47:09,629 - INFO [main:ClientBase@309] - STARTING server
[junit] 2008-11-19 10:47:09,629 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-19 10:47:09,631 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test23401.junit.dir/version-2/snapshot.5
[junit] 2008-11-19 10:47:09,634 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-19 10:47:11,069 - INFO [main-SendThread:ClientCnxn$SendThread@776] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-19 10:47:11,069 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:58588 remote=/127.0.0.1:33221]
[junit] 2008-11-19 10:47:11,070 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:58588 lastZxid 6
[junit] 2008-11-19 10:47:11,070 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11db45c39c00000: true
[junit] 2008-11-19 10:47:11,071 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11db45c39c00000
[junit] 2008-11-19 10:47:12,109 - INFO [main:ZooKeeper@401] - Closing session: 0x11db45c39c00000
[junit] 2008-11-19 10:47:12,109 - INFO [main:ClientCnxn@975] - Closing ClientCnxn for session: 0x11db45c39c00000
[junit] 2008-11-19 10:47:12,110 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11db45c39c00000
[junit] 2008-11-19 10:47:12,121 - INFO [main-SendThread:ClientCnxn$SendThread@868] - Exception while closing send thread for session 0x11db45c39c00000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-19 10:47:12,229 - INFO [main:ClientCnxn@961] - Disconnecting ClientCnxn for session: 0x11db45c39c00000
[junit] 2008-11-19 10:47:12,229 - INFO [main:ZooKeeper@409] - Session: 0x11db45c39c00000 closed
[junit] 2008-11-19 10:47:12,230 - INFO [main:ClientBase@315] - tearDown starting
[junit] 2008-11-19 10:47:12,230 - INFO [main-EventThread:ClientCnxn$EventThread@435] - EventThread shut down
[junit] 2008-11-19 10:47:12,231 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-19 10:47:12,231 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-19 10:47:12,233 - INFO [main:ClientBase@327] - FINISHED testWatcherAutoResetWithLocal
[junit] 2008-11-19 10:47:12,234 - INFO [main:ClientBase@289] - STARTING testWatcherAutoResetDisabledWithGlobal
[junit] 2008-11-19 10:47:12,234 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-19 10:47:12,235 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 0
[junit] 2008-11-19 10:47:12,237 - INFO [main:ClientBase@299] - Client test setup finished
[junit] 2008-11-19 10:47:12,238 - INFO [main-SendThread:ClientCnxn$SendThread@776] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-19 10:47:12,238 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:58591 remote=/127.0.0.1:33221]
[junit] 2008-11-19 10:47:12,239 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:58591 lastZxid 0
[junit] 2008-11-19 10:47:12,239 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@514] - Creating new session 0x11db45c606c0000
[junit] 2008-11-19 10:47:12,249 - WARN [SyncThread:0:NIOServerCnxn@796] - Finished init of 0x11db45c606c0000: true
[junit] 2008-11-19 10:47:12,276 - INFO [main:ClientBase@303] - STOPPING server
[junit] 2008-11-19 10:47:12,276 - WARN [main-SendThread:ClientCnxn$SendThread@874] - Exception closing session 0x11db45c606c0000 to sun.nio.ch.SelectionKeyImpl@a613f8
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:852)
[junit] 2008-11-19 10:47:12,277 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-19 10:47:12,278 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-19 10:47:12,379 - INFO [main:ClientBase@309] - STARTING server
[junit] 2008-11-19 10:47:12,380 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-19 10:47:12,381 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test23402.junit.dir/version-2/snapshot.0
[junit] 2008-11-19 10:47:12,382 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 3
[junit] 2008-11-19 10:47:13,779 - INFO [main-SendThread:ClientCnxn$SendThread@776] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-19 10:47:13,779 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:58594 remote=/127.0.0.1:33221]
[junit] 2008-11-19 10:47:13,780 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:58594 lastZxid 3
[junit] 2008-11-19 10:47:13,780 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11db45c606c0000: true
[junit] 2008-11-19 10:47:13,780 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11db45c606c0000
[junit] 2008-11-19 10:47:47,848 - INFO [main:ClientBase@303] - STOPPING server
[junit] 2008-11-19 10:47:47,849 - WARN [main-SendThread:ClientCnxn$SendThread@874] - Exception closing session 0x11db45c606c0000 to sun.nio.ch.SelectionKeyImpl@4ecfdd
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:852)
[junit] 2008-11-19 10:47:47,849 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-19 10:47:47,850 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-19 10:47:47,958 - INFO [main:ClientBase@309] - STARTING server
[junit] 2008-11-19 10:47:47,959 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-19 10:47:47,960 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test23402.junit.dir/version-2/snapshot.3
[junit] 2008-11-19 10:47:47,962 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 5
[junit] 2008-11-19 10:47:49,008 - INFO [main-SendThread:ClientCnxn$SendThread@776] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-19 10:47:49,009 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:58597 remote=/127.0.0.1:33221]
[junit] 2008-11-19 10:47:49,010 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:58597 lastZxid 5
[junit] 2008-11-19 10:47:49,010 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11db45c606c0000: true
[junit] 2008-11-19 10:47:49,010 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11db45c606c0000
[junit] 2008-11-19 10:47:49,037 - INFO [main:ClientBase@303] - STOPPING server
[junit] 2008-11-19 10:47:49,038 - WARN [main-SendThread:ClientCnxn$SendThread@874] - Exception closing session 0x11db45c606c0000 to sun.nio.ch.SelectionKeyImpl@3b8b49
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:852)
[junit] 2008-11-19 10:47:49,038 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-19 10:47:49,039 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-19 10:47:49,148 - INFO [main:ClientBase@309] - STARTING server
[junit] 2008-11-19 10:47:49,149 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-19 10:47:49,150 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test23402.junit.dir/version-2/snapshot.5
[junit] 2008-11-19 10:47:49,153 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-19 10:47:50,688 - INFO [main-SendThread:ClientCnxn$SendThread@776] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-19 10:47:50,688 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:58600 remote=/127.0.0.1:33221]
[junit] 2008-11-19 10:47:50,689 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:58600 lastZxid 6
[junit] 2008-11-19 10:47:50,690 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11db45c606c0000: true
[junit] 2008-11-19 10:47:50,690 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11db45c606c0000
[junit] 2008-11-19 10:48:01,748 - INFO [main:ZooKeeper@401] - Closing session: 0x11db45c606c0000
[junit] 2008-11-19 10:48:01,748 - INFO [main:ClientCnxn@975] - Closing ClientCnxn for session: 0x11db45c606c0000
[junit] 2008-11-19 10:48:01,749 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11db45c606c0000
[junit] 2008-11-19 10:48:01,793 - INFO [main-SendThread:ClientCnxn$SendThread@868] - Exception while closing send thread for session 0x11db45c606c0000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-19 10:48:01,898 - INFO [main:ClientCnxn@961] - Disconnecting ClientCnxn for session: 0x11db45c606c0000
[junit] 2008-11-19 10:48:01,898 - INFO [main:ZooKeeper@409] - Session: 0x11db45c606c0000 closed
[junit] 2008-11-19 10:48:01,898 - INFO [main:ClientBase@315] - tearDown starting
[junit] 2008-11-19 10:48:01,898 - INFO [main-EventThread:ClientCnxn$EventThread@435] - EventThread shut down
[junit] 2008-11-19 10:48:01,899 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-19 10:48:01,899 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-19 10:48:01,901 - INFO [main:ClientBase@327] - FINISHED testWatcherAutoResetDisabledWithGlobal
[junit] 2008-11-19 10:48:01,902 - INFO [main:ClientBase@289] - STARTING testWatcherAutoResetDisabledWithLocal
[junit] 2008-11-19 10:48:01,902 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-19 10:48:01,903 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 0
[junit] 2008-11-19 10:48:01,905 - INFO [main:ClientBase@299] - Client test setup finished
[junit] 2008-11-19 10:48:01,905 - INFO [main-SendThread:ClientCnxn$SendThread@776] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-19 10:48:01,906 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:58603 remote=/127.0.0.1:33221]
[junit] 2008-11-19 10:48:01,906 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:58603 lastZxid 0
[junit] 2008-11-19 10:48:01,907 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@514] - Creating new session 0x11db45d22700000
[junit] 2008-11-19 10:48:01,918 - WARN [SyncThread:0:NIOServerCnxn@796] - Finished init of 0x11db45d22700000: true
[junit] 2008-11-19 10:48:01,943 - INFO [main:ClientBase@303] - STOPPING server
[junit] 2008-11-19 10:48:01,944 - WARN [main-SendThread:ClientCnxn$SendThread@874] - Exception closing session 0x11db45d22700000 to sun.nio.ch.SelectionKeyImpl@1309e87
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:852)
[junit] 2008-11-19 10:48:01,944 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-19 10:48:01,945 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-19 10:48:02,048 - INFO [main:ClientBase@309] - STARTING server
[junit] 2008-11-19 10:48:02,048 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-19 10:48:02,050 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test23403.junit.dir/version-2/snapshot.0
[junit] 2008-11-19 10:48:02,051 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 3
[junit] 2008-11-19 10:48:03,938 - INFO [main-SendThread:ClientCnxn$SendThread@776] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-19 10:48:03,938 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:58606 remote=/127.0.0.1:33221]
[junit] 2008-11-19 10:48:03,939 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:58606 lastZxid 3
[junit] 2008-11-19 10:48:03,939 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11db45d22700000: true
[junit] 2008-11-19 10:48:03,939 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11db45d22700000
[junit] 2008-11-19 10:48:38,017 - INFO [main:ClientBase@303] - STOPPING server
[junit] 2008-11-19 10:48:38,018 - WARN [main-SendThread:ClientCnxn$SendThread@874] - Exception closing session 0x11db45d22700000 to sun.nio.ch.SelectionKeyImpl@7a1576
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:852)
[junit] 2008-11-19 10:48:38,018 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-19 10:48:38,019 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-19 10:48:38,127 - INFO [main:ClientBase@309] - STARTING server
[junit] 2008-11-19 10:48:38,128 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-19 10:48:38,129 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test23403.junit.dir/version-2/snapshot.3
[junit] 2008-11-19 10:48:38,131 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 5
[junit] 2008-11-19 10:48:39,617 - INFO [main-SendThread:ClientCnxn$SendThread@776] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-19 10:48:39,618 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:58609 remote=/127.0.0.1:33221]
[junit] 2008-11-19 10:48:39,618 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:58609 lastZxid 5
[junit] 2008-11-19 10:48:39,619 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11db45d22700000: true
[junit] 2008-11-19 10:48:39,619 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11db45d22700000
[junit] 2008-11-19 10:48:39,639 - INFO [main:ClientBase@303] - STOPPING server
[junit] 2008-11-19 10:48:39,639 - WARN [main-SendThread:ClientCnxn$SendThread@874] - Exception closing session 0x11db45d22700000 to sun.nio.ch.SelectionKeyImpl@1a42792
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:852)
[junit] 2008-11-19 10:48:39,640 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-19 10:48:39,640 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-19 10:48:39,747 - INFO [main:ClientBase@309] - STARTING server
[junit] 2008-11-19 10:48:39,748 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-19 10:48:39,749 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test23403.junit.dir/version-2/snapshot.5
[junit] 2008-11-19 10:48:39,752 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-19 10:48:41,527 - INFO [main-SendThread:ClientCnxn$SendThread@776] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-19 10:48:41,528 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:58612 remote=/127.0.0.1:33221]
[junit] 2008-11-19 10:48:41,528 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:58612 lastZxid 6
[junit] 2008-11-19 10:48:41,529 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11db45d22700000: true
[junit] 2008-11-19 10:48:41,529 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11db45d22700000
[junit] 2008-11-19 10:48:52,587 - INFO [main:ZooKeeper@401] - Closing session: 0x11db45d22700000
[junit] 2008-11-19 10:48:52,587 - INFO [main:ClientCnxn@975] - Closing ClientCnxn for session: 0x11db45d22700000
[junit] 2008-11-19 10:48:52,588 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11db45d22700000
[junit] 2008-11-19 10:48:52,607 - INFO [main-SendThread:ClientCnxn$SendThread@868] - Exception while closing send thread for session 0x11db45d22700000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-19 10:48:52,717 - INFO [main:ClientCnxn@961] - Disconnecting ClientCnxn for session: 0x11db45d22700000
[junit] 2008-11-19 10:48:52,717 - INFO [main:ZooKeeper@409] - Session: 0x11db45d22700000 closed
[junit] 2008-11-19 10:48:52,718 - INFO [main:ClientBase@315] - tearDown starting
[junit] 2008-11-19 10:48:52,718 - INFO [main-EventThread:ClientCnxn$EventThread@435] - EventThread shut down
[junit] 2008-11-19 10:48:52,718 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-19 10:48:52,719 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-19 10:48:52,720 - INFO [main:ClientBase@327] - FINISHED testWatcherAutoResetDisabledWithLocal
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 126.338 sec
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build.xml:547: Tests failed!
Total time: 12 minutes 36 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Build failed in Hudson: ZooKeeper-trunk #147
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/147/changes
Changes:
[mahadev] ZOOKEEPER-219. fix events.poll timeout in watcher test to be longer. (pat via mahadev)
[mahadev] ZOOKEEPER-213. fix programmer guide C api docs to be in sync with latest zookeeper.h (pat via mahadev)
[mahadev] ZOOKEEPER-212. fix the snapshot to be asynchronous. (mahadev and ben)
[mahadev] ZOOKEEPER-223. change default level in root logger to INFO. (pat via mahadev)
------------------------------------------
[...truncated 42586 lines...]
[junit] 2008-11-18 11:16:28,036 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:34320 lastZxid 5
[junit] 2008-11-18 11:16:28,036 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daf50b18c0000: true
[junit] 2008-11-18 11:16:28,036 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daf50b18c0000
[junit] 2008-11-18 11:16:28,091 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-18 11:16:28,092 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-18 11:16:28,093 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daf50b18c0000 to sun.nio.ch.SelectionKeyImpl@18c56d
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-18 11:16:28,094 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-18 11:16:28,094 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-18 11:16:28,197 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-18 11:16:28,197 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-18 11:16:28,199 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test28937.junit.dir/version-2/snapshot.5
[junit] 2008-11-18 11:16:28,202 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-18 11:16:30,184 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-18 11:16:30,185 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:34325 remote=/127.0.0.1:33221]
[junit] 2008-11-18 11:16:30,185 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:34325 lastZxid 6
[junit] 2008-11-18 11:16:30,186 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daf50b18c0000: true
[junit] 2008-11-18 11:16:30,186 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daf50b18c0000
[junit] 2008-11-18 11:16:30,223 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-18 11:16:31,264 - INFO [main:ZooKeeper@398] - Closing session: 0x11daf50b18c0000
[junit] 2008-11-18 11:16:31,265 - INFO [main:ClientCnxn@961] - Closing ClientCnxn for session: 0x11daf50b18c0000
[junit] 2008-11-18 11:16:31,266 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11daf50b18c0000
[junit] 2008-11-18 11:16:31,315 - INFO [main-SendThread:ClientCnxn$SendThread@854] - Exception while closing send thread for session 0x11daf50b18c0000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-18 11:16:31,424 - INFO [main:ClientCnxn@947] - Disconnecting ClientCnxn for session: 0x11daf50b18c0000
[junit] 2008-11-18 11:16:31,425 - INFO [main:ZooKeeper@406] - Session: 0x11daf50b18c0000 closed
[junit] 2008-11-18 11:16:31,425 - INFO [main:ClientBase@314] - tearDown starting
[junit] 2008-11-18 11:16:31,425 - INFO [main-EventThread:ClientCnxn$EventThread@426] - EventThread shut down
[junit] 2008-11-18 11:16:31,426 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-18 11:16:31,427 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-18 11:16:31,468 - INFO [main:ClientBase@326] - FINISHED testWatcherAutoResetWithGlobal
[junit] 2008-11-18 11:16:31,469 - INFO [main:ClientBase@288] - STARTING testWatcherAutoResetWithLocal
[junit] 2008-11-18 11:16:31,470 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-18 11:16:31,471 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 0
[junit] 2008-11-18 11:16:31,474 - INFO [main:ClientBase@298] - Client test setup finished
[junit] 2008-11-18 11:16:31,475 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-18 11:16:31,475 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:34329 remote=/127.0.0.1:33221]
[junit] 2008-11-18 11:16:31,476 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:34329 lastZxid 0
[junit] 2008-11-18 11:16:31,476 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@514] - Creating new session 0x11daf50dc700000
[junit] 2008-11-18 11:16:31,543 - WARN [SyncThread:0:NIOServerCnxn@796] - Finished init of 0x11daf50dc700000: true
[junit] 2008-11-18 11:16:32,021 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-18 11:16:32,022 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daf50dc700000 to sun.nio.ch.SelectionKeyImpl@bdb503
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-18 11:16:32,022 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-18 11:16:32,024 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-18 11:16:32,124 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-18 11:16:32,125 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-18 11:16:32,127 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test28938.junit.dir/version-2/snapshot.0
[junit] 2008-11-18 11:16:32,129 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 3
[junit] 2008-11-18 11:16:33,514 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-18 11:16:33,515 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:34334 remote=/127.0.0.1:33221]
[junit] 2008-11-18 11:16:33,516 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:34334 lastZxid 3
[junit] 2008-11-18 11:16:33,516 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daf50dc700000: true
[junit] 2008-11-18 11:16:33,517 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daf50dc700000
[junit] 2008-11-18 11:16:37,584 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-18 11:16:37,585 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daf50dc700000 to sun.nio.ch.SelectionKeyImpl@110003
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-18 11:16:37,586 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-18 11:16:37,587 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-18 11:16:38,204 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-18 11:16:38,205 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-18 11:16:38,206 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test28938.junit.dir/version-2/snapshot.3
[junit] 2008-11-18 11:16:38,209 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 5
[junit] 2008-11-18 11:16:39,054 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-18 11:16:39,055 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:34343 remote=/127.0.0.1:33221]
[junit] 2008-11-18 11:16:39,055 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:34343 lastZxid 5
[junit] 2008-11-18 11:16:39,056 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daf50dc700000: true
[junit] 2008-11-18 11:16:39,056 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daf50dc700000
[junit] 2008-11-18 11:16:39,105 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-18 11:16:39,106 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-18 11:16:39,107 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daf50dc700000 to sun.nio.ch.SelectionKeyImpl@c62c8
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-18 11:16:39,107 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-18 11:16:39,107 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-18 11:16:39,214 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-18 11:16:39,215 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-18 11:16:39,217 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test28938.junit.dir/version-2/snapshot.5
[junit] 2008-11-18 11:16:39,219 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-18 11:16:40,294 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-18 11:16:40,295 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:34348 remote=/127.0.0.1:33221]
[junit] 2008-11-18 11:16:40,296 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:34348 lastZxid 6
[junit] 2008-11-18 11:16:40,296 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daf50dc700000: true
[junit] 2008-11-18 11:16:40,296 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daf50dc700000
[junit] 2008-11-18 11:16:40,345 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-18 11:16:41,374 - INFO [main:ZooKeeper@398] - Closing session: 0x11daf50dc700000
[junit] 2008-11-18 11:16:41,375 - INFO [main:ClientCnxn@961] - Closing ClientCnxn for session: 0x11daf50dc700000
[junit] 2008-11-18 11:16:41,375 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11daf50dc700000
[junit] 2008-11-18 11:16:41,413 - INFO [main-SendThread:ClientCnxn$SendThread@854] - Exception while closing send thread for session 0x11daf50dc700000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-18 11:16:41,514 - INFO [main:ClientCnxn@947] - Disconnecting ClientCnxn for session: 0x11daf50dc700000
[junit] 2008-11-18 11:16:41,515 - INFO [main:ZooKeeper@406] - Session: 0x11daf50dc700000 closed
[junit] 2008-11-18 11:16:41,516 - INFO [main:ClientBase@314] - tearDown starting
[junit] 2008-11-18 11:16:41,517 - INFO [main-EventThread:ClientCnxn$EventThread@426] - EventThread shut down
[junit] 2008-11-18 11:16:41,569 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-18 11:16:41,569 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-18 11:16:41,688 - INFO [main:ClientBase@326] - FINISHED testWatcherAutoResetWithLocal
[junit] 2008-11-18 11:16:41,689 - INFO [main:ClientBase@288] - STARTING testWatcherAutoResetDisabledWithGlobal
[junit] 2008-11-18 11:16:41,690 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-18 11:16:41,691 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 0
[junit] 2008-11-18 11:16:41,693 - INFO [main:ClientBase@298] - Client test setup finished
[junit] 2008-11-18 11:16:41,694 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-18 11:16:41,694 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:34352 remote=/127.0.0.1:33221]
[junit] 2008-11-18 11:16:41,695 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:34352 lastZxid 0
[junit] 2008-11-18 11:16:41,696 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@514] - Creating new session 0x11daf51045c0000
[junit] 2008-11-18 11:16:42,049 - WARN [SyncThread:0:NIOServerCnxn@796] - Finished init of 0x11daf51045c0000: true
[junit] 2008-11-18 11:16:42,670 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-18 11:16:42,671 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daf51045c0000 to sun.nio.ch.SelectionKeyImpl@3e0ebb
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-18 11:16:42,671 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-18 11:16:42,672 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-18 11:16:42,774 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-18 11:16:42,775 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-18 11:16:42,776 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test28939.junit.dir/version-2/snapshot.0
[junit] 2008-11-18 11:16:42,777 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 3
[junit] 2008-11-18 11:16:43,934 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-18 11:16:43,935 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:34357 remote=/127.0.0.1:33221]
[junit] 2008-11-18 11:16:43,935 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:34357 lastZxid 3
[junit] 2008-11-18 11:16:43,937 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daf51045c0000: true
[junit] 2008-11-18 11:16:43,937 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daf51045c0000
[junit] 2008-11-18 11:17:18,083 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-18 11:17:18,085 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-18 11:17:18,085 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-18 11:17:18,085 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daf51045c0000 to sun.nio.ch.SelectionKeyImpl@153f67e
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-18 11:17:18,194 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-18 11:17:18,208 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-18 11:17:18,211 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test28939.junit.dir/version-2/snapshot.3
[junit] 2008-11-18 11:17:18,214 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 5
[junit] 2008-11-18 11:17:19,544 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-18 11:17:19,544 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:34400 remote=/127.0.0.1:33221]
[junit] 2008-11-18 11:17:19,545 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:34400 lastZxid 5
[junit] 2008-11-18 11:17:19,545 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daf51045c0000: true
[junit] 2008-11-18 11:17:19,545 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daf51045c0000
[junit] 2008-11-18 11:17:19,578 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-18 11:17:19,579 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-18 11:17:19,579 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daf51045c0000 to sun.nio.ch.SelectionKeyImpl@1b15692
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-18 11:17:19,580 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-18 11:17:19,581 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-18 11:17:19,684 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-18 11:17:19,684 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-18 11:17:19,686 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test28939.junit.dir/version-2/snapshot.5
[junit] 2008-11-18 11:17:19,707 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-18 11:17:20,724 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-18 11:17:20,724 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:34404 remote=/127.0.0.1:33221]
[junit] 2008-11-18 11:17:20,725 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:34404 lastZxid 6
[junit] 2008-11-18 11:17:20,725 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daf51045c0000: true
[junit] 2008-11-18 11:17:20,725 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daf51045c0000
[junit] 2008-11-18 11:17:31,893 - INFO [main:ZooKeeper@398] - Closing session: 0x11daf51045c0000
[junit] 2008-11-18 11:17:31,894 - INFO [main:ClientCnxn@961] - Closing ClientCnxn for session: 0x11daf51045c0000
[junit] 2008-11-18 11:17:31,895 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11daf51045c0000
[junit] 2008-11-18 11:17:32,281 - INFO [main-SendThread:ClientCnxn$SendThread@854] - Exception while closing send thread for session 0x11daf51045c0000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-18 11:17:32,383 - INFO [main:ClientCnxn@947] - Disconnecting ClientCnxn for session: 0x11daf51045c0000
[junit] 2008-11-18 11:17:32,384 - INFO [main:ZooKeeper@406] - Session: 0x11daf51045c0000 closed
[junit] 2008-11-18 11:17:32,384 - INFO [main:ClientBase@314] - tearDown starting
[junit] 2008-11-18 11:17:32,384 - INFO [main-EventThread:ClientCnxn$EventThread@426] - EventThread shut down
[junit] 2008-11-18 11:17:32,386 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-18 11:17:32,386 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-18 11:17:32,547 - INFO [main:ClientBase@326] - FINISHED testWatcherAutoResetDisabledWithGlobal
[junit] 2008-11-18 11:17:32,548 - INFO [main:ClientBase@288] - STARTING testWatcherAutoResetDisabledWithLocal
[junit] 2008-11-18 11:17:32,548 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-18 11:17:32,549 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 0
[junit] 2008-11-18 11:17:32,551 - INFO [main:ClientBase@298] - Client test setup finished
[junit] 2008-11-18 11:17:32,552 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-18 11:17:32,552 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:34419 remote=/127.0.0.1:33221]
[junit] 2008-11-18 11:17:32,553 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:34419 lastZxid 0
[junit] 2008-11-18 11:17:32,553 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@514] - Creating new session 0x11daf51cb060000
[junit] 2008-11-18 11:17:32,820 - WARN [SyncThread:0:NIOServerCnxn@796] - Finished init of 0x11daf51cb060000: true
[junit] 2008-11-18 11:17:32,959 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-18 11:17:32,959 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daf51cb060000 to sun.nio.ch.SelectionKeyImpl@949f69
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-18 11:17:32,960 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-18 11:17:32,960 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-18 11:17:33,063 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-18 11:17:33,064 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-18 11:17:33,065 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test28940.junit.dir/version-2/snapshot.0
[junit] 2008-11-18 11:17:33,066 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 3
[junit] 2008-11-18 11:17:35,023 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-18 11:17:35,024 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:34425 remote=/127.0.0.1:33221]
[junit] 2008-11-18 11:17:35,024 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:34425 lastZxid 3
[junit] 2008-11-18 11:17:35,025 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daf51cb060000: true
[junit] 2008-11-18 11:17:35,025 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daf51cb060000
[junit] 2008-11-18 11:18:09,183 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-18 11:18:09,183 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daf51cb060000 to sun.nio.ch.SelectionKeyImpl@12452e8
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-18 11:18:09,184 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-18 11:18:09,184 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-18 11:18:09,293 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-18 11:18:09,328 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-18 11:18:09,329 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test28940.junit.dir/version-2/snapshot.3
[junit] 2008-11-18 11:18:09,331 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 5
[junit] 2008-11-18 11:18:11,243 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-18 11:18:11,243 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:34470 remote=/127.0.0.1:33221]
[junit] 2008-11-18 11:18:11,244 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:34470 lastZxid 5
[junit] 2008-11-18 11:18:11,244 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daf51cb060000: true
[junit] 2008-11-18 11:18:11,244 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daf51cb060000
[junit] 2008-11-18 11:18:11,311 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-18 11:18:11,312 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-18 11:18:11,313 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daf51cb060000 to sun.nio.ch.SelectionKeyImpl@10dc6b5
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-18 11:18:11,313 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-18 11:18:11,314 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-18 11:18:11,423 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-18 11:18:11,423 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-18 11:18:11,425 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test28940.junit.dir/version-2/snapshot.5
[junit] 2008-11-18 11:18:11,509 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-18 11:18:12,593 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-18 11:18:12,593 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:34474 remote=/127.0.0.1:33221]
[junit] 2008-11-18 11:18:12,594 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:34474 lastZxid 6
[junit] 2008-11-18 11:18:12,594 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daf51cb060000: true
[junit] 2008-11-18 11:18:12,595 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daf51cb060000
[junit] 2008-11-18 11:18:24,262 - INFO [main:ZooKeeper@398] - Closing session: 0x11daf51cb060000
[junit] 2008-11-18 11:18:24,263 - INFO [main:ClientCnxn@961] - Closing ClientCnxn for session: 0x11daf51cb060000
[junit] 2008-11-18 11:18:24,264 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11daf51cb060000
[junit] 2008-11-18 11:18:24,302 - INFO [main-SendThread:ClientCnxn$SendThread@854] - Exception while closing send thread for session 0x11daf51cb060000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-18 11:18:24,403 - INFO [main:ClientCnxn@947] - Disconnecting ClientCnxn for session: 0x11daf51cb060000
[junit] 2008-11-18 11:18:24,403 - INFO [main:ZooKeeper@406] - Session: 0x11daf51cb060000 closed
[junit] 2008-11-18 11:18:24,403 - INFO [main:ClientBase@314] - tearDown starting
[junit] 2008-11-18 11:18:24,404 - INFO [main-EventThread:ClientCnxn$EventThread@426] - EventThread shut down
[junit] 2008-11-18 11:18:24,404 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-18 11:18:24,405 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-18 11:18:24,423 - INFO [main:ClientBase@326] - FINISHED testWatcherAutoResetDisabledWithLocal
[junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 125.528 sec
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build.xml:547: Tests failed!
Total time: 19 minutes 28 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Build failed in Hudson: ZooKeeper-trunk #146
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/146/changes
------------------------------------------
[...truncated 40272 lines...]
[junit] 2008-11-17 11:25:09,386 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:39089 lastZxid 5
[junit] 2008-11-17 11:25:09,386 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daa32487a0000: true
[junit] 2008-11-17 11:25:09,387 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daa32487a0000
[junit] 2008-11-17 11:25:09,414 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-17 11:25:09,416 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-17 11:25:09,417 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daa32487a0000 to sun.nio.ch.SelectionKeyImpl@166afb3
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-17 11:25:09,418 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-17 11:25:09,418 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-17 11:25:09,525 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-17 11:25:09,526 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-17 11:25:09,528 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test21327.junit.dir/version-2/snapshot.5
[junit] 2008-11-17 11:25:09,530 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-17 11:25:11,215 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-17 11:25:11,215 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:39094 remote=/127.0.0.1:33221]
[junit] 2008-11-17 11:25:11,216 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:39094 lastZxid 6
[junit] 2008-11-17 11:25:11,217 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daa32487a0000: true
[junit] 2008-11-17 11:25:11,217 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daa32487a0000
[junit] 2008-11-17 11:25:11,276 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-17 11:25:12,305 - INFO [main:ZooKeeper@398] - Closing session: 0x11daa32487a0000
[junit] 2008-11-17 11:25:12,305 - INFO [main:ClientCnxn@961] - Closing ClientCnxn for session: 0x11daa32487a0000
[junit] 2008-11-17 11:25:12,306 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11daa32487a0000
[junit] 2008-11-17 11:25:12,313 - INFO [main-SendThread:ClientCnxn$SendThread@854] - Exception while closing send thread for session 0x11daa32487a0000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-17 11:25:12,415 - INFO [main:ClientCnxn@947] - Disconnecting ClientCnxn for session: 0x11daa32487a0000
[junit] 2008-11-17 11:25:12,415 - INFO [main:ZooKeeper@406] - Session: 0x11daa32487a0000 closed
[junit] 2008-11-17 11:25:12,416 - INFO [main:ClientBase@314] - tearDown starting
[junit] 2008-11-17 11:25:12,416 - INFO [main-EventThread:ClientCnxn$EventThread@426] - EventThread shut down
[junit] 2008-11-17 11:25:12,417 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-17 11:25:12,417 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-17 11:25:12,419 - INFO [main:ClientBase@326] - FINISHED testWatcherAutoResetWithGlobal
[junit] 2008-11-17 11:25:12,420 - INFO [main:ClientBase@288] - STARTING testWatcherAutoResetWithLocal
[junit] 2008-11-17 11:25:12,421 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-17 11:25:12,422 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 0
[junit] 2008-11-17 11:25:12,424 - INFO [main:ClientBase@298] - Client test setup finished
[junit] 2008-11-17 11:25:12,425 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-17 11:25:12,426 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:39097 remote=/127.0.0.1:33221]
[junit] 2008-11-17 11:25:12,426 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:39097 lastZxid 0
[junit] 2008-11-17 11:25:12,427 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@514] - Creating new session 0x11daa3273670000
[junit] 2008-11-17 11:25:12,446 - WARN [SyncThread:0:NIOServerCnxn@796] - Finished init of 0x11daa3273670000: true
[junit] 2008-11-17 11:25:12,482 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-17 11:25:12,483 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daa3273670000 to sun.nio.ch.SelectionKeyImpl@12940b3
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-17 11:25:12,484 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-17 11:25:12,491 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-17 11:25:12,595 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-17 11:25:12,595 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-17 11:25:12,597 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test21328.junit.dir/version-2/snapshot.0
[junit] 2008-11-17 11:25:12,598 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 3
[junit] 2008-11-17 11:25:14,235 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-17 11:25:14,235 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:39100 remote=/127.0.0.1:33221]
[junit] 2008-11-17 11:25:14,236 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:39100 lastZxid 3
[junit] 2008-11-17 11:25:14,236 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daa3273670000: true
[junit] 2008-11-17 11:25:14,237 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daa3273670000
[junit] 2008-11-17 11:25:18,345 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-17 11:25:18,348 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-17 11:25:18,348 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-17 11:25:18,348 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daa3273670000 to sun.nio.ch.SelectionKeyImpl@158f9d3
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-17 11:25:18,965 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-17 11:25:18,965 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-17 11:25:18,966 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test21328.junit.dir/version-2/snapshot.3
[junit] 2008-11-17 11:25:18,968 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 5
[junit] 2008-11-17 11:25:19,825 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-17 11:25:19,825 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:39105 remote=/127.0.0.1:33221]
[junit] 2008-11-17 11:25:19,826 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:39105 lastZxid 5
[junit] 2008-11-17 11:25:19,826 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daa3273670000: true
[junit] 2008-11-17 11:25:19,826 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daa3273670000
[junit] 2008-11-17 11:25:19,851 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-17 11:25:19,852 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-17 11:25:19,854 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-17 11:25:19,853 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daa3273670000 to sun.nio.ch.SelectionKeyImpl@3e0ebb
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-17 11:25:19,854 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-17 11:25:19,955 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-17 11:25:19,956 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-17 11:25:19,957 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test21328.junit.dir/version-2/snapshot.5
[junit] 2008-11-17 11:25:19,960 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-17 11:25:21,745 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-17 11:25:21,746 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:39111 remote=/127.0.0.1:33221]
[junit] 2008-11-17 11:25:21,747 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:39111 lastZxid 6
[junit] 2008-11-17 11:25:21,747 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daa3273670000: true
[junit] 2008-11-17 11:25:21,747 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daa3273670000
[junit] 2008-11-17 11:25:21,774 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-17 11:25:22,805 - INFO [main:ZooKeeper@398] - Closing session: 0x11daa3273670000
[junit] 2008-11-17 11:25:22,805 - INFO [main:ClientCnxn@961] - Closing ClientCnxn for session: 0x11daa3273670000
[junit] 2008-11-17 11:25:22,806 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11daa3273670000
[junit] 2008-11-17 11:25:22,828 - INFO [main-SendThread:ClientCnxn$SendThread@854] - Exception while closing send thread for session 0x11daa3273670000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-17 11:25:22,936 - INFO [main:ClientCnxn@947] - Disconnecting ClientCnxn for session: 0x11daa3273670000
[junit] 2008-11-17 11:25:22,937 - INFO [main:ZooKeeper@406] - Session: 0x11daa3273670000 closed
[junit] 2008-11-17 11:25:22,937 - INFO [main:ClientBase@314] - tearDown starting
[junit] 2008-11-17 11:25:22,938 - INFO [main-EventThread:ClientCnxn$EventThread@426] - EventThread shut down
[junit] 2008-11-17 11:25:22,938 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-17 11:25:22,939 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-17 11:25:22,940 - INFO [main:ClientBase@326] - FINISHED testWatcherAutoResetWithLocal
[junit] 2008-11-17 11:25:22,941 - INFO [main:ClientBase@288] - STARTING testWatcherAutoResetDisabledWithGlobal
[junit] 2008-11-17 11:25:22,942 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-17 11:25:22,943 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 0
[junit] 2008-11-17 11:25:22,945 - INFO [main:ClientBase@298] - Client test setup finished
[junit] 2008-11-17 11:25:22,946 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-17 11:25:22,946 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:39115 remote=/127.0.0.1:33221]
[junit] 2008-11-17 11:25:22,947 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:39115 lastZxid 0
[junit] 2008-11-17 11:25:22,947 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@514] - Creating new session 0x11daa329c800000
[junit] 2008-11-17 11:25:22,968 - WARN [SyncThread:0:NIOServerCnxn@796] - Finished init of 0x11daa329c800000: true
[junit] 2008-11-17 11:25:23,027 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-17 11:25:23,028 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daa329c800000 to sun.nio.ch.SelectionKeyImpl@153f67e
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-17 11:25:23,029 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-17 11:25:23,029 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-17 11:25:23,135 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-17 11:25:23,135 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-17 11:25:23,136 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test21329.junit.dir/version-2/snapshot.0
[junit] 2008-11-17 11:25:23,138 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 3
[junit] 2008-11-17 11:25:24,195 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-17 11:25:24,195 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:39123 remote=/127.0.0.1:33221]
[junit] 2008-11-17 11:25:24,196 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:39123 lastZxid 3
[junit] 2008-11-17 11:25:24,196 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daa329c800000: true
[junit] 2008-11-17 11:25:24,196 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daa329c800000
[junit] 2008-11-17 11:25:30,284 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-17 11:25:30,286 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-17 11:25:30,286 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-17 11:25:30,287 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daa329c800000 to sun.nio.ch.SelectionKeyImpl@1d86fd3
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-17 11:25:30,395 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-17 11:25:30,395 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-17 11:25:30,396 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test21329.junit.dir/version-2/snapshot.3
[junit] 2008-11-17 11:25:30,398 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 5
[junit] 2008-11-17 11:25:31,454 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-17 11:25:31,455 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:39138 remote=/127.0.0.1:33221]
[junit] 2008-11-17 11:25:31,456 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:39138 lastZxid 5
[junit] 2008-11-17 11:25:31,456 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daa329c800000: true
[junit] 2008-11-17 11:25:31,456 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daa329c800000
[junit] 2008-11-17 11:25:31,593 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-17 11:25:31,688 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-17 11:25:31,690 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-17 11:25:31,690 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-17 11:25:31,691 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daa329c800000 to sun.nio.ch.SelectionKeyImpl@530cf2
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-17 11:25:31,865 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-17 11:25:31,865 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-17 11:25:31,867 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test21329.junit.dir/version-2/snapshot.5
[junit] 2008-11-17 11:25:31,869 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-17 11:25:33,414 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-17 11:25:33,415 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:39144 remote=/127.0.0.1:33221]
[junit] 2008-11-17 11:25:33,416 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:39144 lastZxid 6
[junit] 2008-11-17 11:25:33,416 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daa329c800000: true
[junit] 2008-11-17 11:25:33,416 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daa329c800000
[junit] 2008-11-17 11:25:34,454 - INFO [main:ZooKeeper@398] - Closing session: 0x11daa329c800000
[junit] 2008-11-17 11:25:34,455 - INFO [main:ClientCnxn@961] - Closing ClientCnxn for session: 0x11daa329c800000
[junit] 2008-11-17 11:25:34,456 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11daa329c800000
[junit] 2008-11-17 11:25:34,487 - INFO [main-SendThread:ClientCnxn$SendThread@854] - Exception while closing send thread for session 0x11daa329c800000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-17 11:25:34,607 - INFO [main:ClientCnxn@947] - Disconnecting ClientCnxn for session: 0x11daa329c800000
[junit] 2008-11-17 11:25:34,608 - INFO [main:ZooKeeper@406] - Session: 0x11daa329c800000 closed
[junit] 2008-11-17 11:25:34,608 - INFO [main:ClientBase@314] - tearDown starting
[junit] 2008-11-17 11:25:34,609 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-17 11:25:34,610 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-17 11:25:34,611 - INFO [main:ClientBase@326] - FINISHED testWatcherAutoResetDisabledWithGlobal
[junit] 2008-11-17 11:25:34,612 - INFO [main:ClientBase@288] - STARTING testWatcherAutoResetDisabledWithLocal
[junit] 2008-11-17 11:25:34,612 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-17 11:25:34,613 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 0
[junit] 2008-11-17 11:25:34,616 - INFO [main:ClientBase@298] - Client test setup finished
[junit] 2008-11-17 11:25:34,617 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-17 11:25:34,617 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:39150 remote=/127.0.0.1:33221]
[junit] 2008-11-17 11:25:34,618 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:39150 lastZxid 0
[junit] 2008-11-17 11:25:34,618 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@514] - Creating new session 0x11daa32ca160000
[junit] 2008-11-17 11:25:34,629 - WARN [SyncThread:0:NIOServerCnxn@796] - Finished init of 0x11daa32ca160000: true
[junit] 2008-11-17 11:25:34,636 - INFO [main-EventThread:ClientCnxn$EventThread@426] - EventThread shut down
[junit] 2008-11-17 11:25:34,716 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-17 11:25:34,717 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-17 11:25:34,717 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-17 11:25:34,719 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daa32ca160000 to sun.nio.ch.SelectionKeyImpl@12452e8
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-17 11:25:34,824 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-17 11:25:34,825 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-17 11:25:34,826 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test21330.junit.dir/version-2/snapshot.0
[junit] 2008-11-17 11:25:34,827 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 3
[junit] 2008-11-17 11:25:36,276 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-17 11:25:36,277 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:39157 remote=/127.0.0.1:33221]
[junit] 2008-11-17 11:25:36,278 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:39157 lastZxid 3
[junit] 2008-11-17 11:25:36,278 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daa32ca160000: true
[junit] 2008-11-17 11:25:36,278 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daa32ca160000
[junit] 2008-11-17 11:25:42,384 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-17 11:25:42,385 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-17 11:25:42,386 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-17 11:25:42,394 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daa32ca160000 to sun.nio.ch.SelectionKeyImpl@d0af9b
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-17 11:25:42,504 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-17 11:25:42,505 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-17 11:25:42,506 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test21330.junit.dir/version-2/snapshot.3
[junit] 2008-11-17 11:25:42,508 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 5
[junit] 2008-11-17 11:25:43,714 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-17 11:25:43,715 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:39183 remote=/127.0.0.1:33221]
[junit] 2008-11-17 11:25:43,715 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:39183 lastZxid 5
[junit] 2008-11-17 11:25:43,716 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daa32ca160000: true
[junit] 2008-11-17 11:25:43,716 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daa32ca160000
[junit] 2008-11-17 11:25:43,725 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-17 11:25:43,726 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-17 11:25:43,727 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11daa32ca160000 to sun.nio.ch.SelectionKeyImpl@9cbd4b
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-17 11:25:43,727 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-17 11:25:43,729 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-17 11:25:43,834 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-17 11:25:43,835 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-17 11:25:43,836 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test21330.junit.dir/version-2/snapshot.5
[junit] 2008-11-17 11:25:43,838 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-17 11:25:44,934 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-17 11:25:44,935 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:39186 remote=/127.0.0.1:33221]
[junit] 2008-11-17 11:25:44,936 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:39186 lastZxid 6
[junit] 2008-11-17 11:25:44,936 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11daa32ca160000: true
[junit] 2008-11-17 11:25:44,936 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11daa32ca160000
[junit] 2008-11-17 11:25:45,964 - INFO [main:ZooKeeper@398] - Closing session: 0x11daa32ca160000
[junit] 2008-11-17 11:25:45,965 - INFO [main:ClientCnxn@961] - Closing ClientCnxn for session: 0x11daa32ca160000
[junit] 2008-11-17 11:25:45,966 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11daa32ca160000
[junit] 2008-11-17 11:25:46,018 - INFO [main-SendThread:ClientCnxn$SendThread@854] - Exception while closing send thread for session 0x11daa32ca160000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-17 11:25:46,120 - INFO [main:ClientCnxn@947] - Disconnecting ClientCnxn for session: 0x11daa32ca160000
[junit] 2008-11-17 11:25:46,121 - INFO [main:ZooKeeper@406] - Session: 0x11daa32ca160000 closed
[junit] 2008-11-17 11:25:46,121 - INFO [main:ClientBase@314] - tearDown starting
[junit] 2008-11-17 11:25:46,122 - INFO [main-EventThread:ClientCnxn$EventThread@426] - EventThread shut down
[junit] 2008-11-17 11:25:46,122 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-17 11:25:46,122 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-17 11:25:46,124 - INFO [main:ClientBase@326] - FINISHED testWatcherAutoResetDisabledWithLocal
[junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 46.196 sec
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build.xml:547: Tests failed!
Total time: 15 minutes 49 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Build failed in Hudson: ZooKeeper-trunk #145
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/145/changes
------------------------------------------
[...truncated 42353 lines...]
[junit] 2008-11-16 20:47:06,761 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@514] - Creating new session 0x11da70e88040000
[junit] 2008-11-16 20:47:06,795 - WARN [SyncThread:0:NIOServerCnxn@796] - Finished init of 0x11da70e88040000: true
[junit] 2008-11-16 20:47:06,839 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-16 20:47:06,841 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-16 20:47:06,840 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11da70e88040000 to sun.nio.ch.SelectionKeyImpl@110003
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-16 20:47:06,841 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-16 20:47:06,947 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-16 20:47:06,947 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-16 20:47:06,948 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test10135.junit.dir/version-2/snapshot.0
[junit] 2008-11-16 20:47:06,950 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 3
[junit] 2008-11-16 20:47:08,117 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-16 20:47:08,117 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:37133 remote=/127.0.0.1:33221]
[junit] 2008-11-16 20:47:08,119 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:37133 lastZxid 3
[junit] 2008-11-16 20:47:08,119 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11da70e88040000: true
[junit] 2008-11-16 20:47:08,120 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11da70e88040000
[junit] 2008-11-16 20:47:12,177 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-16 20:47:12,178 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-16 20:47:12,179 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-16 20:47:12,180 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11da70e88040000 to sun.nio.ch.SelectionKeyImpl@79a2e7
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-16 20:47:12,796 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-16 20:47:12,797 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-16 20:47:12,798 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test10135.junit.dir/version-2/snapshot.3
[junit] 2008-11-16 20:47:12,800 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 5
[junit] 2008-11-16 20:47:14,176 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-16 20:47:14,177 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:38552 remote=/127.0.0.1:33221]
[junit] 2008-11-16 20:47:14,178 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:38552 lastZxid 5
[junit] 2008-11-16 20:47:14,178 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11da70e88040000: true
[junit] 2008-11-16 20:47:14,178 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11da70e88040000
[junit] 2008-11-16 20:47:14,205 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-16 20:47:14,217 - INFO [main:ClientBase@314] - tearDown starting
[junit] 2008-11-16 20:47:14,218 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-16 20:47:14,218 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-16 20:47:14,220 - INFO [main:ClientBase@326] - FINISHED testWatcherAutoResetWithLocal
[junit] 2008-11-16 20:47:14,225 - INFO [main:ClientBase@288] - STARTING testWatcherAutoResetDisabledWithGlobal
[junit] 2008-11-16 20:47:14,225 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-16 20:47:14,226 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 0
[junit] 2008-11-16 20:47:14,228 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11da70e88040000 to sun.nio.ch.SelectionKeyImpl@3e0ebb
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-16 20:47:14,230 - INFO [main:ClientBase@298] - Client test setup finished
[junit] 2008-11-16 20:47:14,231 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-16 20:47:14,232 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:38562 remote=/127.0.0.1:33221]
[junit] 2008-11-16 20:47:14,232 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:38562 lastZxid 0
[junit] 2008-11-16 20:47:14,233 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@514] - Creating new session 0x11da70ea5330000
[junit] 2008-11-16 20:47:14,255 - WARN [SyncThread:0:NIOServerCnxn@796] - Finished init of 0x11da70ea5330000: true
[junit] 2008-11-16 20:47:14,292 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-16 20:47:14,293 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11da70ea5330000 to sun.nio.ch.SelectionKeyImpl@8c436b
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-16 20:47:14,293 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-16 20:47:14,294 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-16 20:47:14,397 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-16 20:47:14,397 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-16 20:47:14,398 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test10136.junit.dir/version-2/snapshot.0
[junit] 2008-11-16 20:47:14,399 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 3
[junit] 2008-11-16 20:47:16,016 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-16 20:47:16,017 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:38926 remote=/127.0.0.1:33221]
[junit] 2008-11-16 20:47:16,018 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:38926 lastZxid 6
[junit] 2008-11-16 20:47:16,018 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn@491] - Client has seen zxid 0x6 our last zxid is 0x3
[junit] 2008-11-16 20:47:16,018 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11da70e88040000 to sun.nio.ch.SelectionKeyImpl@60e128
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-16 20:47:16,206 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-16 20:47:16,207 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:38945 remote=/127.0.0.1:33221]
[junit] 2008-11-16 20:47:16,207 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:38945 lastZxid 3
[junit] 2008-11-16 20:47:16,208 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11da70ea5330000: true
[junit] 2008-11-16 20:47:16,208 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11da70ea5330000
[junit] 2008-11-16 20:47:17,416 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-16 20:47:17,417 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:39197 remote=/127.0.0.1:33221]
[junit] 2008-11-16 20:47:17,417 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:39197 lastZxid 6
[junit] 2008-11-16 20:47:17,418 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn@491] - Client has seen zxid 0x6 our last zxid is 0x5
[junit] 2008-11-16 20:47:17,418 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11da70e88040000 to sun.nio.ch.SelectionKeyImpl@181ed9e
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-16 20:47:18,807 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-16 20:47:18,807 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:39501 remote=/127.0.0.1:33221]
[junit] 2008-11-16 20:47:18,808 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:39501 lastZxid 6
[junit] 2008-11-16 20:47:18,808 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn@491] - Client has seen zxid 0x6 our last zxid is 0x5
[junit] 2008-11-16 20:47:18,808 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11da70e88040000 to sun.nio.ch.SelectionKeyImpl@16dadf9
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-16 20:47:20,446 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-16 20:47:20,447 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:39851 remote=/127.0.0.1:33221]
[junit] 2008-11-16 20:47:20,447 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:39851 lastZxid 6
[junit] 2008-11-16 20:47:20,448 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn@491] - Client has seen zxid 0x6 our last zxid is 0x5
[junit] 2008-11-16 20:47:20,448 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11da70e88040000 to sun.nio.ch.SelectionKeyImpl@1c80b01
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-16 20:47:22,206 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-16 20:47:22,207 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:40209 remote=/127.0.0.1:33221]
[junit] 2008-11-16 20:47:22,208 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:40209 lastZxid 6
[junit] 2008-11-16 20:47:22,208 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn@491] - Client has seen zxid 0x6 our last zxid is 0x5
[junit] 2008-11-16 20:47:22,213 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11da70e88040000 to sun.nio.ch.SelectionKeyImpl@18f5824
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-16 20:47:22,306 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-16 20:47:22,307 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11da70ea5330000 to sun.nio.ch.SelectionKeyImpl@1db05b2
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-16 20:47:22,307 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-16 20:47:22,308 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-16 20:47:22,416 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-16 20:47:22,417 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-16 20:47:22,419 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test10136.junit.dir/version-2/snapshot.3
[junit] 2008-11-16 20:47:22,421 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 5
[junit] 2008-11-16 20:47:23,376 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-16 20:47:23,377 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:40439 remote=/127.0.0.1:33221]
[junit] 2008-11-16 20:47:23,377 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:40439 lastZxid 6
[junit] 2008-11-16 20:47:23,378 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn@491] - Client has seen zxid 0x6 our last zxid is 0x5
[junit] 2008-11-16 20:47:23,378 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11da70e88040000 to sun.nio.ch.SelectionKeyImpl@60991f
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-16 20:47:23,506 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-16 20:47:23,507 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:40474 remote=/127.0.0.1:33221]
[junit] 2008-11-16 20:47:23,507 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:40474 lastZxid 5
[junit] 2008-11-16 20:47:23,508 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11da70ea5330000: true
[junit] 2008-11-16 20:47:23,508 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11da70ea5330000
[junit] 2008-11-16 20:47:23,535 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-16 20:47:23,536 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-16 20:47:23,537 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11da70ea5330000 to sun.nio.ch.SelectionKeyImpl@c9d92c
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-16 20:47:23,537 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-16 20:47:23,538 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-16 20:47:23,646 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-16 20:47:23,647 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-16 20:47:23,648 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test10136.junit.dir/version-2/snapshot.5
[junit] 2008-11-16 20:47:23,651 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-16 20:47:25,066 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-16 20:47:25,067 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:40868 remote=/127.0.0.1:33221]
[junit] 2008-11-16 20:47:25,067 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:40868 lastZxid 6
[junit] 2008-11-16 20:47:25,068 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11da70e88040000: false
[junit] 2008-11-16 20:47:25,068 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11da70e88040000
[junit] 2008-11-16 20:47:25,068 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11da70e88040000 to sun.nio.ch.SelectionKeyImpl@f47bf5
[junit] java.io.IOException: Session Expired
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.readConnectResult(ClientCnxn.java:499)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:616)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-16 20:47:25,546 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-16 20:47:25,547 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:40988 remote=/127.0.0.1:33221]
[junit] 2008-11-16 20:47:25,547 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:40988 lastZxid 6
[junit] 2008-11-16 20:47:25,547 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11da70ea5330000: true
[junit] 2008-11-16 20:47:25,548 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11da70ea5330000
[junit] 2008-11-16 20:47:26,606 - INFO [main:ZooKeeper@398] - Closing session: 0x11da70ea5330000
[junit] 2008-11-16 20:47:26,607 - INFO [main:ClientCnxn@961] - Closing ClientCnxn for session: 0x11da70ea5330000
[junit] 2008-11-16 20:47:26,607 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11da70ea5330000
[junit] 2008-11-16 20:47:26,622 - INFO [main-SendThread:ClientCnxn$SendThread@854] - Exception while closing send thread for session 0x11da70ea5330000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-16 20:47:26,726 - INFO [main:ClientCnxn@947] - Disconnecting ClientCnxn for session: 0x11da70ea5330000
[junit] 2008-11-16 20:47:26,727 - INFO [main:ZooKeeper@406] - Session: 0x11da70ea5330000 closed
[junit] 2008-11-16 20:47:26,727 - INFO [main:ClientBase@314] - tearDown starting
[junit] 2008-11-16 20:47:26,727 - INFO [main-EventThread:ClientCnxn$EventThread@426] - EventThread shut down
[junit] 2008-11-16 20:47:26,728 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-16 20:47:26,728 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-16 20:47:26,730 - INFO [main:ClientBase@326] - FINISHED testWatcherAutoResetDisabledWithGlobal
[junit] 2008-11-16 20:47:26,731 - INFO [main:ClientBase@288] - STARTING testWatcherAutoResetDisabledWithLocal
[junit] 2008-11-16 20:47:26,731 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-16 20:47:26,732 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 0
[junit] 2008-11-16 20:47:26,734 - INFO [main:ClientBase@298] - Client test setup finished
[junit] 2008-11-16 20:47:26,735 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-16 20:47:26,735 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:41266 remote=/127.0.0.1:33221]
[junit] 2008-11-16 20:47:26,736 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:41266 lastZxid 0
[junit] 2008-11-16 20:47:26,736 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@514] - Creating new session 0x11da70ed60d0000
[junit] 2008-11-16 20:47:26,774 - WARN [SyncThread:0:NIOServerCnxn@796] - Finished init of 0x11da70ed60d0000: true
[junit] 2008-11-16 20:47:26,852 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-16 20:47:26,853 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11da70ed60d0000 to sun.nio.ch.SelectionKeyImpl@ca470
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-16 20:47:26,853 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-16 20:47:26,860 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-16 20:47:26,956 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-16 20:47:26,957 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-16 20:47:26,958 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test10137.junit.dir/version-2/snapshot.0
[junit] 2008-11-16 20:47:26,959 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 3
[junit] 2008-11-16 20:47:28,296 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-16 20:47:28,297 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:41624 remote=/127.0.0.1:33221]
[junit] 2008-11-16 20:47:28,297 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:41624 lastZxid 3
[junit] 2008-11-16 20:47:28,298 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11da70ed60d0000: true
[junit] 2008-11-16 20:47:28,298 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11da70ed60d0000
[junit] 2008-11-16 20:47:34,386 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-16 20:47:34,387 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11da70ed60d0000 to sun.nio.ch.SelectionKeyImpl@1d2fc36
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-16 20:47:34,388 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-16 20:47:34,388 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-16 20:47:34,496 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-16 20:47:34,497 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-16 20:47:34,498 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test10137.junit.dir/version-2/snapshot.3
[junit] 2008-11-16 20:47:34,499 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 5
[junit] 2008-11-16 20:47:36,076 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-16 20:47:36,076 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:43454 remote=/127.0.0.1:33221]
[junit] 2008-11-16 20:47:36,077 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:43454 lastZxid 5
[junit] 2008-11-16 20:47:36,077 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11da70ed60d0000: true
[junit] 2008-11-16 20:47:36,078 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11da70ed60d0000
[junit] 2008-11-16 20:47:36,103 - WARN [main-SendThread:ZooKeeper$ZKWatchManager@196] - We are triggering an exists watch for delete! Shouldn't happen!
[junit] 2008-11-16 20:47:36,104 - INFO [main:ClientBase@302] - STOPPING server
[junit] 2008-11-16 20:47:36,104 - WARN [main-SendThread:ClientCnxn$SendThread@860] - Exception closing session 0x11da70ed60d0000 to sun.nio.ch.SelectionKeyImpl@170888e
[junit] java.io.IOException: Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:608)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:838)
[junit] 2008-11-16 20:47:36,105 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-16 20:47:36,105 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-16 20:47:36,206 - INFO [main:ClientBase@308] - STARTING server
[junit] 2008-11-16 20:47:36,207 - INFO [main:ZooKeeperServer@144] - Created server
[junit] 2008-11-16 20:47:36,208 - INFO [main:FileSnap@70] - Reading snapshot /zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build/test/tmp/test10137.junit.dir/version-2/snapshot.5
[junit] 2008-11-16 20:47:36,210 - INFO [main:FileTxnSnapLog@180] - Snapshotting: 6
[junit] 2008-11-16 20:47:37,986 - INFO [main-SendThread:ClientCnxn$SendThread@762] - Attempting connection to server /127.0.0.1:33221
[junit] 2008-11-16 20:47:37,986 - INFO [main-SendThread:ClientCnxn$SendThread@692] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:43939 remote=/127.0.0.1:33221]
[junit] 2008-11-16 20:47:37,987 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@485] - Connected to /127.0.0.1:43939 lastZxid 6
[junit] 2008-11-16 20:47:37,987 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@796] - Finished init of 0x11da70ed60d0000: true
[junit] 2008-11-16 20:47:37,987 - WARN [NIOServerCxn.Factory:33221:NIOServerCnxn@511] - Renewing session 0x11da70ed60d0000
[junit] 2008-11-16 20:47:39,036 - INFO [main:ZooKeeper@398] - Closing session: 0x11da70ed60d0000
[junit] 2008-11-16 20:47:39,036 - INFO [main:ClientCnxn@961] - Closing ClientCnxn for session: 0x11da70ed60d0000
[junit] 2008-11-16 20:47:39,037 - INFO [ProcessThread:0:PrepRequestProcessor@346] - Processed session termination request for id: 0x11da70ed60d0000
[junit] 2008-11-16 20:47:39,080 - INFO [main-SendThread:ClientCnxn$SendThread@854] - Exception while closing send thread for session 0x11da70ed60d0000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2008-11-16 20:47:39,186 - INFO [main:ClientCnxn@947] - Disconnecting ClientCnxn for session: 0x11da70ed60d0000
[junit] 2008-11-16 20:47:39,186 - INFO [main:ZooKeeper@406] - Session: 0x11da70ed60d0000 closed
[junit] 2008-11-16 20:47:39,187 - INFO [main:ClientBase@314] - tearDown starting
[junit] 2008-11-16 20:47:39,187 - INFO [main-EventThread:ClientCnxn$EventThread@426] - EventThread shut down
[junit] 2008-11-16 20:47:39,187 - ERROR [NIOServerCxn.Factory:33221:NIOServerCnxn$Factory@169] - =====> Goodbye cruel world <======
[junit] 2008-11-16 20:47:39,188 - INFO [main:FinalRequestProcessor@262] - shutdown of request processor complete
[junit] 2008-11-16 20:47:39,189 - INFO [main:ClientBase@326] - FINISHED testWatcherAutoResetDisabledWithLocal
[junit] Tests run: 5, Failures: 0, Errors: 1, Time elapsed: 44.738 sec
[junit] Test org.apache.zookeeper.test.WatcherTest FAILED
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/ZooKeeper-trunk/workspace/trunk/build.xml:547: Tests failed!
Total time: 13 minutes 59 seconds
Recording fingerprints
Publishing Javadoc
Recording test results