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 2015/07/24 05:57:22 UTC
Build failed in Jenkins: Tajo-master-build #770
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
Jenkins build is back to normal : Tajo-master-build #772
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/772/changes>
Build failed in Jenkins: Tajo-master-build #771
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/771/changes>
Changes:
[jhkim] TAJO-1702: Fix race condition in finished query cache. (jinho)
------------------------------------------
[...truncated 657543 lines...]
56 > DELETE http://127.0.0.1:36650/rest/databases/TestDropDatabase
56 > Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2
56 > Connection: keep-alive
56 > Content-Length: 0
56 > Host: 127.0.0.1:36650
56 > User-Agent: Jersey/2.6 (HttpUrlConnection 1.7.0_25)
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 56 * Server responded with a response on thread Tajo-REST-5 Server Worker #1
56 < 200
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 3 * Client response received on thread main
3 < 200
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 1 * Sending client request on thread main
1 > POST http://127.0.0.1:36650/rest/databases
1 > Content-Type: application/json
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 57 * Server has received a request on thread Tajo-REST-5 Server Worker #0
57 > POST http://127.0.0.1:36650/rest/databases
57 > Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2
57 > Connection: keep-alive
57 > Content-Length: 2
57 > Content-Type: application/json
57 > Host: 127.0.0.1:36650
57 > User-Agent: Jersey/2.6 (HttpUrlConnection 1.7.0_25)
2015-07-24 06:19:50,722 ERROR: org.apache.tajo.ws.rs.resources.DatabasesResource (createBadRequestResponse(40)) - databaseName is null or empty.
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 57 * Server responded with a response on thread Tajo-REST-5 Server Worker #0
57 < 400
57 < Content-Type: application/json
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 1 * Client response received on thread main
1 < 400
1 < Content-Type: application/json
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 1 * Sending client request on thread main
1 > DELETE http://127.0.0.1:36650/rest/databases/TestDropDatabaseNotFound
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 58 * Server has received a request on thread Tajo-REST-5 Server Worker #1
58 > DELETE http://127.0.0.1:36650/rest/databases/TestDropDatabaseNotFound
58 > Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2
58 > Connection: keep-alive
58 > Content-Length: 0
58 > Host: 127.0.0.1:36650
58 > User-Agent: Jersey/2.6 (HttpUrlConnection 1.7.0_25)
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 58 * Server responded with a response on thread Tajo-REST-5 Server Worker #1
58 < 404
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 1 * Client response received on thread main
1 < 404
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 1 * Sending client request on thread main
1 > GET http://127.0.0.1:36650/rest/databases/testGetDatabaseNotFound
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 59 * Server has received a request on thread Tajo-REST-5 Server Worker #0
59 > GET http://127.0.0.1:36650/rest/databases/testGetDatabaseNotFound
59 > Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2
59 > Connection: keep-alive
59 > Content-Length: 0
59 > Host: 127.0.0.1:36650
59 > User-Agent: Jersey/2.6 (HttpUrlConnection 1.7.0_25)
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 59 * Server responded with a response on thread Tajo-REST-5 Server Worker #0
59 < 404
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 1 * Client response received on thread main
1 < 404
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 1 * Sending client request on thread main
1 > POST http://127.0.0.1:36650/rest/databases
1 > Content-Type: application/json
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 60 * Server has received a request on thread Tajo-REST-5 Server Worker #1
60 > POST http://127.0.0.1:36650/rest/databases
60 > Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2
60 > Connection: keep-alive
60 > Content-Length: 40
60 > Content-Type: application/json
60 > Host: 127.0.0.1:36650
60 > User-Agent: Jersey/2.6 (HttpUrlConnection 1.7.0_25)
2015-07-24 06:19:50,807 INFO: org.apache.tajo.catalog.CatalogServer (createDatabase(393)) - database "TestDatabasesResource" is created
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 60 * Server responded with a response on thread Tajo-REST-5 Server Worker #1
60 < 201
60 < Location: http://127.0.0.1:36650/rest/databases/TestDatabasesResource
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 1 * Client response received on thread main
1 < 201
1 < Location: http://127.0.0.1:36650/rest/databases/TestDatabasesResource
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 2 * Sending client request on thread main
2 > GET http://127.0.0.1:36650/rest/databases
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 61 * Server has received a request on thread Tajo-REST-5 Server Worker #0
61 > GET http://127.0.0.1:36650/rest/databases
61 > Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2
61 > Connection: keep-alive
61 > Content-Length: 0
61 > Host: 127.0.0.1:36650
61 > User-Agent: Jersey/2.6 (HttpUrlConnection 1.7.0_25)
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 61 * Server responded with a response on thread Tajo-REST-5 Server Worker #0
61 < 200
61 < Content-Type: application/json
Jul 24, 2015 6:19:50 AM org.glassfish.jersey.filter.LoggingFilter log
INFO: 2 * Client response received on thread main
2 < 200
2 < Content-Type: application/json
2015-07-24 06:19:50,814 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session ad537983-4922-4aa8-955f-e225cb9f98f1 is removed.
Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.257 sec - in org.apache.tajo.ws.rs.resources.TestDatabasesResource
2015-07-24 06:19:50,869 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session c8037512-35ca-4b68-ae99-538fd88767f3 is removed.
Jul 24, 2015 6:08:28 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Jul 24, 2015 6:08:29 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Jul 24, 2015 6:09:01 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Jul 24, 2015 6:09:01 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Jul 24, 2015 6:09:01 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Jul 24, 2015 6:09:01 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Jul 24, 2015 6:09:01 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Jul 24, 2015 6:09:04 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
2015-07-24 06:19:50,925 INFO: org.apache.tajo.master.TajoMaster (run(567)) - ============================================
2015-07-24 06:19:50,934 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session fef3ed70-4200-4490-8747-12fa8ae3e803 is removed.
2015-07-24 06:19:50,934 INFO: org.apache.tajo.master.TajoMaster (run(568)) - TajoMaster received SIGINT Signal
2015-07-24 06:19:50,935 INFO: org.apache.tajo.master.TajoMaster (run(569)) - ============================================
2015-07-24 06:19:50,936 INFO: org.apache.tajo.master.TajoMaster (run(567)) - ============================================
2015-07-24 06:19:50,937 INFO: org.apache.tajo.master.TajoMaster (run(568)) - TajoMaster received SIGINT Signal
2015-07-24 06:19:50,937 INFO: org.apache.tajo.master.TajoMaster (run(569)) - ============================================
2015-07-24 06:19:50,938 INFO: org.apache.tajo.worker.TajoWorker (run(565)) - ============================================
2015-07-24 06:19:50,939 INFO: org.apache.tajo.worker.TajoWorker (run(566)) - TajoWorker received SIGINT Signal
2015-07-24 06:19:50,939 INFO: org.apache.tajo.worker.TajoWorker (run(567)) - ============================================
2015-07-24 06:19:50,943 INFO: org.apache.tajo.util.history.HistoryWriter (run(268)) - HistoryWriter_asf904.gq1.ygridcore.net_36651 stopped.
2015-07-24 06:19:50,943 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped
2015-07-24 06:19:50,943 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:59469) shutdown
2015-07-24 06:19:50,951 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:36650) shutdown
2015-07-24 06:19:50,964 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped.
2015-07-24 06:19:50,958 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped.
2015-07-24 06:19:50,964 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(191)) - Catalog Server (127.0.0.1:36647) shutdown
2015-07-24 06:19:50,965 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(191)) - Catalog Server (127.0.0.1:53356) shutdown
2015-07-24 06:19:50,969 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:36647) shutdown
2015-07-24 06:19:50,980 INFO: org.apache.tajo.util.history.HistoryWriter (run(268)) - HistoryWriter_127.0.0.1_36649 stopped.
2015-07-24 06:19:50,980 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:53356) shutdown
2015-07-24 06:19:50,984 INFO: org.apache.tajo.util.history.HistoryWriter (run(268)) - HistoryWriter_127.0.0.1_56805 stopped.
2015-07-24 06:19:50,984 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43370 is added to blk_1073741836_1012{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-bffe3a8c-468b-4cc1-9864-7be1817f0c22:NORMAL:127.0.0.1:43370|RBW]]} size 542
2015-07-24 06:19:50,984 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped
2015-07-24 06:19:50,999 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:56805) shutdown
2015-07-24 06:19:51,011 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:46770) shutdown
2015-07-24 06:19:51,025 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped
2015-07-24 06:19:51,026 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:36649) shutdown
2015-07-24 06:19:51,026 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:36648) shutdown
2015-07-24 06:19:51,029 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:36646) shutdown
2015-07-24 06:19:51,029 INFO: org.apache.tajo.master.TajoMaster (serviceStop(401)) - Tajo Master main thread exiting
2015-07-24 06:19:51,030 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:41274) shutdown
2015-07-24 06:19:51,031 INFO: org.apache.tajo.master.TajoMaster (serviceStop(401)) - Tajo Master main thread exiting
2015-07-24 06:19:51,076 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(110)) - NodeStatusUpdater stopped.
2015-07-24 06:19:51,076 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(261)) - Heartbeat Thread stopped.
2015-07-24 06:19:51,076 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:36653) shutdown
2015-07-24 06:19:51,077 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) - QueryMasterManagerService stopped
2015-07-24 06:19:51,078 INFO: org.apache.tajo.querymaster.QueryMaster (run(417)) - QueryMaster heartbeat thread stopped
2015-07-24 06:19:51,078 INFO: org.apache.tajo.querymaster.QueryMaster (serviceStop(168)) - QueryMaster stopped
2015-07-24 06:19:51,079 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(99)) - TajoWorkerClientService stopping
2015-07-24 06:19:51,080 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:36652) shutdown
2015-07-24 06:19:51,080 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(103)) - TajoWorkerClientService stopped
2015-07-24 06:19:51,082 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:36651) shutdown
2015-07-24 06:19:51,082 INFO: org.apache.tajo.worker.TajoWorkerManagerService (serviceStop(93)) - TajoWorkerManagerService stopped
2015-07-24 06:19:51,083 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(375)) - TajoWorker main thread exiting
Results :
Failed tests:
TestHAServiceHDFSImpl.testAutoFailOver:71 expected:<1> but was:<0>
Tests run: 1560, Failures: 1, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 2.853 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 2.619 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 5.239 s]
[INFO] Tajo Common ....................................... SUCCESS [ 48.371 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 3.012 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 13.152 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 11.884 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 1.130 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 51.451 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.859 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 13.703 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 14.386 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [01:09 min]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 6.473 s]
[INFO] Tajo PullServer ................................... SUCCESS [ 1.870 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.784 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 2.020 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 4.249 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 1.210 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 7.118 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.648 s]
[INFO] Tajo Core ......................................... FAILURE [27:27 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: 31:55 min
[INFO] Finished at: 2015-07-24T06:19:51+00:00
[INFO] Final Memory: 64M/437M
[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-1702