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 2013/07/13 09:54:40 UTC
ZooKeeper_branch34_solaris - Build # 587 - Still Failing
See https://builds.apache.org/job/ZooKeeper_branch34_solaris/587/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 15523 lines...]
[junit] 2013-07-13 07:53:01,542 [myid:] - WARN [SendWorker:2:QuorumCnxManager$SendWorker@690] - Interrupted while waiting for message on queue
[junit] java.lang.InterruptedException
[junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:1961)
[junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2038)
[junit] at java.util.concurrent.ArrayBlockingQueue.poll(ArrayBlockingQueue.java:342)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.pollSendQueue(QuorumCnxManager.java:842)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.access$500(QuorumCnxManager.java:62)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager$SendWorker.run(QuorumCnxManager.java:678)
[junit] 2013-07-13 07:53:01,544 [myid:] - WARN [SendWorker:2:QuorumCnxManager$SendWorker@699] - Send worker leaving thread
[junit] 2013-07-13 07:53:01,543 [myid:] - INFO [QuorumPeer[myid=2]/0.0.0.0:11275:CommitProcessor@181] - Shutting down
[junit] 2013-07-13 07:53:01,544 [myid:] - INFO [QuorumPeer[myid=2]/0.0.0.0:11275:FinalRequestProcessor@415] - shutdown of request processor complete
[junit] 2013-07-13 07:53:01,544 [myid:] - INFO [QuorumPeer[myid=2]/0.0.0.0:11275:SyncRequestProcessor@175] - Shutting down
[junit] 2013-07-13 07:53:01,544 [myid:] - WARN [QuorumPeer[myid=2]/0.0.0.0:11275:QuorumPeer@768] - QuorumPeer main thread exited
[junit] 2013-07-13 07:53:01,993 [myid:] - INFO [WorkerSender[myid=2]:FastLeaderElection$Messenger$WorkerSender@370] - WorkerSender is down
[junit] 2013-07-13 07:53:01,993 [myid:] - INFO [WorkerReceiver[myid=2]:FastLeaderElection$Messenger$WorkerReceiver@340] - WorkerReceiver is down
[junit] 2013-07-13 07:53:02,044 [myid:] - INFO [main:NIOServerCnxnFactory@94] - binding to port 0.0.0.0/0.0.0.0:11275
[junit] 2013-07-13 07:53:02,044 [myid:] - INFO [main:CnxManagerTest@430] - Round 4, restarting peer 2
[junit] 2013-07-13 07:53:02,045 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/tmp/test5200146761889411974.junit.dir/version-2/snapshot.200000000
[junit] 2013-07-13 07:53:02,046 [myid:] - INFO [Thread-42:QuorumCnxManager$Listener@497] - My election bind port: 0.0.0.0/0.0.0.0:11276
[junit] 2013-07-13 07:53:02,048 [myid:] - INFO [QuorumPeer[myid=2]/0.0.0.0:11275:QuorumPeer@670] - LOOKING
[junit] 2013-07-13 07:53:02,048 [myid:] - INFO [QuorumPeer[myid=2]/0.0.0.0:11275:FastLeaderElection@740] - New election. My id = 2, proposed zxid=0x200000000
[junit] 2013-07-13 07:53:02,049 [myid:] - INFO [0.0.0.0/0.0.0.0:11270:QuorumCnxManager$Listener@504] - Received connection request /192.87.106.251:56345
[junit] 2013-07-13 07:53:02,049 [myid:] - INFO [WorkerReceiver[myid=0]:FastLeaderElection@542] - Notification: 2 (n.leader), 0x200000000 (n.zxid), 0x1 (n.round), LOOKING (n.state), 2 (n.sid), 0x2 (n.peerEPoch), FOLLOWING (my state)
[junit] 2013-07-13 07:53:02,049 [myid:] - INFO [WorkerReceiver[myid=2]:FastLeaderElection@542] - Notification: 1 (n.leader), 0x100000000 (n.zxid), 0x2 (n.round), FOLLOWING (n.state), 0 (n.sid), 0x1 (n.peerEPoch), LOOKING (my state)
[junit] 2013-07-13 07:53:02,049 [myid:] - INFO [0.0.0.0/0.0.0.0:11273:QuorumCnxManager$Listener@504] - Received connection request /192.87.106.251:56346
[junit] 2013-07-13 07:53:02,049 [myid:] - INFO [WorkerReceiver[myid=2]:FastLeaderElection@542] - Notification: 2 (n.leader), 0x200000000 (n.zxid), 0x1 (n.round), LOOKING (n.state), 2 (n.sid), 0x2 (n.peerEPoch), LOOKING (my state)
[junit] 2013-07-13 07:53:02,050 [myid:] - INFO [WorkerReceiver[myid=2]:FastLeaderElection@542] - Notification: 1 (n.leader), 0x100000000 (n.zxid), 0x2 (n.round), FOLLOWING (n.state), 0 (n.sid), 0x1 (n.peerEPoch), LOOKING (my state)
[junit] 2013-07-13 07:53:02,050 [myid:] - INFO [WorkerReceiver[myid=1]:FastLeaderElection@542] - Notification: 2 (n.leader), 0x200000000 (n.zxid), 0x1 (n.round), LOOKING (n.state), 2 (n.sid), 0x2 (n.peerEPoch), LEADING (my state)
[junit] 2013-07-13 07:53:02,050 [myid:] - INFO [WorkerReceiver[myid=2]:FastLeaderElection@542] - Notification: 1 (n.leader), 0x100000000 (n.zxid), 0x2 (n.round), LEADING (n.state), 1 (n.sid), 0x1 (n.peerEPoch), LOOKING (my state)
[junit] 2013-07-13 07:53:02,050 [myid:] - INFO [WorkerReceiver[myid=2]:FastLeaderElection@542] - Notification: 1 (n.leader), 0x100000000 (n.zxid), 0x2 (n.round), LEADING (n.state), 1 (n.sid), 0x1 (n.peerEPoch), FOLLOWING (my state)
[junit] 2013-07-13 07:53:02,050 [myid:] - INFO [QuorumPeer[myid=2]/0.0.0.0:11275:QuorumPeer@738] - FOLLOWING
[junit] 2013-07-13 07:53:02,050 [myid:] - INFO [QuorumPeer[myid=2]/0.0.0.0:11275:ZooKeeperServer@162] - Created server with tickTime 1000 minSessionTimeout 2000 maxSessionTimeout 20000 datadir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/tmp/test5200146761889411974.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/tmp/test5200146761889411974.junit.dir/version-2
[junit] 2013-07-13 07:53:02,050 [myid:] - INFO [QuorumPeer[myid=2]/0.0.0.0:11275:Follower@63] - FOLLOWING - LEADER ELECTION TOOK - 2
[junit] 2013-07-13 07:53:02,052 [myid:] - INFO [LearnerHandler-/192.87.106.251:56347:LearnerHandler@270] - Follower sid: 2 : info : org.apache.zookeeper.server.quorum.QuorumPeer$QuorumServer@148e798
[junit] 2013-07-13 07:53:02,052 [myid:] - INFO [LearnerHandler-/192.87.106.251:56347:LearnerHandler@325] - Synchronizing with Follower sid: 2 maxCommittedLog=0x0 minCommittedLog=0x0 peerLastZxid=0x200000000
[junit] 2013-07-13 07:53:02,052 [myid:] - INFO [LearnerHandler-/192.87.106.251:56347:LearnerHandler@402] - Sending DIFF
[junit] 2013-07-13 07:53:02,052 [myid:] - INFO [QuorumPeer[myid=2]/0.0.0.0:11275:Learner@322] - Getting a diff from the leader 0x200000000
[junit] 2013-07-13 07:53:02,053 [myid:] - INFO [QuorumPeer[myid=2]/0.0.0.0:11275:FileTxnSnapLog@240] - Snapshotting: 0x200000000 to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/tmp/test5200146761889411974.junit.dir/version-2/snapshot.200000000
[junit] 2013-07-13 07:53:02,107 [myid:] - INFO [LearnerHandler-/192.87.106.251:56347:LearnerHandler@462] - Received NEWLEADER-ACK message from 2
[junit] 2013-07-13 07:53:02,543 [myid:] - INFO [0.0.0.0/0.0.0.0:11276:QuorumCnxManager$Listener@523] - Leaving listener
[junit] 2013-07-13 07:53:02,553 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@57] - FINISHED TEST METHOD testWorkerThreads
[junit] 2013-07-13 07:53:02,553 [myid:] - INFO [main:ZKTestCase$1@60] - SUCCEEDED testWorkerThreads
[junit] 2013-07-13 07:53:02,553 [myid:] - INFO [main:ZKTestCase$1@55] - FINISHED testWorkerThreads
[junit] 2013-07-13 07:53:03,013 [myid:] - INFO [WorkerReceiver[myid=2]:FastLeaderElection$Messenger$WorkerReceiver@340] - WorkerReceiver is down
[junit] 2013-07-13 07:53:03,013 [myid:] - INFO [WorkerSender[myid=2]:FastLeaderElection$Messenger$WorkerSender@370] - WorkerSender is down
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 45.949 sec
[junit] Running org.apache.zookeeper.server.quorum.LearnerTest
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build.xml:1220: Process fork failed.
Total time: 7 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
1 tests failed.
FAILED: org.apache.zookeeper.server.quorum.LearnerTest.unknown
Error Message:
Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.
Stack Trace:
junit.framework.AssertionFailedError: Forked Java VM exited abnormally. Please note the time in the report does not reflect the time until the VM exit.