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.
