See https://builds.apache.org/job/ZooKeeper-trunk-jdk8/83/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE
###########################
[...truncated 272612 lines...]
[junit] 2014-07-20 11:42:09,834 [myid:] - INFO
[NIOWorkerThread-6:NIOServerCnxn@1006] - Closed socket connection for client
/127.0.0.1:37788 (no session established for client)
[junit] 2014-07-20 11:42:10,000 [myid:] - INFO
[SessionTracker:SessionTrackerImpl@156] - SessionTrackerImpl exited loop!
[junit] 2014-07-20 11:42:10,084 [myid:] - INFO
[Thread-6:FourLetterWordMain@43] - connecting to 127.0.0.1 11224
[junit] 2014-07-20 11:42:10,084 [myid:] - INFO
[NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11224:NIOServerCnxnFactory$AcceptThread@296]
- Accepted socket connection from /127.0.0.1:37789
[junit] 2014-07-20 11:42:10,093 [myid:] - INFO
[NIOWorkerThread-7:NIOServerCnxn@835] - Processing stat command from
/127.0.0.1:37789
[junit] 2014-07-20 11:42:10,093 [myid:] - INFO
[NIOWorkerThread-7:NIOServerCnxn@1006] - Closed socket connection for client
/127.0.0.1:37789 (no session established for client)
[junit] 2014-07-20 11:42:10,146 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11221:FileSnap@83] - Reading snapshot
/x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test5914692775535477797.junit.dir/version-2/snapshot.0
[junit] 2014-07-20 11:42:10,161 [myid:] - INFO
[LearnerHandler-/127.0.0.1:35357:LearnerHandler@361] - Follower sid: 1 : info :
127.0.0.1:12222:12223:participant;127.0.0.1:11221
[junit] 2014-07-20 11:42:10,237 [myid:] - INFO
[LearnerHandler-/127.0.0.1:35357:LearnerHandler@659] - Synchronizing with
Follower sid: 1 maxCommittedLog=0x100000002 minCommittedLog=0x100000001
lastProcessedZxid=0x100000002 peerLastZxid=0x100000002
[junit] 2014-07-20 11:42:10,253 [myid:] - INFO
[LearnerHandler-/127.0.0.1:35357:LearnerHandler@703] - Sending DIFF
zxid=0x100000002 for peer sid: 1
[junit] 2014-07-20 11:42:10,297 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11221:Learner@332] - Getting a diff from
the leader 0x100000002
[junit] 2014-07-20 11:42:10,297 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11221:Learner@475] - Learner received
NEWLEADER message
[junit] 2014-07-20 11:42:10,298 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11221:FileTxnSnapLog@298] - Snapshotting:
0x100000002 to
/x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test5914692775535477797.junit.dir/version-2/snapshot.100000002
[junit] 2014-07-20 11:42:10,349 [myid:] - INFO
[Thread-6:FourLetterWordMain@43] - connecting to 127.0.0.1 11224
[junit] 2014-07-20 11:42:10,350 [myid:] - INFO
[NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11224:NIOServerCnxnFactory$AcceptThread@296]
- Accepted socket connection from /127.0.0.1:37791
[junit] 2014-07-20 11:42:10,358 [myid:] - INFO
[NIOWorkerThread-8:NIOServerCnxn@835] - Processing stat command from
/127.0.0.1:37791
[junit] 2014-07-20 11:42:10,359 [myid:] - INFO
[NIOWorkerThread-8:NIOServerCnxn@1006] - Closed socket connection for client
/127.0.0.1:37791 (no session established for client)
[junit] 2014-07-20 11:42:10,385 [myid:] - INFO
[QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11224:Leader@1243] - Have quorum of
supporters, sids: [ [1, 2] ]; starting up and setting last processed zxid:
0x200000000
[junit] 2014-07-20 11:42:10,393 [myid:] - INFO
[QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11224:CommitProcessor@253] - Configuring
CommitProcessor with 24 worker threads.
[junit] 2014-07-20 11:42:10,441 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11221:Learner@459] - Learner received
UPTODATE message
[junit] 2014-07-20 11:42:10,442 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11221:CommitProcessor@253] - Configuring
CommitProcessor with 24 worker threads.
[junit] 2014-07-20 11:42:10,611 [myid:] - INFO
[Thread-6:FourLetterWordMain@43] - connecting to 127.0.0.1 11224
[junit] 2014-07-20 11:42:10,612 [myid:] - INFO
[NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11224:NIOServerCnxnFactory$AcceptThread@296]
- Accepted socket connection from /127.0.0.1:37792
[junit] 2014-07-20 11:42:10,617 [myid:] - INFO
[NIOWorkerThread-9:NIOServerCnxn@835] - Processing stat command from
/127.0.0.1:37792
[junit] 2014-07-20 11:42:10,618 [myid:] - INFO
[NIOWorkerThread-9:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-07-20 11:42:10,643 [myid:] - INFO
[NIOWorkerThread-9:NIOServerCnxn@1006] - Closed socket connection for client
/127.0.0.1:37792 (no session established for client)
[junit] 2014-07-20 11:42:10,835 [myid:] - INFO
[Thread-6-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@1093] - Opening
socket connection to server 127.0.0.1/127.0.0.1:11221. Will not attempt to
authenticate using SASL (unknown error)
[junit] 2014-07-20 11:42:10,836 [myid:] - INFO
[Thread-6-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@963] - Socket
connection established to 127.0.0.1/127.0.0.1:11221, initiating session
[junit] 2014-07-20 11:42:10,837 [myid:] - INFO
[NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296]
- Accepted socket connection from /127.0.0.1:45968
[junit] 2014-07-20 11:42:10,845 [myid:] - INFO
[NIOWorkerThread-17:ZooKeeperServer@872] - Client attempting to establish new
session at /127.0.0.1:45968
[junit] 2014-07-20 11:42:10,893 [myid:] - INFO
[SyncThread:2:FileTxnLog@200] - Creating new log file: log.200000001
[junit] 2014-07-20 11:42:10,906 [myid:] - WARN
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11221:Follower@121] - Got zxid 0x200000001
expected 0x1
[junit] 2014-07-20 11:42:10,987 [myid:] - INFO
[CommitProcWorkThread-17:ZooKeeperServer@619] - Established session
0x14753943f790000 with negotiated timeout 30000 for client /127.0.0.1:45968
[junit] 2014-07-20 11:42:11,001 [myid:] - INFO
[Thread-6-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@1346] - Session
establishment complete on server 127.0.0.1/127.0.0.1:11221, sessionid =
0x14753943f790000, negotiated timeout = 30000
[junit] 2014-07-20 11:42:11,017 [myid:] - INFO
[NIOWorkerThread-19:QuorumZooKeeperServer@132] - Submitting global closeSession
request for session 0x14753943f790000
[junit] 2014-07-20 11:42:11,037 [myid:] - INFO [ProcessThread(sid:2
cport:-1)::PrepRequestProcessor@685] - Processed session termination for
sessionid: 0x14753943f790000
[junit] 2014-07-20 11:42:11,081 [myid:] - INFO
[NIOWorkerThread-20:MBeanRegistry@119] - Unregister MBean
[org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower,name3=Connections,name4=127.0.0.1,name5=0x14753943f790000]
[junit] 2014-07-20 11:42:11,097 [myid:] - INFO
[NIOWorkerThread-20:NIOServerCnxn@1006] - Closed socket connection for client
/127.0.0.1:45968 which had sessionid 0x14753943f790000
[junit] 2014-07-20 11:42:11,097 [myid:] - INFO [Thread-6:ZooKeeper@968] -
Session: 0x14753943f790000 closed
[junit] 2014-07-20 11:42:11,097 [myid:] - INFO [Thread-6:ZooKeeper@709] -
Initiating client connection,
connectString=127.0.0.1:11221,127.0.0.1:11224,127.0.0.1:11227
sessionTimeout=30000
watcher=org.apache.zookeeper.test.ClientBase$CountdownWatcher@13776ad0
[junit] 2014-07-20 11:42:11,101 [myid:] - INFO
[Thread-6-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-07-20 11:42:11,145 [myid:] - INFO
[Thread-6-SendThread(127.0.0.1:11227):ClientCnxn$SendThread@1093] - Opening
socket connection to server 127.0.0.1/127.0.0.1:11227. Will not attempt to
authenticate using SASL (unknown error)
[junit] 2014-07-20 11:42:11,145 [myid:] - WARN
[Thread-6-SendThread(127.0.0.1:11227):ClientCnxn$SendThread@1213] - Session 0x0
for server null, 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:712)
[junit] at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:356)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1192)
Build timed out (after 60 minutes). Marking the build as failed.
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Build was aborted
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
[junit] Running org.apache.zookeeper.test.ReadOnlyModeTest
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.apache.zookeeper.test.ReadOnlyModeTest FAILED (crashed)
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any)
##############################
3 tests failed.
REGRESSION:
org.apache.zookeeper.server.quorum.CommitProcessorTest.testOneCommitWorker
Error Message:
Forked Java VM exited abnormally. Please note the time in the report does not
reflect the time until the VM exit.
Stack Trace:
junit.framework.AssertionFailedError: Forked Java VM exited abnormally. Please
note the time in the report does not reflect the time until the VM exit.
REGRESSION: org.apache.zookeeper.test.FLETest.testTripleElection
Error Message:
Fewer than a a majority has joined
Stack Trace:
junit.framework.AssertionFailedError: Fewer than a a majority has joined
at org.apache.zookeeper.test.FLETest.runElection(FLETest.java:361)
at
org.apache.zookeeper.test.FLETest.testTripleElection(FLETest.java:286)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
REGRESSION: org.apache.zookeeper.test.ReadOnlyModeTest.testReadOnlyClient
Error Message:
Forked Java VM exited abnormally. Please note the time in the report does not
reflect the time until the VM exit.
Stack Trace:
junit.framework.AssertionFailedError: Forked Java VM exited abnormally. Please
note the time in the report does not reflect the time until the VM exit.
