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 5D13811C38 for ; Wed, 11 Jun 2014 02:07:59 +0000 (UTC) Received: (qmail 83305 invoked by uid 500); 11 Jun 2014 02:07:59 -0000 Delivered-To: apmail-hbase-builds-archive@hbase.apache.org Received: (qmail 83269 invoked by uid 500); 11 Jun 2014 02:07:59 -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 83259 invoked by uid 99); 11 Jun 2014 02:07:59 -0000 Received: from crius.apache.org (HELO crius) (140.211.11.14) by apache.org (qpsmtpd/0.29) with ESMTP; Wed, 11 Jun 2014 02:07:59 +0000 Received: from crius.apache.org (localhost [127.0.0.1]) by crius (Postfix) with ESMTP id A2CCEE00047 for ; Wed, 11 Jun 2014 02:07:58 +0000 (UTC) Date: Wed, 11 Jun 2014 02:07:57 +0000 (UTC) From: Apache Jenkins Server To: builds@hbase.apache.org Message-ID: <1351857122.1216.1402452478032.JavaMail.jenkins@crius> Subject: Build failed in Jenkins: HBase-TRUNK #5193 MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: 7bit X-Jenkins-Job: HBase-TRUNK X-Jenkins-Result: FAILURE See Changes: [stack] HBASE-11320 Reenable bucket cache logging ------------------------------------------ [...truncated 1909 lines...] [INFO] HBase - Examples .................................. SKIPPED [INFO] HBase - Assembly .................................. SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 2:39:36.204s [INFO] Finished at: Wed Jun 11 01:52:18 UTC 2014 [INFO] Final Memory: 61M/970M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12-TRUNK-HBASE-2:test (secondPartTestsExecution) on project hbase-shell: There are test failures. [ERROR] [ERROR] Please refer to /home/hudson/jenkins-slave/workspace/HBase-TRUNK/hbase-shell/target/surefire-reports for the individual test results. [ERROR] -> [Help 1] [ERROR] [ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch. [ERROR] Re-run Maven using the -X switch to enable full debug logging. [ERROR] [ERROR] For more information about the errors and possible solutions, please read the following articles: [ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException [ERROR] [ERROR] After correcting the problems, you can resume the build with the command [ERROR] mvn -rf :hbase-shell Build step 'Invoke top-level Maven targets' marked build as failure Performing Post build task... Match found for :.* : True Logical operation result is TRUE Running script : ZOMBIE_TESTS_COUNT=`jps | grep surefirebooter | wc -l` 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-TRUNK] $ /bin/bash -xe /tmp/hudson5429700811153293998.sh ++ jps ++ wc -l ++ grep surefirebooter + ZOMBIE_TESTS_COUNT=1 + [[ 1 != 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=1 + [[ 1 != 0 ]] + echo 'There are 1 zombie tests, they should have been killed by surefire but survived' There are 1 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 ++ xargs -n 1 jstack ++ grep .test ++ grep '\.java' + ZB_STACK=' at org.apache.james.smtpserver.SMTPServerTest.testReverseEqualsHelo(SMTPServerTest.java:721)' + jps + grep surefirebooter + cut -d ' ' -f 1 + xargs -n 1 jstack 2014-06-11 01:58:06 Full thread dump OpenJDK 64-Bit Server VM (23.25-b01 mixed mode): "Attach Listener" daemon prio=10 tid=0x00007fb878001000 nid=0x67a6 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "New I/O server worker #16-1" prio=10 tid=0x00007fb844002800 nid=0x6c30 runnable [0x00007fb8b7bfa000] java.lang.Thread.State: RUNNABLE at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:228) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:83) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87) - locked <0x00000000f9570590> (a sun.nio.ch.Util$1) - locked <0x00000000f96faf20> (a java.util.Collections$UnmodifiableSet) - locked <0x00000000f95705e8> (a sun.nio.ch.EPollSelectorImpl) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98) at org.jboss.netty.channel.socket.nio.SelectorUtil.select(SelectorUtil.java:33) at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:157) at org.jboss.netty.util.ThreadRenamingRunnable.run(ThreadRenamingRunnable.java:102) at org.jboss.netty.util.internal.DeadLockProofWorker$1.run(DeadLockProofWorker.java:42) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:701) "Hashed wheel timer #16" prio=10 tid=0x00007fb844001000 nid=0x6c2e sleeping[0x00007fb8b7dfc000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at org.jboss.netty.util.HashedWheelTimer$Worker.waitForNextTick(HashedWheelTimer.java:455) at org.jboss.netty.util.HashedWheelTimer$Worker.run(HashedWheelTimer.java:372) at org.jboss.netty.util.ThreadRenamingRunnable.run(ThreadRenamingRunnable.java:102) at java.lang.Thread.run(Thread.java:701) "New I/O server boss #16 ([id: 0x45093bde, /127.0.0.1:8016])" prio=10 tid=0x00007fb8c86e6000 nid=0x6c2d runnable [0x00007fb8bc67a000] java.lang.Thread.State: RUNNABLE at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:228) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:83) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87) - locked <0x00000000f95900f0> (a sun.nio.ch.Util$1) - locked <0x00000000f9590100> (a java.util.Collections$UnmodifiableSet) - locked <0x00000000f95900a8> (a sun.nio.ch.EPollSelectorImpl) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98) at org.jboss.netty.channel.socket.nio.NioServerSocketPipelineSink$Boss.run(NioServerSocketPipelineSink.java:232) at org.jboss.netty.util.ThreadRenamingRunnable.run(ThreadRenamingRunnable.java:102) at org.jboss.netty.util.internal.DeadLockProofWorker$1.run(DeadLockProofWorker.java:42) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:701) "Low Memory Detector" daemon prio=10 tid=0x00007fb8c8101800 nid=0x6b33 runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE "C2 CompilerThread1" daemon prio=10 tid=0x00007fb8c80ff000 nid=0x6b32 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "C2 CompilerThread0" daemon prio=10 tid=0x00007fb8c80fc000 nid=0x6b31 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "Signal Dispatcher" daemon prio=10 tid=0x00007fb8c80fa000 nid=0x6b30 runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE "Finalizer" daemon prio=10 tid=0x00007fb8c80ad000 nid=0x6b2f in Object.wait() [0x00007fb8bd6b3000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000000f9578690> (a java.lang.ref.ReferenceQueue$Lock) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:133) - locked <0x00000000f9578690> (a java.lang.ref.ReferenceQueue$Lock) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:149) at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:189) "Reference Handler" daemon prio=10 tid=0x00007fb8c80ab000 nid=0x6b2e in Object.wait() [0x00007fb8bd7b4000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000000f9570530> (a java.lang.ref.Reference$Lock) at java.lang.Object.wait(Object.java:502) at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:133) - locked <0x00000000f9570530> (a java.lang.ref.Reference$Lock) "main" prio=10 tid=0x00007fb8c8009800 nid=0x6b24 runnable [0x00007fb8cffac000] java.lang.Thread.State: RUNNABLE at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.read(SocketInputStream.java:146) at sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:282) at sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:324) at sun.nio.cs.StreamDecoder.read(StreamDecoder.java:176) - locked <0x00000000f957e968> (a java.io.InputStreamReader) at java.io.InputStreamReader.read(InputStreamReader.java:184) at java.io.BufferedReader.fill(BufferedReader.java:153) at java.io.BufferedReader.read(BufferedReader.java:174) - locked <0x00000000f957e968> (a java.io.InputStreamReader) at org.apache.commons.net.io.CRLFLineReader.readLine(CRLFLineReader.java:58) - locked <0x00000000f957e968> (a java.io.InputStreamReader) at org.apache.commons.net.smtp.SMTP.__getReply(SMTP.java:196) at org.apache.commons.net.smtp.SMTP.__sendCommand(SMTP.java:171) at org.apache.commons.net.smtp.SMTP.sendCommand(SMTP.java:315) at org.apache.james.smtpserver.SMTPServerTest.testReverseEqualsHelo(SMTPServerTest.java:721) 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:622) 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.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) 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 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:622) at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray2(ReflectionUtils.java:208) at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:158) at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:86) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:95) "VM Thread" prio=10 tid=0x00007fb8c809c000 nid=0x6b2d runnable "GC task thread#0 (ParallelGC)" prio=10 tid=0x00007fb8c8017000 nid=0x6b25 runnable "GC task thread#1 (ParallelGC)" prio=10 tid=0x00007fb8c8019000 nid=0x6b26 runnable "GC task thread#2 (ParallelGC)" prio=10 tid=0x00007fb8c801b000 nid=0x6b27 runnable "GC task thread#3 (ParallelGC)" prio=10 tid=0x00007fb8c801c800 nid=0x6b28 runnable "GC task thread#4 (ParallelGC)" prio=10 tid=0x00007fb8c801e800 nid=0x6b29 runnable "GC task thread#5 (ParallelGC)" prio=10 tid=0x00007fb8c8020800 nid=0x6b2a runnable "GC task thread#6 (ParallelGC)" prio=10 tid=0x00007fb8c8022000 nid=0x6b2b runnable "GC task thread#7 (ParallelGC)" prio=10 tid=0x00007fb8c8024000 nid=0x6b2c runnable "VM Periodic Task Thread" prio=10 tid=0x00007fb8c810c000 nid=0x6b34 waiting on condition JNI global references: 279 + echo '************ END zombies jstack extract' ************ END zombies jstack extract + JIRA_COMMENT=' {color:red}-1 core zombie tests{color}. There are 1 zombie test(s): at org.apache.james.smtpserver.SMTPServerTest.testReverseEqualsHelo(SMTPServerTest.java:721)' + 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-TRUNK #5192 Archived 1657 artifacts Archive block size is 32768 Received 72 blocks and 513910438 bytes Compression is 0.5% Took 5 min 5 sec Recording test results Updating HBASE-11320