See <https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/195/>
------------------------------------------
[...truncated 8391 lines...]
[exec] 2015-05-24 14:24:37,055 INFO http.HttpRequestLog
(HttpRequestLog.java:getRequestLog(80)) - Http request log for
http.requests.datanode is not defined
[exec] 2015-05-24 14:24:37,055 INFO http.HttpServer2
(HttpServer2.java:addGlobalFilter(678)) - Added global filter 'safety'
(class=org.apache.hadoop.http.HttpServer2$QuotingInputFilter)
[exec] 2015-05-24 14:24:37,055 INFO http.HttpServer2
(HttpServer2.java:addFilter(653)) - Added filter static_user_filter
(class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to
context datanode
[exec] 2015-05-24 14:24:37,056 INFO http.HttpServer2
(HttpServer2.java:addFilter(661)) - Added filter static_user_filter
(class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to
context static
[exec] 2015-05-24 14:24:37,057 INFO http.HttpServer2
(HttpServer2.java:openListeners(883)) - Jetty bound to port 48471
[exec] 2015-05-24 14:24:37,057 INFO mortbay.log (Slf4jLog.java:info(67))
- jetty-6.1.26
[exec] 2015-05-24 14:24:37,409 INFO mortbay.log (Slf4jLog.java:info(67))
- Started SelectChannelConnector@localhost:48471
[exec] 2015-05-24 14:24:37,529 INFO web.DatanodeHttpServer
(DatanodeHttpServer.java:start(162)) - Listening HTTP traffic on
/127.0.0.1:45323
[exec] 2015-05-24 14:24:37,530 INFO datanode.DataNode
(DataNode.java:startDataNode(1144)) - dnUserName = jenkins
[exec] 2015-05-24 14:24:37,530 INFO datanode.DataNode
(DataNode.java:startDataNode(1145)) - supergroup = supergroup
[exec] 2015-05-24 14:24:37,545 INFO ipc.CallQueueManager
(CallQueueManager.java:<init>(56)) - Using callQueue class
java.util.concurrent.LinkedBlockingQueue
[exec] 2015-05-24 14:24:37,545 INFO ipc.Server (Server.java:run(622)) -
Starting Socket Reader #1 for port 42270
[exec] 2015-05-24 14:24:37,552 INFO datanode.DataNode
(DataNode.java:initIpcServer(844)) - Opened IPC server at /127.0.0.1:42270
[exec] 2015-05-24 14:24:37,561 INFO datanode.DataNode
(BlockPoolManager.java:refreshNamenodes(149)) - Refresh request received for
nameservices: null
[exec] 2015-05-24 14:24:37,564 INFO datanode.DataNode
(BlockPoolManager.java:doRefreshNamenodes(194)) - Starting BPOfferServices for
nameservices: <default>
[exec] 2015-05-24 14:24:37,574 INFO datanode.DataNode
(BPServiceActor.java:run(791)) - Block pool <registering> (Datanode Uuid
unassigned) service to localhost/127.0.0.1:34418 starting to offer service
[exec] 2015-05-24 14:24:37,579 INFO ipc.Server (Server.java:run(852)) -
IPC Server Responder: starting
[exec] 2015-05-24 14:24:37,579 INFO ipc.Server (Server.java:run(692)) -
IPC Server listener on 42270: starting
[exec] 2015-05-24 14:24:37,809 INFO common.Storage
(Storage.java:tryLock(715)) - Lock on
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data1/in_use.lock>
acquired by nodename 2...@asf905.gq1.ygridcore.net
[exec] 2015-05-24 14:24:37,809 INFO common.Storage
(DataStorage.java:loadStorageDirectory(272)) - Storage directory
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data1>
is not formatted for BP-2084748531-67.195.81.149-1432477475599
[exec] 2015-05-24 14:24:37,809 INFO common.Storage
(DataStorage.java:loadStorageDirectory(274)) - Formatting ...
[exec] 2015-05-24 14:24:37,835 INFO common.Storage
(BlockPoolSliceStorage.java:recoverTransitionRead(241)) - Analyzing storage
directories for bpid BP-2084748531-67.195.81.149-1432477475599
[exec] 2015-05-24 14:24:37,835 INFO common.Storage
(Storage.java:lock(675)) - Locking is disabled for
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data1/current/BP-2084748531-67.195.81.149-1432477475599>
[exec] 2015-05-24 14:24:37,836 INFO common.Storage
(BlockPoolSliceStorage.java:loadStorageDirectory(158)) - Block pool storage
directory
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data1/current/BP-2084748531-67.195.81.149-1432477475599>
is not formatted for BP-2084748531-67.195.81.149-1432477475599
[exec] 2015-05-24 14:24:37,836 INFO common.Storage
(BlockPoolSliceStorage.java:loadStorageDirectory(160)) - Formatting ...
[exec] 2015-05-24 14:24:37,836 INFO common.Storage
(BlockPoolSliceStorage.java:format(267)) - Formatting block pool
BP-2084748531-67.195.81.149-1432477475599 directory
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data1/current/BP-2084748531-67.195.81.149-1432477475599/current>
[exec] 2015-05-24 14:24:37,838 INFO common.Storage
(Storage.java:tryLock(715)) - Lock on
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data2/in_use.lock>
acquired by nodename 2...@asf905.gq1.ygridcore.net
[exec] 2015-05-24 14:24:37,838 INFO common.Storage
(DataStorage.java:loadStorageDirectory(272)) - Storage directory
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data2>
is not formatted for BP-2084748531-67.195.81.149-1432477475599
[exec] 2015-05-24 14:24:37,838 INFO common.Storage
(DataStorage.java:loadStorageDirectory(274)) - Formatting ...
[exec] 2015-05-24 14:24:37,853 INFO common.Storage
(BlockPoolSliceStorage.java:recoverTransitionRead(241)) - Analyzing storage
directories for bpid BP-2084748531-67.195.81.149-1432477475599
[exec] 2015-05-24 14:24:37,853 INFO common.Storage
(Storage.java:lock(675)) - Locking is disabled for
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data2/current/BP-2084748531-67.195.81.149-1432477475599>
[exec] 2015-05-24 14:24:37,853 INFO common.Storage
(BlockPoolSliceStorage.java:loadStorageDirectory(158)) - Block pool storage
directory
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data2/current/BP-2084748531-67.195.81.149-1432477475599>
is not formatted for BP-2084748531-67.195.81.149-1432477475599
[exec] 2015-05-24 14:24:37,853 INFO common.Storage
(BlockPoolSliceStorage.java:loadStorageDirectory(160)) - Formatting ...
[exec] 2015-05-24 14:24:37,853 INFO common.Storage
(BlockPoolSliceStorage.java:format(267)) - Formatting block pool
BP-2084748531-67.195.81.149-1432477475599 directory
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data2/current/BP-2084748531-67.195.81.149-1432477475599/current>
[exec] 2015-05-24 14:24:37,855 INFO datanode.DataNode
(DataNode.java:initStorage(1405)) - Setting up storage:
nsid=377495776;bpid=BP-2084748531-67.195.81.149-1432477475599;lv=-56;nsInfo=lv=-63;cid=testClusterID;nsid=377495776;c=0;bpid=BP-2084748531-67.195.81.149-1432477475599;dnuuid=null
[exec] 2015-05-24 14:24:37,856 INFO datanode.DataNode
(DataNode.java:checkDatanodeUuid(1234)) - Generated and persisted new Datanode
UUID d507fd8f-f35b-41a4-b081-2fb018116f0e
[exec] 2015-05-24 14:24:37,877 INFO impl.FsDatasetImpl
(FsVolumeList.java:addVolume(305)) - Added new volume:
DS-7c42c834-fc72-4e0c-865b-a09f3a5cb708
[exec] 2015-05-24 14:24:37,877 INFO impl.FsDatasetImpl
(FsDatasetImpl.java:addVolume(403)) - Added volume -
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data1/current,>
StorageType: DISK
[exec] 2015-05-24 14:24:37,877 INFO impl.FsDatasetImpl
(FsVolumeList.java:addVolume(305)) - Added new volume:
DS-cd783a9c-7ef7-4c9e-a3ee-8da46e5caede
[exec] 2015-05-24 14:24:37,877 INFO impl.FsDatasetImpl
(FsDatasetImpl.java:addVolume(403)) - Added volume -
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data2/current,>
StorageType: DISK
[exec] 2015-05-24 14:24:37,881 INFO impl.FsDatasetImpl
(FsDatasetImpl.java:registerMBean(2079)) - Registered FSDatasetState MBean
[exec] 2015-05-24 14:24:37,887 INFO datanode.DirectoryScanner
(DirectoryScanner.java:start(333)) - Periodic Directory Tree Verification scan
starting at 1432497941887 with interval 21600000
[exec] 2015-05-24 14:24:37,888 INFO impl.FsDatasetImpl
(FsDatasetImpl.java:addBlockPool(2535)) - Adding block pool
BP-2084748531-67.195.81.149-1432477475599
[exec] 2015-05-24 14:24:37,889 INFO impl.FsDatasetImpl
(FsVolumeList.java:run(404)) - Scanning block pool
BP-2084748531-67.195.81.149-1432477475599 on volume
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data1/current...>
[exec] 2015-05-24 14:24:37,889 INFO impl.FsDatasetImpl
(FsVolumeList.java:run(404)) - Scanning block pool
BP-2084748531-67.195.81.149-1432477475599 on volume
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data2/current...>
[exec] 2015-05-24 14:24:37,902 INFO impl.FsDatasetImpl
(FsVolumeList.java:run(409)) - Time taken to scan block pool
BP-2084748531-67.195.81.149-1432477475599 on
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data2/current>:
13ms
[exec] 2015-05-24 14:24:37,903 INFO impl.FsDatasetImpl
(FsVolumeList.java:run(409)) - Time taken to scan block pool
BP-2084748531-67.195.81.149-1432477475599 on
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data1/current>:
14ms
[exec] 2015-05-24 14:24:37,903 INFO impl.FsDatasetImpl
(FsVolumeList.java:addBlockPool(435)) - Total time to scan all replicas for
block pool BP-2084748531-67.195.81.149-1432477475599: 15ms
[exec] 2015-05-24 14:24:37,905 INFO impl.FsDatasetImpl
(FsVolumeList.java:run(190)) - Adding replicas to map for block pool
BP-2084748531-67.195.81.149-1432477475599 on volume
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data1/current...>
[exec] 2015-05-24 14:24:37,905 INFO impl.FsDatasetImpl
(FsVolumeList.java:run(190)) - Adding replicas to map for block pool
BP-2084748531-67.195.81.149-1432477475599 on volume
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data2/current...>
[exec] 2015-05-24 14:24:37,905 INFO impl.BlockPoolSlice
(BlockPoolSlice.java:readReplicasFromCache(688)) - Replica Cache file:
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data2/current/BP-2084748531-67.195.81.149-1432477475599/current/replicas>
doesn't exist
[exec] 2015-05-24 14:24:37,905 INFO impl.BlockPoolSlice
(BlockPoolSlice.java:readReplicasFromCache(688)) - Replica Cache file:
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data1/current/BP-2084748531-67.195.81.149-1432477475599/current/replicas>
doesn't exist
[exec] 2015-05-24 14:24:37,905 INFO impl.FsDatasetImpl
(FsVolumeList.java:run(195)) - Time to add replicas to map for block pool
BP-2084748531-67.195.81.149-1432477475599 on volume
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data2/current>:
0ms
[exec] 2015-05-24 14:24:37,905 INFO impl.FsDatasetImpl
(FsVolumeList.java:run(195)) - Time to add replicas to map for block pool
BP-2084748531-67.195.81.149-1432477475599 on volume
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/data/data1/current>:
0ms
[exec] 2015-05-24 14:24:37,906 INFO impl.FsDatasetImpl
(FsVolumeList.java:getAllVolumesMap(221)) - Total time to add all replicas to
map: 3ms
[exec] 2015-05-24 14:24:37,908 INFO datanode.DataNode
(BPServiceActor.java:register(746)) - Block pool
BP-2084748531-67.195.81.149-1432477475599 (Datanode Uuid
d507fd8f-f35b-41a4-b081-2fb018116f0e) service to localhost/127.0.0.1:34418
beginning handshake with NN
[exec] 2015-05-24 14:24:37,921 INFO hdfs.StateChange
(DatanodeManager.java:registerDatanode(884)) - BLOCK* registerDatanode: from
DatanodeRegistration(127.0.0.1:43944,
datanodeUuid=d507fd8f-f35b-41a4-b081-2fb018116f0e, infoPort=45323,
infoSecurePort=0, ipcPort=42270,
storageInfo=lv=-56;cid=testClusterID;nsid=377495776;c=0) storage
d507fd8f-f35b-41a4-b081-2fb018116f0e
[exec] 2015-05-24 14:24:37,922 INFO blockmanagement.DatanodeDescriptor
(DatanodeDescriptor.java:updateHeartbeatState(448)) - Number of failed storage
changes from 0 to 0
[exec] 2015-05-24 14:24:37,923 INFO net.NetworkTopology
(NetworkTopology.java:add(418)) - Adding a new node:
/default-rack/127.0.0.1:43944
[exec] 2015-05-24 14:24:37,928 INFO datanode.DataNode
(BPServiceActor.java:register(764)) - Block pool Block pool
BP-2084748531-67.195.81.149-1432477475599 (Datanode Uuid
d507fd8f-f35b-41a4-b081-2fb018116f0e) service to localhost/127.0.0.1:34418
successfully registered with NN
[exec] 2015-05-24 14:24:37,929 INFO datanode.DataNode
(BPServiceActor.java:offerService(625)) - For namenode
localhost/127.0.0.1:34418 using BLOCKREPORT_INTERVAL of 21600000msec
CACHEREPORT_INTERVAL of 10000msec Initial delay: 0msec; heartBeatInterval=3000
[exec] 2015-05-24 14:24:37,933 INFO hdfs.MiniDFSCluster
(MiniDFSCluster.java:shouldWait(2332)) - No heartbeat from DataNode:
127.0.0.1:43944
[exec] 2015-05-24 14:24:37,933 INFO hdfs.MiniDFSCluster
(MiniDFSCluster.java:waitActive(2268)) - Waiting for cluster to become active
[exec] 2015-05-24 14:24:37,943 INFO blockmanagement.DatanodeDescriptor
(DatanodeDescriptor.java:updateHeartbeatState(448)) - Number of failed storage
changes from 0 to 0
[exec] 2015-05-24 14:24:37,943 INFO blockmanagement.DatanodeDescriptor
(DatanodeDescriptor.java:updateStorage(859)) - Adding new storage ID
DS-7c42c834-fc72-4e0c-865b-a09f3a5cb708 for DN 127.0.0.1:43944
[exec] 2015-05-24 14:24:37,944 INFO blockmanagement.DatanodeDescriptor
(DatanodeDescriptor.java:updateStorage(859)) - Adding new storage ID
DS-cd783a9c-7ef7-4c9e-a3ee-8da46e5caede for DN 127.0.0.1:43944
[exec] 2015-05-24 14:24:37,952 INFO datanode.DataNode
(BPOfferService.java:updateActorStatesFromHeartbeat(511)) - Namenode Block pool
BP-2084748531-67.195.81.149-1432477475599 (Datanode Uuid
d507fd8f-f35b-41a4-b081-2fb018116f0e) service to localhost/127.0.0.1:34418
trying to claim ACTIVE state with txid=1
[exec] 2015-05-24 14:24:37,953 INFO datanode.DataNode
(BPOfferService.java:updateActorStatesFromHeartbeat(523)) - Acknowledging
ACTIVE Namenode Block pool BP-2084748531-67.195.81.149-1432477475599 (Datanode
Uuid d507fd8f-f35b-41a4-b081-2fb018116f0e) service to localhost/127.0.0.1:34418
[exec] 2015-05-24 14:24:37,970 INFO blockmanagement.BlockManager
(BlockManager.java:processReport(1823)) - Processing first storage report for
DS-7c42c834-fc72-4e0c-865b-a09f3a5cb708 from datanode
d507fd8f-f35b-41a4-b081-2fb018116f0e
[exec] 2015-05-24 14:24:37,971 INFO BlockStateChange
(BlockManager.java:processReport(1872)) - BLOCK* processReport: from storage
DS-7c42c834-fc72-4e0c-865b-a09f3a5cb708 node
DatanodeRegistration(127.0.0.1:43944,
datanodeUuid=d507fd8f-f35b-41a4-b081-2fb018116f0e, infoPort=45323,
infoSecurePort=0, ipcPort=42270,
storageInfo=lv=-56;cid=testClusterID;nsid=377495776;c=0), blocks: 0,
hasStaleStorage: true, processing time: 1 msecs
[exec] 2015-05-24 14:24:37,971 INFO blockmanagement.BlockManager
(BlockManager.java:processReport(1823)) - Processing first storage report for
DS-cd783a9c-7ef7-4c9e-a3ee-8da46e5caede from datanode
d507fd8f-f35b-41a4-b081-2fb018116f0e
[exec] 2015-05-24 14:24:37,971 INFO BlockStateChange
(BlockManager.java:processReport(1872)) - BLOCK* processReport: from storage
DS-cd783a9c-7ef7-4c9e-a3ee-8da46e5caede node
DatanodeRegistration(127.0.0.1:43944,
datanodeUuid=d507fd8f-f35b-41a4-b081-2fb018116f0e, infoPort=45323,
infoSecurePort=0, ipcPort=42270,
storageInfo=lv=-56;cid=testClusterID;nsid=377495776;c=0), blocks: 0,
hasStaleStorage: false, processing time: 0 msecs
[exec] 2015-05-24 14:24:37,987 INFO datanode.DataNode
(BPServiceActor.java:blockReport(490)) - Successfully sent block report
0x3532f93575bf9d1c, 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
30 msecs for RPC and NN processing. Got back one command: FinalizeCommand/5.
[exec] 2015-05-24 14:24:37,987 INFO datanode.DataNode
(BPOfferService.java:processCommandFromActive(693)) - Got finalize command for
block pool BP-2084748531-67.195.81.149-1432477475599
[exec] 2015-05-24 14:24:38,038 INFO hdfs.MiniDFSCluster
(MiniDFSCluster.java:waitActive(2299)) - Cluster is active
[exec] 2015-05-24 14:24:38,042 INFO hdfs.MiniDFSCluster
(MiniDFSCluster.java:shutdown(1728)) - Shutting down the Mini HDFS Cluster
[exec] 2015-05-24 14:24:38,042 INFO hdfs.MiniDFSCluster
(MiniDFSCluster.java:shutdownDataNodes(1773)) - Shutting down DataNode 0
[exec] 2015-05-24 14:24:38,043 WARN datanode.DirectoryScanner
(DirectoryScanner.java:shutdown(379)) - DirectoryScanner: shutdown has been
called
[exec] 2015-05-24 14:24:38,043 INFO datanode.DataNode
(DataXceiverServer.java:closeAllPeers(263)) - Closing all peers.
[exec] 2015-05-24 14:24:38,046 INFO mortbay.log (Slf4jLog.java:info(67))
- Stopped SelectChannelConnector@localhost:0
[exec] 2015-05-24 14:24:38,156 INFO ipc.Server (Server.java:stop(2569)) -
Stopping server on 42270
[exec] 2015-05-24 14:24:38,158 INFO ipc.Server (Server.java:run(724)) -
Stopping IPC Server listener on 42270
[exec] 2015-05-24 14:24:38,158 INFO ipc.Server (Server.java:run(857)) -
Stopping IPC Server Responder
[exec] 2015-05-24 14:24:38,158 WARN datanode.DataNode
(BPServiceActor.java:offerService(701)) - BPOfferService for Block pool
BP-2084748531-67.195.81.149-1432477475599 (Datanode Uuid
d507fd8f-f35b-41a4-b081-2fb018116f0e) service to localhost/127.0.0.1:34418
interrupted
[exec] 2015-05-24 14:24:38,160 WARN datanode.DataNode
(BPServiceActor.java:run(831)) - Ending block pool service for: Block pool
BP-2084748531-67.195.81.149-1432477475599 (Datanode Uuid
d507fd8f-f35b-41a4-b081-2fb018116f0e) service to localhost/127.0.0.1:34418
[exec] 2015-05-24 14:24:38,263 INFO datanode.DataNode
(BlockPoolManager.java:remove(102)) - Removed Block pool
BP-2084748531-67.195.81.149-1432477475599 (Datanode Uuid
d507fd8f-f35b-41a4-b081-2fb018116f0e)
[exec] 2015-05-24 14:24:38,263 INFO impl.FsDatasetImpl
(FsDatasetImpl.java:shutdownBlockPool(2545)) - Removing block pool
BP-2084748531-67.195.81.149-1432477475599
[exec] 2015-05-24 14:24:38,264 INFO impl.FsDatasetAsyncDiskService
(FsDatasetAsyncDiskService.java:shutdown(183)) - Shutting down all async disk
service threads
[exec] 2015-05-24 14:24:38,265 INFO impl.FsDatasetAsyncDiskService
(FsDatasetAsyncDiskService.java:shutdown(191)) - All async disk service threads
have been shut down
[exec] 2015-05-24 14:24:38,265 INFO impl.RamDiskAsyncLazyPersistService
(RamDiskAsyncLazyPersistService.java:shutdown(165)) - Shutting down all async
lazy persist service threads
[exec] 2015-05-24 14:24:38,265 INFO impl.RamDiskAsyncLazyPersistService
(RamDiskAsyncLazyPersistService.java:shutdown(172)) - All async lazy persist
service threads have been shut down
[exec] 2015-05-24 14:24:38,269 INFO datanode.DataNode
(DataNode.java:shutdown(1821)) - Shutdown complete.
[exec] 2015-05-24 14:24:38,270 INFO namenode.FSNamesystem
(FSNamesystem.java:stopActiveServices(1219)) - Stopping services started for
active state
[exec] 2015-05-24 14:24:38,270 INFO namenode.FSEditLog
(FSEditLog.java:endCurrentLogSegment(1291)) - Ending log segment 1
[exec] 2015-05-24 14:24:38,271 INFO namenode.FSNamesystem
(FSNamesystem.java:run(4205)) - LazyPersistFileScrubber was interrupted, exiting
[exec] 2015-05-24 14:24:38,272 INFO namenode.FSNamesystem
(FSNamesystem.java:run(4125)) - NameNodeEditLogRoller was interrupted, exiting
[exec] 2015-05-24 14:24:38,273 INFO namenode.FSEditLog
(FSEditLog.java:printStatistics(698)) - Number of transactions: 2 Total time
for transactions(ms): 1 Number of transactions batched in Syncs: 0 Number of
syncs: 3 SyncTimes(ms): 4 1
[exec] 2015-05-24 14:24:38,275 INFO namenode.FileJournalManager
(FileJournalManager.java:finalizeLogSegment(134)) - Finalizing edits file
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/name1/current/edits_inprogress_0000000000000000001>
->
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/name1/current/edits_0000000000000000001-0000000000000000002>
[exec] 2015-05-24 14:24:38,276 INFO namenode.FileJournalManager
(FileJournalManager.java:finalizeLogSegment(134)) - Finalizing edits file
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/name2/current/edits_inprogress_0000000000000000001>
->
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/native/build/test/data/dfs/name2/current/edits_0000000000000000001-0000000000000000002>
[exec] 2015-05-24 14:24:38,277 INFO ipc.Server (Server.java:stop(2569)) -
Stopping server on 34418
[exec] 2015-05-24 14:24:38,278 INFO ipc.Server (Server.java:run(724)) -
Stopping IPC Server listener on 34418
[exec] 2015-05-24 14:24:38,278 INFO blockmanagement.BlockManager
(BlockManager.java:run(3693)) - Stopping ReplicationMonitor.
[exec] 2015-05-24 14:24:38,279 INFO ipc.Server (Server.java:run(857)) -
Stopping IPC Server Responder
[exec] 2015-05-24 14:24:38,308 INFO namenode.FSNamesystem
(FSNamesystem.java:stopActiveServices(1219)) - Stopping services started for
active state
[exec] 2015-05-24 14:24:38,308 INFO namenode.FSNamesystem
(FSNamesystem.java:stopStandbyServices(1309)) - Stopping services started for
standby state
[exec] 2015-05-24 14:24:38,310 INFO mortbay.log (Slf4jLog.java:info(67))
- Stopped SelectChannelConnector@localhost:0
[exec] 2015-05-24 14:24:38,411 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stop(211)) - Stopping DataNode metrics system...
[exec] 2015-05-24 14:24:38,412 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:stop(217)) - DataNode metrics system stopped.
[exec] 2015-05-24 14:24:38,412 INFO impl.MetricsSystemImpl
(MetricsSystemImpl.java:shutdown(601)) - DataNode metrics system shutdown
complete.
[echo] Finished test_native_mini_dfs
[INFO] Executed tasks
[INFO]
[INFO] --- maven-jar-plugin:2.5:jar (prepare-jar) @ hadoop-hdfs ---
[INFO] Building jar:
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/hadoop-hdfs-3.0.0-SNAPSHOT.jar>
[INFO]
[INFO] --- maven-jar-plugin:2.5:test-jar (prepare-test-jar) @ hadoop-hdfs ---
[INFO] Building jar:
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/hadoop-hdfs-3.0.0-SNAPSHOT-tests.jar>
[INFO]
[INFO] >>> maven-source-plugin:2.3:jar (default) @ hadoop-hdfs >>>
[INFO]
[INFO] --- maven-antrun-plugin:1.7:run (create-testdirs) @ hadoop-hdfs ---
[INFO] Executing tasks
main:
[INFO] Executed tasks
[INFO]
[INFO] --- hadoop-maven-plugins:3.0.0-SNAPSHOT:protoc (compile-protoc) @
hadoop-hdfs ---
[INFO]
[INFO] <<< maven-source-plugin:2.3:jar (default) @ hadoop-hdfs <<<
[INFO]
[INFO] --- maven-source-plugin:2.3:jar (default) @ hadoop-hdfs ---
[INFO] Building jar:
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/hadoop-hdfs-3.0.0-SNAPSHOT-sources.jar>
[INFO]
[INFO] >>> maven-source-plugin:2.3:test-jar (default) @ hadoop-hdfs >>>
[INFO]
[INFO] --- maven-antrun-plugin:1.7:run (create-testdirs) @ hadoop-hdfs ---
[INFO] Executing tasks
main:
[INFO] Executed tasks
[INFO]
[INFO] --- hadoop-maven-plugins:3.0.0-SNAPSHOT:protoc (compile-protoc) @
hadoop-hdfs ---
[INFO]
[INFO] <<< maven-source-plugin:2.3:test-jar (default) @ hadoop-hdfs <<<
[INFO]
[INFO] --- maven-source-plugin:2.3:test-jar (default) @ hadoop-hdfs ---
[INFO] Building jar:
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/hadoop-hdfs-3.0.0-SNAPSHOT-test-sources.jar>
[INFO]
[INFO] --- findbugs-maven-plugin:3.0.0:findbugs (default) @ hadoop-hdfs ---
[INFO] Fork Value is true
[java] Warnings generated: 1
[INFO] Done FindBugs Analysis....
[INFO]
[INFO] --- maven-dependency-plugin:2.2:copy (site) @ hadoop-hdfs ---
[INFO] Configured Artifact: jdiff:jdiff:1.0.9:jar
[INFO] Configured Artifact:
org.apache.hadoop:hadoop-annotations:3.0.0-SNAPSHOT:jar
[INFO] Configured Artifact: xerces:xercesImpl:2.11.0:jar
[INFO] Copying jdiff-1.0.9.jar to
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/jdiff.jar>
[INFO] Copying hadoop-annotations-3.0.0-SNAPSHOT.jar to
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/hadoop-annotations.jar>
[INFO] Copying xercesImpl-2.11.0.jar to
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/xerces.jar>
[INFO]
[INFO] --- maven-antrun-plugin:1.7:run (site) @ hadoop-hdfs ---
[INFO] Executing tasks
main:
[mkdir] Created dir:
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/docs-src>
[INFO]
[INFO] ------------------------------------------------------------------------
[INFO] Skipping Apache Hadoop HttpFS
[INFO] This project has been banned from the build due to previous failures.
[INFO] ------------------------------------------------------------------------
[INFO]
[INFO] ------------------------------------------------------------------------
[INFO] Skipping Apache Hadoop HDFS BookKeeper Journal
[INFO] This project has been banned from the build due to previous failures.
[INFO] ------------------------------------------------------------------------
[INFO]
[INFO] ------------------------------------------------------------------------
[INFO] Skipping Apache Hadoop HDFS-NFS
[INFO] This project has been banned from the build due to previous failures.
[INFO] ------------------------------------------------------------------------
[INFO]
[INFO] ------------------------------------------------------------------------
[INFO] Building Apache Hadoop HDFS Project 3.0.0-SNAPSHOT
[INFO] ------------------------------------------------------------------------
[INFO]
[INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ hadoop-hdfs-project
---
[INFO] Deleting
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/target>
[INFO]
[INFO] --- maven-antrun-plugin:1.7:run (create-testdirs) @ hadoop-hdfs-project
---
[INFO] Executing tasks
main:
[mkdir] Created dir:
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/target/test-dir>
[INFO] Executed tasks
[INFO]
[INFO] --- maven-source-plugin:2.3:jar-no-fork (hadoop-java-sources) @
hadoop-hdfs-project ---
[INFO]
[INFO] --- maven-source-plugin:2.3:test-jar-no-fork (hadoop-java-sources) @
hadoop-hdfs-project ---
[INFO]
[INFO] --- maven-enforcer-plugin:1.3.1:enforce (dist-enforce) @
hadoop-hdfs-project ---
[INFO]
[INFO] --- maven-site-plugin:3.4:attach-descriptor (attach-descriptor) @
hadoop-hdfs-project ---
[INFO]
[INFO] --- maven-javadoc-plugin:2.8.1:jar (module-javadocs) @
hadoop-hdfs-project ---
[INFO] Not executing Javadoc as the project is not a Java classpath-capable
package
[INFO]
[INFO] --- maven-enforcer-plugin:1.3.1:enforce (depcheck) @ hadoop-hdfs-project
---
[INFO]
[INFO] --- maven-checkstyle-plugin:2.15:checkstyle (default-cli) @
hadoop-hdfs-project ---
[INFO]
[INFO] --- findbugs-maven-plugin:3.0.0:findbugs (default-cli) @
hadoop-hdfs-project ---
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Apache Hadoop HDFS Client ......................... SUCCESS [ 48.092 s]
[INFO] Apache Hadoop HDFS ................................ FAILURE [ 02:51 h]
[INFO] Apache Hadoop HttpFS .............................. SKIPPED
[INFO] Apache Hadoop HDFS BookKeeper Journal ............. SKIPPED
[INFO] Apache Hadoop HDFS-NFS ............................ SKIPPED
[INFO] Apache Hadoop HDFS Project ........................ SUCCESS [ 0.071 s]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 02:52 h
[INFO] Finished at: 2015-05-24T14:26:46+00:00
[INFO] Final Memory: 54M/164M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal
org.apache.maven.plugins:maven-antrun-plugin:1.7:run (site) on project
hadoop-hdfs: An Ant BuildException has occured:
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/src/main/docs>
does not exist.
[ERROR] around Ant part ...<copy
todir="<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/docs-src";>...>
@ 5:127 in
<https://builds.apache.org/job/Hadoop-Hdfs-trunk-Java8/ws/hadoop-hdfs-project/hadoop-hdfs/target/antrun/build-main.xml>
[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/MojoExecutionException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :hadoop-hdfs
Build step 'Execute shell' marked build as failure
Archiving artifacts
Sending artifact delta relative to Hadoop-Hdfs-trunk-Java8 #175
Archived 1 artifacts
Archive block size is 32768
Received 0 blocks and 797647 bytes
Compression is 0.0%
Took 11 sec
Recording test results
