See <https://builds.apache.org/job/Tajo-master-build/1069/changes>
Changes:
[jhkim] TAJO-2065: Increase default heap size.
[jhkim] TAJO-2065: Increase default heap size (missing change).
------------------------------------------
[...truncated 739738 lines...]
[Outgoing]
[q_1453966428708_2257] 1 => 2 (type=HASH_SHUFFLE,
key=default.lineitem.l_orderkey (INT4), num=32)
GROUP_BY(5)(l_orderkey)
=> target list: default.lineitem.l_orderkey (INT4)
=> out schema:{(1) default.lineitem.l_orderkey (INT4)}
=> in schema:{(1) default.lineitem.l_orderkey (INT4)}
SCAN(0) on default.lineitem
=> target list: default.lineitem.l_orderkey (INT4)
=> out schema: {(1) default.lineitem.l_orderkey (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)}
=======================================================
Block Id: eb_1453966428708_2257_000002 [ROOT]
=======================================================
[Incoming]
[q_1453966428708_2257] 1 => 2 (type=HASH_SHUFFLE,
key=default.lineitem.l_orderkey (INT4), num=32)
GROUP_BY(1)(l_orderkey)
=> target list: default.lineitem.l_orderkey (INT4)
=> out schema:{(1) default.lineitem.l_orderkey (INT4)}
=> in schema:{(1) default.lineitem.l_orderkey (INT4)}
SCAN(6) on eb_1453966428708_2257_000001
=> out schema: {(1) default.lineitem.l_orderkey (INT4)}
=> in schema: {(1) default.lineitem.l_orderkey (INT4)}
=======================================================
Block Id: eb_1453966428708_2257_000003 [TERMINAL]
=======================================================
2016-01-28 07:56:23,583 INFO: org.apache.tajo.querymaster.Query (<init>(236)) -
=======================================================
The order of execution:
1: eb_1453966428708_2257_000001
2: eb_1453966428708_2257_000002
3: eb_1453966428708_2257_000003
=======================================================
2016-01-28 07:56:23,585 INFO: org.apache.tajo.querymaster.Query (handle(878)) -
Processing q_1453966428708_2257 of type START
2016-01-28 07:56:23,586 INFO:
org.apache.tajo.engine.planner.global.ParallelExecutionQueue (first(84)) -
Initial executable blocks [eb_1453966428708_2257_000001]
2016-01-28 07:56:23,586 INFO: org.apache.tajo.querymaster.Query (handle(896)) -
q_1453966428708_2257 Query Transitioned from QUERY_NEW to QUERY_RUNNING
2016-01-28 07:56:23,586 INFO: org.apache.tajo.querymaster.Stage
(calculateShuffleOutputNum(1032)) - eb_1453966428708_2257_000001, Table's
volume is approximately 725 MB
2016-01-28 07:56:23,586 INFO: org.apache.tajo.querymaster.Stage
(calculateShuffleOutputNum(1036)) - eb_1453966428708_2257_000001, The
determined number of aggregation partitions is 3
2016-01-28 07:56:23,586 INFO: org.apache.tajo.querymaster.Stage
(initTaskScheduler(915)) - org.apache.tajo.querymaster.DefaultTaskScheduler is
chosen for the task scheduling for eb_1453966428708_2257_000001
2016-01-28 07:56:23,589 INFO: org.apache.tajo.storage.FileTablespace
(listStatus(346)) - Total input paths to process : 1
2016-01-28 07:56:23,590 INFO: org.apache.tajo.storage.FileTablespace
(getSplits(547)) - Total # of splits: 1
2016-01-28 07:56:23,591 INFO: org.apache.tajo.querymaster.Stage (run(867)) - 1
objects are scheduled
2016-01-28 07:56:23,591 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(start(135)) - Start TaskScheduler
2016-01-28 07:56:23,592 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(increaseConcurrency(526)) - Assigned host : 127.0.0.1, Unknown Volume : -1,
Concurrency : 1
2016-01-28 07:56:23,593 INFO: org.apache.tajo.worker.TaskManager
(createExecutionBlock(112)) - QueryMaster Address:asf901.gq1.ygridcore.net:49987
2016-01-28 07:56:23,593 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(assignToLeafTasks(922)) - Assigned Local/Rack/Total: (0/1/1), Attempted
Cancel/Assign/Total: (0/1/1), Locality: 0.00%, Rack host:
asf901.gq1.ygridcore.net
2016-01-28 07:56:23,597 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(128)) - Tajo Root Dir: hdfs://localhost:38781/tajo
2016-01-28 07:56:23,597 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(129)) - Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/8aeee890-8aa1-40b2-94c3-360073d24cf2/tajo-localdir>
2016-01-28 07:56:23,598 INFO: org.apache.tajo.worker.TaskManager (handle(181))
- Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:500,
Disks:5, vCores:2)
2016-01-28 07:56:23,600 INFO: org.apache.tajo.worker.TaskImpl (initPlan(159)) -
* Task ta_1453966428708_2257_000001_000000_00 is initialized. InterQuery: true,
Shuffle: HASH_SHUFFLE, Fragments: 1, Fetches:0, Local dir:
<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/8aeee890-8aa1-40b2-94c3-360073d24cf2/tajo-localdir/q_1453966428708_2257/output/1/0_0>
2016-01-28 07:56:23,600 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(createBestAggregationPlan(1029)) - Aggregation:estimatedSize=604,
threshold=67108864
2016-01-28 07:56:23,600 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(createBestAggregationPlan(1031)) - The planner chooses [Hash Aggregation]
2016-01-28 07:56:23,600 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(createInMemoryHashAggregation(973)) - The planner chooses [Hash Aggregation]
2016-01-28 07:56:23,605 INFO: org.apache.tajo.worker.TaskImpl (run(469)) -
ta_1453966428708_2257_000001_000000_00 is complete. 5 ms elapsed, final
state:TA_SUCCEEDED
2016-01-28 07:56:23,606 INFO: org.apache.tajo.querymaster.Stage
(transition(1365)) - Stage - eb_1453966428708_2257_000001 finalize HASH_SHUFFLE
(total=1, success=1, killed=0)
2016-01-28 07:56:23,606 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(stop(164)) - Task Scheduler stopped
2016-01-28 07:56:23,606 INFO: org.apache.tajo.querymaster.Stage
(transition(1376)) - eb_1453966428708_2257_000001, wait for HASH_SHUFFLE
reports. expected Tasks:1
2016-01-28 07:56:23,607 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(run(127)) - TaskScheduler schedulingThread stopped
2016-01-28 07:56:23,607 INFO:
org.apache.tajo.storage.HashShuffleAppenderManager (close(182)) - Close
HashShuffleAppenderWrapper:eb_1453966428708_2257_000001, intermediates=2
2016-01-28 07:56:23,607 INFO: org.apache.tajo.querymaster.Stage
(finalizeShuffleReport(1322)) - eb_1453966428708_2257_000001, Finalized
HASH_SHUFFLE reports: 1
2016-01-28 07:56:23,608 INFO: org.apache.tajo.querymaster.Stage
(transition(1423)) - Stage completed - eb_1453966428708_2257_000001 (total=1,
success=1, killed=0)
2016-01-28 07:56:23,608 INFO: org.apache.tajo.querymaster.Query (handle(878)) -
Processing q_1453966428708_2257 of type STAGE_COMPLETED
2016-01-28 07:56:23,608 INFO:
org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next
executable block eb_1453966428708_2257_000002
2016-01-28 07:56:23,608 INFO: org.apache.tajo.querymaster.Query
(executeNextBlock(778)) - Scheduling Stage:eb_1453966428708_2257_000002
2016-01-28 07:56:23,608 INFO: org.apache.tajo.querymaster.Stage
(initTaskScheduler(915)) - org.apache.tajo.querymaster.DefaultTaskScheduler is
chosen for the task scheduling for eb_1453966428708_2257_000002
2016-01-28 07:56:23,608 INFO: org.apache.tajo.worker.ExecutionBlockContext
(stop(176)) - Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0
2016-01-28 07:56:23,609 INFO: org.apache.tajo.querymaster.Stage
(getNonLeafTaskNum(1109)) - eb_1453966428708_2257_000002, Table's volume is
approximately 1 MB
2016-01-28 07:56:23,609 INFO: org.apache.tajo.querymaster.Stage
(getNonLeafTaskNum(1114)) - eb_1453966428708_2257_000002, The determined number
of non-leaf tasks is 1
2016-01-28 07:56:23,609 INFO: org.apache.tajo.querymaster.Repartitioner
(scheduleHashShuffledFetches(886)) - eb_1453966428708_2257_000002,
ScheduleHashShuffledFetches - Max num=1, finalFetchURI=2
2016-01-28 07:56:23,610 INFO: org.apache.tajo.querymaster.Repartitioner
(scheduleHashShuffledFetches(906)) - eb_1453966428708_2257_000002,
DeterminedTaskNum : 1
2016-01-28 07:56:23,610 INFO: org.apache.tajo.querymaster.Stage (run(867)) - 1
objects are scheduled
2016-01-28 07:56:23,610 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(start(135)) - Start TaskScheduler
2016-01-28 07:56:23,610 INFO: org.apache.tajo.worker.TaskManager
(stopExecutionBlock(160)) - Stopped execution block:eb_1453966428708_2257_000001
2016-01-28 07:56:23,626 INFO: org.apache.tajo.worker.TaskManager
(createExecutionBlock(112)) - QueryMaster Address:asf901.gq1.ygridcore.net:49987
2016-01-28 07:56:23,628 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(128)) - Tajo Root Dir: hdfs://localhost:38781/tajo
2016-01-28 07:56:23,628 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(129)) - Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/8aeee890-8aa1-40b2-94c3-360073d24cf2/tajo-localdir>
2016-01-28 07:56:23,632 INFO: org.apache.tajo.worker.TaskManager (handle(181))
- Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:500,
Disks:6, vCores:2)
2016-01-28 07:56:23,632 INFO: org.apache.tajo.storage.FileTablespace
(getAppenderFilePath(274)) - Output File Path:
hdfs://localhost:38781/tmp/tajo-jenkins/staging/q_1453966428708_2257/RESULT/part-02-000000-000
2016-01-28 07:56:23,632 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) -
Output File Path:
hdfs://localhost:38781/tmp/tajo-jenkins/staging/q_1453966428708_2257/RESULT/part-02-000000-000
2016-01-28 07:56:23,632 INFO: org.apache.tajo.worker.TaskImpl (initPlan(159)) -
* Task ta_1453966428708_2257_000002_000000_00 is initialized. InterQuery:
false, Shuffle: null, Fragments: 1, Fetches:2, Local dir:
<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/8aeee890-8aa1-40b2-94c3-360073d24cf2/tajo-localdir/q_1453966428708_2257/output/2/0_0>
2016-01-28 07:56:23,635 INFO: org.apache.tajo.worker.TaskImpl
(getFetchRunners(740)) - Create shuffle Fetchers local:2, remote:0
2016-01-28 07:56:23,635 INFO: org.apache.tajo.worker.TaskImpl
(waitForFetch(380)) - ta_1453966428708_2257_000002_000000_00 All fetches are
done!
2016-01-28 07:56:23,636 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(createBestAggregationPlan(1029)) - Aggregation:estimatedSize=24,
threshold=67108864
2016-01-28 07:56:23,636 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(createBestAggregationPlan(1031)) - The planner chooses [Hash Aggregation]
2016-01-28 07:56:23,636 INFO:
org.apache.tajo.engine.planner.PhysicalPlannerImpl
(createInMemoryHashAggregation(973)) - The planner chooses [Hash Aggregation]
2016-01-28 07:56:23,641 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:36313 is added to
blk_1073749123_8299{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d885177a-0794-44f8-91b4-c196e5cfe9db:NORMAL:127.0.0.1:36313|RBW]]}
size 0
2016-01-28 07:56:23,642 INFO: org.apache.tajo.worker.TaskImpl (run(469)) -
ta_1453966428708_2257_000002_000000_00 is complete. 7 ms elapsed, final
state:TA_SUCCEEDED
2016-01-28 07:56:23,642 INFO: org.apache.tajo.querymaster.Stage
(transition(1365)) - Stage - eb_1453966428708_2257_000002 finalize NONE_SHUFFLE
(total=1, success=1, killed=0)
2016-01-28 07:56:23,642 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(stop(164)) - Task Scheduler stopped
2016-01-28 07:56:23,643 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(run(127)) - TaskScheduler schedulingThread stopped
2016-01-28 07:56:23,643 INFO: org.apache.tajo.querymaster.Stage
(transition(1423)) - Stage completed - eb_1453966428708_2257_000002 (total=1,
success=1, killed=0)
2016-01-28 07:56:23,643 INFO: org.apache.tajo.querymaster.Query (handle(878)) -
Processing q_1453966428708_2257 of type STAGE_COMPLETED
2016-01-28 07:56:23,643 INFO: org.apache.tajo.worker.ExecutionBlockContext
(stop(176)) - Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0
2016-01-28 07:56:23,643 INFO:
org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next
executable block eb_1453966428708_2257_000003
2016-01-28 07:56:23,643 INFO: org.apache.tajo.querymaster.Query
(transition(825)) - Complete Stage[eb_1453966428708_2257_000002], State:
SUCCEEDED, 2/2.
2016-01-28 07:56:23,643 INFO: org.apache.tajo.querymaster.Query (handle(878)) -
Processing q_1453966428708_2257 of type QUERY_COMPLETED
2016-01-28 07:56:23,644 INFO: org.apache.tajo.worker.TaskManager
(stopExecutionBlock(160)) - Stopped execution block:eb_1453966428708_2257_000002
2016-01-28 07:56:23,644 INFO: org.apache.tajo.querymaster.Query (handle(896)) -
q_1453966428708_2257 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED
2016-01-28 07:56:23,644 INFO: org.apache.tajo.querymaster.QueryMasterTask
(handle(297)) - Query completion notified from q_1453966428708_2257 final
state: QUERY_SUCCEEDED
2016-01-28 07:56:23,645 INFO: org.apache.tajo.master.QueryInProgress
(heartbeat(257)) - Received QueryMaster
heartbeat:q_1453966428708_2257,state=QUERY_SUCCEEDED,progress=1.0,
queryMaster=asf901.gq1.ygridcore.net
2016-01-28 07:56:23,645 INFO: org.apache.tajo.master.QueryManager
(stopQuery(279)) - Stop QueryInProgress:q_1453966428708_2257
2016-01-28 07:56:23,645 INFO: org.apache.tajo.master.QueryInProgress
(stopProgress(122)) - =========================================================
2016-01-28 07:56:23,645 INFO: org.apache.tajo.master.QueryInProgress
(stopProgress(123)) - Stop query:q_1453966428708_2257
2016-01-28 07:56:23,646 INFO: org.apache.tajo.querymaster.QueryMasterTask
(serviceStop(173)) - Stopping QueryMasterTask:q_1453966428708_2257
2016-01-28 07:56:23,647 INFO: org.apache.tajo.querymaster.QueryMasterTask
(cleanupQuery(474)) - Cleanup resources of all workers. Query:
q_1453966428708_2257, workers: 1
2016-01-28 07:56:23,647 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session 62b828f4-ef8c-4843-ae71-a3878d834883 is removed.
2016-01-28 07:56:23,648 INFO: org.apache.tajo.querymaster.QueryMasterTask
(serviceStop(189)) - Stopped QueryMasterTask:q_1453966428708_2257
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.092 sec - in
org.apache.tajo.querymaster.TestQueryProgress
Running org.apache.tajo.TestQueryIdFactory
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in
org.apache.tajo.TestQueryIdFactory
Running org.apache.tajo.resource.TestResources
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in
org.apache.tajo.resource.TestResources
2016-01-28 07:56:23,661 INFO: org.mortbay.log (info(67)) - Shutdown hook
executing
2016-01-28 07:56:23,663 INFO: org.mortbay.log (info(67)) - Shutdown hook
complete
2016-01-28 07:56:23,669 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session 3eabe687-9234-4d5d-bf24-bca25c5452ea is removed.
2016-01-28 07:56:23,679 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session 7bd0bfa1-7e25-4ee3-b8d0-8fafc8d954fe is removed.
2016-01-28 07:56:23,680 INFO: org.apache.tajo.worker.TajoWorker (run(518)) -
============================================
2016-01-28 07:56:23,683 INFO: org.apache.tajo.worker.TajoWorker (run(519)) -
TajoWorker received SIGINT Signal
2016-01-28 07:56:23,683 INFO: org.apache.tajo.worker.TajoWorker (run(520)) -
============================================
2016-01-28 07:56:23,691 WARN: org.apache.hadoop.hdfs.DFSClient
(completeFile(2283)) - Caught exception
java.lang.InterruptedException: sleep interrupted
at java.lang.Thread.sleep(Native Method)
at
org.apache.hadoop.hdfs.DFSOutputStream.completeFile(DFSOutputStream.java:2277)
at
org.apache.hadoop.hdfs.DFSOutputStream.closeImpl(DFSOutputStream.java:2238)
at
org.apache.hadoop.hdfs.DFSOutputStream.close(DFSOutputStream.java:2204)
at
org.apache.hadoop.fs.FSDataOutputStream$PositionCache.close(FSDataOutputStream.java:72)
at
org.apache.hadoop.fs.FSDataOutputStream.close(FSDataOutputStream.java:106)
at org.apache.hadoop.io.IOUtils.cleanup(IOUtils.java:244)
at
org.apache.tajo.util.history.HistoryWriter$WriterThread.writeQueryHistory(HistoryWriter.java:369)
at
org.apache.tajo.util.history.HistoryWriter$WriterThread.writeHistory(HistoryWriter.java:318)
at
org.apache.tajo.util.history.HistoryWriter$WriterThread.run(HistoryWriter.java:244)
2016-01-28 07:56:23,694 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2016-01-28 07:56:23,699 INFO: org.apache.tajo.util.history.HistoryWriter
(writeQueryHistory(379)) - Saving query unit:
hdfs://localhost:38781/tmp/tajo-jenkins/staging/history/20160128/query-detail/q_1453966428708_2254/eb_1453966428708_2254_000001.hist
2016-01-28 07:56:23,704 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:36313 is added to
blk_1073749124_8300{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d885177a-0794-44f8-91b4-c196e5cfe9db:NORMAL:127.0.0.1:36313|RBW]]}
size 0
2016-01-28 07:56:23,706 INFO: org.apache.tajo.util.history.HistoryWriter
(writeQueryHistory(379)) - Saving query unit:
hdfs://localhost:38781/tmp/tajo-jenkins/staging/history/20160128/query-detail/q_1453966428708_2254/eb_1453966428708_2254_000002.hist
2016-01-28 07:56:23,712 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:36313 is added to
blk_1073749125_8301{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d885177a-0794-44f8-91b4-c196e5cfe9db:NORMAL:127.0.0.1:36313|RBW]]}
size 774
2016-01-28 07:56:24,114 INFO: org.apache.tajo.util.history.HistoryWriter
(writeQueryHistory(379)) - Saving query unit:
hdfs://localhost:38781/tmp/tajo-jenkins/staging/history/20160128/query-detail/q_1453966428708_2254/eb_1453966428708_2254_000003.hist
2016-01-28 07:56:24,119 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:36313 is added to
blk_1073749126_8302{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d885177a-0794-44f8-91b4-c196e5cfe9db:NORMAL:127.0.0.1:36313|RBW]]}
size 0
2016-01-28 07:56:24,132 INFO: org.apache.tajo.worker.NodeStatusUpdater
(serviceStop(113)) - NodeStatusUpdater stopped.
2016-01-28 07:56:24,132 INFO: org.apache.tajo.worker.NodeStatusUpdater
(run(261)) - Heartbeat Thread stopped.
2016-01-28 07:56:24,133 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:49987)
shutdown
2016-01-28 07:56:24,134 INFO:
org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) -
QueryMasterManagerService stopped
2016-01-28 07:56:24,134 INFO: org.apache.tajo.querymaster.QueryMaster
(run(432)) - QueryMaster heartbeat thread stopped
2016-01-28 07:56:24,136 INFO: org.apache.tajo.querymaster.QueryMaster
(serviceStop(164)) - QueryMaster stopped
2016-01-28 07:56:24,136 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(98)) - TajoWorkerClientService stopping
2016-01-28 07:56:24,140 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (QueryMasterClientProtocol) listened on
0:0:0:0:0:0:0:0:49986) shutdown
2016-01-28 07:56:24,140 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(102)) - TajoWorkerClientService stopped
2016-01-28 07:56:24,140 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:49985)
shutdown
2016-01-28 07:56:24,140 INFO: org.apache.tajo.worker.TajoWorkerManagerService
(serviceStop(93)) - TajoWorkerManagerService stopped
2016-01-28 07:56:24,142 INFO: org.apache.tajo.worker.TajoWorker
(serviceStop(332)) - TajoWorker main thread exiting
2016-01-28 07:56:24,146 INFO: org.apache.tajo.util.history.HistoryWriter
(writeQueryHistory(365)) - Saving query summary:
hdfs://localhost:38781/tmp/tajo-jenkins/staging/history/20160128/query-detail/q_1453966428708_2255/query.hist
2016-01-28 07:56:24,155 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:36313 is added to
blk_1073749127_8303{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d885177a-0794-44f8-91b4-c196e5cfe9db:NORMAL:127.0.0.1:36313|RBW]]}
size 0
2016-01-28 07:56:24,159 INFO: org.apache.tajo.util.history.HistoryWriter
(writeQueryHistory(379)) - Saving query unit:
hdfs://localhost:38781/tmp/tajo-jenkins/staging/history/20160128/query-detail/q_1453966428708_2255/eb_1453966428708_2255_000001.hist
2016-01-28 07:56:24,163 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:36313 is added to
blk_1073749128_8304{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d885177a-0794-44f8-91b4-c196e5cfe9db:NORMAL:127.0.0.1:36313|RBW]]}
size 480
2016-01-28 07:56:24,565 INFO: org.apache.tajo.util.history.HistoryWriter
(writeQueryHistory(379)) - Saving query unit:
hdfs://localhost:38781/tmp/tajo-jenkins/staging/history/20160128/query-detail/q_1453966428708_2255/eb_1453966428708_2255_000002.hist
2016-01-28 07:56:24,568 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:36313 is added to
blk_1073749129_8305{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d885177a-0794-44f8-91b4-c196e5cfe9db:NORMAL:127.0.0.1:36313|RBW]]}
size 0
2016-01-28 07:56:24,569 INFO: org.apache.tajo.util.history.HistoryWriter
(run(275)) - HistoryWriter_asf901.gq1.ygridcore.net_49985 stopped.
2016-01-28 07:56:26,345 INFO: org.apache.tajo.master.TajoMaster (run(568)) -
============================================
2016-01-28 07:56:26,346 INFO: org.apache.tajo.master.TajoMaster (run(569)) -
TajoMaster received SIGINT Signal
2016-01-28 07:56:26,346 INFO: org.apache.tajo.master.TajoMaster (run(570)) -
============================================
2016-01-28 07:56:26,347 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (Tajo-REST) listened on 127.0.0.1:49984) shutdown
2016-01-28 07:56:26,347 INFO: org.apache.tajo.ws.rs.TajoRestService
(serviceStop(129)) - Tajo Rest Service stopped.
2016-01-28 07:56:26,352 INFO: org.apache.tajo.util.history.HistoryWriter
(run(275)) - HistoryWriter_127.0.0.1_49983 stopped.
2016-01-28 07:56:26,357 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:36313 is added to
blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d885177a-0794-44f8-91b4-c196e5cfe9db:NORMAL:127.0.0.1:36313|RBW]]}
size 524
2016-01-28 07:56:26,357 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:49983)
shutdown
2016-01-28 07:56:26,358 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2016-01-28 07:56:28,562 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:49982)
shutdown
2016-01-28 07:56:28,563 INFO: org.apache.tajo.catalog.CatalogServer
(serviceStop(182)) - Catalog Server (127.0.0.1:49981) shutdown
2016-01-28 07:56:28,563 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (CatalogProtocol) listened on 127.0.0.1:49981) shutdown
2016-01-28 07:56:28,779 INFO: org.apache.tajo.catalog.store.DerbyStore
(close(2978)) - Close database
(jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/8aeee890-8aa1-40b2-94c3-360073d24cf2/db;create=true)>
2016-01-28 07:56:28,780 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (TajoResourceTrackerProtocol) listened on
127.0.0.1:49980) shutdown
2016-01-28 07:56:28,781 INFO: org.apache.tajo.master.TajoMaster
(serviceStop(424)) - Tajo Master main thread exiting
2016-01-28 07:56:28,820 INFO: org.apache.tajo.catalog.store.DerbyStore
(shutdown(68)) - Derby shutdown complete normally.
2016-01-28 07:56:28,820 INFO: org.apache.tajo.catalog.store.DerbyStore
(shutdown(75)) - Shutdown database
Results :
Tests in error:
TestJoinOnPartitionedTables.testPartialFilterPushDown:95->QueryTestCaseBase.runSimpleTests:622
ยป UndefinedTable
Tests run: 1734, Failures: 0, Errors: 1, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 1.907 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 1.779 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.250 s]
[INFO] Tajo Common ....................................... SUCCESS [ 31.283 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 2.816 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 5.551 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 7.854 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 1.253 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [02:24 min]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.725 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 14.030 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 3.767 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [01:14 min]
[INFO] Tajo PullServer ................................... SUCCESS [ 1.079 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.772 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 2.151 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 5.079 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 2.453 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 5.317 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.911 s]
[INFO] Tajo Core ......................................... SUCCESS [ 8.941 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 0.983 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 12.961 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.073 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 1.126 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.091 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 4.590 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 3.289 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 39.977 s]
[INFO] Tajo JDBC storage common .......................... SUCCESS [ 0.919 s]
[INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 1.565 s]
[INFO] Tajo S3 storage ................................... SUCCESS [ 0.360 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 1.030 s]
[INFO] Tajo Distribution ................................. SUCCESS [ 5.952 s]
[INFO] Tajo Core Tests ................................... FAILURE [22:55 min]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 29:35 min
[INFO] Finished at: 2016-01-28T07:56:29+00:00
[INFO] Final Memory: 162M/2476M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal
org.apache.maven.plugins:maven-surefire-plugin:2.19:test (default-test) on
project tajo-core-tests: There are test failures.
[ERROR]
[ERROR] Please refer to
<https://builds.apache.org/job/Tajo-master-build/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
Updating TAJO-2065
