See <https://builds.apache.org/job/Tajo-master-nightly/667/changes>
Changes: [blrunner] TAJO-1421: Add 'ALTER TABLE SET PROPERTY' statement. [jihoonson] TAJO-1400: Add TajoStatement::setMaxRows method support. ------------------------------------------ [...truncated 25598 lines...] 2015-04-06 02:04:51,450 INFO: org.apache.tajo.querymaster.Query (handle(720)) - Processing q_1428285781630_0052 of type STAGE_COMPLETED 2015-04-06 02:04:51,450 INFO: org.apache.tajo.querymaster.Query (executeNextBlock(634)) - Scheduling Stage:eb_1428285781630_0052_000002 2015-04-06 02:04:51,451 INFO: org.apache.tajo.querymaster.Stage (initTaskScheduler(847)) - org.apache.tajo.querymaster.DefaultTaskScheduler is chosen for the task scheduling for eb_1428285781630_0052_000002 2015-04-06 02:04:51,451 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1008)) - eb_1428285781630_0052_000002, Table's volume is approximately 1 MB 2015-04-06 02:04:51,451 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1012)) - eb_1428285781630_0052_000002, The determined number of non-leaf tasks is 1 2015-04-06 02:04:51,452 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleRangeShuffledFetches(700)) - eb_1428285781630_0052_000002, Try to divide [(0=>2401.0), (0=>289.0)) into 1 sub ranges (total units: 1) 2015-04-06 02:04:51,452 INFO: org.apache.tajo.querymaster.Stage (run(799)) - 1 objects are scheduled 2015-04-06 02:04:51,452 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (start(91)) - Start TaskScheduler 2015-04-06 02:04:51,453 INFO: org.apache.tajo.worker.TajoResourceAllocator (calculateNumRequestContainers(102)) - CalculateNumberRequestContainer - Number of Tasks=1, Number of Cluster Slots=1 2015-04-06 02:04:51,453 INFO: org.apache.tajo.querymaster.Stage (allocateContainers(1060)) - Request Container for eb_1428285781630_0052_000002 containers=1 2015-04-06 02:04:51,453 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(262)) - Start TajoWorkerAllocationThread 2015-04-06 02:04:51,455 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(375)) - Stop TajoWorkerAllocationThread 2015-04-06 02:04:51,455 INFO: org.apache.tajo.querymaster.Stage (transition(1174)) - Stage (eb_1428285781630_0052_000002) has 1 containers! 2015-04-06 02:04:51,457 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(151)) - ======================== Processing eb_1428285781630_0052_000002 of type START 2015-04-06 02:04:51,457 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(121)) - Tajo Root Dir: hdfs://localhost:46221/tajo 2015-04-06 02:04:51,457 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(122)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir> 2015-04-06 02:04:51,457 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(125)) - QueryMaster Address:asf908.gq1.ygridcore.net/67.195.81.152:39010 2015-04-06 02:04:51,459 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(169)) - Start TaskRunner:eb_1428285781630_0052_000002,container_1428285781630_0052_01_000303 2015-04-06 02:04:51,459 INFO: org.apache.tajo.worker.TaskRunner (init(127)) - TaskRunner basedir is created (<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/2)> 2015-04-06 02:04:51,459 INFO: org.apache.tajo.worker.TaskRunner (run(188)) - TaskRunner startup 2015-04-06 02:04:51,460 INFO: org.apache.tajo.worker.TaskRunner (run(221)) - Request GetTask: eb_1428285781630_0052_000002,container_1428285781630_0052_01_000303 2015-04-06 02:04:51,462 INFO: org.apache.tajo.worker.TaskRunner (run(265)) - Accumulated Received Task: 1 2015-04-06 02:04:51,462 INFO: org.apache.tajo.worker.TaskRunner (run(274)) - Initializing: ta_1428285781630_0052_000002_000000_00 2015-04-06 02:04:51,463 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1428285781630_0052_000002_000000_00 is changed to TA_PENDING 2015-04-06 02:04:51,463 INFO: org.apache.tajo.storage.FileStorageManager (getAppenderFilePath(298)) - Output File Path: hdfs://localhost:46221/tmp/tajo-jenkins/staging/q_1428285781630_0052/RESULT/part-02-000000-000 2015-04-06 02:04:51,463 INFO: org.apache.tajo.worker.Task (initPlan(169)) - Output File Path: hdfs://localhost:46221/tmp/tajo-jenkins/staging/q_1428285781630_0052/RESULT/part-02-000000-000 2015-04-06 02:04:51,464 INFO: org.apache.tajo.worker.Task (initPlan(174)) - ================================== 2015-04-06 02:04:51,464 INFO: org.apache.tajo.worker.Task (initPlan(175)) - * Stage ta_1428285781630_0052_000002_000000_00 is initialized 2015-04-06 02:04:51,464 INFO: org.apache.tajo.worker.Task (initPlan(176)) - * InterQuery: false, Fragments (num: 1), Fetches (total:5) : 2015-04-06 02:04:51,464 INFO: org.apache.tajo.worker.Task (initPlan(186)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/2/0_0> 2015-04-06 02:04:51,464 INFO: org.apache.tajo.worker.Task (initPlan(191)) - ================================== 2015-04-06 02:04:51,466 INFO: org.apache.tajo.worker.Task (init(211)) - the directory is created <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/in/eb_1428285781630_0052_000002/0/0/eb_1428285781630_0052_000001> 2015-04-06 02:04:51,467 INFO: org.apache.tajo.worker.Task (getFetchRunners(697)) - Try to get local file chunk at local host 2015-04-06 02:04:51,467 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(739)) - getLocalStoredFileChunk starts 2015-04-06 02:04:51,467 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(774)) - PullServer request param: shuffleType=r, sid=1, partId=0, taskIds=[1_0] 2015-04-06 02:04:51,485 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(686)) - BSTIndex is loaded from disk ((0=>1296.0), (0=>1296.0) 2015-04-06 02:04:51,485 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(710)) - GET Request for <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/1_0/output/output> (start=(0=>2401.0), end=(0=>289.0), last=true) 2015-04-06 02:04:51,486 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(779)) - Retrieve File Chunk: (start=0, length=24, fromRemote=false, ebId=null) <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/1_0/output/output> 2015-04-06 02:04:51,486 INFO: org.apache.tajo.worker.Task (getFetchRunners(725)) - Create a new Fetcher with storeChunk: (start=0, length=24, fromRemote=false, ebId=eb_1428285781630_0052_000001) <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/1_0/output/output> 2015-04-06 02:04:51,486 INFO: org.apache.tajo.worker.Task (getFetchRunners(697)) - Try to get local file chunk at local host 2015-04-06 02:04:51,486 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(739)) - getLocalStoredFileChunk starts 2015-04-06 02:04:51,486 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(774)) - PullServer request param: shuffleType=r, sid=1, partId=0, taskIds=[3_0] 2015-04-06 02:04:51,502 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(686)) - BSTIndex is loaded from disk ((0=>2025.0), (0=>2025.0) 2015-04-06 02:04:51,502 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(710)) - GET Request for <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/3_0/output/output> (start=(0=>2401.0), end=(0=>289.0), last=true) 2015-04-06 02:04:51,502 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(779)) - Retrieve File Chunk: (start=0, length=24, fromRemote=false, ebId=null) <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/3_0/output/output> 2015-04-06 02:04:51,503 INFO: org.apache.tajo.worker.Task (getFetchRunners(725)) - Create a new Fetcher with storeChunk: (start=0, length=24, fromRemote=false, ebId=eb_1428285781630_0052_000001) <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/3_0/output/output> 2015-04-06 02:04:51,503 INFO: org.apache.tajo.worker.Task (getFetchRunners(697)) - Try to get local file chunk at local host 2015-04-06 02:04:51,503 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(739)) - getLocalStoredFileChunk starts 2015-04-06 02:04:51,503 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(774)) - PullServer request param: shuffleType=r, sid=1, partId=0, taskIds=[4_0] 2015-04-06 02:04:51,519 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(686)) - BSTIndex is loaded from disk ((0=>2401.0), (0=>2401.0) 2015-04-06 02:04:51,519 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(710)) - GET Request for <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/4_0/output/output> (start=(0=>2401.0), end=(0=>289.0), last=true) 2015-04-06 02:04:51,519 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(779)) - Retrieve File Chunk: (start=0, length=24, fromRemote=false, ebId=null) <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/4_0/output/output> 2015-04-06 02:04:51,519 INFO: org.apache.tajo.worker.Task (getFetchRunners(725)) - Create a new Fetcher with storeChunk: (start=0, length=24, fromRemote=false, ebId=eb_1428285781630_0052_000001) <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/4_0/output/output> 2015-04-06 02:04:51,520 INFO: org.apache.tajo.worker.Task (getFetchRunners(697)) - Try to get local file chunk at local host 2015-04-06 02:04:51,520 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(739)) - getLocalStoredFileChunk starts 2015-04-06 02:04:51,520 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(774)) - PullServer request param: shuffleType=r, sid=1, partId=0, taskIds=[0_0] 2015-04-06 02:04:51,535 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(686)) - BSTIndex is loaded from disk ((0=>289.0), (0=>289.0) 2015-04-06 02:04:51,536 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(710)) - GET Request for <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/0_0/output/output> (start=(0=>2401.0), end=(0=>289.0), last=true) 2015-04-06 02:04:51,536 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(779)) - Retrieve File Chunk: (start=0, length=24, fromRemote=false, ebId=null) <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/0_0/output/output> 2015-04-06 02:04:51,536 INFO: org.apache.tajo.worker.Task (getFetchRunners(725)) - Create a new Fetcher with storeChunk: (start=0, length=24, fromRemote=false, ebId=eb_1428285781630_0052_000001) <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/0_0/output/output> 2015-04-06 02:04:51,536 INFO: org.apache.tajo.worker.Task (getFetchRunners(697)) - Try to get local file chunk at local host 2015-04-06 02:04:51,536 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(739)) - getLocalStoredFileChunk starts 2015-04-06 02:04:51,537 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(774)) - PullServer request param: shuffleType=r, sid=1, partId=0, taskIds=[2_0] 2015-04-06 02:04:51,552 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(686)) - BSTIndex is loaded from disk ((0=>1444.0), (0=>1444.0) 2015-04-06 02:04:51,552 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(710)) - GET Request for <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/2_0/output/output> (start=(0=>2401.0), end=(0=>289.0), last=true) 2015-04-06 02:04:51,553 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileCunks(779)) - Retrieve File Chunk: (start=0, length=24, fromRemote=false, ebId=null) <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/2_0/output/output> 2015-04-06 02:04:51,553 INFO: org.apache.tajo.worker.Task (getFetchRunners(725)) - Create a new Fetcher with storeChunk: (start=0, length=24, fromRemote=false, ebId=eb_1428285781630_0052_000001) <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/2_0/output/output> 2015-04-06 02:04:51,553 INFO: org.apache.tajo.worker.Fetcher (get(138)) - Get pseudo fetch from local host 2015-04-06 02:04:51,554 INFO: org.apache.tajo.worker.Task (run(621)) - Add a new FileChunk to local chunk list 2015-04-06 02:04:51,554 INFO: org.apache.tajo.worker.Fetcher (get(138)) - Get pseudo fetch from local host 2015-04-06 02:04:51,554 INFO: org.apache.tajo.worker.Task (run(621)) - Add a new FileChunk to local chunk list 2015-04-06 02:04:51,554 INFO: org.apache.tajo.worker.Fetcher (get(138)) - Get pseudo fetch from local host 2015-04-06 02:04:51,554 INFO: org.apache.tajo.worker.Task (run(621)) - Add a new FileChunk to local chunk list 2015-04-06 02:04:51,554 INFO: org.apache.tajo.worker.Fetcher (get(138)) - Get pseudo fetch from local host 2015-04-06 02:04:51,554 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1428285781630_0052_000002_000000_00 is changed to TA_RUNNING 2015-04-06 02:04:51,559 INFO: org.apache.tajo.worker.Task (run(621)) - Add a new FileChunk to local chunk list 2015-04-06 02:04:51,555 INFO: org.apache.tajo.worker.Fetcher (get(138)) - Get pseudo fetch from local host 2015-04-06 02:04:51,560 INFO: org.apache.tajo.worker.Task (run(621)) - Add a new FileChunk to local chunk list 2015-04-06 02:04:51,560 INFO: org.apache.tajo.worker.Task (waitForFetch(366)) - ta_1428285781630_0052_000002_000000_00 All fetches are done! 2015-04-06 02:04:51,560 INFO: org.apache.tajo.worker.Task (localizeFetchedData(577)) - One local chunk is added to listTablets 2015-04-06 02:04:51,560 INFO: org.apache.tajo.worker.Task (localizeFetchedData(577)) - One local chunk is added to listTablets 2015-04-06 02:04:51,561 INFO: org.apache.tajo.worker.Task (localizeFetchedData(577)) - One local chunk is added to listTablets 2015-04-06 02:04:51,561 INFO: org.apache.tajo.worker.Task (localizeFetchedData(577)) - One local chunk is added to listTablets 2015-04-06 02:04:51,561 INFO: org.apache.tajo.worker.Task (localizeFetchedData(577)) - One local chunk is added to listTablets 2015-04-06 02:04:51,567 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) - [ta_1428285781630_0052_000002_000000_00] Total merge time: 0 msec 2015-04-06 02:04:51,568 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (close(792)) - Delete file: "fragment": {"id": "eb_1428285781630_0052_000001", "path": <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/1_0/output/output";,> "start": 0,"length": 24} 2015-04-06 02:04:51,569 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (close(792)) - Delete file: "fragment": {"id": "eb_1428285781630_0052_000001", "path": <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/4_0/output/output";,> "start": 0,"length": 24} 2015-04-06 02:04:51,569 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (close(792)) - Delete file: "fragment": {"id": "eb_1428285781630_0052_000001", "path": <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/0_0/output/output";,> "start": 0,"length": 24} 2015-04-06 02:04:51,569 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (close(792)) - Delete file: "fragment": {"id": "eb_1428285781630_0052_000001", "path": <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/3_0/output/output";,> "start": 0,"length": 24} 2015-04-06 02:04:51,569 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (close(792)) - Delete file: "fragment": {"id": "eb_1428285781630_0052_000001", "path": <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/test-data/a9cb8a03-4c7a-4cee-97a2-5f5a1e68b100/tajo-localdir/q_1428285781630_0052/output/1/2_0/output/output";,> "start": 0,"length": 24} 2015-04-06 02:04:51,575 INFO: BlockStateChange (logAddStoredBlock(2339)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60068 is added to blk_1073742095_1271{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-45c27674-f09d-48f8-a1fb-261ff8cb2aa8:NORMAL|RBW]]} size 0 2015-04-06 02:04:51,577 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(145)) - Query status of ta_1428285781630_0052_000002_000000_00 is changed to TA_SUCCEEDED 2015-04-06 02:04:51,577 INFO: org.apache.tajo.worker.Task (run(465)) - ta_1428285781630_0052_000002_000000_00 completed. Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0 2015-04-06 02:04:51,578 INFO: org.apache.tajo.worker.TaskRunner (run(221)) - Request GetTask: eb_1428285781630_0052_000002,container_1428285781630_0052_01_000303 2015-04-06 02:04:51,578 INFO: org.apache.tajo.querymaster.Stage (transition(1347)) - Stage finalize - eb_1428285781630_0052_000002 (total=1, success=1, killed=0) 2015-04-06 02:04:51,579 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(109)) - TaskScheduler schedulingThread stopped 2015-04-06 02:04:51,579 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(152)) - Task Scheduler stopped 2015-04-06 02:04:51,579 INFO: org.apache.tajo.querymaster.QueryMaster (cleanupExecutionBlock(180)) - cleanup executionBlocks: eb_1428285781630_0052_000001 2015-04-06 02:04:51,580 INFO: org.apache.tajo.worker.TaskRunner (run(259)) - Received ShouldDie flag:eb_1428285781630_0052_000002,container_1428285781630_0052_01_000303 2015-04-06 02:04:51,580 INFO: org.apache.tajo.worker.TaskRunner (stop(153)) - Stop TaskRunner: eb_1428285781630_0052_000002,container_1428285781630_0052_01_000303 2015-04-06 02:04:51,580 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTaskRunner(105)) - Stop Task:eb_1428285781630_0052_000002,container_1428285781630_0052_01_000303 2015-04-06 02:04:51,581 INFO: org.apache.tajo.querymaster.Stage (transition(1353)) - eb_1428285781630_0052_000002, waiting for shuffle reports. expected Tasks:1 2015-04-06 02:04:51,581 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(513)) - Release Resource: 0.5,512 2015-04-06 02:04:51,582 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(151)) - ======================== Processing eb_1428285781630_0052_000002 of type STOP 2015-04-06 02:04:51,582 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(134)) - Close HashShuffleAppender:eb_1428285781630_0052_000002, not a hash shuffle 2015-04-06 02:04:51,582 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(134)) - Close HashShuffleAppender:eb_1428285781630_0052_000002, not a hash shuffle 2015-04-06 02:04:51,582 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(191)) - Stopped execution block:eb_1428285781630_0052_000002 2015-04-06 02:04:51,582 INFO: org.apache.tajo.querymaster.Stage (transition(1333)) - eb_1428285781630_0052_000002, Finalized shuffle reports: 1 2015-04-06 02:04:51,582 INFO: org.apache.tajo.querymaster.Stage (transition(1397)) - Stage completed - eb_1428285781630_0052_000002 (total=1, success=1, killed=0) 2015-04-06 02:04:51,583 INFO: org.apache.tajo.querymaster.Query (handle(720)) - Processing q_1428285781630_0052 of type STAGE_COMPLETED 2015-04-06 02:04:51,583 INFO: org.apache.tajo.querymaster.Query (handle(720)) - Processing q_1428285781630_0052 of type QUERY_COMPLETED 2015-04-06 02:04:51,584 INFO: org.apache.tajo.querymaster.Query (handle(738)) - q_1428285781630_0052 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED 2015-04-06 02:04:51,584 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(285)) - Query completion notified from q_1428285781630_0052 2015-04-06 02:04:51,584 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(296)) - Query final state: QUERY_SUCCEEDED 2015-04-06 02:04:51,585 INFO: org.apache.tajo.querymaster.QueryMasterTask (stop(192)) - Stopping QueryMasterTask:q_1428285781630_0052 2015-04-06 02:04:51,585 INFO: org.apache.tajo.master.QueryInProgress (heartbeat(229)) - Received QueryMaster heartbeat:q_1428285781630_0052,state=QUERY_SUCCEEDED,progress=1.0, queryMaster=asf908.gq1.ygridcore.net 2015-04-06 02:04:51,586 INFO: org.apache.tajo.master.QueryManager (stopQuery(252)) - Stop QueryInProgress:q_1428285781630_0052 2015-04-06 02:04:51,586 INFO: org.apache.tajo.querymaster.QueryMasterTask (stop(205)) - Stopped QueryMasterTask:q_1428285781630_0052 2015-04-06 02:04:51,586 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(109)) - ========================================================= 2015-04-06 02:04:51,586 INFO: org.apache.tajo.querymaster.QueryMaster (cleanup(207)) - cleanup query resources : q_1428285781630_0052 2015-04-06 02:04:51,586 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(110)) - Stop query:q_1428285781630_0052 2015-04-06 02:04:51,586 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(513)) - Release Resource: 0.0,512 2015-04-06 02:04:51,586 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseQueryMaster(535)) - Released QueryMaster (q_1428285781630_0052) resource. 2015-04-06 02:04:51,591 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(362)) - Saving query summary: hdfs://localhost:46221/tmp/tajo-jenkins/staging/history/20150406/query-detail/q_1428285781630_0052/query.hist 2015-04-06 02:04:51,599 INFO: BlockStateChange (logAddStoredBlock(2339)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60068 is added to blk_1073742096_1272{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-eb3fb07f-a810-4c3f-b534-6a4384ea79ec:NORMAL|RBW]]} size 0 2015-04-06 02:04:51,604 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(376)) - Saving query unit: hdfs://localhost:46221/tmp/tajo-jenkins/staging/history/20150406/query-detail/q_1428285781630_0052/eb_1428285781630_0052_000001.hist 2015-04-06 02:04:51,609 INFO: BlockStateChange (logAddStoredBlock(2339)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60068 is added to blk_1073742097_1273{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-45c27674-f09d-48f8-a1fb-261ff8cb2aa8:NORMAL|RBW]]} size 0 2015-04-06 02:04:51,612 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(376)) - Saving query unit: hdfs://localhost:46221/tmp/tajo-jenkins/staging/history/20150406/query-detail/q_1428285781630_0052/eb_1428285781630_0052_000002.hist 2015-04-06 02:04:51,617 INFO: BlockStateChange (logAddStoredBlock(2339)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60068 is added to blk_1073742098_1274{blockUCState=COMMITTED, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-eb3fb07f-a810-4c3f-b534-6a4384ea79ec:NORMAL|RBW]]} size 1672 2015-04-06 02:04:51,959 INFO: org.apache.tajo.master.TajoMasterClientService (getQueryResultData(559)) - Send result to client for 398d68d1-5f16-4154-a8bb-ba9ca8c3f596,q_1428285781630_0052, 5 rows 2015-04-06 02:04:51,961 INFO: org.apache.tajo.master.TajoMasterClientService (getQueryResultData(559)) - Send result to client for 398d68d1-5f16-4154-a8bb-ba9ca8c3f596,q_1428285781630_0052, 0 rows 2015-04-06 02:04:51,964 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(150)) - Query: select col1, key from testQueryCasesOnColumnPartitionedTable WHERE key BETWEEN 35 AND 48 2015-04-06 02:04:51,996 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(239)) - Non Optimized Query: ----------------------------- Query Block Graph ----------------------------- |-#ROOT ----------------------------- Optimization Log: ----------------------------- PROJECTION(2) => Targets: default.testquerycasesoncolumnpartitionedtable.col1 (INT4), default.testquerycasesoncolumnpartitionedtable.key (FLOAT8) => out schema: {(2) default.testquerycasesoncolumnpartitionedtable.col1 (INT4), default.testquerycasesoncolumnpartitionedtable.key (FLOAT8)} => in schema: {(5) default.testquerycasesoncolumnpartitionedtable.col1 (INT4), default.testquerycasesoncolumnpartitionedtable.col2 (INT4), default.testquerycasesoncolumnpartitionedtable.null_col (INT4), default.testquerycasesoncolumnpartitionedtable.key (FLOAT8), ?between (BOOLEAN)} SELECTION(1) => Search Cond: default.testquerycasesoncolumnpartitionedtable.key (FLOAT8) BETWEEN ASYMMETRIC 35.0 AND 48.0 SCAN(0) on default.testquerycasesoncolumnpartitionedtable => target list: default.testquerycasesoncolumnpartitionedtable.col1 (INT4), default.testquerycasesoncolumnpartitionedtable.col2 (INT4), default.testquerycasesoncolumnpartitionedtable.null_col (INT4), default.testquerycasesoncolumnpartitionedtable.key (FLOAT8), default.testquerycasesoncolumnpartitionedtable.key (FLOAT8) BETWEEN ASYMMETRIC 35.0 AND 48.0 as ?between => out schema: {(5) default.testquerycasesoncolumnpartitionedtable.col1 (INT4), default.testquerycasesoncolumnpartitionedtable.col2 (INT4), default.testquerycasesoncolumnpartitionedtable.null_col (INT4), default.testquerycasesoncolumnpartitionedtable.key (FLOAT8), ?between (BOOLEAN)} => in schema: {(3) default.testquerycasesoncolumnpartitionedtable.col1 (INT4), default.testquerycasesoncolumnpartitionedtable.col2 (INT4), default.testquerycasesoncolumnpartitionedtable.null_col (INT4)} 2015-04-06 02:04:51,998 INFO: org.apache.tajo.plan.rewrite.rules.PartitionedTableRewriter (findFilteredPaths(139)) - Filtered directory or files: 3 2015-04-06 02:04:52,002 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(241)) - ============================================= 2015-04-06 02:04:52,002 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(242)) - Optimized Query: ----------------------------- Query Block Graph ----------------------------- |-#ROOT ----------------------------- Optimization Log: [LogicalPlan] > ProjectionNode is eliminated. > PartitionTableRewriter chooses 3 of partitions ----------------------------- PARTITIONS_SCAN(4) on default.testquerycasesoncolumnpartitionedtable => target list: default.testquerycasesoncolumnpartitionedtable.col1 (INT4), default.testquerycasesoncolumnpartitionedtable.key (FLOAT8) => num of filtered paths: 3 => out schema: {(2) default.testquerycasesoncolumnpartitionedtable.col1 (INT4), default.testquerycasesoncolumnpartitionedtable.key (FLOAT8)} => in schema: {(3) default.testquerycasesoncolumnpartitionedtable.col1 (INT4), default.testquerycasesoncolumnpartitionedtable.col2 (INT4), default.testquerycasesoncolumnpartitionedtable.null_col (INT4)} => 0: hdfs://localhost:46221/tajo/warehouse/default/testquerycasesoncolumnpartitionedtable/key=36.0 => 1: hdfs://localhost:46221/tajo/warehouse/default/testquerycasesoncolumnpartitionedtable/key=38.0 => 2: hdfs://localhost:46221/tajo/warehouse/default/testquerycasesoncolumnpartitionedtable/key=45.0 2015-04-06 02:04:52,002 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(243)) - ============================================= 2015-04-06 02:04:52,002 INFO: org.apache.tajo.master.exec.QueryExecutor (executeDistributedQuery(437)) - Query q_1428285781630_0053,select col1, key from testQueryCasesOnColumnPartitionedTable WHERE key BETWEEN 35 AND 48, is forwarded to :0 2015-04-06 02:04:52,002 INFO: org.apache.tajo.master.QueryInProgress (startQueryMaster(133)) - Initializing QueryInProgress for QueryID=q_1428285781630_0053 2015-04-06 02:04:52,003 INFO: org.apache.tajo.master.QueryInProgress (connectQueryMaster(158)) - Connect to QueryMaster:asf908.gq1.ygridcore.net/67.195.81.152:39010 2015-04-06 02:04:52,004 INFO: org.apache.tajo.master.QueryInProgress (submitQueryToMaster(185)) - Call executeQuery to :asf908.gq1.ygridcore.net:39010,q_1428285781630_0053 2015-04-06 02:04:52,005 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (executeQuery(249)) - Receive executeQuery request:q_1428285781630_0053 2015-04-06 02:04:52,005 INFO: org.apache.tajo.querymaster.QueryMaster (handle(400)) - Start QueryStartEventHandler:q_1428285781630_0053 2015-04-06 02:04:52,010 INFO: org.apache.tajo.querymaster.QueryMasterTask (initStagingDir(396)) - The staging dir 'hdfs://localhost:46221/tmp/tajo-jenkins/staging/q_1428285781630_0053' is created. Killed Results : Tests run: 212, Failures: 0, Errors: 0, Skipped: 0 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Tajo Main ......................................... SUCCESS [ 1.661 s] [INFO] Tajo Project POM .................................. SUCCESS [ 1.156 s] [INFO] Tajo Maven Plugins ................................ SUCCESS [ 4.027 s] [INFO] Tajo Common ....................................... SUCCESS [ 24.827 s] [INFO] Tajo Algebra ...................................... SUCCESS [ 2.247 s] [INFO] Tajo Catalog Common ............................... SUCCESS [ 6.679 s] [INFO] Tajo Plan ......................................... SUCCESS [ 6.480 s] [INFO] Tajo Rpc Common ................................... SUCCESS [ 1.288 s] [INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 20.745 s] [INFO] Tajo Catalog Client ............................... SUCCESS [ 0.996 s] [INFO] Tajo Catalog Server ............................... SUCCESS [ 10.212 s] [INFO] Tajo Storage Common ............................... SUCCESS [ 10.649 s] [INFO] Tajo HDFS Storage ................................. SUCCESS [ 40.828 s] [INFO] Tajo HBase Storage ................................ SUCCESS [ 2.870 s] [INFO] Tajo PullServer ................................... SUCCESS [ 0.907 s] [INFO] Tajo Client ....................................... SUCCESS [ 3.430 s] [INFO] Tajo CLI tools .................................... SUCCESS [ 1.500 s] [INFO] Tajo JDBC Driver .................................. SUCCESS [ 2.971 s] [INFO] ASM (thirdparty) .................................. SUCCESS [ 1.394 s] [INFO] Tajo Core ......................................... FAILURE [02:20 min] [INFO] Tajo RESTful Container ............................ SKIPPED [INFO] Tajo RPC .......................................... SKIPPED [INFO] Tajo Catalog Drivers HCatalog ..................... 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: 04:46 min [INFO] Finished at: 2015-04-06T02:04:52+00:00 [INFO] Final Memory: 98M/884M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on project tajo-core: Execution default-test of goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test failed: The forked VM terminated without properly saying goodbye. VM crash or System.exit called? [ERROR] Command was /bin/sh -c cd <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core> && /home/jenkins/tools/java/jdk1.6.0_45-64/jre/bin/java -Xms512m -Xmx1024m -XX:MaxPermSize=128m -Dfile.encoding=UTF-8 -jar <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/surefire/surefirebooter6405613174619894179.jar> <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/surefire/surefire9038701495019155223tmp> <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core/target/surefire/surefire_10788038643226908866tmp> [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/PluginExecutionException [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 Archiving artifacts Sending artifact delta relative to Tajo-master-nightly #666 Archived 1 artifacts Archive block size is 32768 Received 1187 blocks and 14017 bytes Compression is 100.0% Took 3.9 sec Recording test results
