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 2016/01/12 04:08:41 UTC
Build failed in Jenkins: Tajo-master-build #1046
See <https://builds.apache.org/job/Tajo-master-build/1046/changes>
Changes:
[jhkim] TAJO-2038: NPE in DelimitedTextFileScanner#getProgress.
------------------------------------------
[...truncated 740985 lines...]
2016-01-12 03:08:37,403 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56160 is added to blk_1073749083_8259{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-474b8bba-0688-45d4-bf86-24779134cbc8:NORMAL:127.0.0.1:56160|RBW]]} size 0
2016-01-12 03:08:37,404 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56160 is added to blk_1073749084_8260{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-474b8bba-0688-45d4-bf86-24779134cbc8:NORMAL:127.0.0.1:56160|RBW]]} size 0
2016-01-12 03:08:37,406 INFO: org.apache.tajo.worker.TaskImpl (run(477)) - ta_1452566749223_2256_000001_000000_00 is complete. 8 ms elapsed, final state:TA_FAILED
2016-01-12 03:08:37,406 ERROR: org.apache.tajo.querymaster.TaskAttempt (transition(437)) - ta_1452566749223_2256_000001_000000_00 FROM asf900.gq1.ygridcore.net >> internal error: testQueryFailure
2016-01-12 03:08:37,406 INFO: org.apache.tajo.querymaster.Task (transition(655)) - ====================================================================================
2016-01-12 03:08:37,406 INFO: org.apache.tajo.querymaster.Task (transition(656)) - >>> Task Failed: ta_1452566749223_2256_000001_000000_00, retry:true, attempts:1 <<<
2016-01-12 03:08:37,406 INFO: org.apache.tajo.querymaster.Task (transition(658)) - ====================================================================================
2016-01-12 03:08:37,406 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:40310/tmp/tajo-jenkins/staging/history/20160112/query-detail/q_1452566749223_2254/eb_1452566749223_2254_000002.hist
2016-01-12 03:08:37,407 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (increaseConcurrency(526)) - Assigned host : 127.0.0.1, Unknown Volume : -1, Concurrency : 1
2016-01-12 03:08:37,408 INFO: org.apache.tajo.storage.FileTablespace (getAppenderFilePath(274)) - Output File Path: hdfs://localhost:40310/tmp/tajo-jenkins/staging/q_1452566749223_2256/RESULT/part-01-000000-000
2016-01-12 03:08:37,408 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - Output File Path: hdfs://localhost:40310/tmp/tajo-jenkins/staging/q_1452566749223_2256/RESULT/part-01-000000-000
2016-01-12 03:08:37,408 INFO: org.apache.tajo.worker.TaskImpl (initPlan(159)) - * Task ta_1452566749223_2256_000001_000000_01 is initialized. InterQuery: false, Shuffle: null, Fragments: 1, Fetches:0, Local dir: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/0f60fce5-1fdf-4479-817d-1dfdfe8a378b/tajo-localdir/q_1452566749223_2256/output/1/0_1>
2016-01-12 03:08:37,408 INFO: BlockStateChange (invalidateWorkForOneNode(3488)) - BLOCK* BlockManager: ask 127.0.0.1:56160 to delete [blk_1073749074_8250, blk_1073749075_8251, blk_1073749076_8252, blk_1073749080_8256]
2016-01-12 03:08:37,409 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56160 is added to blk_1073749085_8261{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-474b8bba-0688-45d4-bf86-24779134cbc8:NORMAL:127.0.0.1:56160|RBW]]} size 480
2016-01-12 03:08:37,410 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (assignToLeafTasks(922)) - Assigned Local/Rack/Total: (0/2/2), Attempted Cancel/Assign/Total: (0/2/2), Locality: 0.00%, Rack host: asf900.gq1.ygridcore.net
2016-01-12 03:08:37,412 INFO: BlockStateChange (addToInvalidates(1106)) - BLOCK* addToInvalidates: blk_1073749083_8259 127.0.0.1:56160
2016-01-12 03:08:37,413 ERROR: org.apache.tajo.worker.TaskImpl (run(425)) - internal error: testQueryFailure
org.apache.tajo.exception.TajoInternalError: internal error: testQueryFailure
at org.apache.tajo.engine.function.FailFunction.eval(FailFunction.java:65)
at org.apache.tajo.plan.function.ClassBasedScalarFunctionInvoke.eval(ClassBasedScalarFunctionInvoke.java:55)
at org.apache.tajo.plan.expr.GeneralFunctionEval.eval(GeneralFunctionEval.java:60)
at org.apache.tajo.engine.planner.Projector.eval(Projector.java:76)
at org.apache.tajo.engine.planner.physical.SeqScanExec.next(SeqScanExec.java:296)
at org.apache.tajo.engine.planner.physical.StoreTableExec.next(StoreTableExec.java:122)
at org.apache.tajo.worker.TaskImpl.run(TaskImpl.java:420)
at org.apache.tajo.worker.TaskContainer.run(TaskContainer.java:65)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
2016-01-12 03:08:37,414 INFO: org.apache.tajo.master.QueryInProgress (heartbeat(257)) - Received QueryMaster heartbeat:q_1452566749223_2256,state=QUERY_RUNNING,progress=0.0, queryMaster=asf900.gq1.ygridcore.net
2016-01-12 03:08:37,414 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56160 is added to blk_1073749086_8262{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-474b8bba-0688-45d4-bf86-24779134cbc8:NORMAL:127.0.0.1:56160|RBW]]} size 0
2016-01-12 03:08:37,415 INFO: org.apache.tajo.worker.TaskImpl (run(477)) - ta_1452566749223_2256_000001_000000_01 is complete. 7 ms elapsed, final state:TA_FAILED
2016-01-12 03:08:37,415 ERROR: org.apache.tajo.querymaster.TaskAttempt (transition(437)) - ta_1452566749223_2256_000001_000000_01 FROM asf900.gq1.ygridcore.net >> internal error: testQueryFailure
2016-01-12 03:08:37,415 INFO: org.apache.tajo.querymaster.Task (transition(655)) - ====================================================================================
2016-01-12 03:08:37,415 INFO: org.apache.tajo.querymaster.Task (transition(656)) - >>> Task Failed: ta_1452566749223_2256_000001_000000_01, retry:true, attempts:2 <<<
2016-01-12 03:08:37,416 INFO: org.apache.tajo.querymaster.Task (transition(658)) - ====================================================================================
2016-01-12 03:08:37,416 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (increaseConcurrency(526)) - Assigned host : 127.0.0.1, Unknown Volume : -1, Concurrency : 1
2016-01-12 03:08:37,418 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (assignToLeafTasks(922)) - Assigned Local/Rack/Total: (0/3/3), Attempted Cancel/Assign/Total: (0/3/3), Locality: 0.00%, Rack host: asf900.gq1.ygridcore.net
2016-01-12 03:08:37,418 INFO: org.apache.tajo.storage.FileTablespace (getAppenderFilePath(274)) - Output File Path: hdfs://localhost:40310/tmp/tajo-jenkins/staging/q_1452566749223_2256/RESULT/part-01-000000-000
2016-01-12 03:08:37,418 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - Output File Path: hdfs://localhost:40310/tmp/tajo-jenkins/staging/q_1452566749223_2256/RESULT/part-01-000000-000
2016-01-12 03:08:37,418 INFO: org.apache.tajo.worker.TaskImpl (initPlan(159)) - * Task ta_1452566749223_2256_000001_000000_02 is initialized. InterQuery: false, Shuffle: null, Fragments: 1, Fetches:0, Local dir: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/0f60fce5-1fdf-4479-817d-1dfdfe8a378b/tajo-localdir/q_1452566749223_2256/output/1/0_2>
2016-01-12 03:08:37,420 INFO: BlockStateChange (addToInvalidates(1106)) - BLOCK* addToInvalidates: blk_1073749086_8262 127.0.0.1:56160
2016-01-12 03:08:37,420 ERROR: org.apache.tajo.worker.TaskImpl (run(425)) - internal error: testQueryFailure
org.apache.tajo.exception.TajoInternalError: internal error: testQueryFailure
at org.apache.tajo.engine.function.FailFunction.eval(FailFunction.java:65)
at org.apache.tajo.plan.function.ClassBasedScalarFunctionInvoke.eval(ClassBasedScalarFunctionInvoke.java:55)
at org.apache.tajo.plan.expr.GeneralFunctionEval.eval(GeneralFunctionEval.java:60)
at org.apache.tajo.engine.planner.Projector.eval(Projector.java:76)
at org.apache.tajo.engine.planner.physical.SeqScanExec.next(SeqScanExec.java:296)
at org.apache.tajo.engine.planner.physical.StoreTableExec.next(StoreTableExec.java:122)
at org.apache.tajo.worker.TaskImpl.run(TaskImpl.java:420)
at org.apache.tajo.worker.TaskContainer.run(TaskContainer.java:65)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
2016-01-12 03:08:37,422 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56160 is added to blk_1073749087_8263{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-474b8bba-0688-45d4-bf86-24779134cbc8:NORMAL:127.0.0.1:56160|RBW]]} size 0
2016-01-12 03:08:37,423 INFO: org.apache.tajo.worker.TaskImpl (run(477)) - ta_1452566749223_2256_000001_000000_02 is complete. 5 ms elapsed, final state:TA_FAILED
2016-01-12 03:08:37,423 ERROR: org.apache.tajo.querymaster.TaskAttempt (transition(437)) - ta_1452566749223_2256_000001_000000_02 FROM asf900.gq1.ygridcore.net >> internal error: testQueryFailure
2016-01-12 03:08:37,423 INFO: org.apache.tajo.querymaster.Task (transition(655)) - ====================================================================================
2016-01-12 03:08:37,423 INFO: org.apache.tajo.querymaster.Task (transition(656)) - >>> Task Failed: ta_1452566749223_2256_000001_000000_02, retry:false, attempts:3 <<<
2016-01-12 03:08:37,423 INFO: org.apache.tajo.querymaster.Task (transition(658)) - ====================================================================================
2016-01-12 03:08:37,424 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(164)) - Task Scheduler stopped
2016-01-12 03:08:37,424 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(127)) - TaskScheduler schedulingThread stopped
2016-01-12 03:08:37,424 INFO: org.apache.tajo.querymaster.Stage (transition(1404)) - Stage completed - eb_1452566749223_2256_000001 (total=1, success=0, killed=0)
2016-01-12 03:08:37,424 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(164)) - Task Scheduler stopped
2016-01-12 03:08:37,424 INFO: org.apache.tajo.querymaster.Query (handle(867)) - Processing q_1452566749223_2256 of type STAGE_COMPLETED
2016-01-12 03:08:37,424 INFO: org.apache.tajo.querymaster.Query (transition(814)) - Complete Stage[eb_1452566749223_2256_000001], State: FAILED, 1/1.
2016-01-12 03:08:37,424 INFO: org.apache.tajo.querymaster.Query (handle(867)) - Processing q_1452566749223_2256 of type QUERY_COMPLETED
2016-01-12 03:08:37,425 INFO: org.apache.tajo.querymaster.Query (handle(885)) - q_1452566749223_2256 Query Transitioned from QUERY_RUNNING to QUERY_FAILED
2016-01-12 03:08:37,425 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(296)) - Query completion notified from q_1452566749223_2256 final state: QUERY_FAILED
2016-01-12 03:08:37,431 INFO: org.apache.tajo.worker.ExecutionBlockContext (stop(175)) - Worker's task counter - total:3, succeeded: 0, killed: 0, failed: 3
2016-01-12 03:08:37,431 INFO: org.apache.tajo.master.QueryInProgress (heartbeat(257)) - Received QueryMaster heartbeat:q_1452566749223_2256,state=QUERY_FAILED,progress=0.0, queryMaster=asf900.gq1.ygridcore.net
2016-01-12 03:08:37,431 WARN: org.apache.tajo.master.QueryInProgress (heartbeat(272)) - q_1452566749223_2256 is stopped because
2016-01-12 03:08:37,431 INFO: org.apache.tajo.master.QueryManager (stopQuery(279)) - Stop QueryInProgress:q_1452566749223_2256
2016-01-12 03:08:37,431 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(122)) - =========================================================
2016-01-12 03:08:37,431 INFO: org.apache.tajo.worker.TaskManager (stopExecutionBlock(160)) - Stopped execution block:eb_1452566749223_2256_000001
2016-01-12 03:08:37,431 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(123)) - Stop query:q_1452566749223_2256
2016-01-12 03:08:37,431 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(172)) - Stopping QueryMasterTask:q_1452566749223_2256
2016-01-12 03:08:37,431 INFO: org.apache.tajo.querymaster.QueryMasterTask (cleanupQuery(473)) - Cleanup resources of all workers. Query: q_1452566749223_2256, workers: 1
2016-01-12 03:08:37,432 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(188)) - Stopped QueryMasterTask:q_1452566749223_2256
2016-01-12 03:08:37,812 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:40310/tmp/tajo-jenkins/staging/history/20160112/query-detail/q_1452566749223_2254/eb_1452566749223_2254_000001.hist
2016-01-12 03:08:37,815 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56160 is added to blk_1073749088_8264{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-474b8bba-0688-45d4-bf86-24779134cbc8:NORMAL:127.0.0.1:56160|RBW]]} size 0
2016-01-12 03:08:37,822 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(240)) - SQL: CREATE DATABASE IF NOT EXISTS "TestTajoCliNegatives"
2016-01-12 03:08:37,822 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(281)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestTajoCliNegatives
2016-01-12 03:08:37,823 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(283)) - =============================================
2016-01-12 03:08:37,823 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(284)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestTajoCliNegatives
2016-01-12 03:08:37,823 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(285)) - =============================================
2016-01-12 03:08:37,823 INFO: org.apache.tajo.master.exec.DDLExecutor (createDatabase(245)) - database "TestTajoCliNegatives" is already exists.
Used heap: 346.6 MiB/889.0 MiB, direct:33.5 MiB/33.5 MiB, mapped:0 B/0 B, Active Threads: 362, Run: TestTajoCliNegatives.testDescTable
2016-01-12 03:08:37,827 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 49d5868e-67ad-400c-a4ea-ca5832e8d711 is removed.
2016-01-12 03:08:37,835 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 3ef5bcee-b86e-44ac-95c4-99cc5661d6b9 is removed.
Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.955 sec - in org.apache.tajo.cli.tsql.TestTajoCliNegatives
Running org.apache.tajo.cli.tsql.TestDefaultCliOutputFormatter
2016-01-12 03:08:37,848 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session c06d37a1-a80f-46da-9653-1e205af1f6ae is created.
2016-01-12 03:08:37,850 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session c06d37a1-a80f-46da-9653-1e205af1f6ae is removed.
2016-01-12 03:08:37,854 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session 172dfd61-adcc-4b85-aad4-f9614d64c864 is created.
2016-01-12 03:08:37,855 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 172dfd61-adcc-4b85-aad4-f9614d64c864 is removed.
2016-01-12 03:08:37,859 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session ec47e8f4-2a9e-4833-8046-38ce3eb018ec is created.
2016-01-12 03:08:37,872 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session ec47e8f4-2a9e-4833-8046-38ce3eb018ec is removed.
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.036 sec - in org.apache.tajo.cli.tsql.TestDefaultCliOutputFormatter
Running org.apache.tajo.cli.tsql.TestSimpleParser
Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 sec - in org.apache.tajo.cli.tsql.TestSimpleParser
Running org.apache.tajo.cli.tsql.commands.TestHdfsCommand
2016-01-12 03:08:37,880 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session 6e7fd5ec-6e71-4ba8-86a5-ec5a869e1184 is created.
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.051 sec - in org.apache.tajo.cli.tsql.commands.TestHdfsCommand
Running org.apache.tajo.cli.tsql.commands.TestExecExternalShellCommand
2016-01-12 03:08:37,932 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session 9fc5628d-8f40-42b5-9499-a21f2ea7ea16 is created.
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.022 sec - in org.apache.tajo.cli.tsql.commands.TestExecExternalShellCommand
2016-01-12 03:08:37,966 INFO: org.mortbay.log (info(67)) - Shutdown hook executing
2016-01-12 03:08:37,966 INFO: org.mortbay.log (info(67)) - Shutdown hook complete
Jan 12, 2016 2:49:08 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Jan 12, 2016 2:49:08 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Jan 12, 2016 2:49:25 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Jan 12, 2016 2:49:25 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Jan 12, 2016 2:49:25 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Jan 12, 2016 2:49:25 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Jan 12, 2016 2:49:25 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
Jan 12, 2016 2:49:28 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers
2016-01-12 03:08:37,973 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 6e7fd5ec-6e71-4ba8-86a5-ec5a869e1184 is removed.
2016-01-12 03:08:37,977 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 9fc5628d-8f40-42b5-9499-a21f2ea7ea16 is removed.
2016-01-12 03:08:37,981 INFO: org.apache.tajo.worker.TajoWorker (run(518)) - ============================================
2016-01-12 03:08:37,981 INFO: org.apache.tajo.worker.TajoWorker (run(519)) - TajoWorker received SIGINT Signal
2016-01-12 03:08:37,981 INFO: org.apache.tajo.worker.TajoWorker (run(520)) - ============================================
2016-01-12 03:08:37,985 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped
2016-01-12 03:08:37,990 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_asf900.gq1.ygridcore.net_37347 stopped.
2016-01-12 03:08:38,036 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(113)) - NodeStatusUpdater stopped.
2016-01-12 03:08:38,037 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(261)) - Heartbeat Thread stopped.
2016-01-12 03:08:38,037 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:37349) shutdown
2016-01-12 03:08:38,039 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) - QueryMasterManagerService stopped
2016-01-12 03:08:38,039 INFO: org.apache.tajo.querymaster.QueryMaster (run(428)) - QueryMaster heartbeat thread stopped
2016-01-12 03:08:38,040 INFO: org.apache.tajo.querymaster.QueryMaster (serviceStop(164)) - QueryMaster stopped
2016-01-12 03:08:38,040 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(98)) - TajoWorkerClientService stopping
2016-01-12 03:08:38,040 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:37348) shutdown
2016-01-12 03:08:38,040 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(102)) - TajoWorkerClientService stopped
2016-01-12 03:08:38,042 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:37347) shutdown
2016-01-12 03:08:38,042 INFO: org.apache.tajo.worker.TajoWorkerManagerService (serviceStop(93)) - TajoWorkerManagerService stopped
2016-01-12 03:08:38,042 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(332)) - TajoWorker main thread exiting
2016-01-12 03:08:40,409 INFO: BlockStateChange (invalidateWorkForOneNode(3488)) - BLOCK* BlockManager: ask 127.0.0.1:56160 to delete [blk_1073749083_8259, blk_1073749086_8262]
2016-01-12 03:08:41,249 INFO: org.apache.tajo.master.TajoMaster (run(568)) - ============================================
2016-01-12 03:08:41,249 INFO: org.apache.tajo.master.TajoMaster (run(569)) - TajoMaster received SIGINT Signal
2016-01-12 03:08:41,249 INFO: org.apache.tajo.master.TajoMaster (run(570)) - ============================================
2016-01-12 03:08:41,251 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:37346) shutdown
2016-01-12 03:08:41,251 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped.
2016-01-12 03:08:41,259 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_127.0.0.1_37345 stopped.
2016-01-12 03:08:41,261 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56160 is added to blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-474b8bba-0688-45d4-bf86-24779134cbc8:NORMAL:127.0.0.1:56160|RBW]]} size 704
2016-01-12 03:08:41,261 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:37345) shutdown
2016-01-12 03:08:41,261 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped
2016-01-12 03:08:41,263 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:37344) shutdown
2016-01-12 03:08:41,264 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(182)) - Catalog Server (127.0.0.1:37343) shutdown
2016-01-12 03:08:41,264 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (CatalogProtocol) listened on 127.0.0.1:37343) shutdown
2016-01-12 03:08:41,265 INFO: org.apache.tajo.catalog.store.DerbyStore (close(2978)) - Close database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/0f60fce5-1fdf-4479-817d-1dfdfe8a378b/db;create=true)>
2016-01-12 03:08:41,266 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(172)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:37342) shutdown
2016-01-12 03:08:41,266 INFO: org.apache.tajo.master.TajoMaster (serviceStop(424)) - Tajo Master main thread exiting
2016-01-12 03:08:41,310 INFO: org.apache.tajo.catalog.store.DerbyStore (shutdown(68)) - Derby shutdown complete normally.
2016-01-12 03:08:41,310 INFO: org.apache.tajo.catalog.store.DerbyStore (shutdown(75)) - Shutdown database
Results :
Failed tests:
TestQueryResult.testTemporalResultOnClose:49 null
Tests run: 1732, Failures: 1, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 2.751 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 3.160 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 5.933 s]
[INFO] Tajo Common ....................................... SUCCESS [ 36.147 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 3.463 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 6.921 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 9.190 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 1.338 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [02:26 min]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.668 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 14.823 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 3.743 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [01:15 min]
[INFO] Tajo PullServer ................................... SUCCESS [ 1.065 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.774 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 2.573 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 5.282 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 2.124 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 5.020 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.832 s]
[INFO] Tajo Core ......................................... SUCCESS [ 8.955 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 0.942 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 12.287 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.077 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 1.028 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.120 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 5.348 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 3.502 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 40.222 s]
[INFO] Tajo JDBC storage common .......................... SUCCESS [ 0.999 s]
[INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 1.240 s]
[INFO] Tajo S3 storage ................................... SUCCESS [ 0.305 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 0.972 s]
[INFO] Tajo Distribution ................................. SUCCESS [ 6.000 s]
[INFO] Tajo Core Tests ................................... FAILURE [23:05 min]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 30:01 min
[INFO] Finished at: 2016-01-12T03:08:41+00:00
[INFO] Final Memory: 152M/2029M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.19:test (default-test) on project tajo-core-tests: There are test failures.
[ERROR]
[ERROR] Please refer to <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/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-tests
Build step 'Execute shell' marked build as failure
Updating TAJO-2038
Jenkins build is back to normal : Tajo-master-build #1047
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/1047/>