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 2012/03/26 11:10:10 UTC
ZooKeeper_branch34_solaris - Build # 146 - Failure
See https://builds.apache.org/job/ZooKeeper_branch34_solaris/146/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 152987 lines...]
[junit] 2012-03-26 09:01:25,785 [myid:] - INFO [main:JMXEnv@105] - expect:InMemoryDataTree
[junit] 2012-03-26 09:01:25,786 [myid:] - INFO [main:JMXEnv@108] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2012-03-26 09:01:25,786 [myid:] - INFO [main:JMXEnv@105] - expect:StandaloneServer_port
[junit] 2012-03-26 09:01:25,787 [myid:] - INFO [main:JMXEnv@108] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2012-03-26 09:01:25,787 [myid:] - INFO [main:ClientBase@399] - STOPPING server
[junit] 2012-03-26 09:01:25,788 [myid:] - INFO [main:ZooKeeperServer@419] - shutting down
[junit] 2012-03-26 09:01:25,789 [myid:] - INFO [main:SessionTrackerImpl@220] - Shutting down
[junit] 2012-03-26 09:01:25,789 [myid:] - INFO [main:PrepRequestProcessor@743] - Shutting down
[junit] 2012-03-26 09:01:25,790 [myid:] - INFO [main:SyncRequestProcessor@175] - Shutting down
[junit] 2012-03-26 09:01:25,790 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@143] - PrepRequestProcessor exited loop!
[junit] 2012-03-26 09:01:25,791 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@155] - SyncRequestProcessor exited!
[junit] 2012-03-26 09:01:25,792 [myid:] - INFO [main:FinalRequestProcessor@415] - shutdown of request processor complete
[junit] 2012-03-26 09:01:25,793 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2012-03-26 09:01:25,794 [myid:] - INFO [main:JMXEnv@133] - ensureOnly:[]
[junit] 2012-03-26 09:01:25,795 [myid:] - INFO [main:ClientBase@392] - STARTING server
[junit] 2012-03-26 09:01:25,796 [myid:] - INFO [main:ZooKeeperServer@162] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/tmp/test6587200409013564265.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/tmp/test6587200409013564265.junit.dir/version-2
[junit] 2012-03-26 09:01:25,797 [myid:] - INFO [main:NIOServerCnxnFactory@108] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2012-03-26 09:01:25,799 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/tmp/test6587200409013564265.junit.dir/version-2/snapshot.b
[junit] 2012-03-26 09:01:25,801 [myid:] - INFO [main:FileTxnSnapLog@238] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/tmp/test6587200409013564265.junit.dir/version-2/snapshot.b
[junit] 2012-03-26 09:01:25,803 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2012-03-26 09:01:25,804 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@211] - Accepted socket connection from /127.0.0.1:49726
[junit] 2012-03-26 09:01:25,805 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@821] - Processing stat command from /127.0.0.1:49726
[junit] 2012-03-26 09:01:25,806 [myid:] - INFO [Thread-5:NIOServerCnxn$StatCommand@655] - Stat command output
[junit] 2012-03-26 09:01:25,807 [myid:] - INFO [Thread-5:NIOServerCnxn@1001] - Closed socket connection for client /127.0.0.1:49726 (no session established for client)
[junit] 2012-03-26 09:01:25,807 [myid:] - INFO [main:JMXEnv@133] - ensureOnly:[InMemoryDataTree, StandaloneServer_port]
[junit] 2012-03-26 09:01:25,809 [myid:] - INFO [main:JMXEnv@105] - expect:InMemoryDataTree
[junit] 2012-03-26 09:01:25,809 [myid:] - INFO [main:JMXEnv@108] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2012-03-26 09:01:25,810 [myid:] - INFO [main:JMXEnv@105] - expect:StandaloneServer_port
[junit] 2012-03-26 09:01:25,810 [myid:] - INFO [main:JMXEnv@108] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2012-03-26 09:01:25,811 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@57] - FINISHED TEST METHOD testQuota
[junit] 2012-03-26 09:01:25,812 [myid:] - INFO [main:ClientBase@429] - tearDown starting
[junit] 2012-03-26 09:01:25,871 [myid:] - INFO [main:ZooKeeper@679] - Session: 0x1364e3d0ce50000 closed
[junit] 2012-03-26 09:01:25,872 [myid:] - INFO [main:ClientBase@399] - STOPPING server
[junit] 2012-03-26 09:01:25,871 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
[junit] 2012-03-26 09:01:25,874 [myid:] - INFO [main:ZooKeeperServer@419] - shutting down
[junit] 2012-03-26 09:01:25,875 [myid:] - INFO [main:SessionTrackerImpl@220] - Shutting down
[junit] 2012-03-26 09:01:25,876 [myid:] - INFO [main:PrepRequestProcessor@743] - Shutting down
[junit] 2012-03-26 09:01:25,876 [myid:] - INFO [main:SyncRequestProcessor@175] - Shutting down
[junit] 2012-03-26 09:01:25,876 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@143] - PrepRequestProcessor exited loop!
[junit] 2012-03-26 09:01:25,877 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@155] - SyncRequestProcessor exited!
[junit] 2012-03-26 09:01:25,877 [myid:] - INFO [main:FinalRequestProcessor@415] - shutdown of request processor complete
[junit] 2012-03-26 09:01:25,878 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2012-03-26 09:01:25,879 [myid:] - INFO [main:JMXEnv@133] - ensureOnly:[]
[junit] 2012-03-26 09:01:25,884 [myid:] - INFO [main:ClientBase@457] - fdcount after test is: 42 at start it was 28
[junit] 2012-03-26 09:01:25,884 [myid:] - INFO [main:ClientBase@459] - sleeping for 20 secs
[junit] 2012-03-26 09:01:25,886 [myid:] - INFO [main:ZKTestCase$1@60] - SUCCEEDED testQuota
[junit] 2012-03-26 09:01:25,886 [myid:] - INFO [main:ZKTestCase$1@55] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.81 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build.xml:1200: Tests failed!
Total time: 27 minutes 32 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
4 tests failed.
REGRESSION: org.apache.zookeeper.test.FLETest.testJoin
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.LETest.testLE
Error Message:
Threads didn't join
Stack Trace:
junit.framework.AssertionFailedError: Threads didn't join
at org.apache.zookeeper.test.LETest.testLE(LETest.java:122)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
REGRESSION: org.apache.zookeeper.test.LoadFromLogTest.testRestoreWithTransactionErrors
Error Message:
KeeperErrorCode = ConnectionLoss for /invaliddir/test-
Stack Trace:
org.apache.zookeeper.KeeperException$ConnectionLossException: KeeperErrorCode = ConnectionLoss for /invaliddir/test-
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:778)
at org.apache.zookeeper.test.LoadFromLogTest.testRestoreWithTransactionErrors(LoadFromLogTest.java:407)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
REGRESSION: org.apache.zookeeper.test.ZkDatabaseCorruptionTest.testCorruption
Error Message:
the last server is not the leader
Stack Trace:
java.lang.Exception: the last server is not the leader
at org.apache.zookeeper.test.ZkDatabaseCorruptionTest.testCorruption(ZkDatabaseCorruptionTest.java:89)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)
ZooKeeper_branch34_solaris - Build # 147 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch34_solaris/147/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 154394 lines...]
[junit] 2012-03-27 08:06:38,489 [myid:] - INFO [main:JMXEnv@105] - expect:InMemoryDataTree
[junit] 2012-03-27 08:06:38,489 [myid:] - INFO [main:JMXEnv@108] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2012-03-27 08:06:38,490 [myid:] - INFO [main:JMXEnv@105] - expect:StandaloneServer_port
[junit] 2012-03-27 08:06:38,491 [myid:] - INFO [main:JMXEnv@108] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2012-03-27 08:06:38,491 [myid:] - INFO [main:ClientBase@399] - STOPPING server
[junit] 2012-03-27 08:06:38,492 [myid:] - INFO [main:ZooKeeperServer@419] - shutting down
[junit] 2012-03-27 08:06:38,493 [myid:] - INFO [main:SessionTrackerImpl@220] - Shutting down
[junit] 2012-03-27 08:06:38,493 [myid:] - INFO [main:PrepRequestProcessor@743] - Shutting down
[junit] 2012-03-27 08:06:38,494 [myid:] - INFO [main:SyncRequestProcessor@175] - Shutting down
[junit] 2012-03-27 08:06:38,494 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@143] - PrepRequestProcessor exited loop!
[junit] 2012-03-27 08:06:38,495 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@155] - SyncRequestProcessor exited!
[junit] 2012-03-27 08:06:38,495 [myid:] - INFO [main:FinalRequestProcessor@415] - shutdown of request processor complete
[junit] 2012-03-27 08:06:38,496 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2012-03-27 08:06:38,497 [myid:] - INFO [main:JMXEnv@133] - ensureOnly:[]
[junit] 2012-03-27 08:06:38,499 [myid:] - INFO [main:ClientBase@392] - STARTING server
[junit] 2012-03-27 08:06:38,499 [myid:] - INFO [main:ZooKeeperServer@162] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/tmp/test6675363695962819311.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/tmp/test6675363695962819311.junit.dir/version-2
[junit] 2012-03-27 08:06:38,500 [myid:] - INFO [main:NIOServerCnxnFactory@108] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2012-03-27 08:06:38,502 [myid:] - INFO [main:FileSnap@83] - Reading snapshot /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/tmp/test6675363695962819311.junit.dir/version-2/snapshot.b
[junit] 2012-03-27 08:06:38,504 [myid:] - INFO [main:FileTxnSnapLog@238] - Snapshotting: 0xb to /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/tmp/test6675363695962819311.junit.dir/version-2/snapshot.b
[junit] 2012-03-27 08:06:38,506 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2012-03-27 08:06:38,507 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@211] - Accepted socket connection from /127.0.0.1:62946
[junit] 2012-03-27 08:06:38,508 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@821] - Processing stat command from /127.0.0.1:62946
[junit] 2012-03-27 08:06:38,508 [myid:] - INFO [Thread-5:NIOServerCnxn$StatCommand@655] - Stat command output
[junit] 2012-03-27 08:06:38,509 [myid:] - INFO [Thread-5:NIOServerCnxn@1001] - Closed socket connection for client /127.0.0.1:62946 (no session established for client)
[junit] 2012-03-27 08:06:38,509 [myid:] - INFO [main:JMXEnv@133] - ensureOnly:[InMemoryDataTree, StandaloneServer_port]
[junit] 2012-03-27 08:06:38,511 [myid:] - INFO [main:JMXEnv@105] - expect:InMemoryDataTree
[junit] 2012-03-27 08:06:38,511 [myid:] - INFO [main:JMXEnv@108] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree
[junit] 2012-03-27 08:06:38,512 [myid:] - INFO [main:JMXEnv@105] - expect:StandaloneServer_port
[junit] 2012-03-27 08:06:38,513 [myid:] - INFO [main:JMXEnv@108] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit] 2012-03-27 08:06:38,513 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@57] - FINISHED TEST METHOD testQuota
[junit] 2012-03-27 08:06:38,514 [myid:] - INFO [main:ClientBase@429] - tearDown starting
[junit] 2012-03-27 08:06:38,584 [myid:] - INFO [main:ZooKeeper@679] - Session: 0x13653313faf0000 closed
[junit] 2012-03-27 08:06:38,584 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@511] - EventThread shut down
[junit] 2012-03-27 08:06:38,585 [myid:] - INFO [main:ClientBase@399] - STOPPING server
[junit] 2012-03-27 08:06:38,587 [myid:] - INFO [main:ZooKeeperServer@419] - shutting down
[junit] 2012-03-27 08:06:38,587 [myid:] - INFO [main:SessionTrackerImpl@220] - Shutting down
[junit] 2012-03-27 08:06:38,588 [myid:] - INFO [main:PrepRequestProcessor@743] - Shutting down
[junit] 2012-03-27 08:06:38,588 [myid:] - INFO [main:SyncRequestProcessor@175] - Shutting down
[junit] 2012-03-27 08:06:38,588 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@143] - PrepRequestProcessor exited loop!
[junit] 2012-03-27 08:06:38,589 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@155] - SyncRequestProcessor exited!
[junit] 2012-03-27 08:06:38,590 [myid:] - INFO [main:FinalRequestProcessor@415] - shutdown of request processor complete
[junit] 2012-03-27 08:06:38,591 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221
[junit] 2012-03-27 08:06:38,592 [myid:] - INFO [main:JMXEnv@133] - ensureOnly:[]
[junit] 2012-03-27 08:06:38,597 [myid:] - INFO [main:ClientBase@457] - fdcount after test is: 42 at start it was 28
[junit] 2012-03-27 08:06:38,598 [myid:] - INFO [main:ClientBase@459] - sleeping for 20 secs
[junit] 2012-03-27 08:06:38,599 [myid:] - INFO [main:ZKTestCase$1@60] - SUCCEEDED testQuota
[junit] 2012-03-27 08:06:38,599 [myid:] - INFO [main:ZKTestCase$1@55] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 2.247 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build.xml:1200: Tests failed!
Total time: 24 minutes 52 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
###################################################################################
############################## FAILED TESTS (if any) ##############################
1 tests failed.
FAILED: org.apache.zookeeper.test.FLETest.testJoin
Error Message:
Incorrect LEADING state for peer 2
Stack Trace:
junit.framework.AssertionFailedError: Incorrect LEADING state for peer 2
at org.apache.zookeeper.test.FLETest.testJoin(FLETest.java:396)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52)