See <https://builds.apache.org/job/Tajo-master-build/943/>
------------------------------------------
[...truncated 675432 lines...]
-------------------------------------------------------------------------------
Order of Execution
-------------------------------------------------------------------------------
1: eb_1445316369644_1955_000001
2: eb_1445316369644_1955_000002
3: eb_1445316369644_1955_000004
4: eb_1445316369644_1955_000005
5: eb_1445316369644_1955_000003
-------------------------------------------------------------------------------
=======================================================
Block Id: eb_1445316369644_1955_000001 [ROOT]
=======================================================
TABLE_SUBQUERY(22) as default.a
=> Targets: default.a.c_custkey (INT4) as default.a.c_custkey,
default.a.c_nationkey (INT4) as default.a.c_nationkey
=> out schema: {(2) default.a.c_custkey (INT4), default.a.c_nationkey (INT4)}
=> in schema: {(2) default.a.c_custkey (INT4), default.a.c_nationkey (INT4)}
SCAN(1) on default.customer
=> filter: default.customer.c_nationkey (INT4) < 0
=> target list: default.customer.c_custkey (INT4),
default.customer.c_nationkey (INT4)
=> out schema: {(2) default.customer.c_custkey (INT4),
default.customer.c_nationkey (INT4)}
=> in schema: {(8) default.customer.c_custkey (INT4),
default.customer.c_name (TEXT), default.customer.c_address (TEXT),
default.customer.c_nationkey (INT4), default.customer.c_phone (TEXT),
default.customer.c_acctbal (FLOAT8), default.customer.c_mktsegment (TEXT),
default.customer.c_comment (TEXT)}
=======================================================
Block Id: eb_1445316369644_1955_000002 [ROOT]
=======================================================
TABLE_SUBQUERY(23) as default.a
=> Targets: default.a.c_custkey (INT4), default.a.c_nationkey (INT4)
=> out schema: {(2) default.a.c_custkey (INT4), default.a.c_nationkey (INT4)}
=> in schema: {(2) default.a.c_custkey (INT4), default.a.c_nationkey (INT4)}
SCAN(4) on default.customer
=> filter: default.customer.c_nationkey (INT4) > 0
=> target list: default.customer.c_custkey (INT4),
default.customer.c_nationkey (INT4)
=> out schema: {(2) default.customer.c_custkey (INT4),
default.customer.c_nationkey (INT4)}
=> in schema: {(8) default.customer.c_custkey (INT4),
default.customer.c_name (TEXT), default.customer.c_address (TEXT),
default.customer.c_nationkey (INT4), default.customer.c_phone (TEXT),
default.customer.c_acctbal (FLOAT8), default.customer.c_mktsegment (TEXT),
default.customer.c_comment (TEXT)}
=======================================================
Block Id: eb_1445316369644_1955_000004 [ROOT]
=======================================================
TABLE_SUBQUERY(24) as default.b
=> Targets: default.b.c_custkey (INT4) as default.b.c_custkey,
default.b.c_nationkey (INT4) as default.b.c_nationkey
=> out schema: {(2) default.b.c_custkey (INT4), default.b.c_nationkey (INT4)}
=> in schema: {(2) default.b.c_custkey (INT4), default.b.c_nationkey (INT4)}
SCAN(10) on default.customer
=> filter: default.customer.c_nationkey (INT4) < 0
=> target list: default.customer.c_custkey (INT4),
default.customer.c_nationkey (INT4)
=> out schema: {(2) default.customer.c_custkey (INT4),
default.customer.c_nationkey (INT4)}
=> in schema: {(8) default.customer.c_custkey (INT4),
default.customer.c_name (TEXT), default.customer.c_address (TEXT),
default.customer.c_nationkey (INT4), default.customer.c_phone (TEXT),
default.customer.c_acctbal (FLOAT8), default.customer.c_mktsegment (TEXT),
default.customer.c_comment (TEXT)}
=======================================================
Block Id: eb_1445316369644_1955_000005 [ROOT]
=======================================================
TABLE_SUBQUERY(25) as default.b
=> Targets: default.b.c_custkey (INT4), default.b.c_nationkey (INT4)
=> out schema: {(2) default.b.c_custkey (INT4), default.b.c_nationkey (INT4)}
=> in schema: {(2) default.b.c_custkey (INT4), default.b.c_nationkey (INT4)}
SCAN(13) on default.customer
=> filter: default.customer.c_nationkey (INT4) > 0
=> target list: default.customer.c_custkey (INT4),
default.customer.c_nationkey (INT4)
=> out schema: {(2) default.customer.c_custkey (INT4),
default.customer.c_nationkey (INT4)}
=> in schema: {(8) default.customer.c_custkey (INT4),
default.customer.c_name (TEXT), default.customer.c_address (TEXT),
default.customer.c_nationkey (INT4), default.customer.c_phone (TEXT),
default.customer.c_acctbal (FLOAT8), default.customer.c_mktsegment (TEXT),
default.customer.c_comment (TEXT)}
=======================================================
Block Id: eb_1445316369644_1955_000003 [TERMINAL]
=======================================================
2015-10-20 05:08:25,120 INFO: org.apache.tajo.querymaster.Query (<init>(234)) -
=======================================================
The order of execution:
1: eb_1445316369644_1955_000001
2: eb_1445316369644_1955_000002
3: eb_1445316369644_1955_000004
4: eb_1445316369644_1955_000005
5: eb_1445316369644_1955_000003
=======================================================
2015-10-20 05:08:25,121 INFO: org.apache.tajo.querymaster.Query (handle(868)) -
Processing q_1445316369644_1955 of type START
2015-10-20 05:08:25,121 INFO:
org.apache.tajo.engine.planner.global.ParallelExecutionQueue (first(84)) -
Initial executable blocks [eb_1445316369644_1955_000001,
eb_1445316369644_1955_000002, eb_1445316369644_1955_000004,
eb_1445316369644_1955_000005]
2015-10-20 05:08:25,121 INFO: org.apache.tajo.querymaster.Query (handle(886)) -
q_1445316369644_1955 Query Transitioned from QUERY_NEW to QUERY_RUNNING
2015-10-20 05:08:25,137 INFO: org.apache.tajo.querymaster.Stage
(initTaskScheduler(901)) - org.apache.tajo.querymaster.DefaultTaskScheduler is
chosen for the task scheduling for eb_1445316369644_1955_000001
2015-10-20 05:08:25,139 INFO: org.apache.tajo.storage.FileTablespace
(listStatus(363)) - Total input paths to process : 1
2015-10-20 05:08:25,140 INFO: org.apache.tajo.storage.FileTablespace
(getSplits(564)) - Total # of splits: 1
2015-10-20 05:08:25,140 INFO: org.apache.tajo.querymaster.Stage (run(853)) - 1
objects are scheduled
2015-10-20 05:08:25,140 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(start(134)) - Start TaskScheduler
2015-10-20 05:08:25,153 INFO: org.apache.tajo.querymaster.Stage
(initTaskScheduler(901)) - org.apache.tajo.querymaster.DefaultTaskScheduler is
chosen for the task scheduling for eb_1445316369644_1955_000002
2015-10-20 05:08:25,155 INFO: org.apache.tajo.storage.FileTablespace
(listStatus(363)) - Total input paths to process : 1
2015-10-20 05:08:25,156 INFO: org.apache.tajo.storage.FileTablespace
(getSplits(564)) - Total # of splits: 1
2015-10-20 05:08:25,156 INFO: org.apache.tajo.querymaster.Stage (run(853)) - 1
objects are scheduled
2015-10-20 05:08:25,156 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(start(134)) - Start TaskScheduler
2015-10-20 05:08:25,173 INFO: org.apache.tajo.querymaster.Stage
(initTaskScheduler(901)) - org.apache.tajo.querymaster.DefaultTaskScheduler is
chosen for the task scheduling for eb_1445316369644_1955_000004
2015-10-20 05:08:25,175 INFO: org.apache.tajo.storage.FileTablespace
(listStatus(363)) - Total input paths to process : 1
2015-10-20 05:08:25,176 INFO: org.apache.tajo.storage.FileTablespace
(getSplits(564)) - Total # of splits: 1
2015-10-20 05:08:25,176 INFO: org.apache.tajo.querymaster.Stage (run(853)) - 1
objects are scheduled
2015-10-20 05:08:25,176 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(start(134)) - Start TaskScheduler
2015-10-20 05:08:25,216 INFO: org.apache.tajo.querymaster.Stage
(initTaskScheduler(901)) - org.apache.tajo.querymaster.DefaultTaskScheduler is
chosen for the task scheduling for eb_1445316369644_1955_000005
2015-10-20 05:08:25,220 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(increaseConcurrency(524)) - Assigned host : 127.0.0.1, Unknown Volume : -1,
Concurrency : 1
2015-10-20 05:08:25,220 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(increaseConcurrency(524)) - Assigned host : 127.0.0.1, Unknown Volume : -1,
Concurrency : 1
2015-10-20 05:08:25,220 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(increaseConcurrency(524)) - Assigned host : 127.0.0.1, Unknown Volume : -1,
Concurrency : 1
2015-10-20 05:08:25,221 INFO: org.apache.tajo.storage.FileTablespace
(listStatus(363)) - Total input paths to process : 1
2015-10-20 05:08:25,221 INFO: org.apache.tajo.worker.TaskManager
(createExecutionBlock(114)) - QueryMaster Address:asf901.gq1.ygridcore.net:13890
2015-10-20 05:08:25,222 INFO: org.apache.tajo.storage.FileTablespace
(getSplits(564)) - Total # of splits: 1
2015-10-20 05:08:25,222 INFO: org.apache.tajo.querymaster.Stage (run(853)) - 1
objects are scheduled
2015-10-20 05:08:25,222 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(start(134)) - Start TaskScheduler
2015-10-20 05:08:25,222 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(assignToLeafTasks(921)) - Assigned Local/Rack/Total: (0/1/1), Attempted
Cancel/Assign/Total: (0/1/1), Locality: 0.00%, Rack host:
asf901.gq1.ygridcore.net
2015-10-20 05:08:25,222 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(assignToLeafTasks(921)) - Assigned Local/Rack/Total: (0/1/1), Attempted
Cancel/Assign/Total: (0/1/1), Locality: 0.00%, Rack host:
asf901.gq1.ygridcore.net
2015-10-20 05:08:25,222 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(assignToLeafTasks(921)) - Assigned Local/Rack/Total: (0/1/1), Attempted
Cancel/Assign/Total: (0/1/1), Locality: 0.00%, Rack host:
asf901.gq1.ygridcore.net
2015-10-20 05:08:25,223 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(119)) - Tajo Root Dir: hdfs://localhost:51366/tajo
2015-10-20 05:08:25,223 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(120)) - Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/9b92c0e0-d7a9-4e0b-b556-fbc0e8972780/tajo-localdir>
2015-10-20 05:08:25,224 INFO: org.apache.tajo.worker.TaskManager (handle(184))
- Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:0,
Disks:0, vCores:0)
2015-10-20 05:08:25,224 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) -
Task basedir is created
(<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/9b92c0e0-d7a9-4e0b-b556-fbc0e8972780/tajo-localdir/q_1445316369644_1955/output/1)>
2015-10-20 05:08:25,225 INFO: org.apache.tajo.worker.TaskManager
(createExecutionBlock(114)) - QueryMaster Address:asf901.gq1.ygridcore.net:13890
2015-10-20 05:08:25,225 INFO: org.apache.tajo.worker.TaskImpl (init(197)) -
Initializing: ta_1445316369644_1955_000001_000000_00
2015-10-20 05:08:25,225 INFO: org.apache.tajo.storage.FileTablespace
(getAppenderFilePath(289)) - Output File Path:
hdfs://localhost:51366/tajo/warehouse/default/testctaswithmultipleunions/.staging/q_1445316369644_1955/RESULT/part-01-000000-000
2015-10-20 05:08:25,225 INFO: org.apache.tajo.worker.TaskImpl (initPlan(147)) -
Output File Path:
hdfs://localhost:51366/tajo/warehouse/default/testctaswithmultipleunions/.staging/q_1445316369644_1955/RESULT/part-01-000000-000
2015-10-20 05:08:25,225 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) -
==================================
2015-10-20 05:08:25,225 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) -
* Stage ta_1445316369644_1955_000001_000000_00 is initialized
2015-10-20 05:08:25,225 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) -
* InterQuery: false, Fragments (num: 1), Fetches (total:0) :
2015-10-20 05:08:25,225 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) -
* Local task dir:
<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/9b92c0e0-d7a9-4e0b-b556-fbc0e8972780/tajo-localdir/q_1445316369644_1955/output/1/0_0>
2015-10-20 05:08:25,225 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) -
==================================
2015-10-20 05:08:25,226 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(119)) - Tajo Root Dir: hdfs://localhost:51366/tajo
2015-10-20 05:08:25,226 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(120)) - Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/9b92c0e0-d7a9-4e0b-b556-fbc0e8972780/tajo-localdir>
2015-10-20 05:08:25,226 INFO: org.apache.tajo.worker.TaskManager (handle(184))
- Running ExecutionBlocks: 2, running tasks:1, availableResource: (Memory:0,
Disks:0, vCores:0)
2015-10-20 05:08:25,227 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) -
Task basedir is created
(<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/9b92c0e0-d7a9-4e0b-b556-fbc0e8972780/tajo-localdir/q_1445316369644_1955/output/4)>
2015-10-20 05:08:25,227 INFO: org.apache.tajo.worker.TaskManager
(createExecutionBlock(114)) - QueryMaster Address:asf901.gq1.ygridcore.net:13890
2015-10-20 05:08:25,227 INFO: org.apache.tajo.worker.TaskImpl (init(197)) -
Initializing: ta_1445316369644_1955_000004_000000_00
2015-10-20 05:08:25,227 INFO: org.apache.tajo.storage.FileTablespace
(getAppenderFilePath(289)) - Output File Path:
hdfs://localhost:51366/tajo/warehouse/default/testctaswithmultipleunions/.staging/q_1445316369644_1955/RESULT/part-04-000000-000
2015-10-20 05:08:25,227 INFO: org.apache.tajo.worker.TaskImpl (initPlan(147)) -
Output File Path:
hdfs://localhost:51366/tajo/warehouse/default/testctaswithmultipleunions/.staging/q_1445316369644_1955/RESULT/part-04-000000-000
2015-10-20 05:08:25,227 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) -
==================================
2015-10-20 05:08:25,227 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) -
* Stage ta_1445316369644_1955_000004_000000_00 is initialized
2015-10-20 05:08:25,227 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) -
* InterQuery: false, Fragments (num: 1), Fetches (total:0) :
2015-10-20 05:08:25,227 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) -
* Local task dir:
<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/9b92c0e0-d7a9-4e0b-b556-fbc0e8972780/tajo-localdir/q_1445316369644_1955/output/4/0_0>
2015-10-20 05:08:25,227 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) -
==================================
2015-10-20 05:08:25,228 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(119)) - Tajo Root Dir: hdfs://localhost:51366/tajo
2015-10-20 05:08:25,228 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(120)) - Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/9b92c0e0-d7a9-4e0b-b556-fbc0e8972780/tajo-localdir>
2015-10-20 05:08:25,229 INFO: org.apache.tajo.worker.TaskManager (handle(184))
- Running ExecutionBlocks: 3, running tasks:2, availableResource: (Memory:0,
Disks:0, vCores:0)
2015-10-20 05:08:25,230 INFO: org.apache.tajo.worker.TaskImpl (run(462)) -
ta_1445316369644_1955_000001_000000_00 completed. Worker's task counter -
total:1, succeeded: 1, killed: 0, failed: 0
2015-10-20 05:08:25,230 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) -
Task basedir is created
(<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/9b92c0e0-d7a9-4e0b-b556-fbc0e8972780/tajo-localdir/q_1445316369644_1955/output/2)>
2015-10-20 05:08:25,230 INFO: org.apache.tajo.querymaster.Stage
(transition(1349)) - Stage - eb_1445316369644_1955_000001 finalize NONE_SHUFFLE
(total=1, success=1, killed=0)
2015-10-20 05:08:25,230 INFO: org.apache.tajo.worker.TaskImpl (init(197)) -
Initializing: ta_1445316369644_1955_000002_000000_00
2015-10-20 05:08:25,230 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(run(126)) - TaskScheduler schedulingThread stopped
2015-10-20 05:08:25,230 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(stop(163)) - Task Scheduler stopped
2015-10-20 05:08:25,230 INFO: org.apache.tajo.storage.FileTablespace
(getAppenderFilePath(289)) - Output File Path:
hdfs://localhost:51366/tajo/warehouse/default/testctaswithmultipleunions/.staging/q_1445316369644_1955/RESULT/part-02-000000-000
2015-10-20 05:08:25,231 INFO: org.apache.tajo.worker.TaskImpl (initPlan(147)) -
Output File Path:
hdfs://localhost:51366/tajo/warehouse/default/testctaswithmultipleunions/.staging/q_1445316369644_1955/RESULT/part-02-000000-000
2015-10-20 05:08:25,231 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) -
==================================
2015-10-20 05:08:25,231 INFO: org.apache.tajo.querymaster.Stage
(transition(1407)) - Stage completed - eb_1445316369644_1955_000001 (total=1,
success=1, killed=0)
2015-10-20 05:08:25,231 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) -
* Stage ta_1445316369644_1955_000002_000000_00 is initialized
2015-10-20 05:08:25,231 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) -
* InterQuery: false, Fragments (num: 1), Fetches (total:0) :
2015-10-20 05:08:25,231 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) -
* Local task dir:
<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/9b92c0e0-d7a9-4e0b-b556-fbc0e8972780/tajo-localdir/q_1445316369644_1955/output/2/0_0>
2015-10-20 05:08:25,231 INFO: org.apache.tajo.worker.TaskImpl (run(462)) -
ta_1445316369644_1955_000004_000000_00 completed. Worker's task counter -
total:1, succeeded: 1, killed: 0, failed: 0
2015-10-20 05:08:25,231 INFO: org.apache.tajo.worker.TaskManager
(stopExecutionBlock(163)) - Stopped execution block:eb_1445316369644_1955_000001
2015-10-20 05:08:25,231 INFO: org.apache.tajo.querymaster.Query (handle(868)) -
Processing q_1445316369644_1955 of type STAGE_COMPLETED
2015-10-20 05:08:25,231 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) -
==================================
2015-10-20 05:08:25,232 INFO: org.apache.tajo.querymaster.Stage
(transition(1349)) - Stage - eb_1445316369644_1955_000004 finalize NONE_SHUFFLE
(total=1, success=1, killed=0)
2015-10-20 05:08:25,232 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(stop(163)) - Task Scheduler stopped
2015-10-20 05:08:25,232 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(run(126)) - TaskScheduler schedulingThread stopped
2015-10-20 05:08:25,232 INFO: org.apache.tajo.querymaster.Stage
(transition(1407)) - Stage completed - eb_1445316369644_1955_000004 (total=1,
success=1, killed=0)
2015-10-20 05:08:25,232 INFO: org.apache.tajo.querymaster.Query (handle(868)) -
Processing q_1445316369644_1955 of type STAGE_COMPLETED
2015-10-20 05:08:25,233 INFO: org.apache.tajo.worker.TaskManager
(stopExecutionBlock(163)) - Stopped execution block:eb_1445316369644_1955_000004
2015-10-20 05:08:25,237 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:35632 is added to
blk_1073747814_6990{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-c5d9d34c-2c9f-4e0c-b484-15a33a556385:NORMAL:127.0.0.1:35632|RBW]]}
size 22
2015-10-20 05:08:25,273 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(increaseConcurrency(524)) - Assigned host : 127.0.0.1, Unknown Volume : -1,
Concurrency : 1
2015-10-20 05:08:25,274 INFO: org.apache.tajo.worker.TaskManager
(createExecutionBlock(114)) - QueryMaster Address:asf901.gq1.ygridcore.net:13890
2015-10-20 05:08:25,274 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(assignToLeafTasks(921)) - Assigned Local/Rack/Total: (0/1/1), Attempted
Cancel/Assign/Total: (0/1/1), Locality: 0.00%, Rack host:
asf901.gq1.ygridcore.net
2015-10-20 05:08:25,276 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(119)) - Tajo Root Dir: hdfs://localhost:51366/tajo
2015-10-20 05:08:25,276 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(120)) - Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/9b92c0e0-d7a9-4e0b-b556-fbc0e8972780/tajo-localdir>
2015-10-20 05:08:25,277 INFO: org.apache.tajo.worker.TaskManager (handle(184))
- Running ExecutionBlocks: 2, running tasks:1, availableResource: (Memory:500,
Disks:1, vCores:1)
2015-10-20 05:08:25,277 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) -
Task basedir is created
(<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/9b92c0e0-d7a9-4e0b-b556-fbc0e8972780/tajo-localdir/q_1445316369644_1955/output/5)>
2015-10-20 05:08:25,278 INFO: org.apache.tajo.worker.TaskImpl (init(197)) -
Initializing: ta_1445316369644_1955_000005_000000_00
2015-10-20 05:08:25,278 INFO: org.apache.tajo.storage.FileTablespace
(getAppenderFilePath(289)) - Output File Path:
hdfs://localhost:51366/tajo/warehouse/default/testctaswithmultipleunions/.staging/q_1445316369644_1955/RESULT/part-05-000000-000
2015-10-20 05:08:25,278 INFO: org.apache.tajo.worker.TaskImpl (initPlan(147)) -
Output File Path:
hdfs://localhost:51366/tajo/warehouse/default/testctaswithmultipleunions/.staging/q_1445316369644_1955/RESULT/part-05-000000-000
2015-10-20 05:08:25,278 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) -
==================================
2015-10-20 05:08:25,278 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) -
* Stage ta_1445316369644_1955_000005_000000_00 is initialized
2015-10-20 05:08:25,278 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) -
* InterQuery: false, Fragments (num: 1), Fetches (total:0) :
2015-10-20 05:08:25,278 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) -
* Local task dir:
<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/9b92c0e0-d7a9-4e0b-b556-fbc0e8972780/tajo-localdir/q_1445316369644_1955/output/5/0_0>
2015-10-20 05:08:25,279 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) -
==================================
2015-10-20 05:08:25,285 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:35632 is added to
blk_1073747815_6991{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-c5d9d34c-2c9f-4e0c-b484-15a33a556385:NORMAL:127.0.0.1:35632|RBW]]}
size 0
2015-10-20 05:08:25,286 INFO: org.apache.tajo.worker.TaskImpl (run(462)) -
ta_1445316369644_1955_000005_000000_00 completed. Worker's task counter -
total:1, succeeded: 1, killed: 0, failed: 0
2015-10-20 05:08:25,286 INFO: org.apache.tajo.querymaster.Stage
(transition(1349)) - Stage - eb_1445316369644_1955_000005 finalize NONE_SHUFFLE
(total=1, success=1, killed=0)
2015-10-20 05:08:25,286 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(stop(163)) - Task Scheduler stopped
2015-10-20 05:08:25,286 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(run(126)) - TaskScheduler schedulingThread stopped
2015-10-20 05:08:25,286 INFO: org.apache.tajo.querymaster.Stage
(transition(1407)) - Stage completed - eb_1445316369644_1955_000005 (total=1,
success=1, killed=0)
2015-10-20 05:08:25,286 INFO: org.apache.tajo.querymaster.Query (handle(868)) -
Processing q_1445316369644_1955 of type STAGE_COMPLETED
2015-10-20 05:08:25,287 INFO: org.apache.tajo.worker.TaskManager
(stopExecutionBlock(163)) - Stopped execution block:eb_1445316369644_1955_000005
Killed
Results :
Tests run: 1348, Failures: 0, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 2.477 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 2.092 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 4.798 s]
[INFO] Tajo Common ....................................... SUCCESS [ 36.468 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 2.799 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 7.394 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 8.952 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 1.246 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 51.003 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.742 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 13.072 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 3.406 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [ 58.037 s]
[INFO] Tajo PullServer ................................... SUCCESS [ 1.354 s]
[INFO] Tajo Client ....................................... SUCCESS [ 3.099 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 2.229 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 4.936 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 2.379 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 4.451 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.549 s]
[INFO] Tajo Core ......................................... SUCCESS [ 8.996 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 0.924 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 11.481 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.064 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 0.994 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.055 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 4.162 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 3.025 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 37.673 s]
[INFO] Tajo JDBC storage common .......................... SUCCESS [ 0.829 s]
[INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 1.061 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 1.027 s]
[INFO] Tajo Distribution ................................. SUCCESS [ 6.026 s]
[INFO] Tajo Core Tests ................................... FAILURE [22:29 min]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 27:21 min
[INFO] Finished at: 2015-10-20T05:08:25+00:00
[INFO] Final Memory: 156M/2141M
[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-master-build/ws/tajo-core-tests> &&
/home/jenkins/jenkins-slave/tools/hudson.model.JDK/jdk1.8.0_45/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-master-build/ws/tajo-core-tests/target/surefire/surefirebooter160903852312304479.jar>
<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/surefire/surefire9194414432691846963tmp>
<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/surefire/surefire_14597483480993865402tmp>
[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
