You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@hbase.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2014/07/05 09:36:50 UTC
Build failed in Jenkins: HBase-1.0 #14
See <https://builds.apache.org/job/HBase-1.0/14/changes>
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 <https://builds.apache.org/job/HBase-1.0/ws/hbase-server/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 <goals> -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
Jenkins build is back to normal : HBase-1.0 #15
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/HBase-1.0/15/changes>