You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@tajo.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2014/07/17 12:21:31 UTC
Build failed in Jenkins: Tajo-master-build #302
See <https://builds.apache.org/job/Tajo-master-build/302/changes>
Changes:
[jinossy] TAJO-953: RawFile should release a DirectBuffer immediately. (jinho)
------------------------------------------
[...truncated 282897 lines...]
2014-07-17 10:20:14,606 INFO: org.apache.tajo.master.querymaster.QueryInProgress (submmitQueryToMaster(229)) - Call executeQuery to :asf901.gq1.ygridcore.net:14456,q_1405591848714_0668
2014-07-17 10:20:14,608 INFO: org.apache.tajo.master.querymaster.QueryMasterManagerService (executeQuery(236)) - Receive executeQuery request:q_1405591848714_0668
2014-07-17 10:20:14,608 INFO: org.apache.tajo.master.querymaster.QueryMaster (handle(374)) - Start QueryStartEventHandler:q_1405591848714_0668
2014-07-17 10:20:14,613 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (initStagingDir(379)) - The staging dir 'hdfs://localhost:59746/tmp/tajo-jenkins/staging/q_1405591848714_0668' is created.
2014-07-17 10:20:14,624 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(474)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
PROJECTION(2)
=> Targets: default.lineitem.l_orderkey (INT4), default.lineitem.l_partkey (INT4), default.lineitem.l_suppkey (INT4), default.lineitem.l_linenumber (INT4), default.lineitem.l_quantity (FLOAT8), default.lineitem.l_extendedprice (FLOAT8), default.lineitem.l_discount (FLOAT8), default.lineitem.l_tax (FLOAT8), default.lineitem.l_returnflag (TEXT), default.lineitem.l_linestatus (TEXT), default.lineitem.l_shipdate (TEXT), default.lineitem.l_commitdate (TEXT), default.lineitem.l_receiptdate (TEXT), default.lineitem.l_shipinstruct (TEXT), default.lineitem.l_shipmode (TEXT), default.lineitem.l_comment (TEXT)
=> out schema: {(16) default.lineitem.l_orderkey (INT4),default.lineitem.l_partkey (INT4),default.lineitem.l_suppkey (INT4),default.lineitem.l_linenumber (INT4),default.lineitem.l_quantity (FLOAT8),default.lineitem.l_extendedprice (FLOAT8),default.lineitem.l_discount (FLOAT8),default.lineitem.l_tax (FLOAT8),default.lineitem.l_returnflag (TEXT),default.lineitem.l_linestatus (TEXT),default.lineitem.l_shipdate (TEXT),default.lineitem.l_commitdate (TEXT),default.lineitem.l_receiptdate (TEXT),default.lineitem.l_shipinstruct (TEXT),default.lineitem.l_shipmode (TEXT),default.lineitem.l_comment (TEXT)}
=> in schema: {(16) default.lineitem.l_orderkey (INT4),default.lineitem.l_partkey (INT4),default.lineitem.l_suppkey (INT4),default.lineitem.l_linenumber (INT4),default.lineitem.l_quantity (FLOAT8),default.lineitem.l_extendedprice (FLOAT8),default.lineitem.l_discount (FLOAT8),default.lineitem.l_tax (FLOAT8),default.lineitem.l_returnflag (TEXT),default.lineitem.l_linestatus (TEXT),default.lineitem.l_shipdate (TEXT),default.lineitem.l_commitdate (TEXT),default.lineitem.l_receiptdate (TEXT),default.lineitem.l_shipinstruct (TEXT),default.lineitem.l_shipmode (TEXT),default.lineitem.l_comment (TEXT)}
LIMIT(1) 1
SCAN(0) on default.lineitem
=> target list: default.lineitem.l_orderkey (INT4), default.lineitem.l_partkey (INT4), default.lineitem.l_suppkey (INT4), default.lineitem.l_linenumber (INT4), default.lineitem.l_quantity (FLOAT8), default.lineitem.l_extendedprice (FLOAT8), default.lineitem.l_discount (FLOAT8), default.lineitem.l_tax (FLOAT8), default.lineitem.l_returnflag (TEXT), default.lineitem.l_linestatus (TEXT), default.lineitem.l_shipdate (TEXT), default.lineitem.l_commitdate (TEXT), default.lineitem.l_receiptdate (TEXT), default.lineitem.l_shipinstruct (TEXT), default.lineitem.l_shipmode (TEXT), default.lineitem.l_comment (TEXT)
=> out schema: {(16) default.lineitem.l_orderkey (INT4),default.lineitem.l_partkey (INT4),default.lineitem.l_suppkey (INT4),default.lineitem.l_linenumber (INT4),default.lineitem.l_quantity (FLOAT8),default.lineitem.l_extendedprice (FLOAT8),default.lineitem.l_discount (FLOAT8),default.lineitem.l_tax (FLOAT8),default.lineitem.l_returnflag (TEXT),default.lineitem.l_linestatus (TEXT),default.lineitem.l_shipdate (TEXT),default.lineitem.l_commitdate (TEXT),default.lineitem.l_receiptdate (TEXT),default.lineitem.l_shipinstruct (TEXT),default.lineitem.l_shipmode (TEXT),default.lineitem.l_comment (TEXT)}
=> in schema: {(16) default.lineitem.l_orderkey (INT4),default.lineitem.l_partkey (INT4),default.lineitem.l_suppkey (INT4),default.lineitem.l_linenumber (INT4),default.lineitem.l_quantity (FLOAT8),default.lineitem.l_extendedprice (FLOAT8),default.lineitem.l_discount (FLOAT8),default.lineitem.l_tax (FLOAT8),default.lineitem.l_returnflag (TEXT),default.lineitem.l_linestatus (TEXT),default.lineitem.l_shipdate (TEXT),default.lineitem.l_commitdate (TEXT),default.lineitem.l_receiptdate (TEXT),default.lineitem.l_shipinstruct (TEXT),default.lineitem.l_shipmode (TEXT),default.lineitem.l_comment (TEXT)}
2014-07-17 10:20:14,625 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(476)) - =============================================
2014-07-17 10:20:14,625 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(477)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
[LogicalPlan]
> ProjectionNode is eliminated.
-----------------------------
LIMIT(1) 1
SCAN(0) on default.lineitem
=> target list: default.lineitem.l_orderkey (INT4), default.lineitem.l_partkey (INT4), default.lineitem.l_suppkey (INT4), default.lineitem.l_linenumber (INT4), default.lineitem.l_quantity (FLOAT8), default.lineitem.l_extendedprice (FLOAT8), default.lineitem.l_discount (FLOAT8), default.lineitem.l_tax (FLOAT8), default.lineitem.l_returnflag (TEXT), default.lineitem.l_linestatus (TEXT), default.lineitem.l_shipdate (TEXT), default.lineitem.l_commitdate (TEXT), default.lineitem.l_receiptdate (TEXT), default.lineitem.l_shipinstruct (TEXT), default.lineitem.l_shipmode (TEXT), default.lineitem.l_comment (TEXT)
=> out schema: {(16) default.lineitem.l_orderkey (INT4),default.lineitem.l_partkey (INT4),default.lineitem.l_suppkey (INT4),default.lineitem.l_linenumber (INT4),default.lineitem.l_quantity (FLOAT8),default.lineitem.l_extendedprice (FLOAT8),default.lineitem.l_discount (FLOAT8),default.lineitem.l_tax (FLOAT8),default.lineitem.l_returnflag (TEXT),default.lineitem.l_linestatus (TEXT),default.lineitem.l_shipdate (TEXT),default.lineitem.l_commitdate (TEXT),default.lineitem.l_receiptdate (TEXT),default.lineitem.l_shipinstruct (TEXT),default.lineitem.l_shipmode (TEXT),default.lineitem.l_comment (TEXT)}
=> in schema: {(16) default.lineitem.l_orderkey (INT4),default.lineitem.l_partkey (INT4),default.lineitem.l_suppkey (INT4),default.lineitem.l_linenumber (INT4),default.lineitem.l_quantity (FLOAT8),default.lineitem.l_extendedprice (FLOAT8),default.lineitem.l_discount (FLOAT8),default.lineitem.l_tax (FLOAT8),default.lineitem.l_returnflag (TEXT),default.lineitem.l_linestatus (TEXT),default.lineitem.l_shipdate (TEXT),default.lineitem.l_commitdate (TEXT),default.lineitem.l_receiptdate (TEXT),default.lineitem.l_shipinstruct (TEXT),default.lineitem.l_shipmode (TEXT),default.lineitem.l_comment (TEXT)}
2014-07-17 10:20:14,625 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(478)) - =============================================
2014-07-17 10:20:14,634 INFO: org.apache.tajo.engine.planner.global.GlobalPlanner (build(116)) -
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
[LogicalPlan]
> ProjectionNode is eliminated.
-----------------------------
SCAN(0) on default.lineitem
=> target list: sleep(2) as ?sleep
=> out schema: {(1) ?sleep (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)}
2014-07-17 10:20:14,635 INFO: org.apache.tajo.engine.planner.global.GlobalPlanner (build(159)) -
-------------------------------------------------------------------------------
Execution Block Graph (TERMINAL - eb_1405591848714_0668_000002)
-------------------------------------------------------------------------------
|-eb_1405591848714_0668_000002
|-eb_1405591848714_0668_000001
-------------------------------------------------------------------------------
Order of Execution
-------------------------------------------------------------------------------
1: eb_1405591848714_0668_000001
2: eb_1405591848714_0668_000002
-------------------------------------------------------------------------------
=======================================================
Block Id: eb_1405591848714_0668_000001 [ROOT]
=======================================================
SCAN(0) on default.lineitem
=> target list: sleep(2) as ?sleep
=> out schema: {(1) ?sleep (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_1405591848714_0668_000002 [TERMINAL]
=======================================================
2014-07-17 10:20:14,635 INFO: org.apache.tajo.master.querymaster.Query (<init>(226)) -
=======================================================
The order of execution:
1: eb_1405591848714_0668_000001
2: eb_1405591848714_0668_000002
=======================================================
2014-07-17 10:20:14,636 INFO: org.apache.tajo.master.TajoAsyncDispatcher (start(101)) - AsyncDispatcher started:q_1405591848714_0668
2014-07-17 10:20:14,636 INFO: org.apache.tajo.master.querymaster.Query (handle(842)) - Processing q_1405591848714_0668 of type START
2014-07-17 10:20:14,636 INFO: org.apache.tajo.master.querymaster.SubQuery (initTaskScheduler(677)) - org.apache.tajo.master.DefaultTaskScheduler is chosen for the task scheduling for eb_1405591848714_0668_000001
2014-07-17 10:20:14,639 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(386)) - Total input paths to process : 1
2014-07-17 10:20:14,640 INFO: org.apache.tajo.storage.AbstractStorageManager (getSplits(615)) - Total # of splits: 1
2014-07-17 10:20:14,641 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(647)) - 1 objects are scheduled
2014-07-17 10:20:14,641 INFO: org.apache.tajo.master.DefaultTaskScheduler (start(87)) - Start TaskScheduler
2014-07-17 10:20:14,641 INFO: org.apache.tajo.worker.TajoResourceAllocator (calculateNumRequestContainers(100)) - CalculateNumberRequestContainer - Number of Tasks=1, Number of Cluster Slots=1
2014-07-17 10:20:14,641 INFO: org.apache.tajo.master.querymaster.SubQuery (allocateContainers(890)) - Request Container for eb_1405591848714_0668_000001 containers=1
2014-07-17 10:20:14,641 INFO: org.apache.tajo.master.querymaster.Query (handle(859)) - q_1405591848714_0668 Query Transitioned from QUERY_NEW to QUERY_RUNNING
2014-07-17 10:20:14,642 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(217)) - Start TajoWorkerAllocationThread
2014-07-17 10:20:14,643 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(336)) - Stop TajoWorkerAllocationThread
2014-07-17 10:20:14,643 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(999)) - SubQuery (eb_1405591848714_0668_000001) has 1 containers!
2014-07-17 10:20:14,645 INFO: org.apache.tajo.worker.TaskRunner (<init>(122)) - Tajo Root Dir: hdfs://localhost:59746/tajo
2014-07-17 10:20:14,645 INFO: org.apache.tajo.worker.TaskRunner (<init>(123)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d9c880a3-7ffe-4b0d-a2ce-a55ed94319c4/tajo-localdir>
2014-07-17 10:20:14,646 INFO: org.apache.tajo.worker.TaskRunner (<init>(138)) - QueryMaster Address:asf901.gq1.ygridcore.net/67.195.81.145:14456
2014-07-17 10:20:14,646 INFO: org.apache.tajo.worker.TaskRunnerManager (run(156)) - Start TaskRunner:eb_1405591848714_0668_000001,container_1405591848714_0668_01_002626
2014-07-17 10:20:14,647 INFO: org.apache.tajo.worker.TaskRunner (init(191)) - TaskRunner basedir is created (q_1405591848714_0668/output/1)
2014-07-17 10:20:14,647 INFO: org.apache.tajo.worker.TaskRunner (run(327)) - TaskRunner startup
2014-07-17 10:20:14,647 INFO: org.apache.tajo.worker.TaskRunner (run(346)) - Request GetTask: eb_1405591848714_0668_000001,container_1405591848714_0668_01_002626
2014-07-17 10:20:14,670 INFO: org.apache.tajo.master.DefaultTaskScheduler (allocateRackTask(718)) - Assigned Local/Rack/Total: (0/1/1), Locality: 0.00%, Rack host: 67.195.81.145
2014-07-17 10:20:14,672 INFO: org.apache.tajo.worker.TaskRunner (run(385)) - Accumulated Received Task: 1
2014-07-17 10:20:14,672 INFO: org.apache.tajo.worker.TaskRunner (run(394)) - Initializing: ta_1405591848714_0668_000001_000000_00
2014-07-17 10:20:14,673 INFO: org.apache.tajo.worker.Task (<init>(195)) - Output File Path: hdfs://localhost:59746/tmp/tajo-jenkins/staging/q_1405591848714_0668/RESULT/part-01-000000-000
2014-07-17 10:20:14,673 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(120)) - Query status of ta_1405591848714_0668_000001_000000_00 is changed to TA_PENDING
2014-07-17 10:20:14,673 INFO: org.apache.tajo.worker.Task (<init>(200)) - ==================================
2014-07-17 10:20:14,673 INFO: org.apache.tajo.worker.Task (<init>(201)) - * Subquery ta_1405591848714_0668_000001_000000_00 is initialized
2014-07-17 10:20:14,673 INFO: org.apache.tajo.worker.Task (<init>(202)) - * InterQuery: false
2014-07-17 10:20:14,673 INFO: org.apache.tajo.worker.Task (<init>(205)) - * Fragments (num: 1)
2014-07-17 10:20:14,673 INFO: org.apache.tajo.worker.Task (<init>(206)) - * Fetches (total:0) :
2014-07-17 10:20:14,673 INFO: org.apache.tajo.worker.Task (<init>(210)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d9c880a3-7ffe-4b0d-a2ce-a55ed94319c4/tajo-localdir/q_1405591848714_0668/output/1/0_0>
2014-07-17 10:20:14,673 INFO: org.apache.tajo.worker.Task (<init>(215)) - ==================================
2014-07-17 10:20:14,674 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(120)) - Query status of ta_1405591848714_0668_000001_000000_00 is changed to TA_RUNNING
2014-07-17 10:20:15,656 INFO: org.apache.tajo.master.querymaster.Query (handle(842)) - Processing q_1405591848714_0668 of type KILL
2014-07-17 10:20:15,657 INFO: org.apache.tajo.master.querymaster.Query (handle(859)) - q_1405591848714_0668 Query Transitioned from QUERY_RUNNING to QUERY_KILL_WAIT
2014-07-17 10:20:15,657 INFO: org.apache.tajo.master.DefaultTaskScheduler (stop(143)) - Task Scheduler stopped
2014-07-17 10:20:15,657 INFO: org.apache.tajo.master.DefaultTaskScheduler (run(102)) - TaskScheduler schedulingThread stopped
2014-07-17 10:20:15,658 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(120)) - Query status of ta_1405591848714_0668_000001_000000_00 is changed to TA_KILLED
2014-07-17 10:20:16,259 INFO: org.apache.tajo.master.querymaster.QueryInProgress (heartbeat(265)) - Received QueryMaster heartbeat:q_1405591848714_0668,state=QUERY_KILL_WAIT,progress=0.0, queryMaster=asf901.gq1.ygridcore.net
2014-07-17 10:20:18,260 INFO: org.apache.tajo.master.querymaster.QueryInProgress (heartbeat(265)) - Received QueryMaster heartbeat:q_1405591848714_0668,state=QUERY_KILL_WAIT,progress=0.0, queryMaster=asf901.gq1.ygridcore.net
2014-07-17 10:20:20,260 INFO: org.apache.tajo.master.querymaster.QueryInProgress (heartbeat(265)) - Received QueryMaster heartbeat:q_1405591848714_0668,state=QUERY_KILL_WAIT,progress=0.0, queryMaster=asf901.gq1.ygridcore.net
2014-07-17 10:20:22,260 INFO: org.apache.tajo.master.querymaster.QueryInProgress (heartbeat(265)) - Received QueryMaster heartbeat:q_1405591848714_0668,state=QUERY_KILL_WAIT,progress=0.0, queryMaster=asf901.gq1.ygridcore.net
2014-07-17 10:20:24,261 INFO: org.apache.tajo.master.querymaster.QueryInProgress (heartbeat(265)) - Received QueryMaster heartbeat:q_1405591848714_0668,state=QUERY_KILL_WAIT,progress=0.0, queryMaster=asf901.gq1.ygridcore.net
2014-07-17 10:20:24,688 INFO: BlockStateChange (logAddStoredBlock(2300)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:46682 is added to blk_1073743029_2205{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-071ac8c1-9c62-4011-b0fd-5aa8a1f44d33:NORMAL|RBW]]} size 0
2014-07-17 10:20:24,689 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(120)) - Query status of ta_1405591848714_0668_000001_000000_00 is changed to TA_KILLED
2014-07-17 10:20:24,690 INFO: org.apache.tajo.worker.Task (run(488)) - Worker's task counter - total:1, succeeded: 0, killed: 1, failed: 0
2014-07-17 10:20:24,690 INFO: org.apache.tajo.worker.TaskRunner (run(346)) - Request GetTask: eb_1405591848714_0668_000001,container_1405591848714_0668_01_002626
2014-07-17 10:20:24,690 WARN: org.apache.tajo.master.querymaster.QueryMasterManagerService (statusUpdate(162)) - ta_1405591848714_0668_000001_000000_00 Killed
2014-07-17 10:20:24,690 INFO: org.apache.tajo.master.querymaster.QueryUnitAttempt (transition(343)) - ta_1405591848714_0668_000001_000000_00 Received TA_KILLED Status from LocalTask
2014-07-17 10:20:24,690 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1076)) - [eb_1405591848714_0668_000001] Task Completion Event (Total: 1, Success: 0, Killed: 1, Failed: 0)
2014-07-17 10:20:24,691 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1116)) - subQuery completed - eb_1405591848714_0668_000001 (total=1, success=0, killed=1)
2014-07-17 10:20:24,691 INFO: org.apache.tajo.worker.TaskRunner (run(376)) - Received ShouldDie flag:eb_1405591848714_0668_000001,container_1405591848714_0668_01_002626
2014-07-17 10:20:24,691 INFO: org.apache.tajo.master.querymaster.Query (handle(842)) - Processing q_1405591848714_0668 of type SUBQUERY_COMPLETED
2014-07-17 10:20:24,691 INFO: org.apache.tajo.worker.TaskRunner (stop(239)) - Stop TaskRunner: eb_1405591848714_0668_000001
2014-07-17 10:20:24,691 INFO: org.apache.tajo.master.querymaster.Query (handle(842)) - Processing q_1405591848714_0668 of type QUERY_COMPLETED
2014-07-17 10:20:24,691 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTask(89)) - Stop Task:eb_1405591848714_0668_000001,container_1405591848714_0668_01_002626
2014-07-17 10:20:24,692 INFO: org.apache.tajo.master.querymaster.Query (handle(859)) - q_1405591848714_0668 Query Transitioned from QUERY_KILL_WAIT to QUERY_KILLED
2014-07-17 10:20:24,692 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(519)) - Release Resource: 0.5,512
2014-07-17 10:20:24,692 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (handle(297)) - Query completion notified from q_1405591848714_0668
2014-07-17 10:20:24,692 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (handle(308)) - Query final state: QUERY_KILLED
2014-07-17 10:20:24,692 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (stop(187)) - Stopping QueryMasterTask:q_1405591848714_0668
2014-07-17 10:20:24,693 INFO: org.apache.tajo.master.querymaster.QueryJobManager (stopQuery(203)) - Stop QueryInProgress:q_1405591848714_0668
2014-07-17 10:20:24,693 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(116)) - =========================================================
2014-07-17 10:20:24,693 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(117)) - Stop query:q_1405591848714_0668
2014-07-17 10:20:24,693 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(519)) - Release Resource: 0.0,512
2014-07-17 10:20:24,694 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (stopQueryMaster(541)) - Released QueryMaster (q_1405591848714_0668) resource.
2014-07-17 10:20:24,694 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(125)) - q_1405591848714_0668 QueryMaster stopped
2014-07-17 10:20:24,693 INFO: org.apache.tajo.master.querymaster.QueryInProgress (heartbeat(265)) - Received QueryMaster heartbeat:q_1405591848714_0668,state=QUERY_KILLED,progress=0.0, queryMaster=asf901.gq1.ygridcore.net
2014-07-17 10:20:24,695 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:QueryInProgress:q_1405591848714_0668
2014-07-17 10:20:24,696 WARN: org.apache.tajo.master.TajoAsyncDispatcher (stop(115)) - Interrupted Exception while stopping
2014-07-17 10:20:24,696 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:q_1405591848714_0668
2014-07-17 10:20:24,697 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (stop(217)) - Stopped QueryMasterTask:q_1405591848714_0668
2014-07-17 10:20:24,697 INFO: org.apache.tajo.master.querymaster.QueryMaster (cleanup(166)) - cleanup query resources : q_1405591848714_0668
2014-07-17 10:20:24,727 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session af849572-2293-4c4b-ae9c-988f6ee7c668 is removed.
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 30.62 sec
2014-07-17 10:20:24,739 INFO: org.apache.tajo.worker.TajoWorker (run(492)) - ============================================
2014-07-17 10:20:24,739 INFO: org.apache.tajo.worker.TajoWorker (run(493)) - TajoWorker received SIGINT Signal
2014-07-17 10:20:24,739 INFO: org.apache.tajo.worker.TajoWorker (run(494)) - ============================================
2014-07-17 10:20:24,744 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-07-17 10:20:24,744 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-07-17 10:20:24,745 ERROR: org.apache.tajo.client.TajoClient (close(141)) - java.io.IOException: Connect error to localhost/127.0.0.1:14453 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-07-17 10:20:24,747 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session 9c7d1919-c7ea-446a-804d-5ab67959c444 is removed.
2014-07-17 10:20:24,746 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-07-17 10:20:24,746 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-07-17 10:20:24,747 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session 25642f24-bd21-4cc7-9f25-42cb2192ac58 is removed.
2014-07-17 10:20:24,746 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-07-17 10:20:24,746 ERROR: org.apache.tajo.client.TajoClient (close(141)) - java.io.IOException: Connect error to localhost/127.0.0.1:14453 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-07-17 10:20:24,749 ERROR: org.apache.tajo.client.TajoClient (close(141)) - java.io.IOException: Connect error to localhost/127.0.0.1:14453 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-07-17 10:20:24,749 ERROR: org.apache.tajo.client.TajoClient (close(141)) - java.io.IOException: Connect error to localhost/127.0.0.1:14453 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-07-17 10:20:24,748 ERROR: org.apache.tajo.client.TajoClient (close(141)) - java.io.IOException: Connect error to localhost/127.0.0.1:14453 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-07-17 10:20:24,748 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-07-17 10:20:24,748 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-07-17 10:20:24,750 ERROR: org.apache.tajo.client.TajoClient (close(141)) - java.io.IOException: Connect error to localhost/127.0.0.1:14453 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-07-17 10:20:24,751 ERROR: org.apache.tajo.client.TajoClient (close(141)) - java.io.IOException: Connect error to localhost/127.0.0.1:14453 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-07-17 10:20:24,756 INFO: org.apache.tajo.worker.WorkerHeartbeatService (run(260)) - Worker Resource Heartbeat Thread stopped.
2014-07-17 10:20:24,812 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:14457) shutdown
2014-07-17 10:20:24,815 INFO: org.apache.tajo.worker.TajoWorkerManagerService (stop(95)) - TajoWorkerManagerService stopped
2014-07-17 10:20:24,822 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:14456) shutdown
2014-07-17 10:20:24,823 INFO: org.apache.tajo.master.querymaster.QueryMasterManagerService (stop(111)) - QueryMasterManagerService stopped
2014-07-17 10:20:24,823 INFO: org.apache.tajo.master.querymaster.QueryMaster (run(433)) - QueryMaster heartbeat thread stopped
2014-07-17 10:20:24,825 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:querymaster_1405591849413
2014-07-17 10:20:24,825 INFO: org.apache.tajo.master.querymaster.QueryMaster (stop(159)) - QueryMaster stop
2014-07-17 10:20:24,825 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(110)) - TajoWorkerClientService stopping
2014-07-17 10:20:24,829 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:14455) shutdown
2014-07-17 10:20:24,829 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(114)) - TajoWorkerClientService stopped
2014-07-17 10:20:24,829 INFO: org.apache.tajo.worker.TajoWorker (stop(334)) - TajoWorker main thread exiting
Results :
Tests in error:
testCrossJoinWithAsterisk4(org.apache.tajo.engine.query.TestJoinBroadcast): Connect error to asf901.gq1.ygridcore.net/67.195.81.145:0 cause java.net.ConnectException: Connection refused: asf901.gq1.ygridcore.net/67.195.81.145:0
Tests run: 1070, Failures: 0, Errors: 1, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 5.883 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 0.726 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 2.622 s]
[INFO] Tajo Common ....................................... SUCCESS [ 37.814 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 1.283 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 5.333 s]
[INFO] Tajo Rpc .......................................... SUCCESS [ 21.396 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.003 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 5.548 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 42.839 s]
[INFO] Tajo Core PullServer .............................. SUCCESS [ 0.759 s]
[INFO] Tajo Client ....................................... SUCCESS [ 3.101 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 0.470 s]
[INFO] Tajo Core ......................................... FAILURE [09:55 min]
[INFO] Tajo Catalog Drivers .............................. SKIPPED
[INFO] Tajo Catalog ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 12:04 min
[INFO] Finished at: 2014-07-17T10:20:25+00:00
[INFO] Final Memory: 93M/1878M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4: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-953
Jenkins build is back to normal : Tajo-master-build #303
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/303/changes>