See <https://builds.apache.org/job/Tajo-master-build/933/changes>
Changes: [jihoonson] TAJO-1900: When a record column and its child column are retrieved ------------------------------------------ [...truncated 751833 lines...] 2015-10-09 03:08:01,186 INFO: org.apache.tajo.querymaster.Task (transition(664)) - >>> Task Failed: ta_1444358647135_2240_000001_000000_00, retry:true, attempts:1 <<< 2015-10-09 03:08:01,186 INFO: org.apache.tajo.querymaster.Task (transition(666)) - ==================================================================================== 2015-10-09 03:08:01,187 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (increaseConcurrency(524)) - Assigned host : 127.0.0.1, Unknown Volume : -1, Concurrency : 1 2015-10-09 03:08:01,187 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) - Task basedir is created (<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/854d2aa4-cdf7-4365-8746-b7d9efcae131/tajo-localdir/q_1444358647135_2240/output/1)> 2015-10-09 03:08:01,188 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-09 03:08:01,188 INFO: org.apache.tajo.worker.TaskImpl (init(197)) - Initializing: ta_1444358647135_2240_000001_000000_01 2015-10-09 03:08:01,188 INFO: org.apache.tajo.storage.FileTablespace (getAppenderFilePath(290)) - Output File Path: hdfs://localhost:54867/tmp/tajo-jenkins/staging/q_1444358647135_2240/RESULT/part-01-000000-000 2015-10-09 03:08:01,188 INFO: org.apache.tajo.worker.TaskImpl (initPlan(147)) - Output File Path: hdfs://localhost:54867/tmp/tajo-jenkins/staging/q_1444358647135_2240/RESULT/part-01-000000-000 2015-10-09 03:08:01,188 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - ================================== 2015-10-09 03:08:01,188 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) - * Stage ta_1444358647135_2240_000001_000000_01 is initialized 2015-10-09 03:08:01,188 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) - * InterQuery: false, Fragments (num: 1), Fetches (total:0) : 2015-10-09 03:08:01,188 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/854d2aa4-cdf7-4365-8746-b7d9efcae131/tajo-localdir/q_1444358647135_2240/output/1/0_1> 2015-10-09 03:08:01,188 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) - ================================== 2015-10-09 03:08:01,190 INFO: BlockStateChange (addToInvalidates(1106)) - BLOCK* addToInvalidates: blk_1073748799_7975 127.0.0.1:39054 2015-10-09 03:08:01,191 ERROR: org.apache.tajo.worker.TaskImpl (run(410)) - 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:74) 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:405) 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) 2015-10-09 03:08:01,194 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39054 is added to blk_1073748800_7976{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-3b23bd7c-a935-4257-8604-06040f54d964:NORMAL:127.0.0.1:39054|FINALIZED]]} size 0 2015-10-09 03:08:01,194 INFO: org.apache.tajo.worker.TaskImpl (run(462)) - ta_1444358647135_2240_000001_000000_01 completed. Worker's task counter - total:2, succeeded: 0, killed: 0, failed: 2 2015-10-09 03:08:01,195 ERROR: org.apache.tajo.querymaster.TaskAttempt (transition(438)) - ta_1444358647135_2240_000001_000000_01 FROM asf900.gq1.ygridcore.net >> internal error: testQueryFailure 2015-10-09 03:08:01,195 INFO: org.apache.tajo.querymaster.Task (transition(663)) - ==================================================================================== 2015-10-09 03:08:01,195 INFO: org.apache.tajo.querymaster.Task (transition(664)) - >>> Task Failed: ta_1444358647135_2240_000001_000000_01, retry:true, attempts:2 <<< 2015-10-09 03:08:01,195 INFO: org.apache.tajo.querymaster.Task (transition(666)) - ==================================================================================== 2015-10-09 03:08:01,195 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (increaseConcurrency(524)) - Assigned host : 127.0.0.1, Unknown Volume : -1, Concurrency : 1 2015-10-09 03:08:01,196 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-09 03:08:01,196 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) - Task basedir is created (<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/854d2aa4-cdf7-4365-8746-b7d9efcae131/tajo-localdir/q_1444358647135_2240/output/1)> 2015-10-09 03:08:01,196 INFO: org.apache.tajo.worker.TaskImpl (init(197)) - Initializing: ta_1444358647135_2240_000001_000000_02 2015-10-09 03:08:01,197 INFO: org.apache.tajo.storage.FileTablespace (getAppenderFilePath(290)) - Output File Path: hdfs://localhost:54867/tmp/tajo-jenkins/staging/q_1444358647135_2240/RESULT/part-01-000000-000 2015-10-09 03:08:01,197 INFO: org.apache.tajo.worker.TaskImpl (initPlan(147)) - Output File Path: hdfs://localhost:54867/tmp/tajo-jenkins/staging/q_1444358647135_2240/RESULT/part-01-000000-000 2015-10-09 03:08:01,197 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - ================================== 2015-10-09 03:08:01,197 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) - * Stage ta_1444358647135_2240_000001_000000_02 is initialized 2015-10-09 03:08:01,197 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) - * InterQuery: false, Fragments (num: 1), Fetches (total:0) : 2015-10-09 03:08:01,197 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/854d2aa4-cdf7-4365-8746-b7d9efcae131/tajo-localdir/q_1444358647135_2240/output/1/0_2> 2015-10-09 03:08:01,197 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) - ================================== 2015-10-09 03:08:01,199 INFO: BlockStateChange (addToInvalidates(1106)) - BLOCK* addToInvalidates: blk_1073748800_7976 127.0.0.1:39054 2015-10-09 03:08:01,201 ERROR: org.apache.tajo.worker.TaskImpl (run(410)) - 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:74) 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:405) 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) 2015-10-09 03:08:01,203 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39054 is added to blk_1073748801_7977{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-d51e59e6-f7d4-40a8-89bd-26aeecadb151:NORMAL:127.0.0.1:39054|FINALIZED]]} size 0 2015-10-09 03:08:01,203 INFO: org.apache.tajo.worker.TaskImpl (run(462)) - ta_1444358647135_2240_000001_000000_02 completed. Worker's task counter - total:3, succeeded: 0, killed: 0, failed: 3 2015-10-09 03:08:01,204 ERROR: org.apache.tajo.querymaster.TaskAttempt (transition(438)) - ta_1444358647135_2240_000001_000000_02 FROM asf900.gq1.ygridcore.net >> internal error: testQueryFailure 2015-10-09 03:08:01,204 INFO: org.apache.tajo.querymaster.Task (transition(663)) - ==================================================================================== 2015-10-09 03:08:01,204 INFO: org.apache.tajo.querymaster.Task (transition(664)) - >>> Task Failed: ta_1444358647135_2240_000001_000000_02, retry:false, attempts:3 <<< 2015-10-09 03:08:01,204 INFO: org.apache.tajo.querymaster.Task (transition(666)) - ==================================================================================== 2015-10-09 03:08:01,204 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(163)) - Task Scheduler stopped 2015-10-09 03:08:01,204 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (run(126)) - TaskScheduler schedulingThread stopped 2015-10-09 03:08:01,204 INFO: org.apache.tajo.querymaster.Stage (transition(1408)) - Stage completed - eb_1444358647135_2240_000001 (total=1, success=0, killed=0) 2015-10-09 03:08:01,205 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (stop(163)) - Task Scheduler stopped 2015-10-09 03:08:01,205 INFO: org.apache.tajo.querymaster.Query (handle(868)) - Processing q_1444358647135_2240 of type STAGE_COMPLETED 2015-10-09 03:08:01,205 INFO: org.apache.tajo.worker.TaskManager (stopExecutionBlock(163)) - Stopped execution block:eb_1444358647135_2240_000001 2015-10-09 03:08:01,205 INFO: org.apache.tajo.querymaster.Query (transition(815)) - Complete Stage[eb_1444358647135_2240_000001], State: FAILED, 1/1. 2015-10-09 03:08:01,205 INFO: org.apache.tajo.querymaster.Query (handle(868)) - Processing q_1444358647135_2240 of type QUERY_COMPLETED 2015-10-09 03:08:01,206 INFO: org.apache.tajo.querymaster.Query (handle(886)) - q_1444358647135_2240 Query Transitioned from QUERY_RUNNING to QUERY_FAILED 2015-10-09 03:08:01,206 INFO: org.apache.tajo.querymaster.QueryMasterTask (handle(296)) - Query completion notified from q_1444358647135_2240 final state: QUERY_FAILED 2015-10-09 03:08:01,206 INFO: org.apache.tajo.master.QueryInProgress (heartbeat(259)) - Received QueryMaster heartbeat:q_1444358647135_2240,state=QUERY_FAILED,progress=0.0, queryMaster=asf900.gq1.ygridcore.net 2015-10-09 03:08:01,206 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(172)) - Stopping QueryMasterTask:q_1444358647135_2240 2015-10-09 03:08:01,206 WARN: org.apache.tajo.master.QueryInProgress (heartbeat(274)) - q_1444358647135_2240 is stopped because 2015-10-09 03:08:01,206 INFO: org.apache.tajo.querymaster.QueryMasterTask (cleanupQuery(473)) - Cleanup resources of all workers. Query: q_1444358647135_2240, workers: 1 2015-10-09 03:08:01,206 INFO: org.apache.tajo.master.QueryManager (stopQuery(279)) - Stop QueryInProgress:q_1444358647135_2240 2015-10-09 03:08:01,206 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(124)) - ========================================================= 2015-10-09 03:08:01,207 INFO: org.apache.tajo.master.QueryInProgress (stopProgress(125)) - Stop query:q_1444358647135_2240 2015-10-09 03:08:01,206 INFO: org.apache.tajo.querymaster.QueryMasterTask (serviceStop(188)) - Stopped QueryMasterTask:q_1444358647135_2240 2015-10-09 03:08:01,293 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(239)) - SQL: CREATE DATABASE IF NOT EXISTS "TestTajoCliNegatives" 2015-10-09 03:08:01,293 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(280)) - Non Optimized Query: ----------------------------- Query Block Graph ----------------------------- |-#ROOT ----------------------------- Optimization Log: ----------------------------- CREATE_DATABASE(0) IF NOT EXISTS TestTajoCliNegatives 2015-10-09 03:08:01,293 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(282)) - ============================================= 2015-10-09 03:08:01,293 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(283)) - Optimized Query: ----------------------------- Query Block Graph ----------------------------- |-#ROOT ----------------------------- Optimization Log: ----------------------------- CREATE_DATABASE(0) IF NOT EXISTS TestTajoCliNegatives 2015-10-09 03:08:01,294 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(284)) - ============================================= 2015-10-09 03:08:01,294 INFO: org.apache.tajo.master.exec.DDLExecutor (createDatabase(246)) - database "TestTajoCliNegatives" is already exists. Run: testDescTable Used memory: 641 MBytes, Active Threads:419 2015-10-09 03:08:01,296 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 9499c19d-322f-4ce7-9f79-bc1c33349e18 is removed. 2015-10-09 03:08:01,296 INFO: org.apache.tajo.master.exec.NonForwardQueryResultFileScanner (close(175)) - "Sent result to client for 9499c19d-322f-4ce7-9f79-bc1c33349e18, queryId: q_1444358647135_2239 rows: 0 2015-10-09 03:08:01,303 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 2756071f-e6ff-453a-b210-338e0cd3ed0d is removed. Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.612 sec - in org.apache.tajo.cli.tsql.TestTajoCliNegatives Running org.apache.tajo.cli.tsql.TestDefaultCliOutputFormatter 2015-10-09 03:08:01,308 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session 7b6c7b11-d87a-4364-9357-7cf6e84912eb is created. 2015-10-09 03:08:01,310 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 7b6c7b11-d87a-4364-9357-7cf6e84912eb is removed. 2015-10-09 03:08:01,313 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session 201f205f-8846-4c7d-ae34-fac44ab2ebf8 is created. 2015-10-09 03:08:01,315 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 201f205f-8846-4c7d-ae34-fac44ab2ebf8 is removed. 2015-10-09 03:08:01,317 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session 65e698e0-2d0b-4acb-bd87-007551a43411 is created. 2015-10-09 03:08:01,328 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 65e698e0-2d0b-4acb-bd87-007551a43411 is removed. Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.023 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.002 sec - in org.apache.tajo.cli.tsql.TestSimpleParser Running org.apache.tajo.cli.tsql.commands.TestHdfsCommand 2015-10-09 03:08:01,335 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session cc585adc-3396-44fd-bf73-b10d85608365 is created. Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.047 sec - in org.apache.tajo.cli.tsql.commands.TestHdfsCommand Running org.apache.tajo.cli.tsql.commands.TestExecExternalShellCommand 2015-10-09 03:08:01,383 INFO: org.apache.tajo.session.SessionManager (createSession(79)) - Session 80afb405-ad59-4855-8259-d3c6c44967cb is created. Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.023 sec - in org.apache.tajo.cli.tsql.commands.TestExecExternalShellCommand Oct 9, 2015 2:47:25 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Oct 9, 2015 2:47:25 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Oct 9, 2015 2:47:47 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Oct 9, 2015 2:47:47 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Oct 9, 2015 2:47:47 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Oct 9, 2015 2:47:47 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Oct 9, 2015 2:47:47 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Oct 9, 2015 2:47:50 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers 2015-10-09 03:08:01,408 INFO: org.mortbay.log (invoke0(?)) - Shutdown hook executing 2015-10-09 03:08:01,410 INFO: org.mortbay.log (invoke(?)) - Shutdown hook complete 2015-10-09 03:08:01,418 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session cc585adc-3396-44fd-bf73-b10d85608365 is removed. 2015-10-09 03:08:01,422 INFO: org.apache.tajo.session.SessionManager (removeSession(85)) - Session 80afb405-ad59-4855-8259-d3c6c44967cb is removed. 2015-10-09 03:08:01,424 INFO: org.apache.tajo.worker.TajoWorker (run(564)) - ============================================ 2015-10-09 03:08:01,424 INFO: org.apache.tajo.worker.TajoWorker (run(565)) - TajoWorker received SIGINT Signal 2015-10-09 03:08:01,424 INFO: org.apache.tajo.worker.TajoWorker (run(566)) - ============================================ 2015-10-09 03:08:01,427 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_asf900.gq1.ygridcore.net_16132 stopped. 2015-10-09 03:08:01,427 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2015-10-09 03:08:01,463 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(115)) - NodeStatusUpdater stopped. 2015-10-09 03:08:01,463 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(265)) - Heartbeat Thread stopped. 2015-10-09 03:08:01,464 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:16134) shutdown 2015-10-09 03:08:01,465 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) - QueryMasterManagerService stopped 2015-10-09 03:08:01,466 INFO: org.apache.tajo.querymaster.QueryMaster (run(427)) - QueryMaster heartbeat thread stopped 2015-10-09 03:08:01,466 INFO: org.apache.tajo.querymaster.QueryMaster (serviceStop(162)) - QueryMaster stopped 2015-10-09 03:08:01,466 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(98)) - TajoWorkerClientService stopping 2015-10-09 03:08:01,466 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:16133) shutdown 2015-10-09 03:08:01,466 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(102)) - TajoWorkerClientService stopped 2015-10-09 03:08:01,467 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:16132) shutdown 2015-10-09 03:08:01,467 INFO: org.apache.tajo.worker.TajoWorkerManagerService (serviceStop(93)) - TajoWorkerManagerService stopped 2015-10-09 03:08:01,467 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(374)) - TajoWorker main thread exiting 2015-10-09 03:08:03,043 INFO: BlockStateChange (invalidateWorkForOneNode(3488)) - BLOCK* BlockManager: ask 127.0.0.1:39054 to delete [blk_1073748800_7976, blk_1073748794_7970, blk_1073748796_7972, blk_1073748799_7975] 2015-10-09 03:08:05,878 INFO: org.apache.tajo.master.TajoMaster (run(577)) - ============================================ 2015-10-09 03:08:05,879 INFO: org.apache.tajo.master.TajoMaster (run(578)) - TajoMaster received SIGINT Signal 2015-10-09 03:08:05,879 INFO: org.apache.tajo.master.TajoMaster (run(579)) - ============================================ 2015-10-09 03:08:05,880 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:16131) shutdown 2015-10-09 03:08:05,880 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped. 2015-10-09 03:08:05,882 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_127.0.0.1_16130 stopped. 2015-10-09 03:08:05,886 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39054 is added to blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-3b23bd7c-a935-4257-8604-06040f54d964:NORMAL:127.0.0.1:39054|RBW]]} size 704 2015-10-09 03:08:05,887 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:16130) shutdown 2015-10-09 03:08:05,887 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2015-10-09 03:08:05,887 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:16129) shutdown 2015-10-09 03:08:05,889 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(186)) - Catalog Server (127.0.0.1:16128) shutdown 2015-10-09 03:08:05,891 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:16128) shutdown 2015-10-09 03:08:05,891 INFO: org.apache.tajo.catalog.store.DerbyStore (close(3157)) - Close database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/854d2aa4-cdf7-4365-8746-b7d9efcae131/db;create=true)> 2015-10-09 03:08:05,893 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:16127) shutdown 2015-10-09 03:08:05,893 INFO: org.apache.tajo.master.TajoMaster (serviceStop(437)) - Tajo Master main thread exiting 2015-10-09 03:08:06,158 INFO: org.apache.tajo.catalog.store.DerbyStore (shutdown(68)) - Derby shutdown complete normally. 2015-10-09 03:08:06,158 INFO: org.apache.tajo.catalog.store.DerbyStore (shutdown(75)) - Shutdown database Results : Tests in error: TestJoinOnPartitionedTables.testPartialFilterPushDown:95->QueryTestCaseBase.runSimpleTests:611 ยป UndefinedTable Tests run: 1710, Failures: 0, Errors: 1, Skipped: 0 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Tajo Main ......................................... SUCCESS [ 2.425 s] [INFO] Tajo Project POM .................................. SUCCESS [ 2.579 s] [INFO] Tajo Maven Plugins ................................ SUCCESS [ 4.187 s] [INFO] Tajo Common ....................................... SUCCESS [ 33.023 s] [INFO] Tajo Algebra ...................................... SUCCESS [ 2.946 s] [INFO] Tajo Catalog Common ............................... SUCCESS [ 6.697 s] [INFO] Tajo Plan ......................................... SUCCESS [ 8.377 s] [INFO] Tajo Rpc Common ................................... SUCCESS [ 1.384 s] [INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 51.478 s] [INFO] Tajo Catalog Client ............................... SUCCESS [ 1.876 s] [INFO] Tajo Catalog Server ............................... SUCCESS [ 14.184 s] [INFO] Tajo Storage Common ............................... SUCCESS [ 3.211 s] [INFO] Tajo HDFS Storage ................................. SUCCESS [ 53.110 s] [INFO] Tajo PullServer ................................... SUCCESS [ 1.755 s] [INFO] Tajo Client ....................................... SUCCESS [ 4.053 s] [INFO] Tajo CLI tools .................................... SUCCESS [ 3.815 s] [INFO] Tajo SQL Parser ................................... SUCCESS [ 4.646 s] [INFO] ASM (thirdparty) .................................. SUCCESS [ 2.335 s] [INFO] Tajo RESTful Container ............................ SUCCESS [ 4.329 s] [INFO] Tajo Metrics ...................................... SUCCESS [ 1.542 s] [INFO] Tajo Core ......................................... SUCCESS [ 11.840 s] [INFO] Tajo RPC .......................................... SUCCESS [ 1.050 s] [INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 16.324 s] [INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.080 s] [INFO] Tajo Catalog ...................................... SUCCESS [ 1.016 s] [INFO] Tajo Client Example ............................... SUCCESS [ 1.046 s] [INFO] Tajo HBase Storage ................................ SUCCESS [ 5.028 s] [INFO] Tajo Cluster Tests ................................ SUCCESS [ 2.903 s] [INFO] Tajo JDBC Driver .................................. SUCCESS [ 42.554 s] [INFO] Tajo JDBC storage common .......................... SUCCESS [ 0.947 s] [INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 1.217 s] [INFO] Tajo Storage ...................................... SUCCESS [ 1.099 s] [INFO] Tajo Distribution ................................. SUCCESS [ 5.701 s] [INFO] Tajo Core Tests ................................... FAILURE [24:27 min] [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 29:27 min [INFO] Finished at: 2015-10-09T03:08:06+00:00 [INFO] Final Memory: 146M/1610M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.17: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-1900
