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 2017/08/28 20:00:59 UTC

ZooKeeper-trunk-openjdk7 - Build # 1599 - Still Failing

See https://builds.apache.org/job/ZooKeeper-trunk-openjdk7/1599/

###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 65.37 MB...]
    [junit] 2017-08-28 20:00:56,216 [myid:127.0.0.1:30137] - INFO  [main-SendThread(127.0.0.1:30137):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:30137. Will not attempt to authenticate using SASL (unknown error)
    [junit] 2017-08-28 20:00:56,218 [myid:127.0.0.1:30137] - WARN  [main-SendThread(127.0.0.1:30137):ClientCnxn$SendThread@1235] - Session 0x106550878650000 for server 127.0.0.1/127.0.0.1:30137, unexpected error, closing socket connection and attempting reconnect
    [junit] java.net.ConnectException: Connection refused
    [junit] 	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
    [junit] 	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:739)
    [junit] 	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:357)
    [junit] 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1214)
    [junit] 2017-08-28 20:00:56,565 [myid:127.0.0.1:30073] - INFO  [main-SendThread(127.0.0.1:30073):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:30073. Will not attempt to authenticate using SASL (unknown error)
    [junit] 2017-08-28 20:00:56,566 [myid:127.0.0.1:30073] - WARN  [main-SendThread(127.0.0.1:30073):ClientCnxn$SendThread@1235] - Session 0x1065507d4400000 for server 127.0.0.1/127.0.0.1:30073, unexpected error, closing socket connection and attempting reconnect
    [junit] java.net.ConnectException: Connection refused
    [junit] 	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
    [junit] 	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:739)
    [junit] 	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:357)
    [junit] 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1214)
    [junit] 2017-08-28 20:00:56,726 [myid:] - INFO  [ProcessThread(sid:0 cport:30319)::PrepRequestProcessor@614] - Processed session termination for sessionid: 0x106550dee4d0000
    [junit] 2017-08-28 20:00:56,727 [myid:] - INFO  [SyncThread:0:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port30319,name1=Connections,name2=127.0.0.1,name3=0x106550dee4d0000]
    [junit] 2017-08-28 20:00:56,727 [myid:] - INFO  [main:ZooKeeper@1332] - Session: 0x106550dee4d0000 closed
    [junit] 2017-08-28 20:00:56,727 [myid:] - INFO  [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x106550dee4d0000
    [junit] 2017-08-28 20:00:56,728 [myid:] - INFO  [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 220503
    [junit] 2017-08-28 20:00:56,728 [myid:] - INFO  [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 2431
    [junit] 2017-08-28 20:00:56,728 [myid:] - INFO  [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testWatcherAutoResetWithLocal
    [junit] 2017-08-28 20:00:56,728 [myid:] - INFO  [main:ClientBase@601] - tearDown starting
    [junit] 2017-08-28 20:00:56,728 [myid:] - INFO  [main:ClientBase@571] - STOPPING server
    [junit] 2017-08-28 20:00:56,729 [myid:] - INFO  [main:NettyServerCnxnFactory@464] - shutdown called 0.0.0.0/0.0.0.0:30319
    [junit] 2017-08-28 20:00:56,740 [myid:] - INFO  [main:ZooKeeperServer@545] - shutting down
    [junit] 2017-08-28 20:00:56,741 [myid:] - ERROR [main:ZooKeeperServer@509] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
    [junit] 2017-08-28 20:00:56,741 [myid:] - INFO  [main:SessionTrackerImpl@232] - Shutting down
    [junit] 2017-08-28 20:00:56,741 [myid:] - INFO  [main:PrepRequestProcessor@1010] - Shutting down
    [junit] 2017-08-28 20:00:56,741 [myid:] - INFO  [main:SyncRequestProcessor@191] - Shutting down
    [junit] 2017-08-28 20:00:56,741 [myid:] - INFO  [ProcessThread(sid:0 cport:30319)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
    [junit] 2017-08-28 20:00:56,742 [myid:] - INFO  [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
    [junit] 2017-08-28 20:00:56,742 [myid:] - INFO  [main:FinalRequestProcessor@481] - shutdown of request processor complete
    [junit] 2017-08-28 20:00:56,742 [myid:] - INFO  [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port30319,name1=InMemoryDataTree]
    [junit] 2017-08-28 20:00:56,743 [myid:] - INFO  [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port30319]
    [junit] 2017-08-28 20:00:56,743 [myid:] - INFO  [main:FourLetterWordMain@87] - connecting to 127.0.0.1 30319
    [junit] 2017-08-28 20:00:56,744 [myid:] - INFO  [main:JMXEnv@146] - ensureOnly:[]
    [junit] 2017-08-28 20:00:56,755 [myid:] - INFO  [main:ClientBase@626] - fdcount after test is: 7148 at start it was 7148
    [junit] 2017-08-28 20:00:56,755 [myid:] - INFO  [main:ZKTestCase$1@68] - SUCCEEDED testWatcherAutoResetWithLocal
    [junit] 2017-08-28 20:00:56,755 [myid:] - INFO  [main:ZKTestCase$1@63] - FINISHED testWatcherAutoResetWithLocal
    [junit] Tests run: 105, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 405.964 sec, Thread: 8, Class: org.apache.zookeeper.test.NioNettySuiteTest
    [junit] 2017-08-28 20:00:56,916 [myid:127.0.0.1:30122] - INFO  [main-SendThread(127.0.0.1:30122):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:30122. Will not attempt to authenticate using SASL (unknown error)
    [junit] 2017-08-28 20:00:56,916 [myid:127.0.0.1:30122] - WARN  [main-SendThread(127.0.0.1:30122):ClientCnxn$SendThread@1235] - Session 0x106550821ff0001 for server 127.0.0.1/127.0.0.1:30122, unexpected error, closing socket connection and attempting reconnect
    [junit] java.net.ConnectException: Connection refused
    [junit] 	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
    [junit] 	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:739)
    [junit] 	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:357)
    [junit] 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1214)

fail.build.on.test.failure:

BUILD FAILED
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-openjdk7/build.xml:1220: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-openjdk7/build.xml:1224: Tests failed!

Total time: 9 minutes 48 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) ##############################
1 tests failed.
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)

ZooKeeper-trunk-openjdk7 - Build # 1600 - Still Failing

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk-openjdk7/1600/

###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 66.12 MB...]
    [junit] 	at org.apache.zookeeper.server.quorum.LearnerHandler.shutdown(LearnerHandler.java:902)
    [junit] 	at org.apache.zookeeper.server.quorum.LearnerHandler.run(LearnerHandler.java:623)
    [junit] 2017-08-29 20:00:58,951 [myid:] - INFO  [QuorumPeer[myid=5](plain=/127.0.0.1:19462)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5,name2=Leader]
    [junit] 2017-08-29 20:00:58,952 [myid:] - INFO  [/127.0.0.1:19464:QuorumCnxManager$Listener@674] - Leaving listener
    [junit] 2017-08-29 20:00:58,952 [myid:] - WARN  [QuorumPeer[myid=5](plain=/127.0.0.1:19462)(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:563)
    [junit] 	at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:1127)
    [junit] 2017-08-29 20:00:58,953 [myid:] - INFO  [QuorumPeer[myid=5](plain=/127.0.0.1:19462)(secure=disabled):Leader@626] - Shutting down
    [junit] 2017-08-29 20:00:58,953 [myid:] - WARN  [QuorumPeer[myid=5](plain=/127.0.0.1:19462)(secure=disabled):QuorumPeer@1161] - PeerState set to LOOKING
    [junit] 2017-08-29 20:00:58,953 [myid:] - WARN  [QuorumPeer[myid=5](plain=/127.0.0.1:19462)(secure=disabled):QuorumPeer@1143] - QuorumPeer main thread exited
    [junit] 2017-08-29 20:00:58,953 [myid:] - INFO  [main:QuorumUtil@254] - Shutting down leader election QuorumPeer[myid=5](plain=/127.0.0.1:19462)(secure=disabled)
    [junit] 2017-08-29 20:00:58,953 [myid:] - INFO  [main:QuorumUtil@259] - Waiting for QuorumPeer[myid=5](plain=/127.0.0.1:19462)(secure=disabled) to exit thread
    [junit] 2017-08-29 20:00:58,953 [myid:] - INFO  [QuorumPeer[myid=5](plain=/127.0.0.1:19462)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5]
    [junit] 2017-08-29 20:00:58,954 [myid:] - INFO  [QuorumPeer[myid=5](plain=/127.0.0.1:19462)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.5]
    [junit] 2017-08-29 20:00:58,954 [myid:] - INFO  [QuorumPeer[myid=5](plain=/127.0.0.1:19462)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.1]
    [junit] 2017-08-29 20:00:58,954 [myid:] - INFO  [QuorumPeer[myid=5](plain=/127.0.0.1:19462)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.2]
    [junit] 2017-08-29 20:00:58,954 [myid:] - INFO  [QuorumPeer[myid=5](plain=/127.0.0.1:19462)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id5,name1=replica.3]
    [junit] 2017-08-29 20:00:58,955 [myid:] - INFO  [main:FourLetterWordMain@87] - connecting to 127.0.0.1 19450
    [junit] 2017-08-29 20:00:58,955 [myid:] - INFO  [main:QuorumUtil@243] - 127.0.0.1:19450 is no longer accepting client connections
    [junit] 2017-08-29 20:00:58,955 [myid:] - INFO  [main:FourLetterWordMain@87] - connecting to 127.0.0.1 19453
    [junit] 2017-08-29 20:00:58,956 [myid:] - INFO  [main:QuorumUtil@243] - 127.0.0.1:19453 is no longer accepting client connections
    [junit] 2017-08-29 20:00:58,956 [myid:] - INFO  [main:FourLetterWordMain@87] - connecting to 127.0.0.1 19456
    [junit] 2017-08-29 20:00:58,956 [myid:] - INFO  [main:QuorumUtil@243] - 127.0.0.1:19456 is no longer accepting client connections
    [junit] 2017-08-29 20:00:58,957 [myid:] - INFO  [main:FourLetterWordMain@87] - connecting to 127.0.0.1 19459
    [junit] 2017-08-29 20:00:58,957 [myid:] - INFO  [main:QuorumUtil@243] - 127.0.0.1:19459 is no longer accepting client connections
    [junit] 2017-08-29 20:00:58,957 [myid:] - INFO  [main:FourLetterWordMain@87] - connecting to 127.0.0.1 19462
    [junit] 2017-08-29 20:00:58,958 [myid:] - INFO  [main:QuorumUtil@243] - 127.0.0.1:19462 is no longer accepting client connections
    [junit] 2017-08-29 20:00:58,959 [myid:] - INFO  [main:ZKTestCase$1@68] - SUCCEEDED testRemoveOneAsynchronous
    [junit] 2017-08-29 20:00:58,959 [myid:] - INFO  [main:ZKTestCase$1@63] - FINISHED testRemoveOneAsynchronous
    [junit] Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 309.317 sec, Thread: 4, Class: org.apache.zookeeper.test.ReconfigTest
    [junit] 2017-08-29 20:00:59,013 [myid:] - INFO  [SessionTracker:SessionTrackerImpl@158] - SessionTrackerImpl exited loop!
    [junit] 2017-08-29 20:00:59,052 [myid:127.0.0.1:19352] - INFO  [main-SendThread(127.0.0.1:19352):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:19352. Will not attempt to authenticate using SASL (unknown error)
    [junit] 2017-08-29 20:00:59,052 [myid:127.0.0.1:19352] - WARN  [main-SendThread(127.0.0.1:19352):ClientCnxn$SendThread@1235] - Session 0x2065a31e4930000 for server 127.0.0.1/127.0.0.1:19352, unexpected error, closing socket connection and attempting reconnect
    [junit] java.net.ConnectException: Connection refused
    [junit] 	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
    [junit] 	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:739)
    [junit] 	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:357)
    [junit] 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1214)
    [junit] 2017-08-29 20:00:59,175 [myid:127.0.0.1:19355] - INFO  [main-SendThread(127.0.0.1:19355):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:19355. Will not attempt to authenticate using SASL (unknown error)
    [junit] 2017-08-29 20:00:59,176 [myid:127.0.0.1:19355] - WARN  [main-SendThread(127.0.0.1:19355):ClientCnxn$SendThread@1235] - Session 0x3065a31e4970000 for server 127.0.0.1/127.0.0.1:19355, unexpected error, closing socket connection and attempting reconnect
    [junit] java.net.ConnectException: Connection refused
    [junit] 	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
    [junit] 	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:739)
    [junit] 	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:357)
    [junit] 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1214)

fail.build.on.test.failure:

BUILD FAILED
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-openjdk7/build.xml:1220: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-openjdk7/build.xml:1224: Tests failed!

Total time: 9 minutes 51 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) ##############################
1 tests failed.
FAILED:  org.apache.zookeeper.server.quorum.ReconfigRecoveryTest.testCurrentServersAreObserversInNextConfig

Error Message:
waiting for server 4 being up

Stack Trace:
junit.framework.AssertionFailedError: waiting for server 4 being up
	at org.apache.zookeeper.server.quorum.ReconfigRecoveryTest.testCurrentServersAreObserversInNextConfig(ReconfigRecoveryTest.java:223)
	at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)