See <https://builds.apache.org/job/Tajo-master-build/669/changes>
Changes: [jhkim] TAJO-1497: RPC client does not share a connection. (jinho) ------------------------------------------ [...truncated 405605 lines...] 2015-04-16 09:41:50,660 INFO: org.apache.tajo.querymaster.Stage (transition(1347)) - Stage finalize - eb_1429176091004_0946_000003 (total=1, success=1, killed=0) 2015-04-16 09:41:50,660 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(109)) - TaskScheduler schedulingThread stopped 2015-04-16 09:41:50,660 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(152)) - Task Scheduler stopped 2015-04-16 09:41:50,661 INFO: org.apache.tajo.worker.TaskRunner (run(259)) - Received ShouldDie flag:eb_1429176091004_0946_000003,container_1429176091004_0946_01_003456 2015-04-16 09:41:50,661 INFO: org.apache.tajo.querymaster.QueryMaster (cleanupExecutionBlock(180)) - cleanup executionBlocks: eb_1429176091004_0946_000001,eb_1429176091004_0946_000002 2015-04-16 09:41:50,661 INFO: org.apache.tajo.worker.TaskRunner (stop(153)) - Stop TaskRunner: eb_1429176091004_0946_000003,container_1429176091004_0946_01_003456 2015-04-16 09:41:50,661 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTaskRunner(105)) - Stop Task:eb_1429176091004_0946_000003,container_1429176091004_0946_01_003456 2015-04-16 09:41:50,662 INFO: org.apache.tajo.querymaster.Stage (transition(1353)) - eb_1429176091004_0946_000003, waiting for shuffle reports. expected Tasks:1 2015-04-16 09:41:50,663 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(270)) - Stopping ContainerProxy: container_1429176091004_0946_01_003456,eb_1429176091004_0946_000003 2015-04-16 09:41:50,663 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(151)) - ======================== Processing eb_1429176091004_0946_000003 of type STOP 2015-04-16 09:41:50,663 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(134)) - Close HashShuffleAppender:eb_1429176091004_0946_000003, not a hash shuffle 2015-04-16 09:41:50,663 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(576)) - Release Resource: 0.5,512 2015-04-16 09:41:50,663 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(134)) - Close HashShuffleAppender:eb_1429176091004_0946_000003, not a hash shuffle 2015-04-16 09:41:50,663 INFO: org.apache.tajo.querymaster.Stage (transition(1333)) - eb_1429176091004_0946_000003, Finalized shuffle reports: 1 2015-04-16 09:41:50,664 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(191)) - Stopped execution block:eb_1429176091004_0946_000003 2015-04-16 09:41:50,664 INFO: org.apache.tajo.querymaster.Stage (transition(1397)) - Stage completed - eb_1429176091004_0946_000003 (total=1, success=1, killed=0) 2015-04-16 09:41:50,664 INFO: org.apache.tajo.querymaster.Query (handle(720)) - Processing q_1429176091004_0946 of type STAGE_COMPLETED 2015-04-16 09:41:50,664 INFO: org.apache.tajo.querymaster.Query (executeNextBlock(634)) - Scheduling Stage:eb_1429176091004_0946_000004 2015-04-16 09:41:50,664 INFO: org.apache.tajo.querymaster.Stage (initTaskScheduler(847)) - org.apache.tajo.querymaster.DefaultTaskScheduler is chosen for the task scheduling for eb_1429176091004_0946_000004 2015-04-16 09:41:50,665 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1008)) - eb_1429176091004_0946_000004, Table's volume is approximately 1 MB 2015-04-16 09:41:50,665 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1012)) - eb_1429176091004_0946_000004, The determined number of non-leaf tasks is 1 2015-04-16 09:41:50,665 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleRangeShuffledFetches(700)) - eb_1429176091004_0946_000004, Try to divide [(0=>1, 1=>1), (0=>5, 1=>3)) into 1 sub ranges (total units: 1) 2015-04-16 09:41:50,666 INFO: org.apache.tajo.engine.utils.TupleUtil (setMaxRangeIfNull(109)) - Set null into range: default.orders.o_orderkey, current tuple is (0=>5, 1=>NULL) 2015-04-16 09:41:50,666 INFO: org.apache.tajo.querymaster.Stage (run(799)) - 1 objects are scheduled 2015-04-16 09:41:50,666 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (start(91)) - Start TaskScheduler 2015-04-16 09:41:50,667 INFO: org.apache.tajo.worker.TajoResourceAllocator (calculateNumRequestContainers(104)) - CalculateNumberRequestContainer - Number of Tasks=1, Number of Cluster Slots=1 2015-04-16 09:41:50,667 INFO: org.apache.tajo.querymaster.Stage (allocateContainers(1060)) - Request Container for eb_1429176091004_0946_000004 containers=1 2015-04-16 09:41:50,667 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(295)) - Start TajoWorkerAllocationThread 2015-04-16 09:41:50,668 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(408)) - Stop TajoWorkerAllocationThread 2015-04-16 09:41:50,668 INFO: org.apache.tajo.querymaster.Stage (transition(1174)) - Stage (eb_1429176091004_0946_000004) has 1 containers! 2015-04-16 09:41:50,670 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(151)) - ======================== Processing eb_1429176091004_0946_000004 of type START 2015-04-16 09:41:50,670 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(121)) - Tajo Root Dir: hdfs://localhost:49818/tajo 2015-04-16 09:41:50,670 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(122)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/7a7288b7-56fc-413a-acc3-0ffbe6f41053/tajo-localdir> 2015-04-16 09:41:50,670 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(125)) - QueryMaster Address:asf906.gq1.ygridcore.net/67.195.81.150:30423 2015-04-16 09:41:50,671 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(169)) - Start TaskRunner:eb_1429176091004_0946_000004,container_1429176091004_0946_01_003457 2015-04-16 09:41:50,671 INFO: org.apache.tajo.worker.TaskRunner (init(127)) - TaskRunner basedir is created (<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/7a7288b7-56fc-413a-acc3-0ffbe6f41053/tajo-localdir/q_1429176091004_0946/output/4)> 2015-04-16 09:41:50,671 INFO: org.apache.tajo.worker.TaskRunner (run(188)) - TaskRunner startup 2015-04-16 09:41:50,672 INFO: org.apache.tajo.worker.TaskRunner (run(221)) - Request GetTask: eb_1429176091004_0946_000004,container_1429176091004_0946_01_003457 2015-04-16 09:41:50,673 INFO: org.apache.tajo.worker.TaskRunner (run(265)) - Accumulated Received Task: 1 2015-04-16 09:41:50,674 INFO: org.apache.tajo.worker.TaskRunner (run(274)) - Initializing: ta_1429176091004_0946_000004_000000_00 2015-04-16 09:41:50,674 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1429176091004_0946_000004_000000_00 is changed to TA_PENDING 2015-04-16 09:41:50,674 INFO: org.apache.tajo.storage.FileStorageManager (getAppenderFilePath(298)) - Output File Path: hdfs://localhost:49818/tmp/tajo-jenkins/staging/q_1429176091004_0946/RESULT/part-04-000000-000 2015-04-16 09:41:50,674 INFO: org.apache.tajo.worker.Task (initPlan(168)) - Output File Path: hdfs://localhost:49818/tmp/tajo-jenkins/staging/q_1429176091004_0946/RESULT/part-04-000000-000 2015-04-16 09:41:50,674 INFO: org.apache.tajo.worker.Task (initPlan(173)) - ================================== 2015-04-16 09:41:50,675 INFO: org.apache.tajo.worker.Task (initPlan(174)) - * Stage ta_1429176091004_0946_000004_000000_00 is initialized 2015-04-16 09:41:50,675 INFO: org.apache.tajo.worker.Task (initPlan(175)) - * InterQuery: false, Fragments (num: 1), Fetches (total:1) : 2015-04-16 09:41:50,675 INFO: org.apache.tajo.worker.Task (initPlan(185)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/7a7288b7-56fc-413a-acc3-0ffbe6f41053/tajo-localdir/q_1429176091004_0946/output/4/0_0> 2015-04-16 09:41:50,675 INFO: org.apache.tajo.worker.Task (initPlan(190)) - ================================== 2015-04-16 09:41:50,676 INFO: org.apache.tajo.worker.Task (init(210)) - the directory is created <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/7a7288b7-56fc-413a-acc3-0ffbe6f41053/tajo-localdir/q_1429176091004_0946/in/eb_1429176091004_0946_000004/0/0/eb_1429176091004_0946_000003> 2015-04-16 09:41:50,676 INFO: org.apache.tajo.worker.Task (getFetchRunners(693)) - Try to get local file chunk at local host 2015-04-16 09:41:50,676 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(735)) - getLocalStoredFileChunk starts 2015-04-16 09:41:50,676 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(770)) - PullServer request param: shuffleType=r, sid=3, partId=0, taskIds=[0_0] 2015-04-16 09:41:50,695 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileChunks(686)) - BSTIndex is loaded from disk ((0=>1, 1=>1), (0=>5, 1=>NULL) 2015-04-16 09:41:50,695 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/7a7288b7-56fc-413a-acc3-0ffbe6f41053/tajo-localdir/q_1429176091004_0946/output/3/0_0/output/output> (start=(0=>1, 1=>1), end=(0=>5, 1=>NULL), last=true) 2015-04-16 09:41:50,695 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/7a7288b7-56fc-413a-acc3-0ffbe6f41053/tajo-localdir/q_1429176091004_0946/output/3/0_0/output/output> 2015-04-16 09:41:50,696 INFO: org.apache.tajo.worker.Task (getFetchRunners(721)) - Create a new Fetcher with storeChunk: (start=0, length=49, fromRemote=false, ebId=eb_1429176091004_0946_000003) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/7a7288b7-56fc-413a-acc3-0ffbe6f41053/tajo-localdir/q_1429176091004_0946/output/3/0_0/output/output> 2015-04-16 09:41:50,696 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1429176091004_0946_000004_000000_00 is changed to TA_RUNNING 2015-04-16 09:41:50,696 INFO: org.apache.tajo.worker.Fetcher (get(138)) - Get pseudo fetch from local host 2015-04-16 09:41:50,696 INFO: org.apache.tajo.worker.Task (run(617)) - Add a new FileChunk to local chunk list 2015-04-16 09:41:50,696 INFO: org.apache.tajo.worker.Task (waitForFetch(365)) - ta_1429176091004_0946_000004_000000_00 All fetches are done! 2015-04-16 09:41:50,697 INFO: org.apache.tajo.worker.Task (localizeFetchedData(573)) - One local chunk is added to listTablets 2015-04-16 09:41:50,700 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) - [ta_1429176091004_0946_000004_000000_00] Total merge time: 0 msec 2015-04-16 09:41:50,701 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (close(792)) - Delete file: "fragment": {"id": "eb_1429176091004_0946_000003", "path": <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/7a7288b7-56fc-413a-acc3-0ffbe6f41053/tajo-localdir/q_1429176091004_0946/output/3/0_0/output/output";,> "start": 0,"length": 49} 2015-04-16 09:41:50,705 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34480 is added to blk_1073747048_6224{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-b0c7a6e0-7d7f-4c78-a028-367b16620fc0:NORMAL:127.0.0.1:34480|RBW]]} size 0 2015-04-16 09:41:50,706 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1429176091004_0946_000004_000000_00 is changed to TA_SUCCEEDED 2015-04-16 09:41:50,707 INFO: org.apache.tajo.worker.Task (run(464)) - ta_1429176091004_0946_000004_000000_00 completed. Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0 2015-04-16 09:41:50,707 INFO: org.apache.tajo.worker.TaskRunner (run(221)) - Request GetTask: eb_1429176091004_0946_000004,container_1429176091004_0946_01_003457 2015-04-16 09:41:50,707 INFO: org.apache.tajo.querymaster.Stage (transition(1347)) - Stage finalize - eb_1429176091004_0946_000004 (total=1, success=1, killed=0) 2015-04-16 09:41:50,707 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(152)) - Task Scheduler stopped 2015-04-16 09:41:50,707 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(109)) - TaskScheduler schedulingThread stopped 2015-04-16 09:41:50,708 INFO: org.apache.tajo.querymaster.QueryMaster (cleanupExecutionBlock(180)) - cleanup executionBlocks: eb_1429176091004_0946_000003 2015-04-16 09:41:50,708 INFO: org.apache.tajo.worker.TaskRunner (run(259)) - Received ShouldDie flag:eb_1429176091004_0946_000004,container_1429176091004_0946_01_003457 2015-04-16 09:41:50,708 INFO: org.apache.tajo.worker.TaskRunner (stop(153)) - Stop TaskRunner: eb_1429176091004_0946_000004,container_1429176091004_0946_01_003457 2015-04-16 09:41:50,708 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTaskRunner(105)) - Stop Task:eb_1429176091004_0946_000004,container_1429176091004_0946_01_003457 2015-04-16 09:41:50,709 INFO: org.apache.tajo.querymaster.Stage (transition(1353)) - eb_1429176091004_0946_000004, waiting for shuffle reports. expected Tasks:1 2015-04-16 09:41:50,709 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(270)) - Stopping ContainerProxy: container_1429176091004_0946_01_003457,eb_1429176091004_0946_000004 2015-04-16 09:41:50,709 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(151)) - ======================== Processing eb_1429176091004_0946_000004 of type STOP 2015-04-16 09:41:50,710 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(576)) - Release Resource: 0.5,512 2015-04-16 09:41:50,710 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(134)) - Close HashShuffleAppender:eb_1429176091004_0946_000004, not a hash shuffle 2015-04-16 09:41:50,710 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(134)) - Close HashShuffleAppender:eb_1429176091004_0946_000004, not a hash shuffle 2015-04-16 09:41:50,710 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(191)) - Stopped execution block:eb_1429176091004_0946_000004 2015-04-16 09:41:50,710 INFO: org.apache.tajo.querymaster.Stage (transition(1333)) - eb_1429176091004_0946_000004, Finalized shuffle reports: 1 2015-04-16 09:41:50,711 INFO: org.apache.tajo.querymaster.Stage (transition(1397)) - Stage completed - eb_1429176091004_0946_000004 (total=1, success=1, killed=0) 2015-04-16 09:41:50,711 INFO: org.apache.tajo.querymaster.Query (handle(720)) - Processing q_1429176091004_0946 of type STAGE_COMPLETED 2015-04-16 09:41:50,711 INFO: org.apache.tajo.querymaster.Query (handle(720)) - Processing q_1429176091004_0946 of type QUERY_COMPLETED 2015-04-16 09:41:50,712 INFO: org.apache.tajo.querymaster.Query (handle(738)) - q_1429176091004_0946 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED 2015-04-16 09:41:50,712 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(285)) - Query completion notified from q_1429176091004_0946 2015-04-16 09:41:50,712 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(296)) - Query final state: QUERY_SUCCEEDED 2015-04-16 09:41:50,712 INFO: org.apache.tajo.querymaster.QueryMasterTask (stop(192)) - Stopping QueryMasterTask:q_1429176091004_0946 2015-04-16 09:41:50,713 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(276)) - Deallocator exiting 2015-04-16 09:41:50,713 INFO: org.apache.tajo.master.QueryInProgress (heartbeat(229)) - Received QueryMaster heartbeat:q_1429176091004_0946,state=QUERY_SUCCEEDED,progress=1.0, queryMaster=asf906.gq1.ygridcore.net 2015-04-16 09:41:50,713 INFO: org.apache.tajo.master.QueryManager (stopQuery(252)) - Stop QueryInProgress:q_1429176091004_0946 2015-04-16 09:41:50,713 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(109)) - ========================================================= 2015-04-16 09:41:50,713 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(110)) - Stop query:q_1429176091004_0946 2015-04-16 09:41:50,714 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(576)) - Release Resource: 0.0,512 2015-04-16 09:41:50,714 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseQueryMaster(598)) - Released QueryMaster (q_1429176091004_0946) resource. 2015-04-16 09:41:50,713 INFO: org.apache.tajo.querymaster.QueryMasterTask (stop(205)) - Stopped QueryMasterTask:q_1429176091004_0946 2015-04-16 09:41:50,714 INFO: org.apache.tajo.querymaster.QueryMaster (cleanup(205)) - cleanup query resources : q_1429176091004_0946 2015-04-16 09:41:50,729 INFO: org.apache.tajo.master.TajoMasterClientService (getQueryResultData(559)) - Send result to client for 23f95d4a-7d1a-4aed-8330-5db970a3fcf6,q_1429176091004_0946, 5 rows 2015-04-16 09:41:50,730 INFO: org.apache.tajo.master.TajoMasterClientService (getQueryResultData(559)) - Send result to client for 23f95d4a-7d1a-4aed-8330-5db970a3fcf6,q_1429176091004_0946, 0 rows 2015-04-16 09:41:50,732 INFO: org.apache.tajo.session.SessionManager (removeSession(79)) - Session 23f95d4a-7d1a-4aed-8330-5db970a3fcf6 is removed. 2015-04-16 09:41:50,735 INFO: org.apache.tajo.rpc.RpcProtos (channelActive(205)) - Connection established successfully : /127.0.0.1:30418 2015-04-16 09:41:50,735 INFO: org.apache.tajo.session.SessionManager (createSession(73)) - Session 37909583-b1ea-4d47-b3cb-7328620b3ce2 is created. 2015-04-16 09:41:50,737 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(176)) - Query: create table "TEST_DESC_TABLE" (col1 int4, col2 int4) 2015-04-16 09:41:50,738 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(277)) - Non Optimized Query: ----------------------------- Query Block Graph ----------------------------- |-#ROOT ----------------------------- Optimization Log: ----------------------------- 2015-04-16 09:41:50,739 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(279)) - ============================================= 2015-04-16 09:41:50,739 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(280)) - Optimized Query: ----------------------------- Query Block Graph ----------------------------- |-#ROOT ----------------------------- Optimization Log: ----------------------------- 2015-04-16 09:41:50,739 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(281)) - ============================================= 2015-04-16 09:41:50,741 INFO: org.apache.tajo.catalog.CatalogServer (createTable(605)) - relation "default.TEST_DESC_TABLE" is added to the catalog (127.0.0.1:30417) 2015-04-16 09:41:50,741 INFO: org.apache.tajo.master.exec.DDLExecutor (createTable(249)) - Table default.TEST_DESC_TABLE is created (0) 2015-04-16 09:41:50,744 INFO: org.apache.tajo.session.SessionManager (removeSession(79)) - Session 37909583-b1ea-4d47-b3cb-7328620b3ce2 is removed. Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3.245 sec - in org.apache.tajo.cli.tsql.TestTajoCli Apr 16, 2015 9:27:35 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Apr 16, 2015 9:27:35 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Apr 16, 2015 9:27:56 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Apr 16, 2015 9:27:56 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Apr 16, 2015 9:27:56 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Apr 16, 2015 9:27:56 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Apr 16, 2015 9:27:56 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Apr 16, 2015 9:28:00 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers 2015-04-16 09:41:50,755 INFO: org.apache.tajo.rpc.RpcProtos (channelActive(205)) - Connection established successfully : /127.0.0.1:30418 2015-04-16 09:41:50,756 INFO: org.apache.tajo.session.SessionManager (removeSession(79)) - Session 20c2a496-9488-4bfa-af3c-df2837dfeddb is removed. 2015-04-16 09:41:50,758 INFO: org.apache.tajo.worker.TajoWorker (run(532)) - ============================================ 2015-04-16 09:41:50,758 INFO: org.apache.tajo.worker.TajoWorker (run(533)) - TajoWorker received SIGINT Signal 2015-04-16 09:41:50,758 INFO: org.apache.tajo.worker.TajoWorker (run(534)) - ============================================ 2015-04-16 09:41:50,759 INFO: org.apache.tajo.rpc.RpcProtos (channelActive(205)) - Connection established successfully : /127.0.0.1:30418 2015-04-16 09:41:50,759 INFO: org.apache.tajo.session.SessionManager (removeSession(79)) - Session be4592be-e80d-4a68-a661-45f8882994cf is removed. 2015-04-16 09:41:50,760 INFO: org.apache.tajo.master.TajoMaster (run(575)) - ============================================ 2015-04-16 09:41:50,760 INFO: org.apache.tajo.master.TajoMaster (run(576)) - TajoMaster received SIGINT Signal 2015-04-16 09:41:50,760 INFO: org.apache.tajo.master.TajoMaster (run(577)) - ============================================ 2015-04-16 09:41:50,760 INFO: org.apache.tajo.util.history.HistoryWriter (run(268)) - HistoryWriter_asf906.gq1.ygridcore.net_30421 stopped. 2015-04-16 09:41:50,763 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2015-04-16 09:41:50,808 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34480 is added to blk_1073741834_1010{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-b0c7a6e0-7d7f-4c78-a028-367b16620fc0:NORMAL:127.0.0.1:34480|RBW]]} size 414 2015-04-16 09:41:50,826 INFO: org.apache.tajo.worker.WorkerHeartbeatService (run(225)) - Worker Resource Heartbeat Thread stopped. 2015-04-16 09:41:50,826 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:30423) shutdown 2015-04-16 09:41:50,827 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (stop(110)) - QueryMasterManagerService stopped 2015-04-16 09:41:50,827 INFO: org.apache.tajo.querymaster.QueryMaster (run(460)) - QueryMaster heartbeat thread stopped 2015-04-16 09:41:50,827 ERROR: org.apache.tajo.querymaster.QueryMaster (getAllWorker(243)) - java.lang.InterruptedException at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1024) at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1303) at java.util.concurrent.Semaphore.tryAcquire(Semaphore.java:383) at org.apache.tajo.rpc.CallFuture.get(CallFuture.java:78) at org.apache.tajo.querymaster.QueryMaster.getAllWorker(QueryMaster.java:240) at org.apache.tajo.querymaster.QueryMaster.cleanup(QueryMaster.java:207) at org.apache.tajo.querymaster.QueryMaster.access$1300(QueryMaster.java:60) at org.apache.tajo.querymaster.QueryMaster$QueryMasterContext.stopQuery(QueryMaster.java:354) at org.apache.tajo.querymaster.QueryMaster$QueryStopEventHandler.handle(QueryMaster.java:417) at org.apache.tajo.querymaster.QueryMaster$QueryStopEventHandler.handle(QueryMaster.java:414) at org.apache.hadoop.yarn.event.AsyncDispatcher.dispatch(AsyncDispatcher.java:173) at org.apache.hadoop.yarn.event.AsyncDispatcher$1.run(AsyncDispatcher.java:106) at java.lang.Thread.run(Thread.java:662) 2015-04-16 09:41:50,828 INFO: org.apache.tajo.querymaster.QueryMaster (stop(170)) - QueryMaster stopped 2015-04-16 09:41:50,828 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(99)) - TajoWorkerClientService stopping 2015-04-16 09:41:50,829 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:30422) shutdown 2015-04-16 09:41:50,829 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(103)) - TajoWorkerClientService stopped 2015-04-16 09:41:50,829 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:30421) shutdown 2015-04-16 09:41:50,829 INFO: org.apache.tajo.worker.TajoWorkerManagerService (stop(97)) - TajoWorkerManagerService stopped 2015-04-16 09:41:50,829 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(373)) - TajoWorker main thread exiting 2015-04-16 09:41:50,853 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:30420) shutdown 2015-04-16 09:41:50,853 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped. 2015-04-16 09:41:50,853 INFO: org.apache.tajo.catalog.CatalogServer (stop(198)) - Catalog Server (127.0.0.1:30417) shutdown 2015-04-16 09:41:50,853 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:30417) shutdown 2015-04-16 09:41:50,854 INFO: org.apache.tajo.util.history.HistoryWriter (run(268)) - HistoryWriter_127.0.0.1_30419 stopped. 2015-04-16 09:41:50,854 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2015-04-16 09:41:50,855 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:30419) shutdown 2015-04-16 09:41:50,855 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:30418) shutdown 2015-04-16 09:41:50,856 ERROR: org.apache.tajo.master.rm.TajoWorkerResourceManager (run(369)) - java.lang.InterruptedException 2015-04-16 09:41:50,856 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:30416) shutdown 2015-04-16 09:41:50,856 INFO: org.apache.tajo.master.TajoMaster (stop(409)) - Tajo Master main thread exiting Results : Tests in error: TestTajoClient.testDropCurrentDatabase:163 ยป Service org.apache.tajo.rpc.TajoS... Tests run: 1444, Failures: 0, Errors: 1, Skipped: 0 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Tajo Main ......................................... SUCCESS [ 1.619 s] [INFO] Tajo Project POM .................................. SUCCESS [ 1.143 s] [INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.048 s] [INFO] Tajo Common ....................................... SUCCESS [ 24.758 s] [INFO] Tajo Algebra ...................................... SUCCESS [ 1.275 s] [INFO] Tajo Catalog Common ............................... SUCCESS [ 6.632 s] [INFO] Tajo Plan ......................................... SUCCESS [ 6.015 s] [INFO] Tajo Rpc Common ................................... SUCCESS [ 0.398 s] [INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 34.452 s] [INFO] Tajo Catalog Client ............................... SUCCESS [ 1.039 s] [INFO] Tajo Catalog Server ............................... SUCCESS [ 10.672 s] [INFO] Tajo Storage Common ............................... SUCCESS [ 11.155 s] [INFO] Tajo HDFS Storage ................................. SUCCESS [ 55.658 s] [INFO] Tajo HBase Storage ................................ SUCCESS [ 3.016 s] [INFO] Tajo PullServer ................................... SUCCESS [ 0.948 s] [INFO] Tajo Client ....................................... SUCCESS [ 2.915 s] [INFO] Tajo CLI tools .................................... SUCCESS [ 0.699 s] [INFO] Tajo JDBC Driver .................................. SUCCESS [ 2.846 s] [INFO] ASM (thirdparty) .................................. SUCCESS [ 0.579 s] [INFO] Tajo RESTful Container ............................ SUCCESS [ 3.342 s] [INFO] Tajo Core ......................................... FAILURE [20:41 min] [INFO] Tajo RPC .......................................... 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:35 min [INFO] Finished at: 2015-04-16T09:41:51+00:00 [INFO] Final Memory: 104M/1080M [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-1497
