You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@zookeeper.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2014/07/18 12:25:11 UTC
ZooKeeper-trunk-WinVS2008_java - Build # 778 - Still Failing
See https://builds.apache.org/job/ZooKeeper-trunk-WinVS2008_java/778/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 259818 lines...]
[junit] 2014-07-18 11:21:30,614 [myid:] - INFO [main:NIOServerCnxnFactory@683] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2014-07-18 11:21:30,615 [myid:] - INFO [main:ClientBase@339] - STARTING server instance 127.0.0.1:11221
[junit] 2014-07-18 11:21:30,615 [myid:] - INFO [main:ZooKeeperServer@781] - minSessionTimeout set to 6000
[junit] 2014-07-18 11:21:30,615 [myid:] - INFO [main:ZooKeeperServer@790] - maxSessionTimeout set to 60000
[junit] 2014-07-18 11:21:30,616 [myid:] - INFO [main:ZooKeeperServer@152] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test7705430176105086646.junit.dir\version-2 snapdir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test7705430176105086646.junit.dir\version-2
[junit] 2014-07-18 11:21:30,617 [myid:] - INFO [main:FileSnap@83] - Reading snapshot f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test7705430176105086646.junit.dir\version-2\snapshot.b
[junit] 2014-07-18 11:21:30,620 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test7705430176105086646.junit.dir\version-2\snapshot.b
[junit] 2014-07-18 11:21:30,622 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-18 11:21:30,623 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:60371
[junit] 2014-07-18 11:21:30,624 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:60371
[junit] 2014-07-18 11:21:30,624 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-07-18 11:21:30,624 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:60371 (no session established for client)
[junit] 2014-07-18 11:21:30,625 [myid:] - INFO [main:JMXEnv@224] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2014-07-18 11:21:30,626 [myid:] - INFO [main:JMXEnv@241] - expect:InMemoryDataTree
[junit] 2014-07-18 11:21:30,627 [myid:] - INFO [main:JMXEnv@245] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2014-07-18 11:21:30,627 [myid:] - INFO [main:JMXEnv@241] - expect:StandaloneServer_port
[junit] 2014-07-18 11:21:30,627 [myid:] - INFO [main:JMXEnv@245] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2014-07-18 11:21:30,627 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@55] - Memory used 10368
[junit] 2014-07-18 11:21:30,627 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@60] - Number of threads 22
[junit] 2014-07-18 11:21:30,628 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@65] - FINISHED TEST METHOD testQuota
[junit] 2014-07-18 11:21:30,628 [myid:] - INFO [main:ClientBase@520] - tearDown starting
[junit] 2014-07-18 11:21:31,036 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@963] - Socket connection established to 127.0.0.1/127.0.0.1:11221, initiating session
[junit] 2014-07-18 11:21:31,037 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:60366
[junit] 2014-07-18 11:21:31,038 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@877] - Client attempting to renew session 0x14748fda3b00000 at /127.0.0.1:60366
[junit] 2014-07-18 11:21:31,038 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@619] - Established session 0x14748fda3b00000 with negotiated timeout 30000 for client /127.0.0.1:60366
[junit] 2014-07-18 11:21:31,039 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@1346] - Session establishment complete on server 127.0.0.1/127.0.0.1:11221, sessionid = 0x14748fda3b00000, negotiated timeout = 30000
[junit] 2014-07-18 11:21:31,040 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@685] - Processed session termination for sessionid: 0x14748fda3b00000
[junit] 2014-07-18 11:21:31,040 [myid:] - INFO [SyncThread:0:FileTxnLog@200] - Creating new log file: log.c
[junit] 2014-07-18 11:21:31,087 [myid:] - INFO [NIOWorkerThread-1:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x14748fda3b00000]
[junit] 2014-07-18 11:21:31,087 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:60366 which had sessionid 0x14748fda3b00000
[junit] 2014-07-18 11:21:31,098 [myid:] - INFO [main:ZooKeeper@968] - Session: 0x14748fda3b00000 closed
[junit] 2014-07-18 11:21:31,098 [myid:] - INFO [main:ClientBase@490] - STOPPING server
[junit] 2014-07-18 11:21:31,099 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-07-18 11:21:31,099 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-07-18 11:21:31,099 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2014-07-18 11:21:31,100 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2014-07-18 11:21:31,100 [myid:] - INFO [main:ZooKeeperServer@443] - shutting down
[junit] 2014-07-18 11:21:31,100 [myid:] - INFO [main:SessionTrackerImpl@205] - Shutting down
[junit] 2014-07-18 11:21:31,101 [myid:] - INFO [main:PrepRequestProcessor@981] - Shutting down
[junit] 2014-07-18 11:21:31,101 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-07-18 11:21:31,101 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-07-18 11:21:31,121 [myid:] - INFO [main:FinalRequestProcessor@476] - shutdown of request processor complete
[junit] 2014-07-18 11:21:31,121 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree]
[junit] 2014-07-18 11:21:31,121 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1]
[junit] 2014-07-18 11:21:31,122 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-18 11:21:31,134 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-07-18 11:21:32,115 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-07-18 11:21:32,120 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2014-07-18 11:21:32,120 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 4.743 sec
BUILD FAILED
f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build.xml:1337: Tests failed!
Total time: 68 minutes 34 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
2 tests failed.
FAILED: org.apache.zookeeper.server.admin.CommandsTest.testMonitor
Error Message:
Result from command monitor missing field "open_file_descriptor_count"
{}
Stack Trace:
junit.framework.AssertionFailedError: Result from command monitor missing field "open_file_descriptor_count"
{}
at org.apache.zookeeper.server.admin.CommandsTest.testCommand(CommandsTest.java:65)
at org.apache.zookeeper.server.admin.CommandsTest.testCommand(CommandsTest.java:81)
at org.apache.zookeeper.server.admin.CommandsTest.testMonitor(CommandsTest.java:161)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.TruncateTest.testTruncationNullLog
Error Message:
Should not get here
Stack Trace:
junit.framework.AssertionFailedError: Should not get here
at org.apache.zookeeper.test.TruncateTest.testTruncationNullLog(TruncateTest.java:132)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
ZooKeeper-trunk-WinVS2008_java - Build # 790 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk-WinVS2008_java/790/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 304388 lines...]
[junit] 2014-08-01 02:39:05,816 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:51144
[junit] 2014-08-01 02:39:05,818 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@877] - Client attempting to renew session 0x1478f385be50000 at /127.0.0.1:51144
[junit] 2014-08-01 02:39:05,818 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@619] - Established session 0x1478f385be50000 with negotiated timeout 30000 for client /127.0.0.1:51144
[junit] 2014-08-01 02:39:05,826 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@1346] - Session establishment complete on server 127.0.0.1/127.0.0.1:11221, sessionid = 0x1478f385be50000, negotiated timeout = 30000
[junit] 2014-08-01 02:39:05,858 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@676] - Processed session termination for sessionid: 0x1478f385be50000
[junit] 2014-08-01 02:39:05,858 [myid:] - INFO [SyncThread:0:FileTxnLog@200] - Creating new log file: log.c
[junit] 2014-08-01 02:39:05,885 [myid:] - INFO [main:ZooKeeper@968] - Session: 0x1478f385be50000 closed
[junit] 2014-08-01 02:39:05,885 [myid:] - INFO [main:ClientBase@490] - STOPPING server
[junit] 2014-08-01 02:39:05,886 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-08-01 02:39:05,962 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2014-08-01 02:39:05,968 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2014-08-01 02:39:05,972 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-08-01 02:39:05,972 [myid:] - INFO [NIOWorkerThread-1:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x1478f385be50000]
[junit] 2014-08-01 02:39:05,973 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:51144 which had sessionid 0x1478f385be50000
[junit] 2014-08-01 02:39:05,979 [myid:] - INFO [main:ZooKeeperServer@443] - shutting down
[junit] 2014-08-01 02:39:05,979 [myid:] - INFO [main:SessionTrackerImpl@231] - Shutting down
[junit] 2014-08-01 02:39:05,979 [myid:] - INFO [main:PrepRequestProcessor@973] - Shutting down
[junit] 2014-08-01 02:39:05,979 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-08-01 02:39:05,983 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-08-01 02:39:05,983 [myid:] - INFO [main:FinalRequestProcessor@476] - shutdown of request processor complete
[junit] 2014-08-01 02:39:05,983 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree]
[junit] 2014-08-01 02:39:05,983 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1]
[junit] 2014-08-01 02:39:05,984 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-08-01 02:39:05,984 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-08-01 02:39:06,011 [myid:] - INFO [SessionTracker:SessionTrackerImpl@157] - SessionTrackerImpl exited loop!
[junit] 2014-08-01 02:39:06,011 [myid:] - INFO [SessionTracker:SessionTrackerImpl@157] - SessionTrackerImpl exited loop!
[junit] 2014-08-01 02:39:06,011 [myid:] - INFO [SessionTracker:SessionTrackerImpl@157] - SessionTrackerImpl exited loop!
[junit] 2014-08-01 02:39:06,975 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-08-01 02:39:06,980 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2014-08-01 02:39:06,980 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 6.319 sec
BUILD FAILED
f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build.xml:1333: Tests failed!
Total time: 114 minutes 3 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
ERROR: Publisher hudson.tasks.junit.JUnitResultArchiver aborted due to exception
java.lang.InterruptedException
at java.lang.Object.wait(Native Method)
at hudson.remoting.Request.call(Request.java:146)
at hudson.remoting.Channel.call(Channel.java:722)
at hudson.FilePath.act(FilePath.java:903)
at hudson.FilePath.act(FilePath.java:887)
at hudson.tasks.junit.JUnitParser.parse(JUnitParser.java:87)
at hudson.tasks.junit.JUnitResultArchiver.parse(JUnitResultArchiver.java:121)
at hudson.tasks.junit.JUnitResultArchiver.perform(JUnitResultArchiver.java:133)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:804)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:776)
at hudson.model.Build$BuildExecution.post2(Build.java:183)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:725)
at hudson.model.Run.execute(Run.java:1701)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:88)
at hudson.model.Executor.run(Executor.java:231)
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
No tests ran.
ZooKeeper-trunk-WinVS2008_java - Build # 789 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk-WinVS2008_java/789/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 303811 lines...]
[junit] 2014-07-29 11:28:34,463 [myid:] - INFO [main:NIOServerCnxnFactory@683] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2014-07-29 11:28:34,464 [myid:] - INFO [main:ClientBase@339] - STARTING server instance 127.0.0.1:11221
[junit] 2014-07-29 11:28:34,464 [myid:] - INFO [main:ZooKeeperServer@781] - minSessionTimeout set to 6000
[junit] 2014-07-29 11:28:34,464 [myid:] - INFO [main:ZooKeeperServer@790] - maxSessionTimeout set to 60000
[junit] 2014-07-29 11:28:34,464 [myid:] - INFO [main:ZooKeeperServer@152] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test5239697535229958945.junit.dir\version-2 snapdir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test5239697535229958945.junit.dir\version-2
[junit] 2014-07-29 11:28:34,465 [myid:] - INFO [main:FileSnap@83] - Reading snapshot f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test5239697535229958945.junit.dir\version-2\snapshot.b
[junit] 2014-07-29 11:28:34,467 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test5239697535229958945.junit.dir\version-2\snapshot.b
[junit] 2014-07-29 11:28:34,469 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-29 11:28:34,470 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:60802
[junit] 2014-07-29 11:28:34,471 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:60802
[junit] 2014-07-29 11:28:34,471 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-07-29 11:28:34,471 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:60802 (no session established for client)
[junit] 2014-07-29 11:28:34,472 [myid:] - INFO [main:JMXEnv@224] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2014-07-29 11:28:34,473 [myid:] - INFO [main:JMXEnv@241] - expect:InMemoryDataTree
[junit] 2014-07-29 11:28:34,473 [myid:] - INFO [main:JMXEnv@245] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2014-07-29 11:28:34,474 [myid:] - INFO [main:JMXEnv@241] - expect:StandaloneServer_port
[junit] 2014-07-29 11:28:34,474 [myid:] - INFO [main:JMXEnv@245] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2014-07-29 11:28:34,474 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@55] - Memory used 13512
[junit] 2014-07-29 11:28:34,474 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@60] - Number of threads 23
[junit] 2014-07-29 11:28:34,474 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@65] - FINISHED TEST METHOD testQuota
[junit] 2014-07-29 11:28:34,475 [myid:] - INFO [main:ClientBase@520] - tearDown starting
[junit] 2014-07-29 11:28:34,583 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@963] - Socket connection established to 127.0.0.1/127.0.0.1:11221, initiating session
[junit] 2014-07-29 11:28:34,583 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:60795
[junit] 2014-07-29 11:28:34,587 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@877] - Client attempting to renew session 0x14781aa0dd90000 at /127.0.0.1:60795
[junit] 2014-07-29 11:28:34,588 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@619] - Established session 0x14781aa0dd90000 with negotiated timeout 30000 for client /127.0.0.1:60795
[junit] 2014-07-29 11:28:34,589 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@1346] - Session establishment complete on server 127.0.0.1/127.0.0.1:11221, sessionid = 0x14781aa0dd90000, negotiated timeout = 30000
[junit] 2014-07-29 11:28:34,589 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@676] - Processed session termination for sessionid: 0x14781aa0dd90000
[junit] 2014-07-29 11:28:34,590 [myid:] - INFO [SyncThread:0:FileTxnLog@200] - Creating new log file: log.c
[junit] 2014-07-29 11:28:34,605 [myid:] - INFO [main:ZooKeeper@968] - Session: 0x14781aa0dd90000 closed
[junit] 2014-07-29 11:28:34,606 [myid:] - INFO [main:ClientBase@490] - STOPPING server
[junit] 2014-07-29 11:28:34,606 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-07-29 11:28:34,606 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2014-07-29 11:28:34,606 [myid:] - INFO [NIOWorkerThread-1:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x14781aa0dd90000]
[junit] 2014-07-29 11:28:34,607 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:60795 which had sessionid 0x14781aa0dd90000
[junit] 2014-07-29 11:28:34,607 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-07-29 11:28:34,607 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2014-07-29 11:28:34,607 [myid:] - INFO [main:ZooKeeperServer@443] - shutting down
[junit] 2014-07-29 11:28:34,608 [myid:] - INFO [main:SessionTrackerImpl@231] - Shutting down
[junit] 2014-07-29 11:28:34,608 [myid:] - INFO [main:PrepRequestProcessor@973] - Shutting down
[junit] 2014-07-29 11:28:34,608 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-07-29 11:28:34,608 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-07-29 11:28:34,608 [myid:] - INFO [main:FinalRequestProcessor@476] - shutdown of request processor complete
[junit] 2014-07-29 11:28:34,608 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree]
[junit] 2014-07-29 11:28:34,609 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1]
[junit] 2014-07-29 11:28:34,609 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-29 11:28:34,610 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-07-29 11:28:35,609 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-07-29 11:28:35,614 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2014-07-29 11:28:35,614 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 4.774 sec
BUILD FAILED
f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build.xml:1333: Tests failed!
Total time: 76 minutes 36 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
4 tests failed.
REGRESSION: org.apache.zookeeper.test.ReconfigTest.testRemoveAddTwo
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.reconfig(ReconfigTest.java:81)
at org.apache.zookeeper.test.ReconfigTest.testRemoveAddTwo(ReconfigTest.java:327)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch
Error Message:
First leader election failed
Stack Trace:
junit.framework.AssertionFailedError: First leader election failed
at org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch(FLENewEpochTest.java:175)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment
Error Message:
Not following
Stack Trace:
junit.framework.AssertionFailedError: Not following
at org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment(QuorumTest.java:408)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.TruncateTest.testTruncationNullLog
Error Message:
Should not get here
Stack Trace:
junit.framework.AssertionFailedError: Should not get here
at org.apache.zookeeper.test.TruncateTest.testTruncationNullLog(TruncateTest.java:132)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
ZooKeeper-trunk-WinVS2008_java - Build # 788 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk-WinVS2008_java/788/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 294050 lines...]
[junit] 2014-07-28 11:28:59,701 [myid:] - INFO [main:ZooKeeperServer@781] - minSessionTimeout set to 6000
[junit] 2014-07-28 11:28:59,701 [myid:] - INFO [main:ZooKeeperServer@790] - maxSessionTimeout set to 60000
[junit] 2014-07-28 11:28:59,701 [myid:] - INFO [main:ZooKeeperServer@152] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test6206424874482215211.junit.dir\version-2 snapdir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test6206424874482215211.junit.dir\version-2
[junit] 2014-07-28 11:28:59,702 [myid:] - INFO [main:FileSnap@83] - Reading snapshot f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test6206424874482215211.junit.dir\version-2\snapshot.b
[junit] 2014-07-28 11:28:59,704 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test6206424874482215211.junit.dir\version-2\snapshot.b
[junit] 2014-07-28 11:28:59,707 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-28 11:28:59,708 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:51783
[junit] 2014-07-28 11:28:59,709 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:51783
[junit] 2014-07-28 11:28:59,709 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-07-28 11:28:59,709 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:51783 (no session established for client)
[junit] 2014-07-28 11:28:59,710 [myid:] - INFO [main:JMXEnv@224] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2014-07-28 11:28:59,711 [myid:] - INFO [main:JMXEnv@241] - expect:InMemoryDataTree
[junit] 2014-07-28 11:28:59,711 [myid:] - INFO [main:JMXEnv@245] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2014-07-28 11:28:59,711 [myid:] - INFO [main:JMXEnv@241] - expect:StandaloneServer_port
[junit] 2014-07-28 11:28:59,712 [myid:] - INFO [main:JMXEnv@245] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2014-07-28 11:28:59,712 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@55] - Memory used 10528
[junit] 2014-07-28 11:28:59,712 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@60] - Number of threads 24
[junit] 2014-07-28 11:28:59,712 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@65] - FINISHED TEST METHOD testQuota
[junit] 2014-07-28 11:28:59,713 [myid:] - INFO [main:ClientBase@520] - tearDown starting
[junit] 2014-07-28 11:29:00,000 [myid:] - INFO [SessionTracker:SessionTrackerImpl@157] - SessionTrackerImpl exited loop!
[junit] 2014-07-28 11:29:00,001 [myid:] - INFO [SessionTracker:SessionTrackerImpl@157] - SessionTrackerImpl exited loop!
[junit] 2014-07-28 11:29:00,112 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@963] - Socket connection established to 127.0.0.1/127.0.0.1:11221, initiating session
[junit] 2014-07-28 11:29:00,112 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:51778
[junit] 2014-07-28 11:29:00,142 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@877] - Client attempting to renew session 0x1477c84159f0000 at /127.0.0.1:51778
[junit] 2014-07-28 11:29:00,143 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@619] - Established session 0x1477c84159f0000 with negotiated timeout 30000 for client /127.0.0.1:51778
[junit] 2014-07-28 11:29:00,155 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@1346] - Session establishment complete on server 127.0.0.1/127.0.0.1:11221, sessionid = 0x1477c84159f0000, negotiated timeout = 30000
[junit] 2014-07-28 11:29:00,160 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@676] - Processed session termination for sessionid: 0x1477c84159f0000
[junit] 2014-07-28 11:29:00,161 [myid:] - INFO [SyncThread:0:FileTxnLog@200] - Creating new log file: log.c
[junit] 2014-07-28 11:29:00,298 [myid:] - INFO [NIOWorkerThread-1:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x1477c84159f0000]
[junit] 2014-07-28 11:29:00,299 [myid:] - INFO [main:ZooKeeper@968] - Session: 0x1477c84159f0000 closed
[junit] 2014-07-28 11:29:00,299 [myid:] - INFO [main:ClientBase@490] - STOPPING server
[junit] 2014-07-28 11:29:00,300 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:51778 which had sessionid 0x1477c84159f0000
[junit] 2014-07-28 11:29:00,300 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-07-28 11:29:00,300 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2014-07-28 11:29:00,301 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2014-07-28 11:29:00,301 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-07-28 11:29:00,302 [myid:] - INFO [main:ZooKeeperServer@443] - shutting down
[junit] 2014-07-28 11:29:00,302 [myid:] - INFO [main:SessionTrackerImpl@231] - Shutting down
[junit] 2014-07-28 11:29:00,302 [myid:] - INFO [main:PrepRequestProcessor@973] - Shutting down
[junit] 2014-07-28 11:29:00,302 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-07-28 11:29:00,302 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-07-28 11:29:00,302 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-07-28 11:29:00,302 [myid:] - INFO [main:FinalRequestProcessor@476] - shutdown of request processor complete
[junit] 2014-07-28 11:29:00,303 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree]
[junit] 2014-07-28 11:29:00,303 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1]
[junit] 2014-07-28 11:29:00,303 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-28 11:29:01,293 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-07-28 11:29:01,298 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2014-07-28 11:29:01,298 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 5.079 sec
BUILD FAILED
f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build.xml:1333: Tests failed!
Total time: 77 minutes 6 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
4 tests failed.
REGRESSION: org.apache.zookeeper.server.SessionTrackerTest.testAddSessionAfterSessionExpiry
Error Message:
Duplicate session expiry request has been generated expected:<1> but was:<0>
Stack Trace:
junit.framework.AssertionFailedError: Duplicate session expiry request has been generated expected:<1> but was:<0>
at org.apache.zookeeper.server.SessionTrackerTest.testAddSessionAfterSessionExpiry(SessionTrackerTest.java:82)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch
Error Message:
First leader election failed
Stack Trace:
junit.framework.AssertionFailedError: First leader election failed
at org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch(FLENewEpochTest.java:175)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment
Error Message:
Not following
Stack Trace:
junit.framework.AssertionFailedError: Not following
at org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment(QuorumTest.java:408)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.TruncateTest.testTruncationNullLog
Error Message:
Should not get here
Stack Trace:
junit.framework.AssertionFailedError: Should not get here
at org.apache.zookeeper.test.TruncateTest.testTruncationNullLog(TruncateTest.java:132)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
ZooKeeper-trunk-WinVS2008_java - Build # 787 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk-WinVS2008_java/787/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 296791 lines...]
[junit] 2014-07-27 11:28:05,092 [myid:] - WARN [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@1213] - Session 0x147775ce4e80000 for server 127.0.0.1/127.0.0.1:11221, unexpected error, closing socket connection and attempting reconnect
[junit] java.net.ConnectException: Connection refused: no further information
[junit] at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit] at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:701)
[junit] at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:356)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1192)
[junit] 2014-07-27 11:28:05,093 [myid:] - INFO [main:NIOServerCnxnFactory@670] - Configuring NIO connection handler with 10s sessionless connection timeout, 1 selector thread(s), 4 worker threads, and 64 kB direct buffers.
[junit] 2014-07-27 11:28:05,096 [myid:] - INFO [main:NIOServerCnxnFactory@683] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2014-07-27 11:28:05,096 [myid:] - INFO [main:ClientBase@339] - STARTING server instance 127.0.0.1:11221
[junit] 2014-07-27 11:28:05,097 [myid:] - INFO [main:ZooKeeperServer@781] - minSessionTimeout set to 6000
[junit] 2014-07-27 11:28:05,097 [myid:] - INFO [main:ZooKeeperServer@790] - maxSessionTimeout set to 60000
[junit] 2014-07-27 11:28:05,097 [myid:] - INFO [main:ZooKeeperServer@152] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test2828521704645163461.junit.dir\version-2 snapdir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test2828521704645163461.junit.dir\version-2
[junit] 2014-07-27 11:28:05,097 [myid:] - INFO [main:FileSnap@83] - Reading snapshot f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test2828521704645163461.junit.dir\version-2\snapshot.b
[junit] 2014-07-27 11:28:05,099 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test2828521704645163461.junit.dir\version-2\snapshot.b
[junit] 2014-07-27 11:28:05,100 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-27 11:28:05,103 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:51877
[junit] 2014-07-27 11:28:05,104 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:51877
[junit] 2014-07-27 11:28:05,104 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-07-27 11:28:05,105 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:51877 (no session established for client)
[junit] 2014-07-27 11:28:05,105 [myid:] - INFO [main:JMXEnv@224] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2014-07-27 11:28:05,107 [myid:] - INFO [main:JMXEnv@241] - expect:InMemoryDataTree
[junit] 2014-07-27 11:28:05,107 [myid:] - INFO [main:JMXEnv@245] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2014-07-27 11:28:05,107 [myid:] - INFO [main:JMXEnv@241] - expect:StandaloneServer_port
[junit] 2014-07-27 11:28:05,107 [myid:] - INFO [main:JMXEnv@245] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2014-07-27 11:28:05,107 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@55] - Memory used 13380
[junit] 2014-07-27 11:28:05,108 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@60] - Number of threads 23
[junit] 2014-07-27 11:28:05,108 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@65] - FINISHED TEST METHOD testQuota
[junit] 2014-07-27 11:28:05,108 [myid:] - INFO [main:ClientBase@520] - tearDown starting
[junit] 2014-07-27 11:28:05,195 [myid:] - INFO [main:ZooKeeper@968] - Session: 0x147775ce4e80000 closed
[junit] 2014-07-27 11:28:05,196 [myid:] - INFO [main:ClientBase@490] - STOPPING server
[junit] 2014-07-27 11:28:05,196 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-07-27 11:28:05,196 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2014-07-27 11:28:05,196 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-07-27 11:28:05,197 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2014-07-27 11:28:05,197 [myid:] - INFO [main:ZooKeeperServer@443] - shutting down
[junit] 2014-07-27 11:28:05,197 [myid:] - INFO [main:SessionTrackerImpl@231] - Shutting down
[junit] 2014-07-27 11:28:05,197 [myid:] - INFO [main:PrepRequestProcessor@973] - Shutting down
[junit] 2014-07-27 11:28:05,197 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-07-27 11:28:05,198 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-07-27 11:28:05,198 [myid:] - INFO [main:FinalRequestProcessor@476] - shutdown of request processor complete
[junit] 2014-07-27 11:28:05,198 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree]
[junit] 2014-07-27 11:28:05,198 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1]
[junit] 2014-07-27 11:28:05,198 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-27 11:28:05,199 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-07-27 11:28:06,001 [myid:] - INFO [SessionTracker:SessionTrackerImpl@157] - SessionTrackerImpl exited loop!
[junit] 2014-07-27 11:28:06,001 [myid:] - INFO [SessionTracker:SessionTrackerImpl@157] - SessionTrackerImpl exited loop!
[junit] 2014-07-27 11:28:06,193 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-07-27 11:28:06,198 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2014-07-27 11:28:06,198 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 4.308 sec
BUILD FAILED
f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build.xml:1333: Tests failed!
Total time: 75 minutes 55 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
4 tests failed.
REGRESSION: org.apache.zookeeper.test.ReconfigTest.testQuorumSystemChange
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:159)
at org.apache.zookeeper.test.ReconfigTest.testQuorumSystemChange(ReconfigTest.java:733)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch
Error Message:
First leader election failed
Stack Trace:
junit.framework.AssertionFailedError: First leader election failed
at org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch(FLENewEpochTest.java:175)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment
Error Message:
Not following
Stack Trace:
junit.framework.AssertionFailedError: Not following
at org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment(QuorumTest.java:408)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.TruncateTest.testTruncationNullLog
Error Message:
Should not get here
Stack Trace:
junit.framework.AssertionFailedError: Should not get here
at org.apache.zookeeper.test.TruncateTest.testTruncationNullLog(TruncateTest.java:132)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
ZooKeeper-trunk-WinVS2008_java - Build # 786 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk-WinVS2008_java/786/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 299014 lines...]
[junit] 2014-07-26 11:27:32,786 [myid:] - INFO [main:ZooKeeperServer@790] - maxSessionTimeout set to 60000
[junit] 2014-07-26 11:27:32,786 [myid:] - INFO [main:ZooKeeperServer@152] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test3723000009834927899.junit.dir\version-2 snapdir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test3723000009834927899.junit.dir\version-2
[junit] 2014-07-26 11:27:32,787 [myid:] - INFO [main:FileSnap@83] - Reading snapshot f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test3723000009834927899.junit.dir\version-2\snapshot.b
[junit] 2014-07-26 11:27:32,789 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test3723000009834927899.junit.dir\version-2\snapshot.b
[junit] 2014-07-26 11:27:32,791 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-26 11:27:32,792 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:50044
[junit] 2014-07-26 11:27:32,792 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:50044
[junit] 2014-07-26 11:27:32,793 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-07-26 11:27:32,793 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:50044 (no session established for client)
[junit] 2014-07-26 11:27:32,793 [myid:] - INFO [main:JMXEnv@224] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2014-07-26 11:27:32,795 [myid:] - INFO [main:JMXEnv@241] - expect:InMemoryDataTree
[junit] 2014-07-26 11:27:32,795 [myid:] - INFO [main:JMXEnv@245] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2014-07-26 11:27:32,795 [myid:] - INFO [main:JMXEnv@241] - expect:StandaloneServer_port
[junit] 2014-07-26 11:27:32,795 [myid:] - INFO [main:JMXEnv@245] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2014-07-26 11:27:32,796 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@55] - Memory used 12856
[junit] 2014-07-26 11:27:32,796 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@60] - Number of threads 24
[junit] 2014-07-26 11:27:32,796 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@65] - FINISHED TEST METHOD testQuota
[junit] 2014-07-26 11:27:32,796 [myid:] - INFO [main:ClientBase@520] - tearDown starting
[junit] 2014-07-26 11:27:32,931 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@963] - Socket connection established to 127.0.0.1/127.0.0.1:11221, initiating session
[junit] 2014-07-26 11:27:32,931 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:50038
[junit] 2014-07-26 11:27:32,932 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@877] - Client attempting to renew session 0x14772360b750000 at /127.0.0.1:50038
[junit] 2014-07-26 11:27:32,932 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@619] - Established session 0x14772360b750000 with negotiated timeout 30000 for client /127.0.0.1:50038
[junit] 2014-07-26 11:27:32,933 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@1346] - Session establishment complete on server 127.0.0.1/127.0.0.1:11221, sessionid = 0x14772360b750000, negotiated timeout = 30000
[junit] 2014-07-26 11:27:32,934 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@676] - Processed session termination for sessionid: 0x14772360b750000
[junit] 2014-07-26 11:27:32,934 [myid:] - INFO [SyncThread:0:FileTxnLog@200] - Creating new log file: log.c
[junit] 2014-07-26 11:27:32,950 [myid:] - INFO [main:ZooKeeper@968] - Session: 0x14772360b750000 closed
[junit] 2014-07-26 11:27:32,950 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-07-26 11:27:32,950 [myid:] - INFO [NIOWorkerThread-1:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x14772360b750000]
[junit] 2014-07-26 11:27:32,950 [myid:] - INFO [main:ClientBase@490] - STOPPING server
[junit] 2014-07-26 11:27:32,950 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:50038 which had sessionid 0x14772360b750000
[junit] 2014-07-26 11:27:32,951 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2014-07-26 11:27:32,951 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-07-26 11:27:32,951 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2014-07-26 11:27:32,951 [myid:] - INFO [main:ZooKeeperServer@443] - shutting down
[junit] 2014-07-26 11:27:32,951 [myid:] - INFO [main:SessionTrackerImpl@231] - Shutting down
[junit] 2014-07-26 11:27:32,951 [myid:] - INFO [main:PrepRequestProcessor@973] - Shutting down
[junit] 2014-07-26 11:27:32,951 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-07-26 11:27:32,951 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-07-26 11:27:32,951 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-07-26 11:27:32,952 [myid:] - INFO [main:FinalRequestProcessor@476] - shutdown of request processor complete
[junit] 2014-07-26 11:27:32,952 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree]
[junit] 2014-07-26 11:27:32,952 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1]
[junit] 2014-07-26 11:27:32,953 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-26 11:27:33,000 [myid:] - INFO [SessionTracker:SessionTrackerImpl@157] - SessionTrackerImpl exited loop!
[junit] 2014-07-26 11:27:33,001 [myid:] - INFO [SessionTracker:SessionTrackerImpl@157] - SessionTrackerImpl exited loop!
[junit] 2014-07-26 11:27:33,001 [myid:] - INFO [SessionTracker:SessionTrackerImpl@157] - SessionTrackerImpl exited loop!
[junit] 2014-07-26 11:27:33,952 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-07-26 11:27:33,956 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2014-07-26 11:27:33,956 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 4.121 sec
BUILD FAILED
f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build.xml:1333: Tests failed!
Total time: 75 minutes 28 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
4 tests failed.
REGRESSION: org.apache.zookeeper.test.FLETest.testJoin
Error Message:
Incorrect LEADING state for peer 2
Stack Trace:
junit.framework.AssertionFailedError: Incorrect LEADING state for peer 2
at org.apache.zookeeper.test.FLETest.testJoin(FLETest.java:456)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch
Error Message:
First leader election failed
Stack Trace:
junit.framework.AssertionFailedError: First leader election failed
at org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch(FLENewEpochTest.java:175)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment
Error Message:
Not following
Stack Trace:
junit.framework.AssertionFailedError: Not following
at org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment(QuorumTest.java:408)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.TruncateTest.testTruncationNullLog
Error Message:
Should not get here
Stack Trace:
junit.framework.AssertionFailedError: Should not get here
at org.apache.zookeeper.test.TruncateTest.testTruncationNullLog(TruncateTest.java:132)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
ZooKeeper-trunk-WinVS2008_java - Build # 785 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk-WinVS2008_java/785/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 295800 lines...]
[junit] 2014-07-25 11:26:45,564 [myid:] - INFO [main:NIOServerCnxnFactory@683] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2014-07-25 11:26:45,565 [myid:] - INFO [main:ClientBase@339] - STARTING server instance 127.0.0.1:11221
[junit] 2014-07-25 11:26:45,565 [myid:] - INFO [main:ZooKeeperServer@781] - minSessionTimeout set to 6000
[junit] 2014-07-25 11:26:45,565 [myid:] - INFO [main:ZooKeeperServer@790] - maxSessionTimeout set to 60000
[junit] 2014-07-25 11:26:45,565 [myid:] - INFO [main:ZooKeeperServer@152] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test3141363298304915779.junit.dir\version-2 snapdir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test3141363298304915779.junit.dir\version-2
[junit] 2014-07-25 11:26:45,566 [myid:] - INFO [main:FileSnap@83] - Reading snapshot f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test3141363298304915779.junit.dir\version-2\snapshot.b
[junit] 2014-07-25 11:26:45,568 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test3141363298304915779.junit.dir\version-2\snapshot.b
[junit] 2014-07-25 11:26:45,569 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-25 11:26:45,571 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:54615
[junit] 2014-07-25 11:26:45,571 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:54615
[junit] 2014-07-25 11:26:45,571 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-07-25 11:26:45,571 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:54615 (no session established for client)
[junit] 2014-07-25 11:26:45,571 [myid:] - INFO [main:JMXEnv@224] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2014-07-25 11:26:45,573 [myid:] - INFO [main:JMXEnv@241] - expect:InMemoryDataTree
[junit] 2014-07-25 11:26:45,573 [myid:] - INFO [main:JMXEnv@245] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2014-07-25 11:26:45,573 [myid:] - INFO [main:JMXEnv@241] - expect:StandaloneServer_port
[junit] 2014-07-25 11:26:45,573 [myid:] - INFO [main:JMXEnv@245] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2014-07-25 11:26:45,574 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@55] - Memory used 12243
[junit] 2014-07-25 11:26:45,574 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@60] - Number of threads 22
[junit] 2014-07-25 11:26:45,574 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@65] - FINISHED TEST METHOD testQuota
[junit] 2014-07-25 11:26:45,574 [myid:] - INFO [main:ClientBase@520] - tearDown starting
[junit] 2014-07-25 11:26:46,036 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@963] - Socket connection established to 127.0.0.1/127.0.0.1:11221, initiating session
[junit] 2014-07-25 11:26:46,037 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:54610
[junit] 2014-07-25 11:26:46,068 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@877] - Client attempting to renew session 0x1476d0ef66e0000 at /127.0.0.1:54610
[junit] 2014-07-25 11:26:46,069 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@619] - Established session 0x1476d0ef66e0000 with negotiated timeout 30000 for client /127.0.0.1:54610
[junit] 2014-07-25 11:26:46,070 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@1346] - Session establishment complete on server 127.0.0.1/127.0.0.1:11221, sessionid = 0x1476d0ef66e0000, negotiated timeout = 30000
[junit] 2014-07-25 11:26:46,071 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@676] - Processed session termination for sessionid: 0x1476d0ef66e0000
[junit] 2014-07-25 11:26:46,071 [myid:] - INFO [SyncThread:0:FileTxnLog@200] - Creating new log file: log.c
[junit] 2014-07-25 11:26:46,096 [myid:] - INFO [NIOWorkerThread-1:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x1476d0ef66e0000]
[junit] 2014-07-25 11:26:46,097 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:54610 which had sessionid 0x1476d0ef66e0000
[junit] 2014-07-25 11:26:46,097 [myid:] - INFO [main:ZooKeeper@968] - Session: 0x1476d0ef66e0000 closed
[junit] 2014-07-25 11:26:46,097 [myid:] - INFO [main:ClientBase@490] - STOPPING server
[junit] 2014-07-25 11:26:46,098 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2014-07-25 11:26:46,098 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-07-25 11:26:46,099 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-07-25 11:26:46,099 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2014-07-25 11:26:46,099 [myid:] - INFO [main:ZooKeeperServer@443] - shutting down
[junit] 2014-07-25 11:26:46,099 [myid:] - INFO [main:SessionTrackerImpl@231] - Shutting down
[junit] 2014-07-25 11:26:46,099 [myid:] - INFO [main:PrepRequestProcessor@973] - Shutting down
[junit] 2014-07-25 11:26:46,100 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-07-25 11:26:46,100 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-07-25 11:26:46,100 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-07-25 11:26:46,100 [myid:] - INFO [main:FinalRequestProcessor@476] - shutdown of request processor complete
[junit] 2014-07-25 11:26:46,100 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree]
[junit] 2014-07-25 11:26:46,101 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1]
[junit] 2014-07-25 11:26:46,101 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-25 11:26:47,111 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-07-25 11:26:47,115 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2014-07-25 11:26:47,115 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 4.67 sec
BUILD FAILED
f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build.xml:1333: Tests failed!
Total time: 75 minutes 1 second
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
4 tests failed.
REGRESSION: org.apache.zookeeper.test.ReconfigTest.testQuorumSystemChange
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:159)
at org.apache.zookeeper.test.ReconfigTest.testQuorumSystemChange(ReconfigTest.java:733)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch
Error Message:
First leader election failed
Stack Trace:
junit.framework.AssertionFailedError: First leader election failed
at org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch(FLENewEpochTest.java:175)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment
Error Message:
Not following
Stack Trace:
junit.framework.AssertionFailedError: Not following
at org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment(QuorumTest.java:408)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.TruncateTest.testTruncationNullLog
Error Message:
Should not get here
Stack Trace:
junit.framework.AssertionFailedError: Should not get here
at org.apache.zookeeper.test.TruncateTest.testTruncationNullLog(TruncateTest.java:132)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
ZooKeeper-trunk-WinVS2008_java - Build # 784 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk-WinVS2008_java/784/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 301602 lines...]
[junit] 2014-07-24 11:54:38,113 [myid:] - INFO [main:ZooKeeperServer@781] - minSessionTimeout set to 6000
[junit] 2014-07-24 11:54:38,113 [myid:] - INFO [main:ZooKeeperServer@790] - maxSessionTimeout set to 60000
[junit] 2014-07-24 11:54:38,114 [myid:] - INFO [main:ZooKeeperServer@152] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test1826834553568571213.junit.dir\version-2 snapdir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test1826834553568571213.junit.dir\version-2
[junit] 2014-07-24 11:54:38,114 [myid:] - INFO [main:FileSnap@83] - Reading snapshot f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test1826834553568571213.junit.dir\version-2\snapshot.b
[junit] 2014-07-24 11:54:38,116 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test1826834553568571213.junit.dir\version-2\snapshot.b
[junit] 2014-07-24 11:54:38,118 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-24 11:54:38,119 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:58487
[junit] 2014-07-24 11:54:38,121 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:58487
[junit] 2014-07-24 11:54:38,121 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-07-24 11:54:38,121 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:58487 (no session established for client)
[junit] 2014-07-24 11:54:38,121 [myid:] - INFO [main:JMXEnv@224] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2014-07-24 11:54:38,123 [myid:] - INFO [main:JMXEnv@241] - expect:InMemoryDataTree
[junit] 2014-07-24 11:54:38,123 [myid:] - INFO [main:JMXEnv@245] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2014-07-24 11:54:38,123 [myid:] - INFO [main:JMXEnv@241] - expect:StandaloneServer_port
[junit] 2014-07-24 11:54:38,123 [myid:] - INFO [main:JMXEnv@245] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2014-07-24 11:54:38,124 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@55] - Memory used 11852
[junit] 2014-07-24 11:54:38,124 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@60] - Number of threads 23
[junit] 2014-07-24 11:54:38,124 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@65] - FINISHED TEST METHOD testQuota
[junit] 2014-07-24 11:54:38,124 [myid:] - INFO [main:ClientBase@520] - tearDown starting
[junit] 2014-07-24 11:54:38,169 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@963] - Socket connection established to 127.0.0.1/127.0.0.1:11221, initiating session
[junit] 2014-07-24 11:54:38,169 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:58482
[junit] 2014-07-24 11:54:38,170 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@877] - Client attempting to renew session 0x14768021e3e0000 at /127.0.0.1:58482
[junit] 2014-07-24 11:54:38,171 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@619] - Established session 0x14768021e3e0000 with negotiated timeout 30000 for client /127.0.0.1:58482
[junit] 2014-07-24 11:54:38,211 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@1346] - Session establishment complete on server 127.0.0.1/127.0.0.1:11221, sessionid = 0x14768021e3e0000, negotiated timeout = 30000
[junit] 2014-07-24 11:54:38,212 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@685] - Processed session termination for sessionid: 0x14768021e3e0000
[junit] 2014-07-24 11:54:38,212 [myid:] - INFO [SyncThread:0:FileTxnLog@200] - Creating new log file: log.c
[junit] 2014-07-24 11:54:38,327 [myid:] - INFO [NIOWorkerThread-1:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x14768021e3e0000]
[junit] 2014-07-24 11:54:38,328 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:58482 which had sessionid 0x14768021e3e0000
[junit] 2014-07-24 11:54:38,339 [myid:] - INFO [main:ZooKeeper@968] - Session: 0x14768021e3e0000 closed
[junit] 2014-07-24 11:54:38,339 [myid:] - INFO [main:ClientBase@490] - STOPPING server
[junit] 2014-07-24 11:54:38,340 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-07-24 11:54:38,359 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-07-24 11:54:38,360 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2014-07-24 11:54:38,373 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2014-07-24 11:54:38,377 [myid:] - INFO [main:ZooKeeperServer@443] - shutting down
[junit] 2014-07-24 11:54:38,377 [myid:] - INFO [main:SessionTrackerImpl@228] - Shutting down
[junit] 2014-07-24 11:54:38,377 [myid:] - INFO [main:PrepRequestProcessor@987] - Shutting down
[junit] 2014-07-24 11:54:38,377 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-07-24 11:54:38,378 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-07-24 11:54:38,378 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-07-24 11:54:38,378 [myid:] - INFO [main:FinalRequestProcessor@476] - shutdown of request processor complete
[junit] 2014-07-24 11:54:38,378 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree]
[junit] 2014-07-24 11:54:38,379 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1]
[junit] 2014-07-24 11:54:38,379 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-24 11:54:39,001 [myid:] - INFO [SessionTracker:SessionTrackerImpl@156] - SessionTrackerImpl exited loop!
[junit] 2014-07-24 11:54:39,001 [myid:] - INFO [SessionTracker:SessionTrackerImpl@156] - SessionTrackerImpl exited loop!
[junit] 2014-07-24 11:54:39,383 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-07-24 11:54:39,387 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2014-07-24 11:54:39,387 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 4.871 sec
BUILD FAILED
f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build.xml:1333: Tests failed!
Total time: 102 minutes 39 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
5 tests failed.
REGRESSION: org.apache.zookeeper.server.admin.JettyAdminServerTest.testStandalone
Error Message:
Timeout occurred. Please note the time in the report does not reflect the time until the timeout.
Stack Trace:
junit.framework.AssertionFailedError: Timeout occurred. Please note the time in the report does not reflect the time until the timeout.
REGRESSION: org.apache.zookeeper.test.ReadOnlyModeTest.testSeekForRwServer
Error Message:
Timeout occurred. Please note the time in the report does not reflect the time until the timeout.
Stack Trace:
junit.framework.AssertionFailedError: Timeout occurred. Please note the time in the report does not reflect the time until the timeout.
FAILED: org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch
Error Message:
First leader election failed
Stack Trace:
junit.framework.AssertionFailedError: First leader election failed
at org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch(FLENewEpochTest.java:175)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment
Error Message:
Not following
Stack Trace:
junit.framework.AssertionFailedError: Not following
at org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment(QuorumTest.java:408)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.TruncateTest.testTruncationNullLog
Error Message:
Should not get here
Stack Trace:
junit.framework.AssertionFailedError: Should not get here
at org.apache.zookeeper.test.TruncateTest.testTruncationNullLog(TruncateTest.java:132)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
ZooKeeper-trunk-WinVS2008_java - Build # 783 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk-WinVS2008_java/783/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 293259 lines...]
[junit] 2014-07-23 11:29:16,337 [myid:] - INFO [main:NIOServerCnxnFactory@683] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2014-07-23 11:29:16,338 [myid:] - INFO [main:ClientBase@339] - STARTING server instance 127.0.0.1:11221
[junit] 2014-07-23 11:29:16,338 [myid:] - INFO [main:ZooKeeperServer@781] - minSessionTimeout set to 6000
[junit] 2014-07-23 11:29:16,338 [myid:] - INFO [main:ZooKeeperServer@790] - maxSessionTimeout set to 60000
[junit] 2014-07-23 11:29:16,338 [myid:] - INFO [main:ZooKeeperServer@152] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test8721188675138481746.junit.dir\version-2 snapdir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test8721188675138481746.junit.dir\version-2
[junit] 2014-07-23 11:29:16,339 [myid:] - INFO [main:FileSnap@83] - Reading snapshot f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test8721188675138481746.junit.dir\version-2\snapshot.b
[junit] 2014-07-23 11:29:16,341 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test8721188675138481746.junit.dir\version-2\snapshot.b
[junit] 2014-07-23 11:29:16,343 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-23 11:29:16,366 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:65157
[junit] 2014-07-23 11:29:16,375 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:65157
[junit] 2014-07-23 11:29:16,375 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-07-23 11:29:16,375 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:65157 (no session established for client)
[junit] 2014-07-23 11:29:16,376 [myid:] - INFO [main:JMXEnv@224] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2014-07-23 11:29:16,377 [myid:] - INFO [main:JMXEnv@241] - expect:InMemoryDataTree
[junit] 2014-07-23 11:29:16,378 [myid:] - INFO [main:JMXEnv@245] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2014-07-23 11:29:16,378 [myid:] - INFO [main:JMXEnv@241] - expect:StandaloneServer_port
[junit] 2014-07-23 11:29:16,378 [myid:] - INFO [main:JMXEnv@245] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2014-07-23 11:29:16,378 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@55] - Memory used 12157
[junit] 2014-07-23 11:29:16,378 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@60] - Number of threads 23
[junit] 2014-07-23 11:29:16,379 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@65] - FINISHED TEST METHOD testQuota
[junit] 2014-07-23 11:29:16,379 [myid:] - INFO [main:ClientBase@520] - tearDown starting
[junit] 2014-07-23 11:29:16,723 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@963] - Socket connection established to 127.0.0.1/127.0.0.1:11221, initiating session
[junit] 2014-07-23 11:29:16,724 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:65147
[junit] 2014-07-23 11:29:16,765 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@877] - Client attempting to renew session 0x14762c48a250000 at /127.0.0.1:65147
[junit] 2014-07-23 11:29:16,766 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@619] - Established session 0x14762c48a250000 with negotiated timeout 30000 for client /127.0.0.1:65147
[junit] 2014-07-23 11:29:16,785 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@1346] - Session establishment complete on server 127.0.0.1/127.0.0.1:11221, sessionid = 0x14762c48a250000, negotiated timeout = 30000
[junit] 2014-07-23 11:29:16,787 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@685] - Processed session termination for sessionid: 0x14762c48a250000
[junit] 2014-07-23 11:29:16,787 [myid:] - INFO [SyncThread:0:FileTxnLog@200] - Creating new log file: log.c
[junit] 2014-07-23 11:29:16,827 [myid:] - INFO [main:ZooKeeper@968] - Session: 0x14762c48a250000 closed
[junit] 2014-07-23 11:29:16,827 [myid:] - INFO [main:ClientBase@490] - STOPPING server
[junit] 2014-07-23 11:29:16,828 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-07-23 11:29:16,828 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2014-07-23 11:29:16,828 [myid:] - INFO [NIOWorkerThread-1:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x14762c48a250000]
[junit] 2014-07-23 11:29:16,828 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:65147 which had sessionid 0x14762c48a250000
[junit] 2014-07-23 11:29:16,828 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-07-23 11:29:16,829 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2014-07-23 11:29:16,829 [myid:] - INFO [main:ZooKeeperServer@443] - shutting down
[junit] 2014-07-23 11:29:16,836 [myid:] - INFO [main:SessionTrackerImpl@205] - Shutting down
[junit] 2014-07-23 11:29:16,836 [myid:] - INFO [main:PrepRequestProcessor@987] - Shutting down
[junit] 2014-07-23 11:29:16,836 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-07-23 11:29:16,836 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-07-23 11:29:16,837 [myid:] - INFO [main:FinalRequestProcessor@476] - shutdown of request processor complete
[junit] 2014-07-23 11:29:16,837 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree]
[junit] 2014-07-23 11:29:16,837 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1]
[junit] 2014-07-23 11:29:16,837 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-23 11:29:16,851 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-07-23 11:29:17,828 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-07-23 11:29:17,834 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2014-07-23 11:29:17,834 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 4.924 sec
BUILD FAILED
f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build.xml:1333: Tests failed!
Total time: 75 minutes 15 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
5 tests failed.
REGRESSION: org.apache.zookeeper.test.ReconfigTest.testLeaderTimesoutOnNewQuorum
Error Message:
Should have been ConnectionLossException!
Stack Trace:
junit.framework.AssertionFailedError: Should have been ConnectionLossException!
at org.apache.zookeeper.test.ReconfigTest.testLeaderTimesoutOnNewQuorum(ReconfigTest.java:434)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch
Error Message:
First leader election failed
Stack Trace:
junit.framework.AssertionFailedError: First leader election failed
at org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch(FLENewEpochTest.java:175)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.ReconfigTest.testQuorumSystemChange
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:159)
at org.apache.zookeeper.test.ReconfigTest.testQuorumSystemChange(ReconfigTest.java:740)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment
Error Message:
Not following
Stack Trace:
junit.framework.AssertionFailedError: Not following
at org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment(QuorumTest.java:408)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.TruncateTest.testTruncationNullLog
Error Message:
Should not get here
Stack Trace:
junit.framework.AssertionFailedError: Should not get here
at org.apache.zookeeper.test.TruncateTest.testTruncationNullLog(TruncateTest.java:132)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
ZooKeeper-trunk-WinVS2008_java - Build # 782 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk-WinVS2008_java/782/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 300286 lines...]
[junit] 2014-07-22 11:28:46,876 [myid:] - INFO [main:ZooKeeperServer@781] - minSessionTimeout set to 6000
[junit] 2014-07-22 11:28:46,876 [myid:] - INFO [main:ZooKeeperServer@790] - maxSessionTimeout set to 60000
[junit] 2014-07-22 11:28:46,876 [myid:] - INFO [main:ZooKeeperServer@152] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test7247709068627313159.junit.dir\version-2 snapdir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test7247709068627313159.junit.dir\version-2
[junit] 2014-07-22 11:28:46,877 [myid:] - INFO [main:FileSnap@83] - Reading snapshot f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test7247709068627313159.junit.dir\version-2\snapshot.b
[junit] 2014-07-22 11:28:46,881 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test7247709068627313159.junit.dir\version-2\snapshot.b
[junit] 2014-07-22 11:28:46,882 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-22 11:28:46,884 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:55370
[junit] 2014-07-22 11:28:46,885 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:55370
[junit] 2014-07-22 11:28:46,885 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-07-22 11:28:46,886 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:55370 (no session established for client)
[junit] 2014-07-22 11:28:46,886 [myid:] - INFO [main:JMXEnv@224] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2014-07-22 11:28:46,888 [myid:] - INFO [main:JMXEnv@241] - expect:InMemoryDataTree
[junit] 2014-07-22 11:28:46,888 [myid:] - INFO [main:JMXEnv@245] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2014-07-22 11:28:46,888 [myid:] - INFO [main:JMXEnv@241] - expect:StandaloneServer_port
[junit] 2014-07-22 11:28:46,888 [myid:] - INFO [main:JMXEnv@245] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2014-07-22 11:28:46,888 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@55] - Memory used 13268
[junit] 2014-07-22 11:28:46,889 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@60] - Number of threads 23
[junit] 2014-07-22 11:28:46,889 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@65] - FINISHED TEST METHOD testQuota
[junit] 2014-07-22 11:28:46,889 [myid:] - INFO [main:ClientBase@520] - tearDown starting
[junit] 2014-07-22 11:28:47,280 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@963] - Socket connection established to 127.0.0.1/127.0.0.1:11221, initiating session
[junit] 2014-07-22 11:28:47,280 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:55365
[junit] 2014-07-22 11:28:47,281 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@877] - Client attempting to renew session 0x1475d9db94e0000 at /127.0.0.1:55365
[junit] 2014-07-22 11:28:47,282 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@619] - Established session 0x1475d9db94e0000 with negotiated timeout 30000 for client /127.0.0.1:55365
[junit] 2014-07-22 11:28:47,283 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@1346] - Session establishment complete on server 127.0.0.1/127.0.0.1:11221, sessionid = 0x1475d9db94e0000, negotiated timeout = 30000
[junit] 2014-07-22 11:28:47,284 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@685] - Processed session termination for sessionid: 0x1475d9db94e0000
[junit] 2014-07-22 11:28:47,284 [myid:] - INFO [SyncThread:0:FileTxnLog@200] - Creating new log file: log.c
[junit] 2014-07-22 11:28:47,311 [myid:] - INFO [main:ZooKeeper@968] - Session: 0x1475d9db94e0000 closed
[junit] 2014-07-22 11:28:47,311 [myid:] - INFO [main:ClientBase@490] - STOPPING server
[junit] 2014-07-22 11:28:47,311 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-07-22 11:28:47,311 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2014-07-22 11:28:47,312 [myid:] - INFO [NIOWorkerThread-1:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x1475d9db94e0000]
[junit] 2014-07-22 11:28:47,312 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:55365 which had sessionid 0x1475d9db94e0000
[junit] 2014-07-22 11:28:47,312 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-07-22 11:28:47,312 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2014-07-22 11:28:47,313 [myid:] - INFO [main:ZooKeeperServer@443] - shutting down
[junit] 2014-07-22 11:28:47,313 [myid:] - INFO [main:SessionTrackerImpl@205] - Shutting down
[junit] 2014-07-22 11:28:47,313 [myid:] - INFO [main:PrepRequestProcessor@987] - Shutting down
[junit] 2014-07-22 11:28:47,313 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-07-22 11:28:47,314 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-07-22 11:28:47,314 [myid:] - INFO [main:FinalRequestProcessor@476] - shutdown of request processor complete
[junit] 2014-07-22 11:28:47,314 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree]
[junit] 2014-07-22 11:28:47,314 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1]
[junit] 2014-07-22 11:28:47,314 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-22 11:28:47,315 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-07-22 11:28:48,000 [myid:] - INFO [SessionTracker:SessionTrackerImpl@156] - SessionTrackerImpl exited loop!
[junit] 2014-07-22 11:28:48,001 [myid:] - INFO [SessionTracker:SessionTrackerImpl@156] - SessionTrackerImpl exited loop!
[junit] 2014-07-22 11:28:48,312 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-07-22 11:28:48,317 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2014-07-22 11:28:48,317 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 5.363 sec
BUILD FAILED
f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build.xml:1333: Tests failed!
Total time: 75 minutes 43 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
5 tests failed.
REGRESSION: org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch
Error Message:
First leader election failed
Stack Trace:
junit.framework.AssertionFailedError: First leader election failed
at org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch(FLENewEpochTest.java:175)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
REGRESSION: org.apache.zookeeper.test.ReconfigTest.testQuorumSystemChange
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:159)
at org.apache.zookeeper.test.ReconfigTest.testQuorumSystemChange(ReconfigTest.java:740)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.ReconfigTest.testBulkReconfig
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.reconfig(ReconfigTest.java:81)
at org.apache.zookeeper.test.ReconfigTest.testBulkReconfig(ReconfigTest.java:395)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment
Error Message:
Not following
Stack Trace:
junit.framework.AssertionFailedError: Not following
at org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment(QuorumTest.java:408)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.TruncateTest.testTruncationNullLog
Error Message:
Should not get here
Stack Trace:
junit.framework.AssertionFailedError: Should not get here
at org.apache.zookeeper.test.TruncateTest.testTruncationNullLog(TruncateTest.java:132)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
ZooKeeper-trunk-WinVS2008_java - Build # 781 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk-WinVS2008_java/781/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 302394 lines...]
[junit] 2014-07-21 11:26:29,107 [myid:] - INFO [main:ZooKeeperServer@790] - maxSessionTimeout set to 60000
[junit] 2014-07-21 11:26:29,107 [myid:] - INFO [main:ZooKeeperServer@152] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test1011232056647681640.junit.dir\version-2 snapdir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test1011232056647681640.junit.dir\version-2
[junit] 2014-07-21 11:26:29,108 [myid:] - INFO [main:FileSnap@83] - Reading snapshot f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test1011232056647681640.junit.dir\version-2\snapshot.b
[junit] 2014-07-21 11:26:29,110 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test1011232056647681640.junit.dir\version-2\snapshot.b
[junit] 2014-07-21 11:26:29,112 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-21 11:26:29,114 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:51712
[junit] 2014-07-21 11:26:29,115 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:51712
[junit] 2014-07-21 11:26:29,115 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-07-21 11:26:29,115 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:51712 (no session established for client)
[junit] 2014-07-21 11:26:29,116 [myid:] - INFO [main:JMXEnv@224] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2014-07-21 11:26:29,118 [myid:] - INFO [main:JMXEnv@241] - expect:InMemoryDataTree
[junit] 2014-07-21 11:26:29,118 [myid:] - INFO [main:JMXEnv@245] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2014-07-21 11:26:29,118 [myid:] - INFO [main:JMXEnv@241] - expect:StandaloneServer_port
[junit] 2014-07-21 11:26:29,118 [myid:] - INFO [main:JMXEnv@245] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2014-07-21 11:26:29,119 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@55] - Memory used 13058
[junit] 2014-07-21 11:26:29,119 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@60] - Number of threads 24
[junit] 2014-07-21 11:26:29,119 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@65] - FINISHED TEST METHOD testQuota
[junit] 2014-07-21 11:26:29,119 [myid:] - INFO [main:ClientBase@520] - tearDown starting
[junit] 2014-07-21 11:26:29,282 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@963] - Socket connection established to 127.0.0.1/127.0.0.1:11221, initiating session
[junit] 2014-07-21 11:26:29,282 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:51706
[junit] 2014-07-21 11:26:29,296 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@877] - Client attempting to renew session 0x147587546310000 at /127.0.0.1:51706
[junit] 2014-07-21 11:26:29,297 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@619] - Established session 0x147587546310000 with negotiated timeout 30000 for client /127.0.0.1:51706
[junit] 2014-07-21 11:26:29,298 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@1346] - Session establishment complete on server 127.0.0.1/127.0.0.1:11221, sessionid = 0x147587546310000, negotiated timeout = 30000
[junit] 2014-07-21 11:26:29,301 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@685] - Processed session termination for sessionid: 0x147587546310000
[junit] 2014-07-21 11:26:29,301 [myid:] - INFO [SyncThread:0:FileTxnLog@200] - Creating new log file: log.c
[junit] 2014-07-21 11:26:29,323 [myid:] - INFO [main:ZooKeeper@968] - Session: 0x147587546310000 closed
[junit] 2014-07-21 11:26:29,323 [myid:] - INFO [main:ClientBase@490] - STOPPING server
[junit] 2014-07-21 11:26:29,323 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-07-21 11:26:29,324 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2014-07-21 11:26:29,324 [myid:] - INFO [NIOWorkerThread-1:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x147587546310000]
[junit] 2014-07-21 11:26:29,324 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:51706 which had sessionid 0x147587546310000
[junit] 2014-07-21 11:26:29,324 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-07-21 11:26:29,325 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2014-07-21 11:26:29,325 [myid:] - INFO [main:ZooKeeperServer@443] - shutting down
[junit] 2014-07-21 11:26:29,325 [myid:] - INFO [main:SessionTrackerImpl@205] - Shutting down
[junit] 2014-07-21 11:26:29,325 [myid:] - INFO [main:PrepRequestProcessor@981] - Shutting down
[junit] 2014-07-21 11:26:29,325 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-07-21 11:26:29,326 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-07-21 11:26:29,326 [myid:] - INFO [main:FinalRequestProcessor@476] - shutdown of request processor complete
[junit] 2014-07-21 11:26:29,326 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree]
[junit] 2014-07-21 11:26:29,326 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1]
[junit] 2014-07-21 11:26:29,327 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-21 11:26:29,327 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-07-21 11:26:30,001 [myid:] - INFO [SessionTracker:SessionTrackerImpl@156] - SessionTrackerImpl exited loop!
[junit] 2014-07-21 11:26:30,001 [myid:] - INFO [SessionTracker:SessionTrackerImpl@156] - SessionTrackerImpl exited loop!
[junit] 2014-07-21 11:26:30,001 [myid:] - INFO [SessionTracker:SessionTrackerImpl@156] - SessionTrackerImpl exited loop!
[junit] 2014-07-21 11:26:30,328 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-07-21 11:26:30,334 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2014-07-21 11:26:30,334 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 4.317 sec
BUILD FAILED
f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build.xml:1333: Tests failed!
Total time: 75 minutes 20 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
5 tests failed.
REGRESSION: org.apache.zookeeper.test.ReadOnlyModeTest.testSessionEstablishment
Error Message:
KeeperErrorCode = ConnectionLoss for /test
Stack Trace:
org.apache.zookeeper.KeeperException$ConnectionLossException: KeeperErrorCode = ConnectionLoss for /test
at org.apache.zookeeper.KeeperException.create(KeeperException.java:99)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:1067)
at org.apache.zookeeper.test.ReadOnlyModeTest.testSessionEstablishment(ReadOnlyModeTest.java:238)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.ReconfigTest.testBulkReconfig
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.reconfig(ReconfigTest.java:81)
at org.apache.zookeeper.test.ReconfigTest.testBulkReconfig(ReconfigTest.java:395)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment
Error Message:
Not following
Stack Trace:
junit.framework.AssertionFailedError: Not following
at org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment(QuorumTest.java:408)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.server.admin.CommandsTest.testMonitor
Error Message:
Result from command monitor missing field "open_file_descriptor_count"
{}
Stack Trace:
junit.framework.AssertionFailedError: Result from command monitor missing field "open_file_descriptor_count"
{}
at org.apache.zookeeper.server.admin.CommandsTest.testCommand(CommandsTest.java:65)
at org.apache.zookeeper.server.admin.CommandsTest.testCommand(CommandsTest.java:81)
at org.apache.zookeeper.server.admin.CommandsTest.testMonitor(CommandsTest.java:161)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.TruncateTest.testTruncationNullLog
Error Message:
Should not get here
Stack Trace:
junit.framework.AssertionFailedError: Should not get here
at org.apache.zookeeper.test.TruncateTest.testTruncationNullLog(TruncateTest.java:132)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
ZooKeeper-trunk-WinVS2008_java - Build # 780 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk-WinVS2008_java/780/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 301011 lines...]
[junit] 2014-07-20 11:26:38,219 [myid:] - INFO [main:ZooKeeperServer@790] - maxSessionTimeout set to 60000
[junit] 2014-07-20 11:26:38,220 [myid:] - INFO [main:ZooKeeperServer@152] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test53568858679470248.junit.dir\version-2 snapdir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test53568858679470248.junit.dir\version-2
[junit] 2014-07-20 11:26:38,220 [myid:] - INFO [main:FileSnap@83] - Reading snapshot f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test53568858679470248.junit.dir\version-2\snapshot.b
[junit] 2014-07-20 11:26:38,224 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test53568858679470248.junit.dir\version-2\snapshot.b
[junit] 2014-07-20 11:26:38,225 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-20 11:26:38,227 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:64311
[junit] 2014-07-20 11:26:38,229 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:64311
[junit] 2014-07-20 11:26:38,229 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-07-20 11:26:38,229 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:64311 (no session established for client)
[junit] 2014-07-20 11:26:38,229 [myid:] - INFO [main:JMXEnv@224] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2014-07-20 11:26:38,231 [myid:] - INFO [main:JMXEnv@241] - expect:InMemoryDataTree
[junit] 2014-07-20 11:26:38,231 [myid:] - INFO [main:JMXEnv@245] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2014-07-20 11:26:38,231 [myid:] - INFO [main:JMXEnv@241] - expect:StandaloneServer_port
[junit] 2014-07-20 11:26:38,232 [myid:] - INFO [main:JMXEnv@245] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2014-07-20 11:26:38,232 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@55] - Memory used 13073
[junit] 2014-07-20 11:26:38,232 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@60] - Number of threads 24
[junit] 2014-07-20 11:26:38,232 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@65] - FINISHED TEST METHOD testQuota
[junit] 2014-07-20 11:26:38,232 [myid:] - INFO [main:ClientBase@520] - tearDown starting
[junit] 2014-07-20 11:26:38,463 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@963] - Socket connection established to 127.0.0.1/127.0.0.1:11221, initiating session
[junit] 2014-07-20 11:26:38,463 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:64306
[junit] 2014-07-20 11:26:38,480 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@877] - Client attempting to renew session 0x147534f0c640000 at /127.0.0.1:64306
[junit] 2014-07-20 11:26:38,481 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@619] - Established session 0x147534f0c640000 with negotiated timeout 30000 for client /127.0.0.1:64306
[junit] 2014-07-20 11:26:38,482 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@1346] - Session establishment complete on server 127.0.0.1/127.0.0.1:11221, sessionid = 0x147534f0c640000, negotiated timeout = 30000
[junit] 2014-07-20 11:26:38,483 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@685] - Processed session termination for sessionid: 0x147534f0c640000
[junit] 2014-07-20 11:26:38,483 [myid:] - INFO [SyncThread:0:FileTxnLog@200] - Creating new log file: log.c
[junit] 2014-07-20 11:26:38,581 [myid:] - INFO [NIOWorkerThread-1:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x147534f0c640000]
[junit] 2014-07-20 11:26:38,582 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:64306 which had sessionid 0x147534f0c640000
[junit] 2014-07-20 11:26:38,582 [myid:] - INFO [main:ZooKeeper@968] - Session: 0x147534f0c640000 closed
[junit] 2014-07-20 11:26:38,582 [myid:] - INFO [main:ClientBase@490] - STOPPING server
[junit] 2014-07-20 11:26:38,583 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2014-07-20 11:26:38,583 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-07-20 11:26:38,584 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-07-20 11:26:38,584 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2014-07-20 11:26:38,584 [myid:] - INFO [main:ZooKeeperServer@443] - shutting down
[junit] 2014-07-20 11:26:38,584 [myid:] - INFO [main:SessionTrackerImpl@205] - Shutting down
[junit] 2014-07-20 11:26:38,585 [myid:] - INFO [main:PrepRequestProcessor@981] - Shutting down
[junit] 2014-07-20 11:26:38,585 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-07-20 11:26:38,585 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-07-20 11:26:38,585 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-07-20 11:26:38,585 [myid:] - INFO [main:FinalRequestProcessor@476] - shutdown of request processor complete
[junit] 2014-07-20 11:26:38,586 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree]
[junit] 2014-07-20 11:26:38,586 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1]
[junit] 2014-07-20 11:26:38,586 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-20 11:26:39,000 [myid:] - INFO [SessionTracker:SessionTrackerImpl@156] - SessionTrackerImpl exited loop!
[junit] 2014-07-20 11:26:39,000 [myid:] - INFO [SessionTracker:SessionTrackerImpl@156] - SessionTrackerImpl exited loop!
[junit] 2014-07-20 11:26:39,001 [myid:] - INFO [SessionTracker:SessionTrackerImpl@156] - SessionTrackerImpl exited loop!
[junit] 2014-07-20 11:26:39,580 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-07-20 11:26:39,585 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2014-07-20 11:26:39,585 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 4.726 sec
BUILD FAILED
f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build.xml:1333: Tests failed!
Total time: 74 minutes 34 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
6 tests failed.
REGRESSION: org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch
Error Message:
First leader election failed
Stack Trace:
junit.framework.AssertionFailedError: First leader election failed
at org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch(FLENewEpochTest.java:175)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
REGRESSION: org.apache.zookeeper.test.ReconfigTest.testBulkReconfig
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.reconfig(ReconfigTest.java:81)
at org.apache.zookeeper.test.ReconfigTest.testBulkReconfig(ReconfigTest.java:395)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment
Error Message:
Not following
Stack Trace:
junit.framework.AssertionFailedError: Not following
at org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment(QuorumTest.java:408)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.ReconfigTest.testLeaderTimesoutOnNewQuorum
Error Message:
Should have been ConnectionLossException!
Stack Trace:
junit.framework.AssertionFailedError: Should have been ConnectionLossException!
at org.apache.zookeeper.test.ReconfigTest.testLeaderTimesoutOnNewQuorum(ReconfigTest.java:434)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.server.admin.CommandsTest.testMonitor
Error Message:
Result from command monitor missing field "open_file_descriptor_count"
{}
Stack Trace:
junit.framework.AssertionFailedError: Result from command monitor missing field "open_file_descriptor_count"
{}
at org.apache.zookeeper.server.admin.CommandsTest.testCommand(CommandsTest.java:65)
at org.apache.zookeeper.server.admin.CommandsTest.testCommand(CommandsTest.java:81)
at org.apache.zookeeper.server.admin.CommandsTest.testMonitor(CommandsTest.java:161)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.TruncateTest.testTruncationNullLog
Error Message:
Should not get here
Stack Trace:
junit.framework.AssertionFailedError: Should not get here
at org.apache.zookeeper.test.TruncateTest.testTruncationNullLog(TruncateTest.java:132)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
ZooKeeper-trunk-WinVS2008_java - Build # 779 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk-WinVS2008_java/779/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 298620 lines...]
[junit] 2014-07-19 11:25:54,125 [myid:] - INFO [main:ClientBase@339] - STARTING server instance 127.0.0.1:11221
[junit] 2014-07-19 11:25:54,125 [myid:] - INFO [main:ZooKeeperServer@781] - minSessionTimeout set to 6000
[junit] 2014-07-19 11:25:54,125 [myid:] - INFO [main:ZooKeeperServer@790] - maxSessionTimeout set to 60000
[junit] 2014-07-19 11:25:54,125 [myid:] - INFO [main:ZooKeeperServer@152] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test6953301098707099271.junit.dir\version-2 snapdir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test6953301098707099271.junit.dir\version-2
[junit] 2014-07-19 11:25:54,126 [myid:] - INFO [main:FileSnap@83] - Reading snapshot f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test6953301098707099271.junit.dir\version-2\snapshot.b
[junit] 2014-07-19 11:25:54,128 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test6953301098707099271.junit.dir\version-2\snapshot.b
[junit] 2014-07-19 11:25:54,130 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-19 11:25:54,133 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:58226
[junit] 2014-07-19 11:25:54,144 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@963] - Socket connection established to 127.0.0.1/127.0.0.1:11221, initiating session
[junit] 2014-07-19 11:25:54,144 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:58220
[junit] 2014-07-19 11:25:54,147 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@877] - Client attempting to renew session 0x1474e28050b0000 at /127.0.0.1:58220
[junit] 2014-07-19 11:25:54,148 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@619] - Established session 0x1474e28050b0000 with negotiated timeout 30000 for client /127.0.0.1:58220
[junit] 2014-07-19 11:25:54,151 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:58226
[junit] 2014-07-19 11:25:54,151 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-07-19 11:25:54,152 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:58226 (no session established for client)
[junit] 2014-07-19 11:25:54,152 [myid:] - INFO [main:JMXEnv@224] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2014-07-19 11:25:54,154 [myid:] - INFO [main:JMXEnv@241] - expect:InMemoryDataTree
[junit] 2014-07-19 11:25:54,155 [myid:] - INFO [main:JMXEnv@245] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2014-07-19 11:25:54,155 [myid:] - INFO [main:JMXEnv@241] - expect:StandaloneServer_port
[junit] 2014-07-19 11:25:54,155 [myid:] - INFO [main:JMXEnv@245] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2014-07-19 11:25:54,155 [myid:] - INFO [main:ClientBase@465] - found name:92128251174649856 client bean:org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x1474e28050b0000
[junit] 2014-07-19 11:25:54,155 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@55] - Memory used 17549
[junit] 2014-07-19 11:25:54,156 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@60] - Number of threads 24
[junit] 2014-07-19 11:25:54,156 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@65] - FINISHED TEST METHOD testQuota
[junit] 2014-07-19 11:25:54,156 [myid:] - INFO [main:ClientBase@520] - tearDown starting
[junit] 2014-07-19 11:25:54,157 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@1346] - Session establishment complete on server 127.0.0.1/127.0.0.1:11221, sessionid = 0x1474e28050b0000, negotiated timeout = 30000
[junit] 2014-07-19 11:25:54,158 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@685] - Processed session termination for sessionid: 0x1474e28050b0000
[junit] 2014-07-19 11:25:54,159 [myid:] - INFO [SyncThread:0:FileTxnLog@200] - Creating new log file: log.c
[junit] 2014-07-19 11:25:54,171 [myid:] - INFO [main:ZooKeeper@968] - Session: 0x1474e28050b0000 closed
[junit] 2014-07-19 11:25:54,171 [myid:] - INFO [main:ClientBase@490] - STOPPING server
[junit] 2014-07-19 11:25:54,171 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2014-07-19 11:25:54,172 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-07-19 11:25:54,172 [myid:] - INFO [NIOWorkerThread-2:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=Connections,name2=127.0.0.1,name3=0x1474e28050b0000]
[junit] 2014-07-19 11:25:54,172 [myid:] - INFO [NIOWorkerThread-2:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:58220 which had sessionid 0x1474e28050b0000
[junit] 2014-07-19 11:25:54,173 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-07-19 11:25:54,177 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2014-07-19 11:25:54,179 [myid:] - INFO [main:ZooKeeperServer@443] - shutting down
[junit] 2014-07-19 11:25:54,233 [myid:] - INFO [main:SessionTrackerImpl@205] - Shutting down
[junit] 2014-07-19 11:25:54,233 [myid:] - INFO [main:PrepRequestProcessor@981] - Shutting down
[junit] 2014-07-19 11:25:54,233 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-07-19 11:25:54,235 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-07-19 11:25:54,235 [myid:] - INFO [main:FinalRequestProcessor@476] - shutdown of request processor complete
[junit] 2014-07-19 11:25:54,235 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree]
[junit] 2014-07-19 11:25:54,235 [myid:] - INFO [main:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port-1]
[junit] 2014-07-19 11:25:54,236 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-07-19 11:25:54,241 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-07-19 11:25:55,229 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-07-19 11:25:55,233 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2014-07-19 11:25:55,233 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 4.415 sec
BUILD FAILED
f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build.xml:1333: Tests failed!
Total time: 74 minutes 4 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
4 tests failed.
REGRESSION: org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment
Error Message:
Not following
Stack Trace:
junit.framework.AssertionFailedError: Not following
at org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment(QuorumTest.java:408)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
REGRESSION: org.apache.zookeeper.test.ReconfigTest.testLeaderTimesoutOnNewQuorum
Error Message:
expected:<[leaderelection]> but was:<[following]>
Stack Trace:
junit.framework.AssertionFailedError: expected:<[leaderelection]> but was:<[following]>
at org.apache.zookeeper.test.ReconfigTest.testLeaderTimesoutOnNewQuorum(ReconfigTest.java:441)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.server.admin.CommandsTest.testMonitor
Error Message:
Result from command monitor missing field "open_file_descriptor_count"
{}
Stack Trace:
junit.framework.AssertionFailedError: Result from command monitor missing field "open_file_descriptor_count"
{}
at org.apache.zookeeper.server.admin.CommandsTest.testCommand(CommandsTest.java:65)
at org.apache.zookeeper.server.admin.CommandsTest.testCommand(CommandsTest.java:81)
at org.apache.zookeeper.server.admin.CommandsTest.testMonitor(CommandsTest.java:161)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: org.apache.zookeeper.test.TruncateTest.testTruncationNullLog
Error Message:
Should not get here
Stack Trace:
junit.framework.AssertionFailedError: Should not get here
at org.apache.zookeeper.test.TruncateTest.testTruncationNullLog(TruncateTest.java:132)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)