See <https://builds.apache.org/job/Tajo-0.11.2-build/170/changes>
Changes: [jhkim] TAJO-2074: Upgrade hadoop and netty. ------------------------------------------ [...truncated 729976 lines...] 2016-02-15 10:21:09,780 INFO: org.apache.tajo.querymaster.Query (handle(861)) - Processing q_1455527640068_2150 of type STAGE_COMPLETED 2016-02-15 10:21:09,780 INFO: org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next executable block eb_1455527640068_2150_000004 2016-02-15 10:21:09,780 INFO: org.apache.tajo.querymaster.Query (executeNextBlock(761)) - Scheduling Stage:eb_1455527640068_2150_000004 2016-02-15 10:21:09,780 INFO: org.apache.tajo.worker.ExecutionBlockContext (stop(176)) - Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0 2016-02-15 10:21:09,780 INFO: org.apache.tajo.querymaster.Stage (initTaskScheduler(915)) - org.apache.tajo.querymaster.DefaultTaskScheduler is chosen for the task scheduling for eb_1455527640068_2150_000004 2016-02-15 10:21:09,781 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1109)) - eb_1455527640068_2150_000004, Table's volume is approximately 1 MB 2016-02-15 10:21:09,781 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1114)) - eb_1455527640068_2150_000004, The determined number of non-leaf tasks is 1 2016-02-15 10:21:09,781 INFO: org.apache.tajo.worker.TaskManager (stopExecutionBlock(160)) - Stopped execution block:eb_1455527640068_2150_000003 2016-02-15 10:21:09,781 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleRangeShuffledFetches(688)) - eb_1455527640068_2150_000004, Try to divide [(1,1), (5,3)) into 1 sub ranges (total units: 1) 2016-02-15 10:21:09,781 INFO: org.apache.tajo.engine.utils.TupleUtil (setMaxRangeIfNull(71)) - Set null into range: default.orders.o_orderkey, current tuple is (5,NULL) 2016-02-15 10:21:09,782 INFO: org.apache.tajo.querymaster.Stage (run(867)) - 1 objects are scheduled 2016-02-15 10:21:09,782 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (start(135)) - Start TaskScheduler 2016-02-15 10:21:09,784 INFO: org.apache.tajo.worker.TaskManager (createExecutionBlock(112)) - QueryMaster Address:asf908.gq1.ygridcore.net:22865 2016-02-15 10:21:09,786 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(128)) - Tajo Root Dir: hdfs://localhost:39593/tajo 2016-02-15 10:21:09,786 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(129)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-0.11.2-build/ws/tajo-core-tests/target/test-data/f27c0e6a-8be3-45c9-a053-633d1c2a01d7/tajo-localdir> 2016-02-15 10:21:09,787 INFO: org.apache.tajo.worker.TaskManager (handle(181)) - Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:500, Disks:6, vCores:2) 2016-02-15 10:21:09,788 INFO: org.apache.tajo.storage.FileTablespace (getAppenderFilePath(291)) - Output File Path: hdfs://localhost:39593/tajo/warehouse/default/nullcharsession/.staging/q_1455527640068_2150/RESULT/part-04-000000-000 2016-02-15 10:21:09,788 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - Output File Path: hdfs://localhost:39593/tajo/warehouse/default/nullcharsession/.staging/q_1455527640068_2150/RESULT/part-04-000000-000 2016-02-15 10:21:09,788 INFO: org.apache.tajo.worker.TaskImpl (initPlan(159)) - * Task ta_1455527640068_2150_000004_000000_00 is initialized. InterQuery: false, Shuffle: null, Fragments: 1, Fetches:1, Local dir: <https://builds.apache.org/job/Tajo-0.11.2-build/ws/tajo-core-tests/target/test-data/f27c0e6a-8be3-45c9-a053-633d1c2a01d7/tajo-localdir/q_1455527640068_2150/output/4/0_0> 2016-02-15 10:21:09,792 INFO: org.apache.tajo.worker.TaskImpl (getFetchRunners(744)) - Create shuffle Fetchers local:1, remote:0 2016-02-15 10:21:09,792 INFO: org.apache.tajo.worker.TaskImpl (waitForFetch(383)) - ta_1455527640068_2150_000004_000000_00 All fetches are done! 2016-02-15 10:21:09,795 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) - [ta_1455527640068_2150_000004_000000_00] Total merge time: 0 msec 2016-02-15 10:21:09,801 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073748418_7594{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-88eebddb-9ff2-4826-b251-39ebebb8a5c1:NORMAL:127.0.0.1:58499|FINALIZED]]} size 0 2016-02-15 10:21:09,802 INFO: org.apache.tajo.worker.TaskImpl (run(472)) - ta_1455527640068_2150_000004_000000_00 is complete. 10 ms elapsed, final state:TA_SUCCEEDED 2016-02-15 10:21:09,803 INFO: org.apache.tajo.querymaster.Stage (transition(1365)) - Stage - eb_1455527640068_2150_000004 finalize NONE_SHUFFLE (total=1, success=1, killed=0) 2016-02-15 10:21:09,803 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(164)) - Task Scheduler stopped 2016-02-15 10:21:09,803 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(127)) - TaskScheduler schedulingThread stopped 2016-02-15 10:21:09,803 INFO: org.apache.tajo.querymaster.Stage (transition(1423)) - Stage completed - eb_1455527640068_2150_000004 (total=1, success=1, killed=0) 2016-02-15 10:21:09,803 INFO: org.apache.tajo.querymaster.Query (handle(861)) - Processing q_1455527640068_2150 of type STAGE_COMPLETED 2016-02-15 10:21:09,803 INFO: org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next executable block eb_1455527640068_2150_000005 2016-02-15 10:21:09,803 INFO: org.apache.tajo.worker.ExecutionBlockContext (stop(176)) - Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0 2016-02-15 10:21:09,804 INFO: org.apache.tajo.querymaster.Query (transition(808)) - Complete Stage[eb_1455527640068_2150_000004], State: SUCCEEDED, 4/4. 2016-02-15 10:21:09,804 INFO: org.apache.tajo.querymaster.Query (handle(861)) - Processing q_1455527640068_2150 of type QUERY_COMPLETED 2016-02-15 10:21:09,804 INFO: org.apache.tajo.worker.TaskManager (stopExecutionBlock(160)) - Stopped execution block:eb_1455527640068_2150_000004 2016-02-15 10:21:09,807 INFO: org.apache.tajo.storage.FileTablespace (commitOutputData(964)) - Moved from the staging dir to the output directory 'hdfs://localhost:39593/tajo/warehouse/default/nullcharsession 2016-02-15 10:21:09,812 INFO: org.apache.tajo.catalog.CatalogServer (createTable(694)) - relation "default.nullcharsession" is added to the catalog (127.0.0.1:22859) 2016-02-15 10:21:09,812 INFO: org.apache.tajo.querymaster.Query (handle(879)) - q_1455527640068_2150 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED 2016-02-15 10:21:09,812 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(297)) - Query completion notified from q_1455527640068_2150 final state: QUERY_SUCCEEDED 2016-02-15 10:21:09,813 INFO: org.apache.tajo.master.QueryInProgress (heartbeat(257)) - Received QueryMaster heartbeat:q_1455527640068_2150,state=QUERY_SUCCEEDED,progress=1.0, queryMaster=asf908.gq1.ygridcore.net 2016-02-15 10:21:09,813 INFO: org.apache.tajo.master.QueryManager (stopQuery(279)) - Stop QueryInProgress:q_1455527640068_2150 2016-02-15 10:21:09,813 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(122)) - ========================================================= 2016-02-15 10:21:09,813 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(123)) - Stop query:q_1455527640068_2150 2016-02-15 10:21:09,813 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(173)) - Stopping QueryMasterTask:q_1455527640068_2150 2016-02-15 10:21:09,813 INFO: org.apache.tajo.querymaster.QueryMasterTask (cleanupQuery(474)) - Cleanup resources of all workers. Query: q_1455527640068_2150, workers: 1 2016-02-15 10:21:09,814 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(189)) - Stopped QueryMasterTask:q_1455527640068_2150 2016-02-15 10:21:09,940 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(365)) - Saving query summary: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2148/query.hist 2016-02-15 10:21:09,947 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073748419_7595{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-b9d64e55-0132-49dd-a101-616297a0193f:NORMAL:127.0.0.1:58499|FINALIZED]]} size 0 2016-02-15 10:21:09,949 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2148/eb_1455527640068_2148_000002.hist 2016-02-15 10:21:09,952 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2148/eb_1455527640068_2148_000001.hist 2016-02-15 10:21:09,955 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(365)) - Saving query summary: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2149/query.hist 2016-02-15 10:21:09,961 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073748420_7596{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-88eebddb-9ff2-4826-b251-39ebebb8a5c1:NORMAL:127.0.0.1:58499|FINALIZED]]} size 0 2016-02-15 10:21:09,964 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2149/eb_1455527640068_2149_000002.hist 2016-02-15 10:21:09,966 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2149/eb_1455527640068_2149_000001.hist 2016-02-15 10:21:09,970 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(365)) - Saving query summary: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2150/query.hist 2016-02-15 10:21:09,976 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073748421_7597{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-b9d64e55-0132-49dd-a101-616297a0193f:NORMAL:127.0.0.1:58499|FINALIZED]]} size 0 2016-02-15 10:21:09,978 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2150/eb_1455527640068_2150_000001.hist 2016-02-15 10:21:09,983 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073748422_7598{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-88eebddb-9ff2-4826-b251-39ebebb8a5c1:NORMAL:127.0.0.1:58499|RBW]]} size 0 2016-02-15 10:21:09,985 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2150/eb_1455527640068_2150_000002.hist 2016-02-15 10:21:09,989 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073748423_7599{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-b9d64e55-0132-49dd-a101-616297a0193f:NORMAL:127.0.0.1:58499|FINALIZED]]} size 0 2016-02-15 10:21:09,991 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2150/eb_1455527640068_2150_000004.hist 2016-02-15 10:21:09,995 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073748424_7600{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-88eebddb-9ff2-4826-b251-39ebebb8a5c1:NORMAL:127.0.0.1:58499|RBW]]} size 0 2016-02-15 10:21:09,998 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2150/eb_1455527640068_2150_000003.hist 2016-02-15 10:21:10,002 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073748425_7601{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-b9d64e55-0132-49dd-a101-616297a0193f:NORMAL:127.0.0.1:58499|FINALIZED]]} size 0 2016-02-15 10:21:10,176 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(240)) - SQL: create table testFailCreateTablePartitionedOtherExceptColumn (deptname text, score int4)PARTITION BY RANGE (score)( PARTITION sub_part1 VALUES LESS THAN (2),PARTITION sub_part2 VALUES LESS THAN (5),PARTITION sub_part2 VALUES LESS THAN (MAXVALUE) ) 2016-02-15 10:21:10,186 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(240)) - SQL: create table testFailCreateTablePartitionedOtherExceptColumn (deptname text, score int4)PARTITION BY LIST (deptname)( PARTITION sub_part1 VALUES('r&d', 'design'),PARTITION sub_part2 VALUES('sales', 'hr') ) 2016-02-15 10:21:10,189 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(240)) - SQL: create table testFailCreateTablePartitionedOtherExceptColumn (deptname text, score int4)PARTITION BY HASH (deptname)PARTITIONS 2 2016-02-15 10:21:10,190 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 847dd895-a04d-4a0b-8299-a278bf2e2e8f is removed. Tests run: 26, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.532 sec - in org.apache.tajo.client.TestTajoClient Running org.apache.tajo.client.TestQueryClientExceptions 2016-02-15 10:21:10,195 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session 457c1087-9990-4781-8052-ef059deb4cdc is created. 2016-02-15 10:21:10,196 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(240)) - SQL: create table default.lineitem (name int); 2016-02-15 10:21:10,197 WARN: org.apache.tajo.plan.verifier.VerificationState (addVerification(41)) - org.apache.tajo.plan.verifier.PreLogicalPlanVerifier:assertRelationNoExistence(187) causes: table 'default.lineitem' already exists 2016-02-15 10:21:10,627 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(177)) - Query: select * from unknown_table 2016-02-15 10:21:10,628 WARN: org.apache.tajo.plan.verifier.VerificationState (addVerification(41)) - org.apache.tajo.plan.verifier.PreLogicalPlanVerifier:assertRelationExistence(158) causes: relation 'default.unknown_table' does not exist 2016-02-15 10:21:10,629 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(177)) - Query: create table default.lineitem (name int); 2016-02-15 10:21:10,630 WARN: org.apache.tajo.plan.verifier.VerificationState (addVerification(41)) - org.apache.tajo.plan.verifier.PreLogicalPlanVerifier:assertRelationNoExistence(187) causes: table 'default.lineitem' already exists 2016-02-15 10:21:10,747 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(177)) - Query: select * from unknown_table 2016-02-15 10:21:10,748 WARN: org.apache.tajo.plan.verifier.VerificationState (addVerification(41)) - org.apache.tajo.plan.verifier.PreLogicalPlanVerifier:assertRelationExistence(158) causes: relation 'default.unknown_table' does not exist 2016-02-15 10:21:10,847 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 457c1087-9990-4781-8052-ef059deb4cdc is removed. Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.642 sec - in org.apache.tajo.client.TestQueryClientExceptions Running org.apache.tajo.resource.TestResources Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 sec - in org.apache.tajo.resource.TestResources 2016-02-15 10:21:10,855 INFO: org.mortbay.log (info(67)) - Shutdown hook executing 2016-02-15 10:21:10,855 INFO: org.mortbay.log (info(67)) - Shutdown hook complete shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN] Feb 15, 2016 10:11:01 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26 Feb 15, 2016 10:11:01 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN] Feb 15, 2016 10:11:01 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN] Feb 15, 2016 10:11:02 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26 Feb 15, 2016 10:11:02 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN] Feb 15, 2016 10:11:02 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN] Feb 15, 2016 10:11:02 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26 Feb 15, 2016 10:11:02 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN] Feb 15, 2016 10:11:02 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN] Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26 Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN] Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN] Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Feb 15, 2016 10:11:05 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 212 Feb 15, 2016 10:11:05 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 5 values, 10B raw, 10B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN_DICTIONARY], dic { 3 entries, 12B raw, 3B comp} Feb 15, 2016 10:11:05 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 123B for [l_shipdate] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN] Feb 15, 2016 10:11:05 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 123B for [l_shipdate_function] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN] Feb 15, 2016 10:11:05 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Feb 15, 2016 10:11:05 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Feb 15, 2016 10:11:05 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 2016-02-15 10:21:10,862 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 630ddeb2-84ac-4e94-a3cd-16648f226f0e is removed. 2016-02-15 10:21:10,865 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session c12f3728-5c14-45dc-a980-7114304426ec is removed. 2016-02-15 10:21:10,865 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073745872_5048{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-88eebddb-9ff2-4826-b251-39ebebb8a5c1:NORMAL:127.0.0.1:58499|RBW]]} size 957704 2016-02-15 10:21:10,869 INFO: org.apache.tajo.worker.TajoWorker (run(569)) - ============================================ 2016-02-15 10:21:10,869 INFO: org.apache.tajo.worker.TajoWorker (run(570)) - TajoWorker received SIGINT Signal 2016-02-15 10:21:10,869 INFO: org.apache.tajo.worker.TajoWorker (run(571)) - ============================================ 2016-02-15 10:21:10,871 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_asf908.gq1.ygridcore.net_22863 stopped. 2016-02-15 10:21:10,871 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2016-02-15 10:21:11,272 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(113)) - NodeStatusUpdater stopped. 2016-02-15 10:21:11,272 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(261)) - Heartbeat Thread stopped. 2016-02-15 10:21:11,272 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:22865) shutdown 2016-02-15 10:21:11,272 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) - QueryMasterManagerService stopped 2016-02-15 10:21:11,273 INFO: org.apache.tajo.querymaster.QueryMaster (run(432)) - QueryMaster heartbeat thread stopped 2016-02-15 10:21:11,273 INFO: org.apache.tajo.querymaster.QueryMaster (serviceStop(164)) - QueryMaster stopped 2016-02-15 10:21:11,273 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(98)) - TajoWorkerClientService stopping 2016-02-15 10:21:11,274 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:22864) shutdown 2016-02-15 10:21:11,274 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(102)) - TajoWorkerClientService stopped 2016-02-15 10:21:11,274 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:22863) shutdown 2016-02-15 10:21:11,274 INFO: org.apache.tajo.worker.TajoWorkerManagerService (serviceStop(93)) - TajoWorkerManagerService stopped 2016-02-15 10:21:11,275 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(379)) - TajoWorker main thread exiting 2016-02-15 10:21:14,479 INFO: org.apache.tajo.master.TajoMaster (run(580)) - ============================================ 2016-02-15 10:21:14,480 INFO: org.apache.tajo.master.TajoMaster (run(581)) - TajoMaster received SIGINT Signal 2016-02-15 10:21:14,480 INFO: org.apache.tajo.master.TajoMaster (run(582)) - ============================================ 2016-02-15 10:21:14,480 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (Tajo-REST) listened on 127.0.0.1:22862) shutdown 2016-02-15 10:21:14,480 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped. 2016-02-15 10:21:14,484 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:22861) shutdown 2016-02-15 10:21:14,485 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2016-02-15 10:21:14,484 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_127.0.0.1_22861 stopped. 2016-02-15 10:21:16,689 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:22860) shutdown 2016-02-15 10:21:16,689 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(188)) - Catalog Server (127.0.0.1:22859) shutdown 2016-02-15 10:21:16,690 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (CatalogProtocol) listened on 127.0.0.1:22859) shutdown 2016-02-15 10:21:16,690 INFO: org.apache.tajo.catalog.store.DerbyStore (close(2901)) - Close database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-0.11.2-build/ws/tajo-core-tests/target/test-data/f27c0e6a-8be3-45c9-a053-633d1c2a01d7/db;create=true)> 2016-02-15 10:21:16,690 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:22858) shutdown 2016-02-15 10:21:16,691 INFO: org.apache.tajo.master.TajoMaster (serviceStop(440)) - Tajo Master main thread exiting 2016-02-15 10:21:16,726 INFO: org.apache.tajo.catalog.store.DerbyStore (shutdown(68)) - Derby shutdown complete normally. 2016-02-15 10:21:16,726 INFO: org.apache.tajo.catalog.store.DerbyStore (shutdown(75)) - Shutdown database Results : Failed tests: TestHBaseTable.testCreateNotExistsExternalHBaseTable:167 null Tests in error: TestHBaseTable.testBinaryMappedQuery:326 » RetriesExhausted Failed after attem... TestHBaseTable.testCTAS:1293->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testColumnKeyValueSelectQuery:385 » RetriesExhausted Failed aft... TestHBaseTable.testCompositeRowIndexPredication:512->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testCreateExternalHBaseTable:196 » RetriesExhausted Failed afte... TestHBaseTable.testCreateHBaseTable:127->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testGetSplitsWhenRestartHBase:1443->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testGetTableVolume:246 » RetriesExhausted Failed after attempts... TestHBaseTable.testIndexPredication:467->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertInto:742->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoBinaryMultiRegion:1053->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoColumnKeyValue:1108->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoDifferentType:1198->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoLocation:1390->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoMultiRegion:824->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoMultiRegion2:880->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoMultiRegionMultiRowFields:994->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoMultiRegionWithSplitFile:937->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoRowField:1234->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoUsingPut:1340->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertValues1:782->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testJoin:702->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testNonForwardQuery:664->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testRowFieldSelectQuery:428 » RetriesExhausted Failed after att... TestHBaseTable.testSimpleSelectQuery:288 » RetriesExhausted Failed after attem... Tests run: 1715, Failures: 1, Errors: 25, Skipped: 0 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Tajo Main ......................................... SUCCESS [ 2.002 s] [INFO] Tajo Project POM .................................. SUCCESS [ 1.263 s] [INFO] Tajo Maven Plugins ................................ SUCCESS [ 5.552 s] [INFO] Tajo Common ....................................... SUCCESS [ 39.548 s] [INFO] Tajo Algebra ...................................... SUCCESS [ 3.173 s] [INFO] Tajo Catalog Common ............................... SUCCESS [ 5.954 s] [INFO] Tajo Plan ......................................... SUCCESS [ 6.892 s] [INFO] Tajo Rpc Common ................................... SUCCESS [ 2.959 s] [INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [02:25 min] [INFO] Tajo Catalog Client ............................... SUCCESS [ 1.641 s] [INFO] Tajo Catalog Server ............................... SUCCESS [01:14 min] [INFO] Tajo Storage Common ............................... SUCCESS [ 6.552 s] [INFO] Tajo HDFS Storage ................................. SUCCESS [01:23 min] [INFO] Tajo PullServer ................................... SUCCESS [ 2.410 s] [INFO] Tajo Client ....................................... SUCCESS [ 2.470 s] [INFO] Tajo CLI tools .................................... SUCCESS [ 2.717 s] [INFO] Tajo SQL Parser ................................... SUCCESS [ 6.033 s] [INFO] ASM (thirdparty) .................................. SUCCESS [ 1.774 s] [INFO] Tajo RESTful Container ............................ SUCCESS [ 4.169 s] [INFO] Tajo Metrics ...................................... SUCCESS [ 1.849 s] [INFO] Tajo Core ......................................... SUCCESS [ 6.206 s] [INFO] Tajo RPC .......................................... SUCCESS [ 0.927 s] [INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 34.607 s] [INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.103 s] [INFO] Tajo Catalog ...................................... SUCCESS [ 0.977 s] [INFO] Tajo Client Example ............................... SUCCESS [ 1.037 s] [INFO] Tajo HBase Storage ................................ SUCCESS [ 5.729 s] [INFO] Tajo Cluster Tests ................................ SUCCESS [ 3.578 s] [INFO] Tajo JDBC Driver .................................. SUCCESS [ 40.500 s] [INFO] Tajo JDBC storage common .......................... SUCCESS [ 0.912 s] [INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 1.046 s] [INFO] Tajo Storage ...................................... SUCCESS [ 1.004 s] [INFO] Tajo Distribution ................................. SUCCESS [ 5.904 s] [INFO] Tajo Core Tests ................................... FAILURE [ 01:07 h] [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 01:15 h [INFO] Finished at: 2016-02-15T10:21:17+00:00 [INFO] Final Memory: 139M/916M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.19:test (default-test) on project tajo-core-tests: There are test failures. [ERROR] [ERROR] Please refer to <https://builds.apache.org/job/Tajo-0.11.2-build/ws/tajo-core-tests/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 :tajo-core-tests Build step 'Execute shell' marked build as failure Archiving artifacts Updating TAJO-2074
