See http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/213/changes
------------------------------------------ [...truncated 62500 lines...] [junit] 2009-01-29 11:43:48,322 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-29 11:43:48,322 - INFO [main:nioserverc...@732] - closing session:0x11f223397400000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:46688] [junit] 2009-01-29 11:43:48,323 - WARN [main-SendThread:clientcnxn$sendthr...@895] - Exception closing session 0x11f223397400000 to sun.nio.ch.selectionkeyi...@7976c1 [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:628) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:873) [junit] 2009-01-29 11:43:48,323 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-29 11:43:48,323 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-29 11:43:48,323 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-29 11:43:48,323 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-29 11:43:48,423 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-29 11:43:48,424 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-29 11:43:48,425 - INFO [main:files...@70] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test4650600035400512335.junit.dir/version-2/snapshot.5 [junit] 2009-01-29 11:43:48,427 - INFO [main:filetxnsnap...@197] - Snapshotting: 6 [junit] 2009-01-29 11:43:48,429 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:46690 [junit] 2009-01-29 11:43:48,430 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-29 11:43:48,430 - 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:46690] [junit] 2009-01-29 11:43:50,123 - INFO [main-SendThread:clientcnxn$sendthr...@797] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-29 11:43:50,124 - INFO [main-SendThread:clientcnxn$sendthr...@712] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:46691 remote=/127.0.0.1:33221] [junit] 2009-01-29 11:43:50,124 - INFO [main-SendThread:clientcnxn$sendthr...@865] - Server connection successful [junit] 2009-01-29 11:43:50,124 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:46691 lastZxid 6 [junit] 2009-01-29 11:43:50,125 - INFO [NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 0x11f223397400000 valid:true [junit] 2009-01-29 11:43:50,125 - INFO [NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 0x11f223397400000 [junit] 2009-01-29 11:43:51,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-29 11:43:51,132 - INFO [main:zookee...@434] - Closing session: 0x11f223397400000 [junit] 2009-01-29 11:43:51,132 - INFO [main:clientc...@996] - Closing ClientCnxn for session: 0x11f223397400000 [junit] 2009-01-29 11:43:51,133 - INFO [ProcessThread:0:preprequestproces...@344] - Processed session termination request for id: 0x11f223397400000 [junit] 2009-01-29 11:43:51,134 - INFO [SyncThread:0:nioserverc...@732] - closing session:0x11f223397400000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:46691] [junit] 2009-01-29 11:43:51,134 - INFO [main-SendThread:clientcnxn$sendthr...@889] - Exception while closing send thread for session 0x11f223397400000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4] [junit] 2009-01-29 11:43:51,234 - INFO [main:clientc...@982] - Disconnecting ClientCnxn for session: 0x11f223397400000 [junit] 2009-01-29 11:43:51,234 - INFO [main:zookee...@442] - Session: 0x11f223397400000 closed [junit] 2009-01-29 11:43:51,234 - INFO [main-EventThread:clientcnxn$eventthr...@449] - EventThread shut down [junit] 2009-01-29 11:43:51,235 - INFO [main:clientb...@312] - tearDown starting [junit] 2009-01-29 11:43:51,235 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-29 11:43:51,235 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-29 11:43:51,235 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-29 11:43:51,236 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-29 11:43:51,237 - INFO [main:clientb...@322] - FINISHED testWatcherAutoResetWithLocal [junit] 2009-01-29 11:43:51,238 - INFO [main:clientb...@288] - STARTING testWatcherAutoResetDisabledWithGlobal [junit] 2009-01-29 11:43:51,238 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-29 11:43:51,239 - INFO [main:filetxnsnap...@197] - Snapshotting: 0 [junit] 2009-01-29 11:43:51,241 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:46693 [junit] 2009-01-29 11:43:51,241 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-29 11:43:51,241 - INFO [main:clientb...@296] - Client test setup finished [junit] 2009-01-29 11:43:51,242 - INFO [main:zookee...@341] - Initiating client connection, host=127.0.0.1:33221 sessionTimeout=9000 watcher=org.apache.zookeeper.test.watchertest$mywatc...@27982 [junit] 2009-01-29 11:43:51,241 - 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:46693] [junit] 2009-01-29 11:43:51,242 - INFO [main-SendThread:clientcnxn$sendthr...@797] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-29 11:43:51,243 - INFO [main-SendThread:clientcnxn$sendthr...@712] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:46694 remote=/127.0.0.1:33221] [junit] 2009-01-29 11:43:51,243 - INFO [main-SendThread:clientcnxn$sendthr...@865] - Server connection successful [junit] 2009-01-29 11:43:51,243 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:46694 lastZxid 0 [junit] 2009-01-29 11:43:51,243 - INFO [NIOServerCxn.Factory:33221:nioserverc...@519] - Creating new session 0x11f2233c1c70000 [junit] 2009-01-29 11:43:51,251 - INFO [SyncThread:0:nioserverc...@860] - Finished init of 0x11f2233c1c70000 valid:true [junit] 2009-01-29 11:43:51,257 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-29 11:43:51,258 - INFO [main:nioserverc...@732] - closing session:0x11f2233c1c70000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:46694] [junit] 2009-01-29 11:43:51,258 - WARN [main-SendThread:clientcnxn$sendthr...@895] - Exception closing session 0x11f2233c1c70000 to sun.nio.ch.selectionkeyi...@d58939 [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:628) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:873) [junit] 2009-01-29 11:43:51,258 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-29 11:43:51,259 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-29 11:43:51,259 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-29 11:43:51,259 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-29 11:43:51,358 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-29 11:43:51,359 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-29 11:43:51,360 - INFO [main:files...@70] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test2405873259289803074.junit.dir/version-2/snapshot.0 [junit] 2009-01-29 11:43:51,361 - INFO [main:filetxnsnap...@197] - Snapshotting: 3 [junit] 2009-01-29 11:43:51,362 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:46696 [junit] 2009-01-29 11:43:51,363 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-29 11:43:51,363 - 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:46696] [junit] 2009-01-29 11:43:53,126 - INFO [main-SendThread:clientcnxn$sendthr...@797] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-29 11:43:53,127 - INFO [main-SendThread:clientcnxn$sendthr...@712] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:46697 remote=/127.0.0.1:33221] [junit] 2009-01-29 11:43:53,127 - INFO [main-SendThread:clientcnxn$sendthr...@865] - Server connection successful [junit] 2009-01-29 11:43:53,127 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:46697 lastZxid 3 [junit] 2009-01-29 11:43:53,128 - INFO [NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 0x11f2233c1c70000 valid:true [junit] 2009-01-29 11:43:53,128 - INFO [NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 0x11f2233c1c70000 [junit] 2009-01-29 11:43:54,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-29 11:43:54,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-29 11:44:27,143 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-29 11:44:27,144 - INFO [main:nioserverc...@732] - closing session:0x11f2233c1c70000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:46697] [junit] 2009-01-29 11:44:27,144 - WARN [main-SendThread:clientcnxn$sendthr...@895] - Exception closing session 0x11f2233c1c70000 to sun.nio.ch.selectionkeyi...@1264eab [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:628) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:873) [junit] 2009-01-29 11:44:27,145 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-29 11:44:27,145 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-29 11:44:27,145 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-29 11:44:27,145 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-29 11:44:27,245 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-29 11:44:27,245 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-29 11:44:27,246 - INFO [main:files...@70] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test2405873259289803074.junit.dir/version-2/snapshot.3 [junit] 2009-01-29 11:44:27,248 - INFO [main:filetxnsnap...@197] - Snapshotting: 5 [junit] 2009-01-29 11:44:27,250 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:46699 [junit] 2009-01-29 11:44:27,260 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-29 11:44:27,260 - 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:46699] [junit] 2009-01-29 11:44:29,069 - INFO [main-SendThread:clientcnxn$sendthr...@797] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-29 11:44:29,069 - INFO [main-SendThread:clientcnxn$sendthr...@712] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:46700 remote=/127.0.0.1:33221] [junit] 2009-01-29 11:44:29,069 - INFO [main-SendThread:clientcnxn$sendthr...@865] - Server connection successful [junit] 2009-01-29 11:44:29,070 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:46700 lastZxid 5 [junit] 2009-01-29 11:44:29,070 - INFO [NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 0x11f2233c1c70000 valid:true [junit] 2009-01-29 11:44:29,070 - INFO [NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 0x11f2233c1c70000 [junit] 2009-01-29 11:44:29,081 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-29 11:44:29,081 - INFO [main:nioserverc...@732] - closing session:0x11f2233c1c70000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:46700] [junit] 2009-01-29 11:44:29,082 - WARN [main-SendThread:clientcnxn$sendthr...@895] - Exception closing session 0x11f2233c1c70000 to sun.nio.ch.selectionkeyi...@12f1bf0 [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:628) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:873) [junit] 2009-01-29 11:44:29,083 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-29 11:44:29,083 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-29 11:44:29,083 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-29 11:44:29,083 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-29 11:44:29,182 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-29 11:44:29,183 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-29 11:44:29,184 - INFO [main:files...@70] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test2405873259289803074.junit.dir/version-2/snapshot.5 [junit] 2009-01-29 11:44:29,186 - INFO [main:filetxnsnap...@197] - Snapshotting: 6 [junit] 2009-01-29 11:44:29,188 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:46702 [junit] 2009-01-29 11:44:29,188 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-29 11:44:29,189 - 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:46702] [junit] 2009-01-29 11:44:30,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-29 11:44:30,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-29 11:44:30,211 - INFO [main-SendThread:clientcnxn$sendthr...@797] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-29 11:44:30,212 - INFO [main-SendThread:clientcnxn$sendthr...@712] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:46703 remote=/127.0.0.1:33221] [junit] 2009-01-29 11:44:30,212 - INFO [main-SendThread:clientcnxn$sendthr...@865] - Server connection successful [junit] 2009-01-29 11:44:30,212 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:46703 lastZxid 6 [junit] 2009-01-29 11:44:30,213 - INFO [NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 0x11f2233c1c70000 valid:true [junit] 2009-01-29 11:44:30,213 - INFO [NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 0x11f2233c1c70000 [junit] 2009-01-29 11:44:41,232 - INFO [main:zookee...@434] - Closing session: 0x11f2233c1c70000 [junit] 2009-01-29 11:44:41,233 - INFO [main:clientc...@996] - Closing ClientCnxn for session: 0x11f2233c1c70000 [junit] 2009-01-29 11:44:41,233 - INFO [ProcessThread:0:preprequestproces...@344] - Processed session termination request for id: 0x11f2233c1c70000 [junit] 2009-01-29 11:44:41,234 - INFO [SyncThread:0:nioserverc...@732] - closing session:0x11f2233c1c70000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:46703] [junit] 2009-01-29 11:44:41,235 - INFO [main-SendThread:clientcnxn$sendthr...@889] - Exception while closing send thread for session 0x11f2233c1c70000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4] [junit] 2009-01-29 11:44:41,335 - INFO [main:clientc...@982] - Disconnecting ClientCnxn for session: 0x11f2233c1c70000 [junit] 2009-01-29 11:44:41,335 - INFO [main:zookee...@442] - Session: 0x11f2233c1c70000 closed [junit] 2009-01-29 11:44:41,335 - INFO [main-EventThread:clientcnxn$eventthr...@449] - EventThread shut down [junit] 2009-01-29 11:44:41,335 - INFO [main:clientb...@312] - tearDown starting [junit] 2009-01-29 11:44:41,336 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-29 11:44:41,336 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-29 11:44:41,336 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-29 11:44:41,336 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-29 11:44:41,338 - INFO [main:clientb...@322] - FINISHED testWatcherAutoResetDisabledWithGlobal [junit] 2009-01-29 11:44:41,339 - INFO [main:clientb...@288] - STARTING testWatcherAutoResetDisabledWithLocal [junit] 2009-01-29 11:44:41,339 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-29 11:44:41,340 - INFO [main:filetxnsnap...@197] - Snapshotting: 0 [junit] 2009-01-29 11:44:41,342 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:46705 [junit] 2009-01-29 11:44:41,342 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-29 11:44:41,342 - INFO [main:clientb...@296] - Client test setup finished [junit] 2009-01-29 11:44:41,343 - INFO [main:zookee...@341] - Initiating client connection, host=127.0.0.1:33221 sessionTimeout=9000 watcher=org.apache.zookeeper.test.watchertest$mywatc...@5c3987 [junit] 2009-01-29 11:44:41,342 - 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:46705] [junit] 2009-01-29 11:44:41,343 - INFO [main-SendThread:clientcnxn$sendthr...@797] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-29 11:44:41,344 - INFO [main-SendThread:clientcnxn$sendthr...@712] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:46706 remote=/127.0.0.1:33221] [junit] 2009-01-29 11:44:41,344 - INFO [main-SendThread:clientcnxn$sendthr...@865] - Server connection successful [junit] 2009-01-29 11:44:41,344 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:46706 lastZxid 0 [junit] 2009-01-29 11:44:41,344 - INFO [NIOServerCxn.Factory:33221:nioserverc...@519] - Creating new session 0x11f2234857c0000 [junit] 2009-01-29 11:44:41,351 - INFO [SyncThread:0:nioserverc...@860] - Finished init of 0x11f2234857c0000 valid:true [junit] 2009-01-29 11:44:41,356 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-29 11:44:41,357 - INFO [main:nioserverc...@732] - closing session:0x11f2234857c0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:46706] [junit] 2009-01-29 11:44:41,358 - WARN [main-SendThread:clientcnxn$sendthr...@895] - Exception closing session 0x11f2234857c0000 to sun.nio.ch.selectionkeyi...@19fe451 [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:628) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:873) [junit] 2009-01-29 11:44:41,358 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-29 11:44:41,358 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-29 11:44:41,358 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-29 11:44:41,358 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-29 11:44:41,458 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-29 11:44:41,458 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-29 11:44:41,459 - INFO [main:files...@70] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test9100355569859262399.junit.dir/version-2/snapshot.0 [junit] 2009-01-29 11:44:41,460 - INFO [main:filetxnsnap...@197] - Snapshotting: 3 [junit] 2009-01-29 11:44:41,462 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:46708 [junit] 2009-01-29 11:44:41,463 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-29 11:44:41,463 - 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:46708] [junit] 2009-01-29 11:44:42,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-29 11:44:42,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-29 11:44:42,540 - INFO [main-SendThread:clientcnxn$sendthr...@797] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-29 11:44:42,540 - INFO [main-SendThread:clientcnxn$sendthr...@712] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:46709 remote=/127.0.0.1:33221] [junit] 2009-01-29 11:44:42,541 - INFO [main-SendThread:clientcnxn$sendthr...@865] - Server connection successful [junit] 2009-01-29 11:44:42,541 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:46709 lastZxid 3 [junit] 2009-01-29 11:44:42,542 - INFO [NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 0x11f2234857c0000 valid:true [junit] 2009-01-29 11:44:42,542 - INFO [NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 0x11f2234857c0000 [junit] 2009-01-29 11:45:16,562 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-29 11:45:16,563 - INFO [main:nioserverc...@732] - closing session:0x11f2234857c0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:46709] [junit] 2009-01-29 11:45:16,564 - WARN [main-SendThread:clientcnxn$sendthr...@895] - Exception closing session 0x11f2234857c0000 to sun.nio.ch.selectionkeyi...@1be2893 [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:628) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:873) [junit] 2009-01-29 11:45:16,572 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-29 11:45:16,572 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-29 11:45:16,572 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-29 11:45:16,572 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-29 11:45:16,664 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-29 11:45:16,665 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-29 11:45:16,666 - INFO [main:files...@70] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test9100355569859262399.junit.dir/version-2/snapshot.3 [junit] 2009-01-29 11:45:16,672 - INFO [main:filetxnsnap...@197] - Snapshotting: 5 [junit] 2009-01-29 11:45:16,676 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:46711 [junit] 2009-01-29 11:45:16,676 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-29 11:45:16,677 - 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:46711] [junit] 2009-01-29 11:45:17,922 - INFO [main-SendThread:clientcnxn$sendthr...@797] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-29 11:45:17,923 - INFO [main-SendThread:clientcnxn$sendthr...@712] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:46712 remote=/127.0.0.1:33221] [junit] 2009-01-29 11:45:17,923 - INFO [main-SendThread:clientcnxn$sendthr...@865] - Server connection successful [junit] 2009-01-29 11:45:17,923 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:46712 lastZxid 5 [junit] 2009-01-29 11:45:17,924 - INFO [NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 0x11f2234857c0000 valid:true [junit] 2009-01-29 11:45:17,924 - INFO [NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 0x11f2234857c0000 [junit] 2009-01-29 11:45:17,941 - INFO [main:clientb...@300] - STOPPING server [junit] 2009-01-29 11:45:17,942 - INFO [main:nioserverc...@732] - closing session:0x11f2234857c0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:46712] [junit] 2009-01-29 11:45:17,942 - WARN [main-SendThread:clientcnxn$sendthr...@895] - Exception closing session 0x11f2234857c0000 to sun.nio.ch.selectionkeyi...@b6d6ab [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:628) [junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:873) [junit] 2009-01-29 11:45:17,942 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-29 11:45:17,943 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-29 11:45:17,943 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-29 11:45:17,943 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-29 11:45:18,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-29 11:45:18,000 - INFO [SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop! [junit] 2009-01-29 11:45:18,042 - INFO [main:clientb...@306] - STARTING server [junit] 2009-01-29 11:45:18,043 - INFO [main:zookeeperser...@157] - Created server [junit] 2009-01-29 11:45:18,044 - INFO [main:files...@70] - Reading snapshot http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test9100355569859262399.junit.dir/version-2/snapshot.5 [junit] 2009-01-29 11:45:18,046 - INFO [main:filetxnsnap...@197] - Snapshotting: 6 [junit] 2009-01-29 11:45:18,047 - INFO [NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from /127.0.0.1:46714 [junit] 2009-01-29 11:45:18,048 - WARN [NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of session 0x0 due to java.io.IOException: Responded to info probe [junit] 2009-01-29 11:45:18,048 - 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:46714] [junit] 2009-01-29 11:45:19,697 - INFO [main-SendThread:clientcnxn$sendthr...@797] - Attempting connection to server /127.0.0.1:33221 [junit] 2009-01-29 11:45:19,698 - INFO [main-SendThread:clientcnxn$sendthr...@712] - Priming connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:46715 remote=/127.0.0.1:33221] [junit] 2009-01-29 11:45:19,698 - INFO [main-SendThread:clientcnxn$sendthr...@865] - Server connection successful [junit] 2009-01-29 11:45:19,698 - INFO [NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:46715 lastZxid 6 [junit] 2009-01-29 11:45:19,698 - INFO [NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 0x11f2234857c0000 valid:true [junit] 2009-01-29 11:45:19,699 - INFO [NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 0x11f2234857c0000 [junit] 2009-01-29 11:45:30,712 - INFO [main:zookee...@434] - Closing session: 0x11f2234857c0000 [junit] 2009-01-29 11:45:30,712 - INFO [main:clientc...@996] - Closing ClientCnxn for session: 0x11f2234857c0000 [junit] 2009-01-29 11:45:30,713 - INFO [ProcessThread:0:preprequestproces...@344] - Processed session termination request for id: 0x11f2234857c0000 [junit] 2009-01-29 11:45:30,715 - INFO [SyncThread:0:nioserverc...@732] - closing session:0x11f2234857c0000 NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 remote=/127.0.0.1:46715] [junit] 2009-01-29 11:45:30,715 - INFO [main-SendThread:clientcnxn$sendthr...@889] - Exception while closing send thread for session 0x11f2234857c0000 : Read error rc = -1 java.nio.DirectByteBuffer[pos=0 lim=4 cap=4] [junit] 2009-01-29 11:45:30,815 - INFO [main:clientc...@982] - Disconnecting ClientCnxn for session: 0x11f2234857c0000 [junit] 2009-01-29 11:45:30,815 - INFO [main:zookee...@442] - Session: 0x11f2234857c0000 closed [junit] 2009-01-29 11:45:30,815 - INFO [main-EventThread:clientcnxn$eventthr...@449] - EventThread shut down [junit] 2009-01-29 11:45:30,815 - INFO [main:clientb...@312] - tearDown starting [junit] 2009-01-29 11:45:30,816 - INFO [NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory exited run method [junit] 2009-01-29 11:45:30,816 - INFO [main:finalrequestproces...@265] - shutdown of request processor complete [junit] 2009-01-29 11:45:30,816 - INFO [SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited! [junit] 2009-01-29 11:45:30,816 - INFO [ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop! [junit] 2009-01-29 11:45:30,818 - INFO [main:clientb...@322] - FINISHED testWatcherAutoResetDisabledWithLocal [junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 127.273 sec BUILD FAILED http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml :547: Tests failed! Total time: 8 minutes 41 seconds Recording fingerprints Publishing Javadoc Recording test results