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/08/06 09:42:44 UTC

Build failed in Jenkins: Tajo-master-build #793

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

Jenkins build is back to normal : Tajo-master-build #796

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/796/changes>

Build failed in Jenkins: Tajo-master-build #795

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/795/changes>

Changes:

[jihoonson] TAJO-1732: During filter push down phase, join conditions are not set properly.

------------------------------------------
[...truncated 986 lines...]
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.MonitorClientHandler userEventTriggered
INFO: Server has not respond [id: 0xdd2bedd3, /127.0.0.1:56545 => /127.0.0.1:23048]
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:23048)]: 
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23048) shutdown
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-7 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23049
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23049) shutdown
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-8 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23050
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.test.impl.DummyProtocolAsyncImpl getNull
INFO: noCallback is called
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.TestAsyncRpc$4 run
INFO: testGetNull retrieved
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23050) shutdown
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-9 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23051
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler userEventTriggered
INFO: Idle connection closed successfully :[id: 0x24632d48, /127.0.0.1:51250 :> /127.0.0.1:23051]
Aug 11, 2015 6:58:42 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler userEventTriggered
INFO: Idle connection closed successfully :[id: 0x5d92d2f6, /127.0.0.1:51253 :> /127.0.0.1:23051]
Aug 11, 2015 6:58:42 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23051) shutdown
Aug 11, 2015 6:58:42 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-10 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:42 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23052
Aug 11, 2015 6:58:42 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23052) shutdown
Aug 11, 2015 6:58:42 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :localhost/127.0.0.1:23052
Aug 11, 2015 6:58:43 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(localhost/127.0.0.1:23052)]: ClosedChannelException: 
java.nio.channels.ClosedChannelException

Aug 11, 2015 6:58:43 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-11 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:43 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23053
Aug 11, 2015 6:58:43 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23053) shutdown
Aug 11, 2015 6:58:43 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :localhost/127.0.0.1:23053
Aug 11, 2015 6:58:44 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(localhost/127.0.0.1:23053)]: ClosedChannelException: 
java.nio.channels.ClosedChannelException

Aug 11, 2015 6:58:44 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-12 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:44 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23054
Aug 11, 2015 6:58:45 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23054) shutdown
Aug 11, 2015 6:58:45 AM org.apache.tajo.rpc.NettyClientBase doReconnect
WARNING: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:37559)]: ConnectException: Connection refused: /127.0.0.1:37559 Try to reconnect : /127.0.0.1:37559
Aug 11, 2015 6:58:46 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-13 ServerSocketChannelFactory. Worker:2
Aug 11, 2015 6:58:46 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:37559
Aug 11, 2015 6:58:46 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:37559) shutdown
Aug 11, 2015 6:58:46 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-14 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:46 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23055
Aug 11, 2015 6:58:49 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23055) shutdown
Aug 11, 2015 6:58:49 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-15 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:49 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23056
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23056) shutdown
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-16 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23057
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23057) shutdown
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-17 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23058
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23058) shutdown
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-18 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23059
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:23059
Aug 11, 2015 6:58:51 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:23059
Aug 11, 2015 6:58:52 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:23059
Aug 11, 2015 6:58:53 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:23059
Aug 11, 2015 6:58:54 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:23059
Aug 11, 2015 6:58:55 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23059) shutdown
Aug 11, 2015 6:58:55 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-19 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:55 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23060
Aug 11, 2015 6:58:58 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler userEventTriggered
INFO: Idle connection closed successfully :[id: 0xe3855141, /127.0.0.1:39999 :> /127.0.0.1:23060]
Aug 11, 2015 6:58:58 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23060) shutdown
Aug 11, 2015 6:58:58 AM org.apache.tajo.rpc.NettyClientBase doReconnect
WARNING: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:52752)]: ConnectException: Connection refused: /127.0.0.1:52752 Try to reconnect : /127.0.0.1:52752
Aug 11, 2015 6:58:59 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:52752
Aug 11, 2015 6:59:00 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:52752
Aug 11, 2015 6:59:01 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:52752)]: ClosedChannelException: 
java.nio.channels.ClosedChannelException

Tests run: 17, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 81.5 sec <<< FAILURE! - in org.apache.tajo.rpc.TestAsyncRpc
testUnresolvedAddress(org.apache.tajo.rpc.TestAsyncRpc)  Time elapsed: 60.037 sec  <<< ERROR!
java.lang.Exception: test timed out after 60000 milliseconds
	at java.lang.Object.wait(Native Method)
	at java.lang.Object.wait(Object.java:503)
	at io.netty.util.concurrent.DefaultPromise.awaitUninterruptibly(DefaultPromise.java:286)
	at io.netty.channel.DefaultChannelPromise.awaitUninterruptibly(DefaultChannelPromise.java:135)
	at io.netty.channel.DefaultChannelPromise.awaitUninterruptibly(DefaultChannelPromise.java:28)
	at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:180)
	at org.apache.tajo.rpc.TestAsyncRpc.testUnresolvedAddress(TestAsyncRpc.java:415)

Running org.apache.tajo.rpc.TestBlockingRpc
Aug 11, 2015 7:00:01 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-20 ServerSocketChannelFactory. Worker:2
Aug 11, 2015 7:00:01 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23061
Aug 11, 2015 7:00:01 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23061) shutdown
Aug 11, 2015 7:00:01 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-21 ServerSocketChannelFactory. Worker:2
Aug 11, 2015 7:00:01 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23062
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.MonitorClientHandler userEventTriggered
INFO: Server has not respond [id: 0x6ff97001, /127.0.0.1:47206 => /127.0.0.1:23062]
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:23062)]: 
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23062) shutdown
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-22 ServerSocketChannelFactory. Worker:2
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23063
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23063) shutdown
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-23 ServerSocketChannelFactory. Worker:2
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23064
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.test.impl.DummyProtocolBlockingImpl getNull
INFO: noCallback is called
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23064) shutdown
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-24 ServerSocketChannelFactory. Worker:2
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23065
Aug 11, 2015 7:00:03 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler userEventTriggered
INFO: Idle connection closed successfully :[id: 0xdcbf7c40, /127.0.0.1:55429 :> /127.0.0.1:23065]
Aug 11, 2015 7:00:03 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler userEventTriggered
INFO: Idle connection closed successfully :[id: 0x6af16b28, /127.0.0.1:55430 :> /127.0.0.1:23065]
Aug 11, 2015 7:00:04 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23065) shutdown
Aug 11, 2015 7:00:04 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-25 ServerSocketChannelFactory. Worker:2
Aug 11, 2015 7:00:04 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23066
Aug 11, 2015 7:00:04 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23066) shutdown
Aug 11, 2015 7:00:04 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :localhost/127.0.0.1:23066
Killed

Results :

Tests in error: 
  TestAsyncRpc.testUnresolvedAddress:415->Object.wait:503->Object.wait:-2 »  tes...

Tests run: 21, Failures: 0, Errors: 1, Skipped: 0

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Tajo Main ......................................... SUCCESS [  1.860 s]
[INFO] Tajo Project POM .................................. SUCCESS [  1.648 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [  3.048 s]
[INFO] Tajo Common ....................................... SUCCESS [ 30.718 s]
[INFO] Tajo Algebra ...................................... SUCCESS [  2.778 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [  4.786 s]
[INFO] Tajo Plan ......................................... SUCCESS [  6.012 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [  1.452 s]
[INFO] Tajo Protocol Buffer Rpc .......................... FAILURE [01:26 min]
[INFO] Tajo Catalog Client ............................... SKIPPED
[INFO] Tajo Catalog Server ............................... SKIPPED
[INFO] Tajo Storage Common ............................... SKIPPED
[INFO] Tajo HDFS Storage ................................. SKIPPED
[INFO] Tajo HBase Storage ................................ SKIPPED
[INFO] Tajo PullServer ................................... SKIPPED
[INFO] Tajo Client ....................................... SKIPPED
[INFO] Tajo CLI tools .................................... SKIPPED
[INFO] Tajo JDBC Driver .................................. SKIPPED
[INFO] ASM (thirdparty) .................................. SKIPPED
[INFO] Tajo RESTful Container ............................ SKIPPED
[INFO] Tajo Metrics ...................................... SKIPPED
[INFO] Tajo Core ......................................... SKIPPED
[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: 02:19 min
[INFO] Finished at: 2015-08-11T07:00:04+00:00
[INFO] Final Memory: 54M/417M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on project tajo-rpc-protobuf: 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-master-build/ws/tajo-rpc/tajo-rpc-protobuf> && /home/jenkins/tools/java/jdk1.7.0_25-32/jre/bin/java -jar <https://builds.apache.org/job/Tajo-master-build/ws/tajo-rpc/tajo-rpc-protobuf/target/surefire/surefirebooter5824649887925705563.jar> <https://builds.apache.org/job/Tajo-master-build/ws/tajo-rpc/tajo-rpc-protobuf/target/surefire/surefire1808504733469701204tmp> <https://builds.apache.org/job/Tajo-master-build/ws/tajo-rpc/tajo-rpc-protobuf/target/surefire/surefire_42228009705234460076tmp>
[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-rpc-protobuf
Build step 'Execute shell' marked build as failure
Updating TAJO-1732

Build failed in Jenkins: Tajo-master-build #794

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/794/changes>

Changes:

[jhkim] TAJO-1743: Improve calculation of intermediate table statistics.

------------------------------------------
[...truncated 877 lines...]
	at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:180)
	at org.apache.tajo.rpc.TestBlockingRpc.testUnresolvedAddress(TestBlockingRpc.java:376)

Running org.apache.tajo.rpc.TestAsyncRpc
Aug 10, 2015 5:32:45 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-15 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:45 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10188
Aug 10, 2015 5:32:45 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10188) shutdown
Aug 10, 2015 5:32:45 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-16 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:45 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10189
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.MonitorClientHandler userEventTriggered
INFO: Server has not respond [id: 0x0efd6ae4, /127.0.0.1:42392 => /127.0.0.1:10189]
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:10189)]: 
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10189) shutdown
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-17 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10190
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10190) shutdown
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-18 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10191
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.test.impl.DummyProtocolAsyncImpl getNull
INFO: noCallback is called
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.TestAsyncRpc$4 run
INFO: testGetNull retrieved
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10191) shutdown
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-19 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10192
Aug 10, 2015 5:32:47 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler userEventTriggered
INFO: Idle connection closed successfully :[id: 0xffa7196e, /127.0.0.1:40379 :> /127.0.0.1:10192]
Aug 10, 2015 5:32:47 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler userEventTriggered
INFO: Idle connection closed successfully :[id: 0xccaae2d9, /127.0.0.1:40390 :> /127.0.0.1:10192]
Aug 10, 2015 5:32:47 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10192) shutdown
Aug 10, 2015 5:32:47 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-20 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:47 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10193
Aug 10, 2015 5:32:47 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10193) shutdown
Aug 10, 2015 5:32:47 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :localhost/127.0.0.1:10193
Aug 10, 2015 5:32:48 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(localhost/127.0.0.1:10193)]: ClosedChannelException: 
java.nio.channels.ClosedChannelException

Aug 10, 2015 5:32:48 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-21 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:48 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10194
Aug 10, 2015 5:32:48 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10194) shutdown
Aug 10, 2015 5:32:48 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :localhost/127.0.0.1:10194
Aug 10, 2015 5:32:49 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(localhost/127.0.0.1:10194)]: ClosedChannelException: 
java.nio.channels.ClosedChannelException

Aug 10, 2015 5:32:49 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-22 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:49 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10195
Aug 10, 2015 5:32:50 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10195) shutdown
Aug 10, 2015 5:32:50 AM org.apache.tajo.rpc.NettyClientBase doReconnect
WARNING: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:52610)]: ConnectException: Connection refused: /127.0.0.1:52610 Try to reconnect : /127.0.0.1:52610
Aug 10, 2015 5:32:51 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-23 ServerSocketChannelFactory. Worker:2
Aug 10, 2015 5:32:51 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:52610
Aug 10, 2015 5:32:51 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:52610) shutdown
Aug 10, 2015 5:32:51 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-24 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:51 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10196
Aug 10, 2015 5:32:54 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10196) shutdown
Aug 10, 2015 5:32:54 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-25 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:54 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10197
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10197) shutdown
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-26 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10198
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10198) shutdown
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-27 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10199
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10199) shutdown
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-28 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10200
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:10200
Aug 10, 2015 5:32:56 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:10200
Aug 10, 2015 5:32:57 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:10200
Aug 10, 2015 5:32:58 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:10200
Aug 10, 2015 5:32:59 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:10200
Aug 10, 2015 5:33:00 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10200) shutdown
Aug 10, 2015 5:33:00 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-29 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:33:00 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10201
Aug 10, 2015 5:33:03 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler userEventTriggered
INFO: Idle connection closed successfully :[id: 0xb8d1e00c, /127.0.0.1:57025 :> /127.0.0.1:10201]
Aug 10, 2015 5:33:03 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10201) shutdown
Aug 10, 2015 5:33:03 AM org.apache.tajo.rpc.NettyClientBase doReconnect
WARNING: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:50673)]: ConnectException: Connection refused: /127.0.0.1:50673 Try to reconnect : /127.0.0.1:50673
Aug 10, 2015 5:33:04 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:50673
Aug 10, 2015 5:33:05 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:50673
Aug 10, 2015 5:33:06 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:50673)]: ClosedChannelException: 
java.nio.channels.ClosedChannelException

Tests run: 17, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 81.387 sec <<< FAILURE! - in org.apache.tajo.rpc.TestAsyncRpc
testUnresolvedAddress(org.apache.tajo.rpc.TestAsyncRpc)  Time elapsed: 60.003 sec  <<< ERROR!
java.lang.Exception: test timed out after 60000 milliseconds
	at java.lang.Object.wait(Native Method)
	at java.lang.Object.wait(Object.java:503)
	at io.netty.util.concurrent.DefaultPromise.awaitUninterruptibly(DefaultPromise.java:286)
	at io.netty.channel.DefaultChannelPromise.awaitUninterruptibly(DefaultChannelPromise.java:135)
	at io.netty.channel.DefaultChannelPromise.awaitUninterruptibly(DefaultChannelPromise.java:28)
	at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:180)
	at org.apache.tajo.rpc.TestAsyncRpc.testUnresolvedAddress(TestAsyncRpc.java:415)

Running org.apache.tajo.rpc.TestRpcClientManager
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-30 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10202
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10202) shutdown
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.RpcClientManager close
INFO: Closing RPC client manager
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-31 ServerSocketChannelFactory. Worker:50
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10203
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10203) shutdown
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.RpcClientManager close
INFO: Closing RPC client manager
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-32 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10204
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.RpcClientManager close
INFO: Closing RPC client manager
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10204) shutdown
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-33 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10205
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10205) shutdown
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.RpcClientManager close
INFO: Closing RPC client manager
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.092 sec - in org.apache.tajo.rpc.TestRpcClientManager

Results :

Tests in error: 
  TestBlockingRpc.testUnresolvedAddress:376->Object.wait:503->Object.wait:-2 »  ...
  TestAsyncRpc.testUnresolvedAddress:415->Object.wait:503->Object.wait:-2 »  tes...

Tests run: 37, Failures: 0, Errors: 2, Skipped: 0

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Tajo Main ......................................... SUCCESS [  3.169 s]
[INFO] Tajo Project POM .................................. SUCCESS [  2.674 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [  5.066 s]
[INFO] Tajo Common ....................................... SUCCESS [ 41.642 s]
[INFO] Tajo Algebra ...................................... SUCCESS [  2.926 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [  7.707 s]
[INFO] Tajo Plan ......................................... SUCCESS [  8.075 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [  0.792 s]
[INFO] Tajo Protocol Buffer Rpc .......................... FAILURE [02:49 min]
[INFO] Tajo Catalog Client ............................... SKIPPED
[INFO] Tajo Catalog Server ............................... SKIPPED
[INFO] Tajo Storage Common ............................... SKIPPED
[INFO] Tajo HDFS Storage ................................. SKIPPED
[INFO] Tajo HBase Storage ................................ SKIPPED
[INFO] Tajo PullServer ................................... SKIPPED
[INFO] Tajo Client ....................................... SKIPPED
[INFO] Tajo CLI tools .................................... SKIPPED
[INFO] Tajo JDBC Driver .................................. SKIPPED
[INFO] ASM (thirdparty) .................................. SKIPPED
[INFO] Tajo RESTful Container ............................ SKIPPED
[INFO] Tajo Metrics ...................................... SKIPPED
[INFO] Tajo Core ......................................... SKIPPED
[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: 04:03 min
[INFO] Finished at: 2015-08-10T05:34:07+00:00
[INFO] Final Memory: 54M/452M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on project tajo-rpc-protobuf: There are test failures.
[ERROR] 
[ERROR] Please refer to <https://builds.apache.org/job/Tajo-master-build/ws/tajo-rpc/tajo-rpc-protobuf/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-rpc-protobuf
Build step 'Execute shell' marked build as failure
Updating TAJO-1743