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 7F7D217CE3 for ; Thu, 27 Aug 2015 23:35:13 +0000 (UTC) Received: (qmail 46670 invoked by uid 500); 27 Aug 2015 23:35:13 -0000 Delivered-To: apmail-hbase-builds-archive@hbase.apache.org Received: (qmail 46635 invoked by uid 500); 27 Aug 2015 23:35:13 -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 46624 invoked by uid 99); 27 Aug 2015 23:35:13 -0000 Received: from crius.apache.org (HELO crius.apache.org) (140.211.11.14) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 27 Aug 2015 23:35:13 +0000 Received: from crius.apache.org (localhost [127.0.0.1]) by crius.apache.org (ASF Mail Server at crius.apache.org) with ESMTP id 823B89C083C for ; Thu, 27 Aug 2015 23:34:04 +0000 (UTC) Date: Thu, 27 Aug 2015 23:34:03 +0000 (UTC) From: Apache Jenkins Server To: builds@hbase.apache.org Message-ID: <925206115.1001.1440718443803.JavaMail.jenkins@crius> In-Reply-To: <1737849253.674.1440658064994.JavaMail.jenkins@crius> References: <1737849253.674.1440658064994.JavaMail.jenkins@crius> Subject: =?UTF-8?Q?Build_failed_in_Jenkins:_HBase-1.2_=C2=BB_latest1.8,Hadoop_#141?= MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable X-Jenkins-Job: latest1.8,Hadoop X-Jenkins-Result: FAILURE See Changes: [eclark] HBASE-14313 After a Connection sees ConnectionClosingException it = never recovers -- ADDENDUM ------------------------------------------ [...truncated 45959 lines...] Tests run: 953, Failures: 0, Errors: 0, Skipped: 6 [INFO] --------------------------------------------------------------------= ---- [INFO] Reactor Summary: [INFO]=20 [INFO] Apache HBase ...................................... SUCCESS [1:22.65= 4s] [INFO] Apache HBase - Checkstyle ......................... SUCCESS [7.041s] [INFO] Apache HBase - Resource Bundle .................... SUCCESS [0.152s] [INFO] Apache HBase - Annotations ........................ SUCCESS [0.895s] [INFO] Apache HBase - Protocol ........................... SUCCESS [17.106s= ] [INFO] Apache HBase - Common ............................. SUCCESS [2:31.76= 3s] [INFO] Apache HBase - Procedure .......................... SUCCESS [1:52.92= 9s] [INFO] Apache HBase - Client ............................. SUCCESS [1:29.81= 0s] [INFO] Apache HBase - Hadoop Compatibility ............... SUCCESS [8.248s] [INFO] Apache HBase - Hadoop Two Compatibility ........... SUCCESS [10.245s= ] [INFO] Apache HBase - Prefix Tree ........................ SUCCESS [7.931s] [INFO] Apache HBase - Server ............................. FAILURE [28:29.8= 50s] [INFO] Apache HBase - Testing Util ....................... SKIPPED [INFO] Apache HBase - Thrift ............................. SKIPPED [INFO] Apache HBase - Rest ............................... SKIPPED [INFO] Apache HBase - Shell .............................. SKIPPED [INFO] Apache HBase - Integration Tests .................. SKIPPED [INFO] Apache HBase - Examples ........................... SKIPPED [INFO] Apache HBase - Assembly ........................... SKIPPED [INFO] Apache HBase - Shaded ............................. SKIPPED [INFO] Apache HBase - Shaded - Client .................... SKIPPED [INFO] Apache HBase - Shaded - Server .................... SKIPPED [INFO] --------------------------------------------------------------------= ---- [INFO] BUILD FAILURE [INFO] --------------------------------------------------------------------= ---- [INFO] Total time: 37:01.190s [INFO] Finished at: Thu Aug 27 23:34:37 UTC 2015 [INFO] Final Memory: 96M/768M [INFO] --------------------------------------------------------------------= ---- [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plug= in:2.18:test (default-test) on project hbase-server: There was a timeout or= other error in the fork -> [Help 1] [ERROR]=20 [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]=20 [ERROR] For more information about the errors and possible solutions, pleas= e read the following articles: [ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailu= reException [ERROR]=20 [ERROR] After correcting the problems, you can resume the build with the co= mmand [ERROR] mvn -rf :hbase-server 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=3D`jps | grep surefirebooter | wc -l= ` if [[ $ZOMBIE_TESTS_COUNT !=3D 0 ]] ; then #It seems sometimes the tests are not dying immediately. Let's give the= m 10s echo "Suspicious java process found - waiting 10s to see if there are j= ust slow to stop" sleep 10 =20 ZOMBIE_TESTS_COUNT=3D`jps | grep surefirebooter | wc -l` if [[ $ZOMBIE_TESTS_COUNT !=3D 0 ]] ; then echo "There are $ZOMBIE_TESTS_COUNT zombie tests, they should have be= en killed by surefire but survived" echo "************ BEGIN zombies jstack extract" ZB_STACK=3D`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=3D"$JIRA_COMMENT {color:red}-1 core zombie tests{color}. There are ${ZOMBIE_TESTS_COUN= T} zombie test(s): ${ZB_STACK}" BAD=3D1 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 tim= e to stop" fi else echo "We're ok: there is no zombie test" fi [Hadoop] $ /bin/bash -xe /tmp/hudson475819521727093842.sh ++ grep surefirebooter ++ jps ++ wc -l + ZOMBIE_TESTS_COUNT=3D1 + [[ 1 !=3D 0 ]] + echo 'Suspicious java process found - waiting 10s to see if there are jus= t slow to stop' Suspicious java process found - waiting 10s to see if there are just slow t= o stop + sleep 10 ++ grep surefirebooter ++ jps ++ wc -l + ZOMBIE_TESTS_COUNT=3D1 + [[ 1 !=3D 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 surv= ived + echo '************ BEGIN zombies jstack extract' ************ BEGIN zombies jstack extract ++ jps ++ grep surefirebooter ++ xargs -n 1 jstack ++ grep .test ++ cut -d ' ' -f 1 ++ grep '\.java' + ZB_STACK=3D'=09at org.apache.hadoop.crypto.CryptoStreamsTestBase.testComb= inedOp(CryptoStreamsTestBase.java:563)' + jps + grep surefirebooter + cut -d ' ' -f 1 + xargs -n 1 jstack 2015-08-27 23:34:49 Full thread dump Java HotSpot(TM) 64-Bit Server VM (24.55-b03 mixed mode): "Attach Listener" daemon prio=3D10 tid=3D0x0000000001d9b000 nid=3D0x5050 wa= iting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "Thread-8" prio=3D10 tid=3D0x00007f50b094d800 nid=3D0x5007 runnable [0x0000= 7f50a419c000] java.lang.Thread.State: RUNNABLE =09at java.lang.reflect.Array.get(Native Method) =09at org.junit.internal.ComparisonCriteria.arrayEquals(ComparisonCriteria.= java:37) =09at org.junit.Assert.internalArrayEquals(Assert.java:473) =09at org.junit.Assert.assertArrayEquals(Assert.java:294) =09at org.junit.Assert.assertArrayEquals(Assert.java:305) =09at org.apache.hadoop.crypto.CryptoStreamsTestBase.positionedReadCheck(Cr= yptoStreamsTestBase.java:289) =09at org.apache.hadoop.crypto.CryptoStreamsTestBase.testCombinedOp(CryptoS= treamsTestBase.java:586) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:57) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:606) =09at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(Framework= Method.java:47) =09at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCal= lable.java:12) =09at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMe= thod.java:44) =09at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMet= hod.java:17) =09at org.junit.internal.runners.statements.FailOnTimeout$StatementThread.r= un(FailOnTimeout.java:74) "process reaper" daemon prio=3D10 tid=3D0x00007f50b0938000 nid=3D0x4fd9 wai= ting on condition [0x00007f50a41df000] java.lang.Thread.State: TIMED_WAITING (parking) =09at sun.misc.Unsafe.park(Native Method) =09- parking to wait for <0x000000070002c510> (a java.util.concurrent.Sync= hronousQueue$TransferStack) =09at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226= ) =09at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(Sync= hronousQueue.java:460) =09at java.util.concurrent.SynchronousQueue$TransferStack.transfer(Synchron= ousQueue.java:359) =09at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:942) =09at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.ja= va:1068) =09at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.= java:1130) =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor= .java:615) =09at java.lang.Thread.run(Thread.java:745) "Service Thread" daemon prio=3D10 tid=3D0x00007f50b0291000 nid=3D0x4fd6 run= nable [0x0000000000000000] java.lang.Thread.State: RUNNABLE "C2 CompilerThread1" daemon prio=3D10 tid=3D0x00007f50b028e800 nid=3D0x4fd5= waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "C2 CompilerThread0" daemon prio=3D10 tid=3D0x00007f50b028c000 nid=3D0x4fd4= waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "Signal Dispatcher" daemon prio=3D10 tid=3D0x00007f50b0281800 nid=3D0x4fd3 = runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE "Finalizer" daemon prio=3D10 tid=3D0x00007f50b026b000 nid=3D0x4fd2 in Objec= t.wait() [0x00007f50a5214000] java.lang.Thread.State: WAITING (on object monitor) =09at java.lang.Object.wait(Native Method) =09- waiting on <0x000000070002c800> (a java.lang.ref.ReferenceQueue$Lock) =09at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135) =09- locked <0x000000070002c800> (a java.lang.ref.ReferenceQueue$Lock) =09at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:151) =09at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:189) "Reference Handler" daemon prio=3D10 tid=3D0x00007f50b0267000 nid=3D0x4fd1 = in Object.wait() [0x00007f50a5315000] java.lang.Thread.State: WAITING (on object monitor) =09at java.lang.Object.wait(Native Method) =09- waiting on <0x000000070002c898> (a java.lang.ref.Reference$Lock) =09at java.lang.Object.wait(Object.java:503) =09at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:133) =09- locked <0x000000070002c898> (a java.lang.ref.Reference$Lock) "main" prio=3D10 tid=3D0x00007f50b000a000 nid=3D0x4fc2 in Object.wait() [0x= 00007f50b86e8000] java.lang.Thread.State: TIMED_WAITING (on object monitor) =09at java.lang.Object.wait(Native Method) =09- waiting on <0x00000007b5f08000> (a org.junit.internal.runners.statemen= ts.FailOnTimeout$StatementThread) =09at java.lang.Thread.join(Thread.java:1289) =09- locked <0x00000007b5f08000> (a org.junit.internal.runners.statements.F= ailOnTimeout$StatementThread) =09at org.junit.internal.runners.statements.FailOnTimeout.evaluateStatement= (FailOnTimeout.java:26) =09at org.junit.internal.runners.statements.FailOnTimeout.evaluate(FailOnTi= meout.java:17) =09at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.= java:26) =09at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271) =09at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun= ner.java:70) =09at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun= ner.java:50) =09at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238) =09at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63) =09at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236) =09at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53) =09at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229) =09at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.= java:26) =09at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.ja= va:27) =09at org.junit.runners.ParentRunner.run(ParentRunner.java:309) =09at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provide= r.java:264) =09at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4= Provider.java:153) =09at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider= .java:124) =09at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameCla= ssLoader(ForkedBooter.java:200) =09at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(Fork= edBooter.java:153) =09at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:= 103) "VM Thread" prio=3D10 tid=3D0x00007f50b0264800 nid=3D0x4fd0 runnable=20 "GC task thread#0 (ParallelGC)" prio=3D10 tid=3D0x00007f50b0020000 nid=3D0x= 4fc3 runnable=20 "GC task thread#1 (ParallelGC)" prio=3D10 tid=3D0x00007f50b0022000 nid=3D0x= 4fc4 runnable=20 "GC task thread#2 (ParallelGC)" prio=3D10 tid=3D0x00007f50b0023800 nid=3D0x= 4fc5 runnable=20 "GC task thread#3 (ParallelGC)" prio=3D10 tid=3D0x00007f50b0025800 nid=3D0x= 4fc6 runnable=20 "GC task thread#4 (ParallelGC)" prio=3D10 tid=3D0x00007f50b0027800 nid=3D0x= 4fc7 runnable=20 "GC task thread#5 (ParallelGC)" prio=3D10 tid=3D0x00007f50b0029800 nid=3D0x= 4fc8 runnable=20 "GC task thread#6 (ParallelGC)" prio=3D10 tid=3D0x00007f50b002b000 nid=3D0x= 4fc9 runnable=20 "GC task thread#7 (ParallelGC)" prio=3D10 tid=3D0x00007f50b002d000 nid=3D0x= 4fca runnable=20 "GC task thread#8 (ParallelGC)" prio=3D10 tid=3D0x00007f50b002f000 nid=3D0x= 4fcb runnable=20 "GC task thread#9 (ParallelGC)" prio=3D10 tid=3D0x00007f50b0030800 nid=3D0x= 4fcc runnable=20 "GC task thread#10 (ParallelGC)" prio=3D10 tid=3D0x00007f50b0032800 nid=3D0= x4fcd runnable=20 "GC task thread#11 (ParallelGC)" prio=3D10 tid=3D0x00007f50b0034800 nid=3D0= x4fce runnable=20 "GC task thread#12 (ParallelGC)" prio=3D10 tid=3D0x00007f50b0036800 nid=3D0= x4fcf runnable=20 "VM Periodic Task Thread" prio=3D10 tid=3D0x00007f50b029c000 nid=3D0x4fd7 w= aiting on condition=20 JNI global references: 122 + echo '************ END zombies jstack extract' ************ END zombies jstack extract + JIRA_COMMENT=3D' {color:red}-1 core zombie tests{color}. There are 1 zombie test(s): = =09at org.apache.hadoop.crypto.CryptoStreamsTestBase.testCombinedOp(CryptoS= treamsTestBase.java:563)' + BAD=3D1 + 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-1.2 =C2=BB latest1.8,Hadoop #101 Archived 990 artifacts Archive block size is 32768 Received 3 blocks and 38393214 bytes Compression is 0.3% Took 20 sec