See <https://builds.apache.org/job/Tajo-master-jdk8-nightly/163/changes>
Changes: [jihoonson] TAJO-1872: Increase the minimum split size and add a classpath to hadoop tools. ------------------------------------------ [...truncated 719882 lines...] 2015-09-21 06:40:30,342 INFO: org.apache.tajo.worker.Fetcher (get(156)) - Status: FETCH_FETCHING, URI:http://127.0.0.1:32924/?qid=q_0000000000000_0000&sid=1&p=1&type=x&ta=1_0 2015-09-21 06:40:30,342 INFO: org.apache.tajo.pullserver.TajoPullServerService (channelRegistered(435)) - Current number of shuffle connections (2) 2015-09-21 06:40:30,343 ERROR: org.apache.tajo.pullserver.TajoPullServerService (channelRead0(536)) - Unknown shuffle type: x 2015-09-21 06:40:30,344 ERROR: org.apache.tajo.worker.Fetcher (channelRead(227)) - Bad Request 2015-09-21 06:40:30,346 INFO: org.apache.tajo.worker.Fetcher (get(172)) - Fetcher finished:7 ms, FETCH_FAILED, URI:http://127.0.0.1:32924/?qid=q_0000000000000_0000&sid=1&p=1&type=x&ta=1_0 2015-09-21 06:40:30,463 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:45865/tmp/tajo-jenkins/staging/history/20150921/query-detail/q_1442816346158_2121/eb_1442816346158_2121_000012.hist 2015-09-21 06:40:30,467 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40425 is added to blk_1073748659_7835{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-0b7cc50d-52de-4585-92c4-3231fc3b2c17:NORMAL:127.0.0.1:40425|FINALIZED]]} size 0 2015-09-21 06:40:30,469 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:45865/tmp/tajo-jenkins/staging/history/20150921/query-detail/q_1442816346158_2121/eb_1442816346158_2121_000011.hist 2015-09-21 06:40:30,469 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(232)) - httpshuffle listening on port 33238 2015-09-21 06:40:30,469 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(258)) - TajoPullServerService started: port=33238 2015-09-21 06:40:30,469 INFO: org.apache.tajo.pullserver.TajoPullServerService (init(200)) - Tajo PullServer initialized: readaheadLength=4194304 2015-09-21 06:40:30,473 INFO: org.apache.tajo.util.history.HistoryWriter (writeQueryHistory(379)) - Saving query unit: hdfs://localhost:45865/tmp/tajo-jenkins/staging/history/20150921/query-detail/q_1442816346158_2121/eb_1442816346158_2121_000010.hist 2015-09-21 06:40:30,480 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40425 is added to blk_1073748660_7836{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-c508e288-1fc6-478f-b406-92b679ddd34d:NORMAL:127.0.0.1:40425|FINALIZED]]} size 0 2015-09-21 06:40:30,588 INFO: BlockStateChange (invalidateWorkForOneNode(3488)) - BLOCK* BlockManager: ask 127.0.0.1:40425 to delete [blk_1073748634_7810] 2015-09-21 06:40:30,609 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(232)) - httpshuffle listening on port 34178 2015-09-21 06:40:30,609 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(258)) - TajoPullServerService started: port=34178 2015-09-21 06:40:30,609 INFO: org.apache.tajo.pullserver.TajoPullServerService (init(200)) - Tajo PullServer initialized: readaheadLength=4194304 2015-09-21 06:40:30,619 INFO: org.apache.tajo.worker.Fetcher (get(156)) - Status: FETCH_FETCHING, URI:http://127.0.0.1:34178/?qid=q_0000000000000_0000&sid=1&p=1&type=h&ta=1_0 2015-09-21 06:40:30,619 INFO: org.apache.tajo.pullserver.TajoPullServerService (channelRegistered(435)) - Current number of shuffle connections (2) 2015-09-21 06:40:30,620 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 06:40:30,621 WARN: org.apache.tajo.worker.Fetcher (channelRead(223)) - There are no data corresponding to the request 2015-09-21 06:40:30,621 INFO: org.apache.tajo.worker.Fetcher (get(172)) - Fetcher finished:5 ms, FETCH_FINISHED, URI:http://127.0.0.1:34178/?qid=q_0000000000000_0000&sid=1&p=1&type=h&ta=1_0 2015-09-21 06:40:30,740 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(232)) - httpshuffle listening on port 37944 2015-09-21 06:40:30,740 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(258)) - TajoPullServerService started: port=37944 2015-09-21 06:40:30,740 INFO: org.apache.tajo.pullserver.TajoPullServerService (init(200)) - Tajo PullServer initialized: readaheadLength=4194304 2015-09-21 06:40:30,749 INFO: org.apache.tajo.worker.Fetcher (get(156)) - Status: FETCH_FETCHING, URI:http://127.0.0.1:37944/?qid=q_0000000000000_0000&sid=1&p=1&type=h&ta=1_0 2015-09-21 06:40:30,750 INFO: org.apache.tajo.pullserver.TajoPullServerService (channelRegistered(435)) - Current number of shuffle connections (2) 2015-09-21 06:40:30,750 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 06:40:30,751 WARN: org.apache.tajo.worker.Fetcher (channelRead(223)) - There are no data corresponding to the request 2015-09-21 06:40:30,751 INFO: org.apache.tajo.worker.Fetcher (get(172)) - Fetcher finished:4 ms, FETCH_FINISHED, URI:http://127.0.0.1:37944/?qid=q_0000000000000_0000&sid=1&p=1&type=h&ta=1_0 2015-09-21 06:40:30,869 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(232)) - httpshuffle listening on port 43097 2015-09-21 06:40:30,870 INFO: org.apache.tajo.pullserver.TajoPullServerService (serviceInit(258)) - TajoPullServerService started: port=43097 2015-09-21 06:40:30,870 INFO: org.apache.tajo.pullserver.TajoPullServerService (init(200)) - Tajo PullServer initialized: readaheadLength=4194304 2015-09-21 06:40:30,881 INFO: org.apache.tajo.worker.Fetcher (get(172)) - Fetcher finished:3 ms, FETCH_FAILED, URI:http://127.0.0.1:43097/?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.841 sec - in org.apache.tajo.worker.TestFetcher Running org.apache.tajo.worker.TestTaskManager 2015-09-21 06:40:30,917 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:2048, Disks:4, vCores:4) 2015-09-21 06:40:30,918 INFO: org.apache.tajo.worker.TaskExecutor (serviceStart(96)) - Started TaskExecutor[4], Fetcher executor[2] 2015-09-21 06:40:30,918 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 06:40:30,919 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 06:40:30,923 INFO: org.apache.tajo.worker.TaskManager (handle(182)) - Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:1536, Disks:4, vCores:3) 2015-09-21 06:40:30,925 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 06:40:30,925 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 06:40:30,927 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(111)) - NodeStatusUpdater stopped. 2015-09-21 06:40:30,927 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(262)) - Heartbeat Thread stopped. 2015-09-21 06:40:30,966 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:2048, Disks:4, vCores:4) 2015-09-21 06:40:30,968 INFO: org.apache.tajo.worker.TaskExecutor (serviceStart(96)) - Started TaskExecutor[4], Fetcher executor[2] 2015-09-21 06:40:30,968 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 06:40:30,968 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 06:40:30,972 INFO: org.apache.tajo.worker.TaskManager (handle(182)) - Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:2048, Disks:4, vCores:4) 2015-09-21 06:40:30,974 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 06:40:30,974 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 06:40:30,976 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(111)) - NodeStatusUpdater stopped. 2015-09-21 06:40:30,976 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(262)) - Heartbeat Thread stopped. Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.098 sec - in org.apache.tajo.worker.TestTaskManager Running org.apache.tajo.worker.TestNodeStatusUpdater 2015-09-21 06:40:31,016 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:1010, Disks:2, vCores:2) 2015-09-21 06:40:31,019 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 06:40:31,019 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 06:40:31,021 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 06:40:31,023 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 06:40:31,058 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:1010, Disks:2, vCores:2) 2015-09-21 06:40:31,066 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 06:40:31,066 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 06:40:32,071 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 06:40:32,073 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 06:40:32,109 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:1010, Disks:2, vCores:2) 2015-09-21 06:40:32,113 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 06:40:32,114 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 06:40:32,115 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 06:40:32,117 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 06:40:32,154 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:1010, Disks:2, vCores:2) 2015-09-21 06:40:32,157 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 06:40:32,157 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 06:40:32,159 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 06:40:32,161 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.183 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.073 sec - in org.apache.tajo.worker.TestDeletionService Running org.apache.tajo.worker.TestNodeResourceManager 2015-09-21 06:40:34,274 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:2048, Disks:4, vCores:4) 2015-09-21 06:40:34,276 INFO: org.apache.tajo.worker.TaskExecutor (serviceStart(96)) - Started TaskExecutor[4], Fetcher executor[2] 2015-09-21 06:40:34,276 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 06:40:34,276 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 06:40:34,558 INFO: org.apache.tajo.worker.TaskManager (handle(182)) - Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:1536, Disks:4, vCores:3) 2015-09-21 06:40:36,630 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 06:40:36,632 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 06:40:36,633 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(111)) - NodeStatusUpdater stopped. 2015-09-21 06:40:36,634 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(262)) - Heartbeat Thread stopped. 2015-09-21 06:40:36,676 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:2048, Disks:4, vCores:4) 2015-09-21 06:40:36,677 INFO: org.apache.tajo.worker.TaskExecutor (serviceStart(96)) - Started TaskExecutor[4], Fetcher executor[2] 2015-09-21 06:40:36,677 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 06:40:36,678 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 06:40:36,679 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 06:40:36,681 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 06:40:36,682 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(111)) - NodeStatusUpdater stopped. 2015-09-21 06:40:36,682 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(262)) - Heartbeat Thread stopped. 2015-09-21 06:40:36,716 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:2048, Disks:4, vCores:4) 2015-09-21 06:40:36,717 INFO: org.apache.tajo.worker.TaskExecutor (serviceStart(96)) - Started TaskExecutor[4], Fetcher executor[2] 2015-09-21 06:40:36,718 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 06:40:36,718 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 06:40:36,722 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 06:40:36,724 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 06:40:36,725 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(111)) - NodeStatusUpdater stopped. 2015-09-21 06:40:36,725 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(262)) - Heartbeat Thread stopped. 2015-09-21 06:40:36,761 INFO: org.apache.tajo.worker.NodeResourceManager (serviceInit(66)) - Initialized NodeResourceManager for (Memory:2048, Disks:4, vCores:4) 2015-09-21 06:40:36,763 INFO: org.apache.tajo.worker.TaskExecutor (serviceStart(96)) - Started TaskExecutor[4], Fetcher executor[2] 2015-09-21 06:40:36,763 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(97)) - Queueing threshold:1 2015-09-21 06:40:36,763 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStart(101)) - NodeStatusUpdater started. 2015-09-21 06:40:36,765 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE.reporters in tajo-metrics.properties 2015-09-21 06:40:36,767 WARN: org.apache.tajo.util.metrics.TajoSystemMetrics (setMetricsReporter(133)) - No property NODE-JVM.reporters in tajo-metrics.properties 2015-09-21 06:40:36,767 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(111)) - NodeStatusUpdater stopped. 2015-09-21 06:40:36,767 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(262)) - Heartbeat Thread stopped. Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.528 sec - in org.apache.tajo.worker.TestNodeResourceManager 2015-09-21 06:40:36,774 INFO: org.mortbay.log (info(67)) - Shutdown hook executing 2015-09-21 06:40:36,774 INFO: org.mortbay.log (info(67)) - Shutdown hook complete Sep 21, 2015 6:23:06 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Sep 21, 2015 6:23:06 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Sep 21, 2015 6:23:21 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Sep 21, 2015 6:23:21 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Sep 21, 2015 6:23:21 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Sep 21, 2015 6:23:21 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Sep 21, 2015 6:23:21 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers Sep 21, 2015 6:23:25 AM INFO: parquet.hadoop.ParquetFileReader: reading another 1 footers 2015-09-21 06:40:36,780 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session e1d1dc5c-169e-48ae-98a4-7d2ba65d6c39 is removed. 2015-09-21 06:40:36,782 INFO: org.apache.tajo.session.SessionManager (removeSession(86)) - Session d5ee92ab-5382-4826-bd56-50626d03a4f6 is removed. 2015-09-21 06:40:36,785 INFO: org.apache.tajo.worker.TajoWorker (run(570)) - ============================================ 2015-09-21 06:40:36,787 INFO: org.apache.tajo.worker.TajoWorker (run(571)) - TajoWorker received SIGINT Signal 2015-09-21 06:40:36,787 INFO: org.apache.tajo.worker.TajoWorker (run(572)) - ============================================ 2015-09-21 06:40:36,790 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2015-09-21 06:40:36,790 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_asf903.gq1.ygridcore.net_24734 stopped. 2015-09-21 06:40:36,831 INFO: org.apache.tajo.worker.NodeStatusUpdater (serviceStop(111)) - NodeStatusUpdater stopped. 2015-09-21 06:40:36,832 INFO: org.apache.tajo.worker.NodeStatusUpdater (run(262)) - Heartbeat Thread stopped. 2015-09-21 06:40:36,833 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterProtocol) listened on 0:0:0:0:0:0:0:0:24736) shutdown 2015-09-21 06:40:36,839 INFO: org.apache.tajo.querymaster.QueryMasterManagerService (serviceStop(106)) - QueryMasterManagerService stopped 2015-09-21 06:40:36,839 INFO: org.apache.tajo.querymaster.QueryMaster (run(425)) - QueryMaster heartbeat thread stopped 2015-09-21 06:40:36,839 INFO: org.apache.tajo.querymaster.QueryMaster (serviceStop(161)) - QueryMaster stopped 2015-09-21 06:40:36,839 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(98)) - TajoWorkerClientService stopping 2015-09-21 06:40:36,841 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryMasterClientProtocol) listened on 0:0:0:0:0:0:0:0:24735) shutdown 2015-09-21 06:40:36,841 INFO: org.apache.tajo.worker.TajoWorkerClientService (stop(102)) - TajoWorkerClientService stopped 2015-09-21 06:40:36,842 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoWorkerProtocol) listened on 0:0:0:0:0:0:0:0:24734) shutdown 2015-09-21 06:40:36,843 INFO: org.apache.tajo.worker.TajoWorkerManagerService (serviceStop(93)) - TajoWorkerManagerService stopped 2015-09-21 06:40:36,845 INFO: org.apache.tajo.worker.TajoWorker (serviceStop(380)) - TajoWorker main thread exiting 2015-09-21 06:40:41,253 INFO: org.apache.tajo.master.TajoMaster (run(586)) - ============================================ 2015-09-21 06:40:41,256 INFO: org.apache.tajo.master.TajoMaster (run(587)) - TajoMaster received SIGINT Signal 2015-09-21 06:40:41,257 INFO: org.apache.tajo.master.TajoMaster (run(588)) - ============================================ 2015-09-21 06:40:41,257 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (Tajo-REST) listened on 0:0:0:0:0:0:0:0:24733) shutdown 2015-09-21 06:40:41,258 INFO: org.apache.tajo.ws.rs.TajoRestService (serviceStop(129)) - Tajo Rest Service stopped. 2015-09-21 06:40:41,261 INFO: org.apache.tajo.util.history.HistoryWriter (run(275)) - HistoryWriter_127.0.0.1_24732 stopped. 2015-09-21 06:40:41,265 INFO: BlockStateChange (logAddStoredBlock(2624)) - BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40425 is added to blk_1073741837_1013{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-0b7cc50d-52de-4585-92c4-3231fc3b2c17:NORMAL:127.0.0.1:40425|RBW]]} size 1961 2015-09-21 06:40:41,266 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (QueryCoordinatorProtocol) listened on 127.0.0.1:24732) shutdown 2015-09-21 06:40:41,267 INFO: org.apache.tajo.util.history.HistoryCleaner (run(136)) - History cleaner stopped 2015-09-21 06:40:41,267 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoMasterClientProtocol) listened on 127.0.0.1:24731) shutdown 2015-09-21 06:40:41,269 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(184)) - Catalog Server (127.0.0.1:24730) shutdown 2015-09-21 06:40:41,269 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:24730) shutdown 2015-09-21 06:40:41,270 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/8b3aa6fa-8adc-4e1e-85e4-372eba4af575/db;create=true)> 2015-09-21 06:40:41,271 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (TajoResourceTrackerProtocol) listened on 127.0.0.1:24729) shutdown 2015-09-21 06:40:41,272 INFO: org.apache.tajo.master.TajoMaster (serviceStop(446)) - Tajo Master main thread exiting 2015-09-21 06:40:41,497 INFO: org.apache.tajo.catalog.store.DerbyStore (shutdown(68)) - Derby shutdown complete normally. 2015-09-21 06:40:41,497 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.708 s] [INFO] Tajo Project POM .................................. SUCCESS [ 1.238 s] [INFO] Tajo Maven Plugins ................................ SUCCESS [ 2.679 s] [INFO] Tajo Common ....................................... SUCCESS [ 28.400 s] [INFO] Tajo Algebra ...................................... SUCCESS [ 2.249 s] [INFO] Tajo Catalog Common ............................... SUCCESS [ 4.694 s] [INFO] Tajo Plan ......................................... SUCCESS [ 5.787 s] [INFO] Tajo Rpc Common ................................... SUCCESS [ 1.175 s] [INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 49.603 s] [INFO] Tajo Catalog Client ............................... SUCCESS [ 1.444 s] [INFO] Tajo Catalog Server ............................... SUCCESS [ 10.088 s] [INFO] Tajo Storage Common ............................... SUCCESS [ 2.486 s] [INFO] Tajo HDFS Storage ................................. SUCCESS [ 54.101 s] [INFO] Tajo PullServer ................................... SUCCESS [ 0.962 s] [INFO] Tajo Client ....................................... SUCCESS [ 2.454 s] [INFO] Tajo CLI tools .................................... SUCCESS [ 1.785 s] [INFO] Tajo SQL Parser ................................... SUCCESS [ 3.611 s] [INFO] ASM (thirdparty) .................................. SUCCESS [ 1.790 s] [INFO] Tajo RESTful Container ............................ SUCCESS [ 3.627 s] [INFO] Tajo Metrics ...................................... SUCCESS [ 1.355 s] [INFO] Tajo Core ......................................... SUCCESS [ 5.890 s] [INFO] Tajo RPC .......................................... SUCCESS [ 0.905 s] [INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 10.406 s] [INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.042 s] [INFO] Tajo Catalog ...................................... SUCCESS [ 0.943 s] [INFO] Tajo Client Example ............................... SUCCESS [ 1.009 s] [INFO] Tajo HBase Storage ................................ SUCCESS [ 3.257 s] [INFO] Tajo Cluster Tests ................................ SUCCESS [ 2.227 s] [INFO] Tajo JDBC Driver .................................. SUCCESS [ 35.533 s] [INFO] Tajo JDBC storage common .......................... SUCCESS [ 0.816 s] [INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 0.818 s] [INFO] Tajo Storage ...................................... SUCCESS [ 0.961 s] [INFO] Tajo Distribution ................................. SUCCESS [ 5.368 s] [INFO] Tajo Core Tests ................................... FAILURE [21:46 min] [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 25:56 min [INFO] Finished at: 2015-09-21T06:40:41+00:00 [INFO] Final Memory: 153M/1978M [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 59678408 bytes Compression is 0.0% Took 14 sec Recording test results
