See <https://builds.apache.org/job/Tajo-master-build/581/changes>
Changes: [blrunner] TAJO-1260: Add ALTER TABLE ADD/DROP PARTITION statement to parser (jaehwa) ------------------------------------------ [...truncated 381913 lines...] 2015-02-05 06:15:54,567 INFO: org.apache.tajo.worker.Task (getFetchRunners(714)) - Create a new Fetcher with storeChunk: (start=0, length=30, fromRemote=false, ebId=eb_1423116096122_0901_000001) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/6dd4c2a2-41fe-4050-80bc-9630ae3edd3d/tajo-localdir/q_1423116096122_0901/output/1/hash-shuffle/0/0> 2015-02-05 06:15:54,568 INFO: org.apache.tajo.worker.Task (getFetchRunners(686)) - Try to get local file chunk at local host 2015-02-05 06:15:54,568 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(728)) - getLocalStoredFileChunk starts 2015-02-05 06:15:54,568 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(763)) - PullServer request param: shuffleType=h, sid=2, partId=0, taskIds=null 2015-02-05 06:15:54,568 INFO: org.apache.tajo.worker.Task (getFetchRunners(714)) - Create a new Fetcher with storeChunk: (start=0, length=40, fromRemote=false, ebId=eb_1423116096122_0901_000002) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/6dd4c2a2-41fe-4050-80bc-9630ae3edd3d/tajo-localdir/q_1423116096122_0901/output/2/hash-shuffle/0/0> 2015-02-05 06:15:54,568 INFO: org.apache.tajo.worker.Fetcher (get(123)) - Get pseudo fetch from local host 2015-02-05 06:15:54,568 INFO: org.apache.tajo.worker.Task (run(608)) - Add a new FileChunk to local chunk list 2015-02-05 06:15:54,568 INFO: org.apache.tajo.worker.Fetcher (get(123)) - Get pseudo fetch from local host 2015-02-05 06:15:54,569 INFO: org.apache.tajo.worker.Task (run(608)) - Add a new FileChunk to local chunk list 2015-02-05 06:15:54,568 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1423116096122_0901_000003_000000_00 is changed to TA_RUNNING 2015-02-05 06:15:54,569 INFO: org.apache.tajo.worker.Task (waitForFetch(360)) - ta_1423116096122_0901_000003_000000_00 All fetches are done! 2015-02-05 06:15:54,569 INFO: org.apache.tajo.worker.Task (localizeFetchedData(564)) - One local chunk is added to listTablets 2015-02-05 06:15:54,570 INFO: org.apache.tajo.worker.Task (localizeFetchedData(564)) - One local chunk is added to listTablets 2015-02-05 06:15:54,570 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createFullOuterHashJoinPlan(617)) - Full Outer Join (6) chooses [Hash Join] 2015-02-05 06:15:54,571 INFO: org.apache.tajo.engine.planner.physical.RangeShuffleFileWriteExec (init(76)) - Output data directory: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/6dd4c2a2-41fe-4050-80bc-9630ae3edd3d/tajo-localdir/q_1423116096122_0901/output/3/0_0/output> 2015-02-05 06:15:54,591 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) - [ta_1423116096122_0901_000003_000000_00] Chunks creation time: 0 msec 2015-02-05 06:15:54,595 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1423116096122_0901_000003_000000_00 is changed to TA_SUCCEEDED 2015-02-05 06:15:54,596 INFO: org.apache.tajo.worker.Task (run(455)) - ta_1423116096122_0901_000003_000000_00 completed. Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0 2015-02-05 06:15:54,596 INFO: org.apache.tajo.worker.TaskRunner (run(211)) - Request GetTask: eb_1423116096122_0901_000003,container_1423116096122_0901_01_003322 2015-02-05 06:15:54,596 INFO: org.apache.tajo.querymaster.Stage (transition(1338)) - Stage finalize - eb_1423116096122_0901_000003 (total=1, success=1, killed=0) 2015-02-05 06:15:54,596 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(109)) - TaskScheduler schedulingThread stopped 2015-02-05 06:15:54,597 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(152)) - Task Scheduler stopped 2015-02-05 06:15:54,597 INFO: org.apache.tajo.worker.TaskRunner (run(249)) - Received ShouldDie flag:eb_1423116096122_0901_000003,container_1423116096122_0901_01_003322 2015-02-05 06:15:54,597 INFO: org.apache.tajo.querymaster.QueryMaster (cleanupExecutionBlock(180)) - cleanup executionBlocks: eb_1423116096122_0901_000001,eb_1423116096122_0901_000002 2015-02-05 06:15:54,597 INFO: org.apache.tajo.worker.TaskRunner (stop(151)) - Stop TaskRunner: eb_1423116096122_0901_000003,container_1423116096122_0901_01_003322 2015-02-05 06:15:54,597 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTaskRunner(116)) - Stop Task:eb_1423116096122_0901_000003,container_1423116096122_0901_01_003322 2015-02-05 06:15:54,598 INFO: org.apache.tajo.querymaster.Stage (transition(1344)) - eb_1423116096122_0901_000003, waiting for shuffle reports. expected Tasks:1 2015-02-05 06:15:54,599 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(513)) - Release Resource: 0.5,512 2015-02-05 06:15:54,599 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(165)) - ======================== Processing eb_1423116096122_0901_000003 of type STOP 2015-02-05 06:15:54,599 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(132)) - Close HashShuffleAppender:eb_1423116096122_0901_000003, not a hash shuffle 2015-02-05 06:15:54,599 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(132)) - Close HashShuffleAppender:eb_1423116096122_0901_000003, not a hash shuffle 2015-02-05 06:15:54,599 INFO: org.apache.tajo.querymaster.Stage (transition(1324)) - eb_1423116096122_0901_000003, Finalized shuffle reports: 1 2015-02-05 06:15:54,600 INFO: org.apache.tajo.querymaster.Stage (transition(1388)) - Stage completed - eb_1423116096122_0901_000003 (total=1, success=1, killed=0) 2015-02-05 06:15:54,600 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(203)) - Stopped execution block:eb_1423116096122_0901_000003 2015-02-05 06:15:54,600 INFO: org.apache.tajo.querymaster.Query (handle(712)) - Processing q_1423116096122_0901 of type STAGE_COMPLETED 2015-02-05 06:15:54,600 INFO: org.apache.tajo.querymaster.Stage (initTaskScheduler(846)) - org.apache.tajo.querymaster.DefaultTaskScheduler is chosen for the task scheduling for eb_1423116096122_0901_000004 2015-02-05 06:15:54,600 INFO: org.apache.tajo.querymaster.Query (executeNextBlock(632)) - Scheduling Stage:eb_1423116096122_0901_000004 2015-02-05 06:15:54,600 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1008)) - eb_1423116096122_0901_000004, Table's volume is approximately 1 MB 2015-02-05 06:15:54,601 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1012)) - eb_1423116096122_0901_000004, The determined number of non-leaf tasks is 1 2015-02-05 06:15:54,601 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleRangeShuffledFetches(696)) - eb_1423116096122_0901_000004, Try to divide [(0=>1, 1=>1), (0=>5, 1=>3)) into 1 sub ranges (total units: 1) 2015-02-05 06:15:54,601 INFO: org.apache.tajo.engine.utils.TupleUtil (setMaxRangeIfNull(111)) - Set null into range: default.orders.o_orderkey, current tuple is (0=>5, 1=>NULL) 2015-02-05 06:15:54,602 INFO: org.apache.tajo.querymaster.Stage (run(798)) - 1 objects are scheduled 2015-02-05 06:15:54,602 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (start(91)) - Start TaskScheduler 2015-02-05 06:15:54,602 INFO: org.apache.tajo.worker.TajoResourceAllocator (calculateNumRequestContainers(102)) - CalculateNumberRequestContainer - Number of Tasks=1, Number of Cluster Slots=1 2015-02-05 06:15:54,602 INFO: org.apache.tajo.querymaster.Stage (allocateContainers(1060)) - Request Container for eb_1423116096122_0901_000004 containers=1 2015-02-05 06:15:54,602 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(256)) - Start TajoWorkerAllocationThread 2015-02-05 06:15:54,603 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(369)) - Stop TajoWorkerAllocationThread 2015-02-05 06:15:54,603 INFO: org.apache.tajo.querymaster.Stage (transition(1171)) - Stage (eb_1423116096122_0901_000004) has 1 containers! 2015-02-05 06:15:54,605 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(165)) - ======================== Processing eb_1423116096122_0901_000004 of type START 2015-02-05 06:15:54,605 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(122)) - Tajo Root Dir: hdfs://localhost:37220/tajo 2015-02-05 06:15:54,605 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(123)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/6dd4c2a2-41fe-4050-80bc-9630ae3edd3d/tajo-localdir> 2015-02-05 06:15:54,605 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(126)) - QueryMaster Address:asf906.gq1.ygridcore.net/67.195.81.150:31033 2015-02-05 06:15:54,606 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(181)) - Start TaskRunner:eb_1423116096122_0901_000004,container_1423116096122_0901_01_003323 2015-02-05 06:15:54,607 INFO: org.apache.tajo.worker.TaskRunner (init(121)) - TaskRunner basedir is created (<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/6dd4c2a2-41fe-4050-80bc-9630ae3edd3d/tajo-localdir/q_1423116096122_0901/output/4)> 2015-02-05 06:15:54,607 INFO: org.apache.tajo.worker.TaskRunner (run(180)) - TaskRunner startup 2015-02-05 06:15:54,607 INFO: org.apache.tajo.worker.TaskRunner (run(211)) - Request GetTask: eb_1423116096122_0901_000004,container_1423116096122_0901_01_003323 2015-02-05 06:15:54,609 INFO: org.apache.tajo.worker.TaskRunner (run(255)) - Accumulated Received Task: 1 2015-02-05 06:15:54,609 INFO: org.apache.tajo.worker.TaskRunner (run(264)) - Initializing: ta_1423116096122_0901_000004_000000_00 2015-02-05 06:15:54,609 INFO: org.apache.tajo.storage.FileStorageManager (getAppenderFilePath(283)) - Output File Path: hdfs://localhost:37220/tmp/tajo-jenkins/staging/q_1423116096122_0901/RESULT/part-04-000000-000 2015-02-05 06:15:54,609 INFO: org.apache.tajo.worker.Task (<init>(155)) - Output File Path: hdfs://localhost:37220/tmp/tajo-jenkins/staging/q_1423116096122_0901/RESULT/part-04-000000-000 2015-02-05 06:15:54,609 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1423116096122_0901_000004_000000_00 is changed to TA_PENDING 2015-02-05 06:15:54,610 INFO: org.apache.tajo.worker.Task (<init>(162)) - ================================== 2015-02-05 06:15:54,610 INFO: org.apache.tajo.worker.Task (<init>(163)) - * Stage ta_1423116096122_0901_000004_000000_00 is initialized 2015-02-05 06:15:54,610 INFO: org.apache.tajo.worker.Task (<init>(164)) - * InterQuery: false, Fragments (num: 1), Fetches (total:1) : 2015-02-05 06:15:54,610 INFO: org.apache.tajo.worker.Task (<init>(174)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/6dd4c2a2-41fe-4050-80bc-9630ae3edd3d/tajo-localdir/q_1423116096122_0901/output/4/0_0> 2015-02-05 06:15:54,610 INFO: org.apache.tajo.worker.Task (<init>(179)) - ================================== 2015-02-05 06:15:54,612 INFO: org.apache.tajo.worker.Task (init(197)) - the directory is created <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/6dd4c2a2-41fe-4050-80bc-9630ae3edd3d/tajo-localdir/q_1423116096122_0901/in/eb_1423116096122_0901_000004/0/0/eb_1423116096122_0901_000003> 2015-02-05 06:15:54,614 INFO: org.apache.tajo.worker.Task (getFetchRunners(686)) - Try to get local file chunk at local host 2015-02-05 06:15:54,614 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(728)) - getLocalStoredFileChunk starts 2015-02-05 06:15:54,614 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(763)) - PullServer request param: shuffleType=r, sid=3, partId=0, taskIds=[0_0] 2015-02-05 06:15:54,631 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(712)) - BSTIndex is loaded from disk ((0=>1, 1=>1), (0=>5, 1=>NULL) 2015-02-05 06:15:54,631 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(736)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/6dd4c2a2-41fe-4050-80bc-9630ae3edd3d/tajo-localdir/q_1423116096122_0901/output/3/0_0/output/output> (start=(0=>1, 1=>1), end=(0=>5, 1=>NULL), last=true) 2015-02-05 06:15:54,631 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(805)) - 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/6dd4c2a2-41fe-4050-80bc-9630ae3edd3d/tajo-localdir/q_1423116096122_0901/output/3/0_0/output/output> 2015-02-05 06:15:54,631 INFO: org.apache.tajo.worker.Task (getFetchRunners(714)) - Create a new Fetcher with storeChunk: (start=0, length=49, fromRemote=false, ebId=eb_1423116096122_0901_000003) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/6dd4c2a2-41fe-4050-80bc-9630ae3edd3d/tajo-localdir/q_1423116096122_0901/output/3/0_0/output/output> 2015-02-05 06:15:54,632 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1423116096122_0901_000004_000000_00 is changed to TA_RUNNING 2015-02-05 06:15:54,632 INFO: org.apache.tajo.worker.Fetcher (get(123)) - Get pseudo fetch from local host 2015-02-05 06:15:54,632 INFO: org.apache.tajo.worker.Task (run(608)) - Add a new FileChunk to local chunk list 2015-02-05 06:15:54,632 INFO: org.apache.tajo.worker.Task (waitForFetch(360)) - ta_1423116096122_0901_000004_000000_00 All fetches are done! 2015-02-05 06:15:54,632 INFO: org.apache.tajo.worker.Task (localizeFetchedData(564)) - One local chunk is added to listTablets 2015-02-05 06:15:54,636 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) - [ta_1423116096122_0901_000004_000000_00] Total merge time: 0 msec 2015-02-05 06:15:54,637 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (close(860)) - Delete file: "fragment": {"id": "eb_1423116096122_0901_000003", "path": <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/6dd4c2a2-41fe-4050-80bc-9630ae3edd3d/tajo-localdir/q_1423116096122_0901/output/3/0_0/output/output";,> "start": 0,"length": 49} 2015-02-05 06:15:54,642 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43950 is added to blk_1073746872_6048{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-8a3db19a-30ff-4081-870d-1657554f3fac:NORMAL|RBW]]} size 0 2015-02-05 06:15:54,643 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1423116096122_0901_000004_000000_00 is changed to TA_SUCCEEDED 2015-02-05 06:15:54,643 INFO: org.apache.tajo.worker.Task (run(455)) - ta_1423116096122_0901_000004_000000_00 completed. Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0 2015-02-05 06:15:54,643 INFO: org.apache.tajo.worker.TaskRunner (run(211)) - Request GetTask: eb_1423116096122_0901_000004,container_1423116096122_0901_01_003323 2015-02-05 06:15:54,644 INFO: org.apache.tajo.querymaster.Stage (transition(1338)) - Stage finalize - eb_1423116096122_0901_000004 (total=1, success=1, killed=0) 2015-02-05 06:15:54,644 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(109)) - TaskScheduler schedulingThread stopped 2015-02-05 06:15:54,644 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(152)) - Task Scheduler stopped 2015-02-05 06:15:54,644 INFO: org.apache.tajo.worker.TaskRunner (run(249)) - Received ShouldDie flag:eb_1423116096122_0901_000004,container_1423116096122_0901_01_003323 2015-02-05 06:15:54,644 INFO: org.apache.tajo.querymaster.QueryMaster (cleanupExecutionBlock(180)) - cleanup executionBlocks: eb_1423116096122_0901_000003 2015-02-05 06:15:54,644 INFO: org.apache.tajo.worker.TaskRunner (stop(151)) - Stop TaskRunner: eb_1423116096122_0901_000004,container_1423116096122_0901_01_003323 2015-02-05 06:15:54,644 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTaskRunner(116)) - Stop Task:eb_1423116096122_0901_000004,container_1423116096122_0901_01_003323 2015-02-05 06:15:54,645 INFO: org.apache.tajo.querymaster.Stage (transition(1344)) - eb_1423116096122_0901_000004, waiting for shuffle reports. expected Tasks:1 2015-02-05 06:15:54,646 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(513)) - Release Resource: 0.5,512 2015-02-05 06:15:54,646 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(165)) - ======================== Processing eb_1423116096122_0901_000004 of type STOP 2015-02-05 06:15:54,646 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(132)) - Close HashShuffleAppender:eb_1423116096122_0901_000004, not a hash shuffle 2015-02-05 06:15:54,646 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(132)) - Close HashShuffleAppender:eb_1423116096122_0901_000004, not a hash shuffle 2015-02-05 06:15:54,646 INFO: org.apache.tajo.querymaster.Stage (transition(1324)) - eb_1423116096122_0901_000004, Finalized shuffle reports: 1 2015-02-05 06:15:54,647 INFO: org.apache.tajo.querymaster.Stage (transition(1388)) - Stage completed - eb_1423116096122_0901_000004 (total=1, success=1, killed=0) 2015-02-05 06:15:54,647 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(203)) - Stopped execution block:eb_1423116096122_0901_000004 2015-02-05 06:15:54,647 INFO: org.apache.tajo.querymaster.Query (handle(712)) - Processing q_1423116096122_0901 of type STAGE_COMPLETED 2015-02-05 06:15:54,647 INFO: org.apache.tajo.querymaster.Query (handle(712)) - Processing q_1423116096122_0901 of type QUERY_COMPLETED 2015-02-05 06:15:54,648 INFO: org.apache.tajo.querymaster.Query (handle(730)) - q_1423116096122_0901 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED 2015-02-05 06:15:54,648 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(276)) - Query completion notified from q_1423116096122_0901 2015-02-05 06:15:54,648 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(287)) - Query final state: QUERY_SUCCEEDED 2015-02-05 06:15:54,648 INFO: org.apache.tajo.querymaster.QueryMasterTask (stop(183)) - Stopping QueryMasterTask:q_1423116096122_0901 2015-02-05 06:15:54,649 INFO: org.apache.tajo.master.QueryInProgress (heartbeat(196)) - Received QueryMaster heartbeat:q_1423116096122_0901,state=QUERY_SUCCEEDED,progress=1.0, queryMaster=asf906.gq1.ygridcore.net 2015-02-05 06:15:54,649 INFO: org.apache.tajo.master.QueryManager (stopQuery(238)) - Stop QueryInProgress:q_1423116096122_0901 2015-02-05 06:15:54,649 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(97)) - ========================================================= 2015-02-05 06:15:54,649 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(98)) - Stop query:q_1423116096122_0901 2015-02-05 06:15:54,649 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(513)) - Release Resource: 0.0,512 2015-02-05 06:15:54,649 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseQueryMaster(535)) - Released QueryMaster (q_1423116096122_0901) resource. 2015-02-05 06:15:54,649 INFO: org.apache.tajo.querymaster.QueryMasterTask (stop(196)) - Stopped QueryMasterTask:q_1423116096122_0901 2015-02-05 06:15:54,650 INFO: org.apache.tajo.querymaster.QueryMaster (cleanup(204)) - cleanup query resources : q_1423116096122_0901 2015-02-05 06:15:54,653 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(359)) - Saving query summary: hdfs://localhost:37220/tmp/tajo-jenkins/staging/history/20150205/query-detail/q_1423116096122_0901/query.hist 2015-02-05 06:15:54,659 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43950 is added to blk_1073746873_6049{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-2429708e-741e-4754-8307-a24e7093ab37:NORMAL|RBW]]} size 0 2015-02-05 06:15:54,662 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(373)) - Saving query unit: hdfs://localhost:37220/tmp/tajo-jenkins/staging/history/20150205/query-detail/q_1423116096122_0901/eb_1423116096122_0901_000003.hist 2015-02-05 06:15:54,666 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43950 is added to blk_1073746874_6050{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-8a3db19a-30ff-4081-870d-1657554f3fac:NORMAL|RBW]]} size 0 2015-02-05 06:15:54,669 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(373)) - Saving query unit: hdfs://localhost:37220/tmp/tajo-jenkins/staging/history/20150205/query-detail/q_1423116096122_0901/eb_1423116096122_0901_000001.hist 2015-02-05 06:15:54,672 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43950 is added to blk_1073746875_6051{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-2429708e-741e-4754-8307-a24e7093ab37:NORMAL|RBW]]} size 0 2015-02-05 06:15:54,675 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(373)) - Saving query unit: hdfs://localhost:37220/tmp/tajo-jenkins/staging/history/20150205/query-detail/q_1423116096122_0901/eb_1423116096122_0901_000004.hist 2015-02-05 06:15:54,679 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43950 is added to blk_1073746876_6052{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-8a3db19a-30ff-4081-870d-1657554f3fac:NORMAL|RBW]]} size 0 2015-02-05 06:15:54,681 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(373)) - Saving query unit: hdfs://localhost:37220/tmp/tajo-jenkins/staging/history/20150205/query-detail/q_1423116096122_0901/eb_1423116096122_0901_000002.hist 2015-02-05 06:15:54,685 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43950 is added to blk_1073746877_6053{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-2429708e-741e-4754-8307-a24e7093ab37:NORMAL|RBW]]} size 0 2015-02-05 06:15:54,697 INFO: org.apache.tajo.session.SessionManager (removeSession(79)) - Session b109e2d9-4d3b-4e84-951e-84685bd11e54 is removed. 2015-02-05 06:15:54,703 INFO: org.apache.tajo.session.SessionManager (createSession(73)) - Session 57ca42fa-f342-4e75-81b4-d440c4865ba7 is created. 2015-02-05 06:15:54,706 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(150)) - Query: create table "TEST_DESC_TABLE" (col1 int4, col2 int4) 2015-02-05 06:15:54,707 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(239)) - Non Optimized Query: ----------------------------- Query Block Graph ----------------------------- |-#ROOT ----------------------------- Optimization Log: ----------------------------- 2015-02-05 06:15:54,708 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(241)) - ============================================= 2015-02-05 06:15:54,708 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(242)) - Optimized Query: ----------------------------- Query Block Graph ----------------------------- |-#ROOT ----------------------------- Optimization Log: ----------------------------- 2015-02-05 06:15:54,708 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(243)) - ============================================= 2015-02-05 06:15:54,711 INFO: org.apache.tajo.catalog.CatalogServer (createTable(605)) - relation "default.TEST_DESC_TABLE" is added to the catalog (127.0.0.1:31028) 2015-02-05 06:15:54,711 INFO: org.apache.tajo.master.exec.DDLExecutor (createTable(249)) - Table default.TEST_DESC_TABLE is created (0) 2015-02-05 06:15:54,716 INFO: org.apache.tajo.session.SessionManager (removeSession(79)) - Session 57ca42fa-f342-4e75-81b4-d440c4865ba7 is removed. Tests run: 17, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.562 sec - in org.apache.tajo.cli.tsql.TestTajoCli 2015-02-05 06:15:54,723 INFO: org.apache.tajo.worker.TajoWorker (run(600)) - ============================================ 2015-02-05 06:15:54,724 INFO: org.apache.tajo.worker.TajoWorker (run(601)) - TajoWorker received SIGINT Signal 2015-02-05 06:15:54,724 INFO: org.apache.tajo.worker.TajoWorker (run(602)) - ============================================ 2015-02-05 06:15:54,725 INFO: org.apache.tajo.master.TajoMaster (run(555)) - ============================================ 2015-02-05 06:15:54,726 INFO: org.apache.tajo.master.TajoMaster (run(556)) - TajoMaster received SIGINT Signal 2015-02-05 06:15:54,726 INFO: org.apache.tajo.master.TajoMaster (run(557)) - ============================================ 2015-02-05 06:15:54,726 INFO: org.apache.tajo.catalog.CatalogServer (stop(198)) - Catalog Server (127.0.0.1:31028) shutdown 2015-02-05 06:15:54,727 INFO: org.apache.tajo.util.history.HistoryWriter (run(265)) - HistoryWriter_asf906.gq1.ygridcore.net_31033 stopped. 2015-02-05 06:15:54,728 INFO: org.apache.tajo.util.history.HistoryCleaner (run(134)) - History cleaner stopped Feb 5, 2015 6:06:56 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Feb 5, 2015 6:06:57 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Feb 5, 2015 6:07:12 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Feb 5, 2015 6:07:12 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Feb 5, 2015 6:07:12 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Feb 5, 2015 6:07:12 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Feb 5, 2015 6:07:12 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Feb 5, 2015 6:07:15 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers 2015-02-05 06:15:54,729 INFO: org.apache.tajo.session.SessionManager (removeSession(79)) - Session efa72218-f20d-410d-901f-b25f785757dc is removed. 2015-02-05 06:15:54,732 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(126)) - Rpc (CatalogProtocol) listened on 127.0.0.1:31028) shutdown 2015-02-05 06:15:54,734 INFO: org.apache.tajo.session.SessionManager (removeSession(79)) - Session 1cf6f24f-de73-4f26-a404-447d9e07dc78 is removed. 2015-02-05 06:15:54,735 INFO: org.apache.tajo.util.history.HistoryWriter (run(265)) - HistoryWriter_127.0.0.1_31030 stopped. 2015-02-05 06:15:54,738 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43950 is added to blk_1073741835_1011{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-2429708e-741e-4754-8307-a24e7093ab37:NORMAL|RBW]]} size 1614 2015-02-05 06:15:54,740 INFO: org.apache.tajo.util.history.HistoryCleaner (run(134)) - History cleaner stopped 2015-02-05 06:15:54,740 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(126)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:31030) shutdown 2015-02-05 06:15:54,741 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(126)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:31029) shutdown 2015-02-05 06:15:54,742 ERROR: org.apache.tajo.master.rm.TajoWorkerResourceManager (run(346)) - java.lang.InterruptedException 2015-02-05 06:15:54,743 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(126)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:31027) shutdown 2015-02-05 06:15:54,743 INFO: org.apache.tajo.master.TajoMaster (stop(393)) - Tajo Master main thread exiting 2015-02-05 06:15:54,794 INFO: org.apache.tajo.worker.WorkerHeartbeatService (run(231)) - Worker Resource Heartbeat Thread stopped. 2015-02-05 06:15:54,794 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(126)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:31033) shutdown 2015-02-05 06:15:54,796 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (stop(110)) - QueryMasterManagerService stopped 2015-02-05 06:15:54,796 INFO: org.apache.tajo.querymaster.QueryMaster (run(468)) - QueryMaster heartbeat thread stopped 2015-02-05 06:15:54,796 INFO: org.apache.tajo.querymaster.QueryMaster (stop(167)) - QueryMaster stop 2015-02-05 06:15:54,796 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(99)) - TajoWorkerClientService stopping 2015-02-05 06:15:54,797 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(126)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:31032) shutdown 2015-02-05 06:15:54,797 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(103)) - TajoWorkerClientService stopped 2015-02-05 06:15:54,798 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(126)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:31031) shutdown 2015-02-05 06:15:54,798 INFO: org.apache.tajo.worker.TajoWorkerManagerService (stop(97)) - TajoWorkerManagerService stopped 2015-02-05 06:15:54,798 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(417)) - TajoWorker main thread exiting Results : Failed tests: TestKillQuery.testIgnoreStageStateFromKilled:167 expected:<QUERY_KILLED> but was:<QUERY_ERROR> Tests run: 1361, Failures: 1, Errors: 0, Skipped: 0 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Tajo Main ......................................... SUCCESS [ 1.734 s] [INFO] Tajo Project POM .................................. SUCCESS [ 1.149 s] [INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.076 s] [INFO] Tajo Common ....................................... SUCCESS [ 23.853 s] [INFO] Tajo Algebra ...................................... SUCCESS [ 1.305 s] [INFO] Tajo Catalog Common ............................... SUCCESS [ 6.357 s] [INFO] Tajo Plan ......................................... SUCCESS [ 5.802 s] [INFO] Tajo Rpc .......................................... SUCCESS [ 25.035 s] [INFO] Tajo Catalog Client ............................... SUCCESS [ 0.966 s] [INFO] Tajo Catalog Server ............................... SUCCESS [ 10.015 s] [INFO] Tajo Storage Common ............................... SUCCESS [ 10.690 s] [INFO] Tajo HDFS Storage ................................. SUCCESS [ 42.388 s] [INFO] Tajo HBase Storage ................................ SUCCESS [ 2.883 s] [INFO] Tajo PullServer ................................... SUCCESS [ 0.805 s] [INFO] Tajo Client ....................................... SUCCESS [ 3.427 s] [INFO] Tajo JDBC Driver .................................. SUCCESS [ 0.807 s] [INFO] ASM (thirdparty) .................................. SUCCESS [ 0.580 s] [INFO] Tajo Core ......................................... FAILURE [14:39 min] [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: 17:00 min [INFO] Finished at: 2015-02-05T06:15:55+00:00 [INFO] Final Memory: 88M/1054M [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-1260
