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 2015/02/27 08:54:53 UTC

Build failed in Jenkins: HBase-1.1 #217

See <https://builds.apache.org/job/HBase-1.1/217/changes>

Changes:

[tedyu] HBASE-13112 quota.rb, security.rb and visibility_labels.rb leak connection (Ashish Singhi)

------------------------------------------
[...truncated 4114 lines...]
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.1] $ /bin/bash -xe /tmp/hudson956151865078645879.sh
++ grep surefirebooter
++ wc -l
++ jps
+ 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
++ grep .test
++ xargs -n 1 jstack
++ grep '\.java'
+ ZB_STACK='	at org.apache.openjpa.integration.daytrader.TestDaytrader.testTrade(TestDaytrader.java:100)
	at org.apache.openjpa.persistence.test.AbstractPersistenceTestCase.runTest(AbstractPersistenceTestCase.java:579)
	at org.apache.openjpa.persistence.test.AbstractPersistenceTestCase.runBare(AbstractPersistenceTestCase.java:566)
	at org.apache.openjpa.persistence.test.AbstractPersistenceTestCase.runBare(AbstractPersistenceTestCase.java:542)
	at org.apache.openjpa.persistence.test.AbstractPersistenceTestCase.run(AbstractPersistenceTestCase.java:206)'
+ grep surefirebooter
+ jps
+ cut -d ' ' -f 1
+ xargs -n 1 jstack
2015-02-27 07:52:45
Full thread dump Java HotSpot(TM) Server VM (20.45-b01 mixed mode):

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

"derby.rawStoreDaemon" daemon prio=10 tid=0x8fe52c00 nid=0x295b in Object.wait() [0x9257d000]
   java.lang.Thread.State: TIMED_WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	- waiting on <0xb390e420> (a org.apache.derby.impl.services.daemon.BasicDaemon)
	at org.apache.derby.impl.services.daemon.BasicDaemon.rest(Unknown Source)
	- locked <0xb390e420> (a org.apache.derby.impl.services.daemon.BasicDaemon)
	at org.apache.derby.impl.services.daemon.BasicDaemon.run(Unknown Source)
	at java.lang.Thread.run(Thread.java:662)

"Timer-0" daemon prio=10 tid=0x8fef6c00 nid=0x295a in Object.wait() [0x9277d000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	- waiting on <0xb388efc8> (a java.util.TaskQueue)
	at java.lang.Object.wait(Object.java:485)
	at java.util.TimerThread.mainLoop(Timer.java:483)
	- locked <0xb388efc8> (a java.util.TaskQueue)
	at java.util.TimerThread.run(Timer.java:462)

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

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

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

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

"Finalizer" daemon prio=10 tid=0x90ba6400 nid=0x2905 in Object.wait() [0x92f7d000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	- waiting on <0xb3870f18> (a java.lang.ref.ReferenceQueue$Lock)
	at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:118)
	- locked <0xb3870f18> (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=0x90ba4c00 nid=0x2904 in Object.wait() [0x90a7d000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	- waiting on <0xb388bf88> (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 <0xb388bf88> (a java.lang.ref.Reference$Lock)

"main" prio=10 tid=0xf6805400 nid=0x28ef runnable [0xf698d000]
   java.lang.Thread.State: RUNNABLE
	at java.io.RandomAccessFile.writeBytes(Native Method)
	at java.io.RandomAccessFile.write(RandomAccessFile.java:482)
	at org.apache.derby.impl.store.raw.log.LogAccessFile.writeToLog(Unknown Source)
	- locked <0xb3d85e90> (a org.apache.derby.impl.io.DirRandomAccessFile)
	at org.apache.derby.impl.store.raw.log.LogAccessFile.flushDirtyBuffers(Unknown Source)
	at org.apache.derby.impl.store.raw.log.LogToFile.flush(Unknown Source)
	at org.apache.derby.impl.store.raw.log.LogToFile.flush(Unknown Source)
	at org.apache.derby.impl.store.raw.log.FileLogger.flush(Unknown Source)
	at org.apache.derby.impl.store.raw.xact.Xact.prepareCommit(Unknown Source)
	at org.apache.derby.impl.store.raw.xact.Xact.commit(Unknown Source)
	at org.apache.derby.impl.store.raw.xact.Xact.commit(Unknown Source)
	at org.apache.derby.impl.store.access.RAMTransaction.commit(Unknown Source)
	at org.apache.derby.impl.sql.conn.GenericLanguageConnectionContext.doCommit(Unknown Source)
	at org.apache.derby.impl.sql.conn.GenericLanguageConnectionContext.userCommit(Unknown Source)
	at org.apache.derby.impl.jdbc.TransactionResourceImpl.commit(Unknown Source)
	at org.apache.derby.impl.jdbc.EmbedConnection.commit(Unknown Source)
	- locked <0xb3ac86a8> (a org.apache.derby.impl.jdbc.EmbedConnection40)
	at org.apache.commons.dbcp.DelegatingConnection.commit(DelegatingConnection.java:334)
	at org.apache.commons.dbcp.PoolingDataSource$PoolGuardConnectionWrapper.commit(PoolingDataSource.java:211)
	at org.apache.openjpa.lib.jdbc.DelegatingConnection.commit(DelegatingConnection.java:175)
	at org.apache.openjpa.lib.jdbc.LoggingConnectionDecorator$LoggingConnection.commit(LoggingConnectionDecorator.java:341)
	at org.apache.openjpa.lib.jdbc.DelegatingConnection.commit(DelegatingConnection.java:175)
	at org.apache.openjpa.lib.jdbc.ConfiguringConnectionDecorator$ConfiguringConnection.commit(ConfiguringConnectionDecorator.java:124)
	at org.apache.openjpa.lib.jdbc.DelegatingConnection.commit(DelegatingConnection.java:175)
	at org.apache.openjpa.jdbc.kernel.JDBCStoreManager.commit(JDBCStoreManager.java:198)
	at org.apache.openjpa.kernel.DelegatingStoreManager.commit(DelegatingStoreManager.java:95)
	at org.apache.openjpa.kernel.BrokerImpl.endStoreManagerTransaction(BrokerImpl.java:1480)
	at org.apache.openjpa.kernel.BrokerImpl.endTransaction(BrokerImpl.java:2360)
	at org.apache.openjpa.kernel.BrokerImpl.afterCompletion(BrokerImpl.java:2019)
	at org.apache.openjpa.kernel.LocalManagedRuntime.commit(LocalManagedRuntime.java:94)
	- locked <0xde4b39e0> (a org.apache.openjpa.kernel.LocalManagedRuntime)
	at org.apache.openjpa.kernel.BrokerImpl.commit(BrokerImpl.java:1523)
	at org.apache.openjpa.kernel.DelegatingBroker.commit(DelegatingBroker.java:933)
	at org.apache.openjpa.persistence.EntityManagerImpl.commit(EntityManagerImpl.java:570)
	at org.apache.openjpa.integration.daytrader.TradeJPADirect.completeOrder(TradeJPADirect.java:380)
	at org.apache.openjpa.integration.daytrader.TradeJPADirect.buy(TradeJPADirect.java:202)
	at org.apache.openjpa.integration.daytrader.TradeBuildDB.createAccounts(TradeBuildDB.java:114)
	at org.apache.openjpa.integration.daytrader.TradeBuildDB.setup(TradeBuildDB.java:66)
	at org.apache.openjpa.integration.daytrader.TestDaytrader.testTrade(TestDaytrader.java:100)
	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:154)
	at org.apache.openjpa.persistence.test.AbstractPersistenceTestCase.runTest(AbstractPersistenceTestCase.java:579)
	at junit.framework.TestCase.runBare(TestCase.java:127)
	at org.apache.openjpa.persistence.test.AbstractPersistenceTestCase.runBare(AbstractPersistenceTestCase.java:566)
	at org.apache.openjpa.persistence.test.AbstractPersistenceTestCase.runBare(AbstractPersistenceTestCase.java:542)
	at junit.framework.TestResult$1.protect(TestResult.java:106)
	at junit.framework.TestResult.runProtected(TestResult.java:124)
	at junit.framework.TestResult.run(TestResult.java:109)
	at junit.framework.TestCase.run(TestCase.java:118)
	at org.apache.openjpa.persistence.test.AbstractPersistenceTestCase.run(AbstractPersistenceTestCase.java:206)
	at junit.framework.TestSuite.runTest(TestSuite.java:208)
	at junit.framework.TestSuite.run(TestSuite.java:203)
	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 org.apache.maven.surefire.junit.JUnitTestSet.execute(JUnitTestSet.java:213)
	at org.apache.maven.surefire.suite.AbstractDirectoryTestSuite.executeTestSet(AbstractDirectoryTestSuite.java:115)
	at org.apache.maven.surefire.suite.AbstractDirectoryTestSuite.execute(AbstractDirectoryTestSuite.java:102)
	at org.apache.maven.surefire.Surefire.run(Surefire.java:180)
	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 org.apache.maven.surefire.booter.SurefireBooter.runSuitesInProcess(SurefireBooter.java:350)
	at org.apache.maven.surefire.booter.SurefireBooter.main(SurefireBooter.java:1021)

"VM Thread" prio=10 tid=0x90ba1000 nid=0x2902 runnable 

"GC task thread#0 (ParallelGC)" prio=10 tid=0xf680c800 nid=0x28f0 runnable 

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

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

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

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

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

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

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

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

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

"GC task thread#10 (ParallelGC)" prio=10 tid=0xf681a400 nid=0x28fa runnable 

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

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

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

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

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

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

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

"VM Periodic Task Thread" prio=10 tid=0x90bbb000 nid=0x290a waiting on condition 

JNI global references: 1707

+ 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.openjpa.integration.daytrader.TestDaytrader.testTrade(TestDaytrader.java:100)
	at org.apache.openjpa.persistence.test.AbstractPersistenceTestCase.runTest(AbstractPersistenceTestCase.java:579)
	at org.apache.openjpa.persistence.test.AbstractPersistenceTestCase.runBare(AbstractPersistenceTestCase.java:566)
	at org.apache.openjpa.persistence.test.AbstractPersistenceTestCase.runBare(AbstractPersistenceTestCase.java:542)
	at org.apache.openjpa.persistence.test.AbstractPersistenceTestCase.run(AbstractPersistenceTestCase.java:206)'
+ 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.1 #204
Archived 1890 artifacts
Archive block size is 32768
Received 27 blocks and 440935806 bytes
Compression is 0.2%
Took 2 min 1 sec
Recording test results
Updating HBASE-13112