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/25 12:30:28 UTC
ZooKeeper-trunk-WinVS2008_java - Build # 785 - Still Failing
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 # 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)