See <https://builds.apache.org/job/HBase-0.98/1132/changes>
Changes: [larsh] HBASE-14489 postScannerFilterRow consumes a lot of CPU. ------------------------------------------ [...truncated 1252 lines...] Running org.apache.hadoop.hbase.security.TestSecureRPC Tests run: 1, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 0.314 sec - in org.apache.hadoop.hbase.security.TestSecureRPC Running org.apache.hadoop.hbase.zookeeper.TestZooKeeperMainServer Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.01 sec - in org.apache.hadoop.hbase.zookeeper.TestZooKeeperMainServer Running org.apache.hadoop.hbase.zookeeper.TestZKConfig Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.409 sec - in org.apache.hadoop.hbase.zookeeper.TestZKConfig Running org.apache.hadoop.hbase.master.TestClockSkewDetection Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.391 sec - in org.apache.hadoop.hbase.master.TestClockSkewDetection Running org.apache.hadoop.hbase.master.TestCatalogJanitor Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 11.858 sec - in org.apache.hadoop.hbase.master.TestCatalogJanitor Running org.apache.hadoop.hbase.master.TestRegionState Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.028 sec - in org.apache.hadoop.hbase.master.TestRegionState Running org.apache.hadoop.hbase.master.snapshot.TestSnapshotManager Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.544 sec - in org.apache.hadoop.hbase.master.snapshot.TestSnapshotManager Running org.apache.hadoop.hbase.master.snapshot.TestSnapshotLogCleaner Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.564 sec - in org.apache.hadoop.hbase.master.snapshot.TestSnapshotLogCleaner Running org.apache.hadoop.hbase.master.snapshot.TestSnapshotHFileCleaner Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.558 sec - in org.apache.hadoop.hbase.master.snapshot.TestSnapshotHFileCleaner Running org.apache.hadoop.hbase.master.cleaner.TestCleanerChore Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.085 sec - in org.apache.hadoop.hbase.master.cleaner.TestCleanerChore Running org.apache.hadoop.hbase.master.cleaner.TestHFileLinkCleaner Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 10.71 sec - in org.apache.hadoop.hbase.master.cleaner.TestHFileLinkCleaner Running org.apache.hadoop.hbase.master.balancer.TestFavoredNodeAssignmentHelper Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 20.093 sec - in org.apache.hadoop.hbase.master.balancer.TestFavoredNodeAssignmentHelper Running org.apache.hadoop.hbase.master.TestHMasterCommandLine Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.311 sec - in org.apache.hadoop.hbase.master.TestHMasterCommandLine Running org.apache.hadoop.hbase.codec.TestCellMessageCodec Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.382 sec - in org.apache.hadoop.hbase.codec.TestCellMessageCodec Results : Tests run: 817, Failures: 0, Errors: 0, Skipped: 2 [INFO] [INFO] --- maven-surefire-plugin:2.18.1:test (secondPartTestsExecution) @ hbase-server --- [INFO] Surefire report directory: <https://builds.apache.org/job/HBase-0.98/ws/hbase-server/target/surefire-reports> [INFO] Using configured provider org.apache.maven.surefire.junitcore.JUnitCoreProvider [INFO] parallel='none', perCoreThreadCount=true, threadCount=0, useUnlimitedThreads=false, threadCountSuites=0, threadCountClasses=0, threadCountMethods=0, parallelOptimized=true ------------------------------------------------------- T E S T S ------------------------------------------------------- Running org.apache.hadoop.hbase.backup.TestHFileArchiving Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 33.594 sec - in org.apache.hadoop.hbase.backup.TestHFileArchiving Running org.apache.hadoop.hbase.backup.example.TestZooKeeperTableArchiveClient Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3.548 sec - in org.apache.hadoop.hbase.backup.example.TestZooKeeperTableArchiveClient Running org.apache.hadoop.hbase.replication.TestReplicationChangingPeerRegionservers Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.883 sec - in org.apache.hadoop.hbase.replication.TestReplicationChangingPeerRegionservers Running org.apache.hadoop.hbase.replication.TestReplicationTrackerZKImpl Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.843 sec - in org.apache.hadoop.hbase.replication.TestReplicationTrackerZKImpl Running org.apache.hadoop.hbase.replication.TestReplicationSmallTests Tests run: 10, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 35.739 sec - in org.apache.hadoop.hbase.replication.TestReplicationSmallTests Running org.apache.hadoop.hbase.replication.regionserver.TestReplicationHLogReaderManager Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 245.495 sec - in org.apache.hadoop.hbase.replication.regionserver.TestReplicationHLogReaderManager Running org.apache.hadoop.hbase.replication.regionserver.TestReplicationSourceManager Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.309 sec - in org.apache.hadoop.hbase.replication.regionserver.TestReplicationSourceManager Running org.apache.hadoop.hbase.replication.regionserver.TestReplicationSink Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.114 sec - in org.apache.hadoop.hbase.replication.regionserver.TestReplicationSink Running org.apache.hadoop.hbase.replication.TestReplicationSyncUpTool Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 46.383 sec - in org.apache.hadoop.hbase.replication.TestReplicationSyncUpTool Running org.apache.hadoop.hbase.replication.TestPerTableCFReplication Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 22.626 sec - in org.apache.hadoop.hbase.replication.TestPerTableCFReplication Running org.apache.hadoop.hbase.replication.TestReplicationKillSlaveRS Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 13.205 sec - in org.apache.hadoop.hbase.replication.TestReplicationKillSlaveRS Running org.apache.hadoop.hbase.replication.TestMultiSlaveReplication Killed Running org.apache.hadoop.hbase.replication.TestMasterReplication Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 53.798 sec - in org.apache.hadoop.hbase.replication.TestMasterReplication Running org.apache.hadoop.hbase.replication.TestReplicationKillMasterRSCompressed Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 14.98 sec - in org.apache.hadoop.hbase.replication.TestReplicationKillMasterRSCompressed Running org.apache.hadoop.hbase.replication.TestReplicationSource Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.485 sec - in org.apache.hadoop.hbase.replication.TestReplicationSource Running org.apache.hadoop.hbase.replication.TestReplicationKillMasterRS Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 15.322 sec - in org.apache.hadoop.hbase.replication.TestReplicationKillMasterRS Running org.apache.hadoop.hbase.replication.TestReplicationStateZKImpl Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.901 sec - in org.apache.hadoop.hbase.replication.TestReplicationStateZKImpl Running org.apache.hadoop.hbase.replication.TestReplicationDisableInactivePeer Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 19.689 sec - in org.apache.hadoop.hbase.replication.TestReplicationDisableInactivePeer Running org.apache.hadoop.hbase.replication.TestReplicationWithTags Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3.143 sec - in org.apache.hadoop.hbase.replication.TestReplicationWithTags Running org.apache.hadoop.hbase.replication.TestReplicationEndpoint Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.912 sec - in org.apache.hadoop.hbase.replication.TestReplicationEndpoint Running org.apache.hadoop.hbase.fs.TestBlockReorder Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 17.954 sec - in org.apache.hadoop.hbase.fs.TestBlockReorder Running org.apache.hadoop.hbase.mapreduce.TestImportExport Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 343.132 sec - in org.apache.hadoop.hbase.mapreduce.TestImportExport Running org.apache.hadoop.hbase.mapreduce.TestImportTSVWithTTLs Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 36.871 sec - in org.apache.hadoop.hbase.mapreduce.TestImportTSVWithTTLs Running org.apache.hadoop.hbase.mapreduce.TestTableInputFormatScan1 Killed Running org.apache.hadoop.hbase.mapreduce.TestLoadIncrementalHFilesUseSecurityEndPoint Tests run: 16, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 29.68 sec - in org.apache.hadoop.hbase.mapreduce.TestLoadIncrementalHFilesUseSecurityEndPoint Running org.apache.hadoop.hbase.mapreduce.TestHLogRecordReader Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.817 sec - in org.apache.hadoop.hbase.mapreduce.TestHLogRecordReader Running org.apache.hadoop.hbase.mapreduce.TestLoadIncrementalHFilesSplitRecovery Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 14.726 sec - in org.apache.hadoop.hbase.mapreduce.TestLoadIncrementalHFilesSplitRecovery Running org.apache.hadoop.hbase.mapreduce.TestCopyTable Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 129.06 sec - in org.apache.hadoop.hbase.mapreduce.TestCopyTable Running org.apache.hadoop.hbase.mapreduce.TestImportTSVWithVisibilityLabels Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 146.751 sec - in org.apache.hadoop.hbase.mapreduce.TestImportTSVWithVisibilityLabels Running org.apache.hadoop.hbase.mapreduce.TestMultiTableSnapshotInputFormat Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 135.531 sec - in org.apache.hadoop.hbase.mapreduce.TestMultiTableSnapshotInputFormat Running org.apache.hadoop.hbase.mapreduce.TestHFileOutputFormat2 Tests run: 14, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 391.408 sec - in org.apache.hadoop.hbase.mapreduce.TestHFileOutputFormat2 Running org.apache.hadoop.hbase.mapreduce.TestRowCounter Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 175.707 sec - in org.apache.hadoop.hbase.mapreduce.TestRowCounter Running org.apache.hadoop.hbase.mapreduce.TestHashTable Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 61.923 sec - in org.apache.hadoop.hbase.mapreduce.TestHashTable Running org.apache.hadoop.hbase.mapreduce.TestTableSnapshotInputFormat Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 236.147 sec - in org.apache.hadoop.hbase.mapreduce.TestTableSnapshotInputFormat Running org.apache.hadoop.hbase.mapreduce.TestSyncTable Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 84.391 sec - in org.apache.hadoop.hbase.mapreduce.TestSyncTable Running org.apache.hadoop.hbase.mapreduce.TestSecureLoadIncrementalHFiles Tests run: 16, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 30.228 sec - in org.apache.hadoop.hbase.mapreduce.TestSecureLoadIncrementalHFiles Running org.apache.hadoop.hbase.mapreduce.TestTableInputFormat Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 44.705 sec - in org.apache.hadoop.hbase.mapreduce.TestTableInputFormat Running org.apache.hadoop.hbase.mapreduce.TestLoadIncrementalHFiles Tests run: 16, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 28.171 sec - in org.apache.hadoop.hbase.mapreduce.TestLoadIncrementalHFiles Running org.apache.hadoop.hbase.mapreduce.TestTimeRangeMapRed Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 38.562 sec - in org.apache.hadoop.hbase.mapreduce.TestTimeRangeMapRed Running org.apache.hadoop.hbase.mapreduce.TestWALPlayer Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 48.303 sec - in org.apache.hadoop.hbase.mapreduce.TestWALPlayer Running org.apache.hadoop.hbase.mapreduce.TestSecureLoadIncrementalHFilesSplitRecovery Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 14.065 sec - in org.apache.hadoop.hbase.mapreduce.TestSecureLoadIncrementalHFilesSplitRecovery Running org.apache.hadoop.hbase.mapreduce.TestHRegionPartitioner Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.25 sec - in org.apache.hadoop.hbase.mapreduce.TestHRegionPartitioner Running org.apache.hadoop.hbase.mapreduce.TestTableMapReduce Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 127.018 sec - in org.apache.hadoop.hbase.mapreduce.TestTableMapReduce Running org.apache.hadoop.hbase.mapreduce.TestTableInputFormatScan2 Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 207.495 sec - in org.apache.hadoop.hbase.mapreduce.TestTableInputFormatScan2 Running org.apache.hadoop.hbase.mapreduce.TestHFileOutputFormat Tests run: 13, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 219.436 sec - in org.apache.hadoop.hbase.mapreduce.TestHFileOutputFormat Running org.apache.hadoop.hbase.mapreduce.TestMultiTableInputFormat Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 138.326 sec - in org.apache.hadoop.hbase.mapreduce.TestMultiTableInputFormat Running org.apache.hadoop.hbase.mapreduce.TestCellCounter Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 152.945 sec - in org.apache.hadoop.hbase.mapreduce.TestCellCounter Running org.apache.hadoop.hbase.mapreduce.TestMultithreadedTableMapper Tests run: 1, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 45.637 sec <<< FAILURE! - in org.apache.hadoop.hbase.mapreduce.TestMultithreadedTableMapper testMultithreadedTableMapper(org.apache.hadoop.hbase.mapreduce.TestMultithreadedTableMapper) Time elapsed: 15.581 sec <<< FAILURE! java.lang.AssertionError: null at org.junit.Assert.fail(Assert.java:86) at org.junit.Assert.assertTrue(Assert.java:41) at org.junit.Assert.assertTrue(Assert.java:52) at org.apache.hadoop.hbase.mapreduce.TestMultithreadedTableMapper.runTestOnTable(TestMultithreadedTableMapper.java:150) at org.apache.hadoop.hbase.mapreduce.TestMultithreadedTableMapper.testMultithreadedTableMapper(TestMultithreadedTableMapper.java:126) Running org.apache.hadoop.hbase.mapreduce.TestImportTsv Results : Failed tests: TestMultithreadedTableMapper.testMultithreadedTableMapper:126->runTestOnTable:150 null Tests run: 229, Failures: 1, Errors: 0, Skipped: 2 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Apache HBase ...................................... SUCCESS [4.639s] [INFO] Apache HBase - Checkstyle ......................... SUCCESS [0.934s] [INFO] Apache HBase - Resource Bundle .................... SUCCESS [0.247s] [INFO] Apache HBase - Annotations ........................ SUCCESS [1.121s] [INFO] Apache HBase - Common ............................. SUCCESS [50.032s] [INFO] Apache HBase - Protocol ........................... SUCCESS [8.997s] [INFO] Apache HBase - Client ............................. SUCCESS [56.875s] [INFO] Apache HBase - Hadoop Compatibility ............... SUCCESS [7.237s] [INFO] Apache HBase - Hadoop Two Compatibility ........... SUCCESS [6.784s] [INFO] Apache HBase - Prefix Tree ........................ SUCCESS [9.640s] [INFO] Apache HBase - Server ............................. FAILURE [1:19:33.292s] [INFO] Apache HBase - Testing Util ....................... SKIPPED [INFO] Apache HBase - Thrift ............................. SKIPPED [INFO] Apache HBase - Rest ............................... SKIPPED [INFO] Apache HBase - Shell .............................. SKIPPED [INFO] Apache HBase - Integration Tests .................. SKIPPED [INFO] Apache HBase - Examples ........................... SKIPPED [INFO] Apache HBase - Assembly ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 1:22:01.835s [INFO] Finished at: Sun Sep 27 01:50:09 UTC 2015 [INFO] Final Memory: 96M/680M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.18.1:test (secondPartTestsExecution) on project hbase-server: ExecutionException: java.lang.RuntimeException: The forked VM terminated without properly saying goodbye. VM crash or System.exit called? [ERROR] Command was /bin/sh -c cd <https://builds.apache.org/job/HBase-0.98/ws/hbase-server> && /home/jenkins/tools/java/jdk1.7.0_25-32/jre/bin/java -enableassertions -XX:MaxDirectMemorySize=1G -Xmx2800m -XX:MaxPermSize=256m -Djava.security.egd=file:/dev/./urandom -Djava.net.preferIPv4Stack=true -Djava.awt.headless=true -jar <https://builds.apache.org/job/HBase-0.98/ws/hbase-server/target/surefire/surefirebooter9070242516124581647.jar> <https://builds.apache.org/job/HBase-0.98/ws/hbase-server/target/surefire/surefire6293288679704155421tmp> <https://builds.apache.org/job/HBase-0.98/ws/hbase-server/target/surefire/surefire_6246360937626064867366tmp> [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-0.98] $ /bin/bash -xe /tmp/hudson1452088475123557383.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 ++ wc -l ++ grep surefirebooter ++ jps + ZOMBIE_TESTS_COUNT=0 + [[ 0 != 0 ]] + echo 'We'\''re ok: there is no zombie test, but some tests took some time to stop' We're ok: there is no zombie test, but some tests took some time to stop POST BUILD TASK : SUCCESS END OF POST BUILD TASK : 0 Archiving artifacts Sending artifact delta relative to HBase-0.98 #1127 Archived 965 artifacts Archive block size is 32768 Received 6 blocks and 73713109 bytes Compression is 0.3% Took 24 sec Recording test results Updating HBASE-14489
