You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@tajo.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2014/10/02 09:45:40 UTC
Build failed in Jenkins: Tajo-master-CODEGEN-build #26
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
Jenkins build is back to normal : Tajo-master-CODEGEN-build #27
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-CODEGEN-build/27/changes>