See <https://builds.apache.org/job/Tajo-master-build/1089/changes>
Changes:
[jihoonson] TAJO-2033: Printing out query status with progress bar in TSQL.
------------------------------------------
[...truncated 741407 lines...]
2016-02-16 08:31:08,906 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(285)) - =============================================
2016-02-16 08:31:08,907 INFO: org.apache.tajo.master.exec.DDLExecutor
(createDatabase(245)) - database "TestHistoryWriterReader" is already exists.
2016-02-16 08:31:08,909 INFO: org.apache.tajo.util.history.HistoryWriter
(run(231)) - HistoryWriter_127.0.0.1_28090 started.
2016-02-16 08:31:08,909 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(62)) - History cleaner started: expiry day=7
2016-02-16 08:31:09,910 INFO: org.apache.tajo.util.history.HistoryWriter
(writeQueryHistory(365)) - Saving query summary:
hdfs://localhost:45056/tmp/tajo-test-history/20160216/query-detail/q_1455611468909_0001/query.hist
2016-02-16 08:31:09,915 INFO: BlockStateChange (logAddStoredBlock(2621)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:54654 is added to
blk_1073749130_8306{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-2157c584-9f3f-45a5-8808-c326d98e5a84:NORMAL:127.0.0.1:54654|FINALIZED]]}
size 0
2016-02-16 08:31:09,916 INFO: org.apache.tajo.util.history.HistoryWriter
(writeQueryHistory(379)) - Saving query unit:
hdfs://localhost:45056/tmp/tajo-test-history/20160216/query-detail/q_1455611468909_0001/eb_1455611468909_0001_000000.hist
2016-02-16 08:31:09,919 INFO: BlockStateChange (logAddStoredBlock(2621)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:54654 is added to
blk_1073749131_8307{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-705b3759-3ef9-426a-99a0-02cc7dada001:NORMAL:127.0.0.1:54654|FINALIZED]]}
size 0
2016-02-16 08:31:09,920 INFO: org.apache.tajo.util.history.HistoryWriter
(writeQueryHistory(379)) - Saving query unit:
hdfs://localhost:45056/tmp/tajo-test-history/20160216/query-detail/q_1455611468909_0001/eb_1455611468909_0001_000001.hist
2016-02-16 08:31:09,923 INFO: BlockStateChange (logAddStoredBlock(2621)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:54654 is added to
blk_1073749132_8308{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-2157c584-9f3f-45a5-8808-c326d98e5a84:NORMAL:127.0.0.1:54654|FINALIZED]]}
size 0
2016-02-16 08:31:09,924 INFO: org.apache.tajo.util.history.HistoryWriter
(writeQueryHistory(379)) - Saving query unit:
hdfs://localhost:45056/tmp/tajo-test-history/20160216/query-detail/q_1455611468909_0001/eb_1455611468909_0001_000002.hist
2016-02-16 08:31:09,927 INFO: BlockStateChange (logAddStoredBlock(2621)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:54654 is added to
blk_1073749133_8309{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-705b3759-3ef9-426a-99a0-02cc7dada001:NORMAL:127.0.0.1:54654|RBW]]}
size 0
2016-02-16 08:31:09,942 INFO: org.apache.tajo.util.history.HistoryWriter
(run(275)) - HistoryWriter_127.0.0.1_28090 stopped.
2016-02-16 08:31:09,942 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2016-02-16 08:31:09,945 INFO: BlockStateChange (addToInvalidates(1103)) -
BLOCK* addToInvalidates: blk_1073749131_8307 127.0.0.1:54654
2016-02-16 08:31:09,945 INFO: BlockStateChange (addToInvalidates(1103)) -
BLOCK* addToInvalidates: blk_1073749132_8308 127.0.0.1:54654
2016-02-16 08:31:09,945 INFO: BlockStateChange (addToInvalidates(1103)) -
BLOCK* addToInvalidates: blk_1073749133_8309 127.0.0.1:54654
2016-02-16 08:31:09,945 INFO: BlockStateChange (addToInvalidates(1103)) -
BLOCK* addToInvalidates: blk_1073749130_8306 127.0.0.1:54654
2016-02-16 08:31:09,946 INFO: org.apache.tajo.master.GlobalEngine
(updateQuery(240)) - SQL: CREATE DATABASE IF NOT EXISTS
"TestHistoryWriterReader"
2016-02-16 08:31:09,946 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(281)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestHistoryWriterReader
2016-02-16 08:31:09,946 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(283)) - =============================================
2016-02-16 08:31:09,947 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(284)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestHistoryWriterReader
2016-02-16 08:31:09,947 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(285)) - =============================================
2016-02-16 08:31:09,947 INFO: org.apache.tajo.master.exec.DDLExecutor
(createDatabase(245)) - database "TestHistoryWriterReader" is already exists.
2016-02-16 08:31:09,949 INFO: org.apache.tajo.util.history.HistoryWriter
(run(231)) - HistoryWriter_127.0.0.1_28090 started.
2016-02-16 08:31:09,949 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(62)) - History cleaner started: expiry day=7
2016-02-16 08:31:10,951 INFO: org.apache.tajo.util.history.HistoryWriter
(rollingQuerySummaryWriter(432)) - Create query history file:
hdfs://localhost:45056/tmp/tajo-test-history/20160216/query-list/query-list-083110.hist
2016-02-16 08:31:11,085 INFO: org.apache.tajo.util.history.HistoryWriter
(run(275)) - HistoryWriter_127.0.0.1_28090 stopped.
2016-02-16 08:31:11,088 INFO: BlockStateChange (logAddStoredBlock(2621)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:54654 is added to
blk_1073749134_8310{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-2157c584-9f3f-45a5-8808-c326d98e5a84:NORMAL:127.0.0.1:54654|RBW]]}
size 397
2016-02-16 08:31:11,089 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2016-02-16 08:31:11,090 INFO: BlockStateChange (addToInvalidates(1103)) -
BLOCK* addToInvalidates: blk_1073749134_8310 127.0.0.1:54654
2016-02-16 08:31:11,091 INFO: org.apache.tajo.master.GlobalEngine
(updateQuery(240)) - SQL: CREATE DATABASE IF NOT EXISTS
"TestHistoryWriterReader"
2016-02-16 08:31:11,091 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(281)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestHistoryWriterReader
2016-02-16 08:31:11,091 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(283)) - =============================================
2016-02-16 08:31:11,092 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(284)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestHistoryWriterReader
2016-02-16 08:31:11,092 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(285)) - =============================================
2016-02-16 08:31:11,092 INFO: org.apache.tajo.master.exec.DDLExecutor
(createDatabase(245)) - database "TestHistoryWriterReader" is already exists.
2016-02-16 08:31:11,094 INFO: org.apache.tajo.util.history.HistoryWriter
(run(231)) - HistoryWriter_127.0.0.1_28090 started.
2016-02-16 08:31:11,094 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(62)) - History cleaner started: expiry day=7
2016-02-16 08:31:11,099 INFO: BlockStateChange (invalidateWorkForOneNode(3482))
- BLOCK* BlockManager: ask 127.0.0.1:54654 to delete [blk_1073749130_8306,
blk_1073749131_8307, blk_1073749132_8308, blk_1073749133_8309,
blk_1073749134_8310]
2016-02-16 08:31:12,097 INFO: org.apache.tajo.util.history.HistoryWriter
(rollingQuerySummaryWriter(432)) - Create query history file:
hdfs://localhost:45056/tmp/tajo-test-history/20160216/query-list/query-list-083112.hist
2016-02-16 08:31:12,218 INFO: org.apache.tajo.util.history.HistoryWriter
(run(275)) - HistoryWriter_127.0.0.1_28090 stopped.
2016-02-16 08:31:12,219 INFO: BlockStateChange (logAddStoredBlock(2621)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:54654 is added to
blk_1073749135_8311{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-705b3759-3ef9-426a-99a0-02cc7dada001:NORMAL:127.0.0.1:54654|RBW]]}
size 2000
2016-02-16 08:31:12,219 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2016-02-16 08:31:12,221 INFO: BlockStateChange (addToInvalidates(1103)) -
BLOCK* addToInvalidates: blk_1073749135_8311 127.0.0.1:54654
2016-02-16 08:31:12,229 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session 07e92b71-abc0-4e80-b364-b3655ee28d11 is removed.
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.366 sec - in
org.apache.tajo.util.history.TestHistoryWriterReader
Running org.apache.tajo.util.TestRpcParamFactory
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.037 sec - in
org.apache.tajo.util.TestRpcParamFactory
Running org.apache.tajo.util.TestJSPUtil
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.043 sec - in
org.apache.tajo.util.TestJSPUtil
Running org.apache.tajo.TestTajoIds
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 sec - in
org.apache.tajo.TestTajoIds
Running org.apache.tajo.storage.TestFileFragment
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.105 sec - in
org.apache.tajo.storage.TestFileFragment
Running org.apache.tajo.storage.TestRowFile
2016-02-16 08:31:12,433 INFO: BlockStateChange (logAddStoredBlock(2621)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:54654 is added to
blk_1073749136_8312{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-2157c584-9f3f-45a5-8808-c326d98e5a84:NORMAL:127.0.0.1:54654|FINALIZED]]}
size 0
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.035 sec - in
org.apache.tajo.storage.TestRowFile
Running org.apache.tajo.resource.TestResources
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in
org.apache.tajo.resource.TestResources
w, 20B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
Feb 16, 2016 8:25:00 AM INFO:
org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore
to file. allocated memory: 26
Feb 16, 2016 8:25:00 AM INFO:
org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for
[l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [PLAIN,
RLE, BIT_PACKED]
Feb 16, 2016 8:25:00 AM INFO:
org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for
[l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings:
[PLAIN, RLE, BIT_PACKED]
Feb 16, 2016 8:25:00 AM INFO:
org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore
to file. allocated memory: 26
Feb 16, 2016 8:25:00 AM INFO:
org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for
[l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [PLAIN,
RLE, BIT_PACKED]
Feb 16, 2016 8:25:00 AM INFO:
org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for
[l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings:
[PLAIN, RLE, BIT_PACKED]
Feb 16, 2016 8:25:00 AM INFO:
org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore
to file. allocated memory: 26
Feb 16, 2016 8:25:00 AM INFO:
org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for
[l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [PLAIN,
RLE, BIT_PACKED]
Feb 16, 2016 8:25:00 AM INFO:
org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for
[l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings:
[PLAIN, RLE, BIT_PACKED]
Feb 16, 2016 8:25:00 AM INFO:
org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore
to file. allocated memory: 26
Feb 16, 2016 8:25:00 AM INFO:
org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for
[l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [PLAIN,
RLE, BIT_PACKED]
Feb 16, 2016 8:25:00 AM INFO:
org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for
[l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings:
[PLAIN, RLE, BIT_PACKED]
Feb 16, 2016 8:25:01 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
Initiating action with parallelism: 5
Feb 16, 2016 8:25:01 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
reading another 1 footers
Feb 16, 2016 8:25:01 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
Initiating action with parallelism: 5
Feb 16, 2016 8:25:01 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
Initiating action with parallelism: 5
Feb 16, 2016 8:25:01 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
reading another 1 footers
Feb 16, 2016 8:25:01 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
Initiating action with parallelism: 5
Feb 16, 2016 8:25:01 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
Initiating action with parallelism: 5
Feb 16, 2016 8:25:01 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
reading another 1 footers
Feb 16, 2016 8:25:01 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
Initiating action with parallelism: 5
Feb 16, 2016 8:25:01 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
Initiating action with parallelism: 5
Feb 16, 2016 8:25:01 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
reading another 1 footers
Feb 16, 2016 8:25:01 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
Initiating action with parallelism: 5
Feb 16, 2016 8:25:01 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
Initiating action with parallelism: 5
Feb 16, 2016 8:25:01 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
reading another 1 footers
Feb 16, 2016 8:25:01 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
Initiating action with parallelism: 5
Feb 16, 2016 8:25:02 AM INFO:
org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore
to file. allocated memory: 212
Feb 16, 2016 8:25:02 AM INFO:
org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for
[l_orderkey] INT32: 5 values, 10B raw, 10B comp, 1 pages, encodings:
[PLAIN_DICTIONARY, RLE, BIT_PACKED], dic { 3 entries, 12B raw, 3B comp}
Feb 16, 2016 8:25:02 AM INFO:
org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 123B for
[l_shipdate] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings: [PLAIN,
RLE, BIT_PACKED]
Feb 16, 2016 8:25:02 AM INFO:
org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 123B for
[l_shipdate_function] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings:
[PLAIN, RLE, BIT_PACKED]
Feb 16, 2016 8:25:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
Initiating action with parallelism: 5
Feb 16, 2016 8:25:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
reading another 1 footers
Feb 16, 2016 8:25:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader:
Initiating action with parallelism: 5
2016-02-16 08:31:12,464 INFO: org.mortbay.log (info(67)) - Shutdown hook
executing
2016-02-16 08:31:12,464 INFO: org.mortbay.log (info(67)) - Shutdown hook
complete
2016-02-16 08:31:12,473 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session d573276d-646a-4fd5-967d-536be0ab8f85 is removed.
2016-02-16 08:31:12,479 INFO: org.apache.tajo.session.SessionManager
(removeSession(85)) - Session b4717109-2cff-4ca9-a86f-257f5ac016c8 is removed.
2016-02-16 08:31:12,480 INFO: org.apache.tajo.worker.TajoWorker (run(518)) -
============================================
2016-02-16 08:31:12,480 INFO: org.apache.tajo.worker.TajoWorker (run(519)) -
TajoWorker received SIGINT Signal
2016-02-16 08:31:12,480 INFO: org.apache.tajo.worker.TajoWorker (run(520)) -
============================================
2016-02-16 08:31:12,482 INFO: org.apache.tajo.util.history.HistoryWriter
(run(275)) - HistoryWriter_asf905.gq1.ygridcore.net_43771 stopped.
2016-02-16 08:31:12,483 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2016-02-16 08:31:12,484 INFO: org.apache.tajo.worker.NodeStatusUpdater
(run(261)) - Heartbeat Thread stopped.
2016-02-16 08:31:12,484 INFO: org.apache.tajo.worker.NodeStatusUpdater
(serviceStop(113)) - NodeStatusUpdater stopped.
2016-02-16 08:31:12,487 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:43773)
shutdown
2016-02-16 08:31:12,487 INFO:
org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) -
QueryMasterManagerService stopped
2016-02-16 08:31:12,487 INFO: org.apache.tajo.querymaster.QueryMaster
(run(432)) - QueryMaster heartbeat thread stopped
2016-02-16 08:31:12,487 INFO: org.apache.tajo.querymaster.QueryMaster
(serviceStop(164)) - QueryMaster stopped
2016-02-16 08:31:12,488 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(98)) - TajoWorkerClientService stopping
2016-02-16 08:31:12,488 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (QueryMasterClientProtocol) listened on
0:0:0:0:0:0:0:0:43772) shutdown
2016-02-16 08:31:12,488 INFO: org.apache.tajo.worker.TajoWorkerClientService
(stop(102)) - TajoWorkerClientService stopped
2016-02-16 08:31:12,488 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:43771)
shutdown
2016-02-16 08:31:12,488 INFO: org.apache.tajo.worker.TajoWorkerManagerService
(serviceStop(93)) - TajoWorkerManagerService stopped
2016-02-16 08:31:12,489 INFO: org.apache.tajo.worker.TajoWorker
(serviceStop(332)) - TajoWorker main thread exiting
2016-02-16 08:31:14,099 INFO: BlockStateChange (invalidateWorkForOneNode(3482))
- BLOCK* BlockManager: ask 127.0.0.1:54654 to delete [blk_1073749135_8311]
2016-02-16 08:31:14,692 INFO: org.apache.tajo.master.TajoMaster (run(568)) -
============================================
2016-02-16 08:31:14,692 INFO: org.apache.tajo.master.TajoMaster (run(569)) -
TajoMaster received SIGINT Signal
2016-02-16 08:31:14,692 INFO: org.apache.tajo.master.TajoMaster (run(570)) -
============================================
2016-02-16 08:31:14,693 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (Tajo-REST) listened on 127.0.0.1:43770) shutdown
2016-02-16 08:31:14,693 INFO: org.apache.tajo.ws.rs.TajoRestService
(serviceStop(129)) - Tajo Rest Service stopped.
2016-02-16 08:31:14,699 INFO: org.apache.tajo.util.history.HistoryWriter
(run(275)) - HistoryWriter_127.0.0.1_43769 stopped.
2016-02-16 08:31:14,701 INFO: BlockStateChange (logAddStoredBlock(2621)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:54654 is added to
blk_1073741838_1014{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-2157c584-9f3f-45a5-8808-c326d98e5a84:NORMAL:127.0.0.1:54654|RBW]]}
size 700
2016-02-16 08:31:14,702 INFO: org.apache.tajo.util.history.HistoryCleaner
(run(136)) - History cleaner stopped
2016-02-16 08:31:14,703 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:43769)
shutdown
2016-02-16 08:31:16,806 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:43768)
shutdown
2016-02-16 08:31:16,807 INFO: org.apache.tajo.catalog.CatalogServer
(serviceStop(182)) - Catalog Server (127.0.0.1:43767) shutdown
2016-02-16 08:31:16,808 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (CatalogProtocol) listened on 127.0.0.1:43767) shutdown
2016-02-16 08:31:16,808 INFO: org.apache.tajo.catalog.store.DerbyStore
(close(2978)) - Close database
(jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/b6a9097d-5068-48e2-a0f0-26c7dc70001c/db;create=true)>
2016-02-16 08:31:16,808 INFO: org.apache.tajo.rpc.NettyServerBase
(shutdown(172)) - Rpc (TajoResourceTrackerProtocol) listened on
127.0.0.1:43766) shutdown
2016-02-16 08:31:16,809 INFO: org.apache.tajo.master.TajoMaster
(serviceStop(424)) - Tajo Master main thread exiting
2016-02-16 08:31:16,852 INFO: org.apache.tajo.catalog.store.DerbyStore
(shutdown(68)) - Derby shutdown complete normally.
2016-02-16 08:31:16,852 INFO: org.apache.tajo.catalog.store.DerbyStore
(shutdown(75)) - Shutdown database
Results :
Failed tests:
TestTajoCli.testSelectResultWithNullTrueDeprecated:320->verifySelectResultWithNullTrue:314->assertOutputResult:106->assertOutputResult:110->assertOutputResult:128
expected:<...xecute '\help set'.
[]c_custkey, o_orderk...> but was:<...xecute '\help set'.
[
]c_custkey, o_orderk...>
Tests run: 1733, Failures: 1, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 2.897 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 1.515 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 2.679 s]
[INFO] Tajo Common ....................................... SUCCESS [ 28.440 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 3.152 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 5.096 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 7.292 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 1.272 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [02:24 min]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.453 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 59.869 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 3.121 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [01:04 min]
[INFO] Tajo PullServer ................................... SUCCESS [ 0.969 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.667 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 1.901 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 3.837 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 1.788 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 3.801 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.771 s]
[INFO] Tajo Core ......................................... SUCCESS [ 6.779 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 0.948 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 25.491 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.088 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 0.945 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.007 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 4.145 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 2.917 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 34.786 s]
[INFO] Tajo JDBC storage common .......................... SUCCESS [ 0.838 s]
[INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 0.960 s]
[INFO] Tajo S3 storage ................................... SUCCESS [ 0.251 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 0.990 s]
[INFO] Tajo Distribution ................................. SUCCESS [ 6.370 s]
[INFO] Tajo Core Tests ................................... FAILURE [21:46 min]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 28:57 min
[INFO] Finished at: 2016-02-16T08:31:17+00:00
[INFO] Final Memory: 157M/1665M
[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-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
Updating TAJO-2033
