See https://builds.apache.org/job/ZooKeeper-trunk-openjdk7/1677/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE
###########################
[...truncated 61.44 MB...]
[junit] at
org.apache.zookeeper.server.quorum.LearnerHandler.run(LearnerHandler.java:623)
[junit] 2017-10-24 21:02:23,727 [myid:] - INFO
[ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] -
ConnnectionExpirerThread interrupted
[junit] 2017-10-24 21:02:23,727 [myid:] - INFO
[NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420]
- selector thread exitted run method
[junit] 2017-10-24 21:02:23,728 [myid:] - INFO [ProcessThread(sid:5
cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2017-10-24 21:02:23,728 [myid:] - INFO
[/127.0.0.1:27543:QuorumCnxManager$Listener@674] - Leaving listener
[junit] 2017-10-24 21:02:23,728 [myid:] - INFO
[QuorumPeer[myid=5](plain=/127.0.0.1:27541)(secure=disabled):MBeanRegistry@128]
- Unregister MBean
[org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader]
[junit] 2017-10-24 21:02:23,728 [myid:] - INFO [main:QuorumUtil@254] -
Shutting down leader election
QuorumPeer[myid=5](plain=/127.0.0.1:27541)(secure=disabled)
[junit] 2017-10-24 21:02:23,728 [myid:] - WARN
[QuorumPeer[myid=5](plain=/127.0.0.1:27541)(secure=disabled):QuorumPeer@1130] -
Unexpected exception
[junit] java.lang.InterruptedException
[junit] at java.lang.Object.wait(Native Method)
[junit] at
org.apache.zookeeper.server.quorum.Leader.lead(Leader.java:564)
[junit] at
org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:1127)
[junit] 2017-10-24 21:02:23,729 [myid:] - INFO
[QuorumPeer[myid=5](plain=/127.0.0.1:27541)(secure=disabled):Leader@627] -
Shutting down
[junit] 2017-10-24 21:02:23,729 [myid:] - WARN
[QuorumPeer[myid=5](plain=/127.0.0.1:27541)(secure=disabled):QuorumPeer@1161] -
PeerState set to LOOKING
[junit] 2017-10-24 21:02:23,729 [myid:] - WARN
[QuorumPeer[myid=5](plain=/127.0.0.1:27541)(secure=disabled):QuorumPeer@1143] -
QuorumPeer main thread exited
[junit] 2017-10-24 21:02:23,729 [myid:] - INFO [main:QuorumUtil@259] -
Waiting for QuorumPeer[myid=5](plain=/127.0.0.1:27541)(secure=disabled) to exit
thread
[junit] 2017-10-24 21:02:23,729 [myid:] - INFO
[QuorumPeer[myid=5](plain=/127.0.0.1:27541)(secure=disabled):MBeanRegistry@128]
- Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5]
[junit] 2017-10-24 21:02:23,729 [myid:] - INFO
[QuorumPeer[myid=5](plain=/127.0.0.1:27541)(secure=disabled):MBeanRegistry@128]
- Unregister MBean
[org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5]
[junit] 2017-10-24 21:02:23,729 [myid:] - INFO
[QuorumPeer[myid=5](plain=/127.0.0.1:27541)(secure=disabled):MBeanRegistry@128]
- Unregister MBean
[org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.1]
[junit] 2017-10-24 21:02:23,729 [myid:] - INFO
[QuorumPeer[myid=5](plain=/127.0.0.1:27541)(secure=disabled):MBeanRegistry@128]
- Unregister MBean
[org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.2]
[junit] 2017-10-24 21:02:23,729 [myid:] - INFO
[QuorumPeer[myid=5](plain=/127.0.0.1:27541)(secure=disabled):MBeanRegistry@128]
- Unregister MBean
[org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.3]
[junit] 2017-10-24 21:02:23,729 [myid:] - INFO
[main:FourLetterWordMain@87] - connecting to 127.0.0.1 27529
[junit] 2017-10-24 21:02:23,730 [myid:] - INFO [main:QuorumUtil@243] -
127.0.0.1:27529 is no longer accepting client connections
[junit] 2017-10-24 21:02:23,730 [myid:] - INFO
[main:FourLetterWordMain@87] - connecting to 127.0.0.1 27532
[junit] 2017-10-24 21:02:23,730 [myid:] - INFO [main:QuorumUtil@243] -
127.0.0.1:27532 is no longer accepting client connections
[junit] 2017-10-24 21:02:23,730 [myid:] - INFO
[main:FourLetterWordMain@87] - connecting to 127.0.0.1 27535
[junit] 2017-10-24 21:02:23,730 [myid:] - INFO [main:QuorumUtil@243] -
127.0.0.1:27535 is no longer accepting client connections
[junit] 2017-10-24 21:02:23,730 [myid:] - INFO
[main:FourLetterWordMain@87] - connecting to 127.0.0.1 27538
[junit] 2017-10-24 21:02:23,731 [myid:] - INFO [main:QuorumUtil@243] -
127.0.0.1:27538 is no longer accepting client connections
[junit] 2017-10-24 21:02:23,731 [myid:] - INFO
[main:FourLetterWordMain@87] - connecting to 127.0.0.1 27541
[junit] 2017-10-24 21:02:23,731 [myid:] - INFO [main:QuorumUtil@243] -
127.0.0.1:27541 is no longer accepting client connections
[junit] 2017-10-24 21:02:23,732 [myid:] - INFO [main:ZKTestCase$1@68] -
SUCCEEDED testRemoveOneAsynchronous
[junit] 2017-10-24 21:02:23,732 [myid:] - INFO [main:ZKTestCase$1@63] -
FINISHED testRemoveOneAsynchronous
[junit] Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed:
273.067 sec, Thread: 7, Class: org.apache.zookeeper.test.ReconfigTest
[junit] 2017-10-24 21:02:23,810 [myid:] - INFO
[WorkerSender[myid=5]:FastLeaderElection$Messenger$WorkerSender@470] -
WorkerSender is down
[junit] 2017-10-24 21:02:23,811 [myid:] - INFO
[WorkerReceiver[myid=5]:FastLeaderElection$Messenger$WorkerReceiver@440] -
WorkerReceiver is down
[junit] 2017-10-24 21:02:23,814 [myid:] - INFO
[WorkerSender[myid=1]:FastLeaderElection$Messenger$WorkerSender@470] -
WorkerSender is down
[junit] 2017-10-24 21:02:23,822 [myid:] - INFO
[WorkerReceiver[myid=1]:FastLeaderElection$Messenger$WorkerReceiver@440] -
WorkerReceiver is down
[junit] 2017-10-24 21:02:23,822 [myid:] - INFO
[WorkerReceiver[myid=4]:FastLeaderElection$Messenger$WorkerReceiver@440] -
WorkerReceiver is down
[junit] 2017-10-24 21:02:23,826 [myid:] - INFO
[WorkerSender[myid=4]:FastLeaderElection$Messenger$WorkerSender@470] -
WorkerSender is down
[junit] 2017-10-24 21:02:23,829 [myid:] - INFO
[WorkerSender[myid=2]:FastLeaderElection$Messenger$WorkerSender@470] -
WorkerSender is down
[junit] 2017-10-24 21:02:23,830 [myid:] - INFO
[WorkerReceiver[myid=2]:FastLeaderElection$Messenger$WorkerReceiver@440] -
WorkerReceiver is down
[junit] 2017-10-24 21:02:23,850 [myid:] - INFO
[WorkerSender[myid=3]:FastLeaderElection$Messenger$WorkerSender@470] -
WorkerSender is down
[junit] 2017-10-24 21:02:23,850 [myid:] - INFO
[WorkerReceiver[myid=3]:FastLeaderElection$Messenger$WorkerReceiver@440] -
WorkerReceiver is down
[junit] Running org.apache.zookeeper.server.quorum.Zab1_0Test in thread 1
[junit] Tests run: 1, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0
sec, Thread: 1, Class: org.apache.zookeeper.server.quorum.Zab1_0Test
[junit] Test org.apache.zookeeper.server.quorum.Zab1_0Test FAILED (timeout)
fail.build.on.test.failure:
BUILD FAILED
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-openjdk7/build.xml:1267:
The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-openjdk7/build.xml:1271:
Tests failed!
Total time: 16 minutes 43 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)
##############################
3 tests failed.
FAILED:
org.apache.zookeeper.server.quorum.ReconfigRecoveryTest.testCurrentServersAreObserversInNextConfig
Error Message:
waiting for server 3 being up
Stack Trace:
junit.framework.AssertionFailedError: waiting for server 3 being up
at
org.apache.zookeeper.server.quorum.ReconfigRecoveryTest.testCurrentServersAreObserversInNextConfig(ReconfigRecoveryTest.java:224)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
FAILED: org.apache.zookeeper.server.quorum.Zab1_0Test.testNormalObserverRun
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:748)
FAILED:
org.apache.zookeeper.test.WatchEventWhenAutoResetTest.testNodeDataChanged
Error Message:
expected:<NodeDataChanged> but was:<NodeDeleted>
Stack Trace:
junit.framework.AssertionFailedError: expected:<NodeDataChanged> but
was:<NodeDeleted>
at
org.apache.zookeeper.test.WatchEventWhenAutoResetTest$EventsWatcher.assertEvent(WatchEventWhenAutoResetTest.java:67)
at
org.apache.zookeeper.test.WatchEventWhenAutoResetTest.testNodeDataChanged(WatchEventWhenAutoResetTest.java:117)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
