hadoop-common-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From hud...@lucene.zones.apache.org
Subject Build failed in Hudson: Hadoop-Nightly #370
Date Sat, 19 Jan 2008 13:55:13 GMT
See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/370/changes

Changes:

[cdouglas] HADOOP-2582. Prevent 'bin/hadoop fs -copyToLocal' from creating zero-length
files when the src does not exist. Contributed by Lohit Vijayarenu.

[nigel] parameterized clover jar location

[nigel] parameterized nightly build script

------------------------------------------
[...truncated 75123 lines...]
    [junit] 	at org.apache.hadoop.dfs.MiniDFSCluster.<init>(MiniDFSCluster.java:134)
    [junit] 	at org.apache.hadoop.dfs.MiniDFSCluster.<init>(MiniDFSCluster.java:106)
    [junit] 	at org.apache.hadoop.security.TestPermission.testFilePermision(TestPermission.java:111)
    [junit] 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    [junit] 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
    [junit] 2008-01-19 13:51:58,601 INFO  dfs.NameNodeMetrics (NameNodeMetrics.java:<init>(74))
- Initializing NameNodeMeterics using context object:org.apache.hadoop.metrics.spi.NullContext
    [junit] 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
    [junit] 	at java.lang.reflect.Method.invoke(Method.java:585)
    [junit] 	at junit.framework.TestCase.runTest(TestCase.java:154)
    [junit] 	at junit.framework.TestCase.runBare(TestCase.java:127)
    [junit] 	at junit.framework.TestResult$1.protect(TestResult.java:106)
    [junit] 	at junit.framework.TestResult.runProtected(TestResult.java:124)
    [junit] 	at junit.framework.TestResult.run(TestResult.java:109)
    [junit] 	at junit.framework.TestCase.run(TestCase.java:118)
    [junit] 	at junit.framework.TestSuite.runTest(TestSuite.java:208)
    [junit] 	at junit.framework.TestSuite.run(TestSuite.java:203)
    [junit] 	at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:297)
    [junit] 	at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:672)
    [junit] 	at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:567)
    [junit] 2008-01-19 13:51:58,633 INFO  fs.FSNamesystem (FSNamesystem.java:setConfigurationParameters(321))
- fsOwner=hudson,other
    [junit] 2008-01-19 13:51:58,636 INFO  fs.FSNamesystem (FSNamesystem.java:setConfigurationParameters(325))
- supergroup=supergroup
    [junit] 2008-01-19 13:51:58,636 INFO  fs.FSNamesystem (FSNamesystem.java:setConfigurationParameters(326))
- isPermissionEnabled=true
    [junit] 2008-01-19 13:51:58,655 INFO  fs.FSNamesystem (FSNamesystem.java:initialize(248))
- Finished loading FSImage in 52 msecs
    [junit] 2008-01-19 13:51:58,656 INFO  fs.FSNamesystem (FSNamesystem.java:leave(3554))
- Leaving safemode after 53 msecs
    [junit] 2008-01-19 13:51:58,657 INFO  dfs.StateChange (FSNamesystem.java:leave(3563))
- STATE* Network topology has 0 racks and 0 datanodes
    [junit] 2008-01-19 13:51:58,657 INFO  dfs.StateChange (FSNamesystem.java:leave(3566))
- STATE* UnderReplicatedBlocks has 0 blocks
    [junit] 2008-01-19 13:51:58,664 INFO  http.HttpServer (HttpServer.java:doStart(729)) -
Version Jetty/5.1.4
    [junit] 2008-01-19 13:51:58,821 INFO  util.Container (Container.java:start(74)) - Started
org.mortbay.jetty.servlet.WebApplicationHandler@edbe39
    [junit] 2008-01-19 13:51:58,826 INFO  util.Container (Container.java:start(74)) - Started
WebApplicationContext[/,/]
    [junit] 2008-01-19 13:51:58,828 INFO  util.Container (Container.java:start(74)) - Started
HttpContext[/logs,/logs]
    [junit] 2008-01-19 13:51:58,829 INFO  util.Container (Container.java:start(74)) - Started
HttpContext[/static,/static]
    [junit] 2008-01-19 13:51:58,832 INFO  http.SocketListener (SocketListener.java:start(204))
- Started SocketListener on 0.0.0.0:53228
    [junit] 2008-01-19 13:51:58,834 INFO  util.Container (Container.java:start(74)) - Started
org.mortbay.jetty.Server@c4bc34
    [junit] 2008-01-19 13:51:58,836 INFO  fs.FSNamesystem (FSNamesystem.java:initialize(287))
- Web-server up at: 0.0.0.0:53228
    [junit] 2008-01-19 13:51:58,838 INFO  ipc.Server (Server.java:run(470)) - IPC Server Responder:
starting
    [junit] 2008-01-19 13:51:58,839 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
0 on 53227: starting
    [junit] 2008-01-19 13:51:58,840 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
2 on 53227: starting
    [junit] 2008-01-19 13:51:58,842 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
6 on 53227: starting
    [junit] 2008-01-19 13:51:58,842 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
7 on 53227: starting
    [junit] 2008-01-19 13:51:58,843 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
8 on 53227: starting
    [junit] 2008-01-19 13:51:58,843 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
9 on 53227: starting
    [junit] 2008-01-19 13:51:58,841 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
5 on 53227: starting
    [junit] 2008-01-19 13:51:58,841 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
4 on 53227: starting
    [junit] 2008-01-19 13:51:58,841 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
3 on 53227: starting
    [junit] 2008-01-19 13:51:58,840 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
1 on 53227: starting
    [junit] 2008-01-19 13:51:58,838 INFO  ipc.Server (Server.java:run(317)) - IPC Server listener
on 53227: starting
    [junit] Starting DataNode 0 with dfs.data.dir: http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data1,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data2

    [junit] 2008-01-19 13:51:58,895 INFO  jvm.JvmMetrics (JvmMetrics.java:init(51)) - Cannot
initialize JVM Metrics with processName=DataNode, sessionId=null - already initialized
    [junit] 2008-01-19 13:51:58,905 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(117))
- Storage directory http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data1
 is not formatted.
    [junit] 2008-01-19 13:51:58,906 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(118))
- Formatting ...
    [junit] 2008-01-19 13:51:59,423 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(117))
- Storage directory http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data2
 is not formatted.
    [junit] 2008-01-19 13:51:59,424 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(118))
- Formatting ...
    [junit] 2008-01-19 13:51:59,974 INFO  dfs.DataNode (DataNode.java:startDataNode(318))
- Opened server at 53231
    [junit] 2008-01-19 13:51:59,976 INFO  dfs.DataNode (DataNode.java:startDataNode(340))
- Balancing bandwith is 1048576 bytes/s
    [junit] 2008-01-19 13:51:59,981 INFO  http.HttpServer (HttpServer.java:doStart(729)) -
Version Jetty/5.1.4
    [junit] 2008-01-19 13:52:00,161 INFO  util.Container (Container.java:start(74)) - Started
org.mortbay.jetty.servlet.WebApplicationHandler@1b6235b
    [junit] 2008-01-19 13:52:00,166 INFO  util.Container (Container.java:start(74)) - Started
WebApplicationContext[/,/]
    [junit] 2008-01-19 13:52:00,167 INFO  util.Container (Container.java:start(74)) - Started
HttpContext[/logs,/logs]
    [junit] 2008-01-19 13:52:00,168 INFO  util.Container (Container.java:start(74)) - Started
HttpContext[/static,/static]
    [junit] 2008-01-19 13:52:00,169 INFO  http.SocketListener (SocketListener.java:start(204))
- Started SocketListener on 0.0.0.0:53232
    [junit] 2008-01-19 13:52:00,170 INFO  util.Container (Container.java:start(74)) - Started
org.mortbay.jetty.Server@4d41e2
    [junit] 2008-01-19 13:52:00,174 INFO  dfs.StateChange (FSNamesystem.java:registerDatanode(1850))
- BLOCK* NameSystem.registerDatanode: node registration from 127.0.0.1:53231 storage DS-749940427-140.211.11.75-53231-1200750720172
    [junit] 2008-01-19 13:52:00,176 INFO  net.NetworkTopology (NetworkTopology.java:add(320))
- Adding a new node: /default-rack/127.0.0.1:53231
    [junit] 2008-01-19 13:52:00,326 INFO  dfs.DataNode (DataNode.java:register(500)) - New
storage id DS-749940427-140.211.11.75-53231-1200750720172 is assigned to data-node 127.0.0.1:53231
    [junit] 2008-01-19 13:52:00,328 INFO  dfs.DataNode (DataNode.java:run(2432)) - 127.0.0.1:53231In
DataNode.run, data = FSDataset{dirpath='http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data1/current,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data2/current'}

    [junit] 2008-01-19 13:52:00,329 INFO  dfs.DataNode (DataNode.java:offerService(622)) -
using BLOCKREPORT_INTERVAL of 3473877msec Initial delay: 0msec
    [junit] Starting DataNode 1 with dfs.data.dir: http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data3,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data4

    [junit] 2008-01-19 13:52:00,388 INFO  jvm.JvmMetrics (JvmMetrics.java:init(51)) - Cannot
initialize JVM Metrics with processName=DataNode, sessionId=null - already initialized
    [junit] 2008-01-19 13:52:00,446 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(117))
- Storage directory http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data3
 is not formatted.
    [junit] 2008-01-19 13:52:00,447 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(118))
- Formatting ...
    [junit] 2008-01-19 13:52:00,629 INFO  dfs.DataNode (DataNode.java:offerService(701)) -
BlockReport of 0 blocks got processed in 1 msecs
    [junit] 2008-01-19 13:52:00,968 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(117))
- Storage directory http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data4
 is not formatted.
    [junit] 2008-01-19 13:52:00,969 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(118))
- Formatting ...
    [junit] 2008-01-19 13:52:01,662 INFO  dfs.DataNode (DataNode.java:startDataNode(318))
- Opened server at 53237
    [junit] 2008-01-19 13:52:01,664 INFO  dfs.DataNode (DataNode.java:startDataNode(340))
- Balancing bandwith is 1048576 bytes/s
    [junit] 2008-01-19 13:52:01,669 INFO  http.HttpServer (HttpServer.java:doStart(729)) -
Version Jetty/5.1.4
    [junit] 2008-01-19 13:52:01,809 INFO  util.Container (Container.java:start(74)) - Started
org.mortbay.jetty.servlet.WebApplicationHandler@1df2964
    [junit] 2008-01-19 13:52:01,813 INFO  util.Container (Container.java:start(74)) - Started
WebApplicationContext[/,/]
    [junit] 2008-01-19 13:52:01,815 INFO  util.Container (Container.java:start(74)) - Started
HttpContext[/logs,/logs]
    [junit] 2008-01-19 13:52:01,816 INFO  util.Container (Container.java:start(74)) - Started
HttpContext[/static,/static]
    [junit] 2008-01-19 13:52:01,819 INFO  http.SocketListener (SocketListener.java:start(204))
- Started SocketListener on 0.0.0.0:53238
    [junit] 2008-01-19 13:52:01,820 INFO  util.Container (Container.java:start(74)) - Started
org.mortbay.jetty.Server@1d840d9
    [junit] 2008-01-19 13:52:01,825 INFO  dfs.StateChange (FSNamesystem.java:registerDatanode(1850))
- BLOCK* NameSystem.registerDatanode: node registration from 127.0.0.1:53237 storage DS-1737614705-140.211.11.75-53237-1200750721824
    [junit] 2008-01-19 13:52:01,826 INFO  net.NetworkTopology (NetworkTopology.java:add(320))
- Adding a new node: /default-rack/127.0.0.1:53237
    [junit] 2008-01-19 13:52:01,950 INFO  dfs.DataNode (DataNode.java:register(500)) - New
storage id DS-1737614705-140.211.11.75-53237-1200750721824 is assigned to data-node 127.0.0.1:53237
    [junit] Starting DataNode 2 with dfs.data.dir: http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data5,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data6

    [junit] 2008-01-19 13:52:01,952 INFO  dfs.DataNode (DataNode.java:run(2432)) - 127.0.0.1:53237In
DataNode.run, data = FSDataset{dirpath='http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data3/current,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data4/current'}

    [junit] 2008-01-19 13:52:01,954 INFO  dfs.DataNode (DataNode.java:offerService(622)) -
using BLOCKREPORT_INTERVAL of 3421312msec Initial delay: 0msec
    [junit] 2008-01-19 13:52:01,955 INFO  jvm.JvmMetrics (JvmMetrics.java:init(51)) - Cannot
initialize JVM Metrics with processName=DataNode, sessionId=null - already initialized
    [junit] 2008-01-19 13:52:02,003 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(117))
- Storage directory http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data5
 is not formatted.
    [junit] 2008-01-19 13:52:02,004 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(118))
- Formatting ...
    [junit] 2008-01-19 13:52:02,256 INFO  dfs.DataNode (DataNode.java:offerService(701)) -
BlockReport of 0 blocks got processed in 2 msecs
    [junit] 2008-01-19 13:52:02,543 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(117))
- Storage directory http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data6
 is not formatted.
    [junit] 2008-01-19 13:52:02,544 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(118))
- Formatting ...
    [junit] 2008-01-19 13:52:03,780 INFO  dfs.DataNode (DataNode.java:startDataNode(318))
- Opened server at 53239
    [junit] 2008-01-19 13:52:03,781 INFO  dfs.DataNode (DataNode.java:startDataNode(340))
- Balancing bandwith is 1048576 bytes/s
    [junit] 2008-01-19 13:52:03,787 INFO  http.HttpServer (HttpServer.java:doStart(729)) -
Version Jetty/5.1.4
    [junit] 2008-01-19 13:52:03,919 INFO  util.Container (Container.java:start(74)) - Started
org.mortbay.jetty.servlet.WebApplicationHandler@8beff2
    [junit] 2008-01-19 13:52:03,924 INFO  util.Container (Container.java:start(74)) - Started
WebApplicationContext[/,/]
    [junit] 2008-01-19 13:52:03,925 INFO  util.Container (Container.java:start(74)) - Started
HttpContext[/logs,/logs]
    [junit] 2008-01-19 13:52:03,926 INFO  util.Container (Container.java:start(74)) - Started
HttpContext[/static,/static]
    [junit] 2008-01-19 13:52:03,930 INFO  http.SocketListener (SocketListener.java:start(204))
- Started SocketListener on 0.0.0.0:53240
    [junit] 2008-01-19 13:52:03,931 INFO  util.Container (Container.java:start(74)) - Started
org.mortbay.jetty.Server@cc7439
    [junit] 2008-01-19 13:52:03,937 INFO  dfs.StateChange (FSNamesystem.java:registerDatanode(1850))
- BLOCK* NameSystem.registerDatanode: node registration from 127.0.0.1:53239 storage DS-744006028-140.211.11.75-53239-1200750723935
    [junit] 2008-01-19 13:52:03,938 INFO  net.NetworkTopology (NetworkTopology.java:add(320))
- Adding a new node: /default-rack/127.0.0.1:53239
    [junit] 2008-01-19 13:52:04,244 INFO  dfs.DataNode (DataNode.java:register(500)) - New
storage id DS-744006028-140.211.11.75-53239-1200750723935 is assigned to data-node 127.0.0.1:53239
    [junit] 2008-01-19 13:52:04,246 INFO  dfs.DataNode (DataNode.java:run(2432)) - 127.0.0.1:53239In
DataNode.run, data = FSDataset{dirpath='http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data5/current,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data6/current'}

    [junit] 2008-01-19 13:52:04,247 INFO  dfs.DataNode (DataNode.java:offerService(622)) -
using BLOCKREPORT_INTERVAL of 3589598msec Initial delay: 0msec
    [junit] 2008-01-19 13:52:04,505 INFO  dfs.DataNode (DataNode.java:offerService(701)) -
BlockReport of 0 blocks got processed in 3 msecs
    [junit] 2008-01-19 13:52:05,941 INFO  fs.DFSClient (DFSClient.java:run(1592)) - Allocating
new block
    [junit] 2008-01-19 13:52:05,944 INFO  dfs.StateChange (FSNamesystem.java:allocateBlock(1274))
- BLOCK* NameSystem.allocateBlock: /data/file1. blk_8355597249011503480
    [junit] 2008-01-19 13:52:05,947 INFO  fs.DFSClient (DFSClient.java:createBlockOutputStream(1982))
- pipeline = 127.0.0.1:53237
    [junit] 2008-01-19 13:52:05,948 INFO  fs.DFSClient (DFSClient.java:createBlockOutputStream(1982))
- pipeline = 127.0.0.1:53231
    [junit] 2008-01-19 13:52:05,948 INFO  fs.DFSClient (DFSClient.java:createBlockOutputStream(1982))
- pipeline = 127.0.0.1:53239
    [junit] 2008-01-19 13:52:05,948 INFO  fs.DFSClient (DFSClient.java:createBlockOutputStream(1985))
- Connecting to 127.0.0.1:53237
    [junit] 2008-01-19 13:52:05,952 INFO  dfs.DataNode (DataNode.java:writeBlock(1084)) -
Receiving block blk_8355597249011503480 from /127.0.0.1
    [junit] 2008-01-19 13:52:05,956 INFO  dfs.DataNode (DataNode.java:writeBlock(1084)) -
Receiving block blk_8355597249011503480 from /127.0.0.1
    [junit] 2008-01-19 13:52:05,958 INFO  dfs.DataNode (DataNode.java:writeBlock(1084)) -
Receiving block blk_8355597249011503480 from /127.0.0.1
    [junit] 2008-01-19 13:52:05,960 INFO  dfs.DataNode (DataNode.java:writeBlock(1169)) -
Datanode 0 forwarding connect ack to upstream firstbadlink is 
    [junit] 2008-01-19 13:52:05,960 INFO  dfs.DataNode (DataNode.java:writeBlock(1150)) -
Datanode 1 got response for connect ack  from downstream datanode with firstbadlink as 
    [junit] 2008-01-19 13:52:05,961 INFO  dfs.DataNode (DataNode.java:writeBlock(1169)) -
Datanode 1 forwarding connect ack to upstream firstbadlink is 
    [junit] 2008-01-19 13:52:05,961 INFO  dfs.DataNode (DataNode.java:writeBlock(1150)) -
Datanode 2 got response for connect ack  from downstream datanode with firstbadlink as 
    [junit] 2008-01-19 13:52:05,962 INFO  dfs.DataNode (DataNode.java:writeBlock(1169)) -
Datanode 2 forwarding connect ack to upstream firstbadlink is 
    [junit] 2008-01-19 13:52:06,011 INFO  dfs.DataNode (DataNode.java:lastDataNodeRun(1802))
- Received block blk_8355597249011503480 of size 100 from /127.0.0.1
    [junit] 2008-01-19 13:52:06,013 INFO  dfs.StateChange (FSNamesystem.java:addStoredBlock(2467))
- BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:53239 is added to blk_8355597249011503480
size 100
    [junit] 2008-01-19 13:52:06,014 INFO  dfs.DataNode (DataNode.java:lastDataNodeRun(1819))
- PacketResponder 0 for block blk_8355597249011503480 terminating
    [junit] 2008-01-19 13:52:06,463 INFO  dfs.DataNode (DataNode.java:run(1886)) - Received
block blk_8355597249011503480 of size 100 from /127.0.0.1
    [junit] 2008-01-19 13:52:06,464 INFO  dfs.DataNode (DataNode.java:run(1944)) - PacketResponder
1 for block blk_8355597249011503480 terminating
    [junit] 2008-01-19 13:52:06,467 INFO  dfs.StateChange (FSNamesystem.java:addStoredBlock(2467))
- BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:53231 is added to blk_8355597249011503480
size 100
    [junit] 2008-01-19 13:52:06,555 INFO  dfs.DataNode (DataNode.java:run(1886)) - Received
block blk_8355597249011503480 of size 100 from /127.0.0.1
    [junit] 2008-01-19 13:52:06,556 INFO  dfs.DataNode (DataNode.java:run(1944)) - PacketResponder
2 for block blk_8355597249011503480 terminating
    [junit] 2008-01-19 13:52:06,556 INFO  dfs.StateChange (FSNamesystem.java:addStoredBlock(2467))
- BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:53237 is added to blk_8355597249011503480
size 100
    [junit] 2008-01-19 13:52:06,556 INFO  fs.DFSClient (DFSClient.java:run(1653)) - Closing
old block blk_8355597249011503480
    [junit] 2008-01-19 13:52:07,392 INFO  dfs.DataNode (DataNode.java:readBlock(1051)) - 127.0.0.1:53231
Served block blk_8355597249011503480 to /127.0.0.1
    [junit] 2008-01-19 13:52:08,011 INFO  ipc.Server (Server.java:run(910)) - IPC Server handler
7 on 53227, call mkdirs(/data/web2, rwxr-xr-x) from 127.0.0.1:53246: error: org.apache.hadoop.fs.permission.AccessControlException:
Permission denied: user=Who, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
    [junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=Who,
access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
    [junit] 	at org.apache.hadoop.dfs.PermissionChecker.check(PermissionChecker.java:171)
    [junit] 	at org.apache.hadoop.dfs.PermissionChecker.check(PermissionChecker.java:152)
    [junit] 	at org.apache.hadoop.dfs.PermissionChecker.checkPermission(PermissionChecker.java:100)
    [junit] 	at org.apache.hadoop.dfs.FSNamesystem.checkPermission(FSNamesystem.java:3954)
    [junit] 	at org.apache.hadoop.dfs.FSNamesystem.checkAncestorAccess(FSNamesystem.java:3934)
    [junit] 	at org.apache.hadoop.dfs.FSNamesystem.mkdirsInternal(FSNamesystem.java:1541)
    [junit] 	at org.apache.hadoop.dfs.FSNamesystem.mkdirs(FSNamesystem.java:1524)
    [junit] 	at org.apache.hadoop.dfs.NameNode.mkdirs(NameNode.java:413)
    [junit] 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    [junit] 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
    [junit] 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
    [junit] 	at java.lang.reflect.Method.invoke(Method.java:585)
    [junit] 	at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:409)
    [junit] 	at org.apache.hadoop.ipc.Server$Handler.run(Server.java:908)
    [junit] 2008-01-19 13:52:08,037 INFO  ipc.Server (Server.java:run(910)) - IPC Server handler
8 on 53227, call create(/data/file2, rwxr-xr-x, DFSClient_-2146738807, true, 3, 67108864)
from 127.0.0.1:53246: error: org.apache.hadoop.fs.permission.AccessControlException: Permission
denied: user=Who, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
    [junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=Who,
access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
    [junit] 	at org.apache.hadoop.dfs.PermissionChecker.check(PermissionChecker.java:171)
    [junit] 	at org.apache.hadoop.dfs.PermissionChecker.check(PermissionChecker.java:152)
    [junit] 	at org.apache.hadoop.dfs.PermissionChecker.checkPermission(PermissionChecker.java:100)
    [junit] 	at org.apache.hadoop.dfs.FSNamesystem.checkPermission(FSNamesystem.java:3954)
    [junit] 	at org.apache.hadoop.dfs.FSNamesystem.checkAncestorAccess(FSNamesystem.java:3934)
    [junit] 	at org.apache.hadoop.dfs.FSNamesystem.startFileInternal(FSNamesystem.java:940)
    [junit] 	at org.apache.hadoop.dfs.FSNamesystem.startFile(FSNamesystem.java:915)
    [junit] 	at org.apache.hadoop.dfs.NameNode.create(NameNode.java:273)
    [junit] 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    [junit] 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
    [junit] 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
    [junit] 	at java.lang.reflect.Method.invoke(Method.java:585)
    [junit] 	at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:409)
    [junit] 	at org.apache.hadoop.ipc.Server$Handler.run(Server.java:908)
    [junit] 2008-01-19 13:52:08,042 INFO  ipc.Server (Server.java:run(910)) - IPC Server handler
9 on 53227, call open(/data/file1, 0, 671088640) from 127.0.0.1:53246: error: org.apache.hadoop.fs.permission.AccessControlException:
Permission denied: user=Who, access=READ, inode="file1":hudson:supergroup:rw-------
    [junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=Who,
access=READ, inode="file1":hudson:supergroup:rw-------
    [junit] 	at org.apache.hadoop.dfs.PermissionChecker.check(PermissionChecker.java:171)
    [junit] 	at org.apache.hadoop.dfs.PermissionChecker.checkPermission(PermissionChecker.java:106)
    [junit] 	at org.apache.hadoop.dfs.FSNamesystem.checkPermission(FSNamesystem.java:3954)
    [junit] 	at org.apache.hadoop.dfs.FSNamesystem.checkPathAccess(FSNamesystem.java:3924)
    [junit] 	at org.apache.hadoop.dfs.FSNamesystem.getBlockLocations(FSNamesystem.java:732)
    [junit] 	at org.apache.hadoop.dfs.NameNode.getBlockLocations(NameNode.java:246)
    [junit] 	at org.apache.hadoop.dfs.NameNode.open(NameNode.java:233)
    [junit] 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    [junit] 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
    [junit] 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
    [junit] 	at java.lang.reflect.Method.invoke(Method.java:585)
    [junit] 	at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:409)
    [junit] 	at org.apache.hadoop.ipc.Server$Handler.run(Server.java:908)
    [junit] Shutting down the Mini HDFS Cluster
    [junit] Shutting down DataNode 2
    [junit] 2008-01-19 13:52:08,984 INFO  http.SocketListener (SocketListener.java:stop(212))
- Stopped SocketListener on 0.0.0.0:53240
    [junit] 2008-01-19 13:52:08,986 INFO  util.Container (Container.java:stop(156)) - Stopped
org.mortbay.jetty.servlet.WebApplicationHandler@8beff2
    [junit] 2008-01-19 13:52:09,266 INFO  util.Container (Container.java:stop(156)) - Stopped
WebApplicationContext[/,/]
    [junit] 2008-01-19 13:52:09,369 INFO  util.Container (Container.java:stop(156)) - Stopped
HttpContext[/logs,/logs]
    [junit] 2008-01-19 13:52:09,486 INFO  util.Container (Container.java:stop(156)) - Stopped
HttpContext[/static,/static]
    [junit] 2008-01-19 13:52:09,487 INFO  util.Container (Container.java:stop(156)) - Stopped
org.mortbay.jetty.Server@cc7439
    [junit] 2008-01-19 13:52:09,488 INFO  dfs.DataNode (DataNode.java:shutdown(540)) - Waiting
for threadgroup to exit, active threads is 1
    [junit] 2008-01-19 13:52:10,262 INFO  dfs.DataNode (DataNode.java:run(2463)) - 127.0.0.1:53239:Finishing
DataNode in: FSDataset{dirpath='http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data5/current,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data6/current'}

    [junit] 2008-01-19 13:52:10,372 INFO  dfs.DataBlockScanner (DataBlockScanner.java:run(561))
- Exiting DataBlockScanner thread.
    [junit] 2008-01-19 13:52:10,492 INFO  dfs.DataNode (DataNode.java:shutdown(540)) - Waiting
for threadgroup to exit, active threads is 0
    [junit] Shutting down DataNode 1
    [junit] 2008-01-19 13:52:10,493 INFO  http.SocketListener (SocketListener.java:stop(212))
- Stopped SocketListener on 0.0.0.0:53238
    [junit] 2008-01-19 13:52:10,494 INFO  util.Container (Container.java:stop(156)) - Stopped
org.mortbay.jetty.servlet.WebApplicationHandler@1df2964
    [junit] 2008-01-19 13:52:10,596 INFO  util.Container (Container.java:stop(156)) - Stopped
WebApplicationContext[/,/]
    [junit] 2008-01-19 13:52:10,693 INFO  util.Container (Container.java:stop(156)) - Stopped
HttpContext[/logs,/logs]
    [junit] 2008-01-19 13:52:10,791 INFO  util.Container (Container.java:stop(156)) - Stopped
HttpContext[/static,/static]
    [junit] 2008-01-19 13:52:10,791 INFO  util.Container (Container.java:stop(156)) - Stopped
org.mortbay.jetty.Server@1d840d9
    [junit] 2008-01-19 13:52:10,793 INFO  dfs.DataNode (DataNode.java:shutdown(540)) - Waiting
for threadgroup to exit, active threads is 1
    [junit] 2008-01-19 13:52:10,982 INFO  dfs.DataNode (DataNode.java:run(2463)) - 127.0.0.1:53237:Finishing
DataNode in: FSDataset{dirpath='http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data3/current,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data4/current'}

    [junit] 2008-01-19 13:52:11,282 INFO  dfs.DataBlockScanner (DataBlockScanner.java:run(561))
- Exiting DataBlockScanner thread.
    [junit] 2008-01-19 13:52:11,801 INFO  dfs.DataNode (DataNode.java:shutdown(540)) - Waiting
for threadgroup to exit, active threads is 0
    [junit] Shutting down DataNode 0
    [junit] 2008-01-19 13:52:11,803 INFO  http.SocketListener (SocketListener.java:stop(212))
- Stopped SocketListener on 0.0.0.0:53232
    [junit] 2008-01-19 13:52:11,804 INFO  util.Container (Container.java:stop(156)) - Stopped
org.mortbay.jetty.servlet.WebApplicationHandler@1b6235b
    [junit] 2008-01-19 13:52:11,910 INFO  util.Container (Container.java:stop(156)) - Stopped
WebApplicationContext[/,/]
    [junit] 2008-01-19 13:52:12,003 INFO  util.Container (Container.java:stop(156)) - Stopped
HttpContext[/logs,/logs]
    [junit] 2008-01-19 13:52:12,092 INFO  util.Container (Container.java:stop(156)) - Stopped
HttpContext[/static,/static]
    [junit] 2008-01-19 13:52:12,093 INFO  util.Container (Container.java:stop(156)) - Stopped
org.mortbay.jetty.Server@4d41e2
    [junit] 2008-01-19 13:52:12,094 INFO  dfs.DataNode (DataNode.java:run(926)) - 127.0.0.1:53231:Exiting
DataXceiveServer due to java.net.SocketException: Socket closed
    [junit] 2008-01-19 13:52:12,095 INFO  dfs.DataNode (DataNode.java:shutdown(540)) - Waiting
for threadgroup to exit, active threads is 0
    [junit] 2008-01-19 13:52:12,096 INFO  dfs.DataBlockScanner (DataBlockScanner.java:run(561))
- Exiting DataBlockScanner thread.
    [junit] 2008-01-19 13:52:12,096 INFO  dfs.DataNode (DataNode.java:run(2463)) - 127.0.0.1:53231:Finishing
DataNode in: FSDataset{dirpath='http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build/test/data/dfs/data/data1/current,/export/home/hudson/hudson/jobs/Hadoop-Nightly/workspace/trunk/build/test/data/dfs/data/data2/current'}

    [junit] 2008-01-19 13:52:12,097 INFO  util.ThreadedServer (ThreadedServer.java:run(656))
- Stopping Acceptor ServerSocket[addr=0.0.0.0/0.0.0.0,port=0,localport=53228]
    [junit] 2008-01-19 13:52:12,102 INFO  http.SocketListener (SocketListener.java:stop(212))
- Stopped SocketListener on 0.0.0.0:53228
    [junit] 2008-01-19 13:52:12,103 INFO  util.Container (Container.java:stop(156)) - Stopped
org.mortbay.jetty.servlet.WebApplicationHandler@edbe39
    [junit] 2008-01-19 13:52:12,192 INFO  util.Container (Container.java:stop(156)) - Stopped
WebApplicationContext[/,/]
    [junit] 2008-01-19 13:52:12,284 INFO  util.Container (Container.java:stop(156)) - Stopped
HttpContext[/logs,/logs]
    [junit] 2008-01-19 13:52:12,373 INFO  util.Container (Container.java:stop(156)) - Stopped
HttpContext[/static,/static]
    [junit] 2008-01-19 13:52:12,374 INFO  util.Container (Container.java:stop(156)) - Stopped
org.mortbay.jetty.Server@c4bc34
    [junit] 2008-01-19 13:52:12,375 INFO  fs.FSNamesystem (FSEditLog.java:printStatistics(772))
- Number of transactions: 6 Total time for transactions(ms): 3 Number of syncs: 4 SyncTimes(ms):
758 648 
    [junit] 2008-01-19 13:52:12,420 INFO  ipc.Server (Server.java:stop(999)) - Stopping server
on 53227
    [junit] 2008-01-19 13:52:12,422 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
2 on 53227: exiting
    [junit] 2008-01-19 13:52:12,423 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
3 on 53227: exiting
    [junit] 2008-01-19 13:52:12,423 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
4 on 53227: exiting
    [junit] 2008-01-19 13:52:12,424 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
0 on 53227: exiting
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 33.311 sec
    [junit] 2008-01-19 13:52:12,423 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
1 on 53227: exiting
    [junit] 2008-01-19 13:52:12,423 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
6 on 53227: exiting
    [junit] 2008-01-19 13:52:12,423 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
5 on 53227: exiting
    [junit] 2008-01-19 13:52:12,423 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
7 on 53227: exiting
    [junit] 2008-01-19 13:52:12,422 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
8 on 53227: exiting
    [junit] 2008-01-19 13:52:12,428 INFO  ipc.Server (Server.java:run(525)) - Stopping IPC
Server Responder
    [junit] 2008-01-19 13:52:12,422 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
9 on 53227: exiting
    [junit] Running org.apache.hadoop.security.TestUnixUserGroupInformation
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.532 sec
    [junit] Running org.apache.hadoop.util.TestReflectionUtils
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.948 sec

BUILD FAILED
http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/ws/trunk/build.xml :532: Tests
failed!

Total time: 144 minutes 59 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Updating HADOOP-2582


Mime
View raw message