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