See http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/205/changes

------------------------------------------
[...truncated 60527 lines...]
    [junit] 2009-01-21 11:45:18,633 - INFO  [main:clientb...@300] - STOPPING 
server
    [junit] 2009-01-21 11:45:18,634 - INFO  [main:nioserverc...@732] - closing 
session:0x11ef9021cca0000 NIOServerCnxn: 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 
remote=/127.0.0.1:47407]
    [junit] 2009-01-21 11:45:18,634 - WARN  
[main-SendThread:clientcnxn$sendthr...@881] - Exception closing session 
0x11ef9021cca0000 to sun.nio.ch.selectionkeyi...@97d026
    [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:614)
    [junit]     at 
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:859)
    [junit] 2009-01-21 11:45:18,635 - INFO  
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory 
exited run method
    [junit] 2009-01-21 11:45:18,635 - INFO  [main:finalrequestproces...@265] - 
shutdown of request processor complete
    [junit] 2009-01-21 11:45:18,635 - INFO  
[ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop!
    [junit] 2009-01-21 11:45:18,635 - INFO  
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
    [junit] 2009-01-21 11:45:18,735 - INFO  [main:clientb...@306] - STARTING 
server
    [junit] 2009-01-21 11:45:18,735 - INFO  [main:zookeeperser...@157] - 
Created server
    [junit] 2009-01-21 11:45:18,737 - INFO  [main:files...@70] - Reading 
snapshot 
http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test2065253250427019796.junit.dir/version-2/snapshot.5
 
    [junit] 2009-01-21 11:45:18,739 - INFO  [main:filetxnsnap...@197] - 
Snapshotting: 6
    [junit] 2009-01-21 11:45:18,741 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from 
/127.0.0.1:47409
    [junit] 2009-01-21 11:45:18,741 - WARN  
[NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of 
session 0x0 due to java.io.IOException: Responded to info probe
    [junit] 2009-01-21 11:45:18,741 - 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:47409]
    [junit] 2009-01-21 11:45:20,262 - INFO  
[main-SendThread:clientcnxn$sendthr...@783] - Attempting connection to server 
/127.0.0.1:33221
    [junit] 2009-01-21 11:45:20,262 - INFO  
[main-SendThread:clientcnxn$sendthr...@698] - Priming connection to 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:47410 
remote=/127.0.0.1:33221]
    [junit] 2009-01-21 11:45:20,262 - INFO  
[main-SendThread:clientcnxn$sendthr...@851] - Server connection successful
    [junit] 2009-01-21 11:45:20,263 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:47410 
lastZxid 6
    [junit] 2009-01-21 11:45:20,263 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 
0x11ef9021cca0000 valid:true
    [junit] 2009-01-21 11:45:20,263 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 
0x11ef9021cca0000
    [junit] 2009-01-21 11:45:21,000 - INFO  
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
    [junit] 2009-01-21 11:45:21,274 - INFO  [main:zookee...@418] - Closing 
session: 0x11ef9021cca0000
    [junit] 2009-01-21 11:45:21,274 - INFO  [main:clientc...@982] - Closing 
ClientCnxn for session: 0x11ef9021cca0000
    [junit] 2009-01-21 11:45:21,276 - INFO  
[ProcessThread:0:preprequestproces...@344] - Processed session termination 
request for id: 0x11ef9021cca0000
    [junit] 2009-01-21 11:45:21,276 - INFO  [SyncThread:0:nioserverc...@732] - 
closing session:0x11ef9021cca0000 NIOServerCnxn: 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 
remote=/127.0.0.1:47410]
    [junit] 2009-01-21 11:45:21,277 - INFO  
[main-SendThread:clientcnxn$sendthr...@875] - Exception while closing send 
thread for session 0x11ef9021cca0000 : Read error rc = -1 
java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
    [junit] 2009-01-21 11:45:21,377 - INFO  [main:clientc...@968] - 
Disconnecting ClientCnxn for session: 0x11ef9021cca0000
    [junit] 2009-01-21 11:45:21,377 - INFO  [main:zookee...@426] - Session: 
0x11ef9021cca0000 closed
    [junit] 2009-01-21 11:45:21,377 - INFO  
[main-EventThread:clientcnxn$eventthr...@435] - EventThread shut down
    [junit] 2009-01-21 11:45:21,378 - INFO  [main:clientb...@312] - tearDown 
starting
    [junit] 2009-01-21 11:45:21,378 - INFO  
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory 
exited run method
    [junit] 2009-01-21 11:45:21,378 - INFO  [main:finalrequestproces...@265] - 
shutdown of request processor complete
    [junit] 2009-01-21 11:45:21,379 - INFO  
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
    [junit] 2009-01-21 11:45:21,379 - INFO  
[ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop!
    [junit] 2009-01-21 11:45:21,380 - INFO  [main:clientb...@322] - FINISHED 
testWatcherAutoResetWithLocal
    [junit] 2009-01-21 11:45:21,381 - INFO  [main:clientb...@288] - STARTING 
testWatcherAutoResetDisabledWithGlobal
    [junit] 2009-01-21 11:45:21,381 - INFO  [main:zookeeperser...@157] - 
Created server
    [junit] 2009-01-21 11:45:21,382 - INFO  [main:filetxnsnap...@197] - 
Snapshotting: 0
    [junit] 2009-01-21 11:45:21,384 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from 
/127.0.0.1:47412
    [junit] 2009-01-21 11:45:21,384 - WARN  
[NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of 
session 0x0 due to java.io.IOException: Responded to info probe
    [junit] 2009-01-21 11:45:21,384 - INFO  [main:clientb...@296] - Client test 
setup finished
    [junit] 2009-01-21 11:45:21,384 - 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:47412]
    [junit] 2009-01-21 11:45:21,385 - INFO  [main:zookee...@324] - Initiating 
client connection, host=127.0.0.1:33221 sessionTimeout=9000 
watcher=org.apache.zookeeper.test.watchertest$mywatc...@cf710e
    [junit] 2009-01-21 11:45:21,386 - INFO  
[main-SendThread:clientcnxn$sendthr...@783] - Attempting connection to server 
/127.0.0.1:33221
    [junit] 2009-01-21 11:45:21,386 - INFO  
[main-SendThread:clientcnxn$sendthr...@698] - Priming connection to 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:47413 
remote=/127.0.0.1:33221]
    [junit] 2009-01-21 11:45:21,386 - INFO  
[main-SendThread:clientcnxn$sendthr...@851] - Server connection successful
    [junit] 2009-01-21 11:45:21,387 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:47413 
lastZxid 0
    [junit] 2009-01-21 11:45:21,387 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@519] - Creating new session 
0x11ef90241e70000
    [junit] 2009-01-21 11:45:21,401 - INFO  [SyncThread:0:nioserverc...@860] - 
Finished init of 0x11ef90241e70000 valid:true
    [junit] 2009-01-21 11:45:21,406 - INFO  [main:clientb...@300] - STOPPING 
server
    [junit] 2009-01-21 11:45:21,407 - INFO  [main:nioserverc...@732] - closing 
session:0x11ef90241e70000 NIOServerCnxn: 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 
remote=/127.0.0.1:47413]
    [junit] 2009-01-21 11:45:21,407 - WARN  
[main-SendThread:clientcnxn$sendthr...@881] - Exception closing session 
0x11ef90241e70000 to sun.nio.ch.selectionkeyi...@1f4bcf7
    [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:614)
    [junit]     at 
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:859)
    [junit] 2009-01-21 11:45:21,408 - INFO  
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory 
exited run method
    [junit] 2009-01-21 11:45:21,408 - INFO  [main:finalrequestproces...@265] - 
shutdown of request processor complete
    [junit] 2009-01-21 11:45:21,408 - INFO  
[ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop!
    [junit] 2009-01-21 11:45:21,408 - INFO  
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
    [junit] 2009-01-21 11:45:21,508 - INFO  [main:clientb...@306] - STARTING 
server
    [junit] 2009-01-21 11:45:21,508 - INFO  [main:zookeeperser...@157] - 
Created server
    [junit] 2009-01-21 11:45:21,509 - INFO  [main:files...@70] - Reading 
snapshot 
http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test1041219195760130343.junit.dir/version-2/snapshot.0
 
    [junit] 2009-01-21 11:45:21,510 - INFO  [main:filetxnsnap...@197] - 
Snapshotting: 3
    [junit] 2009-01-21 11:45:21,512 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from 
/127.0.0.1:47415
    [junit] 2009-01-21 11:45:21,512 - WARN  
[NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of 
session 0x0 due to java.io.IOException: Responded to info probe
    [junit] 2009-01-21 11:45:21,513 - 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:47415]
    [junit] 2009-01-21 11:45:22,708 - INFO  
[main-SendThread:clientcnxn$sendthr...@783] - Attempting connection to server 
/127.0.0.1:33221
    [junit] 2009-01-21 11:45:22,708 - INFO  
[main-SendThread:clientcnxn$sendthr...@698] - Priming connection to 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:47416 
remote=/127.0.0.1:33221]
    [junit] 2009-01-21 11:45:22,708 - INFO  
[main-SendThread:clientcnxn$sendthr...@851] - Server connection successful
    [junit] 2009-01-21 11:45:22,709 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:47416 
lastZxid 3
    [junit] 2009-01-21 11:45:22,709 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 
0x11ef90241e70000 valid:true
    [junit] 2009-01-21 11:45:22,709 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 
0x11ef90241e70000
    [junit] 2009-01-21 11:45:24,000 - INFO  
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
    [junit] 2009-01-21 11:45:24,000 - INFO  
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
    [junit] 2009-01-21 11:45:56,722 - INFO  [main:clientb...@300] - STOPPING 
server
    [junit] 2009-01-21 11:45:56,723 - INFO  [main:nioserverc...@732] - closing 
session:0x11ef90241e70000 NIOServerCnxn: 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 
remote=/127.0.0.1:47416]
    [junit] 2009-01-21 11:45:56,723 - WARN  
[main-SendThread:clientcnxn$sendthr...@881] - Exception closing session 
0x11ef90241e70000 to sun.nio.ch.selectionkeyi...@1c8ef56
    [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:614)
    [junit]     at 
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:859)
    [junit] 2009-01-21 11:45:56,724 - INFO  
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory 
exited run method
    [junit] 2009-01-21 11:45:56,724 - INFO  [main:finalrequestproces...@265] - 
shutdown of request processor complete
    [junit] 2009-01-21 11:45:56,724 - INFO  
[ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop!
    [junit] 2009-01-21 11:45:56,724 - INFO  
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
    [junit] 2009-01-21 11:45:56,824 - INFO  [main:clientb...@306] - STARTING 
server
    [junit] 2009-01-21 11:45:56,824 - INFO  [main:zookeeperser...@157] - 
Created server
    [junit] 2009-01-21 11:45:56,825 - INFO  [main:files...@70] - Reading 
snapshot 
http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test1041219195760130343.junit.dir/version-2/snapshot.3
 
    [junit] 2009-01-21 11:45:56,827 - INFO  [main:filetxnsnap...@197] - 
Snapshotting: 5
    [junit] 2009-01-21 11:45:56,829 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from 
/127.0.0.1:47418
    [junit] 2009-01-21 11:45:56,839 - WARN  
[NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of 
session 0x0 due to java.io.IOException: Responded to info probe
    [junit] 2009-01-21 11:45:56,839 - 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:47418]
    [junit] 2009-01-21 11:45:57,000 - INFO  
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
    [junit] 2009-01-21 11:45:58,662 - INFO  
[main-SendThread:clientcnxn$sendthr...@783] - Attempting connection to server 
/127.0.0.1:33221
    [junit] 2009-01-21 11:45:58,662 - INFO  
[main-SendThread:clientcnxn$sendthr...@698] - Priming connection to 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:47419 
remote=/127.0.0.1:33221]
    [junit] 2009-01-21 11:45:58,662 - INFO  
[main-SendThread:clientcnxn$sendthr...@851] - Server connection successful
    [junit] 2009-01-21 11:45:58,662 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:47419 
lastZxid 5
    [junit] 2009-01-21 11:45:58,663 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 
0x11ef90241e70000 valid:true
    [junit] 2009-01-21 11:45:58,663 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 
0x11ef90241e70000
    [junit] 2009-01-21 11:45:58,671 - INFO  [main:clientb...@300] - STOPPING 
server
    [junit] 2009-01-21 11:45:58,672 - INFO  [main:nioserverc...@732] - closing 
session:0x11ef90241e70000 NIOServerCnxn: 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 
remote=/127.0.0.1:47419]
    [junit] 2009-01-21 11:45:58,672 - WARN  
[main-SendThread:clientcnxn$sendthr...@881] - Exception closing session 
0x11ef90241e70000 to sun.nio.ch.selectionkeyi...@1bb9a58
    [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:614)
    [junit]     at 
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:859)
    [junit] 2009-01-21 11:45:58,672 - INFO  
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory 
exited run method
    [junit] 2009-01-21 11:45:58,673 - INFO  [main:finalrequestproces...@265] - 
shutdown of request processor complete
    [junit] 2009-01-21 11:45:58,673 - INFO  
[ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop!
    [junit] 2009-01-21 11:45:58,673 - INFO  
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
    [junit] 2009-01-21 11:45:58,772 - INFO  [main:clientb...@306] - STARTING 
server
    [junit] 2009-01-21 11:45:58,773 - INFO  [main:zookeeperser...@157] - 
Created server
    [junit] 2009-01-21 11:45:58,774 - INFO  [main:files...@70] - Reading 
snapshot 
http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test1041219195760130343.junit.dir/version-2/snapshot.5
 
    [junit] 2009-01-21 11:45:58,776 - INFO  [main:filetxnsnap...@197] - 
Snapshotting: 6
    [junit] 2009-01-21 11:45:58,778 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from 
/127.0.0.1:47421
    [junit] 2009-01-21 11:45:58,778 - WARN  
[NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of 
session 0x0 due to java.io.IOException: Responded to info probe
    [junit] 2009-01-21 11:45:58,779 - 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:47421]
    [junit] 2009-01-21 11:46:00,000 - INFO  
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
    [junit] 2009-01-21 11:46:00,153 - INFO  
[main-SendThread:clientcnxn$sendthr...@783] - Attempting connection to server 
/127.0.0.1:33221
    [junit] 2009-01-21 11:46:00,153 - INFO  
[main-SendThread:clientcnxn$sendthr...@698] - Priming connection to 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:47422 
remote=/127.0.0.1:33221]
    [junit] 2009-01-21 11:46:00,154 - INFO  
[main-SendThread:clientcnxn$sendthr...@851] - Server connection successful
    [junit] 2009-01-21 11:46:00,154 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:47422 
lastZxid 6
    [junit] 2009-01-21 11:46:00,155 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 
0x11ef90241e70000 valid:true
    [junit] 2009-01-21 11:46:00,155 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 
0x11ef90241e70000
    [junit] 2009-01-21 11:46:11,160 - INFO  [main:zookee...@418] - Closing 
session: 0x11ef90241e70000
    [junit] 2009-01-21 11:46:11,160 - INFO  [main:clientc...@982] - Closing 
ClientCnxn for session: 0x11ef90241e70000
    [junit] 2009-01-21 11:46:11,162 - INFO  
[ProcessThread:0:preprequestproces...@344] - Processed session termination 
request for id: 0x11ef90241e70000
    [junit] 2009-01-21 11:46:11,163 - INFO  [SyncThread:0:nioserverc...@732] - 
closing session:0x11ef90241e70000 NIOServerCnxn: 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 
remote=/127.0.0.1:47422]
    [junit] 2009-01-21 11:46:11,163 - INFO  
[main-SendThread:clientcnxn$sendthr...@875] - Exception while closing send 
thread for session 0x11ef90241e70000 : Read error rc = -1 
java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
    [junit] 2009-01-21 11:46:11,264 - INFO  [main:clientc...@968] - 
Disconnecting ClientCnxn for session: 0x11ef90241e70000
    [junit] 2009-01-21 11:46:11,264 - INFO  [main:zookee...@426] - Session: 
0x11ef90241e70000 closed
    [junit] 2009-01-21 11:46:11,264 - INFO  
[main-EventThread:clientcnxn$eventthr...@435] - EventThread shut down
    [junit] 2009-01-21 11:46:11,264 - INFO  [main:clientb...@312] - tearDown 
starting
    [junit] 2009-01-21 11:46:11,266 - INFO  
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory 
exited run method
    [junit] 2009-01-21 11:46:11,266 - INFO  [main:finalrequestproces...@265] - 
shutdown of request processor complete
    [junit] 2009-01-21 11:46:11,266 - INFO  
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
    [junit] 2009-01-21 11:46:11,266 - INFO  
[ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop!
    [junit] 2009-01-21 11:46:11,267 - INFO  [main:clientb...@322] - FINISHED 
testWatcherAutoResetDisabledWithGlobal
    [junit] 2009-01-21 11:46:11,269 - INFO  [main:clientb...@288] - STARTING 
testWatcherAutoResetDisabledWithLocal
    [junit] 2009-01-21 11:46:11,269 - INFO  [main:zookeeperser...@157] - 
Created server
    [junit] 2009-01-21 11:46:11,270 - INFO  [main:filetxnsnap...@197] - 
Snapshotting: 0
    [junit] 2009-01-21 11:46:11,272 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from 
/127.0.0.1:47424
    [junit] 2009-01-21 11:46:11,272 - WARN  
[NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of 
session 0x0 due to java.io.IOException: Responded to info probe
    [junit] 2009-01-21 11:46:11,272 - INFO  [main:clientb...@296] - Client test 
setup finished
    [junit] 2009-01-21 11:46:11,272 - 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:47424]
    [junit] 2009-01-21 11:46:11,273 - INFO  [main:zookee...@324] - Initiating 
client connection, host=127.0.0.1:33221 sessionTimeout=9000 
watcher=org.apache.zookeeper.test.watchertest$mywatc...@11ef443
    [junit] 2009-01-21 11:46:11,273 - INFO  
[main-SendThread:clientcnxn$sendthr...@783] - Attempting connection to server 
/127.0.0.1:33221
    [junit] 2009-01-21 11:46:11,274 - INFO  
[main-SendThread:clientcnxn$sendthr...@698] - Priming connection to 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:47425 
remote=/127.0.0.1:33221]
    [junit] 2009-01-21 11:46:11,274 - INFO  
[main-SendThread:clientcnxn$sendthr...@851] - Server connection successful
    [junit] 2009-01-21 11:46:11,274 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:47425 
lastZxid 0
    [junit] 2009-01-21 11:46:11,275 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@519] - Creating new session 
0x11ef90304c70000
    [junit] 2009-01-21 11:46:11,283 - INFO  [SyncThread:0:nioserverc...@860] - 
Finished init of 0x11ef90304c70000 valid:true
    [junit] 2009-01-21 11:46:11,288 - INFO  [main:clientb...@300] - STOPPING 
server
    [junit] 2009-01-21 11:46:11,288 - INFO  [main:nioserverc...@732] - closing 
session:0x11ef90304c70000 NIOServerCnxn: 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 
remote=/127.0.0.1:47425]
    [junit] 2009-01-21 11:46:11,289 - WARN  
[main-SendThread:clientcnxn$sendthr...@881] - Exception closing session 
0x11ef90304c70000 to sun.nio.ch.selectionkeyi...@1797795
    [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:614)
    [junit]     at 
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:859)
    [junit] 2009-01-21 11:46:11,290 - INFO  
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory 
exited run method
    [junit] 2009-01-21 11:46:11,290 - INFO  [main:finalrequestproces...@265] - 
shutdown of request processor complete
    [junit] 2009-01-21 11:46:11,290 - INFO  
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
    [junit] 2009-01-21 11:46:11,290 - INFO  
[ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop!
    [junit] 2009-01-21 11:46:11,390 - INFO  [main:clientb...@306] - STARTING 
server
    [junit] 2009-01-21 11:46:11,390 - INFO  [main:zookeeperser...@157] - 
Created server
    [junit] 2009-01-21 11:46:11,391 - INFO  [main:files...@70] - Reading 
snapshot 
http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test7872400126736832987.junit.dir/version-2/snapshot.0
 
    [junit] 2009-01-21 11:46:11,392 - INFO  [main:filetxnsnap...@197] - 
Snapshotting: 3
    [junit] 2009-01-21 11:46:11,394 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from 
/127.0.0.1:47427
    [junit] 2009-01-21 11:46:11,394 - WARN  
[NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of 
session 0x0 due to java.io.IOException: Responded to info probe
    [junit] 2009-01-21 11:46:11,395 - 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:47427]
    [junit] 2009-01-21 11:46:12,000 - INFO  
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
    [junit] 2009-01-21 11:46:12,000 - INFO  
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
    [junit] 2009-01-21 11:46:13,195 - INFO  
[main-SendThread:clientcnxn$sendthr...@783] - Attempting connection to server 
/127.0.0.1:33221
    [junit] 2009-01-21 11:46:13,195 - INFO  
[main-SendThread:clientcnxn$sendthr...@698] - Priming connection to 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:47428 
remote=/127.0.0.1:33221]
    [junit] 2009-01-21 11:46:13,195 - INFO  
[main-SendThread:clientcnxn$sendthr...@851] - Server connection successful
    [junit] 2009-01-21 11:46:13,196 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:47428 
lastZxid 3
    [junit] 2009-01-21 11:46:13,196 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 
0x11ef90304c70000 valid:true
    [junit] 2009-01-21 11:46:13,196 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 
0x11ef90304c70000
    [junit] 2009-01-21 11:46:47,212 - INFO  [main:clientb...@300] - STOPPING 
server
    [junit] 2009-01-21 11:46:47,213 - INFO  [main:nioserverc...@732] - closing 
session:0x11ef90304c70000 NIOServerCnxn: 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 
remote=/127.0.0.1:47428]
    [junit] 2009-01-21 11:46:47,213 - WARN  
[main-SendThread:clientcnxn$sendthr...@881] - Exception closing session 
0x11ef90304c70000 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:614)
    [junit]     at 
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:859)
    [junit] 2009-01-21 11:46:47,217 - INFO  
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory 
exited run method
    [junit] 2009-01-21 11:46:47,218 - INFO  
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
    [junit] 2009-01-21 11:46:47,218 - INFO  [main:finalrequestproces...@265] - 
shutdown of request processor complete
    [junit] 2009-01-21 11:46:47,227 - INFO  
[ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop!
    [junit] 2009-01-21 11:46:47,314 - INFO  [main:clientb...@306] - STARTING 
server
    [junit] 2009-01-21 11:46:47,314 - INFO  [main:zookeeperser...@157] - 
Created server
    [junit] 2009-01-21 11:46:47,315 - INFO  [main:files...@70] - Reading 
snapshot 
http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test7872400126736832987.junit.dir/version-2/snapshot.3
 
    [junit] 2009-01-21 11:46:47,317 - INFO  [main:filetxnsnap...@197] - 
Snapshotting: 5
    [junit] 2009-01-21 11:46:47,319 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from 
/127.0.0.1:47430
    [junit] 2009-01-21 11:46:47,319 - WARN  
[NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of 
session 0x0 due to java.io.IOException: Responded to info probe
    [junit] 2009-01-21 11:46:47,320 - 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:47430]
    [junit] 2009-01-21 11:46:48,000 - INFO  
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
    [junit] 2009-01-21 11:46:48,636 - INFO  
[main-SendThread:clientcnxn$sendthr...@783] - Attempting connection to server 
/127.0.0.1:33221
    [junit] 2009-01-21 11:46:48,636 - INFO  
[main-SendThread:clientcnxn$sendthr...@698] - Priming connection to 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:47431 
remote=/127.0.0.1:33221]
    [junit] 2009-01-21 11:46:48,636 - INFO  
[main-SendThread:clientcnxn$sendthr...@851] - Server connection successful
    [junit] 2009-01-21 11:46:48,637 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:47431 
lastZxid 5
    [junit] 2009-01-21 11:46:48,637 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 
0x11ef90304c70000 valid:true
    [junit] 2009-01-21 11:46:48,637 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 
0x11ef90304c70000
    [junit] 2009-01-21 11:46:48,651 - INFO  [main:clientb...@300] - STOPPING 
server
    [junit] 2009-01-21 11:46:48,652 - INFO  [main:nioserverc...@732] - closing 
session:0x11ef90304c70000 NIOServerCnxn: 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 
remote=/127.0.0.1:47431]
    [junit] 2009-01-21 11:46:48,652 - WARN  
[main-SendThread:clientcnxn$sendthr...@881] - Exception closing session 
0x11ef90304c70000 to sun.nio.ch.selectionkeyi...@73305c
    [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:614)
    [junit]     at 
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:859)
    [junit] 2009-01-21 11:46:48,652 - INFO  
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory 
exited run method
    [junit] 2009-01-21 11:46:48,653 - INFO  [main:finalrequestproces...@265] - 
shutdown of request processor complete
    [junit] 2009-01-21 11:46:48,653 - INFO  
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
    [junit] 2009-01-21 11:46:48,653 - INFO  
[ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop!
    [junit] 2009-01-21 11:46:48,752 - INFO  [main:clientb...@306] - STARTING 
server
    [junit] 2009-01-21 11:46:48,753 - INFO  [main:zookeeperser...@157] - 
Created server
    [junit] 2009-01-21 11:46:48,754 - INFO  [main:files...@70] - Reading 
snapshot 
http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test7872400126736832987.junit.dir/version-2/snapshot.5
 
    [junit] 2009-01-21 11:46:48,761 - INFO  [main:filetxnsnap...@197] - 
Snapshotting: 6
    [junit] 2009-01-21 11:46:48,766 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@604] - Processing stat command from 
/127.0.0.1:47433
    [junit] 2009-01-21 11:46:48,766 - WARN  
[NIOServerCxn.Factory:33221:nioserverc...@402] - Exception causing close of 
session 0x0 due to java.io.IOException: Responded to info probe
    [junit] 2009-01-21 11:46:48,766 - 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:47433]
    [junit] 2009-01-21 11:46:49,789 - INFO  
[main-SendThread:clientcnxn$sendthr...@783] - Attempting connection to server 
/127.0.0.1:33221
    [junit] 2009-01-21 11:46:49,790 - INFO  
[main-SendThread:clientcnxn$sendthr...@698] - Priming connection to 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:47434 
remote=/127.0.0.1:33221]
    [junit] 2009-01-21 11:46:49,790 - INFO  
[main-SendThread:clientcnxn$sendthr...@851] - Server connection successful
    [junit] 2009-01-21 11:46:49,790 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@488] - Connected to /127.0.0.1:47434 
lastZxid 6
    [junit] 2009-01-21 11:46:49,791 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@860] - Finished init of 
0x11ef90304c70000 valid:true
    [junit] 2009-01-21 11:46:49,791 - INFO  
[NIOServerCxn.Factory:33221:nioserverc...@516] - Renewing session 
0x11ef90304c70000
    [junit] 2009-01-21 11:46:51,000 - INFO  
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
    [junit] 2009-01-21 11:47:00,800 - INFO  [main:zookee...@418] - Closing 
session: 0x11ef90304c70000
    [junit] 2009-01-21 11:47:00,801 - INFO  [main:clientc...@982] - Closing 
ClientCnxn for session: 0x11ef90304c70000
    [junit] 2009-01-21 11:47:00,801 - INFO  
[ProcessThread:0:preprequestproces...@344] - Processed session termination 
request for id: 0x11ef90304c70000
    [junit] 2009-01-21 11:47:00,802 - INFO  [SyncThread:0:nioserverc...@732] - 
closing session:0x11ef90304c70000 NIOServerCnxn: 
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221 
remote=/127.0.0.1:47434]
    [junit] 2009-01-21 11:47:00,803 - INFO  
[main-SendThread:clientcnxn$sendthr...@875] - Exception while closing send 
thread for session 0x11ef90304c70000 : Read error rc = -1 
java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
    [junit] 2009-01-21 11:47:00,903 - INFO  [main:clientc...@968] - 
Disconnecting ClientCnxn for session: 0x11ef90304c70000
    [junit] 2009-01-21 11:47:00,903 - INFO  [main:zookee...@426] - Session: 
0x11ef90304c70000 closed
    [junit] 2009-01-21 11:47:00,903 - INFO  
[main-EventThread:clientcnxn$eventthr...@435] - EventThread shut down
    [junit] 2009-01-21 11:47:00,903 - INFO  [main:clientb...@312] - tearDown 
starting
    [junit] 2009-01-21 11:47:00,904 - INFO  
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory 
exited run method
    [junit] 2009-01-21 11:47:00,904 - INFO  [main:finalrequestproces...@265] - 
shutdown of request processor complete
    [junit] 2009-01-21 11:47:00,904 - INFO  
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
    [junit] 2009-01-21 11:47:00,904 - INFO  
[ProcessThread:0:preprequestproces...@104] - PrepRequestProcessor exited loop!
    [junit] 2009-01-21 11:47:00,906 - INFO  [main:clientb...@322] - FINISHED 
testWatcherAutoResetDisabledWithLocal
    [junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 124.218 sec

BUILD FAILED
http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml 
:547: Tests failed!

Total time: 7 minutes 52 seconds
Recording fingerprints
Publishing Javadoc
Recording test results

Reply via email to