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 2013/08/29 08:53:11 UTC

Build failed in Jenkins: HBase-TRUNK #4444

See <https://builds.apache.org/job/HBase-TRUNK/4444/changes>

Changes:

[stack] HBASE-9110 Meta region edits not recovered while migrating to 0.96.0; PUT BACK

[stack] HBASE-9110 Meta region edits not recovered while migrating to 0.96.0; REVERT

[stack] HBASE-9110 Meta region edits not recovered while migrating to 0.96.0

[stack] HBASE-9208 ReplicationLogCleaner slow at large scale

------------------------------------------
[...truncated 4256 lines...]
Results :

Tests in error: 
  testRunThriftServer[18](org.apache.hadoop.hbase.thrift.TestThriftServerCmdLine): test timed out after 600000 milliseconds

Tests run: 1631, Failures: 0, Errors: 1, Skipped: 21

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] HBase ............................................. SUCCESS [6.676s]
[INFO] HBase - Common .................................... SUCCESS [19.070s]
[INFO] HBase - Protocol .................................. SUCCESS [16.042s]
[INFO] HBase - Client .................................... SUCCESS [19.848s]
[INFO] HBase - Hadoop Compatibility ...................... SUCCESS [5.560s]
[INFO] HBase - Hadoop One Compatibility .................. SUCCESS [1.204s]
[INFO] HBase - Prefix Tree ............................... SUCCESS [2.977s]
[INFO] HBase - Server .................................... FAILURE [1:44:46.436s]
[INFO] HBase - Integration Tests ......................... SKIPPED
[INFO] HBase - Examples .................................. SKIPPED
[INFO] HBase - Assembly .................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 1:45:58.356s
[INFO] Finished at: Thu Aug 29 06:52:31 UTC 2013
[INFO] Final Memory: 33M/248M
[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 /x1/jenkins/jenkins-slave/workspace/HBase-TRUNK/trunk/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-TRUNK] $ /bin/bash -xe /tmp/hudson3571394644056378550.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.camel.component.file.FileConcurrentWriteAppendSameFileTest.testConcurrentAppend(FileConcurrentWriteAppendSameFileTest.java:55)'
+ jps
+ grep surefirebooter
+ cut -d ' ' -f 1
+ xargs -n 1 jstack
2013-08-29 06:52:44
Full thread dump Java HotSpot(TM) Server VM (20.45-b01 mixed mode):

"Camel (camel-1) thread #604 - file://target/concurrent" daemon prio=10 tid=0xabf67000 nid=0x457e waiting on condition [0xada7d000]
   java.lang.Thread.State: TIMED_WAITING (parking)
	at sun.misc.Unsafe.park(Native Method)
	- parking to wait for  <0xe954e920> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)
	at java.util.concurrent.DelayQueue.take(DelayQueue.java:164)
	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:609)
	at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:602)
	at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:957)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:917)
	at java.lang.Thread.run(Thread.java:662)

"Attach Listener" daemon prio=10 tid=0xac600c00 nid=0x455e waiting on condition [0x00000000]
   java.lang.Thread.State: RUNNABLE

"Camel (camel-1) thread #466 - dataset://foo" daemon prio=10 tid=0xaaba7400 nid=0x3ff0 waiting on condition [0xad07d000]
   java.lang.Thread.State: TIMED_WAITING (sleeping)
	at java.lang.Thread.sleep(Native Method)
	at org.apache.camel.component.dataset.DataSetConsumer.sendMessages(DataSetConsumer.java:94)
	at org.apache.camel.component.dataset.DataSetConsumer$1.run(DataSetConsumer.java:70)
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918)
	at java.lang.Thread.run(Thread.java:662)

"Low Memory Detector" daemon prio=10 tid=0xaccb9400 nid=0x2d61 runnable [0x00000000]
   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread1" daemon prio=10 tid=0xaccb7c00 nid=0x2d60 waiting on condition [0x00000000]
   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread0" daemon prio=10 tid=0xaccb5c00 nid=0x2d5f waiting on condition [0x00000000]
   java.lang.Thread.State: RUNNABLE

"Signal Dispatcher" daemon prio=10 tid=0xaccb4400 nid=0x2d5e runnable [0x00000000]
   java.lang.Thread.State: RUNNABLE

"Finalizer" daemon prio=10 tid=0xacca6800 nid=0x2d5d in Object.wait() [0xaf07d000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	- waiting on <0xb399cdd0> (a java.lang.ref.ReferenceQueue$Lock)
	at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:118)
	- locked <0xb399cdd0> (a java.lang.ref.ReferenceQueue$Lock)
	at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:134)
	at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:171)

"Reference Handler" daemon prio=10 tid=0xacca5000 nid=0x2d5c in Object.wait() [0xacb7d000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	- waiting on <0xb399be00> (a java.lang.ref.Reference$Lock)
	at java.lang.Object.wait(Object.java:485)
	at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
	- locked <0xb399be00> (a java.lang.ref.Reference$Lock)

"main" prio=10 tid=0xf6705800 nid=0x2d48 waiting on condition [0xf6912000]
   java.lang.Thread.State: TIMED_WAITING (parking)
	at sun.misc.Unsafe.park(Native Method)
	- parking to wait for  <0xe9521678> (a java.util.concurrent.CountDownLatch$Sync)
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1011)
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1303)
	at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:253)
	at org.apache.camel.component.mock.MockEndpoint.waitForCompleteLatch(MockEndpoint.java:1295)
	at org.apache.camel.component.mock.MockEndpoint.waitForCompleteLatch(MockEndpoint.java:1279)
	at org.apache.camel.component.mock.MockEndpoint.doAssertIsSatisfied(MockEndpoint.java:370)
	at org.apache.camel.component.mock.MockEndpoint.assertIsSatisfied(MockEndpoint.java:351)
	at org.apache.camel.component.mock.MockEndpoint.assertIsSatisfied(MockEndpoint.java:339)
	at org.apache.camel.component.mock.MockEndpoint.assertIsSatisfied(MockEndpoint.java:177)
	at org.apache.camel.ContextTestSupport.assertMockEndpointsSatisfied(ContextTestSupport.java:343)
	at org.apache.camel.component.file.FileConcurrentWriteAppendSameFileTest.testConcurrentAppend(FileConcurrentWriteAppendSameFileTest.java:58)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at junit.framework.TestCase.runTest(TestCase.java:176)
	at junit.framework.TestCase.runBare(TestCase.java:141)
	at org.apache.camel.TestSupport.runBare(TestSupport.java:58)
	at junit.framework.TestResult$1.protect(TestResult.java:122)
	at junit.framework.TestResult.runProtected(TestResult.java:142)
	at junit.framework.TestResult.run(TestResult.java:125)
	at junit.framework.TestCase.run(TestCase.java:129)
	at junit.framework.TestSuite.runTest(TestSuite.java:255)
	at junit.framework.TestSuite.run(TestSuite.java:250)
	at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:84)
	at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124)
	at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200)
	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103)

"VM Thread" prio=10 tid=0xacca1400 nid=0x2d5b runnable 

"GC task thread#0 (ParallelGC)" prio=10 tid=0xf670cc00 nid=0x2d49 runnable 

"GC task thread#1 (ParallelGC)" prio=10 tid=0xf670e000 nid=0x2d4a runnable 

"GC task thread#2 (ParallelGC)" prio=10 tid=0xf670f800 nid=0x2d4b runnable 

"GC task thread#3 (ParallelGC)" prio=10 tid=0xf6710c00 nid=0x2d4c runnable 

"GC task thread#4 (ParallelGC)" prio=10 tid=0xf6712400 nid=0x2d4d runnable 

"GC task thread#5 (ParallelGC)" prio=10 tid=0xf6713800 nid=0x2d4e runnable 

"GC task thread#6 (ParallelGC)" prio=10 tid=0xf6715000 nid=0x2d4f runnable 

"GC task thread#7 (ParallelGC)" prio=10 tid=0xf6716400 nid=0x2d50 runnable 

"GC task thread#8 (ParallelGC)" prio=10 tid=0xf6717c00 nid=0x2d51 runnable 

"GC task thread#9 (ParallelGC)" prio=10 tid=0xf6719000 nid=0x2d52 runnable 

"GC task thread#10 (ParallelGC)" prio=10 tid=0xf671a800 nid=0x2d53 runnable 

"GC task thread#11 (ParallelGC)" prio=10 tid=0xf671bc00 nid=0x2d54 runnable 

"GC task thread#12 (ParallelGC)" prio=10 tid=0xf671d400 nid=0x2d55 runnable 

"GC task thread#13 (ParallelGC)" prio=10 tid=0xf671e800 nid=0x2d56 runnable 

"GC task thread#14 (ParallelGC)" prio=10 tid=0xf6720000 nid=0x2d57 runnable 

"GC task thread#15 (ParallelGC)" prio=10 tid=0xf6721400 nid=0x2d58 runnable 

"GC task thread#16 (ParallelGC)" prio=10 tid=0xf6722c00 nid=0x2d59 runnable 

"GC task thread#17 (ParallelGC)" prio=10 tid=0xf6724000 nid=0x2d5a runnable 

"VM Periodic Task Thread" prio=10 tid=0xaccc3400 nid=0x2d62 waiting on condition 

JNI global references: 1756

+ 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.camel.component.file.FileConcurrentWriteAppendSameFileTest.testConcurrentAppend(FileConcurrentWriteAppendSameFileTest.java:55)'
+ BAD=1
+ jps
+ grep surefirebooter
+ cut -d ' ' -f 1
+ xargs kill -9
POST BUILD TASK : SUCCESS
END OF POST BUILD TASK : 0
Archiving artifacts
Recording test results
Updating HBASE-9110
Updating HBASE-9208

Jenkins build is back to normal : HBase-TRUNK #4445

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/HBase-TRUNK/4445/changes>