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/10/07 10:08:27 UTC
Build failed in Jenkins: Tajo-master-jdk8-nightly #190
See <https://builds.apache.org/job/Tajo-master-jdk8-nightly/190/changes>
Changes:
[jihoonson] TAJO-1907: Reduce the size of the INDEXES table's COLUMN_NAMES field.
[jihoonson] TAJO-1916: 'How to contribute' link is broken.
------------------------------------------
[...truncated 246275 lines...]
2015-10-07 08:08:43,384 INFO: org.apache.tajo.storage.FileTablespace (listStatus(364)) - Total input paths to process : 1
2015-10-07 08:08:43,385 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) - Task basedir is created (<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/1)>
2015-10-07 08:08:43,385 INFO: org.apache.tajo.worker.TaskImpl (init(197)) - Initializing: ta_1444204775490_0684_000001_000000_00
2015-10-07 08:08:43,385 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - ==================================
2015-10-07 08:08:43,385 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) - * Stage ta_1444204775490_0684_000001_000000_00 is initialized
2015-10-07 08:08:43,385 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) - * InterQuery: true, Use HASH_SHUFFLE shuffle, Fragments (num: 1), Fetches (total:0) :
2015-10-07 08:08:43,385 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/1/0_0>
2015-10-07 08:08:43,385 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) - ==================================
2015-10-07 08:08:43,386 INFO: org.apache.tajo.storage.FileTablespace (listStatus(364)) - Total input paths to process : 1
2015-10-07 08:08:43,388 INFO: org.apache.tajo.storage.FileTablespace (listStatus(364)) - Total input paths to process : 1
2015-10-07 08:08:43,389 INFO: org.apache.tajo.worker.TaskImpl (run(462)) - ta_1444204775490_0684_000001_000000_00 completed. Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0
2015-10-07 08:08:43,389 INFO: org.apache.tajo.querymaster.Stage (transition(1350)) - Stage - eb_1444204775490_0684_000001 finalize HASH_SHUFFLE (total=1, success=1, killed=0)
2015-10-07 08:08:43,389 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(163)) - Task Scheduler stopped
2015-10-07 08:08:43,389 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(126)) - TaskScheduler schedulingThread stopped
2015-10-07 08:08:43,390 INFO: org.apache.tajo.querymaster.Stage (transition(1361)) - eb_1444204775490_0684_000001, wait for HASH_SHUFFLE reports. expected Tasks:1
2015-10-07 08:08:43,390 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(155)) - Close HashShuffleAppender:eb_1444204775490_0684_000001, intermediates=1
2015-10-07 08:08:43,391 INFO: org.apache.tajo.storage.FileTablespace (listStatus(364)) - Total input paths to process : 1
2015-10-07 08:08:43,391 INFO: org.apache.tajo.querymaster.Stage (finalizeShuffleReport(1307)) - eb_1444204775490_0684_000001, Finalized HASH_SHUFFLE reports: 1
2015-10-07 08:08:43,391 INFO: org.apache.tajo.querymaster.Stage (transition(1408)) - Stage completed - eb_1444204775490_0684_000001 (total=1, success=1, killed=0)
2015-10-07 08:08:43,391 INFO: org.apache.tajo.worker.TaskManager (stopExecutionBlock(163)) - Stopped execution block:eb_1444204775490_0684_000001
2015-10-07 08:08:43,391 INFO: org.apache.tajo.querymaster.Query (handle(868)) - Processing q_1444204775490_0684 of type STAGE_COMPLETED
2015-10-07 08:08:43,391 INFO: org.apache.tajo.storage.FileTablespace (getSplits(565)) - Total # of splits: 5
2015-10-07 08:08:43,392 INFO: org.apache.tajo.querymaster.Stage (run(854)) - 5 objects are scheduled
2015-10-07 08:08:43,392 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (start(134)) - Start TaskScheduler
2015-10-07 08:08:43,393 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (increaseConcurrency(524)) - Assigned host : 127.0.0.1, Unknown Volume : -1, Concurrency : 1
2015-10-07 08:08:43,394 INFO: org.apache.tajo.worker.TaskManager (createExecutionBlock(114)) - QueryMaster Address:asf900.gq1.ygridcore.net:21521
2015-10-07 08:08:43,394 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (assignToLeafTasks(921)) - Assigned Local/Rack/Total: (0/1/1), Attempted Cancel/Assign/Total: (0/1/1), Locality: 0.00%, Rack host: asf900.gq1.ygridcore.net
2015-10-07 08:08:43,394 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (increaseConcurrency(524)) - Assigned host : 127.0.0.1, Unknown Volume : -1, Concurrency : 2
2015-10-07 08:08:43,395 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (assignToLeafTasks(921)) - Assigned Local/Rack/Total: (0/2/2), Attempted Cancel/Assign/Total: (0/2/2), Locality: 0.00%, Rack host: asf900.gq1.ygridcore.net
2015-10-07 08:08:43,396 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(119)) - Tajo Root Dir: hdfs://localhost:48122/tajo
2015-10-07 08:08:43,396 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(120)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir>
2015-10-07 08:08:43,396 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (increaseConcurrency(524)) - Assigned host : 127.0.0.1, Unknown Volume : -1, Concurrency : 3
2015-10-07 08:08:43,396 INFO: org.apache.tajo.worker.TaskManager (handle(184)) - Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:500, Disks:1, vCores:1)
2015-10-07 08:08:43,397 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) - Task basedir is created (<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/2)>
2015-10-07 08:08:43,397 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (assignToLeafTasks(921)) - Assigned Local/Rack/Total: (0/3/3), Attempted Cancel/Assign/Total: (0/3/3), Locality: 0.00%, Rack host: asf900.gq1.ygridcore.net
2015-10-07 08:08:43,397 INFO: org.apache.tajo.worker.TaskImpl (init(197)) - Initializing: ta_1444204775490_0684_000002_000000_00
2015-10-07 08:08:43,397 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) - Task basedir is created (<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/2)>
2015-10-07 08:08:43,397 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - ==================================
2015-10-07 08:08:43,397 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) - * Stage ta_1444204775490_0684_000002_000000_00 is initialized
2015-10-07 08:08:43,397 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) - Task basedir is created (<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/2)>
2015-10-07 08:08:43,397 INFO: org.apache.tajo.worker.TaskImpl (init(197)) - Initializing: ta_1444204775490_0684_000002_000001_00
2015-10-07 08:08:43,397 INFO: org.apache.tajo.worker.TaskImpl (init(197)) - Initializing: ta_1444204775490_0684_000002_000002_00
2015-10-07 08:08:43,398 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - ==================================
2015-10-07 08:08:43,398 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) - * Stage ta_1444204775490_0684_000002_000001_00 is initialized
2015-10-07 08:08:43,398 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) - * InterQuery: true, Use HASH_SHUFFLE shuffle, Fragments (num: 1), Fetches (total:0) :
2015-10-07 08:08:43,397 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) - * InterQuery: true, Use HASH_SHUFFLE shuffle, Fragments (num: 1), Fetches (total:0) :
2015-10-07 08:08:43,398 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/2/0_0>
2015-10-07 08:08:43,398 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) - ==================================
2015-10-07 08:08:43,398 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/2/1_0>
2015-10-07 08:08:43,398 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) - ==================================
2015-10-07 08:08:43,398 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - ==================================
2015-10-07 08:08:43,398 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) - * Stage ta_1444204775490_0684_000002_000002_00 is initialized
2015-10-07 08:08:43,399 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) - * InterQuery: true, Use HASH_SHUFFLE shuffle, Fragments (num: 1), Fetches (total:0) :
2015-10-07 08:08:43,399 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/2/2_0>
2015-10-07 08:08:43,399 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) - ==================================
2015-10-07 08:08:43,401 INFO: org.apache.tajo.worker.TaskImpl (run(462)) - ta_1444204775490_0684_000002_000000_00 completed. Worker's task counter - total:3, succeeded: 3, killed: 0, failed: 0
2015-10-07 08:08:43,401 INFO: org.apache.tajo.worker.TaskImpl (run(462)) - ta_1444204775490_0684_000002_000002_00 completed. Worker's task counter - total:3, succeeded: 3, killed: 0, failed: 0
2015-10-07 08:08:43,401 INFO: org.apache.tajo.worker.TaskImpl (run(462)) - ta_1444204775490_0684_000002_000001_00 completed. Worker's task counter - total:3, succeeded: 3, killed: 0, failed: 0
2015-10-07 08:08:43,402 INFO: org.apache.tajo.querymaster.Stage (transition(1248)) - [eb_1444204775490_0684_000002] Task Completion Event (Total: 5, Success: 1, Killed: 0, Failed: 0)
2015-10-07 08:08:43,402 INFO: org.apache.tajo.querymaster.Stage (transition(1248)) - [eb_1444204775490_0684_000002] Task Completion Event (Total: 5, Success: 2, Killed: 0, Failed: 0)
2015-10-07 08:08:43,402 INFO: org.apache.tajo.querymaster.Stage (transition(1248)) - [eb_1444204775490_0684_000002] Task Completion Event (Total: 5, Success: 3, Killed: 0, Failed: 0)
2015-10-07 08:08:43,448 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (increaseConcurrency(524)) - Assigned host : 127.0.0.1, Unknown Volume : -1, Concurrency : 4
2015-10-07 08:08:43,449 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (assignToLeafTasks(921)) - Assigned Local/Rack/Total: (0/4/4), Attempted Cancel/Assign/Total: (0/4/4), Locality: 0.00%, Rack host: asf900.gq1.ygridcore.net
2015-10-07 08:08:43,449 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) - Task basedir is created (<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/2)>
2015-10-07 08:08:43,449 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (increaseConcurrency(524)) - Assigned host : 127.0.0.1, Unknown Volume : -1, Concurrency : 1
2015-10-07 08:08:43,450 INFO: org.apache.tajo.worker.TaskImpl (init(197)) - Initializing: ta_1444204775490_0684_000002_000003_00
2015-10-07 08:08:43,450 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - ==================================
2015-10-07 08:08:43,450 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) - * Stage ta_1444204775490_0684_000002_000003_00 is initialized
2015-10-07 08:08:43,450 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) - * InterQuery: true, Use HASH_SHUFFLE shuffle, Fragments (num: 1), Fetches (total:0) :
2015-10-07 08:08:43,450 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/2/3_0>
2015-10-07 08:08:43,450 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) - ==================================
2015-10-07 08:08:43,450 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (assignToLeafTasks(921)) - Assigned Local/Rack/Total: (0/5/5), Attempted Cancel/Assign/Total: (0/5/5), Locality: 0.00%, Rack host: asf900.gq1.ygridcore.net
2015-10-07 08:08:43,450 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) - Task basedir is created (<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/2)>
2015-10-07 08:08:43,451 INFO: org.apache.tajo.worker.TaskImpl (init(197)) - Initializing: ta_1444204775490_0684_000002_000004_00
2015-10-07 08:08:43,451 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - ==================================
2015-10-07 08:08:43,451 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) - * Stage ta_1444204775490_0684_000002_000004_00 is initialized
2015-10-07 08:08:43,451 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) - * InterQuery: true, Use HASH_SHUFFLE shuffle, Fragments (num: 1), Fetches (total:0) :
2015-10-07 08:08:43,451 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/2/4_0>
2015-10-07 08:08:43,451 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) - ==================================
2015-10-07 08:08:43,453 INFO: org.apache.tajo.worker.TaskImpl (run(462)) - ta_1444204775490_0684_000002_000003_00 completed. Worker's task counter - total:4, succeeded: 4, killed: 0, failed: 0
2015-10-07 08:08:43,453 INFO: org.apache.tajo.worker.TaskImpl (run(462)) - ta_1444204775490_0684_000002_000004_00 completed. Worker's task counter - total:5, succeeded: 5, killed: 0, failed: 0
2015-10-07 08:08:43,453 INFO: org.apache.tajo.querymaster.Stage (transition(1248)) - [eb_1444204775490_0684_000002] Task Completion Event (Total: 5, Success: 4, Killed: 0, Failed: 0)
2015-10-07 08:08:43,453 INFO: org.apache.tajo.querymaster.Stage (transition(1350)) - Stage - eb_1444204775490_0684_000002 finalize HASH_SHUFFLE (total=5, success=5, killed=0)
2015-10-07 08:08:43,453 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(163)) - Task Scheduler stopped
2015-10-07 08:08:43,453 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(126)) - TaskScheduler schedulingThread stopped
2015-10-07 08:08:43,454 INFO: org.apache.tajo.querymaster.Stage (transition(1361)) - eb_1444204775490_0684_000002, wait for HASH_SHUFFLE reports. expected Tasks:5
2015-10-07 08:08:43,454 INFO: org.apache.tajo.storage.HashShuffleAppenderManager (close(155)) - Close HashShuffleAppender:eb_1444204775490_0684_000002, intermediates=1
2015-10-07 08:08:43,454 INFO: org.apache.tajo.querymaster.Stage (finalizeShuffleReport(1307)) - eb_1444204775490_0684_000002, Finalized HASH_SHUFFLE reports: 5
2015-10-07 08:08:43,454 INFO: org.apache.tajo.querymaster.Stage (transition(1408)) - Stage completed - eb_1444204775490_0684_000002 (total=5, success=5, killed=0)
2015-10-07 08:08:43,455 INFO: org.apache.tajo.worker.TaskManager (stopExecutionBlock(163)) - Stopped execution block:eb_1444204775490_0684_000002
2015-10-07 08:08:43,455 INFO: org.apache.tajo.querymaster.Query (handle(868)) - Processing q_1444204775490_0684 of type STAGE_COMPLETED
2015-10-07 08:08:43,455 INFO: org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next executable block eb_1444204775490_0684_000003
2015-10-07 08:08:43,455 INFO: org.apache.tajo.querymaster.Query (executeNextBlock(770)) - Scheduling Stage:eb_1444204775490_0684_000003
2015-10-07 08:08:43,455 INFO: org.apache.tajo.querymaster.Stage (calculateShuffleOutputNum(1027)) - ============>>>>> Unexpected Case! <<<<<================
2015-10-07 08:08:43,455 INFO: org.apache.tajo.querymaster.Stage (calculateShuffleOutputNum(1031)) - eb_1444204775490_0684_000003, Table's volume is approximately 1 MB
2015-10-07 08:08:43,455 INFO: org.apache.tajo.querymaster.Stage (calculateShuffleOutputNum(1034)) - eb_1444204775490_0684_000003, The determined number of partitions is 1
2015-10-07 08:08:43,491 INFO: org.apache.tajo.querymaster.Stage (initTaskScheduler(902)) - org.apache.tajo.querymaster.DefaultTaskScheduler is chosen for the task scheduling for eb_1444204775490_0684_000003
2015-10-07 08:08:43,491 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleFragmentsForJoinQuery(262)) - [Distributed Join Strategy] : Symmetric Repartition Join
2015-10-07 08:08:43,492 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleSymmetricRepartitionJoin(358)) - Larger intermediate data is approximately 1 MB
2015-10-07 08:08:43,492 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleSymmetricRepartitionJoin(361)) - The calculated number of tasks is 1
2015-10-07 08:08:43,492 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleSymmetricRepartitionJoin(362)) - The number of total shuffle keys is 1
2015-10-07 08:08:43,492 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleSymmetricRepartitionJoin(366)) - The determined number of join tasks is 1
2015-10-07 08:08:43,492 INFO: org.apache.tajo.querymaster.Stage (run(854)) - 1 objects are scheduled
2015-10-07 08:08:43,492 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (start(134)) - Start TaskScheduler
2015-10-07 08:08:43,494 INFO: org.apache.tajo.worker.TaskManager (createExecutionBlock(114)) - QueryMaster Address:asf900.gq1.ygridcore.net:21521
2015-10-07 08:08:43,495 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(119)) - Tajo Root Dir: hdfs://localhost:48122/tajo
2015-10-07 08:08:43,495 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(120)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir>
2015-10-07 08:08:43,496 INFO: org.apache.tajo.worker.TaskManager (handle(184)) - Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:1000, Disks:3, vCores:2)
2015-10-07 08:08:43,496 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) - Task basedir is created (<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/3)>
2015-10-07 08:08:43,497 INFO: org.apache.tajo.worker.TaskImpl (init(197)) - Initializing: ta_1444204775490_0684_000003_000000_00
2015-10-07 08:08:43,497 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - ==================================
2015-10-07 08:08:43,497 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) - * Stage ta_1444204775490_0684_000003_000000_00 is initialized
2015-10-07 08:08:43,497 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) - * InterQuery: true, Use RANGE_SHUFFLE shuffle, Fragments (num: 4), Fetches (total:2) :
2015-10-07 08:08:43,497 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/3/0_0>
2015-10-07 08:08:43,497 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) - ==================================
2015-10-07 08:08:43,498 INFO: org.apache.tajo.worker.TaskImpl (init(216)) - the directory is created <https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/in/eb_1444204775490_0684_000003/0/0/eb_1444204775490_0684_000002>
2015-10-07 08:08:43,498 INFO: org.apache.tajo.worker.TaskImpl (init(216)) - the directory is created <https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/in/eb_1444204775490_0684_000003/0/0/eb_1444204775490_0684_000001>
2015-10-07 08:08:43,499 INFO: org.apache.tajo.worker.TaskImpl (getFetchRunners(725)) - Create shuffle Fetchers local:2, remote:0
2015-10-07 08:08:43,499 INFO: org.apache.tajo.worker.TaskImpl (run(617)) - Add a new FileChunk to local chunk list
2015-10-07 08:08:43,500 INFO: org.apache.tajo.worker.TaskImpl (run(617)) - Add a new FileChunk to local chunk list
2015-10-07 08:08:43,500 INFO: org.apache.tajo.worker.TaskImpl (waitForFetch(364)) - ta_1444204775490_0684_000003_000000_00 All fetches are done!
2015-10-07 08:08:43,501 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (checkIfInMemoryInnerJoinIsPossible(287)) - [ta_1444204775490_0684_000003_000000_00] the volume of Left relations (eb_1444204775490_0684_000001) is 200 B and is fit to main maemory.
2015-10-07 08:08:43,501 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createBestInnerJoinPlan(432)) - Join (7) chooses [In-memory Hash Join]
2015-10-07 08:08:43,501 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (switchJoinSidesIfNecessary(412)) - [ta_1444204775490_0684_000003_000000_00] Right relations eb_1444204775490_0684_000002 (140 B) is smaller than Left relations eb_1444204775490_0684_000001 (200 B).
2015-10-07 08:08:43,501 INFO: org.apache.tajo.engine.planner.physical.RangeShuffleFileWriteExec (init(80)) - Output data directory: <https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/3/0_0/output>
2015-10-07 08:08:43,531 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) - [ta_1444204775490_0684_000003_000000_00] Chunks creation time: 0 msec
2015-10-07 08:08:43,540 INFO: org.apache.tajo.worker.TaskImpl (run(462)) - ta_1444204775490_0684_000003_000000_00 completed. Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0
2015-10-07 08:08:43,541 INFO: org.apache.tajo.querymaster.Stage (transition(1350)) - Stage - eb_1444204775490_0684_000003 finalize RANGE_SHUFFLE (total=1, success=1, killed=0)
2015-10-07 08:08:43,541 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(163)) - Task Scheduler stopped
2015-10-07 08:08:43,541 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(126)) - TaskScheduler schedulingThread stopped
2015-10-07 08:08:43,541 INFO: org.apache.tajo.querymaster.Stage (transition(1408)) - Stage completed - eb_1444204775490_0684_000003 (total=1, success=1, killed=0)
2015-10-07 08:08:43,541 INFO: org.apache.tajo.querymaster.Query (handle(868)) - Processing q_1444204775490_0684 of type STAGE_COMPLETED
2015-10-07 08:08:43,541 INFO: org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next executable block eb_1444204775490_0684_000004
2015-10-07 08:08:43,542 INFO: org.apache.tajo.querymaster.Query (executeNextBlock(770)) - Scheduling Stage:eb_1444204775490_0684_000004
2015-10-07 08:08:43,542 INFO: org.apache.tajo.worker.TaskManager (stopExecutionBlock(163)) - Stopped execution block:eb_1444204775490_0684_000003
2015-10-07 08:08:43,561 INFO: org.apache.tajo.querymaster.Stage (initTaskScheduler(902)) - org.apache.tajo.querymaster.DefaultTaskScheduler is chosen for the task scheduling for eb_1444204775490_0684_000004
2015-10-07 08:08:43,561 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1096)) - eb_1444204775490_0684_000004, Table's volume is approximately 1 MB
2015-10-07 08:08:43,561 INFO: org.apache.tajo.querymaster.Stage (getNonLeafTaskNum(1101)) - eb_1444204775490_0684_000004, The determined number of non-leaf tasks is 1
2015-10-07 08:08:43,561 INFO: org.apache.tajo.querymaster.Repartitioner (scheduleRangeShuffledFetches(673)) - eb_1444204775490_0684_000004, Try to divide [(1), (5)) into 1 sub ranges (total units: 1)
2015-10-07 08:08:43,562 INFO: org.apache.tajo.querymaster.Stage (run(854)) - 1 objects are scheduled
2015-10-07 08:08:43,562 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (start(134)) - Start TaskScheduler
2015-10-07 08:08:43,563 INFO: org.apache.tajo.worker.TaskManager (createExecutionBlock(114)) - QueryMaster Address:asf900.gq1.ygridcore.net:21521
2015-10-07 08:08:43,565 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(119)) - Tajo Root Dir: hdfs://localhost:48122/tajo
2015-10-07 08:08:43,565 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(120)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir>
2015-10-07 08:08:43,566 INFO: org.apache.tajo.worker.TaskManager (handle(184)) - Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:1000, Disks:3, vCores:2)
2015-10-07 08:08:43,566 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) - Task basedir is created (<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/4)>
2015-10-07 08:08:43,566 INFO: org.apache.tajo.worker.TaskImpl (init(197)) - Initializing: ta_1444204775490_0684_000004_000000_00
2015-10-07 08:08:43,566 INFO: org.apache.tajo.storage.FileTablespace (getAppenderFilePath(290)) - Output File Path: hdfs://localhost:48122/tmp/tajo-jenkins/staging/q_1444204775490_0684/RESULT/part-04-000000-000
2015-10-07 08:08:43,566 INFO: org.apache.tajo.worker.TaskImpl (initPlan(147)) - Output File Path: hdfs://localhost:48122/tmp/tajo-jenkins/staging/q_1444204775490_0684/RESULT/part-04-000000-000
2015-10-07 08:08:43,566 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - ==================================
2015-10-07 08:08:43,566 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) - * Stage ta_1444204775490_0684_000004_000000_00 is initialized
2015-10-07 08:08:43,566 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) - * InterQuery: false, Fragments (num: 1), Fetches (total:1) :
2015-10-07 08:08:43,566 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/4/0_0>
2015-10-07 08:08:43,566 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) - ==================================
2015-10-07 08:08:43,567 INFO: org.apache.tajo.worker.TaskImpl (init(216)) - the directory is created <https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/in/eb_1444204775490_0684_000004/0/0/eb_1444204775490_0684_000003>
2015-10-07 08:08:43,580 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:48122/tmp/tajo-jenkins/staging/history/20151007/query-detail/q_1444204775490_0681/eb_1444204775490_0681_000003.hist
2015-10-07 08:08:43,581 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:48122/tmp/tajo-jenkins/staging/history/20151007/query-detail/q_1444204775490_0681/eb_1444204775490_0681_000001.hist
2015-10-07 08:08:43,584 INFO: org.apache.tajo.pullserver.TajoPullServerService (getFileChunks(688)) - GET Request for <https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/3/0_0/output/output> (start=(1), end=(5), last=true)
2015-10-07 08:08:43,585 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:54689 is added to blk_1073744259_3435{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-7c434919-4171-4b0e-b906-81555da2f0e0:NORMAL:127.0.0.1:54689|RBW]]} size 469
2015-10-07 08:08:43,585 INFO: org.apache.tajo.worker.TaskImpl (getFetchRunners(725)) - Create shuffle Fetchers local:1, remote:0
2015-10-07 08:08:43,585 INFO: org.apache.tajo.worker.TaskImpl (run(617)) - Add a new FileChunk to local chunk list
2015-10-07 08:08:43,585 INFO: org.apache.tajo.worker.TaskImpl (waitForFetch(364)) - ta_1444204775490_0684_000004_000000_00 All fetches are done!
2015-10-07 08:08:43,587 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) - [ta_1444204775490_0684_000004_000000_00] Total merge time: 0 msec
2015-10-07 08:08:43,587 INFO: org.apache.tajo.engine.planner.physical.ExternalSortExec (close(791)) - Delete file: "fragment": {"id": "eb_1444204775490_0684_000003", "path": <https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/22bd0a7c-b22c-495e-9092-53c5be4aea71/tajo-localdir/q_1444204775490_0684/output/3/0_0/output/output",> "start": 0,"length": 145}
2015-10-07 08:08:43,590 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:54689 is added to blk_1073744260_3436{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-c7cb2e06-34ad-4ba3-87d7-65c6b3feccac:NORMAL:127.0.0.1:54689|RBW]]} size 0
2015-10-07 08:08:43,591 INFO: org.apache.tajo.worker.TaskImpl (run(462)) - ta_1444204775490_0684_000004_000000_00 completed. Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0
2015-10-07 08:08:43,591 INFO: org.apache.tajo.querymaster.Stage (transition(1350)) - Stage - eb_1444204775490_0684_000004 finalize NONE_SHUFFLE (total=1, success=1, killed=0)
2015-10-07 08:08:43,591 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(163)) - Task Scheduler stopped
2015-10-07 08:08:43,591 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(126)) - TaskScheduler schedulingThread stopped
2015-10-07 08:08:43,592 INFO: org.apache.tajo.querymaster.Stage (transition(1408)) - Stage completed - eb_1444204775490_0684_000004 (total=1, success=1, killed=0)
2015-10-07 08:08:43,592 INFO: org.apache.tajo.querymaster.Query (handle(868)) - Processing q_1444204775490_0684 of type STAGE_COMPLETED
2015-10-07 08:08:43,592 INFO: org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next executable block eb_1444204775490_0684_000005
2015-10-07 08:08:43,592 INFO: org.apache.tajo.querymaster.Query (transition(815)) - Complete Stage[eb_1444204775490_0684_000004], State: SUCCEEDED, 4/4.
2015-10-07 08:08:43,592 INFO: org.apache.tajo.querymaster.Query (handle(868)) - Processing q_1444204775490_0684 of type QUERY_COMPLETED
2015-10-07 08:08:43,592 INFO: org.apache.tajo.worker.TaskManager (stopExecutionBlock(163)) - Stopped execution block:eb_1444204775490_0684_000004
2015-10-07 08:08:43,593 INFO: org.apache.tajo.querymaster.Query (handle(886)) - q_1444204775490_0684 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED
2015-10-07 08:08:43,593 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(296)) - Query completion notified from q_1444204775490_0684 final state: QUERY_SUCCEEDED
2015-10-07 08:08:43,594 INFO: org.apache.tajo.master.QueryInProgress (heartbeat(259)) - Received QueryMaster heartbeat:q_1444204775490_0684,state=QUERY_SUCCEEDED,progress=1.0, queryMaster=asf900.gq1.ygridcore.net
2015-10-07 08:08:43,594 INFO: org.apache.tajo.master.QueryManager (stopQuery(279)) - Stop QueryInProgress:q_1444204775490_0684
2015-10-07 08:08:43,594 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(124)) - =========================================================
2015-10-07 08:08:43,594 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(125)) - Stop query:q_1444204775490_0684
2015-10-07 08:08:43,595 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(172)) - Stopping QueryMasterTask:q_1444204775490_0684
2015-10-07 08:08:43,595 INFO: org.apache.tajo.querymaster.QueryMasterTask (cleanupQuery(473)) - Cleanup resources of all workers. Query: q_1444204775490_0684, workers: 1
2015-10-07 08:08:43,595 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(188)) - Stopped QueryMasterTask:q_1444204775490_0684
Killed
Results :
Tests run: 556, Failures: 0, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 1.622 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 1.234 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 2.601 s]
[INFO] Tajo Common ....................................... SUCCESS [ 29.866 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 2.221 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 4.911 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 6.809 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 1.182 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 50.905 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.424 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 10.717 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 2.671 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [ 46.222 s]
[INFO] Tajo PullServer ................................... SUCCESS [ 0.959 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.504 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 1.750 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 3.644 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 1.757 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 3.667 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.343 s]
[INFO] Tajo Core ......................................... SUCCESS [ 5.936 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 0.901 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 9.059 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.039 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 0.942 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.013 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 3.284 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 2.232 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 32.248 s]
[INFO] Tajo JDBC storage common .......................... SUCCESS [ 0.801 s]
[INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 0.769 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 0.956 s]
[INFO] Tajo Distribution ................................. SUCCESS [ 5.350 s]
[INFO] Tajo Core Tests ................................... FAILURE [09:29 min]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 13:32 min
[INFO] Finished at: 2015-10-07T08:08:43+00:00
[INFO] Final Memory: 159M/1984M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on project tajo-core-tests: 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-jdk8-nightly/ws/tajo-core-tests> && /home/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.8/jre/bin/java -Xms512m -Xmx1024m -XX:MaxPermSize=152m -Dfile.encoding=UTF-8 -Dderby.storage.pageSize=1024 -Dderby.stream.error.file=/dev/null -jar <https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/surefire/surefirebooter8578689976024429437.jar> <https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/surefire/surefire29726784917069910tmp> <https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/surefire/surefire_145375762704193844710tmp>
[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-tests
Build step 'Execute shell' marked build as failure
Archiving artifacts
Recording test results