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/09/30 02:13:21 UTC
Build failed in Jenkins: Tajo-0.11.0-build #84
See <https://builds.apache.org/job/Tajo-0.11.0-build/84/changes>
Changes:
[hyunsik] Revert "[maven-release-plugin] prepare for next development iteration"
[hyunsik] Revert "[maven-release-plugin] prepare release release-0.11.0-rc0"
[hyunsik] [maven-release-plugin] prepare release release-0.11.0-rc0
[hyunsik] [maven-release-plugin] prepare for next development iteration
------------------------------------------
[...truncated 739563 lines...]
2015-09-30 00:13:18,861 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:51195/tmp/tajo-jenkins/staging/history/20150930/query-detail/q_1443570571394_2210/eb_1443570571394_2210_000006.hist
2015-09-30 00:13:18,864 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:51195/tmp/tajo-jenkins/staging/history/20150930/query-detail/q_1443570571394_2210/eb_1443570571394_2210_000007.hist
2015-09-30 00:13:19,343 INFO: org.apache.tajo.master.rm.TajoResourceManager (serviceInit(97)) - Loaded resource scheduler : class org.apache.tajo.master.scheduler.SimpleScheduler
2015-09-30 00:13:19,345 INFO: org.apache.tajo.rpc.NettyServerBase (start(130)) - Rpc (TajoResourceTrackerProtocol) listens on /127.0.0.1:49057
2015-09-30 00:13:19,345 INFO: org.apache.tajo.master.rm.TajoResourceTracker (serviceInit(99)) - TajoResourceTracker starts up (localhost/127.0.0.1:49057)
2015-09-30 00:13:19,345 INFO: org.apache.tajo.catalog.CatalogServer (serviceInit(132)) - Catalog Store Class: org.apache.tajo.catalog.store.DerbyStore
2015-09-30 00:13:19,346 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(156)) - Loaded the Catalog driver (org.apache.derby.jdbc.EmbeddedDriver)
2015-09-30 00:13:19,346 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(162)) - Trying to connect database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-0.11.0-build/ws/tajo-core-tests/target/test-data/612baeac-44d1-4946-ae2e-b8d6b9add6e6/db;create=true)>
2015-09-30 00:13:19,347 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(164)) - Connected to database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-0.11.0-build/ws/tajo-core-tests/target/test-data/612baeac-44d1-4946-ae2e-b8d6b9add6e6/db;create=true)>
2015-09-30 00:13:19,357 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(176)) - The meta table of CatalogServer already is created.
2015-09-30 00:13:19,358 INFO: org.apache.tajo.catalog.store.DerbyStore (verifySchemaVersion(288)) - The compatibility of the catalog schema (version: 12) has been verified.
Sep 30, 2015 12:13:19 AM org.glassfish.jersey.server.ApplicationHandler initialize
INFO: Initiating Jersey application, version Jersey: 2.6 2014-02-18 21:52:53...
2015-09-30 00:13:19,435 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceInit(109)) - Tajo Rest Service initialized.
2015-09-30 00:13:19,435 INFO: org.apache.tajo.master.TajoMaster (serviceInit(218)) - Tajo Master is initialized.
2015-09-30 00:13:19,435 INFO: org.apache.tajo.master.TajoMaster (serviceStart(320)) - TajoMaster is starting up
2015-09-30 00:13:19,436 INFO: org.apache.tajo.master.TajoMaster (checkBaseTBSpaceAndDatabase(423)) - Default database (default) is already prepared.
2015-09-30 00:13:19,440 INFO: org.apache.tajo.rpc.NettyServerBase (start(130)) - Rpc (CatalogProtocol) listens on /127.0.0.1:58033
2015-09-30 00:13:19,440 INFO: org.apache.tajo.catalog.CatalogServer (serviceStart(183)) - Catalog Server startup (127.0.0.1:58033)
2015-09-30 00:13:19,627 INFO: BlockStateChange (invalidateWorkForOneNode(3488)) - BLOCK* BlockManager: ask 127.0.0.1:37555 to delete [blk_1073741825_1001]
2015-09-30 00:13:19,718 INFO: org.apache.tajo.rpc.NettyServerBase (start(130)) - Rpc (TajoMasterClientProtocol) listens on /127.0.0.1:46512
2015-09-30 00:13:19,718 INFO: org.apache.tajo.master.TajoMasterClientService (serviceStart(108)) - Instantiated TajoMasterClientService at localhost/127.0.0.1:46512
2015-09-30 00:13:19,719 INFO: org.apache.tajo.rpc.NettyServerBase (start(130)) - Rpc (QueryCoordinatorProtocol) listens on /127.0.0.1:54540
2015-09-30 00:13:19,719 INFO: org.apache.tajo.master.QueryCoordinatorService (serviceStart(70)) - Instantiated TajoMasterService at localhost/127.0.0.1:54540
2015-09-30 00:13:19,720 INFO: org.apache.tajo.rpc.NettyServerBase (start(130)) - Rpc (Tajo-REST) listens on /0:0:0:0:0:0:0:0:44499
2015-09-30 00:13:19,720 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStart(118)) - Tajo Rest Service started.
2015-09-30 00:13:19,723 INFO: BlockStateChange (addToInvalidates(1106)) - BLOCK* addToInvalidates: blk_1073748690_7866 127.0.0.1:37555
2015-09-30 00:13:19,724 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property MASTER.reporters in tajo-metrics.properties
2015-09-30 00:13:19,725 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property MASTER-JVM.reporters in tajo-metrics.properties
2015-09-30 00:13:19,729 WARN: org.apache.hadoop.hdfs.StateChange (startFileInternal(2602)) - DIR* NameSystem.startFile: /tajo/system/ha/active/active.lock /tajo/system/ha/active/active.lock for client 127.0.0.1 already exists
2015-09-30 00:13:19,737 INFO: org.apache.tajo.ha.HdfsServiceTracker (createLockFile(227)) - Lock file already exists at (hdfs://localhost:51195/tajo/system/ha/active/active.lock)
2015-09-30 00:13:19,745 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37555 is added to blk_1073748691_7867{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-d0b4cea2-6250-452b-b129-704a0926bfef:NORMAL:127.0.0.1:37555|RBW]]} size 81
2015-09-30 00:13:20,147 INFO: org.apache.tajo.ha.HdfsServiceTracker (register(187)) - This is added to backup master (127.0.0.1:54540)
2015-09-30 00:13:20,150 INFO: org.apache.tajo.util.history.HistoryWriter (run(231)) - HistoryWriter_127.0.0.1_54540 started.
2015-09-30 00:13:20,150 INFO: org.apache.tajo.util.history.HistoryCleaner (run(62)) - History cleaner started: expiry day=7
2015-09-30 00:13:20,172 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session b3e46bea-347d-4647-96f3-a77ac8db72e5 is created.
2015-09-30 00:13:20,173 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(176)) - Query: CREATE TABLE default.ha_test1 (age int);
2015-09-30 00:13:20,176 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(280)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2015-09-30 00:13:20,176 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(282)) - =============================================
2015-09-30 00:13:20,176 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(283)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2015-09-30 00:13:20,177 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(284)) - =============================================
2015-09-30 00:13:20,180 INFO: org.apache.tajo.catalog.CatalogServer (createTable(695)) - relation "default.ha_test1" is added to the catalog (127.0.0.1:47960)
2015-09-30 00:13:20,181 INFO: org.apache.tajo.master.exec.DDLExecutor (create(114)) - relation 'default.ha_test1' created.
2015-09-30 00:13:20,181 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(176)) - Query: CREATE TABLE default.ha_test2 (age int);
2015-09-30 00:13:20,182 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(280)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2015-09-30 00:13:20,182 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(282)) - =============================================
2015-09-30 00:13:20,182 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(283)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2015-09-30 00:13:20,183 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(284)) - =============================================
2015-09-30 00:13:20,186 INFO: org.apache.tajo.catalog.CatalogServer (createTable(695)) - relation "default.ha_test2" is added to the catalog (127.0.0.1:47960)
2015-09-30 00:13:20,186 INFO: org.apache.tajo.master.exec.DDLExecutor (create(114)) - relation 'default.ha_test2' created.
2015-09-30 00:13:20,186 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session b3e46bea-347d-4647-96f3-a77ac8db72e5 is removed.
2015-09-30 00:13:20,190 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session daa2b9e4-6efb-4b17-84ff-85861d1d3282 is created.
2015-09-30 00:13:20,191 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session daa2b9e4-6efb-4b17-84ff-85861d1d3282 is removed.
2015-09-30 00:13:20,193 INFO: BlockStateChange (addToInvalidates(1106)) - BLOCK* addToInvalidates: blk_1073748689_7865 127.0.0.1:37555
2015-09-30 00:13:20,196 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:42679) shutdown
2015-09-30 00:13:20,196 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped.
2015-09-30 00:13:20,198 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped
2015-09-30 00:13:20,198 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:41711) shutdown
2015-09-30 00:13:20,198 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:59314) shutdown
2015-09-30 00:13:20,199 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_127.0.0.1_41711 stopped.
2015-09-30 00:13:20,199 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(189)) - Catalog Server (127.0.0.1:47960) shutdown
2015-09-30 00:13:20,200 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:47960) shutdown
2015-09-30 00:13:20,200 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/612baeac-44d1-4946-ae2e-b8d6b9add6e6/db;create=true)>
2015-09-30 00:13:20,201 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:34494) shutdown
2015-09-30 00:13:20,201 INFO: org.apache.tajo.master.TajoMaster (serviceStop(440)) - Tajo Master main thread exiting
2015-09-30 00:13:21,158 INFO: BlockStateChange (addToInvalidates(1106)) - BLOCK* addToInvalidates: blk_1073748691_7867 127.0.0.1:37555
2015-09-30 00:13:21,165 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37555 is added to blk_1073748692_7868{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-d076b446-a4b0-4a1b-9e85-2992d69ef04f:NORMAL:127.0.0.1:37555|RBW]]} size 0
2015-09-30 00:13:21,192 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37555 is added to blk_1073748693_7869{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-d0b4cea2-6250-452b-b129-704a0926bfef:NORMAL:127.0.0.1:37555|FINALIZED]]} size 0
2015-09-30 00:13:21,194 INFO: org.apache.tajo.ha.HdfsServiceTracker (register(156)) - This is added to active master (127.0.0.1:54540)
2015-09-30 00:13:21,211 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session 2c34eb9e-c95b-4ab3-a4a4-2d0839c7f96b is created.
2015-09-30 00:13:21,214 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 2c34eb9e-c95b-4ab3-a4a4-2d0839c7f96b is removed.
2015-09-30 00:13:21,219 INFO: BlockStateChange (addToInvalidates(1106)) - BLOCK* addToInvalidates: blk_1073748692_7868 127.0.0.1:37555
2015-09-30 00:13:21,221 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:44499) shutdown
2015-09-30 00:13:21,221 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped.
2015-09-30 00:13:21,222 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_127.0.0.1_54540 stopped.
2015-09-30 00:13:21,222 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped
2015-09-30 00:13:21,222 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:54540) shutdown
2015-09-30 00:13:21,224 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:46512) shutdown
2015-09-30 00:13:21,224 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(189)) - Catalog Server (127.0.0.1:58033) shutdown
2015-09-30 00:13:21,224 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:58033) shutdown
2015-09-30 00:13:21,225 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/612baeac-44d1-4946-ae2e-b8d6b9add6e6/db;create=true)>
2015-09-30 00:13:21,225 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:49057) shutdown
2015-09-30 00:13:21,226 INFO: org.apache.tajo.master.TajoMaster (serviceStop(440)) - Tajo Master main thread exiting
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3.379 sec - in org.apache.tajo.ha.TestHAServiceHDFSImpl
Running org.apache.tajo.parser.sql.TestSQLAnalyzer
2015-09-30 00:13:22,628 INFO: BlockStateChange (invalidateWorkForOneNode(3488)) - BLOCK* BlockManager: ask 127.0.0.1:37555 to delete [blk_1073748689_7865, blk_1073748690_7866, blk_1073748691_7867, blk_1073748692_7868]
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.524 sec - in org.apache.tajo.parser.sql.TestSQLAnalyzer
Sep 29, 2015 11:56:21 PM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Sep 29, 2015 11:56:21 PM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Sep 29, 2015 11:56:36 PM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Sep 29, 2015 11:56:36 PM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Sep 29, 2015 11:56:36 PM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Sep 29, 2015 11:56:36 PM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Sep 29, 2015 11:56:36 PM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Sep 29, 2015 11:56:38 PM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
2015-09-30 00:13:22,759 INFO: org.mortbay.log (invoke0(?)) - Shutdown hook executing
2015-09-30 00:13:22,759 INFO: org.mortbay.log (invoke(?)) - Shutdown hook complete
2015-09-30 00:13:22,760 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 8fc539bd-48b1-4488-ba80-6355f2817712 is removed.
2015-09-30 00:13:22,762 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 86f2e0b5-b7b4-4217-ac85-5ee28e39f29b is removed.
2015-09-30 00:13:22,764 INFO: org.apache.tajo.worker.TajoWorker (run(565)) - ============================================
2015-09-30 00:13:22,765 INFO: org.apache.tajo.worker.TajoWorker (run(566)) - TajoWorker received SIGINT Signal
2015-09-30 00:13:22,765 INFO: org.apache.tajo.worker.TajoWorker (run(567)) - ============================================
2015-09-30 00:13:22,767 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_asf909.gq1.ygridcore.net_42156 stopped.
2015-09-30 00:13:22,767 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped
2015-09-30 00:13:22,819 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(115)) - NodeStatusUpdater stopped.
2015-09-30 00:13:22,819 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(265)) - Heartbeat Thread stopped.
2015-09-30 00:13:22,821 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:42158) shutdown
2015-09-30 00:13:22,821 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) - QueryMasterManagerService stopped
2015-09-30 00:13:22,821 INFO: org.apache.tajo.querymaster.QueryMaster (run(427)) - QueryMaster heartbeat thread stopped
2015-09-30 00:13:22,822 INFO: org.apache.tajo.querymaster.QueryMaster (serviceStop(162)) - QueryMaster stopped
2015-09-30 00:13:22,822 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(98)) - TajoWorkerClientService stopping
2015-09-30 00:13:22,823 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:42157) shutdown
2015-09-30 00:13:22,823 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(102)) - TajoWorkerClientService stopped
2015-09-30 00:13:22,823 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:42156) shutdown
2015-09-30 00:13:22,823 INFO: org.apache.tajo.worker.TajoWorkerManagerService (serviceStop(93)) - TajoWorkerManagerService stopped
2015-09-30 00:13:22,824 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(375)) - TajoWorker main thread exiting
2015-09-30 00:13:27,232 INFO: org.apache.tajo.master.TajoMaster (run(580)) - ============================================
2015-09-30 00:13:27,234 INFO: org.apache.tajo.master.TajoMaster (run(581)) - TajoMaster received SIGINT Signal
2015-09-30 00:13:27,234 INFO: org.apache.tajo.master.TajoMaster (run(582)) - ============================================
2015-09-30 00:13:27,234 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:42155) shutdown
2015-09-30 00:13:27,234 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped.
2015-09-30 00:13:27,236 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_127.0.0.1_42154 stopped.
2015-09-30 00:13:27,240 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37555 is added to blk_1073741857_1033{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-d0b4cea2-6250-452b-b129-704a0926bfef:NORMAL:127.0.0.1:37555|RBW]]} size 731
2015-09-30 00:13:27,241 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped
2015-09-30 00:13:27,241 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:42154) shutdown
2015-09-30 00:13:27,241 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:42153) shutdown
2015-09-30 00:13:27,242 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(189)) - Catalog Server (127.0.0.1:42152) shutdown
2015-09-30 00:13:27,242 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:42152) shutdown
2015-09-30 00:13:27,243 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/612baeac-44d1-4946-ae2e-b8d6b9add6e6/db;create=true)>
2015-09-30 00:13:27,243 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:42151) shutdown
2015-09-30 00:13:27,245 INFO: org.apache.tajo.master.TajoMaster (serviceStop(440)) - Tajo Master main thread exiting
2015-09-30 00:13:27,474 INFO: org.apache.tajo.catalog.store.DerbyStore (shutdown(68)) - Derby shutdown complete normally.
2015-09-30 00:13:27,474 INFO: org.apache.tajo.catalog.store.DerbyStore (shutdown(75)) - Shutdown database
Results :
Failed tests:
TestTaskStatusUpdate.case1:66->assertStatus:145 null
Tests in error:
TestGroupByQuery.testNumShufflePartition:742 NullPointer
Tests run: 1687, Failures: 1, Errors: 1, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 1.729 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 1.491 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 2.737 s]
[INFO] Tajo Common ....................................... SUCCESS [ 40.778 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 2.970 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 5.515 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 8.208 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 1.464 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 52.953 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.431 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [01:09 min]
[INFO] Tajo Storage Common ............................... SUCCESS [ 3.471 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [01:10 min]
[INFO] Tajo PullServer ................................... SUCCESS [ 1.161 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.817 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 2.179 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 5.298 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 2.478 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 4.221 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.482 s]
[INFO] Tajo Core ......................................... SUCCESS [ 9.419 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 0.998 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 31.758 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.095 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 1.019 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.094 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 3.950 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 2.915 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 38.977 s]
[INFO] Tajo JDBC storage common .......................... SUCCESS [ 1.009 s]
[INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 1.566 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 1.043 s]
[INFO] Tajo Distribution ................................. SUCCESS [ 7.580 s]
[INFO] Tajo Core Tests ................................... FAILURE [24:40 min]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 31:05 min
[INFO] Finished at: 2015-09-30T00:13:27+00:00
[INFO] Final Memory: 82M/429M
[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 #82
Archived 1 artifacts
Archive block size is 32768
Received 0 blocks and 59768186 bytes
Compression is 0.0%
Took 20 sec
Jenkins build is back to normal : Tajo-0.11.0-build #86
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-0.11.0-build/86/changes>
Build failed in Jenkins: Tajo-0.11.0-build #85
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-0.11.0-build/85/>
------------------------------------------
[...truncated 3631 lines...]
2015-09-30 00:42:46,159 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53226 is added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]} size 0
2015-09-30 00:42:46,161 INFO: org.apache.hadoop.hdfs.StateChange (completeFile(3495)) - DIR* completeFile: /tajo/warehouse/customer/customer.tbl is closed by DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,227 INFO: org.apache.tajo.catalog.CatalogServer (createTable(695)) - relation "default.customer" is added to the catalog (127.0.0.1:16756)
2015-09-30 00:42:46,229 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/lineitem dst=null perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,230 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=getfileinfo src=/tajo/warehouse/lineitem/lineitem.tbl dst=null perm=null proto=rpc
2015-09-30 00:42:46,233 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=create src=/tajo/warehouse/lineitem/lineitem.tbl dst=null perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:46,236 INFO: org.apache.hadoop.hdfs.StateChange (saveAllocatedBlock(3575)) - BLOCK* allocate blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]} for /tajo/warehouse/lineitem/lineitem.tbl
2015-09-30 00:42:46,238 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (writeBlock(655)) - Receiving BP-678977945-67.195.81.144-1443573757221:blk_1073741827_1003 src: /127.0.0.1:37464 dest: /127.0.0.1:53226
2015-09-30 00:42:46,243 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode.clienttrace (finalizeBlock(1375)) - src: /127.0.0.1:37464, dest: /127.0.0.1:53226, bytes: 604, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_2107134690_1, offset: 0, srvID: 796adda9-1490-41d6-81fe-259f60cfac6d, blockid: BP-678977945-67.195.81.144-1443573757221:blk_1073741827_1003, duration: 2939722
2015-09-30 00:42:46,243 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (run(1348)) - PacketResponder: BP-678977945-67.195.81.144-1443573757221:blk_1073741827_1003, type=LAST_IN_PIPELINE, downstreams=0:[] terminating
2015-09-30 00:42:46,245 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53226 is added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-77ea458b-a19f-424d-a993-69cd94133c02:NORMAL:127.0.0.1:53226|FINALIZED]]} size 0
2015-09-30 00:42:46,246 INFO: org.apache.hadoop.hdfs.StateChange (completeFile(3495)) - DIR* completeFile: /tajo/warehouse/lineitem/lineitem.tbl is closed by DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,260 INFO: org.apache.tajo.catalog.CatalogServer (createTable(695)) - relation "default.lineitem" is added to the catalog (127.0.0.1:16756)
2015-09-30 00:42:46,261 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/nation dst=null perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,263 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=getfileinfo src=/tajo/warehouse/nation/nation.tbl dst=null perm=null proto=rpc
2015-09-30 00:42:46,265 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=create src=/tajo/warehouse/nation/nation.tbl dst=null perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:46,268 INFO: org.apache.hadoop.hdfs.StateChange (saveAllocatedBlock(3575)) - BLOCK* allocate blk_1073741828_1004{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]} for /tajo/warehouse/nation/nation.tbl
2015-09-30 00:42:46,270 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (writeBlock(655)) - Receiving BP-678977945-67.195.81.144-1443573757221:blk_1073741828_1004 src: /127.0.0.1:37465 dest: /127.0.0.1:53226
2015-09-30 00:42:46,274 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode.clienttrace (finalizeBlock(1375)) - src: /127.0.0.1:37465, dest: /127.0.0.1:53226, bytes: 2224, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_2107134690_1, offset: 0, srvID: 796adda9-1490-41d6-81fe-259f60cfac6d, blockid: BP-678977945-67.195.81.144-1443573757221:blk_1073741828_1004, duration: 2500162
2015-09-30 00:42:46,274 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (run(1348)) - PacketResponder: BP-678977945-67.195.81.144-1443573757221:blk_1073741828_1004, type=LAST_IN_PIPELINE, downstreams=0:[] terminating
2015-09-30 00:42:46,275 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53226 is added to blk_1073741828_1004{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]} size 0
2015-09-30 00:42:46,276 INFO: org.apache.hadoop.hdfs.StateChange (completeFile(3495)) - DIR* completeFile: /tajo/warehouse/nation/nation.tbl is closed by DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,284 INFO: org.apache.tajo.catalog.CatalogServer (createTable(695)) - relation "default.nation" is added to the catalog (127.0.0.1:16756)
2015-09-30 00:42:46,286 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/orders dst=null perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,288 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=getfileinfo src=/tajo/warehouse/orders/orders.tbl dst=null perm=null proto=rpc
2015-09-30 00:42:46,291 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=create src=/tajo/warehouse/orders/orders.tbl dst=null perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:46,293 INFO: org.apache.hadoop.hdfs.StateChange (saveAllocatedBlock(3575)) - BLOCK* allocate blk_1073741829_1005{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]} for /tajo/warehouse/orders/orders.tbl
2015-09-30 00:42:46,295 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (writeBlock(655)) - Receiving BP-678977945-67.195.81.144-1443573757221:blk_1073741829_1005 src: /127.0.0.1:37466 dest: /127.0.0.1:53226
2015-09-30 00:42:46,299 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode.clienttrace (finalizeBlock(1375)) - src: /127.0.0.1:37466, dest: /127.0.0.1:53226, bytes: 324, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_2107134690_1, offset: 0, srvID: 796adda9-1490-41d6-81fe-259f60cfac6d, blockid: BP-678977945-67.195.81.144-1443573757221:blk_1073741829_1005, duration: 2242432
2015-09-30 00:42:46,299 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (run(1348)) - PacketResponder: BP-678977945-67.195.81.144-1443573757221:blk_1073741829_1005, type=LAST_IN_PIPELINE, downstreams=0:[] terminating
2015-09-30 00:42:46,300 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53226 is added to blk_1073741829_1005{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-77ea458b-a19f-424d-a993-69cd94133c02:NORMAL:127.0.0.1:53226|FINALIZED]]} size 0
2015-09-30 00:42:46,301 INFO: org.apache.hadoop.hdfs.StateChange (completeFile(3495)) - DIR* completeFile: /tajo/warehouse/orders/orders.tbl is closed by DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,311 INFO: org.apache.tajo.catalog.CatalogServer (createTable(695)) - relation "default.orders" is added to the catalog (127.0.0.1:16756)
2015-09-30 00:42:46,313 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/part dst=null perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,315 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=getfileinfo src=/tajo/warehouse/part/part.tbl dst=null perm=null proto=rpc
2015-09-30 00:42:46,318 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=create src=/tajo/warehouse/part/part.tbl dst=null perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:46,321 INFO: org.apache.hadoop.hdfs.StateChange (saveAllocatedBlock(3575)) - BLOCK* allocate blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]} for /tajo/warehouse/part/part.tbl
2015-09-30 00:42:46,322 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (writeBlock(655)) - Receiving BP-678977945-67.195.81.144-1443573757221:blk_1073741830_1006 src: /127.0.0.1:37467 dest: /127.0.0.1:53226
2015-09-30 00:42:46,326 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode.clienttrace (finalizeBlock(1375)) - src: /127.0.0.1:37467, dest: /127.0.0.1:53226, bytes: 480, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_2107134690_1, offset: 0, srvID: 796adda9-1490-41d6-81fe-259f60cfac6d, blockid: BP-678977945-67.195.81.144-1443573757221:blk_1073741830_1006, duration: 2276753
2015-09-30 00:42:46,326 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (run(1348)) - PacketResponder: BP-678977945-67.195.81.144-1443573757221:blk_1073741830_1006, type=LAST_IN_PIPELINE, downstreams=0:[] terminating
2015-09-30 00:42:46,328 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53226 is added to blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]} size 0
2015-09-30 00:42:46,329 INFO: org.apache.hadoop.hdfs.StateChange (completeFile(3495)) - DIR* completeFile: /tajo/warehouse/part/part.tbl is closed by DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,337 INFO: org.apache.tajo.catalog.CatalogServer (createTable(695)) - relation "default.part" is added to the catalog (127.0.0.1:16756)
2015-09-30 00:42:46,339 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/partsupp dst=null perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,341 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=getfileinfo src=/tajo/warehouse/partsupp/partsupp.tbl dst=null perm=null proto=rpc
2015-09-30 00:42:46,344 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=create src=/tajo/warehouse/partsupp/partsupp.tbl dst=null perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:46,346 INFO: org.apache.hadoop.hdfs.StateChange (saveAllocatedBlock(3575)) - BLOCK* allocate blk_1073741831_1007{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]} for /tajo/warehouse/partsupp/partsupp.tbl
2015-09-30 00:42:46,349 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (writeBlock(655)) - Receiving BP-678977945-67.195.81.144-1443573757221:blk_1073741831_1007 src: /127.0.0.1:37468 dest: /127.0.0.1:53226
2015-09-30 00:42:46,353 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode.clienttrace (finalizeBlock(1375)) - src: /127.0.0.1:37468, dest: /127.0.0.1:53226, bytes: 469, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_2107134690_1, offset: 0, srvID: 796adda9-1490-41d6-81fe-259f60cfac6d, blockid: BP-678977945-67.195.81.144-1443573757221:blk_1073741831_1007, duration: 3077804
2015-09-30 00:42:46,353 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (run(1348)) - PacketResponder: BP-678977945-67.195.81.144-1443573757221:blk_1073741831_1007, type=LAST_IN_PIPELINE, downstreams=0:[] terminating
2015-09-30 00:42:46,355 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53226 is added to blk_1073741831_1007{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-77ea458b-a19f-424d-a993-69cd94133c02:NORMAL:127.0.0.1:53226|FINALIZED]]} size 0
2015-09-30 00:42:46,356 INFO: org.apache.hadoop.hdfs.StateChange (completeFile(3495)) - DIR* completeFile: /tajo/warehouse/partsupp/partsupp.tbl is closed by DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,364 INFO: org.apache.tajo.catalog.CatalogServer (createTable(695)) - relation "default.partsupp" is added to the catalog (127.0.0.1:16756)
2015-09-30 00:42:46,366 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/region dst=null perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,368 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=getfileinfo src=/tajo/warehouse/region/region.tbl dst=null perm=null proto=rpc
2015-09-30 00:42:46,370 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=create src=/tajo/warehouse/region/region.tbl dst=null perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:46,373 INFO: org.apache.hadoop.hdfs.StateChange (saveAllocatedBlock(3575)) - BLOCK* allocate blk_1073741832_1008{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]} for /tajo/warehouse/region/region.tbl
2015-09-30 00:42:46,375 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (writeBlock(655)) - Receiving BP-678977945-67.195.81.144-1443573757221:blk_1073741832_1008 src: /127.0.0.1:37469 dest: /127.0.0.1:53226
2015-09-30 00:42:46,379 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode.clienttrace (finalizeBlock(1375)) - src: /127.0.0.1:37469, dest: /127.0.0.1:53226, bytes: 389, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_2107134690_1, offset: 0, srvID: 796adda9-1490-41d6-81fe-259f60cfac6d, blockid: BP-678977945-67.195.81.144-1443573757221:blk_1073741832_1008, duration: 2320305
2015-09-30 00:42:46,379 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (run(1348)) - PacketResponder: BP-678977945-67.195.81.144-1443573757221:blk_1073741832_1008, type=LAST_IN_PIPELINE, downstreams=0:[] terminating
2015-09-30 00:42:46,381 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53226 is added to blk_1073741832_1008{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dee562b1-33a7-47d6-a5a9-574805d35954:NORMAL:127.0.0.1:53226|RBW]]} size 0
2015-09-30 00:42:46,382 INFO: org.apache.hadoop.hdfs.StateChange (completeFile(3495)) - DIR* completeFile: /tajo/warehouse/region/region.tbl is closed by DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,389 INFO: org.apache.tajo.catalog.CatalogServer (createTable(695)) - relation "default.region" is added to the catalog (127.0.0.1:16756)
2015-09-30 00:42:46,391 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/supplier dst=null perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,393 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=getfileinfo src=/tajo/warehouse/supplier/supplier.tbl dst=null perm=null proto=rpc
2015-09-30 00:42:46,396 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=create src=/tajo/warehouse/supplier/supplier.tbl dst=null perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:46,399 INFO: org.apache.hadoop.hdfs.StateChange (saveAllocatedBlock(3575)) - BLOCK* allocate blk_1073741833_1009{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-77ea458b-a19f-424d-a993-69cd94133c02:NORMAL:127.0.0.1:53226|RBW]]} for /tajo/warehouse/supplier/supplier.tbl
2015-09-30 00:42:46,401 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (writeBlock(655)) - Receiving BP-678977945-67.195.81.144-1443573757221:blk_1073741833_1009 src: /127.0.0.1:37470 dest: /127.0.0.1:53226
2015-09-30 00:42:46,405 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode.clienttrace (finalizeBlock(1375)) - src: /127.0.0.1:37470, dest: /127.0.0.1:53226, bytes: 366, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_2107134690_1, offset: 0, srvID: 796adda9-1490-41d6-81fe-259f60cfac6d, blockid: BP-678977945-67.195.81.144-1443573757221:blk_1073741833_1009, duration: 2759025
2015-09-30 00:42:46,406 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (run(1348)) - PacketResponder: BP-678977945-67.195.81.144-1443573757221:blk_1073741833_1009, type=LAST_IN_PIPELINE, downstreams=0:[] terminating
2015-09-30 00:42:46,407 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53226 is added to blk_1073741833_1009{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-77ea458b-a19f-424d-a993-69cd94133c02:NORMAL:127.0.0.1:53226|RBW]]} size 0
2015-09-30 00:42:46,408 INFO: org.apache.hadoop.hdfs.StateChange (completeFile(3495)) - DIR* completeFile: /tajo/warehouse/supplier/supplier.tbl is closed by DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,418 INFO: org.apache.tajo.catalog.CatalogServer (createTable(695)) - relation "default.supplier" is added to the catalog (127.0.0.1:16756)
2015-09-30 00:42:46,421 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/empty_orders dst=null perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,423 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=getfileinfo src=/tajo/warehouse/empty_orders/empty_orders.tbl dst=null perm=null proto=rpc
2015-09-30 00:42:46,426 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=create src=/tajo/warehouse/empty_orders/empty_orders.tbl dst=null perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:46,428 INFO: org.apache.hadoop.hdfs.StateChange (completeFile(3495)) - DIR* completeFile: /tajo/warehouse/empty_orders/empty_orders.tbl is closed by DFSClient_NONMAPREDUCE_2107134690_1
2015-09-30 00:42:46,436 INFO: org.apache.tajo.catalog.CatalogServer (createTable(695)) - relation "default.empty_orders" is added to the catalog (127.0.0.1:16756)
2015-09-30 00:42:46,437 INFO: org.apache.tajo.LocalTajoTestingUtility (setup(131)) - ===================================================
2015-09-30 00:42:46,437 INFO: org.apache.tajo.LocalTajoTestingUtility (setup(132)) - Test Cluster ready and test table created.
2015-09-30 00:42:46,437 INFO: org.apache.tajo.LocalTajoTestingUtility (setup(133)) - ===================================================
2015-09-30 00:42:46,451 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session 32b7ca59-4454-413e-b81e-0752f68cbf3e is created.
2015-09-30 00:42:46,468 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(239)) - SQL: CREATE DATABASE IF NOT EXISTS "TestSQLState"
2015-09-30 00:42:46,672 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(280)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestSQLState
2015-09-30 00:42:46,774 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(282)) - =============================================
2015-09-30 00:42:46,774 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(283)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestSQLState
2015-09-30 00:42:46,774 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(284)) - =============================================
2015-09-30 00:42:46,779 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/TestSQLState dst=null perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:46,783 INFO: org.apache.tajo.catalog.CatalogServer (createDatabase(421)) - database "TestSQLState" is created
2015-09-30 00:42:46,783 INFO: org.apache.tajo.master.exec.DDLExecutor (createDatabase(257)) - database "TestSQLState" created.
Run: testSyntaxError Used memory: 193 MBytes, Active Threads:103
2015-09-30 00:42:46,824 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session 8456e524-025b-424f-aed1-6a3d06c66dec is created.
2015-09-30 00:42:46,836 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(176)) - Query: SELECT 1;
2015-09-30 00:42:47,090 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(280)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2015-09-30 00:42:47,152 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(282)) - =============================================
2015-09-30 00:42:47,152 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(283)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2015-09-30 00:42:47,152 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(284)) - =============================================
2015-09-30 00:42:47,172 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(124)) - =========================================================
2015-09-30 00:42:47,173 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(125)) - Stop query:q_1443573761614_0001
2015-09-30 00:42:47,185 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(176)) - Query: selec x,y,x from lineitem
2015-09-30 00:42:47,192 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 8456e524-025b-424f-aed1-6a3d06c66dec is removed.
2015-09-30 00:42:47,251 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 32b7ca59-4454-413e-b81e-0752f68cbf3e is removed.
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 13.223 sec - in org.apache.tajo.jdbc.TestSQLState
Running org.apache.tajo.jdbc.TestTajoJdbcNegative
2015-09-30 00:42:47,267 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session 0db02158-2caa-4579-9b2c-e6358a250c82 is created.
2015-09-30 00:42:47,271 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(239)) - SQL: CREATE DATABASE IF NOT EXISTS "TestTajoJdbcNegative"
2015-09-30 00:42:47,273 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(280)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestTajoJdbcNegative
2015-09-30 00:42:47,274 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(282)) - =============================================
2015-09-30 00:42:47,274 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(283)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestTajoJdbcNegative
2015-09-30 00:42:47,274 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(284)) - =============================================
2015-09-30 00:42:47,276 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=mkdirs src=/tajo/warehouse/TestTajoJdbcNegative dst=null perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:47,278 INFO: org.apache.tajo.catalog.CatalogServer (createDatabase(421)) - database "TestTajoJdbcNegative" is created
2015-09-30 00:42:47,278 INFO: org.apache.tajo.master.exec.DDLExecutor (createDatabase(257)) - database "TestTajoJdbcNegative" created.
Run: testConnectTimeout Used memory: 220 MBytes, Active Threads:111
2015-09-30 00:42:47,850 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=getfileinfo src=/tmp/tajo-jenkins/staging/history/20150930/query-list dst=null perm=null proto=rpc
2015-09-30 00:42:47,852 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=mkdirs src=/tmp/tajo-jenkins/staging/history/20150930/query-list dst=null perm=jenkins:supergroup:rwxr-xr-x proto=rpc
2015-09-30 00:42:47,853 INFO: org.apache.tajo.util.history.HistoryWriter (rollingQuerySummaryWriter(432)) - Create query history file: hdfs://localhost:58551/tmp/tajo-jenkins/staging/history/20150930/query-list/query-list-004247.hist
2015-09-30 00:42:47,855 INFO: org.apache.hadoop.hdfs.server.namenode.FSNamesystem.audit (logAuditMessage(8264)) - allowed=true ugi=jenkins (auth:SIMPLE) ip=/127.0.0.1 cmd=create src=/tmp/tajo-jenkins/staging/history/20150930/query-list/query-list-004247.hist dst=null perm=jenkins:supergroup:rw-r--r-- proto=rpc
2015-09-30 00:42:47,873 INFO: org.apache.hadoop.hdfs.StateChange (saveAllocatedBlock(3575)) - BLOCK* allocate blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-77ea458b-a19f-424d-a993-69cd94133c02:NORMAL:127.0.0.1:53226|RBW]]} for /tmp/tajo-jenkins/staging/history/20150930/query-list/query-list-004247.hist
2015-09-30 00:42:47,876 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (writeBlock(655)) - Receiving BP-678977945-67.195.81.144-1443573757221:blk_1073741834_1010 src: /127.0.0.1:37475 dest: /127.0.0.1:53226
2015-09-30 00:42:47,884 INFO: org.apache.hadoop.hdfs.StateChange (fsync(3977)) - BLOCK* fsync: /tmp/tajo-jenkins/staging/history/20150930/query-list/query-list-004247.hist for DFSClient_NONMAPREDUCE_2107134690_1
Killed
Results :
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 2.077 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 1.458 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.212 s]
[INFO] Tajo Common ....................................... SUCCESS [ 43.569 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 3.125 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 6.826 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 8.339 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 1.424 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 52.062 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.352 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 12.549 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 3.238 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [ 54.460 s]
[INFO] Tajo PullServer ................................... SUCCESS [ 1.239 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.503 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 2.068 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 4.696 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 1.799 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 4.138 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.345 s]
[INFO] Tajo Core ......................................... SUCCESS [ 6.270 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 0.920 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 11.359 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.062 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 1.015 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.057 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 4.335 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 2.942 s]
[INFO] Tajo JDBC Driver .................................. FAILURE [ 16.167 s]
[INFO] Tajo JDBC storage common .......................... SKIPPED
[INFO] Tajo PostgreSQL JDBC storage ...................... SKIPPED
[INFO] Tajo Storage ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] Tajo Core Tests ................................... SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 04:16 min
[INFO] Finished at: 2015-09-30T00:42:48+00:00
[INFO] Final Memory: 121M/507M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on project tajo-jdbc: Execution default-test of goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test failed: The forked VM terminated without properly saying goodbye. VM crash or System.exit called?
[ERROR] Command was /bin/sh -c cd <https://builds.apache.org/job/Tajo-0.11.0-build/ws/tajo-jdbc> && /home/jenkins/tools/java/jdk1.7.0_25-32/jre/bin/java -Xms512m -Xmx1024m -XX:MaxPermSize=152m -Dfile.encoding=UTF-8 -Dderby.storage.pageSize=1024 -Dderby.stream.error.file=/dev/null -jar <https://builds.apache.org/job/Tajo-0.11.0-build/ws/tajo-jdbc/target/surefire/surefirebooter5434800226325486627.jar> <https://builds.apache.org/job/Tajo-0.11.0-build/ws/tajo-jdbc/target/surefire/surefire334072516956304884tmp> <https://builds.apache.org/job/Tajo-0.11.0-build/ws/tajo-jdbc/target/surefire/surefire_137815203712329215974tmp>
[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/PluginExecutionException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :tajo-jdbc
Build step 'Execute shell' marked build as failure
Archiving artifacts