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/21 13:21:13 UTC
ZooKeeper-trunk-jdk7 - Build # 920 - Failure
See https://builds.apache.org/job/ZooKeeper-trunk-jdk7/920/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 214379 lines...]
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:991)
[junit] 2014-07-21 11:21:09,073 [myid:] - INFO [QuorumPeer[myid=5]/0:0:0:0:0:0:0:0:11305:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=LeaderElection]
[junit] 2014-07-21 11:21:09,077 [myid:] - INFO [QuorumPeer[myid=5]/0:0:0:0:0:0:0:0:11305:QuorumPeer@1001] - LEADING
[junit] 2014-07-21 11:21:09,077 [myid:] - INFO [QuorumPeer[myid=5]/0:0:0:0:0:0:0:0:11305:ZooKeeperServer@781] - minSessionTimeout set to 4000
[junit] 2014-07-21 11:21:09,078 [myid:] - INFO [QuorumPeer[myid=5]/0:0:0:0:0:0:0:0:11305:ZooKeeperServer@790] - maxSessionTimeout set to 40000
[junit] 2014-07-21 11:21:09,078 [myid:] - INFO [QuorumPeer[myid=5]/0:0:0:0:0:0:0:0:11305:ZooKeeperServer@152] - Created server with tickTime 2000 minSessionTimeout 4000 maxSessionTimeout 40000 datadir /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/tmp/test8231874464311190693.junit.dir/version-2 snapdir /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/tmp/test8231874464311190693.junit.dir/version-2
[junit] 2014-07-21 11:21:09,078 [myid:] - INFO [QuorumPeer[myid=5]/0:0:0:0:0:0:0:0:11305:Leader@409] - LEADING - LEADER ELECTION TOOK - 1
[junit] 2014-07-21 11:21:09,078 [myid:] - INFO [QuorumPeer[myid=5]/0:0:0:0:0:0:0:0:11305:FileTxnSnapLog@298] - Snapshotting: 0x0 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/tmp/test8231874464311190693.junit.dir/version-2/snapshot.0
[junit] 2014-07-21 11:21:09,077 [myid:] - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11302:MBeanRegistry@119] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=LeaderElection]
[junit] 2014-07-21 11:21:09,085 [myid:] - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11302:QuorumPeer@989] - FOLLOWING
[junit] 2014-07-21 11:21:09,085 [myid:] - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11302:ZooKeeperServer@781] - minSessionTimeout set to 4000
[junit] 2014-07-21 11:21:09,085 [myid:] - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11302:ZooKeeperServer@790] - maxSessionTimeout set to 40000
[junit] 2014-07-21 11:21:09,086 [myid:] - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11302:ZooKeeperServer@152] - Created server with tickTime 2000 minSessionTimeout 4000 maxSessionTimeout 40000 datadir /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/tmp/test8700816573016622124.junit.dir/version-2 snapdir /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/tmp/test8700816573016622124.junit.dir/version-2
[junit] 2014-07-21 11:21:09,086 [myid:] - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11302:Follower@65] - FOLLOWING - LEADER ELECTION TOOK - 1
[junit] 2014-07-21 11:21:09,109 [myid:] - INFO [LearnerHandler-/127.0.0.1:51939:LearnerHandler@361] - Follower sid: 2 : info : 127.0.0.1:12292:12297:participant;127.0.0.1:11302
[junit] 2014-07-21 11:21:09,147 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11301
[junit] 2014-07-21 11:21:09,148 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11301:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:40567
[junit] 2014-07-21 11:21:09,157 [myid:] - INFO [NIOWorkerThread-3:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:40567
[junit] 2014-07-21 11:21:09,158 [myid:] - INFO [NIOWorkerThread-3:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:40567 (no session established for client)
[junit] 2014-07-21 11:21:09,411 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11301
[junit] 2014-07-21 11:21:09,412 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11301:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:40568
[junit] 2014-07-21 11:21:09,418 [myid:] - INFO [NIOWorkerThread-4:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:40568
[junit] 2014-07-21 11:21:09,418 [myid:] - INFO [NIOWorkerThread-4:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:40568 (no session established for client)
[junit] 2014-07-21 11:21:09,673 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11301
[junit] 2014-07-21 11:21:09,674 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11301:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:40569
[junit] 2014-07-21 11:21:09,685 [myid:] - INFO [NIOWorkerThread-5:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:40569
[junit] 2014-07-21 11:21:09,686 [myid:] - INFO [NIOWorkerThread-5:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:40569 (no session established for client)
[junit] 2014-07-21 11:21:09,945 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11301
[junit] 2014-07-21 11:21:09,946 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11301:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:40570
[junit] 2014-07-21 11:21:09,957 [myid:] - INFO [NIOWorkerThread-6:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:40570
[junit] 2014-07-21 11:21:09,958 [myid:] - INFO [NIOWorkerThread-6:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:40570 (no session established for client)
[junit] 2014-07-21 11:21:10,077 [myid:] - INFO [LearnerHandler-/127.0.0.1:51944:LearnerHandler@361] - Follower sid: 3 : info : 127.0.0.1:12293:12298:participant;127.0.0.1:11303
[junit] 2014-07-21 11:21:10,089 [myid:] - INFO [LearnerHandler-/127.0.0.1:51945:LearnerHandler@361] - Follower sid: 4 : info : 127.0.0.1:12294:12299:participant;127.0.0.1:11304
[junit] 2014-07-21 11:21:10,097 [myid:] - INFO [LearnerHandler-/127.0.0.1:51946:LearnerHandler@361] - Follower sid: 1 : info : 127.0.0.1:12291:12296:participant;127.0.0.1:11301
Build timed out (after 60 minutes). Marking the build as failed.
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Build was aborted
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
[junit] 2014-07-21 11:21:10,217 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11301
[junit] 2014-07-21 11:21:10,218 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11301:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:40574
[junit] 2014-07-21 11:21:10,225 [myid:] - INFO [NIOWorkerThread-7:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:40574
[junit] 2014-07-21 11:21:10,226 [myid:] - INFO [NIOWorkerThread-7:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:40574 (no session established for client)
[junit] 2014-07-21 11:21:10,237 [myid:] - INFO [LearnerHandler-/127.0.0.1:51946:LearnerHandler@659] - Synchronizing with Follower sid: 1 maxCommittedLog=0x0 minCommittedLog=0x0 lastProcessedZxid=0x0 peerLastZxid=0x0
[junit] 2014-07-21 11:21:10,237 [myid:] - INFO [LearnerHandler-/127.0.0.1:51946:LearnerHandler@703] - Sending DIFF zxid=0x0 for peer sid: 1
[junit] 2014-07-21 11:21:10,237 [myid:] - INFO [LearnerHandler-/127.0.0.1:51944:LearnerHandler@659] - Synchronizing with Follower sid: 3 maxCommittedLog=0x0 minCommittedLog=0x0 lastProcessedZxid=0x0 peerLastZxid=0x0
[junit] 2014-07-21 11:21:10,248 [myid:] - INFO [LearnerHandler-/127.0.0.1:51944:LearnerHandler@703] - Sending DIFF zxid=0x0 for peer sid: 3
[junit] 2014-07-21 11:21:10,249 [myid:] - INFO [LearnerHandler-/127.0.0.1:51945:LearnerHandler@659] - Synchronizing with Follower sid: 4 maxCommittedLog=0x0 minCommittedLog=0x0 lastProcessedZxid=0x0 peerLastZxid=0x0
[junit] 2014-07-21 11:21:10,249 [myid:] - INFO [LearnerHandler-/127.0.0.1:51945:LearnerHandler@703] - Sending DIFF zxid=0x0 for peer sid: 4
[junit] 2014-07-21 11:21:10,273 [myid:] - INFO [LearnerHandler-/127.0.0.1:51939:LearnerHandler@659] - Synchronizing with Follower sid: 2 maxCommittedLog=0x0 minCommittedLog=0x0 lastProcessedZxid=0x0 peerLastZxid=0x0
[junit] 2014-07-21 11:21:10,273 [myid:] - INFO [LearnerHandler-/127.0.0.1:51939:LearnerHandler@703] - Sending DIFF zxid=0x0 for peer sid: 2
[junit] 2014-07-21 11:21:10,277 [myid:] - INFO [QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11301:Learner@332] - Getting a diff from the leader 0x0
[junit] 2014-07-21 11:21:10,277 [myid:] - INFO [QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11301:Learner@475] - Learner received NEWLEADER message
[junit] 2014-07-21 11:21:10,297 [myid:] - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11302:Learner@332] - Getting a diff from the leader 0x0
[junit] 2014-07-21 11:21:10,297 [myid:] - INFO [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11302:Learner@475] - Learner received NEWLEADER message
[junit] 2014-07-21 11:21:10,305 [myid:] - INFO [QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11301:FileTxnSnapLog@298] - Snapshotting: 0x0 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/tmp/test2655411181164079182.junit.dir/version-2/snapshot.0
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
2 tests failed.
REGRESSION: org.apache.zookeeper.test.CnxManagerTest.testWorkerThreads
Error Message:
Mon Jul 21 11:00:03 UTC 2014 Incorrect number of Worker threads for sid=0 expected 4 found 2
Stack Trace:
junit.framework.AssertionFailedError: Mon Jul 21 11:00:03 UTC 2014 Incorrect number of Worker threads for sid=0 expected 4 found 2
at org.apache.zookeeper.test.CnxManagerTest.testWorkerThreads(CnxManagerTest.java:343)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
FAILED: TEST-org.apache.zookeeper.test.QuorumTest.xml.<init>
Error Message:
Stack Trace:
Test report file /home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/logs/TEST-org.apache.zookeeper.test.QuorumTest.xml was length 0