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/04/20 04:40:55 UTC
Build failed in Jenkins: Tajo-master-build #680
See <https://builds.apache.org/job/Tajo-master-build/680/changes>
Changes:
[jhkim] TAJO-1568: Apply UnpooledByteBufAllocator when a tajo.test.enabled is set to enable. (jinho)
------------------------------------------
[...truncated 402033 lines...]
2015-04-20 02:40:52,208 INFO: org.apache.tajo.worker.Task (run(633)) - Add a new FileChunk to local chunk list
2015-04-20 02:40:52,208 INFO: org.apache.tajo.worker.Fetcher (get(127)) - Get pseudo fetch from local host
2015-04-20 02:40:52,208 INFO: org.apache.tajo.worker.Task (run(633)) - Add a new FileChunk to local chunk list
2015-04-20 02:40:52,208 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(148)) - Query status of ta_1429496549906_0957_000003_000000_00 is changed to TA_RUNNING
2015-04-20 02:40:52,208 INFO: org.apache.tajo.worker.Task (waitForFetch(379)) - ta_1429496549906_0957_000003_000000_00 All fetches are done!
2015-04-20 02:40:52,208 INFO: org.apache.tajo.worker.Task (localizeFetchedData(589)) - One local chunk is added to listTablets
2015-04-20 02:40:52,209 INFO: org.apache.tajo.worker.Task (localizeFetchedData(589)) - One local chunk is added to listTablets
2015-04-20 02:40:52,209 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createFullOuterHashJoinPlan(617)) - Full Outer Join (6) chooses [Hash Join]
2015-04-20 02:40:52,210 INFO: org.apache.tajo.engine.planner.physical.RangeShuffleFileWriteExec (init(76)) - Output data directory: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/6b3eba18-0bbf-464a-bb40-0d47b11ed917/tajo-localdir/q_1429496549906_0957/output/3/0_0/output>
2015-04-20 02:40:52,234 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) - [ta_1429496549906_0957_000003_000000_00] Chunks creation time: 1 msec
2015-04-20 02:40:52,239 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(148)) - Query status of ta_1429496549906_0957_000003_000000_00 is changed to TA_SUCCEEDED
2015-04-20 02:40:52,239 INFO: org.apache.tajo.worker.Task (run(479)) - ta_1429496549906_0957_000003_000000_00 completed. Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0
2015-04-20 02:40:52,239 INFO: org.apache.tajo.worker.TaskRunner (run(221)) - Request GetTask: eb_1429496549906_0957_000003,container_1429496549906_0957_01_003486
2015-04-20 02:40:52,240 INFO: org.apache.tajo.querymaster.Stage (transition(1370)) - Stage - eb_1429496549906_0957_000003 finalize RANGE_SHUFFLE (total=1, success=1, killed=0)
2015-04-20 02:40:52,240 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(152)) - Task Scheduler stopped
2015-04-20 02:40:52,240 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(109)) - TaskScheduler schedulingThread stopped
2015-04-20 02:40:52,240 INFO: org.apache.tajo.querymaster.QueryMaster (cleanupExecutionBlock(180)) - cleanup executionBlocks: eb_1429496549906_0957_000001,eb_1429496549906_0957_000002
2015-04-20 02:40:52,240 INFO: org.apache.tajo.worker.TaskRunner (run(259)) - Received ShouldDie flag:eb_1429496549906_0957_000003,container_1429496549906_0957_01_003486
2015-04-20 02:40:52,240 INFO: org.apache.tajo.worker.TaskRunner (stop(153)) - Stop TaskRunner: eb_1429496549906_0957_000003,container_1429496549906_0957_01_003486
2015-04-20 02:40:52,241 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTaskRunner(104)) - Stop Task:eb_1429496549906_0957_000003,container_1429496549906_0957_01_003486
2015-04-20 02:40:52,241 INFO: org.apache.tajo.querymaster.Stage (transition(1428)) - Stage completed - eb_1429496549906_0957_000003 (total=1, success=1, killed=0)
2015-04-20 02:40:52,242 INFO: org.apache.tajo.querymaster.Query (handle(720)) - Processing q_1429496549906_0957 of type STAGE_COMPLETED
2015-04-20 02:40:52,242 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(270)) - Stopping ContainerProxy: container_1429496549906_0957_01_003486,eb_1429496549906_0957_000003
2015-04-20 02:40:52,242 INFO: org.apache.tajo.querymaster.Query (executeNextBlock(634)) - Scheduling Stage:eb_1429496549906_0957_000004
2015-04-20 02:40:52,242 INFO: org.apache.tajo.querymaster.Stage (initTaskScheduler(852)) - org.apache.tajo.querymaster.DefaultTaskScheduler is chosen for the task scheduling for eb_1429496549906_0957_000004
2015-04-20 02:40:52,242 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(150)) - ======================== Processing eb_1429496549906_0957_000003 of type STOP
2015-04-20 02:40:52,242 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(576)) - Release Resource: 0.5,512
2015-04-20 02:40:52,242 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1013)) - eb_1429496549906_0957_000004, Table's volume is approximately 1 MB
2015-04-20 02:40:52,242 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(195)) - Stopped execution block:eb_1429496549906_0957_000003
2015-04-20 02:40:52,243 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1017)) - eb_1429496549906_0957_000004, The determined number of non-leaf tasks is 1
2015-04-20 02:40:52,244 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleRangeShuffledFetches(700)) - eb_1429496549906_0957_000004, Try to divide [(0=>1, 1=>1), (0=>5, 1=>3)) into 1 sub ranges (total units: 1)
2015-04-20 02:40:52,244 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-20 02:40:52,244 INFO: org.apache.tajo.querymaster.Stage (run(804)) - 1 objects are scheduled
2015-04-20 02:40:52,244 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (start(91)) - Start TaskScheduler
2015-04-20 02:40:52,245 INFO: org.apache.tajo.worker.TajoResourceAllocator (calculateNumRequestContainers(104)) - CalculateNumberRequestContainer - Number of Tasks=1, Number of Cluster Slots=1
2015-04-20 02:40:52,245 INFO: org.apache.tajo.querymaster.Stage (allocateContainers(1065)) - Request Container for eb_1429496549906_0957_000004 containers=1
2015-04-20 02:40:52,245 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(295)) - Start TajoWorkerAllocationThread
2015-04-20 02:40:52,246 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(408)) - Stop TajoWorkerAllocationThread
2015-04-20 02:40:52,246 INFO: org.apache.tajo.querymaster.Stage (transition(1179)) - Stage (eb_1429496549906_0957_000004) has 1 containers!
2015-04-20 02:40:52,247 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(150)) - ======================== Processing eb_1429496549906_0957_000004 of type START
2015-04-20 02:40:52,248 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(122)) - Tajo Root Dir: hdfs://localhost:51605/tajo
2015-04-20 02:40:52,248 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(123)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/6b3eba18-0bbf-464a-bb40-0d47b11ed917/tajo-localdir>
2015-04-20 02:40:52,248 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(126)) - QueryMaster Address:asf906.gq1.ygridcore.net/67.195.81.150:38401
2015-04-20 02:40:52,249 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(174)) - Start TaskRunner:eb_1429496549906_0957_000004,container_1429496549906_0957_01_003487
2015-04-20 02:40:52,249 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/6b3eba18-0bbf-464a-bb40-0d47b11ed917/tajo-localdir/q_1429496549906_0957/output/4)>
2015-04-20 02:40:52,249 INFO: org.apache.tajo.worker.TaskRunner (run(188)) - TaskRunner startup
2015-04-20 02:40:52,250 INFO: org.apache.tajo.worker.TaskRunner (run(221)) - Request GetTask: eb_1429496549906_0957_000004,container_1429496549906_0957_01_003487
2015-04-20 02:40:52,251 INFO: org.apache.tajo.worker.TaskRunner (run(265)) - Accumulated Received Task: 1
2015-04-20 02:40:52,252 INFO: org.apache.tajo.worker.TaskRunner (run(274)) - Initializing: ta_1429496549906_0957_000004_000000_00
2015-04-20 02:40:52,252 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(148)) - Query status of ta_1429496549906_0957_000004_000000_00 is changed to TA_PENDING
2015-04-20 02:40:52,252 INFO: org.apache.tajo.storage.FileStorageManager (getAppenderFilePath(298)) - Output File Path: hdfs://localhost:51605/tmp/tajo-jenkins/staging/q_1429496549906_0957/RESULT/part-04-000000-000
2015-04-20 02:40:52,252 INFO: org.apache.tajo.worker.Task (initPlan(167)) - Output File Path: hdfs://localhost:51605/tmp/tajo-jenkins/staging/q_1429496549906_0957/RESULT/part-04-000000-000
2015-04-20 02:40:52,252 INFO: org.apache.tajo.worker.Task (initPlan(172)) - ==================================
2015-04-20 02:40:52,252 INFO: org.apache.tajo.worker.Task (initPlan(173)) - * Stage ta_1429496549906_0957_000004_000000_00 is initialized
2015-04-20 02:40:52,253 INFO: org.apache.tajo.worker.Task (initPlan(174)) - * InterQuery: false, Fragments (num: 1), Fetches (total:1) :
2015-04-20 02:40:52,253 INFO: org.apache.tajo.worker.Task (initPlan(184)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/6b3eba18-0bbf-464a-bb40-0d47b11ed917/tajo-localdir/q_1429496549906_0957/output/4/0_0>
2015-04-20 02:40:52,253 INFO: org.apache.tajo.worker.Task (initPlan(189)) - ==================================
2015-04-20 02:40:52,253 INFO: org.apache.tajo.worker.Task (init(222)) - the directory is created <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/6b3eba18-0bbf-464a-bb40-0d47b11ed917/tajo-localdir/q_1429496549906_0957/in/eb_1429496549906_0957_000004/0/0/eb_1429496549906_0957_000003>
2015-04-20 02:40:52,254 INFO: org.apache.tajo.worker.Task (getFetchRunners(710)) - Try to get local file chunk at local host
2015-04-20 02:40:52,254 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(752)) - getLocalStoredFileChunk starts
2015-04-20 02:40:52,254 INFO: org.apache.tajo.worker.Task (getLocalStoredFileChunk(787)) - PullServer request param: shuffleType=r, sid=3, partId=0, taskIds=[0_0]
2015-04-20 02:40:52,271 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileChunks(686)) - BSTIndex is loaded from disk ((0=>1, 1=>1), (0=>5, 1=>NULL)
2015-04-20 02:40:52,271 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/6b3eba18-0bbf-464a-bb40-0d47b11ed917/tajo-localdir/q_1429496549906_0957/output/3/0_0/output/output> (start=(0=>1, 1=>1), end=(0=>5, 1=>NULL), last=true)
2015-04-20 02:40:52,271 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/6b3eba18-0bbf-464a-bb40-0d47b11ed917/tajo-localdir/q_1429496549906_0957/output/3/0_0/output/output>
2015-04-20 02:40:52,271 INFO: org.apache.tajo.worker.Task (getFetchRunners(738)) - Create a new Fetcher with storeChunk: (start=0, length=49, fromRemote=false, ebId=eb_1429496549906_0957_000003) <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/6b3eba18-0bbf-464a-bb40-0d47b11ed917/tajo-localdir/q_1429496549906_0957/output/3/0_0/output/output>
2015-04-20 02:40:52,271 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(148)) - Query status of ta_1429496549906_0957_000004_000000_00 is changed to TA_RUNNING
2015-04-20 02:40:52,271 INFO: org.apache.tajo.worker.Fetcher (get(127)) - Get pseudo fetch from local host
2015-04-20 02:40:52,272 INFO: org.apache.tajo.worker.Task (run(633)) - Add a new FileChunk to local chunk list
2015-04-20 02:40:52,272 INFO: org.apache.tajo.worker.Task (waitForFetch(379)) - ta_1429496549906_0957_000004_000000_00 All fetches are done!
2015-04-20 02:40:52,272 INFO: org.apache.tajo.worker.Task (localizeFetchedData(589)) - One local chunk is added to listTablets
2015-04-20 02:40:52,276 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) - [ta_1429496549906_0957_000004_000000_00] Total merge time: 0 msec
2015-04-20 02:40:52,276 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (close(792)) - Delete file: "fragment": {"id": "eb_1429496549906_0957_000003", "path": <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core/target/test-data/6b3eba18-0bbf-464a-bb40-0d47b11ed917/tajo-localdir/q_1429496549906_0957/output/3/0_0/output/output",> "start": 0,"length": 49}
2015-04-20 02:40:52,281 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60272 is added to blk_1073747096_6272{blockUCState=COMMITTED, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-36eb34e0-94d0-435e-bbb6-50a3a48759c5:NORMAL:127.0.0.1:60272|RBW]]} size 30
2015-04-20 02:40:52,682 INFO: org.apache.tajo.worker.TaskAttemptContext (setState(148)) - Query status of ta_1429496549906_0957_000004_000000_00 is changed to TA_SUCCEEDED
2015-04-20 02:40:52,683 INFO: org.apache.tajo.worker.Task (run(479)) - ta_1429496549906_0957_000004_000000_00 completed. Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0
2015-04-20 02:40:52,683 INFO: org.apache.tajo.worker.TaskRunner (run(221)) - Request GetTask: eb_1429496549906_0957_000004,container_1429496549906_0957_01_003487
2015-04-20 02:40:52,683 INFO: org.apache.tajo.querymaster.Stage (transition(1370)) - Stage - eb_1429496549906_0957_000004 finalize NONE_SHUFFLE (total=1, success=1, killed=0)
2015-04-20 02:40:52,684 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(109)) - TaskScheduler schedulingThread stopped
2015-04-20 02:40:52,684 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(152)) - Task Scheduler stopped
2015-04-20 02:40:52,684 INFO: org.apache.tajo.querymaster.QueryMaster (cleanupExecutionBlock(180)) - cleanup executionBlocks: eb_1429496549906_0957_000003
2015-04-20 02:40:52,684 INFO: org.apache.tajo.worker.TaskRunner (run(259)) - Received ShouldDie flag:eb_1429496549906_0957_000004,container_1429496549906_0957_01_003487
2015-04-20 02:40:52,684 INFO: org.apache.tajo.worker.TaskRunner (stop(153)) - Stop TaskRunner: eb_1429496549906_0957_000004,container_1429496549906_0957_01_003487
2015-04-20 02:40:52,684 INFO: org.apache.tajo.worker.TaskRunnerManager (stopTaskRunner(104)) - Stop Task:eb_1429496549906_0957_000004,container_1429496549906_0957_01_003487
2015-04-20 02:40:52,685 INFO: org.apache.tajo.querymaster.Stage (transition(1428)) - Stage completed - eb_1429496549906_0957_000004 (total=1, success=1, killed=0)
2015-04-20 02:40:52,685 INFO: org.apache.tajo.querymaster.Query (handle(720)) - Processing q_1429496549906_0957 of type STAGE_COMPLETED
2015-04-20 02:40:52,685 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(270)) - Stopping ContainerProxy: container_1429496549906_0957_01_003487,eb_1429496549906_0957_000004
2015-04-20 02:40:52,685 INFO: org.apache.tajo.querymaster.Query (handle(720)) - Processing q_1429496549906_0957 of type QUERY_COMPLETED
2015-04-20 02:40:52,686 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(150)) - ======================== Processing eb_1429496549906_0957_000004 of type STOP
2015-04-20 02:40:52,686 INFO: org.apache.tajo.worker.TaskRunnerManager (handle(195)) - Stopped execution block:eb_1429496549906_0957_000004
2015-04-20 02:40:52,686 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(576)) - Release Resource: 0.5,512
2015-04-20 02:40:52,687 INFO: org.apache.tajo.querymaster.Query (handle(738)) - q_1429496549906_0957 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED
2015-04-20 02:40:52,687 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(285)) - Query completion notified from q_1429496549906_0957
2015-04-20 02:40:52,687 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(296)) - Query final state: QUERY_SUCCEEDED
2015-04-20 02:40:52,687 INFO: org.apache.tajo.querymaster.QueryMasterTask (stop(192)) - Stopping QueryMasterTask:q_1429496549906_0957
2015-04-20 02:40:52,688 INFO: org.apache.tajo.worker.TajoResourceAllocator (run(276)) - Deallocator exiting
2015-04-20 02:40:52,688 INFO: org.apache.tajo.master.QueryInProgress (heartbeat(229)) - Received QueryMaster heartbeat:q_1429496549906_0957,state=QUERY_SUCCEEDED,progress=1.0, queryMaster=asf906.gq1.ygridcore.net
2015-04-20 02:40:52,688 INFO: org.apache.tajo.querymaster.QueryMasterTask (stop(205)) - Stopped QueryMasterTask:q_1429496549906_0957
2015-04-20 02:40:52,688 INFO: org.apache.tajo.querymaster.QueryMaster (cleanup(205)) - cleanup query resources : q_1429496549906_0957
2015-04-20 02:40:52,688 INFO: org.apache.tajo.master.QueryManager (stopQuery(252)) - Stop QueryInProgress:q_1429496549906_0957
2015-04-20 02:40:52,689 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(109)) - =========================================================
2015-04-20 02:40:52,689 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(110)) - Stop query:q_1429496549906_0957
2015-04-20 02:40:52,689 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseWorkerResource(576)) - Release Resource: 0.0,512
2015-04-20 02:40:52,689 INFO: org.apache.tajo.master.rm.TajoWorkerResourceManager (releaseQueryMaster(598)) - Released QueryMaster (q_1429496549906_0957) resource.
2015-04-20 02:40:52,750 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(358)) - Saving query summary: hdfs://localhost:51605/tmp/tajo-jenkins/staging/history/20150420/query-detail/q_1429496549906_0957/query.hist
2015-04-20 02:40:52,757 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60272 is added to blk_1073747097_6273{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-8786a975-38d7-4ba3-85fc-7a9fb08f2ab2:NORMAL:127.0.0.1:60272|RBW]]} size 0
2015-04-20 02:40:52,760 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(372)) - Saving query unit: hdfs://localhost:51605/tmp/tajo-jenkins/staging/history/20150420/query-detail/q_1429496549906_0957/eb_1429496549906_0957_000002.hist
2015-04-20 02:40:52,764 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60272 is added to blk_1073747098_6274{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-36eb34e0-94d0-435e-bbb6-50a3a48759c5:NORMAL:127.0.0.1:60272|FINALIZED]]} size 0
2015-04-20 02:40:52,766 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(372)) - Saving query unit: hdfs://localhost:51605/tmp/tajo-jenkins/staging/history/20150420/query-detail/q_1429496549906_0957/eb_1429496549906_0957_000001.hist
2015-04-20 02:40:52,771 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60272 is added to blk_1073747099_6275{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-8786a975-38d7-4ba3-85fc-7a9fb08f2ab2:NORMAL:127.0.0.1:60272|FINALIZED]]} size 0
2015-04-20 02:40:52,773 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(372)) - Saving query unit: hdfs://localhost:51605/tmp/tajo-jenkins/staging/history/20150420/query-detail/q_1429496549906_0957/eb_1429496549906_0957_000004.hist
2015-04-20 02:40:52,777 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60272 is added to blk_1073747100_6276{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-36eb34e0-94d0-435e-bbb6-50a3a48759c5:NORMAL:127.0.0.1:60272|FINALIZED]]} size 0
2015-04-20 02:40:52,779 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(372)) - Saving query unit: hdfs://localhost:51605/tmp/tajo-jenkins/staging/history/20150420/query-detail/q_1429496549906_0957/eb_1429496549906_0957_000003.hist
2015-04-20 02:40:52,783 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60272 is added to blk_1073747101_6277{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-8786a975-38d7-4ba3-85fc-7a9fb08f2ab2:NORMAL:127.0.0.1:60272|RBW]]} size 0
2015-04-20 02:40:52,816 INFO: org.apache.tajo.master.TajoMasterClientService (getQueryResultData(559)) - Send result to client for 6be31259-1f7e-4c78-9d2e-7fcc1b0539d9,q_1429496549906_0957, 5 rows
2015-04-20 02:40:52,817 INFO: org.apache.tajo.master.TajoMasterClientService (getQueryResultData(559)) - Send result to client for 6be31259-1f7e-4c78-9d2e-7fcc1b0539d9,q_1429496549906_0957, 0 rows
2015-04-20 02:40:52,819 INFO: org.apache.tajo.session.SessionManager (removeSession(79)) - Session 6be31259-1f7e-4c78-9d2e-7fcc1b0539d9 is removed.
2015-04-20 02:40:52,822 INFO: org.apache.tajo.rpc.RpcProtos (channelActive(206)) - Connection established successfully : /127.0.0.1:38396
2015-04-20 02:40:52,823 INFO: org.apache.tajo.session.SessionManager (createSession(73)) - Session 9290e690-781b-48cf-b4a3-5ae8e2a31b1f is created.
2015-04-20 02:40:52,824 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(174)) - Query: create table "TEST_DESC_TABLE" (col1 int4, col2 int4)
2015-04-20 02:40:52,825 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(275)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2015-04-20 02:40:52,826 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(277)) - =============================================
2015-04-20 02:40:52,826 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(278)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2015-04-20 02:40:52,826 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(279)) - =============================================
2015-04-20 02:40:52,828 INFO: org.apache.tajo.catalog.CatalogServer (createTable(605)) - relation "default.TEST_DESC_TABLE" is added to the catalog (127.0.0.1:38395)
2015-04-20 02:40:52,828 INFO: org.apache.tajo.master.exec.DDLExecutor (createTable(249)) - Table default.TEST_DESC_TABLE is created (0)
2015-04-20 02:40:52,831 INFO: org.apache.tajo.session.SessionManager (removeSession(79)) - Session 9290e690-781b-48cf-b4a3-5ae8e2a31b1f is removed.
Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.203 sec - in org.apache.tajo.cli.tsql.TestTajoCli
2015-04-20 02:40:52,844 INFO: org.apache.tajo.rpc.RpcProtos (channelActive(206)) - Connection established successfully : /127.0.0.1:38396
2015-04-20 02:40:52,844 INFO: org.apache.tajo.session.SessionManager (removeSession(79)) - Session 6fde1ba8-03a4-4eee-b057-cd898bb6ad8b is removed.
2015-04-20 02:40:52,844 INFO: org.apache.tajo.master.TajoMaster (run(581)) - ============================================
2015-04-20 02:40:52,846 INFO: org.apache.tajo.master.TajoMaster (run(582)) - TajoMaster received SIGINT Signal
2015-04-20 02:40:52,847 INFO: org.apache.tajo.master.TajoMaster (run(583)) - ============================================
Apr 20, 2015 2:28:43 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Apr 20, 2015 2:28:44 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Apr 20, 2015 2:29:03 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Apr 20, 2015 2:29:03 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Apr 20, 2015 2:29:03 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Apr 20, 2015 2:29:03 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Apr 20, 2015 2:29:03 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Apr 20, 2015 2:29:06 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
2015-04-20 02:40:52,850 INFO: org.apache.tajo.session.SessionManager (removeSession(79)) - Session 83dc7ec3-a9c4-4eed-9615-c562563a806e is removed.
2015-04-20 02:40:52,850 INFO: org.apache.tajo.worker.TajoWorker (run(535)) - ============================================
2015-04-20 02:40:52,852 INFO: org.apache.tajo.worker.TajoWorker (run(536)) - TajoWorker received SIGINT Signal
2015-04-20 02:40:52,852 INFO: org.apache.tajo.worker.TajoWorker (run(537)) - ============================================
2015-04-20 02:40:52,853 INFO: org.apache.tajo.util.history.HistoryWriter (run(264)) - HistoryWriter_asf906.gq1.ygridcore.net_38399 stopped.
2015-04-20 02:40:52,862 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped
2015-04-20 02:40:52,891 INFO: BlockStateChange (logAddStoredBlock(2473)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60272 is added to blk_1073741834_1010{blockUCState=COMMITTED, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-36eb34e0-94d0-435e-bbb6-50a3a48759c5:NORMAL:127.0.0.1:60272|RBW]]} size 1915509
2015-04-20 02:40:52,935 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:38398) shutdown
2015-04-20 02:40:52,937 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped.
2015-04-20 02:40:52,937 INFO: org.apache.tajo.catalog.CatalogServer (stop(198)) - Catalog Server (127.0.0.1:38395) shutdown
2015-04-20 02:40:52,937 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:38395) shutdown
2015-04-20 02:40:52,937 INFO: org.apache.tajo.util.history.HistoryWriter (run(264)) - HistoryWriter_127.0.0.1_38397 stopped.
2015-04-20 02:40:53,293 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped
2015-04-20 02:40:53,293 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:38397) shutdown
2015-04-20 02:40:53,293 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:38396) shutdown
2015-04-20 02:40:53,294 ERROR: org.apache.tajo.master.rm.TajoWorkerResourceManager (run(369)) - java.lang.InterruptedException
2015-04-20 02:40:53,294 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:38394) shutdown
2015-04-20 02:40:53,295 INFO: org.apache.tajo.master.TajoMaster (stop(415)) - Tajo Master main thread exiting
2015-04-20 02:40:53,328 INFO: org.apache.tajo.worker.WorkerHeartbeatService (run(225)) - Worker Resource Heartbeat Thread stopped.
2015-04-20 02:40:53,328 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:38401) shutdown
2015-04-20 02:40:53,330 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (stop(110)) - QueryMasterManagerService stopped
2015-04-20 02:40:53,330 INFO: org.apache.tajo.querymaster.QueryMaster (run(460)) - QueryMaster heartbeat thread stopped
2015-04-20 02:40:53,330 INFO: org.apache.tajo.querymaster.QueryMaster (stop(170)) - QueryMaster stopped
2015-04-20 02:40:53,330 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(99)) - TajoWorkerClientService stopping
2015-04-20 02:40:53,331 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:38400) shutdown
2015-04-20 02:40:53,331 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(103)) - TajoWorkerClientService stopped
2015-04-20 02:40:53,331 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:38399) shutdown
2015-04-20 02:40:53,331 INFO: org.apache.tajo.worker.TajoWorkerManagerService (stop(97)) - TajoWorkerManagerService stopped
2015-04-20 02:40:53,332 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(376)) - TajoWorker main thread exiting
Results :
Tests in error:
TestHAServiceHDFSImpl.testAutoFailOver:65 ยป ServiceState java.net.BindExceptio...
Tests run: 1457, Failures: 0, Errors: 1, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 1.594 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 1.151 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.072 s]
[INFO] Tajo Common ....................................... SUCCESS [ 24.226 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 1.298 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 6.718 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 5.973 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 0.413 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 37.376 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.068 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 10.377 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 10.950 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [ 56.984 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 3.042 s]
[INFO] Tajo PullServer ................................... SUCCESS [ 0.907 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.988 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 0.701 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 2.829 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 0.577 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 3.304 s]
[INFO] Tajo Core ......................................... FAILURE [18:45 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: 21:41 min
[INFO] Finished at: 2015-04-20T02:40:53+00:00
[INFO] Final Memory: 106M/1032M
[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-1568
Jenkins build is back to normal : Tajo-master-build #681
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/681/changes>