See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/580/changes
Changes:
[nigel] disable clover in nightly build until HADOOP-3921 can be addressed
[omalley] HADOOP-1480. Add counters to the C++ Pipes API. (acmurthy via omalley)
[omalley] HADOOP-2130. Pipes submit job should have both blocking and
non-blocking
versions. (acmurthy via omalley)
[omalley] HADOOP-3934. Upgrade log4j to 1.2.15. (omalley)
[acmurthy] HADOOP-3958. Fix TestMapRed to check the success of test-job.
Contributed by Owen O'Malley.
[omalley] HADOOP-3549. Give more meaningful errno's in libhdfs. In particular,
EACCES is returned for permission problems. (Ben Slusky via omalley)
[cdouglas] HADOOP-3957. Fix javac warnings in DistCp and TestCopyFiles.
Contributed by Tsz Wo (Nicholas), SZE.
[mahadev] HADOOP-3962. Shell command fs -count should support paths with
different file systems. (Tsz Wo (Nicholas), SZE via mahadev)
------------------------------------------
[...truncated 194003 lines...]
[junit] 2008-08-21 19:04:35,096 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 41557,
ugi=hudson,hudson
[junit] 2008-08-21 19:04:35,096 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 41557,
ugi=hudson,hudson
[junit] 2008-08-21 19:04:35,096 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,097 INFO hdfs.StateChange
(FSNamesystem.java:allocateBlock(1342)) - BLOCK* NameSystem.allocateBlock:
/data/file1. blk_-5826057635197892725_1001
[junit] 2008-08-21 19:04:35,097 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,101 INFO datanode.DataNode
(DataXceiver.java:writeBlock(212)) - Receiving block
blk_-5826057635197892725_1001 src: /127.0.0.1:41570 dest: /127.0.0.1:41564
[junit] 2008-08-21 19:04:35,103 INFO datanode.DataNode
(DataXceiver.java:writeBlock(212)) - Receiving block
blk_-5826057635197892725_1001 src: /127.0.0.1:41571 dest: /127.0.0.1:41567
[junit] 2008-08-21 19:04:35,111 INFO datanode.DataNode
(DataXceiver.java:writeBlock(212)) - Receiving block
blk_-5826057635197892725_1001 src: /127.0.0.1:41572 dest: /127.0.0.1:41561
[junit] 2008-08-21 19:04:35,115 INFO datanode.DataNode
(BlockReceiver.java:lastDataNodeRun(779)) - Received block
blk_-5826057635197892725_1001 of size 100 from /127.0.0.1
[junit] 2008-08-21 19:04:35,116 INFO datanode.DataNode
(BlockReceiver.java:lastDataNodeRun(797)) - PacketResponder 0 for block
blk_-5826057635197892725_1001 terminating
[junit] 2008-08-21 19:04:35,116 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,117 INFO hdfs.StateChange
(FSNamesystem.java:addStoredBlock(2784)) - BLOCK* NameSystem.addStoredBlock:
blockMap updated: 127.0.0.1:41561 is added to blk_-5826057635197892725_1001
size 100
[junit] 2008-08-21 19:04:35,117 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,118 INFO datanode.DataNode
(BlockReceiver.java:run(894)) - Received block blk_-5826057635197892725_1001 of
size 100 from /127.0.0.1
[junit] 2008-08-21 19:04:35,119 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,119 INFO hdfs.StateChange
(FSNamesystem.java:addStoredBlock(2784)) - BLOCK* NameSystem.addStoredBlock:
blockMap updated: 127.0.0.1:41567 is added to blk_-5826057635197892725_1001
size 100
[junit] 2008-08-21 19:04:35,120 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,120 INFO datanode.DataNode
(BlockReceiver.java:run(894)) - Received block blk_-5826057635197892725_1001 of
size 100 from /127.0.0.1
[junit] 2008-08-21 19:04:35,121 INFO datanode.DataNode
(BlockReceiver.java:run(952)) - PacketResponder 1 for block
blk_-5826057635197892725_1001 terminating
[junit] 2008-08-21 19:04:35,121 INFO datanode.DataNode
(BlockReceiver.java:run(952)) - PacketResponder 2 for block
blk_-5826057635197892725_1001 terminating
[junit] 2008-08-21 19:04:35,123 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,123 INFO hdfs.StateChange
(FSNamesystem.java:addStoredBlock(2784)) - BLOCK* NameSystem.addStoredBlock:
blockMap updated: 127.0.0.1:41564 is added to blk_-5826057635197892725_1001
size 100
[junit] 2008-08-21 19:04:35,123 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,125 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 41557,
ugi=hudson,hudson
[junit] 2008-08-21 19:04:35,146 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,147 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 41557,
ugi=hudson,hudson
[junit] 2008-08-21 19:04:35,147 DEBUG security.UserGroupInformation
(PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-08-21 19:04:35,174 INFO FSNamesystem.audit
(FSNamesystem.java:logAuditEvent(106)) - ugi=hudson,hudson ip=/127.0.0.1
cmd=setPermission src=/data/file1 dst=null
perm=hudson:supergroup:rw-------
[junit] 2008-08-21 19:04:35,174 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,177 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 41557,
ugi=hudson,hudson
[junit] 2008-08-21 19:04:35,177 DEBUG security.UserGroupInformation
(PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-08-21 19:04:35,178 INFO FSNamesystem.audit
(FSNamesystem.java:logAuditEvent(106)) - ugi=hudson,hudson ip=/127.0.0.1
cmd=open src=/data/file1 dst=null perm=null
[junit] 2008-08-21 19:04:35,179 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,187 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 41557,
ugi=user-1248776474,group1,group2
[junit] 2008-08-21 19:04:35,187 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,187 INFO datanode.DataNode
(DataXceiver.java:readBlock(177)) - DatanodeRegistration(127.0.0.1:41561,
storageID=DS-1409363543-140.211.11.106-41561-1219345473797, infoPort=41562,
ipcPort=41563) Served block blk_-5826057635197892725_1001 to /127.0.0.1
[junit] 2008-08-21 19:04:35,189 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 41557,
ugi=user-1248776474,group1,group2
[junit] 2008-08-21 19:04:35,189 DEBUG security.UserGroupInformation
(PermissionChecker.java:<init>(40)) - ugi=user-1248776474,group1,group2
[junit] 2008-08-21 19:04:35,189 DEBUG security.UserGroupInformation
(PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: [EMAIL PROTECTED],
doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null,
subAccess=null
[junit] 2008-08-21 19:04:35,190 INFO FSNamesystem.audit
(FSNamesystem.java:logAuditEvent(106)) - ugi=user-1248776474,group1,group2
ip=/127.0.0.1 cmd=mkdirs src=/data/child1 dst=null
perm=hudson:supergroup:rwxr-xr-x
[junit] 2008-08-21 19:04:35,191 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,192 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 41557,
ugi=user-1248776474,group1,group2
[junit] 2008-08-21 19:04:35,192 DEBUG security.UserGroupInformation
(PermissionChecker.java:<init>(40)) - ugi=user-1248776474,group1,group2
[junit] 2008-08-21 19:04:35,192 DEBUG security.UserGroupInformation
(PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: [EMAIL PROTECTED],
doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null,
subAccess=null
[junit] 2008-08-21 19:04:35,192 DEBUG security.UserGroupInformation
(PermissionChecker.java:<init>(40)) - ugi=user-1248776474,group1,group2
[junit] 2008-08-21 19:04:35,193 DEBUG security.UserGroupInformation
(PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: [EMAIL PROTECTED],
doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null,
subAccess=null
[junit] 2008-08-21 19:04:35,193 INFO ipc.Server (Server.java:run(890)) -
IPC Server handler 7 on 41557, call mkdirs(/data/child2, rwxr-xr-x) from
127.0.0.1:41574: error: org.apache.hadoop.fs.permission.AccessControlException:
Permission denied: user=user-1248776474, access=WRITE,
inode="data":hudson:supergroup:rwxr-xr-x
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission
denied: user=user-1248776474, access=WRITE,
inode="data":hudson:supergroup:rwxr-xr-x
[junit] at
org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at
org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:156)
[junit] at
org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:104)
[junit] at
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4212)
[junit] at
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkAncestorAccess(FSNamesystem.java:4182)
[junit] at
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.mkdirsInternal(FSNamesystem.java:1636)
[junit] at
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.mkdirs(FSNamesystem.java:1605)
[junit] at
org.apache.hadoop.hdfs.server.namenode.NameNode.mkdirs(NameNode.java:468)
[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:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-08-21 19:04:35,194 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,196 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 41557,
ugi=user-1248776474,group1,group2
[junit] 2008-08-21 19:04:35,196 DEBUG security.UserGroupInformation
(PermissionChecker.java:<init>(40)) - ugi=user-1248776474,group1,group2
[junit] 2008-08-21 19:04:35,197 DEBUG security.UserGroupInformation
(PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: [EMAIL PROTECTED],
doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null,
subAccess=null
[junit] 2008-08-21 19:04:35,197 INFO ipc.Server (Server.java:run(890)) -
IPC Server handler 9 on 41557, call create(/data/file2, rwxr-xr-x,
DFSClient_-1843271536, true, 3, 67108864) from 127.0.0.1:41574: error:
org.apache.hadoop.fs.permission.AccessControlException: Permission denied:
user=user-1248776474, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission
denied: user=user-1248776474, access=WRITE,
inode="data":hudson:supergroup:rwxr-xr-x
[junit] at
org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at
org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:156)
[junit] at
org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:104)
[junit] at
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4212)
[junit] at
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkAncestorAccess(FSNamesystem.java:4182)
[junit] at
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.startFileInternal(FSNamesystem.java:957)
[junit] at
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.startFile(FSNamesystem.java:918)
[junit] at
org.apache.hadoop.hdfs.server.namenode.NameNode.create(NameNode.java:298)
[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:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-08-21 19:04:35,198 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,201 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 41557,
ugi=user-1248776474,group1,group2
[junit] 2008-08-21 19:04:35,201 DEBUG security.UserGroupInformation
(PermissionChecker.java:<init>(40)) - ugi=user-1248776474,group1,group2
[junit] 2008-08-21 19:04:35,202 DEBUG security.UserGroupInformation
(PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: [EMAIL PROTECTED],
doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=READ,
subAccess=null
[junit] 2008-08-21 19:04:35,202 INFO ipc.Server (Server.java:run(890)) -
IPC Server handler 4 on 41557, call getBlockLocations(/data/file1, 0,
671088640) from 127.0.0.1:41574: error:
org.apache.hadoop.fs.permission.AccessControlException: Permission denied:
user=user-1248776474, access=READ, inode="file1":hudson:supergroup:rw-------
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission
denied: user=user-1248776474, access=READ,
inode="file1":hudson:supergroup:rw-------
[junit] at
org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at
org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:110)
[junit] at
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4212)
[junit] at
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPathAccess(FSNamesystem.java:4172)
[junit] at
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlockLocations(FSNamesystem.java:715)
[junit] at
org.apache.hadoop.hdfs.server.namenode.NameNode.getBlockLocations(NameNode.java:269)
[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:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-08-21 19:04:35,203 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,206 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 41557,
ugi=hudson,hudson
[junit] 2008-08-21 19:04:35,206 DEBUG security.UserGroupInformation
(PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-08-21 19:04:35,224 INFO FSNamesystem.audit
(FSNamesystem.java:logAuditEvent(106)) - ugi=hudson,hudson ip=/127.0.0.1
cmd=setPermission src=/data dst=null
perm=hudson:supergroup:rwxr-xr-x
[junit] 2008-08-21 19:04:35,225 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,226 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 41557,
ugi=hudson,hudson
[junit] 2008-08-21 19:04:35,226 DEBUG security.UserGroupInformation
(PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-08-21 19:04:35,247 INFO FSNamesystem.audit
(FSNamesystem.java:logAuditEvent(106)) - ugi=hudson,hudson ip=/127.0.0.1
cmd=setPermission src=/data/child1 dst=null
perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-08-21 19:04:35,247 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,248 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 41557,
ugi=hudson,hudson
[junit] 2008-08-21 19:04:35,248 DEBUG security.UserGroupInformation
(PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-08-21 19:04:35,266 INFO FSNamesystem.audit
(FSNamesystem.java:logAuditEvent(106)) - ugi=hudson,hudson ip=/127.0.0.1
cmd=setPermission src=/ dst=null perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-08-21 19:04:35,266 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,267 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 41557,
ugi=user-1248776474,group1,group2
[junit] 2008-08-21 19:04:35,268 DEBUG security.UserGroupInformation
(PermissionChecker.java:<init>(40)) - ugi=user-1248776474,group1,group2
[junit] 2008-08-21 19:04:35,268 DEBUG security.UserGroupInformation
(PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: [EMAIL PROTECTED],
doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null,
subAccess=null
[junit] 2008-08-21 19:04:35,268 DEBUG security.UserGroupInformation
(PermissionChecker.java:<init>(40)) - ugi=user-1248776474,group1,group2
[junit] 2008-08-21 19:04:35,269 DEBUG security.UserGroupInformation
(PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: [EMAIL PROTECTED],
doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null,
subAccess=null
[junit] 2008-08-21 19:04:35,283 INFO FSNamesystem.audit
(FSNamesystem.java:logAuditEvent(106)) - ugi=user-1248776474,group1,group2
ip=/127.0.0.1 cmd=mkdirs src=/foo/bar dst=null
perm=user-1248776474:supergroup:rwxr-xr-x
[junit] 2008-08-21 19:04:35,284 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 41557,
ugi=null
[junit] 2008-08-21 19:04:35,285 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 41557,
ugi=user-1248776474,group1,group2
[junit] 2008-08-21 19:04:35,285 DEBUG security.UserGroupInformation
(PermissionChecker.java:<init>(40)) - ugi=user-1248776474,group1,group2
[junit] 2008-08-21 19:04:35,286 DEBUG security.UserGroupInformation
(PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: [EMAIL PROTECTED],
doCheckOwner=false, ancestorAccess=null, parentAccess=WRITE, access=null,
subAccess=null
[junit] 2008-08-21 19:04:35,286 DEBUG security.UserGroupInformation
(PermissionChecker.java:<init>(40)) - ugi=user-1248776474,group1,group2
[junit] 2008-08-21 19:04:35,286 DEBUG security.UserGroupInformation
(PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: [EMAIL PROTECTED],
doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null,
subAccess=null
[junit] 2008-08-21 19:04:35,305 INFO FSNamesystem.audit
(FSNamesystem.java:logAuditEvent(106)) - ugi=user-1248776474,group1,group2
ip=/127.0.0.1 cmd=rename src=/foo/bar dst=/data/child1
perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-08-21 19:04:35,305 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 41557,
ugi=null
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 2
[junit] 2008-08-21 19:04:35,307 INFO http.SocketListener
(SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:41568
[junit] 2008-08-21 19:04:35,416 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-08-21 19:04:35,483 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-08-21 19:04:35,484 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-08-21 19:04:35,558 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-08-21 19:04:35,559 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-08-21 19:04:35,559 INFO ipc.Server (Server.java:stop(992)) -
Stopping server on 41569
[junit] 2008-08-21 19:04:35,560 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 0 on 41569: exiting
[junit] 2008-08-21 19:04:35,560 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 1 on 41569: exiting
[junit] 2008-08-21 19:04:35,560 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 2 on 41569: exiting
[junit] 2008-08-21 19:04:35,560 INFO ipc.Server (Server.java:run(330)) -
Stopping IPC Server listener on 41569
[junit] 2008-08-21 19:04:35,561 INFO datanode.DataNode
(DataNode.java:shutdown(550)) - Waiting for threadgroup to exit, active threads
is 1
[junit] 2008-08-21 19:04:35,564 INFO datanode.DataNode
(DataNode.java:run(1104)) - DatanodeRegistration(127.0.0.1:41567,
storageID=DS-27610378-140.211.11.106-41567-1219345474836, infoPort=41568,
ipcPort=41569):Finishing DataNode in:
FSDataset{dirpath='/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data5/current,/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data6/current'}
[junit] 2008-08-21 19:04:35,565 INFO ipc.Server (Server.java:stop(992)) -
Stopping server on 41569
[junit] 2008-08-21 19:04:35,564 INFO datanode.DataBlockScanner
(DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] 2008-08-21 19:04:35,565 INFO datanode.DataNode
(DataNode.java:shutdown(550)) - Waiting for threadgroup to exit, active threads
is 0
[junit] Shutting down DataNode 1
[junit] 2008-08-21 19:04:35,567 INFO http.SocketListener
(SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:41565
[junit] 2008-08-21 19:04:35,633 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-08-21 19:04:35,698 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-08-21 19:04:35,698 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-08-21 19:04:35,765 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-08-21 19:04:35,765 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-08-21 19:04:35,766 INFO ipc.Server (Server.java:stop(992)) -
Stopping server on 41566
[junit] 2008-08-21 19:04:35,766 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 0 on 41566: exiting
[junit] 2008-08-21 19:04:35,766 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 1 on 41566: exiting
[junit] 2008-08-21 19:04:35,767 INFO ipc.Server (Server.java:run(330)) -
Stopping IPC Server listener on 41566
[junit] 2008-08-21 19:04:35,767 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 2 on 41566: exiting
[junit] 2008-08-21 19:04:35,768 INFO datanode.DataNode
(DataNode.java:shutdown(550)) - Waiting for threadgroup to exit, active threads
is 1
[junit] 2008-08-21 19:04:35,769 INFO ipc.Server (Server.java:run(502)) -
Stopping IPC Server Responder
[junit] 2008-08-21 19:04:36,490 INFO datanode.DataBlockScanner
(DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] 2008-08-21 19:04:36,770 INFO datanode.DataNode
(DataNode.java:shutdown(550)) - Waiting for threadgroup to exit, active threads
is 0
[junit] 2008-08-21 19:04:36,771 INFO datanode.DataNode
(DataNode.java:run(1104)) - DatanodeRegistration(127.0.0.1:41564,
storageID=DS-2043774138-140.211.11.106-41564-1219345474307, infoPort=41565,
ipcPort=41566):Finishing DataNode in:
FSDataset{dirpath='/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data3/current,/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data4/current'}
[junit] 2008-08-21 19:04:36,771 INFO ipc.Server (Server.java:stop(992)) -
Stopping server on 41566
[junit] 2008-08-21 19:04:36,771 INFO datanode.DataNode
(DataNode.java:shutdown(550)) - Waiting for threadgroup to exit, active threads
is 0
[junit] Shutting down DataNode 0
[junit] 2008-08-21 19:04:36,773 INFO util.ThreadedServer
(ThreadedServer.java:run(656)) - Stopping Acceptor
ServerSocket[addr=localhost/127.0.0.1,port=0,localport=41562]
[junit] 2008-08-21 19:04:36,773 INFO http.SocketListener
(SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:41562
[junit] 2008-08-21 19:04:36,839 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-08-21 19:04:36,905 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-08-21 19:04:36,905 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-08-21 19:04:36,906 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 41557,
ugi=null
[junit] 2008-08-21 19:04:36,970 DEBUG security.UserGroupInformation
(UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 41557,
ugi=null
[junit] 2008-08-21 19:04:36,969 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-08-21 19:04:36,970 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-08-21 19:04:36,971 INFO ipc.Server (Server.java:stop(992)) -
Stopping server on 41563
[junit] 2008-08-21 19:04:36,972 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 1 on 41563: exiting
[junit] 2008-08-21 19:04:36,972 INFO datanode.DataNode
(DataNode.java:shutdown(550)) - Waiting for threadgroup to exit, active threads
is 1
[junit] 2008-08-21 19:04:36,973 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 0 on 41563: exiting
[junit] 2008-08-21 19:04:36,973 INFO ipc.Server (Server.java:run(502)) -
Stopping IPC Server Responder
[junit] 2008-08-21 19:04:36,974 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 2 on 41563: exiting
[junit] 2008-08-21 19:04:36,975 INFO ipc.Server (Server.java:run(330)) -
Stopping IPC Server listener on 41563
[junit] 2008-08-21 19:04:37,000 INFO datanode.DataBlockScanner
(DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] 2008-08-21 19:04:37,980 INFO datanode.DataNode
(DataNode.java:shutdown(550)) - Waiting for threadgroup to exit, active threads
is 0
[junit] 2008-08-21 19:04:37,981 INFO datanode.DataNode
(DataNode.java:run(1104)) - DatanodeRegistration(127.0.0.1:41561,
storageID=DS-1409363543-140.211.11.106-41561-1219345473797, infoPort=41562,
ipcPort=41563):Finishing DataNode in:
FSDataset{dirpath='/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data1/current,/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data2/current'}
[junit] 2008-08-21 19:04:37,981 INFO ipc.Server (Server.java:stop(992)) -
Stopping server on 41563
[junit] 2008-08-21 19:04:37,981 INFO datanode.DataNode
(DataNode.java:shutdown(550)) - Waiting for threadgroup to exit, active threads
is 0
[junit] 2008-08-21 19:04:37,982 INFO http.SocketListener
(SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:41558
[junit] 2008-08-21 19:04:38,047 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-08-21 19:04:38,111 INFO util.Container
(Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-08-21 19:04:38,111 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-08-21 19:04:38,172 INFO util.Container
(Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-08-21 19:04:38,172 INFO util.Container
(Container.java:stop(156)) - Stopped [EMAIL PROTECTED]
[junit] 2008-08-21 19:04:38,173 WARN namenode.FSNamesystem
(FSNamesystem.java:run(2191)) - ReplicationMonitor thread received
InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2008-08-21 19:04:38,173 INFO namenode.FSNamesystem
(FSEditLog.java:printStatistics(860)) - Number of transactions: 12 Total time
for transactions(ms): 0 Number of syncs: 9 SyncTimes(ms): 72 92
[junit] 2008-08-21 19:04:38,173 INFO ipc.Server (Server.java:stop(992)) -
Stopping server on 41557
[junit] 2008-08-21 19:04:38,173 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 3 on 41557: exiting
[junit] 2008-08-21 19:04:38,174 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 5 on 41557: exiting
[junit] 2008-08-21 19:04:38,174 INFO ipc.Server (Server.java:run(330)) -
Stopping IPC Server listener on 41557
[junit] 2008-08-21 19:04:38,174 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 0 on 41557: exiting
[junit] 2008-08-21 19:04:38,174 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 1 on 41557: exiting
[junit] 2008-08-21 19:04:38,176 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 8 on 41557: exiting
[junit] 2008-08-21 19:04:38,176 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 6 on 41557: exiting
[junit] 2008-08-21 19:04:38,175 INFO ipc.Server (Server.java:run(502)) -
Stopping IPC Server Responder
[junit] 2008-08-21 19:04:38,175 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 4 on 41557: exiting
[junit] 2008-08-21 19:04:38,175 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 2 on 41557: exiting
[junit] 2008-08-21 19:04:38,176 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 9 on 41557: exiting
[junit] 2008-08-21 19:04:38,176 INFO ipc.Server (Server.java:run(920)) -
IPC Server handler 7 on 41557: exiting
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 14.182 sec
[junit] Running org.apache.hadoop.security.TestUnixUserGroupInformation
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.39 sec
[junit] Running org.apache.hadoop.util.TestGenericsUtil
[junit] 2008-08-21 19:04:39,654 WARN util.GenericOptionsParser
(GenericOptionsParser.java:parseGeneralOptions(318)) - options parsing failed:
Missing argument for option:jt
[junit] usage: general options are:
[junit] -archives <paths> comma separated archives to be
unarchived
[junit] on the compute machines.
[junit] -conf <configuration file> specify an application configuration
file
[junit] -D <property=value> use value for given property
[junit] -files <paths> comma separated files to be copied
to the
[junit] map reduce cluster
[junit] -fs <local|namenode:port> specify a namenode
[junit] -jt <local|jobtracker:port> specify a job tracker
[junit] -libjars <paths> comma separated jar files to include
in the
[junit] classpath.
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.184 sec
[junit] Running org.apache.hadoop.util.TestIndexedSort
[junit] sortRandom seed:
-474170138288711933(org.apache.hadoop.util.QuickSort)
[junit] testSorted seed:
7600109333848433437(org.apache.hadoop.util.QuickSort)
[junit] testAllEqual setting min/max at
180/489(org.apache.hadoop.util.QuickSort)
[junit] sortWritable seed:
-4624207891462708475(org.apache.hadoop.util.QuickSort)
[junit] QuickSort degen cmp/swp:
23252/3713(org.apache.hadoop.util.QuickSort)
[junit] sortRandom seed:
7059121889165608250(org.apache.hadoop.util.HeapSort)
[junit] testSorted seed:
5148325599637107224(org.apache.hadoop.util.HeapSort)
[junit] testAllEqual setting min/max at
292/177(org.apache.hadoop.util.HeapSort)
[junit] sortWritable seed:
-3250578676356512623(org.apache.hadoop.util.HeapSort)
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 1.446 sec
[junit] Running org.apache.hadoop.util.TestReflectionUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.668 sec
[junit] Running org.apache.hadoop.util.TestShell
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.229 sec
[junit] Running org.apache.hadoop.util.TestStringUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.09 sec
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build.xml:656:
Tests failed!
Total time: 82 minutes 20 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
