See <https://builds.apache.org/job/Tajo-master-CODEGEN-build/26/changes>
Changes: [hyunsik] TAJO-1081: Non-forwarded (simple) query shows wrong rows. ------------------------------------------ [...truncated 314306 lines...] Block Id: eb_1412235109605_0739_000003 [TERMINAL] ======================================================= 2014-10-02 07:45:37,336 INFO: org.apache.tajo.master.querymaster.Query (<init>(227)) - ======================================================= The order of execution: 1: eb_1412235109605_0739_000001 2: eb_1412235109605_0739_000002 3: eb_1412235109605_0739_000003 ======================================================= 2014-10-02 07:45:37,337 INFO: org.apache.tajo.master.TajoAsyncDispatcher (start(101)) - AsyncDispatcher started:q_1412235109605_0739 2014-10-02 07:45:37,337 INFO: org.apache.tajo.master.querymaster.Query (handle(843)) - Processing q_1412235109605_0739 of type START 2014-10-02 07:45:37,337 INFO: org.apache.tajo.master.querymaster.Query (handle(861)) - q_1412235109605_0739 Query Transitioned from QUERY_NEW to QUERY_RUNNING 2014-10-02 07:45:37,337 INFO: org.apache.tajo.master.querymaster.SubQuery (initTaskScheduler(708)) - org.apache.tajo.master.DefaultTaskScheduler is chosen for the task scheduling for eb_1412235109605_0739_000001 2014-10-02 07:45:37,340 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(385)) - Total input paths to process : 1 2014-10-02 07:45:37,341 INFO: org.apache.tajo.storage.AbstractStorageManager (getSplits(614)) - Total # of splits: 1 2014-10-02 07:45:37,341 INFO: org.apache.tajo.master.querymaster.SubQuery (run(668)) - 1 objects are scheduled 2014-10-02 07:45:37,341 INFO: org.apache.tajo.master.DefaultTaskScheduler (start(89)) - Start TaskScheduler 2014-10-02 07:45:37,342 INFO: org.apache.tajo.worker.TajoResourceAllocator (calculateNumRequestContainers(100)) - CalculateNumberRequestContainer - Number of Tasks=1, Number of Cluster Slots=1 2014-10-02 07:45:37,342 INFO: org.apache.tajo.master.querymaster.SubQuery (allocateContainers(916)) - Request Container for eb_1412235109605_0739_000001 containers=1 2014-10-02 07:45:37,342 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(252)) - Start TajoWorkerAllocationThread 2014-10-02 07:45:37,344 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(389)) - Stop TajoWorkerAllocationThread 2014-10-02 07:45:37,344 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1024)) - SubQuery (eb_1412235109605_0739_000001) has 1 containers! 2014-10-02 07:45:37,346 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(165)) - ======================== Processing eb_1412235109605_0739_000001 of type START 2014-10-02 07:45:37,346 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(122)) - Tajo Root Dir: hdfs://localhost:51752/tajo 2014-10-02 07:45:37,346 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(123)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-master-CODEGEN-build/ws/tajo-core/target/test-data/13285f42-68e7-4c9c-9d15-4a478ad3b5c5/tajo-localdir> 2014-10-02 07:45:37,346 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(126)) - QueryMaster Address:asf907.gq1.ygridcore.net/67.195.81.151:43243 2014-10-02 07:45:37,350 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(181)) - Start TaskRunner:eb_1412235109605_0739_000001,container_1412235109605_0739_01_002795 2014-10-02 07:45:37,350 INFO: org.apache.tajo.worker.TaskRunner (init(121)) - TaskRunner basedir is created (<https://builds.apache.org/job/Tajo-master-CODEGEN-build/ws/tajo-core/target/test-data/13285f42-68e7-4c9c-9d15-4a478ad3b5c5/tajo-localdir/q_1412235109605_0739/output/1)> 2014-10-02 07:45:37,350 INFO: org.apache.tajo.worker.TaskRunner (run(180)) - TaskRunner startup 2014-10-02 07:45:37,351 INFO: org.apache.tajo.worker.TaskRunner (run(211)) - Request GetTask: eb_1412235109605_0739_000001,container_1412235109605_0739_01_002795 2014-10-02 07:45:37,352 INFO: org.apache.tajo.master.DefaultTaskScheduler (allocateRackTask(734)) - Assigned Local/Rack/Total: (0/1/1), Locality: 0.00%, Rack host: asf907.gq1.ygridcore.net 2014-10-02 07:45:37,353 INFO: org.apache.tajo.worker.TaskRunner (run(255)) - Accumulated Received Task: 1 2014-10-02 07:45:37,354 INFO: org.apache.tajo.worker.TaskRunner (run(264)) - Initializing: ta_1412235109605_0739_000001_000000_00 2014-10-02 07:45:37,355 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1412235109605_0739_000001_000000_00 is changed to TA_PENDING 2014-10-02 07:45:37,355 INFO: org.apache.tajo.worker.Task (<init>(204)) - ================================== 2014-10-02 07:45:37,355 INFO: org.apache.tajo.worker.Task (<init>(205)) - * Subquery ta_1412235109605_0739_000001_000000_00 is initialized 2014-10-02 07:45:37,355 INFO: org.apache.tajo.worker.Task (<init>(206)) - * InterQuery: true, Use HASH_SHUFFLE shuffle, Fragments (num: 1), Fetches (total:0) : 2014-10-02 07:45:37,355 INFO: org.apache.tajo.worker.Task (<init>(216)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-CODEGEN-build/ws/tajo-core/target/test-data/13285f42-68e7-4c9c-9d15-4a478ad3b5c5/tajo-localdir/q_1412235109605_0739/output/1/0_0> 2014-10-02 07:45:37,356 INFO: org.apache.tajo.worker.Task (<init>(221)) - ================================== 2014-10-02 07:45:37,356 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1412235109605_0739_000001_000000_00 is changed to TA_RUNNING 2014-10-02 07:45:37,356 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createInMemoryHashAggregation(958)) - The planner chooses [Hash Aggregation] 2014-10-02 07:45:37,381 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (getAppender(99)) - Create Hash shuffle file(partId=0): <https://builds.apache.org/job/Tajo-master-CODEGEN-build/ws/tajo-core/target/test-data/13285f42-68e7-4c9c-9d15-4a478ad3b5c5/tajo-localdir/q_1412235109605_0739/output/1/hash-shuffle/0/0> 2014-10-02 07:45:37,381 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1412235109605_0739_000001_000000_00 is changed to TA_SUCCEEDED 2014-10-02 07:45:37,382 INFO: org.apache.tajo.worker.Task (run(499)) - ta_1412235109605_0739_000001_000000_00 completed. Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0 2014-10-02 07:45:37,382 INFO: org.apache.tajo.worker.TaskRunner (run(211)) - Request GetTask: eb_1412235109605_0739_000001,container_1412235109605_0739_01_002795 2014-10-02 07:45:37,382 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1088)) - [eb_1412235109605_0739_000001] Task Completion Event (Total: 1, Success: 1, Killed: 0, Failed: 0) 2014-10-02 07:45:37,382 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1190)) - subQuery completed - eb_1412235109605_0739_000001 (total=1, success=1, killed=0) 2014-10-02 07:45:37,383 INFO: org.apache.tajo.master.DefaultTaskScheduler (run(107)) - TaskScheduler schedulingThread stopped 2014-10-02 07:45:37,383 INFO: org.apache.tajo.master.DefaultTaskScheduler (stop(148)) - Task Scheduler stopped 2014-10-02 07:45:37,383 INFO: org.apache.tajo.worker.TaskRunner (run(249)) - Received ShouldDie flag:eb_1412235109605_0739_000001,container_1412235109605_0739_01_002795 2014-10-02 07:45:37,383 INFO: org.apache.tajo.master.querymaster.QueryMaster (cleanupExecutionBlock(186)) - cleanup executionBlocks: 2014-10-02 07:45:37,383 INFO: org.apache.tajo.worker.TaskRunner (stop(151)) - Stop TaskRunner: eb_1412235109605_0739_000001,container_1412235109605_0739_01_002795 2014-10-02 07:45:37,384 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTaskRunner(116)) - Stop Task:eb_1412235109605_0739_000001,container_1412235109605_0739_01_002795 2014-10-02 07:45:37,385 INFO: org.apache.tajo.master.querymaster.Query (handle(843)) - Processing q_1412235109605_0739 of type SUBQUERY_COMPLETED 2014-10-02 07:45:37,385 INFO: org.apache.tajo.master.querymaster.SubQuery (waitingIntermediateReport(1138)) - eb_1412235109605_0739_000001, waiting IntermediateReport: expectedTaskNum=0 2014-10-02 07:45:37,385 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(514)) - Release Resource: 0.5,512 2014-10-02 07:45:37,385 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(165)) - ======================== Processing eb_1412235109605_0739_000001 of type STOP 2014-10-02 07:45:37,386 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(152)) - Close HashShuffleAppender:eb_1412235109605_0739_000001, intermediates=1 2014-10-02 07:45:37,386 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(132)) - Close HashShuffleAppender:eb_1412235109605_0739_000001, not a hash shuffle 2014-10-02 07:45:37,386 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(201)) - Stopped execution block:eb_1412235109605_0739_000001 2014-10-02 07:45:37,386 INFO: org.apache.tajo.master.querymaster.SubQuery (receiveExecutionBlockReport(1162)) - eb_1412235109605_0739_000001, receiveExecutionBlockReport:1 2014-10-02 07:45:37,386 INFO: org.apache.tajo.master.querymaster.SubQuery (waitingIntermediateReport(1143)) - eb_1412235109605_0739_000001, completed waiting IntermediateReport 2014-10-02 07:45:37,387 INFO: org.apache.tajo.master.querymaster.Query (executeNextBlock(763)) - Scheduling SubQuery:eb_1412235109605_0739_000002 2014-10-02 07:45:37,387 INFO: org.apache.tajo.master.querymaster.SubQuery (initTaskScheduler(708)) - org.apache.tajo.master.DefaultTaskScheduler is chosen for the task scheduling for eb_1412235109605_0739_000002 2014-10-02 07:45:37,387 INFO: org.apache.tajo.master.querymaster.SubQuery (getNonLeafTaskNum(865)) - eb_1412235109605_0739_000002, Table's volume is approximately 1 MB 2014-10-02 07:45:37,387 INFO: org.apache.tajo.master.querymaster.SubQuery (getNonLeafTaskNum(868)) - eb_1412235109605_0739_000002, The determined number of non-leaf tasks is 1 2014-10-02 07:45:37,387 INFO: org.apache.tajo.master.querymaster.Repartitioner (scheduleHashShuffledFetches(813)) - eb_1412235109605_0739_000002, ScheduleHashShuffledFetches - Max num=1, finalFetchURI=1 2014-10-02 07:45:37,387 INFO: org.apache.tajo.master.querymaster.Repartitioner (scheduleHashShuffledFetches(817)) - eb_1412235109605_0739_000002, No Grouping Column - determinedTaskNum is set to 1 2014-10-02 07:45:37,388 INFO: org.apache.tajo.master.querymaster.Repartitioner (scheduleHashShuffledFetches(833)) - eb_1412235109605_0739_000002, DeterminedTaskNum : 1 2014-10-02 07:45:37,388 INFO: org.apache.tajo.master.querymaster.SubQuery (run(668)) - 1 objects are scheduled 2014-10-02 07:45:37,388 INFO: org.apache.tajo.master.DefaultTaskScheduler (start(89)) - Start TaskScheduler 2014-10-02 07:45:37,388 INFO: org.apache.tajo.worker.TajoResourceAllocator (calculateNumRequestContainers(100)) - CalculateNumberRequestContainer - Number of Tasks=1, Number of Cluster Slots=1 2014-10-02 07:45:37,388 INFO: org.apache.tajo.master.querymaster.SubQuery (allocateContainers(916)) - Request Container for eb_1412235109605_0739_000002 containers=1 2014-10-02 07:45:37,389 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(252)) - Start TajoWorkerAllocationThread 2014-10-02 07:45:37,390 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(389)) - Stop TajoWorkerAllocationThread 2014-10-02 07:45:37,390 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1024)) - SubQuery (eb_1412235109605_0739_000002) has 1 containers! 2014-10-02 07:45:37,391 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(165)) - ======================== Processing eb_1412235109605_0739_000002 of type START 2014-10-02 07:45:37,392 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(122)) - Tajo Root Dir: hdfs://localhost:51752/tajo 2014-10-02 07:45:37,392 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(123)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-master-CODEGEN-build/ws/tajo-core/target/test-data/13285f42-68e7-4c9c-9d15-4a478ad3b5c5/tajo-localdir> 2014-10-02 07:45:37,392 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(126)) - QueryMaster Address:asf907.gq1.ygridcore.net/67.195.81.151:43243 2014-10-02 07:45:37,394 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(181)) - Start TaskRunner:eb_1412235109605_0739_000002,container_1412235109605_0739_01_002796 2014-10-02 07:45:37,395 INFO: org.apache.tajo.worker.TaskRunner (init(121)) - TaskRunner basedir is created (<https://builds.apache.org/job/Tajo-master-CODEGEN-build/ws/tajo-core/target/test-data/13285f42-68e7-4c9c-9d15-4a478ad3b5c5/tajo-localdir/q_1412235109605_0739/output/2)> 2014-10-02 07:45:37,395 INFO: org.apache.tajo.worker.TaskRunner (run(180)) - TaskRunner startup 2014-10-02 07:45:37,395 INFO: org.apache.tajo.worker.TaskRunner (run(211)) - Request GetTask: eb_1412235109605_0739_000002,container_1412235109605_0739_01_002796 2014-10-02 07:45:37,397 INFO: org.apache.tajo.worker.TaskRunner (run(255)) - Accumulated Received Task: 1 2014-10-02 07:45:37,397 INFO: org.apache.tajo.worker.TaskRunner (run(264)) - Initializing: ta_1412235109605_0739_000002_000000_00 2014-10-02 07:45:37,398 INFO: org.apache.tajo.worker.Task (<init>(197)) - Output File Path: hdfs://localhost:51752/tmp/tajo-jenkins/staging/q_1412235109605_0739/RESULT/part-02-000000-000 2014-10-02 07:45:37,398 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1412235109605_0739_000002_000000_00 is changed to TA_PENDING 2014-10-02 07:45:37,398 INFO: org.apache.tajo.worker.Task (<init>(204)) - ================================== 2014-10-02 07:45:37,398 INFO: org.apache.tajo.worker.Task (<init>(205)) - * Subquery ta_1412235109605_0739_000002_000000_00 is initialized 2014-10-02 07:45:37,398 INFO: org.apache.tajo.worker.Task (<init>(206)) - * InterQuery: false, Fragments (num: 1), Fetches (total:1) : 2014-10-02 07:45:37,398 INFO: org.apache.tajo.worker.Task (<init>(216)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-CODEGEN-build/ws/tajo-core/target/test-data/13285f42-68e7-4c9c-9d15-4a478ad3b5c5/tajo-localdir/q_1412235109605_0739/output/2/0_0> 2014-10-02 07:45:37,399 INFO: org.apache.tajo.worker.Task (<init>(221)) - ================================== 2014-10-02 07:45:37,400 INFO: org.apache.tajo.worker.Task (init(239)) - the directory is created <https://builds.apache.org/job/Tajo-master-CODEGEN-build/ws/tajo-core/target/test-data/13285f42-68e7-4c9c-9d15-4a478ad3b5c5/tajo-localdir/q_1412235109605_0739/in/eb_1412235109605_0739_000002/0/0/eb_1412235109605_0739_000001> 2014-10-02 07:45:37,406 INFO: org.apache.tajo.worker.Task (getFetchRunners(726)) - Try to get local file chunk at local host 2014-10-02 07:45:37,407 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(768)) - getLocalStoredFileChunk starts 2014-10-02 07:45:37,407 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(803)) - PullServer request param: shuffleType=h, sid=1, partId=0, taskIds=null 2014-10-02 07:45:37,407 INFO: org.apache.tajo.worker.Task (getFetchRunners(754)) - Create a new Fetcher with storeChunk: (start=0, length=12, fromRemote=false, ebId=eb_1412235109605_0739_000001) <https://builds.apache.org/job/Tajo-master-CODEGEN-build/ws/tajo-core/target/test-data/13285f42-68e7-4c9c-9d15-4a478ad3b5c5/tajo-localdir/q_1412235109605_0739/output/1/hash-shuffle/0/0> 2014-10-02 07:45:37,407 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1412235109605_0739_000002_000000_00 is changed to TA_RUNNING 2014-10-02 07:45:37,407 INFO: org.apache.tajo.worker.Fetcher (get(123)) - Get pseudo fetch from local host 2014-10-02 07:45:37,408 INFO: org.apache.tajo.worker.Task (run(649)) - Add a new FileChunk to local chunk list 2014-10-02 07:45:37,408 INFO: org.apache.tajo.worker.Task (waitForFetch(404)) - ta_1412235109605_0739_000002_000000_00 All fetches are done! 2014-10-02 07:45:37,408 INFO: org.apache.tajo.worker.Task (localizeFetchedData(605)) - One local chunk is added to listTablets 2014-10-02 07:45:37,408 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createInMemoryHashAggregation(958)) - The planner chooses [Hash Aggregation] 2014-10-02 07:45:37,437 INFO: BlockStateChange (logAddStoredBlock(2383)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58911 is added to blk_1073743081_2257{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-a2122840-bae9-4fd4-9376-73bdd4ee6b96:NORMAL|RBW]]} size 0 2014-10-02 07:45:37,438 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1412235109605_0739_000002_000000_00 is changed to TA_SUCCEEDED 2014-10-02 07:45:37,438 INFO: org.apache.tajo.worker.Task (run(499)) - ta_1412235109605_0739_000002_000000_00 completed. Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0 2014-10-02 07:45:37,439 INFO: org.apache.tajo.worker.TaskRunner (run(211)) - Request GetTask: eb_1412235109605_0739_000002,container_1412235109605_0739_01_002796 2014-10-02 07:45:37,439 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1088)) - [eb_1412235109605_0739_000002] Task Completion Event (Total: 1, Success: 1, Killed: 0, Failed: 0) 2014-10-02 07:45:37,439 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1190)) - subQuery completed - eb_1412235109605_0739_000002 (total=1, success=1, killed=0) 2014-10-02 07:45:37,439 INFO: org.apache.tajo.master.DefaultTaskScheduler (run(107)) - TaskScheduler schedulingThread stopped 2014-10-02 07:45:37,439 INFO: org.apache.tajo.master.DefaultTaskScheduler (stop(148)) - Task Scheduler stopped 2014-10-02 07:45:37,440 INFO: org.apache.tajo.worker.TaskRunner (run(249)) - Received ShouldDie flag:eb_1412235109605_0739_000002,container_1412235109605_0739_01_002796 2014-10-02 07:45:37,440 INFO: org.apache.tajo.master.querymaster.QueryMaster (cleanupExecutionBlock(186)) - cleanup executionBlocks: eb_1412235109605_0739_000001 2014-10-02 07:45:37,440 INFO: org.apache.tajo.worker.TaskRunner (stop(151)) - Stop TaskRunner: eb_1412235109605_0739_000002,container_1412235109605_0739_01_002796 2014-10-02 07:45:37,440 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTaskRunner(116)) - Stop Task:eb_1412235109605_0739_000002,container_1412235109605_0739_01_002796 2014-10-02 07:45:37,441 INFO: org.apache.tajo.master.querymaster.Query (handle(843)) - Processing q_1412235109605_0739 of type SUBQUERY_COMPLETED 2014-10-02 07:45:37,441 INFO: org.apache.tajo.master.querymaster.Query (handle(843)) - Processing q_1412235109605_0739 of type QUERY_COMPLETED 2014-10-02 07:45:37,441 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(514)) - Release Resource: 0.5,512 2014-10-02 07:45:37,441 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(165)) - ======================== Processing eb_1412235109605_0739_000002 of type STOP 2014-10-02 07:45:37,442 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(132)) - Close HashShuffleAppender:eb_1412235109605_0739_000002, not a hash shuffle 2014-10-02 07:45:37,442 INFO: org.apache.tajo.master.querymaster.SubQuery (receiveExecutionBlockReport(1162)) - eb_1412235109605_0739_000002, receiveExecutionBlockReport:1 2014-10-02 07:45:37,442 INFO: org.apache.tajo.master.querymaster.Query (handle(861)) - q_1412235109605_0739 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED 2014-10-02 07:45:37,442 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (handle(323)) - Query completion notified from q_1412235109605_0739 2014-10-02 07:45:37,442 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (handle(334)) - Query final state: QUERY_SUCCEEDED 2014-10-02 07:45:37,443 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (stop(188)) - Stopping QueryMasterTask:q_1412235109605_0739 2014-10-02 07:45:37,443 INFO: org.apache.tajo.master.querymaster.QueryInProgress (heartbeat(265)) - Received QueryMaster heartbeat:q_1412235109605_0739,state=QUERY_SUCCEEDED,progress=1.0, queryMaster=asf907.gq1.ygridcore.net 2014-10-02 07:45:37,443 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(116)) - ========================================================= 2014-10-02 07:45:37,443 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(117)) - Stop query:q_1412235109605_0739 2014-10-02 07:45:37,443 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(514)) - Release Resource: 0.0,512 2014-10-02 07:45:37,444 INFO: org.apache.tajo.master.querymaster.QueryJobManager (stopQuery(204)) - Stop QueryInProgress:q_1412235109605_0739 2014-10-02 07:45:37,444 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (stopQueryMaster(536)) - Released QueryMaster (q_1412235109605_0739) resource. 2014-10-02 07:45:37,444 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(125)) - q_1412235109605_0739 QueryMaster stopped 2014-10-02 07:45:37,444 WARN: org.apache.tajo.master.TajoAsyncDispatcher (stop(115)) - Interrupted Exception while stopping 2014-10-02 07:45:37,444 WARN: org.apache.tajo.master.TajoAsyncDispatcher (stop(115)) - Interrupted Exception while stopping 2014-10-02 07:45:37,444 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(132)) - Close HashShuffleAppender:eb_1412235109605_0739_000002, not a hash shuffle 2014-10-02 07:45:37,445 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(201)) - Stopped execution block:eb_1412235109605_0739_000002 2014-10-02 07:45:37,445 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:QueryInProgress:q_1412235109605_0739 2014-10-02 07:45:37,444 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:q_1412235109605_0739 2014-10-02 07:45:37,445 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (stop(243)) - Stopped QueryMasterTask:q_1412235109605_0739 2014-10-02 07:45:37,445 INFO: org.apache.tajo.master.querymaster.QueryMaster (cleanup(210)) - cleanup query resources : q_1412235109605_0739 2014-10-02 07:45:37,636 INFO: org.apache.tajo.worker.TajoWorkerClientService (closeQuery(229)) - Stop Query:q_1412235109605_0739 2014-10-02 07:45:37,637 INFO: org.apache.tajo.master.session.SessionManager (removeSession(79)) - Session 5ce1ec78-4cec-4fc5-b258-36ba32145da9 is removed. Tests run: 11, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.834 sec - in org.apache.tajo.engine.function.TestBuiltinFunctions Running org.apache.tajo.cluster.TestWorkerConnectionInfo Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in org.apache.tajo.cluster.TestWorkerConnectionInfo Running org.apache.tajo.cluster.TestServerName Tests run: 11, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.004 sec - in org.apache.tajo.cluster.TestServerName Running org.apache.tajo.TestTajoIds Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 sec - in org.apache.tajo.TestTajoIds 2014-10-02 07:45:37,653 INFO: org.apache.tajo.worker.TajoWorker (run(578)) - ============================================ 2014-10-02 07:45:37,653 INFO: org.apache.tajo.worker.TajoWorker (run(579)) - TajoWorker received SIGINT Signal 2014-10-02 07:45:37,653 INFO: org.apache.tajo.worker.TajoWorker (run(580)) - ============================================ 2014-10-02 07:45:37,688 INFO: org.apache.tajo.master.session.SessionManager (removeSession(79)) - Session 70a4e082-c8d4-4865-85d0-bbdbfd49ebaa is removed. 2014-10-02 07:45:37,688 INFO: org.apache.tajo.master.session.SessionManager (removeSession(79)) - Session baf179f4-3660-44e2-a1d9-ae8c3d4f24b1 is removed. 2014-10-02 07:45:37,688 INFO: org.apache.tajo.master.session.SessionManager (removeSession(79)) - Session 85e512f7-236b-42e7-b67c-b3deda69c475 is removed. 2014-10-02 07:45:37,693 INFO: org.apache.tajo.master.session.SessionManager (removeSession(79)) - Session f6e20223-9314-49aa-afa3-3e127be8acd6 is removed. 2014-10-02 07:45:37,691 INFO: org.apache.tajo.worker.TajoWorker (run(578)) - ============================================ 2014-10-02 07:45:37,705 INFO: org.apache.tajo.worker.TajoWorker (run(579)) - TajoWorker received SIGINT Signal 2014-10-02 07:45:37,705 INFO: org.apache.tajo.worker.TajoWorker (run(580)) - ============================================ 2014-10-02 07:45:37,707 INFO: org.apache.tajo.master.session.SessionManager (removeSession(79)) - Session 17179be3-7b4b-487e-b96a-b25705a1be88 is removed. 2014-10-02 07:45:37,764 INFO: org.apache.tajo.worker.WorkerHeartbeatService (run(242)) - Worker Resource Heartbeat Thread stopped. 2014-10-02 07:45:37,764 INFO: org.apache.tajo.worker.WorkerHeartbeatService (run(242)) - Worker Resource Heartbeat Thread stopped. 2014-10-02 07:45:37,766 INFO: org.apache.tajo.worker.TaskRunner (stop(151)) - Stop TaskRunner: eb_1412235109605_0019_000001,container_1412235109605_0019_01_000165 2014-10-02 07:45:37,772 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:43252) shutdown 2014-10-02 07:45:37,773 INFO: org.apache.tajo.master.querymaster.QueryMasterManagerService (stop(111)) - QueryMasterManagerService stopped 2014-10-02 07:45:37,773 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:43243) shutdown 2014-10-02 07:45:37,773 INFO: org.apache.tajo.master.querymaster.QueryMasterManagerService (stop(111)) - QueryMasterManagerService stopped 2014-10-02 07:45:37,773 INFO: org.apache.tajo.master.querymaster.QueryMaster (run(553)) - QueryMaster heartbeat thread stopped 2014-10-02 07:45:37,773 INFO: org.apache.tajo.master.querymaster.QueryMaster (run(553)) - QueryMaster heartbeat thread stopped 2014-10-02 07:45:37,774 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:querymaster_1412235135317 2014-10-02 07:45:37,774 INFO: org.apache.tajo.master.querymaster.QueryMaster (stop(173)) - QueryMaster stop 2014-10-02 07:45:37,774 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(108)) - TajoWorkerClientService stopping 2014-10-02 07:45:37,775 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:querymaster_1412235110374 2014-10-02 07:45:37,775 INFO: org.apache.tajo.master.querymaster.QueryMaster (stop(173)) - QueryMaster stop 2014-10-02 07:45:37,775 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(108)) - TajoWorkerClientService stopping 2014-10-02 07:45:37,778 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:43251) shutdown 2014-10-02 07:45:37,778 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(112)) - TajoWorkerClientService stopped 2014-10-02 07:45:37,779 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:43242) shutdown 2014-10-02 07:45:37,779 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(112)) - TajoWorkerClientService stopped 2014-10-02 07:45:37,784 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:43241) shutdown 2014-10-02 07:45:37,785 INFO: org.apache.tajo.worker.TajoWorkerManagerService (stop(97)) - TajoWorkerManagerService stopped 2014-10-02 07:45:37,785 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:43250) shutdown 2014-10-02 07:45:37,787 INFO: org.apache.tajo.worker.TajoWorkerManagerService (stop(97)) - TajoWorkerManagerService stopped 2014-10-02 07:45:37,789 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(376)) - TajoWorker main thread exiting 2014-10-02 07:45:37,789 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(376)) - TajoWorker main thread exiting Results : Failed tests: TestTajoCli.testLocalQueryWithoutFrom:177->assertOutputResult:94->assertOutputResult:98->assertOutputResult:116 expected:<...bc, 123 (1 rows, , [0 B selected) ?substr ------------------------------- 123 (1 rows, , 0 B] selected)> but was:<...bc, 123 (1 rows, , [unknown bytes selected) ?substr ------------------------------- 123 (1 rows, , unknown bytes] selected)> Tests run: 1173, Failures: 1, Errors: 0, Skipped: 0 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Tajo Main ......................................... SUCCESS [ 1.486 s] [INFO] Tajo Project POM .................................. SUCCESS [ 1.114 s] [INFO] Tajo Maven Plugins ................................ SUCCESS [ 2.594 s] [INFO] Tajo Common ....................................... SUCCESS [ 50.771 s] [INFO] Tajo Algebra ...................................... SUCCESS [ 1.352 s] [INFO] Tajo Catalog Common ............................... SUCCESS [ 5.331 s] [INFO] Tajo Rpc .......................................... SUCCESS [ 26.168 s] [INFO] Tajo Catalog Client ............................... SUCCESS [ 1.061 s] [INFO] Tajo Catalog Server ............................... SUCCESS [ 5.742 s] [INFO] Tajo Storage ...................................... SUCCESS [ 47.749 s] [INFO] Tajo Core PullServer .............................. SUCCESS [ 0.960 s] [INFO] Tajo Client ....................................... SUCCESS [ 3.136 s] [INFO] Tajo JDBC Driver .................................. SUCCESS [ 0.592 s] [INFO] ASM (thirdparty) .................................. SUCCESS [ 1.014 s] [INFO] Tajo Core ......................................... FAILURE [14:14 min] [INFO] Tajo Catalog Drivers .............................. SKIPPED [INFO] Tajo Catalog ...................................... SKIPPED [INFO] Tajo Distribution ................................. SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 16:44 min [INFO] Finished at: 2014-10-02T07:45:38+00:00 [INFO] Final Memory: 52M/414M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.16:test (default-test) on project tajo-core: There are test failures. [ERROR] [ERROR] Please refer to <https://builds.apache.org/job/Tajo-master-CODEGEN-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-1081
