Return-Path: X-Original-To: apmail-hbase-builds-archive@minotaur.apache.org Delivered-To: apmail-hbase-builds-archive@minotaur.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 9CF5D1762B for ; Wed, 8 Apr 2015 03:09:57 +0000 (UTC) Received: (qmail 30695 invoked by uid 500); 8 Apr 2015 03:09:57 -0000 Delivered-To: apmail-hbase-builds-archive@hbase.apache.org Received: (qmail 30656 invoked by uid 500); 8 Apr 2015 03:09:57 -0000 Mailing-List: contact builds-help@hbase.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: builds@hbase.apache.org Delivered-To: mailing list builds@hbase.apache.org Received: (qmail 30645 invoked by uid 99); 8 Apr 2015 03:09:57 -0000 Received: from crius.apache.org (HELO crius) (140.211.11.14) by apache.org (qpsmtpd/0.29) with ESMTP; Wed, 08 Apr 2015 03:09:57 +0000 Received: from crius.apache.org (localhost [127.0.0.1]) by crius (Postfix) with ESMTP id EF75FE00071 for ; Wed, 8 Apr 2015 03:09:56 +0000 (UTC) Date: Wed, 8 Apr 2015 03:09:56 +0000 (UTC) From: Apache Jenkins Server To: builds@hbase.apache.org Message-ID: <1377448690.9532.1428462596978.JavaMail.jenkins@crius> Subject: Build failed in Jenkins: HBase-0.98-on-Hadoop-1.1 #892 MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: 7bit X-Jenkins-Job: HBase-0.98-on-Hadoop-1.1 X-Jenkins-Result: FAILURE See Changes: [jon] HBASE-13289 typo in splitSucessCount metric ------------------------------------------ [...truncated 1950 lines...] if [[ $ZOMBIE_TESTS_COUNT != 0 ]] ; then #It seems sometimes the tests are not dying immediately. Let's give them 10s echo "Suspicious java process found - waiting 10s to see if there are just slow to stop" sleep 10 ZOMBIE_TESTS_COUNT=`jps | grep surefirebooter | wc -l` if [[ $ZOMBIE_TESTS_COUNT != 0 ]] ; then echo "There are $ZOMBIE_TESTS_COUNT zombie tests, they should have been killed by surefire but survived" echo "************ BEGIN zombies jstack extract" ZB_STACK=`jps | grep surefirebooter | cut -d ' ' -f 1 | xargs -n 1 jstack | grep ".test" | grep "\.java"` jps | grep surefirebooter | cut -d ' ' -f 1 | xargs -n 1 jstack echo "************ END zombies jstack extract" JIRA_COMMENT="$JIRA_COMMENT {color:red}-1 core zombie tests{color}. There are ${ZOMBIE_TESTS_COUNT} zombie test(s): ${ZB_STACK}" BAD=1 jps | grep surefirebooter | cut -d ' ' -f 1 | xargs kill -9 else echo "We're ok: there is no zombie test, but some tests took some time to stop" fi else echo "We're ok: there is no zombie test" fi [HBase-0.98-on-Hadoop-1.1] $ /bin/bash -xe /tmp/hudson4291570935145250832.sh ++ jps ++ grep surefirebooter ++ wc -l + ZOMBIE_TESTS_COUNT=2 + [[ 2 != 0 ]] + echo 'Suspicious java process found - waiting 10s to see if there are just slow to stop' Suspicious java process found - waiting 10s to see if there are just slow to stop + sleep 10 ++ jps ++ grep surefirebooter ++ wc -l + ZOMBIE_TESTS_COUNT=2 + [[ 2 != 0 ]] + echo 'There are 2 zombie tests, they should have been killed by surefire but survived' There are 2 zombie tests, they should have been killed by surefire but survived + echo '************ BEGIN zombies jstack extract' ************ BEGIN zombies jstack extract ++ jps ++ grep surefirebooter ++ cut -d ' ' -f 1 ++ grep .test ++ grep '\.java' ++ xargs -n 1 jstack 22414: Unable to open socket file: target process not responding or HotSpot VM not loaded The -F option can be used when the target process is not responding + ZB_STACK=' at org.apache.hadoop.hdfs.server.mover.TestMover.testMoverCliWithFederationHA(TestMover.java:277)' + jps + grep surefirebooter + cut -d ' ' -f 1 + xargs -n 1 jstack 2015-04-08 03:08:21 Full thread dump Java HotSpot(TM) 64-Bit Server VM (24.55-b03 mixed mode): "Attach Listener" daemon prio=10 tid=0x00007f545c004000 nid=0x5f00 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "Timer-0" daemon prio=10 tid=0x00007f5464f24800 nid=0x5eff in Object.wait() [0x00007f544a19f000] java.lang.Thread.State: TIMED_WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000007b6838340> (a java.util.TaskQueue) at java.util.TimerThread.mainLoop(Timer.java:552) - locked <0x00000007b6838340> (a java.util.TaskQueue) at java.util.TimerThread.run(Timer.java:505) "1773132304@qtp-949733110-1 - Acceptor0 SelectChannelConnector@localhost:34111" daemon prio=10 tid=0x00007f5464f1d800 nid=0x5ef6 runnable [0x00007f544a2a0000] java.lang.Thread.State: RUNNABLE at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:79) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87) - locked <0x00000007b6820100> (a sun.nio.ch.Util$2) - locked <0x00000007b6820110> (a java.util.Collections$UnmodifiableSet) - locked <0x00000007b68200b8> (a sun.nio.ch.EPollSelectorImpl) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98) at org.mortbay.io.nio.SelectorManager$SelectSet.doSelect(SelectorManager.java:498) at org.mortbay.io.nio.SelectorManager.doSelect(SelectorManager.java:192) at org.mortbay.jetty.nio.SelectChannelConnector.accept(SelectChannelConnector.java:124) at org.mortbay.jetty.AbstractConnector$Acceptor.run(AbstractConnector.java:708) at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:582) "1808184273@qtp-949733110-0" daemon prio=10 tid=0x00007f546552c800 nid=0x5ef5 in Object.wait() [0x00007f544a3a1000] java.lang.Thread.State: TIMED_WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000007b68382d0> (a org.mortbay.thread.QueuedThreadPool$PoolThread) at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:626) - locked <0x00000007b68382d0> (a org.mortbay.thread.QueuedThreadPool$PoolThread) "pool-2-thread-1" prio=10 tid=0x00007f5464f17800 nid=0x5ee5 waiting on condition [0x00007f5458186000] java.lang.Thread.State: TIMED_WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000007b6830078> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1090) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:745) "Timer for 'NameNode' metrics system" daemon prio=10 tid=0x00007f5464ecc800 nid=0x5edf in Object.wait() [0x00007f54588b0000] java.lang.Thread.State: TIMED_WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000007b6838070> (a java.util.TaskQueue) at java.util.TimerThread.mainLoop(Timer.java:552) - locked <0x00000007b6838070> (a java.util.TaskQueue) at java.util.TimerThread.run(Timer.java:505) "process reaper" daemon prio=10 tid=0x00007f5464d63800 nid=0x5ec6 waiting on condition [0x00007f545914e000] java.lang.Thread.State: TIMED_WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000007b6840068> (a java.util.concurrent.SynchronousQueue$TransferStack) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:359) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:942) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:745) "Service Thread" daemon prio=10 tid=0x00007f5464291800 nid=0x5ec3 runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE "C2 CompilerThread1" daemon prio=10 tid=0x00007f546428e800 nid=0x5ec2 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "C2 CompilerThread0" daemon prio=10 tid=0x00007f546428b800 nid=0x5ec1 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "Signal Dispatcher" daemon prio=10 tid=0x00007f5464281800 nid=0x5ec0 runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE "Finalizer" daemon prio=10 tid=0x00007f546426b000 nid=0x5ebf in Object.wait() [0x00007f5459cdb000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000007b6809ff0> (a java.lang.ref.ReferenceQueue$Lock) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135) - locked <0x00000007b6809ff0> (a java.lang.ref.ReferenceQueue$Lock) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:151) at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:189) "Reference Handler" daemon prio=10 tid=0x00007f5464267000 nid=0x5ebe in Object.wait() [0x00007f5459ddc000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000007b68099b0> (a java.lang.ref.Reference$Lock) at java.lang.Object.wait(Object.java:503) at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:133) - locked <0x00000007b68099b0> (a java.lang.ref.Reference$Lock) "main" prio=10 tid=0x00007f546400a000 nid=0x5eaf runnable [0x00007f546d285000] java.lang.Thread.State: RUNNABLE at org.apache.hadoop.ha.proto.HAServiceProtocolProtos$TransitionToStandbyResponseProto.(HAServiceProtocolProtos.java:2912) at org.apache.hadoop.ha.protocolPB.HAServiceProtocolServerSideTranslatorPB.(HAServiceProtocolServerSideTranslatorPB.java:62) at org.apache.hadoop.hdfs.server.namenode.NameNodeRpcServer.(NameNodeRpcServer.java:272) at org.apache.hadoop.hdfs.server.namenode.NameNode.createRpcServer(NameNode.java:673) at org.apache.hadoop.hdfs.server.namenode.NameNode.initialize(NameNode.java:646) at org.apache.hadoop.hdfs.server.namenode.NameNode.(NameNode.java:811) at org.apache.hadoop.hdfs.server.namenode.NameNode.(NameNode.java:795) at org.apache.hadoop.hdfs.server.namenode.NameNode.createNameNode(NameNode.java:1492) at org.apache.hadoop.hdfs.MiniDFSCluster.createNameNode(MiniDFSCluster.java:1111) at org.apache.hadoop.hdfs.MiniDFSCluster.createNameNodesAndSetConf(MiniDFSCluster.java:982) at org.apache.hadoop.hdfs.MiniDFSCluster.initMiniDFSCluster(MiniDFSCluster.java:811) at org.apache.hadoop.hdfs.MiniDFSCluster.(MiniDFSCluster.java:471) at org.apache.hadoop.hdfs.MiniDFSCluster$Builder.build(MiniDFSCluster.java:430) at org.apache.hadoop.hdfs.server.mover.TestStorageMover$MigrationTest.setupCluster(TestStorageMover.java:207) at org.apache.hadoop.hdfs.server.mover.TestStorageMover$MigrationTest.runBasicTest(TestStorageMover.java:215) at org.apache.hadoop.hdfs.server.mover.TestStorageMover$MigrationTest.access$600(TestStorageMover.java:186) at org.apache.hadoop.hdfs.server.mover.TestStorageMover.testMigrateFileToArchival(TestStorageMover.java:482) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) at org.junit.runners.ParentRunner.run(ParentRunner.java:309) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) "VM Thread" prio=10 tid=0x00007f5464264800 nid=0x5ebd runnable "GC task thread#0 (ParallelGC)" prio=10 tid=0x00007f5464020000 nid=0x5eb0 runnable "GC task thread#1 (ParallelGC)" prio=10 tid=0x00007f5464022000 nid=0x5eb1 runnable "GC task thread#2 (ParallelGC)" prio=10 tid=0x00007f5464023800 nid=0x5eb2 runnable "GC task thread#3 (ParallelGC)" prio=10 tid=0x00007f5464025800 nid=0x5eb3 runnable "GC task thread#4 (ParallelGC)" prio=10 tid=0x00007f5464027800 nid=0x5eb4 runnable "GC task thread#5 (ParallelGC)" prio=10 tid=0x00007f5464029800 nid=0x5eb5 runnable "GC task thread#6 (ParallelGC)" prio=10 tid=0x00007f546402b000 nid=0x5eb6 runnable "GC task thread#7 (ParallelGC)" prio=10 tid=0x00007f546402d000 nid=0x5eb7 runnable "GC task thread#8 (ParallelGC)" prio=10 tid=0x00007f546402f000 nid=0x5eb8 runnable "GC task thread#9 (ParallelGC)" prio=10 tid=0x00007f5464030800 nid=0x5eb9 runnable "GC task thread#10 (ParallelGC)" prio=10 tid=0x00007f5464032800 nid=0x5eba runnable "GC task thread#11 (ParallelGC)" prio=10 tid=0x00007f5464034800 nid=0x5ebb runnable "GC task thread#12 (ParallelGC)" prio=10 tid=0x00007f5464036800 nid=0x5ebc runnable "VM Periodic Task Thread" prio=10 tid=0x00007f54642a4800 nid=0x5ec4 waiting on condition JNI global references: 264 + echo '************ END zombies jstack extract' ************ END zombies jstack extract + JIRA_COMMENT=' {color:red}-1 core zombie tests{color}. There are 2 zombie test(s): at org.apache.hadoop.hdfs.server.mover.TestMover.testMoverCliWithFederationHA(TestMover.java:277)' + BAD=1 + jps + grep surefirebooter + cut -d ' ' -f 1 + xargs kill -9 POST BUILD TASK : SUCCESS END OF POST BUILD TASK : 0 Archiving artifacts Sending artifact delta relative to HBase-0.98-on-Hadoop-1.1 #891 Archived 1721 artifacts Archive block size is 32768 Received 7 blocks and 272502780 bytes Compression is 0.1% Took 1 min 25 sec Recording test results Updating HBASE-13289