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 2016/01/22 11:52:59 UTC
Build failed in Jenkins: Tajo-0.11.1-nightly #115
See <https://builds.apache.org/job/Tajo-0.11.1-nightly/115/changes>
Changes:
[blrunner] TAJO-2061: Add description for EXPLAIN statement.
------------------------------------------
[...truncated 706093 lines...]
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestNonForwardQueryResultSystemScanner
2016-01-22 10:52:48,033 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(283)) - =============================================
2016-01-22 10:52:48,034 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(284)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestNonForwardQueryResultSystemScanner
2016-01-22 10:52:48,034 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(285)) - =============================================
2016-01-22 10:52:48,034 INFO: org.apache.tajo.master.exec.DDLExecutor (createDatabase(245)) - database "TestNonForwardQueryResultSystemScanner" is already exists.
2016-01-22 10:52:48,036 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(177)) - Query: SELECT TYPE FROM INFORMATION_SCHEMA.CLUSTER
2016-01-22 10:52:48,038 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(281)) - 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)}
2016-01-22 10:52:48,039 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(283)) - =============================================
2016-01-22 10:52:48,039 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(284)) - 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)}
2016-01-22 10:52:48,039 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(285)) - =============================================
2016-01-22 10:52:48,039 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(122)) - =========================================================
2016-01-22 10:52:48,039 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(123)) - Stop query:q_1453458733563_2175
2016-01-22 10:52:48,040 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)}
2016-01-22 10:52:48,040 INFO: org.apache.tajo.engine.planner.global.GlobalPlanner (build(157)) -
Non-optimized master plan
-------------------------------------------------------------------------------
Execution Block Graph (TERMINAL - eb_1453458733563_2175_000002)
-------------------------------------------------------------------------------
|-eb_1453458733563_2175_000002
|-eb_1453458733563_2175_000001
-------------------------------------------------------------------------------
Order of Execution
-------------------------------------------------------------------------------
1: eb_1453458733563_2175_000001
2: eb_1453458733563_2175_000002
-------------------------------------------------------------------------------
=======================================================
Block Id: eb_1453458733563_2175_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_1453458733563_2175_000002 [TERMINAL]
=======================================================
2016-01-22 10:52:48,040 INFO: org.apache.tajo.engine.planner.global.GlobalPlanner (build(160)) -
Optimized master plan
-------------------------------------------------------------------------------
Execution Block Graph (TERMINAL - eb_1453458733563_2175_000002)
-------------------------------------------------------------------------------
|-eb_1453458733563_2175_000002
|-eb_1453458733563_2175_000001
-------------------------------------------------------------------------------
Order of Execution
-------------------------------------------------------------------------------
1: eb_1453458733563_2175_000001
2: eb_1453458733563_2175_000002
-------------------------------------------------------------------------------
=======================================================
Block Id: eb_1453458733563_2175_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_1453458733563_2175_000002 [TERMINAL]
=======================================================
2016-01-22 10:52:48,048 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session e516deb2-a076-4643-ae24-2d645cb6304d is removed.
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.093 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
2016-01-22 10:52:48,058 INFO: org.mortbay.log (info(67)) - Shutdown hook executing
2016-01-22 10:52:48,058 INFO: org.mortbay.log (info(67)) - Shutdown hook complete
2016-01-22 10:52:48,067 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 94eaba10-0932-433e-9252-332d9b75ad04 is removed.
2016-01-22 10:52:48,070 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 2cc7454f-1847-47b3-bd50-30ec60209284 is removed.
2016-01-22 10:52:48,072 INFO: org.apache.tajo.worker.TajoWorker (run(569)) - ============================================
2016-01-22 10:52:48,072 INFO: org.apache.tajo.worker.TajoWorker (run(570)) - TajoWorker received SIGINT Signal
2016-01-22 10:52:48,072 INFO: org.apache.tajo.worker.TajoWorker (run(571)) - ============================================
2016-01-22 10:52:48,075 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_asf902.gq1.ygridcore.net_25121 stopped.
2016-01-22 10:52:48,077 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped
2016-01-22 10:52:48,137 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(113)) - NodeStatusUpdater stopped.
2016-01-22 10:52:48,137 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(261)) - Heartbeat Thread stopped.
2016-01-22 10:52:48,138 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:25123) shutdown
2016-01-22 10:52:48,139 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) - QueryMasterManagerService stopped
2016-01-22 10:52:48,139 INFO: org.apache.tajo.querymaster.QueryMaster (run(432)) - QueryMaster heartbeat thread stopped
2016-01-22 10:52:48,140 INFO: org.apache.tajo.querymaster.QueryMaster (serviceStop(164)) - QueryMaster stopped
2016-01-22 10:52:48,141 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(98)) - TajoWorkerClientService stopping
2016-01-22 10:52:48,142 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:25122) shutdown
2016-01-22 10:52:48,142 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(102)) - TajoWorkerClientService stopped
2016-01-22 10:52:48,142 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:25121) shutdown
2016-01-22 10:52:48,142 INFO: org.apache.tajo.worker.TajoWorkerManagerService (serviceStop(93)) - TajoWorkerManagerService stopped
2016-01-22 10:52:48,142 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(379)) - TajoWorker main thread exiting
2016-01-22 10:52:50,346 INFO: org.apache.tajo.master.TajoMaster (run(580)) - ============================================
2016-01-22 10:52:50,346 INFO: org.apache.tajo.master.TajoMaster (run(581)) - TajoMaster received SIGINT Signal
2016-01-22 10:52:50,346 INFO: org.apache.tajo.master.TajoMaster (run(582)) - ============================================
2016-01-22 10:52:50,346 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (Tajo-REST) listened on 127.0.0.1:25120) shutdown
2016-01-22 10:52:50,347 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped.
2016-01-22 10:52:50,351 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_127.0.0.1_25119 stopped.
2016-01-22 10:52:50,354 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:50853 is added to blk_1073741838_1014{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-9f8cdb68-ad21-412a-9276-b32440ddfc8f:NORMAL:127.0.0.1:50853|RBW]]} size 1257
2016-01-22 10:52:50,355 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:25119) shutdown
2016-01-22 10:52:50,355 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped
2016-01-22 10:52:52,458 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:25118) shutdown
2016-01-22 10:52:52,459 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(188)) - Catalog Server (127.0.0.1:25117) shutdown
2016-01-22 10:52:52,459 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (CatalogProtocol) listened on 127.0.0.1:25117) shutdown
2016-01-22 10:52:52,461 INFO: org.apache.tajo.catalog.store.DerbyStore (close(2901)) - Close database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-0.11.1-nightly/ws/tajo-core-tests/target/test-data/de539d55-646e-4eb0-8702-fa68152b404a/db;create=true)>
2016-01-22 10:52:52,462 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:25116) shutdown
2016-01-22 10:52:52,462 INFO: org.apache.tajo.master.TajoMaster (serviceStop(440)) - Tajo Master main thread exiting
2016-01-22 10:52:52,489 INFO: org.apache.tajo.catalog.store.DerbyStore (shutdown(68)) - Derby shutdown complete normally.
2016-01-22 10:52:52,489 INFO: org.apache.tajo.catalog.store.DerbyStore (shutdown(75)) - Shutdown database
Results :
Tests in error:
TestJoinOnPartitionedTables.testPartialFilterPushDown:95->QueryTestCaseBase.runSimpleTests:622 ยป UndefinedTable
Tests run: 1717, Failures: 0, Errors: 1, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 1.812 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 1.131 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 2.705 s]
[INFO] Tajo Common ....................................... SUCCESS [ 35.137 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 2.789 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 5.383 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 6.448 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 1.277 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [02:24 min]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.335 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 12.972 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 3.242 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [01:11 min]
[INFO] Tajo PullServer ................................... SUCCESS [ 1.166 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.390 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 1.708 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 5.327 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 1.667 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 4.127 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.694 s]
[INFO] Tajo Core ......................................... SUCCESS [ 5.522 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 0.927 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 11.948 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.059 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 0.988 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.025 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 4.381 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 2.996 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 37.590 s]
[INFO] Tajo JDBC storage common .......................... SUCCESS [ 0.902 s]
[INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 0.941 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 1.019 s]
[INFO] Tajo Distribution ................................. SUCCESS [ 5.351 s]
[INFO] Tajo Core Tests ................................... FAILURE [20:49 min]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 27:13 min
[INFO] Finished at: 2016-01-22T10:52:52+00:00
[INFO] Final Memory: 138M/906M
[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-0.11.1-nightly/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
Recording test results
Jenkins build is back to normal : Tajo-0.11.1-nightly #116
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-0.11.1-nightly/116/changes>