See https://builds.apache.org/job/ZooKeeper-trunk-jdk7/500/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE
###########################
[...truncated 157597 lines...]
[junit] at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit] at
sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:701)
[junit] at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:345)
[junit] at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1082)
[junit] 2013-01-03 00:27:28,582 [myid:] - INFO
[main:FourLetterWordMain@43] - connecting to 127.0.0.1 11239
[junit] 2013-01-03 00:27:28,582 [myid:] - INFO
[NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory$AcceptThread@274]
- Accepted socket connection from /127.0.0.1:45475
[junit] 2013-01-03 00:27:28,582 [myid:] - INFO
[NIOWorkerThread-5:NIOServerCnxn@829] - Processing stat command from
/127.0.0.1:45475
[junit] 2013-01-03 00:27:28,583 [myid:] - INFO
[NIOWorkerThread-5:NIOServerCnxn$StatCommand@678] - Stat command output
[junit] 2013-01-03 00:27:28,583 [myid:] - INFO
[NIOWorkerThread-5:NIOServerCnxn@1000] - Closed socket connection for client
/127.0.0.1:45475 (no session established for client)
[junit] 2013-01-03 00:27:28,724 [myid:] - INFO
[QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11242:ZooKeeperServer@398] - shutting down
[junit] 2013-01-03 00:27:28,724 [myid:] - INFO
[Thread-29:QuorumPeer$1@862] - Interrupted while attempting to start
ReadOnlyZooKeeperServer, not started
[junit] 2013-01-03 00:27:28,724 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11239:ZooKeeperServer@398] - shutting down
[junit] 2013-01-03 00:27:28,724 [myid:] - INFO
[QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11242:QuorumPeer@916] - LEADING
[junit] 2013-01-03 00:27:28,724 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11239:SessionTrackerImpl@180] - Shutting
down
[junit] 2013-01-03 00:27:28,724 [myid:] - INFO
[QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11242:ZooKeeperServer@149] - Created server
with tickTime 2000 minSessionTimeout 4000 maxSessionTimeout 40000 datadir
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/tmp/test6483361702093221008.junit.dir/version-2
snapdir
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/tmp/test6483361702093221008.junit.dir/version-2
[junit] 2013-01-03 00:27:28,725 [myid:] - INFO
[ReadOnlyRequestProcessor:1:ReadOnlyRequestProcessor@111] -
ReadOnlyRequestProcessor exited loop!
[junit] 2013-01-03 00:27:28,725 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11239:PrepRequestProcessor@804] - Shutting
down
[junit] 2013-01-03 00:27:28,725 [myid:] - INFO
[QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11242:Leader@344] - LEADING - LEADER
ELECTION TOOK - 933
[junit] 2013-01-03 00:27:28,725 [myid:] - INFO [ProcessThread(sid:1
cport:-1)::PrepRequestProcessor@144] - PrepRequestProcessor exited loop!
[junit] 2013-01-03 00:27:28,725 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11239:FinalRequestProcessor@421] - shutdown
of request processor complete
[junit] 2013-01-03 00:27:28,726 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11239:QuorumPeer@904] - FOLLOWING
[junit] 2013-01-03 00:27:28,726 [myid:] - INFO
[QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11242:FileSnap@83] - Reading snapshot
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/tmp/test6483361702093221008.junit.dir/version-2/snapshot.0
[junit] 2013-01-03 00:27:28,726 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11239:ZooKeeperServer@149] - Created server
with tickTime 2000 minSessionTimeout 4000 maxSessionTimeout 40000 datadir
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/tmp/test2266011802239101348.junit.dir/version-2
snapdir
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/tmp/test2266011802239101348.junit.dir/version-2
[junit] 2013-01-03 00:27:28,726 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11239:Follower@61] - FOLLOWING - LEADER
ELECTION TOOK - 9813
[junit] 2013-01-03 00:27:28,726 [myid:] - INFO
[QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11242:FileTxnSnapLog@270] - Snapshotting:
0x0 to
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/tmp/test6483361702093221008.junit.dir/version-2/snapshot.0
[junit] 2013-01-03 00:27:28,727 [myid:] - INFO
[LearnerHandler-/127.0.0.1:58371:LearnerHandler@264] - Follower sid: 1 : info :
localhost:12240:12241:participant;localhost:11239
[junit] 2013-01-03 00:27:28,734 [myid:] - INFO
[LearnerHandler-/127.0.0.1:58371:LearnerHandler@322] - Synchronizing with
Follower sid: 1 maxCommittedLog=0x0 minCommittedLog=0x0 peerLastZxid=0x0
[junit] 2013-01-03 00:27:28,734 [myid:] - INFO
[LearnerHandler-/127.0.0.1:58371:LearnerHandler@399] - Sending DIFF
[junit] 2013-01-03 00:27:28,734 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11239:Learner@322] - Getting a diff from
the leader 0x0
[junit] 2013-01-03 00:27:28,734 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11239:FileTxnSnapLog@270] - Snapshotting:
0x0 to
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/tmp/test2266011802239101348.junit.dir/version-2/snapshot.0
[junit] 2013-01-03 00:27:28,741 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11239:FileTxnSnapLog@270] - Snapshotting:
0x0 to
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk7/trunk/build/test/tmp/test2266011802239101348.junit.dir/version-2/snapshot.0
[junit] 2013-01-03 00:27:28,741 [myid:] - WARN
[LearnerHandler-/127.0.0.1:58371:Leader@575] - Commiting zxid 0x200000000 from
/127.0.0.1:12243 not first!
[junit] 2013-01-03 00:27:28,741 [myid:] - WARN
[LearnerHandler-/127.0.0.1:58371:Leader@577] - First is 0x0
[junit] 2013-01-03 00:27:28,741 [myid:] - INFO
[LearnerHandler-/127.0.0.1:58371:Leader@599] - Have quorum of supporters;
starting up and setting last processed zxid: 0x200000000
[junit] 2013-01-03 00:27:28,741 [myid:] - INFO
[LearnerHandler-/127.0.0.1:58371:CommitProcessor@228] - Configuring
CommitProcessor with 8 worker threads.
[junit] 2013-01-03 00:27:28,744 [myid:] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11239:CommitProcessor@228] - Configuring
CommitProcessor with 8 worker threads.
[junit] 2013-01-03 00:27:28,833 [myid:] - INFO
[main:FourLetterWordMain@43] - connecting to 127.0.0.1 11239
[junit] 2013-01-03 00:27:28,834 [myid:] - INFO
[NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11239:NIOServerCnxnFactory$AcceptThread@274]
- Accepted socket connection from /127.0.0.1:45477
[junit] 2013-01-03 00:27:28,834 [myid:] - INFO
[NIOWorkerThread-6:NIOServerCnxn@829] - Processing stat command from
/127.0.0.1:45477
[junit] 2013-01-03 00:27:28,834 [myid:] - INFO
[NIOWorkerThread-6:NIOServerCnxn$StatCommand@678] - Stat command output
[junit] 2013-01-03 00:27:28,834 [myid:] - INFO
[NIOWorkerThread-6:NIOServerCnxn@1000] - Closed socket connection for client
/127.0.0.1:45477 (no session established for client)
[junit] 2013-01-03 00:27:28,835 [myid:] - INFO
[main:FourLetterWordMain@43] - connecting to 127.0.0.1 11242
[junit] 2013-01-03 00:27:28,835 [myid:] - INFO
[NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11242:NIOServerCnxnFactory$AcceptThread@274]
- Accepted socket connection from /127.0.0.1:43990
[junit] 2013-01-03 00:27:28,836 [myid:] - INFO
[NIOWorkerThread-1:NIOServerCnxn@829] - Processing stat command from
/127.0.0.1:43990
[junit] 2013-01-03 00:27:28,836 [myid:] - INFO
[NIOWorkerThread-1:NIOServerCnxn@1000] - Closed socket connection for client
/127.0.0.1:43990 (no session established for client)
[junit] 2013-01-03 00:27:29,086 [myid:] - INFO
[main:FourLetterWordMain@43] - connecting to 127.0.0.1 11242
[junit] 2013-01-03 00:27:29,087 [myid:] - INFO
[NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11242:NIOServerCnxnFactory$AcceptThread@274]
- Accepted socket connection from /127.0.0.1:43991
[junit] 2013-01-03 00:27:29,087 [myid:] - INFO
[NIOWorkerThread-2:NIOServerCnxn@829] - Processing stat command from
/127.0.0.1:43991
[junit] 2013-01-03 00:27:29,088 [myid:] - INFO
[NIOWorkerThread-2:NIOServerCnxn@1000] - Closed socket connection for client
/127.0.0.1:43991 (no session established for client)
[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)
[junit] 2013-01-03 00:27:29,749 [myid:] - INFO [main:PortAssignment@32] -
assigning port 11221
[WARNINGS] Skipping publisher since build result is FAILURE
Archiving artifacts
Recording test results
Publishing Javadoc
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any)
##############################
18 tests failed.
REGRESSION:
org.apache.zookeeper.server.TruncateCorruptionTest.testTransactionLogCorruption
Error Message:
null
Stack Trace:
junit.framework.AssertionFailedError
at
org.apache.zookeeper.server.TruncateCorruptionTest.waitForConnection(TruncateCorruptionTest.java:226)
at
org.apache.zookeeper.server.TruncateCorruptionTest.testTransactionLogCorruption(TruncateCorruptionTest.java:152)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
REGRESSION:
org.apache.zookeeper.server.quorum.QuorumPeerMainTest.testHighestZxidJoinLate
Error Message:
Waiting too long
Stack Trace:
java.lang.RuntimeException: Waiting too long
at
org.apache.zookeeper.server.quorum.QuorumPeerMainTest.waitForAll(QuorumPeerMainTest.java:320)
at
org.apache.zookeeper.server.quorum.QuorumPeerMainTest.LaunchServers(QuorumPeerMainTest.java:366)
at
org.apache.zookeeper.server.quorum.QuorumPeerMainTest.testHighestZxidJoinLate(QuorumPeerMainTest.java:218)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
REGRESSION:
org.apache.zookeeper.server.quorum.QuorumPeerMainTest.testEarlyLeaderAbandonment
Error Message:
Waiting too long
Stack Trace:
java.lang.RuntimeException: Waiting too long
at
org.apache.zookeeper.server.quorum.QuorumPeerMainTest.waitForAll(QuorumPeerMainTest.java:320)
at
org.apache.zookeeper.server.quorum.QuorumPeerMainTest.testEarlyLeaderAbandonment(QuorumPeerMainTest.java:134)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
REGRESSION:
org.apache.zookeeper.test.SessionTest.testSessionStateNoDupStateReporting
Error Message:
Unable to connect to server
Stack Trace:
junit.framework.AssertionFailedError: Unable to connect to server
at
org.apache.zookeeper.test.SessionTest.createClient(SessionTest.java:123)
at
org.apache.zookeeper.test.SessionTest.testSessionStateNoDupStateReporting(SessionTest.java:366)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
REGRESSION: org.apache.zookeeper.test.WatcherTest.testWatcherCorrectness
Error Message:
expected:<0> but was:<1>
Stack Trace:
junit.framework.AssertionFailedError: expected:<0> but was:<1>
at org.apache.zookeeper.test.JMXEnv.ensureOnly(JMXEnv.java:138)
at org.apache.zookeeper.test.ClientBase.startServer(ClientBase.java:417)
at org.apache.zookeeper.test.ClientBase.setUp(ClientBase.java:408)
REGRESSION: org.apache.zookeeper.test.WatcherTest.testWatcherCount
Error Message:
expected:<0> but was:<1>
Stack Trace:
junit.framework.AssertionFailedError: expected:<0> but was:<1>
at org.apache.zookeeper.test.JMXEnv.ensureOnly(JMXEnv.java:138)
at org.apache.zookeeper.test.ClientBase.startServer(ClientBase.java:417)
at org.apache.zookeeper.test.ClientBase.setUp(ClientBase.java:408)
REGRESSION: org.apache.zookeeper.test.WatcherTest.testWatchAutoResetWithPending
Error Message:
expected:<0> but was:<1>
Stack Trace:
junit.framework.AssertionFailedError: expected:<0> but was:<1>
at org.apache.zookeeper.test.JMXEnv.ensureOnly(JMXEnv.java:138)
at org.apache.zookeeper.test.ClientBase.startServer(ClientBase.java:417)
at org.apache.zookeeper.test.ClientBase.setUp(ClientBase.java:408)
REGRESSION:
org.apache.zookeeper.test.WatcherTest.testWatcherAutoResetWithGlobal
Error Message:
expected:<0> but was:<1>
Stack Trace:
junit.framework.AssertionFailedError: expected:<0> but was:<1>
at org.apache.zookeeper.test.JMXEnv.ensureOnly(JMXEnv.java:138)
at org.apache.zookeeper.test.ClientBase.startServer(ClientBase.java:417)
at org.apache.zookeeper.test.ClientBase.setUp(ClientBase.java:408)
REGRESSION: org.apache.zookeeper.test.WatcherTest.testWatcherAutoResetWithLocal
Error Message:
expected:<0> but was:<1>
Stack Trace:
junit.framework.AssertionFailedError: expected:<0> but was:<1>
at org.apache.zookeeper.test.JMXEnv.ensureOnly(JMXEnv.java:138)
at org.apache.zookeeper.test.ClientBase.startServer(ClientBase.java:417)
at org.apache.zookeeper.test.ClientBase.setUp(ClientBase.java:408)
REGRESSION:
org.apache.zookeeper.test.WatcherTest.testWatcherAutoResetDisabledWithGlobal
Error Message:
expected:<0> but was:<1>
Stack Trace:
junit.framework.AssertionFailedError: expected:<0> but was:<1>
at org.apache.zookeeper.test.JMXEnv.ensureOnly(JMXEnv.java:138)
at org.apache.zookeeper.test.ClientBase.startServer(ClientBase.java:417)
at org.apache.zookeeper.test.ClientBase.setUp(ClientBase.java:408)
REGRESSION:
org.apache.zookeeper.test.WatcherTest.testWatcherAutoResetDisabledWithLocal
Error Message:
expected:<0> but was:<1>
Stack Trace:
junit.framework.AssertionFailedError: expected:<0> but was:<1>
at org.apache.zookeeper.test.JMXEnv.ensureOnly(JMXEnv.java:138)
at org.apache.zookeeper.test.ClientBase.startServer(ClientBase.java:417)
at org.apache.zookeeper.test.ClientBase.setUp(ClientBase.java:408)
REGRESSION: org.apache.zookeeper.test.PurgeTxnTest.testPurge
Error Message:
KeeperErrorCode = ConnectionLoss for /invalidsnap-0
Stack Trace:
org.apache.zookeeper.KeeperException$ConnectionLossException: KeeperErrorCode =
ConnectionLoss for /invalidsnap-0
at org.apache.zookeeper.KeeperException.create(KeeperException.java:99)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:843)
at
org.apache.zookeeper.test.PurgeTxnTest.testPurge(PurgeTxnTest.java:61)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
REGRESSION: org.apache.zookeeper.test.QuorumTest.testPing
Error Message:
Unable to connect to server
Stack Trace:
junit.framework.AssertionFailedError: Unable to connect to server
at
org.apache.zookeeper.test.ClientBase.createClient(ClientBase.java:182)
at org.apache.zookeeper.test.ClientTest.testPing(ClientTest.java:58)
at org.apache.zookeeper.test.QuorumTest.testPing(QuorumTest.java:77)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
REGRESSION:
org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment
Error Message:
Waiting for server up
Stack Trace:
junit.framework.AssertionFailedError: Waiting for server up
at org.apache.zookeeper.test.QuorumUtil.startQuorum(QuorumUtil.java:174)
at
org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment(QuorumTest.java:356)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
REGRESSION: org.apache.zookeeper.test.QuorumZxidSyncTest.testLateLogs
Error Message:
KeeperErrorCode = ConnectionLoss for /
Stack Trace:
org.apache.zookeeper.KeeperException$ConnectionLossException: KeeperErrorCode =
ConnectionLoss for /
at org.apache.zookeeper.KeeperException.create(KeeperException.java:99)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1644)
at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1672)
at
org.apache.zookeeper.test.QuorumZxidSyncTest.testLateLogs(QuorumZxidSyncTest.java:149)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
REGRESSION: org.apache.zookeeper.test.QuorumZxidSyncTest.testBehindLeader
Error Message:
KeeperErrorCode = ConnectionLoss for /0
Stack Trace:
org.apache.zookeeper.KeeperException$ConnectionLossException: KeeperErrorCode =
ConnectionLoss for /0
at org.apache.zookeeper.KeeperException.create(KeeperException.java:99)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:843)
at
org.apache.zookeeper.test.QuorumZxidSyncTest.testBehindLeader(QuorumZxidSyncTest.java:53)
at
org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
REGRESSION: org.apache.zookeeper.test.ReadOnlyModeTest.testSessionEstablishment
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.
FAILED: TEST-org.apache.zookeeper.test.RecoveryTest.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.RecoveryTest.xml
was length 0
