See <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/498/>
------------------------------------------
[...truncated 80593 lines...]
[junit] 2009-10-15 10:38:35,968 - INFO [main:zookee...@502] - Closing
session: 0x12457c7019c0000
[junit] 2009-10-15 10:38:35,968 - INFO [main:clientc...@1070] - Closing
ClientCnxn for session: 0x12457c7019c0000
[junit] 2009-10-15 10:38:35,969 - INFO
[ProcessThread:-1:preprequestproces...@384] - Processed session termination
request for id: 0x12457c7019c0000
[junit] 2009-10-15 10:38:35,970 - INFO [SyncThread:0:nioserverc...@834] -
closing session:0x12457c7019c0000 NIOServerCnxn:
java.nio.channels.SocketChannel[connected local=/127.0.0.1:11225
remote=/127.0.0.1:50402]
[junit] 2009-10-15 10:38:35,970 - INFO
[main-SendThread(localhost:11225):clientcnxn$sendthr...@963] - Exception while
closing send thread for session 0x12457c7019c0000 : Read error rc = -1
java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2009-10-15 10:38:36,000 - INFO
[SessionTracker:sessiontrackeri...@143] - SessionTrackerImpl exited loop!
[junit] 2009-10-15 10:38:36,071 - INFO [main:clientc...@1056] -
Disconnecting ClientCnxn for session: 0x12457c7019c0000
[junit] 2009-10-15 10:38:36,071 - INFO [main:zookee...@510] - Session:
0x12457c7019c0000 closed
[junit] 2009-10-15 10:38:36,071 - INFO
[main-EventThread:clientcnxn$eventthr...@514] - EventThread shut down
[junit] 2009-10-15 10:38:36,071 - INFO [main:clientb...@397] - tearDown
starting
[junit] 2009-10-15 10:38:36,072 - INFO [main:clientb...@409] - fdcount
after test is: 37
[junit] 2009-10-15 10:38:36,072 - INFO [main:zookee...@502] - Closing
session: 0x12457c7019c0000
[junit] 2009-10-15 10:38:36,072 - INFO [main:clientc...@1070] - Closing
ClientCnxn for session: 0x12457c7019c0000
[junit] 2009-10-15 10:38:36,072 - INFO [main:clientc...@1056] -
Disconnecting ClientCnxn for session: 0x12457c7019c0000
[junit] 2009-10-15 10:38:36,073 - INFO [main:zookee...@510] - Session:
0x12457c7019c0000 closed
[junit] 2009-10-15 10:38:36,073 - INFO [main:clientb...@374] - STOPPING
server
[junit] 2009-10-15 10:38:36,073 - INFO
[NIOServerCxn.Factory:11225:nioservercnxn$fact...@236] - NIOServerCnxn factory
exited run method
[junit] 2009-10-15 10:38:36,073 - INFO [main:finalrequestproces...@283] -
shutdown of request processor complete
[junit] 2009-10-15 10:38:36,073 - INFO
[ProcessThread:-1:preprequestproces...@119] - PrepRequestProcessor exited loop!
[junit] 2009-10-15 10:38:36,073 - INFO
[SyncThread:0:syncrequestproces...@134] - SyncRequestProcessor exited!
[junit] ensureOnly:[]
[junit] 2009-10-15 10:38:36,088 - INFO [main:clientb...@428] - FINISHED
testWatcherAutoResetDisabledWithGlobal
[junit] 2009-10-15 10:38:36,089 - INFO [main:clientb...@338] - STARTING
testWatcherAutoResetDisabledWithLocal
[junit] 2009-10-15 10:38:36,094 - INFO [main:clientb...@367] - STARTING
server
[junit] 2009-10-15 10:38:36,095 - INFO [main:zookeeperser...@160] -
Created server
[junit] 2009-10-15 10:38:36,095 - INFO [main:nioservercnxn$fact...@122] -
binding to port 11226
[junit] 2009-10-15 10:38:36,096 - INFO [main:filetxnsnap...@208] -
Snapshotting: 0
[junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port]
[junit] 2009-10-15 10:38:36,097 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@699] - Processing stat command from
/127.0.0.1:36680
[junit] 2009-10-15 10:38:36,098 - WARN
[NIOServerCxn.Factory:11226:nioserverc...@495] - Exception causing close of
session 0x0 due to java.io.IOException: Responded to info probe
[junit] 2009-10-15 10:38:36,098 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@834] - closing session:0x0
NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:11226
remote=/127.0.0.1:36680]
[junit] expect:InMemoryDataTree
[junit] found:InMemoryDataTree
org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] expect:StandaloneServer_port
[junit] found:StandaloneServer_port
org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2009-10-15 10:38:36,099 - INFO [main:clientb...@359] - Initial
fdcount is: 37
[junit] 2009-10-15 10:38:36,099 - INFO [main:clientb...@363] - Client test
setup finished
[junit] 2009-10-15 10:38:36,099 - INFO [main:zookee...@369] - Initiating
client connection, connectString=127.0.0.1:11226 sessionTimeout=5000
watcher=org.apache.zookeeper.test.watchertest$mywatc...@1786a3c
[junit] 2009-10-15 10:38:36,100 - INFO
[main-SendThread():clientcnxn$sendthr...@869] - Attempting connection to server
/127.0.0.1:11226
[junit] 2009-10-15 10:38:36,100 - INFO
[main-SendThread(localhost:11226):clientcnxn$sendthr...@785] - Priming
connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:36681
remote=localhost/127.0.0.1:11226]
[junit] 2009-10-15 10:38:36,101 - INFO
[main-SendThread(localhost:11226):clientcnxn$sendthr...@939] - Server
connection successful
[junit] 2009-10-15 10:38:36,101 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@584] - Connected to /127.0.0.1:36681
lastZxid 0
[junit] 2009-10-15 10:38:36,101 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@616] - Creating new session
0x12457c718400000
[junit] 2009-10-15 10:38:36,108 - INFO [SyncThread:0:nioserverc...@965] -
Finished init of 0x12457c718400000 valid:true
[junit] expect:0x12457c718400000
[junit] found:0x12457c718400000
org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x12457c718400000
[junit] 2009-10-15 10:38:36,115 - INFO [main:clientb...@374] - STOPPING
server
[junit] 2009-10-15 10:38:36,116 - INFO [main:nioserverc...@834] - closing
session:0x12457c718400000 NIOServerCnxn:
java.nio.channels.SocketChannel[connected local=/127.0.0.1:11226
remote=/127.0.0.1:36681]
[junit] 2009-10-15 10:38:36,116 - WARN
[main-SendThread(localhost:11226):clientcnxn$sendthr...@969] - Exception
closing session 0x12457c718400000 to sun.nio.ch.selectionkeyi...@106df95
[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:701)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:947)
[junit] 2009-10-15 10:38:36,116 - INFO
[NIOServerCxn.Factory:11226:nioservercnxn$fact...@236] - NIOServerCnxn factory
exited run method
[junit] 2009-10-15 10:38:36,117 - INFO [main:finalrequestproces...@283] -
shutdown of request processor complete
[junit] 2009-10-15 10:38:36,117 - INFO
[SyncThread:0:syncrequestproces...@134] - SyncRequestProcessor exited!
[junit] 2009-10-15 10:38:36,117 - INFO
[ProcessThread:-1:preprequestproces...@119] - PrepRequestProcessor exited loop!
[junit] ensureOnly:[]
[junit] 2009-10-15 10:38:36,216 - INFO [main:clientb...@367] - STARTING
server
[junit] 2009-10-15 10:38:36,217 - INFO [main:zookeeperser...@160] -
Created server
[junit] 2009-10-15 10:38:36,217 - INFO [main:nioservercnxn$fact...@122] -
binding to port 11226
[junit] 2009-10-15 10:38:36,218 - INFO [main:files...@81] - Reading
snapshot
<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test2019795094492060292.junit.dir/version-2/snapshot.0>
[junit] 2009-10-15 10:38:36,219 - INFO [main:filetxnsnap...@208] -
Snapshotting: 3
[junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port]
[junit] 2009-10-15 10:38:36,221 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@699] - Processing stat command from
/127.0.0.1:36683
[junit] 2009-10-15 10:38:36,221 - WARN
[NIOServerCxn.Factory:11226:nioserverc...@495] - Exception causing close of
session 0x0 due to java.io.IOException: Responded to info probe
[junit] 2009-10-15 10:38:36,222 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@834] - closing session:0x0
NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:11226
remote=/127.0.0.1:36683]
[junit] expect:InMemoryDataTree
[junit] found:InMemoryDataTree
org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] expect:StandaloneServer_port
[junit] found:StandaloneServer_port
org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2009-10-15 10:38:37,228 - INFO
[main-SendThread(localhost:11226):clientcnxn$sendthr...@869] - Attempting
connection to server localhost/127.0.0.1:11226
[junit] 2009-10-15 10:38:37,229 - INFO
[main-SendThread(localhost:11226):clientcnxn$sendthr...@785] - Priming
connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:36684
remote=localhost/127.0.0.1:11226]
[junit] 2009-10-15 10:38:37,229 - INFO
[main-SendThread(localhost:11226):clientcnxn$sendthr...@939] - Server
connection successful
[junit] 2009-10-15 10:38:37,229 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@584] - Connected to /127.0.0.1:36684
lastZxid 3
[junit] 2009-10-15 10:38:37,230 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@965] - Finished init of
0x12457c718400000 valid:true
[junit] 2009-10-15 10:38:37,230 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@613] - Renewing session
0x12457c718400000
[junit] 2009-10-15 10:38:37,236 - INFO [main:clientb...@374] - STOPPING
server
[junit] 2009-10-15 10:38:37,237 - INFO [main:nioserverc...@834] - closing
session:0x12457c718400000 NIOServerCnxn:
java.nio.channels.SocketChannel[connected local=/127.0.0.1:11226
remote=/127.0.0.1:36684]
[junit] 2009-10-15 10:38:37,237 - WARN
[main-SendThread(localhost:11226):clientcnxn$sendthr...@969] - Exception
closing session 0x12457c718400000 to sun.nio.ch.selectionkeyi...@893969
[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:701)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:947)
[junit] 2009-10-15 10:38:37,237 - INFO
[NIOServerCxn.Factory:11226:nioservercnxn$fact...@236] - NIOServerCnxn factory
exited run method
[junit] 2009-10-15 10:38:37,238 - INFO [main:finalrequestproces...@283] -
shutdown of request processor complete
[junit] 2009-10-15 10:38:37,238 - INFO
[SyncThread:0:syncrequestproces...@134] - SyncRequestProcessor exited!
[junit] 2009-10-15 10:38:37,238 - INFO
[ProcessThread:-1:preprequestproces...@119] - PrepRequestProcessor exited loop!
[junit] ensureOnly:[]
[junit] 2009-10-15 10:38:37,337 - INFO [main:clientb...@367] - STARTING
server
[junit] 2009-10-15 10:38:37,338 - INFO [main:zookeeperser...@160] -
Created server
[junit] 2009-10-15 10:38:37,338 - INFO [main:nioservercnxn$fact...@122] -
binding to port 11226
[junit] 2009-10-15 10:38:37,339 - INFO [main:files...@81] - Reading
snapshot
<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test2019795094492060292.junit.dir/version-2/snapshot.3>
[junit] 2009-10-15 10:38:37,341 - INFO [main:filetxnsnap...@208] -
Snapshotting: 5
[junit] 2009-10-15 10:38:37,343 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@699] - Processing stat command from
/127.0.0.1:36686
[junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port]
[junit] 2009-10-15 10:38:37,343 - WARN
[NIOServerCxn.Factory:11226:nioserverc...@495] - Exception causing close of
session 0x0 due to java.io.IOException: Responded to info probe
[junit] 2009-10-15 10:38:37,344 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@834] - closing session:0x0
NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:11226
remote=/127.0.0.1:36686]
[junit] expect:InMemoryDataTree
[junit] found:InMemoryDataTree
org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] expect:StandaloneServer_port
[junit] found:StandaloneServer_port
org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2009-10-15 10:38:38,768 - INFO
[main-SendThread(localhost:11226):clientcnxn$sendthr...@869] - Attempting
connection to server localhost/127.0.0.1:11226
[junit] 2009-10-15 10:38:38,769 - INFO
[main-SendThread(localhost:11226):clientcnxn$sendthr...@785] - Priming
connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:36687
remote=localhost/127.0.0.1:11226]
[junit] 2009-10-15 10:38:38,769 - INFO
[main-SendThread(localhost:11226):clientcnxn$sendthr...@939] - Server
connection successful
[junit] 2009-10-15 10:38:38,769 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@584] - Connected to /127.0.0.1:36687
lastZxid 5
[junit] 2009-10-15 10:38:38,770 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@965] - Finished init of
0x12457c718400000 valid:true
[junit] 2009-10-15 10:38:38,770 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@613] - Renewing session
0x12457c718400000
[junit] 2009-10-15 10:38:38,780 - INFO [main:clientb...@374] - STOPPING
server
[junit] 2009-10-15 10:38:38,781 - INFO [main:nioserverc...@834] - closing
session:0x12457c718400000 NIOServerCnxn:
java.nio.channels.SocketChannel[connected local=/127.0.0.1:11226
remote=/127.0.0.1:36687]
[junit] 2009-10-15 10:38:38,781 - WARN
[main-SendThread(localhost:11226):clientcnxn$sendthr...@969] - Exception
closing session 0x12457c718400000 to sun.nio.ch.selectionkeyi...@ec898a
[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:701)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:947)
[junit] 2009-10-15 10:38:38,782 - INFO
[NIOServerCxn.Factory:11226:nioservercnxn$fact...@236] - NIOServerCnxn factory
exited run method
[junit] 2009-10-15 10:38:38,782 - INFO [main:finalrequestproces...@283] -
shutdown of request processor complete
[junit] 2009-10-15 10:38:38,782 - INFO
[ProcessThread:-1:preprequestproces...@119] - PrepRequestProcessor exited loop!
[junit] 2009-10-15 10:38:38,782 - INFO
[SyncThread:0:syncrequestproces...@134] - SyncRequestProcessor exited!
[junit] ensureOnly:[]
[junit] 2009-10-15 10:38:38,881 - INFO [main:clientb...@367] - STARTING
server
[junit] 2009-10-15 10:38:38,882 - INFO [main:zookeeperser...@160] -
Created server
[junit] 2009-10-15 10:38:38,882 - INFO [main:nioservercnxn$fact...@122] -
binding to port 11226
[junit] 2009-10-15 10:38:38,884 - INFO [main:files...@81] - Reading
snapshot
<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test2019795094492060292.junit.dir/version-2/snapshot.5>
[junit] 2009-10-15 10:38:38,886 - INFO [main:filetxnsnap...@208] -
Snapshotting: 6
[junit] 2009-10-15 10:38:38,888 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@699] - Processing stat command from
/127.0.0.1:36689
[junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port]
[junit] 2009-10-15 10:38:38,888 - WARN
[NIOServerCxn.Factory:11226:nioserverc...@495] - Exception causing close of
session 0x0 due to java.io.IOException: Responded to info probe
[junit] 2009-10-15 10:38:38,889 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@834] - closing session:0x0
NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:11226
remote=/127.0.0.1:36689]
[junit] expect:InMemoryDataTree
[junit] found:InMemoryDataTree
org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] expect:StandaloneServer_port
[junit] found:StandaloneServer_port
org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2009-10-15 10:38:39,000 - INFO
[SessionTracker:sessiontrackeri...@143] - SessionTrackerImpl exited loop!
[junit] 2009-10-15 10:38:39,000 - INFO
[SessionTracker:sessiontrackeri...@143] - SessionTrackerImpl exited loop!
[junit] 2009-10-15 10:38:39,000 - INFO
[SessionTracker:sessiontrackeri...@143] - SessionTrackerImpl exited loop!
[junit] 2009-10-15 10:38:39,000 - INFO
[SessionTracker:sessiontrackeri...@143] - SessionTrackerImpl exited loop!
[junit] 2009-10-15 10:38:40,562 - INFO
[main-SendThread(localhost:11226):clientcnxn$sendthr...@869] - Attempting
connection to server localhost/127.0.0.1:11226
[junit] 2009-10-15 10:38:40,562 - INFO
[main-SendThread(localhost:11226):clientcnxn$sendthr...@785] - Priming
connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:36690
remote=localhost/127.0.0.1:11226]
[junit] 2009-10-15 10:38:40,562 - INFO
[main-SendThread(localhost:11226):clientcnxn$sendthr...@939] - Server
connection successful
[junit] 2009-10-15 10:38:40,562 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@584] - Connected to /127.0.0.1:36690
lastZxid 6
[junit] 2009-10-15 10:38:40,563 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@965] - Finished init of
0x12457c718400000 valid:true
[junit] 2009-10-15 10:38:40,563 - INFO
[NIOServerCxn.Factory:11226:nioserverc...@613] - Renewing session
0x12457c718400000
[junit] 2009-10-15 10:38:41,572 - INFO [main:zookee...@502] - Closing
session: 0x12457c718400000
[junit] 2009-10-15 10:38:41,572 - INFO [main:clientc...@1070] - Closing
ClientCnxn for session: 0x12457c718400000
[junit] 2009-10-15 10:38:41,573 - INFO
[ProcessThread:-1:preprequestproces...@384] - Processed session termination
request for id: 0x12457c718400000
[junit] 2009-10-15 10:38:41,574 - INFO [SyncThread:0:nioserverc...@834] -
closing session:0x12457c718400000 NIOServerCnxn:
java.nio.channels.SocketChannel[connected local=/127.0.0.1:11226
remote=/127.0.0.1:36690]
[junit] 2009-10-15 10:38:41,575 - INFO
[main-SendThread(localhost:11226):clientcnxn$sendthr...@963] - Exception while
closing send thread for session 0x12457c718400000 : Read error rc = -1
java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2009-10-15 10:38:41,675 - INFO [main:clientc...@1056] -
Disconnecting ClientCnxn for session: 0x12457c718400000
[junit] 2009-10-15 10:38:41,675 - INFO [main:zookee...@510] - Session:
0x12457c718400000 closed
[junit] 2009-10-15 10:38:41,675 - INFO
[main-EventThread:clientcnxn$eventthr...@514] - EventThread shut down
[junit] 2009-10-15 10:38:41,675 - INFO [main:clientb...@397] - tearDown
starting
[junit] 2009-10-15 10:38:41,676 - INFO [main:clientb...@409] - fdcount
after test is: 41
[junit] 2009-10-15 10:38:41,676 - INFO [main:zookee...@502] - Closing
session: 0x12457c718400000
[junit] 2009-10-15 10:38:41,677 - INFO [main:clientc...@1070] - Closing
ClientCnxn for session: 0x12457c718400000
[junit] 2009-10-15 10:38:41,677 - INFO [main:clientc...@1056] -
Disconnecting ClientCnxn for session: 0x12457c718400000
[junit] 2009-10-15 10:38:41,677 - INFO [main:zookee...@510] - Session:
0x12457c718400000 closed
[junit] 2009-10-15 10:38:41,677 - INFO [main:clientb...@374] - STOPPING
server
[junit] 2009-10-15 10:38:41,677 - INFO
[NIOServerCxn.Factory:11226:nioservercnxn$fact...@236] - NIOServerCnxn factory
exited run method
[junit] 2009-10-15 10:38:41,678 - INFO [main:finalrequestproces...@283] -
shutdown of request processor complete
[junit] 2009-10-15 10:38:41,678 - INFO
[ProcessThread:-1:preprequestproces...@119] - PrepRequestProcessor exited loop!
[junit] 2009-10-15 10:38:41,678 - INFO
[SyncThread:0:syncrequestproces...@134] - SyncRequestProcessor exited!
[junit] ensureOnly:[]
[junit] 2009-10-15 10:38:41,681 - INFO [main:clientb...@428] - FINISHED
testWatcherAutoResetDisabledWithLocal
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 28.068 sec
[junit] 2009-10-15 10:38:42,318 - INFO [main:portassignm...@31] -
assigning port 11221
[junit] Running org.apache.zookeeper.test.ZooKeeperQuotaTest
[junit] 2009-10-15 10:38:42,368 - INFO [main:clientb...@338] - STARTING
testQuota
[junit] 2009-10-15 10:38:42,529 - INFO [main:clientb...@367] - STARTING
server
[junit] 2009-10-15 10:38:42,537 - INFO [main:environm...@97] - Server
environment:zookeeper.version=3.3.0-825457, built on 10/15/2009 10:30 GMT
[junit] 2009-10-15 10:38:42,537 - INFO [main:environm...@97] - Server
environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2009-10-15 10:38:42,538 - INFO [main:environm...@97] - Server
environment:java.version=1.6.0_11
[junit] 2009-10-15 10:38:42,539 - INFO [main:environm...@97] - Server
environment:java.vendor=Sun Microsystems Inc.
[junit] 2009-10-15 10:38:42,539 - INFO [main:environm...@97] - Server
environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] 2009-10-15 10:38:42,540 - INFO [main:environm...@97] - Server
environment:java.class.path=<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/classes>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/antlr-2.7.6.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/checkstyle-5.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-beanutils-core-1.7.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-cli-1.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-collections-2.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-lang-1.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-logging-1.0.3.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/google-collections-0.9.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/junit-4.7.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/classes>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/ivy-2.1.0-rc2.jar>:/homes/hudson/tools/ant/apache-ant-1.7.0/lib/ant.jar:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/jline-0.9.94.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/log4j-1.2.15.jar>:/homes/hudson/tools/ant/apache-ant-1.7.0/lib/ant-launcher.jar:/homes/hudson/tools/ant/apache-ant-1.7.0/lib/ant-junit.jar
[junit] 2009-10-15 10:38:42,541 - INFO [main:environm...@97] - Server
environment:java.library.path=/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386/server:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib
[junit] 2009-10-15 10:38:42,541 - INFO [main:environm...@97] - Server
environment:java.io.tmpdir=/tmp
[junit] 2009-10-15 10:38:42,542 - INFO [main:environm...@97] - Server
environment:java.compiler=<NA>
[junit] 2009-10-15 10:38:42,542 - INFO [main:environm...@97] - Server
environment:os.name=Linux
[junit] 2009-10-15 10:38:42,543 - INFO [main:environm...@97] - Server
environment:os.arch=i386
[junit] 2009-10-15 10:38:42,543 - INFO [main:environm...@97] - Server
environment:os.version=2.6.28-15-generic
[junit] 2009-10-15 10:38:42,544 - INFO [main:environm...@97] - Server
environment:user.name=hudson
[junit] 2009-10-15 10:38:42,544 - INFO [main:environm...@97] - Server
environment:user.home=/homes/hudson
[junit] 2009-10-15 10:38:42,545 - INFO [main:environm...@97] - Server
environment:user.dir=<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2009-10-15 10:38:42,549 - INFO [main:zookeeperser...@160] -
Created server
[junit] 2009-10-15 10:38:42,560 - INFO [main:nioservercnxn$fact...@122] -
binding to port 11221
[junit] 2009-10-15 10:38:42,572 - INFO [main:filetxnsnap...@208] -
Snapshotting: 0
[junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port]
[junit] 2009-10-15 10:38:42,595 - INFO
[NIOServerCxn.Factory:11221:nioserverc...@699] - Processing stat command from
/127.0.0.1:49069
[junit] 2009-10-15 10:38:42,596 - WARN
[NIOServerCxn.Factory:11221:nioserverc...@495] - Exception causing close of
session 0x0 due to java.io.IOException: Responded to info probe
[junit] 2009-10-15 10:38:42,597 - INFO
[NIOServerCxn.Factory:11221:nioserverc...@834] - closing session:0x0
NIOServerCnxn: java.nio.channels.SocketChannel[connected local=/127.0.0.1:11221
remote=/127.0.0.1:49069]
[junit] 2009-10-15 10:38:42,602 - INFO [main:clientb...@359] - Initial
fdcount is: 31
[junit] 2009-10-15 10:38:42,602 - INFO [main:clientb...@363] - Client test
setup finished
[junit] 2009-10-15 10:38:42,606 - INFO [main:environm...@97] - Client
environment:zookeeper.version=3.3.0-825457, built on 10/15/2009 10:30 GMT
[junit] 2009-10-15 10:38:42,606 - INFO [main:environm...@97] - Client
environment:host.name=h8.grid.sp2.yahoo.net
[junit] 2009-10-15 10:38:42,607 - INFO [main:environm...@97] - Client
environment:java.version=1.6.0_11
[junit] 2009-10-15 10:38:42,607 - INFO [main:environm...@97] - Client
environment:java.vendor=Sun Microsystems Inc.
[junit] 2009-10-15 10:38:42,608 - INFO [main:environm...@97] - Client
environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre
[junit] expect:InMemoryDataTree
[junit] found:InMemoryDataTree
org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] expect:StandaloneServer_port
[junit] found:StandaloneServer_port
org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2009-10-15 10:38:42,608 - INFO [main:environm...@97] - Client
environment:java.class.path=<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/classes>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/antlr-2.7.6.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/checkstyle-5.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-beanutils-core-1.7.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-cli-1.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-collections-2.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-lang-1.0.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/commons-logging-1.0.3.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/google-collections-0.9.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/lib/junit-4.7.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/classes>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/src/java/lib/ivy-2.1.0-rc2.jar>:/homes/hudson/tools/ant/apache-ant-1.7.0/lib/ant.jar:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/jline-0.9.94.jar>:<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/lib/log4j-1.2.15.jar>:/homes/hudson/tools/ant/apache-ant-1.7.0/lib/ant-launcher.jar:/homes/hudson/tools/ant/apache-ant-1.7.0/lib/ant-junit.jar
[junit] 2009-10-15 10:38:42,609 - INFO [main:environm...@97] - Client
environment:java.library.path=/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386/server:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/lib/i386:/homes/hudson/tools/java/jdk1.6.0_11-32/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib
[junit] 2009-10-15 10:38:42,610 - INFO [main:environm...@97] - Client
environment:java.io.tmpdir=/tmp
[junit] 2009-10-15 10:38:42,610 - INFO [main:environm...@97] - Client
environment:java.compiler=<NA>
[junit] 2009-10-15 10:38:42,611 - INFO [main:environm...@97] - Client
environment:os.name=Linux
[junit] 2009-10-15 10:38:42,611 - INFO [main:environm...@97] - Client
environment:os.arch=i386
[junit] 2009-10-15 10:38:42,612 - INFO [main:environm...@97] - Client
environment:os.version=2.6.28-15-generic
[junit] 2009-10-15 10:38:42,612 - INFO [main:environm...@97] - Client
environment:user.name=hudson
[junit] 2009-10-15 10:38:42,613 - INFO [main:environm...@97] - Client
environment:user.home=/homes/hudson
[junit] 2009-10-15 10:38:42,613 - INFO [main:environm...@97] - Client
environment:user.dir=<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk>
[junit] 2009-10-15 10:38:42,614 - INFO [main:zookee...@369] - Initiating
client connection, connectString=127.0.0.1:11221 sessionTimeout=30000
watcher=org.apache.zookeeper.test.clientbase$countdownwatc...@983d95
[junit] 2009-10-15 10:38:42,616 - INFO [main:clientc...@92] -
zookeeper.disableAutoWatchReset is false
[junit] 2009-10-15 10:38:42,622 - INFO
[main-SendThread():clientcnxn$sendthr...@869] - Attempting connection to server
/127.0.0.1:11221
[junit] 2009-10-15 10:38:42,623 - INFO
[main-SendThread(localhost:11221):clientcnxn$sendthr...@785] - Priming
connection to java.nio.channels.SocketChannel[connected local=/127.0.0.1:49070
remote=localhost/127.0.0.1:11221]
[junit] 2009-10-15 10:38:42,627 - INFO
[main-SendThread(localhost:11221):clientcnxn$sendthr...@939] - Server
connection successful
[junit] 2009-10-15 10:38:42,629 - INFO
[NIOServerCxn.Factory:11221:nioserverc...@584] - Connected to /127.0.0.1:49070
lastZxid 0
[junit] 2009-10-15 10:38:42,630 - INFO
[NIOServerCxn.Factory:11221:nioserverc...@616] - Creating new session
0x12457c731910000
[junit] 2009-10-15 10:38:42,650 - INFO [SyncThread:0:nioserverc...@965] -
Finished init of 0x12457c731910000 valid:true
[junit] expect:0x12457c731910000
[junit] found:0x12457c731910000
org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x12457c731910000
[junit] 2009-10-15 10:38:42,692 - INFO [main:clientb...@397] - tearDown
starting
[junit] 2009-10-15 10:38:42,693 - INFO [main:clientb...@409] - fdcount
after test is: 37
[junit] 2009-10-15 10:38:42,693 - INFO [main:zookee...@502] - Closing
session: 0x12457c731910000
[junit] 2009-10-15 10:38:42,693 - INFO [main:clientc...@1070] - Closing
ClientCnxn for session: 0x12457c731910000
[junit] 2009-10-15 10:38:42,694 - INFO
[ProcessThread:-1:preprequestproces...@384] - Processed session termination
request for id: 0x12457c731910000
[junit] 2009-10-15 10:38:42,695 - INFO [SyncThread:0:nioserverc...@834] -
closing session:0x12457c731910000 NIOServerCnxn:
java.nio.channels.SocketChannel[connected local=/127.0.0.1:11221
remote=/127.0.0.1:49070]
[junit] 2009-10-15 10:38:42,696 - INFO
[main-SendThread(localhost:11221):clientcnxn$sendthr...@963] - Exception while
closing send thread for session 0x12457c731910000 : Read error rc = -1
java.nio.DirectByteBuffer[pos=0 lim=4 cap=4]
[junit] 2009-10-15 10:38:42,797 - INFO [main:clientc...@1056] -
Disconnecting ClientCnxn for session: 0x12457c731910000
[junit] 2009-10-15 10:38:42,797 - INFO [main:zookee...@510] - Session:
0x12457c731910000 closed
[junit] 2009-10-15 10:38:42,797 - INFO [main:clientb...@374] - STOPPING
server
[junit] 2009-10-15 10:38:42,797 - INFO
[main-EventThread:clientcnxn$eventthr...@514] - EventThread shut down
[junit] 2009-10-15 10:38:42,809 - INFO
[NIOServerCxn.Factory:11221:nioservercnxn$fact...@236] - NIOServerCnxn factory
exited run method
[junit] 2009-10-15 10:38:42,810 - INFO
[ProcessThread:-1:preprequestproces...@119] - PrepRequestProcessor exited loop!
[junit] 2009-10-15 10:38:42,810 - INFO
[SyncThread:0:syncrequestproces...@134] - SyncRequestProcessor exited!
[junit] 2009-10-15 10:38:42,810 - INFO [main:finalrequestproces...@283] -
shutdown of request processor complete
[junit] ensureOnly:[]
[junit] 2009-10-15 10:38:42,814 - INFO [main:clientb...@428] - FINISHED
testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.493 sec
BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:798:
The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:709:
Tests failed!
Total time: 7 minutes 54 seconds
Publishing Javadoc
Archiving artifacts
Recording test results
Recording fingerprints
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
