See http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/309/changes
Changes: [fpj] ZOOKEEPER-400. Issues with procedure to close ledger. (flavio) [mahadev] ZOOKEEPER-395. Python bindings. (henry robinson via mahadev) ------------------------------------------ [...truncated 122494 lines...] [junit] 2009-05-14 14:06:23,020 - INFO [main:finalrequestproces...@268] - shutdown of request processor complete [junit] 2009-05-14 14:06:23,020 - INFO [SyncThread:0:syncrequestproces...@119] - SyncRequestProcessor exited! [junit] 2009-05-14 14:06:23,020 - INFO [ProcessThread:-1:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-05-14 14:06:23,119 - INFO [main:clientb...@307] - STARTING server [junit] 2009-05-14 14:06:23,120 - INFO [main:zookeeperser...@160] - Created server [junit] 2009-05-14 14:06:23,121 - INFO [main:files...@81] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test1052532416715671574.junit.dir/version-2/snapshot.0 [junit] 2009-05-14 14:06:23,122 - INFO [main:filetxnsnap...@198] - Snapshotting: 3 [junit] 2009-05-14 14:06:23,124 - INFO [NIOServerCxn.Factory:33221:nioserverc...@631] - Processing stat command from /127.0.0.1:52111 [junit] 2009-05-14 14:06:23,124 - WARN [NIOServerCxn.Factory:33221:nioserverc...@431] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-05-14 14:06:23,125 - INFO [NIOServerCxn.Factory:33221:nioserverc...@762] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:52111] [junit] 2009-05-14 14:06:24,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-05-14 14:06:24,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-05-14 14:06:24,795 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-05-14 14:06:24,796 - INFO [main-SendThread:clientcnxn$sendthr...@716] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:52112 remote=/127.0.0.1:33221] [junit] 2009-05-14 14:06:24,796 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-05-14 14:06:24,797 - INFO [NIOServerCxn.Factory:33221:nioserverc...@517] - Connected to /127.0.0.1:52112 lastZxid 3 [junit] 2009-05-14 14:06:24,797 - INFO [NIOServerCxn.Factory:33221:nioserverc...@891] - Finished init of 0x1213f71fb0f0000 valid:true [junit] 2009-05-14 14:06:24,798 - INFO [NIOServerCxn.Factory:33221:nioserverc...@545] - Renewing session 0x1213f71fb0f0000 [junit] 2009-05-14 14:06:58,813 - INFO [main:clientb...@301] - STOPPING server [junit] 2009-05-14 14:06:58,813 - INFO [main:nioserverc...@762] - closing session:0x1213f71fb0f0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:52112] [junit] 2009-05-14 14:06:58,814 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x1213f71fb0f0000 to sun.nio.ch.selectionkeyi...@23f1bb [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:632) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876) [junit] 2009-05-14 14:06:58,814 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@177] - NIOServerCnxn factory exited run method [junit] 2009-05-14 14:06:58,814 - INFO [main:finalrequestproces...@268] - shutdown of request processor complete [junit] 2009-05-14 14:06:58,814 - INFO [SyncThread:0:syncrequestproces...@119] - SyncRequestProcessor exited! [junit] 2009-05-14 14:06:58,814 - INFO [ProcessThread:-1:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-05-14 14:06:58,914 - INFO [main:clientb...@307] - STARTING server [junit] 2009-05-14 14:06:58,914 - INFO [main:zookeeperser...@160] - Created server [junit] 2009-05-14 14:06:58,916 - INFO [main:files...@81] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test1052532416715671574.junit.dir/version-2/snapshot.3 [junit] 2009-05-14 14:06:58,917 - INFO [main:filetxnsnap...@198] - Snapshotting: 5 [junit] 2009-05-14 14:06:58,919 - INFO [NIOServerCxn.Factory:33221:nioserverc...@631] - Processing stat command from /127.0.0.1:52114 [junit] 2009-05-14 14:06:58,920 - WARN [NIOServerCxn.Factory:33221:nioserverc...@431] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-05-14 14:06:58,920 - INFO [NIOServerCxn.Factory:33221:nioserverc...@762] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:52114] [junit] 2009-05-14 14:07:00,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-05-14 14:07:00,541 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-05-14 14:07:00,541 - INFO [main-SendThread:clientcnxn$sendthr...@716] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:52115 remote=/127.0.0.1:33221] [junit] 2009-05-14 14:07:00,541 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-05-14 14:07:00,551 - INFO [NIOServerCxn.Factory:33221:nioserverc...@517] - Connected to /127.0.0.1:52115 lastZxid 5 [junit] 2009-05-14 14:07:00,552 - INFO [NIOServerCxn.Factory:33221:nioserverc...@891] - Finished init of 0x1213f71fb0f0000 valid:true [junit] 2009-05-14 14:07:00,552 - INFO [NIOServerCxn.Factory:33221:nioserverc...@545] - Renewing session 0x1213f71fb0f0000 [junit] 2009-05-14 14:07:00,561 - INFO [main:clientb...@301] - STOPPING server [junit] 2009-05-14 14:07:00,562 - INFO [main:nioserverc...@762] - closing session:0x1213f71fb0f0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:52115] [junit] 2009-05-14 14:07:00,562 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x1213f71fb0f0000 to sun.nio.ch.selectionkeyi...@14e113b [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:632) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876) [junit] 2009-05-14 14:07:00,563 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@177] - NIOServerCnxn factory exited run method [junit] 2009-05-14 14:07:00,563 - INFO [main:finalrequestproces...@268] - shutdown of request processor complete [junit] 2009-05-14 14:07:00,563 - INFO [ProcessThread:-1:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-05-14 14:07:00,563 - INFO [SyncThread:0:syncrequestproces...@119] - SyncRequestProcessor exited! [junit] 2009-05-14 14:07:00,663 - INFO [main:clientb...@307] - STARTING server [junit] 2009-05-14 14:07:00,663 - INFO [main:zookeeperser...@160] - Created server [junit] 2009-05-14 14:07:00,665 - INFO [main:files...@81] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test1052532416715671574.junit.dir/version-2/snapshot.5 [junit] 2009-05-14 14:07:00,667 - INFO [main:filetxnsnap...@198] - Snapshotting: 6 [junit] 2009-05-14 14:07:00,668 - INFO [NIOServerCxn.Factory:33221:nioserverc...@631] - Processing stat command from /127.0.0.1:52117 [junit] 2009-05-14 14:07:00,669 - WARN [NIOServerCxn.Factory:33221:nioserverc...@431] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-05-14 14:07:00,669 - INFO [NIOServerCxn.Factory:33221:nioserverc...@762] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:52117] [junit] 2009-05-14 14:07:01,746 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-05-14 14:07:01,746 - INFO [main-SendThread:clientcnxn$sendthr...@716] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:52118 remote=/127.0.0.1:33221] [junit] 2009-05-14 14:07:01,746 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-05-14 14:07:01,747 - INFO [NIOServerCxn.Factory:33221:nioserverc...@517] - Connected to /127.0.0.1:52118 lastZxid 6 [junit] 2009-05-14 14:07:01,747 - INFO [NIOServerCxn.Factory:33221:nioserverc...@891] - Finished init of 0x1213f71fb0f0000 valid:true [junit] 2009-05-14 14:07:01,747 - INFO [NIOServerCxn.Factory:33221:nioserverc...@545] - Renewing session 0x1213f71fb0f0000 [junit] 2009-05-14 14:07:03,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-05-14 14:07:12,762 - INFO [main:zookee...@435] - Closing session: 0x1213f71fb0f0000 [junit] 2009-05-14 14:07:12,763 - INFO [main:clientc...@999] - Closing ClientCnxn for session: 0x1213f71fb0f0000 [junit] 2009-05-14 14:07:12,763 - INFO [ProcessThread:-1:preprequestproces...@365] - Processed session termination request for id: 0x1213f71fb0f0000 [junit] 2009-05-14 14:07:12,764 - INFO [SyncThread:0:nioserverc...@762] - closing session:0x1213f71fb0f0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:52118] [junit] 2009-05-14 14:07:12,764 - INFO [main-SendThread:clientcnxn$sendthr...@892] - Exception while closing send thread for session 0x1213f71fb0f0000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4] [junit] 2009-05-14 14:07:12,865 - INFO [main:clientc...@985] - Disconnecting ClientCnxn for session: 0x1213f71fb0f0000 [junit] 2009-05-14 14:07:12,865 - INFO [main:zookee...@443] - Session: 0x1213f71fb0f0000 closed [junit] 2009-05-14 14:07:12,865 - INFO [main-EventThread:clientcnxn$eventthr...@452] - EventThread shut down [junit] 2009-05-14 14:07:12,865 - INFO [main:clientb...@313] - tearDown starting [junit] 2009-05-14 14:07:12,866 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@177] - NIOServerCnxn factory exited run method [junit] 2009-05-14 14:07:12,866 - INFO [main:finalrequestproces...@268] - shutdown of request processor complete [junit] 2009-05-14 14:07:12,866 - INFO [ProcessThread:-1:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-05-14 14:07:12,866 - INFO [SyncThread:0:syncrequestproces...@119] - SyncRequestProcessor exited! [junit] 2009-05-14 14:07:12,868 - INFO [main:clientb...@323] - FINISHED testWatcherAutoResetDisabledWithGlobal [junit] 2009-05-14 14:07:12,868 - INFO [main:clientb...@289] - STARTING testWatcherAutoResetDisabledWithLocal [junit] 2009-05-14 14:07:12,869 - INFO [main:zookeeperser...@160] - Created server [junit] 2009-05-14 14:07:12,869 - INFO [main:filetxnsnap...@198] - Snapshotting: 0 [junit] 2009-05-14 14:07:12,872 - INFO [NIOServerCxn.Factory:33221:nioserverc...@631] - Processing stat command from /127.0.0.1:52120 [junit] 2009-05-14 14:07:12,873 - INFO [main:clientb...@297] - Client test setup finished [junit] 2009-05-14 14:07:12,873 - WARN [NIOServerCxn.Factory:33221:nioserverc...@431] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-05-14 14:07:12,873 - INFO [main:zookee...@342] - Initiating client connection, host=127.0.0.1:33221 sessionTimeout=30000 watcher=org.apache.zookeeper.test.watchertest$mywatc...@1f6f27b [junit] 2009-05-14 14:07:12,874 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-05-14 14:07:12,874 - INFO [main-SendThread:clientcnxn$sendthr...@716] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:52121 remote=/127.0.0.1:33221] [junit] 2009-05-14 14:07:12,875 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-05-14 14:07:12,875 - INFO [NIOServerCxn.Factory:33221:nioserverc...@762] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:52120] [junit] 2009-05-14 14:07:12,877 - INFO [NIOServerCxn.Factory:33221:nioserverc...@517] - Connected to /127.0.0.1:52121 lastZxid 0 [junit] 2009-05-14 14:07:12,877 - INFO [NIOServerCxn.Factory:33221:nioserverc...@548] - Creating new session 0x1213f72bde60000 [junit] 2009-05-14 14:07:12,891 - INFO [SyncThread:0:nioserverc...@891] - Finished init of 0x1213f72bde60000 valid:true [junit] 2009-05-14 14:07:12,896 - INFO [main:clientb...@301] - STOPPING server [junit] 2009-05-14 14:07:12,897 - INFO [main:nioserverc...@762] - closing session:0x1213f72bde60000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:52121] [junit] 2009-05-14 14:07:12,897 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x1213f72bde60000 to sun.nio.ch.selectionkeyi...@11a01dd [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:632) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876) [junit] 2009-05-14 14:07:12,898 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@177] - NIOServerCnxn factory exited run method [junit] 2009-05-14 14:07:12,899 - INFO [main:finalrequestproces...@268] - shutdown of request processor complete [junit] 2009-05-14 14:07:12,899 - INFO [SyncThread:0:syncrequestproces...@119] - SyncRequestProcessor exited! [junit] 2009-05-14 14:07:12,899 - INFO [ProcessThread:-1:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-05-14 14:07:12,998 - INFO [main:clientb...@307] - STARTING server [junit] 2009-05-14 14:07:12,998 - INFO [main:zookeeperser...@160] - Created server [junit] 2009-05-14 14:07:12,999 - INFO [main:files...@81] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test4693638624287650627.junit.dir/version-2/snapshot.0 [junit] 2009-05-14 14:07:13,000 - INFO [main:filetxnsnap...@198] - Snapshotting: 3 [junit] 2009-05-14 14:07:13,002 - INFO [NIOServerCxn.Factory:33221:nioserverc...@631] - Processing stat command from /127.0.0.1:52123 [junit] 2009-05-14 14:07:13,002 - WARN [NIOServerCxn.Factory:33221:nioserverc...@431] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-05-14 14:07:13,004 - INFO [NIOServerCxn.Factory:33221:nioserverc...@762] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:52123] [junit] 2009-05-14 14:07:14,774 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-05-14 14:07:14,774 - INFO [main-SendThread:clientcnxn$sendthr...@716] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:54325 remote=/127.0.0.1:33221] [junit] 2009-05-14 14:07:14,774 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-05-14 14:07:14,775 - INFO [NIOServerCxn.Factory:33221:nioserverc...@517] - Connected to /127.0.0.1:54325 lastZxid 3 [junit] 2009-05-14 14:07:14,775 - INFO [NIOServerCxn.Factory:33221:nioserverc...@891] - Finished init of 0x1213f72bde60000 valid:true [junit] 2009-05-14 14:07:14,775 - INFO [NIOServerCxn.Factory:33221:nioserverc...@545] - Renewing session 0x1213f72bde60000 [junit] 2009-05-14 14:07:15,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-05-14 14:07:15,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-05-14 14:07:48,782 - INFO [main:clientb...@301] - STOPPING server [junit] 2009-05-14 14:07:48,783 - INFO [main:nioserverc...@762] - closing session:0x1213f72bde60000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:54325] [junit] 2009-05-14 14:07:48,783 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x1213f72bde60000 to sun.nio.ch.selectionkeyi...@827968 [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:632) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876) [junit] 2009-05-14 14:07:48,818 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@177] - NIOServerCnxn factory exited run method [junit] 2009-05-14 14:07:48,819 - INFO [main:finalrequestproces...@268] - shutdown of request processor complete [junit] 2009-05-14 14:07:48,819 - INFO [SyncThread:0:syncrequestproces...@119] - SyncRequestProcessor exited! [junit] 2009-05-14 14:07:48,819 - INFO [ProcessThread:-1:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-05-14 14:07:48,919 - INFO [main:clientb...@307] - STARTING server [junit] 2009-05-14 14:07:48,919 - INFO [main:zookeeperser...@160] - Created server [junit] 2009-05-14 14:07:48,920 - INFO [main:files...@81] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test4693638624287650627.junit.dir/version-2/snapshot.3 [junit] 2009-05-14 14:07:48,922 - INFO [main:filetxnsnap...@198] - Snapshotting: 5 [junit] 2009-05-14 14:07:48,924 - INFO [NIOServerCxn.Factory:33221:nioserverc...@631] - Processing stat command from /127.0.0.1:54327 [junit] 2009-05-14 14:07:48,924 - WARN [NIOServerCxn.Factory:33221:nioserverc...@431] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-05-14 14:07:48,925 - INFO [NIOServerCxn.Factory:33221:nioserverc...@762] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:54327] [junit] 2009-05-14 14:07:50,489 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-05-14 14:07:50,495 - INFO [main-SendThread:clientcnxn$sendthr...@716] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:54328 remote=/127.0.0.1:33221] [junit] 2009-05-14 14:07:50,495 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-05-14 14:07:50,495 - INFO [NIOServerCxn.Factory:33221:nioserverc...@517] - Connected to /127.0.0.1:54328 lastZxid 5 [junit] 2009-05-14 14:07:50,497 - INFO [NIOServerCxn.Factory:33221:nioserverc...@891] - Finished init of 0x1213f72bde60000 valid:true [junit] 2009-05-14 14:07:50,498 - INFO [NIOServerCxn.Factory:33221:nioserverc...@545] - Renewing session 0x1213f72bde60000 [junit] 2009-05-14 14:07:50,512 - INFO [main:clientb...@301] - STOPPING server [junit] 2009-05-14 14:07:50,512 - INFO [main:nioserverc...@762] - closing session:0x1213f72bde60000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:54328] [junit] 2009-05-14 14:07:50,512 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x1213f72bde60000 to sun.nio.ch.selectionkeyi...@6c1a82 [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:632) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876) [junit] 2009-05-14 14:07:50,513 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@177] - NIOServerCnxn factory exited run method [junit] 2009-05-14 14:07:50,513 - INFO [main:finalrequestproces...@268] - shutdown of request processor complete [junit] 2009-05-14 14:07:50,513 - INFO [SyncThread:0:syncrequestproces...@119] - SyncRequestProcessor exited! [junit] 2009-05-14 14:07:50,514 - INFO [ProcessThread:-1:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-05-14 14:07:50,613 - INFO [main:clientb...@307] - STARTING server [junit] 2009-05-14 14:07:50,613 - INFO [main:zookeeperser...@160] - Created server [junit] 2009-05-14 14:07:50,614 - INFO [main:files...@81] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test4693638624287650627.junit.dir/version-2/snapshot.5 [junit] 2009-05-14 14:07:50,616 - INFO [main:filetxnsnap...@198] - Snapshotting: 6 [junit] 2009-05-14 14:07:50,618 - INFO [NIOServerCxn.Factory:33221:nioserverc...@631] - Processing stat command from /127.0.0.1:54330 [junit] 2009-05-14 14:07:50,618 - WARN [NIOServerCxn.Factory:33221:nioserverc...@431] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-05-14 14:07:50,619 - INFO [NIOServerCxn.Factory:33221:nioserverc...@762] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:54330] [junit] 2009-05-14 14:07:51,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-05-14 14:07:51,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-05-14 14:07:51,723 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-05-14 14:07:51,723 - INFO [main-SendThread:clientcnxn$sendthr...@716] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:54331 remote=/127.0.0.1:33221] [junit] 2009-05-14 14:07:51,723 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-05-14 14:07:51,723 - INFO [NIOServerCxn.Factory:33221:nioserverc...@517] - Connected to /127.0.0.1:54331 lastZxid 6 [junit] 2009-05-14 14:07:51,724 - INFO [NIOServerCxn.Factory:33221:nioserverc...@891] - Finished init of 0x1213f72bde60000 valid:true [junit] 2009-05-14 14:07:51,724 - INFO [NIOServerCxn.Factory:33221:nioserverc...@545] - Renewing session 0x1213f72bde60000 [junit] 2009-05-14 14:08:02,732 - INFO [main:zookee...@435] - Closing session: 0x1213f72bde60000 [junit] 2009-05-14 14:08:02,732 - INFO [main:clientc...@999] - Closing ClientCnxn for session: 0x1213f72bde60000 [junit] 2009-05-14 14:08:02,733 - INFO [ProcessThread:-1:preprequestproces...@365] - Processed session termination request for id: 0x1213f72bde60000 [junit] 2009-05-14 14:08:02,734 - INFO [SyncThread:0:nioserverc...@762] - closing session:0x1213f72bde60000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:54331] [junit] 2009-05-14 14:08:02,734 - INFO [main-SendThread:clientcnxn$sendthr...@892] - Exception while closing send thread for session 0x1213f72bde60000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4] [junit] 2009-05-14 14:08:02,834 - INFO [main:clientc...@985] - Disconnecting ClientCnxn for session: 0x1213f72bde60000 [junit] 2009-05-14 14:08:02,835 - INFO [main:zookee...@443] - Session: 0x1213f72bde60000 closed [junit] 2009-05-14 14:08:02,835 - INFO [main-EventThread:clientcnxn$eventthr...@452] - EventThread shut down [junit] 2009-05-14 14:08:02,835 - INFO [main:clientb...@313] - tearDown starting [junit] 2009-05-14 14:08:02,835 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@177] - NIOServerCnxn factory exited run method [junit] 2009-05-14 14:08:02,836 - INFO [main:finalrequestproces...@268] - shutdown of request processor complete [junit] 2009-05-14 14:08:02,836 - INFO [ProcessThread:-1:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-05-14 14:08:02,836 - INFO [SyncThread:0:syncrequestproces...@119] - SyncRequestProcessor exited! [junit] 2009-05-14 14:08:02,837 - INFO [main:clientb...@323] - FINISHED testWatcherAutoResetDisabledWithLocal [junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 127.282 sec [junit] Running org.apache.zookeeper.test.ZooKeeperQuotaTest [junit] 2009-05-14 14:08:03,102 - INFO [main:clientb...@289] - STARTING testQuota [junit] 2009-05-14 14:08:03,115 - INFO [main:environm...@97] - Server environment:zookeeper.version=3.2.0-774763, built on 05/14/2009 13:59 GMT [junit] 2009-05-14 14:08:03,116 - INFO [main:environm...@97] - Server environment:host.name=vesta.apache.org [junit] 2009-05-14 14:08:03,116 - INFO [main:environm...@97] - Server environment:java.version=1.6.0_11 [junit] 2009-05-14 14:08:03,117 - INFO [main:environm...@97] - Server environment:java.vendor=Sun Microsystems Inc. [junit] 2009-05-14 14:08:03,117 - INFO [main:environm...@97] - Server environment:java.home=/home/hudson/tools/java/jdk1.6.0_11-32/jre [junit] 2009-05-14 14:08:03,118 - INFO [main:environm...@97] - Server environment:java.class.path=http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/309/artifact/trunk/zookeeper-dev.jar :http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/jline-0.9.94.jar :http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/junit-4.4.jar :http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/log4j-1.2.15.jar :http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/testclasses :/home/hudson/tools/ant/apache-ant-1.7.0/lib/ant-launcher.jar:/home/hudson/tools/ant/apache-ant-1.7.0/lib/ant.jar:/home/hudson/tools/ant/apache-ant-1.7.0/lib/ant-junit.jar [junit] 2009-05-14 14:08:03,119 - INFO [main:environm...@97] - Server environment:java.library.path=/home/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386/server:/home/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386:/home/hudson/tools/java/jdk1.6.0_11-32/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib [junit] 2009-05-14 14:08:03,119 - INFO [main:environm...@97] - Server environment:java.io.tmpdir=/tmp [junit] 2009-05-14 14:08:03,120 - INFO [main:environm...@97] - Server environment:java.compiler=<NA> [junit] 2009-05-14 14:08:03,120 - INFO [main:environm...@97] - Server environment:os.name=Linux [junit] 2009-05-14 14:08:03,121 - INFO [main:environm...@97] - Server environment:os.arch=i386 [junit] 2009-05-14 14:08:03,121 - INFO [main:environm...@97] - Server environment:os.version=2.6.27-7-server [junit] 2009-05-14 14:08:03,122 - INFO [main:environm...@97] - Server environment:user.name=hudson [junit] 2009-05-14 14:08:03,122 - INFO [main:environm...@97] - Server environment:user.home=/home/hudson [junit] 2009-05-14 14:08:03,123 - INFO [main:environm...@97] - Server environment:user.dir=http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk [junit] 2009-05-14 14:08:03,127 - INFO [main:zookeeperser...@160] - Created server [junit] 2009-05-14 14:08:03,144 - INFO [main:filetxnsnap...@198] - Snapshotting: 0 [junit] 2009-05-14 14:08:03,200 - INFO [NIOServerCxn.Factory:33221:nioserverc...@631] - Processing stat command from /127.0.0.1:54334 [junit] 2009-05-14 14:08:03,200 - WARN [NIOServerCxn.Factory:33221:nioserverc...@431] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-05-14 14:08:03,200 - INFO [main:clientb...@297] - Client test setup finished [junit] 2009-05-14 14:08:03,201 - INFO [NIOServerCxn.Factory:33221:nioserverc...@762] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:54334] [junit] 2009-05-14 14:08:03,204 - INFO [main:environm...@97] - Client environment:zookeeper.version=3.2.0-774763, built on 05/14/2009 13:59 GMT [junit] 2009-05-14 14:08:03,204 - INFO [main:environm...@97] - Client environment:host.name=vesta.apache.org [junit] 2009-05-14 14:08:03,205 - INFO [main:environm...@97] - Client environment:java.version=1.6.0_11 [junit] 2009-05-14 14:08:03,205 - INFO [main:environm...@97] - Client environment:java.vendor=Sun Microsystems Inc. [junit] 2009-05-14 14:08:03,205 - INFO [main:environm...@97] - Client environment:java.home=/home/hudson/tools/java/jdk1.6.0_11-32/jre [junit] 2009-05-14 14:08:03,214 - INFO [main:environm...@97] - Client environment:java.class.path=http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/309/artifact/trunk/zookeeper-dev.jar :http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/jline-0.9.94.jar :http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/junit-4.4.jar :http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/log4j-1.2.15.jar :http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/testclasses :/home/hudson/tools/ant/apache-ant-1.7.0/lib/ant-launcher.jar:/home/hudson/tools/ant/apache-ant-1.7.0/lib/ant.jar:/home/hudson/tools/ant/apache-ant-1.7.0/lib/ant-junit.jar [junit] 2009-05-14 14:08:03,214 - INFO [main:environm...@97] - Client environment:java.library.path=/home/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386/server:/home/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386:/home/hudson/tools/java/jdk1.6.0_11-32/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib [junit] 2009-05-14 14:08:03,215 - INFO [main:environm...@97] - Client environment:java.io.tmpdir=/tmp [junit] 2009-05-14 14:08:03,215 - INFO [main:environm...@97] - Client environment:java.compiler=<NA> [junit] 2009-05-14 14:08:03,216 - INFO [main:environm...@97] - Client environment:os.name=Linux [junit] 2009-05-14 14:08:03,216 - INFO [main:environm...@97] - Client environment:os.arch=i386 [junit] 2009-05-14 14:08:03,216 - INFO [main:environm...@97] - Client environment:os.version=2.6.27-7-server [junit] 2009-05-14 14:08:03,217 - INFO [main:environm...@97] - Client environment:user.name=hudson [junit] 2009-05-14 14:08:03,217 - INFO [main:environm...@97] - Client environment:user.home=/home/hudson [junit] 2009-05-14 14:08:03,218 - INFO [main:environm...@97] - Client environment:user.dir=http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk [junit] 2009-05-14 14:08:03,219 - INFO [main:zookee...@342] - Initiating client connection, host=127.0.0.1:33221 sessionTimeout=30000 watcher=org.apache.zookeeper.test.clientbase$countdownwatc...@cf66b [junit] 2009-05-14 14:08:03,220 - INFO [main:clientc...@91] - zookeeper.disableAutoWatchReset is false [junit] 2009-05-14 14:08:03,224 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-05-14 14:08:03,225 - INFO [main-SendThread:clientcnxn$sendthr...@716] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:54335 remote=/127.0.0.1:33221] [junit] 2009-05-14 14:08:03,228 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-05-14 14:08:03,229 - INFO [NIOServerCxn.Factory:33221:nioserverc...@517] - Connected to /127.0.0.1:54335 lastZxid 0 [junit] 2009-05-14 14:08:03,230 - INFO [NIOServerCxn.Factory:33221:nioserverc...@548] - Creating new session 0x1213f73824b0000 [junit] 2009-05-14 14:08:03,245 - INFO [SyncThread:0:nioserverc...@891] - Finished init of 0x1213f73824b0000 valid:true [junit] 2009-05-14 14:08:03,272 - INFO [main:clientb...@313] - tearDown starting [junit] 2009-05-14 14:08:03,273 - INFO [main:nioserverc...@762] - closing session:0x1213f73824b0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:54335] [junit] 2009-05-14 14:08:03,274 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x1213f73824b0000 to sun.nio.ch.selectionkeyi...@1976011 [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:632) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876) [junit] 2009-05-14 14:08:03,275 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@177] - NIOServerCnxn factory exited run method [junit] 2009-05-14 14:08:03,275 - INFO [main:finalrequestproces...@268] - shutdown of request processor complete [junit] 2009-05-14 14:08:03,275 - INFO [SyncThread:0:syncrequestproces...@119] - SyncRequestProcessor exited! [junit] 2009-05-14 14:08:03,275 - INFO [ProcessThread:-1:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-05-14 14:08:03,277 - INFO [main:clientb...@323] - FINISHED testQuota [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.216 sec BUILD FAILED http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml :702: The following error occurred while executing this line: http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml :613: Tests failed! Total time: 8 minutes 40 seconds Recording fingerprints Publishing Javadoc Recording test results