See <https://builds.apache.org/job/Tajo-0.8.0-build/35/>
------------------------------------------
[...truncated 71856 lines...]
|-eb_1395741379266_0203_000004
|-eb_1395741379266_0203_000003
-------------------------------------------------------------------------------
=======================================================
Block Id: eb_1395741379266_0203_000003 [LEAF]
=======================================================
[Outgoing]
[q_1395741379266_0203] 3 => 4 (type=HASH_SHUFFLE, key=, num=1)
[Enforcers]
0: type=Broadcast, tables=default.lineitem
1: type=Broadcast, tables=default.part
GROUP_BY(9)()
=> exprs: (sum(?casewhen (FLOAT8)),sum(?multiply_2 (FLOAT8)))
=> target list: ?sum_6 (FLOAT8), ?sum_7 (FLOAT8)
=> 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
default.lineitem.l_extendedprice (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 -
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_1395741379266_0203_000004 [ROOT]
=======================================================
[Incoming]
[q_1395741379266_0203] 3 => 4 (type=HASH_SHUFFLE, key=, num=1)
PROJECTION(3)
=> Targets: 100 * ?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_1395741379266_0203_000003
=> out schema: {(2) ?sum_6 (FLOAT8),?sum_7 (FLOAT8)}
=> in schema: {(2) ?sum_6 (FLOAT8),?sum_7 (FLOAT8)}
=======================================================
Block Id: eb_1395741379266_0203_000005 [TERMINAL]
=======================================================
2014-03-25 09:59:25,136 INFO master.TajoAsyncDispatcher
(TajoAsyncDispatcher.java:start(101)) - AsyncDispatcher
started:q_1395741379266_0203
2014-03-25 09:59:25,136 INFO querymaster.Query (Query.java:handle(655)) -
Processing q_1395741379266_0203 of type START
2014-03-25 09:59:25,137 INFO querymaster.SubQuery
(SubQuery.java:initTaskScheduler(668)) -
org.apache.tajo.master.DefaultTaskScheduler is chosen for the task scheduling
2014-03-25 09:59:25,140 INFO storage.AbstractStorageManager
(AbstractStorageManager.java:listStatus(382)) - Total input paths to process : 1
2014-03-25 09:59:25,143 INFO storage.AbstractStorageManager
(AbstractStorageManager.java:getSplits(607)) - Total # of splits: 1
2014-03-25 09:59:25,145 INFO storage.AbstractStorageManager
(AbstractStorageManager.java:listStatus(382)) - Total input paths to process : 1
2014-03-25 09:59:25,147 INFO storage.AbstractStorageManager
(AbstractStorageManager.java:getSplits(607)) - Total # of splits: 1
2014-03-25 09:59:25,147 INFO querymaster.Repartitioner
(Repartitioner.java:scheduleFragmentsForJoinQuery(117)) - Left Volume: 368,
Right Volume: 604
2014-03-25 09:59:25,147 INFO querymaster.Repartitioner
(Repartitioner.java:scheduleFragmentsForJoinQuery(133)) - [Distributed Join
Strategy] : Immediate Two Way Join on Single Machine
2014-03-25 09:59:25,148 INFO querymaster.SubQuery
(SubQuery.java:transition(638)) - 2 objects are scheduled
2014-03-25 09:59:25,148 INFO master.DefaultTaskScheduler
(DefaultTaskScheduler.java:start(88)) - Start TaskScheduler
2014-03-25 09:59:25,148 INFO worker.TajoResourceAllocator
(TajoResourceAllocator.java:calculateNumRequestContainers(98)) -
CalculateNumberRequestContainer - Number of Tasks=1, Number of Cluster Slots=1
2014-03-25 09:59:25,148 INFO querymaster.SubQuery
(SubQuery.java:allocateContainers(850)) - Request Container for
eb_1395741379266_0203_000003 containers=1
2014-03-25 09:59:25,148 INFO querymaster.Query (Query.java:handle(668)) -
q_1395741379266_0203 Query Transitioned from QUERY_NEW to QUERY_RUNNING
2014-03-25 09:59:25,149 INFO worker.TajoResourceAllocator
(TajoResourceAllocator.java:run(215)) - Start TajoWorkerAllocationThread
2014-03-25 09:59:25,150 INFO worker.TajoResourceAllocator
(TajoResourceAllocator.java:run(330)) - Stop TajoWorkerAllocationThread
2014-03-25 09:59:25,150 INFO querymaster.SubQuery
(SubQuery.java:transition(957)) - SubQuery (eb_1395741379266_0203_000003) has 1
containers!
2014-03-25 09:59:25,152 INFO worker.TaskRunner (TaskRunner.java:<init>(121)) -
Tajo Root Dir: hdfs://localhost:50138/tajo
2014-03-25 09:59:25,152 INFO worker.TaskRunner (TaskRunner.java:<init>(122)) -
Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-0.8.0-build/ws/tajo-core/tajo-core-backend/target/test-data/dec5eb03-c407-41e1-95c3-7f39192d0b50/tajo-localdir>
2014-03-25 09:59:25,152 INFO worker.TaskRunner (TaskRunner.java:<init>(136)) -
QueryMaster Address:asf011.sp2.ygridcore.net/67.195.138.20:35587
2014-03-25 09:59:25,152 INFO worker.TaskRunnerManager
(TaskRunnerManager.java:run(188)) - Start
TaskRunner:eb_1395741379266_0203_000003,container_1395741379266_0203_01_000563
2014-03-25 09:59:25,153 INFO worker.TaskRunner (TaskRunner.java:init(183)) -
TaskRunner basedir is created (q_1395741379266_0203/output/3)
2014-03-25 09:59:25,154 INFO worker.TaskRunner (TaskRunner.java:run(316)) -
TaskRunner startup
2014-03-25 09:59:25,154 INFO worker.TaskRunner (TaskRunner.java:run(336)) -
Request GetTask:
eb_1395741379266_0203_000003,container_1395741379266_0203_01_000563
2014-03-25 09:59:25,155 INFO master.DefaultTaskScheduler
(DefaultTaskScheduler.java:allocateRackTask(702)) - Assigned Local/Rack/Total:
(0/1/1), Locality: 0.00%, Rack host: 67.195.138.20
2014-03-25 09:59:25,158 INFO worker.TaskRunner (TaskRunner.java:run(374)) -
Accumulated Received Task: 1
2014-03-25 09:59:25,159 INFO worker.TaskRunner (TaskRunner.java:run(383)) -
Initializing: ta_1395741379266_0203_000003_000000_00
2014-03-25 09:59:25,162 INFO worker.TaskAttemptContext
(TaskAttemptContext.java:setState(110)) - Query status of
ta_1395741379266_0203_000003_000000_00 is changed to TA_PENDING
2014-03-25 09:59:25,162 INFO worker.Task (Task.java:<init>(188)) -
==================================
2014-03-25 09:59:25,162 INFO worker.Task (Task.java:<init>(189)) - * Subquery
ta_1395741379266_0203_000003_000000_00 is initialized
2014-03-25 09:59:25,162 INFO worker.Task (Task.java:<init>(190)) - *
InterQuery: true, Use HASH_SHUFFLE shuffle
2014-03-25 09:59:25,162 INFO worker.Task (Task.java:<init>(193)) - * Fragments
(num: 2)
2014-03-25 09:59:25,162 INFO worker.Task (Task.java:<init>(194)) - * Fetches
(total:0) :
2014-03-25 09:59:25,162 INFO worker.Task (Task.java:<init>(198)) - * Local
task dir:
<https://builds.apache.org/job/Tajo-0.8.0-build/ws/tajo-core/tajo-core-backend/target/test-data/dec5eb03-c407-41e1-95c3-7f39192d0b50/tajo-localdir/q_1395741379266_0203/output/3/0_0>
2014-03-25 09:59:25,162 INFO worker.Task (Task.java:<init>(203)) -
==================================
2014-03-25 09:59:25,163 INFO worker.TaskAttemptContext
(TaskAttemptContext.java:setState(110)) - Query status of
ta_1395741379266_0203_000003_000000_00 is changed to TA_RUNNING
2014-03-25 09:59:25,164 INFO planner.PhysicalPlannerImpl
(PhysicalPlannerImpl.java:checkIfInMemoryInnerJoinIsPossible(245)) -
[ta_1395741379266_0203_000003_000000_00] the volume of Left relations
(default.lineitem) is 604 B and is fit to main maemory.
2014-03-25 09:59:25,164 INFO planner.PhysicalPlannerImpl
(PhysicalPlannerImpl.java:createBestInnerJoinPlan(398)) - Join (7) chooses
[In-memory Hash Join]
2014-03-25 09:59:25,164 INFO planner.PhysicalPlannerImpl
(PhysicalPlannerImpl.java:switchJoinSidesIfNecessary(378)) -
[ta_1395741379266_0203_000003_000000_00] Right relations default.part (368 B)
is smaller than Left relations default.lineitem (604 B).
2014-03-25 09:59:25,164 INFO planner.PhysicalPlannerImpl
(PhysicalPlannerImpl.java:createInMemoryHashAggregation(898)) - The planner
chooses [Hash Aggregation]
2014-03-25 09:59:25,175 INFO worker.TaskAttemptContext
(TaskAttemptContext.java:setState(110)) - Query status of
ta_1395741379266_0203_000003_000000_00 is changed to TA_SUCCEEDED
2014-03-25 09:59:25,176 INFO worker.Task (Task.java:run(447)) - Task Counter -
total:462, succeeded: 461, killed: 1, failed: 0
2014-03-25 09:59:25,176 INFO worker.TaskRunner (TaskRunner.java:run(336)) -
Request GetTask:
eb_1395741379266_0203_000003,container_1395741379266_0203_01_000563
2014-03-25 09:59:25,176 INFO querymaster.SubQuery
(SubQuery.java:transition(1032)) - [eb_1395741379266_0203_000003] Task
Completion Event (Total: 2, Success: 2, Killed: 0, Failed: 0
2014-03-25 09:59:25,177 INFO querymaster.SubQuery
(SubQuery.java:transition(1072)) - subQuery completed -
eb_1395741379266_0203_000003 (total=2, success=2, killed=0)
2014-03-25 09:59:25,177 INFO master.DefaultTaskScheduler
(DefaultTaskScheduler.java:stop(144)) - Task Scheduler stopped
2014-03-25 09:59:25,177 INFO master.DefaultTaskScheduler
(DefaultTaskScheduler.java:run(103)) - TaskScheduler schedulingThread stopped
2014-03-25 09:59:25,177 INFO worker.TaskRunner (TaskRunner.java:run(366)) -
Received ShouldDie
flag:eb_1395741379266_0203_000003,container_1395741379266_0203_01_000563
2014-03-25 09:59:25,177 INFO worker.TaskRunner (TaskRunner.java:stop(227)) -
Stop TaskRunner: eb_1395741379266_0203_000003
2014-03-25 09:59:25,177 INFO querymaster.Query (Query.java:handle(655)) -
Processing q_1395741379266_0203 of type SUBQUERY_COMPLETED
2014-03-25 09:59:25,177 INFO worker.TaskRunnerManager
(TaskRunnerManager.java:stopTask(89)) - Stop
Task:eb_1395741379266_0203_000003,container_1395741379266_0203_01_000563
2014-03-25 09:59:25,178 INFO querymaster.SubQuery
(SubQuery.java:initTaskScheduler(668)) -
org.apache.tajo.master.DefaultTaskScheduler is chosen for the task scheduling
2014-03-25 09:59:25,178 INFO querymaster.SubQuery
(SubQuery.java:getNonLeafTaskNum(805)) - Table's volume is approximately 1 MB
2014-03-25 09:59:25,178 INFO querymaster.SubQuery
(SubQuery.java:getNonLeafTaskNum(808)) - The determined number of non-leaf
tasks is 1
2014-03-25 09:59:25,178 INFO querymaster.Repartitioner
(Repartitioner.java:scheduleHashShuffledFetches(482)) -
ScheduleHashShuffledFetches - Max num=1, finalFetchURI=1
2014-03-25 09:59:25,178 INFO querymaster.Repartitioner
(Repartitioner.java:scheduleHashShuffledFetches(485)) - No Grouping Column -
determinedTaskNum is set to 1
2014-03-25 09:59:25,179 INFO rm.TajoWorkerResourceManager
(TajoWorkerResourceManager.java:releaseWorkerResource(595)) - Release
Resource:eb_1395741379266_0203_000003,1.0,512
2014-03-25 09:59:25,179 INFO querymaster.Repartitioner
(Repartitioner.java:scheduleHashShuffledFetches(492)) - DeterminedTaskNum : 1
2014-03-25 09:59:25,179 INFO querymaster.SubQuery
(SubQuery.java:transition(638)) - 1 objects are scheduled
2014-03-25 09:59:25,179 INFO master.DefaultTaskScheduler
(DefaultTaskScheduler.java:start(88)) - Start TaskScheduler
2014-03-25 09:59:25,180 INFO worker.TajoResourceAllocator
(TajoResourceAllocator.java:calculateNumRequestContainers(98)) -
CalculateNumberRequestContainer - Number of Tasks=1, Number of Cluster Slots=1
2014-03-25 09:59:25,180 INFO querymaster.SubQuery
(SubQuery.java:allocateContainers(850)) - Request Container for
eb_1395741379266_0203_000004 containers=1
2014-03-25 09:59:25,180 INFO querymaster.Query
(Query.java:executeNextBlock(576)) - Scheduling
SubQuery:eb_1395741379266_0203_000004
2014-03-25 09:59:25,180 INFO worker.TajoResourceAllocator
(TajoResourceAllocator.java:run(215)) - Start TajoWorkerAllocationThread
2014-03-25 09:59:25,181 INFO worker.TajoResourceAllocator
(TajoResourceAllocator.java:run(330)) - Stop TajoWorkerAllocationThread
2014-03-25 09:59:25,181 INFO querymaster.SubQuery
(SubQuery.java:transition(957)) - SubQuery (eb_1395741379266_0203_000004) has 1
containers!
2014-03-25 09:59:25,183 INFO worker.TaskRunner (TaskRunner.java:<init>(121)) -
Tajo Root Dir: hdfs://localhost:50138/tajo
2014-03-25 09:59:25,183 INFO worker.TaskRunner (TaskRunner.java:<init>(122)) -
Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-0.8.0-build/ws/tajo-core/tajo-core-backend/target/test-data/dec5eb03-c407-41e1-95c3-7f39192d0b50/tajo-localdir>
2014-03-25 09:59:25,183 INFO worker.TaskRunner (TaskRunner.java:<init>(136)) -
QueryMaster Address:asf011.sp2.ygridcore.net/67.195.138.20:35587
2014-03-25 09:59:25,183 INFO worker.TaskRunnerManager
(TaskRunnerManager.java:run(188)) - Start
TaskRunner:eb_1395741379266_0203_000004,container_1395741379266_0203_01_000564
2014-03-25 09:59:25,184 INFO worker.TaskRunner (TaskRunner.java:init(183)) -
TaskRunner basedir is created (q_1395741379266_0203/output/4)
2014-03-25 09:59:25,184 INFO worker.TaskRunner (TaskRunner.java:run(316)) -
TaskRunner startup
2014-03-25 09:59:25,185 INFO worker.TaskRunner (TaskRunner.java:run(336)) -
Request GetTask:
eb_1395741379266_0203_000004,container_1395741379266_0203_01_000564
2014-03-25 09:59:25,187 INFO worker.TaskRunner (TaskRunner.java:run(374)) -
Accumulated Received Task: 1
2014-03-25 09:59:25,187 INFO worker.TaskRunner (TaskRunner.java:run(383)) -
Initializing: ta_1395741379266_0203_000004_000000_00
2014-03-25 09:59:25,189 INFO worker.Task (Task.java:<init>(183)) - Output File
Path:
hdfs://localhost:50138/tmp/tajo-jenkins/staging/q_1395741379266_0203/RESULT/part-04-000000
2014-03-25 09:59:25,189 INFO worker.TaskAttemptContext
(TaskAttemptContext.java:setState(110)) - Query status of
ta_1395741379266_0203_000004_000000_00 is changed to TA_PENDING
2014-03-25 09:59:25,189 INFO worker.Task (Task.java:<init>(188)) -
==================================
2014-03-25 09:59:25,189 INFO worker.Task (Task.java:<init>(189)) - * Subquery
ta_1395741379266_0203_000004_000000_00 is initialized
2014-03-25 09:59:25,189 INFO worker.Task (Task.java:<init>(190)) - *
InterQuery: false
2014-03-25 09:59:25,189 INFO worker.Task (Task.java:<init>(193)) - * Fragments
(num: 1)
2014-03-25 09:59:25,189 INFO worker.Task (Task.java:<init>(194)) - * Fetches
(total:1) :
2014-03-25 09:59:25,190 INFO worker.Task (Task.java:<init>(196)) - Table Id:
eb_1395741379266_0203_000003, url:
http://67.195.138.20:42544/?qid=q_1395741379266_0203&sid=3&p=0&type=h&ta=0_0
2014-03-25 09:59:25,190 INFO worker.Task (Task.java:<init>(198)) - * Local
task dir:
<https://builds.apache.org/job/Tajo-0.8.0-build/ws/tajo-core/tajo-core-backend/target/test-data/dec5eb03-c407-41e1-95c3-7f39192d0b50/tajo-localdir/q_1395741379266_0203/output/4/0_0>
2014-03-25 09:59:25,190 INFO worker.Task (Task.java:<init>(203)) -
==================================
2014-03-25 09:59:25,192 INFO worker.Task (Task.java:init(219)) - the directory
is created
<https://builds.apache.org/job/Tajo-0.8.0-build/ws/tajo-core/tajo-core-backend/target/test-data/dec5eb03-c407-41e1-95c3-7f39192d0b50/tajo-localdir/q_1395741379266_0203/in/eb_1395741379266_0203_000004/0/0/eb_1395741379266_0203_000003>
2014-03-25 09:59:25,195 INFO worker.TaskAttemptContext
(TaskAttemptContext.java:setState(110)) - Query status of
ta_1395741379266_0203_000004_000000_00 is changed to TA_RUNNING
2014-03-25 09:59:25,196 INFO pullserver.TajoPullServerService
(TajoPullServerService.java:channelOpen(346)) - Current number of shuffle
connections (2)
2014-03-25 09:59:25,196 INFO worker.Fetcher (Fetcher.java:get(129)) - Fetch:
http://67.195.138.20:42544/?qid=q_1395741379266_0203&sid=3&p=0&type=h&ta=0_0
2014-03-25 09:59:25,197 INFO pullserver.TajoPullServerService
(TajoPullServerService.java:messageReceived(391)) - PullServer request param:
shuffleType=h, sid=3, partId=0, taskIds=[0_0]
2014-03-25 09:59:25,197 INFO pullserver.TajoPullServerService
(TajoPullServerService.java:messageReceived(397)) - PullServer baseDir:
file://<https://builds.apache.org/job/Tajo-0.8.0-build/ws/tajo-core/tajo-core-backend/target/test-data/dec5eb03-c407-41e1-95c3-7f39192d0b50/tajo-localdir/q_1395741379266_0203/output>
2014-03-25 09:59:25,198 INFO worker.Task (Task.java:waitForFetch(356)) -
ta_1395741379266_0203_000004_000000_00 All fetches are done!
2014-03-25 09:59:25,200 INFO planner.PhysicalPlannerImpl
(PhysicalPlannerImpl.java:createInMemoryHashAggregation(898)) - The planner
chooses [Hash Aggregation]
2014-03-25 09:59:25,210 INFO BlockStateChange
(BlockManager.java:logAddStoredBlock(2237)) - BLOCK* addStoredBlock: blockMap
updated: 127.0.0.1:48715 is added to
blk_1073742106_1282{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1,
replicas=[ReplicaUnderConstruction[127.0.0.1:48715|RBW]]} size 0
2014-03-25 09:59:25,213 INFO worker.TaskAttemptContext
(TaskAttemptContext.java:setState(110)) - Query status of
ta_1395741379266_0203_000004_000000_00 is changed to TA_SUCCEEDED
2014-03-25 09:59:25,213 INFO worker.Task (Task.java:run(447)) - Task Counter -
total:463, succeeded: 462, killed: 1, failed: 0
2014-03-25 09:59:25,213 INFO worker.TaskRunner (TaskRunner.java:run(336)) -
Request GetTask:
eb_1395741379266_0203_000004,container_1395741379266_0203_01_000564
2014-03-25 09:59:25,213 INFO querymaster.SubQuery
(SubQuery.java:transition(1032)) - [eb_1395741379266_0203_000004] Task
Completion Event (Total: 1, Success: 1, Killed: 0, Failed: 0
2014-03-25 09:59:25,214 INFO querymaster.SubQuery
(SubQuery.java:transition(1072)) - subQuery completed -
eb_1395741379266_0203_000004 (total=1, success=1, killed=0)
2014-03-25 09:59:25,214 INFO master.DefaultTaskScheduler
(DefaultTaskScheduler.java:run(103)) - TaskScheduler schedulingThread stopped
2014-03-25 09:59:25,214 INFO master.DefaultTaskScheduler
(DefaultTaskScheduler.java:stop(144)) - Task Scheduler stopped
2014-03-25 09:59:25,214 INFO worker.TaskRunner (TaskRunner.java:run(366)) -
Received ShouldDie
flag:eb_1395741379266_0203_000004,container_1395741379266_0203_01_000564
2014-03-25 09:59:25,214 INFO worker.TaskRunner (TaskRunner.java:stop(227)) -
Stop TaskRunner: eb_1395741379266_0203_000004
2014-03-25 09:59:25,214 INFO worker.TaskRunnerManager
(TaskRunnerManager.java:stopTask(89)) - Stop
Task:eb_1395741379266_0203_000004,container_1395741379266_0203_01_000564
2014-03-25 09:59:25,215 INFO querymaster.Query (Query.java:handle(655)) -
Processing q_1395741379266_0203 of type SUBQUERY_COMPLETED
2014-03-25 09:59:25,215 INFO querymaster.Query (Query.java:handle(655)) -
Processing q_1395741379266_0203 of type QUERY_COMPLETED
2014-03-25 09:59:25,215 INFO rm.TajoWorkerResourceManager
(TajoWorkerResourceManager.java:releaseWorkerResource(595)) - Release
Resource:eb_1395741379266_0203_000004,1.0,512
2014-03-25 09:59:25,216 INFO querymaster.Query (Query.java:handle(668)) -
q_1395741379266_0203 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED
2014-03-25 09:59:25,217 INFO querymaster.QueryMasterTask
(QueryMasterTask.java:handle(272)) - Query completion notified from
q_1395741379266_0203
2014-03-25 09:59:25,217 INFO querymaster.QueryMasterTask
(QueryMasterTask.java:handle(283)) - Query final state: QUERY_SUCCEEDED
2014-03-25 09:59:25,217 INFO querymaster.QueryMasterTask
(QueryMasterTask.java:stop(178)) - Stopping QueryMasterTask:q_1395741379266_0203
2014-03-25 09:59:25,217 INFO querymaster.QueryInProgress
(QueryInProgress.java:heartbeat(252)) - Received QueryMaster
heartbeat:q_1395741379266_0203state=QUERY_SUCCEEDED,progress=1.0,
queryMaster=host:asf011.sp2.ygridcore.net, port=35587,35588,35586,0,
slots=m:0,d:0.0,c:0, used=m:0,d:0.0,c:0
2014-03-25 09:59:25,217 INFO querymaster.QueryJobManager
(QueryJobManager.java:stopQuery(141)) - Stop
QueryInProgress:q_1395741379266_0203
2014-03-25 09:59:25,217 INFO querymaster.QueryInProgress
(QueryInProgress.java:stop(109)) -
=========================================================
2014-03-25 09:59:25,218 INFO querymaster.QueryInProgress
(QueryInProgress.java:stop(110)) - Stop query:q_1395741379266_0203
2014-03-25 09:59:25,218 INFO rm.TajoWorkerResourceManager
(TajoWorkerResourceManager.java:stopQueryMaster(634)) - release QueryMaster
resource:q_1395741379266_0203,host:asf011.sp2.ygridcore.net,
port=35587,35588,35586,42544, slots=m:1024,d:2.0,c:1, used=m:0,d:0.0,c:0
2014-03-25 09:59:25,218 WARN master.TajoAsyncDispatcher
(TajoAsyncDispatcher.java:stop(115)) - Interrupted Exception while stopping
2014-03-25 09:59:25,218 INFO querymaster.QueryInProgress
(QueryInProgress.java:stop(118)) - q_1395741379266_0203 QueryMaster stopped
2014-03-25 09:59:25,218 INFO master.TajoAsyncDispatcher
(TajoAsyncDispatcher.java:stop(122)) - AsyncDispatcher
stopped:q_1395741379266_0203
2014-03-25 09:59:25,218 WARN master.TajoAsyncDispatcher
(TajoAsyncDispatcher.java:stop(115)) - Interrupted Exception while stopping
2014-03-25 09:59:25,219 INFO master.TajoAsyncDispatcher
(TajoAsyncDispatcher.java:stop(122)) - AsyncDispatcher
stopped:QueryInProgress:q_1395741379266_0203
2014-03-25 09:59:25,219 INFO querymaster.QueryMasterTask
(QueryMasterTask.java:stop(206)) - Stopped QueryMasterTask:q_1395741379266_0203
2014-03-25 09:59:25,556 INFO worker.TajoWorkerClientService
(TajoWorkerClientService.java:closeQuery(211)) - Stop Query:q_1395741379266_0203
2014-03-25 09:59:25,557 INFO session.SessionManager
(SessionManager.java:removeSession(80)) - Session
777f01b8-3bd3-4d05-81a7-8dfb37ee20dd is removed.
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.886 sec
2014-03-25 09:59:25,561 INFO worker.TajoWorker (TajoWorker.java:run(678)) -
============================================
2014-03-25 09:59:25,562 INFO worker.TajoWorker (TajoWorker.java:run(679)) -
TajoWorker received SIGINT Signal
2014-03-25 09:59:25,563 INFO worker.TajoWorker (TajoWorker.java:run(680)) -
============================================
2014-03-25 09:59:25,563 INFO worker.TajoWorker (TajoWorker.java:run(652)) -
Worker Resource Heartbeat Thread stopped.
2014-03-25 09:59:25,583 INFO rpc.NettyServerBase
(NettyServerBase.java:shutdown(126)) - Rpc (TajoWorkerProtocol) listened on
0:0:0:0:0:0:0:0:35588) shutdown
2014-03-25 09:59:25,585 INFO worker.TajoWorkerManagerService
(TajoWorkerManagerService.java:stop(95)) - TajoWorkerManagerService stopped
2014-03-25 09:59:25,587 INFO rpc.NettyServerBase
(NettyServerBase.java:shutdown(126)) - Rpc (QueryMasterProtocol) listened on
0:0:0:0:0:0:0:0:35587) shutdown
2014-03-25 09:59:25,587 INFO querymaster.QueryMasterManagerService
(QueryMasterManagerService.java:stop(111)) - QueryMasterManagerService stopped
2014-03-25 09:59:25,588 INFO querymaster.QueryMaster
(QueryMaster.java:run(425)) - QueryMaster heartbeat thread stopped
2014-03-25 09:59:25,588 INFO master.TajoAsyncDispatcher
(TajoAsyncDispatcher.java:stop(122)) - AsyncDispatcher
stopped:querymaster_1395741380707
2014-03-25 09:59:25,590 INFO querymaster.QueryMaster
(QueryMaster.java:stop(160)) - QueryMaster stop
2014-03-25 09:59:25,590 INFO worker.TajoWorkerClientService
(TajoWorkerClientService.java:stop(107)) - TajoWorkerClientService stopping
2014-03-25 09:59:25,591 INFO rpc.NettyServerBase
(NettyServerBase.java:shutdown(126)) - Rpc (QueryMasterClientProtocol) listened
on 0:0:0:0:0:0:0:0:35586) shutdown
2014-03-25 09:59:25,591 INFO worker.TajoWorkerClientService
(TajoWorkerClientService.java:stop(111)) - TajoWorkerClientService stopped
2014-03-25 09:59:25,592 INFO worker.TajoWorker (TajoWorker.java:stop(347)) -
TajoWorker main thread exiting
Results :
Failed tests: testSort(org.apache.tajo.engine.query.TestNetTypes): Table
creation is failed.
testGroupby(org.apache.tajo.engine.query.TestNetTypes): Table creation is
failed.
testGroupby2(org.apache.tajo.engine.query.TestNetTypes): Table creation is
failed.
testSelect(org.apache.tajo.engine.query.TestNetTypes): Table creation is
failed.
Tests run: 607, Failures: 4, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [7.401s]
[INFO] Tajo Project POM .................................. SUCCESS [0.801s]
[INFO] Tajo Common ....................................... SUCCESS [7.117s]
[INFO] Tajo Algebra ...................................... SUCCESS [1.472s]
[INFO] Tajo Rpc .......................................... SUCCESS [18.309s]
[INFO] Tajo Catalog Common ............................... SUCCESS [5.728s]
[INFO] Tajo Catalog Client ............................... SUCCESS [1.161s]
[INFO] Tajo Catalog Server ............................... SUCCESS [7.712s]
[INFO] Tajo Storage ...................................... SUCCESS [46.281s]
[INFO] Tajo Core PullServer .............................. SUCCESS [0.665s]
[INFO] Tajo Client ....................................... SUCCESS [2.921s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [0.526s]
[INFO] Tajo Core Backend ................................. FAILURE [3:25.624s]
[INFO] Tajo Core ......................................... SKIPPED
[INFO] Tajo Catalog Drivers .............................. SKIPPED
[INFO] Tajo Catalog ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 5:06.639s
[INFO] Finished at: Tue Mar 25 09:59:26 UTC 2014
[INFO] Final Memory: 45M/402M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal
org.apache.maven.plugins:maven-surefire-plugin:2.12.4:test (default-test) on
project tajo-core-backend: There are test failures.
[ERROR]
[ERROR] Please refer to
<https://builds.apache.org/job/Tajo-0.8.0-build/ws/tajo-core/tajo-core-backend/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-backend
Build step 'Execute shell' marked build as failure
