See https://builds.apache.org/job/ZooKeeper_branch35_jdk7/715/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE
###########################
[...truncated 404590 lines...]
[junit] at
org.apache.zookeeper.server.quorum.QuorumCnxManager.toSend(QuorumCnxManager.java:419)
[junit] at
org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.process(FastLeaderElection.java:486)
[junit] at
org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.run(FastLeaderElection.java:465)
[junit] at java.lang.Thread.run(Thread.java:745)
[junit] 2016-10-29 09:00:38,376 [myid:] - INFO
[QuorumPeer[myid=5](plain=/127.0.0.1:30089)(secure=disabled):FastLeaderElection@928]
- Notification time out: 60000
[junit] 2016-10-29 09:00:38,377 [myid:] - INFO
[WorkerReceiver[myid=3]:FastLeaderElection@688] - Notification: 2 (message
format version), 5 (n.leader), 0x100000071 (n.zxid), 0x0 (n.round), LOOKING
(n.state), 5 (n.sid), 0x1 (n.peerEPoch), LEADING (my state)100000000 (n.config
version)
[junit] 2016-10-29 09:00:38,377 [myid:] - INFO
[WorkerReceiver[myid=5]:FastLeaderElection@688] - Notification: 2 (message
format version), 5 (n.leader), 0x100000071 (n.zxid), 0x0 (n.round), LOOKING
(n.state), 5 (n.sid), 0x1 (n.peerEPoch), LOOKING (my state)100000000 (n.config
version)
[junit] 2016-10-29 09:00:38,377 [myid:] - INFO
[QuorumPeer[myid=4](plain=/127.0.0.1:30088)(secure=disabled):FastLeaderElection@928]
- Notification time out: 60000
[junit] 2016-10-29 09:00:38,377 [myid:] - INFO
[WorkerReceiver[myid=4]:FastLeaderElection@688] - Notification: 2 (message
format version), 5 (n.leader), 0x100000071 (n.zxid), 0x0 (n.round), LOOKING
(n.state), 5 (n.sid), 0x1 (n.peerEPoch), LOOKING (my state)100000000 (n.config
version)
[junit] 2016-10-29 09:00:38,378 [myid:] - INFO
[WorkerReceiver[myid=3]:FastLeaderElection@688] - Notification: 2 (message
format version), 5 (n.leader), 0x100000071 (n.zxid), 0x0 (n.round), LOOKING
(n.state), 4 (n.sid), 0x1 (n.peerEPoch), LEADING (my state)100000000 (n.config
version)
[junit] 2016-10-29 09:00:38,378 [myid:] - INFO
[WorkerReceiver[myid=4]:FastLeaderElection@688] - Notification: 2 (message
format version), 5 (n.leader), 0x100000071 (n.zxid), 0x0 (n.round), LOOKING
(n.state), 4 (n.sid), 0x1 (n.peerEPoch), LOOKING (my state)100000000 (n.config
version)
[junit] 2016-10-29 09:00:38,378 [myid:] - INFO
[WorkerReceiver[myid=4]:FastLeaderElection@688] - Notification: 2 (message
format version), 3 (n.leader), 0x0 (n.zxid), 0xffffffffffffffff (n.round),
LEADING (n.state), 3 (n.sid), 0x1 (n.peerEPoch), LOOKING (my state)100000000
(n.config version)
[junit] 2016-10-29 09:00:38,382 [myid:] - INFO
[WorkerReceiver[myid=5]:FastLeaderElection@688] - Notification: 2 (message
format version), 5 (n.leader), 0x100000071 (n.zxid), 0x0 (n.round), LOOKING
(n.state), 4 (n.sid), 0x1 (n.peerEPoch), LOOKING (my state)100000000 (n.config
version)
[junit] 2016-10-29 09:00:38,382 [myid:] - INFO
[WorkerReceiver[myid=5]:FastLeaderElection@688] - Notification: 2 (message
format version), 3 (n.leader), 0x0 (n.zxid), 0xffffffffffffffff (n.round),
LEADING (n.state), 3 (n.sid), 0x1 (n.peerEPoch), LOOKING (my state)100000000
(n.config version)
[junit] 2016-10-29 09:01:38,380 [myid:] - WARN
[WorkerSender[myid=4]:QuorumCnxManager@455] - Cannot open channel to 1 at
election address /127.0.0.1:30080
[junit] java.net.ConnectException: Connection refused
[junit] at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit] at
java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
[junit] at
java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
[junit] at
java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
[junit] at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
[junit] at java.net.Socket.connect(Socket.java:579)
[junit] at
org.apache.zookeeper.server.quorum.QuorumCnxManager.connectOne(QuorumCnxManager.java:441)
[junit] at
org.apache.zookeeper.server.quorum.QuorumCnxManager.connectOne(QuorumCnxManager.java:482)
[junit] at
org.apache.zookeeper.server.quorum.QuorumCnxManager.toSend(QuorumCnxManager.java:419)
[junit] at
org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.process(FastLeaderElection.java:486)
[junit] at
org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.run(FastLeaderElection.java:465)
[junit] at java.lang.Thread.run(Thread.java:745)
[junit] 2016-10-29 09:01:38,381 [myid:] - WARN
[WorkerSender[myid=4]:QuorumCnxManager@455] - Cannot open channel to 2 at
election address /127.0.0.1:30081
[junit] java.net.ConnectException: Connection refused
[junit] at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit] at
java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
[junit] at
java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
[junit] at
java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
[junit] at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
[junit] at java.net.Socket.connect(Socket.java:579)
[junit] at
org.apache.zookeeper.server.quorum.QuorumCnxManager.connectOne(QuorumCnxManager.java:441)
[junit] at
org.apache.zookeeper.server.quorum.QuorumCnxManager.connectOne(QuorumCnxManager.java:482)
[junit] at
org.apache.zookeeper.server.quorum.QuorumCnxManager.toSend(QuorumCnxManager.java:419)
[junit] at
org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.process(FastLeaderElection.java:486)
[junit] at
org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.run(FastLeaderElection.java:465)
[junit] at java.lang.Thread.run(Thread.java:745)
[junit] 2016-10-29 09:01:38,381 [myid:] - INFO
[QuorumPeer[myid=4](plain=/127.0.0.1:30088)(secure=disabled):FastLeaderElection@928]
- Notification time out: 60000
[junit] 2016-10-29 09:01:38,382 [myid:] - INFO
[WorkerReceiver[myid=4]:FastLeaderElection@688] - Notification: 2 (message
format version), 5 (n.leader), 0x100000071 (n.zxid), 0x0 (n.round), LOOKING
(n.state), 4 (n.sid), 0x1 (n.peerEPoch), LOOKING (my state)100000000 (n.config
version)
[junit] 2016-10-29 09:01:38,382 [myid:] - INFO
[WorkerReceiver[myid=3]:FastLeaderElection@688] - Notification: 2 (message
format version), 5 (n.leader), 0x100000071 (n.zxid), 0x0 (n.round), LOOKING
(n.state), 4 (n.sid), 0x1 (n.peerEPoch), LEADING (my state)100000000 (n.config
version)
[junit] 2016-10-29 09:01:38,382 [myid:] - INFO
[WorkerReceiver[myid=5]:FastLeaderElection@688] - Notification: 2 (message
format version), 5 (n.leader), 0x100000071 (n.zxid), 0x0 (n.round), LOOKING
(n.state), 4 (n.sid), 0x1 (n.peerEPoch), LOOKING (my state)100000000 (n.config
version)
[junit] 2016-10-29 09:01:38,383 [myid:] - INFO
[WorkerReceiver[myid=4]:FastLeaderElection@688] - Notification: 2 (message
format version), 3 (n.leader), 0x0 (n.zxid), 0xffffffffffffffff (n.round),
LEADING (n.state), 3 (n.sid), 0x1 (n.peerEPoch), LOOKING (my state)100000000
(n.config version)
[junit] Running org.apache.zookeeper.test.QuorumTest in thread 8
[junit] Tests run: 1, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0
sec, Thread: 8, Class: org.apache.zookeeper.test.QuorumTest
[junit] Test org.apache.zookeeper.test.QuorumTest FAILED (timeout)
BUILD FAILED
/home/jenkins/jenkins-slave/workspace/ZooKeeper_branch35_jdk7/build.xml:1207:
Tests failed!
Total time: 23 minutes 42 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any)
##############################
7 tests failed.
FAILED:
org.apache.zookeeper.RemoveWatchesTest.testRemoveAllDataWatchesOnAPath[1]
Error Message:
waiting for server up
Stack Trace:
junit.framework.AssertionFailedError: waiting for server up
at
org.apache.zookeeper.test.ClientBase.startServerInstance(ClientBase.java:364)
at org.apache.zookeeper.test.ClientBase.startServer(ClientBase.java:469)
at org.apache.zookeeper.test.ClientBase.setUp(ClientBase.java:460)
at
org.apache.zookeeper.RemoveWatchesTest.setUp(RemoveWatchesTest.java:63)
FAILED:
org.apache.zookeeper.server.PurgeTxnTest.testPurgeWhenLogRollingInProgress
Error Message:
ZkClient ops is not finished!
Stack Trace:
junit.framework.AssertionFailedError: ZkClient ops is not finished!
at
org.apache.zookeeper.server.PurgeTxnTest.manyClientOps(PurgeTxnTest.java:445)
at
org.apache.zookeeper.server.PurgeTxnTest.testPurgeWhenLogRollingInProgress(PurgeTxnTest.java:148)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED:
org.apache.zookeeper.server.ZooKeeperServerMainTest.testNonRecoverableError
Error Message:
test timed out after 30000 milliseconds
Stack Trace:
org.junit.runners.model.TestTimedOutException: test timed out after 30000
milliseconds
at org.apache.log4j.Category.callAppenders(Category.java:205)
at org.apache.log4j.Category.forcedLog(Category.java:391)
at org.apache.log4j.Category.log(Category.java:856)
at org.slf4j.impl.Log4jLoggerAdapter.info(Log4jLoggerAdapter.java:346)
at
org.apache.zookeeper.client.FourLetterWordMain.send4LetterWord(FourLetterWordMain.java:85)
at
org.apache.zookeeper.client.FourLetterWordMain.send4LetterWord(FourLetterWordMain.java:69)
at
org.apache.zookeeper.test.ClientBase.waitForServerUp(ClientBase.java:241)
at
org.apache.zookeeper.test.ClientBase.waitForServerUp(ClientBase.java:232)
at
org.apache.zookeeper.server.ZooKeeperServerMainTest.testNonRecoverableError(ZooKeeperServerMainTest.java:162)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.lang.Thread.run(Thread.java:745)
FAILED:
org.apache.zookeeper.server.ZxidRolloverTest.testRolloverThenFollowerRestart
Error Message:
waiting for server up
Stack Trace:
junit.framework.AssertionFailedError: waiting for server up
at
org.apache.zookeeper.server.ZxidRolloverTest.start(ZxidRolloverTest.java:159)
at
org.apache.zookeeper.server.ZxidRolloverTest.testRolloverThenFollowerRestart(ZxidRolloverTest.java:328)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED:
org.apache.zookeeper.server.quorum.QuorumPeerMainTest.testQuorumPeerExitTime
Error Message:
QuorumPeer took 128720 to shutdown, expected 3000
Stack Trace:
junit.framework.AssertionFailedError: QuorumPeer took 128720 to shutdown,
expected 3000
at
org.apache.zookeeper.server.quorum.QuorumPeerMainTest.testQuorumPeerExitTime(QuorumPeerMainTest.java:697)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED:
org.apache.zookeeper.server.quorum.RaceConditionTest.testRaceConditionBetweenLeaderAndAckRequestProcessor
Error Message:
test timed out after 30000 milliseconds
Stack Trace:
org.junit.runners.model.TestTimedOutException: test timed out after 30000
milliseconds
at org.apache.log4j.Category.callAppenders(Category.java:205)
at org.apache.log4j.Category.forcedLog(Category.java:391)
at org.apache.log4j.Category.log(Category.java:856)
at org.slf4j.impl.Log4jLoggerAdapter.info(Log4jLoggerAdapter.java:346)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:98)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.zookeeper.test.QuorumTest.testLeaderShutdown
Error Message:
Timeout occurred. Please note the time in the report does not reflect the time
until the timeout.
Stack Trace:
junit.framework.AssertionFailedError: Timeout occurred. Please note the time in
the report does not reflect the time until the timeout.
at java.lang.Thread.run(Thread.java:745)
