See <https://builds.apache.org/job/Tajo-master-build/285/changes>
Changes:
[hyunsik] TAJO-925: Child ExecutionBlock of JOIN node has different number of
shuffle keys. (Hyoungjun Kim via hyunsik)
------------------------------------------
[...truncated 272756 lines...]
|-#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-11 05:54:53,400 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(478)) - =============================================
2014-07-11 05:54:53,411 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-11 05:54:53,411 INFO:
org.apache.tajo.engine.planner.global.GlobalPlanner (build(159)) -
-------------------------------------------------------------------------------
Execution Block Graph (TERMINAL - eb_1405057565813_0643_000002)
-------------------------------------------------------------------------------
|-eb_1405057565813_0643_000002
|-eb_1405057565813_0643_000001
-------------------------------------------------------------------------------
Order of Execution
-------------------------------------------------------------------------------
1: eb_1405057565813_0643_000001
2: eb_1405057565813_0643_000002
-------------------------------------------------------------------------------
=======================================================
Block Id: eb_1405057565813_0643_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_1405057565813_0643_000002 [TERMINAL]
=======================================================
2014-07-11 05:54:53,412 INFO: org.apache.tajo.master.querymaster.Query
(<init>(223)) -
=======================================================
The order of execution:
1: eb_1405057565813_0643_000001
2: eb_1405057565813_0643_000002
=======================================================
2014-07-11 05:54:53,412 INFO: org.apache.tajo.master.TajoAsyncDispatcher
(start(101)) - AsyncDispatcher started:q_1405057565813_0643
2014-07-11 05:54:53,412 INFO: org.apache.tajo.master.querymaster.Query
(handle(701)) - Processing q_1405057565813_0643 of type START
2014-07-11 05:54:53,412 INFO: org.apache.tajo.master.querymaster.SubQuery
(initTaskScheduler(677)) - org.apache.tajo.master.DefaultTaskScheduler is
chosen for the task scheduling for eb_1405057565813_0643_000001
2014-07-11 05:54:53,415 INFO: org.apache.tajo.storage.AbstractStorageManager
(listStatus(386)) - Total input paths to process : 1
2014-07-11 05:54:53,417 INFO: org.apache.tajo.storage.AbstractStorageManager
(getSplits(615)) - Total # of splits: 1
2014-07-11 05:54:53,417 INFO: org.apache.tajo.master.querymaster.SubQuery
(transition(647)) - 1 objects are scheduled
2014-07-11 05:54:53,417 INFO: org.apache.tajo.master.DefaultTaskScheduler
(start(87)) - Start TaskScheduler
2014-07-11 05:54:53,417 INFO: org.apache.tajo.worker.TajoResourceAllocator
(calculateNumRequestContainers(100)) - CalculateNumberRequestContainer - Number
of Tasks=1, Number of Cluster Slots=1
2014-07-11 05:54:53,418 INFO: org.apache.tajo.master.querymaster.SubQuery
(allocateContainers(890)) - Request Container for eb_1405057565813_0643_000001
containers=1
2014-07-11 05:54:53,418 INFO: org.apache.tajo.master.querymaster.Query
(handle(718)) - q_1405057565813_0643 Query Transitioned from QUERY_NEW to
QUERY_RUNNING
2014-07-11 05:54:53,418 INFO: org.apache.tajo.worker.TajoResourceAllocator
(run(217)) - Start TajoWorkerAllocationThread
2014-07-11 05:54:53,419 INFO: org.apache.tajo.worker.TajoResourceAllocator
(run(336)) - Stop TajoWorkerAllocationThread
2014-07-11 05:54:53,419 INFO: org.apache.tajo.master.querymaster.SubQuery
(transition(999)) - SubQuery (eb_1405057565813_0643_000001) has 1 containers!
2014-07-11 05:54:53,421 INFO: org.apache.tajo.worker.TaskRunner (<init>(122)) -
Tajo Root Dir: hdfs://localhost:34266/tajo
2014-07-11 05:54:53,421 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/7d3cc4a7-d56a-41ba-8e88-cf12db6eb4c4/tajo-localdir>
2014-07-11 05:54:53,422 INFO: org.apache.tajo.worker.TaskRunner (<init>(138)) -
QueryMaster Address:asf901.gq1.ygridcore.net/67.195.81.145:31980
2014-07-11 05:54:53,422 INFO: org.apache.tajo.worker.TaskRunnerManager
(run(156)) - Start
TaskRunner:eb_1405057565813_0643_000001,container_1405057565813_0643_01_002555
2014-07-11 05:54:53,423 INFO: org.apache.tajo.worker.TaskRunner (init(191)) -
TaskRunner basedir is created (q_1405057565813_0643/output/1)
2014-07-11 05:54:53,423 INFO: org.apache.tajo.worker.TaskRunner (run(327)) -
TaskRunner startup
2014-07-11 05:54:53,423 INFO: org.apache.tajo.worker.TaskRunner (run(346)) -
Request GetTask:
eb_1405057565813_0643_000001,container_1405057565813_0643_01_002555
2014-07-11 05:54:53,424 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-11 05:54:53,426 INFO: org.apache.tajo.worker.TaskRunner (run(385)) -
Accumulated Received Task: 1
2014-07-11 05:54:53,426 INFO: org.apache.tajo.worker.TaskRunner (run(394)) -
Initializing: ta_1405057565813_0643_000001_000000_00
2014-07-11 05:54:53,427 INFO: org.apache.tajo.worker.Task (<init>(184)) -
Output File Path:
hdfs://localhost:34266/tmp/tajo-jenkins/staging/q_1405057565813_0643/RESULT/part-01-000000
2014-07-11 05:54:53,427 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(115)) - Query status of ta_1405057565813_0643_000001_000000_00 is
changed to TA_PENDING
2014-07-11 05:54:53,428 INFO: org.apache.tajo.worker.Task (<init>(189)) -
==================================
2014-07-11 05:54:53,428 INFO: org.apache.tajo.worker.Task (<init>(190)) - *
Subquery ta_1405057565813_0643_000001_000000_00 is initialized
2014-07-11 05:54:53,428 INFO: org.apache.tajo.worker.Task (<init>(191)) - *
InterQuery: false
2014-07-11 05:54:53,428 INFO: org.apache.tajo.worker.Task (<init>(194)) - *
Fragments (num: 1)
2014-07-11 05:54:53,428 INFO: org.apache.tajo.worker.Task (<init>(195)) - *
Fetches (total:0) :
2014-07-11 05:54:53,428 INFO: org.apache.tajo.worker.Task (<init>(199)) - *
Local task dir:
<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/7d3cc4a7-d56a-41ba-8e88-cf12db6eb4c4/tajo-localdir/q_1405057565813_0643/output/1/0_0>
2014-07-11 05:54:53,428 INFO: org.apache.tajo.worker.Task (<init>(204)) -
==================================
2014-07-11 05:54:53,429 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(115)) - Query status of ta_1405057565813_0643_000001_000000_00 is
changed to TA_RUNNING
2014-07-11 05:54:54,424 INFO: org.apache.tajo.master.querymaster.Query
(handle(701)) - Processing q_1405057565813_0643 of type KILL
2014-07-11 05:54:54,425 INFO: org.apache.tajo.master.querymaster.Query
(handle(718)) - q_1405057565813_0643 Query Transitioned from QUERY_RUNNING to
QUERY_KILL_WAIT
2014-07-11 05:54:54,425 INFO: org.apache.tajo.master.DefaultTaskScheduler
(stop(143)) - Task Scheduler stopped
2014-07-11 05:54:54,425 INFO: org.apache.tajo.master.DefaultTaskScheduler
(run(102)) - TaskScheduler schedulingThread stopped
2014-07-11 05:54:54,425 INFO: org.apache.tajo.worker.TaskAttemptContext
(setState(115)) - Query status of ta_1405057565813_0643_000001_000000_00 is
changed to TA_KILLED
2014-07-11 05:54:55,127 INFO:
org.apache.tajo.master.querymaster.QueryInProgress (heartbeat(265)) - Received
QueryMaster heartbeat:q_1405057565813_0643,state=QUERY_KILL_WAIT,progress=0.0,
queryMaster=asf901.gq1.ygridcore.net
2014-07-11 05:54:56,185 ERROR: org.apache.tajo.rpc.AsyncRpcClient
(exceptionCaught(220)) - asf901.gq1.ygridcore.net/67.195.81.145:31980,class
org.apache.tajo.ipc.QueryMasterProtocol,null
java.nio.channels.ClosedChannelException
at
org.jboss.netty.channel.socket.nio.AbstractNioWorker.cleanUpWriteBuffer(AbstractNioWorker.java:434)
at
org.jboss.netty.channel.socket.nio.AbstractNioWorker.writeFromUserCode(AbstractNioWorker.java:129)
at
org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.eventSunk(NioClientSocketPipelineSink.java:84)
at org.jboss.netty.channel.Channels.write(Channels.java:725)
at
org.jboss.netty.handler.codec.oneone.OneToOneEncoder.doEncode(OneToOneEncoder.java:71)
at
org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:59)
at org.jboss.netty.channel.Channels.write(Channels.java:725)
at
org.jboss.netty.handler.codec.oneone.OneToOneEncoder.doEncode(OneToOneEncoder.java:71)
at
org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:59)
at org.jboss.netty.channel.Channels.write(Channels.java:704)
at org.jboss.netty.channel.Channels.write(Channels.java:671)
at
org.jboss.netty.channel.AbstractChannel.write(AbstractChannel.java:248)
at
org.apache.tajo.rpc.AsyncRpcClient$ProxyRpcChannel.callMethod(AsyncRpcClient.java:126)
at
org.apache.tajo.ipc.QueryMasterProtocol$QueryMasterProtocolService$Stub.statusUpdate(QueryMasterProtocol.java:461)
at org.apache.tajo.worker.Task$Reporter$1.run(Task.java:720)
at java.lang.Thread.run(Thread.java:662)
2014-07-11 05:54:56,186 ERROR: org.apache.tajo.rpc.AsyncRpcClient
(exceptionCaught(235)) - RPC Exception:java.nio.channels.ClosedChannelException
2014-07-11 05:54:56,319 ERROR: org.apache.tajo.rpc.AsyncRpcClient
(exceptionCaught(220)) - asf901.gq1.ygridcore.net/67.195.81.145:31980,class
org.apache.tajo.ipc.QueryMasterProtocol,null
java.nio.channels.ClosedChannelException
at
org.jboss.netty.channel.socket.nio.AbstractNioWorker.cleanUpWriteBuffer(AbstractNioWorker.java:434)
at
org.jboss.netty.channel.socket.nio.AbstractNioWorker.writeFromUserCode(AbstractNioWorker.java:129)
at
org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.eventSunk(NioClientSocketPipelineSink.java:84)
at org.jboss.netty.channel.Channels.write(Channels.java:725)
at
org.jboss.netty.handler.codec.oneone.OneToOneEncoder.doEncode(OneToOneEncoder.java:71)
at
org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:59)
at org.jboss.netty.channel.Channels.write(Channels.java:725)
at
org.jboss.netty.handler.codec.oneone.OneToOneEncoder.doEncode(OneToOneEncoder.java:71)
at
org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:59)
at org.jboss.netty.channel.Channels.write(Channels.java:704)
at org.jboss.netty.channel.Channels.write(Channels.java:671)
at
org.jboss.netty.channel.AbstractChannel.write(AbstractChannel.java:248)
at
org.apache.tajo.rpc.AsyncRpcClient$ProxyRpcChannel.callMethod(AsyncRpcClient.java:126)
at
org.apache.tajo.ipc.QueryMasterProtocol$QueryMasterProtocolService$Stub.statusUpdate(QueryMasterProtocol.java:461)
at org.apache.tajo.worker.Task$Reporter$1.run(Task.java:720)
at java.lang.Thread.run(Thread.java:662)
2014-07-11 05:54:56,320 ERROR: org.apache.tajo.rpc.AsyncRpcClient
(exceptionCaught(235)) - RPC Exception:java.nio.channels.ClosedChannelException
2014-07-11 05:54:56,427 WARN:
org.apache.tajo.master.querymaster.QueryMasterManagerService
(statusUpdate(162)) - ta_1405057565813_0643_000001_000000_00 Killed
2014-07-11 05:54:56,428 INFO:
org.apache.tajo.master.querymaster.QueryUnitAttempt (transition(343)) -
ta_1405057565813_0643_000001_000000_00 Received TA_KILLED Status from LocalTask
2014-07-11 05:54:56,428 INFO: org.apache.tajo.master.querymaster.SubQuery
(transition(1076)) - [eb_1405057565813_0643_000001] Task Completion Event
(Total: 1, Success: 0, Killed: 1, Failed: 0)
2014-07-11 05:54:56,428 INFO: org.apache.tajo.master.querymaster.SubQuery
(transition(1116)) - subQuery completed - eb_1405057565813_0643_000001
(total=1, success=0, killed=1)
2014-07-11 05:54:56,429 INFO: org.apache.tajo.master.querymaster.Query
(handle(701)) - Processing q_1405057565813_0643 of type SUBQUERY_COMPLETED
2014-07-11 05:54:56,429 INFO: org.apache.tajo.master.querymaster.Query
(handle(701)) - Processing q_1405057565813_0643 of type QUERY_COMPLETED
2014-07-11 05:54:56,429 INFO: org.apache.tajo.master.querymaster.Query
(handle(718)) - q_1405057565813_0643 Query Transitioned from QUERY_KILL_WAIT to
QUERY_KILLED
2014-07-11 05:54:56,429 INFO:
org.apache.tajo.master.querymaster.QueryMasterTask (handle(297)) - Query
completion notified from q_1405057565813_0643
2014-07-11 05:54:56,429 INFO:
org.apache.tajo.master.querymaster.QueryMasterTask (handle(308)) - Query final
state: QUERY_KILLED
2014-07-11 05:54:56,429 INFO:
org.apache.tajo.master.rm.TajoWorkerResourceManager
(releaseWorkerResource(519)) - Release Resource: 0.5,512
2014-07-11 05:54:56,429 INFO:
org.apache.tajo.master.querymaster.QueryMasterTask (stop(187)) - Stopping
QueryMasterTask:q_1405057565813_0643
2014-07-11 05:54:56,430 INFO:
org.apache.tajo.master.querymaster.QueryInProgress (heartbeat(265)) - Received
QueryMaster heartbeat:q_1405057565813_0643,state=QUERY_KILLED,progress=0.0,
queryMaster=asf901.gq1.ygridcore.net
2014-07-11 05:54:56,430 INFO:
org.apache.tajo.master.querymaster.QueryInProgress (stop(116)) -
=========================================================
2014-07-11 05:54:56,430 INFO:
org.apache.tajo.master.querymaster.QueryJobManager (stopQuery(203)) - Stop
QueryInProgress:q_1405057565813_0643
2014-07-11 05:54:56,430 INFO:
org.apache.tajo.master.querymaster.QueryInProgress (stop(117)) - Stop
query:q_1405057565813_0643
2014-07-11 05:54:56,430 INFO:
org.apache.tajo.master.rm.TajoWorkerResourceManager
(releaseWorkerResource(519)) - Release Resource: 0.0,512
2014-07-11 05:54:56,430 WARN: org.apache.tajo.master.TajoAsyncDispatcher
(stop(115)) - Interrupted Exception while stopping
2014-07-11 05:54:56,431 INFO: org.apache.tajo.master.TajoAsyncDispatcher
(stop(122)) - AsyncDispatcher stopped:q_1405057565813_0643
2014-07-11 05:54:56,430 INFO:
org.apache.tajo.master.rm.TajoWorkerResourceManager (stopQueryMaster(541)) -
Released QueryMaster (q_1405057565813_0643) resource.
2014-07-11 05:54:56,431 INFO:
org.apache.tajo.master.querymaster.QueryInProgress (stop(125)) -
q_1405057565813_0643 QueryMaster stopped
2014-07-11 05:54:56,431 INFO:
org.apache.tajo.master.querymaster.QueryMasterTask (stop(217)) - Stopped
QueryMasterTask:q_1405057565813_0643
2014-07-11 05:54:56,431 INFO: org.apache.tajo.master.querymaster.QueryMaster
(cleanup(166)) - cleanup query resources : q_1405057565813_0643
2014-07-11 05:54:56,431 WARN: org.apache.tajo.master.TajoAsyncDispatcher
(stop(115)) - Interrupted Exception while stopping
2014-07-11 05:54:56,432 INFO: org.apache.tajo.master.TajoAsyncDispatcher
(stop(122)) - AsyncDispatcher stopped:QueryInProgress:q_1405057565813_0643
2014-07-11 05:54:56,440 INFO: org.apache.tajo.master.session.SessionManager
(removeSession(80)) - Session b81de15a-662e-4130-aab6-2e3932a8e87a is removed.
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 9.347 sec
2014-07-11 05:54:56,452 INFO: org.apache.tajo.worker.TajoWorker (run(492)) -
============================================
2014-07-11 05:54:56,452 INFO: org.apache.tajo.worker.TajoWorker (run(493)) -
TajoWorker received SIGINT Signal
2014-07-11 05:54:56,452 INFO: org.apache.tajo.worker.TajoWorker (run(494)) -
============================================
2014-07-11 05:54:56,456 INFO: org.apache.tajo.master.session.SessionManager
(removeSession(80)) - Session ef947033-c1b7-481c-9fdc-bb614ddbac5a is removed.
2014-07-11 05:54:56,457 ERROR: org.apache.tajo.rpc.RpcProtos
(exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-07-11 05:54:56,457 ERROR: org.apache.tajo.rpc.RpcProtos
(exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-07-11 05:54:56,457 INFO: org.apache.tajo.master.session.SessionManager
(removeSession(80)) - Session 40a7374d-2695-4212-91cc-996b2cc99e66 is removed.
2014-07-11 05:54:56,457 ERROR: org.apache.tajo.rpc.RpcProtos
(exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-07-11 05:54:56,456 ERROR: org.apache.tajo.rpc.RpcProtos
(exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-07-11 05:54:56,456 ERROR: org.apache.tajo.rpc.RpcProtos
(exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-07-11 05:54:56,456 ERROR: org.apache.tajo.rpc.RpcProtos
(exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-07-11 05:54:56,460 ERROR: org.apache.tajo.client.TajoClient (close(141)) -
java.io.IOException: Connect error to localhost/127.0.0.1:31977 cause
java.util.concurrent.RejectedExecutionException: Worker has already been
shutdown
2014-07-11 05:54:56,459 ERROR: org.apache.tajo.client.TajoClient (close(141)) -
java.io.IOException: Connect error to localhost/127.0.0.1:31977 cause
java.util.concurrent.RejectedExecutionException: Worker has already been
shutdown
2014-07-11 05:54:56,459 ERROR: org.apache.tajo.client.TajoClient (close(141)) -
java.io.IOException: Connect error to localhost/127.0.0.1:31977 cause
java.util.concurrent.RejectedExecutionException: Worker has already been
shutdown
2014-07-11 05:54:56,459 ERROR: org.apache.tajo.rpc.RpcProtos
(exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-07-11 05:54:56,458 ERROR: org.apache.tajo.client.TajoClient (close(141)) -
java.io.IOException: Connect error to localhost/127.0.0.1:31977 cause
java.util.concurrent.RejectedExecutionException: Worker has already been
shutdown
2014-07-11 05:54:56,458 ERROR: org.apache.tajo.client.TajoClient (close(141)) -
java.io.IOException: Connect error to localhost/127.0.0.1:31977 cause
java.util.concurrent.RejectedExecutionException: Worker has already been
shutdown
2014-07-11 05:54:56,461 ERROR: org.apache.tajo.client.TajoClient (close(141)) -
java.io.IOException: Connect error to localhost/127.0.0.1:31977 cause
java.util.concurrent.RejectedExecutionException: Worker has already been
shutdown
2014-07-11 05:54:56,460 ERROR: org.apache.tajo.client.TajoClient (close(141)) -
java.io.IOException: Connect error to localhost/127.0.0.1:31977 cause
java.util.concurrent.RejectedExecutionException: Worker has already been
shutdown
2014-07-11 05:54:56,468 INFO: org.apache.tajo.worker.WorkerHeartbeatService
(run(260)) - Worker Resource Heartbeat Thread stopped.
2014-07-11 05:54:56,524 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(128)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:31981)
shutdown
2014-07-11 05:54:56,526 INFO: org.apache.tajo.worker.TajoWorkerManagerService
(stop(95)) - TajoWorkerManagerService stopped
2014-07-11 05:54:56,529 INFO: org.apache.tajo.worker.TaskRunner (stop(239)) -
Stop TaskRunner: eb_1405057565813_0639_000001
2014-07-11 05:54:56,530 INFO: org.apache.tajo.worker.TaskRunner (stop(239)) -
Stop TaskRunner: eb_1405057565813_0637_000001
2014-07-11 05:54:56,530 INFO: org.apache.tajo.worker.TaskRunner (stop(239)) -
Stop TaskRunner: eb_1405057565813_0643_000001
2014-07-11 05:54:56,537 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(128)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:31980)
shutdown
2014-07-11 05:54:56,537 INFO:
org.apache.tajo.master.querymaster.QueryMasterManagerService (stop(111)) -
QueryMasterManagerService stopped
2014-07-11 05:54:56,538 INFO: org.apache.tajo.master.querymaster.QueryMaster
(run(433)) - QueryMaster heartbeat thread stopped
2014-07-11 05:54:56,539 INFO: org.apache.tajo.master.TajoAsyncDispatcher
(stop(122)) - AsyncDispatcher stopped:querymaster_1405057566493
2014-07-11 05:54:56,539 INFO: org.apache.tajo.master.querymaster.QueryMaster
(stop(159)) - QueryMaster stop
2014-07-11 05:54:56,539 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(110)) - TajoWorkerClientService stopping
2014-07-11 05:54:56,544 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(128)) - Rpc (QueryMasterClientProtocol) listened on
0:0:0:0:0:0:0:0:31979) shutdown
2014-07-11 05:54:56,544 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(114)) - TajoWorkerClientService stopped
2014-07-11 05:54:56,544 INFO: org.apache.tajo.worker.TajoWorker (stop(334)) -
TajoWorker main thread exiting
Results :
Failed tests:
testGroupByWithConstantKeys2(org.apache.tajo.engine.query.TestGroupByQuery):
Result Verification expected:<...-------------------(..)
Tests run: 1055, Failures: 1, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 5.541 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 0.703 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 2.610 s]
[INFO] Tajo Common ....................................... SUCCESS [ 37.559 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 1.291 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 5.228 s]
[INFO] Tajo Rpc .......................................... SUCCESS [ 21.452 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.030 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 5.516 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 42.264 s]
[INFO] Tajo Core PullServer .............................. SUCCESS [ 0.746 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.940 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 0.450 s]
[INFO] Tajo Core ......................................... FAILURE [09:09 min]
[INFO] Tajo Catalog Drivers .............................. SKIPPED
[INFO] Tajo Catalog ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 11:17 min
[INFO] Finished at: 2014-07-11T05:54:56+00:00
[INFO] Final Memory: 94M/1934M
[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-925
