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/11/05 02:18:04 UTC
Build failed in Jenkins: HBase-1.0 #424
See <https://builds.apache.org/job/HBase-1.0/424/changes>
Changes:
[stack] Cache more efficiently getAll() and get() in FSTableDescriptors
------------------------------------------
[...truncated 1958 lines...]
TestAssignmentManager.testBalanceOnMasterFailoverScenarioWithOpenedNode:267 ยป
Tests run: 2074, Failures: 0, Errors: 1, Skipped: 19
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] HBase ............................................. SUCCESS [3.050s]
[INFO] HBase - Checkstyle ................................ SUCCESS [2.124s]
[INFO] HBase - Annotations ............................... SUCCESS [0.858s]
[INFO] HBase - Common .................................... SUCCESS [57.417s]
[INFO] HBase - Protocol .................................. SUCCESS [8.841s]
[INFO] HBase - Client .................................... SUCCESS [1:16.357s]
[INFO] HBase - Hadoop Compatibility ...................... SUCCESS [6.899s]
[INFO] HBase - Hadoop Two Compatibility .................. SUCCESS [5.597s]
[INFO] HBase - Prefix Tree ............................... SUCCESS [8.181s]
[INFO] HBase - Server .................................... FAILURE [58:28.100s]
[INFO] HBase - Testing Util .............................. SKIPPED
[INFO] HBase - Thrift .................................... SKIPPED
[INFO] HBase - Rest ...................................... SKIPPED
[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: 1:01:18.250s
[INFO] Finished at: Wed Nov 05 01:14:57 UTC 2014
[INFO] Final Memory: 46M/712M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.18-SNAPSHOT: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/hudson4761542289809171127.sh
++ grep surefirebooter
++ jps
++ 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
++ wc -l
++ grep surefirebooter
+ 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
++ grep .test
++ cut -d ' ' -f 1
++ grep '\.java'
++ xargs -n 1 jstack
+ ZB_STACK=' at org.apache.hadoop.hbase.master.balancer.TestFavoredNodeAssignmentHelper.testSecondaryAndTertiaryPlacementWithMultipleRacks(TestFavoredNodeAssignmentHelper.java:195)'
+ jps
+ xargs -n 1 jstack
+ grep surefirebooter
+ cut -d ' ' -f 1
2014-11-05 01:15:10
Full thread dump Java HotSpot(TM) Server VM (23.25-b01 mixed mode):
"Attach Listener" daemon prio=10 tid=0x64800800 nid=0x1df7 waiting on condition [0x00000000]
java.lang.Thread.State: RUNNABLE
"process reaper" daemon prio=10 tid=0x635e1400 nid=0x1c4a waiting on condition [0x6aa34000]
java.lang.Thread.State: TIMED_WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0x7cc5e4a8> (a java.util.concurrent.SynchronousQueue$TransferStack)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:359)
at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:942)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:724)
"Service Thread" daemon prio=10 tid=0x64cf7800 nid=0x1c47 runnable [0x00000000]
java.lang.Thread.State: RUNNABLE
"C2 CompilerThread1" daemon prio=10 tid=0x64cf5c00 nid=0x1c46 waiting on condition [0x00000000]
java.lang.Thread.State: RUNNABLE
"C2 CompilerThread0" daemon prio=10 tid=0x64cf3c00 nid=0x1c45 waiting on condition [0x00000000]
java.lang.Thread.State: RUNNABLE
"Signal Dispatcher" daemon prio=10 tid=0x64cf2000 nid=0x1c44 runnable [0x00000000]
java.lang.Thread.State: RUNNABLE
"Finalizer" daemon prio=10 tid=0x64cb7000 nid=0x1c43 in Object.wait() [0x6bc7d000]
java.lang.Thread.State: WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
- waiting on <0x7cb3cf70> (a java.lang.ref.ReferenceQueue$Lock)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135)
- locked <0x7cb3cf70> (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=0x64cb5400 nid=0x1c42 in Object.wait() [0x6be7d000]
java.lang.Thread.State: WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
- waiting on <0x7cb7a740> (a java.lang.ref.Reference$Lock)
at java.lang.Object.wait(Object.java:503)
at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:133)
- locked <0x7cb7a740> (a java.lang.ref.Reference$Lock)
"main" prio=10 tid=0xf6706800 nid=0x1c33 runnable [0xf689f000]
java.lang.Thread.State: RUNNABLE
at java.util.Formatter$FormatSpecifier.<init>(Formatter.java:2675)
at java.util.Formatter.parse(Formatter.java:2528)
at java.util.Formatter.format(Formatter.java:2469)
at java.util.Formatter.format(Formatter.java:2423)
at java.lang.String.format(String.java:2797)
at org.apache.hadoop.hbase.util.Bytes.toStringBinary(Bytes.java:649)
at org.apache.hadoop.hbase.util.Bytes.toStringBinary(Bytes.java:603)
at org.apache.hadoop.hbase.HRegionInfo.getRegionNameAsString(HRegionInfo.java:614)
at org.apache.hadoop.hbase.master.balancer.FavoredNodeAssignmentHelper.placeSecondaryAndTertiaryRS(FavoredNodeAssignmentHelper.java:274)
at org.apache.hadoop.hbase.master.balancer.TestFavoredNodeAssignmentHelper.testSecondaryAndTertiaryPlacementWithMultipleRacks(TestFavoredNodeAssignmentHelper.java:195)
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: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.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.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
at org.junit.runners.Suite.runChild(Suite.java:127)
at org.junit.runners.Suite.runChild(Suite.java:26)
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.junit.runner.JUnitCore.run(JUnitCore.java:160)
at org.junit.runner.JUnitCore.run(JUnitCore.java:138)
at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.createRequestAndRun(JUnitCoreWrapper.java:107)
at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.executeEager(JUnitCoreWrapper.java:77)
at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:53)
at org.apache.maven.surefire.junitcore.JUnitCoreProvider.invoke(JUnitCoreProvider.java:144)
at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:203)
at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:155)
at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103)
"VM Thread" prio=10 tid=0x64bf8800 nid=0x1c41 runnable
"GC task thread#0 (ParallelGC)" prio=10 tid=0xf6710800 nid=0x1c34 runnable
"GC task thread#1 (ParallelGC)" prio=10 tid=0xf6712000 nid=0x1c35 runnable
"GC task thread#2 (ParallelGC)" prio=10 tid=0xf6713800 nid=0x1c36 runnable
"GC task thread#3 (ParallelGC)" prio=10 tid=0xf6715000 nid=0x1c37 runnable
"GC task thread#4 (ParallelGC)" prio=10 tid=0xf6716400 nid=0x1c38 runnable
"GC task thread#5 (ParallelGC)" prio=10 tid=0xf6717c00 nid=0x1c39 runnable
"GC task thread#6 (ParallelGC)" prio=10 tid=0xf6719400 nid=0x1c3a runnable
"GC task thread#7 (ParallelGC)" prio=10 tid=0xf671a800 nid=0x1c3b runnable
"GC task thread#8 (ParallelGC)" prio=10 tid=0xf671c000 nid=0x1c3c runnable
"GC task thread#9 (ParallelGC)" prio=10 tid=0xf671d800 nid=0x1c3d runnable
"GC task thread#10 (ParallelGC)" prio=10 tid=0xf671ec00 nid=0x1c3e runnable
"GC task thread#11 (ParallelGC)" prio=10 tid=0xf6720400 nid=0x1c3f runnable
"GC task thread#12 (ParallelGC)" prio=10 tid=0xf6721c00 nid=0x1c40 runnable
"VM Periodic Task Thread" prio=10 tid=0x64cf9800 nid=0x1c48 waiting on condition
JNI global references: 193
+ 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.hadoop.hbase.master.balancer.TestFavoredNodeAssignmentHelper.testSecondaryAndTertiaryPlacementWithMultipleRacks(TestFavoredNodeAssignmentHelper.java:195)'
+ 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 #423
Archived 1782 artifacts
Archive block size is 32768
Received 27 blocks and 338769190 bytes
Compression is 0.3%
Took 1 min 50 sec
Recording test results
Jenkins build is back to normal : HBase-1.0 #425
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/HBase-1.0/425/changes>