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/09/30 08:50:03 UTC
ZooKeeper_branch35_jdk7 - Build # 1129 - Still Failing
See https://builds.apache.org/job/ZooKeeper_branch35_jdk7/1129/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 70.30 MB...]
[junit] 2017-09-30 08:50:00,075 [myid:] - INFO [ProcessThread(sid:0 cport:22240)::PrepRequestProcessor@611] - Processed session termination for sessionid: 0x1064b6bd5bf0000
[junit] 2017-09-30 08:50:00,081 [myid:] - INFO [SyncThread:0:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port22240,name1=Connections,name2=127.0.0.1,name3=0x1064b6bd5bf0000]
[junit] 2017-09-30 08:50:00,081 [myid:] - INFO [main:ZooKeeper@1334] - Session: 0x1064b6bd5bf0000 closed
[junit] 2017-09-30 08:50:00,081 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for session: 0x1064b6bd5bf0000
[junit] 2017-09-30 08:50:00,082 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 111564
[junit] 2017-09-30 08:50:00,082 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 863
[junit] 2017-09-30 08:50:00,082 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD testWatcherAutoResetWithLocal
[junit] 2017-09-30 08:50:00,082 [myid:] - INFO [main:ClientBase@586] - tearDown starting
[junit] 2017-09-30 08:50:00,082 [myid:] - INFO [main:ClientBase@556] - STOPPING server
[junit] 2017-09-30 08:50:00,083 [myid:] - INFO [main:NettyServerCnxnFactory@464] - shutdown called 0.0.0.0/0.0.0.0:22240
[junit] 2017-09-30 08:50:00,085 [myid:] - INFO [main:ZooKeeperServer@541] - shutting down
[junit] 2017-09-30 08:50:00,085 [myid:] - ERROR [main:ZooKeeperServer@505] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2017-09-30 08:50:00,085 [myid:] - INFO [main:SessionTrackerImpl@232] - Shutting down
[junit] 2017-09-30 08:50:00,085 [myid:] - INFO [main:PrepRequestProcessor@1005] - Shutting down
[junit] 2017-09-30 08:50:00,085 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2017-09-30 08:50:00,086 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2017-09-30 08:50:00,086 [myid:] - INFO [main:FinalRequestProcessor@481] - shutdown of request processor complete
[junit] 2017-09-30 08:50:00,086 [myid:] - INFO [ProcessThread(sid:0 cport:22240)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2017-09-30 08:50:00,086 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port22240,name1=InMemoryDataTree]
[junit] 2017-09-30 08:50:00,087 [myid:] - INFO [main:MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port22240]
[junit] 2017-09-30 08:50:00,087 [myid:] - INFO [main:FourLetterWordMain@87] - connecting to 127.0.0.1 22240
[junit] 2017-09-30 08:50:00,088 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2017-09-30 08:50:00,093 [myid:] - INFO [main:ClientBase@611] - fdcount after test is: 2554 at start it was 2554
[junit] 2017-09-30 08:50:00,093 [myid:] - INFO [main:ZKTestCase$1@68] - SUCCEEDED testWatcherAutoResetWithLocal
[junit] 2017-09-30 08:50:00,094 [myid:] - INFO [main:ZKTestCase$1@63] - FINISHED testWatcherAutoResetWithLocal
[junit] Tests run: 103, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 432.596 sec, Thread: 5, Class: org.apache.zookeeper.test.NioNettySuiteTest
[junit] 2017-09-30 08:50:00,199 [myid:127.0.0.1:22058] - INFO [main-SendThread(127.0.0.1:22058):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:22058. Will not attempt to authenticate using SASL (unknown error)
[junit] 2017-09-30 08:50:00,200 [myid:127.0.0.1:22058] - WARN [main-SendThread(127.0.0.1:22058):ClientCnxn$SendThread@1235] - Session 0x1064b662a0b0000 for server 127.0.0.1/127.0.0.1:22058, 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:744)
[junit] at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:357)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1214)
[junit] 2017-09-30 08:50:00,335 [myid:127.0.0.1:22043] - INFO [main-SendThread(127.0.0.1:22043):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:22043. Will not attempt to authenticate using SASL (unknown error)
[junit] 2017-09-30 08:50:00,336 [myid:127.0.0.1:22043] - WARN [main-SendThread(127.0.0.1:22043):ClientCnxn$SendThread@1235] - Session 0x1064b65beb30001 for server 127.0.0.1/127.0.0.1:22043, 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:744)
[junit] at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:357)
[junit] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1214)
[junit] 2017-09-30 08:50:00,373 [myid:127.0.0.1:21994] - INFO [main-SendThread(127.0.0.1:21994):ClientCnxn$SendThread@1113] - Opening socket connection to server 127.0.0.1/127.0.0.1:21994. Will not attempt to authenticate using SASL (unknown error)
[junit] 2017-09-30 08:50:00,373 [myid:127.0.0.1:21994] - WARN [main-SendThread(127.0.0.1:21994):ClientCnxn$SendThread@1235] - Session 0x1064b6556650000 for server 127.0.0.1/127.0.0.1:21994, 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:744)
[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_branch35_jdk7/build.xml:1267: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/ZooKeeper_branch35_jdk7/build.xml:1271: Tests failed!
Total time: 11 minutes 47 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.LETest.testLE
Error Message:
Threads didn't join
Stack Trace:
junit.framework.AssertionFailedError: Threads didn't join
at org.apache.zookeeper.test.LETest.testLE(LETest.java:123)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
ZooKeeper_branch35_jdk7 - Build # 1130 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch35_jdk7/1130/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 9.96 MB...]
[junit] 2017-09-30 19:28:55,871 [myid:] - INFO [WorkerReceiver[myid=1]:FastLeaderElection$Messenger$WorkerReceiver@440] - WorkerReceiver is down
[junit] 2017-09-30 19:28:55,877 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:14122)(secure=disabled):SyncRequestProcessor@191] - Shutting down
[junit] 2017-09-30 19:28:55,877 [myid:] - INFO [QuorumPeer[myid=4](plain=/127.0.0.1:14121)(secure=disabled):ObserverRequestProcessor@151] - Shutting down
[junit] 2017-09-30 19:28:55,878 [myid:] - INFO [QuorumPeer[myid=4](plain=/127.0.0.1:14121)(secure=disabled):CommitProcessor@359] - Shutting down
[junit] 2017-09-30 19:28:55,876 [myid:] - WARN [SendWorker:3:QuorumCnxManager$SendWorker@1074] - Send worker leaving thread id 3 my id = 5
[junit] 2017-09-30 19:28:55,875 [myid:] - INFO [QuorumPeer[myid=3](plain=/127.0.0.1:14120)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.3]
[junit] 2017-09-30 19:28:55,875 [myid:] - WARN [SendWorker:4:QuorumCnxManager$SendWorker@1074] - Send worker leaving thread id 4 my id = 3
[junit] 2017-09-30 19:28:55,875 [myid:] - WARN [SendWorker:3:QuorumCnxManager$SendWorker@1064] - Interrupted while waiting for message on queue
[junit] java.lang.InterruptedException
[junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2017)
[junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2095)
[junit] at java.util.concurrent.ArrayBlockingQueue.poll(ArrayBlockingQueue.java:389)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.pollSendQueue(QuorumCnxManager.java:1215)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.access$700(QuorumCnxManager.java:75)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager$SendWorker.run(QuorumCnxManager.java:1052)
[junit] 2017-09-30 19:28:55,874 [myid:] - INFO [main:QuorumBase@403] - Waiting for QuorumPeer[myid=3](plain=/127.0.0.1:14120)(secure=disabled) to exit thread
[junit] 2017-09-30 19:28:55,879 [myid:] - WARN [SendWorker:3:QuorumCnxManager$SendWorker@1074] - Send worker leaving thread id 3 my id = 4
[junit] 2017-09-30 19:28:55,878 [myid:] - INFO [QuorumPeer[myid=3](plain=/127.0.0.1:14120)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.1]
[junit] 2017-09-30 19:28:55,878 [myid:] - INFO [QuorumPeer[myid=4](plain=/127.0.0.1:14121)(secure=disabled):FinalRequestProcessor@481] - shutdown of request processor complete
[junit] 2017-09-30 19:28:55,878 [myid:] - INFO [CommitProcessor:4:CommitProcessor@194] - CommitProcessor exited loop!
[junit] 2017-09-30 19:28:55,878 [myid:] - INFO [ObserverRequestProcessor:4:ObserverRequestProcessor@119] - ObserverRequestProcessor exited loop!
[junit] 2017-09-30 19:28:55,878 [myid:] - INFO [SyncThread:5:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2017-09-30 19:28:55,879 [myid:] - INFO [QuorumPeer[myid=4](plain=/127.0.0.1:14121)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id4,name1=replica.4,name2=Observer,name3=InMemoryDataTree]
[junit] 2017-09-30 19:28:55,879 [myid:] - INFO [QuorumPeer[myid=3](plain=/127.0.0.1:14120)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.2]
[junit] 2017-09-30 19:28:55,880 [myid:] - INFO [QuorumPeer[myid=4](plain=/127.0.0.1:14121)(secure=disabled):SyncRequestProcessor@191] - Shutting down
[junit] 2017-09-30 19:28:55,880 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:14122)(secure=disabled):SyncRequestProcessor@191] - Shutting down
[junit] 2017-09-30 19:28:55,880 [myid:] - INFO [QuorumPeer[myid=3](plain=/127.0.0.1:14120)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.4]
[junit] 2017-09-30 19:28:55,881 [myid:] - INFO [SyncThread:4:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2017-09-30 19:28:55,881 [myid:] - INFO [QuorumPeer[myid=3](plain=/127.0.0.1:14120)(secure=disabled):MBeanRegistry@128] - Unregister MBean [org.apache.ZooKeeperService:name0=ReplicatedServer_id3,name1=replica.5]
[junit] 2017-09-30 19:28:55,881 [myid:] - WARN [QuorumPeer[myid=5](plain=/127.0.0.1:14122)(secure=disabled):QuorumPeer@1261] - PeerState set to LOOKING
[junit] 2017-09-30 19:28:55,881 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:14122)(secure=disabled):QuorumPeer@1136] - LOOKING
[junit] 2017-09-30 19:28:55,881 [myid:] - INFO [main:QuorumBase@394] - Shutting down quorum peer QuorumPeer[myid=4](plain=/127.0.0.1:14121)(secure=disabled)
[junit] 2017-09-30 19:28:55,881 [myid:] - INFO [QuorumPeer[myid=4](plain=/127.0.0.1:14121)(secure=disabled):SyncRequestProcessor@191] - Shutting down
[junit] 2017-09-30 19:28:55,881 [myid:] - INFO [QuorumPeer[myid=5](plain=/127.0.0.1:14122)(secure=disabled):FastLeaderElection@894] - New election. My id = 5, proposed zxid=0x8000000000000000
[junit] 2017-09-30 19:28:55,882 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:/127.0.0.1:14121:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2017-09-30 19:28:55,882 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2017-09-30 19:28:55,882 [myid:] - WARN [WorkerSender[myid=5]:QuorumCnxManager@660] - Cannot open channel to 1 at election address /127.0.0.1:14113
[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:638)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.connectOne(QuorumCnxManager.java:688)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.toSend(QuorumCnxManager.java:616)
[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)
ERROR: qnode2 is offline; cannot locate JDK 1.7 (latest)
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
ERROR: qnode2 is offline; cannot locate JDK 1.7 (latest)
[junit] 2017-09-30 19:28:55,883 [myid:] - WARN [WorkerSender[myid=5]:QuorumCnxManager@660] - Cannot open channel to 1 at election address /127.0.0.1:14113
[junit] java.net.ConnectException: Connection refused
[junit] at java.net.PlainSocketImpl.socketConnect(Native Method)
ERROR: qnode2 is offline; cannot locate JDK 1.7 (latest)
###################################################################################
############################## FAILED TESTS (if any) ##############################
No tests ran.