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 2014/05/23 21:55:54 UTC
Build failed in Jenkins: Tajo-master-build #221
See <https://builds.apache.org/job/Tajo-master-build/221/changes>
Changes:
[hyunsik] TAJO-832: NPE occurs when Exception's message is null in Task. (Hyoungjun Kim via hyunsik)
[hyunsik] TAJO-827: SUM() overflow in the case of INT4. (Hyoungjun Kim via hyunsik)
[jinossy] TAJO-808: Fix pre-commit build failure. (jinho)
[jhjung] TAJO-819: KillQuery does not work for running query on TajoWorker. (jaehwa)
[jihoonson] TAJO-825: Datetime type refactoring. (Hyoungjun Kim via jihoon)
[jihoonson] TAJO-825: Datetime type refactoring. (fixed missing changes)
------------------------------------------
[...truncated 92791 lines...]
2014-05-23 19:52:48,711 INFO: org.apache.tajo.catalog.CatalogServer (createTable(523)) - relation "Jdbc_Test4.table3" is added to the catalog (127.0.0.1:14903)
2014-05-23 19:52:48,712 INFO: org.apache.tajo.master.GlobalEngine (createTableOnPath(686)) - Table Jdbc_Test4.table3 is created (0)
2014-05-23 19:52:48,714 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(121)) - Query: CREATE TABLE "Jdbc_Test4".table4 (age int)
2014-05-23 19:52:48,714 INFO: org.apache.tajo.master.GlobalEngine (buildExpressionFromSql(175)) - hive.query.mode:false
2014-05-23 19:52:48,714 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(482)) - =============================================
2014-05-23 19:52:48,715 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(483)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2014-05-23 19:52:48,715 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(484)) - =============================================
2014-05-23 19:52:48,718 INFO: org.apache.tajo.catalog.CatalogServer (createTable(523)) - relation "Jdbc_Test4.table4" is added to the catalog (127.0.0.1:14903)
2014-05-23 19:52:48,719 INFO: org.apache.tajo.master.GlobalEngine (createTableOnPath(686)) - Table Jdbc_Test4.table4 is created (0)
2014-05-23 19:52:48,724 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session 94b20d65-b39f-4db6-b15b-ce5511f2ccc3 is removed.
2014-05-23 19:52:48,747 INFO: org.apache.tajo.master.session.SessionManager (createSession(73)) - Session 9d9f555c-6504-414c-b4ec-90a32bd7b8c1 is created.
2014-05-23 19:52:48,748 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session 9d9f555c-6504-414c-b4ec-90a32bd7b8c1 is removed.
2014-05-23 19:52:48,752 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(121)) - Query: DROP TABLE jdbc_test3.table1
2014-05-23 19:52:48,752 INFO: org.apache.tajo.master.GlobalEngine (buildExpressionFromSql(175)) - hive.query.mode:false
2014-05-23 19:52:48,753 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(482)) - =============================================
2014-05-23 19:52:48,753 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(483)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2014-05-23 19:52:48,753 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(484)) - =============================================
2014-05-23 19:52:48,753 INFO: org.apache.tajo.catalog.CatalogServer (dropTable(554)) - relation "jdbc_test3.table1" is deleted from the catalog (127.0.0.1:14903)
2014-05-23 19:52:48,753 INFO: org.apache.tajo.master.GlobalEngine (dropTable(790)) - relation "jdbc_test3.table1" is dropped.
2014-05-23 19:52:48,754 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(121)) - Query: DROP TABLE jdbc_test3.table2
2014-05-23 19:52:48,755 INFO: org.apache.tajo.master.GlobalEngine (buildExpressionFromSql(175)) - hive.query.mode:false
2014-05-23 19:52:48,755 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(482)) - =============================================
2014-05-23 19:52:48,755 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(483)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2014-05-23 19:52:48,755 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(484)) - =============================================
2014-05-23 19:52:48,755 INFO: org.apache.tajo.catalog.CatalogServer (dropTable(554)) - relation "jdbc_test3.table2" is deleted from the catalog (127.0.0.1:14903)
2014-05-23 19:52:48,756 INFO: org.apache.tajo.master.GlobalEngine (dropTable(790)) - relation "jdbc_test3.table2" is dropped.
2014-05-23 19:52:48,757 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(121)) - Query: DROP DATABASE jdbc_test3
2014-05-23 19:52:48,757 INFO: org.apache.tajo.master.GlobalEngine (buildExpressionFromSql(175)) - hive.query.mode:false
2014-05-23 19:52:48,757 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(482)) - =============================================
2014-05-23 19:52:48,757 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(483)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
DROP_DATABASE(1) jdbc_test3
2014-05-23 19:52:48,758 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(484)) - =============================================
2014-05-23 19:52:48,758 INFO: org.apache.tajo.master.GlobalEngine (dropDatabase(743)) - database jdbc_test3 is dropped.
2014-05-23 19:52:48,780 INFO: org.apache.tajo.master.session.SessionManager (createSession(73)) - Session 2bfbf7b8-1577-4718-8088-6d12608e4ccf is created.
2014-05-23 19:52:48,782 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session 2bfbf7b8-1577-4718-8088-6d12608e4ccf is removed.
2014-05-23 19:52:48,786 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(121)) - Query: DROP TABLE "Jdbc_Test4".table3
2014-05-23 19:52:48,786 INFO: org.apache.tajo.master.GlobalEngine (buildExpressionFromSql(175)) - hive.query.mode:false
2014-05-23 19:52:48,786 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(482)) - =============================================
2014-05-23 19:52:48,787 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(483)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2014-05-23 19:52:48,787 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(484)) - =============================================
2014-05-23 19:52:48,787 INFO: org.apache.tajo.catalog.CatalogServer (dropTable(554)) - relation "Jdbc_Test4.table3" is deleted from the catalog (127.0.0.1:14903)
2014-05-23 19:52:48,787 INFO: org.apache.tajo.master.GlobalEngine (dropTable(790)) - relation "Jdbc_Test4.table3" is dropped.
2014-05-23 19:52:48,788 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(121)) - Query: DROP TABLE "Jdbc_Test4".table4
2014-05-23 19:52:48,788 INFO: org.apache.tajo.master.GlobalEngine (buildExpressionFromSql(175)) - hive.query.mode:false
2014-05-23 19:52:48,788 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(482)) - =============================================
2014-05-23 19:52:48,789 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(483)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2014-05-23 19:52:48,789 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(484)) - =============================================
2014-05-23 19:52:48,789 INFO: org.apache.tajo.catalog.CatalogServer (dropTable(554)) - relation "Jdbc_Test4.table4" is deleted from the catalog (127.0.0.1:14903)
2014-05-23 19:52:48,789 INFO: org.apache.tajo.master.GlobalEngine (dropTable(790)) - relation "Jdbc_Test4.table4" is dropped.
2014-05-23 19:52:48,790 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(121)) - Query: DROP DATABASE "Jdbc_Test4"
2014-05-23 19:52:48,790 INFO: org.apache.tajo.master.GlobalEngine (buildExpressionFromSql(175)) - hive.query.mode:false
2014-05-23 19:52:48,791 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(482)) - =============================================
2014-05-23 19:52:48,791 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(483)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
DROP_DATABASE(1) Jdbc_Test4
2014-05-23 19:52:48,791 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(484)) - =============================================
2014-05-23 19:52:48,792 INFO: org.apache.tajo.master.GlobalEngine (dropDatabase(743)) - database Jdbc_Test4 is dropped.
2014-05-23 19:52:48,794 INFO: org.apache.tajo.master.GlobalEngine (dropDatabase(743)) - database TestTajoDatabaseMetaData is dropped.
2014-05-23 19:52:48,794 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session 50cb4a84-8076-415c-b532-3ecbb45218be is removed.
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.086 sec
May 23, 2014 7:51:01 PM INFO: parquet.hadoop.InternalParquetRecordWriter: Flushing mem store to file. allocated memory: 63,754,392
May 23, 2014 7:51:01 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 27B for [l_orderkey] INT32: 5 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, BIT_PACKED, PLAIN_DICTIONARY], dic { 3 entries, 12B raw, 3B comp}
May 23, 2014 7:51:01 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 27B for [l_partkey] INT32: 5 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, BIT_PACKED, PLAIN_DICTIONARY], dic { 3 entries, 12B raw, 3B comp}
May 23, 2014 7:51:01 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_suppkey] INT32: 5 values, 26B raw, 26B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:01 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 26B for [l_linenumber] INT32: 5 values, 9B raw, 9B comp, 1 pages, encodings: [RLE, BIT_PACKED, PLAIN_DICTIONARY], dic { 2 entries, 8B raw, 2B comp}
May 23, 2014 7:51:01 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 63B for [l_quantity] DOUBLE: 5 values, 46B raw, 46B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:01 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 63B for [l_extendedprice] DOUBLE: 5 values, 46B raw, 46B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:01 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 63B for [l_discount] DOUBLE: 5 values, 46B raw, 46B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:01 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 27B for [l_tax] DOUBLE: 5 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, BIT_PACKED, PLAIN_DICTIONARY], dic { 4 entries, 32B raw, 4B comp}
May 23, 2014 7:51:01 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 26B for [l_returnflag] BINARY: 5 values, 9B raw, 9B comp, 1 pages, encodings: [RLE, BIT_PACKED, PLAIN_DICTIONARY], dic { 2 entries, 10B raw, 2B comp}
May 23, 2014 7:51:01 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 26B for [l_linestatus] BINARY: 5 values, 9B raw, 9B comp, 1 pages, encodings: [RLE, BIT_PACKED, PLAIN_DICTIONARY], dic { 2 entries, 10B raw, 2B comp}
May 23, 2014 7:51:01 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 95B for [l_shipdate] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:01 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 95B for [l_commitdate] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:01 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 95B for [l_receiptdate] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:01 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 27B for [l_shipinstruct] BINARY: 5 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, BIT_PACKED, PLAIN_DICTIONARY], dic { 3 entries, 49B raw, 3B comp}
May 23, 2014 7:51:01 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 27B for [l_shipmode] BINARY: 5 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, BIT_PACKED, PLAIN_DICTIONARY], dic { 4 entries, 32B raw, 4B comp}
May 23, 2014 7:51:01 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 190B for [l_comment] BINARY: 5 values, 171B raw, 171B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.InternalParquetRecordWriter: Flushing mem store to file. allocated memory: 63,753,806
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 25B for [l_orderkey] INT32: 2 values, 8B raw, 8B comp, 1 pages, encodings: [RLE, BIT_PACKED, PLAIN_DICTIONARY], dic { 1 entries, 4B raw, 1B comp}
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 31B for [l_partkey] INT32: 2 values, 14B raw, 14B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 31B for [l_suppkey] INT32: 2 values, 14B raw, 14B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 31B for [l_linenumber] INT32: 2 values, 14B raw, 14B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 39B for [l_quantity] DOUBLE: 2 values, 22B raw, 22B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 39B for [l_extendedprice] DOUBLE: 2 values, 22B raw, 22B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 39B for [l_discount] DOUBLE: 2 values, 22B raw, 22B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 25B for [l_tax] DOUBLE: 2 values, 8B raw, 8B comp, 1 pages, encodings: [RLE, BIT_PACKED, PLAIN_DICTIONARY], dic { 1 entries, 8B raw, 1B comp}
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 25B for [l_returnflag] BINARY: 2 values, 8B raw, 8B comp, 1 pages, encodings: [RLE, BIT_PACKED, PLAIN_DICTIONARY], dic { 1 entries, 5B raw, 1B comp}
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 25B for [l_linestatus] BINARY: 2 values, 8B raw, 8B comp, 1 pages, encodings: [RLE, BIT_PACKED, PLAIN_DICTIONARY], dic { 1 entries, 5B raw, 1B comp}
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 51B for [l_shipdate] BINARY: 2 values, 34B raw, 34B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 51B for [l_commitdate] BINARY: 2 values, 34B raw, 34B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 51B for [l_receiptdate] BINARY: 2 values, 34B raw, 34B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 51B for [l_shipinstruct] BINARY: 2 values, 34B raw, 34B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 38B for [l_shipmode] BINARY: 2 values, 21B raw, 21B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 90B for [l_comment] BINARY: 2 values, 71B raw, 71B comp, 1 pages, encodings: [PLAIN, RLE, BIT_PACKED]
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 2 records.
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.InternalParquetRecordReader: block read in memory in 8 ms. row count = 2
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 2 records.
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block
May 23, 2014 7:51:02 PM INFO: parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 2
2014-05-23 19:52:48,803 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-23 19:52:48,807 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-23 19:52:48,809 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session 91efa4cc-64c2-4618-8371-ef29f188152b is removed.
2014-05-23 19:52:48,806 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-23 19:52:48,809 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:14904 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-23 19:52:48,805 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-23 19:52:48,811 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:14904 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-23 19:52:48,810 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session 78091b59-5841-42a6-8fd3-12d8d53358cf is removed.
2014-05-23 19:52:48,810 INFO: org.apache.tajo.worker.TajoWorker (run(492)) - ============================================
2014-05-23 19:52:48,814 INFO: org.apache.tajo.worker.TajoWorker (run(493)) - TajoWorker received SIGINT Signal
2014-05-23 19:52:48,814 INFO: org.apache.tajo.worker.TajoWorker (run(494)) - ============================================
2014-05-23 19:52:48,809 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:14904 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-23 19:52:48,808 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:14904 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-23 19:52:48,820 INFO: org.apache.tajo.worker.WorkerHeartbeatService (run(260)) - Worker Resource Heartbeat Thread stopped.
2014-05-23 19:52:48,842 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:14908) shutdown
2014-05-23 19:52:48,843 INFO: org.apache.tajo.worker.TajoWorkerManagerService (stop(95)) - TajoWorkerManagerService stopped
2014-05-23 19:52:48,846 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:14907) shutdown
2014-05-23 19:52:48,846 INFO: org.apache.tajo.master.querymaster.QueryMasterManagerService (stop(111)) - QueryMasterManagerService stopped
2014-05-23 19:52:48,846 INFO: org.apache.tajo.master.querymaster.QueryMaster (run(434)) - QueryMaster heartbeat thread stopped
2014-05-23 19:52:48,849 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:querymaster_1400874462979
2014-05-23 19:52:48,850 INFO: org.apache.tajo.master.querymaster.QueryMaster (stop(160)) - QueryMaster stop
2014-05-23 19:52:48,850 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(109)) - TajoWorkerClientService stopping
2014-05-23 19:52:48,860 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:14906) shutdown
2014-05-23 19:52:48,860 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(113)) - TajoWorkerClientService stopped
2014-05-23 19:52:48,860 INFO: org.apache.tajo.worker.TajoWorker (stop(334)) - TajoWorker main thread exiting
Results :
Failed tests: testCastFromTable(org.apache.tajo.engine.eval.TestSQLExpression): select col1::timestamp as t1, col2::float from table1 where t1 = '1980-04-01 01:50:01'::timestamp expected:<1980-0[3-31 16]:50:01> but was:<1980-0[4-01 01]:50:01>
Tests run: 747, Failures: 1, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [36.634s]
[INFO] Tajo Project POM .................................. SUCCESS [0.846s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [3.024s]
[INFO] Tajo Common ....................................... SUCCESS [56.582s]
[INFO] Tajo Algebra ...................................... SUCCESS [1.668s]
[INFO] Tajo Catalog Common ............................... SUCCESS [6.044s]
[INFO] Tajo Rpc .......................................... SUCCESS [21.690s]
[INFO] Tajo Catalog Client ............................... SUCCESS [1.181s]
[INFO] Tajo Catalog Server ............................... SUCCESS [7.653s]
[INFO] Tajo Storage ...................................... SUCCESS [58.924s]
[INFO] Tajo Core PullServer .............................. SUCCESS [1.258s]
[INFO] Tajo Client ....................................... SUCCESS [4.480s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [1.022s]
[INFO] Tajo Core ......................................... FAILURE [7:27.777s]
[INFO] Tajo Catalog Drivers .............................. SKIPPED
[INFO] Tajo Catalog ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 10:50.071s
[INFO] Finished at: Fri May 23 19:54:44 UTC 2014
[INFO] Final Memory: 52M/394M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4: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-832
Updating TAJO-808
Updating TAJO-819
Updating TAJO-825
Updating TAJO-827
Jenkins build is back to normal : Tajo-master-build #227
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/227/changes>
Build failed in Jenkins: Tajo-master-build #226
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/226/changes>
Changes:
[hyunsik] TAJO-699: Create a table using LIKE. (Prafulla T via hyunsik)
------------------------------------------
[...truncated 96093 lines...]
Running org.apache.tajo.worker.TestRangeRetrieverHandler
2014-05-27 18:42:44,043 INFO: org.apache.tajo.TajoTestingCluster (startCatalogCluster(241)) - Apache Derby repository is set to jdbc:derby:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d9af8985-5e09-4cca-9807-70bf46fe66af/df5dda93-4b66-4aba-84eb-801a95ffaa49/db>
2014-05-27 18:42:44,044 INFO: org.apache.tajo.catalog.CatalogServer (serviceInit(128)) - Catalog Store Class: org.apache.tajo.catalog.store.MemStore
2014-05-27 18:42:44,044 INFO: org.apache.tajo.rpc.RpcChannelFactory (createServerChannelFactory(81)) - Create CatalogProtocol-79 ServerSocketChannelFactory. Worker:16
2014-05-27 18:42:44,048 INFO: org.apache.tajo.rpc.NettyServerBase (start(106)) - Rpc (CatalogProtocol) listens on /127.0.0.1:41118
2014-05-27 18:42:44,048 INFO: org.apache.tajo.catalog.CatalogServer (start(188)) - Catalog Server startup (127.0.0.1:41118)
2014-05-27 18:42:44,048 INFO: org.apache.tajo.catalog.CatalogServer (createTablespace(232)) - tablespace "default" (<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2)> is created
2014-05-27 18:42:44,049 INFO: org.apache.tajo.catalog.CatalogServer (createDatabase(357)) - database "default" is created
2014-05-27 18:42:44,065 INFO: org.apache.tajo.catalog.CatalogServer (createTable(523)) - relation "default.employee" is added to the catalog (127.0.0.1:41118)
2014-05-27 18:42:44,092 INFO: org.apache.tajo.engine.planner.physical.RangeShuffleFileWriteExec (init(76)) - Output data directory: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output>
2014-05-27 18:42:44,120 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(61)) - [ta_0000000000000_0073_000001_000076_42] Chunks creation time: 5 msec
2014-05-27 18:42:44,471 INFO: BlockStateChange (logAddStoredBlock(2300)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:44821 is added to blk_1073742226_1402{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-670972c4-eda1-4a7e-9012-3cf6895f1846:NORMAL|RBW]]} size 0
2014-05-27 18:42:44,475 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(112)) - Query status of ta_1401215895598_0297_000001_000000_00 is changed to TA_KILLED
2014-05-27 18:42:44,475 INFO: org.apache.tajo.worker.Task (run(450)) - Worker's task counter - total:720, succeeded: 718, killed: 2, failed: 0
2014-05-27 18:42:44,476 ERROR: org.apache.tajo.rpc.AsyncRpcClient (exceptionCaught(220)) - asf000.sp2.ygridcore.net/67.195.138.24:41046,class org.apache.tajo.ipc.QueryMasterProtocol,null
java.nio.channels.ClosedChannelException
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.cleanUpWriteBuffer(AbstractNioWorker.java:434)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.writeFromUserCode(AbstractNioWorker.java:129)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.eventSunk(NioClientSocketPipelineSink.java:84)
at org.jboss.netty.channel.Channels.write(Channels.java:725)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.doEncode(OneToOneEncoder.java:71)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:59)
at org.jboss.netty.channel.Channels.write(Channels.java:725)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.doEncode(OneToOneEncoder.java:71)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:59)
at org.jboss.netty.channel.Channels.write(Channels.java:704)
at org.jboss.netty.channel.Channels.write(Channels.java:671)
at org.jboss.netty.channel.AbstractChannel.write(AbstractChannel.java:248)
at org.apache.tajo.rpc.AsyncRpcClient$ProxyRpcChannel.callMethod(AsyncRpcClient.java:126)
at org.apache.tajo.ipc.QueryMasterProtocol$QueryMasterProtocolService$Stub.statusUpdate(QueryMasterProtocol.java:461)
at org.apache.tajo.worker.Task.run(Task.java:403)
at org.apache.tajo.worker.TaskRunner$1.run(TaskRunner.java:391)
at java.lang.Thread.run(Thread.java:662)
2014-05-27 18:42:44,476 ERROR: org.apache.tajo.rpc.AsyncRpcClient (exceptionCaught(235)) - RPC Exception:java.nio.channels.ClosedChannelException
2014-05-27 18:42:44,476 INFO: org.apache.tajo.worker.TaskRunner (run(332)) - Request GetTask: eb_1401215895598_0297_000001,container_1401215895598_0297_01_001080
2014-05-27 18:42:44,477 INFO: org.apache.tajo.worker.TaskRunner (run(362)) - Received ShouldDie flag:eb_1401215895598_0297_000001,container_1401215895598_0297_01_001080
2014-05-27 18:42:44,477 INFO: org.apache.tajo.worker.TaskRunner (stop(227)) - Stop TaskRunner: eb_1401215895598_0297_000001
2014-05-27 18:42:44,477 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTask(89)) - Stop Task:eb_1401215895598_0297_000001,container_1401215895598_0297_01_001080
2014-05-27 18:42:45,053 INFO: org.apache.tajo.worker.RangeRetrieverHandler (<init>(73)) - BSTIndex is loaded from disk ((0=>9999, 1=>10004), (0=>0, 1=>5)
2014-05-27 18:42:45,054 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>9599, 1=>10004), end=(0=>9999, 1=>10004), last=true)
2014-05-27 18:42:45,054 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=5200, length=-5200) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,054 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>9199, 1=>10004), end=(0=>9599, 1=>10004))
2014-05-27 18:42:45,055 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=10400, length=-5200) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,055 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>8799, 1=>10004), end=(0=>9199, 1=>10004))
2014-05-27 18:42:45,055 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=15600, length=-5200) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,055 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>8399, 1=>10004), end=(0=>8799, 1=>10004))
2014-05-27 18:42:45,056 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=20800, length=-5200) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,056 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>7999, 1=>10004), end=(0=>8399, 1=>10004))
2014-05-27 18:42:45,056 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=25621, length=-4821) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,056 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>7599, 1=>10004), end=(0=>7999, 1=>10004))
2014-05-27 18:42:45,057 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=30021, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,057 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>7199, 1=>10004), end=(0=>7599, 1=>10004))
2014-05-27 18:42:45,057 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=34421, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,057 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>6799, 1=>10004), end=(0=>7199, 1=>10004))
2014-05-27 18:42:45,058 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=38821, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,058 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>6399, 1=>10004), end=(0=>6799, 1=>10004))
2014-05-27 18:42:45,058 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=43221, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,058 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>5999, 1=>10004), end=(0=>6399, 1=>10004))
2014-05-27 18:42:45,059 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=47621, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,059 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>5599, 1=>10004), end=(0=>5999, 1=>10004))
2014-05-27 18:42:45,059 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=52021, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,059 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>5199, 1=>10004), end=(0=>5599, 1=>10004))
2014-05-27 18:42:45,060 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=56421, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,060 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>4799, 1=>10004), end=(0=>5199, 1=>10004))
2014-05-27 18:42:45,060 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=60821, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,060 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>4399, 1=>10004), end=(0=>4799, 1=>10004))
2014-05-27 18:42:45,061 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=65221, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,061 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>3999, 1=>10004), end=(0=>4399, 1=>10004))
2014-05-27 18:42:45,061 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=69621, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,061 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>3599, 1=>10004), end=(0=>3999, 1=>10004))
2014-05-27 18:42:45,062 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=74021, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,062 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>3199, 1=>10004), end=(0=>3599, 1=>10004))
2014-05-27 18:42:45,062 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=78421, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,062 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>2799, 1=>10004), end=(0=>3199, 1=>10004))
2014-05-27 18:42:45,063 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=82821, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,063 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>2399, 1=>10004), end=(0=>2799, 1=>10004))
2014-05-27 18:42:45,063 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=87221, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,063 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>1999, 1=>10004), end=(0=>2399, 1=>10004))
2014-05-27 18:42:45,064 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=91621, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,064 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>1599, 1=>10004), end=(0=>1999, 1=>10004))
2014-05-27 18:42:45,064 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=96021, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,064 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>1199, 1=>10004), end=(0=>1599, 1=>10004))
2014-05-27 18:42:45,065 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=100421, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,065 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>799, 1=>10004), end=(0=>1199, 1=>10004))
2014-05-27 18:42:45,065 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=104821, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,065 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>399, 1=>10004), end=(0=>799, 1=>10004))
2014-05-27 18:42:45,066 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=109221, length=-4400) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,066 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data> (start=(0=>0, 1=>5), end=(0=>399, 1=>10004))
2014-05-27 18:42:45,066 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=113489, length=-4268) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/dd80228e-7983-4fb0-a49c-01cb27e790e2/output/data/data>
2014-05-27 18:42:45,067 INFO: org.apache.tajo.catalog.CatalogServer (stop(193)) - Catalog Server (127.0.0.1:41118) shutdown
2014-05-27 18:42:45,070 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (CatalogProtocol) listened on 127.0.0.1:41118) shutdown
2014-05-27 18:42:45,104 INFO: org.apache.tajo.TajoTestingCluster (startCatalogCluster(241)) - Apache Derby repository is set to jdbc:derby:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d9af8985-5e09-4cca-9807-70bf46fe66af/fdd01a11-f8ca-4abf-9ec5-df644d6e7d99/db>
2014-05-27 18:42:45,105 INFO: org.apache.tajo.catalog.CatalogServer (serviceInit(128)) - Catalog Store Class: org.apache.tajo.catalog.store.MemStore
2014-05-27 18:42:45,105 INFO: org.apache.tajo.rpc.RpcChannelFactory (createServerChannelFactory(81)) - Create CatalogProtocol-80 ServerSocketChannelFactory. Worker:16
2014-05-27 18:42:45,109 INFO: org.apache.tajo.rpc.NettyServerBase (start(106)) - Rpc (CatalogProtocol) listens on /127.0.0.1:41119
2014-05-27 18:42:45,109 INFO: org.apache.tajo.catalog.CatalogServer (start(188)) - Catalog Server startup (127.0.0.1:41119)
2014-05-27 18:42:45,110 INFO: org.apache.tajo.catalog.CatalogServer (createTablespace(232)) - tablespace "default" (<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/c85057fd-f40a-4cc5-9133-67b34c31c114)> is created
2014-05-27 18:42:45,110 INFO: org.apache.tajo.catalog.CatalogServer (createDatabase(357)) - database "default" is created
2014-05-27 18:42:45,126 INFO: org.apache.tajo.catalog.CatalogServer (createTable(523)) - relation "default.employee" is added to the catalog (127.0.0.1:41119)
2014-05-27 18:42:45,154 INFO: org.apache.tajo.engine.planner.physical.RangeShuffleFileWriteExec (init(76)) - Output data directory: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/c85057fd-f40a-4cc5-9133-67b34c31c114/output>
2014-05-27 18:42:45,183 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(61)) - [ta_0000000000000_0074_000001_000077_43] Chunks creation time: 5 msec
2014-05-27 18:42:45,903 INFO: org.apache.tajo.worker.RangeRetrieverHandler (<init>(73)) - BSTIndex is loaded from disk ((0=>0, 1=>5), (0=>9999, 1=>10004)
2014-05-27 18:42:45,903 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/c85057fd-f40a-4cc5-9133-67b34c31c114/output/data/data> (start=(0=>0, 1=>5), end=(0=>1429, 1=>5))
2014-05-27 18:42:45,904 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=0, length=15596) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/c85057fd-f40a-4cc5-9133-67b34c31c114/output/data/data>
2014-05-27 18:42:45,904 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/c85057fd-f40a-4cc5-9133-67b34c31c114/output/data/data> (start=(0=>1429, 1=>5), end=(0=>2858, 1=>5))
2014-05-27 18:42:45,904 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=15596, length=15719) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/c85057fd-f40a-4cc5-9133-67b34c31c114/output/data/data>
2014-05-27 18:42:45,904 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/c85057fd-f40a-4cc5-9133-67b34c31c114/output/data/data> (start=(0=>2858, 1=>5), end=(0=>4287, 1=>5))
2014-05-27 18:42:45,905 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=31315, length=15719) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/c85057fd-f40a-4cc5-9133-67b34c31c114/output/data/data>
2014-05-27 18:42:45,905 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/c85057fd-f40a-4cc5-9133-67b34c31c114/output/data/data> (start=(0=>4287, 1=>5), end=(0=>5716, 1=>5))
2014-05-27 18:42:45,905 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=47034, length=15719) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/c85057fd-f40a-4cc5-9133-67b34c31c114/output/data/data>
2014-05-27 18:42:45,905 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/c85057fd-f40a-4cc5-9133-67b34c31c114/output/data/data> (start=(0=>5716, 1=>5), end=(0=>7145, 1=>5))
2014-05-27 18:42:45,906 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=62753, length=15719) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/c85057fd-f40a-4cc5-9133-67b34c31c114/output/data/data>
2014-05-27 18:42:45,906 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/c85057fd-f40a-4cc5-9133-67b34c31c114/output/data/data> (start=(0=>7145, 1=>5), end=(0=>8574, 1=>5))
2014-05-27 18:42:45,906 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=78472, length=16488) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/c85057fd-f40a-4cc5-9133-67b34c31c114/output/data/data>
2014-05-27 18:42:45,906 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(97)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/c85057fd-f40a-4cc5-9133-67b34c31c114/output/data/data> (start=(0=>8574, 1=>5), end=(0=>9999, 1=>10004), last=true)
2014-05-27 18:42:45,907 INFO: org.apache.tajo.worker.RangeRetrieverHandler (get(160)) - Retrieve File Chunk: (start=94960, length=-94960) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/c85057fd-f40a-4cc5-9133-67b34c31c114/output/data/data>
2014-05-27 18:42:45,907 INFO: org.apache.tajo.catalog.CatalogServer (stop(193)) - Catalog Server (127.0.0.1:41119) shutdown
2014-05-27 18:42:45,909 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (CatalogProtocol) listened on 127.0.0.1:41119) shutdown
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.822 sec
Running org.apache.tajo.worker.TestFetcher
2014-05-27 18:42:46,069 INFO: org.apache.tajo.worker.dataserver.HttpDataServer (start(69)) - HttpDataServer starts up (127.0.0.1:60419)
2014-05-27 18:42:46,071 INFO: org.apache.tajo.worker.Fetcher (get(129)) - Fetch: http://127.0.0.1:60419/data
2014-05-27 18:42:46,073 INFO: org.apache.tajo.worker.dataserver.HttpDataServer (stop(83)) - HttpDataServer shutdown (127.0.0.1:60419)
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec
Running org.apache.tajo.storage.TestFileFragment
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec
Running org.apache.tajo.storage.TestRowFile
2014-05-27 18:42:46,394 WARN: org.apache.tajo.storage.AbstractStorageManager (<init>(86)) - does not support block metadata. ('dfs.datanode.hdfs-blocks-metadata.enabled')
2014-05-27 18:42:46,432 INFO: BlockStateChange (logAddStoredBlock(2300)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:44821 is added to blk_1073742227_1403{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-edc3f15c-5217-47d6-8044-4df592c288e9:NORMAL|RBW]]} size 0
2014-05-27 18:42:46,448 INFO: BlockStateChange (logAddStoredBlock(2300)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:44821 is added to blk_1073742228_1404{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-670972c4-eda1-4a7e-9012-3cf6895f1846:NORMAL|RBW]]} size 0
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.239 sec
2014-05-27 18:42:46,480 INFO: org.apache.tajo.worker.TajoWorker (run(492)) - ============================================
2014-05-27 18:42:46,482 INFO: org.apache.tajo.worker.TajoWorker (run(493)) - TajoWorker received SIGINT Signal
2014-05-27 18:42:46,483 INFO: org.apache.tajo.worker.TajoWorker (run(494)) - ============================================
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.InternalParquetRecordWriter: Flushing mem store to file. allocated memory: 63,754,392
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 27B for [l_orderkey] INT32: 5 values, 10B raw, 10B comp, 1 pages, encodings: [BIT_PACKED, PLAIN_DICTIONARY, RLE], dic { 3 entries, 12B raw, 3B comp}
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 27B for [l_partkey] INT32: 5 values, 10B raw, 10B comp, 1 pages, encodings: [BIT_PACKED, PLAIN_DICTIONARY, RLE], dic { 3 entries, 12B raw, 3B comp}
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_suppkey] INT32: 5 values, 26B raw, 26B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 26B for [l_linenumber] INT32: 5 values, 9B raw, 9B comp, 1 pages, encodings: [BIT_PACKED, PLAIN_DICTIONARY, RLE], dic { 2 entries, 8B raw, 2B comp}
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 63B for [l_quantity] DOUBLE: 5 values, 46B raw, 46B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 63B for [l_extendedprice] DOUBLE: 5 values, 46B raw, 46B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 63B for [l_discount] DOUBLE: 5 values, 46B raw, 46B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 27B for [l_tax] DOUBLE: 5 values, 10B raw, 10B comp, 1 pages, encodings: [BIT_PACKED, PLAIN_DICTIONARY, RLE], dic { 4 entries, 32B raw, 4B comp}
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 26B for [l_returnflag] BINARY: 5 values, 9B raw, 9B comp, 1 pages, encodings: [BIT_PACKED, PLAIN_DICTIONARY, RLE], dic { 2 entries, 10B raw, 2B comp}
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 26B for [l_linestatus] BINARY: 5 values, 9B raw, 9B comp, 1 pages, encodings: [BIT_PACKED, PLAIN_DICTIONARY, RLE], dic { 2 entries, 10B raw, 2B comp}
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 95B for [l_shipdate] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 95B for [l_commitdate] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 95B for [l_receiptdate] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 27B for [l_shipinstruct] BINARY: 5 values, 10B raw, 10B comp, 1 pages, encodings: [BIT_PACKED, PLAIN_DICTIONARY, RLE], dic { 3 entries, 49B raw, 3B comp}
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 27B for [l_shipmode] BINARY: 5 values, 10B raw, 10B comp, 1 pages, encodings: [BIT_PACKED, PLAIN_DICTIONARY, RLE], dic { 4 entries, 32B raw, 4B comp}
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 190B for [l_comment] BINARY: 5 values, 171B raw, 171B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.InternalParquetRecordWriter: Flushing mem store to file. allocated memory: 63,753,806
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 25B for [l_orderkey] INT32: 2 values, 8B raw, 8B comp, 1 pages, encodings: [BIT_PACKED, PLAIN_DICTIONARY, RLE], dic { 1 entries, 4B raw, 1B comp}
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 31B for [l_partkey] INT32: 2 values, 14B raw, 14B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 31B for [l_suppkey] INT32: 2 values, 14B raw, 14B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 31B for [l_linenumber] INT32: 2 values, 14B raw, 14B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 39B for [l_quantity] DOUBLE: 2 values, 22B raw, 22B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 39B for [l_extendedprice] DOUBLE: 2 values, 22B raw, 22B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 39B for [l_discount] DOUBLE: 2 values, 22B raw, 22B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 25B for [l_tax] DOUBLE: 2 values, 8B raw, 8B comp, 1 pages, encodings: [BIT_PACKED, PLAIN_DICTIONARY, RLE], dic { 1 entries, 8B raw, 1B comp}
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 25B for [l_returnflag] BINARY: 2 values, 8B raw, 8B comp, 1 pages, encodings: [BIT_PACKED, PLAIN_DICTIONARY, RLE], dic { 1 entries, 5B raw, 1B comp}
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 25B for [l_linestatus] BINARY: 2 values, 8B raw, 8B comp, 1 pages, encodings: [BIT_PACKED, PLAIN_DICTIONARY, RLE], dic { 1 entries, 5B raw, 1B comp}
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 51B for [l_shipdate] BINARY: 2 values, 34B raw, 34B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 51B for [l_commitdate] BINARY: 2 values, 34B raw, 34B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 51B for [l_receiptdate] BINARY: 2 values, 34B raw, 34B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 51B for [l_shipinstruct] BINARY: 2 values, 34B raw, 34B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 38B for [l_shipmode] BINARY: 2 values, 21B raw, 21B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:45 PM INFO: parquet.hadoop.ColumnChunkPageWriteStore: written 90B for [l_comment] BINARY: 2 values, 71B raw, 71B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
May 27, 2014 6:40:46 PM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
May 27, 2014 6:40:46 PM INFO: parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 2 records.
May 27, 2014 6:40:46 PM INFO: parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block
May 27, 2014 6:40:46 PM INFO: parquet.hadoop.InternalParquetRecordReader: block read in memory in 8 ms. row count = 2
May 27, 2014 6:40:47 PM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
May 27, 2014 6:40:47 PM INFO: parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 2 records.
May 27, 2014 6:40:47 PM INFO: parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block
May 27, 2014 6:40:47 PM INFO: parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 2
2014-05-27 18:42:46,487 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-27 18:42:46,487 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-27 18:42:46,487 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-27 18:42:46,487 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-27 18:42:46,488 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:41043 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-27 18:42:46,488 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:41043 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-27 18:42:46,488 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:41043 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-27 18:42:46,490 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session 43b05dc7-68dc-45b7-8bd0-114465cfe21f is removed.
2014-05-27 18:42:46,489 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:41043 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-27 18:42:46,495 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session fa9aa015-b1cb-480f-a743-a1df84d66bd3 is removed.
2014-05-27 18:42:46,497 INFO: org.apache.tajo.worker.WorkerHeartbeatService (run(260)) - Worker Resource Heartbeat Thread stopped.
2014-05-27 18:42:46,520 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:41047) shutdown
2014-05-27 18:42:46,521 INFO: org.apache.tajo.worker.TajoWorkerManagerService (stop(95)) - TajoWorkerManagerService stopped
2014-05-27 18:42:46,521 INFO: org.apache.tajo.worker.TaskRunner (stop(227)) - Stop TaskRunner: eb_1401215895598_0299_000001
2014-05-27 18:42:46,521 INFO: org.apache.tajo.worker.TaskRunner (stop(227)) - Stop TaskRunner: eb_1401215895598_0303_000001
2014-05-27 18:42:46,523 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:41046) shutdown
2014-05-27 18:42:46,523 INFO: org.apache.tajo.master.querymaster.QueryMasterManagerService (stop(111)) - QueryMasterManagerService stopped
2014-05-27 18:42:46,523 INFO: org.apache.tajo.master.querymaster.QueryMaster (run(434)) - QueryMaster heartbeat thread stopped
2014-05-27 18:42:46,524 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:querymaster_1401215896235
2014-05-27 18:42:46,525 INFO: org.apache.tajo.master.querymaster.QueryMaster (stop(160)) - QueryMaster stop
2014-05-27 18:42:46,525 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(109)) - TajoWorkerClientService stopping
2014-05-27 18:42:46,526 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:41045) shutdown
2014-05-27 18:42:46,526 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(113)) - TajoWorkerClientService stopped
2014-05-27 18:42:46,526 INFO: org.apache.tajo.worker.TajoWorker (stop(334)) - TajoWorker main thread exiting
Results :
Failed tests: testCastFromTable(org.apache.tajo.engine.eval.TestSQLExpression): select col1::timestamp as t1, col2::float from table1 where t1 = '1980-04-01 01:50:01'::timestamp expected:<1980-0[3-31 16]:50:01> but was:<1980-0[4-01 01]:50:01>
Tests run: 756, Failures: 1, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [4.727s]
[INFO] Tajo Project POM .................................. SUCCESS [0.941s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [2.836s]
[INFO] Tajo Common ....................................... SUCCESS [55.141s]
[INFO] Tajo Algebra ...................................... SUCCESS [1.538s]
[INFO] Tajo Catalog Common ............................... SUCCESS [5.337s]
[INFO] Tajo Rpc .......................................... SUCCESS [21.286s]
[INFO] Tajo Catalog Client ............................... SUCCESS [1.039s]
[INFO] Tajo Catalog Server ............................... SUCCESS [7.111s]
[INFO] Tajo Storage ...................................... SUCCESS [54.719s]
[INFO] Tajo Core PullServer .............................. SUCCESS [1.146s]
[INFO] Tajo Client ....................................... SUCCESS [3.845s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [0.618s]
[INFO] Tajo Core ......................................... FAILURE [6:17.683s]
[INFO] Tajo Catalog Drivers .............................. SKIPPED
[INFO] Tajo Catalog ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 8:58.843s
[INFO] Finished at: Tue May 27 18:44:10 UTC 2014
[INFO] Final Memory: 46M/408M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4: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-699
Build failed in Jenkins: Tajo-master-build #225
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/225/changes>
Changes:
[hyunsik] TAJO-834: Add Travis notification to issues@tajo.a.o and IRC. (hyunsik)
------------------------------------------
[...truncated 95681 lines...]
2014-05-27 05:05:02,159 INFO: org.apache.tajo.pullserver.TajoPullServerService (messageReceived(400)) - PullServer baseDir: file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/6ff54dfc-57b8-456d-91bd-3774caf16fb1/tajo-localdir/q_1401166805974_0302/output>
2014-05-27 05:05:02,160 INFO: org.apache.tajo.worker.Task (waitForFetch(354)) - ta_1401166805974_0302_000004_000000_00 All fetches are done!
2014-05-27 05:05:02,161 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createInMemoryHashAggregation(912)) - The planner chooses [Hash Aggregation]
2014-05-27 05:05:02,172 INFO: BlockStateChange (logAddStoredBlock(2300)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:36997 is added to blk_1073742230_1406{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-4ba60913-02d5-426b-9fe1-4366931d2419:NORMAL|RBW]]} size 0
2014-05-27 05:05:02,174 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(112)) - Query status of ta_1401166805974_0302_000004_000000_00 is changed to TA_SUCCEEDED
2014-05-27 05:05:02,174 INFO: org.apache.tajo.worker.Task (run(450)) - Worker's task counter - total:722, succeeded: 718, killed: 4, failed: 0
2014-05-27 05:05:02,174 INFO: org.apache.tajo.worker.TaskRunner (run(332)) - Request GetTask: eb_1401166805974_0302_000004,container_1401166805974_0302_01_001083
2014-05-27 05:05:02,175 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1069)) - [eb_1401166805974_0302_000004] Task Completion Event (Total: 1, Success: 1, Killed: 0, Failed: 0)
2014-05-27 05:05:02,175 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1109)) - subQuery completed - eb_1401166805974_0302_000004 (total=1, success=1, killed=0)
2014-05-27 05:05:02,175 INFO: org.apache.tajo.master.DefaultTaskScheduler (stop(144)) - Task Scheduler stopped
2014-05-27 05:05:02,175 INFO: org.apache.tajo.worker.TaskRunner (run(362)) - Received ShouldDie flag:eb_1401166805974_0302_000004,container_1401166805974_0302_01_001083
2014-05-27 05:05:02,175 INFO: org.apache.tajo.worker.TaskRunner (stop(227)) - Stop TaskRunner: eb_1401166805974_0302_000004
2014-05-27 05:05:02,175 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTask(89)) - Stop Task:eb_1401166805974_0302_000004,container_1401166805974_0302_01_001083
2014-05-27 05:05:02,175 INFO: org.apache.tajo.master.querymaster.Query (handle(682)) - Processing q_1401166805974_0302 of type SUBQUERY_COMPLETED
2014-05-27 05:05:02,175 INFO: org.apache.tajo.master.DefaultTaskScheduler (run(103)) - TaskScheduler schedulingThread stopped
2014-05-27 05:05:02,176 INFO: org.apache.tajo.master.querymaster.Query (handle(682)) - Processing q_1401166805974_0302 of type QUERY_COMPLETED
2014-05-27 05:05:02,177 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(518)) - Release Resource: 0.5,512
2014-05-27 05:05:02,177 INFO: org.apache.tajo.master.querymaster.Query (handle(699)) - q_1401166805974_0302 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED
2014-05-27 05:05:02,177 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (handle(295)) - Query completion notified from q_1401166805974_0302
2014-05-27 05:05:02,177 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (handle(306)) - Query final state: QUERY_SUCCEEDED
2014-05-27 05:05:02,178 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (stop(187)) - Stopping QueryMasterTask:q_1401166805974_0302
2014-05-27 05:05:02,178 INFO: org.apache.tajo.master.querymaster.QueryJobManager (stopQuery(197)) - Stop QueryInProgress:q_1401166805974_0302
2014-05-27 05:05:02,178 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(116)) - =========================================================
2014-05-27 05:05:02,178 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(117)) - Stop query:q_1401166805974_0302
2014-05-27 05:05:02,178 INFO: org.apache.tajo.master.querymaster.QueryInProgress (heartbeat(266)) - Received QueryMaster heartbeat:q_1401166805974_0302,state=QUERY_SUCCEEDED,progress=1.0, queryMaster=asf011.sp2.ygridcore.net
2014-05-27 05:05:02,179 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(518)) - Release Resource: 0.0,512
2014-05-27 05:05:02,179 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (stopQueryMaster(541)) - Released QueryMaster (q_1401166805974_0302) resource:null
2014-05-27 05:05:02,179 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(125)) - q_1401166805974_0302 QueryMaster stopped
2014-05-27 05:05:02,181 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:QueryInProgress:q_1401166805974_0302
2014-05-27 05:05:02,181 WARN: org.apache.tajo.master.TajoAsyncDispatcher (stop(115)) - Interrupted Exception while stopping
2014-05-27 05:05:02,181 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:q_1401166805974_0302
2014-05-27 05:05:02,182 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (stop(215)) - Stopped QueryMasterTask:q_1401166805974_0302
2014-05-27 05:05:02,547 INFO: org.apache.tajo.worker.TajoWorkerClientService (closeQuery(222)) - Stop Query:q_1401166805974_0302
2014-05-27 05:05:02,548 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session 7a65fdbc-058f-4c6d-9560-c649967bc337 is removed.
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 5.643 sec
2014-05-27 05:05:02,552 INFO: org.apache.tajo.worker.TajoWorker (run(492)) - ============================================
2014-05-27 05:05:02,552 INFO: org.apache.tajo.worker.TajoWorker (run(493)) - TajoWorker received SIGINT Signal
2014-05-27 05:05:02,552 INFO: org.apache.tajo.worker.TajoWorker (run(494)) - ============================================
2014-05-27 05:05:02,559 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session 55180c61-39bf-48b6-964d-8af9f0b6b76a is removed.
2014-05-27 05:05:02,560 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session b5f64ac1-68be-4144-9700-9d2f59c03cdb is removed.
May 27, 2014 5:05:02 AM org.jboss.netty.channel.DefaultChannelPipeline
WARNING: An exception was thrown by an exception handler.
java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
at org.jboss.netty.channel.socket.nio.AbstractNioSelector.registerTask(AbstractNioSelector.java:115)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:73)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:57)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioChannelSink.execute(AbstractNioChannelSink.java:34)
at org.jboss.netty.channel.Channels.fireExceptionCaughtLater(Channels.java:496)
at org.jboss.netty.channel.AbstractChannelSink.exceptionCaught(AbstractChannelSink.java:46)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.close(Channels.java:812)
at org.jboss.netty.channel.AbstractChannel.close(AbstractChannel.java:197)
at org.jboss.netty.channel.ChannelFutureListener$2.operationComplete(ChannelFutureListener.java:52)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListener(DefaultChannelFuture.java:427)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListeners(DefaultChannelFuture.java:413)
at org.jboss.netty.channel.DefaultChannelFuture.setFailure(DefaultChannelFuture.java:380)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.connect(NioClientSocketPipelineSink.java:130)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.eventSunk(NioClientSocketPipelineSink.java:70)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.connect(Channels.java:634)
at org.jboss.netty.channel.AbstractChannel.connect(AbstractChannel.java:207)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:229)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:182)
at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:76)
at org.apache.tajo.rpc.NettyClientBase.init(NettyClientBase.java:63)
at org.apache.tajo.rpc.BlockingRpcClient.<init>(BlockingRpcClient.java:81)
at org.apache.tajo.rpc.RpcConnectionPool.makeConnection(RpcConnectionPool.java:66)
at org.apache.tajo.rpc.RpcConnectionPool.getConnection(RpcConnectionPool.java:78)
at org.apache.tajo.client.TajoClient.close(TajoClient.java:136)
at org.apache.tajo.cli.TajoCli$1.run(TajoCli.java:276)
at java.lang.Thread.run(Thread.java:662)
2014-05-27 05:05:02,569 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
May 27, 2014 5:05:02 AM org.jboss.netty.channel.DefaultChannelPipeline
WARNING: An exception was thrown by an exception handler.
java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
at org.jboss.netty.channel.socket.nio.AbstractNioSelector.registerTask(AbstractNioSelector.java:115)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:73)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:57)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioChannelSink.execute(AbstractNioChannelSink.java:34)
at org.jboss.netty.channel.Channels.fireExceptionCaughtLater(Channels.java:496)
at org.jboss.netty.channel.AbstractChannelSink.exceptionCaught(AbstractChannelSink.java:46)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.close(Channels.java:812)
at org.jboss.netty.channel.AbstractChannel.close(AbstractChannel.java:197)
at org.jboss.netty.channel.ChannelFutureListener$2.operationComplete(ChannelFutureListener.java:52)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListener(DefaultChannelFuture.java:427)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListeners(DefaultChannelFuture.java:413)
at org.jboss.netty.channel.DefaultChannelFuture.setFailure(DefaultChannelFuture.java:380)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.connect(NioClientSocketPipelineSink.java:130)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.eventSunk(NioClientSocketPipelineSink.java:70)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.connect(Channels.java:634)
at org.jboss.netty.channel.AbstractChannel.connect(AbstractChannel.java:207)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:229)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:182)
at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:76)
at org.apache.tajo.rpc.NettyClientBase.init(NettyClientBase.java:63)
at org.apache.tajo.rpc.BlockingRpcClient.<init>(BlockingRpcClient.java:81)
at org.apache.tajo.rpc.RpcConnectionPool.makeConnection(RpcConnectionPool.java:66)
at org.apache.tajo.rpc.RpcConnectionPool.getConnection(RpcConnectionPool.java:78)
at org.apache.tajo.client.TajoClient.close(TajoClient.java:136)
at org.apache.tajo.cli.TajoCli$1.run(TajoCli.java:276)
at java.lang.Thread.run(Thread.java:662)
2014-05-27 05:05:02,569 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:32621 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
May 27, 2014 5:05:02 AM org.jboss.netty.channel.DefaultChannelPipeline
WARNING: An exception was thrown by an exception handler.
java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
at org.jboss.netty.channel.socket.nio.AbstractNioSelector.registerTask(AbstractNioSelector.java:115)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:73)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:57)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioChannelSink.execute(AbstractNioChannelSink.java:34)
at org.jboss.netty.channel.Channels.fireExceptionCaughtLater(Channels.java:496)
at org.jboss.netty.channel.AbstractChannelSink.exceptionCaught(AbstractChannelSink.java:46)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.close(Channels.java:812)
at org.jboss.netty.channel.AbstractChannel.close(AbstractChannel.java:197)
at org.jboss.netty.channel.ChannelFutureListener$2.operationComplete(ChannelFutureListener.java:52)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListener(DefaultChannelFuture.java:427)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListeners(DefaultChannelFuture.java:413)
at org.jboss.netty.channel.DefaultChannelFuture.setFailure(DefaultChannelFuture.java:380)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.connect(NioClientSocketPipelineSink.java:130)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.eventSunk(NioClientSocketPipelineSink.java:70)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.connect(Channels.java:634)
at org.jboss.netty.channel.AbstractChannel.connect(AbstractChannel.java:207)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:229)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:182)
at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:76)
at org.apache.tajo.rpc.NettyClientBase.init(NettyClientBase.java:63)
at org.apache.tajo.rpc.BlockingRpcClient.<init>(BlockingRpcClient.java:81)
at org.apache.tajo.rpc.RpcConnectionPool.makeConnection(RpcConnectionPool.java:66)
at org.apache.tajo.rpc.RpcConnectionPool.getConnection(RpcConnectionPool.java:78)
at org.apache.tajo.client.TajoClient.close(TajoClient.java:136)
at org.apache.tajo.cli.TajoCli$1.run(TajoCli.java:276)
at java.lang.Thread.run(Thread.java:662)
2014-05-27 05:05:02,569 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
May 27, 2014 5:05:02 AM org.jboss.netty.channel.DefaultChannelPipeline
WARNING: An exception was thrown by an exception handler.
java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
at org.jboss.netty.channel.socket.nio.AbstractNioSelector.registerTask(AbstractNioSelector.java:115)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:73)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:57)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioChannelSink.execute(AbstractNioChannelSink.java:34)
at org.jboss.netty.channel.Channels.fireExceptionCaughtLater(Channels.java:496)
at org.jboss.netty.channel.AbstractChannelSink.exceptionCaught(AbstractChannelSink.java:46)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.close(Channels.java:812)
at org.jboss.netty.channel.AbstractChannel.close(AbstractChannel.java:197)
at org.jboss.netty.channel.ChannelFutureListener$2.operationComplete(ChannelFutureListener.java:52)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListener(DefaultChannelFuture.java:427)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListeners(DefaultChannelFuture.java:413)
at org.jboss.netty.channel.DefaultChannelFuture.setFailure(DefaultChannelFuture.java:380)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.connect(NioClientSocketPipelineSink.java:130)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.eventSunk(NioClientSocketPipelineSink.java:70)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.connect(Channels.java:634)
at org.jboss.netty.channel.AbstractChannel.connect(AbstractChannel.java:207)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:229)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:182)
at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:76)
at org.apache.tajo.rpc.NettyClientBase.init(NettyClientBase.java:63)
at org.apache.tajo.rpc.BlockingRpcClient.<init>(BlockingRpcClient.java:81)
at org.apache.tajo.rpc.RpcConnectionPool.makeConnection(RpcConnectionPool.java:66)
at org.apache.tajo.rpc.RpcConnectionPool.getConnection(RpcConnectionPool.java:78)
at org.apache.tajo.client.TajoClient.close(TajoClient.java:136)
at org.apache.tajo.cli.TajoCli$1.run(TajoCli.java:276)
at java.lang.Thread.run(Thread.java:662)
2014-05-27 05:05:02,571 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-27 05:05:02,571 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:32621 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-27 05:05:02,571 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:32621 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-27 05:05:02,571 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-27 05:05:02,573 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:32621 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-27 05:05:02,576 INFO: org.apache.tajo.worker.WorkerHeartbeatService (run(260)) - Worker Resource Heartbeat Thread stopped.
2014-05-27 05:05:02,594 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:32625) shutdown
2014-05-27 05:05:02,595 INFO: org.apache.tajo.worker.TajoWorkerManagerService (stop(95)) - TajoWorkerManagerService stopped
2014-05-27 05:05:02,597 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:32624) shutdown
2014-05-27 05:05:02,597 INFO: org.apache.tajo.master.querymaster.QueryMasterManagerService (stop(111)) - QueryMasterManagerService stopped
2014-05-27 05:05:02,597 INFO: org.apache.tajo.master.querymaster.QueryMaster (run(434)) - QueryMaster heartbeat thread stopped
2014-05-27 05:05:02,597 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:querymaster_1401166806641
2014-05-27 05:05:02,604 INFO: org.apache.tajo.master.querymaster.QueryMaster (stop(160)) - QueryMaster stop
2014-05-27 05:05:02,604 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(109)) - TajoWorkerClientService stopping
2014-05-27 05:05:02,606 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:32623) shutdown
2014-05-27 05:05:02,606 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(113)) - TajoWorkerClientService stopped
2014-05-27 05:05:02,606 INFO: org.apache.tajo.worker.TajoWorker (stop(334)) - TajoWorker main thread exiting
Results :
Failed tests: testCastFromTable(org.apache.tajo.engine.eval.TestSQLExpression): select col1::timestamp as t1, col2::float from table1 where t1 = '1980-04-01 01:50:01'::timestamp expected:<1980-0[3-31 16]:50:01> but was:<1980-0[4-01 01]:50:01>
Tests run: 753, Failures: 1, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [6.811s]
[INFO] Tajo Project POM .................................. SUCCESS [0.571s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [2.853s]
[INFO] Tajo Common ....................................... SUCCESS [57.058s]
[INFO] Tajo Algebra ...................................... SUCCESS [1.183s]
[INFO] Tajo Catalog Common ............................... SUCCESS [5.935s]
[INFO] Tajo Rpc .......................................... SUCCESS [21.402s]
[INFO] Tajo Catalog Client ............................... SUCCESS [1.051s]
[INFO] Tajo Catalog Server ............................... SUCCESS [7.358s]
[INFO] Tajo Storage ...................................... SUCCESS [57.228s]
[INFO] Tajo Core PullServer .............................. SUCCESS [0.857s]
[INFO] Tajo Client ....................................... SUCCESS [3.266s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [0.641s]
[INFO] Tajo Core ......................................... FAILURE [6:42.928s]
[INFO] Tajo Catalog Drivers .............................. SKIPPED
[INFO] Tajo Catalog ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 9:30.178s
[INFO] Finished at: Tue May 27 05:06:15 UTC 2014
[INFO] Final Memory: 53M/458M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4: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-834
Build failed in Jenkins: Tajo-master-build #224
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/224/>
------------------------------------------
[...truncated 95805 lines...]
2014-05-26 19:26:05,334 INFO: org.apache.tajo.pullserver.TajoPullServerService (messageReceived(394)) - PullServer request param: shuffleType=h, sid=3, partId=0, taskIds=[0_0]
2014-05-26 19:26:05,334 INFO: org.apache.tajo.pullserver.TajoPullServerService (messageReceived(400)) - PullServer baseDir: file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/845dba17-7474-4e4d-8b58-f5523d97c8cd/tajo-localdir/q_1401132065918_0302/output>
2014-05-26 19:26:05,335 INFO: org.apache.tajo.worker.Task (waitForFetch(354)) - ta_1401132065918_0302_000004_000000_00 All fetches are done!
2014-05-26 19:26:05,336 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createInMemoryHashAggregation(912)) - The planner chooses [Hash Aggregation]
2014-05-26 19:26:05,349 INFO: BlockStateChange (logAddStoredBlock(2300)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:54943 is added to blk_1073742230_1406{blockUCState=COMMITTED, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-a90f9ac8-6132-4507-90ad-62ccb1435e9a:NORMAL|RBW]]} size 19
2014-05-26 19:26:05,760 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(112)) - Query status of ta_1401132065918_0302_000004_000000_00 is changed to TA_SUCCEEDED
2014-05-26 19:26:05,761 INFO: org.apache.tajo.worker.Task (run(450)) - Worker's task counter - total:722, succeeded: 718, killed: 4, failed: 0
2014-05-26 19:26:05,761 INFO: org.apache.tajo.worker.TaskRunner (run(332)) - Request GetTask: eb_1401132065918_0302_000004,container_1401132065918_0302_01_001083
2014-05-26 19:26:05,761 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1069)) - [eb_1401132065918_0302_000004] Task Completion Event (Total: 1, Success: 1, Killed: 0, Failed: 0)
2014-05-26 19:26:05,762 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1109)) - subQuery completed - eb_1401132065918_0302_000004 (total=1, success=1, killed=0)
2014-05-26 19:26:05,762 INFO: org.apache.tajo.master.DefaultTaskScheduler (run(103)) - TaskScheduler schedulingThread stopped
2014-05-26 19:26:05,762 INFO: org.apache.tajo.worker.TaskRunner (run(362)) - Received ShouldDie flag:eb_1401132065918_0302_000004,container_1401132065918_0302_01_001083
2014-05-26 19:26:05,762 INFO: org.apache.tajo.master.DefaultTaskScheduler (stop(144)) - Task Scheduler stopped
2014-05-26 19:26:05,762 INFO: org.apache.tajo.worker.TaskRunner (stop(227)) - Stop TaskRunner: eb_1401132065918_0302_000004
2014-05-26 19:26:05,762 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTask(89)) - Stop Task:eb_1401132065918_0302_000004,container_1401132065918_0302_01_001083
2014-05-26 19:26:05,763 INFO: org.apache.tajo.master.querymaster.Query (handle(682)) - Processing q_1401132065918_0302 of type SUBQUERY_COMPLETED
2014-05-26 19:26:05,763 INFO: org.apache.tajo.master.querymaster.Query (handle(682)) - Processing q_1401132065918_0302 of type QUERY_COMPLETED
2014-05-26 19:26:05,763 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(518)) - Release Resource: 0.5,512
2014-05-26 19:26:05,764 INFO: org.apache.tajo.master.querymaster.Query (handle(699)) - q_1401132065918_0302 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED
2014-05-26 19:26:05,764 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (handle(295)) - Query completion notified from q_1401132065918_0302
2014-05-26 19:26:05,765 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (handle(306)) - Query final state: QUERY_SUCCEEDED
2014-05-26 19:26:05,765 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (stop(187)) - Stopping QueryMasterTask:q_1401132065918_0302
2014-05-26 19:26:05,765 INFO: org.apache.tajo.master.querymaster.QueryJobManager (stopQuery(197)) - Stop QueryInProgress:q_1401132065918_0302
2014-05-26 19:26:05,765 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(116)) - =========================================================
2014-05-26 19:26:05,765 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(117)) - Stop query:q_1401132065918_0302
2014-05-26 19:26:05,765 INFO: org.apache.tajo.master.querymaster.QueryInProgress (heartbeat(266)) - Received QueryMaster heartbeat:q_1401132065918_0302,state=QUERY_SUCCEEDED,progress=1.0, queryMaster=asf011.sp2.ygridcore.net
2014-05-26 19:26:05,766 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(518)) - Release Resource: 0.0,512
2014-05-26 19:26:05,766 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (stopQueryMaster(541)) - Released QueryMaster (q_1401132065918_0302) resource:null
2014-05-26 19:26:05,766 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(125)) - q_1401132065918_0302 QueryMaster stopped
2014-05-26 19:26:05,768 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:QueryInProgress:q_1401132065918_0302
2014-05-26 19:26:05,769 WARN: org.apache.tajo.master.TajoAsyncDispatcher (stop(115)) - Interrupted Exception while stopping
2014-05-26 19:26:05,769 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:q_1401132065918_0302
2014-05-26 19:26:05,769 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (stop(215)) - Stopped QueryMasterTask:q_1401132065918_0302
2014-05-26 19:26:06,219 INFO: org.apache.tajo.worker.TajoWorkerClientService (closeQuery(222)) - Stop Query:q_1401132065918_0302
2014-05-26 19:26:06,220 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session 4c9d50f1-2c56-4149-9ff9-fdb0114a8ca8 is removed.
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 15.044 sec
2014-05-26 19:26:06,224 INFO: org.apache.tajo.worker.TajoWorker (run(492)) - ============================================
2014-05-26 19:26:06,224 INFO: org.apache.tajo.worker.TajoWorker (run(493)) - TajoWorker received SIGINT Signal
2014-05-26 19:26:06,228 INFO: org.apache.tajo.worker.TajoWorker (run(494)) - ============================================
2014-05-26 19:26:06,233 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session bd276172-981c-4bcc-bcfe-23d90e0a71bc is removed.
2014-05-26 19:26:06,235 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session a15616b5-76f3-455c-908c-83606f1fd0bb is removed.
May 26, 2014 7:26:06 PM org.jboss.netty.channel.DefaultChannelPipeline
WARNING: An exception was thrown by an exception handler.
java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
at org.jboss.netty.channel.socket.nio.AbstractNioSelector.registerTask(AbstractNioSelector.java:115)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:73)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:57)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioChannelSink.execute(AbstractNioChannelSink.java:34)
at org.jboss.netty.channel.Channels.fireExceptionCaughtLater(Channels.java:496)
at org.jboss.netty.channel.AbstractChannelSink.exceptionCaught(AbstractChannelSink.java:46)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.close(Channels.java:812)
at org.jboss.netty.channel.AbstractChannel.close(AbstractChannel.java:197)
at org.jboss.netty.channel.ChannelFutureListener$2.operationComplete(ChannelFutureListener.java:52)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListener(DefaultChannelFuture.java:427)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListeners(DefaultChannelFuture.java:413)
at org.jboss.netty.channel.DefaultChannelFuture.setFailure(DefaultChannelFuture.java:380)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.connect(NioClientSocketPipelineSink.java:130)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.eventSunk(NioClientSocketPipelineSink.java:70)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.connect(Channels.java:634)
at org.jboss.netty.channel.AbstractChannel.connect(AbstractChannel.java:207)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:229)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:182)
at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:76)
at org.apache.tajo.rpc.NettyClientBase.init(NettyClientBase.java:63)
at org.apache.tajo.rpc.BlockingRpcClient.<init>(BlockingRpcClient.java:81)
at org.apache.tajo.rpc.RpcConnectionPool.makeConnection(RpcConnectionPool.java:66)
at org.apache.tajo.rpc.RpcConnectionPool.getConnection(RpcConnectionPool.java:78)
at org.apache.tajo.client.TajoClient.close(TajoClient.java:136)
at org.apache.tajo.cli.TajoCli$1.run(TajoCli.java:276)
at java.lang.Thread.run(Thread.java:662)
2014-05-26 19:26:06,241 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-26 19:26:06,241 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:43719 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
May 26, 2014 7:26:06 PM org.jboss.netty.channel.DefaultChannelPipeline
WARNING: An exception was thrown by an exception handler.
java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
at org.jboss.netty.channel.socket.nio.AbstractNioSelector.registerTask(AbstractNioSelector.java:115)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:73)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:57)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioChannelSink.execute(AbstractNioChannelSink.java:34)
at org.jboss.netty.channel.Channels.fireExceptionCaughtLater(Channels.java:496)
at org.jboss.netty.channel.AbstractChannelSink.exceptionCaught(AbstractChannelSink.java:46)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.close(Channels.java:812)
at org.jboss.netty.channel.AbstractChannel.close(AbstractChannel.java:197)
at org.jboss.netty.channel.ChannelFutureListener$2.operationComplete(ChannelFutureListener.java:52)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListener(DefaultChannelFuture.java:427)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListeners(DefaultChannelFuture.java:413)
at org.jboss.netty.channel.DefaultChannelFuture.setFailure(DefaultChannelFuture.java:380)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.connect(NioClientSocketPipelineSink.java:130)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.eventSunk(NioClientSocketPipelineSink.java:70)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.connect(Channels.java:634)
at org.jboss.netty.channel.AbstractChannel.connect(AbstractChannel.java:207)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:229)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:182)
at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:76)
at org.apache.tajo.rpc.NettyClientBase.init(NettyClientBase.java:63)
at org.apache.tajo.rpc.BlockingRpcClient.<init>(BlockingRpcClient.java:81)
at org.apache.tajo.rpc.RpcConnectionPool.makeConnection(RpcConnectionPool.java:66)
at org.apache.tajo.rpc.RpcConnectionPool.getConnection(RpcConnectionPool.java:78)
at org.apache.tajo.client.TajoClient.close(TajoClient.java:136)
at org.apache.tajo.cli.TajoCli$1.run(TajoCli.java:276)
at java.lang.Thread.run(Thread.java:662)
2014-05-26 19:26:06,242 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-26 19:26:06,242 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:43719 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
May 26, 2014 7:26:06 PM org.jboss.netty.channel.DefaultChannelPipeline
WARNING: An exception was thrown by an exception handler.
java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
at org.jboss.netty.channel.socket.nio.AbstractNioSelector.registerTask(AbstractNioSelector.java:115)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:73)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:57)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioChannelSink.execute(AbstractNioChannelSink.java:34)
at org.jboss.netty.channel.Channels.fireExceptionCaughtLater(Channels.java:496)
at org.jboss.netty.channel.AbstractChannelSink.exceptionCaught(AbstractChannelSink.java:46)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.close(Channels.java:812)
at org.jboss.netty.channel.AbstractChannel.close(AbstractChannel.java:197)
at org.jboss.netty.channel.ChannelFutureListener$2.operationComplete(ChannelFutureListener.java:52)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListener(DefaultChannelFuture.java:427)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListeners(DefaultChannelFuture.java:413)
at org.jboss.netty.channel.DefaultChannelFuture.setFailure(DefaultChannelFuture.java:380)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.connect(NioClientSocketPipelineSink.java:130)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.eventSunk(NioClientSocketPipelineSink.java:70)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.connect(Channels.java:634)
at org.jboss.netty.channel.AbstractChannel.connect(AbstractChannel.java:207)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:229)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:182)
at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:76)
at org.apache.tajo.rpc.NettyClientBase.init(NettyClientBase.java:63)
at org.apache.tajo.rpc.BlockingRpcClient.<init>(BlockingRpcClient.java:81)
at org.apache.tajo.rpc.RpcConnectionPool.makeConnection(RpcConnectionPool.java:66)
at org.apache.tajo.rpc.RpcConnectionPool.getConnection(RpcConnectionPool.java:78)
at org.apache.tajo.client.TajoClient.close(TajoClient.java:136)
at org.apache.tajo.cli.TajoCli$1.run(TajoCli.java:276)
at java.lang.Thread.run(Thread.java:662)
May 26, 2014 7:26:06 PM org.jboss.netty.channel.DefaultChannelPipeline
WARNING: An exception was thrown by an exception handler.
java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
at org.jboss.netty.channel.socket.nio.AbstractNioSelector.registerTask(AbstractNioSelector.java:115)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:73)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:57)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioChannelSink.execute(AbstractNioChannelSink.java:34)
at org.jboss.netty.channel.Channels.fireExceptionCaughtLater(Channels.java:496)
at org.jboss.netty.channel.AbstractChannelSink.exceptionCaught(AbstractChannelSink.java:46)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.close(Channels.java:812)
at org.jboss.netty.channel.AbstractChannel.close(AbstractChannel.java:197)
at org.jboss.netty.channel.ChannelFutureListener$2.operationComplete(ChannelFutureListener.java:52)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListener(DefaultChannelFuture.java:427)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListeners(DefaultChannelFuture.java:413)
at org.jboss.netty.channel.DefaultChannelFuture.setFailure(DefaultChannelFuture.java:380)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.connect(NioClientSocketPipelineSink.java:130)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.eventSunk(NioClientSocketPipelineSink.java:70)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.connect(Channels.java:634)
at org.jboss.netty.channel.AbstractChannel.connect(AbstractChannel.java:207)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:229)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:182)
at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:76)
at org.apache.tajo.rpc.NettyClientBase.init(NettyClientBase.java:63)
at org.apache.tajo.rpc.BlockingRpcClient.<init>(BlockingRpcClient.java:81)
at org.apache.tajo.rpc.RpcConnectionPool.makeConnection(RpcConnectionPool.java:66)
at org.apache.tajo.rpc.RpcConnectionPool.getConnection(RpcConnectionPool.java:78)
at org.apache.tajo.client.TajoClient.close(TajoClient.java:136)
at org.apache.tajo.cli.TajoCli$1.run(TajoCli.java:276)
at java.lang.Thread.run(Thread.java:662)
2014-05-26 19:26:06,244 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-26 19:26:06,244 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-26 19:26:06,244 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:43719 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-26 19:26:06,244 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:43719 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-26 19:26:06,247 INFO: org.apache.tajo.worker.WorkerHeartbeatService (run(260)) - Worker Resource Heartbeat Thread stopped.
2014-05-26 19:26:06,264 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:43723) shutdown
2014-05-26 19:26:06,265 INFO: org.apache.tajo.worker.TajoWorkerManagerService (stop(95)) - TajoWorkerManagerService stopped
2014-05-26 19:26:06,267 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:43722) shutdown
2014-05-26 19:26:06,267 INFO: org.apache.tajo.master.querymaster.QueryMasterManagerService (stop(111)) - QueryMasterManagerService stopped
2014-05-26 19:26:06,268 INFO: org.apache.tajo.master.querymaster.QueryMaster (run(434)) - QueryMaster heartbeat thread stopped
2014-05-26 19:26:06,268 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:querymaster_1401132066528
2014-05-26 19:26:06,270 INFO: org.apache.tajo.master.querymaster.QueryMaster (stop(160)) - QueryMaster stop
2014-05-26 19:26:06,271 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(109)) - TajoWorkerClientService stopping
2014-05-26 19:26:06,273 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:43721) shutdown
2014-05-26 19:26:06,274 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(113)) - TajoWorkerClientService stopped
2014-05-26 19:26:06,274 INFO: org.apache.tajo.worker.TajoWorker (stop(334)) - TajoWorker main thread exiting
Results :
Failed tests: testCastFromTable(org.apache.tajo.engine.eval.TestSQLExpression): select col1::timestamp as t1, col2::float from table1 where t1 = '1980-04-01 01:50:01'::timestamp expected:<1980-0[3-31 16]:50:01> but was:<1980-0[4-01 01]:50:01>
Tests run: 753, Failures: 1, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [6.452s]
[INFO] Tajo Project POM .................................. SUCCESS [0.562s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [3.026s]
[INFO] Tajo Common ....................................... SUCCESS [56.476s]
[INFO] Tajo Algebra ...................................... SUCCESS [1.207s]
[INFO] Tajo Catalog Common ............................... SUCCESS [5.793s]
[INFO] Tajo Rpc .......................................... SUCCESS [21.405s]
[INFO] Tajo Catalog Client ............................... SUCCESS [1.038s]
[INFO] Tajo Catalog Server ............................... SUCCESS [7.141s]
[INFO] Tajo Storage ...................................... SUCCESS [57.139s]
[INFO] Tajo Core PullServer .............................. SUCCESS [0.875s]
[INFO] Tajo Client ....................................... SUCCESS [3.272s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [0.635s]
[INFO] Tajo Core ......................................... FAILURE [10:00.375s]
[INFO] Tajo Catalog Drivers .............................. SKIPPED
[INFO] Tajo Catalog ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 12:46.420s
[INFO] Finished at: Mon May 26 19:30:42 UTC 2014
[INFO] Final Memory: 52M/432M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4: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
Build failed in Jenkins: Tajo-master-build #223
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/223/changes>
Changes:
[hyunsik] TAJO-830: Some filter conditions with a SUBQUERY are removed by optimizer. (Hyoungjun Kim via hyunsik)
------------------------------------------
[...truncated 95761 lines...]
2014-05-26 19:11:44,444 INFO: org.apache.tajo.worker.Task (waitForFetch(354)) - ta_1401131206755_0302_000004_000000_00 All fetches are done!
2014-05-26 19:11:44,445 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createInMemoryHashAggregation(912)) - The planner chooses [Hash Aggregation]
2014-05-26 19:11:44,457 INFO: BlockStateChange (logAddStoredBlock(2300)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:44187 is added to blk_1073742230_1406{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-edfa0a31-c3c4-4f79-b69e-7043f0820edd:NORMAL|RBW]]} size 0
2014-05-26 19:11:44,460 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(112)) - Query status of ta_1401131206755_0302_000004_000000_00 is changed to TA_SUCCEEDED
2014-05-26 19:11:44,460 INFO: org.apache.tajo.worker.Task (run(450)) - Worker's task counter - total:722, succeeded: 718, killed: 4, failed: 0
2014-05-26 19:11:44,461 INFO: org.apache.tajo.worker.TaskRunner (run(332)) - Request GetTask: eb_1401131206755_0302_000004,container_1401131206755_0302_01_001083
2014-05-26 19:11:44,461 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1069)) - [eb_1401131206755_0302_000004] Task Completion Event (Total: 1, Success: 1, Killed: 0, Failed: 0)
2014-05-26 19:11:44,461 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1109)) - subQuery completed - eb_1401131206755_0302_000004 (total=1, success=1, killed=0)
2014-05-26 19:11:44,461 INFO: org.apache.tajo.master.DefaultTaskScheduler (run(103)) - TaskScheduler schedulingThread stopped
2014-05-26 19:11:44,461 INFO: org.apache.tajo.worker.TaskRunner (run(362)) - Received ShouldDie flag:eb_1401131206755_0302_000004,container_1401131206755_0302_01_001083
2014-05-26 19:11:44,461 INFO: org.apache.tajo.master.DefaultTaskScheduler (stop(144)) - Task Scheduler stopped
2014-05-26 19:11:44,462 INFO: org.apache.tajo.worker.TaskRunner (stop(227)) - Stop TaskRunner: eb_1401131206755_0302_000004
2014-05-26 19:11:44,462 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTask(89)) - Stop Task:eb_1401131206755_0302_000004,container_1401131206755_0302_01_001083
2014-05-26 19:11:44,462 INFO: org.apache.tajo.master.querymaster.Query (handle(682)) - Processing q_1401131206755_0302 of type SUBQUERY_COMPLETED
2014-05-26 19:11:44,462 INFO: org.apache.tajo.master.querymaster.Query (handle(682)) - Processing q_1401131206755_0302 of type QUERY_COMPLETED
2014-05-26 19:11:44,463 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(518)) - Release Resource: 0.5,512
2014-05-26 19:11:44,463 INFO: org.apache.tajo.master.querymaster.Query (handle(699)) - q_1401131206755_0302 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED
2014-05-26 19:11:44,464 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (handle(295)) - Query completion notified from q_1401131206755_0302
2014-05-26 19:11:44,464 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (handle(306)) - Query final state: QUERY_SUCCEEDED
2014-05-26 19:11:44,464 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (stop(187)) - Stopping QueryMasterTask:q_1401131206755_0302
2014-05-26 19:11:44,464 INFO: org.apache.tajo.master.querymaster.QueryInProgress (heartbeat(266)) - Received QueryMaster heartbeat:q_1401131206755_0302,state=QUERY_SUCCEEDED,progress=1.0, queryMaster=asf011.sp2.ygridcore.net
2014-05-26 19:11:44,464 INFO: org.apache.tajo.master.querymaster.QueryJobManager (stopQuery(197)) - Stop QueryInProgress:q_1401131206755_0302
2014-05-26 19:11:44,464 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(116)) - =========================================================
2014-05-26 19:11:44,465 WARN: org.apache.tajo.master.TajoAsyncDispatcher (stop(115)) - Interrupted Exception while stopping
2014-05-26 19:11:44,465 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(117)) - Stop query:q_1401131206755_0302
2014-05-26 19:11:44,465 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:q_1401131206755_0302
2014-05-26 19:11:44,465 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(518)) - Release Resource: 0.0,512
2014-05-26 19:11:44,465 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (stopQueryMaster(541)) - Released QueryMaster (q_1401131206755_0302) resource:null
2014-05-26 19:11:44,465 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(125)) - q_1401131206755_0302 QueryMaster stopped
2014-05-26 19:11:44,465 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (stop(215)) - Stopped QueryMasterTask:q_1401131206755_0302
2014-05-26 19:11:44,466 WARN: org.apache.tajo.master.TajoAsyncDispatcher (stop(115)) - Interrupted Exception while stopping
2014-05-26 19:11:44,466 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:QueryInProgress:q_1401131206755_0302
2014-05-26 19:11:44,832 INFO: org.apache.tajo.worker.TajoWorkerClientService (closeQuery(222)) - Stop Query:q_1401131206755_0302
2014-05-26 19:11:44,833 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session ba20d0f4-bbf1-4b3e-acb1-6dbc1de9f783 is removed.
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.989 sec
2014-05-26 19:11:44,840 INFO: org.apache.tajo.worker.TajoWorker (run(492)) - ============================================
2014-05-26 19:11:44,840 INFO: org.apache.tajo.worker.TajoWorker (run(493)) - TajoWorker received SIGINT Signal
2014-05-26 19:11:44,840 INFO: org.apache.tajo.worker.TajoWorker (run(494)) - ============================================
2014-05-26 19:11:44,845 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session caa0a165-11b3-4723-b559-776b1a3e92d6 is removed.
2014-05-26 19:11:44,850 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session e2f73bf1-7e6f-4524-874f-b37ca6078c5b is removed.
2014-05-26 19:11:44,850 INFO: org.apache.tajo.worker.WorkerHeartbeatService (run(260)) - Worker Resource Heartbeat Thread stopped.
May 26, 2014 7:11:44 PM org.jboss.netty.channel.DefaultChannelPipeline
WARNING: An exception was thrown by an exception handler.
java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
at org.jboss.netty.channel.socket.nio.AbstractNioSelector.registerTask(AbstractNioSelector.java:115)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:73)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:57)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioChannelSink.execute(AbstractNioChannelSink.java:34)
at org.jboss.netty.channel.Channels.fireExceptionCaughtLater(Channels.java:496)
at org.jboss.netty.channel.AbstractChannelSink.exceptionCaught(AbstractChannelSink.java:46)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.close(Channels.java:812)
at org.jboss.netty.channel.AbstractChannel.close(AbstractChannel.java:197)
at org.jboss.netty.channel.ChannelFutureListener$2.operationComplete(ChannelFutureListener.java:52)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListener(DefaultChannelFuture.java:427)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListeners(DefaultChannelFuture.java:413)
at org.jboss.netty.channel.DefaultChannelFuture.setFailure(DefaultChannelFuture.java:380)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.connect(NioClientSocketPipelineSink.java:130)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.eventSunk(NioClientSocketPipelineSink.java:70)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.connect(Channels.java:634)
at org.jboss.netty.channel.AbstractChannel.connect(AbstractChannel.java:207)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:229)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:182)
at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:76)
at org.apache.tajo.rpc.NettyClientBase.init(NettyClientBase.java:63)
at org.apache.tajo.rpc.BlockingRpcClient.<init>(BlockingRpcClient.java:81)
at org.apache.tajo.rpc.RpcConnectionPool.makeConnection(RpcConnectionPool.java:66)
at org.apache.tajo.rpc.RpcConnectionPool.getConnection(RpcConnectionPool.java:78)
at org.apache.tajo.client.TajoClient.close(TajoClient.java:136)
at org.apache.tajo.cli.TajoCli$1.run(TajoCli.java:276)
at java.lang.Thread.run(Thread.java:662)
2014-05-26 19:11:44,852 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-26 19:11:44,852 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:47460 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
May 26, 2014 7:11:44 PM org.jboss.netty.channel.DefaultChannelPipeline
WARNING: An exception was thrown by an exception handler.
java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
at org.jboss.netty.channel.socket.nio.AbstractNioSelector.registerTask(AbstractNioSelector.java:115)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:73)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:57)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioChannelSink.execute(AbstractNioChannelSink.java:34)
at org.jboss.netty.channel.Channels.fireExceptionCaughtLater(Channels.java:496)
at org.jboss.netty.channel.AbstractChannelSink.exceptionCaught(AbstractChannelSink.java:46)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.close(Channels.java:812)
at org.jboss.netty.channel.AbstractChannel.close(AbstractChannel.java:197)
at org.jboss.netty.channel.ChannelFutureListener$2.operationComplete(ChannelFutureListener.java:52)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListener(DefaultChannelFuture.java:427)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListeners(DefaultChannelFuture.java:413)
at org.jboss.netty.channel.DefaultChannelFuture.setFailure(DefaultChannelFuture.java:380)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.connect(NioClientSocketPipelineSink.java:130)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.eventSunk(NioClientSocketPipelineSink.java:70)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.connect(Channels.java:634)
at org.jboss.netty.channel.AbstractChannel.connect(AbstractChannel.java:207)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:229)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:182)
at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:76)
at org.apache.tajo.rpc.NettyClientBase.init(NettyClientBase.java:63)
at org.apache.tajo.rpc.BlockingRpcClient.<init>(BlockingRpcClient.java:81)
at org.apache.tajo.rpc.RpcConnectionPool.makeConnection(RpcConnectionPool.java:66)
at org.apache.tajo.rpc.RpcConnectionPool.getConnection(RpcConnectionPool.java:78)
at org.apache.tajo.client.TajoClient.close(TajoClient.java:136)
at org.apache.tajo.cli.TajoCli$1.run(TajoCli.java:276)
at java.lang.Thread.run(Thread.java:662)
2014-05-26 19:11:44,854 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-26 19:11:44,854 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:47460 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
May 26, 2014 7:11:44 PM org.jboss.netty.channel.DefaultChannelPipeline
WARNING: An exception was thrown by an exception handler.
java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
at org.jboss.netty.channel.socket.nio.AbstractNioSelector.registerTask(AbstractNioSelector.java:115)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:73)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:57)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioChannelSink.execute(AbstractNioChannelSink.java:34)
at org.jboss.netty.channel.Channels.fireExceptionCaughtLater(Channels.java:496)
at org.jboss.netty.channel.AbstractChannelSink.exceptionCaught(AbstractChannelSink.java:46)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.close(Channels.java:812)
at org.jboss.netty.channel.AbstractChannel.close(AbstractChannel.java:197)
at org.jboss.netty.channel.ChannelFutureListener$2.operationComplete(ChannelFutureListener.java:52)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListener(DefaultChannelFuture.java:427)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListeners(DefaultChannelFuture.java:413)
at org.jboss.netty.channel.DefaultChannelFuture.setFailure(DefaultChannelFuture.java:380)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.connect(NioClientSocketPipelineSink.java:130)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.eventSunk(NioClientSocketPipelineSink.java:70)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.connect(Channels.java:634)
at org.jboss.netty.channel.AbstractChannel.connect(AbstractChannel.java:207)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:229)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:182)
at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:76)
at org.apache.tajo.rpc.NettyClientBase.init(NettyClientBase.java:63)
at org.apache.tajo.rpc.BlockingRpcClient.<init>(BlockingRpcClient.java:81)
at org.apache.tajo.rpc.RpcConnectionPool.makeConnection(RpcConnectionPool.java:66)
at org.apache.tajo.rpc.RpcConnectionPool.getConnection(RpcConnectionPool.java:78)
at org.apache.tajo.client.TajoClient.close(TajoClient.java:136)
at org.apache.tajo.cli.TajoCli$1.run(TajoCli.java:276)
at java.lang.Thread.run(Thread.java:662)
2014-05-26 19:11:44,855 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
May 26, 2014 7:11:44 PM org.jboss.netty.channel.DefaultChannelPipeline
WARNING: An exception was thrown by an exception handler.
java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
at org.jboss.netty.channel.socket.nio.AbstractNioSelector.registerTask(AbstractNioSelector.java:115)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:73)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.executeInIoThread(AbstractNioWorker.java:57)
at org.jboss.netty.channel.socket.nio.NioWorker.executeInIoThread(NioWorker.java:36)
at org.jboss.netty.channel.socket.nio.AbstractNioChannelSink.execute(AbstractNioChannelSink.java:34)
at org.jboss.netty.channel.Channels.fireExceptionCaughtLater(Channels.java:496)
at org.jboss.netty.channel.AbstractChannelSink.exceptionCaught(AbstractChannelSink.java:46)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.close(Channels.java:812)
at org.jboss.netty.channel.AbstractChannel.close(AbstractChannel.java:197)
at org.jboss.netty.channel.ChannelFutureListener$2.operationComplete(ChannelFutureListener.java:52)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListener(DefaultChannelFuture.java:427)
at org.jboss.netty.channel.DefaultChannelFuture.notifyListeners(DefaultChannelFuture.java:413)
at org.jboss.netty.channel.DefaultChannelFuture.setFailure(DefaultChannelFuture.java:380)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.connect(NioClientSocketPipelineSink.java:130)
at org.jboss.netty.channel.socket.nio.NioClientSocketPipelineSink.eventSunk(NioClientSocketPipelineSink.java:70)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.handler.codec.oneone.OneToOneEncoder.handleDownstream(OneToOneEncoder.java:54)
at org.jboss.netty.channel.Channels.connect(Channels.java:634)
at org.jboss.netty.channel.AbstractChannel.connect(AbstractChannel.java:207)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:229)
at org.jboss.netty.bootstrap.ClientBootstrap.connect(ClientBootstrap.java:182)
at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:76)
at org.apache.tajo.rpc.NettyClientBase.init(NettyClientBase.java:63)
at org.apache.tajo.rpc.BlockingRpcClient.<init>(BlockingRpcClient.java:81)
at org.apache.tajo.rpc.RpcConnectionPool.makeConnection(RpcConnectionPool.java:66)
at org.apache.tajo.rpc.RpcConnectionPool.getConnection(RpcConnectionPool.java:78)
at org.apache.tajo.client.TajoClient.close(TajoClient.java:136)
at org.apache.tajo.cli.TajoCli$1.run(TajoCli.java:276)
at java.lang.Thread.run(Thread.java:662)
2014-05-26 19:11:44,855 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:47460 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-26 19:11:44,855 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-26 19:11:44,856 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:47460 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-26 19:11:44,874 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:47464) shutdown
2014-05-26 19:11:44,875 INFO: org.apache.tajo.worker.TajoWorkerManagerService (stop(95)) - TajoWorkerManagerService stopped
2014-05-26 19:11:44,877 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:47463) shutdown
2014-05-26 19:11:44,877 INFO: org.apache.tajo.master.querymaster.QueryMasterManagerService (stop(111)) - QueryMasterManagerService stopped
2014-05-26 19:11:44,877 INFO: org.apache.tajo.master.querymaster.QueryMaster (run(434)) - QueryMaster heartbeat thread stopped
2014-05-26 19:11:44,878 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:querymaster_1401131207382
2014-05-26 19:11:44,879 INFO: org.apache.tajo.master.querymaster.QueryMaster (stop(160)) - QueryMaster stop
2014-05-26 19:11:44,880 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(109)) - TajoWorkerClientService stopping
2014-05-26 19:11:44,881 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:47462) shutdown
2014-05-26 19:11:44,881 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(113)) - TajoWorkerClientService stopped
2014-05-26 19:11:44,881 INFO: org.apache.tajo.worker.TajoWorker (stop(334)) - TajoWorker main thread exiting
Results :
Failed tests: testCastFromTable(org.apache.tajo.engine.eval.TestSQLExpression): select col1::timestamp as t1, col2::float from table1 where t1 = '1980-04-01 01:50:01'::timestamp expected:<1980-0[3-31 16]:50:01> but was:<1980-0[4-01 01]:50:01>
Tests run: 753, Failures: 1, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [53.846s]
[INFO] Tajo Project POM .................................. SUCCESS [2.082s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [4.528s]
[INFO] Tajo Common ....................................... SUCCESS [59.555s]
[INFO] Tajo Algebra ...................................... SUCCESS [3.426s]
[INFO] Tajo Catalog Common ............................... SUCCESS [5.795s]
[INFO] Tajo Rpc .......................................... SUCCESS [21.905s]
[INFO] Tajo Catalog Client ............................... SUCCESS [1.150s]
[INFO] Tajo Catalog Server ............................... SUCCESS [8.105s]
[INFO] Tajo Storage ...................................... SUCCESS [1:00.908s]
[INFO] Tajo Core PullServer .............................. SUCCESS [1.449s]
[INFO] Tajo Client ....................................... SUCCESS [4.404s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [1.347s]
[INFO] Tajo Core ......................................... FAILURE [9:25.602s]
[INFO] Tajo Catalog Drivers .............................. SKIPPED
[INFO] Tajo Catalog ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 13:15.364s
[INFO] Finished at: Mon May 26 19:15:42 UTC 2014
[INFO] Final Memory: 49M/343M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4: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-830
Build failed in Jenkins: Tajo-master-build #222
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/222/>
------------------------------------------
[...truncated 92750 lines...]
=> 0: hdfs://localhost:52816/tajo/warehouse/default/columnpartitionedtable/key=17.0
=> 1: hdfs://localhost:52816/tajo/warehouse/default/columnpartitionedtable/key=36.0
=> 2: hdfs://localhost:52816/tajo/warehouse/default/columnpartitionedtable/key=38.0
=> 3: hdfs://localhost:52816/tajo/warehouse/default/columnpartitionedtable/key=45.0
=> 4: hdfs://localhost:52816/tajo/warehouse/default/columnpartitionedtable/key=49.0
PARTITIONS_SCAN(10) on default.columnpartitionedtable
=> target list: default.a.col1 (INT4), default.a.col2 (INT4), default.a.key (FLOAT8)
=> num of filtered paths: 2
=> out schema: {(3) default.a.col1 (INT4),default.a.col2 (INT4),default.a.key (FLOAT8)}
=> in schema: {(2) default.a.col1 (INT4),default.a.col2 (INT4)}
=> 0: hdfs://localhost:52816/tajo/warehouse/default/columnpartitionedtable/key=38.0
=> 1: hdfs://localhost:52816/tajo/warehouse/default/columnpartitionedtable/key=45.0
=======================================================
Block Id: eb_1400880522585_0296_000004 [ROOT]
=======================================================
[Incoming]
[q_1400880522585_0296] 3 => 4 (type=RANGE_SHUFFLE, key=default.test.col1 (INT4), default.test.col2 (INT4), num=32)
[Enforcers]
0:
PROJECTION(7)
=> Targets: default.test.col1 (INT4), default.test.col2 (INT4), default.test.key (FLOAT8)
=> out schema: {(3) default.test.col1 (INT4),default.test.col2 (INT4),default.test.key (FLOAT8)}
=> in schema: {(3) default.test.col1 (INT4),default.test.col2 (INT4),default.test.key (FLOAT8)}
SORT(6)
=> Sort Keys: col1 asc,col2 asc
SCAN(14) on eb_1400880522585_0296_000003
=> out schema: {(3) default.test.col1 (INT4),default.test.col2 (INT4),default.test.key (FLOAT8)}
=> in schema: {(3) default.test.col1 (INT4),default.test.col2 (INT4),default.test.key (FLOAT8)}
=======================================================
Block Id: eb_1400880522585_0296_000005 [TERMINAL]
=======================================================
2014-05-23 21:32:59,656 INFO: org.apache.tajo.master.TajoAsyncDispatcher (start(101)) - AsyncDispatcher started:q_1400880522585_0296
2014-05-23 21:32:59,656 INFO: org.apache.tajo.master.querymaster.Query (handle(682)) - Processing q_1400880522585_0296 of type START
2014-05-23 21:32:59,657 INFO: org.apache.tajo.master.querymaster.SubQuery (calculateShuffleOutputNum(797)) - ============>>>>> Unexpected Case! <<<<<================
2014-05-23 21:32:59,657 INFO: org.apache.tajo.master.querymaster.SubQuery (calculateShuffleOutputNum(801)) - eb_1400880522585_0296_000003, Table's volume is approximately 1 MB
2014-05-23 21:32:59,657 INFO: org.apache.tajo.master.querymaster.SubQuery (calculateShuffleOutputNum(804)) - eb_1400880522585_0296_000003, The determined number of partitions is 1
2014-05-23 21:32:59,657 INFO: org.apache.tajo.master.querymaster.SubQuery (initTaskScheduler(677)) - org.apache.tajo.master.DefaultTaskScheduler is chosen for the task scheduling for eb_1400880522585_0296_000003
2014-05-23 21:32:59,659 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(386)) - Total input paths to process : 5
2014-05-23 21:32:59,659 INFO: org.apache.tajo.storage.AbstractStorageManager (getSplits(615)) - Total # of splits: 5
2014-05-23 21:32:59,661 INFO: org.apache.tajo.storage.AbstractStorageManager (listStatus(386)) - Total input paths to process : 5
2014-05-23 21:32:59,661 INFO: org.apache.tajo.storage.AbstractStorageManager (getSplits(615)) - Total # of splits: 5
2014-05-23 21:32:59,661 INFO: org.apache.tajo.master.querymaster.Repartitioner (scheduleFragmentsForJoinQuery(142)) - [Distributed Join Strategy] : Immediate 2 Way Join on Single Machine
2014-05-23 21:32:59,661 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(647)) - 1 objects are scheduled
2014-05-23 21:32:59,662 INFO: org.apache.tajo.master.DefaultTaskScheduler (start(88)) - Start TaskScheduler
2014-05-23 21:32:59,662 INFO: org.apache.tajo.worker.TajoResourceAllocator (calculateNumRequestContainers(100)) - CalculateNumberRequestContainer - Number of Tasks=1, Number of Cluster Slots=1
2014-05-23 21:32:59,662 INFO: org.apache.tajo.master.querymaster.SubQuery (allocateContainers(884)) - Request Container for eb_1400880522585_0296_000003 containers=1
2014-05-23 21:32:59,662 INFO: org.apache.tajo.master.querymaster.Query (handle(699)) - q_1400880522585_0296 Query Transitioned from QUERY_NEW to QUERY_RUNNING
2014-05-23 21:32:59,662 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(217)) - Start TajoWorkerAllocationThread
2014-05-23 21:32:59,663 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(336)) - Stop TajoWorkerAllocationThread
2014-05-23 21:32:59,663 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(992)) - SubQuery (eb_1400880522585_0296_000003) has 1 containers!
2014-05-23 21:32:59,664 INFO: org.apache.tajo.worker.TaskRunner (<init>(123)) - Tajo Root Dir: hdfs://localhost:52816/tajo
2014-05-23 21:32:59,664 INFO: org.apache.tajo.worker.TaskRunner (<init>(124)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/aa06a2be-ca34-4981-882f-82ae8d9c93c9/tajo-localdir>
2014-05-23 21:32:59,664 INFO: org.apache.tajo.worker.TaskRunner (<init>(138)) - QueryMaster Address:asf001.sp2.ygridcore.net/67.195.138.31:25210
2014-05-23 21:32:59,665 INFO: org.apache.tajo.worker.TaskRunnerManager (run(188)) - Start TaskRunner:eb_1400880522585_0296_000003,container_1400880522585_0296_01_001053
2014-05-23 21:32:59,665 INFO: org.apache.tajo.worker.TaskRunner (init(185)) - TaskRunner basedir is created (q_1400880522585_0296/output/3)
2014-05-23 21:32:59,666 INFO: org.apache.tajo.worker.TaskRunner (run(312)) - TaskRunner startup
2014-05-23 21:32:59,666 INFO: org.apache.tajo.worker.TaskRunner (run(332)) - Request GetTask: eb_1400880522585_0296_000003,container_1400880522585_0296_01_001053
2014-05-23 21:32:59,667 INFO: org.apache.tajo.master.DefaultTaskScheduler (assignToLeafTasks(798)) - Assigned Local/Remote/Total: (0/1/1), Locality: 0.00%,
2014-05-23 21:32:59,668 INFO: org.apache.tajo.worker.TaskRunner (run(370)) - Accumulated Received Task: 1
2014-05-23 21:32:59,669 INFO: org.apache.tajo.worker.TaskRunner (run(379)) - Initializing: ta_1400880522585_0296_000003_000000_00
2014-05-23 21:32:59,684 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(112)) - Query status of ta_1400880522585_0296_000003_000000_00 is changed to TA_PENDING
2014-05-23 21:32:59,684 INFO: org.apache.tajo.worker.Task (<init>(186)) - ==================================
2014-05-23 21:32:59,685 INFO: org.apache.tajo.worker.Task (<init>(187)) - * Subquery ta_1400880522585_0296_000003_000000_00 is initialized
2014-05-23 21:32:59,685 INFO: org.apache.tajo.worker.Task (<init>(188)) - * InterQuery: true, Use RANGE_SHUFFLE shuffle
2014-05-23 21:32:59,685 INFO: org.apache.tajo.worker.Task (<init>(191)) - * Fragments (num: 1)
2014-05-23 21:32:59,685 INFO: org.apache.tajo.worker.Task (<init>(192)) - * Fetches (total:0) :
2014-05-23 21:32:59,685 INFO: org.apache.tajo.worker.Task (<init>(196)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/aa06a2be-ca34-4981-882f-82ae8d9c93c9/tajo-localdir/q_1400880522585_0296/output/3/0_0>
2014-05-23 21:32:59,685 INFO: org.apache.tajo.worker.Task (<init>(201)) - ==================================
2014-05-23 21:32:59,686 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(112)) - Query status of ta_1400880522585_0296_000003_000000_00 is changed to TA_RUNNING
2014-05-23 21:32:59,690 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (checkIfInMemoryInnerJoinIsPossible(256)) - [ta_1400880522585_0296_000003_000000_00] the volume of Left relations (default.columnpartitionedtable) is 0 B and is fit to main maemory.
2014-05-23 21:32:59,690 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createBestInnerJoinPlan(409)) - Join (9) chooses [In-memory Hash Join]
2014-05-23 21:32:59,690 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (switchJoinSidesIfNecessary(380)) - [ta_1400880522585_0296_000003_000000_00] Left relations default.columnpartitionedtable (0 B) is smaller than Right relations default.columnpartitionedtable (0 B).
2014-05-23 21:32:59,698 INFO: org.apache.tajo.engine.planner.physical.RangeShuffleFileWriteExec (init(76)) - Output data directory: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/aa06a2be-ca34-4981-882f-82ae8d9c93c9/tajo-localdir/q_1400880522585_0296/output/3/0_0/output>
2014-05-23 21:32:59,735 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(61)) - [ta_1400880522585_0296_000003_000000_00] Chunks creation time: 13 msec
2014-05-23 21:32:59,742 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(112)) - Query status of ta_1400880522585_0296_000003_000000_00 is changed to TA_SUCCEEDED
2014-05-23 21:32:59,742 INFO: org.apache.tajo.worker.Task (run(450)) - Worker's task counter - total:698, succeeded: 694, killed: 4, failed: 0
2014-05-23 21:32:59,742 INFO: org.apache.tajo.worker.TaskRunner (run(332)) - Request GetTask: eb_1400880522585_0296_000003,container_1400880522585_0296_01_001053
2014-05-23 21:32:59,742 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1069)) - [eb_1400880522585_0296_000003] Task Completion Event (Total: 1, Success: 1, Killed: 0, Failed: 0)
2014-05-23 21:32:59,743 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1109)) - subQuery completed - eb_1400880522585_0296_000003 (total=1, success=1, killed=0)
2014-05-23 21:32:59,743 INFO: org.apache.tajo.master.DefaultTaskScheduler (run(103)) - TaskScheduler schedulingThread stopped
2014-05-23 21:32:59,743 INFO: org.apache.tajo.master.DefaultTaskScheduler (stop(144)) - Task Scheduler stopped
2014-05-23 21:32:59,743 INFO: org.apache.tajo.worker.TaskRunner (run(362)) - Received ShouldDie flag:eb_1400880522585_0296_000003,container_1400880522585_0296_01_001053
2014-05-23 21:32:59,743 INFO: org.apache.tajo.worker.TaskRunner (stop(227)) - Stop TaskRunner: eb_1400880522585_0296_000003
2014-05-23 21:32:59,743 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTask(89)) - Stop Task:eb_1400880522585_0296_000003,container_1400880522585_0296_01_001053
2014-05-23 21:32:59,743 INFO: org.apache.tajo.master.querymaster.Query (handle(682)) - Processing q_1400880522585_0296 of type SUBQUERY_COMPLETED
2014-05-23 21:32:59,744 INFO: org.apache.tajo.master.querymaster.SubQuery (initTaskScheduler(677)) - org.apache.tajo.master.DefaultTaskScheduler is chosen for the task scheduling for eb_1400880522585_0296_000004
2014-05-23 21:32:59,744 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(518)) - Release Resource: 0.5,512
2014-05-23 21:32:59,744 INFO: org.apache.tajo.master.querymaster.SubQuery (getNonLeafTaskNum(833)) - Table's volume is approximately 1 MB
2014-05-23 21:32:59,744 INFO: org.apache.tajo.master.querymaster.SubQuery (getNonLeafTaskNum(836)) - The determined number of non-leaf tasks is 1
2014-05-23 21:32:59,745 INFO: org.apache.tajo.master.querymaster.Repartitioner (scheduleRangeShuffledFetches(384)) - Try to divide [(0=>2, 1=>2), (0=>3, 1=>2)) into 1 sub ranges (total units: 1)
2014-05-23 21:32:59,745 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(647)) - 1 objects are scheduled
2014-05-23 21:32:59,745 INFO: org.apache.tajo.master.DefaultTaskScheduler (start(88)) - Start TaskScheduler
2014-05-23 21:32:59,745 INFO: org.apache.tajo.worker.TajoResourceAllocator (calculateNumRequestContainers(100)) - CalculateNumberRequestContainer - Number of Tasks=1, Number of Cluster Slots=1
2014-05-23 21:32:59,746 INFO: org.apache.tajo.master.querymaster.SubQuery (allocateContainers(884)) - Request Container for eb_1400880522585_0296_000004 containers=1
2014-05-23 21:32:59,746 INFO: org.apache.tajo.master.querymaster.Query (executeNextBlock(603)) - Scheduling SubQuery:eb_1400880522585_0296_000004
2014-05-23 21:32:59,746 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(217)) - Start TajoWorkerAllocationThread
2014-05-23 21:32:59,747 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(336)) - Stop TajoWorkerAllocationThread
2014-05-23 21:32:59,747 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(992)) - SubQuery (eb_1400880522585_0296_000004) has 1 containers!
2014-05-23 21:32:59,748 INFO: org.apache.tajo.worker.TaskRunner (<init>(123)) - Tajo Root Dir: hdfs://localhost:52816/tajo
2014-05-23 21:32:59,748 INFO: org.apache.tajo.worker.TaskRunner (<init>(124)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/aa06a2be-ca34-4981-882f-82ae8d9c93c9/tajo-localdir>
2014-05-23 21:32:59,748 INFO: org.apache.tajo.worker.TaskRunner (<init>(138)) - QueryMaster Address:asf001.sp2.ygridcore.net/67.195.138.31:25210
2014-05-23 21:32:59,748 INFO: org.apache.tajo.worker.TaskRunnerManager (run(188)) - Start TaskRunner:eb_1400880522585_0296_000004,container_1400880522585_0296_01_001054
2014-05-23 21:32:59,749 INFO: org.apache.tajo.worker.TaskRunner (init(185)) - TaskRunner basedir is created (q_1400880522585_0296/output/4)
2014-05-23 21:32:59,749 INFO: org.apache.tajo.worker.TaskRunner (run(312)) - TaskRunner startup
2014-05-23 21:32:59,749 INFO: org.apache.tajo.worker.TaskRunner (run(332)) - Request GetTask: eb_1400880522585_0296_000004,container_1400880522585_0296_01_001054
2014-05-23 21:32:59,751 INFO: org.apache.tajo.worker.TaskRunner (run(370)) - Accumulated Received Task: 1
2014-05-23 21:32:59,751 INFO: org.apache.tajo.worker.TaskRunner (run(379)) - Initializing: ta_1400880522585_0296_000004_000000_00
2014-05-23 21:32:59,752 INFO: org.apache.tajo.worker.Task (<init>(181)) - Output File Path: hdfs://localhost:52816/tmp/tajo-jenkins/staging/q_1400880522585_0296/RESULT/part-04-000000
2014-05-23 21:32:59,752 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(112)) - Query status of ta_1400880522585_0296_000004_000000_00 is changed to TA_PENDING
2014-05-23 21:32:59,752 INFO: org.apache.tajo.worker.Task (<init>(186)) - ==================================
2014-05-23 21:32:59,752 INFO: org.apache.tajo.worker.Task (<init>(187)) - * Subquery ta_1400880522585_0296_000004_000000_00 is initialized
2014-05-23 21:32:59,752 INFO: org.apache.tajo.worker.Task (<init>(188)) - * InterQuery: false
2014-05-23 21:32:59,752 INFO: org.apache.tajo.worker.Task (<init>(191)) - * Fragments (num: 1)
2014-05-23 21:32:59,752 INFO: org.apache.tajo.worker.Task (<init>(192)) - * Fetches (total:1) :
2014-05-23 21:32:59,752 INFO: org.apache.tajo.worker.Task (<init>(194)) - Table Id: eb_1400880522585_0296_000003, Simple URIs: [http://67.195.138.31:56620/?qid=q_1400880522585_0296&sid=3&p=0&type=r&start=AAAAAAIAAAAC&end=AAAAAAMAAAAC&final=true]
2014-05-23 21:32:59,752 INFO: org.apache.tajo.worker.Task (<init>(196)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/aa06a2be-ca34-4981-882f-82ae8d9c93c9/tajo-localdir/q_1400880522585_0296/output/4/0_0>
2014-05-23 21:32:59,753 INFO: org.apache.tajo.worker.Task (<init>(201)) - ==================================
2014-05-23 21:32:59,754 INFO: org.apache.tajo.worker.Task (init(217)) - the directory is created <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/aa06a2be-ca34-4981-882f-82ae8d9c93c9/tajo-localdir/q_1400880522585_0296/in/eb_1400880522585_0296_000004/0/0/eb_1400880522585_0296_000003>
2014-05-23 21:32:59,756 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(112)) - Query status of ta_1400880522585_0296_000004_000000_00 is changed to TA_RUNNING
2014-05-23 21:32:59,756 INFO: org.apache.tajo.pullserver.TajoPullServerService (channelOpen(349)) - Current number of shuffle connections (2)
2014-05-23 21:32:59,757 INFO: org.apache.tajo.worker.Fetcher (get(129)) - Fetch: http://67.195.138.31:56620/?qid=q_1400880522585_0296&sid=3&p=0&type=r&start=AAAAAAIAAAAC&end=AAAAAAMAAAAC&final=true&ta=0_0
2014-05-23 21:32:59,757 INFO: org.apache.tajo.pullserver.TajoPullServerService (messageReceived(394)) - PullServer request param: shuffleType=r, sid=3, partId=0, taskIds=[0_0]
2014-05-23 21:32:59,757 INFO: org.apache.tajo.pullserver.TajoPullServerService (messageReceived(400)) - PullServer baseDir: file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/aa06a2be-ca34-4981-882f-82ae8d9c93c9/tajo-localdir/q_1400880522585_0296/output>
2014-05-23 21:32:59,778 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(552)) - BSTIndex is loaded from disk ((0=>2, 1=>2), (0=>3, 1=>2)
2014-05-23 21:32:59,778 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(576)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/aa06a2be-ca34-4981-882f-82ae8d9c93c9/tajo-localdir/q_1400880522585_0296/output/3/0_0/output/output> (start=(0=>2, 1=>2), end=(0=>3, 1=>2), last=true)
2014-05-23 21:32:59,779 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(645)) - Retrieve File Chunk: (start=0, length=34) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/aa06a2be-ca34-4981-882f-82ae8d9c93c9/tajo-localdir/q_1400880522585_0296/output/3/0_0/output/output>
2014-05-23 21:32:59,780 INFO: org.apache.tajo.worker.Task (waitForFetch(354)) - ta_1400880522585_0296_000004_000000_00 All fetches are done!
2014-05-23 21:32:59,784 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(61)) - [ta_1400880522585_0296_000004_000000_00] Total merge time: 0 msec
2014-05-23 21:32:59,789 INFO: BlockStateChange (logAddStoredBlock(2300)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:51169 is added to blk_1073742222_1398{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-b14668c5-a04a-461c-bc14-bf8ac25d5ac0:NORMAL|RBW]]} size 0
2014-05-23 21:32:59,790 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(112)) - Query status of ta_1400880522585_0296_000004_000000_00 is changed to TA_SUCCEEDED
2014-05-23 21:32:59,790 INFO: org.apache.tajo.worker.Task (run(450)) - Worker's task counter - total:699, succeeded: 695, killed: 4, failed: 0
2014-05-23 21:32:59,791 INFO: org.apache.tajo.worker.TaskRunner (run(332)) - Request GetTask: eb_1400880522585_0296_000004,container_1400880522585_0296_01_001054
2014-05-23 21:32:59,791 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1069)) - [eb_1400880522585_0296_000004] Task Completion Event (Total: 1, Success: 1, Killed: 0, Failed: 0)
2014-05-23 21:32:59,791 INFO: org.apache.tajo.master.querymaster.SubQuery (transition(1109)) - subQuery completed - eb_1400880522585_0296_000004 (total=1, success=1, killed=0)
2014-05-23 21:32:59,791 INFO: org.apache.tajo.master.DefaultTaskScheduler (run(103)) - TaskScheduler schedulingThread stopped
2014-05-23 21:32:59,791 INFO: org.apache.tajo.master.DefaultTaskScheduler (stop(144)) - Task Scheduler stopped
2014-05-23 21:32:59,791 INFO: org.apache.tajo.worker.TaskRunner (run(362)) - Received ShouldDie flag:eb_1400880522585_0296_000004,container_1400880522585_0296_01_001054
2014-05-23 21:32:59,791 INFO: org.apache.tajo.worker.TaskRunner (stop(227)) - Stop TaskRunner: eb_1400880522585_0296_000004
2014-05-23 21:32:59,792 INFO: org.apache.tajo.master.querymaster.Query (handle(682)) - Processing q_1400880522585_0296 of type SUBQUERY_COMPLETED
2014-05-23 21:32:59,792 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTask(89)) - Stop Task:eb_1400880522585_0296_000004,container_1400880522585_0296_01_001054
2014-05-23 21:32:59,792 INFO: org.apache.tajo.master.querymaster.Query (handle(682)) - Processing q_1400880522585_0296 of type QUERY_COMPLETED
2014-05-23 21:32:59,792 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(518)) - Release Resource: 0.5,512
2014-05-23 21:32:59,792 INFO: org.apache.tajo.master.querymaster.Query (handle(699)) - q_1400880522585_0296 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED
2014-05-23 21:32:59,793 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (handle(295)) - Query completion notified from q_1400880522585_0296
2014-05-23 21:32:59,793 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (handle(306)) - Query final state: QUERY_SUCCEEDED
2014-05-23 21:32:59,793 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (stop(187)) - Stopping QueryMasterTask:q_1400880522585_0296
2014-05-23 21:32:59,793 INFO: org.apache.tajo.master.querymaster.QueryJobManager (stopQuery(197)) - Stop QueryInProgress:q_1400880522585_0296
2014-05-23 21:32:59,793 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(116)) - =========================================================
2014-05-23 21:32:59,793 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(117)) - Stop query:q_1400880522585_0296
2014-05-23 21:32:59,793 INFO: org.apache.tajo.master.querymaster.QueryInProgress (heartbeat(266)) - Received QueryMaster heartbeat:q_1400880522585_0296,state=QUERY_SUCCEEDED,progress=1.0, queryMaster=asf001.sp2.ygridcore.net
2014-05-23 21:32:59,794 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(518)) - Release Resource: 0.0,512
2014-05-23 21:32:59,794 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (stopQueryMaster(541)) - Released QueryMaster (q_1400880522585_0296) resource:null
2014-05-23 21:32:59,794 INFO: org.apache.tajo.master.querymaster.QueryInProgress (stop(125)) - q_1400880522585_0296 QueryMaster stopped
2014-05-23 21:32:59,795 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:QueryInProgress:q_1400880522585_0296
2014-05-23 21:32:59,795 WARN: org.apache.tajo.master.TajoAsyncDispatcher (stop(115)) - Interrupted Exception while stopping
2014-05-23 21:32:59,795 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:q_1400880522585_0296
2014-05-23 21:32:59,796 INFO: org.apache.tajo.master.querymaster.QueryMasterTask (stop(215)) - Stopped QueryMasterTask:q_1400880522585_0296
2014-05-23 21:33:00,110 INFO: org.apache.tajo.worker.TajoWorkerClientService (closeQuery(222)) - Stop Query:q_1400880522585_0296
2014-05-23 21:33:00,111 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session 98289ee9-605c-42e1-9cbe-bbb71b79abdd is removed.
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 5.18 sec
Running org.apache.tajo.cluster.TestServerName
Tests run: 11, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec
Running org.apache.tajo.util.TestJSPUtil
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec
Running org.apache.tajo.util.metrics.TestSystemMetrics
2014-05-23 21:33:00,177 INFO: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(180)) - Started metrics reporter org.apache.tajo.util.metrics.reporter.MetricsFileScheduledReporter for test-file-group
2014-05-23 21:33:00,177 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(132)) - No property test-file-group-jvm.reporters in <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/599fa1c5-ec32-46f7-85f3-c94294057c40/1400880780136.properties>
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec
Running org.apache.tajo.util.metrics.TestMetricsFilter
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec
2014-05-23 21:33:00,197 INFO: org.apache.tajo.worker.TajoWorker (run(492)) - ============================================
2014-05-23 21:33:00,198 INFO: org.apache.tajo.worker.TajoWorker (run(493)) - TajoWorker received SIGINT Signal
2014-05-23 21:33:00,198 INFO: org.apache.tajo.worker.TajoWorker (run(494)) - ============================================
2014-05-23 21:33:00,202 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session 84ff7612-6bbd-4fa7-a91f-b70f0e9a5604 is removed.
2014-05-23 21:33:00,202 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-23 21:33:00,203 INFO: org.apache.tajo.master.session.SessionManager (removeSession(80)) - Session 5d4375de-f56d-4741-b59c-3519508f1c59 is removed.
2014-05-23 21:33:00,202 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-23 21:33:00,202 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-23 21:33:00,203 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:25207 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-23 21:33:00,202 ERROR: org.apache.tajo.rpc.RpcProtos (exceptionCaught(225)) - RPC Exception:Worker has already been shutdown
2014-05-23 21:33:00,205 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:25207 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-23 21:33:00,204 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:25207 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-23 21:33:00,207 INFO: org.apache.tajo.worker.WorkerHeartbeatService (run(260)) - Worker Resource Heartbeat Thread stopped.
2014-05-23 21:33:00,206 ERROR: org.apache.tajo.client.TajoClient (close(140)) - java.io.IOException: Connect error to localhost/127.0.0.1:25207 cause java.util.concurrent.RejectedExecutionException: Worker has already been shutdown
2014-05-23 21:33:00,228 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:25211) shutdown
2014-05-23 21:33:00,229 INFO: org.apache.tajo.worker.TajoWorkerManagerService (stop(95)) - TajoWorkerManagerService stopped
2014-05-23 21:33:00,231 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:25210) shutdown
2014-05-23 21:33:00,232 INFO: org.apache.tajo.master.querymaster.QueryMasterManagerService (stop(111)) - QueryMasterManagerService stopped
2014-05-23 21:33:00,232 INFO: org.apache.tajo.master.querymaster.QueryMaster (run(434)) - QueryMaster heartbeat thread stopped
2014-05-23 21:33:00,236 INFO: org.apache.tajo.master.TajoAsyncDispatcher (stop(122)) - AsyncDispatcher stopped:querymaster_1400880523027
2014-05-23 21:33:00,236 INFO: org.apache.tajo.master.querymaster.QueryMaster (stop(160)) - QueryMaster stop
2014-05-23 21:33:00,236 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(109)) - TajoWorkerClientService stopping
2014-05-23 21:33:00,237 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(128)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:25209) shutdown
2014-05-23 21:33:00,237 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(113)) - TajoWorkerClientService stopped
2014-05-23 21:33:00,238 INFO: org.apache.tajo.worker.TajoWorker (stop(334)) - TajoWorker main thread exiting
Results :
Failed tests: testCastFromTable(org.apache.tajo.engine.eval.TestSQLExpression): select col1::timestamp as t1, col2::float from table1 where t1 = '1980-04-01 01:50:01'::timestamp expected:<1980-0[3-31 16]:50:01> but was:<1980-0[4-01 01]:50:01>
Tests run: 747, Failures: 1, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [26.537s]
[INFO] Tajo Project POM .................................. SUCCESS [0.506s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [3.320s]
[INFO] Tajo Common ....................................... SUCCESS [52.596s]
[INFO] Tajo Algebra ...................................... SUCCESS [1.107s]
[INFO] Tajo Catalog Common ............................... SUCCESS [5.205s]
[INFO] Tajo Rpc .......................................... SUCCESS [21.296s]
[INFO] Tajo Catalog Client ............................... SUCCESS [1.012s]
[INFO] Tajo Catalog Server ............................... SUCCESS [6.169s]
[INFO] Tajo Storage ...................................... SUCCESS [49.786s]
[INFO] Tajo Core PullServer .............................. SUCCESS [0.833s]
[INFO] Tajo Client ....................................... SUCCESS [3.432s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [0.978s]
[INFO] Tajo Core ......................................... FAILURE [5:59.489s]
[INFO] Tajo Catalog Drivers .............................. SKIPPED
[INFO] Tajo Catalog ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 8:53.268s
[INFO] Finished at: Fri May 23 21:34:01 UTC 2014
[INFO] Final Memory: 46M/431M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4: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