See <https://builds.apache.org/job/Tajo-master-build/384/changes>
Changes:
[hyunsik] TAJO-1081: Non-forwarded (simple) query shows wrong rows.
------------------------------------------
[...truncated 313182 lines...]
-----------------------------
Optimization Log:
[LogicalPlan]
> ProjectionNode is eliminated.
-----------------------------
SCAN(0) on default.lineitem
=> filter: default.lineitem.l_quantity (FLOAT8) > 20.0 AND
default.lineitem.l_returnflag (TEXT) = R
=> target list: default.lineitem.l_orderkey (INT4),
default.lineitem.l_quantity (FLOAT8), default.lineitem.l_returnflag (TEXT)
=> out schema: {(3) default.lineitem.l_orderkey
(INT4),default.lineitem.l_quantity (FLOAT8),default.lineitem.l_returnflag
(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)}
2014-10-02 07:43:52,513 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(507)) - =============================================
2014-10-02 07:43:52,513 INFO: org.apache.tajo.master.GlobalEngine
(executeQueryInternal(314)) - Query is forwarded to :0
2014-10-02 07:43:52,514 INFO: org.apache.tajo.master.TajoAsyncDispatcher
(start(101)) - AsyncDispatcher started:QueryInProgress:q_1412235058396_0739
2014-10-02 07:43:52,514 INFO:
org.apache.tajo.master.querymaster.QueryInProgress (startQueryMaster(165)) -
Initializing QueryInProgress for QueryID=q_1412235058396_0739
2014-10-02 07:43:52,514 INFO:
org.apache.tajo.master.querymaster.QueryInProgress (connectQueryMaster(209)) -
Connect to QueryMaster:asf905.gq1.ygridcore.net/67.195.81.149:40237
2014-10-02 07:43:52,515 INFO:
org.apache.tajo.master.querymaster.QueryInProgress (submmitQueryToMaster(229))
- Call executeQuery to :asf905.gq1.ygridcore.net:40237,q_1412235058396_0739
2014-10-02 07:43:52,517 INFO:
org.apache.tajo.master.querymaster.QueryMasterManagerService
(executeQuery(250)) - Receive executeQuery request:q_1412235058396_0739
2014-10-02 07:43:52,517 INFO: org.apache.tajo.master.querymaster.QueryMaster
(handle(473)) - Start QueryStartEventHandler:q_1412235058396_0739
2014-10-02 07:43:52,522 INFO:
org.apache.tajo.master.querymaster.QueryMasterTask (initStagingDir(405)) - The
staging dir
'hdfs://localhost:55253/tmp/tajo-jenkins/staging/q_1412235058396_0739' is
created.
2014-10-02 07:43:52,751 INFO:
org.apache.tajo.engine.planner.global.GlobalPlanner (build(117)) -
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
[LogicalPlan]
> ProjectionNode is eliminated.
-----------------------------
SCAN(0) on default.lineitem
=> filter: default.lineitem.l_quantity (FLOAT8) > 20.0 AND
default.lineitem.l_returnflag (TEXT) = R
=> target list: default.lineitem.l_orderkey (INT4),
default.lineitem.l_quantity (FLOAT8), default.lineitem.l_returnflag (TEXT)
=> out schema: {(3) default.lineitem.l_orderkey
(INT4),default.lineitem.l_quantity (FLOAT8),default.lineitem.l_returnflag
(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)}
2014-10-02 07:43:52,752 INFO:
org.apache.tajo.engine.planner.global.GlobalPlanner (build(159)) -
-------------------------------------------------------------------------------
Execution Block Graph (TERMINAL - eb_1412235058396_0739_000002)
-------------------------------------------------------------------------------
|-eb_1412235058396_0739_000002
|-eb_1412235058396_0739_000001
-------------------------------------------------------------------------------
Order of Execution
-------------------------------------------------------------------------------
1: eb_1412235058396_0739_000001
2: eb_1412235058396_0739_000002
-------------------------------------------------------------------------------
=======================================================
Block Id: eb_1412235058396_0739_000001 [ROOT]
=======================================================
SCAN(0) on default.lineitem
=> filter: default.lineitem.l_quantity (FLOAT8) > 20.0 AND
default.lineitem.l_returnflag (TEXT) = R
=> target list: default.lineitem.l_orderkey (INT4),
default.lineitem.l_quantity (FLOAT8), default.lineitem.l_returnflag (TEXT)
=> out schema: {(3) default.lineitem.l_orderkey
(INT4),default.lineitem.l_quantity (FLOAT8),default.lineitem.l_returnflag
(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)}
=======================================================
Block Id: eb_1412235058396_0739_000002 [TERMINAL]
=======================================================
2014-10-02 07:43:52,752 INFO: org.apache.tajo.master.querymaster.Query
(<init>(227)) -
=======================================================
The order of execution:
1: eb_1412235058396_0739_000001
2: eb_1412235058396_0739_000002
=======================================================
2014-10-02 07:43:52,752 INFO: org.apache.tajo.master.TajoAsyncDispatcher
(start(101)) - AsyncDispatcher started:q_1412235058396_0739
2014-10-02 07:43:52,752 INFO: org.apache.tajo.master.querymaster.Query
(handle(843)) - Processing q_1412235058396_0739 of type START
2014-10-02 07:43:52,753 INFO: org.apache.tajo.master.querymaster.Query
(handle(861)) - q_1412235058396_0739 Query Transitioned from QUERY_NEW to
QUERY_RUNNING
2014-10-02 07:43:52,753 INFO: org.apache.tajo.master.querymaster.SubQuery
(initTaskScheduler(708)) - org.apache.tajo.master.DefaultTaskScheduler is
chosen for the task scheduling for eb_1412235058396_0739_000001
2014-10-02 07:43:52,756 INFO: org.apache.tajo.storage.AbstractStorageManager
(listStatus(385)) - Total input paths to process : 1
2014-10-02 07:43:52,757 INFO: org.apache.tajo.storage.AbstractStorageManager
(getSplits(614)) - Total # of splits: 1
2014-10-02 07:43:52,758 INFO: org.apache.tajo.master.querymaster.SubQuery
(run(668)) - 1 objects are scheduled
2014-10-02 07:43:52,758 INFO: org.apache.tajo.master.DefaultTaskScheduler
(start(89)) - Start TaskScheduler
2014-10-02 07:43:52,758 INFO: org.apache.tajo.worker.TajoResourceAllocator
(calculateNumRequestContainers(100)) - CalculateNumberRequestContainer - Number
of Tasks=1, Number of Cluster Slots=1
2014-10-02 07:43:52,758 INFO: org.apache.tajo.master.querymaster.SubQuery
(allocateContainers(916)) - Request Container for eb_1412235058396_0739_000001
containers=1
2014-10-02 07:43:52,759 INFO: org.apache.tajo.worker.TajoResourceAllocator
(run(252)) - Start TajoWorkerAllocationThread
2014-10-02 07:43:52,760 INFO: org.apache.tajo.worker.TajoResourceAllocator
(run(389)) - Stop TajoWorkerAllocationThread
2014-10-02 07:43:52,760 INFO: org.apache.tajo.master.querymaster.SubQuery
(transition(1024)) - SubQuery (eb_1412235058396_0739_000001) has 1 containers!
2014-10-02 07:43:52,762 INFO: org.apache.tajo.worker.TaskRunnerManager
(handle(165)) - ======================== Processing
eb_1412235058396_0739_000001 of type START
2014-10-02 07:43:52,763 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(122)) - Tajo Root Dir: hdfs://localhost:55253/tajo
2014-10-02 07:43:52,763 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(123)) - Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/742ab17a-a914-4700-8d6f-452f5f193acd/tajo-localdir>
2014-10-02 07:43:52,763 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(126)) - QueryMaster Address:asf905.gq1.ygridcore.net/67.195.81.149:40237
2014-10-02 07:43:52,764 INFO: org.apache.tajo.worker.TaskRunnerManager
(handle(181)) - Start
TaskRunner:eb_1412235058396_0739_000001,container_1412235058396_0739_01_002796
2014-10-02 07:43:52,765 INFO: org.apache.tajo.worker.TaskRunner (init(121)) -
TaskRunner basedir is created
(<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/742ab17a-a914-4700-8d6f-452f5f193acd/tajo-localdir/q_1412235058396_0739/output/1)>
2014-10-02 07:43:52,765 INFO: org.apache.tajo.worker.TaskRunner (run(180)) -
TaskRunner startup
2014-10-02 07:43:52,766 INFO: org.apache.tajo.worker.TaskRunner (run(211)) -
Request GetTask:
eb_1412235058396_0739_000001,container_1412235058396_0739_01_002796
2014-10-02 07:43:52,767 INFO: org.apache.tajo.master.DefaultTaskScheduler
(allocateRackTask(734)) - Assigned Local/Rack/Total: (0/1/1), Locality: 0.00%,
Rack host: asf905.gq1.ygridcore.net
2014-10-02 07:43:52,768 INFO: org.apache.tajo.worker.TaskRunner (run(255)) -
Accumulated Received Task: 1
2014-10-02 07:43:52,768 INFO: org.apache.tajo.worker.TaskRunner (run(264)) -
Initializing: ta_1412235058396_0739_000001_000000_00
2014-10-02 07:43:52,770 INFO: org.apache.tajo.worker.Task (<init>(197)) -
Output File Path:
hdfs://localhost:55253/tmp/tajo-jenkins/staging/q_1412235058396_0739/RESULT/part-01-000000-000
2014-10-02 07:43:52,770 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(145)) - Query status of ta_1412235058396_0739_000001_000000_00 is
changed to TA_PENDING
2014-10-02 07:43:52,770 INFO: org.apache.tajo.worker.Task (<init>(204)) -
==================================
2014-10-02 07:43:52,770 INFO: org.apache.tajo.worker.Task (<init>(205)) - *
Subquery ta_1412235058396_0739_000001_000000_00 is initialized
2014-10-02 07:43:52,770 INFO: org.apache.tajo.worker.Task (<init>(206)) - *
InterQuery: false, Fragments (num: 1), Fetches (total:0) :
2014-10-02 07:43:52,770 INFO: org.apache.tajo.worker.Task (<init>(216)) - *
Local task dir:
<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/742ab17a-a914-4700-8d6f-452f5f193acd/tajo-localdir/q_1412235058396_0739/output/1/0_0>
2014-10-02 07:43:52,770 INFO: org.apache.tajo.worker.Task (<init>(221)) -
==================================
2014-10-02 07:43:52,771 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(145)) - Query status of ta_1412235058396_0739_000001_000000_00 is
changed to TA_RUNNING
2014-10-02 07:43:52,804 INFO: BlockStateChange (logAddStoredBlock(2383)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37533 is added to
blk_1073743081_2257{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1,
replicas=[ReplicaUnderConstruction[[DISK]DS-2cbe89bf-7675-4a93-9bda-41be34e88fc8:NORMAL|RBW]]}
size 0
2014-10-02 07:43:52,805 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(145)) - Query status of ta_1412235058396_0739_000001_000000_00 is
changed to TA_SUCCEEDED
2014-10-02 07:43:52,806 INFO: org.apache.tajo.worker.Task (run(499)) -
ta_1412235058396_0739_000001_000000_00 completed. Worker's task counter -
total:1, succeeded: 1, killed: 0, failed: 0
2014-10-02 07:43:52,806 INFO: org.apache.tajo.worker.TaskRunner (run(211)) -
Request GetTask:
eb_1412235058396_0739_000001,container_1412235058396_0739_01_002796
2014-10-02 07:43:52,806 INFO: org.apache.tajo.master.querymaster.SubQuery
(transition(1088)) - [eb_1412235058396_0739_000001] Task Completion Event
(Total: 1, Success: 1, Killed: 0, Failed: 0)
2014-10-02 07:43:52,806 INFO: org.apache.tajo.master.querymaster.SubQuery
(transition(1190)) - subQuery completed - eb_1412235058396_0739_000001
(total=1, success=1, killed=0)
2014-10-02 07:43:52,806 INFO: org.apache.tajo.master.DefaultTaskScheduler
(run(107)) - TaskScheduler schedulingThread stopped
2014-10-02 07:43:52,806 INFO: org.apache.tajo.master.DefaultTaskScheduler
(stop(148)) - Task Scheduler stopped
2014-10-02 07:43:52,807 INFO: org.apache.tajo.worker.TaskRunner (run(249)) -
Received ShouldDie
flag:eb_1412235058396_0739_000001,container_1412235058396_0739_01_002796
2014-10-02 07:43:52,807 INFO: org.apache.tajo.master.querymaster.QueryMaster
(cleanupExecutionBlock(186)) - cleanup executionBlocks:
2014-10-02 07:43:52,807 INFO: org.apache.tajo.worker.TaskRunner (stop(151)) -
Stop TaskRunner:
eb_1412235058396_0739_000001,container_1412235058396_0739_01_002796
2014-10-02 07:43:52,807 INFO: org.apache.tajo.worker.TaskRunnerManager
(stopTaskRunner(116)) - Stop
Task:eb_1412235058396_0739_000001,container_1412235058396_0739_01_002796
2014-10-02 07:43:52,808 INFO: org.apache.tajo.master.querymaster.Query
(handle(843)) - Processing q_1412235058396_0739 of type SUBQUERY_COMPLETED
2014-10-02 07:43:52,808 INFO: org.apache.tajo.master.querymaster.Query
(handle(843)) - Processing q_1412235058396_0739 of type QUERY_COMPLETED
2014-10-02 07:43:52,809 INFO:
org.apache.tajo.master.rm.TajoWorkerResourceManager
(releaseWorkerResource(514)) - Release Resource: 0.5,512
2014-10-02 07:43:52,809 INFO: org.apache.tajo.worker.TaskRunnerManager
(handle(165)) - ======================== Processing
eb_1412235058396_0739_000001 of type STOP
2014-10-02 07:43:52,809 INFO:
org.apache.tajo.storage.HashShuffleAppenderManager (close(132)) - Close
HashShuffleAppender:eb_1412235058396_0739_000001, not a hash shuffle
2014-10-02 07:43:52,809 INFO: org.apache.tajo.master.querymaster.Query
(handle(861)) - q_1412235058396_0739 Query Transitioned from QUERY_RUNNING to
QUERY_SUCCEEDED
2014-10-02 07:43:52,809 INFO:
org.apache.tajo.master.querymaster.QueryMasterTask (handle(323)) - Query
completion notified from q_1412235058396_0739
2014-10-02 07:43:52,810 INFO:
org.apache.tajo.master.querymaster.QueryMasterTask (handle(334)) - Query final
state: QUERY_SUCCEEDED
2014-10-02 07:43:52,810 INFO: org.apache.tajo.master.querymaster.SubQuery
(receiveExecutionBlockReport(1162)) - eb_1412235058396_0739_000001,
receiveExecutionBlockReport:1
2014-10-02 07:43:52,810 INFO:
org.apache.tajo.storage.HashShuffleAppenderManager (close(132)) - Close
HashShuffleAppender:eb_1412235058396_0739_000001, not a hash shuffle
2014-10-02 07:43:52,810 INFO: org.apache.tajo.worker.TaskRunnerManager
(handle(201)) - Stopped execution block:eb_1412235058396_0739_000001
2014-10-02 07:43:52,810 INFO:
org.apache.tajo.master.querymaster.QueryMasterTask (stop(188)) - Stopping
QueryMasterTask:q_1412235058396_0739
2014-10-02 07:43:52,810 INFO:
org.apache.tajo.master.querymaster.QueryInProgress (heartbeat(265)) - Received
QueryMaster heartbeat:q_1412235058396_0739,state=QUERY_SUCCEEDED,progress=1.0,
queryMaster=asf905.gq1.ygridcore.net
2014-10-02 07:43:52,811 INFO:
org.apache.tajo.master.querymaster.QueryInProgress (stop(116)) -
=========================================================
2014-10-02 07:43:52,811 INFO:
org.apache.tajo.master.querymaster.QueryInProgress (stop(117)) - Stop
query:q_1412235058396_0739
2014-10-02 07:43:52,811 INFO:
org.apache.tajo.master.rm.TajoWorkerResourceManager
(releaseWorkerResource(514)) - Release Resource: 0.0,512
2014-10-02 07:43:52,811 INFO:
org.apache.tajo.master.querymaster.QueryJobManager (stopQuery(204)) - Stop
QueryInProgress:q_1412235058396_0739
2014-10-02 07:43:52,811 INFO:
org.apache.tajo.master.rm.TajoWorkerResourceManager (stopQueryMaster(536)) -
Released QueryMaster (q_1412235058396_0739) resource.
2014-10-02 07:43:52,811 INFO:
org.apache.tajo.master.querymaster.QueryInProgress (stop(125)) -
q_1412235058396_0739 QueryMaster stopped
2014-10-02 07:43:52,811 WARN: org.apache.tajo.master.TajoAsyncDispatcher
(stop(115)) - Interrupted Exception while stopping
2014-10-02 07:43:52,812 INFO: org.apache.tajo.master.TajoAsyncDispatcher
(stop(122)) - AsyncDispatcher stopped:q_1412235058396_0739
2014-10-02 07:43:52,812 WARN: org.apache.tajo.master.TajoAsyncDispatcher
(stop(115)) - Interrupted Exception while stopping
2014-10-02 07:43:52,812 INFO: org.apache.tajo.master.TajoAsyncDispatcher
(stop(122)) - AsyncDispatcher stopped:QueryInProgress:q_1412235058396_0739
2014-10-02 07:43:52,812 INFO:
org.apache.tajo.master.querymaster.QueryMasterTask (stop(243)) - Stopped
QueryMasterTask:q_1412235058396_0739
2014-10-02 07:43:52,812 INFO: org.apache.tajo.master.querymaster.QueryMaster
(cleanup(210)) - cleanup query resources : q_1412235058396_0739
2014-10-02 07:43:53,025 INFO: org.apache.tajo.worker.TajoWorkerClientService
(closeQuery(229)) - Stop Query:q_1412235058396_0739
2014-10-02 07:43:53,028 INFO: org.apache.tajo.master.GlobalEngine
(dropDatabase(824)) - database TestTajoJdbc is dropped.
2014-10-02 07:43:53,030 INFO: org.apache.tajo.master.session.SessionManager
(removeSession(79)) - Session 5bd747ed-fdef-4e6a-9b57-c3e2064de564 is removed.
Tests run: 10, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 7.036 sec - in
org.apache.tajo.jdbc.TestTajoJdbc
2014-10-02 07:43:53,037 INFO: org.apache.tajo.worker.TajoWorker (run(578)) -
============================================
2014-10-02 07:43:53,040 INFO: org.apache.tajo.worker.TajoWorker (run(579)) -
TajoWorker received SIGINT Signal
2014-10-02 07:43:53,040 INFO: org.apache.tajo.worker.TajoWorker (run(580)) -
============================================
Oct 2, 2014 7:38:22 AM INFO: parquet.hadoop.ParquetFileReader: reading another
1 footers
Oct 2, 2014 7:38:22 AM INFO: parquet.hadoop.ParquetFileReader: reading another
1 footers
Oct 2, 2014 7:38:35 AM INFO: parquet.hadoop.ParquetFileReader: reading another
1 footers
Oct 2, 2014 7:38:35 AM INFO: parquet.hadoop.ParquetFileReader: reading another
1 footers
Oct 2, 2014 7:38:35 AM INFO: parquet.hadoop.ParquetFileReader: reading another
1 footers
Oct 2, 2014 7:38:35 AM INFO: parquet.hadoop.ParquetFileReader: reading another
1 footers
Oct 2, 2014 7:38:35 AM INFO: parquet.hadoop.ParquetFileReader: reading another
1 footers
Oct 2, 2014 7:38:38 AM INFO: parquet.hadoop.ParquetFileReader: reading another
1 footers
2014-10-02 07:43:53,067 INFO: org.apache.tajo.worker.TajoWorker (run(578)) -
============================================
2014-10-02 07:43:53,068 INFO: org.apache.tajo.worker.TajoWorker (run(579)) -
TajoWorker received SIGINT Signal
2014-10-02 07:43:53,069 INFO: org.apache.tajo.worker.TajoWorker (run(580)) -
============================================
2014-10-02 07:43:53,080 INFO: org.apache.tajo.master.session.SessionManager
(removeSession(79)) - Session 4d4c09b6-1ce3-43d6-bce0-7a84c495bf27 is removed.
2014-10-02 07:43:53,081 INFO: org.apache.tajo.master.session.SessionManager
(removeSession(79)) - Session 4af42aac-b2c0-4b5a-a95b-bfb1c6da1126 is removed.
2014-10-02 07:43:53,081 INFO: org.apache.tajo.master.session.SessionManager
(removeSession(79)) - Session 26ccc279-ff18-41af-8ef4-d8441e2d480f is removed.
2014-10-02 07:43:53,081 INFO: org.apache.tajo.master.session.SessionManager
(removeSession(79)) - Session 36cf4018-b1be-42e3-8c3b-6ecb32e379b8 is removed.
2014-10-02 07:43:53,081 INFO: org.apache.tajo.master.session.SessionManager
(removeSession(79)) - Session 07d9e99d-8df6-4adc-b6a9-ec1ca560e37d is removed.
2014-10-02 07:43:53,140 INFO: org.apache.tajo.worker.WorkerHeartbeatService
(run(242)) - Worker Resource Heartbeat Thread stopped.
2014-10-02 07:43:53,140 INFO: org.apache.tajo.worker.WorkerHeartbeatService
(run(242)) - Worker Resource Heartbeat Thread stopped.
2014-10-02 07:43:53,148 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(128)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:40237)
shutdown
2014-10-02 07:43:53,150 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(128)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:40318)
shutdown
2014-10-02 07:43:53,150 INFO:
org.apache.tajo.master.querymaster.QueryMasterManagerService (stop(111)) -
QueryMasterManagerService stopped
2014-10-02 07:43:53,150 INFO:
org.apache.tajo.master.querymaster.QueryMasterManagerService (stop(111)) -
QueryMasterManagerService stopped
2014-10-02 07:43:53,151 INFO: org.apache.tajo.master.querymaster.QueryMaster
(run(553)) - QueryMaster heartbeat thread stopped
2014-10-02 07:43:53,152 INFO: org.apache.tajo.master.querymaster.QueryMaster
(run(553)) - QueryMaster heartbeat thread stopped
2014-10-02 07:43:53,153 INFO: org.apache.tajo.master.TajoAsyncDispatcher
(stop(122)) - AsyncDispatcher stopped:querymaster_1412235780688
2014-10-02 07:43:53,153 INFO: org.apache.tajo.master.querymaster.QueryMaster
(stop(173)) - QueryMaster stop
2014-10-02 07:43:53,153 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(108)) - TajoWorkerClientService stopping
2014-10-02 07:43:53,154 INFO: org.apache.tajo.master.TajoAsyncDispatcher
(stop(122)) - AsyncDispatcher stopped:querymaster_1412235059376
2014-10-02 07:43:53,154 INFO: org.apache.tajo.master.querymaster.QueryMaster
(stop(173)) - QueryMaster stop
2014-10-02 07:43:53,154 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(108)) - TajoWorkerClientService stopping
2014-10-02 07:43:53,157 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(128)) - Rpc (QueryMasterClientProtocol) listened on
0:0:0:0:0:0:0:0:40236) shutdown
2014-10-02 07:43:53,157 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(112)) - TajoWorkerClientService stopped
2014-10-02 07:43:53,158 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(128)) - Rpc (QueryMasterClientProtocol) listened on
0:0:0:0:0:0:0:0:40317) shutdown
2014-10-02 07:43:53,158 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(112)) - TajoWorkerClientService stopped
2014-10-02 07:43:53,163 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(128)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:40235)
shutdown
2014-10-02 07:43:53,163 INFO: org.apache.tajo.worker.TajoWorkerManagerService
(stop(97)) - TajoWorkerManagerService stopped
2014-10-02 07:43:53,164 INFO: org.apache.tajo.worker.TajoWorker
(serviceStop(376)) - TajoWorker main thread exiting
2014-10-02 07:43:53,166 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(128)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:40316)
shutdown
2014-10-02 07:43:53,166 INFO: org.apache.tajo.worker.TajoWorkerManagerService
(stop(97)) - TajoWorkerManagerService stopped
2014-10-02 07:43:53,167 INFO: org.apache.tajo.worker.TajoWorker
(serviceStop(376)) - TajoWorker main thread exiting
Results :
Failed tests:
TestTajoCli.testLocalQueryWithoutFrom:177->assertOutputResult:94->assertOutputResult:98->assertOutputResult:116
expected:<...bc, 123
(1 rows, , [0 B selected)
?substr
-------------------------------
123
(1 rows, , 0 B] selected)> but was:<...bc, 123
(1 rows, , [unknown bytes selected)
?substr
-------------------------------
123
(1 rows, , unknown bytes] selected)>
Tests run: 1173, Failures: 1, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 1.543 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 1.110 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 2.778 s]
[INFO] Tajo Common ....................................... SUCCESS [ 51.123 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 1.366 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 5.608 s]
[INFO] Tajo Rpc .......................................... SUCCESS [ 25.600 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.025 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 5.701 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 46.602 s]
[INFO] Tajo Core PullServer .............................. SUCCESS [ 0.849 s]
[INFO] Tajo Client ....................................... SUCCESS [ 3.127 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 0.592 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 0.991 s]
[INFO] Tajo Core ......................................... FAILURE [13:15 min]
[INFO] Tajo Catalog Drivers .............................. SKIPPED
[INFO] Tajo Catalog ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 15:44 min
[INFO] Finished at: 2014-10-02T07:43:53+00:00
[INFO] Final Memory: 50M/381M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal
org.apache.maven.plugins:maven-surefire-plugin:2.16:test (default-test) on
project tajo-core: There are test failures.
[ERROR]
[ERROR] Please refer to
<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/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
Build step 'Execute shell' marked build as failure
Updating TAJO-1081
