See <https://builds.apache.org/job/Tajo-master-build/233/changes>
Changes:
[jihoonson] TAJO-854: Supports INSERT INTO with UNION. (Hyoungjun Kim via
jihoon)
------------------------------------------
[...truncated 99637 lines...]
=> out schema:{(2) ?sum_6 (FLOAT8),?sum_7 (FLOAT8)}
=> in schema:{(2) ?casewhen (FLOAT8),?multiply_2 (FLOAT8)}
JOIN(7)(INNER)
=> Join Cond: default.lineitem.l_partkey (INT4) = default.part.p_partkey
(INT4)
=> target list: CASE WHEN default.part.p_type (TEXT)LIKE'PROMO%' THEN CAST
(default.lineitem.l_extendedprice (FLOAT8) AS FLOAT8) ELSE 0.0 END as
?casewhen, ?multiply_2 (FLOAT8)
=> out schema: {(2) ?casewhen (FLOAT8),?multiply_2 (FLOAT8)}
=> in schema: {(5) ?multiply_2 (FLOAT8),default.lineitem.l_partkey
(INT4),default.lineitem.l_extendedprice (FLOAT8),default.part.p_partkey
(INT4),default.part.p_type (TEXT)}
SCAN(1) on default.part
=> target list: default.part.p_partkey (INT4), default.part.p_type
(TEXT)
=> out schema: {(2) default.part.p_partkey (INT4),default.part.p_type
(TEXT)}
=> in schema: {(9) default.part.p_partkey (INT4),default.part.p_name
(TEXT),default.part.p_mfgr (TEXT),default.part.p_brand
(TEXT),default.part.p_type (TEXT),default.part.p_size
(INT4),default.part.p_container (TEXT),default.part.p_retailprice
(FLOAT8),default.part.p_comment (TEXT)}
SCAN(0) on default.lineitem
=> target list: default.lineitem.l_extendedprice (FLOAT8) * 1.0 -
default.lineitem.l_discount (FLOAT8) as ?multiply_2, default.lineitem.l_partkey
(INT4), default.lineitem.l_extendedprice (FLOAT8)
=> out schema: {(3) ?multiply_2 (FLOAT8),default.lineitem.l_partkey
(INT4),default.lineitem.l_extendedprice (FLOAT8)}
=> in schema: {(16) default.lineitem.l_orderkey
(INT4),default.lineitem.l_partkey (INT4),default.lineitem.l_suppkey
(INT4),default.lineitem.l_linenumber (INT4),default.lineitem.l_quantity
(FLOAT8),default.lineitem.l_extendedprice (FLOAT8),default.lineitem.l_discount
(FLOAT8),default.lineitem.l_tax (FLOAT8),default.lineitem.l_returnflag
(TEXT),default.lineitem.l_linestatus (TEXT),default.lineitem.l_shipdate
(TEXT),default.lineitem.l_commitdate (TEXT),default.lineitem.l_receiptdate
(TEXT),default.lineitem.l_shipinstruct (TEXT),default.lineitem.l_shipmode
(TEXT),default.lineitem.l_comment (TEXT)}
=======================================================
Block Id: eb_1401701616892_0316_000004 [ROOT]
=======================================================
[Incoming]
[q_1401701616892_0316] 3 => 4 (type=HASH_SHUFFLE, key=, num=1)
PROJECTION(3)
=> Targets: 100.0 * ?sum_1 (FLOAT8) / ?sum_3 (FLOAT8) as promo_revenue
=> out schema: {(1) promo_revenue (FLOAT8)}
=> in schema: {(2) ?sum_1 (FLOAT8),?sum_3 (FLOAT8)}
GROUP_BY(5)()
=> exprs: (sum(?sum_6 (FLOAT8)),sum(?sum_7 (FLOAT8)))
=> target list: ?sum_1 (FLOAT8), ?sum_3 (FLOAT8)
=> out schema:{(2) ?sum_1 (FLOAT8),?sum_3 (FLOAT8)}
=> in schema:{(2) ?sum_6 (FLOAT8),?sum_7 (FLOAT8)}
SCAN(10) on eb_1401701616892_0316_000003
=> out schema: {(2) ?sum_6 (FLOAT8),?sum_7 (FLOAT8)}
=> in schema: {(2) ?sum_6 (FLOAT8),?sum_7 (FLOAT8)}
=======================================================
Block Id: eb_1401701616892_0316_000005 [TERMINAL]
=======================================================
2014-06-02 09:38:47,380 INFO: org.apache.tajo.master.TajoAsyncDispatcher
(start(101)) - AsyncDispatcher started:q_1401701616892_0316
2014-06-02 09:38:47,380 INFO: org.apache.tajo.master.querymaster.Query
(handle(688)) - Processing q_1401701616892_0316 of type START
2014-06-02 09:38:47,380 INFO: org.apache.tajo.master.querymaster.SubQuery
(initTaskScheduler(677)) - org.apache.tajo.master.DefaultTaskScheduler is
chosen for the task scheduling for eb_1401701616892_0316_000003
2014-06-02 09:38:47,383 INFO: org.apache.tajo.storage.AbstractStorageManager
(listStatus(386)) - Total input paths to process : 1
2014-06-02 09:38:47,384 INFO: org.apache.tajo.storage.AbstractStorageManager
(getSplits(615)) - Total # of splits: 1
2014-06-02 09:38:47,386 INFO: org.apache.tajo.storage.AbstractStorageManager
(listStatus(386)) - Total input paths to process : 1
2014-06-02 09:38:47,387 INFO: org.apache.tajo.storage.AbstractStorageManager
(getSplits(615)) - Total # of splits: 1
2014-06-02 09:38:47,387 INFO: org.apache.tajo.master.querymaster.Repartitioner
(scheduleFragmentsForJoinQuery(142)) - [Distributed Join Strategy] : Immediate
2 Way Join on Single Machine
2014-06-02 09:38:47,387 INFO: org.apache.tajo.master.querymaster.SubQuery
(transition(647)) - 1 objects are scheduled
2014-06-02 09:38:47,387 INFO: org.apache.tajo.master.DefaultTaskScheduler
(start(88)) - Start TaskScheduler
2014-06-02 09:38:47,387 INFO: org.apache.tajo.worker.TajoResourceAllocator
(calculateNumRequestContainers(100)) - CalculateNumberRequestContainer - Number
of Tasks=1, Number of Cluster Slots=1
2014-06-02 09:38:47,387 INFO: org.apache.tajo.master.querymaster.SubQuery
(allocateContainers(884)) - Request Container for eb_1401701616892_0316_000003
containers=1
2014-06-02 09:38:47,388 INFO: org.apache.tajo.master.querymaster.Query
(handle(705)) - q_1401701616892_0316 Query Transitioned from QUERY_NEW to
QUERY_RUNNING
2014-06-02 09:38:47,388 INFO: org.apache.tajo.worker.TajoResourceAllocator
(run(217)) - Start TajoWorkerAllocationThread
2014-06-02 09:38:47,389 INFO: org.apache.tajo.worker.TajoResourceAllocator
(run(336)) - Stop TajoWorkerAllocationThread
2014-06-02 09:38:47,389 INFO: org.apache.tajo.master.querymaster.SubQuery
(transition(992)) - SubQuery (eb_1401701616892_0316_000003) has 1 containers!
2014-06-02 09:38:47,390 INFO: org.apache.tajo.worker.TaskRunner (<init>(122)) -
Tajo Root Dir: hdfs://localhost:38723/tajo
2014-06-02 09:38:47,390 INFO: org.apache.tajo.worker.TaskRunner (<init>(123)) -
Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/05141c94-05f8-4c79-adb0-e8f53b4674c0/tajo-localdir>
2014-06-02 09:38:47,390 INFO: org.apache.tajo.worker.TaskRunner (<init>(138)) -
QueryMaster Address:asf009.sp2.ygridcore.net/67.195.138.22:18668
2014-06-02 09:38:47,391 INFO: org.apache.tajo.worker.TaskRunnerManager
(run(156)) - Start
TaskRunner:eb_1401701616892_0316_000003,container_1401701616892_0316_01_001123
2014-06-02 09:38:47,392 INFO: org.apache.tajo.worker.TaskRunner (init(191)) -
TaskRunner basedir is created (q_1401701616892_0316/output/3)
2014-06-02 09:38:47,392 INFO: org.apache.tajo.worker.TaskRunner (run(327)) -
TaskRunner startup
2014-06-02 09:38:47,392 INFO: org.apache.tajo.worker.TaskRunner (run(346)) -
Request GetTask:
eb_1401701616892_0316_000003,container_1401701616892_0316_01_001123
2014-06-02 09:38:47,393 INFO: org.apache.tajo.master.DefaultTaskScheduler
(allocateRackTask(709)) - Assigned Local/Rack/Total: (0/1/1), Locality: 0.00%,
Rack host: 67.195.138.22
2014-06-02 09:38:47,396 INFO: org.apache.tajo.worker.TaskRunner (run(385)) -
Accumulated Received Task: 1
2014-06-02 09:38:47,396 INFO: org.apache.tajo.worker.TaskRunner (run(394)) -
Initializing: ta_1401701616892_0316_000003_000000_00
2014-06-02 09:38:47,399 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(115)) - Query status of ta_1401701616892_0316_000003_000000_00 is
changed to TA_PENDING
2014-06-02 09:38:47,400 INFO: org.apache.tajo.worker.Task (<init>(181)) -
==================================
2014-06-02 09:38:47,400 INFO: org.apache.tajo.worker.Task (<init>(182)) - *
Subquery ta_1401701616892_0316_000003_000000_00 is initialized
2014-06-02 09:38:47,400 INFO: org.apache.tajo.worker.Task (<init>(183)) - *
InterQuery: true, Use HASH_SHUFFLE shuffle
2014-06-02 09:38:47,400 INFO: org.apache.tajo.worker.Task (<init>(186)) - *
Fragments (num: 2)
2014-06-02 09:38:47,400 INFO: org.apache.tajo.worker.Task (<init>(187)) - *
Fetches (total:0) :
2014-06-02 09:38:47,400 INFO: org.apache.tajo.worker.Task (<init>(191)) - *
Local task dir:
<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/05141c94-05f8-4c79-adb0-e8f53b4674c0/tajo-localdir/q_1401701616892_0316/output/3/0_0>
2014-06-02 09:38:47,400 INFO: org.apache.tajo.worker.Task (<init>(196)) -
==================================
2014-06-02 09:38:47,401 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(115)) - Query status of ta_1401701616892_0316_000003_000000_00 is
changed to TA_RUNNING
2014-06-02 09:38:47,401 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(checkIfInMemoryInnerJoinIsPossible(256)) -
[ta_1401701616892_0316_000003_000000_00] the volume of Left relations
(default.lineitem) is 604 B and is fit to main maemory.
2014-06-02 09:38:47,401 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(createBestInnerJoinPlan(409)) - Join (7) chooses [In-memory Hash Join]
2014-06-02 09:38:47,401 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(switchJoinSidesIfNecessary(389)) - [ta_1401701616892_0316_000003_000000_00]
Right relations default.part (368 B) is smaller than Left relations
default.lineitem (604 B).
2014-06-02 09:38:47,402 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(createInMemoryHashAggregation(912)) - The planner chooses [Hash Aggregation]
2014-06-02 09:38:47,412 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(115)) - Query status of ta_1401701616892_0316_000003_000000_00 is
changed to TA_SUCCEEDED
2014-06-02 09:38:47,413 INFO: org.apache.tajo.worker.Task (run(447)) - Worker's
task counter - total:1, succeeded: 1, killed: 1, failed: 0
2014-06-02 09:38:47,413 INFO: org.apache.tajo.worker.TaskRunner (run(346)) -
Request GetTask:
eb_1401701616892_0316_000003,container_1401701616892_0316_01_001123
2014-06-02 09:38:47,413 INFO: org.apache.tajo.master.querymaster.SubQuery
(transition(1069)) - [eb_1401701616892_0316_000003] Task Completion Event
(Total: 1, Success: 1, Killed: 0, Failed: 0)
2014-06-02 09:38:47,413 INFO: org.apache.tajo.master.querymaster.SubQuery
(transition(1109)) - subQuery completed - eb_1401701616892_0316_000003
(total=1, success=1, killed=0)
2014-06-02 09:38:47,413 INFO: org.apache.tajo.master.DefaultTaskScheduler
(run(103)) - TaskScheduler schedulingThread stopped
2014-06-02 09:38:47,413 INFO: org.apache.tajo.master.DefaultTaskScheduler
(stop(144)) - Task Scheduler stopped
2014-06-02 09:38:47,414 INFO: org.apache.tajo.worker.TaskRunner (run(376)) -
Received ShouldDie
flag:eb_1401701616892_0316_000003,container_1401701616892_0316_01_001123
2014-06-02 09:38:47,414 INFO: org.apache.tajo.master.querymaster.Query
(handle(688)) - Processing q_1401701616892_0316 of type SUBQUERY_COMPLETED
2014-06-02 09:38:47,414 INFO: org.apache.tajo.worker.TaskRunner (stop(239)) -
Stop TaskRunner: eb_1401701616892_0316_000003
2014-06-02 09:38:47,414 INFO: org.apache.tajo.master.querymaster.SubQuery
(initTaskScheduler(677)) - org.apache.tajo.master.DefaultTaskScheduler is
chosen for the task scheduling for eb_1401701616892_0316_000004
2014-06-02 09:38:47,414 INFO: org.apache.tajo.master.querymaster.SubQuery
(getNonLeafTaskNum(833)) - Table's volume is approximately 1 MB
2014-06-02 09:38:47,415 INFO: org.apache.tajo.master.querymaster.SubQuery
(getNonLeafTaskNum(836)) - The determined number of non-leaf tasks is 1
2014-06-02 09:38:47,415 INFO: org.apache.tajo.master.querymaster.Repartitioner
(scheduleHashShuffledFetches(502)) - eb_1401701616892_0316_000004,
ScheduleHashShuffledFetches - Max num=1, finalFetchURI=1
2014-06-02 09:38:47,415 INFO: org.apache.tajo.master.querymaster.Repartitioner
(scheduleHashShuffledFetches(505)) - eb_1401701616892_0316_000004, No Grouping
Column - determinedTaskNum is set to 1
2014-06-02 09:38:47,415 INFO: org.apache.tajo.master.querymaster.Repartitioner
(scheduleHashShuffledFetches(512)) - eb_1401701616892_0316_000004,
DeterminedTaskNum : 1
2014-06-02 09:38:47,415 INFO: org.apache.tajo.master.querymaster.SubQuery
(transition(647)) - 1 objects are scheduled
2014-06-02 09:38:47,415 INFO: org.apache.tajo.master.DefaultTaskScheduler
(start(88)) - Start TaskScheduler
2014-06-02 09:38:47,414 INFO: org.apache.tajo.worker.TaskRunnerManager
(stopTask(89)) - Stop
Task:eb_1401701616892_0316_000003,container_1401701616892_0316_01_001123
2014-06-02 09:38:47,415 INFO: org.apache.tajo.worker.TajoResourceAllocator
(calculateNumRequestContainers(100)) - CalculateNumberRequestContainer - Number
of Tasks=1, Number of Cluster Slots=1
2014-06-02 09:38:47,414 INFO:
org.apache.tajo.master.rm.TajoWorkerResourceManager
(releaseWorkerResource(518)) - Release Resource: 0.5,512
2014-06-02 09:38:47,416 INFO: org.apache.tajo.master.querymaster.SubQuery
(allocateContainers(884)) - Request Container for eb_1401701616892_0316_000004
containers=1
2014-06-02 09:38:47,416 INFO: org.apache.tajo.master.querymaster.Query
(executeNextBlock(609)) - Scheduling SubQuery:eb_1401701616892_0316_000004
2014-06-02 09:38:47,416 INFO: org.apache.tajo.worker.TajoResourceAllocator
(run(217)) - Start TajoWorkerAllocationThread
2014-06-02 09:38:47,417 INFO: org.apache.tajo.worker.TajoResourceAllocator
(run(336)) - Stop TajoWorkerAllocationThread
2014-06-02 09:38:47,417 INFO: org.apache.tajo.master.querymaster.SubQuery
(transition(992)) - SubQuery (eb_1401701616892_0316_000004) has 1 containers!
2014-06-02 09:38:47,419 INFO: org.apache.tajo.worker.TaskRunner (<init>(122)) -
Tajo Root Dir: hdfs://localhost:38723/tajo
2014-06-02 09:38:47,419 INFO: org.apache.tajo.worker.TaskRunner (<init>(123)) -
Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/05141c94-05f8-4c79-adb0-e8f53b4674c0/tajo-localdir>
2014-06-02 09:38:47,419 INFO: org.apache.tajo.worker.TaskRunner (<init>(138)) -
QueryMaster Address:asf009.sp2.ygridcore.net/67.195.138.22:18668
2014-06-02 09:38:47,419 INFO: org.apache.tajo.worker.TaskRunnerManager
(run(156)) - Start
TaskRunner:eb_1401701616892_0316_000004,container_1401701616892_0316_01_001124
2014-06-02 09:38:47,420 INFO: org.apache.tajo.worker.TaskRunner (init(191)) -
TaskRunner basedir is created (q_1401701616892_0316/output/4)
2014-06-02 09:38:47,420 INFO: org.apache.tajo.worker.TaskRunner (run(327)) -
TaskRunner startup
2014-06-02 09:38:47,421 INFO: org.apache.tajo.worker.TaskRunner (run(346)) -
Request GetTask:
eb_1401701616892_0316_000004,container_1401701616892_0316_01_001124
2014-06-02 09:38:47,423 INFO: org.apache.tajo.worker.TaskRunner (run(385)) -
Accumulated Received Task: 1
2014-06-02 09:38:47,423 INFO: org.apache.tajo.worker.TaskRunner (run(394)) -
Initializing: ta_1401701616892_0316_000004_000000_00
2014-06-02 09:38:47,425 INFO: org.apache.tajo.worker.Task (<init>(176)) -
Output File Path:
hdfs://localhost:38723/tmp/tajo-jenkins/staging/q_1401701616892_0316/RESULT/part-04-000000
2014-06-02 09:38:47,425 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(115)) - Query status of ta_1401701616892_0316_000004_000000_00 is
changed to TA_PENDING
2014-06-02 09:38:47,425 INFO: org.apache.tajo.worker.Task (<init>(181)) -
==================================
2014-06-02 09:38:47,425 INFO: org.apache.tajo.worker.Task (<init>(182)) - *
Subquery ta_1401701616892_0316_000004_000000_00 is initialized
2014-06-02 09:38:47,425 INFO: org.apache.tajo.worker.Task (<init>(183)) - *
InterQuery: false
2014-06-02 09:38:47,425 INFO: org.apache.tajo.worker.Task (<init>(186)) - *
Fragments (num: 1)
2014-06-02 09:38:47,425 INFO: org.apache.tajo.worker.Task (<init>(187)) - *
Fetches (total:1) :
2014-06-02 09:38:47,425 INFO: org.apache.tajo.worker.Task (<init>(189)) - Table
Id: eb_1401701616892_0316_000003, Simple URIs:
[http://67.195.138.22:60436/?qid=q_1401701616892_0316&sid=3&p=0&type=h]
2014-06-02 09:38:47,426 INFO: org.apache.tajo.worker.Task (<init>(191)) - *
Local task dir:
<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/05141c94-05f8-4c79-adb0-e8f53b4674c0/tajo-localdir/q_1401701616892_0316/output/4/0_0>
2014-06-02 09:38:47,426 INFO: org.apache.tajo.worker.Task (<init>(196)) -
==================================
2014-06-02 09:38:47,428 INFO: org.apache.tajo.worker.Task (init(212)) - the
directory is created
<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/05141c94-05f8-4c79-adb0-e8f53b4674c0/tajo-localdir/q_1401701616892_0316/in/eb_1401701616892_0316_000004/0/0/eb_1401701616892_0316_000003>
2014-06-02 09:38:47,431 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(115)) - Query status of ta_1401701616892_0316_000004_000000_00 is
changed to TA_RUNNING
2014-06-02 09:38:47,431 INFO: org.apache.tajo.pullserver.TajoPullServerService
(channelOpen(349)) - Current number of shuffle connections (2)
2014-06-02 09:38:47,431 INFO: org.apache.tajo.worker.Fetcher (get(126)) -
Fetch:
http://67.195.138.22:60436/?qid=q_1401701616892_0316&sid=3&p=0&type=h&ta=0_0
2014-06-02 09:38:47,432 INFO: org.apache.tajo.pullserver.TajoPullServerService
(messageReceived(394)) - PullServer request param: shuffleType=h, sid=3,
partId=0, taskIds=[0_0]
2014-06-02 09:38:47,432 INFO: org.apache.tajo.pullserver.TajoPullServerService
(messageReceived(400)) - PullServer baseDir:
file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/05141c94-05f8-4c79-adb0-e8f53b4674c0/tajo-localdir/q_1401701616892_0316/output>
2014-06-02 09:38:47,433 INFO: org.apache.tajo.worker.Task (waitForFetch(353)) -
ta_1401701616892_0316_000004_000000_00 All fetches are done!
2014-06-02 09:38:47,435 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(createInMemoryHashAggregation(912)) - The planner chooses [Hash Aggregation]
2014-06-02 09:38:47,446 INFO: BlockStateChange (logAddStoredBlock(2300)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40089 is added to
blk_1073742249_1425{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1,
replicas=[ReplicaUnderConstruction[[DISK]DS-5b6084e9-3f80-42a5-889b-108a65e9c021:NORMAL|RBW]]}
size 0
2014-06-02 09:38:47,448 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(115)) - Query status of ta_1401701616892_0316_000004_000000_00 is
changed to TA_SUCCEEDED
2014-06-02 09:38:47,449 INFO: org.apache.tajo.worker.Task (run(447)) - Worker's
task counter - total:1, succeeded: 1, killed: 1, failed: 0
2014-06-02 09:38:47,449 INFO: org.apache.tajo.worker.TaskRunner (run(346)) -
Request GetTask:
eb_1401701616892_0316_000004,container_1401701616892_0316_01_001124
2014-06-02 09:38:47,449 INFO: org.apache.tajo.master.querymaster.SubQuery
(transition(1069)) - [eb_1401701616892_0316_000004] Task Completion Event
(Total: 1, Success: 1, Killed: 0, Failed: 0)
2014-06-02 09:38:47,449 INFO: org.apache.tajo.master.querymaster.SubQuery
(transition(1109)) - subQuery completed - eb_1401701616892_0316_000004
(total=1, success=1, killed=0)
2014-06-02 09:38:47,449 INFO: org.apache.tajo.master.DefaultTaskScheduler
(run(103)) - TaskScheduler schedulingThread stopped
2014-06-02 09:38:47,449 INFO: org.apache.tajo.master.DefaultTaskScheduler
(stop(144)) - Task Scheduler stopped
2014-06-02 09:38:47,450 INFO: org.apache.tajo.worker.TaskRunner (run(376)) -
Received ShouldDie
flag:eb_1401701616892_0316_000004,container_1401701616892_0316_01_001124
2014-06-02 09:38:47,450 INFO: org.apache.tajo.worker.TaskRunner (stop(239)) -
Stop TaskRunner: eb_1401701616892_0316_000004
2014-06-02 09:38:47,450 INFO: org.apache.tajo.worker.TaskRunnerManager
(stopTask(89)) - Stop
Task:eb_1401701616892_0316_000004,container_1401701616892_0316_01_001124
2014-06-02 09:38:47,450 INFO: org.apache.tajo.master.querymaster.Query
(handle(688)) - Processing q_1401701616892_0316 of type SUBQUERY_COMPLETED
2014-06-02 09:38:47,450 INFO: org.apache.tajo.master.querymaster.Query
(handle(688)) - Processing q_1401701616892_0316 of type QUERY_COMPLETED
2014-06-02 09:38:47,450 INFO:
org.apache.tajo.master.rm.TajoWorkerResourceManager
(releaseWorkerResource(518)) - Release Resource: 0.5,512
2014-06-02 09:38:47,451 INFO: org.apache.tajo.master.querymaster.Query
(handle(705)) - q_1401701616892_0316 Query Transitioned from QUERY_RUNNING to
QUERY_SUCCEEDED
2014-06-02 09:38:47,451 INFO:
org.apache.tajo.master.querymaster.QueryMasterTask (handle(295)) - Query
completion notified from q_1401701616892_0316
2014-06-02 09:38:47,451 INFO:
org.apache.tajo.master.querymaster.QueryMasterTask (handle(306)) - Query final
state: QUERY_SUCCEEDED
2014-06-02 09:38:47,451 INFO:
org.apache.tajo.master.querymaster.QueryMasterTask (stop(187)) - Stopping
QueryMasterTask:q_1401701616892_0316
2014-06-02 09:38:47,452 INFO:
org.apache.tajo.master.querymaster.QueryInProgress (heartbeat(265)) - Received
QueryMaster heartbeat:q_1401701616892_0316,state=QUERY_SUCCEEDED,progress=1.0,
queryMaster=asf009.sp2.ygridcore.net
2014-06-02 09:38:47,452 INFO:
org.apache.tajo.master.querymaster.QueryJobManager (stopQuery(203)) - Stop
QueryInProgress:q_1401701616892_0316
2014-06-02 09:38:47,452 INFO:
org.apache.tajo.master.querymaster.QueryInProgress (stop(116)) -
=========================================================
2014-06-02 09:38:47,452 INFO:
org.apache.tajo.master.querymaster.QueryInProgress (stop(117)) - Stop
query:q_1401701616892_0316
2014-06-02 09:38:47,452 WARN: org.apache.tajo.master.TajoAsyncDispatcher
(stop(115)) - Interrupted Exception while stopping
2014-06-02 09:38:47,452 INFO:
org.apache.tajo.master.rm.TajoWorkerResourceManager
(releaseWorkerResource(518)) - Release Resource: 0.0,512
2014-06-02 09:38:47,452 INFO: org.apache.tajo.master.TajoAsyncDispatcher
(stop(122)) - AsyncDispatcher stopped:q_1401701616892_0316
2014-06-02 09:38:47,452 INFO:
org.apache.tajo.master.rm.TajoWorkerResourceManager (stopQueryMaster(541)) -
Released QueryMaster (q_1401701616892_0316) resource:null
2014-06-02 09:38:47,453 INFO:
org.apache.tajo.master.querymaster.QueryInProgress (stop(125)) -
q_1401701616892_0316 QueryMaster stopped
2014-06-02 09:38:47,453 INFO:
org.apache.tajo.master.querymaster.QueryMasterTask (stop(215)) - Stopped
QueryMasterTask:q_1401701616892_0316
2014-06-02 09:38:47,453 WARN: org.apache.tajo.master.TajoAsyncDispatcher
(stop(115)) - Interrupted Exception while stopping
2014-06-02 09:38:47,453 INFO: org.apache.tajo.master.TajoAsyncDispatcher
(stop(122)) - AsyncDispatcher stopped:QueryInProgress:q_1401701616892_0316
2014-06-02 09:38:47,816 INFO: org.apache.tajo.worker.TajoWorkerClientService
(closeQuery(222)) - Stop Query:q_1401701616892_0316
2014-06-02 09:38:47,817 INFO: org.apache.tajo.master.session.SessionManager
(removeSession(80)) - Session 70b1f202-3234-4e59-b3ce-60e098be6387 is removed.
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 8.59 sec
2014-06-02 09:38:47,870 INFO: org.apache.tajo.master.session.SessionManager
(removeSession(80)) - Session 5468a613-9aa9-458f-abf4-97c231e0be49 is removed.
2014-06-02 09:38:47,872 INFO: org.apache.tajo.worker.TajoWorker (run(492)) -
============================================
2014-06-02 09:38:47,872 INFO: org.apache.tajo.worker.TajoWorker (run(493)) -
TajoWorker received SIGINT Signal
2014-06-02 09:38:47,873 INFO: org.apache.tajo.worker.TajoWorker (run(494)) -
============================================
2014-06-02 09:38:47,872 INFO: org.apache.tajo.master.session.SessionManager
(removeSession(80)) - Session 26b9920a-e41e-4452-94ab-f24bb54a639f is removed.
2014-06-02 09:38:47,876 ERROR: org.apache.tajo.rpc.RpcProtos
(exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-06-02 09:38:47,876 ERROR: org.apache.tajo.rpc.RpcProtos
(exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-06-02 09:38:47,876 ERROR: org.apache.tajo.client.TajoClient (close(140)) -
java.io.IOException: Connect error to localhost/127.0.0.1:18665 cause
java.util.concurrent.RejectedExecutionException: Worker has already been
shutdown
2014-06-02 09:38:47,876 ERROR: org.apache.tajo.client.TajoClient (close(140)) -
java.io.IOException: Connect error to localhost/127.0.0.1:18665 cause
java.util.concurrent.RejectedExecutionException: Worker has already been
shutdown
2014-06-02 09:38:47,877 ERROR: org.apache.tajo.rpc.RpcProtos
(exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-06-02 09:38:47,878 ERROR: org.apache.tajo.client.TajoClient (close(140)) -
java.io.IOException: Connect error to localhost/127.0.0.1:18665 cause
java.util.concurrent.RejectedExecutionException: Worker has already been
shutdown
2014-06-02 09:38:47,879 ERROR: org.apache.tajo.rpc.RpcProtos
(exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-06-02 09:38:47,879 ERROR: org.apache.tajo.client.TajoClient (close(140)) -
java.io.IOException: Connect error to localhost/127.0.0.1:18665 cause
java.util.concurrent.RejectedExecutionException: Worker has already been
shutdown
2014-06-02 09:38:47,880 ERROR: org.apache.tajo.rpc.RpcProtos
(exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-06-02 09:38:47,880 ERROR: org.apache.tajo.rpc.RpcProtos
(exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-06-02 09:38:47,880 ERROR: org.apache.tajo.client.TajoClient (close(140)) -
java.io.IOException: Connect error to localhost/127.0.0.1:18665 cause
java.util.concurrent.RejectedExecutionException: Worker has already been
shutdown
2014-06-02 09:38:47,880 ERROR: org.apache.tajo.rpc.RpcProtos
(exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-06-02 09:38:47,881 ERROR: org.apache.tajo.client.TajoClient (close(140)) -
java.io.IOException: Connect error to localhost/127.0.0.1:18665 cause
java.util.concurrent.RejectedExecutionException: Worker has already been
shutdown
2014-06-02 09:38:47,881 ERROR: org.apache.tajo.client.TajoClient (close(140)) -
java.io.IOException: Connect error to localhost/127.0.0.1:18665 cause
java.util.concurrent.RejectedExecutionException: Worker has already been
shutdown
2014-06-02 09:38:47,882 INFO: org.apache.tajo.worker.WorkerHeartbeatService
(run(260)) - Worker Resource Heartbeat Thread stopped.
2014-06-02 09:38:47,906 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(128)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:18669)
shutdown
2014-06-02 09:38:47,910 INFO: org.apache.tajo.worker.TajoWorkerManagerService
(stop(95)) - TajoWorkerManagerService stopped
2014-06-02 09:38:47,911 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(128)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:18668)
shutdown
2014-06-02 09:38:47,912 INFO:
org.apache.tajo.master.querymaster.QueryMasterManagerService (stop(111)) -
QueryMasterManagerService stopped
2014-06-02 09:38:47,912 INFO: org.apache.tajo.master.querymaster.QueryMaster
(run(434)) - QueryMaster heartbeat thread stopped
2014-06-02 09:38:47,912 INFO: org.apache.tajo.master.TajoAsyncDispatcher
(stop(122)) - AsyncDispatcher stopped:querymaster_1401701617502
2014-06-02 09:38:47,914 INFO: org.apache.tajo.master.querymaster.QueryMaster
(stop(160)) - QueryMaster stop
2014-06-02 09:38:47,914 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(109)) - TajoWorkerClientService stopping
2014-06-02 09:38:47,915 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(128)) - Rpc (QueryMasterClientProtocol) listened on
0:0:0:0:0:0:0:0:18667) shutdown
2014-06-02 09:38:47,915 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(113)) - TajoWorkerClientService stopped
2014-06-02 09:38:47,915 INFO: org.apache.tajo.worker.TajoWorker (stop(334)) -
TajoWorker main thread exiting
Results :
Tests in error:
testUnion11(org.apache.tajo.engine.query.TestUnionQuery): Connect error to
asf009.sp2.ygridcore.net/67.195.138.22:0 cause java.net.ConnectException:
Connection refused: asf009.sp2.ygridcore.net/67.195.138.22:0
Tests run: 771, Failures: 0, Errors: 1, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [40.469s]
[INFO] Tajo Project POM .................................. SUCCESS [0.827s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [3.466s]
[INFO] Tajo Common ....................................... SUCCESS [56.434s]
[INFO] Tajo Algebra ...................................... SUCCESS [1.425s]
[INFO] Tajo Catalog Common ............................... SUCCESS [5.559s]
[INFO] Tajo Rpc .......................................... SUCCESS [21.577s]
[INFO] Tajo Catalog Client ............................... SUCCESS [1.113s]
[INFO] Tajo Catalog Server ............................... SUCCESS [7.410s]
[INFO] Tajo Storage ...................................... SUCCESS [57.322s]
[INFO] Tajo Core PullServer .............................. SUCCESS [1.140s]
[INFO] Tajo Client ....................................... SUCCESS [4.122s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [1.026s]
[INFO] Tajo Core ......................................... FAILURE [8:17.135s]
[INFO] Tajo Catalog Drivers .............................. SKIPPED
[INFO] Tajo Catalog ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 11:40.351s
[INFO] Finished at: Mon Jun 02 09:41:29 UTC 2014
[INFO] Final Memory: 51M/426M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal
org.apache.maven.plugins:maven-surefire-plugin:2.12.4: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-793
Updating TAJO-854
Error updating JIRA issues. Saving issues for next build.
com.atlassian.jira.rpc.exception.RemoteAuthenticationException: User not
authenticated yet, or session timed out.
