See <https://builds.apache.org/job/Tajo-master-nightly/965/changes>
Changes:
[hyunsik] TAJO-1921: Hbase Storage can cause NPE when the hbase cluster is
------------------------------------------
[...truncated 739549 lines...]
2016-01-19 22:32:06,077 INFO: org.apache.tajo.rpc.NettyServerBase (start(129))
- Rpc (Tajo-REST) listens on /127.0.0.1:59921
2016-01-19 22:32:06,077 INFO: org.apache.tajo.ws.rs.TajoRestService
(serviceStart(118)) - Tajo Rest Service started.
2016-01-19 22:32:06,078 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics
(setMetricsReporter(140)) - No property MASTER.reporters in
tajo-metrics.properties
2016-01-19 22:32:06,080 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics
(setMetricsReporter(140)) - No property MASTER-JVM.reporters in
tajo-metrics.properties
2016-01-19 22:32:06,084 INFO: BlockStateChange (addToInvalidates(1106)) -
BLOCK* addToInvalidates: blk_1073741825_1001 127.0.0.1:34089
2016-01-19 22:32:06,098 INFO: org.apache.tajo.ha.HdfsServiceTracker
(initSystemDirectory(91)) - System HA dir
'hdfs://localhost:35437/tajo/system/ha' is created
2016-01-19 22:32:06,099 INFO: org.apache.tajo.ha.HdfsServiceTracker
(initSystemDirectory(97)) - System HA Active dir
'hdfs://localhost:35437/tajo/system/ha/active' is created
2016-01-19 22:32:06,100 INFO: org.apache.tajo.ha.HdfsServiceTracker
(initSystemDirectory(103)) - System HA Backup dir
'hdfs://localhost:35437/tajo/system/ha/backup' is created
2016-01-19 22:32:06,107 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34089 is added to
blk_1073749128_8304{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d101614c-4253-4fe1-97db-bee8e2303c0c:NORMAL:127.0.0.1:34089|RBW]]}
size 0
2016-01-19 22:32:06,129 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34089 is added to
blk_1073749129_8305{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d101614c-4253-4fe1-97db-bee8e2303c0c:NORMAL:127.0.0.1:34089|RBW]]}
size 0
2016-01-19 22:32:06,131 INFO: org.apache.tajo.ha.HdfsServiceTracker
(register(156)) - This is added to active master (127.0.0.1:58883)
2016-01-19 22:32:06,132 INFO: org.apache.tajo.util.history.HistoryWriter
(run(231)) - HistoryWriter_127.0.0.1_58883 started.
2016-01-19 22:32:06,132 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(62)) - History cleaner started: expiry day=7
2016-01-19 22:32:06,132 INFO: org.apache.tajo.master.TajoMaster
(checkAndInitializeSystemDirectories(245)) - Tajo Root Directory:
hdfs://localhost:35437/tajo
2016-01-19 22:32:06,133 INFO: org.apache.tajo.master.TajoMaster
(checkAndInitializeSystemDirectories(250)) - FileSystem
(hdfs://localhost:35437) is initialized.
2016-01-19 22:32:06,134 INFO: org.apache.tajo.master.TajoMaster
(checkAndInitializeSystemDirectories(270)) - Tajo Warehouse dir:
hdfs://localhost:35437/tajo/warehouse
2016-01-19 22:32:06,134 INFO: org.apache.tajo.master.TajoMaster
(checkAndInitializeSystemDirectories(279)) - Staging dir:
hdfs://localhost:35437/tajo/warehouse
2016-01-19 22:32:06,208 INFO: BlockStateChange (invalidateWorkForOneNode(3488))
- BLOCK* BlockManager: ask 127.0.0.1:34089 to delete [blk_1073749120_8296,
blk_1073741825_1001, blk_1073749124_8300]
2016-01-19 22:32:06,597 INFO: org.apache.tajo.master.rm.TajoResourceManager
(serviceInit(97)) - Loaded resource scheduler : class
org.apache.tajo.master.scheduler.SimpleScheduler
2016-01-19 22:32:06,598 INFO: org.apache.tajo.rpc.NettyServerBase (start(129))
- Rpc (TajoResourceTrackerProtocol) listens on /127.0.0.1:55624
2016-01-19 22:32:06,599 INFO: org.apache.tajo.master.rm.TajoResourceTracker
(serviceInit(99)) - TajoResourceTracker starts up (localhost/127.0.0.1:55624)
2016-01-19 22:32:06,599 INFO: org.apache.tajo.catalog.CatalogServer
(serviceInit(125)) - Catalog Store Class:
org.apache.tajo.catalog.store.DerbyStore
2016-01-19 22:32:06,599 INFO: org.apache.tajo.catalog.store.DerbyStore
(<init>(155)) - Loaded the Catalog driver (org.apache.derby.jdbc.EmbeddedDriver)
2016-01-19 22:32:06,599 INFO: org.apache.tajo.catalog.store.DerbyStore
(<init>(161)) - Trying to connect database
(jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/a6ddfe1d-5c3b-4dac-8a53-4b4bd839d5b6/db;create=true)>
2016-01-19 22:32:06,600 INFO: org.apache.tajo.catalog.store.DerbyStore
(<init>(163)) - Connected to database
(jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/a6ddfe1d-5c3b-4dac-8a53-4b4bd839d5b6/db;create=true)>
2016-01-19 22:32:06,605 INFO: org.apache.tajo.catalog.store.DerbyStore
(<init>(175)) - The meta table of CatalogServer already is created.
2016-01-19 22:32:06,606 INFO: org.apache.tajo.catalog.store.DerbyStore
(verifySchemaVersion(279)) - The compatibility of the catalog schema (version:
12) has been verified.
Jan 19, 2016 10:32:06 PM org.glassfish.jersey.server.ApplicationHandler
initialize
INFO: Initiating Jersey application, version Jersey: 2.6 2014-02-18 21:52:53...
2016-01-19 22:32:06,652 INFO: org.apache.tajo.ws.rs.TajoRestService
(serviceInit(109)) - Tajo Rest Service initialized.
2016-01-19 22:32:06,652 INFO: org.apache.tajo.master.TajoMaster
(serviceInit(215)) - Tajo Master is initialized.
2016-01-19 22:32:06,652 INFO: org.apache.tajo.master.TajoMaster
(serviceStart(307)) - TajoMaster is starting up
2016-01-19 22:32:06,653 INFO: org.apache.tajo.master.TajoMaster
(checkBaseTBSpaceAndDatabase(407)) - Default database (default) is already
prepared.
2016-01-19 22:32:06,656 INFO: org.apache.tajo.rpc.NettyServerBase (start(129))
- Rpc (CatalogProtocol) listens on /127.0.0.1:52386
2016-01-19 22:32:06,656 INFO: org.apache.tajo.catalog.CatalogServer
(serviceStart(176)) - Catalog Server startup (127.0.0.1:52386)
2016-01-19 22:32:06,658 INFO: org.apache.tajo.rpc.NettyServerBase (start(129))
- Rpc (TajoMasterClientProtocol) listens on /127.0.0.1:35322
2016-01-19 22:32:06,659 INFO: org.apache.tajo.master.TajoMasterClientService
(serviceStart(106)) - Instantiated TajoMasterClientService at
localhost/127.0.0.1:35322
2016-01-19 22:32:06,659 INFO: org.apache.tajo.rpc.NettyServerBase (start(129))
- Rpc (QueryCoordinatorProtocol) listens on /127.0.0.1:59780
2016-01-19 22:32:06,660 INFO: org.apache.tajo.master.QueryCoordinatorService
(serviceStart(70)) - Instantiated TajoMasterService at localhost/127.0.0.1:59780
2016-01-19 22:32:06,660 INFO: org.apache.tajo.rpc.NettyServerBase (start(129))
- Rpc (Tajo-REST) listens on /127.0.0.1:56610
2016-01-19 22:32:06,661 INFO: org.apache.tajo.ws.rs.TajoRestService
(serviceStart(118)) - Tajo Rest Service started.
2016-01-19 22:32:06,662 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics
(setMetricsReporter(140)) - No property MASTER.reporters in
tajo-metrics.properties
2016-01-19 22:32:06,662 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics
(setMetricsReporter(140)) - No property MASTER-JVM.reporters in
tajo-metrics.properties
2016-01-19 22:32:06,670 INFO: BlockStateChange (addToInvalidates(1106)) -
BLOCK* addToInvalidates: blk_1073749129_8305 127.0.0.1:34089
2016-01-19 22:32:06,672 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
2016-01-19 22:32:06,681 INFO: org.apache.tajo.ha.HdfsServiceTracker
(createLockFile(224)) - Lock file already exists at
(hdfs://localhost:35437/tajo/system/ha/active/active.lock)
2016-01-19 22:32:06,692 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34089 is added to
blk_1073749130_8306{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d101614c-4253-4fe1-97db-bee8e2303c0c:NORMAL:127.0.0.1:34089|RBW]]}
size 0
2016-01-19 22:32:06,693 INFO: org.apache.tajo.ha.HdfsServiceTracker
(register(187)) - This is added to backup master (127.0.0.1:59780)
2016-01-19 22:32:06,695 INFO: org.apache.tajo.util.history.HistoryWriter
(run(231)) - HistoryWriter_127.0.0.1_59780 started.
2016-01-19 22:32:06,695 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(62)) - History cleaner started: expiry day=7
2016-01-19 22:32:06,707 INFO: org.apache.tajo.session.SessionManager
(createSession(79)) - Session 185a98ef-4d12-4ae1-8cb1-e94b7e81cef5 is created.
2016-01-19 22:32:06,708 INFO: org.apache.tajo.master.GlobalEngine
(executeQuery(177)) - Query: CREATE TABLE default.ha_test1 (age int);
2016-01-19 22:32:06,709 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(281)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2016-01-19 22:32:06,709 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(283)) - =============================================
2016-01-19 22:32:06,709 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(284)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2016-01-19 22:32:06,709 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(285)) - =============================================
2016-01-19 22:32:06,713 INFO: org.apache.tajo.catalog.CatalogServer
(createTable(688)) - relation "default.ha_test1" is added to the catalog
(127.0.0.1:49215)
2016-01-19 22:32:06,714 INFO: org.apache.tajo.master.exec.DDLExecutor
(create(112)) - relation 'default.ha_test1' created.
2016-01-19 22:32:06,714 INFO: org.apache.tajo.master.GlobalEngine
(executeQuery(177)) - Query: CREATE TABLE default.ha_test2 (age int);
2016-01-19 22:32:06,715 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(281)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2016-01-19 22:32:06,715 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(283)) - =============================================
2016-01-19 22:32:06,715 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(284)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2016-01-19 22:32:06,715 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(285)) - =============================================
2016-01-19 22:32:06,718 INFO: org.apache.tajo.catalog.CatalogServer
(createTable(688)) - relation "default.ha_test2" is added to the catalog
(127.0.0.1:49215)
2016-01-19 22:32:06,719 INFO: org.apache.tajo.master.exec.DDLExecutor
(create(112)) - relation 'default.ha_test2' created.
2016-01-19 22:32:06,719 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session 185a98ef-4d12-4ae1-8cb1-e94b7e81cef5 is removed.
2016-01-19 22:32:06,722 INFO: org.apache.tajo.session.SessionManager
(createSession(79)) - Session 83524634-9988-47d3-b09b-5860e3408252 is created.
2016-01-19 22:32:06,724 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session 83524634-9988-47d3-b09b-5860e3408252 is removed.
2016-01-19 22:32:06,726 INFO: BlockStateChange (addToInvalidates(1106)) -
BLOCK* addToInvalidates: blk_1073749128_8304 127.0.0.1:34089
2016-01-19 22:32:06,727 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (Tajo-REST) listened on 127.0.0.1:59921) shutdown
2016-01-19 22:32:06,728 INFO: org.apache.tajo.ws.rs.TajoRestService
(serviceStop(129)) - Tajo Rest Service stopped.
2016-01-19 22:32:06,730 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:58883)
shutdown
2016-01-19 22:32:06,730 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2016-01-19 22:32:06,730 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:38637)
shutdown
2016-01-19 22:32:06,730 INFO: org.apache.tajo.util.history.HistoryWriter
(run(275)) - HistoryWriter_127.0.0.1_58883 stopped.
2016-01-19 22:32:06,733 INFO: org.apache.tajo.catalog.CatalogServer
(serviceStop(182)) - Catalog Server (127.0.0.1:49215) shutdown
2016-01-19 22:32:06,733 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (CatalogProtocol) listened on 127.0.0.1:49215) shutdown
2016-01-19 22:32:06,734 INFO: org.apache.tajo.catalog.store.DerbyStore
(close(2978)) - Close database
(jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/a6ddfe1d-5c3b-4dac-8a53-4b4bd839d5b6/db;create=true)>
2016-01-19 22:32:06,735 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (TajoResourceTrackerProtocol) listened on
127.0.0.1:49516) shutdown
2016-01-19 22:32:06,735 INFO: org.apache.tajo.master.TajoMaster
(serviceStop(424)) - Tajo Master main thread exiting
2016-01-19 22:32:07,701 INFO: BlockStateChange (addToInvalidates(1106)) -
BLOCK* addToInvalidates: blk_1073749130_8306 127.0.0.1:34089
2016-01-19 22:32:07,706 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34089 is added to
blk_1073749131_8307{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d101614c-4253-4fe1-97db-bee8e2303c0c:NORMAL:127.0.0.1:34089|RBW]]}
size 81
2016-01-19 22:32:07,743 INFO: org.apache.tajo.session.SessionManager
(createSession(79)) - Session e1f98662-d500-4ae8-9dd7-f21cde8aa572 is created.
2016-01-19 22:32:07,745 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session e1f98662-d500-4ae8-9dd7-f21cde8aa572 is removed.
2016-01-19 22:32:08,130 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34089 is added to
blk_1073749132_8308{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d101614c-4253-4fe1-97db-bee8e2303c0c:NORMAL:127.0.0.1:34089|RBW]]}
size 0
2016-01-19 22:32:08,133 INFO: org.apache.tajo.ha.HdfsServiceTracker
(register(156)) - This is added to active master (127.0.0.1:59780)
2016-01-19 22:32:08,134 INFO: BlockStateChange (addToInvalidates(1106)) -
BLOCK* addToInvalidates: blk_1073749131_8307 127.0.0.1:34089
2016-01-19 22:32:08,136 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (Tajo-REST) listened on 127.0.0.1:56610) shutdown
2016-01-19 22:32:08,136 INFO: org.apache.tajo.ws.rs.TajoRestService
(serviceStop(129)) - Tajo Rest Service stopped.
2016-01-19 22:32:08,138 INFO: org.apache.tajo.util.history.HistoryWriter
(run(275)) - HistoryWriter_127.0.0.1_59780 stopped.
2016-01-19 22:32:08,138 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2016-01-19 22:32:08,139 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:59780)
shutdown
2016-01-19 22:32:08,139 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:35322)
shutdown
2016-01-19 22:32:08,140 INFO: org.apache.tajo.catalog.CatalogServer
(serviceStop(182)) - Catalog Server (127.0.0.1:52386) shutdown
2016-01-19 22:32:08,141 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (CatalogProtocol) listened on 127.0.0.1:52386) shutdown
2016-01-19 22:32:08,141 INFO: org.apache.tajo.catalog.store.DerbyStore
(close(2978)) - Close database
(jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/a6ddfe1d-5c3b-4dac-8a53-4b4bd839d5b6/db;create=true)>
2016-01-19 22:32:08,142 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (TajoResourceTrackerProtocol) listened on
127.0.0.1:55624) shutdown
2016-01-19 22:32:08,142 INFO: org.apache.tajo.master.TajoMaster
(serviceStop(424)) - Tajo Master main thread exiting
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.666 sec - in
org.apache.tajo.ha.TestHAServiceHDFSImpl
Running org.apache.tajo.TestTajoIds
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in
org.apache.tajo.TestTajoIds
2016-01-19 22:32:08,149 INFO: org.mortbay.log (info(67)) - Shutdown hook
executing
2016-01-19 22:32:08,153 INFO: org.mortbay.log (info(67)) - Shutdown hook
complete
2016-01-19 22:32:08,159 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session ca66457e-3be5-4a04-a243-0f149e280afa is removed.
2016-01-19 22:32:08,160 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session 74b6a978-0d2a-4db2-bab6-bcd93429eae1 is removed.
2016-01-19 22:32:08,162 INFO: org.apache.tajo.worker.TajoWorker (run(518)) -
============================================
2016-01-19 22:32:08,162 INFO: org.apache.tajo.worker.TajoWorker (run(519)) -
TajoWorker received SIGINT Signal
2016-01-19 22:32:08,162 INFO: org.apache.tajo.worker.TajoWorker (run(520)) -
============================================
2016-01-19 22:32:08,165 INFO: org.apache.tajo.util.history.HistoryWriter
(run(275)) - HistoryWriter_asf907.gq1.ygridcore.net_28491 stopped.
2016-01-19 22:32:08,168 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2016-01-19 22:32:08,198 INFO: org.apache.tajo.worker.NodeStatusUpdater
(serviceStop(113)) - NodeStatusUpdater stopped.
2016-01-19 22:32:08,198 INFO: org.apache.tajo.worker.NodeStatusUpdater
(run(261)) - Heartbeat Thread stopped.
2016-01-19 22:32:08,199 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:28493)
shutdown
2016-01-19 22:32:08,201 INFO:
org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) -
QueryMasterManagerService stopped
2016-01-19 22:32:08,201 INFO: org.apache.tajo.querymaster.QueryMaster
(run(428)) - QueryMaster heartbeat thread stopped
2016-01-19 22:32:08,202 INFO: org.apache.tajo.querymaster.QueryMaster
(serviceStop(164)) - QueryMaster stopped
2016-01-19 22:32:08,202 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(98)) - TajoWorkerClientService stopping
2016-01-19 22:32:08,202 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (QueryMasterClientProtocol) listened on
0:0:0:0:0:0:0:0:28492) shutdown
2016-01-19 22:32:08,202 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(102)) - TajoWorkerClientService stopped
2016-01-19 22:32:08,203 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:28491)
shutdown
2016-01-19 22:32:08,203 INFO: org.apache.tajo.worker.TajoWorkerManagerService
(serviceStop(93)) - TajoWorkerManagerService stopped
2016-01-19 22:32:08,203 INFO: org.apache.tajo.worker.TajoWorker
(serviceStop(332)) - TajoWorker main thread exiting
2016-01-19 22:32:09,209 INFO: BlockStateChange (invalidateWorkForOneNode(3488))
- BLOCK* BlockManager: ask 127.0.0.1:34089 to delete [blk_1073749128_8304,
blk_1073749129_8305, blk_1073749130_8306, blk_1073749131_8307]
2016-01-19 22:32:10,407 INFO: org.apache.tajo.master.TajoMaster (run(568)) -
============================================
2016-01-19 22:32:10,409 INFO: org.apache.tajo.master.TajoMaster (run(569)) -
TajoMaster received SIGINT Signal
2016-01-19 22:32:10,409 INFO: org.apache.tajo.master.TajoMaster (run(570)) -
============================================
2016-01-19 22:32:10,409 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (Tajo-REST) listened on 127.0.0.1:28490) shutdown
2016-01-19 22:32:10,409 INFO: org.apache.tajo.ws.rs.TajoRestService
(serviceStop(129)) - Tajo Rest Service stopped.
2016-01-19 22:32:10,415 INFO: org.apache.tajo.util.history.HistoryWriter
(run(275)) - HistoryWriter_127.0.0.1_28489 stopped.
2016-01-19 22:32:10,417 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34089 is added to
blk_1073741834_1010{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d101614c-4253-4fe1-97db-bee8e2303c0c:NORMAL:127.0.0.1:34089|RBW]]}
size 2943264
2016-01-19 22:32:10,818 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:28489)
shutdown
2016-01-19 22:32:10,818 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2016-01-19 22:32:10,820 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:28488)
shutdown
2016-01-19 22:32:10,821 INFO: org.apache.tajo.catalog.CatalogServer
(serviceStop(182)) - Catalog Server (127.0.0.1:28487) shutdown
2016-01-19 22:32:10,821 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (CatalogProtocol) listened on 127.0.0.1:28487) shutdown
2016-01-19 22:32:10,821 INFO: org.apache.tajo.catalog.store.DerbyStore
(close(2978)) - Close database
(jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/a6ddfe1d-5c3b-4dac-8a53-4b4bd839d5b6/db;create=true)>
2016-01-19 22:32:10,822 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (TajoResourceTrackerProtocol) listened on
127.0.0.1:28486) shutdown
2016-01-19 22:32:10,822 INFO: org.apache.tajo.master.TajoMaster
(serviceStop(424)) - Tajo Master main thread exiting
2016-01-19 22:32:10,869 INFO: org.apache.tajo.catalog.store.DerbyStore
(shutdown(68)) - Derby shutdown complete normally.
2016-01-19 22:32:10,869 INFO: org.apache.tajo.catalog.store.DerbyStore
(shutdown(75)) - Shutdown database
Results :
Tests in error:
TestJoinOnPartitionedTables.testPartialFilterPushDown:95->QueryTestCaseBase.runSimpleTests:622
ยป UndefinedTable
Tests run: 1733, Failures: 0, Errors: 1, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 1.984 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 1.763 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.071 s]
[INFO] Tajo Common ....................................... SUCCESS [ 29.476 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 2.804 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 5.109 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 7.279 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 1.285 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [02:24 min]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.471 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 10.764 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 3.108 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [01:00 min]
[INFO] Tajo PullServer ................................... SUCCESS [ 1.064 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.638 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 2.242 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 4.373 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 1.924 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 4.166 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.719 s]
[INFO] Tajo Core ......................................... SUCCESS [ 8.147 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 0.972 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 11.229 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.076 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 0.987 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.088 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 4.615 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 3.127 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 31.183 s]
[INFO] Tajo JDBC storage common .......................... SUCCESS [ 0.863 s]
[INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 1.905 s]
[INFO] Tajo S3 storage ................................... SUCCESS [ 0.268 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 1.015 s]
[INFO] Tajo Distribution ................................. SUCCESS [ 5.277 s]
[INFO] Tajo Core Tests ................................... FAILURE [21:46 min]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 27:49 min
[INFO] Finished at: 2016-01-19T22:32:11+00:00
[INFO] Final Memory: 155M/1670M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal
org.apache.maven.plugins:maven-surefire-plugin:2.19:test (default-test) on
project tajo-core-tests: There are test failures.
[ERROR]
[ERROR] Please refer to
<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/surefire-reports>
for the individual test results.
[ERROR] -> [Help 1]
[ERROR]
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e
switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR]
[ERROR] For more information about the errors and possible solutions, please
read the following articles:
[ERROR] [Help 1]
http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :tajo-core-tests
Build step 'Execute shell' marked build as failure
Archiving artifacts
Recording test results
