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 9B52918E0D for ; Thu, 27 Aug 2015 08:21:57 +0000 (UTC) Received: (qmail 33227 invoked by uid 500); 27 Aug 2015 08:21:57 -0000 Delivered-To: apmail-hbase-builds-archive@hbase.apache.org Received: (qmail 33193 invoked by uid 500); 27 Aug 2015 08:21: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 33181 invoked by uid 99); 27 Aug 2015 08:21:57 -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 08:21:57 +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 9587F9C0974 for ; Thu, 27 Aug 2015 08:20:49 +0000 (UTC) Date: Thu, 27 Aug 2015 08:20:48 +0000 (UTC) From: Apache Jenkins Server To: builds@hbase.apache.org Message-ID: <1217160641.698.1440663648875.JavaMail.jenkins@crius> In-Reply-To: <389295353.316.1440580262361.JavaMail.jenkins@crius> References: <389295353.316.1440580262361.JavaMail.jenkins@crius> Subject: Build failed in Jenkins: HBase-0.98 #1102 MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable X-Jenkins-Job: HBase-0.98 X-Jenkins-Result: FAILURE See Changes: [tedyu] HBASE-14269 FuzzyRowFilter omits certain rows when multiple fuzzy k= eys exist (hongbin ma) ------------------------------------------ [...truncated 2365 lines...] TestCacheOnWrite.testStoreFileCacheOnWrite:479->testStoreFileCacheOnWrite= Internals:262->writeStoreFile:384 =C2=BB OutOfMemory TestCacheOnWrite.testStoreFileCacheOnWrite:479->testStoreFileCacheOnWrite= Internals:262->writeStoreFile:384 =C2=BB OutOfMemory TestAssignmentManagerOnCluster.testSSHWhenDisablingTableRegionsInOpeningO= rPendingOpenState:676 =C2=BB=20 Tests run: 2307, Failures: 0, Errors: 16, Skipped: 23 [INFO] --------------------------------------------------------------------= ---- [INFO] Reactor Summary: [INFO]=20 [INFO] Apache HBase ...................................... SUCCESS [3.012s] [INFO] Apache HBase - Checkstyle ......................... SUCCESS [0.561s] [INFO] Apache HBase - Resource Bundle .................... SUCCESS [0.216s] [INFO] Apache HBase - Annotations ........................ SUCCESS [0.861s] [INFO] Apache HBase - Common ............................. SUCCESS [46.811s= ] [INFO] Apache HBase - Protocol ........................... SUCCESS [10.060s= ] [INFO] Apache HBase - Client ............................. SUCCESS [53.098s= ] [INFO] Apache HBase - Hadoop Compatibility ............... SUCCESS [7.344s] [INFO] Apache HBase - Hadoop Two Compatibility ........... SUCCESS [6.237s] [INFO] Apache HBase - Prefix Tree ........................ SUCCESS [8.548s] [INFO] Apache HBase - Server ............................. FAILURE [3:48:37= .396s] [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] --------------------------------------------------------------------= ---- [INFO] BUILD FAILURE [INFO] --------------------------------------------------------------------= ---- [INFO] Total time: 3:50:55.728s [INFO] Finished at: Thu Aug 27 08:19:24 UTC 2015 [INFO] Final Memory: 51M/483M [INFO] --------------------------------------------------------------------= ---- [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plug= in:2.18:test (secondPartTestsExecution) 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 [HBase-0.98] $ /bin/bash -xe /tmp/hudson7211982603507541469.sh ++ jps ++ wc -l ++ grep surefirebooter + ZOMBIE_TESTS_COUNT=3D2 + [[ 2 !=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 ++ jps ++ grep surefirebooter ++ wc -l + ZOMBIE_TESTS_COUNT=3D2 + [[ 2 !=3D 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 surv= ived + echo '************ BEGIN zombies jstack extract' ************ BEGIN zombies jstack extract ++ jps ++ grep surefirebooter ++ xargs -n 1 jstack ++ grep '\.java' ++ grep .test ++ cut -d ' ' -f 1 4850: 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=3D'=09at org.apache.hadoop.hdfs.TestAppendSnapshotTruncate.testA= ST(TestAppendSnapshotTruncate.java:130)' + grep surefirebooter + xargs -n 1 jstack + jps + cut -d ' ' -f 1 2015-08-27 08:19:43 Full thread dump Java HotSpot(TM) 64-Bit Server VM (24.55-b03 mixed mode): "Attach Listener" daemon prio=3D10 tid=3D0x00007facc0a78800 nid=3D0x7071 wa= iting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "process reaper" daemon prio=3D10 tid=3D0x00007facc0a0e000 nid=3D0x7058 wai= ting on condition [0x00007facb49bc000] java.lang.Thread.State: TIMED_WAITING (parking) =09at sun.misc.Unsafe.park(Native Method) =09- parking to wait for <0x00000007ad9fc130> (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=3D0x00007facc0291000 nid=3D0x703b run= nable [0x0000000000000000] java.lang.Thread.State: RUNNABLE "C2 CompilerThread1" daemon prio=3D10 tid=3D0x00007facc028e800 nid=3D0x703a= waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "C2 CompilerThread0" daemon prio=3D10 tid=3D0x00007facc028b800 nid=3D0x7039= runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE "Signal Dispatcher" daemon prio=3D10 tid=3D0x00007facc0281800 nid=3D0x7038 = runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE "Finalizer" daemon prio=3D10 tid=3D0x00007facc026b000 nid=3D0x7037 in Objec= t.wait() [0x00007facb5ddc000] java.lang.Thread.State: WAITING (on object monitor) =09at java.lang.Object.wait(Native Method) =09- waiting on <0x00000007aaa85568> (a java.lang.ref.ReferenceQueue$Lock) =09at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135) =09- locked <0x00000007aaa85568> (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=3D0x00007facc0267000 nid=3D0x7036 = in Object.wait() [0x00007facb5edd000] java.lang.Thread.State: WAITING (on object monitor) =09at java.lang.Object.wait(Native Method) =09- waiting on <0x00000007aaa850f0> (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 <0x00000007aaa850f0> (a java.lang.ref.Reference$Lock) "main" prio=3D10 tid=3D0x00007facc000a000 nid=3D0x7027 runnable [0x00007fac= c9323000] java.lang.Thread.State: RUNNABLE =09at java.lang.Thread.currentThread(Native Method) =09at org.apache.commons.logging.LogFactory.directGetContextClassLoader(Log= Factory.java:851) =09at org.apache.commons.logging.LogFactory$1.run(LogFactory.java:817) =09at java.security.AccessController.doPrivileged(Native Method) =09at org.apache.commons.logging.LogFactory.getContextClassLoaderInternal(L= ogFactory.java:814) =09at org.apache.commons.logging.LogFactory.getFactory(LogFactory.java:421) =09at org.apache.commons.logging.LogFactory.getLog(LogFactory.java:657) =09at org.apache.hadoop.hdfs.server.namenode.LeaseManager.(LeaseMan= ager.java:68) =09at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.(FSNamesyst= em.java:431) =09at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.(FSNamesyst= em.java:696) =09at org.apache.hadoop.hdfs.server.namenode.NameNode.format(NameNode.java:= 1069) =09at org.apache.hadoop.hdfs.server.namenode.NameNode.format(NameNode.java:= 366) =09at org.apache.hadoop.hdfs.DFSTestUtil.formatNameNode(DFSTestUtil.java:21= 8) =09at org.apache.hadoop.hdfs.MiniDFSCluster.configureNameService(MiniDFSClu= ster.java:966) =09at org.apache.hadoop.hdfs.MiniDFSCluster.createNameNodesAndSetConf(MiniD= FSCluster.java:887) =09at org.apache.hadoop.hdfs.MiniDFSCluster.initMiniDFSCluster(MiniDFSClust= er.java:819) =09at org.apache.hadoop.hdfs.MiniDFSCluster.(MiniDFSCluster.java:478) =09at org.apache.hadoop.hdfs.MiniDFSCluster$Builder.build(MiniDFSCluster.ja= va:437) =09at org.apache.hadoop.hdfs.TestInjectionForSimulatedStorage.testInjection= (TestInjectionForSimulatedStorage.java:130) =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.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.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=3D0x00007facc0264800 nid=3D0x7035 runnable=20 "GC task thread#0 (ParallelGC)" prio=3D10 tid=3D0x00007facc0020000 nid=3D0x= 7028 runnable=20 "GC task thread#1 (ParallelGC)" prio=3D10 tid=3D0x00007facc0022000 nid=3D0x= 7029 runnable=20 "GC task thread#2 (ParallelGC)" prio=3D10 tid=3D0x00007facc0023800 nid=3D0x= 702a runnable=20 "GC task thread#3 (ParallelGC)" prio=3D10 tid=3D0x00007facc0025800 nid=3D0x= 702b runnable=20 "GC task thread#4 (ParallelGC)" prio=3D10 tid=3D0x00007facc0027800 nid=3D0x= 702c runnable=20 "GC task thread#5 (ParallelGC)" prio=3D10 tid=3D0x00007facc0029800 nid=3D0x= 702d runnable=20 "GC task thread#6 (ParallelGC)" prio=3D10 tid=3D0x00007facc002b000 nid=3D0x= 702e runnable=20 "GC task thread#7 (ParallelGC)" prio=3D10 tid=3D0x00007facc002d000 nid=3D0x= 702f runnable=20 "GC task thread#8 (ParallelGC)" prio=3D10 tid=3D0x00007facc002f000 nid=3D0x= 7030 runnable=20 "GC task thread#9 (ParallelGC)" prio=3D10 tid=3D0x00007facc0030800 nid=3D0x= 7031 runnable=20 "GC task thread#10 (ParallelGC)" prio=3D10 tid=3D0x00007facc0032800 nid=3D0= x7032 runnable=20 "GC task thread#11 (ParallelGC)" prio=3D10 tid=3D0x00007facc0034800 nid=3D0= x7033 runnable=20 "GC task thread#12 (ParallelGC)" prio=3D10 tid=3D0x00007facc0036800 nid=3D0= x7034 runnable=20 "VM Periodic Task Thread" prio=3D10 tid=3D0x00007facc029b800 nid=3D0x703c w= aiting on condition=20 JNI global references: 210 + echo '************ END zombies jstack extract' ************ END zombies jstack extract + JIRA_COMMENT=3D' {color:red}-1 core zombie tests{color}. There are 2 zombie test(s): = =09at org.apache.hadoop.hdfs.TestAppendSnapshotTruncate.testAST(TestAppendS= napshotTruncate.java:130)' + 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-0.98 #1099 Archived 1779 artifacts Archive block size is 32768 Received 19 blocks and 306721763 bytes Compression is 0.2% Took 1 min 20 sec Recording test results Updating HBASE-14269