See https://builds.apache.org/job/ZooKeeper-trunk-openjdk7/1199/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE
###########################
[...truncated 459674 lines...]
[junit] at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit] at java.lang.Thread.run(Thread.java:745)
[junit] 2016-10-11 20:46:45,176 [myid:] - INFO [New I/O boss
#18:ClientCnxnSocketNetty@208] - channel is told closing
[junit] 2016-10-11 20:46:45,176 [myid:127.0.0.1:19301] - INFO
[main-SendThread(127.0.0.1:19301):ClientCnxn$SendThread@1231] - channel for
sessionid 0x10000d386310000 is lost, closing socket connection and attempting
reconnect
[junit] 2016-10-11 20:46:45,346 [myid:] - INFO [ProcessThread(sid:0
cport:19545)::PrepRequestProcessor@605] - Processed session termination for
sessionid: 0x10000d9efe40000
[junit] 2016-10-11 20:46:45,347 [myid:] - WARN [New I/O worker
#3953:NettyServerCnxnFactory$CnxnChannelHandler@142] - Exception caught [id:
0x0116d95d, /127.0.0.1:59696 :> /127.0.0.1:19545] EXCEPTION:
java.nio.channels.ClosedChannelException
[junit] java.nio.channels.ClosedChannelException
[junit] at
sun.nio.ch.SocketChannelImpl.ensureWriteOpen(SocketChannelImpl.java:270)
[junit] at
sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:479)
[junit] at
org.jboss.netty.channel.socket.nio.SocketSendBufferPool$UnpooledSendBuffer.transferTo(SocketSendBufferPool.java:203)
[junit] at
org.jboss.netty.channel.socket.nio.AbstractNioWorker.write0(AbstractNioWorker.java:201)
[junit] at
org.jboss.netty.channel.socket.nio.AbstractNioWorker.writeFromTaskLoop(AbstractNioWorker.java:151)
[junit] at
org.jboss.netty.channel.socket.nio.AbstractNioChannel$WriteTask.run(AbstractNioChannel.java:315)
[junit] at
org.jboss.netty.channel.socket.nio.AbstractNioSelector.processTaskQueue(AbstractNioSelector.java:391)
[junit] at
org.jboss.netty.channel.socket.nio.AbstractNioSelector.run(AbstractNioSelector.java:315)
[junit] at
org.jboss.netty.channel.socket.nio.AbstractNioWorker.run(AbstractNioWorker.java:89)
[junit] at
org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)
[junit] at
org.jboss.netty.util.ThreadRenamingRunnable.run(ThreadRenamingRunnable.java:108)
[junit] at
org.jboss.netty.util.internal.DeadLockProofWorker$1.run(DeadLockProofWorker.java:42)
[junit] at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[junit] at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit] at java.lang.Thread.run(Thread.java:745)
[junit] 2016-10-11 20:46:45,347 [myid:] - INFO [New I/O worker
#3928:ClientCnxnSocketNetty$ZKClientHandler@384] - channel is disconnected:
[id: 0x2cb96b07, /127.0.0.1:59696 :> 127.0.0.1/127.0.0.1:19545]
[junit] 2016-10-11 20:46:45,347 [myid:] - INFO
[SyncThread:0:MBeanRegistry@128] - Unregister MBean
[org.apache.ZooKeeperService:name0=StandaloneServer_port19545,name1=Connections,name2=127.0.0.1,name3=0x10000d9efe40000]
[junit] 2016-10-11 20:46:45,347 [myid:] - INFO [New I/O worker
#3928:ClientCnxnSocketNetty@208] - channel is told closing
[junit] 2016-10-11 20:46:45,348 [myid:] - INFO
[main:ClientCnxnSocketNetty@208] - channel is told closing
[junit] 2016-10-11 20:46:45,348 [myid:] - INFO [main:ZooKeeper@1315] -
Session: 0x10000d9efe40000 closed
[junit] 2016-10-11 20:46:45,348 [myid:] - INFO
[main-EventThread:ClientCnxn$EventThread@513] - EventThread shut down for
session: 0x10000d9efe40000
[junit] 2016-10-11 20:46:45,348 [myid:] - INFO
[main:JUnit4ZKTestRunner$LoggedInvokeMethod@82] - Memory used 48290
[junit] 2016-10-11 20:46:45,348 [myid:] - INFO
[main:JUnit4ZKTestRunner$LoggedInvokeMethod@87] - Number of threads 516
[junit] 2016-10-11 20:46:45,348 [myid:] - INFO
[main:JUnit4ZKTestRunner$LoggedInvokeMethod@102] - FINISHED TEST METHOD
testWatcherAutoResetWithLocal
[junit] 2016-10-11 20:46:45,348 [myid:] - INFO [main:ClientBase@543] -
tearDown starting
[junit] 2016-10-11 20:46:45,348 [myid:] - INFO [main:ClientBase@513] -
STOPPING server
[junit] 2016-10-11 20:46:45,348 [myid:] - INFO
[main:NettyServerCnxnFactory@464] - shutdown called 0.0.0.0/0.0.0.0:19545
[junit] 2016-10-11 20:46:45,350 [myid:] - INFO [main:ZooKeeperServer@529]
- shutting down
[junit] 2016-10-11 20:46:45,350 [myid:] - ERROR [main:ZooKeeperServer@501]
- ZKShutdownHandler is not registered, so ZooKeeper server won't take any
action on ERROR or SHUTDOWN server state changes
[junit] 2016-10-11 20:46:45,350 [myid:] - INFO
[main:SessionTrackerImpl@232] - Shutting down
[junit] 2016-10-11 20:46:45,350 [myid:] - INFO
[main:PrepRequestProcessor@996] - Shutting down
[junit] 2016-10-11 20:46:45,350 [myid:] - INFO
[main:SyncRequestProcessor@191] - Shutting down
[junit] 2016-10-11 20:46:45,350 [myid:] - INFO [ProcessThread(sid:0
cport:19545)::PrepRequestProcessor@156] - PrepRequestProcessor exited loop!
[junit] 2016-10-11 20:46:45,350 [myid:] - INFO
[SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2016-10-11 20:46:45,350 [myid:] - INFO
[main:FinalRequestProcessor@481] - shutdown of request processor complete
[junit] 2016-10-11 20:46:45,351 [myid:] - INFO [main:MBeanRegistry@128] -
Unregister MBean
[org.apache.ZooKeeperService:name0=StandaloneServer_port19545,name1=InMemoryDataTree]
[junit] 2016-10-11 20:46:45,351 [myid:] - INFO [main:MBeanRegistry@128] -
Unregister MBean [org.apache.ZooKeeperService:name0=StandaloneServer_port19545]
[junit] 2016-10-11 20:46:45,351 [myid:] - INFO
[main:FourLetterWordMain@85] - connecting to 127.0.0.1 19545
[junit] 2016-10-11 20:46:45,351 [myid:] - INFO [main:JMXEnv@146] -
ensureOnly:[]
[junit] 2016-10-11 20:46:45,354 [myid:] - INFO [main:ClientBase@568] -
fdcount after test is: 1463 at start it was 1464
[junit] 2016-10-11 20:46:45,354 [myid:] - INFO [main:ZKTestCase$1@65] -
SUCCEEDED testWatcherAutoResetWithLocal
[junit] 2016-10-11 20:46:45,354 [myid:] - INFO [main:ZKTestCase$1@60] -
FINISHED testWatcherAutoResetWithLocal
[junit] Tests run: 101, Failures: 0, Errors: 0, Skipped: 0, Time elapsed:
427.39 sec, Thread: 4, Class: org.apache.zookeeper.test.NettyNettySuiteTest
BUILD FAILED
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-openjdk7/build.xml:1207:
Tests failed!
Total time: 10 minutes 31 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)
##############################
2 tests failed.
FAILED:
org.apache.zookeeper.server.quorum.RaceConditionTest.testRaceConditionBetweenLeaderAndAckRequestProcessor
Error Message:
Leader failed to transition to LOOKING or FOLLOWING state
Stack Trace:
junit.framework.AssertionFailedError: Leader failed to transition to LOOKING or
FOLLOWING state
at
org.apache.zookeeper.server.quorum.RaceConditionTest.testRaceConditionBetweenLeaderAndAckRequestProcessor(RaceConditionTest.java:74)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.lang.Thread.run(Thread.java:745)
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:217)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:79)
