See https://builds.apache.org/job/ZooKeeper-trunk-jdk7/904/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE
###########################
[...truncated 290137 lines...]
[junit] 2014-07-07 15:24:04,318 [myid:] - INFO
[LearnerHandler-/127.0.0.1:57446:LearnerHandler@361] - Follower sid: 2 : info :
127.0.0.1:12224:13224:participant;127.0.0.1:14224
[junit] 2014-07-07 15:24:04,437 [myid:] - INFO
[LearnerHandler-/127.0.0.1:57446:LearnerHandler@659] - Synchronizing with
Follower sid: 2 maxCommittedLog=0x0 minCommittedLog=0x0 lastProcessedZxid=0x0
peerLastZxid=0x0
[junit] 2014-07-07 15:24:04,437 [myid:] - INFO
[LearnerHandler-/127.0.0.1:57446:LearnerHandler@703] - Sending DIFF zxid=0x0
for peer sid: 2
[junit] 2014-07-07 15:24:04,439 [myid:] - INFO
[QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11224:Learner@332] - Getting a diff from
the leader 0x0
[junit] 2014-07-07 15:24:04,442 [myid:] - INFO
[QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11224:Learner@474] - Learner received
NEWLEADER message
[junit] 2014-07-07 15:24:04,487 [myid:] - INFO
[QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11224:FileTxnSnapLog@298] - Snapshotting:
0x0 to
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/tmp/test3450959195472270541.junit.dir/version-2/snapshot.0
[junit] 2014-07-07 15:24:04,580 [myid:] - INFO
[QuorumPeer[myid=3]/0:0:0:0:0:0:0:0:11225:Leader@1239] - Have quorum of
supporters, sids: [ [2, 3],[2, 3] ]; starting up and setting last processed
zxid: 0x100000000
[junit] 2014-07-07 15:24:04,625 [myid:] - INFO
[QuorumPeer[myid=3]/0:0:0:0:0:0:0:0:11225:CommitProcessor@232] - Configuring
CommitProcessor with 8 worker threads.
[junit] 2014-07-07 15:24:04,627 [myid:] - INFO
[QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11224:Learner@459] - Learner received
UPTODATE message
[junit] 2014-07-07 15:24:04,664 [myid:] - INFO
[QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11224:CommitProcessor@232] - Configuring
CommitProcessor with 8 worker threads.
[junit] 2014-07-07 15:24:05,205 [myid:] - INFO
[main-SendThread(127.0.0.1:11224):ClientCnxn$SendThread@1093] - Opening socket
connection to server 127.0.0.1/127.0.0.1:11224. Will not attempt to
authenticate using SASL (unknown error)
[junit] 2014-07-07 15:24:05,206 [myid:] - INFO
[main-SendThread(127.0.0.1:11224):ClientCnxn$SendThread@963] - Socket
connection established to 127.0.0.1/127.0.0.1:11224, initiating session
[junit] 2014-07-07 15:24:05,206 [myid:] - INFO
[NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11224:NIOServerCnxnFactory$AcceptThread@296]
- Accepted socket connection from /127.0.0.1:38931
[junit] 2014-07-07 15:24:05,207 [myid:] - INFO
[NIOWorkerThread-3:ZooKeeperServer@853] - Client attempting to establish new
session at /127.0.0.1:38931
[junit] 2014-07-07 15:24:05,208 [myid:] - INFO
[SyncThread:3:FileTxnLog@200] - Creating new log file: log.100000001
[junit] 2014-07-07 15:24:05,208 [myid:] - WARN
[QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11224:Follower@121] - Got zxid 0x100000001
expected 0x1
[junit] 2014-07-07 15:24:05,209 [myid:] - INFO
[SyncThread:2:FileTxnLog@200] - Creating new log file: log.100000001
[junit] 2014-07-07 15:24:05,291 [myid:] - INFO
[CommitProcWorkThread-1:ZooKeeperServer@604] - Established session
0x247116cbbda0000 with negotiated timeout 15000 for client /127.0.0.1:38931
[junit] 2014-07-07 15:24:05,291 [myid:] - INFO
[main-SendThread(127.0.0.1:11224):ClientCnxn$SendThread@1346] - Session
establishment complete on server 127.0.0.1/127.0.0.1:11224, sessionid =
0x247116cbbda0000, negotiated timeout = 15000
[junit] 2014-07-07 15:24:06,166 [myid:] - INFO
[NIOWorkerThread-9:QuorumZooKeeperServer@132] - Submitting global closeSession
request for session 0x247116cbbda0000
[junit] 2014-07-07 15:24:06,167 [myid:] - INFO [ProcessThread(sid:3
cport:-1)::PrepRequestProcessor@685] - Processed session termination for
sessionid: 0x247116cbbda0000
[junit] 2014-07-07 15:24:06,193 [myid:] - INFO [main:ZooKeeper@968] -
Session: 0x247116cbbda0000 closed
[junit] 2014-07-07 15:24:06,193 [myid:] - INFO
[NIOWorkerThread-10:MBeanRegistry@119] - Unregister MBean
[org.apache.ZooKeeperService:name0=ReplicatedServer_id2,name1=replica.2,name2=Follower,name3=Connections,name4=127.0.0.1,name5=0x247116cbbda0000]
[junit] 2014-07-07 15:24:06,193 [myid:] - INFO
[main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-07-07 15:24:06,194 [myid:] - INFO
[NIOWorkerThread-10:NIOServerCnxn@1006] - Closed socket connection for client
/127.0.0.1:38931 which had sessionid 0x247116cbbda0000
[junit] 2014-07-07 15:24:06,193 [myid:] - INFO [main:ZooKeeper@709] -
Initiating client connection, connectString=127.0.0.1:11224
sessionTimeout=15000 watcher=org.apache.zookeeper.test.TruncateTest$1@191c387
[junit] 2014-07-07 15:24:06,195 [myid:] - INFO
[main-SendThread(127.0.0.1:11224):ClientCnxn$SendThread@1093] - Opening socket
connection to server 127.0.0.1/127.0.0.1:11224. Will not attempt to
authenticate using SASL (unknown error)
[junit] 2014-07-07 15:24:06,195 [myid:] - INFO
[main-SendThread(127.0.0.1:11224):ClientCnxn$SendThread@963] - Socket
connection established to 127.0.0.1/127.0.0.1:11224, initiating session
[junit] 2014-07-07 15:24:06,195 [myid:] - INFO
[NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11224:NIOServerCnxnFactory$AcceptThread@296]
- Accepted socket connection from /127.0.0.1:38932
[junit] 2014-07-07 15:24:06,196 [myid:] - INFO
[NIOWorkerThread-11:ZooKeeperServer@853] - Client attempting to establish new
session at /127.0.0.1:38932
[junit] 2014-07-07 15:24:06,216 [myid:] - INFO
[CommitProcWorkThread-2:ZooKeeperServer@604] - Established session
0x247116cbbda0001 with negotiated timeout 15000 for client /127.0.0.1:38932
[junit] 2014-07-07 15:24:06,216 [myid:] - INFO
[main-SendThread(127.0.0.1:11224):ClientCnxn$SendThread@1346] - Session
establishment complete on server 127.0.0.1/127.0.0.1:11224, sessionid =
0x247116cbbda0001, negotiated timeout = 15000
[junit] 2014-07-07 15:24:06,219 [myid:] - INFO
[main:NIOServerCnxnFactory@670] - Configuring NIO connection handler with 10s
sessionless connection timeout, 2 selector thread(s), 16 worker threads, and 64
kB direct buffers.
[junit] 2014-07-07 15:24:06,220 [myid:] - INFO
[main:NIOServerCnxnFactory@683] - binding to port 0.0.0.0/0.0.0.0:11223
[junit] 2014-07-07 15:24:06,221 [myid:] - INFO [main:FileSnap@83] -
Reading snapshot
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/tmp/test8231825740920136558.junit.dir/version-2/snapshot.100000000
[junit] 2014-07-07 15:24:06,228 [myid:] - INFO [main:QuorumPeer@657] -
currentEpoch not found! Creating with a reasonable default of 1. This should
only happen when you are upgrading your installation
[junit] 2014-07-07 15:24:06,273 [myid:] - INFO [main:QuorumPeer@672] -
acceptedEpoch not found! Creating with a reasonable default of 1. This should
only happen when you are upgrading your installation
[junit] 2014-07-07 15:24:06,325 [myid:] - INFO [main:ZooKeeper@709] -
Initiating client connection, connectString=127.0.0.1:11223
sessionTimeout=15000 watcher=org.apache.zookeeper.test.TruncateTest$1@191c387
[junit] 2014-07-07 15:24:06,326 [myid:] - INFO
[main-SendThread(127.0.0.1:11223):ClientCnxn$SendThread@1093] - Opening socket
connection to server 127.0.0.1/127.0.0.1:11223. Will not attempt to
authenticate using SASL (unknown error)
[junit] 2014-07-07 15:24:06,327 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11223:QuorumPeer@897] - LOOKING
[junit] 2014-07-07 15:24:06,327 [myid:] - INFO
[main-SendThread(127.0.0.1:11223):ClientCnxn$SendThread@963] - Socket
connection established to 127.0.0.1/127.0.0.1:11223, initiating session
[junit] 2014-07-07 15:24:06,327 [myid:] - INFO
[NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11223:NIOServerCnxnFactory$AcceptThread@296]
- Accepted socket connection from /127.0.0.1:51707
[junit] 2014-07-07 15:24:06,327 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11223:LeaderElection@185] - Server address:
/127.0.0.1:12223
[junit] 2014-07-07 15:24:06,328 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11223:LeaderElection@185] - Server address:
/127.0.0.1:12224
[junit] 2014-07-07 15:24:06,328 [myid:] - WARN
[NIOWorkerThread-1:NIOServerCnxn@372] - Exception causing close of session 0x0:
ZooKeeperServer not running
[junit] 2014-07-07 15:24:06,328 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11223:LeaderElection@185] - Server address:
/127.0.0.1:12225
[junit] 2014-07-07 15:24:06,328 [myid:] - INFO
[NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client
/127.0.0.1:51707 (no session established for client)
[junit] 2014-07-07 15:24:06,328 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11223:LeaderElection@122] - Election tally:
[junit] 2014-07-07 15:24:06,328 [myid:] - INFO
[main-SendThread(127.0.0.1:11223):ClientCnxn$SendThread@1209] - Unable to read
additional data from server sessionid 0x0, likely server has closed socket,
closing socket connection and attempting reconnect
[junit] 2014-07-07 15:24:06,329 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11223:LeaderElection@128] - 1 -> 1
[junit] 2014-07-07 15:24:06,329 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11223:LeaderElection@128] - 3 -> 2
[junit] 2014-07-07 15:24:06,329 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11223:LeaderElection@248] - Found leader:
my type is: PARTICIPANT
[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
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any)
##############################
2 tests failed.
REGRESSION:
org.apache.zookeeper.test.FollowerResyncConcurrencyTest.testFollowerWatcherResync
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.
FAILED: TEST-org.apache.zookeeper.test.TruncateTest.xml.<init>
Error Message:
Stack Trace:
Test report file
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/logs/TEST-org.apache.zookeeper.test.TruncateTest.xml
was length 0
