See
<https://builds.apache.org/job/Tajo-master-nightly/1059/display/redirect?page=changes>
Changes:
[github] Change the git repo to the Aapche Tajo Github (#1066)
------------------------------------------
[...truncated 76.97 MB...]
=======================================================
Block Id: eb_1567659359185_2293_000003 [ROOT]
=======================================================
[Incoming]
[q_1567659359185_2293] 2 => 3 (type=RANGE_SHUFFLE,
key=default.lineitem.l_orderkey (INT4), num=32)
[Enforcers]
0: sorted input=eb_1567659359185_2293_000002
SORT(2)
=> Sort Keys: default.lineitem.l_orderkey (INT4) (asc, nulls last)
SCAN(9) on eb_1567659359185_2293_000002
=> out schema: {(1) default.lineitem.l_orderkey (INT4)}
=> in schema: {(1) default.lineitem.l_orderkey (INT4)}
=======================================================
Block Id: eb_1567659359185_2293_000004 [TERMINAL]
=======================================================
2019-09-05 05:18:18,423 INFO: org.apache.tajo.querymaster.Query (<init>(238)) -
=======================================================
The order of execution:
1: eb_1567659359185_2293_000001
2: eb_1567659359185_2293_000002
3: eb_1567659359185_2293_000003
4: eb_1567659359185_2293_000004
=======================================================
2019-09-05 05:18:18,424 INFO: org.apache.tajo.querymaster.Query (handle(920)) -
Processing q_1567659359185_2293 of type START
2019-09-05 05:18:18,424 INFO:
org.apache.tajo.engine.planner.global.ParallelExecutionQueue (first(84)) -
Initial executable blocks [eb_1567659359185_2293_000001]
2019-09-05 05:18:18,424 INFO: org.apache.tajo.querymaster.Query (handle(938)) -
q_1567659359185_2293 Query Transitioned from QUERY_NEW to QUERY_RUNNING
2019-09-05 05:18:18,424 INFO: org.apache.tajo.querymaster.Stage
(calculatePartitionNumForAgg(1032)) - eb_1567659359185_2293_000001, Table's
volume is approximately 725 MB
2019-09-05 05:18:18,425 INFO: org.apache.tajo.querymaster.Stage
(calculateShuffleOutputNum(986)) - eb_1567659359185_2293_000001, The determined
number of aggregation partitions is 3
2019-09-05 05:18:18,425 INFO: org.apache.tajo.querymaster.Stage
(initTaskScheduler(917)) - org.apache.tajo.querymaster.DefaultTaskScheduler is
chosen for the task scheduling for eb_1567659359185_2293_000001
2019-09-05 05:18:18,427 INFO: org.apache.tajo.storage.FileTablespace
(listStatus(358)) - Total input paths to process : 1
2019-09-05 05:18:18,428 INFO: org.apache.tajo.storage.FileTablespace
(getSplits(559)) - Total # of splits: 1
2019-09-05 05:18:18,428 INFO: org.apache.tajo.querymaster.Stage (run(869)) - 1
objects are scheduled
2019-09-05 05:18:18,428 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(info(172)) - [eb_1567659359185_2293_000001] Start TaskScheduler
2019-09-05 05:18:18,429 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(info(172)) - [eb_1567659359185_2293_000001] Assigned host : 127.0.0.1, Unknown
Volume : -1, Concurrency : 1
2019-09-05 05:18:18,431 INFO: org.apache.tajo.worker.TaskManager
(createExecutionBlock(122)) - QueryMaster Address:localhost:26006
2019-09-05 05:18:18,431 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(info(172)) - [eb_1567659359185_2293_000001] Assigned Local/Rack/Total:
(0/1/1), Attempted Cancel/Assign/Total: (0/1/1), Locality: 0.00%, Rack host:
localhost
2019-09-05 05:18:18,434 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(130)) - Tajo Root Dir: hdfs://localhost:33065/tajo
2019-09-05 05:18:18,434 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(131)) - Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/375f66ea-1e51-473e-9ebe-bc0e814c587b/tajo-localdir>
2019-09-05 05:18:18,434 INFO: org.apache.tajo.worker.TaskManager (handle(192))
- Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:500,
vCores:2)
2019-09-05 05:18:18,436 INFO: org.apache.tajo.worker.TaskImpl (initPlan(159)) -
* Task ta_1567659359185_2293_000001_000000_00 is initialized. InterQuery: true,
Shuffle: HASH_SHUFFLE, Fragments: 1, Fetches:0, Local dir:
<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/375f66ea-1e51-473e-9ebe-bc0e814c587b/tajo-localdir/q_1567659359185_2293/output/1/0_0>
2019-09-05 05:18:18,436 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(createBestAggregationPlan(1021)) - Aggregation:estimatedSize=737,
threshold=67108864
2019-09-05 05:18:18,436 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(createBestAggregationPlan(1023)) - The planner chooses [Hash Aggregation]
2019-09-05 05:18:18,437 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(createInMemoryHashAggregation(965)) - The planner chooses [Hash Aggregation]
2019-09-05 05:18:18,441 INFO: org.apache.tajo.worker.TaskImpl (run(469)) -
ta_1567659359185_2293_000001_000000_00 is complete. 5 ms elapsed, final
state:TA_SUCCEEDED
2019-09-05 05:18:18,442 INFO: org.apache.tajo.querymaster.Stage
(transition(1378)) - Stage - eb_1567659359185_2293_000001 finalize HASH_SHUFFLE
(total=1, success=1, killed=0)
2019-09-05 05:18:18,442 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(info(172)) - [eb_1567659359185_2293_000001] Task Scheduler stopped
2019-09-05 05:18:18,442 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(info(172)) - [eb_1567659359185_2293_000001] TaskScheduler schedulingThread
stopped
2019-09-05 05:18:18,442 INFO: org.apache.tajo.querymaster.Stage
(transition(1389)) - eb_1567659359185_2293_000001, wait for HASH_SHUFFLE
reports. expected Tasks:1
2019-09-05 05:18:18,443 INFO:
org.apache.tajo.storage.HashShuffleAppenderManager (close(182)) - Close
HashShuffleAppenderWrapper:eb_1567659359185_2293_000001, intermediates=2
2019-09-05 05:18:18,443 INFO: org.apache.tajo.querymaster.Stage
(finalizeShuffleReport(1335)) - eb_1567659359185_2293_000001, Finalized
HASH_SHUFFLE reports: 1
2019-09-05 05:18:18,443 INFO: org.apache.tajo.worker.ExecutionBlockContext
(stop(178)) - Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0
2019-09-05 05:18:18,444 INFO: org.apache.tajo.querymaster.Stage
(transition(1436)) - Stage completed - eb_1567659359185_2293_000001 (total=1,
success=1, killed=0)
2019-09-05 05:18:18,444 INFO: org.apache.tajo.worker.TaskManager
(stopExecutionBlock(171)) - Stopped execution block:eb_1567659359185_2293_000001
2019-09-05 05:18:18,444 INFO: org.apache.tajo.querymaster.Query (handle(920)) -
Processing q_1567659359185_2293 of type STAGE_COMPLETED
2019-09-05 05:18:18,444 INFO:
org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next
executable block eb_1567659359185_2293_000002
2019-09-05 05:18:18,444 INFO: org.apache.tajo.querymaster.Query
(executeNextBlock(820)) - Scheduling Stage:eb_1567659359185_2293_000002
2019-09-05 05:18:18,445 INFO: org.apache.tajo.querymaster.Stage
(initTaskScheduler(917)) - org.apache.tajo.querymaster.DefaultTaskScheduler is
chosen for the task scheduling for eb_1567659359185_2293_000002
2019-09-05 05:18:18,445 INFO: org.apache.tajo.querymaster.Stage
(getNonLeafTaskNum(1143)) - eb_1567659359185_2293_000002, Table's volume is
approximately 1 MB
2019-09-05 05:18:18,445 INFO: org.apache.tajo.querymaster.Stage
(getNonLeafTaskNum(1148)) - eb_1567659359185_2293_000002, The determined number
of non-leaf tasks is 1
2019-09-05 05:18:18,445 INFO: org.apache.tajo.querymaster.Repartitioner
(scheduleHashShuffledFetches(843)) - eb_1567659359185_2293_000002,
ScheduleHashShuffledFetches - Max num=1, finalFetchURI=2
2019-09-05 05:18:18,445 INFO: org.apache.tajo.querymaster.Repartitioner
(scheduleHashShuffledFetches(863)) - eb_1567659359185_2293_000002,
DeterminedTaskNum : 1
2019-09-05 05:18:18,445 INFO: org.apache.tajo.querymaster.Stage (run(869)) - 1
objects are scheduled
2019-09-05 05:18:18,445 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(info(172)) - [eb_1567659359185_2293_000002] Start TaskScheduler
2019-09-05 05:18:18,448 INFO: org.apache.tajo.worker.TaskManager
(createExecutionBlock(122)) - QueryMaster Address:localhost:26006
2019-09-05 05:18:18,450 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(130)) - Tajo Root Dir: hdfs://localhost:33065/tajo
2019-09-05 05:18:18,450 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(131)) - Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/375f66ea-1e51-473e-9ebe-bc0e814c587b/tajo-localdir>
2019-09-05 05:18:18,450 INFO: org.apache.tajo.worker.TaskManager (handle(192))
- Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:500,
vCores:2)
2019-09-05 05:18:18,451 INFO: org.apache.tajo.worker.TaskImpl (initPlan(159)) -
* Task ta_1567659359185_2293_000002_000000_00 is initialized. InterQuery: true,
Shuffle: RANGE_SHUFFLE, Fragments: 1, Fetches:2, Local dir:
<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/375f66ea-1e51-473e-9ebe-bc0e814c587b/tajo-localdir/q_1567659359185_2293/output/2/0_0>
2019-09-05 05:18:18,453 INFO: org.apache.tajo.worker.TaskImpl
(getFetchRunners(713)) - Create shuffle Fetchers local:2, remote:0
2019-09-05 05:18:18,453 INFO: org.apache.tajo.worker.TaskImpl
(waitForFetch(380)) - ta_1567659359185_2293_000002_000000_00 All fetches are
done!
2019-09-05 05:18:18,454 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(createBestAggregationPlan(1021)) - Aggregation:estimatedSize=31,
threshold=67108864
2019-09-05 05:18:18,454 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(createBestAggregationPlan(1023)) - The planner chooses [Hash Aggregation]
2019-09-05 05:18:18,454 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(createInMemoryHashAggregation(965)) - The planner chooses [Hash Aggregation]
2019-09-05 05:18:18,454 INFO:
org.apache.tajo.engine.planner.physical.ExternalSortExec (<init>(144)) - TIM
sort is selected
2019-09-05 05:18:18,455 INFO:
org.apache.tajo.engine.planner.physical.RangeShuffleFileWriteExec (init(79)) -
Output data directory:
<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/375f66ea-1e51-473e-9ebe-bc0e814c587b/tajo-localdir/q_1567659359185_2293/output/2/0_0/output>
2019-09-05 05:18:18,456 INFO:
org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) -
[ta_1567659359185_2293_000002_000000_00] 1 Chunks creation time: 0 msec
2019-09-05 05:18:18,457 INFO:
org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) -
[ta_1567659359185_2293_000002_000000_00] Total merge time: 0 msec
2019-09-05 05:18:18,457 INFO: org.apache.tajo.worker.TaskImpl (run(469)) -
ta_1567659359185_2293_000002_000000_00 is complete. 4 ms elapsed, final
state:TA_SUCCEEDED
2019-09-05 05:18:18,458 INFO: org.apache.tajo.querymaster.Stage
(transition(1378)) - Stage - eb_1567659359185_2293_000002 finalize
RANGE_SHUFFLE (total=1, success=1, killed=0)
2019-09-05 05:18:18,458 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(info(172)) - [eb_1567659359185_2293_000002] Task Scheduler stopped
2019-09-05 05:18:18,458 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(info(172)) - [eb_1567659359185_2293_000002] TaskScheduler schedulingThread
stopped
2019-09-05 05:18:18,458 INFO: org.apache.tajo.querymaster.Stage
(transition(1436)) - Stage completed - eb_1567659359185_2293_000002 (total=1,
success=1, killed=0)
2019-09-05 05:18:18,459 INFO: org.apache.tajo.querymaster.Query (handle(920)) -
Processing q_1567659359185_2293 of type STAGE_COMPLETED
2019-09-05 05:18:18,459 INFO:
org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next
executable block eb_1567659359185_2293_000003
2019-09-05 05:18:18,459 INFO: org.apache.tajo.worker.ExecutionBlockContext
(stop(178)) - Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0
2019-09-05 05:18:18,459 INFO: org.apache.tajo.querymaster.Query
(executeNextBlock(820)) - Scheduling Stage:eb_1567659359185_2293_000003
2019-09-05 05:18:18,459 INFO: org.apache.tajo.querymaster.Stage
(initTaskScheduler(917)) - org.apache.tajo.querymaster.DefaultTaskScheduler is
chosen for the task scheduling for eb_1567659359185_2293_000003
2019-09-05 05:18:18,460 INFO: org.apache.tajo.querymaster.Stage
(getNonLeafTaskNum(1143)) - eb_1567659359185_2293_000003, Table's volume is
approximately 1 MB
2019-09-05 05:18:18,460 INFO: org.apache.tajo.querymaster.Stage
(getNonLeafTaskNum(1148)) - eb_1567659359185_2293_000003, The determined number
of non-leaf tasks is 1
2019-09-05 05:18:18,460 INFO: org.apache.tajo.worker.TaskManager
(stopExecutionBlock(171)) - Stopped execution block:eb_1567659359185_2293_000002
2019-09-05 05:18:18,460 INFO: org.apache.tajo.querymaster.Repartitioner
(scheduleRangeShuffledFetches(647)) - eb_1567659359185_2293_000003, Try to
divide [(1), (3)) into 1 sub ranges (total units: 1)
2019-09-05 05:18:18,460 INFO: org.apache.tajo.engine.utils.TupleUtil
(setMaxRangeIfNull(71)) - Set null into range: default.lineitem.l_orderkey,
current tuple is (NULL)
2019-09-05 05:18:18,460 INFO: org.apache.tajo.querymaster.Stage (run(869)) - 1
objects are scheduled
2019-09-05 05:18:18,460 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(info(172)) - [eb_1567659359185_2293_000003] Start TaskScheduler
2019-09-05 05:18:18,463 INFO: org.apache.tajo.worker.TaskManager
(createExecutionBlock(122)) - QueryMaster Address:localhost:26006
2019-09-05 05:18:18,465 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(130)) - Tajo Root Dir: hdfs://localhost:33065/tajo
2019-09-05 05:18:18,465 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(131)) - Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/375f66ea-1e51-473e-9ebe-bc0e814c587b/tajo-localdir>
2019-09-05 05:18:18,465 INFO: org.apache.tajo.worker.TaskManager (handle(192))
- Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:500,
vCores:2)
2019-09-05 05:18:18,466 INFO: org.apache.tajo.storage.FileTablespace
(getAppenderFilePath(282)) - Output File Path:
hdfs://localhost:33065/tmp/tajo-jenkins/staging/q_1567659359185_2293/RESULT/part-03-000000-000
2019-09-05 05:18:18,466 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) -
Output File Path:
hdfs://localhost:33065/tmp/tajo-jenkins/staging/q_1567659359185_2293/RESULT/part-03-000000-000
2019-09-05 05:18:18,466 INFO: org.apache.tajo.worker.TaskImpl (initPlan(159)) -
* Task ta_1567659359185_2293_000003_000000_00 is initialized. InterQuery:
false, Shuffle: null, Fragments: 1, Fetches:1, Local dir:
<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/375f66ea-1e51-473e-9ebe-bc0e814c587b/tajo-localdir/q_1567659359185_2293/output/3/0_0>
2019-09-05 05:18:18,468 INFO: org.apache.tajo.worker.TaskImpl
(getFetchRunners(713)) - Create shuffle Fetchers local:1, remote:0
2019-09-05 05:18:18,470 INFO: org.apache.tajo.pullserver.PullServerUtil
(getFileChunks(699)) - Index lookup time: 2 ms
2019-09-05 05:18:18,470 INFO: org.apache.tajo.worker.TaskImpl
(waitForFetch(380)) - ta_1567659359185_2293_000003_000000_00 All fetches are
done!
2019-09-05 05:18:18,471 INFO:
org.apache.tajo.engine.planner.physical.ExternalSortExec (<init>(144)) - TIM
sort is selected
2019-09-05 05:18:18,474 INFO:
org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) -
[ta_1567659359185_2293_000003_000000_00] Total merge time: 0 msec
2019-09-05 05:18:18,478 INFO: BlockStateChange (logAddStoredBlock(2621)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:36541 is added to
blk_1073749280_8456{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-b8d97d5e-7590-4cf0-bf50-155afed0d5c4:NORMAL:127.0.0.1:36541|RBW]]}
size 0
2019-09-05 05:18:18,479 INFO: org.apache.tajo.worker.TaskImpl (run(469)) -
ta_1567659359185_2293_000003_000000_00 is complete. 11 ms elapsed, final
state:TA_SUCCEEDED
2019-09-05 05:18:18,480 INFO: org.apache.tajo.querymaster.Stage
(transition(1378)) - Stage - eb_1567659359185_2293_000003 finalize NONE_SHUFFLE
(total=1, success=1, killed=0)
2019-09-05 05:18:18,480 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(info(172)) - [eb_1567659359185_2293_000003] Task Scheduler stopped
2019-09-05 05:18:18,480 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(info(172)) - [eb_1567659359185_2293_000003] TaskScheduler schedulingThread
stopped
2019-09-05 05:18:18,481 INFO: org.apache.tajo.querymaster.Stage
(transition(1436)) - Stage completed - eb_1567659359185_2293_000003 (total=1,
success=1, killed=0)
2019-09-05 05:18:18,481 INFO: org.apache.tajo.querymaster.Query (handle(920)) -
Processing q_1567659359185_2293 of type STAGE_COMPLETED
2019-09-05 05:18:18,481 INFO:
org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next
executable block eb_1567659359185_2293_000004
2019-09-05 05:18:18,481 INFO: org.apache.tajo.querymaster.Query
(transition(867)) - Complete Stage[eb_1567659359185_2293_000003], State:
SUCCEEDED, 3/3.
2019-09-05 05:18:18,481 INFO: org.apache.tajo.worker.ExecutionBlockContext
(stop(178)) - Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0
2019-09-05 05:18:18,481 INFO: org.apache.tajo.querymaster.Query (handle(920)) -
Processing q_1567659359185_2293 of type QUERY_COMPLETED
2019-09-05 05:18:18,482 INFO: org.apache.tajo.worker.TaskManager
(stopExecutionBlock(171)) - Stopped execution block:eb_1567659359185_2293_000003
2019-09-05 05:18:18,482 INFO: org.apache.tajo.querymaster.Query (handle(938)) -
q_1567659359185_2293 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED
2019-09-05 05:18:18,483 INFO: org.apache.tajo.querymaster.QueryMasterTask
(handle(299)) - Query completion notified from q_1567659359185_2293 final
state: QUERY_SUCCEEDED
2019-09-05 05:18:18,484 INFO: org.apache.tajo.master.QueryInProgress
(heartbeat(257)) - Received QueryMaster
heartbeat:q_1567659359185_2293,state=QUERY_SUCCEEDED,progress=1.0,
queryMaster=localhost
2019-09-05 05:18:18,484 INFO: org.apache.tajo.master.QueryManager
(stopQuery(279)) - Stop QueryInProgress:q_1567659359185_2293
2019-09-05 05:18:18,484 INFO: org.apache.tajo.master.QueryInProgress
(stopProgress(122)) - =========================================================
2019-09-05 05:18:18,484 INFO: org.apache.tajo.master.QueryInProgress
(stopProgress(123)) - Stop query:q_1567659359185_2293
2019-09-05 05:18:18,484 INFO: org.apache.tajo.querymaster.QueryMasterTask
(serviceStop(174)) - Stopping QueryMasterTask:q_1567659359185_2293
2019-09-05 05:18:18,485 INFO: org.apache.tajo.querymaster.QueryMasterTask
(cleanupQuery(476)) - Cleanup resources of all workers. Query:
q_1567659359185_2293, workers: 1
2019-09-05 05:18:18,485 INFO: org.apache.tajo.querymaster.QueryMasterTask
(serviceStop(191)) - Stopped QueryMasterTask:q_1567659359185_2293
2019-09-05 05:18:18,513 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session db911e65-473c-4a59-b91a-927ac6a9395e is removed.
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.734 sec - in
org.apache.tajo.querymaster.TestQueryState
Running org.apache.tajo.cluster.TestWorkerConnectionInfo
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in
org.apache.tajo.cluster.TestWorkerConnectionInfo
2019-09-05 05:18:18,522 INFO: org.mortbay.log (info(67)) - Shutdown hook
executing
2019-09-05 05:18:18,524 INFO: org.mortbay.log (info(67)) - Shutdown hook
complete
2019-09-05 05:18:18,534 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session c2a233af-647c-4dd1-aa30-20295e14546e is removed.
2019-09-05 05:18:18,538 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session 709fa3d3-3c1d-47a9-ab93-f50395db2bbe is removed.
2019-09-05 05:18:18,541 INFO: org.apache.tajo.worker.TajoWorker (run(506)) -
============================================
2019-09-05 05:18:18,542 INFO: org.apache.tajo.worker.TajoWorker (run(507)) -
TajoWorker received SIGINT Signal
2019-09-05 05:18:18,542 INFO: org.apache.tajo.worker.TajoWorker (run(508)) -
============================================
2019-09-05 05:18:18,545 INFO: org.apache.tajo.util.history.HistoryWriter
(run(288)) - HistoryWriter_localhost_26004 stopped.
2019-09-05 05:18:18,546 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2019-09-05 05:18:18,546 INFO: org.apache.tajo.worker.NodeStatusUpdater
(run(261)) - Heartbeat Thread stopped.
2019-09-05 05:18:18,546 INFO: org.apache.tajo.worker.NodeStatusUpdater
(serviceStop(113)) - NodeStatusUpdater stopped.
2019-09-05 05:18:18,549 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (QueryMasterProtocol) listened on 127.0.0.1:26006)
shutdown
2019-09-05 05:18:18,550 INFO:
org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(98)) -
QueryMasterManagerService stopped
2019-09-05 05:18:18,550 INFO: org.apache.tajo.querymaster.QueryMaster
(run(432)) - QueryMaster heartbeat thread stopped
2019-09-05 05:18:18,551 INFO: org.apache.tajo.querymaster.QueryMaster
(serviceStop(164)) - QueryMaster stopped
2019-09-05 05:18:18,551 INFO: org.apache.tajo.worker.TajoWorkerClientService
(serviceStop(85)) - TajoWorkerClientService stopping
2019-09-05 05:18:18,552 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (QueryMasterClientProtocol) listened on 127.0.0.1:26005)
shutdown
2019-09-05 05:18:18,552 INFO: org.apache.tajo.worker.TajoWorkerClientService
(serviceStop(89)) - TajoWorkerClientService stopped
2019-09-05 05:18:18,553 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (TajoWorkerProtocol) listened on 127.0.0.1:26004) shutdown
2019-09-05 05:18:18,553 INFO: org.apache.tajo.worker.TajoWorkerManagerService
(serviceStop(90)) - TajoWorkerManagerService stopped
2019-09-05 05:18:18,555 INFO: org.apache.tajo.worker.TajoWorker
(serviceStop(320)) - TajoWorker main thread exiting
2019-09-05 05:18:20,053 INFO: BlockStateChange (invalidateWorkForOneNode(3482))
- BLOCK* BlockManager: ask 127.0.0.1:36541 to delete [blk_1073749279_8455]
2019-09-05 05:18:22,862 INFO: org.apache.tajo.master.TajoMaster (run(604)) -
============================================
2019-09-05 05:18:22,864 INFO: org.apache.tajo.master.TajoMaster (run(605)) -
TajoMaster received SIGINT Signal
2019-09-05 05:18:22,865 INFO: org.apache.tajo.master.TajoMaster (run(606)) -
============================================
2019-09-05 05:18:22,865 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (Tajo-REST) listened on 127.0.0.1:26003) shutdown
2019-09-05 05:18:22,865 INFO: org.apache.tajo.ws.rs.TajoRestService
(serviceStop(90)) - Tajo Rest Service stopped.
2019-09-05 05:18:22,871 INFO: org.apache.tajo.util.history.HistoryWriter
(run(288)) - HistoryWriter_127.0.0.1_26002 stopped.
2019-09-05 05:18:22,873 INFO: BlockStateChange (logAddStoredBlock(2621)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:36541 is added to
blk_1073741835_1011{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-83b239f4-0697-480f-9aca-0042dd2d3cf9:NORMAL:127.0.0.1:36541|RBW]]}
size 997
2019-09-05 05:18:22,874 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:26002)
shutdown
2019-09-05 05:18:22,875 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2019-09-05 05:18:24,979 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:26001)
shutdown
2019-09-05 05:18:24,980 INFO: org.apache.tajo.catalog.CatalogServer
(serviceStop(180)) - Catalog Server (localhost/127.0.0.1:26000) shutdown
2019-09-05 05:18:24,980 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (CatalogProtocol) listened on 127.0.0.1:26000) shutdown
2019-09-05 05:18:24,980 INFO: org.apache.tajo.catalog.store.DerbyStore
(close(2998)) - Close database
(jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/375f66ea-1e51-473e-9ebe-bc0e814c587b/db;create=true)>
2019-09-05 05:18:24,982 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (TajoResourceTrackerProtocol) listened on
127.0.0.1:25999) shutdown
2019-09-05 05:18:24,982 INFO: org.apache.tajo.master.TajoMaster
(serviceStop(460)) - Tajo Master main thread exiting
2019-09-05 05:18:25,027 INFO: org.apache.tajo.catalog.store.DerbyStore
(shutdown(68)) - Derby shutdown complete normally.
2019-09-05 05:18:25,027 INFO: org.apache.tajo.catalog.store.DerbyStore
(shutdown(75)) - Shutdown database
Results :
Failed tests:
TestTajoCli.testRunWhenError:443 TSQL should be alive
Tests run: 1811, Failures: 1, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary for Tajo Main 0.12.0-SNAPSHOT:
[INFO]
[INFO] Tajo Main .......................................... SUCCESS [ 3.280 s]
[INFO] Tajo Project POM ................................... SUCCESS [ 2.933 s]
[INFO] Tajo Maven Plugins ................................. SUCCESS [ 3.759 s]
[INFO] Tajo Common ........................................ SUCCESS [ 37.384 s]
[INFO] Tajo Algebra ....................................... SUCCESS [ 2.998 s]
[INFO] Tajo Catalog Common ................................ SUCCESS [ 4.848 s]
[INFO] Tajo Plan .......................................... SUCCESS [ 20.284 s]
[INFO] Tajo Rpc Common .................................... SUCCESS [ 1.921 s]
[INFO] Tajo Protocol Buffer Rpc ........................... SUCCESS [02:25 min]
[INFO] Tajo Catalog Client ................................ SUCCESS [ 1.328 s]
[INFO] Tajo Catalog Server ................................ SUCCESS [ 21.521 s]
[INFO] Tajo Storage Common ................................ SUCCESS [ 5.810 s]
[INFO] Tajo HDFS Storage .................................. SUCCESS [01:13 min]
[INFO] Tajo PullServer .................................... SUCCESS [ 1.934 s]
[INFO] Tajo Client ........................................ SUCCESS [ 2.494 s]
[INFO] Tajo SQL Parser .................................... SUCCESS [ 4.050 s]
[INFO] Tajo CLI tools ..................................... SUCCESS [ 2.630 s]
[INFO] ASM (thirdparty) ................................... SUCCESS [ 1.624 s]
[INFO] Tajo RESTful Container ............................. SUCCESS [ 4.591 s]
[INFO] Tajo Metrics ....................................... SUCCESS [ 1.270 s]
[INFO] Tajo Core .......................................... SUCCESS [ 10.987 s]
[INFO] Tajo RPC ........................................... SUCCESS [ 0.500 s]
[INFO] Tajo Catalog Drivers Hive .......................... SUCCESS [ 17.243 s]
[INFO] Tajo Catalog Drivers ............................... SUCCESS [ 0.052 s]
[INFO] Tajo Catalog ....................................... SUCCESS [ 0.609 s]
[INFO] Tajo Client Example ................................ SUCCESS [ 0.728 s]
[INFO] Tajo HBase Storage ................................. SUCCESS [ 9.420 s]
[INFO] Tajo Cluster Tests ................................. SUCCESS [ 4.867 s]
[INFO] Tajo JDBC Driver ................................... SUCCESS [ 36.959 s]
[INFO] Tajo JDBC storage common ........................... SUCCESS [ 0.425 s]
[INFO] Tajo PostgreSQL JDBC storage ....................... SUCCESS [ 5.437 s]
[INFO] Tajo S3 storage .................................... SUCCESS [ 9.608 s]
[INFO] Tajo Kafka Storage ................................. SUCCESS [ 57.663 s]
[INFO] Tajo Storage ....................................... SUCCESS [ 0.658 s]
[INFO] Tajo Yarn .......................................... SUCCESS [ 2.886 s]
[INFO] Tajo Core Tests .................................... FAILURE [22:39 min]
[INFO] Tajo Tablespace Example ............................ SKIPPED
[INFO] Tajo Distribution .................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 31:02 min
[INFO] Finished at: 2019-09-05T05:18:25Z
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal
org.apache.maven.plugins:maven-surefire-plugin:2.19.1:test (default-test) on
project tajo-core-tests: There are test failures.
[ERROR]
[ERROR] Please refer to
<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/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-tests
Build step 'Execute shell' marked build as failure
Archiving artifacts
Recording test results
Not sending mail to unregistered user [email protected]
