See <https://builds.apache.org/job/Tajo-master-build/793/changes>
Changes: [hyunsik] TAJO-1735: Implement MetadataProvider and LinkedMetadataManager. ------------------------------------------ [...truncated 675590 lines...] 2015-08-06 07:43:24,000 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(119)) - Tajo Root Dir: hdfs://localhost:39237/tajo 2015-08-06 07:43:24,000 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(120)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/e0158a97-3c76-41bd-a49c-718229a3c3e5/tajo-localdir> 2015-08-06 07:43:24,001 INFO: org.apache.tajo.worker.TaskManager (handle(182)) - Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:1000, Disks:2, vCores:2) 2015-08-06 07:43:24,002 INFO: org.apache.tajo.worker.TaskImpl (<init>(114)) - Task basedir is created (<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/e0158a97-3c76-41bd-a49c-718229a3c3e5/tajo-localdir/q_1438845643467_1979/output/1)> 2015-08-06 07:43:24,002 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(142)) - Query status of ta_1438845643467_1979_000001_000000_00 is changed to TA_PENDING 2015-08-06 07:43:24,002 INFO: org.apache.tajo.worker.TaskImpl (init(195)) - Initializing: ta_1438845643467_1979_000001_000000_00 2015-08-06 07:43:24,002 INFO: org.apache.tajo.storage.FileTablespace (getAppenderFilePath(310)) - Output File Path: hdfs://localhost:39237/tmp/tajo-jenkins/staging/q_1438845643467_1979/RESULT/part-01-000000-000 2015-08-06 07:43:24,002 INFO: org.apache.tajo.worker.TaskImpl (initPlan(145)) - Output File Path: hdfs://localhost:39237/tmp/tajo-jenkins/staging/q_1438845643467_1979/RESULT/part-01-000000-000 2015-08-06 07:43:24,002 INFO: org.apache.tajo.worker.TaskImpl (initPlan(150)) - ================================== 2015-08-06 07:43:24,002 INFO: org.apache.tajo.worker.TaskImpl (initPlan(151)) - * Stage ta_1438845643467_1979_000001_000000_00 is initialized 2015-08-06 07:43:24,002 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - * InterQuery: false, Fragments (num: 1), Fetches (total:0) : 2015-08-06 07:43:24,002 INFO: org.apache.tajo.worker.TaskImpl (initPlan(162)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/e0158a97-3c76-41bd-a49c-718229a3c3e5/tajo-localdir/q_1438845643467_1979/output/1/0_0> 2015-08-06 07:43:24,002 INFO: org.apache.tajo.worker.TaskImpl (initPlan(167)) - ================================== 2015-08-06 07:43:24,003 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(142)) - Query status of ta_1438845643467_1979_000001_000000_00 is changed to TA_RUNNING 2015-08-06 07:43:24,014 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:44234 is added to blk_1073748254_7430{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-c9e3e6df-0593-4d6d-a870-4f2ffff1d062:NORMAL:127.0.0.1:44234|RBW]]} size 0 2015-08-06 07:43:24,015 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(142)) - Query status of ta_1438845643467_1979_000001_000000_00 is changed to TA_SUCCEEDED 2015-08-06 07:43:24,015 INFO: org.apache.tajo.worker.TaskImpl (run(460)) - ta_1438845643467_1979_000001_000000_00 completed. Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0 2015-08-06 07:43:24,015 INFO: org.apache.tajo.querymaster.Stage (transition(1297)) - Stage - eb_1438845643467_1979_000001 finalize NONE_SHUFFLE (total=1, success=1, killed=0) 2015-08-06 07:43:24,016 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(158)) - Task Scheduler stopped 2015-08-06 07:43:24,016 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(139)) - TaskScheduler schedulingThread stopped 2015-08-06 07:43:24,016 INFO: org.apache.tajo.querymaster.Stage (transition(1355)) - Stage completed - eb_1438845643467_1979_000001 (total=1, success=1, killed=0) 2015-08-06 07:43:24,016 INFO: org.apache.tajo.querymaster.Query (handle(860)) - Processing q_1438845643467_1979 of type STAGE_COMPLETED 2015-08-06 07:43:24,016 INFO: org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next executable block eb_1438845643467_1979_000002 2015-08-06 07:43:24,016 INFO: org.apache.tajo.querymaster.Query (transition(807)) - Complete Stage[eb_1438845643467_1979_000001], State: SUCCEEDED, 1/1. 2015-08-06 07:43:24,017 INFO: org.apache.tajo.querymaster.Query (handle(860)) - Processing q_1438845643467_1979 of type QUERY_COMPLETED 2015-08-06 07:43:24,017 INFO: org.apache.tajo.worker.TaskManager (stopExecutionBlock(161)) - Stopped execution block:eb_1438845643467_1979_000001 2015-08-06 07:43:24,018 INFO: org.apache.tajo.querymaster.Query (finalizeQuery(532)) - Can't find partitions for adding. 2015-08-06 07:43:24,018 INFO: org.apache.tajo.querymaster.Query (handle(878)) - q_1438845643467_1979 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED 2015-08-06 07:43:24,018 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(294)) - Query completion notified from q_1438845643467_1979 final state: QUERY_SUCCEEDED 2015-08-06 07:43:24,018 INFO: org.apache.tajo.master.QueryInProgress (heartbeat(252)) - Received QueryMaster heartbeat:q_1438845643467_1979,state=QUERY_SUCCEEDED,progress=1.0, queryMaster=asf905.gq1.ygridcore.net 2015-08-06 07:43:24,019 INFO: org.apache.tajo.master.QueryManager (stopQuery(275)) - Stop QueryInProgress:q_1438845643467_1979 2015-08-06 07:43:24,019 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(117)) - ========================================================= 2015-08-06 07:43:24,019 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(118)) - Stop query:q_1438845643467_1979 2015-08-06 07:43:24,018 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(171)) - Stopping QueryMasterTask:q_1438845643467_1979 2015-08-06 07:43:24,019 INFO: org.apache.tajo.querymaster.QueryMasterTask (cleanupQuery(470)) - Cleanup resources of all workers. Query: q_1438845643467_1979, workers: 1 2015-08-06 07:43:24,020 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(187)) - Stopped QueryMasterTask:q_1438845643467_1979 2015-08-06 07:43:24,232 INFO: org.apache.tajo.master.TajoMasterClientService (getQueryResultData(574)) - Send result to client for 21ed0151-9a86-4e85-badf-1844da7e05d7,q_1438845643467_1979, 2 rows 2015-08-06 07:43:24,233 INFO: org.apache.tajo.master.TajoMasterClientService (getQueryResultData(574)) - Send result to client for 21ed0151-9a86-4e85-badf-1844da7e05d7,q_1438845643467_1979, 0 rows 2015-08-06 07:43:24,235 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session 21ed0151-9a86-4e85-badf-1844da7e05d7 is removed. 2015-08-06 07:43:24,236 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(235)) - SQL: DROP TABLE IF EXISTS "TestTajoJdbc".table1 2015-08-06 07:43:24,236 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(278)) - Non Optimized Query: ----------------------------- Query Block Graph ----------------------------- |-#ROOT ----------------------------- Optimization Log: ----------------------------- 2015-08-06 07:43:24,236 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(280)) - ============================================= 2015-08-06 07:43:24,236 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(281)) - Optimized Query: ----------------------------- Query Block Graph ----------------------------- |-#ROOT ----------------------------- Optimization Log: ----------------------------- 2015-08-06 07:43:24,237 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(282)) - ============================================= 2015-08-06 07:43:24,237 INFO: org.apache.tajo.master.exec.DDLExecutor (dropTable(314)) - relation "TestTajoJdbc.table1" is already exists. 2015-08-06 07:43:24,237 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(235)) - SQL: DROP TABLE IF EXISTS testaltertablepartition 2015-08-06 07:43:24,238 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(278)) - Non Optimized Query: ----------------------------- Query Block Graph ----------------------------- |-#ROOT ----------------------------- Optimization Log: ----------------------------- 2015-08-06 07:43:24,238 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(280)) - ============================================= 2015-08-06 07:43:24,238 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(281)) - Optimized Query: ----------------------------- Query Block Graph ----------------------------- |-#ROOT ----------------------------- Optimization Log: ----------------------------- 2015-08-06 07:43:24,238 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(282)) - ============================================= 2015-08-06 07:43:24,239 INFO: org.apache.tajo.catalog.CatalogServer (dropTable(775)) - relation "TestTajoJdbc.testaltertablepartition" is deleted from the catalog (127.0.0.1:11692) 2015-08-06 07:43:24,239 INFO: org.apache.tajo.master.exec.DDLExecutor (dropTable(332)) - relation "TestTajoJdbc.testaltertablepartition" is dropped. 2015-08-06 07:43:24,240 INFO: org.apache.tajo.master.exec.DDLExecutor (dropDatabase(281)) - database TestTajoJdbc is dropped. 2015-08-06 07:43:24,240 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session 1d51d5cd-9a5f-4fec-bf46-ee17a4e8ac3a is removed. Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 7.111 sec - in org.apache.tajo.jdbc.TestTajoJdbc 2015-08-06 07:43:24,244 INFO: org.apache.tajo.worker.TajoWorker (run(567)) - ============================================ 2015-08-06 07:43:24,244 INFO: org.apache.tajo.worker.TajoWorker (run(568)) - TajoWorker received SIGINT Signal 2015-08-06 07:43:24,244 INFO: org.apache.tajo.worker.TajoWorker (run(569)) - ============================================ 2015-08-06 07:43:24,248 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session 06ee023f-6585-486a-ac40-522b53ce5169 is removed. 2015-08-06 07:43:24,248 INFO: org.mortbay.log (info(67)) - Shutdown hook executing 2015-08-06 07:43:24,248 INFO: org.mortbay.log (info(67)) - Shutdown hook complete 2015-08-06 07:43:24,249 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session 588e9abc-2d78-46e7-a2a3-2372566bdf29 is removed. rds. Aug 6, 2015 7:34:49 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block Aug 6, 2015 7:34:49 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 2 Aug 6, 2015 7:35:11 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26 Aug 6, 2015 7:35:11 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 6, 2015 7:35:11 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 6, 2015 7:35:11 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26 Aug 6, 2015 7:35:11 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 6, 2015 7:35:11 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 6, 2015 7:35:11 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26 Aug 6, 2015 7:35:11 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 6, 2015 7:35:11 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 6, 2015 7:35:11 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26 Aug 6, 2015 7:35:11 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 6, 2015 7:35:11 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 6, 2015 7:35:12 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26 Aug 6, 2015 7:35:12 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 6, 2015 7:35:12 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 1 records. Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 1 records. Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 1 records. Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 1 Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 1 Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 1 Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 1 records. Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 1 Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 1 records. Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block Aug 6, 2015 7:35:13 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 1 Aug 6, 2015 7:35:15 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 212 Aug 6, 2015 7:35:15 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 5 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN_DICTIONARY, BIT_PACKED], dic { 3 entries, 12B raw, 3B comp} Aug 6, 2015 7:35:15 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 123B for [l_shipdate] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 6, 2015 7:35:15 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 123B for [l_shipdate_function] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED] Aug 6, 2015 7:35:16 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 6, 2015 7:35:16 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers Aug 6, 2015 7:35:16 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5 Aug 6, 2015 7:35:16 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 5 records. Aug 6, 2015 7:35:16 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block Aug 6, 2015 7:35:16 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 5 2015-08-06 07:43:24,251 INFO: org.apache.tajo.master.TajoMaster (run(535)) - ============================================ 2015-08-06 07:43:24,251 INFO: org.apache.tajo.master.TajoMaster (run(536)) - TajoMaster received SIGINT Signal 2015-08-06 07:43:24,251 INFO: org.apache.tajo.master.TajoMaster (run(537)) - ============================================ 2015-08-06 07:43:24,255 INFO: org.apache.tajo.util.history.HistoryWriter (run(268)) - HistoryWriter_asf905.gq1.ygridcore.net_11696 stopped. 2015-08-06 07:43:24,255 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2015-08-06 07:43:24,255 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:11695) shutdown 2015-08-06 07:43:24,255 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped. 2015-08-06 07:43:24,256 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(184)) - Catalog Server (127.0.0.1:11692) shutdown 2015-08-06 07:43:24,256 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:11692) shutdown 2015-08-06 07:43:24,260 INFO: org.apache.tajo.util.history.HistoryWriter (run(268)) - HistoryWriter_127.0.0.1_11694 stopped. 2015-08-06 07:43:24,262 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:44234 is added to blk_1073741834_1010{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-c9e3e6df-0593-4d6d-a870-4f2ffff1d062:NORMAL:127.0.0.1:44234|RBW]]} size 590 2015-08-06 07:43:24,264 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2015-08-06 07:43:24,264 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:11694) shutdown 2015-08-06 07:43:24,264 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:11693) shutdown 2015-08-06 07:43:24,267 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:11691) shutdown 2015-08-06 07:43:24,267 INFO: org.apache.tajo.master.TajoMaster (serviceStop(403)) - Tajo Master main thread exiting 2015-08-06 07:43:24,319 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(111)) - NodeStatusUpdater stopped. 2015-08-06 07:43:24,320 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(262)) - Heartbeat Thread stopped. 2015-08-06 07:43:24,321 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:11698) shutdown 2015-08-06 07:43:24,322 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) - QueryMasterManagerService stopped 2015-08-06 07:43:24,322 INFO: org.apache.tajo.querymaster.QueryMaster (run(417)) - QueryMaster heartbeat thread stopped 2015-08-06 07:43:24,324 INFO: org.apache.tajo.querymaster.QueryMaster (serviceStop(168)) - QueryMaster stopped 2015-08-06 07:43:24,324 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(99)) - TajoWorkerClientService stopping 2015-08-06 07:43:24,324 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:11697) shutdown 2015-08-06 07:43:24,324 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(103)) - TajoWorkerClientService stopped 2015-08-06 07:43:24,325 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:11696) shutdown 2015-08-06 07:43:24,325 INFO: org.apache.tajo.worker.TajoWorkerManagerService (serviceStop(93)) - TajoWorkerManagerService stopped 2015-08-06 07:43:24,326 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(377)) - TajoWorker main thread exiting Results : Tests in error: TestTajoClientV2.testExecuteQueryAsyncWithListener:191 ยป test timed out after... Tests run: 1591, Failures: 0, Errors: 1, Skipped: 0 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Tajo Main ......................................... SUCCESS [ 1.820 s] [INFO] Tajo Project POM .................................. SUCCESS [ 1.899 s] [INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.088 s] [INFO] Tajo Common ....................................... SUCCESS [ 31.485 s] [INFO] Tajo Algebra ...................................... SUCCESS [ 1.653 s] [INFO] Tajo Catalog Common ............................... SUCCESS [ 5.273 s] [INFO] Tajo Plan ......................................... SUCCESS [ 5.832 s] [INFO] Tajo Rpc Common ................................... SUCCESS [ 0.475 s] [INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 48.106 s] [INFO] Tajo Catalog Client ............................... SUCCESS [ 1.412 s] [INFO] Tajo Catalog Server ............................... SUCCESS [ 11.586 s] [INFO] Tajo Storage Common ............................... SUCCESS [ 10.476 s] [INFO] Tajo HDFS Storage ................................. SUCCESS [ 49.803 s] [INFO] Tajo HBase Storage ................................ SUCCESS [ 4.333 s] [INFO] Tajo PullServer ................................... SUCCESS [ 1.130 s] [INFO] Tajo Client ....................................... SUCCESS [ 2.283 s] [INFO] Tajo CLI tools .................................... SUCCESS [ 1.406 s] [INFO] Tajo JDBC Driver .................................. SUCCESS [ 3.319 s] [INFO] ASM (thirdparty) .................................. SUCCESS [ 1.097 s] [INFO] Tajo RESTful Container ............................ SUCCESS [ 3.385 s] [INFO] Tajo Metrics ...................................... SUCCESS [ 0.960 s] [INFO] Tajo Core ......................................... FAILURE [23:13 min] [INFO] Tajo RPC .......................................... SKIPPED [INFO] Tajo Catalog Drivers Hive ......................... SKIPPED [INFO] Tajo Catalog Drivers .............................. SKIPPED [INFO] Tajo Catalog ...................................... SKIPPED [INFO] Tajo Storage ...................................... SKIPPED [INFO] Tajo Distribution ................................. SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 26:24 min [INFO] Finished at: 2015-08-06T07:43:25+00:00 [INFO] Final Memory: 66M/376M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on project tajo-core: There are test failures. [ERROR] [ERROR] Please refer to <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/surefire-reports> for the individual test results. [ERROR] -> [Help 1] [ERROR] [ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch. [ERROR] Re-run Maven using the -X switch to enable full debug logging. [ERROR] [ERROR] For more information about the errors and possible solutions, please read the following articles: [ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException [ERROR] [ERROR] After correcting the problems, you can resume the build with the command [ERROR] mvn <goals> -rf :tajo-core Build step 'Execute shell' marked build as failure Updating TAJO-1735
