See http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/214/changes
Changes: [mahadev] ZOOKEEPER-269. connectionloss- add more documentation to detail. (phunt and flavio via mahadev) [mahadev] ZOOKEEPER-272. getchildren can fail for large number of children. (mahadev) [mahadev] ZOOKEEPER-275. Bug in FastLeaderElection. (flavio via mahadev) ------------------------------------------ [...truncated 62276 lines...] [junit] 2009-01-30 11:49:10,061 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-30 11:49:10,062 - INFO [main:nioserverc...@732] - closing session:0x11f275eddb80000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51077] [junit] 2009-01-30 11:49:10,062 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x11f275eddb80000 to sun.nio.ch.selectionkeyi...@1a19458 [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:631) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876) [junit] 2009-01-30 11:49:10,063 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-30 11:49:10,063 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-30 11:49:10,063 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-30 11:49:10,063 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-30 11:49:10,163 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-30 11:49:10,163 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-30 11:49:10,165 - INFO [main:files...@70] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test1801438123754631699.junit.dir/version-2/snapshot.5 [junit] 2009-01-30 11:49:10,166 - INFO [main:filetxnsnap...@197] - Snapshotting: 6 [junit] 2009-01-30 11:49:10,168 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:51079 [junit] 2009-01-30 11:49:10,169 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-30 11:49:10,169 - INFO [NIOServerCxn.Factory:33221:nioserverc...@732] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51079] [junit] 2009-01-30 11:49:11,430 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-30 11:49:11,430 - INFO [main-SendThread:clientcnxn$sendthr...@715] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:51080 remote=/127.0.0.1:33221] [junit] 2009-01-30 11:49:11,430 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-30 11:49:11,431 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:51080 lastZxid 6 [junit] 2009-01-30 11:49:11,431 - INFO [NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 0x11f275eddb80000 valid:true [junit] 2009-01-30 11:49:11,431 - INFO [NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 0x11f275eddb80000 [junit] 2009-01-30 11:49:12,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-30 11:49:12,441 - INFO [main:zookee...@434] - Closing session: 0x11f275eddb80000 [junit] 2009-01-30 11:49:12,441 - INFO [main:clientc...@999] - Closing ClientCnxn for session: 0x11f275eddb80000 [junit] 2009-01-30 11:49:12,442 - INFO [ProcessThread:0:preprequestproces...@344] - Processed session termination request for id: 0x11f275eddb80000 [junit] 2009-01-30 11:49:12,443 - INFO [SyncThread:0:nioserverc...@732] - closing session:0x11f275eddb80000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51080] [junit] 2009-01-30 11:49:12,443 - INFO [main-SendThread:clientcnxn$sendthr...@892] - Exception while closing send thread for session 0x11f275eddb80000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4] [junit] 2009-01-30 11:49:12,543 - INFO [main:clientc...@985] - Disconnecting ClientCnxn for session: 0x11f275eddb80000 [junit] 2009-01-30 11:49:12,544 - INFO [main:zookee...@442] - Session: 0x11f275eddb80000 closed [junit] 2009-01-30 11:49:12,544 - INFO [main-EventThread:clientcnxn$eventthr...@452] - EventThread shut down [junit] 2009-01-30 11:49:12,544 - INFO [main:clientb...@312] - tearDown starting [junit] 2009-01-30 11:49:12,545 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-30 11:49:12,545 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-30 11:49:12,545 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-30 11:49:12,545 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-30 11:49:12,546 - INFO [main:clientb...@322] - FINISHED testWatcherAutoResetWithLocal [junit] 2009-01-30 11:49:12,547 - INFO [main:clientb...@288] - STARTING testWatcherAutoResetDisabledWithGlobal [junit] 2009-01-30 11:49:12,548 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-30 11:49:12,548 - INFO [main:filetxnsnap...@197] - Snapshotting: 0 [junit] 2009-01-30 11:49:12,550 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:51082 [junit] 2009-01-30 11:49:12,550 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-30 11:49:12,550 - INFO [main:clientb...@296] - Client test setup finished [junit] 2009-01-30 11:49:12,551 - INFO [NIOServerCxn.Factory:33221:nioserverc...@732] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51082] [junit] 2009-01-30 11:49:12,551 - INFO [main:zookee...@341] - Initiating client connection, host=127.0.0.1:33221 sessionTimeout=9000 watcher=org.apache.zookeeper.test.watchertest$mywatc...@51127a [junit] 2009-01-30 11:49:12,552 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-30 11:49:12,552 - INFO [main-SendThread:clientcnxn$sendthr...@715] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:51083 remote=/127.0.0.1:33221] [junit] 2009-01-30 11:49:12,552 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-30 11:49:12,552 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:51083 lastZxid 0 [junit] 2009-01-30 11:49:12,553 - INFO [NIOServerCxn.Factory:33221:nioserverc...@519] - Creating new session 0x11f275f04e50000 [junit] 2009-01-30 11:49:12,559 - INFO [SyncThread:0:nioserverc...@860] - Finished init of 0x11f275f04e50000 valid:true [junit] 2009-01-30 11:49:12,564 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-30 11:49:12,565 - INFO [main:nioserverc...@732] - closing session:0x11f275f04e50000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51083] [junit] 2009-01-30 11:49:12,566 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x11f275f04e50000 to sun.nio.ch.selectionkeyi...@691dee [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:631) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876) [junit] 2009-01-30 11:49:12,566 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-30 11:49:12,566 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-30 11:49:12,566 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-30 11:49:12,568 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-30 11:49:12,666 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-30 11:49:12,667 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-30 11:49:12,667 - INFO [main:files...@70] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test6315128355627669072.junit.dir/version-2/snapshot.0 [junit] 2009-01-30 11:49:12,668 - INFO [main:filetxnsnap...@197] - Snapshotting: 3 [junit] 2009-01-30 11:49:12,670 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:51085 [junit] 2009-01-30 11:49:12,671 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-30 11:49:12,671 - INFO [NIOServerCxn.Factory:33221:nioserverc...@732] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51085] [junit] 2009-01-30 11:49:14,235 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-30 11:49:14,236 - INFO [main-SendThread:clientcnxn$sendthr...@715] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:51086 remote=/127.0.0.1:33221] [junit] 2009-01-30 11:49:14,236 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-30 11:49:14,236 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:51086 lastZxid 3 [junit] 2009-01-30 11:49:14,237 - INFO [NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 0x11f275f04e50000 valid:true [junit] 2009-01-30 11:49:14,237 - INFO [NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 0x11f275f04e50000 [junit] 2009-01-30 11:49:15,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-30 11:49:15,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-30 11:49:48,253 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-30 11:49:48,254 - INFO [main:nioserverc...@732] - closing session:0x11f275f04e50000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51086] [junit] 2009-01-30 11:49:48,254 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x11f275f04e50000 to sun.nio.ch.selectionkeyi...@bb494b [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:631) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876) [junit] 2009-01-30 11:49:48,254 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-30 11:49:48,255 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-30 11:49:48,255 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-30 11:49:48,255 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-30 11:49:48,355 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-30 11:49:48,355 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-30 11:49:48,356 - INFO [main:files...@70] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test6315128355627669072.junit.dir/version-2/snapshot.3 [junit] 2009-01-30 11:49:48,358 - INFO [main:filetxnsnap...@197] - Snapshotting: 5 [junit] 2009-01-30 11:49:48,360 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:51088 [junit] 2009-01-30 11:49:48,360 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-30 11:49:48,360 - INFO [NIOServerCxn.Factory:33221:nioserverc...@732] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51088] [junit] 2009-01-30 11:49:49,890 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-30 11:49:49,890 - INFO [main-SendThread:clientcnxn$sendthr...@715] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:51089 remote=/127.0.0.1:33221] [junit] 2009-01-30 11:49:49,890 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-30 11:49:49,890 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:51089 lastZxid 5 [junit] 2009-01-30 11:49:49,891 - INFO [NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 0x11f275f04e50000 valid:true [junit] 2009-01-30 11:49:49,891 - INFO [NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 0x11f275f04e50000 [junit] 2009-01-30 11:49:49,901 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-30 11:49:49,902 - INFO [main:nioserverc...@732] - closing session:0x11f275f04e50000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51089] [junit] 2009-01-30 11:49:49,903 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x11f275f04e50000 to sun.nio.ch.selectionkeyi...@ee1ede [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:631) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876) [junit] 2009-01-30 11:49:49,903 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-30 11:49:49,903 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-30 11:49:49,903 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-30 11:49:49,903 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-30 11:49:50,003 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-30 11:49:50,003 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-30 11:49:50,005 - INFO [main:files...@70] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test6315128355627669072.junit.dir/version-2/snapshot.5 [junit] 2009-01-30 11:49:50,007 - INFO [main:filetxnsnap...@197] - Snapshotting: 6 [junit] 2009-01-30 11:49:50,008 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:51091 [junit] 2009-01-30 11:49:50,009 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-30 11:49:50,009 - INFO [NIOServerCxn.Factory:33221:nioserverc...@732] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51091] [junit] 2009-01-30 11:49:51,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-30 11:49:51,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-30 11:49:51,231 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-30 11:49:51,231 - INFO [main-SendThread:clientcnxn$sendthr...@715] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:51092 remote=/127.0.0.1:33221] [junit] 2009-01-30 11:49:51,232 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-30 11:49:51,232 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:51092 lastZxid 6 [junit] 2009-01-30 11:49:51,232 - INFO [NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 0x11f275f04e50000 valid:true [junit] 2009-01-30 11:49:51,233 - INFO [NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 0x11f275f04e50000 [junit] 2009-01-30 11:50:02,240 - INFO [main:zookee...@434] - Closing session: 0x11f275f04e50000 [junit] 2009-01-30 11:50:02,241 - INFO [main:clientc...@999] - Closing ClientCnxn for session: 0x11f275f04e50000 [junit] 2009-01-30 11:50:02,242 - INFO [ProcessThread:0:preprequestproces...@344] - Processed session termination request for id: 0x11f275f04e50000 [junit] 2009-01-30 11:50:02,243 - INFO [SyncThread:0:nioserverc...@732] - closing session:0x11f275f04e50000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51092] [junit] 2009-01-30 11:50:02,243 - INFO [main-SendThread:clientcnxn$sendthr...@892] - Exception while closing send thread for session 0x11f275f04e50000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4] [junit] 2009-01-30 11:50:02,344 - INFO [main:clientc...@985] - Disconnecting ClientCnxn for session: 0x11f275f04e50000 [junit] 2009-01-30 11:50:02,344 - INFO [main:zookee...@442] - Session: 0x11f275f04e50000 closed [junit] 2009-01-30 11:50:02,344 - INFO [main:clientb...@312] - tearDown starting [junit] 2009-01-30 11:50:02,344 - INFO [main-EventThread:clientcnxn$eventthr...@452] - EventThread shut down [junit] 2009-01-30 11:50:02,345 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-30 11:50:02,345 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-30 11:50:02,345 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-30 11:50:02,345 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-30 11:50:02,347 - INFO [main:clientb...@322] - FINISHED testWatcherAutoResetDisabledWithGlobal [junit] 2009-01-30 11:50:02,348 - INFO [main:clientb...@288] - STARTING testWatcherAutoResetDisabledWithLocal [junit] 2009-01-30 11:50:02,348 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-30 11:50:02,349 - INFO [main:filetxnsnap...@197] - Snapshotting: 0 [junit] 2009-01-30 11:50:02,351 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:51094 [junit] 2009-01-30 11:50:02,351 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-30 11:50:02,351 - INFO [main:clientb...@296] - Client test setup finished [junit] 2009-01-30 11:50:02,352 - INFO [NIOServerCxn.Factory:33221:nioserverc...@732] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51094] [junit] 2009-01-30 11:50:02,352 - INFO [main:zookee...@341] - Initiating client connection, host=127.0.0.1:33221 sessionTimeout=9000 watcher=org.apache.zookeeper.test.watchertest$mywatc...@3e96cf [junit] 2009-01-30 11:50:02,353 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-30 11:50:02,353 - INFO [main-SendThread:clientcnxn$sendthr...@715] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:51095 remote=/127.0.0.1:33221] [junit] 2009-01-30 11:50:02,353 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-30 11:50:02,354 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:51095 lastZxid 0 [junit] 2009-01-30 11:50:02,354 - INFO [NIOServerCxn.Factory:33221:nioserverc...@519] - Creating new session 0x11f275fc76e0000 [junit] 2009-01-30 11:50:02,362 - INFO [SyncThread:0:nioserverc...@860] - Finished init of 0x11f275fc76e0000 valid:true [junit] 2009-01-30 11:50:02,366 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-30 11:50:02,367 - INFO [main:nioserverc...@732] - closing session:0x11f275fc76e0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51095] [junit] 2009-01-30 11:50:02,367 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x11f275fc76e0000 to sun.nio.ch.selectionkeyi...@94af2f [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:631) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876) [junit] 2009-01-30 11:50:02,368 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-30 11:50:02,368 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-30 11:50:02,368 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-30 11:50:02,368 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-30 11:50:02,468 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-30 11:50:02,468 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-30 11:50:02,469 - INFO [main:files...@70] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test6849952115639494848.junit.dir/version-2/snapshot.0 [junit] 2009-01-30 11:50:02,470 - INFO [main:filetxnsnap...@197] - Snapshotting: 3 [junit] 2009-01-30 11:50:02,472 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:51097 [junit] 2009-01-30 11:50:02,472 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-30 11:50:02,473 - INFO [NIOServerCxn.Factory:33221:nioserverc...@732] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51097] [junit] 2009-01-30 11:50:03,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-30 11:50:03,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-30 11:50:03,891 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-30 11:50:03,891 - INFO [main-SendThread:clientcnxn$sendthr...@715] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:51098 remote=/127.0.0.1:33221] [junit] 2009-01-30 11:50:03,891 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-30 11:50:03,892 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:51098 lastZxid 3 [junit] 2009-01-30 11:50:03,893 - INFO [NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 0x11f275fc76e0000 valid:true [junit] 2009-01-30 11:50:03,893 - INFO [NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 0x11f275fc76e0000 [junit] 2009-01-30 11:50:37,903 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-30 11:50:37,904 - INFO [main:nioserverc...@732] - closing session:0x11f275fc76e0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51098] [junit] 2009-01-30 11:50:37,904 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x11f275fc76e0000 to sun.nio.ch.selectionkeyi...@8ae45a [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:631) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876) [junit] 2009-01-30 11:50:37,937 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-30 11:50:37,937 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-30 11:50:37,938 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-30 11:50:37,937 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-30 11:50:38,035 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-30 11:50:38,035 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-30 11:50:38,036 - INFO [main:files...@70] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test6849952115639494848.junit.dir/version-2/snapshot.3 [junit] 2009-01-30 11:50:38,045 - INFO [main:filetxnsnap...@197] - Snapshotting: 5 [junit] 2009-01-30 11:50:38,047 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:51100 [junit] 2009-01-30 11:50:38,047 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-30 11:50:38,047 - INFO [NIOServerCxn.Factory:33221:nioserverc...@732] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51100] [junit] 2009-01-30 11:50:39,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-30 11:50:39,455 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-30 11:50:39,455 - INFO [main-SendThread:clientcnxn$sendthr...@715] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:51101 remote=/127.0.0.1:33221] [junit] 2009-01-30 11:50:39,455 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-30 11:50:39,455 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:51101 lastZxid 5 [junit] 2009-01-30 11:50:39,456 - INFO [NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 0x11f275fc76e0000 valid:true [junit] 2009-01-30 11:50:39,456 - INFO [NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 0x11f275fc76e0000 [junit] 2009-01-30 11:50:39,471 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-30 11:50:39,472 - INFO [main:nioserverc...@732] - closing session:0x11f275fc76e0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51101] [junit] 2009-01-30 11:50:39,472 - WARN [main-SendThread:clientcnxn$sendthr...@898] - Exception closing session 0x11f275fc76e0000 to sun.nio.ch.selectionkeyi...@1d6fbb3 [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:631) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876) [junit] 2009-01-30 11:50:39,473 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-30 11:50:39,473 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-30 11:50:39,473 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-30 11:50:39,474 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-30 11:50:39,573 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-30 11:50:39,573 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-30 11:50:39,574 - INFO [main:files...@70] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test6849952115639494848.junit.dir/version-2/snapshot.5 [junit] 2009-01-30 11:50:39,576 - INFO [main:filetxnsnap...@197] - Snapshotting: 6 [junit] 2009-01-30 11:50:39,578 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:51103 [junit] 2009-01-30 11:50:39,578 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-30 11:50:39,578 - INFO [NIOServerCxn.Factory:33221:nioserverc...@732] - closing session:0x0 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51103] [junit] 2009-01-30 11:50:41,241 - INFO [main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-30 11:50:41,241 - INFO [main-SendThread:clientcnxn$sendthr...@715] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:51104 remote=/127.0.0.1:33221] [junit] 2009-01-30 11:50:41,241 - INFO [main-SendThread:clientcnxn$sendthr...@868] - Server connection successful [junit] 2009-01-30 11:50:41,242 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:51104 lastZxid 6 [junit] 2009-01-30 11:50:41,242 - INFO [NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 0x11f275fc76e0000 valid:true [junit] 2009-01-30 11:50:41,242 - INFO [NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 0x11f275fc76e0000 [junit] 2009-01-30 11:50:42,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-30 11:50:52,252 - INFO [main:zookee...@434] - Closing session: 0x11f275fc76e0000 [junit] 2009-01-30 11:50:52,252 - INFO [main:clientc...@999] - Closing ClientCnxn for session: 0x11f275fc76e0000 [junit] 2009-01-30 11:50:52,253 - INFO [ProcessThread:0:preprequestproces...@344] - Processed session termination request for id: 0x11f275fc76e0000 [junit] 2009-01-30 11:50:52,253 - INFO [SyncThread:0:nioserverc...@732] - closing session:0x11f275fc76e0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:51104] [junit] 2009-01-30 11:50:52,254 - INFO [main-SendThread:clientcnxn$sendthr...@892] - Exception while closing send thread for session 0x11f275fc76e0000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4] [junit] 2009-01-30 11:50:52,354 - INFO [main:clientc...@985] - Disconnecting ClientCnxn for session: 0x11f275fc76e0000 [junit] 2009-01-30 11:50:52,354 - INFO [main:zookee...@442] - Session: 0x11f275fc76e0000 closed [junit] 2009-01-30 11:50:52,354 - INFO [main-EventThread:clientcnxn$eventthr...@452] - EventThread shut down [junit] 2009-01-30 11:50:52,354 - INFO [main:clientb...@312] - tearDown starting [junit] 2009-01-30 11:50:52,355 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-30 11:50:52,355 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-30 11:50:52,355 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-30 11:50:52,355 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-30 11:50:52,357 - INFO [main:clientb...@322] - FINISHED testWatcherAutoResetDisabledWithLocal [junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 126.492 sec BUILD FAILED http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml :547: Tests failed! Total time: 8 minutes 43 seconds Recording fingerprints Publishing Javadoc Recording test results