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 4509E11D47 for ; Sat, 5 Jul 2014 07:36:52 +0000 (UTC) Received: (qmail 24863 invoked by uid 500); 5 Jul 2014 07:36:52 -0000 Delivered-To: apmail-hbase-builds-archive@hbase.apache.org Received: (qmail 24836 invoked by uid 500); 5 Jul 2014 07:36:52 -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 24825 invoked by uid 99); 5 Jul 2014 07:36:51 -0000 Received: from crius.apache.org (HELO crius) (140.211.11.14) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 05 Jul 2014 07:36:51 +0000 Received: from crius.apache.org (localhost [127.0.0.1]) by crius (Postfix) with ESMTP id 48DC7E00358 for ; Sat, 5 Jul 2014 07:36:51 +0000 (UTC) Date: Sat, 5 Jul 2014 07:36:50 +0000 (UTC) From: Apache Jenkins Server To: builds@hbase.apache.org Message-ID: <737615939.1396.1404545810703.JavaMail.jenkins@crius> In-Reply-To: <1926686418.1265.1404502323124.JavaMail.jenkins@crius> References: <1926686418.1265.1404502323124.JavaMail.jenkins@crius> Subject: Build failed in Jenkins: HBase-1.0 #14 MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: 7bit X-Jenkins-Job: HBase-1.0 X-Jenkins-Result: FAILURE See Changes: [stack] HBASE-11452 add getUserPermission feature in AccessControlClient as client API (Demai Ni) ------------------------------------------ [...truncated 1805 lines...] [INFO] HBase - Shell ..................................... SKIPPED [INFO] HBase - Integration Tests ......................... SKIPPED [INFO] HBase - Examples .................................. SKIPPED [INFO] HBase - Assembly .................................. SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 3:47:44.624s [INFO] Finished at: Sat Jul 05 07:29:27 UTC 2014 [INFO] Final Memory: 38M/805M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12-TRUNK-HBASE-2:test (secondPartTestsExecution) on project hbase-server: There are test failures. [ERROR] [ERROR] Please refer to 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-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=`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-1.0] $ /bin/bash -xe /tmp/hudson288603301434508583.sh ++ jps ++ grep surefirebooter ++ wc -l + 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.blur.lucene.warmup.IndexWarmupTest.testIndexWarmupBitSet(IndexWarmupTest.java:90)' + jps + grep surefirebooter + cut -d ' ' -f 1 + xargs -n 1 jstack 2014-07-05 07:29:42 Full thread dump Java HotSpot(TM) Server VM (23.25-b01 mixed mode): "Attach Listener" daemon prio=10 tid=0xab900c00 nid=0x1142 waiting on condition [0x00000000] java.lang.Thread.State: RUNNABLE "Lucene Merge Thread #3" daemon prio=10 tid=0xaac2c800 nid=0x10dc in Object.wait() [0xac35c000] java.lang.Thread.State: TIMED_WAITING (on object monitor) at java.lang.Object.wait(Native Method) at org.apache.blur.lucene.warmup.SlowAccessDirectory$SlowAccessIndexInput.delay(SlowAccessDirectory.java:48) - locked <0xb1673e98> (a org.apache.blur.lucene.warmup.SlowAccessDirectory$SlowAccessIndexInput) at org.apache.blur.lucene.warmup.SlowAccessDirectory$SlowAccessIndexInput.readInternal(SlowAccessDirectory.java:72) at org.apache.lucene.store.BufferedIndexInput.refill(BufferedIndexInput.java:272) at org.apache.lucene.store.BufferedIndexInput.readByte(BufferedIndexInput.java:51) at org.apache.blur.lucene.warmup.TraceableIndexInput.readByte(TraceableIndexInput.java:62) at org.apache.lucene.store.DataInput.readVInt(DataInput.java:108) at org.apache.lucene.codecs.lucene41.Lucene41PostingsReader$BlockDocsAndPositionsEnum.refillPositions(Lucene41PostingsReader.java:773) at org.apache.lucene.codecs.lucene41.Lucene41PostingsReader$BlockDocsAndPositionsEnum.nextPosition(Lucene41PostingsReader.java:993) at org.apache.lucene.codecs.MappingMultiDocsAndPositionsEnum.nextPosition(MappingMultiDocsAndPositionsEnum.java:120) at org.apache.lucene.codecs.PostingsConsumer.merge(PostingsConsumer.java:118) at org.apache.lucene.codecs.TermsConsumer.merge(TermsConsumer.java:164) at org.apache.lucene.codecs.FieldsConsumer.merge(FieldsConsumer.java:72) at org.apache.lucene.index.SegmentMerger.mergeTerms(SegmentMerger.java:365) at org.apache.lucene.index.SegmentMerger.merge(SegmentMerger.java:98) at org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:3709) at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:3313) at org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:401) at org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:478) "Lucene Merge Thread #1" daemon prio=10 tid=0xaac2b800 nid=0xb6a in Object.wait() [0xab77b000] java.lang.Thread.State: TIMED_WAITING (on object monitor) at java.lang.Object.wait(Native Method) at org.apache.lucene.index.MergePolicy$OneMerge.checkAborted(MergePolicy.java:211) - locked <0xc56527c0> (a org.apache.lucene.index.MergePolicy$OneMerge) at org.apache.lucene.index.MergeState$CheckAbort.work(MergeState.java:197) at org.apache.lucene.codecs.TermsConsumer.merge(TermsConsumer.java:171) at org.apache.lucene.codecs.FieldsConsumer.merge(FieldsConsumer.java:72) at org.apache.lucene.index.SegmentMerger.mergeTerms(SegmentMerger.java:365) at org.apache.lucene.index.SegmentMerger.merge(SegmentMerger.java:98) at org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:3709) at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:3313) at org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:401) at org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:478) "Service Thread" daemon prio=10 tid=0xaba1b800 nid=0xaea runnable [0x00000000] java.lang.Thread.State: RUNNABLE "C2 CompilerThread1" daemon prio=10 tid=0xaba19c00 nid=0xae9 waiting on condition [0x00000000] java.lang.Thread.State: RUNNABLE "C2 CompilerThread0" daemon prio=10 tid=0xaba18000 nid=0xae8 waiting on condition [0x00000000] java.lang.Thread.State: RUNNABLE "Signal Dispatcher" daemon prio=10 tid=0xaba16400 nid=0xae7 runnable [0x00000000] java.lang.Thread.State: RUNNABLE "Surrogate Locker Thread (Concurrent GC)" daemon prio=10 tid=0xaba14c00 nid=0xae6 waiting on condition [0x00000000] java.lang.Thread.State: RUNNABLE "Finalizer" daemon prio=10 tid=0xabfda400 nid=0xae5 in Object.wait() [0xf337d000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135) - locked <0xc3bfe4e0> (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=0xabfd8400 nid=0xae4 in Object.wait() [0xabc75000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) at java.lang.Object.wait(Object.java:503) at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:133) - locked <0xc3bfe5e0> (a java.lang.ref.Reference$Lock) "main" prio=10 tid=0xf6706800 nid=0xad7 in Object.wait() [0xf68a2000] java.lang.Thread.State: TIMED_WAITING (on object monitor) at java.lang.Object.wait(Native Method) at org.apache.lucene.index.IndexWriter.doWait(IndexWriter.java:3923) - locked <0xc3cee368> (a org.apache.lucene.index.IndexWriter) at org.apache.lucene.index.IndexWriter.waitForMerges(IndexWriter.java:2159) - locked <0xc3cee368> (a org.apache.lucene.index.IndexWriter) at org.apache.lucene.index.IndexWriter.finishMerges(IndexWriter.java:2143) - locked <0xc3cee368> (a org.apache.lucene.index.IndexWriter) at org.apache.lucene.index.IndexWriter.closeInternal(IndexWriter.java:967) - locked <0xc3cee368> (a org.apache.lucene.index.IndexWriter) at org.apache.lucene.index.IndexWriter.close(IndexWriter.java:895) - locked <0xc3dd7200> (a java.lang.Object) at org.apache.lucene.index.IndexWriter.close(IndexWriter.java:857) at org.apache.blur.lucene.warmup.IndexWarmupTest.populate(IndexWarmupTest.java:144) at org.apache.blur.lucene.warmup.IndexWarmupTest.getIndexReader(IndexWarmupTest.java:131) at org.apache.blur.lucene.warmup.IndexWarmupTest.testIndexWarmupBitSet(IndexWarmupTest.java:90) 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:44) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:69) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:48) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:292) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112) 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.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189) at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165) at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75) "VM Thread" prio=10 tid=0xabfd2c00 nid=0xae3 runnable "Gang worker#0 (Parallel GC Threads)" prio=10 tid=0xf670d800 nid=0xad8 runnable "Gang worker#1 (Parallel GC Threads)" prio=10 tid=0xf670f000 nid=0xad9 runnable "Gang worker#2 (Parallel GC Threads)" prio=10 tid=0xf6710400 nid=0xada runnable "Gang worker#3 (Parallel GC Threads)" prio=10 tid=0xf6711c00 nid=0xadb runnable "Gang worker#4 (Parallel GC Threads)" prio=10 tid=0xf6713400 nid=0xadc runnable "Gang worker#5 (Parallel GC Threads)" prio=10 tid=0xf6714800 nid=0xadd runnable "Gang worker#6 (Parallel GC Threads)" prio=10 tid=0xf6716000 nid=0xade runnable "Gang worker#7 (Parallel GC Threads)" prio=10 tid=0xf6717800 nid=0xadf runnable "Concurrent Mark-Sweep GC Thread" prio=10 tid=0xae1a6400 nid=0xae2 runnable "Gang worker#0 (Parallel CMS Threads)" prio=10 tid=0xae123400 nid=0xae0 runnable "Gang worker#1 (Parallel CMS Threads)" prio=10 tid=0xae124800 nid=0xae1 runnable "VM Periodic Task Thread" prio=10 tid=0xaba25c00 nid=0xaeb waiting on condition JNI global references: 173 + 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.blur.lucene.warmup.IndexWarmupTest.testIndexWarmupBitSet(IndexWarmupTest.java:90)' + 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-1.0 #7 Archived 1728 artifacts Archive block size is 32768 Received 75 blocks and 651344550 bytes Compression is 0.4% Took 5 min 12 sec Recording test results Updating HBASE-11452