See <https://builds.apache.org/job/Tajo-master-build/967/>
------------------------------------------
[...truncated 751952 lines...]
2015-11-06 08:15:00,114 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(280)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestNonForwardQueryResultSystemScanner
2015-11-06 08:15:00,114 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(282)) - =============================================
2015-11-06 08:15:00,114 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(283)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestNonForwardQueryResultSystemScanner
2015-11-06 08:15:00,114 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(284)) - =============================================
2015-11-06 08:15:00,114 INFO: org.apache.tajo.master.exec.DDLExecutor
(createDatabase(246)) - database "TestNonForwardQueryResultSystemScanner" is
already exists.
2015-11-06 08:15:00,115 INFO: org.apache.tajo.master.GlobalEngine
(executeQuery(176)) - Query: SELECT TYPE FROM INFORMATION_SCHEMA.CLUSTER
2015-11-06 08:15:00,118 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(280)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
PROJECTION(1)
=> Targets: information_schema.cluster.type (TEXT)
=> out schema: {(1) information_schema.cluster.type (TEXT)}
=> in schema: {(11) information_schema.cluster.host (TEXT),
information_schema.cluster.port (INT4), information_schema.cluster.type (TEXT),
information_schema.cluster.status (TEXT), information_schema.cluster.total_cpu
(INT4), information_schema.cluster.used_mem (INT8),
information_schema.cluster.total_mem (INT8),
information_schema.cluster.used_disk (INT4),
information_schema.cluster.total_disk (INT4),
information_schema.cluster.running_tasks (INT4),
information_schema.cluster.last_heartbeat_ts (TIMESTAMP)}
SCAN(0) on information_schema.cluster
=> target list: information_schema.cluster.host (TEXT),
information_schema.cluster.port (INT4), information_schema.cluster.type (TEXT),
information_schema.cluster.status (TEXT), information_schema.cluster.total_cpu
(INT4), information_schema.cluster.used_mem (INT8),
information_schema.cluster.total_mem (INT8),
information_schema.cluster.used_disk (INT4),
information_schema.cluster.total_disk (INT4),
information_schema.cluster.running_tasks (INT4),
information_schema.cluster.last_heartbeat_ts (TIMESTAMP)
=> out schema: {(11) information_schema.cluster.host (TEXT),
information_schema.cluster.port (INT4), information_schema.cluster.type (TEXT),
information_schema.cluster.status (TEXT), information_schema.cluster.total_cpu
(INT4), information_schema.cluster.used_mem (INT8),
information_schema.cluster.total_mem (INT8),
information_schema.cluster.used_disk (INT4),
information_schema.cluster.total_disk (INT4),
information_schema.cluster.running_tasks (INT4),
information_schema.cluster.last_heartbeat_ts (TIMESTAMP)}
=> in schema: {(11) information_schema.cluster.host (TEXT),
information_schema.cluster.port (INT4), information_schema.cluster.type (TEXT),
information_schema.cluster.status (TEXT), information_schema.cluster.total_cpu
(INT4), information_schema.cluster.used_mem (INT8),
information_schema.cluster.total_mem (INT8),
information_schema.cluster.used_disk (INT4),
information_schema.cluster.total_disk (INT4),
information_schema.cluster.running_tasks (INT4),
information_schema.cluster.last_heartbeat_ts (TIMESTAMP)}
2015-11-06 08:15:00,118 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(282)) - =============================================
2015-11-06 08:15:00,118 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(283)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
[LogicalPlan]
> ProjectionNode is eliminated.
-----------------------------
SCAN(0) on information_schema.cluster
=> target list: information_schema.cluster.type (TEXT)
=> out schema: {(1) information_schema.cluster.type (TEXT)}
=> in schema: {(11) information_schema.cluster.host (TEXT),
information_schema.cluster.port (INT4), information_schema.cluster.type (TEXT),
information_schema.cluster.status (TEXT), information_schema.cluster.total_cpu
(INT4), information_schema.cluster.used_mem (INT8),
information_schema.cluster.total_mem (INT8),
information_schema.cluster.used_disk (INT4),
information_schema.cluster.total_disk (INT4),
information_schema.cluster.running_tasks (INT4),
information_schema.cluster.last_heartbeat_ts (TIMESTAMP)}
2015-11-06 08:15:00,118 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(284)) - =============================================
2015-11-06 08:15:00,118 INFO: org.apache.tajo.master.QueryInProgress
(stopProgress(124)) - =========================================================
2015-11-06 08:15:00,119 INFO: org.apache.tajo.master.QueryInProgress
(stopProgress(125)) - Stop query:q_1446796257840_2245
2015-11-06 08:15:00,119 INFO:
org.apache.tajo.engine.planner.global.GlobalPlanner (build(128)) -
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
[LogicalPlan]
> ProjectionNode is eliminated.
-----------------------------
SCAN(0) on information_schema.cluster
=> target list: information_schema.cluster.type (TEXT)
=> out schema: {(1) information_schema.cluster.type (TEXT)}
=> in schema: {(11) information_schema.cluster.host (TEXT),
information_schema.cluster.port (INT4), information_schema.cluster.type (TEXT),
information_schema.cluster.status (TEXT), information_schema.cluster.total_cpu
(INT4), information_schema.cluster.used_mem (INT8),
information_schema.cluster.total_mem (INT8),
information_schema.cluster.used_disk (INT4),
information_schema.cluster.total_disk (INT4),
information_schema.cluster.running_tasks (INT4),
information_schema.cluster.last_heartbeat_ts (TIMESTAMP)}
2015-11-06 08:15:00,119 INFO:
org.apache.tajo.engine.planner.global.GlobalPlanner (build(157)) -
Non-optimized master plan
-------------------------------------------------------------------------------
Execution Block Graph (TERMINAL - eb_1446796257840_2245_000002)
-------------------------------------------------------------------------------
|-eb_1446796257840_2245_000002
|-eb_1446796257840_2245_000001
-------------------------------------------------------------------------------
Order of Execution
-------------------------------------------------------------------------------
1: eb_1446796257840_2245_000001
2: eb_1446796257840_2245_000002
-------------------------------------------------------------------------------
=======================================================
Block Id: eb_1446796257840_2245_000001 [ROOT]
=======================================================
SCAN(0) on information_schema.cluster
=> target list: information_schema.cluster.type (TEXT)
=> out schema: {(1) information_schema.cluster.type (TEXT)}
=> in schema: {(11) information_schema.cluster.host (TEXT),
information_schema.cluster.port (INT4), information_schema.cluster.type (TEXT),
information_schema.cluster.status (TEXT), information_schema.cluster.total_cpu
(INT4), information_schema.cluster.used_mem (INT8),
information_schema.cluster.total_mem (INT8),
information_schema.cluster.used_disk (INT4),
information_schema.cluster.total_disk (INT4),
information_schema.cluster.running_tasks (INT4),
information_schema.cluster.last_heartbeat_ts (TIMESTAMP)}
=======================================================
Block Id: eb_1446796257840_2245_000002 [TERMINAL]
=======================================================
2015-11-06 08:15:00,119 INFO:
org.apache.tajo.engine.planner.global.GlobalPlanner (build(160)) -
Optimized master plan
-------------------------------------------------------------------------------
Execution Block Graph (TERMINAL - eb_1446796257840_2245_000002)
-------------------------------------------------------------------------------
|-eb_1446796257840_2245_000002
|-eb_1446796257840_2245_000001
-------------------------------------------------------------------------------
Order of Execution
-------------------------------------------------------------------------------
1: eb_1446796257840_2245_000001
2: eb_1446796257840_2245_000002
-------------------------------------------------------------------------------
=======================================================
Block Id: eb_1446796257840_2245_000001 [ROOT]
=======================================================
SCAN(0) on information_schema.cluster
=> target list: information_schema.cluster.type (TEXT)
=> out schema: {(1) information_schema.cluster.type (TEXT)}
=> in schema: {(11) information_schema.cluster.host (TEXT),
information_schema.cluster.port (INT4), information_schema.cluster.type (TEXT),
information_schema.cluster.status (TEXT), information_schema.cluster.total_cpu
(INT4), information_schema.cluster.used_mem (INT8),
information_schema.cluster.total_mem (INT8),
information_schema.cluster.used_disk (INT4),
information_schema.cluster.total_disk (INT4),
information_schema.cluster.running_tasks (INT4),
information_schema.cluster.last_heartbeat_ts (TIMESTAMP)}
=======================================================
Block Id: eb_1446796257840_2245_000002 [TERMINAL]
=======================================================
2015-11-06 08:15:00,124 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session 825538ef-07bc-44dc-a232-3d519f1a3c09 is removed.
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.073 sec - in
org.apache.tajo.master.TestNonForwardQueryResultSystemScanner
Running org.apache.tajo.TestQueryIdFactory
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 sec - in
org.apache.tajo.TestQueryIdFactory
2015-11-06 08:15:00,136 INFO: org.mortbay.log (invoke0(?)) - Shutdown hook
executing
2015-11-06 08:15:00,137 INFO: org.mortbay.log (invoke(?)) - Shutdown hook
complete
2015-11-06 08:15:00,141 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session fc26a4e8-8d3c-4778-aaa9-9bae98088ad9 is removed.
2015-11-06 08:15:00,144 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session 81477c97-64e9-4d9b-9b4d-211cef80ede6 is removed.
2015-11-06 08:15:00,145 INFO: org.apache.tajo.worker.TajoWorker (run(564)) -
============================================
2015-11-06 08:15:00,145 INFO: org.apache.tajo.worker.TajoWorker (run(565)) -
TajoWorker received SIGINT Signal
2015-11-06 08:15:00,145 INFO: org.apache.tajo.worker.TajoWorker (run(566)) -
============================================
2015-11-06 08:15:00,146 INFO: org.apache.tajo.util.history.HistoryWriter
(run(275)) - HistoryWriter_asf902.gq1.ygridcore.net_24823 stopped.
2015-11-06 08:15:00,148 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2015-11-06 08:15:00,200 INFO: org.apache.tajo.worker.NodeStatusUpdater
(serviceStop(115)) - NodeStatusUpdater stopped.
2015-11-06 08:15:00,200 INFO: org.apache.tajo.worker.NodeStatusUpdater
(run(265)) - Heartbeat Thread stopped.
2015-11-06 08:15:00,200 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(171)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:24825)
shutdown
2015-11-06 08:15:00,202 INFO:
org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) -
QueryMasterManagerService stopped
2015-11-06 08:15:00,202 INFO: org.apache.tajo.querymaster.QueryMaster
(run(427)) - QueryMaster heartbeat thread stopped
2015-11-06 08:15:00,202 INFO: org.apache.tajo.querymaster.QueryMaster
(serviceStop(162)) - QueryMaster stopped
2015-11-06 08:15:00,202 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(98)) - TajoWorkerClientService stopping
2015-11-06 08:15:00,203 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(171)) - Rpc (QueryMasterClientProtocol) listened on
0:0:0:0:0:0:0:0:24824) shutdown
2015-11-06 08:15:00,203 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(102)) - TajoWorkerClientService stopped
2015-11-06 08:15:00,203 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(171)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:24823)
shutdown
2015-11-06 08:15:00,203 INFO: org.apache.tajo.worker.TajoWorkerManagerService
(serviceStop(93)) - TajoWorkerManagerService stopped
2015-11-06 08:15:00,203 INFO: org.apache.tajo.worker.TajoWorker
(serviceStop(374)) - TajoWorker main thread exiting
2015-11-06 08:15:01,367 INFO: BlockStateChange (invalidateWorkForOneNode(3488))
- BLOCK* BlockManager: ask 127.0.0.1:33365 to delete [blk_1073748816_7992]
2015-11-06 08:15:04,514 INFO: org.apache.tajo.master.TajoMaster (run(577)) -
============================================
2015-11-06 08:15:04,514 INFO: org.apache.tajo.master.TajoMaster (run(578)) -
TajoMaster received SIGINT Signal
2015-11-06 08:15:04,514 INFO: org.apache.tajo.master.TajoMaster (run(579)) -
============================================
2015-11-06 08:15:04,515 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(171)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:24822) shutdown
2015-11-06 08:15:04,515 INFO: org.apache.tajo.ws.rs.TajoRestService
(serviceStop(129)) - Tajo Rest Service stopped.
2015-11-06 08:15:04,520 INFO: org.apache.tajo.util.history.HistoryWriter
(run(275)) - HistoryWriter_127.0.0.1_24821 stopped.
2015-11-06 08:15:04,526 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:33365 is added to
blk_1073741835_1011{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-e8190cdb-0107-4923-a2a4-88653f181dcc:NORMAL:127.0.0.1:33365|RBW]]}
size 1479
2015-11-06 08:15:04,526 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(171)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:24821)
shutdown
2015-11-06 08:15:04,526 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2015-11-06 08:15:04,527 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(171)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:24820)
shutdown
2015-11-06 08:15:04,528 INFO: org.apache.tajo.catalog.CatalogServer
(serviceStop(185)) - Catalog Server (127.0.0.1:24819) shutdown
2015-11-06 08:15:04,528 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(171)) - Rpc (CatalogProtocol) listened on 127.0.0.1:24819) shutdown
2015-11-06 08:15:04,529 INFO: org.apache.tajo.catalog.store.DerbyStore
(close(2979)) - Close database
(jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/03c39e7e-41dc-4d02-ac59-91561a518449/db;create=true)>
2015-11-06 08:15:04,530 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(171)) - Rpc (TajoResourceTrackerProtocol) listened on
127.0.0.1:24818) shutdown
2015-11-06 08:15:04,531 INFO: org.apache.tajo.master.TajoMaster
(serviceStop(437)) - Tajo Master main thread exiting
2015-11-06 08:15:04,560 INFO: org.apache.tajo.catalog.store.DerbyStore
(shutdown(68)) - Derby shutdown complete normally.
2015-11-06 08:15:04,560 INFO: org.apache.tajo.catalog.store.DerbyStore
(shutdown(75)) - Shutdown database
Results :
Tests in error:
TestJoinOnPartitionedTables.testPartialFilterPushDown:95->QueryTestCaseBase.runSimpleTests:622
ยป UndefinedTable
Tests run: 1713, Failures: 0, Errors: 1, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 2.485 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 2.830 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.513 s]
[INFO] Tajo Common ....................................... SUCCESS [ 35.880 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 3.157 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 5.830 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 7.836 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 4.033 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 51.074 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.491 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 11.791 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 3.242 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [01:07 min]
[INFO] Tajo PullServer ................................... SUCCESS [ 1.004 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.589 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 2.298 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 4.421 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 2.065 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 4.028 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.782 s]
[INFO] Tajo Core ......................................... SUCCESS [ 8.767 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 0.951 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 11.123 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.076 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 1.003 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.075 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 5.130 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 3.153 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 35.049 s]
[INFO] Tajo JDBC storage common .......................... SUCCESS [ 0.862 s]
[INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 1.157 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 0.980 s]
[INFO] Tajo Distribution ................................. SUCCESS [ 5.277 s]
[INFO] Tajo Core Tests ................................... FAILURE [24:18 min]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 29:13 min
[INFO] Finished at: 2015-11-06T08:15:05+00:00
[INFO] Final Memory: 154M/2631M
[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
