See <https://builds.apache.org/job/Tajo-0.11.1-nightly/97/changes>
Changes: [jhkim] TAJO-2013: FilterPushDownRule fails due to the unsupported index. [jhkim] TAJO-2016: Fix storage tests hangs. ------------------------------------------ [...truncated 710997 lines...] 2015-12-10 08:12:37,602 INFO: org.apache.tajo.querymaster.Stage (transition(1348)) - Stage - eb_1449731324472_2154_000002 finalize HASH_SHUFFLE (total=1, success=1, killed=0) 2015-12-10 08:12:37,602 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(166)) - Task Scheduler stopped 2015-12-10 08:12:37,602 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(129)) - TaskScheduler schedulingThread stopped 2015-12-10 08:12:37,602 INFO: org.apache.tajo.querymaster.Stage (transition(1359)) - eb_1449731324472_2154_000002, wait for HASH_SHUFFLE reports. expected Tasks:1 2015-12-10 08:12:37,603 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(181)) - Close HashShuffleAppenderWrapper:eb_1449731324472_2154_000002, intermediates=1 2015-12-10 08:12:37,603 INFO: org.apache.tajo.querymaster.Stage (finalizeShuffleReport(1305)) - eb_1449731324472_2154_000002, Finalized HASH_SHUFFLE reports: 1 2015-12-10 08:12:37,603 INFO: org.apache.tajo.querymaster.Stage (transition(1406)) - Stage completed - eb_1449731324472_2154_000002 (total=1, success=1, killed=0) 2015-12-10 08:12:37,603 INFO: org.apache.tajo.worker.ExecutionBlockContext (stop(169)) - Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0 2015-12-10 08:12:37,603 INFO: org.apache.tajo.querymaster.Query (handle(851)) - Processing q_1449731324472_2154 of type STAGE_COMPLETED 2015-12-10 08:12:37,604 INFO: org.apache.tajo.worker.TaskManager (stopExecutionBlock(160)) - Stopped execution block:eb_1449731324472_2154_000002 2015-12-10 08:12:37,604 INFO: org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next executable block eb_1449731324472_2154_000003 2015-12-10 08:12:37,604 INFO: org.apache.tajo.querymaster.Query (executeNextBlock(753)) - Scheduling Stage:eb_1449731324472_2154_000003 2015-12-10 08:12:37,604 INFO: org.apache.tajo.querymaster.Stage (calculateShuffleOutputNum(1025)) - ============>>>>> Unexpected Case! <<<<<================ 2015-12-10 08:12:37,604 INFO: org.apache.tajo.querymaster.Stage (calculateShuffleOutputNum(1029)) - eb_1449731324472_2154_000003, Table's volume is approximately 1 MB 2015-12-10 08:12:37,604 INFO: org.apache.tajo.querymaster.Stage (calculateShuffleOutputNum(1032)) - eb_1449731324472_2154_000003, The determined number of partitions is 1 2015-12-10 08:12:37,624 INFO: org.apache.tajo.querymaster.Stage (initTaskScheduler(900)) - org.apache.tajo.querymaster.DefaultTaskScheduler is chosen for the task scheduling for eb_1449731324472_2154_000003 2015-12-10 08:12:37,624 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleFragmentsForJoinQuery(265)) - [Distributed Join Strategy] : Symmetric Repartition Join 2015-12-10 08:12:37,624 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleSymmetricRepartitionJoin(361)) - Larger intermediate data is approximately 1 MB 2015-12-10 08:12:37,624 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleSymmetricRepartitionJoin(364)) - The calculated number of tasks is 1 2015-12-10 08:12:37,625 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleSymmetricRepartitionJoin(365)) - The number of total shuffle keys is 1 2015-12-10 08:12:37,625 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleSymmetricRepartitionJoin(369)) - The determined number of join tasks is 1 2015-12-10 08:12:37,625 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:37180/tmp/tajo-jenkins/staging/history/20151210/query-detail/q_1449731324472_2152/eb_1449731324472_2152_000002.hist 2015-12-10 08:12:37,625 INFO: org.apache.tajo.querymaster.Stage (run(852)) - 1 objects are scheduled 2015-12-10 08:12:37,625 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (start(137)) - Start TaskScheduler 2015-12-10 08:12:37,627 INFO: org.apache.tajo.worker.TaskManager (createExecutionBlock(112)) - QueryMaster Address:asf908.gq1.ygridcore.net:20070 2015-12-10 08:12:37,627 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:37180/tmp/tajo-jenkins/staging/history/20151210/query-detail/q_1449731324472_2152/eb_1449731324472_2152_000001.hist 2015-12-10 08:12:37,630 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(121)) - Tajo Root Dir: hdfs://localhost:37180/tajo 2015-12-10 08:12:37,630 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(122)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-0.11.1-nightly/ws/tajo-core-tests/target/test-data/efc8088f-3e56-4f98-bf21-b792b5760de0/tajo-localdir> 2015-12-10 08:12:37,630 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(365)) - Saving query summary: hdfs://localhost:37180/tmp/tajo-jenkins/staging/history/20151210/query-detail/q_1449731324472_2153/query.hist 2015-12-10 08:12:37,631 INFO: org.apache.tajo.worker.TaskManager (handle(181)) - Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:1000, Disks:3, vCores:2) 2015-12-10 08:12:37,631 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - * Task ta_1449731324472_2154_000003_000000_00 is initialized. InterQuery: true, Shuffle: RANGE_SHUFFLE, Fragments: 4, Fetches:2, Local dir: <https://builds.apache.org/job/Tajo-0.11.1-nightly/ws/tajo-core-tests/target/test-data/efc8088f-3e56-4f98-bf21-b792b5760de0/tajo-localdir/q_1449731324472_2154/output/3/0_0> 2015-12-10 08:12:37,633 INFO: org.apache.tajo.worker.TaskImpl (getFetchRunners(719)) - Create shuffle Fetchers local:2, remote:0 2015-12-10 08:12:37,633 INFO: org.apache.tajo.worker.TaskImpl (waitForFetch(361)) - ta_1449731324472_2154_000003_000000_00 All fetches are done! 2015-12-10 08:12:37,634 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createFullOuterHashJoinPlan(622)) - Full Outer Join (7) chooses [Hash Join] 2015-12-10 08:12:37,635 INFO: org.apache.tajo.engine.planner.physical.RangeShuffleFileWriteExec (init(80)) - Output data directory: <https://builds.apache.org/job/Tajo-0.11.1-nightly/ws/tajo-core-tests/target/test-data/efc8088f-3e56-4f98-bf21-b792b5760de0/tajo-localdir/q_1449731324472_2154/output/3/0_0/output> 2015-12-10 08:12:37,636 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42246 is added to blk_1073748202_7378{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-de2d52eb-c526-409f-9d78-bd585765eca5:NORMAL:127.0.0.1:42246|FINALIZED]]} size 0 2015-12-10 08:12:37,638 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:37180/tmp/tajo-jenkins/staging/history/20151210/query-detail/q_1449731324472_2153/eb_1449731324472_2153_000002.hist 2015-12-10 08:12:37,640 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:37180/tmp/tajo-jenkins/staging/history/20151210/query-detail/q_1449731324472_2153/eb_1449731324472_2153_000001.hist 2015-12-10 08:12:37,655 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) - [ta_1449731324472_2154_000003_000000_00] 1 Chunks creation time: 0 msec 2015-12-10 08:12:37,655 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) - [ta_1449731324472_2154_000003_000000_00] Total merge time: 0 msec 2015-12-10 08:12:37,656 INFO: org.apache.tajo.worker.TaskImpl (run(459)) - ta_1449731324472_2154_000003_000000_00 is complete. 23 ms elapsed, final state:TA_SUCCEEDED 2015-12-10 08:12:37,656 INFO: org.apache.tajo.querymaster.Stage (transition(1348)) - Stage - eb_1449731324472_2154_000003 finalize RANGE_SHUFFLE (total=1, success=1, killed=0) 2015-12-10 08:12:37,657 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(166)) - Task Scheduler stopped 2015-12-10 08:12:37,657 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(129)) - TaskScheduler schedulingThread stopped 2015-12-10 08:12:37,657 INFO: org.apache.tajo.querymaster.Stage (transition(1406)) - Stage completed - eb_1449731324472_2154_000003 (total=1, success=1, killed=0) 2015-12-10 08:12:37,657 INFO: org.apache.tajo.querymaster.Query (handle(851)) - Processing q_1449731324472_2154 of type STAGE_COMPLETED 2015-12-10 08:12:37,657 INFO: org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next executable block eb_1449731324472_2154_000004 2015-12-10 08:12:37,657 INFO: org.apache.tajo.worker.ExecutionBlockContext (stop(169)) - Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0 2015-12-10 08:12:37,657 INFO: org.apache.tajo.querymaster.Query (executeNextBlock(753)) - Scheduling Stage:eb_1449731324472_2154_000004 2015-12-10 08:12:37,658 INFO: org.apache.tajo.worker.TaskManager (stopExecutionBlock(160)) - Stopped execution block:eb_1449731324472_2154_000003 2015-12-10 08:12:37,675 INFO: org.apache.tajo.querymaster.Stage (initTaskScheduler(900)) - org.apache.tajo.querymaster.DefaultTaskScheduler is chosen for the task scheduling for eb_1449731324472_2154_000004 2015-12-10 08:12:37,675 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1094)) - eb_1449731324472_2154_000004, Table's volume is approximately 1 MB 2015-12-10 08:12:37,676 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1099)) - eb_1449731324472_2154_000004, The determined number of non-leaf tasks is 1 2015-12-10 08:12:37,676 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleRangeShuffledFetches(676)) - eb_1449731324472_2154_000004, Try to divide [(1,1), (5,3)) into 1 sub ranges (total units: 1) 2015-12-10 08:12:37,676 INFO: org.apache.tajo.engine.utils.TupleUtil (setMaxRangeIfNull(103)) - Set null into range: default.orders.o_orderkey, current tuple is (5,NULL) 2015-12-10 08:12:37,677 INFO: org.apache.tajo.querymaster.Stage (run(852)) - 1 objects are scheduled 2015-12-10 08:12:37,677 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (start(137)) - Start TaskScheduler 2015-12-10 08:12:37,680 INFO: org.apache.tajo.worker.TaskManager (createExecutionBlock(112)) - QueryMaster Address:asf908.gq1.ygridcore.net:20070 2015-12-10 08:12:37,682 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(121)) - Tajo Root Dir: hdfs://localhost:37180/tajo 2015-12-10 08:12:37,682 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(122)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-0.11.1-nightly/ws/tajo-core-tests/target/test-data/efc8088f-3e56-4f98-bf21-b792b5760de0/tajo-localdir> 2015-12-10 08:12:37,683 INFO: org.apache.tajo.worker.TaskManager (handle(181)) - Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:1000, Disks:3, vCores:2) 2015-12-10 08:12:37,684 INFO: org.apache.tajo.storage.FileTablespace (getAppenderFilePath(291)) - Output File Path: hdfs://localhost:37180/tajo/warehouse/default/nullcharsession/.staging/q_1449731324472_2154/RESULT/part-04-000000-000 2015-12-10 08:12:37,684 INFO: org.apache.tajo.worker.TaskImpl (initPlan(146)) - Output File Path: hdfs://localhost:37180/tajo/warehouse/default/nullcharsession/.staging/q_1449731324472_2154/RESULT/part-04-000000-000 2015-12-10 08:12:37,684 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - * Task ta_1449731324472_2154_000004_000000_00 is initialized. InterQuery: false, Shuffle: null, Fragments: 1, Fetches:1, Local dir: <https://builds.apache.org/job/Tajo-0.11.1-nightly/ws/tajo-core-tests/target/test-data/efc8088f-3e56-4f98-bf21-b792b5760de0/tajo-localdir/q_1449731324472_2154/output/4/0_0> 2015-12-10 08:12:37,706 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileChunks(693)) - GET Request for <https://builds.apache.org/job/Tajo-0.11.1-nightly/ws/tajo-core-tests/target/test-data/efc8088f-3e56-4f98-bf21-b792b5760de0/tajo-localdir/q_1449731324472_2154/output/3/0_0/output/output> (start=(1,1), end=(5,NULL), last=true) 2015-12-10 08:12:37,707 INFO: org.apache.tajo.worker.TaskImpl (getFetchRunners(719)) - Create shuffle Fetchers local:1, remote:0 2015-12-10 08:12:37,707 INFO: org.apache.tajo.worker.TaskImpl (waitForFetch(361)) - ta_1449731324472_2154_000004_000000_00 All fetches are done! 2015-12-10 08:12:37,711 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) - [ta_1449731324472_2154_000004_000000_00] Total merge time: 0 msec 2015-12-10 08:12:37,718 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42246 is added to blk_1073748203_7379{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-efcaf662-ef84-428c-81a0-70a5245f376a:NORMAL:127.0.0.1:42246|RBW]]} size 0 2015-12-10 08:12:37,719 INFO: org.apache.tajo.worker.TaskImpl (run(459)) - ta_1449731324472_2154_000004_000000_00 is complete. 12 ms elapsed, final state:TA_SUCCEEDED 2015-12-10 08:12:37,719 INFO: org.apache.tajo.querymaster.Stage (transition(1348)) - Stage - eb_1449731324472_2154_000004 finalize NONE_SHUFFLE (total=1, success=1, killed=0) 2015-12-10 08:12:37,720 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(166)) - Task Scheduler stopped 2015-12-10 08:12:37,720 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(129)) - TaskScheduler schedulingThread stopped 2015-12-10 08:12:37,720 INFO: org.apache.tajo.querymaster.Stage (transition(1406)) - Stage completed - eb_1449731324472_2154_000004 (total=1, success=1, killed=0) 2015-12-10 08:12:37,720 INFO: org.apache.tajo.querymaster.Query (handle(851)) - Processing q_1449731324472_2154 of type STAGE_COMPLETED 2015-12-10 08:12:37,720 INFO: org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next executable block eb_1449731324472_2154_000005 2015-12-10 08:12:37,720 INFO: org.apache.tajo.querymaster.Query (transition(798)) - Complete Stage[eb_1449731324472_2154_000004], State: SUCCEEDED, 4/4. 2015-12-10 08:12:37,720 INFO: org.apache.tajo.querymaster.Query (handle(851)) - Processing q_1449731324472_2154 of type QUERY_COMPLETED 2015-12-10 08:12:37,720 INFO: org.apache.tajo.worker.ExecutionBlockContext (stop(169)) - Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0 2015-12-10 08:12:37,721 INFO: org.apache.tajo.worker.TaskManager (stopExecutionBlock(160)) - Stopped execution block:eb_1449731324472_2154_000004 2015-12-10 08:12:37,723 INFO: org.apache.tajo.storage.FileTablespace (commitOutputData(966)) - Moved from the staging dir to the output directory 'hdfs://localhost:37180/tajo/warehouse/default/nullcharsession 2015-12-10 08:12:37,729 INFO: org.apache.tajo.catalog.CatalogServer (createTable(694)) - relation "default.nullcharsession" is added to the catalog (127.0.0.1:20064) 2015-12-10 08:12:37,729 INFO: org.apache.tajo.querymaster.Query (handle(869)) - q_1449731324472_2154 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED 2015-12-10 08:12:37,730 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(296)) - Query completion notified from q_1449731324472_2154 final state: QUERY_SUCCEEDED 2015-12-10 08:12:37,730 INFO: org.apache.tajo.master.QueryInProgress (heartbeat(257)) - Received QueryMaster heartbeat:q_1449731324472_2154,state=QUERY_SUCCEEDED,progress=1.0, queryMaster=asf908.gq1.ygridcore.net 2015-12-10 08:12:37,730 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(172)) - Stopping QueryMasterTask:q_1449731324472_2154 2015-12-10 08:12:37,731 INFO: org.apache.tajo.master.QueryManager (stopQuery(279)) - Stop QueryInProgress:q_1449731324472_2154 2015-12-10 08:12:37,731 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(122)) - ========================================================= 2015-12-10 08:12:37,731 INFO: org.apache.tajo.querymaster.QueryMasterTask (cleanupQuery(473)) - Cleanup resources of all workers. Query: q_1449731324472_2154, workers: 1 2015-12-10 08:12:37,731 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(123)) - Stop query:q_1449731324472_2154 2015-12-10 08:12:37,731 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(188)) - Stopped QueryMasterTask:q_1449731324472_2154 2015-12-10 08:12:37,803 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) ) 2015-12-10 08:12:37,811 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') ) 2015-12-10 08:12:37,813 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(240)) - SQL: create table testFailCreateTablePartitionedOtherExceptColumn (deptname text, score int4)PARTITION BY HASH (deptname)PARTITIONS 2 2015-12-10 08:12:37,815 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 2b461407-116d-4fba-975e-111993e9e5ce is removed. Tests run: 26, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.707 sec - in org.apache.tajo.client.TestTajoClient Running org.apache.tajo.client.TestQueryClientExceptions 2015-12-10 08:12:37,823 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session ca7cfc39-e5d3-437c-ab4f-6594cc1dd855 is created. 2015-12-10 08:12:37,825 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(240)) - SQL: create table default.lineitem (name int); 2015-12-10 08:12:37,826 WARN: org.apache.tajo.plan.verifier.VerificationState (addVerification(41)) - org.apache.tajo.plan.verifier.PreLogicalPlanVerifier:assertRelationNoExistence(187) causes: table 'default.lineitem' already exists 2015-12-10 08:12:38,250 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(177)) - Query: select * from unknown_table 2015-12-10 08:12:38,251 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 2015-12-10 08:12:38,252 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(177)) - Query: create table default.lineitem (name int); 2015-12-10 08:12:38,252 WARN: org.apache.tajo.plan.verifier.VerificationState (addVerification(41)) - org.apache.tajo.plan.verifier.PreLogicalPlanVerifier:assertRelationNoExistence(187) causes: table 'default.lineitem' already exists 2015-12-10 08:12:38,301 INFO: BlockStateChange (invalidateWorkForOneNode(3488)) - BLOCK* BlockManager: ask 127.0.0.1:42246 to delete [blk_1073748191_7367] 2015-12-10 08:12:38,372 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(177)) - Query: select * from unknown_table 2015-12-10 08:12:38,373 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 2015-12-10 08:12:38,467 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session ca7cfc39-e5d3-437c-ab4f-6594cc1dd855 is removed. Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.632 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 sec - in org.apache.tajo.resource.TestResources Dec 10, 2015 7:59:31 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Dec 10, 2015 7:59:31 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Dec 10, 2015 8:00:06 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Dec 10, 2015 8:00:06 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Dec 10, 2015 8:00:06 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Dec 10, 2015 8:00:06 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Dec 10, 2015 8:00:06 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Dec 10, 2015 8:00:10 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers 2015-12-10 08:12:38,477 INFO: org.mortbay.log (invoke0(?)) - Shutdown hook executing 2015-12-10 08:12:38,478 INFO: org.mortbay.log (invoke(?)) - Shutdown hook complete 2015-12-10 08:12:38,481 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 001bbbc9-afa4-4719-b7f7-13c2dc31a175 is removed. 2015-12-10 08:12:38,483 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 225b48e5-e66d-4c14-8a30-2836bdcbbeb3 is removed. 2015-12-10 08:12:38,484 INFO: org.apache.tajo.worker.TajoWorker (run(569)) - ============================================ 2015-12-10 08:12:38,484 INFO: org.apache.tajo.worker.TajoWorker (run(570)) - TajoWorker received SIGINT Signal 2015-12-10 08:12:38,484 INFO: org.apache.tajo.worker.TajoWorker (run(571)) - ============================================ 2015-12-10 08:12:38,487 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_asf908.gq1.ygridcore.net_20068 stopped. 2015-12-10 08:12:38,487 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2015-12-10 08:12:38,487 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42246 is added to blk_1073747456_6632{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-de2d52eb-c526-409f-9d78-bd585765eca5:NORMAL:127.0.0.1:42246|RBW]]} size 242218 2015-12-10 08:12:38,966 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(113)) - NodeStatusUpdater stopped. 2015-12-10 08:12:38,966 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(261)) - Heartbeat Thread stopped. 2015-12-10 08:12:38,966 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:20070) shutdown 2015-12-10 08:12:38,966 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) - QueryMasterManagerService stopped 2015-12-10 08:12:38,967 INFO: org.apache.tajo.querymaster.QueryMaster (run(428)) - QueryMaster heartbeat thread stopped 2015-12-10 08:12:38,967 INFO: org.apache.tajo.querymaster.QueryMaster (serviceStop(164)) - QueryMaster stopped 2015-12-10 08:12:38,967 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(98)) - TajoWorkerClientService stopping 2015-12-10 08:12:38,968 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:20069) shutdown 2015-12-10 08:12:38,968 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(102)) - TajoWorkerClientService stopped 2015-12-10 08:12:38,968 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:20068) shutdown 2015-12-10 08:12:38,968 INFO: org.apache.tajo.worker.TajoWorkerManagerService (serviceStop(93)) - TajoWorkerManagerService stopped 2015-12-10 08:12:38,968 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(379)) - TajoWorker main thread exiting 2015-12-10 08:12:43,276 INFO: org.apache.tajo.master.TajoMaster (run(580)) - ============================================ 2015-12-10 08:12:43,277 INFO: org.apache.tajo.master.TajoMaster (run(581)) - TajoMaster received SIGINT Signal 2015-12-10 08:12:43,277 INFO: org.apache.tajo.master.TajoMaster (run(582)) - ============================================ 2015-12-10 08:12:43,277 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:20067) shutdown 2015-12-10 08:12:43,277 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped. 2015-12-10 08:12:43,282 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_127.0.0.1_20066 stopped. 2015-12-10 08:12:43,282 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2015-12-10 08:12:43,282 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:20066) shutdown 2015-12-10 08:12:43,283 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:20065) shutdown 2015-12-10 08:12:43,283 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(188)) - Catalog Server (127.0.0.1:20064) shutdown 2015-12-10 08:12:43,283 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (CatalogProtocol) listened on 127.0.0.1:20064) shutdown 2015-12-10 08:12:43,284 INFO: org.apache.tajo.catalog.store.DerbyStore (close(2901)) - Close database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-0.11.1-nightly/ws/tajo-core-tests/target/test-data/efc8088f-3e56-4f98-bf21-b792b5760de0/db;create=true)> 2015-12-10 08:12:43,284 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:20063) shutdown 2015-12-10 08:12:43,284 INFO: org.apache.tajo.master.TajoMaster (serviceStop(440)) - Tajo Master main thread exiting 2015-12-10 08:12:43,321 INFO: org.apache.tajo.catalog.store.DerbyStore (shutdown(68)) - Derby shutdown complete normally. 2015-12-10 08:12:43,321 INFO: org.apache.tajo.catalog.store.DerbyStore (shutdown(75)) - Shutdown database Results : Failed tests: TestHBaseTable.testCreateNotExistsExternalHBaseTable:168 null Tests in error: TestHBaseTable.testBinaryMappedQuery:280 » RetriesExhausted Failed after attem... TestHBaseTable.testCTAS:1247->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testColumnKeyValueSelectQuery:339 » RetriesExhausted Failed aft... TestHBaseTable.testCompositeRowIndexPredication:466->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testCreateExternalHBaseTable:197 » RetriesExhausted Failed afte... TestHBaseTable.testCreateHBaseTable:128->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testIndexPredication:421->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertInto:696->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoBinaryMultiRegion:1007->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoColumnKeyValue:1062->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoDifferentType:1152->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoLocation:1344->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoMultiRegion:778->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoMultiRegion2:834->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoMultiRegionMultiRowFields:948->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoMultiRegionWithSplitFile:891->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoRowField:1188->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertIntoUsingPut:1294->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testInsertValues1:736->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testJoin:656->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testNonForwardQuery:618->QueryTestCaseBase.executeString:391 » TajoInternal TestHBaseTable.testRowFieldSelectQuery:382 » RetriesExhausted Failed after att... TestHBaseTable.testSimpleSelectQuery:234 » RetriesExhausted Failed after attem... Tests run: 1717, Failures: 1, Errors: 23, Skipped: 0 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Tajo Main ......................................... SUCCESS [ 3.198 s] [INFO] Tajo Project POM .................................. SUCCESS [ 3.457 s] [INFO] Tajo Maven Plugins ................................ SUCCESS [ 5.210 s] [INFO] Tajo Common ....................................... SUCCESS [ 44.356 s] [INFO] Tajo Algebra ...................................... SUCCESS [ 3.779 s] [INFO] Tajo Catalog Common ............................... SUCCESS [ 8.420 s] [INFO] Tajo Plan ......................................... SUCCESS [ 7.931 s] [INFO] Tajo Rpc Common ................................... SUCCESS [ 1.345 s] [INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [02:25 min] [INFO] Tajo Catalog Client ............................... SUCCESS [ 1.720 s] [INFO] Tajo Catalog Server ............................... SUCCESS [01:28 min] [INFO] Tajo Storage Common ............................... SUCCESS [ 4.009 s] [INFO] Tajo HDFS Storage ................................. SUCCESS [01:29 min] [INFO] Tajo PullServer ................................... SUCCESS [ 1.303 s] [INFO] Tajo Client ....................................... SUCCESS [ 2.587 s] [INFO] Tajo CLI tools .................................... SUCCESS [ 1.975 s] [INFO] Tajo SQL Parser ................................... SUCCESS [ 5.966 s] [INFO] ASM (thirdparty) .................................. SUCCESS [ 1.977 s] [INFO] Tajo RESTful Container ............................ SUCCESS [ 4.977 s] [INFO] Tajo Metrics ...................................... SUCCESS [ 1.801 s] [INFO] Tajo Core ......................................... SUCCESS [ 6.742 s] [INFO] Tajo RPC .......................................... SUCCESS [ 0.927 s] [INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 37.953 s] [INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.064 s] [INFO] Tajo Catalog ...................................... SUCCESS [ 0.988 s] [INFO] Tajo Client Example ............................... SUCCESS [ 1.119 s] [INFO] Tajo HBase Storage ................................ SUCCESS [ 5.337 s] [INFO] Tajo Cluster Tests ................................ SUCCESS [ 3.560 s] [INFO] Tajo JDBC Driver .................................. SUCCESS [ 40.346 s] [INFO] Tajo JDBC storage common .......................... SUCCESS [ 0.920 s] [INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 1.035 s] [INFO] Tajo Storage ...................................... SUCCESS [ 0.999 s] [INFO] Tajo Distribution ................................. SUCCESS [ 5.528 s] [INFO] Tajo Core Tests ................................... FAILURE [ 01:04 h] [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 01:13 h [INFO] Finished at: 2015-12-10T08:12:43+00:00 [INFO] Final Memory: 143M/1009M [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.1-nightly/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 Recording test results
