See <https://builds.apache.org/job/Tajo-master-build/770/changes>
Changes:
[jihoonson] TAJO-1597: Problem of ignoring theta join condition. (jihoon)
------------------------------------------
[...truncated 656321 lines...]
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestNonForwardQueryResultSystemScanner
2015-07-24 03:57:47,193 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(280)) - =============================================
2015-07-24 03:57:47,193 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(281)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestNonForwardQueryResultSystemScanner
2015-07-24 03:57:47,193 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(282)) - =============================================
2015-07-24 03:57:47,193 INFO: org.apache.tajo.master.exec.DDLExecutor
(createDatabase(158)) - database "TestNonForwardQueryResultSystemScanner" is
already exists.
2015-07-24 03:57:47,194 INFO: org.apache.tajo.master.GlobalEngine
(executeQuery(179)) - Query: SELECT TYPE FROM INFORMATION_SCHEMA.CLUSTER
2015-07-24 03:57:47,196 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(278)) - 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-07-24 03:57:47,196 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(280)) - =============================================
2015-07-24 03:57:47,197 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(281)) - 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-07-24 03:57:47,197 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(282)) - =============================================
2015-07-24 03:57:47,197 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-07-24 03:57:47,197 INFO:
org.apache.tajo.engine.planner.global.GlobalPlanner (build(157)) -
Non-optimized master plan
-------------------------------------------------------------------------------
Execution Block Graph (TERMINAL - eb_1437709222616_1140_000002)
-------------------------------------------------------------------------------
|-eb_1437709222616_1140_000002
|-eb_1437709222616_1140_000001
-------------------------------------------------------------------------------
Order of Execution
-------------------------------------------------------------------------------
1: eb_1437709222616_1140_000001
2: eb_1437709222616_1140_000002
-------------------------------------------------------------------------------
=======================================================
Block Id: eb_1437709222616_1140_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_1437709222616_1140_000002 [TERMINAL]
=======================================================
2015-07-24 03:57:47,197 INFO:
org.apache.tajo.engine.planner.global.GlobalPlanner (build(160)) -
Optimized master plan
-------------------------------------------------------------------------------
Execution Block Graph (TERMINAL - eb_1437709222616_1140_000002)
-------------------------------------------------------------------------------
|-eb_1437709222616_1140_000002
|-eb_1437709222616_1140_000001
-------------------------------------------------------------------------------
Order of Execution
-------------------------------------------------------------------------------
1: eb_1437709222616_1140_000001
2: eb_1437709222616_1140_000002
-------------------------------------------------------------------------------
=======================================================
Block Id: eb_1437709222616_1140_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_1437709222616_1140_000002 [TERMINAL]
=======================================================
2015-07-24 03:57:47,199 INFO: org.apache.tajo.master.TajoMasterClientService
(getQueryResultData(576)) - Send result to client for
d22beaa4-8849-4519-acd8-ca349ab6eef8,q_1437709222616_1140, 2 rows
2015-07-24 03:57:47,199 INFO: org.apache.tajo.master.TajoMasterClientService
(getQueryResultData(576)) - Send result to client for
d22beaa4-8849-4519-acd8-ca349ab6eef8,q_1437709222616_1140, 0 rows
2015-07-24 03:57:47,201 INFO: org.apache.tajo.master.exec.DDLExecutor
(dropDatabase(191)) - database TestNonForwardQueryResultSystemScanner is
dropped.
2015-07-24 03:57:47,201 INFO: org.apache.tajo.session.SessionManager
(removeSession(86)) - Session d22beaa4-8849-4519-acd8-ca349ab6eef8 is removed.
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.066 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-07-24 03:57:47,210 INFO: org.apache.tajo.session.SessionManager
(removeSession(86)) - Session 47386a1f-7925-4835-8ed4-1d7dcf31121e is removed.
2015-07-24 03:57:47,210 INFO: org.apache.tajo.session.SessionManager
(removeSession(86)) - Session 18c542e4-df64-44c2-8cdd-d9ecc7103c78 is removed.
2015-07-24 03:57:47,211 INFO: org.apache.tajo.worker.TajoWorker (run(565)) -
============================================
2015-07-24 03:57:47,214 INFO: org.apache.tajo.worker.TajoWorker (run(566)) -
TajoWorker received SIGINT Signal
2015-07-24 03:57:47,214 INFO: org.apache.tajo.worker.TajoWorker (run(567)) -
============================================
2015-07-24 03:57:47,217 WARN: org.apache.hadoop.hdfs.DFSClient
(completeFile(2275)) - Caught exception
java.lang.InterruptedException: sleep interrupted
at java.lang.Thread.sleep(Native Method)
at
org.apache.hadoop.hdfs.DFSOutputStream.completeFile(DFSOutputStream.java:2269)
at
org.apache.hadoop.hdfs.DFSOutputStream.close(DFSOutputStream.java:2234)
at
org.apache.hadoop.fs.FSDataOutputStream$PositionCache.close(FSDataOutputStream.java:72)
at
org.apache.hadoop.fs.FSDataOutputStream.close(FSDataOutputStream.java:106)
at org.apache.hadoop.io.IOUtils.cleanup(IOUtils.java:237)
at
org.apache.tajo.util.history.HistoryWriter$WriterThread.writeQueryHistory(HistoryWriter.java:374)
at
org.apache.tajo.util.history.HistoryWriter$WriterThread.writeHistory(HistoryWriter.java:311)
at
org.apache.tajo.util.history.HistoryWriter$WriterThread.run(HistoryWriter.java:237)
2015-07-24 03:57:47,219 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2015-07-24 03:57:47,219 INFO: org.apache.tajo.master.TajoMaster (run(567)) -
============================================
2015-07-24 03:57:47,219 INFO: org.apache.tajo.util.history.HistoryWriter
(run(268)) - HistoryWriter_asf902.gq1.ygridcore.net_36910 stopped.
2015-07-24 03:57:47,219 INFO: org.apache.tajo.master.TajoMaster (run(568)) -
TajoMaster received SIGINT Signal
2015-07-24 03:57:47,219 INFO: org.apache.tajo.master.TajoMaster (run(569)) -
============================================
2015-07-24 03:57:47,220 INFO: org.apache.tajo.worker.NodeStatusUpdater
(serviceStop(110)) - NodeStatusUpdater stopped.
2015-07-24 03:57:47,220 INFO: org.apache.tajo.worker.NodeStatusUpdater
(run(261)) - Heartbeat Thread stopped.
2015-07-24 03:57:47,220 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(173)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:36909) shutdown
2015-07-24 03:57:47,221 INFO: org.apache.tajo.ws.rs.TajoRestService
(serviceStop(129)) - Tajo Rest Service stopped.
2015-07-24 03:57:47,221 INFO: org.apache.tajo.catalog.CatalogServer
(serviceStop(191)) - Catalog Server (127.0.0.1:36906) shutdown
2015-07-24 03:57:47,222 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:36906) shutdown
2015-07-24 03:57:47,225 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(173)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:36912)
shutdown
2015-07-24 03:57:47,227 INFO: org.apache.tajo.util.history.HistoryWriter
(run(268)) - HistoryWriter_127.0.0.1_36908 stopped.
2015-07-24 03:57:47,227 INFO:
org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) -
QueryMasterManagerService stopped
2015-07-24 03:57:47,228 INFO: org.apache.tajo.querymaster.QueryMaster
(run(409)) - QueryMaster heartbeat thread stopped
2015-07-24 03:57:47,233 INFO: org.apache.tajo.querymaster.QueryMaster
(serviceStop(168)) - QueryMaster stopped
2015-07-24 03:57:47,233 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(99)) - TajoWorkerClientService stopping
2015-07-24 03:57:47,234 INFO: BlockStateChange (logAddStoredBlock(2473)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56695 is added to
blk_1073741835_1011{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1,
replicas=[ReplicaUnderConstruction[[DISK]DS-9c48666d-1438-454b-a661-ca0188af0dc2:NORMAL:127.0.0.1:56695|RBW]]}
size 1478
2015-07-24 03:57:47,234 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(173)) - Rpc (QueryMasterClientProtocol) listened on
0:0:0:0:0:0:0:0:36911) shutdown
2015-07-24 03:57:47,234 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(103)) - TajoWorkerClientService stopped
2015-07-24 03:57:47,235 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(173)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:36910)
shutdown
2015-07-24 03:57:47,235 INFO: org.apache.tajo.worker.TajoWorkerManagerService
(serviceStop(93)) - TajoWorkerManagerService stopped
2015-07-24 03:57:47,235 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2015-07-24 03:57:47,236 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(173)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:36908)
shutdown
2015-07-24 03:57:47,237 INFO: org.apache.tajo.worker.TajoWorker
(serviceStop(375)) - TajoWorker main thread exiting
2015-07-24 03:57:47,237 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(173)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:36907)
shutdown
2015-07-24 03:57:47,240 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(173)) - Rpc (TajoResourceTrackerProtocol) listened on
127.0.0.1:36905) shutdown
2015-07-24 03:57:47,240 INFO: org.apache.tajo.master.TajoMaster
(serviceStop(401)) - Tajo Master main thread exiting
2015-07-24 03:57:47,242 INFO: org.apache.tajo.master.scheduler.SimpleScheduler
(run(384)) - Running Queries: 4
Results :
Tests in error:
TestInnerJoinQuery.testComplexJoinCondition4:273->QueryTestCaseBase.runSimpleTests:527
ยป SQL
Tests run: 1560, Failures: 0, Errors: 1, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 1.558 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 1.258 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 2.774 s]
[INFO] Tajo Common ....................................... SUCCESS [ 29.374 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 1.557 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 4.828 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 5.155 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 0.395 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 46.773 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.373 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 10.649 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 10.117 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [ 48.612 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 4.360 s]
[INFO] Tajo PullServer ................................... SUCCESS [ 1.085 s]
[INFO] Tajo Client ....................................... SUCCESS [ 1.960 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 1.126 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 3.339 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 0.889 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 3.719 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.128 s]
[INFO] Tajo Core ......................................... FAILURE [18:00 min]
[INFO] Tajo RPC .......................................... SKIPPED
[INFO] Tajo Catalog Drivers Hive ......................... SKIPPED
[INFO] Tajo Catalog Drivers .............................. SKIPPED
[INFO] Tajo Catalog ...................................... SKIPPED
[INFO] Tajo Storage ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 21:03 min
[INFO] Finished at: 2015-07-24T03:57:47+00:00
[INFO] Final Memory: 64M/466M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal
org.apache.maven.plugins:maven-surefire-plugin:2.17: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-1597
