See <https://builds.apache.org/job/Tajo-0.11.0-build/77/changes>
Changes:
[jihoonson] TAJO-1894: Filter condition is ignored when a query involves
multiple subqueries and aggregations.
[jihoonson] TAJO-1889: UndefinedColumnException when a query with table
subquery is executed on self-describing tables.
[hyunsik] TAJO-1663: Change the variable name storeType to dataFormat.
------------------------------------------
[...truncated 739137 lines...]
2015-09-28 23:00:27,775 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(283)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestNonForwardQueryResultSystemScanner
2015-09-28 23:00:27,775 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(284)) - =============================================
2015-09-28 23:00:27,776 INFO: org.apache.tajo.master.exec.DDLExecutor
(createDatabase(245)) - database "TestNonForwardQueryResultSystemScanner" is
already exists.
2015-09-28 23:00:27,777 INFO: org.apache.tajo.master.GlobalEngine
(executeQuery(176)) - Query: SELECT TYPE FROM INFORMATION_SCHEMA.CLUSTER
2015-09-28 23:00:27,779 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-09-28 23:00:27,779 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(282)) - =============================================
2015-09-28 23:00:27,780 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-09-28 23:00:27,780 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(284)) - =============================================
2015-09-28 23:00:27,780 INFO: org.apache.tajo.master.QueryInProgress
(stopProgress(124)) - =========================================================
2015-09-28 23:00:27,780 INFO: org.apache.tajo.master.QueryInProgress
(stopProgress(125)) - Stop query:q_1443479771399_2210
2015-09-28 23:00:27,780 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-09-28 23:00:27,780 INFO:
org.apache.tajo.engine.planner.global.GlobalPlanner (build(157)) -
Non-optimized master plan
-------------------------------------------------------------------------------
Execution Block Graph (TERMINAL - eb_1443479771399_2210_000002)
-------------------------------------------------------------------------------
|-eb_1443479771399_2210_000002
|-eb_1443479771399_2210_000001
-------------------------------------------------------------------------------
Order of Execution
-------------------------------------------------------------------------------
1: eb_1443479771399_2210_000001
2: eb_1443479771399_2210_000002
-------------------------------------------------------------------------------
=======================================================
Block Id: eb_1443479771399_2210_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_1443479771399_2210_000002 [TERMINAL]
=======================================================
2015-09-28 23:00:27,781 INFO:
org.apache.tajo.engine.planner.global.GlobalPlanner (build(160)) -
Optimized master plan
-------------------------------------------------------------------------------
Execution Block Graph (TERMINAL - eb_1443479771399_2210_000002)
-------------------------------------------------------------------------------
|-eb_1443479771399_2210_000002
|-eb_1443479771399_2210_000001
-------------------------------------------------------------------------------
Order of Execution
-------------------------------------------------------------------------------
1: eb_1443479771399_2210_000001
2: eb_1443479771399_2210_000002
-------------------------------------------------------------------------------
=======================================================
Block Id: eb_1443479771399_2210_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_1443479771399_2210_000002 [TERMINAL]
=======================================================
2015-09-28 23:00:27,789 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session dbd3bece-d697-4f33-991d-ed298f8e9c30 is removed.
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.089 sec - in
org.apache.tajo.master.TestNonForwardQueryResultSystemScanner
Running org.apache.tajo.TestQueryIdFactory
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in
org.apache.tajo.TestQueryIdFactory
2015-09-28 23:00:27,799 INFO: org.mortbay.log (invoke0(?)) - Shutdown hook
executing
2015-09-28 23:00:27,800 INFO: org.mortbay.log (invoke(?)) - Shutdown hook
complete
2015-09-28 23:00:27,803 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session 63757e29-eafc-4870-b408-81afa5c30f55 is removed.
2015-09-28 23:00:27,806 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session 36962fb1-6a99-4e7f-9b3c-e6518579ff84 is removed.
2015-09-28 23:00:27,807 INFO: org.apache.tajo.worker.TajoWorker (run(565)) -
============================================
2015-09-28 23:00:27,807 INFO: org.apache.tajo.worker.TajoWorker (run(566)) -
TajoWorker received SIGINT Signal
2015-09-28 23:00:27,807 INFO: org.apache.tajo.worker.TajoWorker (run(567)) -
============================================
2015-09-28 23:00:27,809 INFO: org.apache.tajo.util.history.HistoryWriter
(run(275)) - HistoryWriter_asf902.gq1.ygridcore.net_38255 stopped.
2015-09-28 23:00:27,809 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2015-09-28 23:00:27,870 INFO: org.apache.tajo.worker.NodeStatusUpdater
(serviceStop(115)) - NodeStatusUpdater stopped.
2015-09-28 23:00:27,872 INFO: org.apache.tajo.worker.NodeStatusUpdater
(run(265)) - Heartbeat Thread stopped.
2015-09-28 23:00:27,873 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(173)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:38257)
shutdown
2015-09-28 23:00:27,874 INFO:
org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) -
QueryMasterManagerService stopped
2015-09-28 23:00:27,874 INFO: org.apache.tajo.querymaster.QueryMaster
(run(427)) - QueryMaster heartbeat thread stopped
2015-09-28 23:00:27,875 INFO: org.apache.tajo.querymaster.QueryMaster
(serviceStop(162)) - QueryMaster stopped
2015-09-28 23:00:27,875 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(98)) - TajoWorkerClientService stopping
2015-09-28 23:00:27,875 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(173)) - Rpc (QueryMasterClientProtocol) listened on
0:0:0:0:0:0:0:0:38256) shutdown
2015-09-28 23:00:27,876 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(102)) - TajoWorkerClientService stopped
2015-09-28 23:00:27,876 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(173)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:38255)
shutdown
2015-09-28 23:00:27,876 INFO: org.apache.tajo.worker.TajoWorkerManagerService
(serviceStop(93)) - TajoWorkerManagerService stopped
2015-09-28 23:00:27,876 INFO: org.apache.tajo.worker.TajoWorker
(serviceStop(375)) - TajoWorker main thread exiting
2015-09-28 23:00:29,292 INFO: BlockStateChange (invalidateWorkForOneNode(3488))
- BLOCK* BlockManager: ask 127.0.0.1:37622 to delete [blk_1073748685_7861]
2015-09-28 23:00:32,288 INFO: org.apache.tajo.master.TajoMaster (run(580)) -
============================================
2015-09-28 23:00:32,289 INFO: org.apache.tajo.master.TajoMaster (run(581)) -
TajoMaster received SIGINT Signal
2015-09-28 23:00:32,289 INFO: org.apache.tajo.master.TajoMaster (run(582)) -
============================================
2015-09-28 23:00:32,289 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(173)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:38254) shutdown
2015-09-28 23:00:32,289 INFO: org.apache.tajo.ws.rs.TajoRestService
(serviceStop(129)) - Tajo Rest Service stopped.
2015-09-28 23:00:32,291 INFO: org.apache.tajo.util.history.HistoryWriter
(run(275)) - HistoryWriter_127.0.0.1_38253 stopped.
2015-09-28 23:00:32,295 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37622 is added to
blk_1073741838_1014{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-776fd9c6-696b-4dc8-b9a5-c0d686f28c11:NORMAL:127.0.0.1:37622|RBW]]}
size 1479
2015-09-28 23:00:32,297 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2015-09-28 23:00:32,297 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(173)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:38253)
shutdown
2015-09-28 23:00:32,298 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(173)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:38252)
shutdown
2015-09-28 23:00:32,298 INFO: org.apache.tajo.catalog.CatalogServer
(serviceStop(189)) - Catalog Server (127.0.0.1:38251) shutdown
2015-09-28 23:00:32,299 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:38251) shutdown
2015-09-28 23:00:32,299 INFO: org.apache.tajo.catalog.store.DerbyStore
(close(3157)) - Close database
(jdbc:derby:memory:<https://builds.apache.org/job/Tajo-0.11.0-build/ws/tajo-core-tests/target/test-data/6029a908-076c-489d-aa05-d6ccaee284e2/db;create=true)>
2015-09-28 23:00:32,301 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(173)) - Rpc (TajoResourceTrackerProtocol) listened on
127.0.0.1:38250) shutdown
2015-09-28 23:00:32,301 INFO: org.apache.tajo.master.TajoMaster
(serviceStop(440)) - Tajo Master main thread exiting
2015-09-28 23:00:32,559 INFO: org.apache.tajo.catalog.store.DerbyStore
(shutdown(68)) - Derby shutdown complete normally.
2015-09-28 23:00:32,560 INFO: org.apache.tajo.catalog.store.DerbyStore
(shutdown(75)) - Shutdown database
Results :
Failed tests:
TestQueryOnSelfDescTable.testTableSubquery3:130->QueryTestCaseBase.runSimpleTests:617
Result Verification for: 1 th test expected:<...-------------------
[example glossary,1,null,SGML,ISO 8879:1986
]> but was:<...-------------------
[]>
Tests run: 1689, Failures: 1, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 2.202 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 1.660 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.499 s]
[INFO] Tajo Common ....................................... SUCCESS [ 40.755 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 2.767 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 5.030 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 6.716 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 1.520 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 52.069 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.459 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 13.596 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 3.203 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [ 55.028 s]
[INFO] Tajo PullServer ................................... SUCCESS [ 1.260 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.504 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 2.387 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 5.591 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 2.168 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 5.023 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.431 s]
[INFO] Tajo Core ......................................... SUCCESS [ 6.648 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 0.967 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 15.906 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.100 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 1.001 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.116 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 4.772 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 3.006 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 38.444 s]
[INFO] Tajo JDBC storage common .......................... SUCCESS [ 0.918 s]
[INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 1.394 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 1.049 s]
[INFO] Tajo Distribution ................................. SUCCESS [ 5.432 s]
[INFO] Tajo Core Tests ................................... FAILURE [24:32 min]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 29:24 min
[INFO] Finished at: 2015-09-28T23:00:32+00:00
[INFO] Final Memory: 81M/417M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal
org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on
project tajo-core-tests: There are test failures.
[ERROR]
[ERROR] Please refer to
<https://builds.apache.org/job/Tajo-0.11.0-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
Archiving artifacts
Sending artifact delta relative to Tajo-0.11.0-build #74
Archived 1 artifacts
Archive block size is 32768
Received 0 blocks and 59769261 bytes
Compression is 0.0%
Took 44 sec
Updating TAJO-1663
Updating TAJO-1894
Updating TAJO-1889
