See http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/216/changes
------------------------------------------
[...truncated 63345 lines...]
[junit] 2009-02-01 11:45:08,844 - INFO [main:nioserverc...@737] - closing
session:0x11f31a7e8780000 NIOServerCnxn:
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56219]
[junit] 2009-02-01 11:45:08,844 - WARN
[main-SendThread:clientcnxn$sendthr...@898] - Exception closing session
0x11f31a7e8780000 to sun.nio.ch.selectionkeyi...@1f54c5f
[junit] java.io.IOException: Read error rc = -1
java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:632)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876)
[junit] 2009-02-01 11:45:08,845 - INFO
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory
exited run method
[junit] 2009-02-01 11:45:08,845 - INFO [main:finalrequestproces...@265] -
shutdown of request processor complete
[junit] 2009-02-01 11:45:08,845 - INFO
[ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop!
[junit] 2009-02-01 11:45:08,845 - INFO
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
[junit] 2009-02-01 11:45:08,945 - INFO [main:clientb...@306] - STARTING
server
[junit] 2009-02-01 11:45:08,945 - INFO [main:zookeeperser...@157] -
Created server
[junit] 2009-02-01 11:45:08,947 - INFO [main:files...@71] - Reading
snapshot
http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test4296859123344405422.junit.dir/version-2/snapshot.5
[junit] 2009-02-01 11:45:08,949 - INFO [main:filetxnsnap...@198] -
Snapshotting: 6
[junit] 2009-02-01 11:45:08,950 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from
/127.0.0.1:56221
[junit] 2009-02-01 11:45:08,951 - WARN
[NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of
session 0x0 due to java.io.IOException: Responded to info probe
[junit] 2009-02-01 11:45:08,951 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0
NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56221]
[junit] 2009-02-01 11:45:09,000 - INFO
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
[junit] 2009-02-01 11:45:09,000 - INFO
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
[junit] 2009-02-01 11:45:10,004 - INFO
[main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server
/127.0.0.1:33221
[junit] 2009-02-01 11:45:10,004 - INFO
[main-SendThread:clientcnxn$sendthr...@716] - Priming connection to
java.nio.channels.SocketChannel[connected local=/127.0.0.1:56222
remote=/127.0.0.1:33221]
[junit] 2009-02-01 11:45:10,005 - INFO
[main-SendThread:clientcnxn$sendthr...@868] - Server connection successful
[junit] 2009-02-01 11:45:10,005 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:56222
lastZxid 6
[junit] 2009-02-01 11:45:10,005 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@865] - Finished init of
0x11f31a7e8780000 valid:true
[junit] 2009-02-01 11:45:10,006 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@521] - Renewing session
0x11f31a7e8780000
[junit] 2009-02-01 11:45:11,012 - INFO [main:zookee...@434] - Closing
session: 0x11f31a7e8780000
[junit] 2009-02-01 11:45:11,012 - INFO [main:clientc...@999] - Closing
ClientCnxn for session: 0x11f31a7e8780000
[junit] 2009-02-01 11:45:11,013 - INFO
[ProcessThread:0:preprequestproces...@360] - Processed session termination
request for id: 0x11f31a7e8780000
[junit] 2009-02-01 11:45:11,014 - INFO [SyncThread:0:nioserverc...@737] -
closing session:0x11f31a7e8780000 NIOServerCnxn:
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56222]
[junit] 2009-02-01 11:45:11,014 - INFO
[main-SendThread:clientcnxn$sendthr...@892] - Exception while closing send
thread for session 0x11f31a7e8780000 : Read error rc = -1
java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2009-02-01 11:45:11,114 - INFO [main:clientc...@985] -
Disconnecting ClientCnxn for session: 0x11f31a7e8780000
[junit] 2009-02-01 11:45:11,114 - INFO [main:zookee...@442] - Session:
0x11f31a7e8780000 closed
[junit] 2009-02-01 11:45:11,114 - INFO
[main-EventThread:clientcnxn$eventthr...@452] - EventThread shut down
[junit] 2009-02-01 11:45:11,115 - INFO [main:clientb...@312] - tearDown
starting
[junit] 2009-02-01 11:45:11,115 - INFO
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory
exited run method
[junit] 2009-02-01 11:45:11,116 - INFO [main:finalrequestproces...@265] -
shutdown of request processor complete
[junit] 2009-02-01 11:45:11,116 - INFO
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
[junit] 2009-02-01 11:45:11,116 - INFO
[ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop!
[junit] 2009-02-01 11:45:11,118 - INFO [main:clientb...@322] - FINISHED
testWatcherAutoResetWithLocal
[junit] 2009-02-01 11:45:11,118 - INFO [main:clientb...@288] - STARTING
testWatcherAutoResetDisabledWithGlobal
[junit] 2009-02-01 11:45:11,119 - INFO [main:zookeeperser...@157] -
Created server
[junit] 2009-02-01 11:45:11,120 - INFO [main:filetxnsnap...@198] -
Snapshotting: 0
[junit] 2009-02-01 11:45:11,121 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from
/127.0.0.1:56224
[junit] 2009-02-01 11:45:11,121 - WARN
[NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of
session 0x0 due to java.io.IOException: Responded to info probe
[junit] 2009-02-01 11:45:11,122 - INFO [main:clientb...@296] - Client test
setup finished
[junit] 2009-02-01 11:45:11,122 - INFO [main:zookee...@341] - Initiating
client connection, host=127.0.0.1:33221 sessionTimeout=9000
watcher=org.apache.zookeeper.test.watchertest$mywatc...@d1e233
[junit] 2009-02-01 11:45:11,122 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0
NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56224]
[junit] 2009-02-01 11:45:11,123 - INFO
[main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server
/127.0.0.1:33221
[junit] 2009-02-01 11:45:11,123 - INFO
[main-SendThread:clientcnxn$sendthr...@716] - Priming connection to
java.nio.channels.SocketChannel[connected local=/127.0.0.1:56225
remote=/127.0.0.1:33221]
[junit] 2009-02-01 11:45:11,123 - INFO
[main-SendThread:clientcnxn$sendthr...@868] - Server connection successful
[junit] 2009-02-01 11:45:11,124 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:56225
lastZxid 0
[junit] 2009-02-01 11:45:11,124 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@524] - Creating new session
0x11f31a80dd00000
[junit] 2009-02-01 11:45:11,141 - INFO [SyncThread:0:nioserverc...@865] -
Finished init of 0x11f31a80dd00000 valid:true
[junit] 2009-02-01 11:45:11,146 - INFO [main:clientb...@300] - STOPPING
server
[junit] 2009-02-01 11:45:11,147 - INFO [main:nioserverc...@737] - closing
session:0x11f31a80dd00000 NIOServerCnxn:
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56225]
[junit] 2009-02-01 11:45:11,147 - WARN
[main-SendThread:clientcnxn$sendthr...@898] - Exception closing session
0x11f31a80dd00000 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:632)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876)
[junit] 2009-02-01 11:45:11,148 - INFO
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory
exited run method
[junit] 2009-02-01 11:45:11,148 - INFO [main:finalrequestproces...@265] -
shutdown of request processor complete
[junit] 2009-02-01 11:45:11,148 - INFO
[ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop!
[junit] 2009-02-01 11:45:11,148 - INFO
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
[junit] 2009-02-01 11:45:11,248 - INFO [main:clientb...@306] - STARTING
server
[junit] 2009-02-01 11:45:11,248 - INFO [main:zookeeperser...@157] -
Created server
[junit] 2009-02-01 11:45:11,249 - INFO [main:files...@71] - Reading
snapshot
http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test5883751898880163489.junit.dir/version-2/snapshot.0
[junit] 2009-02-01 11:45:11,250 - INFO [main:filetxnsnap...@198] -
Snapshotting: 3
[junit] 2009-02-01 11:45:11,252 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from
/127.0.0.1:56227
[junit] 2009-02-01 11:45:11,253 - WARN
[NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of
session 0x0 due to java.io.IOException: Responded to info probe
[junit] 2009-02-01 11:45:11,253 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0
NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56227]
[junit] 2009-02-01 11:45:12,000 - INFO
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
[junit] 2009-02-01 11:45:12,000 - INFO
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
[junit] 2009-02-01 11:45:12,527 - INFO
[main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server
/127.0.0.1:33221
[junit] 2009-02-01 11:45:12,527 - INFO
[main-SendThread:clientcnxn$sendthr...@716] - Priming connection to
java.nio.channels.SocketChannel[connected local=/127.0.0.1:56228
remote=/127.0.0.1:33221]
[junit] 2009-02-01 11:45:12,527 - INFO
[main-SendThread:clientcnxn$sendthr...@868] - Server connection successful
[junit] 2009-02-01 11:45:12,528 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:56228
lastZxid 3
[junit] 2009-02-01 11:45:12,528 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@865] - Finished init of
0x11f31a80dd00000 valid:true
[junit] 2009-02-01 11:45:12,528 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@521] - Renewing session
0x11f31a80dd00000
[junit] 2009-02-01 11:45:46,551 - INFO [main:clientb...@300] - STOPPING
server
[junit] 2009-02-01 11:45:46,551 - INFO [main:nioserverc...@737] - closing
session:0x11f31a80dd00000 NIOServerCnxn:
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56228]
[junit] 2009-02-01 11:45:46,552 - WARN
[main-SendThread:clientcnxn$sendthr...@898] - Exception closing session
0x11f31a80dd00000 to sun.nio.ch.selectionkeyi...@1236cd5
[junit] java.io.IOException: Read error rc = -1
java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:632)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876)
[junit] 2009-02-01 11:45:46,552 - INFO
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory
exited run method
[junit] 2009-02-01 11:45:46,553 - INFO [main:finalrequestproces...@265] -
shutdown of request processor complete
[junit] 2009-02-01 11:45:46,553 - INFO
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
[junit] 2009-02-01 11:45:46,553 - INFO
[ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop!
[junit] 2009-02-01 11:45:46,652 - INFO [main:clientb...@306] - STARTING
server
[junit] 2009-02-01 11:45:46,653 - INFO [main:zookeeperser...@157] -
Created server
[junit] 2009-02-01 11:45:46,654 - INFO [main:files...@71] - Reading
snapshot
http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test5883751898880163489.junit.dir/version-2/snapshot.3
[junit] 2009-02-01 11:45:46,655 - INFO [main:filetxnsnap...@198] -
Snapshotting: 5
[junit] 2009-02-01 11:45:46,657 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from
/127.0.0.1:56230
[junit] 2009-02-01 11:45:46,658 - WARN
[NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of
session 0x0 due to java.io.IOException: Responded to info probe
[junit] 2009-02-01 11:45:46,658 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0
NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56230]
[junit] 2009-02-01 11:45:47,680 - INFO
[main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server
/127.0.0.1:33221
[junit] 2009-02-01 11:45:47,681 - INFO
[main-SendThread:clientcnxn$sendthr...@716] - Priming connection to
java.nio.channels.SocketChannel[connected local=/127.0.0.1:56231
remote=/127.0.0.1:33221]
[junit] 2009-02-01 11:45:47,681 - INFO
[main-SendThread:clientcnxn$sendthr...@868] - Server connection successful
[junit] 2009-02-01 11:45:47,681 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:56231
lastZxid 5
[junit] 2009-02-01 11:45:47,682 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@865] - Finished init of
0x11f31a80dd00000 valid:true
[junit] 2009-02-01 11:45:47,682 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@521] - Renewing session
0x11f31a80dd00000
[junit] 2009-02-01 11:45:47,701 - INFO [main:clientb...@300] - STOPPING
server
[junit] 2009-02-01 11:45:47,702 - INFO [main:nioserverc...@737] - closing
session:0x11f31a80dd00000 NIOServerCnxn:
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56231]
[junit] 2009-02-01 11:45:47,702 - WARN
[main-SendThread:clientcnxn$sendthr...@898] - Exception closing session
0x11f31a80dd00000 to sun.nio.ch.selectionkeyi...@1eb5666
[junit] java.io.IOException: Read error rc = -1
java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:632)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876)
[junit] 2009-02-01 11:45:47,702 - INFO
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory
exited run method
[junit] 2009-02-01 11:45:47,703 - INFO [main:finalrequestproces...@265] -
shutdown of request processor complete
[junit] 2009-02-01 11:45:47,703 - INFO
[ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop!
[junit] 2009-02-01 11:45:47,703 - INFO
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
[junit] 2009-02-01 11:45:47,802 - INFO [main:clientb...@306] - STARTING
server
[junit] 2009-02-01 11:45:47,803 - INFO [main:zookeeperser...@157] -
Created server
[junit] 2009-02-01 11:45:47,804 - INFO [main:files...@71] - Reading
snapshot
http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test5883751898880163489.junit.dir/version-2/snapshot.5
[junit] 2009-02-01 11:45:47,806 - INFO [main:filetxnsnap...@198] -
Snapshotting: 6
[junit] 2009-02-01 11:45:47,808 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from
/127.0.0.1:56233
[junit] 2009-02-01 11:45:47,808 - WARN
[NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of
session 0x0 due to java.io.IOException: Responded to info probe
[junit] 2009-02-01 11:45:47,809 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0
NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56233]
[junit] 2009-02-01 11:45:48,000 - INFO
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
[junit] 2009-02-01 11:45:48,001 - INFO
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
[junit] 2009-02-01 11:45:49,428 - INFO
[main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server
/127.0.0.1:33221
[junit] 2009-02-01 11:45:49,429 - INFO
[main-SendThread:clientcnxn$sendthr...@716] - Priming connection to
java.nio.channels.SocketChannel[connected local=/127.0.0.1:56234
remote=/127.0.0.1:33221]
[junit] 2009-02-01 11:45:49,429 - INFO
[main-SendThread:clientcnxn$sendthr...@868] - Server connection successful
[junit] 2009-02-01 11:45:49,429 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:56234
lastZxid 6
[junit] 2009-02-01 11:45:49,430 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@865] - Finished init of
0x11f31a80dd00000 valid:true
[junit] 2009-02-01 11:45:49,430 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@521] - Renewing session
0x11f31a80dd00000
[junit] 2009-02-01 11:46:00,442 - INFO [main:zookee...@434] - Closing
session: 0x11f31a80dd00000
[junit] 2009-02-01 11:46:00,442 - INFO [main:clientc...@999] - Closing
ClientCnxn for session: 0x11f31a80dd00000
[junit] 2009-02-01 11:46:00,443 - INFO
[ProcessThread:0:preprequestproces...@360] - Processed session termination
request for id: 0x11f31a80dd00000
[junit] 2009-02-01 11:46:00,444 - INFO [SyncThread:0:nioserverc...@737] -
closing session:0x11f31a80dd00000 NIOServerCnxn:
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56234]
[junit] 2009-02-01 11:46:00,444 - INFO
[main-SendThread:clientcnxn$sendthr...@892] - Exception while closing send
thread for session 0x11f31a80dd00000 : Read error rc = -1
java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2009-02-01 11:46:00,544 - INFO [main:clientc...@985] -
Disconnecting ClientCnxn for session: 0x11f31a80dd00000
[junit] 2009-02-01 11:46:00,544 - INFO [main:zookee...@442] - Session:
0x11f31a80dd00000 closed
[junit] 2009-02-01 11:46:00,544 - INFO
[main-EventThread:clientcnxn$eventthr...@452] - EventThread shut down
[junit] 2009-02-01 11:46:00,545 - INFO [main:clientb...@312] - tearDown
starting
[junit] 2009-02-01 11:46:00,545 - INFO
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory
exited run method
[junit] 2009-02-01 11:46:00,546 - INFO [main:finalrequestproces...@265] -
shutdown of request processor complete
[junit] 2009-02-01 11:46:00,546 - INFO
[ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop!
[junit] 2009-02-01 11:46:00,546 - INFO
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
[junit] 2009-02-01 11:46:00,547 - INFO [main:clientb...@322] - FINISHED
testWatcherAutoResetDisabledWithGlobal
[junit] 2009-02-01 11:46:00,548 - INFO [main:clientb...@288] - STARTING
testWatcherAutoResetDisabledWithLocal
[junit] 2009-02-01 11:46:00,549 - INFO [main:zookeeperser...@157] -
Created server
[junit] 2009-02-01 11:46:00,550 - INFO [main:filetxnsnap...@198] -
Snapshotting: 0
[junit] 2009-02-01 11:46:00,551 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from
/127.0.0.1:56236
[junit] 2009-02-01 11:46:00,552 - WARN
[NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of
session 0x0 due to java.io.IOException: Responded to info probe
[junit] 2009-02-01 11:46:00,552 - INFO [main:clientb...@296] - Client test
setup finished
[junit] 2009-02-01 11:46:00,552 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0
NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56236]
[junit] 2009-02-01 11:46:00,552 - INFO [main:zookee...@341] - Initiating
client connection, host=127.0.0.1:33221 sessionTimeout=9000
watcher=org.apache.zookeeper.test.watchertest$mywatc...@29c204
[junit] 2009-02-01 11:46:00,553 - INFO
[main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server
/127.0.0.1:33221
[junit] 2009-02-01 11:46:00,554 - INFO
[main-SendThread:clientcnxn$sendthr...@716] - Priming connection to
java.nio.channels.SocketChannel[connected local=/127.0.0.1:56237
remote=/127.0.0.1:33221]
[junit] 2009-02-01 11:46:00,554 - INFO
[main-SendThread:clientcnxn$sendthr...@868] - Server connection successful
[junit] 2009-02-01 11:46:00,554 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:56237
lastZxid 0
[junit] 2009-02-01 11:46:00,554 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@524] - Creating new session
0x11f31a8cee60000
[junit] 2009-02-01 11:46:00,561 - INFO [SyncThread:0:nioserverc...@865] -
Finished init of 0x11f31a8cee60000 valid:true
[junit] 2009-02-01 11:46:00,566 - INFO [main:clientb...@300] - STOPPING
server
[junit] 2009-02-01 11:46:00,567 - INFO [main:nioserverc...@737] - closing
session:0x11f31a8cee60000 NIOServerCnxn:
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56237]
[junit] 2009-02-01 11:46:00,567 - WARN
[main-SendThread:clientcnxn$sendthr...@898] - Exception closing session
0x11f31a8cee60000 to sun.nio.ch.selectionkeyi...@df48c4
[junit] java.io.IOException: Read error rc = -1
java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:632)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876)
[junit] 2009-02-01 11:46:00,568 - INFO
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory
exited run method
[junit] 2009-02-01 11:46:00,568 - INFO [main:finalrequestproces...@265] -
shutdown of request processor complete
[junit] 2009-02-01 11:46:00,568 - INFO
[ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop!
[junit] 2009-02-01 11:46:00,568 - INFO
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
[junit] 2009-02-01 11:46:00,668 - INFO [main:clientb...@306] - STARTING
server
[junit] 2009-02-01 11:46:00,668 - INFO [main:zookeeperser...@157] -
Created server
[junit] 2009-02-01 11:46:00,669 - INFO [main:files...@71] - Reading
snapshot
http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test2427092952603938520.junit.dir/version-2/snapshot.0
[junit] 2009-02-01 11:46:00,670 - INFO [main:filetxnsnap...@198] -
Snapshotting: 3
[junit] 2009-02-01 11:46:00,672 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from
/127.0.0.1:56239
[junit] 2009-02-01 11:46:00,672 - WARN
[NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of
session 0x0 due to java.io.IOException: Responded to info probe
[junit] 2009-02-01 11:46:00,673 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0
NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56239]
[junit] 2009-02-01 11:46:01,780 - INFO
[main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server
/127.0.0.1:33221
[junit] 2009-02-01 11:46:01,781 - INFO
[main-SendThread:clientcnxn$sendthr...@716] - Priming connection to
java.nio.channels.SocketChannel[connected local=/127.0.0.1:56240
remote=/127.0.0.1:33221]
[junit] 2009-02-01 11:46:01,781 - INFO
[main-SendThread:clientcnxn$sendthr...@868] - Server connection successful
[junit] 2009-02-01 11:46:01,782 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:56240
lastZxid 3
[junit] 2009-02-01 11:46:01,783 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@865] - Finished init of
0x11f31a8cee60000 valid:true
[junit] 2009-02-01 11:46:01,783 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@521] - Renewing session
0x11f31a8cee60000
[junit] 2009-02-01 11:46:03,000 - INFO
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
[junit] 2009-02-01 11:46:03,000 - INFO
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
[junit] 2009-02-01 11:46:35,794 - INFO [main:clientb...@300] - STOPPING
server
[junit] 2009-02-01 11:46:35,795 - INFO [main:nioserverc...@737] - closing
session:0x11f31a8cee60000 NIOServerCnxn:
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56240]
[junit] 2009-02-01 11:46:35,795 - WARN
[main-SendThread:clientcnxn$sendthr...@898] - Exception closing session
0x11f31a8cee60000 to sun.nio.ch.selectionkeyi...@3afb99
[junit] java.io.IOException: Read error rc = -1
java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:632)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876)
[junit] 2009-02-01 11:46:35,806 - INFO
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory
exited run method
[junit] 2009-02-01 11:46:35,807 - INFO [main:finalrequestproces...@265] -
shutdown of request processor complete
[junit] 2009-02-01 11:46:35,807 - INFO
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
[junit] 2009-02-01 11:46:35,807 - INFO
[ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop!
[junit] 2009-02-01 11:46:35,896 - INFO [main:clientb...@306] - STARTING
server
[junit] 2009-02-01 11:46:35,896 - INFO [main:zookeeperser...@157] -
Created server
[junit] 2009-02-01 11:46:35,902 - INFO [main:files...@71] - Reading
snapshot
http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test2427092952603938520.junit.dir/version-2/snapshot.3
[junit] 2009-02-01 11:46:35,905 - INFO [main:filetxnsnap...@198] -
Snapshotting: 5
[junit] 2009-02-01 11:46:35,907 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from
/127.0.0.1:56242
[junit] 2009-02-01 11:46:35,908 - WARN
[NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of
session 0x0 due to java.io.IOException: Responded to info probe
[junit] 2009-02-01 11:46:35,908 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0
NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56242]
[junit] 2009-02-01 11:46:36,000 - INFO
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
[junit] 2009-02-01 11:46:37,235 - INFO
[main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server
/127.0.0.1:33221
[junit] 2009-02-01 11:46:37,235 - INFO
[main-SendThread:clientcnxn$sendthr...@716] - Priming connection to
java.nio.channels.SocketChannel[connected local=/127.0.0.1:56243
remote=/127.0.0.1:33221]
[junit] 2009-02-01 11:46:37,235 - INFO
[main-SendThread:clientcnxn$sendthr...@868] - Server connection successful
[junit] 2009-02-01 11:46:37,235 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:56243
lastZxid 5
[junit] 2009-02-01 11:46:37,236 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@865] - Finished init of
0x11f31a8cee60000 valid:true
[junit] 2009-02-01 11:46:37,236 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@521] - Renewing session
0x11f31a8cee60000
[junit] 2009-02-01 11:46:37,249 - INFO [main:clientb...@300] - STOPPING
server
[junit] 2009-02-01 11:46:37,249 - INFO [main:nioserverc...@737] - closing
session:0x11f31a8cee60000 NIOServerCnxn:
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56243]
[junit] 2009-02-01 11:46:37,250 - WARN
[main-SendThread:clientcnxn$sendthr...@898] - Exception closing session
0x11f31a8cee60000 to sun.nio.ch.selectionkeyi...@d1e7c2
[junit] java.io.IOException: Read error rc = -1
java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.doIO(ClientCnxn.java:632)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:876)
[junit] 2009-02-01 11:46:37,250 - INFO
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory
exited run method
[junit] 2009-02-01 11:46:37,250 - INFO [main:finalrequestproces...@265] -
shutdown of request processor complete
[junit] 2009-02-01 11:46:37,250 - INFO
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
[junit] 2009-02-01 11:46:37,250 - INFO
[ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop!
[junit] 2009-02-01 11:46:37,350 - INFO [main:clientb...@306] - STARTING
server
[junit] 2009-02-01 11:46:37,350 - INFO [main:zookeeperser...@157] -
Created server
[junit] 2009-02-01 11:46:37,352 - INFO [main:files...@71] - Reading
snapshot
http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test2427092952603938520.junit.dir/version-2/snapshot.5
[junit] 2009-02-01 11:46:37,353 - INFO [main:filetxnsnap...@198] -
Snapshotting: 6
[junit] 2009-02-01 11:46:37,355 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@609] - Processing stat command from
/127.0.0.1:56245
[junit] 2009-02-01 11:46:37,355 - WARN
[NIOServerCxn.Factory:33221:nioserverc...@407] - Exception causing close of
session 0x0 due to java.io.IOException: Responded to info probe
[junit] 2009-02-01 11:46:37,356 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@737] - closing session:0x0
NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56245]
[junit] 2009-02-01 11:46:38,498 - INFO
[main-SendThread:clientcnxn$sendthr...@800] - Attempting connection to server
/127.0.0.1:33221
[junit] 2009-02-01 11:46:38,498 - INFO
[main-SendThread:clientcnxn$sendthr...@716] - Priming connection to
java.nio.channels.SocketChannel[connected local=/127.0.0.1:56246
remote=/127.0.0.1:33221]
[junit] 2009-02-01 11:46:38,498 - INFO
[main-SendThread:clientcnxn$sendthr...@868] - Server connection successful
[junit] 2009-02-01 11:46:38,499 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@493] - Connected to /127.0.0.1:56246
lastZxid 6
[junit] 2009-02-01 11:46:38,499 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@865] - Finished init of
0x11f31a8cee60000 valid:true
[junit] 2009-02-01 11:46:38,499 - INFO
[NIOServerCxn.Factory:33221:nioserverc...@521] - Renewing session
0x11f31a8cee60000
[junit] 2009-02-01 11:46:39,000 - INFO
[SessionTracker:sessiontrackeri...@142] - SessionTrackerImpl exited loop!
[junit] 2009-02-01 11:46:49,511 - INFO [main:zookee...@434] - Closing
session: 0x11f31a8cee60000
[junit] 2009-02-01 11:46:49,512 - INFO [main:clientc...@999] - Closing
ClientCnxn for session: 0x11f31a8cee60000
[junit] 2009-02-01 11:46:49,512 - INFO
[ProcessThread:0:preprequestproces...@360] - Processed session termination
request for id: 0x11f31a8cee60000
[junit] 2009-02-01 11:46:49,513 - INFO [SyncThread:0:nioserverc...@737] -
closing session:0x11f31a8cee60000 NIOServerCnxn:
java.nio.channels.SocketChannel[connected local=/127.0.0.1:33221
remote=/127.0.0.1:56246]
[junit] 2009-02-01 11:46:49,513 - INFO
[main-SendThread:clientcnxn$sendthr...@892] - Exception while closing send
thread for session 0x11f31a8cee60000 : Read error rc = -1
java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2009-02-01 11:46:49,613 - INFO [main:clientc...@985] -
Disconnecting ClientCnxn for session: 0x11f31a8cee60000
[junit] 2009-02-01 11:46:49,614 - INFO [main:zookee...@442] - Session:
0x11f31a8cee60000 closed
[junit] 2009-02-01 11:46:49,614 - INFO [main:clientb...@312] - tearDown
starting
[junit] 2009-02-01 11:46:49,614 - INFO
[main-EventThread:clientcnxn$eventthr...@452] - EventThread shut down
[junit] 2009-02-01 11:46:49,614 - INFO
[NIOServerCxn.Factory:33221:nioservercnxn$fact...@171] - NIOServerCnxn factory
exited run method
[junit] 2009-02-01 11:46:49,615 - INFO [main:finalrequestproces...@265] -
shutdown of request processor complete
[junit] 2009-02-01 11:46:49,615 - INFO
[SyncThread:0:syncrequestproces...@117] - SyncRequestProcessor exited!
[junit] 2009-02-01 11:46:49,615 - INFO
[ProcessThread:0:preprequestproces...@111] - PrepRequestProcessor exited loop!
[junit] 2009-02-01 11:46:49,616 - INFO [main:clientb...@322] - FINISHED
testWatcherAutoResetDisabledWithLocal
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 124.105 sec
BUILD FAILED
http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml
:554: Tests failed!
Total time: 8 minutes 31 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
