See <https://builds.apache.org/job/Tajo-master-build/653/changes>
Changes: [jihun] TAJO-1338: Defines RESTful API for Clients ------------------------------------------ [...truncated 400265 lines...] 2015-04-06 03:13:24,146 INFO: org.apache.tajo.querymaster.Stage (transition(1347)) - Stage finalize - eb_1428288782453_0944_000003 (total=1, success=1, killed=0) 2015-04-06 03:13:24,146 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(152)) - Task Scheduler stopped 2015-04-06 03:13:24,146 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(109)) - TaskScheduler schedulingThread stopped 2015-04-06 03:13:24,146 INFO: org.apache.tajo.querymaster.QueryMaster (cleanupExecutionBlock(180)) - cleanup executionBlocks: eb_1428288782453_0944_000001,eb_1428288782453_0944_000002 2015-04-06 03:13:24,146 INFO: org.apache.tajo.worker.TaskRunner (run(259)) - Received ShouldDie flag:eb_1428288782453_0944_000003,container_1428288782453_0944_01_003450 2015-04-06 03:13:24,147 INFO: org.apache.tajo.worker.TaskRunner (stop(153)) - Stop TaskRunner: eb_1428288782453_0944_000003,container_1428288782453_0944_01_003450 2015-04-06 03:13:24,147 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTaskRunner(105)) - Stop Task:eb_1428288782453_0944_000003,container_1428288782453_0944_01_003450 2015-04-06 03:13:24,148 INFO: org.apache.tajo.querymaster.Stage (transition(1353)) - eb_1428288782453_0944_000003, waiting for shuffle reports. expected Tasks:1 2015-04-06 03:13:24,148 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(513)) - Release Resource: 0.5,512 2015-04-06 03:13:24,148 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(151)) - ======================== Processing eb_1428288782453_0944_000003 of type STOP 2015-04-06 03:13:24,149 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(134)) - Close HashShuffleAppender:eb_1428288782453_0944_000003, not a hash shuffle 2015-04-06 03:13:24,149 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(134)) - Close HashShuffleAppender:eb_1428288782453_0944_000003, not a hash shuffle 2015-04-06 03:13:24,149 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(191)) - Stopped execution block:eb_1428288782453_0944_000003 2015-04-06 03:13:24,149 INFO: org.apache.tajo.querymaster.Stage (transition(1333)) - eb_1428288782453_0944_000003, Finalized shuffle reports: 1 2015-04-06 03:13:24,149 INFO: org.apache.tajo.querymaster.Stage (transition(1397)) - Stage completed - eb_1428288782453_0944_000003 (total=1, success=1, killed=0) 2015-04-06 03:13:24,150 INFO: org.apache.tajo.querymaster.Query (handle(720)) - Processing q_1428288782453_0944 of type STAGE_COMPLETED 2015-04-06 03:13:24,150 INFO: org.apache.tajo.querymaster.Query (executeNextBlock(634)) - Scheduling Stage:eb_1428288782453_0944_000004 2015-04-06 03:13:24,150 INFO: org.apache.tajo.querymaster.Stage (initTaskScheduler(847)) - org.apache.tajo.querymaster.DefaultTaskScheduler is chosen for the task scheduling for eb_1428288782453_0944_000004 2015-04-06 03:13:24,150 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1008)) - eb_1428288782453_0944_000004, Table's volume is approximately 1 MB 2015-04-06 03:13:24,150 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1012)) - eb_1428288782453_0944_000004, The determined number of non-leaf tasks is 1 2015-04-06 03:13:24,151 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleRangeShuffledFetches(700)) - eb_1428288782453_0944_000004, Try to divide [(0=>1, 1=>1), (0=>5, 1=>3)) into 1 sub ranges (total units: 1) 2015-04-06 03:13:24,151 INFO: org.apache.tajo.engine.utils.TupleUtil (setMaxRangeIfNull(109)) - Set null into range: default.orders.o_orderkey, current tuple is (0=>5, 1=>NULL) 2015-04-06 03:13:24,151 INFO: org.apache.tajo.querymaster.Stage (run(799)) - 1 objects are scheduled 2015-04-06 03:13:24,152 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (start(91)) - Start TaskScheduler 2015-04-06 03:13:24,152 INFO: org.apache.tajo.worker.TajoResourceAllocator (calculateNumRequestContainers(102)) - CalculateNumberRequestContainer - Number of Tasks=1, Number of Cluster Slots=1 2015-04-06 03:13:24,152 INFO: org.apache.tajo.querymaster.Stage (allocateContainers(1060)) - Request Container for eb_1428288782453_0944_000004 containers=1 2015-04-06 03:13:24,152 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(262)) - Start TajoWorkerAllocationThread 2015-04-06 03:13:24,153 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(375)) - Stop TajoWorkerAllocationThread 2015-04-06 03:13:24,154 INFO: org.apache.tajo.querymaster.Stage (transition(1174)) - Stage (eb_1428288782453_0944_000004) has 1 containers! 2015-04-06 03:13:24,155 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(151)) - ======================== Processing eb_1428288782453_0944_000004 of type START 2015-04-06 03:13:24,155 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(121)) - Tajo Root Dir: hdfs://localhost:45590/tajo 2015-04-06 03:13:24,155 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(122)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d6e27f02-ca7a-48bb-b13f-ce0f21345560/tajo-localdir> 2015-04-06 03:13:24,155 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(125)) - QueryMaster Address:asf906.gq1.ygridcore.net/67.195.81.150:11906 2015-04-06 03:13:24,156 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(169)) - Start TaskRunner:eb_1428288782453_0944_000004,container_1428288782453_0944_01_003451 2015-04-06 03:13:24,157 INFO: org.apache.tajo.worker.TaskRunner (init(127)) - TaskRunner basedir is created (<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d6e27f02-ca7a-48bb-b13f-ce0f21345560/tajo-localdir/q_1428288782453_0944/output/4)> 2015-04-06 03:13:24,157 INFO: org.apache.tajo.worker.TaskRunner (run(188)) - TaskRunner startup 2015-04-06 03:13:24,157 INFO: org.apache.tajo.worker.TaskRunner (run(221)) - Request GetTask: eb_1428288782453_0944_000004,container_1428288782453_0944_01_003451 2015-04-06 03:13:24,159 INFO: org.apache.tajo.worker.TaskRunner (run(265)) - Accumulated Received Task: 1 2015-04-06 03:13:24,159 INFO: org.apache.tajo.worker.TaskRunner (run(274)) - Initializing: ta_1428288782453_0944_000004_000000_00 2015-04-06 03:13:24,159 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1428288782453_0944_000004_000000_00 is changed to TA_PENDING 2015-04-06 03:13:24,160 INFO: org.apache.tajo.storage.FileStorageManager (getAppenderFilePath(298)) - Output File Path: hdfs://localhost:45590/tmp/tajo-jenkins/staging/q_1428288782453_0944/RESULT/part-04-000000-000 2015-04-06 03:13:24,160 INFO: org.apache.tajo.worker.Task (initPlan(169)) - Output File Path: hdfs://localhost:45590/tmp/tajo-jenkins/staging/q_1428288782453_0944/RESULT/part-04-000000-000 2015-04-06 03:13:24,160 INFO: org.apache.tajo.worker.Task (initPlan(174)) - ================================== 2015-04-06 03:13:24,160 INFO: org.apache.tajo.worker.Task (initPlan(175)) - * Stage ta_1428288782453_0944_000004_000000_00 is initialized 2015-04-06 03:13:24,160 INFO: org.apache.tajo.worker.Task (initPlan(176)) - * InterQuery: false, Fragments (num: 1), Fetches (total:1) : 2015-04-06 03:13:24,161 INFO: org.apache.tajo.worker.Task (initPlan(186)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d6e27f02-ca7a-48bb-b13f-ce0f21345560/tajo-localdir/q_1428288782453_0944/output/4/0_0> 2015-04-06 03:13:24,161 INFO: org.apache.tajo.worker.Task (initPlan(191)) - ================================== 2015-04-06 03:13:24,162 INFO: org.apache.tajo.worker.Task (init(211)) - the directory is created <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d6e27f02-ca7a-48bb-b13f-ce0f21345560/tajo-localdir/q_1428288782453_0944/in/eb_1428288782453_0944_000004/0/0/eb_1428288782453_0944_000003> 2015-04-06 03:13:24,162 INFO: org.apache.tajo.worker.Task (getFetchRunners(697)) - Try to get local file chunk at local host 2015-04-06 03:13:24,162 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(739)) - getLocalStoredFileChunk starts 2015-04-06 03:13:24,162 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(774)) - PullServer request param: shuffleType=r, sid=3, partId=0, taskIds=[0_0] 2015-04-06 03:13:24,179 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(686)) - BSTIndex is loaded from disk ((0=>1, 1=>1), (0=>5, 1=>NULL) 2015-04-06 03:13:24,179 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(710)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d6e27f02-ca7a-48bb-b13f-ce0f21345560/tajo-localdir/q_1428288782453_0944/output/3/0_0/output/output> (start=(0=>1, 1=>1), end=(0=>5, 1=>NULL), last=true) 2015-04-06 03:13:24,179 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(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/d6e27f02-ca7a-48bb-b13f-ce0f21345560/tajo-localdir/q_1428288782453_0944/output/3/0_0/output/output> 2015-04-06 03:13:24,179 INFO: org.apache.tajo.worker.Task (getFetchRunners(725)) - Create a new Fetcher with storeChunk: (start=0, length=49, fromRemote=false, ebId=eb_1428288782453_0944_000003) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d6e27f02-ca7a-48bb-b13f-ce0f21345560/tajo-localdir/q_1428288782453_0944/output/3/0_0/output/output> 2015-04-06 03:13:24,180 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1428288782453_0944_000004_000000_00 is changed to TA_RUNNING 2015-04-06 03:13:24,180 INFO: org.apache.tajo.worker.Fetcher (get(138)) - Get pseudo fetch from local host 2015-04-06 03:13:24,180 INFO: org.apache.tajo.worker.Task (run(621)) - Add a new FileChunk to local chunk list 2015-04-06 03:13:24,180 INFO: org.apache.tajo.worker.Task (waitForFetch(366)) - ta_1428288782453_0944_000004_000000_00 All fetches are done! 2015-04-06 03:13:24,181 INFO: org.apache.tajo.worker.Task (localizeFetchedData(577)) - One local chunk is added to listTablets 2015-04-06 03:13:24,185 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) - [ta_1428288782453_0944_000004_000000_00] Total merge time: 0 msec 2015-04-06 03:13:24,185 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (close(792)) - Delete file: "fragment": {"id": "eb_1428288782453_0944_000003", "path": <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d6e27f02-ca7a-48bb-b13f-ce0f21345560/tajo-localdir/q_1428288782453_0944/output/3/0_0/output/output";,> "start": 0,"length": 49} 2015-04-06 03:13:24,190 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:59176 is added to blk_1073747040_6216{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-efba173d-af80-429e-8d9b-3fcdfa22963a:NORMAL:127.0.0.1:59176|RBW]]} size 0 2015-04-06 03:13:24,191 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1428288782453_0944_000004_000000_00 is changed to TA_SUCCEEDED 2015-04-06 03:13:24,191 INFO: org.apache.tajo.worker.Task (run(465)) - ta_1428288782453_0944_000004_000000_00 completed. Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0 2015-04-06 03:13:24,191 INFO: org.apache.tajo.worker.TaskRunner (run(221)) - Request GetTask: eb_1428288782453_0944_000004,container_1428288782453_0944_01_003451 2015-04-06 03:13:24,192 INFO: org.apache.tajo.querymaster.Stage (transition(1347)) - Stage finalize - eb_1428288782453_0944_000004 (total=1, success=1, killed=0) 2015-04-06 03:13:24,192 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(152)) - Task Scheduler stopped 2015-04-06 03:13:24,192 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(109)) - TaskScheduler schedulingThread stopped 2015-04-06 03:13:24,192 INFO: org.apache.tajo.querymaster.QueryMaster (cleanupExecutionBlock(180)) - cleanup executionBlocks: eb_1428288782453_0944_000003 2015-04-06 03:13:24,192 INFO: org.apache.tajo.worker.TaskRunner (run(259)) - Received ShouldDie flag:eb_1428288782453_0944_000004,container_1428288782453_0944_01_003451 2015-04-06 03:13:24,193 INFO: org.apache.tajo.worker.TaskRunner (stop(153)) - Stop TaskRunner: eb_1428288782453_0944_000004,container_1428288782453_0944_01_003451 2015-04-06 03:13:24,193 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTaskRunner(105)) - Stop Task:eb_1428288782453_0944_000004,container_1428288782453_0944_01_003451 2015-04-06 03:13:24,193 INFO: org.apache.tajo.querymaster.Stage (transition(1353)) - eb_1428288782453_0944_000004, waiting for shuffle reports. expected Tasks:1 2015-04-06 03:13:24,194 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(513)) - Release Resource: 0.5,512 2015-04-06 03:13:24,194 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(151)) - ======================== Processing eb_1428288782453_0944_000004 of type STOP 2015-04-06 03:13:24,194 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(134)) - Close HashShuffleAppender:eb_1428288782453_0944_000004, not a hash shuffle 2015-04-06 03:13:24,195 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(134)) - Close HashShuffleAppender:eb_1428288782453_0944_000004, not a hash shuffle 2015-04-06 03:13:24,195 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(191)) - Stopped execution block:eb_1428288782453_0944_000004 2015-04-06 03:13:24,195 INFO: org.apache.tajo.querymaster.Stage (transition(1333)) - eb_1428288782453_0944_000004, Finalized shuffle reports: 1 2015-04-06 03:13:24,195 INFO: org.apache.tajo.querymaster.Stage (transition(1397)) - Stage completed - eb_1428288782453_0944_000004 (total=1, success=1, killed=0) 2015-04-06 03:13:24,195 INFO: org.apache.tajo.querymaster.Query (handle(720)) - Processing q_1428288782453_0944 of type STAGE_COMPLETED 2015-04-06 03:13:24,195 INFO: org.apache.tajo.querymaster.Query (handle(720)) - Processing q_1428288782453_0944 of type QUERY_COMPLETED 2015-04-06 03:13:24,196 INFO: org.apache.tajo.querymaster.Query (handle(738)) - q_1428288782453_0944 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED 2015-04-06 03:13:24,197 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(285)) - Query completion notified from q_1428288782453_0944 2015-04-06 03:13:24,197 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(296)) - Query final state: QUERY_SUCCEEDED 2015-04-06 03:13:24,197 INFO: org.apache.tajo.querymaster.QueryMasterTask (stop(192)) - Stopping QueryMasterTask:q_1428288782453_0944 2015-04-06 03:13:24,197 INFO: org.apache.tajo.querymaster.QueryMasterTask (stop(205)) - Stopped QueryMasterTask:q_1428288782453_0944 2015-04-06 03:13:24,198 INFO: org.apache.tajo.querymaster.QueryMaster (cleanup(207)) - cleanup query resources : q_1428288782453_0944 2015-04-06 03:13:24,198 INFO: org.apache.tajo.master.QueryInProgress (heartbeat(229)) - Received QueryMaster heartbeat:q_1428288782453_0944,state=QUERY_SUCCEEDED,progress=1.0, queryMaster=asf906.gq1.ygridcore.net 2015-04-06 03:13:24,198 INFO: org.apache.tajo.master.QueryManager (stopQuery(252)) - Stop QueryInProgress:q_1428288782453_0944 2015-04-06 03:13:24,198 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(109)) - ========================================================= 2015-04-06 03:13:24,198 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(110)) - Stop query:q_1428288782453_0944 2015-04-06 03:13:24,198 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(513)) - Release Resource: 0.0,512 2015-04-06 03:13:24,198 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseQueryMaster(535)) - Released QueryMaster (q_1428288782453_0944) resource. 2015-04-06 03:13:24,201 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(362)) - Saving query summary: hdfs://localhost:45590/tmp/tajo-jenkins/staging/history/20150406/query-detail/q_1428288782453_0944/query.hist 2015-04-06 03:13:24,207 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:59176 is added to blk_1073747041_6217{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-cefa959b-4cc9-49b3-9f90-da8daf6d0767:NORMAL:127.0.0.1:59176|FINALIZED]]} size 0 2015-04-06 03:13:24,209 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(376)) - Saving query unit: hdfs://localhost:45590/tmp/tajo-jenkins/staging/history/20150406/query-detail/q_1428288782453_0944/eb_1428288782453_0944_000002.hist 2015-04-06 03:13:24,213 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:59176 is added to blk_1073747042_6218{blockUCState=COMMITTED, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-cefa959b-4cc9-49b3-9f90-da8daf6d0767:NORMAL:127.0.0.1:59176|RBW]]} size 480 2015-04-06 03:13:24,238 INFO: org.apache.tajo.master.TajoMasterClientService (getQueryResultData(559)) - Send result to client for 5f4ed121-d746-48b5-a0b9-2db96ec8bf04,q_1428288782453_0944, 5 rows 2015-04-06 03:13:24,240 INFO: org.apache.tajo.master.TajoMasterClientService (getQueryResultData(559)) - Send result to client for 5f4ed121-d746-48b5-a0b9-2db96ec8bf04,q_1428288782453_0944, 0 rows 2015-04-06 03:13:24,243 INFO: org.apache.tajo.session.SessionManager (removeSession(79)) - Session 5f4ed121-d746-48b5-a0b9-2db96ec8bf04 is removed. 2015-04-06 03:13:24,246 INFO: org.apache.tajo.session.SessionManager (createSession(73)) - Session 0c9af972-0089-4091-b46c-28e08486d970 is created. 2015-04-06 03:13:24,248 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(150)) - Query: create table "TEST_DESC_TABLE" (col1 int4, col2 int4) 2015-04-06 03:13:24,251 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(239)) - Non Optimized Query: ----------------------------- Query Block Graph ----------------------------- |-#ROOT ----------------------------- Optimization Log: ----------------------------- 2015-04-06 03:13:24,251 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(241)) - ============================================= 2015-04-06 03:13:24,252 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(242)) - Optimized Query: ----------------------------- Query Block Graph ----------------------------- |-#ROOT ----------------------------- Optimization Log: ----------------------------- 2015-04-06 03:13:24,252 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(243)) - ============================================= 2015-04-06 03:13:24,254 INFO: org.apache.tajo.catalog.CatalogServer (createTable(605)) - relation "default.TEST_DESC_TABLE" is added to the catalog (127.0.0.1:11900) 2015-04-06 03:13:24,254 INFO: org.apache.tajo.master.exec.DDLExecutor (createTable(249)) - Table default.TEST_DESC_TABLE is created (0) 2015-04-06 03:13:24,259 INFO: org.apache.tajo.session.SessionManager (removeSession(79)) - Session 0c9af972-0089-4091-b46c-28e08486d970 is removed. Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3.253 sec - in org.apache.tajo.cli.tsql.TestTajoCli 2015-04-06 03:13:24,265 INFO: org.apache.tajo.session.SessionManager (removeSession(79)) - Session 21ec0585-6839-4422-bf19-7693d3ddd76e is removed. Apr 6, 2015 2:59:15 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Apr 6, 2015 2:59:15 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Apr 6, 2015 2:59:35 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Apr 6, 2015 2:59:35 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Apr 6, 2015 2:59:35 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Apr 6, 2015 2:59:35 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Apr 6, 2015 2:59:35 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Apr 6, 2015 2:59:38 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers 2015-04-06 03:13:24,265 INFO: org.apache.tajo.worker.TajoWorker (run(532)) - ============================================ 2015-04-06 03:13:24,266 INFO: org.apache.tajo.master.TajoMaster (run(575)) - ============================================ 2015-04-06 03:13:24,269 INFO: org.apache.tajo.worker.TajoWorker (run(533)) - TajoWorker received SIGINT Signal 2015-04-06 03:13:24,269 INFO: org.apache.tajo.worker.TajoWorker (run(534)) - ============================================ 2015-04-06 03:13:24,269 INFO: org.apache.tajo.master.TajoMaster (run(576)) - TajoMaster received SIGINT Signal 2015-04-06 03:13:24,269 INFO: org.apache.tajo.master.TajoMaster (run(577)) - ============================================ 2015-04-06 03:13:24,270 WARN: org.apache.hadoop.hdfs.DFSClient (completeFile(2275)) - Caught exception java.lang.InterruptedException: sleep interrupted at java.lang.Thread.sleep(Native Method) at org.apache.hadoop.hdfs.DFSOutputStream.completeFile(DFSOutputStream.java:2269) at org.apache.hadoop.hdfs.DFSOutputStream.close(DFSOutputStream.java:2234) at org.apache.hadoop.fs.FSDataOutputStream$PositionCache.close(FSDataOutputStream.java:72) at org.apache.hadoop.fs.FSDataOutputStream.close(FSDataOutputStream.java:106) at org.apache.hadoop.io.IOUtils.cleanup(IOUtils.java:237) at org.apache.tajo.util.history.HistoryWriter$WriterThread.writeQueryHistory(HistoryWriter.java:378) at org.apache.tajo.util.history.HistoryWriter$WriterThread.writeHistory(HistoryWriter.java:315) at org.apache.tajo.util.history.HistoryWriter$WriterThread.run(HistoryWriter.java:231) 2015-04-06 03:13:24,272 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2015-04-06 03:13:24,273 INFO: org.apache.tajo.worker.WorkerHeartbeatService (run(227)) - Worker Resource Heartbeat Thread stopped. 2015-04-06 03:13:24,273 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(376)) - Saving query unit: hdfs://localhost:45590/tmp/tajo-jenkins/staging/history/20150406/query-detail/q_1428288782453_0944/eb_1428288782453_0944_000001.hist 2015-04-06 03:13:24,273 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:11906) shutdown 2015-04-06 03:13:24,275 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (stop(110)) - QueryMasterManagerService stopped 2015-04-06 03:13:24,275 INFO: org.apache.tajo.session.SessionManager (removeSession(79)) - Session 07863ec0-3549-4d63-917d-425b0dad677c is removed. 2015-04-06 03:13:24,275 INFO: org.apache.tajo.querymaster.QueryMaster (run(468)) - QueryMaster heartbeat thread stopped 2015-04-06 03:13:24,276 INFO: org.apache.tajo.querymaster.QueryMaster (stop(170)) - QueryMaster stopped 2015-04-06 03:13:24,276 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(99)) - TajoWorkerClientService stopping 2015-04-06 03:13:24,278 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:11905) shutdown 2015-04-06 03:13:24,279 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(103)) - TajoWorkerClientService stopped 2015-04-06 03:13:24,279 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:11904) shutdown 2015-04-06 03:13:24,279 INFO: org.apache.tajo.worker.TajoWorkerManagerService (stop(97)) - TajoWorkerManagerService stopped 2015-04-06 03:13:24,280 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(373)) - TajoWorker main thread exiting 2015-04-06 03:13:24,293 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:59176 is added to blk_1073747043_6219{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-cefa959b-4cc9-49b3-9f90-da8daf6d0767:NORMAL:127.0.0.1:59176|RBW]]} size 0 2015-04-06 03:13:24,296 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(376)) - Saving query unit: hdfs://localhost:45590/tmp/tajo-jenkins/staging/history/20150406/query-detail/q_1428288782453_0944/eb_1428288782453_0944_000003.hist 2015-04-06 03:13:24,300 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:59176 is added to blk_1073747044_6220{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-efba173d-af80-429e-8d9b-3fcdfa22963a:NORMAL:127.0.0.1:59176|FINALIZED]]} size 0 2015-04-06 03:13:24,301 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(376)) - Saving query unit: hdfs://localhost:45590/tmp/tajo-jenkins/staging/history/20150406/query-detail/q_1428288782453_0944/eb_1428288782453_0944_000004.hist 2015-04-06 03:13:24,305 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:59176 is added to blk_1073747045_6221{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-cefa959b-4cc9-49b3-9f90-da8daf6d0767:NORMAL:127.0.0.1:59176|RBW]]} size 0 2015-04-06 03:13:24,306 INFO: org.apache.tajo.util.history.HistoryWriter (run(268)) - HistoryWriter_asf906.gq1.ygridcore.net_11904 stopped. 2015-04-06 03:13:24,320 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:59176 is added to blk_1073741834_1010{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-efba173d-af80-429e-8d9b-3fcdfa22963a:NORMAL:127.0.0.1:59176|RBW]]} size 414 2015-04-06 03:13:24,379 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:11903) shutdown 2015-04-06 03:13:24,379 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped. 2015-04-06 03:13:24,380 INFO: org.apache.tajo.catalog.CatalogServer (stop(198)) - Catalog Server (127.0.0.1:11900) shutdown 2015-04-06 03:13:24,380 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:11900) shutdown 2015-04-06 03:13:24,381 INFO: org.apache.tajo.util.history.HistoryWriter (run(268)) - HistoryWriter_127.0.0.1_11902 stopped. 2015-04-06 03:13:24,382 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2015-04-06 03:13:24,382 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:11902) shutdown 2015-04-06 03:13:24,382 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:11901) shutdown 2015-04-06 03:13:24,383 ERROR: org.apache.tajo.master.rm.TajoWorkerResourceManager (run(346)) - java.lang.InterruptedException 2015-04-06 03:13:24,383 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:11899) shutdown 2015-04-06 03:13:24,383 INFO: org.apache.tajo.master.TajoMaster (stop(409)) - Tajo Master main thread exiting Results : Failed tests: TestKillQuery.testIgnoreStageStateFromKilled:174 expected:<QUERY_KILLED> but was:<QUERY_ERROR> Tests run: 1440, Failures: 1, Errors: 0, Skipped: 0 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Tajo Main ......................................... SUCCESS [ 1.572 s] [INFO] Tajo Project POM .................................. SUCCESS [ 1.113 s] [INFO] Tajo Maven Plugins ................................ SUCCESS [ 2.665 s] [INFO] Tajo Common ....................................... SUCCESS [ 25.861 s] [INFO] Tajo Algebra ...................................... SUCCESS [ 1.308 s] [INFO] Tajo Catalog Common ............................... SUCCESS [ 6.774 s] [INFO] Tajo Plan ......................................... SUCCESS [ 5.945 s] [INFO] Tajo Rpc Common ................................... SUCCESS [ 0.449 s] [INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 20.411 s] [INFO] Tajo Catalog Client ............................... SUCCESS [ 1.040 s] [INFO] Tajo Catalog Server ............................... SUCCESS [ 10.021 s] [INFO] Tajo Storage Common ............................... SUCCESS [ 11.020 s] [INFO] Tajo HDFS Storage ................................. SUCCESS [ 44.128 s] [INFO] Tajo HBase Storage ................................ SUCCESS [ 3.085 s] [INFO] Tajo PullServer ................................... SUCCESS [ 0.897 s] [INFO] Tajo Client ....................................... SUCCESS [ 3.072 s] [INFO] Tajo CLI tools .................................... SUCCESS [ 0.678 s] [INFO] Tajo JDBC Driver .................................. SUCCESS [ 2.889 s] [INFO] ASM (thirdparty) .................................. SUCCESS [ 0.578 s] [INFO] Tajo RESTful Container ............................ SUCCESS [ 3.285 s] [INFO] Tajo Core ......................................... FAILURE [20:43 min] [INFO] Tajo RPC .......................................... 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:11 min [INFO] Finished at: 2015-04-06T03:13:24+00:00 [INFO] Final Memory: 103M/1076M [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-1338
