See <https://builds.apache.org/job/Tajo-0.11.0-nightly/26/changes>
Changes:
[hyunsik] TAJO-1600: Invalid query planning for distinct group-by.
------------------------------------------
[...truncated 71301 lines...]
=======================================================
[Incoming]
[q_1441869218561_0154] 1 => 2 (type=HASH_SHUFFLE, key=, num=1)
GROUP_BY(3)()
=> exprs: (sum(?sum_1 (INT8)))
=> target list: ?sum (INT8)
=> out schema:{(1) ?sum (INT8)}
=> in schema:{(1) ?sum_1 (INT8)}
SCAN(7) on eb_1441869218561_0154_000001
=> out schema: {(1) ?sum_1 (INT8)}
=> in schema: {(1) ?sum_1 (INT8)}
=======================================================
Block Id: eb_1441869218561_0154_000003 [TERMINAL]
=======================================================
2015-09-10 07:16:09,397 INFO: org.apache.tajo.querymaster.Query (<init>(235)) -
=======================================================
The order of execution:
1: eb_1441869218561_0154_000001
2: eb_1441869218561_0154_000002
3: eb_1441869218561_0154_000003
=======================================================
2015-09-10 07:16:09,398 INFO: org.apache.tajo.querymaster.Query (handle(855)) -
Processing q_1441869218561_0154 of type START
2015-09-10 07:16:09,399 INFO:
org.apache.tajo.engine.planner.global.ParallelExecutionQueue (first(84)) -
Initial executable blocks [eb_1441869218561_0154_000001]
2015-09-10 07:16:09,399 INFO: org.apache.tajo.querymaster.Query (handle(873)) -
q_1441869218561_0154 Query Transitioned from QUERY_NEW to QUERY_RUNNING
2015-09-10 07:16:09,399 INFO: org.apache.tajo.querymaster.Stage
(calculateShuffleOutputNum(1008)) - eb_1441869218561_0154_000001, No Grouping
Column - determinedTaskNum is set to 1
2015-09-10 07:16:09,399 INFO: org.apache.tajo.querymaster.Stage
(initTaskScheduler(897)) - org.apache.tajo.querymaster.DefaultTaskScheduler is
chosen for the task scheduling for eb_1441869218561_0154_000001
2015-09-10 07:16:09,402 INFO: org.apache.tajo.storage.FileTablespace
(listStatus(368)) - Total input paths to process : 1
2015-09-10 07:16:09,404 INFO: org.apache.tajo.storage.FileTablespace
(getSplits(569)) - Total # of splits: 1
2015-09-10 07:16:09,404 INFO: org.apache.tajo.querymaster.Stage (run(849)) - 1
objects are scheduled
2015-09-10 07:16:09,404 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(start(131)) - Start TaskScheduler
2015-09-10 07:16:09,406 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(increaseConcurrency(521)) - Assigned host : 127.0.0.1, Unknown Volume : -1,
Concurrency : 1
2015-09-10 07:16:09,408 INFO: org.apache.tajo.worker.TaskManager
(createExecutionBlock(112)) - QueryMaster Address:asf901.gq1.ygridcore.net:29027
2015-09-10 07:16:09,408 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(assignToLeafTasks(915)) - Assigned Local/Rack/Total: (0/1/1), Attempted
Cancel/Assign/Total: (0/1/1), Locality: 0.00%, Rack host:
asf901.gq1.ygridcore.net
2015-09-10 07:16:09,411 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(119)) - Tajo Root Dir: hdfs://localhost:43704/tajo
2015-09-10 07:16:09,411 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(120)) - Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-0.11.0-nightly/ws/tajo-core-tests/target/test-data/45810e0d-e392-41fb-9221-34d2778d7385/tajo-localdir>
2015-09-10 07:16:09,412 INFO: org.apache.tajo.worker.TaskManager (handle(182))
- Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:1000,
Disks:2, vCores:2)
2015-09-10 07:16:09,413 INFO: org.apache.tajo.worker.TaskImpl (<init>(114)) -
Task basedir is created
(<https://builds.apache.org/job/Tajo-0.11.0-nightly/ws/tajo-core-tests/target/test-data/45810e0d-e392-41fb-9221-34d2778d7385/tajo-localdir/q_1441869218561_0154/output/1)>
2015-09-10 07:16:09,413 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(142)) - Query status of ta_1441869218561_0154_000001_000000_00 is
changed to TA_PENDING
2015-09-10 07:16:09,413 INFO: org.apache.tajo.worker.TaskImpl (init(195)) -
Initializing: ta_1441869218561_0154_000001_000000_00
2015-09-10 07:16:09,414 INFO: org.apache.tajo.worker.TaskImpl (initPlan(150)) -
==================================
2015-09-10 07:16:09,414 INFO: org.apache.tajo.worker.TaskImpl (initPlan(151)) -
* Stage ta_1441869218561_0154_000001_000000_00 is initialized
2015-09-10 07:16:09,414 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) -
* InterQuery: true, Use HASH_SHUFFLE shuffle, Fragments (num: 1), Fetches
(total:0) :
2015-09-10 07:16:09,414 INFO: org.apache.tajo.worker.TaskImpl (initPlan(162)) -
* Local task dir:
<https://builds.apache.org/job/Tajo-0.11.0-nightly/ws/tajo-core-tests/target/test-data/45810e0d-e392-41fb-9221-34d2778d7385/tajo-localdir/q_1441869218561_0154/output/1/0_0>
2015-09-10 07:16:09,414 INFO: org.apache.tajo.worker.TaskImpl (initPlan(167)) -
==================================
2015-09-10 07:16:09,414 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(142)) - Query status of ta_1441869218561_0154_000001_000000_00 is
changed to TA_RUNNING
2015-09-10 07:16:09,414 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(createInMemoryHashAggregation(974)) - The planner chooses [Hash Aggregation]
2015-09-10 07:16:09,420 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(142)) - Query status of ta_1441869218561_0154_000001_000000_00 is
changed to TA_SUCCEEDED
2015-09-10 07:16:09,420 INFO: org.apache.tajo.worker.TaskImpl (run(460)) -
ta_1441869218561_0154_000001_000000_00 completed. Worker's task counter -
total:1, succeeded: 1, killed: 0, failed: 0
2015-09-10 07:16:09,421 INFO: org.apache.tajo.querymaster.Stage
(transition(1345)) - Stage - eb_1441869218561_0154_000001 finalize HASH_SHUFFLE
(total=1, success=1, killed=0)
2015-09-10 07:16:09,421 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(stop(160)) - Task Scheduler stopped
2015-09-10 07:16:09,421 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(run(123)) - TaskScheduler schedulingThread stopped
2015-09-10 07:16:09,421 INFO: org.apache.tajo.querymaster.Stage
(transition(1356)) - eb_1441869218561_0154_000001, wait for HASH_SHUFFLE
reports. expected Tasks:1
2015-09-10 07:16:09,422 INFO:
org.apache.tajo.storage.HashShuffleAppenderManager (close(155)) - Close
HashShuffleAppender:eb_1441869218561_0154_000001, intermediates=1
2015-09-10 07:16:09,422 INFO: org.apache.tajo.querymaster.Stage
(finalizeShuffleReport(1302)) - eb_1441869218561_0154_000001, Finalized
HASH_SHUFFLE reports: 1
2015-09-10 07:16:09,422 INFO: org.apache.tajo.querymaster.Stage
(transition(1403)) - Stage completed - eb_1441869218561_0154_000001 (total=1,
success=1, killed=0)
2015-09-10 07:16:09,423 INFO: org.apache.tajo.worker.TaskManager
(stopExecutionBlock(161)) - Stopped execution block:eb_1441869218561_0154_000001
2015-09-10 07:16:09,423 INFO: org.apache.tajo.querymaster.Query (handle(855)) -
Processing q_1441869218561_0154 of type STAGE_COMPLETED
2015-09-10 07:16:09,423 INFO:
org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next
executable block eb_1441869218561_0154_000002
2015-09-10 07:16:09,423 INFO: org.apache.tajo.querymaster.Query
(executeNextBlock(757)) - Scheduling Stage:eb_1441869218561_0154_000002
2015-09-10 07:16:09,423 INFO: org.apache.tajo.querymaster.Stage
(initTaskScheduler(897)) - org.apache.tajo.querymaster.DefaultTaskScheduler is
chosen for the task scheduling for eb_1441869218561_0154_000002
2015-09-10 07:16:09,424 INFO: org.apache.tajo.querymaster.Stage
(getNonLeafTaskNum(1091)) - eb_1441869218561_0154_000002, Table's volume is
approximately 1 MB
2015-09-10 07:16:09,424 INFO: org.apache.tajo.querymaster.Stage
(getNonLeafTaskNum(1096)) - eb_1441869218561_0154_000002, The determined number
of non-leaf tasks is 1
2015-09-10 07:16:09,424 INFO: org.apache.tajo.querymaster.Repartitioner
(scheduleHashShuffledFetches(865)) - eb_1441869218561_0154_000002,
ScheduleHashShuffledFetches - Max num=1, finalFetchURI=1
2015-09-10 07:16:09,424 INFO: org.apache.tajo.querymaster.Repartitioner
(scheduleHashShuffledFetches(869)) - eb_1441869218561_0154_000002, No Grouping
Column - determinedTaskNum is set to 1
2015-09-10 07:16:09,424 INFO: org.apache.tajo.querymaster.Repartitioner
(scheduleHashShuffledFetches(885)) - eb_1441869218561_0154_000002,
DeterminedTaskNum : 1
2015-09-10 07:16:09,424 INFO: org.apache.tajo.querymaster.Stage (run(849)) - 1
objects are scheduled
2015-09-10 07:16:09,425 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(start(131)) - Start TaskScheduler
2015-09-10 07:16:09,427 INFO: org.apache.tajo.worker.TaskManager
(createExecutionBlock(112)) - QueryMaster Address:asf901.gq1.ygridcore.net:29027
2015-09-10 07:16:09,429 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(119)) - Tajo Root Dir: hdfs://localhost:43704/tajo
2015-09-10 07:16:09,429 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(120)) - Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-0.11.0-nightly/ws/tajo-core-tests/target/test-data/45810e0d-e392-41fb-9221-34d2778d7385/tajo-localdir>
2015-09-10 07:16:09,430 INFO: org.apache.tajo.worker.TaskManager (handle(182))
- Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:1000,
Disks:3, vCores:2)
2015-09-10 07:16:09,431 INFO: org.apache.tajo.worker.TaskImpl (<init>(114)) -
Task basedir is created
(<https://builds.apache.org/job/Tajo-0.11.0-nightly/ws/tajo-core-tests/target/test-data/45810e0d-e392-41fb-9221-34d2778d7385/tajo-localdir/q_1441869218561_0154/output/2)>
2015-09-10 07:16:09,431 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(142)) - Query status of ta_1441869218561_0154_000002_000000_00 is
changed to TA_PENDING
2015-09-10 07:16:09,431 INFO: org.apache.tajo.worker.TaskImpl (init(195)) -
Initializing: ta_1441869218561_0154_000002_000000_00
2015-09-10 07:16:09,431 INFO: org.apache.tajo.storage.FileTablespace
(getAppenderFilePath(294)) - Output File Path:
hdfs://localhost:43704/tmp/tajo-jenkins/staging/q_1441869218561_0154/RESULT/part-02-000000-000
2015-09-10 07:16:09,431 INFO: org.apache.tajo.worker.TaskImpl (initPlan(145)) -
Output File Path:
hdfs://localhost:43704/tmp/tajo-jenkins/staging/q_1441869218561_0154/RESULT/part-02-000000-000
2015-09-10 07:16:09,431 INFO: org.apache.tajo.worker.TaskImpl (initPlan(150)) -
==================================
2015-09-10 07:16:09,432 INFO: org.apache.tajo.worker.TaskImpl (initPlan(151)) -
* Stage ta_1441869218561_0154_000002_000000_00 is initialized
2015-09-10 07:16:09,432 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) -
* InterQuery: false, Fragments (num: 1), Fetches (total:1) :
2015-09-10 07:16:09,432 INFO: org.apache.tajo.worker.TaskImpl (initPlan(162)) -
* Local task dir:
<https://builds.apache.org/job/Tajo-0.11.0-nightly/ws/tajo-core-tests/target/test-data/45810e0d-e392-41fb-9221-34d2778d7385/tajo-localdir/q_1441869218561_0154/output/2/0_0>
2015-09-10 07:16:09,432 INFO: org.apache.tajo.worker.TaskImpl (initPlan(167)) -
==================================
2015-09-10 07:16:09,432 INFO: org.apache.tajo.worker.TaskImpl (init(214)) - the
directory is created
<https://builds.apache.org/job/Tajo-0.11.0-nightly/ws/tajo-core-tests/target/test-data/45810e0d-e392-41fb-9221-34d2778d7385/tajo-localdir/q_1441869218561_0154/in/eb_1441869218561_0154_000002/0/0/eb_1441869218561_0154_000001>
2015-09-10 07:16:09,433 INFO: org.apache.tajo.worker.TaskImpl
(getFetchRunners(692)) - Try to get local file chunk at local host
2015-09-10 07:16:09,433 INFO: org.apache.tajo.worker.TaskImpl
(getLocalStoredFileChunk(734)) - getLocalStoredFileChunk starts
2015-09-10 07:16:09,433 INFO: org.apache.tajo.worker.TaskImpl
(getLocalStoredFileChunk(769)) - PullServer request param: shuffleType=h,
sid=1, partId=0, taskIds=null
2015-09-10 07:16:09,433 INFO: org.apache.tajo.worker.TaskImpl
(getFetchRunners(720)) - Create a new Fetcher with storeChunk: (start=0,
length=8, fromRemote=false, ebId=eb_1441869218561_0154_000001)
<https://builds.apache.org/job/Tajo-0.11.0-nightly/ws/tajo-core-tests/target/test-data/45810e0d-e392-41fb-9221-34d2778d7385/tajo-localdir/q_1441869218561_0154/output/1/hash-shuffle/0/0>
2015-09-10 07:16:09,433 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(142)) - Query status of ta_1441869218561_0154_000002_000000_00 is
changed to TA_RUNNING
2015-09-10 07:16:09,433 INFO: org.apache.tajo.worker.Fetcher (get(128)) - Get
pseudo fetch from local host
2015-09-10 07:16:09,434 INFO: org.apache.tajo.worker.TaskImpl (run(616)) - Add
a new FileChunk to local chunk list
2015-09-10 07:16:09,434 INFO: org.apache.tajo.worker.TaskImpl
(waitForFetch(362)) - ta_1441869218561_0154_000002_000000_00 All fetches are
done!
2015-09-10 07:16:09,434 INFO: org.apache.tajo.worker.TaskImpl
(localizeFetchedData(572)) - One local chunk is added to listTablets
2015-09-10 07:16:09,434 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(createInMemoryHashAggregation(974)) - The planner chooses [Hash Aggregation]
2015-09-10 07:16:09,444 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:47763 is added to
blk_1073742452_1628{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-02df53c2-b77a-427d-9410-692c8655dbb0:NORMAL:127.0.0.1:47763|FINALIZED]]}
size 0
2015-09-10 07:16:09,445 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(142)) - Query status of ta_1441869218561_0154_000002_000000_00 is
changed to TA_SUCCEEDED
2015-09-10 07:16:09,445 INFO: org.apache.tajo.worker.TaskImpl (run(460)) -
ta_1441869218561_0154_000002_000000_00 completed. Worker's task counter -
total:1, succeeded: 1, killed: 0, failed: 0
2015-09-10 07:16:09,446 INFO: org.apache.tajo.querymaster.Stage
(transition(1345)) - Stage - eb_1441869218561_0154_000002 finalize NONE_SHUFFLE
(total=1, success=1, killed=0)
2015-09-10 07:16:09,446 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(stop(160)) - Task Scheduler stopped
2015-09-10 07:16:09,446 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(run(123)) - TaskScheduler schedulingThread stopped
2015-09-10 07:16:09,446 INFO: org.apache.tajo.querymaster.Stage
(transition(1403)) - Stage completed - eb_1441869218561_0154_000002 (total=1,
success=1, killed=0)
2015-09-10 07:16:09,446 INFO: org.apache.tajo.querymaster.Query (handle(855)) -
Processing q_1441869218561_0154 of type STAGE_COMPLETED
2015-09-10 07:16:09,446 INFO:
org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next
executable block eb_1441869218561_0154_000003
2015-09-10 07:16:09,447 INFO: org.apache.tajo.querymaster.Query
(transition(802)) - Complete Stage[eb_1441869218561_0154_000002], State:
SUCCEEDED, 2/2.
2015-09-10 07:16:09,447 INFO: org.apache.tajo.querymaster.Query (handle(855)) -
Processing q_1441869218561_0154 of type QUERY_COMPLETED
2015-09-10 07:16:09,447 INFO: org.apache.tajo.worker.TaskManager
(stopExecutionBlock(161)) - Stopped execution block:eb_1441869218561_0154_000002
2015-09-10 07:16:09,448 INFO: org.apache.tajo.querymaster.Query (handle(873)) -
q_1441869218561_0154 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED
2015-09-10 07:16:09,448 INFO: org.apache.tajo.querymaster.QueryMasterTask
(handle(292)) - Query completion notified from q_1441869218561_0154 final
state: QUERY_SUCCEEDED
2015-09-10 07:16:09,449 INFO: org.apache.tajo.master.QueryInProgress
(heartbeat(253)) - Received QueryMaster
heartbeat:q_1441869218561_0154,state=QUERY_SUCCEEDED,progress=1.0,
queryMaster=asf901.gq1.ygridcore.net
2015-09-10 07:16:09,449 INFO: org.apache.tajo.querymaster.QueryMasterTask
(serviceStop(169)) - Stopping QueryMasterTask:q_1441869218561_0154
2015-09-10 07:16:09,449 INFO: org.apache.tajo.master.QueryManager
(stopQuery(279)) - Stop QueryInProgress:q_1441869218561_0154
2015-09-10 07:16:09,449 INFO: org.apache.tajo.master.QueryInProgress
(stopProgress(118)) - =========================================================
2015-09-10 07:16:09,449 INFO: org.apache.tajo.master.QueryInProgress
(stopProgress(119)) - Stop query:q_1441869218561_0154
2015-09-10 07:16:09,449 INFO: org.apache.tajo.querymaster.QueryMasterTask
(cleanupQuery(469)) - Cleanup resources of all workers. Query:
q_1441869218561_0154, workers: 1
2015-09-10 07:16:09,449 INFO: org.apache.tajo.querymaster.QueryMasterTask
(serviceStop(185)) - Stopped QueryMasterTask:q_1441869218561_0154
2015-09-10 07:16:09,562 INFO: org.apache.tajo.storage.FileTablespace
(listStatus(368)) - Total input paths to process : 1
2015-09-10 07:16:09,564 INFO: org.apache.tajo.storage.FileTablespace
(getSplits(569)) - Total # of splits: 1
2015-09-10 07:16:09,568 INFO: org.apache.tajo.master.TajoMasterClientService
(getQueryResultData(579)) - Send result to client for
9dbd5517-66b2-4357-be88-497902909ebb,q_1441869218561_0154, 1 rows
2015-09-10 07:16:09,569 INFO: org.apache.tajo.master.TajoMasterClientService
(getQueryResultData(579)) - Send result to client for
9dbd5517-66b2-4357-be88-497902909ebb,q_1441869218561_0154, 0 rows
2015-09-10 07:16:09,571 INFO: BlockStateChange (addToInvalidates(1106)) -
BLOCK* addToInvalidates: blk_1073742452_1628 127.0.0.1:47763
2015-09-10 07:16:09,574 INFO: org.apache.tajo.master.GlobalEngine
(executeQuery(176)) - Query: select sum(l_linenumber) from lineitem limit 10
2015-09-10 07:16:09,596 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(280)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
PROJECTION(2)
=> Targets: ?sum (INT8)
=> out schema: {(1) ?sum (INT8)}
=> in schema: {(1) ?sum (INT8)}
GROUP_BY(3)()
=> exprs: (sum(default.lineitem.l_linenumber (INT4)))
=> target list: ?sum (INT8)
=> out schema:{(1) ?sum (INT8)}
=> 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)}
SCAN(0) on default.lineitem
=> target list: 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)
=> out 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)}
=> 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)}
2015-09-10 07:16:09,597 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(282)) - =============================================
2015-09-10 07:16:09,597 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(283)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
[LogicalPlan]
> ProjectionNode is eliminated.
-----------------------------
GROUP_BY(3)()
=> exprs: (sum(default.lineitem.l_linenumber (INT4)))
=> target list: ?sum (INT8)
=> out schema:{(1) ?sum (INT8)}
=> in schema:{(1) default.lineitem.l_linenumber (INT4)}
SCAN(0) on default.lineitem
=> target list: default.lineitem.l_linenumber (INT4)
=> out schema: {(1) default.lineitem.l_linenumber (INT4)}
=> 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)}
2015-09-10 07:16:09,597 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(284)) - =============================================
2015-09-10 07:16:09,597 INFO: org.apache.tajo.master.exec.QueryExecutor
(executeDistributedQuery(514)) - Query q_1441869218561_0155,select
sum(l_linenumber) from lineitem limit 10, is forwarded to :0
2015-09-10 07:16:09,598 INFO: org.apache.tajo.master.QueryInProgress
(connectQueryMaster(184)) - Try to connect to
QueryMaster:asf901.gq1.ygridcore.net/67.195.81.145:29027
2015-09-10 07:16:09,599 INFO: org.apache.tajo.master.QueryInProgress
(allocateToQueryMaster(163)) - Initializing QueryInProgress for
QueryID=q_1441869218561_0155
2015-09-10 07:16:09,599 INFO: org.apache.tajo.master.scheduler.SimpleScheduler
(run(384)) - Running Queries: 1
2015-09-10 07:16:09,599 INFO: org.apache.tajo.master.QueryInProgress
(submitToQueryMaster(206)) - Call executeQuery to
:asf901.gq1.ygridcore.net:29027,q_1441869218561_0155
2015-09-10 07:16:09,601 INFO:
org.apache.tajo.querymaster.QueryMasterManagerService (executeQuery(235)) -
Receive executeQuery request:q_1441869218561_0155
2015-09-10 07:16:09,601 INFO: org.apache.tajo.querymaster.QueryMaster
(handle(361)) - Start QueryStartEventHandler:q_1441869218561_0155
2015-09-10 07:16:09,601 INFO: org.apache.tajo.querymaster.QueryMasterTask
(startQuery(314)) - INDEX_ENABLED : false
Killed
Results :
Tests run: 302, Failures: 0, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 2.092 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 2.028 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.480 s]
[INFO] Tajo Common ....................................... SUCCESS [ 40.375 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 2.426 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 4.725 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 6.710 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 1.439 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 46.335 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.382 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 10.936 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 3.129 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [ 54.720 s]
[INFO] Tajo PullServer ................................... SUCCESS [ 1.058 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.343 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 2.193 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 4.973 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 1.850 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 4.342 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.379 s]
[INFO] Tajo Core ......................................... SUCCESS [ 6.289 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 0.941 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 14.635 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.096 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 1.034 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.033 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 4.587 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 2.618 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 35.044 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 1.043 s]
[INFO] Tajo Distribution ................................. SUCCESS [ 5.539 s]
[INFO] Tajo Core Tests ................................... FAILURE [02:41 min]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 07:13 min
[INFO] Finished at: 2015-09-10T07:16:09+00:00
[INFO] Final Memory: 120M/486M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal
org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on
project tajo-core-tests: Execution default-test of goal
org.apache.maven.plugins:maven-surefire-plugin:2.17:test failed: The forked VM
terminated without properly saying goodbye. VM crash or System.exit called?
[ERROR] Command was /bin/sh -c cd
<https://builds.apache.org/job/Tajo-0.11.0-nightly/ws/tajo-core-tests> &&
/home/jenkins/tools/java/jdk1.7.0_25-32/jre/bin/java -Xms512m -Xmx1024m
-XX:MaxPermSize=152m -Dfile.encoding=UTF-8 -Dderby.storage.pageSize=1024
-Dderby.stream.error.file=/dev/null -jar
<https://builds.apache.org/job/Tajo-0.11.0-nightly/ws/tajo-core-tests/target/surefire/surefirebooter5011696590026558956.jar>
<https://builds.apache.org/job/Tajo-0.11.0-nightly/ws/tajo-core-tests/target/surefire/surefire3552591505436042107tmp>
<https://builds.apache.org/job/Tajo-0.11.0-nightly/ws/tajo-core-tests/target/surefire/surefire_141757170815533326753tmp>
[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/PluginExecutionException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :tajo-core-tests
Build step 'Execute shell' marked build as failure
Archiving artifacts
Sending artifact delta relative to Tajo-0.11.0-nightly #22
Archived 1 artifacts
Archive block size is 32768
Received 0 blocks and 59077595 bytes
Compression is 0.0%
Took 17 sec
Recording test results
