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