See <https://builds.apache.org/job/Tajo-master-build/786/changes>
Changes: [jhkim] TAJO-1736: Remove unnecessary getMountPath(). ------------------------------------------ [...truncated 676833 lines...] 2015-08-03 07:13:25,634 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(142)) - Query status of ta_1438584797456_1967_000004_000000_00 is changed to TA_PENDING 2015-08-03 07:13:25,634 INFO: org.apache.tajo.worker.TaskImpl (init(195)) - Initializing: ta_1438584797456_1967_000004_000000_00 2015-08-03 07:13:25,635 INFO: org.apache.tajo.storage.FileTablespace (getAppenderFilePath(310)) - Output File Path: hdfs://localhost:41004/tmp/tajo-jenkins/staging/q_1438584797456_1967/RESULT/part-04-000000-000 2015-08-03 07:13:25,635 INFO: org.apache.tajo.worker.TaskImpl (initPlan(145)) - Output File Path: hdfs://localhost:41004/tmp/tajo-jenkins/staging/q_1438584797456_1967/RESULT/part-04-000000-000 2015-08-03 07:13:25,635 INFO: org.apache.tajo.worker.TaskImpl (initPlan(150)) - ================================== 2015-08-03 07:13:25,635 INFO: org.apache.tajo.worker.TaskImpl (initPlan(151)) - * Stage ta_1438584797456_1967_000004_000000_00 is initialized 2015-08-03 07:13:25,635 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - * InterQuery: false, Fragments (num: 1), Fetches (total:1) : 2015-08-03 07:13:25,635 INFO: org.apache.tajo.worker.TaskImpl (initPlan(162)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d008c333-0094-454c-a9e3-00f488457cfd/tajo-localdir/q_1438584797456_1967/output/4/0_0> 2015-08-03 07:13:25,635 INFO: org.apache.tajo.worker.TaskImpl (initPlan(167)) - ================================== 2015-08-03 07:13:25,636 INFO: org.apache.tajo.worker.TaskImpl (init(214)) - the directory is created <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d008c333-0094-454c-a9e3-00f488457cfd/tajo-localdir/q_1438584797456_1967/in/eb_1438584797456_1967_000004/0/0/eb_1438584797456_1967_000003> 2015-08-03 07:13:25,637 INFO: org.apache.tajo.worker.TaskImpl (getFetchRunners(692)) - Try to get local file chunk at local host 2015-08-03 07:13:25,637 INFO: org.apache.tajo.worker.TaskImpl (getLocalStoredFileChunk(734)) - getLocalStoredFileChunk starts 2015-08-03 07:13:25,637 INFO: org.apache.tajo.worker.TaskImpl (getLocalStoredFileChunk(769)) - PullServer request param: shuffleType=r, sid=3, partId=0, taskIds=[0_0] 2015-08-03 07:13:25,658 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileChunks(686)) - BSTIndex is loaded from disk ((1,1), (5,NULL) 2015-08-03 07:13:25,658 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileChunks(710)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d008c333-0094-454c-a9e3-00f488457cfd/tajo-localdir/q_1438584797456_1967/output/3/0_0/output/output> (start=(1,1), end=(5,NULL), last=true) 2015-08-03 07:13:25,659 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileChunks(779)) - Retrieve File Chunk: (start=0, length=49, fromRemote=false, ebId=null) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d008c333-0094-454c-a9e3-00f488457cfd/tajo-localdir/q_1438584797456_1967/output/3/0_0/output/output> 2015-08-03 07:13:25,659 INFO: org.apache.tajo.worker.TaskImpl (getFetchRunners(720)) - Create a new Fetcher with storeChunk: (start=0, length=49, fromRemote=false, ebId=eb_1438584797456_1967_000003) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d008c333-0094-454c-a9e3-00f488457cfd/tajo-localdir/q_1438584797456_1967/output/3/0_0/output/output> 2015-08-03 07:13:25,659 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(142)) - Query status of ta_1438584797456_1967_000004_000000_00 is changed to TA_RUNNING 2015-08-03 07:13:25,659 INFO: org.apache.tajo.worker.Fetcher (get(128)) - Get pseudo fetch from local host 2015-08-03 07:13:25,659 INFO: org.apache.tajo.worker.TaskImpl (run(616)) - Add a new FileChunk to local chunk list 2015-08-03 07:13:25,659 INFO: org.apache.tajo.worker.TaskImpl (waitForFetch(362)) - ta_1438584797456_1967_000004_000000_00 All fetches are done! 2015-08-03 07:13:25,659 INFO: org.apache.tajo.worker.TaskImpl (localizeFetchedData(572)) - One local chunk is added to listTablets 2015-08-03 07:13:25,663 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) - [ta_1438584797456_1967_000004_000000_00] Total merge time: 0 msec 2015-08-03 07:13:25,663 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (close(796)) - Delete file: "fragment": {"id": "eb_1438584797456_1967_000003", "path": <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d008c333-0094-454c-a9e3-00f488457cfd/tajo-localdir/q_1438584797456_1967/output/3/0_0/output/output";,> "start": 0,"length": 49} 2015-08-03 07:13:25,668 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748222_7398{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-fb25aafa-396b-4c8a-a025-34404fbb325a:NORMAL:127.0.0.1:60826|RBW]]} size 0 2015-08-03 07:13:25,669 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(142)) - Query status of ta_1438584797456_1967_000004_000000_00 is changed to TA_SUCCEEDED 2015-08-03 07:13:25,669 INFO: org.apache.tajo.worker.TaskImpl (run(460)) - ta_1438584797456_1967_000004_000000_00 completed. Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0 2015-08-03 07:13:25,670 INFO: org.apache.tajo.querymaster.Stage (transition(1297)) - Stage - eb_1438584797456_1967_000004 finalize NONE_SHUFFLE (total=1, success=1, killed=0) 2015-08-03 07:13:25,670 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(158)) - Task Scheduler stopped 2015-08-03 07:13:25,670 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(139)) - TaskScheduler schedulingThread stopped 2015-08-03 07:13:25,670 INFO: org.apache.tajo.querymaster.Stage (transition(1355)) - Stage completed - eb_1438584797456_1967_000004 (total=1, success=1, killed=0) 2015-08-03 07:13:25,671 INFO: org.apache.tajo.querymaster.Query (handle(860)) - Processing q_1438584797456_1967 of type STAGE_COMPLETED 2015-08-03 07:13:25,671 INFO: org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next executable block eb_1438584797456_1967_000005 2015-08-03 07:13:25,671 INFO: org.apache.tajo.querymaster.Query (transition(807)) - Complete Stage[eb_1438584797456_1967_000004], State: SUCCEEDED, 4/4. 2015-08-03 07:13:25,671 INFO: org.apache.tajo.querymaster.Query (handle(860)) - Processing q_1438584797456_1967 of type QUERY_COMPLETED 2015-08-03 07:13:25,671 INFO: org.apache.tajo.worker.TaskManager (stopExecutionBlock(161)) - Stopped execution block:eb_1438584797456_1967_000004 2015-08-03 07:13:25,672 INFO: org.apache.tajo.querymaster.Query (finalizeQuery(532)) - Can't find partitions for adding. 2015-08-03 07:13:25,672 INFO: org.apache.tajo.querymaster.Query (handle(878)) - q_1438584797456_1967 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED 2015-08-03 07:13:25,672 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(294)) - Query completion notified from q_1438584797456_1967 final state: QUERY_SUCCEEDED 2015-08-03 07:13:25,673 INFO: org.apache.tajo.master.QueryInProgress (heartbeat(252)) - Received QueryMaster heartbeat:q_1438584797456_1967,state=QUERY_SUCCEEDED,progress=1.0, queryMaster=asf906.gq1.ygridcore.net 2015-08-03 07:13:25,674 INFO: org.apache.tajo.master.QueryManager (stopQuery(279)) - Stop QueryInProgress:q_1438584797456_1967 2015-08-03 07:13:25,674 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(117)) - ========================================================= 2015-08-03 07:13:25,674 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(118)) - Stop query:q_1438584797456_1967 2015-08-03 07:13:25,674 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(171)) - Stopping QueryMasterTask:q_1438584797456_1967 2015-08-03 07:13:25,674 INFO: org.apache.tajo.querymaster.QueryMasterTask (cleanupQuery(470)) - Cleanup resources of all workers. Query: q_1438584797456_1967, workers: 1 2015-08-03 07:13:25,675 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(187)) - Stopped QueryMasterTask:q_1438584797456_1967 2015-08-03 07:13:25,691 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(358)) - Saving query summary: hdfs://localhost:41004/tmp/tajo-jenkins/staging/history/20150803/query-detail/q_1438584797456_1966/query.hist 2015-08-03 07:13:25,697 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748223_7399{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-72bba928-dd43-4f26-a207-01a9ec6763b6:NORMAL:127.0.0.1:60826|FINALIZED]]} size 0 2015-08-03 07:13:25,699 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(372)) - Saving query unit: hdfs://localhost:41004/tmp/tajo-jenkins/staging/history/20150803/query-detail/q_1438584797456_1966/eb_1438584797456_1966_000002.hist 2015-08-03 07:13:25,702 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748224_7400{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-fb25aafa-396b-4c8a-a025-34404fbb325a:NORMAL:127.0.0.1:60826|FINALIZED]]} size 0 2015-08-03 07:13:25,704 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(372)) - Saving query unit: hdfs://localhost:41004/tmp/tajo-jenkins/staging/history/20150803/query-detail/q_1438584797456_1966/eb_1438584797456_1966_000001.hist 2015-08-03 07:13:25,708 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748225_7401{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-72bba928-dd43-4f26-a207-01a9ec6763b6:NORMAL:127.0.0.1:60826|RBW]]} size 0 2015-08-03 07:13:25,711 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(358)) - Saving query summary: hdfs://localhost:41004/tmp/tajo-jenkins/staging/history/20150803/query-detail/q_1438584797456_1967/query.hist 2015-08-03 07:13:25,716 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748226_7402{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-fb25aafa-396b-4c8a-a025-34404fbb325a:NORMAL:127.0.0.1:60826|FINALIZED]]} size 0 2015-08-03 07:13:25,718 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(372)) - Saving query unit: hdfs://localhost:41004/tmp/tajo-jenkins/staging/history/20150803/query-detail/q_1438584797456_1967/eb_1438584797456_1967_000002.hist 2015-08-03 07:13:25,722 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748227_7403{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-72bba928-dd43-4f26-a207-01a9ec6763b6:NORMAL:127.0.0.1:60826|FINALIZED]]} size 0 2015-08-03 07:13:25,724 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(372)) - Saving query unit: hdfs://localhost:41004/tmp/tajo-jenkins/staging/history/20150803/query-detail/q_1438584797456_1967/eb_1438584797456_1967_000003.hist 2015-08-03 07:13:25,728 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748228_7404{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-fb25aafa-396b-4c8a-a025-34404fbb325a:NORMAL:127.0.0.1:60826|RBW]]} size 0 2015-08-03 07:13:25,730 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(372)) - Saving query unit: hdfs://localhost:41004/tmp/tajo-jenkins/staging/history/20150803/query-detail/q_1438584797456_1967/eb_1438584797456_1967_000001.hist 2015-08-03 07:13:25,734 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748229_7405{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-72bba928-dd43-4f26-a207-01a9ec6763b6:NORMAL:127.0.0.1:60826|RBW]]} size 0 2015-08-03 07:13:25,736 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(372)) - Saving query unit: hdfs://localhost:41004/tmp/tajo-jenkins/staging/history/20150803/query-detail/q_1438584797456_1967/eb_1438584797456_1967_000004.hist 2015-08-03 07:13:25,740 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748230_7406{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-fb25aafa-396b-4c8a-a025-34404fbb325a:NORMAL:127.0.0.1:60826|FINALIZED]]} size 0 2015-08-03 07:13:25,791 INFO: org.apache.tajo.master.TajoMasterClientService (getQueryResultData(576)) - Send result to client for 7a4f6f7e-15b6-4bf2-a161-32bdc028328b,q_1438584797456_1967, 5 rows 2015-08-03 07:13:25,792 INFO: org.apache.tajo.master.TajoMasterClientService (getQueryResultData(576)) - Send result to client for 7a4f6f7e-15b6-4bf2-a161-32bdc028328b,q_1438584797456_1967, 0 rows 2015-08-03 07:13:25,793 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session 7a4f6f7e-15b6-4bf2-a161-32bdc028328b is removed. 2015-08-03 07:13:25,796 INFO: org.apache.tajo.session.SessionManager (createSession(80)) - Session 3f40b968-b5b5-4aa3-a93a-a65ab281bcfa is created. 2015-08-03 07:13:25,798 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(180)) - Query: create table "TEST_DESC_TABLE" (col1 int4, col2 int4) 2015-08-03 07:13:25,799 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(278)) - Non Optimized Query: ----------------------------- Query Block Graph ----------------------------- |-#ROOT ----------------------------- Optimization Log: ----------------------------- 2015-08-03 07:13:25,799 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(280)) - ============================================= 2015-08-03 07:13:25,800 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(281)) - Optimized Query: ----------------------------- Query Block Graph ----------------------------- |-#ROOT ----------------------------- Optimization Log: ----------------------------- 2015-08-03 07:13:25,800 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(282)) - ============================================= 2015-08-03 07:13:25,802 INFO: org.apache.tajo.catalog.CatalogServer (createTable(662)) - relation "default.TEST_DESC_TABLE" is added to the catalog (127.0.0.1:27403) 2015-08-03 07:13:25,802 INFO: org.apache.tajo.master.exec.DDLExecutor (createTable(367)) - Table default.TEST_DESC_TABLE is created (0) 2015-08-03 07:13:25,805 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session 3f40b968-b5b5-4aa3-a93a-a65ab281bcfa is removed. Tests run: 21, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3.12 sec - in org.apache.tajo.cli.tsql.TestTajoCli 2015-08-03 07:13:25,812 INFO: org.apache.tajo.master.TajoMaster (run(533)) - ============================================ 2015-08-03 07:13:25,812 INFO: org.apache.tajo.master.TajoMaster (run(534)) - TajoMaster received SIGINT Signal 2015-08-03 07:13:25,812 INFO: org.apache.tajo.master.TajoMaster (run(535)) - ============================================ rds. Aug 3, 2015 7:01:41 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block Aug 3, 2015 7:01:41 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 2 Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26 Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26 Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26 Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26 Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26 Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 1 records. Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 2 ms. row count = 1 Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 1 records. Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 1 Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 1 records. Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 1 Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 1 records. Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 1 records. Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 1 Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 1 Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 212 Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 5 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN_DICTIONARY, BIT_PACKED], dic { 3 entries, 12B raw, 3B comp} Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 123B for [l_shipdate] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 123B for [l_shipdate_function] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 5 records. Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 5 2015-08-03 07:13:25,815 INFO: org.apache.tajo.worker.TajoWorker (run(567)) - ============================================ 2015-08-03 07:13:25,815 INFO: org.apache.tajo.worker.TajoWorker (run(568)) - TajoWorker received SIGINT Signal 2015-08-03 07:13:25,815 INFO: org.apache.tajo.worker.TajoWorker (run(569)) - ============================================ 2015-08-03 07:13:25,816 INFO: org.mortbay.log (info(67)) - Shutdown hook executing 2015-08-03 07:13:25,816 INFO: org.mortbay.log (info(67)) - Shutdown hook complete 2015-08-03 07:13:25,818 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session c7add68d-1617-406d-bf51-b6074442aa78 is removed. 2015-08-03 07:13:25,818 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session d57e8115-6fb0-4b61-9555-ecd7f3793963 is removed. 2015-08-03 07:13:25,818 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:27406) shutdown 2015-08-03 07:13:25,822 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped. 2015-08-03 07:13:25,822 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(193)) - Catalog Server (127.0.0.1:27403) shutdown 2015-08-03 07:13:25,822 INFO: org.apache.tajo.util.history.HistoryWriter (run(268)) - HistoryWriter_asf906.gq1.ygridcore.net_27407 stopped. 2015-08-03 07:13:25,823 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2015-08-03 07:13:25,824 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:27403) shutdown 2015-08-03 07:13:25,827 INFO: org.apache.tajo.util.history.HistoryWriter (run(268)) - HistoryWriter_127.0.0.1_27405 stopped. 2015-08-03 07:13:25,830 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073741834_1010{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-fb25aafa-396b-4c8a-a025-34404fbb325a:NORMAL:127.0.0.1:60826|RBW]]} size 524 2015-08-03 07:13:25,831 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2015-08-03 07:13:25,831 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:27405) shutdown 2015-08-03 07:13:25,832 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:27404) shutdown 2015-08-03 07:13:25,835 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:27402) shutdown 2015-08-03 07:13:25,835 INFO: org.apache.tajo.master.TajoMaster (serviceStop(401)) - Tajo Master main thread exiting 2015-08-03 07:13:25,882 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(111)) - NodeStatusUpdater stopped. 2015-08-03 07:13:25,882 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(262)) - Heartbeat Thread stopped. 2015-08-03 07:13:25,883 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:27409) shutdown 2015-08-03 07:13:25,883 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) - QueryMasterManagerService stopped 2015-08-03 07:13:25,884 INFO: org.apache.tajo.querymaster.QueryMaster (run(417)) - QueryMaster heartbeat thread stopped 2015-08-03 07:13:25,885 INFO: org.apache.tajo.querymaster.QueryMaster (serviceStop(168)) - QueryMaster stopped 2015-08-03 07:13:25,886 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(99)) - TajoWorkerClientService stopping 2015-08-03 07:13:25,886 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:27408) shutdown 2015-08-03 07:13:25,886 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(103)) - TajoWorkerClientService stopped 2015-08-03 07:13:25,887 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:27407) shutdown 2015-08-03 07:13:25,887 INFO: org.apache.tajo.worker.TajoWorkerManagerService (serviceStop(93)) - TajoWorkerManagerService stopped 2015-08-03 07:13:25,887 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(377)) - TajoWorker main thread exiting Results : Failed tests: TestTajoClient.testGetFinishedQueryList:628 expected:<103> but was:<101> Tests run: 1579, Failures: 1, Errors: 0, Skipped: 0 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Tajo Main ......................................... SUCCESS [ 1.783 s] [INFO] Tajo Project POM .................................. SUCCESS [ 1.660 s] [INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.018 s] [INFO] Tajo Common ....................................... SUCCESS [ 30.479 s] [INFO] Tajo Algebra ...................................... SUCCESS [ 1.648 s] [INFO] Tajo Catalog Common ............................... SUCCESS [ 5.004 s] [INFO] Tajo Plan ......................................... SUCCESS [ 5.478 s] [INFO] Tajo Rpc Common ................................... SUCCESS [ 0.532 s] [INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 47.887 s] [INFO] Tajo Catalog Client ............................... SUCCESS [ 1.450 s] [INFO] Tajo Catalog Server ............................... SUCCESS [ 10.815 s] [INFO] Tajo Storage Common ............................... SUCCESS [ 10.073 s] [INFO] Tajo HDFS Storage ................................. SUCCESS [ 48.112 s] [INFO] Tajo HBase Storage ................................ SUCCESS [ 9.858 s] [INFO] Tajo PullServer ................................... SUCCESS [ 1.308 s] [INFO] Tajo Client ....................................... SUCCESS [ 2.122 s] [INFO] Tajo CLI tools .................................... SUCCESS [ 1.347 s] [INFO] Tajo JDBC Driver .................................. SUCCESS [ 3.363 s] [INFO] ASM (thirdparty) .................................. SUCCESS [ 1.034 s] [INFO] Tajo RESTful Container ............................ SUCCESS [ 3.305 s] [INFO] Tajo Metrics ...................................... SUCCESS [ 1.060 s] [INFO] Tajo Core ......................................... FAILURE [20:42 min] [INFO] Tajo RPC .......................................... SKIPPED [INFO] Tajo Catalog Drivers Hive ......................... SKIPPED [INFO] Tajo Catalog Drivers .............................. SKIPPED [INFO] Tajo Catalog ...................................... SKIPPED [INFO] Tajo Storage ...................................... SKIPPED [INFO] Tajo Distribution ................................. SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 23:54 min [INFO] Finished at: 2015-08-03T07:13:27+00:00 [INFO] Final Memory: 67M/431M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on project tajo-core: There are test failures. [ERROR] [ERROR] Please refer to <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/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 Build step 'Execute shell' marked build as failure Updating TAJO-1736
