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 2016/02/15 11:21:37 UTC
Build failed in Jenkins: Tajo-0.11.2-build #170
See <https://builds.apache.org/job/Tajo-0.11.2-build/170/changes>
Changes:
[jhkim] TAJO-2074: Upgrade hadoop and netty.
------------------------------------------
[...truncated 729976 lines...]
2016-02-15 10:21:09,780 INFO: org.apache.tajo.querymaster.Query (handle(861)) - Processing q_1455527640068_2150 of type STAGE_COMPLETED
2016-02-15 10:21:09,780 INFO: org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next executable block eb_1455527640068_2150_000004
2016-02-15 10:21:09,780 INFO: org.apache.tajo.querymaster.Query (executeNextBlock(761)) - Scheduling Stage:eb_1455527640068_2150_000004
2016-02-15 10:21:09,780 INFO: org.apache.tajo.worker.ExecutionBlockContext (stop(176)) - Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0
2016-02-15 10:21:09,780 INFO: org.apache.tajo.querymaster.Stage (initTaskScheduler(915)) - org.apache.tajo.querymaster.DefaultTaskScheduler is chosen for the task scheduling for eb_1455527640068_2150_000004
2016-02-15 10:21:09,781 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1109)) - eb_1455527640068_2150_000004, Table's volume is approximately 1 MB
2016-02-15 10:21:09,781 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1114)) - eb_1455527640068_2150_000004, The determined number of non-leaf tasks is 1
2016-02-15 10:21:09,781 INFO: org.apache.tajo.worker.TaskManager (stopExecutionBlock(160)) - Stopped execution block:eb_1455527640068_2150_000003
2016-02-15 10:21:09,781 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleRangeShuffledFetches(688)) - eb_1455527640068_2150_000004, Try to divide [(1,1), (5,3)) into 1 sub ranges (total units: 1)
2016-02-15 10:21:09,781 INFO: org.apache.tajo.engine.utils.TupleUtil (setMaxRangeIfNull(71)) - Set null into range: default.orders.o_orderkey, current tuple is (5,NULL)
2016-02-15 10:21:09,782 INFO: org.apache.tajo.querymaster.Stage (run(867)) - 1 objects are scheduled
2016-02-15 10:21:09,782 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (start(135)) - Start TaskScheduler
2016-02-15 10:21:09,784 INFO: org.apache.tajo.worker.TaskManager (createExecutionBlock(112)) - QueryMaster Address:asf908.gq1.ygridcore.net:22865
2016-02-15 10:21:09,786 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(128)) - Tajo Root Dir: hdfs://localhost:39593/tajo
2016-02-15 10:21:09,786 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(129)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-0.11.2-build/ws/tajo-core-tests/target/test-data/f27c0e6a-8be3-45c9-a053-633d1c2a01d7/tajo-localdir>
2016-02-15 10:21:09,787 INFO: org.apache.tajo.worker.TaskManager (handle(181)) - Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:500, Disks:6, vCores:2)
2016-02-15 10:21:09,788 INFO: org.apache.tajo.storage.FileTablespace (getAppenderFilePath(291)) - Output File Path: hdfs://localhost:39593/tajo/warehouse/default/nullcharsession/.staging/q_1455527640068_2150/RESULT/part-04-000000-000
2016-02-15 10:21:09,788 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - Output File Path: hdfs://localhost:39593/tajo/warehouse/default/nullcharsession/.staging/q_1455527640068_2150/RESULT/part-04-000000-000
2016-02-15 10:21:09,788 INFO: org.apache.tajo.worker.TaskImpl (initPlan(159)) - * Task ta_1455527640068_2150_000004_000000_00 is initialized. InterQuery: false, Shuffle: null, Fragments: 1, Fetches:1, Local dir: <https://builds.apache.org/job/Tajo-0.11.2-build/ws/tajo-core-tests/target/test-data/f27c0e6a-8be3-45c9-a053-633d1c2a01d7/tajo-localdir/q_1455527640068_2150/output/4/0_0>
2016-02-15 10:21:09,792 INFO: org.apache.tajo.worker.TaskImpl (getFetchRunners(744)) - Create shuffle Fetchers local:1, remote:0
2016-02-15 10:21:09,792 INFO: org.apache.tajo.worker.TaskImpl (waitForFetch(383)) - ta_1455527640068_2150_000004_000000_00 All fetches are done!
2016-02-15 10:21:09,795 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) - [ta_1455527640068_2150_000004_000000_00] Total merge time: 0 msec
2016-02-15 10:21:09,801 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073748418_7594{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-88eebddb-9ff2-4826-b251-39ebebb8a5c1:NORMAL:127.0.0.1:58499|FINALIZED]]} size 0
2016-02-15 10:21:09,802 INFO: org.apache.tajo.worker.TaskImpl (run(472)) - ta_1455527640068_2150_000004_000000_00 is complete. 10 ms elapsed, final state:TA_SUCCEEDED
2016-02-15 10:21:09,803 INFO: org.apache.tajo.querymaster.Stage (transition(1365)) - Stage - eb_1455527640068_2150_000004 finalize NONE_SHUFFLE (total=1, success=1, killed=0)
2016-02-15 10:21:09,803 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(164)) - Task Scheduler stopped
2016-02-15 10:21:09,803 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(127)) - TaskScheduler schedulingThread stopped
2016-02-15 10:21:09,803 INFO: org.apache.tajo.querymaster.Stage (transition(1423)) - Stage completed - eb_1455527640068_2150_000004 (total=1, success=1, killed=0)
2016-02-15 10:21:09,803 INFO: org.apache.tajo.querymaster.Query (handle(861)) - Processing q_1455527640068_2150 of type STAGE_COMPLETED
2016-02-15 10:21:09,803 INFO: org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next executable block eb_1455527640068_2150_000005
2016-02-15 10:21:09,803 INFO: org.apache.tajo.worker.ExecutionBlockContext (stop(176)) - Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0
2016-02-15 10:21:09,804 INFO: org.apache.tajo.querymaster.Query (transition(808)) - Complete Stage[eb_1455527640068_2150_000004], State: SUCCEEDED, 4/4.
2016-02-15 10:21:09,804 INFO: org.apache.tajo.querymaster.Query (handle(861)) - Processing q_1455527640068_2150 of type QUERY_COMPLETED
2016-02-15 10:21:09,804 INFO: org.apache.tajo.worker.TaskManager (stopExecutionBlock(160)) - Stopped execution block:eb_1455527640068_2150_000004
2016-02-15 10:21:09,807 INFO: org.apache.tajo.storage.FileTablespace (commitOutputData(964)) - Moved from the staging dir to the output directory 'hdfs://localhost:39593/tajo/warehouse/default/nullcharsession
2016-02-15 10:21:09,812 INFO: org.apache.tajo.catalog.CatalogServer (createTable(694)) - relation "default.nullcharsession" is added to the catalog (127.0.0.1:22859)
2016-02-15 10:21:09,812 INFO: org.apache.tajo.querymaster.Query (handle(879)) - q_1455527640068_2150 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED
2016-02-15 10:21:09,812 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(297)) - Query completion notified from q_1455527640068_2150 final state: QUERY_SUCCEEDED
2016-02-15 10:21:09,813 INFO: org.apache.tajo.master.QueryInProgress (heartbeat(257)) - Received QueryMaster heartbeat:q_1455527640068_2150,state=QUERY_SUCCEEDED,progress=1.0, queryMaster=asf908.gq1.ygridcore.net
2016-02-15 10:21:09,813 INFO: org.apache.tajo.master.QueryManager (stopQuery(279)) - Stop QueryInProgress:q_1455527640068_2150
2016-02-15 10:21:09,813 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(122)) - =========================================================
2016-02-15 10:21:09,813 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(123)) - Stop query:q_1455527640068_2150
2016-02-15 10:21:09,813 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(173)) - Stopping QueryMasterTask:q_1455527640068_2150
2016-02-15 10:21:09,813 INFO: org.apache.tajo.querymaster.QueryMasterTask (cleanupQuery(474)) - Cleanup resources of all workers. Query: q_1455527640068_2150, workers: 1
2016-02-15 10:21:09,814 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(189)) - Stopped QueryMasterTask:q_1455527640068_2150
2016-02-15 10:21:09,940 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(365)) - Saving query summary: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2148/query.hist
2016-02-15 10:21:09,947 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073748419_7595{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-b9d64e55-0132-49dd-a101-616297a0193f:NORMAL:127.0.0.1:58499|FINALIZED]]} size 0
2016-02-15 10:21:09,949 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2148/eb_1455527640068_2148_000002.hist
2016-02-15 10:21:09,952 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2148/eb_1455527640068_2148_000001.hist
2016-02-15 10:21:09,955 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(365)) - Saving query summary: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2149/query.hist
2016-02-15 10:21:09,961 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073748420_7596{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-88eebddb-9ff2-4826-b251-39ebebb8a5c1:NORMAL:127.0.0.1:58499|FINALIZED]]} size 0
2016-02-15 10:21:09,964 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2149/eb_1455527640068_2149_000002.hist
2016-02-15 10:21:09,966 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2149/eb_1455527640068_2149_000001.hist
2016-02-15 10:21:09,970 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(365)) - Saving query summary: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2150/query.hist
2016-02-15 10:21:09,976 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073748421_7597{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-b9d64e55-0132-49dd-a101-616297a0193f:NORMAL:127.0.0.1:58499|FINALIZED]]} size 0
2016-02-15 10:21:09,978 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2150/eb_1455527640068_2150_000001.hist
2016-02-15 10:21:09,983 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073748422_7598{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-88eebddb-9ff2-4826-b251-39ebebb8a5c1:NORMAL:127.0.0.1:58499|RBW]]} size 0
2016-02-15 10:21:09,985 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2150/eb_1455527640068_2150_000002.hist
2016-02-15 10:21:09,989 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073748423_7599{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-b9d64e55-0132-49dd-a101-616297a0193f:NORMAL:127.0.0.1:58499|FINALIZED]]} size 0
2016-02-15 10:21:09,991 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2150/eb_1455527640068_2150_000004.hist
2016-02-15 10:21:09,995 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073748424_7600{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-88eebddb-9ff2-4826-b251-39ebebb8a5c1:NORMAL:127.0.0.1:58499|RBW]]} size 0
2016-02-15 10:21:09,998 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:39593/tmp/tajo-jenkins/staging/history/20160215/query-detail/q_1455527640068_2150/eb_1455527640068_2150_000003.hist
2016-02-15 10:21:10,002 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073748425_7601{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-b9d64e55-0132-49dd-a101-616297a0193f:NORMAL:127.0.0.1:58499|FINALIZED]]} size 0
2016-02-15 10:21:10,176 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(240)) - SQL: create table testFailCreateTablePartitionedOtherExceptColumn (deptname text, score int4)PARTITION BY RANGE (score)( PARTITION sub_part1 VALUES LESS THAN (2),PARTITION sub_part2 VALUES LESS THAN (5),PARTITION sub_part2 VALUES LESS THAN (MAXVALUE) )
2016-02-15 10:21:10,186 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(240)) - SQL: create table testFailCreateTablePartitionedOtherExceptColumn (deptname text, score int4)PARTITION BY LIST (deptname)( PARTITION sub_part1 VALUES('r&d', 'design'),PARTITION sub_part2 VALUES('sales', 'hr') )
2016-02-15 10:21:10,189 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(240)) - SQL: create table testFailCreateTablePartitionedOtherExceptColumn (deptname text, score int4)PARTITION BY HASH (deptname)PARTITIONS 2
2016-02-15 10:21:10,190 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 847dd895-a04d-4a0b-8299-a278bf2e2e8f is removed.
Tests run: 26, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.532 sec - in org.apache.tajo.client.TestTajoClient
Running org.apache.tajo.client.TestQueryClientExceptions
2016-02-15 10:21:10,195 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session 457c1087-9990-4781-8052-ef059deb4cdc is created.
2016-02-15 10:21:10,196 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(240)) - SQL: create table default.lineitem (name int);
2016-02-15 10:21:10,197 WARN: org.apache.tajo.plan.verifier.VerificationState (addVerification(41)) - org.apache.tajo.plan.verifier.PreLogicalPlanVerifier:assertRelationNoExistence(187) causes: table 'default.lineitem' already exists
2016-02-15 10:21:10,627 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(177)) - Query: select * from unknown_table
2016-02-15 10:21:10,628 WARN: org.apache.tajo.plan.verifier.VerificationState (addVerification(41)) - org.apache.tajo.plan.verifier.PreLogicalPlanVerifier:assertRelationExistence(158) causes: relation 'default.unknown_table' does not exist
2016-02-15 10:21:10,629 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(177)) - Query: create table default.lineitem (name int);
2016-02-15 10:21:10,630 WARN: org.apache.tajo.plan.verifier.VerificationState (addVerification(41)) - org.apache.tajo.plan.verifier.PreLogicalPlanVerifier:assertRelationNoExistence(187) causes: table 'default.lineitem' already exists
2016-02-15 10:21:10,747 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(177)) - Query: select * from unknown_table
2016-02-15 10:21:10,748 WARN: org.apache.tajo.plan.verifier.VerificationState (addVerification(41)) - org.apache.tajo.plan.verifier.PreLogicalPlanVerifier:assertRelationExistence(158) causes: relation 'default.unknown_table' does not exist
2016-02-15 10:21:10,847 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 457c1087-9990-4781-8052-ef059deb4cdc is removed.
Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.642 sec - in org.apache.tajo.client.TestQueryClientExceptions
Running org.apache.tajo.resource.TestResources
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 sec - in org.apache.tajo.resource.TestResources
2016-02-15 10:21:10,855 INFO: org.mortbay.log (info(67)) - Shutdown hook executing
2016-02-15 10:21:10,855 INFO: org.mortbay.log (info(67)) - Shutdown hook complete
shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
Feb 15, 2016 10:11:01 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26
Feb 15, 2016 10:11:01 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
Feb 15, 2016 10:11:01 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
Feb 15, 2016 10:11:02 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26
Feb 15, 2016 10:11:02 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
Feb 15, 2016 10:11:02 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
Feb 15, 2016 10:11:02 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26
Feb 15, 2016 10:11:02 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
Feb 15, 2016 10:11:02 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers
Feb 15, 2016 10:11:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Feb 15, 2016 10:11:05 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 212
Feb 15, 2016 10:11:05 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 5 values, 10B raw, 10B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN_DICTIONARY], dic { 3 entries, 12B raw, 3B comp}
Feb 15, 2016 10:11:05 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 123B for [l_shipdate] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
Feb 15, 2016 10:11:05 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 123B for [l_shipdate_function] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings: [BIT_PACKED, RLE, PLAIN]
Feb 15, 2016 10:11:05 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Feb 15, 2016 10:11:05 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers
Feb 15, 2016 10:11:05 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
2016-02-15 10:21:10,862 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 630ddeb2-84ac-4e94-a3cd-16648f226f0e is removed.
2016-02-15 10:21:10,865 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session c12f3728-5c14-45dc-a980-7114304426ec is removed.
2016-02-15 10:21:10,865 INFO: BlockStateChange (logAddStoredBlock(2621)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58499 is added to blk_1073745872_5048{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-88eebddb-9ff2-4826-b251-39ebebb8a5c1:NORMAL:127.0.0.1:58499|RBW]]} size 957704
2016-02-15 10:21:10,869 INFO: org.apache.tajo.worker.TajoWorker (run(569)) - ============================================
2016-02-15 10:21:10,869 INFO: org.apache.tajo.worker.TajoWorker (run(570)) - TajoWorker received SIGINT Signal
2016-02-15 10:21:10,869 INFO: org.apache.tajo.worker.TajoWorker (run(571)) - ============================================
2016-02-15 10:21:10,871 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_asf908.gq1.ygridcore.net_22863 stopped.
2016-02-15 10:21:10,871 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped
2016-02-15 10:21:11,272 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(113)) - NodeStatusUpdater stopped.
2016-02-15 10:21:11,272 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(261)) - Heartbeat Thread stopped.
2016-02-15 10:21:11,272 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:22865) shutdown
2016-02-15 10:21:11,272 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) - QueryMasterManagerService stopped
2016-02-15 10:21:11,273 INFO: org.apache.tajo.querymaster.QueryMaster (run(432)) - QueryMaster heartbeat thread stopped
2016-02-15 10:21:11,273 INFO: org.apache.tajo.querymaster.QueryMaster (serviceStop(164)) - QueryMaster stopped
2016-02-15 10:21:11,273 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(98)) - TajoWorkerClientService stopping
2016-02-15 10:21:11,274 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:22864) shutdown
2016-02-15 10:21:11,274 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(102)) - TajoWorkerClientService stopped
2016-02-15 10:21:11,274 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:22863) shutdown
2016-02-15 10:21:11,274 INFO: org.apache.tajo.worker.TajoWorkerManagerService (serviceStop(93)) - TajoWorkerManagerService stopped
2016-02-15 10:21:11,275 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(379)) - TajoWorker main thread exiting
2016-02-15 10:21:14,479 INFO: org.apache.tajo.master.TajoMaster (run(580)) - ============================================
2016-02-15 10:21:14,480 INFO: org.apache.tajo.master.TajoMaster (run(581)) - TajoMaster received SIGINT Signal
2016-02-15 10:21:14,480 INFO: org.apache.tajo.master.TajoMaster (run(582)) - ============================================
2016-02-15 10:21:14,480 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (Tajo-REST) listened on 127.0.0.1:22862) shutdown
2016-02-15 10:21:14,480 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped.
2016-02-15 10:21:14,484 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:22861) shutdown
2016-02-15 10:21:14,485 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped
2016-02-15 10:21:14,484 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_127.0.0.1_22861 stopped.
2016-02-15 10:21:16,689 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:22860) shutdown
2016-02-15 10:21:16,689 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(188)) - Catalog Server (127.0.0.1:22859) shutdown
2016-02-15 10:21:16,690 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (CatalogProtocol) listened on 127.0.0.1:22859) shutdown
2016-02-15 10:21:16,690 INFO: org.apache.tajo.catalog.store.DerbyStore (close(2901)) - Close database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-0.11.2-build/ws/tajo-core-tests/target/test-data/f27c0e6a-8be3-45c9-a053-633d1c2a01d7/db;create=true)>
2016-02-15 10:21:16,690 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:22858) shutdown
2016-02-15 10:21:16,691 INFO: org.apache.tajo.master.TajoMaster (serviceStop(440)) - Tajo Master main thread exiting
2016-02-15 10:21:16,726 INFO: org.apache.tajo.catalog.store.DerbyStore (shutdown(68)) - Derby shutdown complete normally.
2016-02-15 10:21:16,726 INFO: org.apache.tajo.catalog.store.DerbyStore (shutdown(75)) - Shutdown database
Results :
Failed tests:
TestHBaseTable.testCreateNotExistsExternalHBaseTable:167 null
Tests in error:
TestHBaseTable.testBinaryMappedQuery:326 » RetriesExhausted Failed after attem...
TestHBaseTable.testCTAS:1293->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testColumnKeyValueSelectQuery:385 » RetriesExhausted Failed aft...
TestHBaseTable.testCompositeRowIndexPredication:512->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testCreateExternalHBaseTable:196 » RetriesExhausted Failed afte...
TestHBaseTable.testCreateHBaseTable:127->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testGetSplitsWhenRestartHBase:1443->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testGetTableVolume:246 » RetriesExhausted Failed after attempts...
TestHBaseTable.testIndexPredication:467->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testInsertInto:742->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testInsertIntoBinaryMultiRegion:1053->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testInsertIntoColumnKeyValue:1108->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testInsertIntoDifferentType:1198->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testInsertIntoLocation:1390->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testInsertIntoMultiRegion:824->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testInsertIntoMultiRegion2:880->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testInsertIntoMultiRegionMultiRowFields:994->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testInsertIntoMultiRegionWithSplitFile:937->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testInsertIntoRowField:1234->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testInsertIntoUsingPut:1340->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testInsertValues1:782->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testJoin:702->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testNonForwardQuery:664->QueryTestCaseBase.executeString:391 » TajoInternal
TestHBaseTable.testRowFieldSelectQuery:428 » RetriesExhausted Failed after att...
TestHBaseTable.testSimpleSelectQuery:288 » RetriesExhausted Failed after attem...
Tests run: 1715, Failures: 1, Errors: 25, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 2.002 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 1.263 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 5.552 s]
[INFO] Tajo Common ....................................... SUCCESS [ 39.548 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 3.173 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 5.954 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 6.892 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 2.959 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [02:25 min]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.641 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [01:14 min]
[INFO] Tajo Storage Common ............................... SUCCESS [ 6.552 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [01:23 min]
[INFO] Tajo PullServer ................................... SUCCESS [ 2.410 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.470 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 2.717 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 6.033 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 1.774 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 4.169 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.849 s]
[INFO] Tajo Core ......................................... SUCCESS [ 6.206 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 0.927 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 34.607 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.103 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 0.977 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.037 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 5.729 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 3.578 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 40.500 s]
[INFO] Tajo JDBC storage common .......................... SUCCESS [ 0.912 s]
[INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 1.046 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 1.004 s]
[INFO] Tajo Distribution ................................. SUCCESS [ 5.904 s]
[INFO] Tajo Core Tests ................................... FAILURE [ 01:07 h]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 01:15 h
[INFO] Finished at: 2016-02-15T10:21:17+00:00
[INFO] Final Memory: 139M/916M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.19:test (default-test) on project tajo-core-tests: There are test failures.
[ERROR]
[ERROR] Please refer to <https://builds.apache.org/job/Tajo-0.11.2-build/ws/tajo-core-tests/target/surefire-reports> for the individual test results.
[ERROR] -> [Help 1]
[ERROR]
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR]
[ERROR] For more information about the errors and possible solutions, please read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :tajo-core-tests
Build step 'Execute shell' marked build as failure
Archiving artifacts
Updating TAJO-2074