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 2014/03/29 05:48:26 UTC
ZooKeeper-trunk - Build # 2271 - Failure
See https://builds.apache.org/job/ZooKeeper-trunk/2271/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 54291 lines...]
[junit] 2014-03-29 03:51:04,615 [myid:] - INFO [WorkerReceiver[myid=0]:FastLeaderElection$Messenger$WorkerReceiver@412] - WorkerReceiver is down
[junit] 2014-03-29 03:51:04,611 [myid:] - INFO [WorkerSender[myid=0]:FastLeaderElection$Messenger$WorkerSender@445] - WorkerSender is down
[junit] 2014-03-29 03:51:04,443 [myid:] - INFO [QuorumPeer[myid=0]/0:0:0:0:0:0:0:0:11261:Follower@194] - shutdown called
[junit] java.lang.Exception: shutdown Follower
[junit] at org.apache.zookeeper.server.quorum.Follower.shutdown(Follower.java:194)
[junit] at org.apache.zookeeper.server.quorum.QuorumPeer.run(QuorumPeer.java:975)
[junit] 2014-03-29 03:51:04,443 [myid:] - ERROR [LearnerHandler-/127.0.0.1:55370:LearnerHandler@571] - Unexpected exception causing shutdown while sock still open
[junit] java.net.SocketTimeoutException: Read timed out
[junit] at java.net.SocketInputStream.socketRead0(Native Method)
[junit] at java.net.SocketInputStream.read(SocketInputStream.java:129)
[junit] at java.io.BufferedInputStream.fill(BufferedInputStream.java:218)
[junit] at java.io.BufferedInputStream.read(BufferedInputStream.java:237)
[junit] at java.io.DataInputStream.readInt(DataInputStream.java:370)
[junit] at org.apache.jute.BinaryInputArchive.readInt(BinaryInputArchive.java:63)
[junit] at org.apache.zookeeper.server.quorum.QuorumPacket.deserialize(QuorumPacket.java:83)
[junit] at org.apache.jute.BinaryInputArchive.readRecord(BinaryInputArchive.java:103)
[junit] at org.apache.zookeeper.server.quorum.LearnerHandler.run(LearnerHandler.java:484)
[junit] 2014-03-29 03:51:04,076 [myid:] - WARN [SendWorker:1:QuorumCnxManager$SendWorker@749] - Send worker leaving thread id 1 my id = 0
[junit] 2014-03-29 03:51:04,075 [myid:] - WARN [RecvWorker:1:QuorumCnxManager$RecvWorker@826] - Interrupting SendWorker
[junit] 2014-03-29 03:51:24,771 [myid:] - WARN [LearnerHandler-/127.0.0.1:55370:LearnerHandler@584] - ******* GOODBYE /127.0.0.1:55370 ********
[junit] 2014-03-29 03:51:24,771 [myid:] - INFO [QuorumPeer[myid=0]/0:0:0:0:0:0:0:0:11261:LearnerZooKeeperServer@161] - Shutting down
FATAL: channel is already closed
[junit] 2014-03-29 03:51:24,770 [myid:] - WARN [LearnerHandler-/127.0.0.1:55375:LearnerHandler@584] - ******* GOODBYE /127.0.0.1:55375 ********
[junit] 2014-03-29 03:51:24,769 [myid:] - WARN [LearnerHandler-/127.0.0.1:55380:LearnerHandler@584] - ******* GOODBYE /127.0.0.1:55380 ********
[junit] 2014-03-29 03:51:24,768 [myid:] - WARN [SendWorker:0:QuorumCnxManager$SendWorker@740] - 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:892)
[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:728)
[junit] 2014-03-29 03:51:24,768 [myid:] - WARN [SendWorker:0:QuorumCnxManager$SendWorker@740] - Interrupted while waiting for message on queue
[junit] java.lang.InterruptedException
[junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:1961)
hudson.remoting.ChannelClosedException: channel is already closed
at hudson.remoting.Channel.send(Channel.java:524)
at hudson.remoting.Request.call(Request.java:129)
at hudson.remoting.Channel.call(Channel.java:722)
at hudson.Launcher$RemoteLauncher.kill(Launcher.java:887)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:546)
at hudson.model.Run.execute(Run.java:1688)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:88)
at hudson.model.Executor.run(Executor.java:231)
Caused by: java.io.IOException: Unexpected reader termination
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:76)
Caused by: java.lang.OutOfMemoryError: GC overhead limit exceeded
at java.io.ObjectInputStream.readClassDescriptor(ObjectInputStream.java:829)
at java.io.ObjectInputStream.readNonProxyDesc(ObjectInputStream.java:1601)
at java.io.ObjectInputStream.readClassDesc(ObjectInputStream.java:1517)
at java.io.ObjectInputStream.readNonProxyDesc(ObjectInputStream.java:1622)
at java.io.ObjectInputStream.readClassDesc(ObjectInputStream.java:1517)
at java.io.ObjectInputStream.readOrdinaryObject(ObjectInputStream.java:1771)
at java.io.ObjectInputStream.readObject0(ObjectInputStream.java:1350)
at java.io.ObjectInputStream.readObject(ObjectInputStream.java:370)
at hudson.remoting.Command.readFrom(Command.java:92)
at hudson.remoting.ClassicCommandTransport.read(ClassicCommandTransport.java:71)
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:48)
###################################################################################
############################## FAILED TESTS (if any) ##############################
No tests ran.
ZooKeeper-trunk - Build # 2272 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk/2272/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 310797 lines...]
[junit] 2014-03-29 21:22:05,947 [myid:] - INFO [main:ClientBase@339] - STARTING server instance 127.0.0.1:11221
[junit] 2014-03-29 21:22:05,947 [myid:] - INFO [main:ZooKeeperServer@766] - minSessionTimeout set to 6000
[junit] 2014-03-29 21:22:05,947 [myid:] - INFO [main:ZooKeeperServer@775] - maxSessionTimeout set to 60000
[junit] 2014-03-29 21:22:05,948 [myid:] - INFO [main:ZooKeeperServer@149] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk/trunk/build/test/tmp/test4764228096358029266.junit.dir/version-2 snapdir /home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk/trunk/build/test/tmp/test4764228096358029266.junit.dir/version-2
[junit] 2014-03-29 21:22:05,949 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk/trunk/build/test/tmp/test4764228096358029266.junit.dir/version-2/snapshot.b
[junit] 2014-03-29 21:22:05,951 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to /home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk/trunk/build/test/tmp/test4764228096358029266.junit.dir/version-2/snapshot.b
[junit] 2014-03-29 21:22:05,953 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-03-29 21:22:05,954 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:52469
[junit] 2014-03-29 21:22:05,954 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:52469
[junit] 2014-03-29 21:22:05,954 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-03-29 21:22:05,955 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:52469 (no session established for client)
[junit] 2014-03-29 21:22:05,955 [myid:] - INFO [main:JMXEnv@224] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2014-03-29 21:22:05,956 [myid:] - INFO [main:JMXEnv@241] - expect:InMemoryDataTree
[junit] 2014-03-29 21:22:05,956 [myid:] - INFO [main:JMXEnv@245] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2014-03-29 21:22:05,957 [myid:] - INFO [main:JMXEnv@241] - expect:StandaloneServer_port
[junit] 2014-03-29 21:22:05,957 [myid:] - INFO [main:JMXEnv@245] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2014-03-29 21:22:05,957 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@55] - Memory used 14695
[junit] 2014-03-29 21:22:05,958 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@60] - Number of threads 24
[junit] 2014-03-29 21:22:05,958 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@65] - FINISHED TEST METHOD testQuota
[junit] 2014-03-29 21:22:05,958 [myid:] - INFO [main:ClientBase@520] - tearDown starting
[junit] 2014-03-29 21:22:06,000 [myid:] - INFO [SessionTracker:SessionTrackerImpl@135] - SessionTrackerImpl exited loop!
[junit] 2014-03-29 21:22:06,000 [myid:] - INFO [SessionTracker:SessionTrackerImpl@135] - SessionTrackerImpl exited loop!
[junit] 2014-03-29 21:22:06,022 [myid:] - INFO [main:ZooKeeper@954] - Session: 0x1450fb892ff0000 closed
[junit] 2014-03-29 21:22:06,022 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-03-29 21:22:06,022 [myid:] - INFO [main:ClientBase@490] - STOPPING server
[junit] 2014-03-29 21:22:06,023 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2014-03-29 21:22:06,023 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-03-29 21:22:06,023 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2014-03-29 21:22:06,023 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-03-29 21:22:06,024 [myid:] - INFO [main:ZooKeeperServer@428] - shutting down
[junit] 2014-03-29 21:22:06,024 [myid:] - INFO [main:SessionTrackerImpl@184] - Shutting down
[junit] 2014-03-29 21:22:06,024 [myid:] - INFO [main:PrepRequestProcessor@974] - Shutting down
[junit] 2014-03-29 21:22:06,024 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-03-29 21:22:06,024 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-03-29 21:22:06,024 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-03-29 21:22:06,025 [myid:] - INFO [main:FinalRequestProcessor@454] - shutdown of request processor complete
[junit] 2014-03-29 21:22:06,025 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-03-29 21:22:06,026 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-03-29 21:22:06,029 [myid:] - INFO [main:ClientBase@545] - fdcount after test is: 41 at start it was 28
[junit] 2014-03-29 21:22:06,029 [myid:] - INFO [main:ClientBase@547] - sleeping for 20 secs
[junit] 2014-03-29 21:22:06,030 [myid:] - INFO [main:ZKTestCase$1@61] - SUCCEEDED testQuota
[junit] 2014-03-29 21:22:06,030 [myid:] - INFO [main:ZKTestCase$1@56] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.657 sec
BUILD FAILED
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk/trunk/build.xml:1426: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk/trunk/build.xml:1337: Tests failed!
Total time: 30 minutes 56 seconds
Build step 'Execute shell' marked build as failure
[FINDBUGS] Skipping publisher since build result is FAILURE
[WARNINGS] Skipping publisher since build result is FAILURE
Archiving artifacts
Recording fingerprints
Updating ZOOKEEPER-1408
Recording test results
Publishing Javadoc
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
1 tests failed.
REGRESSION: org.apache.zookeeper.server.quorum.StandaloneDisabledTest.startSingleServerTest
Error Message:
client could not connect to reestablished quorum: giving up after 30+ seconds.
Stack Trace:
junit.framework.AssertionFailedError: client could not connect to reestablished quorum: giving up after 30+ seconds.
at org.apache.zookeeper.test.ReconfigTest.testNormalOperation(ReconfigTest.java:153)
at org.apache.zookeeper.server.quorum.StandaloneDisabledTest.startSingleServerTest(StandaloneDisabledTest.java:75)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)