See <https://builds.apache.org/job/Tajo-master-jdk8-nightly/162/changes>
Changes: [jhkim] TAJO-1869: Incorrect result when sorting table with small files. ------------------------------------------ [...truncated 719968 lines...] 2015-09-21 02:40:17,587 INFO: org.apache.tajo.pullserver.TajoPullServerService (decrementRemainFiles(410)) - PullServer processing status: totalTime=6 ms, makeFileListTime=1 ms, minTime=4 ms, maxTime=4 ms, numFiles=1, numSlowFile=0 2015-09-21 02:40:17,587 INFO: org.apache.tajo.worker.Fetcher (get(172)) - Fetcher finished:21 ms, FETCH_FINISHED, URI:http://127.0.0.1:49690/?qid=q_0000000000000_0000&sid=1&p=1&type=h 2015-09-21 02:40:17,755 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(232)) - httpshuffle listening on port 38152 2015-09-21 02:40:17,755 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(258)) - TajoPullServerService started: port=38152 2015-09-21 02:40:17,755 INFO: org.apache.tajo.pullserver.TajoPullServerService (init(200)) - Tajo PullServer initialized: readaheadLength=4194304 2015-09-21 02:40:17,764 INFO: org.apache.tajo.worker.Fetcher (get(156)) - Status: FETCH_FETCHING, URI:http://127.0.0.1:38152/?qid=q_0000000000000_0000&sid=1&p=1&type=x&ta=1_0 2015-09-21 02:40:17,764 INFO: org.apache.tajo.pullserver.TajoPullServerService (channelRegistered(435)) - Current number of shuffle connections (2) 2015-09-21 02:40:17,765 ERROR: org.apache.tajo.pullserver.TajoPullServerService (channelRead0(536)) - Unknown shuffle type: x 2015-09-21 02:40:17,767 ERROR: org.apache.tajo.worker.Fetcher (channelRead(227)) - Bad Request 2015-09-21 02:40:17,769 INFO: org.apache.tajo.worker.Fetcher (get(172)) - Fetcher finished:8 ms, FETCH_FAILED, URI:http://127.0.0.1:38152/?qid=q_0000000000000_0000&sid=1&p=1&type=x&ta=1_0 2015-09-21 02:40:17,895 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(232)) - httpshuffle listening on port 55668 2015-09-21 02:40:17,895 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(258)) - TajoPullServerService started: port=55668 2015-09-21 02:40:17,895 INFO: org.apache.tajo.pullserver.TajoPullServerService (init(200)) - Tajo PullServer initialized: readaheadLength=4194304 2015-09-21 02:40:18,014 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(232)) - httpshuffle listening on port 48647 2015-09-21 02:40:18,014 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(258)) - TajoPullServerService started: port=48647 2015-09-21 02:40:18,014 INFO: org.apache.tajo.pullserver.TajoPullServerService (init(200)) - Tajo PullServer initialized: readaheadLength=4194304 2015-09-21 02:40:18,023 INFO: org.apache.tajo.worker.Fetcher (get(156)) - Status: FETCH_FETCHING, URI:http://127.0.0.1:48647/?qid=q_0000000000000_0000&sid=1&p=1&type=h&ta=1_0 2015-09-21 02:40:18,024 INFO: org.apache.tajo.pullserver.TajoPullServerService (channelRegistered(435)) - Current number of shuffle connections (2) 2015-09-21 02:40:18,025 WARN: org.apache.tajo.pullserver.TajoPullServerService (channelRead0(515)) - Partition shuffle file not exists: q_0000000000000_0000/output/1/hash-shuffle/1/1 2015-09-21 02:40:18,025 WARN: org.apache.tajo.worker.Fetcher (channelRead(223)) - There are no data corresponding to the request 2015-09-21 02:40:18,026 INFO: org.apache.tajo.worker.Fetcher (get(172)) - Fetcher finished:6 ms, FETCH_FINISHED, URI:http://127.0.0.1:48647/?qid=q_0000000000000_0000&sid=1&p=1&type=h&ta=1_0 2015-09-21 02:40:18,150 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(232)) - httpshuffle listening on port 59419 2015-09-21 02:40:18,150 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(258)) - TajoPullServerService started: port=59419 2015-09-21 02:40:18,150 INFO: org.apache.tajo.pullserver.TajoPullServerService (init(200)) - Tajo PullServer initialized: readaheadLength=4194304 2015-09-21 02:40:18,160 INFO: org.apache.tajo.worker.Fetcher (get(156)) - Status: FETCH_FETCHING, URI:http://127.0.0.1:59419/?qid=q_0000000000000_0000&sid=1&p=1&type=h&ta=1_0 2015-09-21 02:40:18,160 INFO: org.apache.tajo.pullserver.TajoPullServerService (channelRegistered(435)) - Current number of shuffle connections (2) 2015-09-21 02:40:18,161 WARN: org.apache.tajo.pullserver.TajoPullServerService (channelRead0(515)) - Partition shuffle file not exists: q_0000000000000_0000/output/1/hash-shuffle/1/1 2015-09-21 02:40:18,162 WARN: org.apache.tajo.worker.Fetcher (channelRead(223)) - There are no data corresponding to the request 2015-09-21 02:40:18,162 INFO: org.apache.tajo.worker.Fetcher (get(172)) - Fetcher finished:5 ms, FETCH_FINISHED, URI:http://127.0.0.1:59419/?qid=q_0000000000000_0000&sid=1&p=1&type=h&ta=1_0 2015-09-21 02:40:18,217 INFO: BlockStateChange (invalidateWorkForOneNode(3488)) - BLOCK* BlockManager: ask 127.0.0.1:49094 to delete [blk_1073748634_7810] 2015-09-21 02:40:18,283 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(232)) - httpshuffle listening on port 55368 2015-09-21 02:40:18,283 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(258)) - TajoPullServerService started: port=55368 2015-09-21 02:40:18,283 INFO: org.apache.tajo.pullserver.TajoPullServerService (init(200)) - Tajo PullServer initialized: readaheadLength=4194304 2015-09-21 02:40:18,295 INFO: org.apache.tajo.worker.Fetcher (get(172)) - Fetcher finished:3 ms, FETCH_FAILED, URI:http://127.0.0.1:55368/?qid=q_0000000000000_0000&sid=1&p=1&type=h&ta=1_0 Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.852 sec - in org.apache.tajo.worker.TestFetcher Running org.apache.tajo.worker.TestTaskManager 2015-09-21 02:40:18,329 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:2048, Disks:4, vCores:4) 2015-09-21 02:40:18,331 INFO: org.apache.tajo.worker.TaskExecutor (serviceStart(96)) - Started TaskExecutor[4], Fetcher executor[2] 2015-09-21 02:40:18,331 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 02:40:18,331 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 02:40:18,335 INFO: org.apache.tajo.worker.TaskManager (handle(182)) - Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:1536, Disks:4, vCores:3) 2015-09-21 02:40:18,338 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 02:40:18,340 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 02:40:18,340 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(111)) - NodeStatusUpdater stopped. 2015-09-21 02:40:18,340 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(262)) - Heartbeat Thread stopped. 2015-09-21 02:40:18,381 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:2048, Disks:4, vCores:4) 2015-09-21 02:40:18,382 INFO: org.apache.tajo.worker.TaskExecutor (serviceStart(96)) - Started TaskExecutor[4], Fetcher executor[2] 2015-09-21 02:40:18,383 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 02:40:18,383 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 02:40:18,387 INFO: org.apache.tajo.worker.TaskManager (handle(182)) - Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:2048, Disks:4, vCores:4) 2015-09-21 02:40:18,389 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 02:40:18,391 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 02:40:18,391 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(111)) - NodeStatusUpdater stopped. 2015-09-21 02:40:18,391 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(262)) - Heartbeat Thread stopped. Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.097 sec - in org.apache.tajo.worker.TestTaskManager Running org.apache.tajo.worker.TestNodeStatusUpdater 2015-09-21 02:40:18,429 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:1001, Disks:2, vCores:2) 2015-09-21 02:40:18,432 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 02:40:18,432 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 02:40:18,434 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 02:40:18,436 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 02:40:18,473 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:1001, Disks:2, vCores:2) 2015-09-21 02:40:18,479 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 02:40:18,479 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 02:40:19,487 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 02:40:19,489 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 02:40:19,524 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:1001, Disks:2, vCores:2) 2015-09-21 02:40:19,527 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 02:40:19,527 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 02:40:19,529 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 02:40:19,529 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 02:40:19,566 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:1001, Disks:2, vCores:2) 2015-09-21 02:40:19,568 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 02:40:19,569 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 02:40:19,571 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 02:40:19,571 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.18 sec - in org.apache.tajo.worker.TestNodeStatusUpdater Running org.apache.tajo.worker.TestDeletionService Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.08 sec - in org.apache.tajo.worker.TestDeletionService Running org.apache.tajo.worker.TestNodeResourceManager 2015-09-21 02:40:21,689 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:2048, Disks:4, vCores:4) 2015-09-21 02:40:21,691 INFO: org.apache.tajo.worker.TaskExecutor (serviceStart(96)) - Started TaskExecutor[4], Fetcher executor[2] 2015-09-21 02:40:21,691 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 02:40:21,691 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 02:40:21,857 INFO: org.apache.tajo.worker.TaskManager (handle(182)) - Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:1536, Disks:4, vCores:3) 2015-09-21 02:40:23,521 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 02:40:23,523 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 02:40:23,523 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(111)) - NodeStatusUpdater stopped. 2015-09-21 02:40:23,523 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(262)) - Heartbeat Thread stopped. 2015-09-21 02:40:23,561 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:2048, Disks:4, vCores:4) 2015-09-21 02:40:23,563 INFO: org.apache.tajo.worker.TaskExecutor (serviceStart(96)) - Started TaskExecutor[4], Fetcher executor[2] 2015-09-21 02:40:23,563 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 02:40:23,563 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 02:40:23,566 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 02:40:23,567 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 02:40:23,567 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(111)) - NodeStatusUpdater stopped. 2015-09-21 02:40:23,567 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(262)) - Heartbeat Thread stopped. 2015-09-21 02:40:23,603 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:2048, Disks:4, vCores:4) 2015-09-21 02:40:23,604 INFO: org.apache.tajo.worker.TaskExecutor (serviceStart(96)) - Started TaskExecutor[4], Fetcher executor[2] 2015-09-21 02:40:23,604 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 02:40:23,604 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 02:40:23,606 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 02:40:23,608 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 02:40:23,609 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(111)) - NodeStatusUpdater stopped. 2015-09-21 02:40:23,609 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(262)) - Heartbeat Thread stopped. 2015-09-21 02:40:23,656 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:2048, Disks:4, vCores:4) 2015-09-21 02:40:23,657 INFO: org.apache.tajo.worker.TaskExecutor (serviceStart(96)) - Started TaskExecutor[4], Fetcher executor[2] 2015-09-21 02:40:23,657 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 02:40:23,658 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 02:40:23,660 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 02:40:23,662 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 02:40:23,662 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(111)) - NodeStatusUpdater stopped. 2015-09-21 02:40:23,663 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(262)) - Heartbeat Thread stopped. Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.009 sec - in org.apache.tajo.worker.TestNodeResourceManager 2015-09-21 02:40:23,670 INFO: org.mortbay.log (info(67)) - Shutdown hook executing Sep 21, 2015 2:23:18 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Sep 21, 2015 2:23:18 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Sep 21, 2015 2:23:34 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Sep 21, 2015 2:23:34 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Sep 21, 2015 2:23:34 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Sep 21, 2015 2:23:34 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Sep 21, 2015 2:23:34 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Sep 21, 2015 2:23:38 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers 2015-09-21 02:40:23,672 INFO: org.mortbay.log (info(67)) - Shutdown hook complete 2015-09-21 02:40:23,679 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session 96c43071-0eef-49d2-9979-31d8fe98e968 is removed. 2015-09-21 02:40:23,683 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session 45a146a6-0894-4be1-93bf-5e494a67d83a is removed. 2015-09-21 02:40:23,685 INFO: org.apache.tajo.worker.TajoWorker (run(570)) - ============================================ 2015-09-21 02:40:23,686 INFO: org.apache.tajo.worker.TajoWorker (run(571)) - TajoWorker received SIGINT Signal 2015-09-21 02:40:23,686 INFO: org.apache.tajo.worker.TajoWorker (run(572)) - ============================================ 2015-09-21 02:40:23,689 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_asf903.gq1.ygridcore.net_40437 stopped. 2015-09-21 02:40:23,689 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2015-09-21 02:40:23,719 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(111)) - NodeStatusUpdater stopped. 2015-09-21 02:40:23,719 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(262)) - Heartbeat Thread stopped. 2015-09-21 02:40:23,720 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:40439) shutdown 2015-09-21 02:40:23,722 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) - QueryMasterManagerService stopped 2015-09-21 02:40:23,722 INFO: org.apache.tajo.querymaster.QueryMaster (run(425)) - QueryMaster heartbeat thread stopped 2015-09-21 02:40:23,723 INFO: org.apache.tajo.querymaster.QueryMaster (serviceStop(161)) - QueryMaster stopped 2015-09-21 02:40:23,723 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(98)) - TajoWorkerClientService stopping 2015-09-21 02:40:23,725 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:40438) shutdown 2015-09-21 02:40:23,726 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(102)) - TajoWorkerClientService stopped 2015-09-21 02:40:23,726 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:40437) shutdown 2015-09-21 02:40:23,726 INFO: org.apache.tajo.worker.TajoWorkerManagerService (serviceStop(93)) - TajoWorkerManagerService stopped 2015-09-21 02:40:23,728 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(380)) - TajoWorker main thread exiting 2015-09-21 02:40:28,139 INFO: org.apache.tajo.master.TajoMaster (run(586)) - ============================================ 2015-09-21 02:40:28,141 INFO: org.apache.tajo.master.TajoMaster (run(587)) - TajoMaster received SIGINT Signal 2015-09-21 02:40:28,141 INFO: org.apache.tajo.master.TajoMaster (run(588)) - ============================================ 2015-09-21 02:40:28,142 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:40436) shutdown 2015-09-21 02:40:28,142 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped. 2015-09-21 02:40:28,145 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_127.0.0.1_40435 stopped. 2015-09-21 02:40:28,148 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49094 is added to blk_1073741837_1013{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-2d615bfa-db01-4ec7-b552-b09db3d14281:NORMAL:127.0.0.1:49094|RBW]]} size 1961 2015-09-21 02:40:28,149 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:40435) shutdown 2015-09-21 02:40:28,149 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2015-09-21 02:40:28,149 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:40434) shutdown 2015-09-21 02:40:28,152 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(184)) - Catalog Server (127.0.0.1:40433) shutdown 2015-09-21 02:40:28,153 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:40433) shutdown 2015-09-21 02:40:28,153 INFO: org.apache.tajo.catalog.store.DerbyStore (close(2897)) - Close database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/9b1fc5de-f48f-4f37-94f1-b375d084116d/db;create=true)> 2015-09-21 02:40:28,155 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:40432) shutdown 2015-09-21 02:40:28,155 INFO: org.apache.tajo.master.TajoMaster (serviceStop(446)) - Tajo Master main thread exiting 2015-09-21 02:40:28,400 INFO: org.apache.tajo.catalog.store.DerbyStore (shutdown(68)) - Derby shutdown complete normally. 2015-09-21 02:40:28,400 INFO: org.apache.tajo.catalog.store.DerbyStore (shutdown(75)) - Shutdown database Results : Failed tests: TestUnionQuery.testUnionAndFilter:698->QueryTestCaseBase.runSimpleTests:617 Result Verification for: 1 th test expected:<...------------------- [4,2866.83 1,711.56 5,794.47 3,7498.12 2,121.65] > but was:<...------------------- [1,711.56 4,2866.83 5,794.47 2,121.65 3,7498.12] > TestDDLBuilder.testBuildDDLForExternalTable:64 expected:<...) USING TEXT WITH ('[compression.codec'='org.apache.hadoop.io.compress.GzipCodec', 'text.delimiter'='|]') PARTITION BY COLU...> but was:<...) USING TEXT WITH ('[text.delimiter'='|', 'compression.codec'='org.apache.hadoop.io.compress.GzipCodec]') PARTITION BY COLU...> TestDDLBuilder.testBuildDDLForBaseTable:103 expected:<...) USING TEXT WITH ('[compression.codec'='org.apache.hadoop.io.compress.GzipCodec', 'text.delimiter'='|]');> but was:<...) USING TEXT WITH ('[text.delimiter'='|', 'compression.codec'='org.apache.hadoop.io.compress.GzipCodec]');> TestDDLBuilder.testBuildDDLQuotedTableName:90 expected:<...) USING TEXT WITH ('[compression.codec'='org.apache.hadoop.io.compress.GzipCodec', 'text.delimiter'='|]') PARTITION BY COLU...> but was:<...) USING TEXT WITH ('[text.delimiter'='|', 'compression.codec'='org.apache.hadoop.io.compress.GzipCodec]') PARTITION BY COLU...> Tests run: 1641, Failures: 4, Errors: 0, Skipped: 0 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Tajo Main ......................................... SUCCESS [ 1.872 s] [INFO] Tajo Project POM .................................. SUCCESS [ 1.784 s] [INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.100 s] [INFO] Tajo Common ....................................... SUCCESS [ 28.458 s] [INFO] Tajo Algebra ...................................... SUCCESS [ 2.658 s] [INFO] Tajo Catalog Common ............................... SUCCESS [ 4.727 s] [INFO] Tajo Plan ......................................... SUCCESS [ 6.510 s] [INFO] Tajo Rpc Common ................................... SUCCESS [ 1.379 s] [INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 51.830 s] [INFO] Tajo Catalog Client ............................... SUCCESS [ 1.464 s] [INFO] Tajo Catalog Server ............................... SUCCESS [ 10.704 s] [INFO] Tajo Storage Common ............................... SUCCESS [ 2.679 s] [INFO] Tajo HDFS Storage ................................. SUCCESS [ 54.340 s] [INFO] Tajo PullServer ................................... SUCCESS [ 0.987 s] [INFO] Tajo Client ....................................... SUCCESS [ 2.590 s] [INFO] Tajo CLI tools .................................... SUCCESS [ 2.061 s] [INFO] Tajo SQL Parser ................................... SUCCESS [ 3.837 s] [INFO] ASM (thirdparty) .................................. SUCCESS [ 2.101 s] [INFO] Tajo RESTful Container ............................ SUCCESS [ 4.143 s] [INFO] Tajo Metrics ...................................... SUCCESS [ 1.371 s] [INFO] Tajo Core ......................................... SUCCESS [ 6.652 s] [INFO] Tajo RPC .......................................... SUCCESS [ 0.945 s] [INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 11.124 s] [INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.115 s] [INFO] Tajo Catalog ...................................... SUCCESS [ 1.108 s] [INFO] Tajo Client Example ............................... SUCCESS [ 1.057 s] [INFO] Tajo HBase Storage ................................ SUCCESS [ 3.936 s] [INFO] Tajo Cluster Tests ................................ SUCCESS [ 2.800 s] [INFO] Tajo JDBC Driver .................................. SUCCESS [ 34.867 s] [INFO] Tajo JDBC storage common .......................... SUCCESS [ 0.974 s] [INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 1.380 s] [INFO] Tajo Storage ...................................... SUCCESS [ 1.011 s] [INFO] Tajo Distribution ................................. SUCCESS [ 5.269 s] [INFO] Tajo Core Tests ................................... FAILURE [21:13 min] [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 25:33 min [INFO] Finished at: 2015-09-21T02:40:28+00:00 [INFO] Final Memory: 150M/1764M [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-jdk8-nightly/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 Archiving artifacts Sending artifact delta relative to Tajo-master-jdk8-nightly #82 Archived 1 artifacts Archive block size is 32768 Received 0 blocks and 59678854 bytes Compression is 0.0% Took 15 sec Recording test results
