See <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/606/>
------------------------------------------ [...truncated 95936 lines...] [junit] 2009-12-08 19:57:30,352 - INFO [main-SendThread(localhost:11225):clientcnxn$sendthr...@837] - Socket connection established to localhost/127.0.0.1:11225, initiating session [junit] 2009-12-08 19:57:30,352 - INFO [NIOServerCxn.Factory:11225:nioservercnxn$fact...@231] - Accepted socket connection from /127.0.0.1:47786 [junit] 2009-12-08 19:57:30,353 - INFO [NIOServerCxn.Factory:11225:nioserverc...@709] - Client attempting to renew session 0x1256fde23440000 at /127.0.0.1:47786 [junit] 2009-12-08 19:57:30,353 - INFO [NIOServerCxn.Factory:11225:nioserverc...@1142] - Established session 0x1256fde23440000 for client /127.0.0.1:47786 [junit] 2009-12-08 19:57:30,353 - INFO [main-SendThread(localhost:11225):clientcnxn$sendthr...@640] - Session establishment complete, sessionid = 0x1256fde23440000 [junit] 2009-12-08 19:57:30,363 - INFO [main:clientb...@385] - STOPPING server [junit] 2009-12-08 19:57:30,364 - INFO [main:nioserverc...@989] - Closed socket connection for client /127.0.0.1:47786 which had sessionid 0x1256fde23440000 [junit] 2009-12-08 19:57:30,364 - INFO [main-SendThread(localhost:11225):clientcnxn$sendthr...@1047] - Unable to read additional data from server sessionid 0x1256fde23440000, likely server has closed socket, closing socket connection and attempting reconnect [junit] 2009-12-08 19:57:30,365 - INFO [NIOServerCxn.Factory:11225:nioservercnxn$fact...@258] - NIOServerCnxn factory exited run method [junit] 2009-12-08 19:57:30,365 - INFO [main:finalrequestproces...@365] - shutdown of request processor complete [junit] 2009-12-08 19:57:30,365 - INFO [SyncThread:0:syncrequestproces...@151] - SyncRequestProcessor exited! [junit] 2009-12-08 19:57:30,365 - INFO [ProcessThread:-1:preprequestproces...@119] - PrepRequestProcessor exited loop! [junit] ensureOnly:[] [junit] 2009-12-08 19:57:30,465 - INFO [main:clientb...@378] - STARTING server [junit] 2009-12-08 19:57:30,465 - INFO [main:zookeeperser...@160] - Created server [junit] 2009-12-08 19:57:30,466 - INFO [main:nioservercnxn$fact...@140] - binding to port 11225 [junit] 2009-12-08 19:57:30,472 - INFO [main:files...@81] - Reading snapshot <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test8087505815007629806.junit.dir/version-2/snapshot.5> [junit] 2009-12-08 19:57:30,475 - INFO [main:filetxnsnap...@208] - Snapshotting: 6 [junit] 2009-12-08 19:57:30,478 - INFO [NIOServerCxn.Factory:11225:nioservercnxn$fact...@231] - Accepted socket connection from /127.0.0.1:47788 [junit] 2009-12-08 19:57:30,479 - INFO [NIOServerCxn.Factory:11225:nioserverc...@803] - Processing stat command from /127.0.0.1:47788 [junit] 2009-12-08 19:57:30,479 - INFO [NIOServerCxn.Factory:11225:nioserverc...@989] - Closed socket connection for client /127.0.0.1:47788 (no session established for client) [junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port] [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-12-08 19:57:31,488 - INFO [main-SendThread(localhost:11225):clientcnxn$sendthr...@929] - Opening socket connection to server localhost/127.0.0.1:11225 [junit] 2009-12-08 19:57:31,488 - INFO [NIOServerCxn.Factory:11225:nioservercnxn$fact...@231] - Accepted socket connection from /127.0.0.1:47789 [junit] 2009-12-08 19:57:31,488 - INFO [main-SendThread(localhost:11225):clientcnxn$sendthr...@837] - Socket connection established to localhost/127.0.0.1:11225, initiating session [junit] 2009-12-08 19:57:31,489 - INFO [NIOServerCxn.Factory:11225:nioserverc...@709] - Client attempting to renew session 0x1256fde23440000 at /127.0.0.1:47789 [junit] 2009-12-08 19:57:31,491 - INFO [NIOServerCxn.Factory:11225:nioserverc...@1142] - Established session 0x1256fde23440000 for client /127.0.0.1:47789 [junit] 2009-12-08 19:57:31,491 - INFO [main-SendThread(localhost:11225):clientcnxn$sendthr...@640] - Session establishment complete, sessionid = 0x1256fde23440000 [junit] 2009-12-08 19:57:32,502 - INFO [ProcessThread:-1:preprequestproces...@385] - Processed session termination for sessionid: 0x1256fde23440000 [junit] 2009-12-08 19:57:32,503 - INFO [NIOServerCxn.Factory:11225:nioserverc...@989] - Closed socket connection for client /127.0.0.1:47789 which had sessionid 0x1256fde23440000 [junit] 2009-12-08 19:57:32,503 - INFO [main:zookee...@524] - Session: 0x1256fde23440000 closed [junit] 2009-12-08 19:57:32,503 - INFO [main:clientb...@408] - tearDown starting [junit] 2009-12-08 19:57:32,504 - INFO [main:clientb...@420] - fdcount after test is: 38 [junit] 2009-12-08 19:57:32,504 - INFO [main:clientb...@385] - STOPPING server [junit] 2009-12-08 19:57:32,505 - INFO [NIOServerCxn.Factory:11225:nioservercnxn$fact...@258] - NIOServerCnxn factory exited run method [junit] 2009-12-08 19:57:32,505 - INFO [main:finalrequestproces...@365] - shutdown of request processor complete [junit] 2009-12-08 19:57:32,505 - INFO [ProcessThread:-1:preprequestproces...@119] - PrepRequestProcessor exited loop! [junit] 2009-12-08 19:57:32,505 - INFO [SyncThread:0:syncrequestproces...@151] - SyncRequestProcessor exited! [junit] ensureOnly:[] [junit] 2009-12-08 19:57:32,527 - INFO [main:clientb...@439] - FINISHED testWatcherAutoResetDisabledWithGlobal [junit] 2009-12-08 19:57:32,528 - INFO [main:clientb...@349] - STARTING testWatcherAutoResetDisabledWithLocal [junit] 2009-12-08 19:57:32,533 - INFO [main:clientb...@378] - STARTING server [junit] 2009-12-08 19:57:32,534 - INFO [main:zookeeperser...@160] - Created server [junit] 2009-12-08 19:57:32,534 - INFO [main:nioservercnxn$fact...@140] - binding to port 11226 [junit] 2009-12-08 19:57:32,537 - INFO [main:filetxnsnap...@208] - Snapshotting: 0 [junit] 2009-12-08 19:57:32,539 - INFO [NIOServerCxn.Factory:11226:nioservercnxn$fact...@231] - Accepted socket connection from /127.0.0.1:35244 [junit] 2009-12-08 19:57:32,539 - INFO [NIOServerCxn.Factory:11226:nioserverc...@803] - Processing stat command from /127.0.0.1:35244 [junit] 2009-12-08 19:57:32,539 - INFO [NIOServerCxn.Factory:11226:nioserverc...@989] - Closed socket connection for client /127.0.0.1:35244 (no session established for client) [junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port] [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-12-08 19:57:32,541 - INFO [main:clientb...@370] - Initial fdcount is: 38 [junit] 2009-12-08 19:57:32,541 - INFO [main:clientb...@374] - Client test setup finished [junit] 2009-12-08 19:57:32,541 - INFO [main:zookee...@372] - Initiating client connection, connectString=127.0.0.1:11226 sessionTimeout=5000 watcher=org.apache.zookeeper.test.watchertest$mywatc...@1f94884 [junit] 2009-12-08 19:57:32,542 - INFO [main-SendThread():clientcnxn$sendthr...@929] - Opening socket connection to server /127.0.0.1:11226 [junit] 2009-12-08 19:57:32,543 - INFO [NIOServerCxn.Factory:11226:nioservercnxn$fact...@231] - Accepted socket connection from /127.0.0.1:35245 [junit] 2009-12-08 19:57:32,543 - INFO [main-SendThread(localhost:11226):clientcnxn$sendthr...@837] - Socket connection established to localhost/127.0.0.1:11226, initiating session [junit] 2009-12-08 19:57:32,543 - INFO [NIOServerCxn.Factory:11226:nioserverc...@716] - Client attempting to establish new session at /127.0.0.1:35245 [junit] 2009-12-08 19:57:32,549 - INFO [SyncThread:0:nioserverc...@1142] - Established session 0x1256fde39f90000 for client /127.0.0.1:35245 [junit] 2009-12-08 19:57:32,549 - INFO [main-SendThread(localhost:11226):clientcnxn$sendthr...@640] - Session establishment complete, sessionid = 0x1256fde39f90000 [junit] expect:0x1256fde39f90000 [junit] found:0x1256fde39f90000 org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x1256fde39f90000 [junit] 2009-12-08 19:57:32,559 - INFO [main:clientb...@385] - STOPPING server [junit] 2009-12-08 19:57:32,560 - INFO [main:nioserverc...@989] - Closed socket connection for client /127.0.0.1:35245 which had sessionid 0x1256fde39f90000 [junit] 2009-12-08 19:57:32,560 - INFO [main-SendThread(localhost:11226):clientcnxn$sendthr...@1047] - Unable to read additional data from server sessionid 0x1256fde39f90000, likely server has closed socket, closing socket connection and attempting reconnect [junit] 2009-12-08 19:57:32,561 - INFO [NIOServerCxn.Factory:11226:nioservercnxn$fact...@258] - NIOServerCnxn factory exited run method [junit] 2009-12-08 19:57:32,568 - INFO [main:finalrequestproces...@365] - shutdown of request processor complete [junit] 2009-12-08 19:57:32,568 - INFO [SyncThread:0:syncrequestproces...@151] - SyncRequestProcessor exited! [junit] 2009-12-08 19:57:32,568 - INFO [ProcessThread:-1:preprequestproces...@119] - PrepRequestProcessor exited loop! [junit] ensureOnly:[] [junit] 2009-12-08 19:57:32,661 - INFO [main:clientb...@378] - STARTING server [junit] 2009-12-08 19:57:32,661 - INFO [main:zookeeperser...@160] - Created server [junit] 2009-12-08 19:57:32,661 - INFO [main:nioservercnxn$fact...@140] - binding to port 11226 [junit] 2009-12-08 19:57:32,662 - INFO [main:files...@81] - Reading snapshot <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test464937403766719498.junit.dir/version-2/snapshot.0> [junit] 2009-12-08 19:57:32,663 - INFO [main:filetxnsnap...@208] - Snapshotting: 3 [junit] 2009-12-08 19:57:32,666 - INFO [NIOServerCxn.Factory:11226:nioservercnxn$fact...@231] - Accepted socket connection from /127.0.0.1:35247 [junit] 2009-12-08 19:57:32,666 - INFO [NIOServerCxn.Factory:11226:nioserverc...@803] - Processing stat command from /127.0.0.1:35247 [junit] 2009-12-08 19:57:32,667 - INFO [NIOServerCxn.Factory:11226:nioserverc...@989] - Closed socket connection for client /127.0.0.1:35247 (no session established for client) [junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port] [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-12-08 19:57:33,000 - INFO [SessionTracker:sessiontrackeri...@145] - SessionTrackerImpl exited loop! [junit] 2009-12-08 19:57:33,000 - INFO [SessionTracker:sessiontrackeri...@145] - SessionTrackerImpl exited loop! [junit] 2009-12-08 19:57:33,000 - INFO [SessionTracker:sessiontrackeri...@145] - SessionTrackerImpl exited loop! [junit] 2009-12-08 19:57:33,813 - INFO [main-SendThread(localhost:11226):clientcnxn$sendthr...@929] - Opening socket connection to server localhost/127.0.0.1:11226 [junit] 2009-12-08 19:57:33,813 - INFO [main-SendThread(localhost:11226):clientcnxn$sendthr...@837] - Socket connection established to localhost/127.0.0.1:11226, initiating session [junit] 2009-12-08 19:57:33,813 - INFO [NIOServerCxn.Factory:11226:nioservercnxn$fact...@231] - Accepted socket connection from /127.0.0.1:35248 [junit] 2009-12-08 19:57:33,814 - INFO [NIOServerCxn.Factory:11226:nioserverc...@709] - Client attempting to renew session 0x1256fde39f90000 at /127.0.0.1:35248 [junit] 2009-12-08 19:57:33,814 - INFO [NIOServerCxn.Factory:11226:nioserverc...@1142] - Established session 0x1256fde39f90000 for client /127.0.0.1:35248 [junit] 2009-12-08 19:57:33,814 - INFO [main-SendThread(localhost:11226):clientcnxn$sendthr...@640] - Session establishment complete, sessionid = 0x1256fde39f90000 [junit] 2009-12-08 19:57:33,821 - INFO [main:clientb...@385] - STOPPING server [junit] 2009-12-08 19:57:33,822 - INFO [main:nioserverc...@989] - Closed socket connection for client /127.0.0.1:35248 which had sessionid 0x1256fde39f90000 [junit] 2009-12-08 19:57:33,822 - INFO [main-SendThread(localhost:11226):clientcnxn$sendthr...@1047] - Unable to read additional data from server sessionid 0x1256fde39f90000, likely server has closed socket, closing socket connection and attempting reconnect [junit] 2009-12-08 19:57:33,822 - INFO [NIOServerCxn.Factory:11226:nioservercnxn$fact...@258] - NIOServerCnxn factory exited run method [junit] 2009-12-08 19:57:33,823 - INFO [main:finalrequestproces...@365] - shutdown of request processor complete [junit] 2009-12-08 19:57:33,823 - INFO [SyncThread:0:syncrequestproces...@151] - SyncRequestProcessor exited! [junit] 2009-12-08 19:57:33,823 - INFO [ProcessThread:-1:preprequestproces...@119] - PrepRequestProcessor exited loop! [junit] ensureOnly:[] [junit] 2009-12-08 19:57:33,922 - INFO [main:clientb...@378] - STARTING server [junit] 2009-12-08 19:57:33,923 - INFO [main:zookeeperser...@160] - Created server [junit] 2009-12-08 19:57:33,923 - INFO [main:nioservercnxn$fact...@140] - binding to port 11226 [junit] 2009-12-08 19:57:33,924 - INFO [main:files...@81] - Reading snapshot <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test464937403766719498.junit.dir/version-2/snapshot.3> [junit] 2009-12-08 19:57:33,926 - INFO [main:filetxnsnap...@208] - Snapshotting: 5 [junit] 2009-12-08 19:57:33,928 - INFO [NIOServerCxn.Factory:11226:nioservercnxn$fact...@231] - Accepted socket connection from /127.0.0.1:35250 [junit] 2009-12-08 19:57:33,928 - INFO [NIOServerCxn.Factory:11226:nioserverc...@803] - Processing stat command from /127.0.0.1:35250 [junit] 2009-12-08 19:57:33,928 - INFO [NIOServerCxn.Factory:11226:nioserverc...@989] - Closed socket connection for client /127.0.0.1:35250 (no session established for client) [junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port] [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-12-08 19:57:35,560 - INFO [main-SendThread(localhost:11226):clientcnxn$sendthr...@929] - Opening socket connection to server localhost/127.0.0.1:11226 [junit] 2009-12-08 19:57:35,561 - INFO [main-SendThread(localhost:11226):clientcnxn$sendthr...@837] - Socket connection established to localhost/127.0.0.1:11226, initiating session [junit] 2009-12-08 19:57:35,561 - INFO [NIOServerCxn.Factory:11226:nioservercnxn$fact...@231] - Accepted socket connection from /127.0.0.1:35251 [junit] 2009-12-08 19:57:35,561 - INFO [NIOServerCxn.Factory:11226:nioserverc...@709] - Client attempting to renew session 0x1256fde39f90000 at /127.0.0.1:35251 [junit] 2009-12-08 19:57:35,562 - INFO [NIOServerCxn.Factory:11226:nioserverc...@1142] - Established session 0x1256fde39f90000 for client /127.0.0.1:35251 [junit] 2009-12-08 19:57:35,562 - INFO [main-SendThread(localhost:11226):clientcnxn$sendthr...@640] - Session establishment complete, sessionid = 0x1256fde39f90000 [junit] 2009-12-08 19:57:35,572 - INFO [main:clientb...@385] - STOPPING server [junit] 2009-12-08 19:57:35,573 - INFO [main:nioserverc...@989] - Closed socket connection for client /127.0.0.1:35251 which had sessionid 0x1256fde39f90000 [junit] 2009-12-08 19:57:35,573 - INFO [main-SendThread(localhost:11226):clientcnxn$sendthr...@1047] - Unable to read additional data from server sessionid 0x1256fde39f90000, likely server has closed socket, closing socket connection and attempting reconnect [junit] 2009-12-08 19:57:35,573 - INFO [NIOServerCxn.Factory:11226:nioservercnxn$fact...@258] - NIOServerCnxn factory exited run method [junit] 2009-12-08 19:57:35,574 - INFO [main:finalrequestproces...@365] - shutdown of request processor complete [junit] 2009-12-08 19:57:35,574 - INFO [SyncThread:0:syncrequestproces...@151] - SyncRequestProcessor exited! [junit] 2009-12-08 19:57:35,574 - INFO [ProcessThread:-1:preprequestproces...@119] - PrepRequestProcessor exited loop! [junit] ensureOnly:[] [junit] 2009-12-08 19:57:35,673 - INFO [main:clientb...@378] - STARTING server [junit] 2009-12-08 19:57:35,674 - INFO [main:zookeeperser...@160] - Created server [junit] 2009-12-08 19:57:35,674 - INFO [main:nioservercnxn$fact...@140] - binding to port 11226 [junit] 2009-12-08 19:57:35,675 - INFO [main:files...@81] - Reading snapshot <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build/test/tmp/test464937403766719498.junit.dir/version-2/snapshot.5> [junit] 2009-12-08 19:57:35,678 - INFO [main:filetxnsnap...@208] - Snapshotting: 6 [junit] 2009-12-08 19:57:35,679 - INFO [NIOServerCxn.Factory:11226:nioservercnxn$fact...@231] - Accepted socket connection from /127.0.0.1:35253 [junit] 2009-12-08 19:57:35,679 - INFO [NIOServerCxn.Factory:11226:nioserverc...@803] - Processing stat command from /127.0.0.1:35253 [junit] 2009-12-08 19:57:35,680 - INFO [NIOServerCxn.Factory:11226:nioserverc...@989] - Closed socket connection for client /127.0.0.1:35253 (no session established for client) [junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port] [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-12-08 19:57:36,000 - INFO [SessionTracker:sessiontrackeri...@145] - SessionTrackerImpl exited loop! [junit] 2009-12-08 19:57:36,000 - INFO [SessionTracker:sessiontrackeri...@145] - SessionTrackerImpl exited loop! [junit] 2009-12-08 19:57:36,895 - INFO [main-SendThread(localhost:11226):clientcnxn$sendthr...@929] - Opening socket connection to server localhost/127.0.0.1:11226 [junit] 2009-12-08 19:57:36,896 - INFO [main-SendThread(localhost:11226):clientcnxn$sendthr...@837] - Socket connection established to localhost/127.0.0.1:11226, initiating session [junit] 2009-12-08 19:57:36,896 - INFO [NIOServerCxn.Factory:11226:nioservercnxn$fact...@231] - Accepted socket connection from /127.0.0.1:35254 [junit] 2009-12-08 19:57:36,896 - INFO [NIOServerCxn.Factory:11226:nioserverc...@709] - Client attempting to renew session 0x1256fde39f90000 at /127.0.0.1:35254 [junit] 2009-12-08 19:57:36,897 - INFO [NIOServerCxn.Factory:11226:nioserverc...@1142] - Established session 0x1256fde39f90000 for client /127.0.0.1:35254 [junit] 2009-12-08 19:57:36,897 - INFO [main-SendThread(localhost:11226):clientcnxn$sendthr...@640] - Session establishment complete, sessionid = 0x1256fde39f90000 [junit] 2009-12-08 19:57:37,905 - INFO [ProcessThread:-1:preprequestproces...@385] - Processed session termination for sessionid: 0x1256fde39f90000 [junit] 2009-12-08 19:57:37,906 - INFO [main:zookee...@524] - Session: 0x1256fde39f90000 closed [junit] 2009-12-08 19:57:37,907 - INFO [main:clientb...@408] - tearDown starting [junit] 2009-12-08 19:57:37,907 - INFO [NIOServerCxn.Factory:11226:nioserverc...@989] - Closed socket connection for client /127.0.0.1:35254 which had sessionid 0x1256fde39f90000 [junit] 2009-12-08 19:57:37,907 - INFO [main:clientb...@420] - fdcount after test is: 43 [junit] 2009-12-08 19:57:37,907 - INFO [main:clientb...@385] - STOPPING server [junit] 2009-12-08 19:57:37,908 - INFO [NIOServerCxn.Factory:11226:nioservercnxn$fact...@258] - NIOServerCnxn factory exited run method [junit] 2009-12-08 19:57:37,908 - INFO [main:finalrequestproces...@365] - shutdown of request processor complete [junit] 2009-12-08 19:57:37,908 - INFO [SyncThread:0:syncrequestproces...@151] - SyncRequestProcessor exited! [junit] 2009-12-08 19:57:37,908 - INFO [ProcessThread:-1:preprequestproces...@119] - PrepRequestProcessor exited loop! [junit] ensureOnly:[] [junit] 2009-12-08 19:57:37,912 - INFO [main:clientb...@439] - FINISHED testWatcherAutoResetDisabledWithLocal [junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 27.003 sec [junit] 2009-12-08 19:57:38,570 - INFO [main:portassignm...@31] - assigning port 11221 [junit] Running org.apache.zookeeper.test.ZooKeeperQuotaTest [junit] 2009-12-08 19:57:38,618 - INFO [main:clientb...@349] - STARTING testQuota [junit] 2009-12-08 19:57:38,772 - INFO [main:clientb...@378] - STARTING server [junit] 2009-12-08 19:57:38,780 - INFO [main:environm...@97] - Server environment:zookeeper.version=3.3.0-888545, built on 12/08/2009 19:45 GMT [junit] 2009-12-08 19:57:38,781 - INFO [main:environm...@97] - Server environment:host.name=h8.grid.sp2.yahoo.net [junit] 2009-12-08 19:57:38,782 - INFO [main:environm...@97] - Server environment:java.version=1.6.0_11 [junit] 2009-12-08 19:57:38,782 - INFO [main:environm...@97] - Server environment:java.vendor=Sun Microsystems Inc. [junit] 2009-12-08 19:57:38,783 - INFO [main:environm...@97] - Server environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre [junit] 2009-12-08 19:57:38,784 - 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-12-08 19:57:38,784 - 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-12-08 19:57:38,785 - INFO [main:environm...@97] - Server environment:java.io.tmpdir=/tmp [junit] 2009-12-08 19:57:38,786 - INFO [main:environm...@97] - Server environment:java.compiler=<NA> [junit] 2009-12-08 19:57:38,786 - INFO [main:environm...@97] - Server environment:os.name=Linux [junit] 2009-12-08 19:57:38,787 - INFO [main:environm...@97] - Server environment:os.arch=i386 [junit] 2009-12-08 19:57:38,787 - INFO [main:environm...@97] - Server environment:os.version=2.6.28-15-generic [junit] 2009-12-08 19:57:38,788 - INFO [main:environm...@97] - Server environment:user.name=hudson [junit] 2009-12-08 19:57:38,788 - INFO [main:environm...@97] - Server environment:user.home=/homes/hudson [junit] 2009-12-08 19:57:38,789 - INFO [main:environm...@97] - Server environment:user.dir=<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk> [junit] 2009-12-08 19:57:38,793 - INFO [main:zookeeperser...@160] - Created server [junit] 2009-12-08 19:57:38,810 - INFO [main:nioservercnxn$fact...@140] - binding to port 11221 [junit] 2009-12-08 19:57:38,824 - INFO [main:filetxnsnap...@208] - Snapshotting: 0 [junit] 2009-12-08 19:57:38,849 - INFO [NIOServerCxn.Factory:11221:nioservercnxn$fact...@231] - Accepted socket connection from /127.0.0.1:54682 [junit] 2009-12-08 19:57:38,852 - INFO [NIOServerCxn.Factory:11221:nioserverc...@803] - Processing stat command from /127.0.0.1:54682 [junit] 2009-12-08 19:57:38,853 - INFO [NIOServerCxn.Factory:11221:nioserverc...@989] - Closed socket connection for client /127.0.0.1:54682 (no session established for client) [junit] ensureOnly:[InMemoryDataTree, StandaloneServer_port] [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-12-08 19:57:38,870 - INFO [main:clientb...@370] - Initial fdcount is: 31 [junit] 2009-12-08 19:57:38,870 - INFO [main:clientb...@374] - Client test setup finished [junit] 2009-12-08 19:57:38,875 - INFO [main:environm...@97] - Client environment:zookeeper.version=3.3.0-888545, built on 12/08/2009 19:45 GMT [junit] 2009-12-08 19:57:38,876 - INFO [main:environm...@97] - Client environment:host.name=h8.grid.sp2.yahoo.net [junit] 2009-12-08 19:57:38,876 - INFO [main:environm...@97] - Client environment:java.version=1.6.0_11 [junit] 2009-12-08 19:57:38,876 - INFO [main:environm...@97] - Client environment:java.vendor=Sun Microsystems Inc. [junit] 2009-12-08 19:57:38,877 - INFO [main:environm...@97] - Client environment:java.home=/homes/hudson/tools/java/jdk1.6.0_11-32/jre [junit] 2009-12-08 19:57:38,877 - 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-12-08 19:57:38,878 - 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-12-08 19:57:38,878 - INFO [main:environm...@97] - Client environment:java.io.tmpdir=/tmp [junit] 2009-12-08 19:57:38,879 - INFO [main:environm...@97] - Client environment:java.compiler=<NA> [junit] 2009-12-08 19:57:38,879 - INFO [main:environm...@97] - Client environment:os.name=Linux [junit] 2009-12-08 19:57:38,880 - INFO [main:environm...@97] - Client environment:os.arch=i386 [junit] 2009-12-08 19:57:38,880 - INFO [main:environm...@97] - Client environment:os.version=2.6.28-15-generic [junit] 2009-12-08 19:57:38,881 - INFO [main:environm...@97] - Client environment:user.name=hudson [junit] 2009-12-08 19:57:38,881 - INFO [main:environm...@97] - Client environment:user.home=/homes/hudson [junit] 2009-12-08 19:57:38,882 - INFO [main:environm...@97] - Client environment:user.dir=<http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk> [junit] 2009-12-08 19:57:38,883 - INFO [main:zookee...@372] - Initiating client connection, connectString=127.0.0.1:11221 sessionTimeout=30000 watcher=org.apache.zookeeper.test.clientbase$countdownwatc...@15ed659 [junit] 2009-12-08 19:57:38,894 - INFO [main-SendThread():clientcnxn$sendthr...@929] - Opening socket connection to server /127.0.0.1:11221 [junit] 2009-12-08 19:57:38,895 - INFO [NIOServerCxn.Factory:11221:nioservercnxn$fact...@231] - Accepted socket connection from /127.0.0.1:54683 [junit] 2009-12-08 19:57:38,895 - INFO [main-SendThread(localhost:11221):clientcnxn$sendthr...@837] - Socket connection established to localhost/127.0.0.1:11221, initiating session [junit] 2009-12-08 19:57:38,898 - INFO [NIOServerCxn.Factory:11221:nioserverc...@716] - Client attempting to establish new session at /127.0.0.1:54683 [junit] 2009-12-08 19:57:38,921 - INFO [SyncThread:0:nioserverc...@1142] - Established session 0x1256fde528f0000 for client /127.0.0.1:54683 [junit] 2009-12-08 19:57:38,922 - INFO [main-SendThread(localhost:11221):clientcnxn$sendthr...@640] - Session establishment complete, sessionid = 0x1256fde528f0000 [junit] expect:0x1256fde528f0000 [junit] found:0x1256fde528f0000 org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x1256fde528f0000 [junit] 2009-12-08 19:57:38,968 - INFO [main:clientb...@408] - tearDown starting [junit] 2009-12-08 19:57:38,968 - INFO [main:clientb...@420] - fdcount after test is: 37 [junit] 2009-12-08 19:57:38,969 - INFO [ProcessThread:-1:preprequestproces...@385] - Processed session termination for sessionid: 0x1256fde528f0000 [junit] 2009-12-08 19:57:38,970 - INFO [main:zookee...@524] - Session: 0x1256fde528f0000 closed [junit] 2009-12-08 19:57:38,970 - INFO [main:clientb...@385] - STOPPING server [junit] 2009-12-08 19:57:38,971 - INFO [NIOServerCxn.Factory:11221:nioserverc...@989] - Closed socket connection for client /127.0.0.1:54683 which had sessionid 0x1256fde528f0000 [junit] 2009-12-08 19:57:38,971 - INFO [NIOServerCxn.Factory:11221:nioservercnxn$fact...@258] - NIOServerCnxn factory exited run method [junit] 2009-12-08 19:57:38,972 - INFO [main:finalrequestproces...@365] - shutdown of request processor complete [junit] 2009-12-08 19:57:38,972 - INFO [ProcessThread:-1:preprequestproces...@119] - PrepRequestProcessor exited loop! [junit] 2009-12-08 19:57:38,972 - INFO [SyncThread:0:syncrequestproces...@151] - SyncRequestProcessor exited! [junit] ensureOnly:[] [junit] 2009-12-08 19:57:38,981 - INFO [main:clientb...@439] - FINISHED testQuota [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.409 sec [junit] 2009-12-08 19:57:39,000 - INFO [SessionTracker:sessiontrackeri...@145] - SessionTrackerImpl exited loop! BUILD FAILED <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:879: The following error occurred while executing this line: <http://hudson.zones.apache.org/hudson/job/ZooKeeper-trunk/ws/trunk/build.xml>:790: Tests failed! Total time: 11 minutes 48 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