See http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/5/changes
Changes:
[cdouglas] HDFS-447. Add LDAP lookup to hdfsproxy. Contributed by Zhiyong Zhang
[gkesavan] HADOOP-6112. Fix hudsonPatchQueueAdmin for different projects.
------------------------------------------
[...truncated 340149 lines...]
[junit] 2009-06-27 13:07:25,892 INFO common.Storage
(DataStorage.java:recoverTransitionRead(122)) - Storage directory
http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data1
is not formatted.
[junit] 2009-06-27 13:07:25,893 INFO common.Storage
(DataStorage.java:recoverTransitionRead(123)) - Formatting ...
[junit] 2009-06-27 13:07:26,103 INFO common.Storage
(DataStorage.java:recoverTransitionRead(122)) - Storage directory
http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data2
is not formatted.
[junit] 2009-06-27 13:07:26,104 INFO common.Storage
(DataStorage.java:recoverTransitionRead(123)) - Formatting ...
[junit] 2009-06-27 13:07:26,398 INFO datanode.DataNode
(FSDataset.java:registerMBean(1417)) - Registered FSDatasetStatusMBean
[junit] 2009-06-27 13:07:26,399 INFO datanode.DataNode
(DataNode.java:startDataNode(326)) - Opened info server at 40254
[junit] 2009-06-27 13:07:26,400 INFO datanode.DataNode
(DataXceiverServer.java:<init>(74)) - Balancing bandwith is 1048576 bytes/s
[junit] 2009-06-27 13:07:26,400 INFO datanode.DirectoryScanner
(DirectoryScanner.java:<init>(133)) - scan starts at 1246116836400 with
interval 21600000
[junit] 2009-06-27 13:07:26,402 INFO http.HttpServer
(HttpServer.java:start(425)) - Port returned by
webServer.getConnectors()[0].getLocalPort() before open() is -1. Opening the
listener on 0
[junit] 2009-06-27 13:07:26,402 INFO http.HttpServer
(HttpServer.java:start(430)) - listener.getLocalPort() returned 51178
webServer.getConnectors()[0].getLocalPort() returned 51178
[junit] 2009-06-27 13:07:26,402 INFO http.HttpServer
(HttpServer.java:start(463)) - Jetty bound to port 51178
[junit] 2009-06-27 13:07:26,403 INFO mortbay.log (?:invoke(?)) -
jetty-6.1.14
[junit] 2009-06-27 13:07:26,470 INFO mortbay.log (?:invoke(?)) - Started
selectchannelconnec...@localhost:51178
[junit] 2009-06-27 13:07:26,472 INFO jvm.JvmMetrics
(JvmMetrics.java:init(66)) - Cannot initialize JVM Metrics with
processName=DataNode, sessionId=null - already initialized
[junit] 2009-06-27 13:07:26,485 INFO metrics.RpcMetrics
(RpcMetrics.java:<init>(58)) - Initializing RPC Metrics with hostName=DataNode,
port=41449
[junit] 2009-06-27 13:07:26,486 INFO ipc.Server (Server.java:run(474)) -
IPC Server Responder: starting
[junit] 2009-06-27 13:07:26,486 INFO ipc.Server (Server.java:run(939)) -
IPC Server handler 0 on 41449: starting
[junit] 2009-06-27 13:07:26,486 INFO ipc.Server (Server.java:run(313)) -
IPC Server listener on 41449: starting
[junit] 2009-06-27 13:07:26,487 INFO ipc.Server (Server.java:run(939)) -
IPC Server handler 2 on 41449: starting
[junit] 2009-06-27 13:07:26,487 INFO datanode.DataNode
(DataNode.java:startDataNode(404)) - dnRegistration =
DatanodeRegistration(vesta.apache.org:40254, storageID=, infoPort=51178,
ipcPort=41449)
[junit] 2009-06-27 13:07:26,486 INFO ipc.Server (Server.java:run(939)) -
IPC Server handler 1 on 41449: starting
[junit] 2009-06-27 13:07:26,489 INFO hdfs.StateChange
(FSNamesystem.java:registerDatanode(1772)) - BLOCK*
NameSystem.registerDatanode: node registration from 127.0.0.1:40254 storage
DS-1022175986-67.195.138.9-40254-1246108046488
[junit] 2009-06-27 13:07:26,489 INFO net.NetworkTopology
(NetworkTopology.java:add(327)) - Adding a new node:
/default-rack/127.0.0.1:40254
[junit] 2009-06-27 13:07:26,532 INFO datanode.DataNode
(DataNode.java:register(566)) - New storage id
DS-1022175986-67.195.138.9-40254-1246108046488 is assigned to data-node
127.0.0.1:40254
[junit] 2009-06-27 13:07:26,533 INFO datanode.DataNode
(DataNode.java:run(1253)) - DatanodeRegistration(127.0.0.1:40254,
storageID=DS-1022175986-67.195.138.9-40254-1246108046488, infoPort=51178,
ipcPort=41449)In DataNode.run, data =
FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data1/current,/home/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/data/data2/current'}
[junit] Starting DataNode 1 with dfs.data.dir:
http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data3,/home/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/data/data4
[junit] 2009-06-27 13:07:26,533 INFO datanode.DataNode
(DataNode.java:offerService(734)) - using BLOCKREPORT_INTERVAL of 21600000msec
Initial delay: 0msec
[junit] 2009-06-27 13:07:26,543 INFO common.Storage
(DataStorage.java:recoverTransitionRead(122)) - Storage directory
http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data3
is not formatted.
[junit] 2009-06-27 13:07:26,544 INFO common.Storage
(DataStorage.java:recoverTransitionRead(123)) - Formatting ...
[junit] 2009-06-27 13:07:26,577 INFO datanode.DataNode
(DataNode.java:blockReport(969)) - BlockReport of 0 blocks got processed in 1
msecs
[junit] 2009-06-27 13:07:26,578 INFO datanode.DataNode
(DataNode.java:offerService(777)) - Starting Periodic block scanner.
[junit] 2009-06-27 13:07:26,726 INFO common.Storage
(DataStorage.java:recoverTransitionRead(122)) - Storage directory
http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data4
is not formatted.
[junit] 2009-06-27 13:07:26,727 INFO common.Storage
(DataStorage.java:recoverTransitionRead(123)) - Formatting ...
[junit] 2009-06-27 13:07:26,777 INFO mapred.JvmManager
(JvmManager.java:runChild(411)) - JVM : jvm_200906271305_0004_m_-988429809
exited. Number of tasks it ran: 1
[junit] 2009-06-27 13:07:27,009 INFO datanode.DataNode
(FSDataset.java:registerMBean(1417)) - Registered FSDatasetStatusMBean
[junit] 2009-06-27 13:07:27,010 INFO datanode.DataNode
(DataNode.java:startDataNode(326)) - Opened info server at 51104
[junit] 2009-06-27 13:07:27,010 INFO datanode.DataNode
(DataXceiverServer.java:<init>(74)) - Balancing bandwith is 1048576 bytes/s
[junit] 2009-06-27 13:07:27,010 INFO datanode.DirectoryScanner
(DirectoryScanner.java:<init>(133)) - scan starts at 1246116914010 with
interval 21600000
[junit] 2009-06-27 13:07:27,012 INFO http.HttpServer
(HttpServer.java:start(425)) - Port returned by
webServer.getConnectors()[0].getLocalPort() before open() is -1. Opening the
listener on 0
[junit] 2009-06-27 13:07:27,012 INFO http.HttpServer
(HttpServer.java:start(430)) - listener.getLocalPort() returned 56048
webServer.getConnectors()[0].getLocalPort() returned 56048
[junit] 2009-06-27 13:07:27,012 INFO http.HttpServer
(HttpServer.java:start(463)) - Jetty bound to port 56048
[junit] 2009-06-27 13:07:27,012 INFO mortbay.log (?:invoke(?)) -
jetty-6.1.14
[junit] 2009-06-27 13:07:27,075 INFO mortbay.log (?:invoke(?)) - Started
selectchannelconnec...@localhost:56048
[junit] 2009-06-27 13:07:27,075 INFO jvm.JvmMetrics
(JvmMetrics.java:init(66)) - Cannot initialize JVM Metrics with
processName=DataNode, sessionId=null - already initialized
[junit] 2009-06-27 13:07:27,087 INFO metrics.RpcMetrics
(RpcMetrics.java:<init>(58)) - Initializing RPC Metrics with hostName=DataNode,
port=55376
[junit] 2009-06-27 13:07:27,088 INFO ipc.Server (Server.java:run(474)) -
IPC Server Responder: starting
[junit] 2009-06-27 13:07:27,088 INFO ipc.Server (Server.java:run(939)) -
IPC Server handler 1 on 55376: starting
[junit] 2009-06-27 13:07:27,088 INFO ipc.Server (Server.java:run(939)) -
IPC Server handler 0 on 55376: starting
[junit] 2009-06-27 13:07:27,088 INFO ipc.Server (Server.java:run(313)) -
IPC Server listener on 55376: starting
[junit] 2009-06-27 13:07:27,089 INFO datanode.DataNode
(DataNode.java:startDataNode(404)) - dnRegistration =
DatanodeRegistration(vesta.apache.org:51104, storageID=, infoPort=56048,
ipcPort=55376)
[junit] 2009-06-27 13:07:27,088 INFO ipc.Server (Server.java:run(939)) -
IPC Server handler 2 on 55376: starting
[junit] 2009-06-27 13:07:27,090 INFO hdfs.StateChange
(FSNamesystem.java:registerDatanode(1772)) - BLOCK*
NameSystem.registerDatanode: node registration from 127.0.0.1:51104 storage
DS-691879620-67.195.138.9-51104-1246108047090
[junit] 2009-06-27 13:07:27,091 INFO net.NetworkTopology
(NetworkTopology.java:add(327)) - Adding a new node:
/default-rack/127.0.0.1:51104
[junit] 2009-06-27 13:07:27,141 INFO datanode.DataNode
(DataNode.java:register(566)) - New storage id
DS-691879620-67.195.138.9-51104-1246108047090 is assigned to data-node
127.0.0.1:51104
[junit] 2009-06-27 13:07:27,142 INFO datanode.DataNode
(DataNode.java:run(1253)) - DatanodeRegistration(127.0.0.1:51104,
storageID=DS-691879620-67.195.138.9-51104-1246108047090, infoPort=56048,
ipcPort=55376)In DataNode.run, data =
FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data3/current,/home/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/data/data4/current'}
[junit] Starting DataNode 2 with dfs.data.dir:
http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data5,/home/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/data/data6
[junit] 2009-06-27 13:07:27,143 INFO datanode.DataNode
(DataNode.java:offerService(734)) - using BLOCKREPORT_INTERVAL of 21600000msec
Initial delay: 0msec
[junit] 2009-06-27 13:07:27,153 INFO common.Storage
(DataStorage.java:recoverTransitionRead(122)) - Storage directory
http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data5
is not formatted.
[junit] 2009-06-27 13:07:27,154 INFO common.Storage
(DataStorage.java:recoverTransitionRead(123)) - Formatting ...
[junit] 2009-06-27 13:07:27,184 INFO datanode.DataNode
(DataNode.java:blockReport(969)) - BlockReport of 0 blocks got processed in 1
msecs
[junit] 2009-06-27 13:07:27,184 INFO datanode.DataNode
(DataNode.java:offerService(777)) - Starting Periodic block scanner.
[junit] 2009-06-27 13:07:27,353 INFO common.Storage
(DataStorage.java:recoverTransitionRead(122)) - Storage directory
http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data6
is not formatted.
[junit] 2009-06-27 13:07:27,353 INFO common.Storage
(DataStorage.java:recoverTransitionRead(123)) - Formatting ...
[junit] 2009-06-27 13:07:27,614 INFO datanode.DataNode
(FSDataset.java:registerMBean(1417)) - Registered FSDatasetStatusMBean
[junit] 2009-06-27 13:07:27,615 INFO datanode.DataNode
(DataNode.java:startDataNode(326)) - Opened info server at 40626
[junit] 2009-06-27 13:07:27,615 INFO datanode.DataNode
(DataXceiverServer.java:<init>(74)) - Balancing bandwith is 1048576 bytes/s
[junit] 2009-06-27 13:07:27,615 INFO datanode.DirectoryScanner
(DirectoryScanner.java:<init>(133)) - scan starts at 1246125532615 with
interval 21600000
[junit] 2009-06-27 13:07:27,617 INFO http.HttpServer
(HttpServer.java:start(425)) - Port returned by
webServer.getConnectors()[0].getLocalPort() before open() is -1. Opening the
listener on 0
[junit] 2009-06-27 13:07:27,617 INFO http.HttpServer
(HttpServer.java:start(430)) - listener.getLocalPort() returned 36687
webServer.getConnectors()[0].getLocalPort() returned 36687
[junit] 2009-06-27 13:07:27,617 INFO http.HttpServer
(HttpServer.java:start(463)) - Jetty bound to port 36687
[junit] 2009-06-27 13:07:27,617 INFO mortbay.log (?:invoke(?)) -
jetty-6.1.14
[junit] 2009-06-27 13:07:27,684 INFO mortbay.log (?:invoke(?)) - Started
selectchannelconnec...@localhost:36687
[junit] 2009-06-27 13:07:27,687 INFO jvm.JvmMetrics
(JvmMetrics.java:init(66)) - Cannot initialize JVM Metrics with
processName=DataNode, sessionId=null - already initialized
[junit] 2009-06-27 13:07:27,698 INFO metrics.RpcMetrics
(RpcMetrics.java:<init>(58)) - Initializing RPC Metrics with hostName=DataNode,
port=43831
[junit] 2009-06-27 13:07:27,699 INFO ipc.Server (Server.java:run(474)) -
IPC Server Responder: starting
[junit] 2009-06-27 13:07:27,699 INFO ipc.Server (Server.java:run(939)) -
IPC Server handler 1 on 43831: starting
[junit] 2009-06-27 13:07:27,700 INFO datanode.DataNode
(DataNode.java:startDataNode(404)) - dnRegistration =
DatanodeRegistration(vesta.apache.org:40626, storageID=, infoPort=36687,
ipcPort=43831)
[junit] 2009-06-27 13:07:27,700 INFO ipc.Server (Server.java:run(939)) -
IPC Server handler 2 on 43831: starting
[junit] 2009-06-27 13:07:27,699 INFO ipc.Server (Server.java:run(939)) -
IPC Server handler 0 on 43831: starting
[junit] 2009-06-27 13:07:27,699 INFO ipc.Server (Server.java:run(313)) -
IPC Server listener on 43831: starting
[junit] 2009-06-27 13:07:27,701 INFO hdfs.StateChange
(FSNamesystem.java:registerDatanode(1772)) - BLOCK*
NameSystem.registerDatanode: node registration from 127.0.0.1:40626 storage
DS-1353494496-67.195.138.9-40626-1246108047701
[junit] 2009-06-27 13:07:27,701 INFO net.NetworkTopology
(NetworkTopology.java:add(327)) - Adding a new node:
/default-rack/127.0.0.1:40626
[junit] 2009-06-27 13:07:27,771 INFO datanode.DataNode
(DataNode.java:register(566)) - New storage id
DS-1353494496-67.195.138.9-40626-1246108047701 is assigned to data-node
127.0.0.1:40626
[junit] Starting DataNode 3 with dfs.data.dir:
http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data7,/home/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/data/data8
[junit] 2009-06-27 13:07:27,771 INFO datanode.DataNode
(DataNode.java:run(1253)) - DatanodeRegistration(127.0.0.1:40626,
storageID=DS-1353494496-67.195.138.9-40626-1246108047701, infoPort=36687,
ipcPort=43831)In DataNode.run, data =
FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data5/current,/home/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/data/data6/current'}
[junit] 2009-06-27 13:07:27,772 INFO datanode.DataNode
(DataNode.java:offerService(734)) - using BLOCKREPORT_INTERVAL of 21600000msec
Initial delay: 0msec
[junit] 2009-06-27 13:07:27,781 INFO common.Storage
(DataStorage.java:recoverTransitionRead(122)) - Storage directory
http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data7
is not formatted.
[junit] 2009-06-27 13:07:27,782 INFO common.Storage
(DataStorage.java:recoverTransitionRead(123)) - Formatting ...
[junit] 2009-06-27 13:07:27,809 INFO datanode.DataNode
(DataNode.java:blockReport(969)) - BlockReport of 0 blocks got processed in 1
msecs
[junit] 2009-06-27 13:07:27,809 INFO datanode.DataNode
(DataNode.java:offerService(777)) - Starting Periodic block scanner.
[junit] 2009-06-27 13:07:27,966 INFO common.Storage
(DataStorage.java:recoverTransitionRead(122)) - Storage directory
http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data8
is not formatted.
[junit] 2009-06-27 13:07:27,967 INFO common.Storage
(DataStorage.java:recoverTransitionRead(123)) - Formatting ...
[junit] 2009-06-27 13:07:28,240 INFO datanode.DataNode
(FSDataset.java:registerMBean(1417)) - Registered FSDatasetStatusMBean
[junit] 2009-06-27 13:07:28,241 INFO datanode.DataNode
(DataNode.java:startDataNode(326)) - Opened info server at 37133
[junit] 2009-06-27 13:07:28,254 INFO datanode.DataNode
(DataXceiverServer.java:<init>(74)) - Balancing bandwith is 1048576 bytes/s
[junit] 2009-06-27 13:07:28,255 INFO datanode.DirectoryScanner
(DirectoryScanner.java:<init>(133)) - scan starts at 1246124967255 with
interval 21600000
[junit] 2009-06-27 13:07:28,256 INFO http.HttpServer
(HttpServer.java:start(425)) - Port returned by
webServer.getConnectors()[0].getLocalPort() before open() is -1. Opening the
listener on 0
[junit] 2009-06-27 13:07:28,257 INFO http.HttpServer
(HttpServer.java:start(430)) - listener.getLocalPort() returned 48805
webServer.getConnectors()[0].getLocalPort() returned 48805
[junit] 2009-06-27 13:07:28,257 INFO http.HttpServer
(HttpServer.java:start(463)) - Jetty bound to port 48805
[junit] 2009-06-27 13:07:28,257 INFO mortbay.log (?:invoke(?)) -
jetty-6.1.14
[junit] 2009-06-27 13:07:28,320 INFO mortbay.log (?:invoke(?)) - Started
selectchannelconnec...@localhost:48805
[junit] 2009-06-27 13:07:28,321 INFO jvm.JvmMetrics
(JvmMetrics.java:init(66)) - Cannot initialize JVM Metrics with
processName=DataNode, sessionId=null - already initialized
[junit] 2009-06-27 13:07:28,333 INFO metrics.RpcMetrics
(RpcMetrics.java:<init>(58)) - Initializing RPC Metrics with hostName=DataNode,
port=40999
[junit] 2009-06-27 13:07:28,333 INFO ipc.Server (Server.java:run(474)) -
IPC Server Responder: starting
[junit] 2009-06-27 13:07:28,334 INFO ipc.Server (Server.java:run(939)) -
IPC Server handler 2 on 40999: starting
[junit] 2009-06-27 13:07:28,334 INFO ipc.Server (Server.java:run(939)) -
IPC Server handler 1 on 40999: starting
[junit] 2009-06-27 13:07:28,334 INFO ipc.Server (Server.java:run(939)) -
IPC Server handler 0 on 40999: starting
[junit] 2009-06-27 13:07:28,333 INFO ipc.Server (Server.java:run(313)) -
IPC Server listener on 40999: starting
[junit] 2009-06-27 13:07:28,334 INFO datanode.DataNode
(DataNode.java:startDataNode(404)) - dnRegistration =
DatanodeRegistration(vesta.apache.org:37133, storageID=, infoPort=48805,
ipcPort=40999)
[junit] 2009-06-27 13:07:28,336 INFO hdfs.StateChange
(FSNamesystem.java:registerDatanode(1772)) - BLOCK*
NameSystem.registerDatanode: node registration from 127.0.0.1:37133 storage
DS-932181558-67.195.138.9-37133-1246108048336
[junit] 2009-06-27 13:07:28,336 INFO net.NetworkTopology
(NetworkTopology.java:add(327)) - Adding a new node:
/default-rack/127.0.0.1:37133
[junit] 2009-06-27 13:07:28,382 INFO datanode.DataNode
(DataNode.java:register(566)) - New storage id
DS-932181558-67.195.138.9-37133-1246108048336 is assigned to data-node
127.0.0.1:37133
[junit] 2009-06-27 13:07:28,382 INFO datanode.DataNode
(DataNode.java:run(1253)) - DatanodeRegistration(127.0.0.1:37133,
storageID=DS-932181558-67.195.138.9-37133-1246108048336, infoPort=48805,
ipcPort=40999)In DataNode.run, data =
FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data7/current,/home/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/data/data8/current'}
[junit] 2009-06-27 13:07:28,464 INFO datanode.DataNode
(DataNode.java:offerService(734)) - using BLOCKREPORT_INTERVAL of 21600000msec
Initial delay: 0msec
[junit] 2009-06-27 13:07:28,510 INFO datanode.DataNode
(DataNode.java:blockReport(969)) - BlockReport of 0 blocks got processed in 1
msecs
[junit] 2009-06-27 13:07:28,510 INFO datanode.DataNode
(DataNode.java:offerService(777)) - Starting Periodic block scanner.
[junit]
<property><name>security.client.protocol.acl</name><value>*</value></property>
[junit]
[junit]
<property><name>security.client.datanode.protocol.acl</name><value>*</value></property>
[junit]
[junit]
<property><name>security.datanode.protocol.acl</name><value>*</value></property>
[junit]
[junit]
<property><name>security.inter.datanode.protocol.acl</name><value>*</value></property>
[junit]
[junit]
<property><name>security.namenode.protocol.acl</name><value>*</value></property>
[junit]
[junit]
<property><name>security.refresh.policy.protocol.acl</name><value>nouser
nogroup</value></property>
[junit]
[junit] 2009-06-27 13:07:28,544 INFO authorize.ServiceAuthorizationManager
(ServiceAuthorizationManager.java:run(92)) - Authorization failed for dev,null
[junit] java.security.AccessControlException: access denied
ConnectionPermission(org.apache.hadoop.security.authorize.RefreshAuthorizationPolicyProtocol)
[junit] at
java.security.AccessControlContext.checkPermission(AccessControlContext.java:323)
[junit] at
java.security.AccessController.checkPermission(AccessController.java:546)
[junit] at
org.apache.hadoop.security.authorize.ServiceAuthorizationManager$1.run(ServiceAuthorizationManager.java:89)
[junit] at
org.apache.hadoop.security.authorize.ServiceAuthorizationManager$1.run(ServiceAuthorizationManager.java:84)
[junit] at java.security.AccessController.doPrivileged(Native Method)
[junit] at javax.security.auth.Subject.doAs(Subject.java:396)
[junit] at
org.apache.hadoop.security.authorize.ServiceAuthorizationManager.checkPermission(ServiceAuthorizationManager.java:83)
[junit] at
org.apache.hadoop.security.authorize.ServiceAuthorizationManager.authorize(ServiceAuthorizationManager.java:68)
[junit] at org.apache.hadoop.ipc.RPC$Server.authorize(RPC.java:565)
[junit] at
org.apache.hadoop.ipc.Server$Connection.readAndProcess(Server.java:858)
[junit] at org.apache.hadoop.ipc.Server$Listener.doRead(Server.java:420)
[junit] at org.apache.hadoop.ipc.Server$Listener.run(Server.java:328)
[junit] Good, refresh worked... refresh failed with:
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 3
[junit] 2009-06-27 13:07:28,649 INFO ipc.Server (Server.java:stop(1103)) -
Stopping server on 40999
[junit] 2009-06-27 13:07:28,649 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 0 on 40999: exiting
[junit] 2009-06-27 13:07:28,650 INFO ipc.Server (Server.java:run(352)) -
Stopping IPC Server listener on 40999
[junit] 2009-06-27 13:07:28,650 WARN datanode.DataNode
(DataXceiverServer.java:run(137)) - DatanodeRegistration(127.0.0.1:37133,
storageID=DS-932181558-67.195.138.9-37133-1246108048336, infoPort=48805,
ipcPort=40999):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at
java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at
sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at
sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2009-06-27 13:07:28,649 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 1 on 40999: exiting
[junit] 2009-06-27 13:07:28,649 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 2 on 40999: exiting
[junit] 2009-06-27 13:07:28,650 INFO datanode.DataNode
(DataNode.java:shutdown(638)) - Waiting for threadgroup to exit, active threads
is 1
[junit] 2009-06-27 13:07:28,650 INFO ipc.Server (Server.java:run(539)) -
Stopping IPC Server Responder
[junit] 2009-06-27 13:07:28,652 INFO datanode.DataBlockScanner
(DataBlockScanner.java:run(616)) - Exiting DataBlockScanner thread.
[junit] 2009-06-27 13:07:28,652 INFO datanode.DataNode
(DataNode.java:run(1273)) - DatanodeRegistration(127.0.0.1:37133,
storageID=DS-932181558-67.195.138.9-37133-1246108048336, infoPort=48805,
ipcPort=40999):Finishing DataNode in:
FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data7/current,/home/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/data/data8/current'}
[junit] 2009-06-27 13:07:28,653 INFO ipc.Server (Server.java:stop(1103)) -
Stopping server on 40999
[junit] 2009-06-27 13:07:28,653 INFO datanode.DataNode
(DataNode.java:shutdown(638)) - Waiting for threadgroup to exit, active threads
is 0
[junit] Shutting down DataNode 2
[junit] 2009-06-27 13:07:28,755 INFO ipc.Server (Server.java:stop(1103)) -
Stopping server on 43831
[junit] 2009-06-27 13:07:28,755 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 0 on 43831: exiting
[junit] 2009-06-27 13:07:28,756 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 2 on 43831: exiting
[junit] 2009-06-27 13:07:28,756 INFO ipc.Server (Server.java:run(352)) -
Stopping IPC Server listener on 43831
[junit] 2009-06-27 13:07:28,757 INFO datanode.DataNode
(DataNode.java:shutdown(638)) - Waiting for threadgroup to exit, active threads
is 1
[junit] 2009-06-27 13:07:28,757 INFO ipc.Server (Server.java:run(539)) -
Stopping IPC Server Responder
[junit] 2009-06-27 13:07:28,757 WARN datanode.DataNode
(DataXceiverServer.java:run(137)) - DatanodeRegistration(127.0.0.1:40626,
storageID=DS-1353494496-67.195.138.9-40626-1246108047701, infoPort=36687,
ipcPort=43831):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at
java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at
sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at
sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2009-06-27 13:07:28,757 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 1 on 43831: exiting
[junit] 2009-06-27 13:07:28,759 INFO datanode.DataNode
(DataNode.java:shutdown(638)) - Waiting for threadgroup to exit, active threads
is 0
[junit] 2009-06-27 13:07:28,759 INFO datanode.DataBlockScanner
(DataBlockScanner.java:run(616)) - Exiting DataBlockScanner thread.
[junit] 2009-06-27 13:07:28,760 INFO datanode.DataNode
(DataNode.java:run(1273)) - DatanodeRegistration(127.0.0.1:40626,
storageID=DS-1353494496-67.195.138.9-40626-1246108047701, infoPort=36687,
ipcPort=43831):Finishing DataNode in:
FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data5/current,/home/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/data/data6/current'}
[junit] 2009-06-27 13:07:28,760 INFO ipc.Server (Server.java:stop(1103)) -
Stopping server on 43831
[junit] 2009-06-27 13:07:28,760 INFO datanode.DataNode
(DataNode.java:shutdown(638)) - Waiting for threadgroup to exit, active threads
is 0
[junit] Shutting down DataNode 1
[junit] 2009-06-27 13:07:28,863 INFO ipc.Server (Server.java:stop(1103)) -
Stopping server on 55376
[junit] 2009-06-27 13:07:28,863 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 0 on 55376: exiting
[junit] 2009-06-27 13:07:28,863 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 1 on 55376: exiting
[junit] 2009-06-27 13:07:28,864 INFO ipc.Server (Server.java:run(352)) -
Stopping IPC Server listener on 55376
[junit] 2009-06-27 13:07:28,864 INFO ipc.Server (Server.java:run(539)) -
Stopping IPC Server Responder
[junit] 2009-06-27 13:07:28,864 WARN datanode.DataNode
(DataXceiverServer.java:run(137)) - DatanodeRegistration(127.0.0.1:51104,
storageID=DS-691879620-67.195.138.9-51104-1246108047090, infoPort=56048,
ipcPort=55376):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at
java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at
sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at
sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2009-06-27 13:07:28,864 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 2 on 55376: exiting
[junit] 2009-06-27 13:07:28,864 INFO datanode.DataNode
(DataNode.java:shutdown(638)) - Waiting for threadgroup to exit, active threads
is 1
[junit] 2009-06-27 13:07:28,865 INFO datanode.DataBlockScanner
(DataBlockScanner.java:run(616)) - Exiting DataBlockScanner thread.
[junit] 2009-06-27 13:07:28,865 INFO datanode.DataNode
(DataNode.java:run(1273)) - DatanodeRegistration(127.0.0.1:51104,
storageID=DS-691879620-67.195.138.9-51104-1246108047090, infoPort=56048,
ipcPort=55376):Finishing DataNode in:
FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data3/current,/home/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/data/data4/current'}
[junit] 2009-06-27 13:07:28,865 INFO ipc.Server (Server.java:stop(1103)) -
Stopping server on 55376
[junit] 2009-06-27 13:07:28,866 INFO datanode.DataNode
(DataNode.java:shutdown(638)) - Waiting for threadgroup to exit, active threads
is 0
[junit] Shutting down DataNode 0
[junit] 2009-06-27 13:07:28,968 INFO ipc.Server (Server.java:stop(1103)) -
Stopping server on 41449
[junit] 2009-06-27 13:07:28,968 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 0 on 41449: exiting
[junit] 2009-06-27 13:07:28,968 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 2 on 41449: exiting
[junit] 2009-06-27 13:07:28,969 WARN datanode.DataNode
(DataXceiverServer.java:run(137)) - DatanodeRegistration(127.0.0.1:40254,
storageID=DS-1022175986-67.195.138.9-40254-1246108046488, infoPort=51178,
ipcPort=41449):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at
java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at
sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at
sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2009-06-27 13:07:28,969 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 1 on 41449: exiting
[junit] 2009-06-27 13:07:28,969 INFO ipc.Server (Server.java:run(539)) -
Stopping IPC Server Responder
[junit] 2009-06-27 13:07:28,969 INFO datanode.DataNode
(DataNode.java:shutdown(638)) - Waiting for threadgroup to exit, active threads
is 1
[junit] 2009-06-27 13:07:28,969 INFO ipc.Server (Server.java:run(352)) -
Stopping IPC Server listener on 41449
[junit] 2009-06-27 13:07:28,970 INFO datanode.DataBlockScanner
(DataBlockScanner.java:run(616)) - Exiting DataBlockScanner thread.
[junit] 2009-06-27 13:07:28,971 INFO datanode.DataNode
(DataNode.java:run(1273)) - DatanodeRegistration(127.0.0.1:40254,
storageID=DS-1022175986-67.195.138.9-40254-1246108046488, infoPort=51178,
ipcPort=41449):Finishing DataNode in:
FSDataset{dirpath='http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build/test/data/dfs/data/data1/current,/home/hudson/hudson-slave/workspace/Hadoop-Hdfs-trunk/trunk/build/test/data/dfs/data/data2/current'}
[junit] 2009-06-27 13:07:28,971 INFO ipc.Server (Server.java:stop(1103)) -
Stopping server on 41449
[junit] 2009-06-27 13:07:28,971 INFO datanode.DataNode
(DataNode.java:shutdown(638)) - Waiting for threadgroup to exit, active threads
is 0
[junit] 2009-06-27 13:07:29,073 WARN namenode.FSNamesystem
(FSNamesystem.java:run(2075)) - ReplicationMonitor thread received
InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2009-06-27 13:07:29,073 INFO namenode.FSNamesystem
(FSEditLog.java:printStatistics(876)) - Number of transactions: 0 Total time
for transactions(ms): 0Number of transactions batched in Syncs: 0 Number of
syncs: 0 SyncTimes(ms): 0 0
[junit] 2009-06-27 13:07:29,073 WARN namenode.DecommissionManager
(DecommissionManager.java:run(67)) - Monitor interrupted:
java.lang.InterruptedException: sleep interrupted
[junit] 2009-06-27 13:07:29,180 INFO ipc.Server (Server.java:stop(1103)) -
Stopping server on 47963
[junit] 2009-06-27 13:07:29,181 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 0 on 47963: exiting
[junit] 2009-06-27 13:07:29,181 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 1 on 47963: exiting
[junit] 2009-06-27 13:07:29,181 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 2 on 47963: exiting
[junit] 2009-06-27 13:07:29,181 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 3 on 47963: exiting
[junit] 2009-06-27 13:07:29,181 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 4 on 47963: exiting
[junit] 2009-06-27 13:07:29,182 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 5 on 47963: exiting
[junit] 2009-06-27 13:07:29,182 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 6 on 47963: exiting
[junit] 2009-06-27 13:07:29,182 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 7 on 47963: exiting
[junit] 2009-06-27 13:07:29,182 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 8 on 47963: exiting
[junit] 2009-06-27 13:07:29,182 INFO ipc.Server (Server.java:run(997)) -
IPC Server handler 9 on 47963: exiting
[junit] 2009-06-27 13:07:29,183 INFO ipc.Server (Server.java:run(539)) -
Stopping IPC Server Responder
[junit] 2009-06-27 13:07:29,183 INFO ipc.Server (Server.java:run(352)) -
Stopping IPC Server listener on 47963
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 149.027 sec
checkfailure:
BUILD FAILED
http://hudson.zones.apache.org/hudson/job/Hadoop-Hdfs-trunk/ws/trunk/build.xml
:571: Tests failed!
Total time: 93 minutes 33 seconds
Publishing Javadoc
Recording test results
Recording fingerprints
Publishing Clover coverage report...
