You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@tajo.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2015/08/03 09:12:55 UTC
Build failed in Jenkins: Tajo-master-build #786
See <https://builds.apache.org/job/Tajo-master-build/786/changes>
Changes:
[jhkim] TAJO-1736: Remove unnecessary getMountPath().
------------------------------------------
[...truncated 676833 lines...]
2015-08-03 07:13:25,634 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(142)) - Query status of ta_1438584797456_1967_000004_000000_00 is changed to TA_PENDING
2015-08-03 07:13:25,634 INFO: org.apache.tajo.worker.TaskImpl (init(195)) - Initializing: ta_1438584797456_1967_000004_000000_00
2015-08-03 07:13:25,635 INFO: org.apache.tajo.storage.FileTablespace (getAppenderFilePath(310)) - Output File Path: hdfs://localhost:41004/tmp/tajo-jenkins/staging/q_1438584797456_1967/RESULT/part-04-000000-000
2015-08-03 07:13:25,635 INFO: org.apache.tajo.worker.TaskImpl (initPlan(145)) - Output File Path: hdfs://localhost:41004/tmp/tajo-jenkins/staging/q_1438584797456_1967/RESULT/part-04-000000-000
2015-08-03 07:13:25,635 INFO: org.apache.tajo.worker.TaskImpl (initPlan(150)) - ==================================
2015-08-03 07:13:25,635 INFO: org.apache.tajo.worker.TaskImpl (initPlan(151)) - * Stage ta_1438584797456_1967_000004_000000_00 is initialized
2015-08-03 07:13:25,635 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - * InterQuery: false, Fragments (num: 1), Fetches (total:1) :
2015-08-03 07:13:25,635 INFO: org.apache.tajo.worker.TaskImpl (initPlan(162)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d008c333-0094-454c-a9e3-00f488457cfd/tajo-localdir/q_1438584797456_1967/output/4/0_0>
2015-08-03 07:13:25,635 INFO: org.apache.tajo.worker.TaskImpl (initPlan(167)) - ==================================
2015-08-03 07:13:25,636 INFO: org.apache.tajo.worker.TaskImpl (init(214)) - the directory is created <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d008c333-0094-454c-a9e3-00f488457cfd/tajo-localdir/q_1438584797456_1967/in/eb_1438584797456_1967_000004/0/0/eb_1438584797456_1967_000003>
2015-08-03 07:13:25,637 INFO: org.apache.tajo.worker.TaskImpl (getFetchRunners(692)) - Try to get local file chunk at local host
2015-08-03 07:13:25,637 INFO: org.apache.tajo.worker.TaskImpl (getLocalStoredFileChunk(734)) - getLocalStoredFileChunk starts
2015-08-03 07:13:25,637 INFO: org.apache.tajo.worker.TaskImpl (getLocalStoredFileChunk(769)) - PullServer request param: shuffleType=r, sid=3, partId=0, taskIds=[0_0]
2015-08-03 07:13:25,658 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileChunks(686)) - BSTIndex is loaded from disk ((1,1), (5,NULL)
2015-08-03 07:13:25,658 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileChunks(710)) - GET Request for <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d008c333-0094-454c-a9e3-00f488457cfd/tajo-localdir/q_1438584797456_1967/output/3/0_0/output/output> (start=(1,1), end=(5,NULL), last=true)
2015-08-03 07:13:25,659 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileChunks(779)) - Retrieve File Chunk: (start=0, length=49, fromRemote=false, ebId=null) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d008c333-0094-454c-a9e3-00f488457cfd/tajo-localdir/q_1438584797456_1967/output/3/0_0/output/output>
2015-08-03 07:13:25,659 INFO: org.apache.tajo.worker.TaskImpl (getFetchRunners(720)) - Create a new Fetcher with storeChunk: (start=0, length=49, fromRemote=false, ebId=eb_1438584797456_1967_000003) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d008c333-0094-454c-a9e3-00f488457cfd/tajo-localdir/q_1438584797456_1967/output/3/0_0/output/output>
2015-08-03 07:13:25,659 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(142)) - Query status of ta_1438584797456_1967_000004_000000_00 is changed to TA_RUNNING
2015-08-03 07:13:25,659 INFO: org.apache.tajo.worker.Fetcher (get(128)) - Get pseudo fetch from local host
2015-08-03 07:13:25,659 INFO: org.apache.tajo.worker.TaskImpl (run(616)) - Add a new FileChunk to local chunk list
2015-08-03 07:13:25,659 INFO: org.apache.tajo.worker.TaskImpl (waitForFetch(362)) - ta_1438584797456_1967_000004_000000_00 All fetches are done!
2015-08-03 07:13:25,659 INFO: org.apache.tajo.worker.TaskImpl (localizeFetchedData(572)) - One local chunk is added to listTablets
2015-08-03 07:13:25,663 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) - [ta_1438584797456_1967_000004_000000_00] Total merge time: 0 msec
2015-08-03 07:13:25,663 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (close(796)) - Delete file: "fragment": {"id": "eb_1438584797456_1967_000003", "path": <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/d008c333-0094-454c-a9e3-00f488457cfd/tajo-localdir/q_1438584797456_1967/output/3/0_0/output/output",> "start": 0,"length": 49}
2015-08-03 07:13:25,668 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748222_7398{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-fb25aafa-396b-4c8a-a025-34404fbb325a:NORMAL:127.0.0.1:60826|RBW]]} size 0
2015-08-03 07:13:25,669 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(142)) - Query status of ta_1438584797456_1967_000004_000000_00 is changed to TA_SUCCEEDED
2015-08-03 07:13:25,669 INFO: org.apache.tajo.worker.TaskImpl (run(460)) - ta_1438584797456_1967_000004_000000_00 completed. Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0
2015-08-03 07:13:25,670 INFO: org.apache.tajo.querymaster.Stage (transition(1297)) - Stage - eb_1438584797456_1967_000004 finalize NONE_SHUFFLE (total=1, success=1, killed=0)
2015-08-03 07:13:25,670 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(158)) - Task Scheduler stopped
2015-08-03 07:13:25,670 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(139)) - TaskScheduler schedulingThread stopped
2015-08-03 07:13:25,670 INFO: org.apache.tajo.querymaster.Stage (transition(1355)) - Stage completed - eb_1438584797456_1967_000004 (total=1, success=1, killed=0)
2015-08-03 07:13:25,671 INFO: org.apache.tajo.querymaster.Query (handle(860)) - Processing q_1438584797456_1967 of type STAGE_COMPLETED
2015-08-03 07:13:25,671 INFO: org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next executable block eb_1438584797456_1967_000005
2015-08-03 07:13:25,671 INFO: org.apache.tajo.querymaster.Query (transition(807)) - Complete Stage[eb_1438584797456_1967_000004], State: SUCCEEDED, 4/4.
2015-08-03 07:13:25,671 INFO: org.apache.tajo.querymaster.Query (handle(860)) - Processing q_1438584797456_1967 of type QUERY_COMPLETED
2015-08-03 07:13:25,671 INFO: org.apache.tajo.worker.TaskManager (stopExecutionBlock(161)) - Stopped execution block:eb_1438584797456_1967_000004
2015-08-03 07:13:25,672 INFO: org.apache.tajo.querymaster.Query (finalizeQuery(532)) - Can't find partitions for adding.
2015-08-03 07:13:25,672 INFO: org.apache.tajo.querymaster.Query (handle(878)) - q_1438584797456_1967 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED
2015-08-03 07:13:25,672 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(294)) - Query completion notified from q_1438584797456_1967 final state: QUERY_SUCCEEDED
2015-08-03 07:13:25,673 INFO: org.apache.tajo.master.QueryInProgress (heartbeat(252)) - Received QueryMaster heartbeat:q_1438584797456_1967,state=QUERY_SUCCEEDED,progress=1.0, queryMaster=asf906.gq1.ygridcore.net
2015-08-03 07:13:25,674 INFO: org.apache.tajo.master.QueryManager (stopQuery(279)) - Stop QueryInProgress:q_1438584797456_1967
2015-08-03 07:13:25,674 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(117)) - =========================================================
2015-08-03 07:13:25,674 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(118)) - Stop query:q_1438584797456_1967
2015-08-03 07:13:25,674 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(171)) - Stopping QueryMasterTask:q_1438584797456_1967
2015-08-03 07:13:25,674 INFO: org.apache.tajo.querymaster.QueryMasterTask (cleanupQuery(470)) - Cleanup resources of all workers. Query: q_1438584797456_1967, workers: 1
2015-08-03 07:13:25,675 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(187)) - Stopped QueryMasterTask:q_1438584797456_1967
2015-08-03 07:13:25,691 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(358)) - Saving query summary: hdfs://localhost:41004/tmp/tajo-jenkins/staging/history/20150803/query-detail/q_1438584797456_1966/query.hist
2015-08-03 07:13:25,697 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748223_7399{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-72bba928-dd43-4f26-a207-01a9ec6763b6:NORMAL:127.0.0.1:60826|FINALIZED]]} size 0
2015-08-03 07:13:25,699 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(372)) - Saving query unit: hdfs://localhost:41004/tmp/tajo-jenkins/staging/history/20150803/query-detail/q_1438584797456_1966/eb_1438584797456_1966_000002.hist
2015-08-03 07:13:25,702 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748224_7400{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-fb25aafa-396b-4c8a-a025-34404fbb325a:NORMAL:127.0.0.1:60826|FINALIZED]]} size 0
2015-08-03 07:13:25,704 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(372)) - Saving query unit: hdfs://localhost:41004/tmp/tajo-jenkins/staging/history/20150803/query-detail/q_1438584797456_1966/eb_1438584797456_1966_000001.hist
2015-08-03 07:13:25,708 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748225_7401{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-72bba928-dd43-4f26-a207-01a9ec6763b6:NORMAL:127.0.0.1:60826|RBW]]} size 0
2015-08-03 07:13:25,711 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(358)) - Saving query summary: hdfs://localhost:41004/tmp/tajo-jenkins/staging/history/20150803/query-detail/q_1438584797456_1967/query.hist
2015-08-03 07:13:25,716 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748226_7402{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-fb25aafa-396b-4c8a-a025-34404fbb325a:NORMAL:127.0.0.1:60826|FINALIZED]]} size 0
2015-08-03 07:13:25,718 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(372)) - Saving query unit: hdfs://localhost:41004/tmp/tajo-jenkins/staging/history/20150803/query-detail/q_1438584797456_1967/eb_1438584797456_1967_000002.hist
2015-08-03 07:13:25,722 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748227_7403{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-72bba928-dd43-4f26-a207-01a9ec6763b6:NORMAL:127.0.0.1:60826|FINALIZED]]} size 0
2015-08-03 07:13:25,724 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(372)) - Saving query unit: hdfs://localhost:41004/tmp/tajo-jenkins/staging/history/20150803/query-detail/q_1438584797456_1967/eb_1438584797456_1967_000003.hist
2015-08-03 07:13:25,728 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748228_7404{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-fb25aafa-396b-4c8a-a025-34404fbb325a:NORMAL:127.0.0.1:60826|RBW]]} size 0
2015-08-03 07:13:25,730 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(372)) - Saving query unit: hdfs://localhost:41004/tmp/tajo-jenkins/staging/history/20150803/query-detail/q_1438584797456_1967/eb_1438584797456_1967_000001.hist
2015-08-03 07:13:25,734 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748229_7405{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-72bba928-dd43-4f26-a207-01a9ec6763b6:NORMAL:127.0.0.1:60826|RBW]]} size 0
2015-08-03 07:13:25,736 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(372)) - Saving query unit: hdfs://localhost:41004/tmp/tajo-jenkins/staging/history/20150803/query-detail/q_1438584797456_1967/eb_1438584797456_1967_000004.hist
2015-08-03 07:13:25,740 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073748230_7406{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-fb25aafa-396b-4c8a-a025-34404fbb325a:NORMAL:127.0.0.1:60826|FINALIZED]]} size 0
2015-08-03 07:13:25,791 INFO: org.apache.tajo.master.TajoMasterClientService (getQueryResultData(576)) - Send result to client for 7a4f6f7e-15b6-4bf2-a161-32bdc028328b,q_1438584797456_1967, 5 rows
2015-08-03 07:13:25,792 INFO: org.apache.tajo.master.TajoMasterClientService (getQueryResultData(576)) - Send result to client for 7a4f6f7e-15b6-4bf2-a161-32bdc028328b,q_1438584797456_1967, 0 rows
2015-08-03 07:13:25,793 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session 7a4f6f7e-15b6-4bf2-a161-32bdc028328b is removed.
2015-08-03 07:13:25,796 INFO: org.apache.tajo.session.SessionManager (createSession(80)) - Session 3f40b968-b5b5-4aa3-a93a-a65ab281bcfa is created.
2015-08-03 07:13:25,798 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(180)) - Query: create table "TEST_DESC_TABLE" (col1 int4, col2 int4)
2015-08-03 07:13:25,799 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(278)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2015-08-03 07:13:25,799 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(280)) - =============================================
2015-08-03 07:13:25,800 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(281)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2015-08-03 07:13:25,800 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(282)) - =============================================
2015-08-03 07:13:25,802 INFO: org.apache.tajo.catalog.CatalogServer (createTable(662)) - relation "default.TEST_DESC_TABLE" is added to the catalog (127.0.0.1:27403)
2015-08-03 07:13:25,802 INFO: org.apache.tajo.master.exec.DDLExecutor (createTable(367)) - Table default.TEST_DESC_TABLE is created (0)
2015-08-03 07:13:25,805 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session 3f40b968-b5b5-4aa3-a93a-a65ab281bcfa is removed.
Tests run: 21, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3.12 sec - in org.apache.tajo.cli.tsql.TestTajoCli
2015-08-03 07:13:25,812 INFO: org.apache.tajo.master.TajoMaster (run(533)) - ============================================
2015-08-03 07:13:25,812 INFO: org.apache.tajo.master.TajoMaster (run(534)) - TajoMaster received SIGINT Signal
2015-08-03 07:13:25,812 INFO: org.apache.tajo.master.TajoMaster (run(535)) - ============================================
rds.
Aug 3, 2015 7:01:41 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block
Aug 3, 2015 7:01:41 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 2
Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26
Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED]
Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED]
Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26
Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED]
Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED]
Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26
Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED]
Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED]
Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26
Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED]
Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED]
Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 26
Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 1 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED]
Aug 3, 2015 7:01:59 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 65B for [l_shipdate_function] BINARY: 1 values, 20B raw, 20B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED]
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 1 records.
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 2 ms. row count = 1
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 1 records.
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 1
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 1 records.
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 1
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 1 records.
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 1 records.
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 1
Aug 3, 2015 7:02:00 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 1
Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordWriter: Flushing mem columnStore to file. allocated memory: 212
Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 43B for [l_orderkey] INT32: 5 values, 10B raw, 10B comp, 1 pages, encodings: [RLE, PLAIN_DICTIONARY, BIT_PACKED], dic { 3 entries, 12B raw, 3B comp}
Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 123B for [l_shipdate] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED]
Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.ColumnChunkPageWriteStore: written 123B for [l_shipdate_function] BINARY: 5 values, 76B raw, 76B comp, 1 pages, encodings: [RLE, PLAIN, BIT_PACKED]
Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: reading another 1 footers
Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.ParquetFileReader: Initiating action with parallelism: 5
Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: RecordReader initialized will read a total of 5 records.
Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: at row 0. reading next block
Aug 3, 2015 7:02:03 AM INFO: org.apache.parquet.hadoop.InternalParquetRecordReader: block read in memory in 1 ms. row count = 5
2015-08-03 07:13:25,815 INFO: org.apache.tajo.worker.TajoWorker (run(567)) - ============================================
2015-08-03 07:13:25,815 INFO: org.apache.tajo.worker.TajoWorker (run(568)) - TajoWorker received SIGINT Signal
2015-08-03 07:13:25,815 INFO: org.apache.tajo.worker.TajoWorker (run(569)) - ============================================
2015-08-03 07:13:25,816 INFO: org.mortbay.log (info(67)) - Shutdown hook executing
2015-08-03 07:13:25,816 INFO: org.mortbay.log (info(67)) - Shutdown hook complete
2015-08-03 07:13:25,818 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session c7add68d-1617-406d-bf51-b6074442aa78 is removed.
2015-08-03 07:13:25,818 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session d57e8115-6fb0-4b61-9555-ecd7f3793963 is removed.
2015-08-03 07:13:25,818 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:27406) shutdown
2015-08-03 07:13:25,822 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped.
2015-08-03 07:13:25,822 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(193)) - Catalog Server (127.0.0.1:27403) shutdown
2015-08-03 07:13:25,822 INFO: org.apache.tajo.util.history.HistoryWriter (run(268)) - HistoryWriter_asf906.gq1.ygridcore.net_27407 stopped.
2015-08-03 07:13:25,823 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped
2015-08-03 07:13:25,824 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:27403) shutdown
2015-08-03 07:13:25,827 INFO: org.apache.tajo.util.history.HistoryWriter (run(268)) - HistoryWriter_127.0.0.1_27405 stopped.
2015-08-03 07:13:25,830 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60826 is added to blk_1073741834_1010{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-fb25aafa-396b-4c8a-a025-34404fbb325a:NORMAL:127.0.0.1:60826|RBW]]} size 524
2015-08-03 07:13:25,831 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped
2015-08-03 07:13:25,831 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:27405) shutdown
2015-08-03 07:13:25,832 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:27404) shutdown
2015-08-03 07:13:25,835 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:27402) shutdown
2015-08-03 07:13:25,835 INFO: org.apache.tajo.master.TajoMaster (serviceStop(401)) - Tajo Master main thread exiting
2015-08-03 07:13:25,882 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(111)) - NodeStatusUpdater stopped.
2015-08-03 07:13:25,882 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(262)) - Heartbeat Thread stopped.
2015-08-03 07:13:25,883 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:27409) shutdown
2015-08-03 07:13:25,883 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) - QueryMasterManagerService stopped
2015-08-03 07:13:25,884 INFO: org.apache.tajo.querymaster.QueryMaster (run(417)) - QueryMaster heartbeat thread stopped
2015-08-03 07:13:25,885 INFO: org.apache.tajo.querymaster.QueryMaster (serviceStop(168)) - QueryMaster stopped
2015-08-03 07:13:25,886 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(99)) - TajoWorkerClientService stopping
2015-08-03 07:13:25,886 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:27408) shutdown
2015-08-03 07:13:25,886 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(103)) - TajoWorkerClientService stopped
2015-08-03 07:13:25,887 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:27407) shutdown
2015-08-03 07:13:25,887 INFO: org.apache.tajo.worker.TajoWorkerManagerService (serviceStop(93)) - TajoWorkerManagerService stopped
2015-08-03 07:13:25,887 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(377)) - TajoWorker main thread exiting
Results :
Failed tests:
TestTajoClient.testGetFinishedQueryList:628 expected:<103> but was:<101>
Tests run: 1579, Failures: 1, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 1.783 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 1.660 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.018 s]
[INFO] Tajo Common ....................................... SUCCESS [ 30.479 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 1.648 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 5.004 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 5.478 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 0.532 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 47.887 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.450 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 10.815 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 10.073 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [ 48.112 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 9.858 s]
[INFO] Tajo PullServer ................................... SUCCESS [ 1.308 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.122 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 1.347 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 3.363 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 1.034 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 3.305 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.060 s]
[INFO] Tajo Core ......................................... FAILURE [20:42 min]
[INFO] Tajo RPC .......................................... SKIPPED
[INFO] Tajo Catalog Drivers Hive ......................... SKIPPED
[INFO] Tajo Catalog Drivers .............................. SKIPPED
[INFO] Tajo Catalog ...................................... SKIPPED
[INFO] Tajo Storage ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 23:54 min
[INFO] Finished at: 2015-08-03T07:13:27+00:00
[INFO] Final Memory: 67M/431M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on project tajo-core: There are test failures.
[ERROR]
[ERROR] Please refer to <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/surefire-reports> for the individual test results.
[ERROR] -> [Help 1]
[ERROR]
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR]
[ERROR] For more information about the errors and possible solutions, please read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :tajo-core
Build step 'Execute shell' marked build as failure
Updating TAJO-1736
Jenkins build is back to normal : Tajo-master-build #787
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/787/changes>