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 #369
Date Fri, 18 Jan 2008 15:41:50 GMT
See http://lucene.zones.apache.org:8080/hudson/job/Hadoop-Nightly/369/changes

------------------------------------------
[...truncated 71768 lines...]
    [junit] 	at org.apache.hadoop.dfs.NameNode.createNameNode(NameNode.java:849)
    [junit] 	at org.apache.hadoop.dfs.MiniDFSCluster.<init>(MiniDFSCluster.java:195)
    [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] 	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] 2008-01-18 15:36:45,782 INFO  dfs.NameNodeMetrics (NameNodeMetrics.java:<init>(74))
- Initializing NameNodeMeterics using context object:org.apache.hadoop.metrics.spi.NullContext
    [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-18 15:36:45,810 INFO  fs.FSNamesystem (FSNamesystem.java:setConfigurationParameters(321))
- fsOwner=hudson,other
    [junit] 2008-01-18 15:36:45,812 INFO  fs.FSNamesystem (FSNamesystem.java:setConfigurationParameters(325))
- supergroup=supergroup
    [junit] 2008-01-18 15:36:45,812 INFO  fs.FSNamesystem (FSNamesystem.java:setConfigurationParameters(326))
- isPermissionEnabled=true
    [junit] 2008-01-18 15:36:45,829 INFO  fs.FSNamesystem (FSNamesystem.java:initialize(248))
- Finished loading FSImage in 45 msecs
    [junit] 2008-01-18 15:36:45,831 INFO  fs.FSNamesystem (FSNamesystem.java:leave(3554))
- Leaving safemode after 47 msecs
    [junit] 2008-01-18 15:36:45,832 INFO  dfs.StateChange (FSNamesystem.java:leave(3563))
- STATE* Network topology has 0 racks and 0 datanodes
    [junit] 2008-01-18 15:36:45,832 INFO  dfs.StateChange (FSNamesystem.java:leave(3566))
- STATE* UnderReplicatedBlocks has 0 blocks
    [junit] 2008-01-18 15:36:46,004 INFO  http.HttpServer (HttpServer.java:doStart(729)) -
Version Jetty/5.1.4
    [junit] 2008-01-18 15:36:46,171 INFO  util.Container (Container.java:start(74)) - Started
org.mortbay.jetty.servlet.WebApplicationHandler@10980e7
    [junit] 2008-01-18 15:36:46,180 INFO  util.Container (Container.java:start(74)) - Started
WebApplicationContext[/,/]
    [junit] 2008-01-18 15:36:46,181 INFO  util.Container (Container.java:start(74)) - Started
HttpContext[/logs,/logs]
    [junit] 2008-01-18 15:36:46,182 INFO  util.Container (Container.java:start(74)) - Started
HttpContext[/static,/static]
    [junit] 2008-01-18 15:36:46,184 INFO  http.SocketListener (SocketListener.java:start(204))
- Started SocketListener on 0.0.0.0:49801
    [junit] 2008-01-18 15:36:46,185 INFO  util.Container (Container.java:start(74)) - Started
org.mortbay.jetty.Server@4c71d2
    [junit] 2008-01-18 15:36:46,186 INFO  fs.FSNamesystem (FSNamesystem.java:initialize(287))
- Web-server up at: 0.0.0.0:49801
    [junit] 2008-01-18 15:36:46,188 INFO  ipc.Server (Server.java:run(470)) - IPC Server Responder:
starting
    [junit] 2008-01-18 15:36:46,189 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
1 on 49800: starting
    [junit] 2008-01-18 15:36:46,190 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
4 on 49800: starting
    [junit] 2008-01-18 15:36:46,191 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
5 on 49800: starting
    [junit] 2008-01-18 15:36:46,192 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
6 on 49800: starting
    [junit] 2008-01-18 15:36:46,192 INFO  ipc.Server (Server.java:run(317)) - IPC Server listener
on 49800: starting
    [junit] 2008-01-18 15:36:46,192 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
3 on 49800: starting
    [junit] 2008-01-18 15:36:46,191 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
9 on 49800: starting
    [junit] 2008-01-18 15:36:46,191 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
8 on 49800: starting
    [junit] 2008-01-18 15:36:46,191 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
7 on 49800: starting
    [junit] 2008-01-18 15:36:46,190 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
2 on 49800: starting
    [junit] 2008-01-18 15:36:46,189 INFO  ipc.Server (Server.java:run(872)) - IPC Server handler
0 on 49800: 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-18 15:36:46,258 INFO  jvm.JvmMetrics (JvmMetrics.java:init(51)) - Cannot
initialize JVM Metrics with processName=DataNode, sessionId=null - already initialized
    [junit] 2008-01-18 15:36:46,264 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-18 15:36:46,265 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(118))
- Formatting ...
    [junit] 2008-01-18 15:36:48,107 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-18 15:36:48,108 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(118))
- Formatting ...
    [junit] 2008-01-18 15:36:50,600 INFO  dfs.DataNode (DataNode.java:startDataNode(318))
- Opened server at 49805
    [junit] 2008-01-18 15:36:50,601 INFO  dfs.DataNode (DataNode.java:startDataNode(340))
- Balancing bandwith is 1048576 bytes/s
    [junit] 2008-01-18 15:36:50,746 INFO  http.HttpServer (HttpServer.java:doStart(729)) -
Version Jetty/5.1.4
    [junit] 2008-01-18 15:36:50,950 INFO  util.Container (Container.java:start(74)) - Started
org.mortbay.jetty.servlet.WebApplicationHandler@1972e3a
    [junit] 2008-01-18 15:36:50,977 INFO  util.Container (Container.java:start(74)) - Started
WebApplicationContext[/,/]
    [junit] 2008-01-18 15:36:50,978 INFO  util.Container (Container.java:start(74)) - Started
HttpContext[/logs,/logs]
    [junit] 2008-01-18 15:36:50,979 INFO  util.Container (Container.java:start(74)) - Started
HttpContext[/static,/static]
    [junit] 2008-01-18 15:36:50,980 INFO  http.SocketListener (SocketListener.java:start(204))
- Started SocketListener on 0.0.0.0:49806
    [junit] 2008-01-18 15:36:50,981 INFO  util.Container (Container.java:start(74)) - Started
org.mortbay.jetty.Server@c24193
    [junit] 2008-01-18 15:36:50,985 INFO  dfs.StateChange (FSNamesystem.java:registerDatanode(1850))
- BLOCK* NameSystem.registerDatanode: node registration from 127.0.0.1:49805 storage DS-763672180-140.211.11.75-49805-1200670610983
    [junit] 2008-01-18 15:36:50,986 INFO  net.NetworkTopology (NetworkTopology.java:add(320))
- Adding a new node: /default-rack/127.0.0.1:49805
    [junit] 2008-01-18 15:36:51,437 INFO  dfs.DataNode (DataNode.java:register(500)) - New
storage id DS-763672180-140.211.11.75-49805-1200670610983 is assigned to data-node 127.0.0.1:49805
    [junit] 2008-01-18 15:36:51,439 INFO  dfs.DataNode (DataNode.java:run(2432)) - 127.0.0.1:49805In
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-18 15:36:51,440 INFO  dfs.DataNode (DataNode.java:offerService(622)) -
using BLOCKREPORT_INTERVAL of 3508737msec 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-18 15:36:51,597 INFO  jvm.JvmMetrics (JvmMetrics.java:init(51)) - Cannot
initialize JVM Metrics with processName=DataNode, sessionId=null - already initialized
    [junit] 2008-01-18 15:36:51,694 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-18 15:36:51,698 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(118))
- Formatting ...
    [junit] 2008-01-18 15:36:51,841 INFO  dfs.DataNode (DataNode.java:offerService(701)) -
BlockReport of 0 blocks got processed in 3 msecs
    [junit] 2008-01-18 15:36:54,696 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-18 15:36:54,697 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(118))
- Formatting ...
    [junit] 2008-01-18 15:36:58,381 INFO  dfs.DataNode (DataNode.java:startDataNode(318))
- Opened server at 49810
    [junit] 2008-01-18 15:36:58,383 INFO  dfs.DataNode (DataNode.java:startDataNode(340))
- Balancing bandwith is 1048576 bytes/s
    [junit] 2008-01-18 15:36:58,397 INFO  http.HttpServer (HttpServer.java:doStart(729)) -
Version Jetty/5.1.4
    [junit] 2008-01-18 15:36:58,556 INFO  util.Container (Container.java:start(74)) - Started
org.mortbay.jetty.servlet.WebApplicationHandler@19b4748
    [junit] 2008-01-18 15:36:58,609 INFO  util.Container (Container.java:start(74)) - Started
WebApplicationContext[/,/]
    [junit] 2008-01-18 15:36:58,611 INFO  util.Container (Container.java:start(74)) - Started
HttpContext[/logs,/logs]
    [junit] 2008-01-18 15:36:58,613 INFO  util.Container (Container.java:start(74)) - Started
HttpContext[/static,/static]
    [junit] 2008-01-18 15:36:58,616 INFO  http.SocketListener (SocketListener.java:start(204))
- Started SocketListener on 0.0.0.0:49811
    [junit] 2008-01-18 15:36:58,617 INFO  util.Container (Container.java:start(74)) - Started
org.mortbay.jetty.Server@89dd
    [junit] 2008-01-18 15:36:58,622 INFO  dfs.StateChange (FSNamesystem.java:registerDatanode(1850))
- BLOCK* NameSystem.registerDatanode: node registration from 127.0.0.1:49810 storage DS-908038220-140.211.11.75-49810-1200670618621
    [junit] 2008-01-18 15:36:58,623 INFO  net.NetworkTopology (NetworkTopology.java:add(320))
- Adding a new node: /default-rack/127.0.0.1:49810
    [junit] 2008-01-18 15:36:59,065 INFO  dfs.DataNode (DataNode.java:register(500)) - New
storage id DS-908038220-140.211.11.75-49810-1200670618621 is assigned to data-node 127.0.0.1:49810
    [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-18 15:36:59,070 INFO  dfs.DataNode (DataNode.java:run(2432)) - 127.0.0.1:49810In
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-18 15:36:59,071 INFO  jvm.JvmMetrics (JvmMetrics.java:init(51)) - Cannot
initialize JVM Metrics with processName=DataNode, sessionId=null - already initialized
    [junit] 2008-01-18 15:36:59,072 INFO  dfs.DataNode (DataNode.java:offerService(622)) -
using BLOCKREPORT_INTERVAL of 3302827msec Initial delay: 0msec
    [junit] 2008-01-18 15:36:59,286 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-18 15:36:59,287 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(118))
- Formatting ...
    [junit] 2008-01-18 15:36:59,770 INFO  dfs.DataNode (DataNode.java:offerService(701)) -
BlockReport of 0 blocks got processed in 3 msecs
    [junit] 2008-01-18 15:37:01,053 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-18 15:37:01,054 INFO  dfs.Storage (DataStorage.java:recoverTransitionRead(118))
- Formatting ...
    [junit] 2008-01-18 15:37:03,268 INFO  dfs.DataNode (DataNode.java:startDataNode(318))
- Opened server at 49814
    [junit] 2008-01-18 15:37:03,269 INFO  dfs.DataNode (DataNode.java:startDataNode(340))
- Balancing bandwith is 1048576 bytes/s
    [junit] 2008-01-18 15:37:03,344 INFO  http.HttpServer (HttpServer.java:doStart(729)) -
Version Jetty/5.1.4
    [junit] 2008-01-18 15:37:03,493 INFO  util.Container (Container.java:start(74)) - Started
org.mortbay.jetty.servlet.WebApplicationHandler@d647d8
    [junit] 2008-01-18 15:37:03,669 INFO  util.Container (Container.java:start(74)) - Started
WebApplicationContext[/,/]
    [junit] 2008-01-18 15:37:03,670 INFO  util.Container (Container.java:start(74)) - Started
HttpContext[/logs,/logs]
    [junit] 2008-01-18 15:37:03,671 INFO  util.Container (Container.java:start(74)) - Started
HttpContext[/static,/static]
    [junit] 2008-01-18 15:37:03,675 INFO  http.SocketListener (SocketListener.java:start(204))
- Started SocketListener on 0.0.0.0:49818
    [junit] 2008-01-18 15:37:03,676 INFO  util.Container (Container.java:start(74)) - Started
org.mortbay.jetty.Server@1a41cc7
    [junit] 2008-01-18 15:37:03,680 INFO  dfs.StateChange (FSNamesystem.java:registerDatanode(1850))
- BLOCK* NameSystem.registerDatanode: node registration from 127.0.0.1:49814 storage DS-1639333971-140.211.11.75-49814-1200670623679
    [junit] 2008-01-18 15:37:03,681 INFO  net.NetworkTopology (NetworkTopology.java:add(320))
- Adding a new node: /default-rack/127.0.0.1:49814
    [junit] 2008-01-18 15:37:04,139 INFO  dfs.DataNode (DataNode.java:register(500)) - New
storage id DS-1639333971-140.211.11.75-49814-1200670623679 is assigned to data-node 127.0.0.1:49814
    [junit] 2008-01-18 15:37:04,142 INFO  dfs.DataNode (DataNode.java:run(2432)) - 127.0.0.1:49814In
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-18 15:37:04,144 INFO  dfs.DataNode (DataNode.java:offerService(622)) -
using BLOCKREPORT_INTERVAL of 3386847msec Initial delay: 0msec
    [junit] 2008-01-18 15:37:04,379 INFO  dfs.DataNode (DataNode.java:offerService(701)) -
BlockReport of 0 blocks got processed in 5 msecs
    [junit] 2008-01-18 15:37:05,654 INFO  fs.DFSClient (DFSClient.java:run(1592)) - Allocating
new block
    [junit] 2008-01-18 15:37:05,657 INFO  dfs.StateChange (FSNamesystem.java:allocateBlock(1274))
- BLOCK* NameSystem.allocateBlock: /data/file1. blk_6638807983918515008
    [junit] 2008-01-18 15:37:05,660 INFO  fs.DFSClient (DFSClient.java:createBlockOutputStream(1982))
- pipeline = 127.0.0.1:49805
    [junit] 2008-01-18 15:37:05,661 INFO  fs.DFSClient (DFSClient.java:createBlockOutputStream(1982))
- pipeline = 127.0.0.1:49810
    [junit] 2008-01-18 15:37:05,661 INFO  fs.DFSClient (DFSClient.java:createBlockOutputStream(1982))
- pipeline = 127.0.0.1:49814
    [junit] 2008-01-18 15:37:05,662 INFO  fs.DFSClient (DFSClient.java:createBlockOutputStream(1985))
- Connecting to 127.0.0.1:49805
    [junit] 2008-01-18 15:37:05,666 INFO  dfs.DataNode (DataNode.java:writeBlock(1084)) -
Receiving block blk_6638807983918515008 from /127.0.0.1
    [junit] 2008-01-18 15:37:05,723 INFO  dfs.DataNode (DataNode.java:writeBlock(1084)) -
Receiving block blk_6638807983918515008 from /127.0.0.1
    [junit] 2008-01-18 15:37:05,861 INFO  dfs.DataNode (DataNode.java:writeBlock(1084)) -
Receiving block blk_6638807983918515008 from /127.0.0.1
    [junit] 2008-01-18 15:37:05,972 INFO  dfs.DataNode (DataNode.java:writeBlock(1169)) -
Datanode 0 forwarding connect ack to upstream firstbadlink is 
    [junit] 2008-01-18 15:37:05,973 INFO  dfs.DataNode (DataNode.java:writeBlock(1150)) -
Datanode 1 got response for connect ack  from downstream datanode with firstbadlink as 
    [junit] 2008-01-18 15:37:05,974 INFO  dfs.DataNode (DataNode.java:writeBlock(1169)) -
Datanode 1 forwarding connect ack to upstream firstbadlink is 
    [junit] 2008-01-18 15:37:05,974 INFO  dfs.DataNode (DataNode.java:writeBlock(1150)) -
Datanode 2 got response for connect ack  from downstream datanode with firstbadlink as 
    [junit] 2008-01-18 15:37:05,975 INFO  dfs.DataNode (DataNode.java:writeBlock(1169)) -
Datanode 2 forwarding connect ack to upstream firstbadlink is 
    [junit] 2008-01-18 15:37:06,227 INFO  dfs.DataNode (DataNode.java:lastDataNodeRun(1802))
- Received block blk_6638807983918515008 of size 100 from /127.0.0.1
    [junit] 2008-01-18 15:37:06,228 INFO  dfs.DataNode (DataNode.java:lastDataNodeRun(1819))
- PacketResponder 0 for block blk_6638807983918515008 terminating
    [junit] 2008-01-18 15:37:06,228 INFO  dfs.StateChange (FSNamesystem.java:addStoredBlock(2467))
- BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:49814 is added to blk_6638807983918515008
size 100
    [junit] 2008-01-18 15:37:06,359 INFO  dfs.DataNode (DataNode.java:run(1886)) - Received
block blk_6638807983918515008 of size 100 from /127.0.0.1
    [junit] 2008-01-18 15:37:06,361 INFO  dfs.DataNode (DataNode.java:run(1944)) - PacketResponder
1 for block blk_6638807983918515008 terminating
    [junit] 2008-01-18 15:37:06,361 INFO  dfs.StateChange (FSNamesystem.java:addStoredBlock(2467))
- BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:49810 is added to blk_6638807983918515008
size 100
    [junit] 2008-01-18 15:37:06,527 INFO  dfs.DataNode (DataNode.java:run(1886)) - Received
block blk_6638807983918515008 of size 100 from /127.0.0.1
    [junit] 2008-01-18 15:37:06,527 INFO  dfs.DataNode (DataNode.java:run(1944)) - PacketResponder
2 for block blk_6638807983918515008 terminating
    [junit] 2008-01-18 15:37:06,528 INFO  fs.DFSClient (DFSClient.java:run(1653)) - Closing
old block blk_6638807983918515008
    [junit] 2008-01-18 15:37:06,530 INFO  dfs.StateChange (FSNamesystem.java:addStoredBlock(2467))
- BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:49805 is added to blk_6638807983918515008
size 100
    [junit] 2008-01-18 15:37:07,222 INFO  dfs.DataNode (DataNode.java:readBlock(1051)) - 127.0.0.1:49810
Served block blk_6638807983918515008 to /127.0.0.1
    [junit] 2008-01-18 15:37:07,467 INFO  ipc.Server (Server.java:run(910)) - IPC Server handler
8 on 49800, call mkdirs(/data/web2, rwxr-xr-x) from 127.0.0.1:49825: 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-18 15:37:07,492 INFO  ipc.Server (Server.java:run(910)) - IPC Server handler
7 on 49800, call create(/data/file2, rwxr-xr-x, DFSClient_-2020994479, true, 3, 67108864)
from 127.0.0.1:49825: 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-18 15:37:07,497 INFO  ipc.Server (Server.java:run(910)) - IPC Server handler
2 on 49800, call open(/data/file1, 0, 671088640) from 127.0.0.1:49825: 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-18 15:37:08,445 INFO  http.SocketListener (SocketListener.java:stop(212))
- Stopped SocketListener on 0.0.0.0:49818
    [junit] 2008-01-18 15:37:08,447 INFO  util.Container (Container.java:stop(156)) - Stopped
org.mortbay.jetty.servlet.WebApplicationHandler@d647d8
    [junit] 2008-01-18 15:37:08,781 INFO  util.Container (Container.java:stop(156)) - Stopped
WebApplicationContext[/,/]
    [junit] 2008-01-18 15:37:08,890 INFO  util.Container (Container.java:stop(156)) - Stopped
HttpContext[/logs,/logs]
    [junit] 2008-01-18 15:37:09,006 INFO  util.Container (Container.java:stop(156)) - Stopped
HttpContext[/static,/static]
    [junit] 2008-01-18 15:37:09,007 INFO  util.Container (Container.java:stop(156)) - Stopped
org.mortbay.jetty.Server@1a41cc7
    [junit] 2008-01-18 15:37:09,008 INFO  dfs.DataNode (DataNode.java:shutdown(540)) - Waiting
for threadgroup to exit, active threads is 1
    [junit] 2008-01-18 15:37:09,262 INFO  dfs.DataBlockScanner (DataBlockScanner.java:run(561))
- Exiting DataBlockScanner thread.
    [junit] 2008-01-18 15:37:10,012 INFO  dfs.DataNode (DataNode.java:shutdown(540)) - Waiting
for threadgroup to exit, active threads is 0
    [junit] 2008-01-18 15:37:10,013 INFO  dfs.DataNode (DataNode.java:run(2463)) - 127.0.0.1:49814: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] Shutting down DataNode 1
    [junit] 2008-01-18 15:37:10,017 INFO  http.SocketListener (SocketListener.java:stop(212))
- Stopped SocketListener on 0.0.0.0:49811
    [junit] 2008-01-18 15:37:10,018 INFO  util.Container (Container.java:stop(156)) - Stopped
org.mortbay.jetty.servlet.WebApplicationHandler@19b4748
    [junit] 2008-01-18 15:37:10,133 INFO  util.Container (Container.java:stop(156)) - Stopped
WebApplicationContext[/,/]
    [junit] 2008-01-18 15:37:10,237 INFO  util.Container (Container.java:stop(156)) - Stopped
HttpContext[/logs,/logs]
    [junit] 2008-01-18 15:37:10,333 INFO  util.Container (Container.java:stop(156)) - Stopped
HttpContext[/static,/static]
    [junit] 2008-01-18 15:37:10,334 INFO  util.Container (Container.java:stop(156)) - Stopped
org.mortbay.jetty.Server@89dd
    [junit] 2008-01-18 15:37:10,335 INFO  dfs.DataNode (DataNode.java:shutdown(540)) - Waiting
for threadgroup to exit, active threads is 1
    [junit] 2008-01-18 15:37:10,336 INFO  dfs.DataBlockScanner (DataBlockScanner.java:run(561))
- Exiting DataBlockScanner thread.
    [junit] 2008-01-18 15:37:10,336 INFO  dfs.DataNode (DataNode.java:run(2463)) - 127.0.0.1:49810: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] Shutting down DataNode 0
    [junit] 2008-01-18 15:37:10,338 INFO  http.SocketListener (SocketListener.java:stop(212))
- Stopped SocketListener on 0.0.0.0:49806
    [junit] 2008-01-18 15:37:10,339 INFO  util.Container (Container.java:stop(156)) - Stopped
org.mortbay.jetty.servlet.WebApplicationHandler@1972e3a
    [junit] 2008-01-18 15:37:10,439 INFO  util.Container (Container.java:stop(156)) - Stopped
WebApplicationContext[/,/]
    [junit] 2008-01-18 15:37:10,536 INFO  util.Container (Container.java:stop(156)) - Stopped
HttpContext[/logs,/logs]
    [junit] 2008-01-18 15:37:10,633 INFO  util.Container (Container.java:stop(156)) - Stopped
HttpContext[/static,/static]
    [junit] 2008-01-18 15:37:10,633 INFO  util.Container (Container.java:stop(156)) - Stopped
org.mortbay.jetty.Server@c24193
    [junit] 2008-01-18 15:37:10,634 INFO  dfs.DataNode (DataNode.java:shutdown(540)) - Waiting
for threadgroup to exit, active threads is 1
    [junit] 2008-01-18 15:37:10,792 INFO  dfs.DataBlockScanner (DataBlockScanner.java:run(561))
- Exiting DataBlockScanner thread.
    [junit] 2008-01-18 15:37:11,642 INFO  dfs.DataNode (DataNode.java:shutdown(540)) - Waiting
for threadgroup to exit, active threads is 0
    [junit] 2008-01-18 15:37:11,643 INFO  dfs.DataNode (DataNode.java:run(2463)) - 127.0.0.1:49805: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-18 15:37:11,644 INFO  http.SocketListener (SocketListener.java:stop(212))
- Stopped SocketListener on 0.0.0.0:49801
    [junit] 2008-01-18 15:37:11,645 INFO  util.Container (Container.java:stop(156)) - Stopped
org.mortbay.jetty.servlet.WebApplicationHandler@10980e7
    [junit] 2008-01-18 15:37:11,742 INFO  util.Container (Container.java:stop(156)) - Stopped
WebApplicationContext[/,/]
    [junit] 2008-01-18 15:37:11,839 INFO  util.Container (Container.java:stop(156)) - Stopped
HttpContext[/logs,/logs]
    [junit] 2008-01-18 15:37:11,933 INFO  util.Container (Container.java:stop(156)) - Stopped
HttpContext[/static,/static]
    [junit] 2008-01-18 15:37:11,933 INFO  util.Container (Container.java:stop(156)) - Stopped
org.mortbay.jetty.Server@4c71d2
    [junit] 2008-01-18 15:37:11,934 INFO  fs.FSNamesystem (FSEditLog.java:printStatistics(772))
- Number of transactions: 6 Total time for transactions(ms): 0 Number of syncs: 4 SyncTimes(ms):
457 592 
    [junit] 2008-01-18 15:37:12,027 INFO  ipc.Server (Server.java:stop(999)) - Stopping server
on 49800
    [junit] 2008-01-18 15:37:12,028 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
1 on 49800: exiting
    [junit] 2008-01-18 15:37:12,028 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
0 on 49800: exiting
    [junit] 2008-01-18 15:37:12,030 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
7 on 49800: exiting
    [junit] 2008-01-18 15:37:12,031 INFO  ipc.Server (Server.java:run(525)) - Stopping IPC
Server Responder
    [junit] 2008-01-18 15:37:12,030 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
3 on 49800: exiting
    [junit] 2008-01-18 15:37:12,030 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
5 on 49800: exiting
    [junit] 2008-01-18 15:37:12,030 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
6 on 49800: exiting
    [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 58.865 sec
    [junit] 2008-01-18 15:37:12,030 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
8 on 49800: exiting
    [junit] 2008-01-18 15:37:12,029 INFO  ipc.Server (Server.java:run(353)) - Stopping IPC
Server listener on 49800
    [junit] 2008-01-18 15:37:12,029 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
9 on 49800: exiting
    [junit] 2008-01-18 15:37:12,029 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
4 on 49800: exiting
    [junit] 2008-01-18 15:37:12,029 INFO  ipc.Server (Server.java:run(939)) - IPC Server handler
2 on 49800: exiting
    [junit] Running org.apache.hadoop.security.TestUnixUserGroupInformation
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.574 sec
    [junit] Running org.apache.hadoop.util.TestReflectionUtils
    [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 1.021 sec

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

Total time: 224 minutes 50 seconds
Recording fingerprints
Publishing Javadoc
Recording test results


Mime
View raw message