hadoop-hdfs-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Hudson Server <hud...@hudson.zones.apache.org>
Subject Build failed in Hudson: Hadoop-Hdfs-trunk #5
Date Sat, 27 Jun 2009 13:08:39 GMT
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 SelectChannelConnector@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 SelectChannelConnector@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 SelectChannelConnector@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 SelectChannelConnector@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...


Mime
View raw message