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
