See http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/215/changes
Changes: [phunt] ZOOKEEPER-229. improve documentation regarding user's responsibility to cleanup datadir (snaps/logs) [phunt] ZOOKEEPER-252. PurgeTxnLog is not handling the new dataDir directory structure [phunt] ZOOKEEPER-215. expand system test environment [mahadev] ZOOKEEPER-16. Need to do path validation. (pat, mahadev) [phunt] ZOOKEEPER-260. document the recommended values for server id's [mahadev] ZOOKEEPER-276. Bookkeeper contribution (Flavio and Luca Telloli via mahadev) ------------------------------------------ [...truncated 62302 lines...] [junit] 2009-01-31 11:46:54,241 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-31 11:46:54,242 - INFO [main:nioserverc...@737] - closing session:0x11f2c8328070000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:45427] [junit] 2009-01-31 11:46:54,242 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x11f2c8328070000 to sun.nio.ch.selectionkeyi...@19ba640 [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-01-31 11:46:54,242 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-31 11:46:54,243 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-31 11:46:54,243 - INFO [ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-01-31 11:46:54,243 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-31 11:46:54,342 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-31 11:46:54,343 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-31 11:46:54,344 - INFO [main:files...@71] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test6064899344319696207.junit.dir/version-2/snapshot.5 [junit] 2009-01-31 11:46:54,346 - INFO [main:filetxnsnap...@198] - Snapshotting: 6 [junit] 2009-01-31 11:46:54,347 - INFO [NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from /127.0.0.1:45429 [junit] 2009-01-31 11:46:54,348 - WARN [NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-31 11:46:54,349 - INFO [NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:45429] [junit] 2009-01-31 11:46:55,725 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-31 11:46:55,726 - INFO [main-SendThread:clientcnxn$sendthr...@716] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:45430 remote=/127.0.0.1:33221] [junit] 2009-01-31 11:46:55,726 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-31 11:46:55,726 - INFO [NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:45430 lastZxid 6 [junit] 2009-01-31 11:46:55,727 - INFO [NIOServerCxn.Factory:33221:nioserverc...@865] - Finished init of 0x11f2c8328070000 valid:true [junit] 2009-01-31 11:46:55,727 - INFO [NIOServerCxn.Factory:33221:nioserverc...@521] - Renewing session 0x11f2c8328070000 [junit] 2009-01-31 11:46:56,751 - INFO [main:zookee...@434] - Closing session: 0x11f2c8328070000 [junit] 2009-01-31 11:46:56,752 - INFO [main:clientc...@999] - Closing ClientCnxn for session: 0x11f2c8328070000 [junit] 2009-01-31 11:46:56,753 - INFO [ProcessThread:0:preprequestproces...@360] - Processed session termination request for id: 0x11f2c8328070000 [junit] 2009-01-31 11:46:56,754 - INFO [SyncThread:0:nioserverc...@737] - closing session:0x11f2c8328070000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:45430] [junit] 2009-01-31 11:46:56,754 - INFO [main-SendThread:clientcnxn$sendthr...@892] - Exception while closing send thread for session 0x11f2c8328070000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4] [junit] 2009-01-31 11:46:56,854 - INFO [main:clientc...@985] - Disconnecting ClientCnxn for session: 0x11f2c8328070000 [junit] 2009-01-31 11:46:56,855 - INFO [main:zookee...@442] - Session: 0x11f2c8328070000 closed [junit] 2009-01-31 11:46:56,855 - INFO [main-EventThread:clientcnxn$eventthr...@452] - EventThread shut down [junit] 2009-01-31 11:46:56,856 - INFO [main:clientb...@312] - tearDown starting [junit] 2009-01-31 11:46:56,857 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-31 11:46:56,858 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-31 11:46:56,858 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-31 11:46:56,858 - INFO [ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-01-31 11:46:56,859 - INFO [main:clientb...@322] - FINISHED testWatcherAutoResetWithLocal [junit] 2009-01-31 11:46:56,860 - INFO [main:clientb...@288] - STARTING testWatcherAutoResetDisabledWithGlobal [junit] 2009-01-31 11:46:56,860 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-31 11:46:56,861 - INFO [main:filetxnsnap...@198] - Snapshotting: 0 [junit] 2009-01-31 11:46:56,863 - INFO [NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from /127.0.0.1:45432 [junit] 2009-01-31 11:46:56,863 - WARN [NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-31 11:46:56,863 - INFO [main:clientb...@296] - Client test setup finished [junit] 2009-01-31 11:46:56,863 - INFO [NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:45432] [junit] 2009-01-31 11:46:56,864 - INFO [main:zookee...@341] - Initiating client connection, host=127.0.0.1:33221 sessionTimeout=9000 watcher=org.apache.zookeeper.test.watchertest$mywatc...@6835fb [junit] 2009-01-31 11:46:56,865 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-31 11:46:56,865 - INFO [main-SendThread:clientcnxn$sendthr...@716] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:45433 remote=/127.0.0.1:33221] [junit] 2009-01-31 11:46:56,865 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-31 11:46:56,866 - INFO [NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:45433 lastZxid 0 [junit] 2009-01-31 11:46:56,866 - INFO [NIOServerCxn.Factory:33221:nioserverc...@524] - Creating new session 0x11f2c834ede0000 [junit] 2009-01-31 11:46:56,870 - INFO [SyncThread:0:nioserverc...@865] - Finished init of 0x11f2c834ede0000 valid:true [junit] 2009-01-31 11:46:56,874 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-31 11:46:56,875 - INFO [main:nioserverc...@737] - closing session:0x11f2c834ede0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:45433] [junit] 2009-01-31 11:46:56,876 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x11f2c834ede0000 to sun.nio.ch.selectionkeyi...@1c8efd1 [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-01-31 11:46:56,876 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-31 11:46:56,876 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-31 11:46:56,876 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-31 11:46:56,876 - INFO [ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-01-31 11:46:56,976 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-31 11:46:56,976 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-31 11:46:56,977 - INFO [main:files...@71] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test4597051642932905888.junit.dir/version-2/snapshot.0 [junit] 2009-01-31 11:46:56,978 - INFO [main:filetxnsnap...@198] - Snapshotting: 3 [junit] 2009-01-31 11:46:56,980 - INFO [NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from /127.0.0.1:45435 [junit] 2009-01-31 11:46:56,980 - WARN [NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-31 11:46:56,981 - INFO [NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:45435] [junit] 2009-01-31 11:46:57,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-31 11:46:57,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-31 11:46:57,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-31 11:46:58,382 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-31 11:46:58,382 - INFO [main-SendThread:clientcnxn$sendthr...@716] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:45436 remote=/127.0.0.1:33221] [junit] 2009-01-31 11:46:58,382 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-31 11:46:58,383 - INFO [NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:45436 lastZxid 3 [junit] 2009-01-31 11:46:58,383 - INFO [NIOServerCxn.Factory:33221:nioserverc...@865] - Finished init of 0x11f2c834ede0000 valid:true [junit] 2009-01-31 11:46:58,383 - INFO [NIOServerCxn.Factory:33221:nioserverc...@521] - Renewing session 0x11f2c834ede0000 [junit] 2009-01-31 11:47:32,394 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-31 11:47:32,395 - INFO [main:nioserverc...@737] - closing session:0x11f2c834ede0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:45436] [junit] 2009-01-31 11:47:32,395 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x11f2c834ede0000 to sun.nio.ch.selectionkeyi...@8b8a47 [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-01-31 11:47:32,396 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-31 11:47:32,396 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-31 11:47:32,396 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-31 11:47:32,396 - INFO [ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-01-31 11:47:32,496 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-31 11:47:32,496 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-31 11:47:32,497 - INFO [main:files...@71] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test4597051642932905888.junit.dir/version-2/snapshot.3 [junit] 2009-01-31 11:47:32,517 - INFO [main:filetxnsnap...@198] - Snapshotting: 5 [junit] 2009-01-31 11:47:32,519 - INFO [NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from /127.0.0.1:59883 [junit] 2009-01-31 11:47:32,529 - WARN [NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-31 11:47:32,530 - INFO [NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:59883] [junit] 2009-01-31 11:47:33,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-31 11:47:34,111 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-31 11:47:34,111 - INFO [main-SendThread:clientcnxn$sendthr...@716] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:59884 remote=/127.0.0.1:33221] [junit] 2009-01-31 11:47:34,111 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-31 11:47:34,111 - INFO [NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:59884 lastZxid 5 [junit] 2009-01-31 11:47:34,112 - INFO [NIOServerCxn.Factory:33221:nioserverc...@865] - Finished init of 0x11f2c834ede0000 valid:true [junit] 2009-01-31 11:47:34,112 - INFO [NIOServerCxn.Factory:33221:nioserverc...@521] - Renewing session 0x11f2c834ede0000 [junit] 2009-01-31 11:47:34,123 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-31 11:47:34,124 - INFO [main:nioserverc...@737] - closing session:0x11f2c834ede0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:59884] [junit] 2009-01-31 11:47:34,124 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x11f2c834ede0000 to sun.nio.ch.selectionkeyi...@10deb5f [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-01-31 11:47:34,125 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-31 11:47:34,125 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-31 11:47:34,125 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-31 11:47:34,125 - INFO [ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-01-31 11:47:34,225 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-31 11:47:34,225 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-31 11:47:34,226 - INFO [main:files...@71] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test4597051642932905888.junit.dir/version-2/snapshot.5 [junit] 2009-01-31 11:47:34,228 - INFO [main:filetxnsnap...@198] - Snapshotting: 6 [junit] 2009-01-31 11:47:34,230 - INFO [NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from /127.0.0.1:59886 [junit] 2009-01-31 11:47:34,231 - WARN [NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-31 11:47:34,231 - INFO [NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:59886] [junit] 2009-01-31 11:47:35,401 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-31 11:47:35,401 - INFO [main-SendThread:clientcnxn$sendthr...@716] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:59887 remote=/127.0.0.1:33221] [junit] 2009-01-31 11:47:35,401 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-31 11:47:35,402 - INFO [NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:59887 lastZxid 6 [junit] 2009-01-31 11:47:35,402 - INFO [NIOServerCxn.Factory:33221:nioserverc...@865] - Finished init of 0x11f2c834ede0000 valid:true [junit] 2009-01-31 11:47:35,402 - INFO [NIOServerCxn.Factory:33221:nioserverc...@521] - Renewing session 0x11f2c834ede0000 [junit] 2009-01-31 11:47:36,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-31 11:47:46,414 - INFO [main:zookee...@434] - Closing session: 0x11f2c834ede0000 [junit] 2009-01-31 11:47:46,414 - INFO [main:clientc...@999] - Closing ClientCnxn for session: 0x11f2c834ede0000 [junit] 2009-01-31 11:47:46,415 - INFO [ProcessThread:0:preprequestproces...@360] - Processed session termination request for id: 0x11f2c834ede0000 [junit] 2009-01-31 11:47:46,416 - INFO [SyncThread:0:nioserverc...@737] - closing session:0x11f2c834ede0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:59887] [junit] 2009-01-31 11:47:46,416 - INFO [main-SendThread:clientcnxn$sendthr...@892] - Exception while closing send thread for session 0x11f2c834ede0000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4] [junit] 2009-01-31 11:47:46,516 - INFO [main:clientc...@985] - Disconnecting ClientCnxn for session: 0x11f2c834ede0000 [junit] 2009-01-31 11:47:46,517 - INFO [main:zookee...@442] - Session: 0x11f2c834ede0000 closed [junit] 2009-01-31 11:47:46,517 - INFO [main-EventThread:clientcnxn$eventthr...@452] - EventThread shut down [junit] 2009-01-31 11:47:46,517 - INFO [main:clientb...@312] - tearDown starting [junit] 2009-01-31 11:47:46,517 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-31 11:47:46,518 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-31 11:47:46,518 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-31 11:47:46,518 - INFO [ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-01-31 11:47:46,519 - INFO [main:clientb...@322] - FINISHED testWatcherAutoResetDisabledWithGlobal [junit] 2009-01-31 11:47:46,520 - INFO [main:clientb...@288] - STARTING testWatcherAutoResetDisabledWithLocal [junit] 2009-01-31 11:47:46,520 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-31 11:47:46,521 - INFO [main:filetxnsnap...@198] - Snapshotting: 0 [junit] 2009-01-31 11:47:46,523 - INFO [NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from /127.0.0.1:59889 [junit] 2009-01-31 11:47:46,523 - WARN [NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-31 11:47:46,523 - INFO [main:clientb...@296] - Client test setup finished [junit] 2009-01-31 11:47:46,523 - INFO [NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:59889] [junit] 2009-01-31 11:47:46,524 - INFO [main:zookee...@341] - Initiating client connection, host=127.0.0.1:33221 sessionTimeout=9000 watcher=org.apache.zookeeper.test.watchertest$mywatc...@a53de4 [junit] 2009-01-31 11:47:46,525 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-31 11:47:46,525 - INFO [main-SendThread:clientcnxn$sendthr...@716] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:59890 remote=/127.0.0.1:33221] [junit] 2009-01-31 11:47:46,525 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-31 11:47:46,526 - INFO [NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:59890 lastZxid 0 [junit] 2009-01-31 11:47:46,526 - INFO [NIOServerCxn.Factory:33221:nioserverc...@524] - Creating new session 0x11f2c8410da0000 [junit] 2009-01-31 11:47:46,533 - INFO [SyncThread:0:nioserverc...@865] - Finished init of 0x11f2c8410da0000 valid:true [junit] 2009-01-31 11:47:46,538 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-31 11:47:46,539 - INFO [main:nioserverc...@737] - closing session:0x11f2c8410da0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:59890] [junit] 2009-01-31 11:47:46,539 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x11f2c8410da0000 to sun.nio.ch.selectionkeyi...@b4be3d [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-01-31 11:47:46,539 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-31 11:47:46,540 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-31 11:47:46,540 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-31 11:47:46,541 - INFO [ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-01-31 11:47:46,640 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-31 11:47:46,640 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-31 11:47:46,641 - INFO [main:files...@71] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test637807701887696278.junit.dir/version-2/snapshot.0 [junit] 2009-01-31 11:47:46,642 - INFO [main:filetxnsnap...@198] - Snapshotting: 3 [junit] 2009-01-31 11:47:46,644 - INFO [NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from /127.0.0.1:59892 [junit] 2009-01-31 11:47:46,644 - WARN [NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-31 11:47:46,645 - INFO [NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:59892] [junit] 2009-01-31 11:47:48,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-31 11:47:48,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-31 11:47:48,285 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-31 11:47:48,285 - INFO [main-SendThread:clientcnxn$sendthr...@716] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:59893 remote=/127.0.0.1:33221] [junit] 2009-01-31 11:47:48,285 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-31 11:47:48,285 - INFO [NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:59893 lastZxid 3 [junit] 2009-01-31 11:47:48,286 - INFO [NIOServerCxn.Factory:33221:nioserverc...@865] - Finished init of 0x11f2c8410da0000 valid:true [junit] 2009-01-31 11:47:48,286 - INFO [NIOServerCxn.Factory:33221:nioserverc...@521] - Renewing session 0x11f2c8410da0000 [junit] 2009-01-31 11:48:22,294 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-31 11:48:22,295 - INFO [main:nioserverc...@737] - closing session:0x11f2c8410da0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:59893] [junit] 2009-01-31 11:48:22,295 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x11f2c8410da0000 to sun.nio.ch.selectionkeyi...@57ea52 [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-01-31 11:48:22,312 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-31 11:48:22,313 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-31 11:48:22,313 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-31 11:48:22,313 - INFO [ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-01-31 11:48:22,412 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-31 11:48:22,413 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-31 11:48:22,413 - INFO [main:files...@71] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test637807701887696278.junit.dir/version-2/snapshot.3 [junit] 2009-01-31 11:48:22,415 - INFO [main:filetxnsnap...@198] - Snapshotting: 5 [junit] 2009-01-31 11:48:22,423 - INFO [NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from /127.0.0.1:59895 [junit] 2009-01-31 11:48:22,424 - WARN [NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-31 11:48:22,424 - INFO [NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:59895] [junit] 2009-01-31 11:48:23,640 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-31 11:48:23,641 - INFO [main-SendThread:clientcnxn$sendthr...@716] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:59896 remote=/127.0.0.1:33221] [junit] 2009-01-31 11:48:23,641 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-31 11:48:23,641 - INFO [NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:59896 lastZxid 5 [junit] 2009-01-31 11:48:23,642 - INFO [NIOServerCxn.Factory:33221:nioserverc...@865] - Finished init of 0x11f2c8410da0000 valid:true [junit] 2009-01-31 11:48:23,642 - INFO [NIOServerCxn.Factory:33221:nioserverc...@521] - Renewing session 0x11f2c8410da0000 [junit] 2009-01-31 11:48:23,659 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-31 11:48:23,660 - INFO [main:nioserverc...@737] - closing session:0x11f2c8410da0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:59896] [junit] 2009-01-31 11:48:23,660 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x11f2c8410da0000 to sun.nio.ch.selectionkeyi...@126d3df [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-01-31 11:48:23,662 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-31 11:48:23,662 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-31 11:48:23,662 - INFO [ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-01-31 11:48:23,662 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-31 11:48:23,761 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-31 11:48:23,761 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-31 11:48:23,762 - INFO [main:files...@71] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test637807701887696278.junit.dir/version-2/snapshot.5 [junit] 2009-01-31 11:48:23,768 - INFO [main:filetxnsnap...@198] - Snapshotting: 6 [junit] 2009-01-31 11:48:23,792 - INFO [NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from /127.0.0.1:59898 [junit] 2009-01-31 11:48:23,792 - WARN [NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-31 11:48:23,792 - INFO [NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:59898] [junit] 2009-01-31 11:48:24,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-31 11:48:24,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-31 11:48:25,252 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-31 11:48:25,252 - INFO [main-SendThread:clientcnxn$sendthr...@716] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:59899 remote=/127.0.0.1:33221] [junit] 2009-01-31 11:48:25,252 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-31 11:48:25,253 - INFO [NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:59899 lastZxid 6 [junit] 2009-01-31 11:48:25,254 - INFO [NIOServerCxn.Factory:33221:nioserverc...@865] - Finished init of 0x11f2c8410da0000 valid:true [junit] 2009-01-31 11:48:25,254 - INFO [NIOServerCxn.Factory:33221:nioserverc...@521] - Renewing session 0x11f2c8410da0000 [junit] 2009-01-31 11:48:36,264 - INFO [main:zookee...@434] - Closing session: 0x11f2c8410da0000 [junit] 2009-01-31 11:48:36,264 - INFO [main:clientc...@999] - Closing ClientCnxn for session: 0x11f2c8410da0000 [junit] 2009-01-31 11:48:36,265 - INFO [ProcessThread:0:preprequestproces...@360] - Processed session termination request for id: 0x11f2c8410da0000 [junit] 2009-01-31 11:48:36,265 - INFO [SyncThread:0:nioserverc...@737] - closing session:0x11f2c8410da0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:59899] [junit] 2009-01-31 11:48:36,268 - INFO [main-SendThread:clientcnxn$sendthr...@892] - Exception while closing send thread for session 0x11f2c8410da0000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4] [junit] 2009-01-31 11:48:36,368 - INFO [main:clientc...@985] - Disconnecting ClientCnxn for session: 0x11f2c8410da0000 [junit] 2009-01-31 11:48:36,369 - INFO [main:zookee...@442] - Session: 0x11f2c8410da0000 closed [junit] 2009-01-31 11:48:36,369 - INFO [main-EventThread:clientcnxn$eventthr...@452] - EventThread shut down [junit] 2009-01-31 11:48:36,369 - INFO [main:clientb...@312] - tearDown starting [junit] 2009-01-31 11:48:36,369 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-31 11:48:36,370 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-31 11:48:36,370 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-31 11:48:36,370 - INFO [ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop! [junit] 2009-01-31 11:48:36,371 - INFO [main:clientb...@322] - FINISHED testWatcherAutoResetDisabledWithLocal [junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 125.538 sec BUILD FAILED http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml :554: Tests failed! Total time: 8 minutes 31 seconds Recording fingerprints Publishing Javadoc Recording test results