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/04/21 14:20:46 UTC
ZooKeeper-trunk-jdk8 - Build # 14 - Still Failing
See https://builds.apache.org/job/ZooKeeper-trunk-jdk8/14/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 273354 lines...]
[junit] 2014-04-21 12:16:54,368 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x2000005a9 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test7178459447508344951.junit.dir/version-2/snapshot.2000005a9
[junit] 2014-04-21 12:16:54,420 [myid:] - INFO [SyncThread:2:FileTxnLog@200] - Creating new log file: log.2000005ab
[junit] 2014-04-21 12:16:57,352 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x2000005dc to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test7178459447508344951.junit.dir/version-2/snapshot.2000005dc
[junit] 2014-04-21 12:16:57,382 [myid:] - INFO [SyncThread:2:FileTxnLog@200] - Creating new log file: log.2000005de
[junit] 2014-04-21 12:16:57,763 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x2000005e7 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test8843168338773766001.junit.dir/version-2/snapshot.2000005e7
[junit] 2014-04-21 12:16:57,803 [myid:] - INFO [SyncThread:3:FileTxnLog@200] - Creating new log file: log.2000005e9
[junit] 2014-04-21 12:16:58,343 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x2000005f3 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test6166558243069636639.junit.dir/version-2/snapshot.2000005f3
[junit] 2014-04-21 12:16:58,372 [myid:] - INFO [SyncThread:1:FileTxnLog@200] - Creating new log file: log.2000005f5
[junit] 2014-04-21 12:16:59,000 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000603 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test5190379201787673046.junit.dir/version-2/snapshot.200000603
[junit] 2014-04-21 12:16:59,033 [myid:] - INFO [SyncThread:4:FileTxnLog@200] - Creating new log file: log.200000605
[junit] 2014-04-21 12:17:00,477 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000622 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test7178459447508344951.junit.dir/version-2/snapshot.200000622
[junit] 2014-04-21 12:17:00,523 [myid:] - INFO [SyncThread:2:FileTxnLog@200] - Creating new log file: log.200000624
[junit] 2014-04-21 12:17:03,222 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000646 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test8843168338773766001.junit.dir/version-2/snapshot.200000646
[junit] 2014-04-21 12:17:03,260 [myid:] - INFO [SyncThread:3:FileTxnLog@200] - Creating new log file: log.200000648
[junit] 2014-04-21 12:17:03,867 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000652 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test6166558243069636639.junit.dir/version-2/snapshot.200000652
[junit] 2014-04-21 12:17:03,915 [myid:] - INFO [SyncThread:1:FileTxnLog@200] - Creating new log file: log.200000654
[junit] 2014-04-21 12:17:04,741 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000661 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test5190379201787673046.junit.dir/version-2/snapshot.200000661
[junit] 2014-04-21 12:17:04,776 [myid:] - INFO [SyncThread:4:FileTxnLog@200] - Creating new log file: log.200000663
[junit] 2014-04-21 12:17:06,667 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000678 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test7178459447508344951.junit.dir/version-2/snapshot.200000678
[junit] 2014-04-21 12:17:06,714 [myid:] - INFO [SyncThread:2:FileTxnLog@200] - Creating new log file: log.20000067a
[junit] 2014-04-21 12:17:08,140 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000692 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test8843168338773766001.junit.dir/version-2/snapshot.200000692
[junit] 2014-04-21 12:17:08,180 [myid:] - INFO [SyncThread:3:FileTxnLog@200] - Creating new log file: log.200000694
[junit] 2014-04-21 12:17:08,340 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000696 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test6166558243069636639.junit.dir/version-2/snapshot.200000696
[junit] 2014-04-21 12:17:08,384 [myid:] - INFO [SyncThread:1:FileTxnLog@200] - Creating new log file: log.200000698
[junit] 2014-04-21 12:17:10,838 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x2000006be to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test5190379201787673046.junit.dir/version-2/snapshot.2000006be
[junit] 2014-04-21 12:17:10,880 [myid:] - INFO [SyncThread:4:FileTxnLog@200] - Creating new log file: log.2000006c0
[junit] 2014-04-21 12:17:11,647 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x2000006c7 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test7178459447508344951.junit.dir/version-2/snapshot.2000006c7
[junit] 2014-04-21 12:17:11,742 [myid:] - INFO [SyncThread:2:FileTxnLog@200] - Creating new log file: log.2000006c9
[junit] 2014-04-21 12:17:12,500 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x2000006d3 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test8843168338773766001.junit.dir/version-2/snapshot.2000006d3
[junit] 2014-04-21 12:17:12,545 [myid:] - INFO [SyncThread:3:FileTxnLog@200] - Creating new log file: log.2000006d5
[junit] 2014-04-21 12:17:12,545 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x2000006d4 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test6166558243069636639.junit.dir/version-2/snapshot.2000006d4
[junit] 2014-04-21 12:17:12,612 [myid:] - INFO [SyncThread:1:FileTxnLog@200] - Creating new log file: log.2000006d6
[junit] 2014-04-21 12:17:13,849 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x2000006f2 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test5190379201787673046.junit.dir/version-2/snapshot.2000006f2
[junit] 2014-04-21 12:17:13,874 [myid:] - INFO [SyncThread:4:FileTxnLog@200] - Creating new log file: log.2000006f4
[junit] 2014-04-21 12:17:14,468 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000701 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test7178459447508344951.junit.dir/version-2/snapshot.200000701
[junit] 2014-04-21 12:17:14,531 [myid:] - INFO [SyncThread:2:FileTxnLog@200] - Creating new log file: log.200000703
[junit] 2014-04-21 12:17:14,977 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000710 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test8843168338773766001.junit.dir/version-2/snapshot.200000710
[junit] 2014-04-21 12:17:15,010 [myid:] - INFO [SyncThread:3:FileTxnLog@200] - Creating new log file: log.200000712
[junit] 2014-04-21 12:17:15,158 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000715 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test6166558243069636639.junit.dir/version-2/snapshot.200000715
[junit] 2014-04-21 12:17:15,295 [myid:] - INFO [SyncThread:1:FileTxnLog@200] - Creating new log file: log.200000717
[junit] 2014-04-21 12:17:16,679 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000735 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test5190379201787673046.junit.dir/version-2/snapshot.200000735
[junit] 2014-04-21 12:17:16,696 [myid:] - INFO [SyncThread:4:FileTxnLog@200] - Creating new log file: log.200000737
[junit] 2014-04-21 12:17:17,881 [myid:] - WARN [SyncThread:4:FileTxnLog@335] - fsync-ing the write ahead log in SyncThread:4 took 1184ms which will adversely effect operation latency. See the ZooKeeper troubleshooting guide
[junit] 2014-04-21 12:17:17,882 [myid:] - WARN [SyncThread:2:FileTxnLog@335] - fsync-ing the write ahead log in SyncThread:2 took 1562ms which will adversely effect operation latency. See the ZooKeeper troubleshooting guide
[junit] 2014-04-21 12:17:18,770 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000741 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test7178459447508344951.junit.dir/version-2/snapshot.200000741
[junit] 2014-04-21 12:17:18,794 [myid:] - INFO [SyncThread:2:FileTxnLog@200] - Creating new log file: log.200000743
[junit] 2014-04-21 12:17:19,678 [myid:] - WARN [SyncThread:3:FileTxnLog@335] - fsync-ing the write ahead log in SyncThread:3 took 1481ms which will adversely effect operation latency. See the ZooKeeper troubleshooting guide
[junit] 2014-04-21 12:17:20,280 [myid:] - WARN [SyncThread:1:FileTxnLog@335] - fsync-ing the write ahead log in SyncThread:1 took 1485ms which will adversely effect operation latency. See the ZooKeeper troubleshooting guide
[junit] 2014-04-21 12:17:20,602 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000748 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test6166558243069636639.junit.dir/version-2/snapshot.200000748
[junit] 2014-04-21 12:17:20,637 [myid:] - INFO [SyncThread:1:FileTxnLog@200] - Creating new log file: log.20000074a
[junit] 2014-04-21 12:17:22,048 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000769 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test8843168338773766001.junit.dir/version-2/snapshot.200000769
[junit] 2014-04-21 12:17:22,126 [myid:] - INFO [SyncThread:3:FileTxnLog@200] - Creating new log file: log.20000076b
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
[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) ##############################
3 tests failed.
REGRESSION: org.apache.zookeeper.test.StaticHostProviderTest.testUpdateClientMigrateOrNot
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.ZkDatabaseCorruptionTest.xml.<init>
Error Message:
Stack Trace:
Test report file /home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/logs/TEST-org.apache.zookeeper.test.ZkDatabaseCorruptionTest.xml was length 0
FAILED: 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)
ZooKeeper-trunk-jdk8 - Build # 19 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk-jdk8/19/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 274375 lines...]
[junit] 2014-04-27 11:24:12,315 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-04-27 11:24:12,315 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-04-27 11:24:12,316 [myid:] - INFO [main:ClientBase@443] - STARTING server
[junit] 2014-04-27 11:24:12,317 [myid:] - INFO [main:ClientBase@364] - CREATING server instance 127.0.0.1:11221
[junit] 2014-04-27 11:24:12,317 [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-04-27 11:24:12,317 [myid:] - INFO [main:NIOServerCnxnFactory@683] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2014-04-27 11:24:12,318 [myid:] - INFO [main:ClientBase@339] - STARTING server instance 127.0.0.1:11221
[junit] 2014-04-27 11:24:12,318 [myid:] - INFO [main:ZooKeeperServer@766] - minSessionTimeout set to 6000
[junit] 2014-04-27 11:24:12,318 [myid:] - INFO [main:ZooKeeperServer@775] - maxSessionTimeout set to 60000
[junit] 2014-04-27 11:24:12,318 [myid:] - INFO [main:ZooKeeperServer@149] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test3345792095088560853.junit.dir/version-2 snapdir /home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test3345792095088560853.junit.dir/version-2
[junit] 2014-04-27 11:24:12,319 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test3345792095088560853.junit.dir/version-2/snapshot.b
[junit] 2014-04-27 11:24:12,321 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to /home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test3345792095088560853.junit.dir/version-2/snapshot.b
[junit] 2014-04-27 11:24:12,323 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-04-27 11:24:12,323 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:36280
[junit] 2014-04-27 11:24:12,324 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:36280
[junit] 2014-04-27 11:24:12,324 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-04-27 11:24:12,325 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:36280 (no session established for client)
[junit] 2014-04-27 11:24:12,325 [myid:] - INFO [main:JMXEnv@224] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2014-04-27 11:24:12,326 [myid:] - INFO [main:JMXEnv@241] - expect:InMemoryDataTree
[junit] 2014-04-27 11:24:12,326 [myid:] - INFO [main:JMXEnv@245] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2014-04-27 11:24:12,327 [myid:] - INFO [main:JMXEnv@241] - expect:StandaloneServer_port
[junit] 2014-04-27 11:24:12,327 [myid:] - INFO [main:JMXEnv@245] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2014-04-27 11:24:12,327 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@55] - Memory used 3508
[junit] 2014-04-27 11:24:12,327 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@60] - Number of threads 24
[junit] 2014-04-27 11:24:12,327 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@65] - FINISHED TEST METHOD testQuota
[junit] 2014-04-27 11:24:12,328 [myid:] - INFO [main:ClientBase@520] - tearDown starting
[junit] 2014-04-27 11:24:12,392 [myid:] - INFO [main:ZooKeeper@966] - Session: 0x145a2ed9b070000 closed
[junit] 2014-04-27 11:24:12,392 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-04-27 11:24:12,393 [myid:] - INFO [main:ClientBase@490] - STOPPING server
[junit] 2014-04-27 11:24:12,393 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2014-04-27 11:24:12,393 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-04-27 11:24:12,393 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-04-27 11:24:12,393 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2014-04-27 11:24:12,394 [myid:] - INFO [main:ZooKeeperServer@428] - shutting down
[junit] 2014-04-27 11:24:12,394 [myid:] - INFO [main:SessionTrackerImpl@184] - Shutting down
[junit] 2014-04-27 11:24:12,394 [myid:] - INFO [main:PrepRequestProcessor@980] - Shutting down
[junit] 2014-04-27 11:24:12,394 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-04-27 11:24:12,394 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-04-27 11:24:12,394 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-04-27 11:24:12,395 [myid:] - INFO [main:FinalRequestProcessor@462] - shutdown of request processor complete
[junit] 2014-04-27 11:24:12,395 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-04-27 11:24:12,396 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-04-27 11:24:12,399 [myid:] - INFO [main:ClientBase@545] - fdcount after test is: 46 at start it was 30
[junit] 2014-04-27 11:24:12,399 [myid:] - INFO [main:ClientBase@547] - sleeping for 20 secs
[junit] 2014-04-27 11:24:12,401 [myid:] - INFO [main:ZKTestCase$1@61] - SUCCEEDED testQuota
[junit] 2014-04-27 11:24:12,401 [myid:] - INFO [main:ZKTestCase$1@56] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.77 sec
BUILD FAILED
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build.xml:1337: Tests failed!
Total time: 47 minutes 56 seconds
Build step 'Execute shell' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
[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) ##############################
1 tests failed.
REGRESSION: org.apache.zookeeper.server.quorum.CommitProcessorTest.testManyCommitWorkers
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.
$PROJECT_NAME - Build # $BUILD_NUMBER - $BUILD_STATUS
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See ${BUILD_URL}
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
${BUILD_LOG, maxLines=60}
###################################################################################
############################## FAILED TESTS (if any) ##############################
${FAILED_TESTS}
ZooKeeper-trunk-jdk8 - Build # 17 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk-jdk8/17/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 274128 lines...]
[junit] 2014-04-25 12:35:08,747 [myid:] - INFO [main:ZkDatabaseCorruptionTest@130] - 127.0.0.1:11232 is accepting client connections
[junit] 2014-04-25 12:35:08,747 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11233
[junit] 2014-04-25 12:35:08,747 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11233:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:45271
[junit] 2014-04-25 12:35:08,748 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:45271
[junit] 2014-04-25 12:35:08,749 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-04-25 12:35:08,749 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:45271 (no session established for client)
[junit] 2014-04-25 12:35:08,749 [myid:] - INFO [main:ZkDatabaseCorruptionTest@130] - 127.0.0.1:11233 is accepting client connections
[junit] 2014-04-25 12:35:08,750 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11234
[junit] 2014-04-25 12:35:08,750 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11234:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:38622
[junit] 2014-04-25 12:35:08,751 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:38622
[junit] 2014-04-25 12:35:08,751 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-04-25 12:35:08,752 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:38622 (no session established for client)
[junit] 2014-04-25 12:35:08,752 [myid:] - INFO [main:ZkDatabaseCorruptionTest@130] - 127.0.0.1:11234 is accepting client connections
[junit] 2014-04-25 12:35:08,752 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11235
[junit] 2014-04-25 12:35:08,752 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11235:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:45986
[junit] 2014-04-25 12:35:08,753 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:45986
[junit] 2014-04-25 12:35:08,754 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-04-25 12:35:08,754 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:45986 (no session established for client)
[junit] 2014-04-25 12:35:08,754 [myid:] - INFO [main:ZkDatabaseCorruptionTest@130] - 127.0.0.1:11235 is accepting client connections
[junit] 2014-04-25 12:35:08,755 [myid:] - INFO [main:ZkDatabaseCorruptionTest@132] - Skipping the leader
[junit] 2014-04-25 12:35:08,756 [myid:] - INFO [main:ZooKeeper@707] - Initiating client connection, connectString=127.0.0.1:11232,127.0.0.1:11233,127.0.0.1:11234,127.0.0.1:11235,127.0.0.1:11236 sessionTimeout=30000 watcher=org.apache.zookeeper.test.ClientBase$CountdownWatcher@735f7ae5
[junit] 2014-04-25 12:35:08,757 [myid:] - INFO [main-SendThread(127.0.0.1:11232):ClientCnxn$SendThread@1093] - Opening socket connection to server 127.0.0.1/127.0.0.1:11232. Will not attempt to authenticate using SASL (unknown error)
[junit] 2014-04-25 12:35:08,758 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11232:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:35993
[junit] 2014-04-25 12:35:08,758 [myid:] - INFO [main-SendThread(127.0.0.1:11232):ClientCnxn$SendThread@963] - Socket connection established to 127.0.0.1/127.0.0.1:11232, initiating session
[junit] 2014-04-25 12:35:08,759 [myid:] - INFO [NIOWorkerThread-8:ZooKeeperServer@853] - Client attempting to establish new session at /127.0.0.1:35993
[junit] 2014-04-25 12:35:08,762 [myid:] - INFO [SyncThread:4:FileTxnLog@200] - Creating new log file: log.200000001
[junit] 2014-04-25 12:35:08,763 [myid:] - WARN [QuorumPeer[myid=3]/0:0:0:0:0:0:0:0:11234:Follower@121] - Got zxid 0x200000001 expected 0x1
[junit] 2014-04-25 12:35:08,763 [myid:] - WARN [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:11233:Follower@121] - Got zxid 0x200000001 expected 0x1
[junit] 2014-04-25 12:35:08,763 [myid:] - WARN [QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:11232:Follower@121] - Got zxid 0x200000001 expected 0x1
[junit] 2014-04-25 12:35:08,763 [myid:] - INFO [SyncThread:2:FileTxnLog@200] - Creating new log file: log.200000001
[junit] 2014-04-25 12:35:08,763 [myid:] - INFO [SyncThread:3:FileTxnLog@200] - Creating new log file: log.200000001
[junit] 2014-04-25 12:35:08,763 [myid:] - INFO [SyncThread:1:FileTxnLog@200] - Creating new log file: log.200000001
[junit] 2014-04-25 12:35:08,912 [myid:] - INFO [CommitProcWorkThread-9:ZooKeeperServer@604] - Established session 0x14598e1d69d0000 with negotiated timeout 30000 for client /127.0.0.1:35993
[junit] 2014-04-25 12:35:08,913 [myid:] - INFO [main-SendThread(127.0.0.1:11232):ClientCnxn$SendThread@1346] - Session establishment complete on server 127.0.0.1/127.0.0.1:11232, sessionid = 0x14598e1d69d0000, negotiated timeout = 30000
[junit] 2014-04-25 12:35:08,919 [myid:] - INFO [main:JMXEnv@113] - expect:0x14598e1d69d0000
[junit] 2014-04-25 12:35:08,919 [myid:] - INFO [main:JMXEnv@116] - found:0x14598e1d69d0000 org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=Follower,name3=Connections,name4=127.0.0.1,name5=0x14598e1d69d0000
[junit] 2014-04-25 12:35:10,951 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000048 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test8164843032837091271.junit.dir/version-2/snapshot.200000048
[junit] 2014-04-25 12:35:10,974 [myid:] - INFO [SyncThread:1:FileTxnLog@200] - Creating new log file: log.20000004a
[junit] 2014-04-25 12:35:11,182 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x20000004f to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test4516132787339648363.junit.dir/version-2/snapshot.20000004f
[junit] 2014-04-25 12:35:11,182 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x20000004f to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test8896974237850678125.junit.dir/version-2/snapshot.20000004f
[junit] 2014-04-25 12:35:11,206 [myid:] - INFO [SyncThread:4:FileTxnLog@200] - Creating new log file: log.200000051
[junit] 2014-04-25 12:35:11,206 [myid:] - INFO [SyncThread:2:FileTxnLog@200] - Creating new log file: log.200000051
[junit] 2014-04-25 12:35:11,367 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000051 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test5985989361498137423.junit.dir/version-2/snapshot.200000051
[junit] 2014-04-25 12:35:11,387 [myid:] - INFO [SyncThread:3:FileTxnLog@200] - Creating new log file: log.200000053
[junit] 2014-04-25 12:35:13,943 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x200000087 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test8896974237850678125.junit.dir/version-2/snapshot.200000087
[junit] 2014-04-25 12:35:13,985 [myid:] - INFO [SyncThread:2:FileTxnLog@200] - Creating new log file: log.200000089
[junit] 2014-04-25 12:35:14,186 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x20000008c to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test5985989361498137423.junit.dir/version-2/snapshot.20000008c
[junit] 2014-04-25 12:35:14,232 [myid:] - INFO [SyncThread:3:FileTxnLog@200] - Creating new log file: log.20000008e
[junit] 2014-04-25 12:35:15,122 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x2000000a5 to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test8164843032837091271.junit.dir/version-2/snapshot.2000000a5
[junit] 2014-04-25 12:35:15,163 [myid:] - INFO [SyncThread:1:FileTxnLog@200] - Creating new log file: log.2000000a7
[junit] 2014-04-25 12:35:15,425 [myid:] - INFO [Snapshot Thread:FileTxnSnapLog@298] - Snapshotting: 0x2000000ad to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test4516132787339648363.junit.dir/version-2/snapshot.2000000ad
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
[junit] 2014-04-25 12:35:15,449 [myid:] - INFO [SyncThread:4:FileTxnLog@200] - Creating new log file: log.2000000af
[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.
FAILED: org.apache.zookeeper.test.StaticHostProviderTest.testUpdateClientMigrateOrNot
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.ZkDatabaseCorruptionTest.xml.<init>
Error Message:
Stack Trace:
Test report file /home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/logs/TEST-org.apache.zookeeper.test.ZkDatabaseCorruptionTest.xml was length 0
ZooKeeper-trunk-jdk8 - Build # 16 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk-jdk8/16/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 238710 lines...]
[junit] 2014-04-23 16:54:22,317 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-04-23 16:54:22,318 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-04-23 16:54:22,317 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-04-23 16:54:22,318 [myid:] - INFO [main:FinalRequestProcessor@462] - shutdown of request processor complete
[junit] 2014-04-23 16:54:22,318 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-04-23 16:54:22,319 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-04-23 16:54:22,320 [myid:] - INFO [main:ClientBase@443] - STARTING server
[junit] 2014-04-23 16:54:22,320 [myid:] - INFO [main:ClientBase@364] - CREATING server instance 127.0.0.1:11221
[junit] 2014-04-23 16:54:22,320 [myid:] - INFO [main:NIOServerCnxnFactory@670] - Configuring NIO connection handler with 10s sessionless connection timeout, 3 selector thread(s), 48 worker threads, and 64 kB direct buffers.
[junit] 2014-04-23 16:54:22,327 [myid:] - INFO [main:NIOServerCnxnFactory@683] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2014-04-23 16:54:22,328 [myid:] - INFO [main:ClientBase@339] - STARTING server instance 127.0.0.1:11221
[junit] 2014-04-23 16:54:22,328 [myid:] - INFO [main:ZooKeeperServer@766] - minSessionTimeout set to 6000
[junit] 2014-04-23 16:54:22,328 [myid:] - INFO [main:ZooKeeperServer@775] - maxSessionTimeout set to 60000
[junit] 2014-04-23 16:54:22,328 [myid:] - INFO [main:ZooKeeperServer@149] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test8578587173622632695.junit.dir/version-2 snapdir /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test8578587173622632695.junit.dir/version-2
[junit] 2014-04-23 16:54:22,329 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test8578587173622632695.junit.dir/version-2/snapshot.b
[junit] 2014-04-23 16:54:22,333 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to /x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test8578587173622632695.junit.dir/version-2/snapshot.b
[junit] 2014-04-23 16:54:22,335 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-04-23 16:54:22,335 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:57046
[junit] 2014-04-23 16:54:22,336 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:57046
[junit] 2014-04-23 16:54:22,336 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-04-23 16:54:22,337 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:57046 (no session established for client)
[junit] 2014-04-23 16:54:22,337 [myid:] - INFO [main:JMXEnv@224] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2014-04-23 16:54:22,338 [myid:] - INFO [main:JMXEnv@241] - expect:InMemoryDataTree
[junit] 2014-04-23 16:54:22,339 [myid:] - INFO [main:JMXEnv@245] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2014-04-23 16:54:22,339 [myid:] - INFO [main:JMXEnv@241] - expect:StandaloneServer_port
[junit] 2014-04-23 16:54:22,339 [myid:] - INFO [main:JMXEnv@245] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2014-04-23 16:54:22,339 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@55] - Memory used 3651
[junit] 2014-04-23 16:54:22,339 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@60] - Number of threads 25
[junit] 2014-04-23 16:54:22,340 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@65] - FINISHED TEST METHOD testQuota
[junit] 2014-04-23 16:54:22,340 [myid:] - INFO [main:ClientBase@520] - tearDown starting
[junit] 2014-04-23 16:54:22,385 [myid:] - INFO [main:ZooKeeper@966] - Session: 0x1458f82712c0000 closed
[junit] 2014-04-23 16:54:22,385 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-04-23 16:54:22,385 [myid:] - INFO [main:ClientBase@490] - STOPPING server
[junit] 2014-04-23 16:54:22,388 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2014-04-23 16:54:22,389 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-04-23 16:54:22,389 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-04-23 16:54:22,392 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2014-04-23 16:54:22,392 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-2:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-04-23 16:54:22,396 [myid:] - INFO [main:ZooKeeperServer@428] - shutting down
[junit] 2014-04-23 16:54:22,396 [myid:] - INFO [main:SessionTrackerImpl@184] - Shutting down
[junit] 2014-04-23 16:54:22,396 [myid:] - INFO [main:PrepRequestProcessor@980] - Shutting down
[junit] 2014-04-23 16:54:22,396 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-04-23 16:54:22,396 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-04-23 16:54:22,396 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-04-23 16:54:22,400 [myid:] - INFO [main:FinalRequestProcessor@462] - shutdown of request processor complete
[junit] 2014-04-23 16:54:22,400 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-04-23 16:54:22,401 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-04-23 16:54:22,404 [myid:] - INFO [main:ClientBase@545] - fdcount after test is: 47 at start it was 31
[junit] 2014-04-23 16:54:22,404 [myid:] - INFO [main:ClientBase@547] - sleeping for 20 secs
[junit] 2014-04-23 16:54:22,405 [myid:] - INFO [main:ZKTestCase$1@61] - SUCCEEDED testQuota
[junit] 2014-04-23 16:54:22,405 [myid:] - INFO [main:ZKTestCase$1@56] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.964 sec
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build.xml:1337: Tests failed!
Total time: 60 minutes 0 seconds
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
4 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)
REGRESSION: org.apache.zookeeper.test.LETest.testLE
Error Message:
Address already in use
Stack Trace:
java.net.BindException: Address already in use
at sun.nio.ch.Net.bind0(Native Method)
at sun.nio.ch.Net.bind(Net.java:414)
at sun.nio.ch.Net.bind(Net.java:406)
at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:214)
at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:74)
at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:67)
at org.apache.zookeeper.server.NIOServerCnxnFactory.configure(NIOServerCnxnFactory.java:684)
at org.apache.zookeeper.server.ServerCnxnFactory.createFactory(ServerCnxnFactory.java:127)
at org.apache.zookeeper.server.quorum.QuorumPeer.<init>(QuorumPeer.java:741)
at org.apache.zookeeper.test.LETest.testLE(LETest.java:112)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
REGRESSION: org.apache.zookeeper.test.StaticHostProviderTest.testUpdateLoadBalancing
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.
REGRESSION: org.apache.zookeeper.test.ZkDatabaseCorruptionTest.testCorruption
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.
ZooKeeper-trunk-jdk8 - Build # 15 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper-trunk-jdk8/15/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 265969 lines...]
[junit] 2014-04-22 18:34:36,444 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-04-22 18:34:36,445 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-04-22 18:34:36,446 [myid:] - INFO [main:ClientBase@443] - STARTING server
[junit] 2014-04-22 18:34:36,446 [myid:] - INFO [main:ClientBase@364] - CREATING server instance 127.0.0.1:11221
[junit] 2014-04-22 18:34:36,446 [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-04-22 18:34:36,447 [myid:] - INFO [main:NIOServerCnxnFactory@683] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2014-04-22 18:34:36,447 [myid:] - INFO [main:ClientBase@339] - STARTING server instance 127.0.0.1:11221
[junit] 2014-04-22 18:34:36,447 [myid:] - INFO [main:ZooKeeperServer@766] - minSessionTimeout set to 6000
[junit] 2014-04-22 18:34:36,448 [myid:] - INFO [main:ZooKeeperServer@775] - maxSessionTimeout set to 60000
[junit] 2014-04-22 18:34:36,448 [myid:] - INFO [main:ZooKeeperServer@149] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test405058806239266840.junit.dir/version-2 snapdir /home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test405058806239266840.junit.dir/version-2
[junit] 2014-04-22 18:34:36,448 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test405058806239266840.junit.dir/version-2/snapshot.b
[junit] 2014-04-22 18:34:36,451 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to /home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build/test/tmp/test405058806239266840.junit.dir/version-2/snapshot.b
[junit] 2014-04-22 18:34:36,452 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-04-22 18:34:36,453 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:58551
[junit] 2014-04-22 18:34:36,453 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:58551
[junit] 2014-04-22 18:34:36,453 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output
[junit] 2014-04-22 18:34:36,454 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:58551 (no session established for client)
[junit] 2014-04-22 18:34:36,454 [myid:] - INFO [main:JMXEnv@224] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2014-04-22 18:34:36,455 [myid:] - INFO [main:JMXEnv@241] - expect:InMemoryDataTree
[junit] 2014-04-22 18:34:36,455 [myid:] - INFO [main:JMXEnv@245] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2014-04-22 18:34:36,456 [myid:] - INFO [main:JMXEnv@241] - expect:StandaloneServer_port
[junit] 2014-04-22 18:34:36,456 [myid:] - INFO [main:JMXEnv@245] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2014-04-22 18:34:36,456 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@55] - Memory used 3407
[junit] 2014-04-22 18:34:36,456 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@60] - Number of threads 24
[junit] 2014-04-22 18:34:36,456 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@65] - FINISHED TEST METHOD testQuota
[junit] 2014-04-22 18:34:36,457 [myid:] - INFO [main:ClientBase@520] - tearDown starting
[junit] 2014-04-22 18:34:36,522 [myid:] - INFO [main:ZooKeeper@966] - Session: 0x1458ab7d98b0000 closed
[junit] 2014-04-22 18:34:36,522 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down
[junit] 2014-04-22 18:34:36,523 [myid:] - INFO [main:ClientBase@490] - STOPPING server
[junit] 2014-04-22 18:34:36,523 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted
[junit] 2014-04-22 18:34:36,523 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-1:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-04-22 18:34:36,523 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method
[junit] 2014-04-22 18:34:36,523 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method
[junit] 2014-04-22 18:34:36,524 [myid:] - INFO [main:ZooKeeperServer@428] - shutting down
[junit] 2014-04-22 18:34:36,524 [myid:] - INFO [main:SessionTrackerImpl@184] - Shutting down
[junit] 2014-04-22 18:34:36,524 [myid:] - INFO [main:PrepRequestProcessor@980] - Shutting down
[junit] 2014-04-22 18:34:36,524 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down
[junit] 2014-04-22 18:34:36,524 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop!
[junit] 2014-04-22 18:34:36,524 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited!
[junit] 2014-04-22 18:34:36,525 [myid:] - INFO [main:FinalRequestProcessor@462] - shutdown of request processor complete
[junit] 2014-04-22 18:34:36,525 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2014-04-22 18:34:36,526 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[]
[junit] 2014-04-22 18:34:36,529 [myid:] - INFO [main:ClientBase@545] - fdcount after test is: 46 at start it was 30
[junit] 2014-04-22 18:34:36,529 [myid:] - INFO [main:ClientBase@547] - sleeping for 20 secs
[junit] 2014-04-22 18:34:36,530 [myid:] - INFO [main:ZKTestCase$1@61] - SUCCEEDED testQuota
[junit] 2014-04-22 18:34:36,531 [myid:] - INFO [main:ZKTestCase$1@56] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 1.009 sec
BUILD FAILED
/home/jenkins/jenkins-slave/workspace/ZooKeeper-trunk-jdk8/trunk/build.xml:1337: Tests failed!
Total time: 53 minutes 20 seconds
Build step 'Execute shell' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
[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) ##############################
1 tests failed.
REGRESSION: org.apache.zookeeper.test.RestoreCommittedLogTest.testRestoreCommittedLog
Error Message:
KeeperErrorCode = ConnectionLoss for /invalidsnap-978
Stack Trace:
org.apache.zookeeper.KeeperException$ConnectionLossException: KeeperErrorCode = ConnectionLoss for /invalidsnap-978
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:1065)
at org.apache.zookeeper.test.RestoreCommittedLogTest.testRestoreCommittedLog(RestoreCommittedLogTest.java:64)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)