See <https://builds.apache.org/job/Tajo-master-nightly/944/changes>
Changes: [hyunsik] TAJO-1990: Refine some parts in HBaseTablespace. ------------------------------------------ [...truncated 3364 lines...] 2015-12-14 06:55:14,964 INFO: org.apache.hadoop.hdfs.MiniDFSCluster (startDataNodes(1413)) - Starting DataNode 0 with dfs.datanode.data.dir: [DISK]<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data1,[DISK]file>:<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data2> 2015-12-14 06:55:14,993 INFO: org.apache.hadoop.metrics2.impl.MetricsSystemImpl (init(159)) - DataNode metrics system started (again) 2015-12-14 06:55:14,998 INFO: org.apache.hadoop.hdfs.server.datanode.BlockScanner (<init>(172)) - Initialized block scanner with targetBytesPerSec 1048576 2015-12-14 06:55:14,999 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (<init>(427)) - Configured hostname is 127.0.0.1 2015-12-14 06:55:15,004 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (startDataNode(1100)) - Starting DataNode with maxLockedMemory = 0 2015-12-14 06:55:15,012 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (initDataXceiver(898)) - Opened streaming server at /127.0.0.1:41619 2015-12-14 06:55:15,014 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (<init>(76)) - Balancing bandwith is 1048576 bytes/s 2015-12-14 06:55:15,014 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (<init>(77)) - Number threads for balancing is 5 2015-12-14 06:55:15,021 INFO: org.apache.hadoop.security.authentication.server.AuthenticationFilter (constructSecretProvider(284)) - Unable to initialize FileSignerSecretProvider, falling back to use random secrets. 2015-12-14 06:55:15,022 INFO: org.apache.hadoop.http.HttpRequestLog (getRequestLog(80)) - Http request log for http.requests.datanode is not defined 2015-12-14 06:55:15,022 INFO: org.apache.hadoop.http.HttpServer2 (addGlobalFilter(710)) - Added global filter 'safety' (class=org.apache.hadoop.http.HttpServer2$QuotingInputFilter) 2015-12-14 06:55:15,023 INFO: org.apache.hadoop.http.HttpServer2 (addFilter(685)) - Added filter static_user_filter (class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to context datanode 2015-12-14 06:55:15,024 INFO: org.apache.hadoop.http.HttpServer2 (addFilter(693)) - Added filter static_user_filter (class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to context static 2015-12-14 06:55:15,026 INFO: org.apache.hadoop.http.HttpServer2 (openListeners(915)) - Jetty bound to port 41267 2015-12-14 06:55:15,027 INFO: org.mortbay.log (invoke0(?)) - jetty-6.1.14 2015-12-14 06:55:15,030 INFO: org.mortbay.log (invoke0(?)) - Extract jar:file:/home/jenkins/.m2/repository/org/apache/hadoop/hadoop-hdfs/2.7.1/hadoop-hdfs-2.7.1-tests.jar!/webapps/datanode to /tmp/Jetty_localhost_41267_datanode____.tkl743/webapp 2015-12-14 06:55:15,200 INFO: org.mortbay.log (invoke0(?)) - Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:41267 2015-12-14 06:55:15,405 INFO: org.apache.hadoop.hdfs.server.datanode.web.DatanodeHttpServer (start(186)) - Listening HTTP traffic on /127.0.0.1:44699 2015-12-14 06:55:15,407 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (startDataNode(1117)) - dnUserName = jenkins 2015-12-14 06:55:15,408 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (startDataNode(1118)) - supergroup = supergroup 2015-12-14 06:55:15,426 INFO: org.apache.hadoop.ipc.CallQueueManager (<init>(53)) - Using callQueue class java.util.concurrent.LinkedBlockingQueue 2015-12-14 06:55:15,427 INFO: org.apache.hadoop.ipc.Server (run(606)) - Starting Socket Reader #1 for port 35452 2015-12-14 06:55:15,435 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (initIpcServer(817)) - Opened IPC server at /127.0.0.1:35452 2015-12-14 06:55:15,445 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (refreshNamenodes(152)) - Refresh request received for nameservices: null 2015-12-14 06:55:15,448 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (doRefreshNamenodes(197)) - Starting BPOfferServices for nameservices: <default> 2015-12-14 06:55:15,459 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (run(794)) - Block pool <registering> (Datanode Uuid unassigned) service to localhost/127.0.0.1:56207 starting to offer service 2015-12-14 06:55:15,466 INFO: org.apache.hadoop.ipc.Server (run(836)) - IPC Server Responder: starting 2015-12-14 06:55:15,468 INFO: org.apache.hadoop.ipc.Server (run(676)) - IPC Server listener on 35452: starting 2015-12-14 06:55:15,838 INFO: org.apache.hadoop.hdfs.server.common.Storage (tryLock(715)) - Lock on <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data1/in_use.lock> acquired by nodename [email protected] 2015-12-14 06:55:15,840 INFO: org.apache.hadoop.hdfs.server.common.Storage (loadStorageDirectory(273)) - Storage directory <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data1> is not formatted for BP-436438203-67.195.81.152-1450076113417 2015-12-14 06:55:15,841 INFO: org.apache.hadoop.hdfs.server.common.Storage (loadStorageDirectory(275)) - Formatting ... 2015-12-14 06:55:15,929 INFO: org.apache.hadoop.hdfs.server.common.Storage (recoverTransitionRead(241)) - Analyzing storage directories for bpid BP-436438203-67.195.81.152-1450076113417 2015-12-14 06:55:15,929 INFO: org.apache.hadoop.hdfs.server.common.Storage (lock(675)) - Locking is disabled for <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data1/current/BP-436438203-67.195.81.152-1450076113417> 2015-12-14 06:55:15,930 INFO: org.apache.hadoop.hdfs.server.common.Storage (loadStorageDirectory(158)) - Block pool storage directory <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data1/current/BP-436438203-67.195.81.152-1450076113417> is not formatted for BP-436438203-67.195.81.152-1450076113417 2015-12-14 06:55:15,930 INFO: org.apache.hadoop.hdfs.server.common.Storage (loadStorageDirectory(160)) - Formatting ... 2015-12-14 06:55:15,930 INFO: org.apache.hadoop.hdfs.server.common.Storage (format(267)) - Formatting block pool BP-436438203-67.195.81.152-1450076113417 directory <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data1/current/BP-436438203-67.195.81.152-1450076113417/current> 2015-12-14 06:55:15,979 INFO: org.apache.hadoop.hdfs.server.common.Storage (doTransition(359)) - Restored 0 block files from trash. 2015-12-14 06:55:16,001 INFO: org.apache.hadoop.hdfs.MiniDFSCluster (shouldWait(2303)) - dnInfo.length != numDataNodes 2015-12-14 06:55:16,001 INFO: org.apache.hadoop.hdfs.MiniDFSCluster (waitActive(2255)) - Waiting for cluster to become active 2015-12-14 06:55:16,044 INFO: org.apache.hadoop.hdfs.server.common.Storage (tryLock(715)) - Lock on <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data2/in_use.lock> acquired by nodename [email protected] 2015-12-14 06:55:16,044 INFO: org.apache.hadoop.hdfs.server.common.Storage (loadStorageDirectory(273)) - Storage directory <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data2> is not formatted for BP-436438203-67.195.81.152-1450076113417 2015-12-14 06:55:16,044 INFO: org.apache.hadoop.hdfs.server.common.Storage (loadStorageDirectory(275)) - Formatting ... 2015-12-14 06:55:16,104 INFO: org.apache.hadoop.hdfs.MiniDFSCluster (shouldWait(2303)) - dnInfo.length != numDataNodes 2015-12-14 06:55:16,104 INFO: org.apache.hadoop.hdfs.MiniDFSCluster (waitActive(2255)) - Waiting for cluster to become active 2015-12-14 06:55:16,108 INFO: org.apache.hadoop.hdfs.server.common.Storage (recoverTransitionRead(241)) - Analyzing storage directories for bpid BP-436438203-67.195.81.152-1450076113417 2015-12-14 06:55:16,108 INFO: org.apache.hadoop.hdfs.server.common.Storage (lock(675)) - Locking is disabled for <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data2/current/BP-436438203-67.195.81.152-1450076113417> 2015-12-14 06:55:16,108 INFO: org.apache.hadoop.hdfs.server.common.Storage (loadStorageDirectory(158)) - Block pool storage directory <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data2/current/BP-436438203-67.195.81.152-1450076113417> is not formatted for BP-436438203-67.195.81.152-1450076113417 2015-12-14 06:55:16,108 INFO: org.apache.hadoop.hdfs.server.common.Storage (loadStorageDirectory(160)) - Formatting ... 2015-12-14 06:55:16,108 INFO: org.apache.hadoop.hdfs.server.common.Storage (format(267)) - Formatting block pool BP-436438203-67.195.81.152-1450076113417 directory <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data2/current/BP-436438203-67.195.81.152-1450076113417/current> 2015-12-14 06:55:16,145 INFO: org.apache.hadoop.hdfs.server.common.Storage (doTransition(359)) - Restored 0 block files from trash. 2015-12-14 06:55:16,160 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (initStorage(1364)) - Setting up storage: nsid=1945749807;bpid=BP-436438203-67.195.81.152-1450076113417;lv=-56;nsInfo=lv=-63;cid=testClusterID;nsid=1945749807;c=0;bpid=BP-436438203-67.195.81.152-1450076113417;dnuuid=null 2015-12-14 06:55:16,206 INFO: org.apache.hadoop.hdfs.MiniDFSCluster (shouldWait(2303)) - dnInfo.length != numDataNodes 2015-12-14 06:55:16,206 INFO: org.apache.hadoop.hdfs.MiniDFSCluster (waitActive(2255)) - Waiting for cluster to become active 2015-12-14 06:55:16,251 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (checkDatanodeUuid(1193)) - Generated and persisted new Datanode UUID dfb31aa7-8aad-41f4-8f71-ac5e35c7b205 2015-12-14 06:55:16,283 INFO: org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl (addVolume(303)) - Added new volume: DS-dc09846c-e0bf-494d-b5ae-32eef72cec99 2015-12-14 06:55:16,283 INFO: org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl (addVolume(380)) - Added volume - <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data1/current,> StorageType: DISK 2015-12-14 06:55:16,284 INFO: org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl (addVolume(303)) - Added new volume: DS-ad3c7dea-11da-4dad-8b88-536bd0ee6958 2015-12-14 06:55:16,284 INFO: org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl (addVolume(380)) - Added volume - <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data2/current,> StorageType: DISK 2015-12-14 06:55:16,292 INFO: org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl (registerMBean(2028)) - Registered FSDatasetState MBean 2015-12-14 06:55:16,293 INFO: org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl (addBlockPool(2474)) - Adding block pool BP-436438203-67.195.81.152-1450076113417 2015-12-14 06:55:16,293 INFO: org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl (run(402)) - Scanning block pool BP-436438203-67.195.81.152-1450076113417 on volume <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data1/current...> 2015-12-14 06:55:16,294 INFO: org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl (run(402)) - Scanning block pool BP-436438203-67.195.81.152-1450076113417 on volume <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data2/current...> 2015-12-14 06:55:16,307 INFO: org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl (run(407)) - Time taken to scan block pool BP-436438203-67.195.81.152-1450076113417 on <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data1/current>: 13ms 2015-12-14 06:55:16,307 INFO: org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl (run(407)) - Time taken to scan block pool BP-436438203-67.195.81.152-1450076113417 on <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data2/current>: 13ms 2015-12-14 06:55:16,309 INFO: org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl (addBlockPool(433)) - Total time to scan all replicas for block pool BP-436438203-67.195.81.152-1450076113417: 17ms 2015-12-14 06:55:16,310 INFO: org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl (run(189)) - Adding replicas to map for block pool BP-436438203-67.195.81.152-1450076113417 on volume <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data1/current...> 2015-12-14 06:55:16,311 INFO: org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl (run(189)) - Adding replicas to map for block pool BP-436438203-67.195.81.152-1450076113417 on volume <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data2/current...> 2015-12-14 06:55:16,311 INFO: org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl (run(194)) - Time to add replicas to map for block pool BP-436438203-67.195.81.152-1450076113417 on volume <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data1/current>: 1ms 2015-12-14 06:55:16,311 INFO: org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl (run(194)) - Time to add replicas to map for block pool BP-436438203-67.195.81.152-1450076113417 on volume <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data2/current>: 0ms 2015-12-14 06:55:16,312 INFO: org.apache.hadoop.hdfs.server.datanode.fsdataset.impl.FsDatasetImpl (getAllVolumesMap(220)) - Total time to add all replicas to map: 2ms 2015-12-14 06:55:16,312 INFO: org.apache.hadoop.hdfs.MiniDFSCluster (shouldWait(2303)) - dnInfo.length != numDataNodes 2015-12-14 06:55:16,312 INFO: org.apache.hadoop.hdfs.MiniDFSCluster (waitActive(2255)) - Waiting for cluster to become active 2015-12-14 06:55:16,414 INFO: org.apache.hadoop.hdfs.MiniDFSCluster (shouldWait(2303)) - dnInfo.length != numDataNodes 2015-12-14 06:55:16,414 INFO: org.apache.hadoop.hdfs.MiniDFSCluster (waitActive(2255)) - Waiting for cluster to become active 2015-12-14 06:55:16,484 INFO: org.apache.hadoop.hdfs.server.datanode.VolumeScanner (findNextUsableBlockIter(378)) - Now scanning bpid BP-436438203-67.195.81.152-1450076113417 on volume <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data2> 2015-12-14 06:55:16,484 INFO: org.apache.hadoop.hdfs.server.datanode.VolumeScanner (findNextUsableBlockIter(378)) - Now scanning bpid BP-436438203-67.195.81.152-1450076113417 on volume <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data1> 2015-12-14 06:55:16,487 INFO: org.apache.hadoop.hdfs.server.datanode.VolumeScanner (runLoop(533)) - VolumeScanner(<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data1,> DS-dc09846c-e0bf-494d-b5ae-32eef72cec99): finished scanning block pool BP-436438203-67.195.81.152-1450076113417 2015-12-14 06:55:16,487 INFO: org.apache.hadoop.hdfs.server.datanode.VolumeScanner (runLoop(533)) - VolumeScanner(<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data2,> DS-ad3c7dea-11da-4dad-8b88-536bd0ee6958): finished scanning block pool BP-436438203-67.195.81.152-1450076113417 2015-12-14 06:55:16,489 INFO: org.apache.hadoop.hdfs.server.datanode.DirectoryScanner (start(332)) - Periodic Directory Tree Verification scan starting at 1450080075489 with interval 21600000 2015-12-14 06:55:16,492 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (register(749)) - Block pool BP-436438203-67.195.81.152-1450076113417 (Datanode Uuid null) service to localhost/127.0.0.1:56207 beginning handshake with NN 2015-12-14 06:55:16,503 INFO: org.apache.hadoop.hdfs.StateChange (registerDatanode(879)) - BLOCK* registerDatanode: from DatanodeRegistration(127.0.0.1:41619, datanodeUuid=dfb31aa7-8aad-41f4-8f71-ac5e35c7b205, infoPort=44699, infoSecurePort=0, ipcPort=35452, storageInfo=lv=-56;cid=testClusterID;nsid=1945749807;c=0) storage dfb31aa7-8aad-41f4-8f71-ac5e35c7b205 2015-12-14 06:55:16,504 INFO: org.apache.hadoop.hdfs.server.blockmanagement.DatanodeDescriptor (updateHeartbeatState(450)) - Number of failed storage changes from 0 to 0 2015-12-14 06:55:16,509 INFO: org.apache.hadoop.net.NetworkTopology (add(419)) - Adding a new node: /default-rack/127.0.0.1:41619 2015-12-14 06:55:16,519 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (register(767)) - Block pool Block pool BP-436438203-67.195.81.152-1450076113417 (Datanode Uuid null) service to localhost/127.0.0.1:56207 successfully registered with NN 2015-12-14 06:55:16,519 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (offerService(625)) - For namenode localhost/127.0.0.1:56207 using DELETEREPORT_INTERVAL of 300000 msec BLOCKREPORT_INTERVAL of 21600000msec CACHEREPORT_INTERVAL of 10000msec Initial delay: 0msec; heartBeatInterval=3000 2015-12-14 06:55:16,522 INFO: org.apache.hadoop.hdfs.MiniDFSCluster (shouldWait(2319)) - No heartbeat from DataNode: 127.0.0.1:41619 2015-12-14 06:55:16,522 INFO: org.apache.hadoop.hdfs.MiniDFSCluster (waitActive(2255)) - Waiting for cluster to become active 2015-12-14 06:55:16,537 INFO: org.apache.hadoop.hdfs.server.blockmanagement.DatanodeDescriptor (updateHeartbeatState(450)) - Number of failed storage changes from 0 to 0 2015-12-14 06:55:16,537 INFO: org.apache.hadoop.hdfs.server.blockmanagement.DatanodeDescriptor (updateStorage(856)) - Adding new storage ID DS-dc09846c-e0bf-494d-b5ae-32eef72cec99 for DN 127.0.0.1:41619 2015-12-14 06:55:16,538 INFO: org.apache.hadoop.hdfs.server.blockmanagement.DatanodeDescriptor (updateStorage(856)) - Adding new storage ID DS-ad3c7dea-11da-4dad-8b88-536bd0ee6958 for DN 127.0.0.1:41619 2015-12-14 06:55:16,552 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (updateActorStatesFromHeartbeat(507)) - Namenode Block pool BP-436438203-67.195.81.152-1450076113417 (Datanode Uuid dfb31aa7-8aad-41f4-8f71-ac5e35c7b205) service to localhost/127.0.0.1:56207 trying to claim ACTIVE state with txid=1 2015-12-14 06:55:16,552 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (updateActorStatesFromHeartbeat(519)) - Acknowledging ACTIVE Namenode Block pool BP-436438203-67.195.81.152-1450076113417 (Datanode Uuid dfb31aa7-8aad-41f4-8f71-ac5e35c7b205) service to localhost/127.0.0.1:56207 2015-12-14 06:55:16,562 INFO: org.apache.hadoop.hdfs.server.datanode.VolumeScanner (findNextUsableBlockIter(395)) - VolumeScanner(<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data1,> DS-dc09846c-e0bf-494d-b5ae-32eef72cec99): no suitable block pools found to scan. Waiting 1814399922 ms. 2015-12-14 06:55:16,562 INFO: org.apache.hadoop.hdfs.server.datanode.VolumeScanner (findNextUsableBlockIter(395)) - VolumeScanner(<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/target/test-data/3b50fab8-a45c-4c40-bf0a-cc073c573632/data/data2,> DS-ad3c7dea-11da-4dad-8b88-536bd0ee6958): no suitable block pools found to scan. Waiting 1814399921 ms. 2015-12-14 06:55:16,570 INFO: BlockStateChange (processReport(1862)) - BLOCK* processReport: from storage DS-dc09846c-e0bf-494d-b5ae-32eef72cec99 node DatanodeRegistration(127.0.0.1:41619, datanodeUuid=dfb31aa7-8aad-41f4-8f71-ac5e35c7b205, infoPort=44699, infoSecurePort=0, ipcPort=35452, storageInfo=lv=-56;cid=testClusterID;nsid=1945749807;c=0), blocks: 0, hasStaleStorage: true, processing time: 1 msecs 2015-12-14 06:55:16,570 INFO: BlockStateChange (processReport(1862)) - BLOCK* processReport: from storage DS-ad3c7dea-11da-4dad-8b88-536bd0ee6958 node DatanodeRegistration(127.0.0.1:41619, datanodeUuid=dfb31aa7-8aad-41f4-8f71-ac5e35c7b205, infoPort=44699, infoSecurePort=0, ipcPort=35452, storageInfo=lv=-56;cid=testClusterID;nsid=1945749807;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs 2015-12-14 06:55:16,592 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (blockReport(492)) - Successfully sent block report 0x362687c99017f, containing 2 storage report(s), of which we sent 2. The reports had 0 total blocks and used 1 RPC(s). This took 4 msec to generate and 35 msecs for RPC and NN processing. Got back one command: FinalizeCommand/5. 2015-12-14 06:55:16,593 INFO: org.apache.hadoop.hdfs.server.datanode.DataNode (processCommandFromActive(690)) - Got finalize command for block pool BP-436438203-67.195.81.152-1450076113417 2015-12-14 06:55:16,629 INFO: org.apache.hadoop.hdfs.MiniDFSCluster (waitActive(2286)) - Cluster is active 2015-12-14 06:55:16,648 WARN: org.apache.tajo.storage.FileTablespace (storageInit(123)) - does not support block metadata. ('dfs.datanode.hdfs-blocks-metadata.enabled') 2015-12-14 06:55:16,678 WARN: org.apache.tajo.storage.TablespaceManager (loadStorages(168)) - java.lang.ClassNotFoundException: org.apache.tajo.storage.s3.S3TableSpace 2015-12-14 06:55:16,678 WARN: org.apache.tajo.storage.TablespaceManager (loadStorages(168)) - java.lang.ClassNotFoundException: org.apache.tajo.storage.s3.S3TableSpace Tests run: 2, Failures: 0, Errors: 2, Skipped: 0, Time elapsed: 6.084 sec <<< FAILURE! - in org.apache.tajo.jdbc.TestResultSet org.apache.tajo.jdbc.TestResultSet Time elapsed: 6.084 sec <<< ERROR! java.lang.NoClassDefFoundError: org/apache/hadoop/hbase/HConstants at org.apache.tajo.jdbc.TestResultSet.setup(TestResultSet.java:68) Caused by: java.lang.ClassNotFoundException: org.apache.hadoop.hbase.HConstants at org.apache.tajo.jdbc.TestResultSet.setup(TestResultSet.java:68) org.apache.tajo.jdbc.TestResultSet Time elapsed: 6.084 sec <<< ERROR! java.lang.NullPointerException at org.apache.tajo.jdbc.TestResultSet.terminate(TestResultSet.java:111) Running org.apache.tajo.jdbc.util.TestQueryStringDecoder Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in org.apache.tajo.jdbc.util.TestQueryStringDecoder Running org.apache.tajo.jdbc.util.TestResultSetUtil Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec - in org.apache.tajo.jdbc.util.TestResultSetUtil Running org.apache.tajo.jdbc.TestTajoJdbc Tests run: 3, Failures: 0, Errors: 3, Skipped: 0, Time elapsed: 0 sec <<< FAILURE! - in org.apache.tajo.jdbc.TestTajoJdbc org.apache.tajo.jdbc.TestTajoJdbc Time elapsed: 0 sec <<< ERROR! java.lang.NoClassDefFoundError: Could not initialize class org.apache.tajo.TpchTestBase org.apache.tajo.jdbc.TestTajoJdbc Time elapsed: 0 sec <<< ERROR! java.lang.NoClassDefFoundError: Could not initialize class org.apache.tajo.QueryTestCaseBase org.apache.tajo.jdbc.TestTajoJdbc Time elapsed: 0 sec <<< ERROR! java.lang.NoClassDefFoundError: Could not initialize class org.apache.tajo.QueryTestCaseBase Running org.apache.tajo.jdbc.TestTajoJdbcNegative Tests run: 3, Failures: 0, Errors: 3, Skipped: 0, Time elapsed: 0 sec <<< FAILURE! - in org.apache.tajo.jdbc.TestTajoJdbcNegative org.apache.tajo.jdbc.TestTajoJdbcNegative Time elapsed: 0 sec <<< ERROR! java.lang.NoClassDefFoundError: Could not initialize class org.apache.tajo.QueryTestCaseBase org.apache.tajo.jdbc.TestTajoJdbcNegative Time elapsed: 0 sec <<< ERROR! java.lang.NoClassDefFoundError: Could not initialize class org.apache.tajo.QueryTestCaseBase org.apache.tajo.jdbc.TestTajoJdbcNegative Time elapsed: 0 sec <<< ERROR! java.lang.NoClassDefFoundError: Could not initialize class org.apache.tajo.QueryTestCaseBase Running org.apache.tajo.jdbc.TestTajoDatabaseMetaData Tests run: 2, Failures: 0, Errors: 2, Skipped: 0, Time elapsed: 0 sec <<< FAILURE! - in org.apache.tajo.jdbc.TestTajoDatabaseMetaData org.apache.tajo.jdbc.TestTajoDatabaseMetaData Time elapsed: 0 sec <<< ERROR! java.lang.NoClassDefFoundError: Could not initialize class org.apache.tajo.QueryTestCaseBase org.apache.tajo.jdbc.TestTajoDatabaseMetaData Time elapsed: 0 sec <<< ERROR! java.lang.NoClassDefFoundError: Could not initialize class org.apache.tajo.QueryTestCaseBase Running org.apache.tajo.jdbc.TestSQLState Tests run: 3, Failures: 0, Errors: 3, Skipped: 0, Time elapsed: 0.001 sec <<< FAILURE! - in org.apache.tajo.jdbc.TestSQLState org.apache.tajo.jdbc.TestSQLState Time elapsed: 0 sec <<< ERROR! java.lang.NoClassDefFoundError: Could not initialize class org.apache.tajo.QueryTestCaseBase org.apache.tajo.jdbc.TestSQLState Time elapsed: 0 sec <<< ERROR! java.lang.NoClassDefFoundError: Could not initialize class org.apache.tajo.QueryTestCaseBase org.apache.tajo.jdbc.TestSQLState Time elapsed: 0 sec <<< ERROR! java.lang.NoClassDefFoundError: Could not initialize class org.apache.tajo.QueryTestCaseBase Results : Tests in error: org.apache.tajo.jdbc.TestResultSet.org.apache.tajo.jdbc.TestResultSet Run 1: TestResultSet.setup:68 » NoClassDefFound org/apache/hadoop/hbase/HConstants Run 2: TestResultSet.terminate:111 NullPointer org.apache.tajo.jdbc.TestSQLState.org.apache.tajo.jdbc.TestSQLState Run 1: TestSQLState.org.apache.tajo.jdbc.TestSQLState » NoClassDefFound Could not ini... Run 2: TestSQLState.org.apache.tajo.jdbc.TestSQLState » NoClassDefFound Could not ini... Run 3: TestSQLState.org.apache.tajo.jdbc.TestSQLState » NoClassDefFound Could not ini... org.apache.tajo.jdbc.TestTajoDatabaseMetaData.org.apache.tajo.jdbc.TestTajoDatabaseMetaData Run 1: TestTajoDatabaseMetaData.org.apache.tajo.jdbc.TestTajoDatabaseMetaData » NoClassDefFound Run 2: TestTajoDatabaseMetaData.org.apache.tajo.jdbc.TestTajoDatabaseMetaData » NoClassDefFound org.apache.tajo.jdbc.TestTajoJdbc.org.apache.tajo.jdbc.TestTajoJdbc Run 1: TestTajoJdbc>QueryTestCaseBase.<clinit>:177 » NoClassDefFound Could not initia... Run 2: TestTajoJdbc.org.apache.tajo.jdbc.TestTajoJdbc » NoClassDefFound Could not ini... Run 3: TestTajoJdbc.org.apache.tajo.jdbc.TestTajoJdbc » NoClassDefFound Could not ini... org.apache.tajo.jdbc.TestTajoJdbcNegative.org.apache.tajo.jdbc.TestTajoJdbcNegative Run 1: TestTajoJdbcNegative.org.apache.tajo.jdbc.TestTajoJdbcNegative » NoClassDefFound Run 2: TestTajoJdbcNegative.org.apache.tajo.jdbc.TestTajoJdbcNegative » NoClassDefFound Run 3: TestTajoJdbcNegative.org.apache.tajo.jdbc.TestTajoJdbcNegative » NoClassDefFound Tests run: 10, Failures: 0, Errors: 5, Skipped: 0 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Tajo Main ......................................... SUCCESS [ 1.533 s] [INFO] Tajo Project POM .................................. SUCCESS [ 1.210 s] [INFO] Tajo Maven Plugins ................................ SUCCESS [ 2.473 s] [INFO] Tajo Common ....................................... SUCCESS [ 28.086 s] [INFO] Tajo Algebra ...................................... SUCCESS [ 2.588 s] [INFO] Tajo Catalog Common ............................... SUCCESS [ 5.111 s] [INFO] Tajo Plan ......................................... SUCCESS [ 7.237 s] [INFO] Tajo Rpc Common ................................... SUCCESS [ 1.243 s] [INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [02:24 min] [INFO] Tajo Catalog Client ............................... SUCCESS [ 1.461 s] [INFO] Tajo Catalog Server ............................... SUCCESS [01:11 min] [INFO] Tajo Storage Common ............................... SUCCESS [ 3.095 s] [INFO] Tajo HDFS Storage ................................. SUCCESS [01:08 min] [INFO] Tajo PullServer ................................... SUCCESS [ 0.844 s] [INFO] Tajo Client ....................................... SUCCESS [ 2.909 s] [INFO] Tajo CLI tools .................................... SUCCESS [ 1.897 s] [INFO] Tajo SQL Parser ................................... SUCCESS [ 3.912 s] [INFO] ASM (thirdparty) .................................. SUCCESS [ 2.137 s] [INFO] Tajo RESTful Container ............................ SUCCESS [ 3.657 s] [INFO] Tajo Metrics ...................................... SUCCESS [ 2.036 s] [INFO] Tajo Core ......................................... SUCCESS [ 7.519 s] [INFO] Tajo RPC .......................................... SUCCESS [ 0.944 s] [INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 30.143 s] [INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.073 s] [INFO] Tajo Catalog ...................................... SUCCESS [ 0.990 s] [INFO] Tajo Client Example ............................... SUCCESS [ 1.072 s] [INFO] Tajo HBase Storage ................................ SUCCESS [ 4.014 s] [INFO] Tajo Cluster Tests ................................ SUCCESS [ 2.717 s] [INFO] Tajo JDBC Driver .................................. FAILURE [ 8.267 s] [INFO] Tajo JDBC storage common .......................... SKIPPED [INFO] Tajo PostgreSQL JDBC storage ...................... SKIPPED [INFO] Tajo S3 storage ................................... SKIPPED [INFO] Tajo Storage ...................................... SKIPPED [INFO] Tajo Distribution ................................. SKIPPED [INFO] Tajo Core Tests ................................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 06:52 min [INFO] Finished at: 2015-12-14T06:55:17+00:00 [INFO] Final Memory: 155M/2027M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.19:test (default-test) on project tajo-jdbc: There are test failures. [ERROR] [ERROR] Please refer to <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-jdbc/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-jdbc Build step 'Execute shell' marked build as failure Archiving artifacts Recording test results
