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 2016/09/10 13:33:49 UTC
ZooKeeper_branch34_solaris - Build # 1288 - Failure
See https://builds.apache.org/job/ZooKeeper_branch34_solaris/1288/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 26168 lines...]
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.toSend(QuorumCnxManager.java:354)
[junit] at org.apache.zookeeper.server.quorum.QuorumCnxManager.connectOne(QuorumCnxManager.java:381)
[junit] at org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.process(FastLeaderElection.java:452)
[junit] at org.apache.zookeeper.server.quorum.FastLeaderElection$Messenger$WorkerSender.run(FastLeaderElection.java:433)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit] 2016-09-10 13:34:39,321 [myid:1] - INFO [QuorumPeer[myid=1]/0.0.0.0:11289:FastLeaderElection@852] - Notification time out: 800
[junit] 2016-09-10 13:34:38,978 [myid:0] - INFO [WorkerReceiver[myid=0]:FastLeaderElection@600] - Notification: 1 (message format version), 1 (n.leader), 0x0 (n.zxid), 0x1 (n.round), LOOKING (n.state), 1 (n.sid), 0x0 (n.peerEpoch) LOOKING (my state)
[junit] 2016-09-10 13:34:38,978 [myid:1] - INFO [WorkerReceiver[myid=1]:FastLeaderElection@600] - Notification: 1 (message format version), 0 (n.leader), 0x0 (n.zxid), 0x1 (n.round), LOOKING (n.state), 0 (n.sid), 0x0 (n.peerEpoch) LOOKING (my state)
[junit] 2016-09-10 13:34:38,949 [myid:1] - INFO [/127.0.0.1:11291:QuorumCnxManager$Listener@541] - Received connection request /127.0.0.1:58662
[junit] 2016-09-10 13:34:38,915 [myid:1] - WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11289:NIOServerCnxn@362] - Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running
[junit] 2016-09-10 13:34:41,002 [myid:1] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11289:NIOServerCnxn@1008] - Closed socket connection for client /127.0.0.1:58668 (no session established for client)
[junit] 2016-09-10 13:34:41,007 [myid:1] - WARN [RecvWorker:0:QuorumCnxManager$RecvWorker@813] - Interrupting SendWorker
[junit] 2016-09-10 13:34:40,993 [myid:1] - INFO [WorkerReceiver[myid=1]:FastLeaderEl
[junit] Test org.apache.zookeeper.server.quorum.QuorumPeerMainTest FAILED
[junit] Running org.apache.zookeeper.server.quorum.WatchLeakTest
[junit] 2016-09-10 13:35:11,391 [myid:] - INFO [main:Environment@100] - Server environment:zookeeper.version=3.4.10-SNAPSHOT-1760160, built on 09/10/2016 13:26 GMT
[junit] 2016-09-10 13:35:12,053 [myid:] - INFO [main:Environment@100] - Server environment:host.name=hudson-solaris
[junit] 2016-09-10 13:35:12,053 [myid:] - INFO [main:Environment@100] - Server environment:java.version=1.6.0_26
[junit] 2016-09-10 13:35:12,053 [myid:] - INFO [main:Environment@100] - Server environment:java.vendor=Sun Microsystems Inc.
[junit] 2016-09-10 13:35:12,053 [myid:] - INFO [main:Environment@100] - Server environment:java.home=/zonestorage/hudson_solaris/home/hudson/hudson-slave/tools/jdk6-u26-auto-install/jre
[junit] 2016-09-10 13:35:12,054 [myid:] - INFO [main:Environment@100] - Server environment:java.class.path=/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/classes:/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/lib/antlr-2.7.6.jar:/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/lib/checkstyle-5.0.jar:/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/lib/commons-beanutils-core-1.7.0.jar:/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/lib/commons-cli-1.0.jar:/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/lib/commons-collections-3.2.2.jar:/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/lib/commons-lang-1.0.jar:/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/lib/commons-logging-1.0.3.jar:/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/lib/google-collections-0.9.jar:/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/lib/junit-4.8.1.jar:/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/lib/mockito-all-1.8.2.jar:/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/classes:/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/src/java/lib/ivy-2.4.0.jar:/home/jenkins/tools/ant/latest/lib/ant.jar:/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/lib/jline-0.9.94.jar:/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/lib/log4j-1.2.16.jar:/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/lib/netty-3.10.5.Final.jar:/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/lib/slf4j-api-1.6.1.jar:/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/lib/slf4j-log4j12-1.6.1.jar:/zonestorage/hudson_solaris/home/hudson/tools/ant/apache-ant-1.8.2/lib/ant-launcher.jar:/home/jenkins/tools/ant/latest/lib/ant-junit.jar:/home/jenkins/tools/ant/latest/lib/ant-junit4.jar
[junit] 2016-09-10 13:35:12,054 [myid:] - INFO [main:Environment@100] - Server environment:java.library.path=/zonestorage/hudson_solaris/home/hudson/hudson-slave/tools/jdk6-u26-auto-install/jre/lib/i386/server:/zonestorage/hudson_solaris/home/hudson/hudson-slave/tools/jdk6-u26-auto-install/jre/lib/i386:/zonestorage/hudson_solaris/home/hudson/hudson-slave/tools/jdk6-u26-auto-install/jre/../lib/i386:/usr/jdk/packages/lib/i386:/lib:/usr/lib
[junit] 2016-09-10 13:35:12,054 [myid:] - INFO [main:Environment@100] - Server environment:java.io.tmpdir=/var/tmp/
[junit] 2016-09-10 13:35:12,054 [myid:] - INFO [main:Environment@100] - Server environment:java.compiler=<NA>
[junit] 2016-09-10 13:35:12,054 [myid:] - INFO [main:Environment@100] - Server environment:os.name=SunOS
[junit] 2016-09-10 13:35:12,055 [myid:] - INFO [main:Environment@100] - Server environment:os.arch=x86
[junit] 2016-09-10 13:35:12,055 [myid:] - INFO [main:Environment@100] - Server environment:os.version=5.10
[junit] 2016-09-10 13:35:12,055 [myid:] - INFO [main:Environment@100] - Server environment:user.name=hudson
[junit] 2016-09-10 13:35:12,055 [myid:] - INFO [main:Environment@100] - Server environment:user.home=/export/home/hudson
[junit] 2016-09-10 13:35:12,055 [myid:] - INFO [main:Environment@100] - Server environment:user.dir=/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk
[junit] 2016-09-10 13:35:12,287 [myid:] - INFO [main:ZooKeeperServer@173] - Created server with tickTime 0 minSessionTimeout 0 maxSessionTimeout 0 datadir /tmp snapdir /tmp
[junit] 2016-09-10 13:35:13,797 [myid:] - INFO [main:Learner@86] - TCP NoDelay set to: true
[junit] 2016-09-10 13:35:14,710 [myid:] - INFO [main:ZooKeeperServer@921] - Client attempting to renew session 0xbabe at null
[junit] 2016-09-10 13:35:14,710 [myid:] - INFO [main:Learner@108] - Revalidating client: 0xbabe
[junit] 2016-09-10 13:35:14,717 [myid:] - INFO [main:ZooKeeperServer@666] - Invalid session 0xbabe for client null, probably expired
[junit] 2016-09-10 13:35:15,721 [myid:] - INFO [main:WatchLeakTest@115] - watches = 0
[junit] 2016-09-10 13:35:15,721 [myid:] - INFO [main:FollowerZooKeeperServer@140] - Shutting down
[junit] 2016-09-10 13:35:15,721 [myid:] - INFO [main:ZooKeeperServer@497] - shutting down
[junit] 2016-09-10 13:35:15,721 [myid:] - INFO [main:FollowerRequestProcessor@107] - Shutting down
[junit] 2016-09-10 13:35:15,722 [myid:] - INFO [main:CommitProcessor@184] - Shutting down
[junit] 2016-09-10 13:35:15,722 [myid:] - INFO [FollowerRequestProcessor:0:FollowerRequestProcessor@97] - FollowerRequestProcessor exited loop!
[junit] 2016-09-10 13:35:15,722 [myid:] - INFO [CommitProcessor:0:CommitProcessor@153] - CommitProcessor exited loop!
[junit] 2016-09-10 13:35:15,722 [myid:] - INFO [main:FinalRequestProcessor@402] - shutdown of request processor complete
[junit] 2016-09-10 13:35:15,723 [myid:] - INFO [main:SyncRequestProcessor@208] - Shutting down
[junit] 2016-09-10 13:35:15,723 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@186] - SyncRequestProcessor exited!
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 9.719 sec
[junit] Running org.apache.zookeeper.server.quorum.Zab1_0Test
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build.xml:1289: Process fork failed.
Total time: 9 minutes 16 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 - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
3 tests failed.
FAILED: org.apache.zookeeper.server.quorum.QuorumPeerMainTest.testUpdatingEpoch
Error Message:
Waiting too long
Stack Trace:
java.lang.RuntimeException: Waiting too long
at org.apache.zookeeper.server.quorum.QuorumPeerMainTest.waitForAll(QuorumPeerMainTest.java:348)
at org.apache.zookeeper.server.quorum.QuorumPeerMainTest.testUpdatingEpoch(QuorumPeerMainTest.java:872)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:55)
FAILED: org.apache.zookeeper.server.quorum.QuorumPeerMainTest.testNewFollowerRestartAfterNewEpoch
Error Message:
Waiting too long
Stack Trace:
java.lang.RuntimeException: Waiting too long
at org.apache.zookeeper.server.quorum.QuorumPeerMainTest.waitForAll(QuorumPeerMainTest.java:348)
at org.apache.zookeeper.server.quorum.QuorumPeerMainTest.LaunchServers(QuorumPeerMainTest.java:394)
at org.apache.zookeeper.server.quorum.QuorumPeerMainTest.testNewFollowerRestartAfterNewEpoch(QuorumPeerMainTest.java:932)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:55)
FAILED: org.apache.zookeeper.server.quorum.Zab1_0Test.unknown
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_branch34_solaris - Build # 1289 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See https://builds.apache.org/job/ZooKeeper_branch34_solaris/1289/
###################################################################################
########################## LAST 60 LINES OF THE CONSOLE ###########################
[...truncated 179246 lines...]
[junit] 2016-09-11 13:48:57,064 [myid:] - INFO [main:ZooKeeperServer@497] - shutting down
[junit] 2016-09-11 13:48:57,064 [myid:] - ERROR [main:ZooKeeperServer@472] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-11 13:48:57,064 [myid:] - INFO [main:SessionTrackerImpl@225] - Shutting down
[junit] 2016-09-11 13:48:57,064 [myid:] - INFO [main:PrepRequestProcessor@765] - Shutting down
[junit] 2016-09-11 13:48:57,064 [myid:] - INFO [main:SyncRequestProcessor@208] - Shutting down
[junit] 2016-09-11 13:48:57,064 [myid:] - INFO [ProcessThread(sid:0 cport:11221)::PrepRequestProcessor@143] - PrepRequestProcessor exited loop!
[junit] 2016-09-11 13:48:57,065 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@186] - SyncRequestProcessor exited!
[junit] 2016-09-11 13:48:57,065 [myid:] - INFO [main:FinalRequestProcessor@402] - shutdown of request processor complete
[junit] 2016-09-11 13:48:57,065 [myid:] - INFO [main:FourLetterWordMain@62] - connecting to 127.0.0.1 11221
[junit] 2016-09-11 13:48:57,066 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-09-11 13:48:57,066 [myid:] - INFO [main:ClientBase@445] - STARTING server
[junit] 2016-09-11 13:48:57,066 [myid:] - INFO [main:ClientBase@366] - CREATING server instance 127.0.0.1:11221
[junit] 2016-09-11 13:48:57,067 [myid:] - INFO [main:NIOServerCnxnFactory@89] - binding to port 0.0.0.0/0.0.0.0:11221
[junit] 2016-09-11 13:48:57,067 [myid:] - INFO [main:ClientBase@341] - STARTING server instance 127.0.0.1:11221
[junit] 2016-09-11 13:48:57,067 [myid:] - INFO [main:ZooKeeperServer@173] - 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/test2711436226713463038.junit.dir/version-2 snapdir /zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build/test/tmp/test2711436226713463038.junit.dir/version-2
[junit] 2016-09-11 13:48:57,070 [myid:] - ERROR [main:ZooKeeperServer@472] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-11 13:48:57,070 [myid:] - INFO [main:FourLetterWordMain@62] - connecting to 127.0.0.1 11221
[junit] 2016-09-11 13:48:57,070 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory@192] - Accepted socket connection from /127.0.0.1:38917
[junit] 2016-09-11 13:48:57,070 [myid:] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:11221:NIOServerCnxn@827] - Processing stat command from /127.0.0.1:38917
[junit] 2016-09-11 13:48:57,071 [myid:] - INFO [Thread-5:NIOServerCnxn$StatCommand@663] - Stat command output
[junit] 2016-09-11 13:48:57,071 [myid:] - INFO [Thread-5:NIOServerCnxn@1008] - Closed socket connection for client /127.0.0.1:38917 (no session established for client)
[junit] 2016-09-11 13:48:57,071 [myid:] - INFO [main:JMXEnv@229] - ensureParent:[InMemoryDataTree, StandaloneServer_port]
[junit] 2016-09-11 13:48:57,072 [myid:] - INFO [main:JMXEnv@246] - expect:InMemoryDataTree
[junit] 2016-09-11 13:48:57,072 [myid:] - INFO [main:JMXEnv@250] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port11221,name1=InMemoryDataTree
[junit] 2016-09-11 13:48:57,073 [myid:] - INFO [main:JMXEnv@246] - expect:StandaloneServer_port
[junit] 2016-09-11 13:48:57,073 [myid:] - INFO [main:JMXEnv@250] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port11221
[junit] 2016-09-11 13:48:57,073 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@58] - Memory used 9207
[junit] 2016-09-11 13:48:57,073 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@63] - Number of threads 20
[junit] 2016-09-11 13:48:57,073 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@78] - FINISHED TEST METHOD testQuota
[junit] 2016-09-11 13:48:57,073 [myid:] - INFO [main:ClientBase@522] - tearDown starting
[junit] 2016-09-11 13:48:57,152 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@519] - EventThread shut down for session: 0x157198210710000
[junit] 2016-09-11 13:48:57,152 [myid:] - INFO [main:ZooKeeper@684] - Session: 0x157198210710000 closed
[junit] 2016-09-11 13:48:57,152 [myid:] - INFO [main:ClientBase@492] - STOPPING server
[junit] 2016-09-11 13:48:57,153 [myid:] - INFO [main:ZooKeeperServer@497] - shutting down
[junit] 2016-09-11 13:48:57,153 [myid:] - ERROR [main:ZooKeeperServer@472] - ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit] 2016-09-11 13:48:57,153 [myid:] - INFO [main:SessionTrackerImpl@225] - Shutting down
[junit] 2016-09-11 13:48:57,153 [myid:] - INFO [main:PrepRequestProcessor@765] - Shutting down
[junit] 2016-09-11 13:48:57,153 [myid:] - INFO [main:SyncRequestProcessor@208] - Shutting down
[junit] 2016-09-11 13:48:57,153 [myid:] - INFO [ProcessThread(sid:0 cport:11221)::PrepRequestProcessor@143] - PrepRequestProcessor exited loop!
[junit] 2016-09-11 13:48:57,153 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@186] - SyncRequestProcessor exited!
[junit] 2016-09-11 13:48:57,154 [myid:] - INFO [main:FinalRequestProcessor@402] - shutdown of request processor complete
[junit] 2016-09-11 13:48:57,154 [myid:] - INFO [main:FourLetterWordMain@62] - connecting to 127.0.0.1 11221
[junit] 2016-09-11 13:48:57,155 [myid:] - INFO [main:JMXEnv@146] - ensureOnly:[]
[junit] 2016-09-11 13:48:57,158 [myid:] - INFO [main:ClientBase@547] - fdcount after test is: 42 at start it was 28
[junit] 2016-09-11 13:48:57,158 [myid:] - INFO [main:ClientBase@549] - sleeping for 20 secs
[junit] 2016-09-11 13:48:57,159 [myid:] - INFO [main:ZKTestCase$1@65] - SUCCEEDED testQuota
[junit] 2016-09-11 13:48:57,159 [myid:] - INFO [main:ZKTestCase$1@60] - FINISHED testQuota
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.922 sec
BUILD FAILED
/zonestorage/hudson_solaris/home/hudson/hudson-slave/workspace/ZooKeeper_branch34_solaris/trunk/build.xml:1320: Tests failed!
Total time: 22 minutes 40 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 - Any
Sending email for trigger: Failure - Any
###################################################################################
############################## FAILED TESTS (if any) ##############################
1 tests failed.
FAILED: org.apache.zookeeper.test.NonRecoverableErrorTest.testZooKeeperServiceAvailableOnLeader
Error Message:
IOException is expected due to error injected to transaction log commit
Stack Trace:
junit.framework.AssertionFailedError: IOException is expected due to error injected to transaction log commit
at org.apache.zookeeper.test.NonRecoverableErrorTest.testZooKeeperServiceAvailableOnLeader(NonRecoverableErrorTest.java:113)
at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:55)