You are viewing a plain text version of this content. The canonical link for it is here.
Posted to common-dev@hadoop.apache.org by Apache Hudson Server <hu...@hudson.zones.apache.org> on 2008/04/17 14:13:56 UTC
Build failed in Hudson: Hadoop-trunk #463
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/463/changes
Changes:
[ddas] HADOOP-3266. Removed HOD changes from CHANGES.txt, as they are now inside src/contrib/hod. Contributed by Hemanth Yamijala.
[ddas] HADOOP-3251. Fixes getFilesystemName in JobTracker and LocalJobRunner to use FileSystem.getUri instead of FileSystem.getName. Contributed by Arun Murthy.
[shv] HADOOP-2865. FsShell.ls() printout format changed to print file names in the end of the line. Contributed by Edward J. Yoon.
[acmurthy] HADOOP-3263. Ensure that the job-history log file always follows the pattern of hostname_timestamp_jobid_username_jobname even if username and/or jobname are not specfied. This helps to avoid wrong assumptions made about the job-history log filename in jobhistory.jsp.
[rangadi] HADOOP-3124. Make DataNode socket write timeout configurable. (rangadi)
[dhruba] HADOOP-2703. The default options to fsck skips checking files
that are being written to. The output of fsck is incompatible
with previous release. (lohit vijayarenu via dhruba)
[dhruba] HADOOP-2703. The default options to fsck skips checking files
that are being written to. (lohit vijayarenu via dhruba)
[rangadi] HADOOP-3160. Remove deprecated exists() from ClientProtocol and FSNamesystem (Lohit Vjayarenu via rangadi)
[acmurthy] HADOOP-3162 related ocumentation changes
[acmurthy] HADOOP-3162. Ensure that comma-separated input paths are treated correctly as multiple input paths. Contributed by Amareshwari Sri Ramadasu.
[acmurthy] HADOOP-3162. Ensure that comma-separated input paths are treated correctly as multiple input paths. Contributed by Amareshwari Sri Ramadasu.
[ddas] HADOOP-3256. Encodes the job name used in the filename for history files. Contributed by Arun Murthy.
[ddas] HADOOP-3130. Make the connect timeout smaller for getFile. Contributed by Amar Ramesh Kamat and Runping Qi.
------------------------------------------
[...truncated 96968 lines...]
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:446)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:896)
[junit] 2008-04-17 12:12:59,473 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,492 INFO security.TestPermission (TestPermission.java:testFilePermision(135)) - GOOD: got java.io.FileNotFoundException: java.io.FileNotFoundException: File does not exist: /data/file1
[junit] at org.apache.hadoop.dfs.FSDirectory.unprotectedSetOwner(FSDirectory.java:445)
[junit] at org.apache.hadoop.dfs.FSDirectory.setOwner(FSDirectory.java:437)
[junit] at org.apache.hadoop.dfs.FSNamesystem.setOwner(FSNamesystem.java:774)
[junit] at org.apache.hadoop.dfs.NameNode.setOwner(NameNode.java:291)
[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:446)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:896)
[junit]
[junit] 2008-04-17 12:12:59,493 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 37822, ugi=hudson,hudson
[junit] 2008-04-17 12:12:59,494 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-04-17 12:12:59,494 INFO ipc.Server (Server.java:run(898)) - IPC Server handler 2 on 37822, call setPermission(/data/file1, rwxrwxrwx) from 127.0.0.1:37832: error: java.io.FileNotFoundException: File does not exist: /data/file1
[junit] java.io.FileNotFoundException: File does not exist: /data/file1
[junit] at org.apache.hadoop.dfs.FSDirectory.unprotectedSetPermission(FSDirectory.java:430)
[junit] at org.apache.hadoop.dfs.FSDirectory.setPermission(FSDirectory.java:422)
[junit] at org.apache.hadoop.dfs.FSNamesystem.setPermission(FSNamesystem.java:754)
[junit] at org.apache.hadoop.dfs.NameNode.setPermission(NameNode.java:285)
[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:446)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:896)
[junit] 2008-04-17 12:12:59,495 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,496 INFO security.TestPermission (TestPermission.java:testFilePermision(142)) - GOOD: got java.io.FileNotFoundException: java.io.FileNotFoundException: File does not exist: /data/file1
[junit] at org.apache.hadoop.dfs.FSDirectory.unprotectedSetPermission(FSDirectory.java:430)
[junit] at org.apache.hadoop.dfs.FSDirectory.setPermission(FSDirectory.java:422)
[junit] at org.apache.hadoop.dfs.FSNamesystem.setPermission(FSNamesystem.java:754)
[junit] at org.apache.hadoop.dfs.NameNode.setPermission(NameNode.java:285)
[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:446)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:896)
[junit]
[junit] 2008-04-17 12:12:59,497 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 37822, ugi=hudson,hudson
[junit] 2008-04-17 12:12:59,497 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-04-17 12:12:59,498 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-04-17 12:12:59,524 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,525 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 37822, ugi=hudson,hudson
[junit] 2008-04-17 12:12:59,526 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-04-17 12:12:59,528 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,528 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,530 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,530 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,531 INFO dfs.DataNode (DataNode.java:offerService(676)) - BlockReport of 0 blocks got processed in 2 msecs
[junit] 2008-04-17 12:12:59,550 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,551 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 37822, ugi=hudson,hudson
[junit] 2008-04-17 12:12:59,553 INFO dfs.StateChange (FSNamesystem.java:allocateBlock(1332)) - BLOCK* NameSystem.allocateBlock: /data/file1. blk_5011233294003789081
[junit] 2008-04-17 12:12:59,553 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,557 INFO dfs.DataNode (DataNode.java:writeBlock(1087)) - Receiving block blk_5011233294003789081 src: /127.0.0.1:37833 dest: /127.0.0.1:37828
[junit] 2008-04-17 12:12:59,567 INFO dfs.DataNode (DataNode.java:writeBlock(1087)) - Receiving block blk_5011233294003789081 src: /127.0.0.1:37834 dest: /127.0.0.1:37826
[junit] 2008-04-17 12:12:59,568 INFO dfs.DataNode (DataNode.java:writeBlock(1087)) - Receiving block blk_5011233294003789081 src: /127.0.0.1:37835 dest: /127.0.0.1:37830
[junit] 2008-04-17 12:12:59,572 INFO dfs.DataNode (DataNode.java:lastDataNodeRun(1953)) - Received block blk_5011233294003789081 of size 100 from /127.0.0.1
[junit] 2008-04-17 12:12:59,572 INFO dfs.DataNode (DataNode.java:lastDataNodeRun(1971)) - PacketResponder 0 for block blk_5011233294003789081 terminating
[junit] 2008-04-17 12:12:59,573 INFO dfs.DataNode (DataNode.java:run(2047)) - Received block blk_5011233294003789081 of size 100 from /127.0.0.1
[junit] 2008-04-17 12:12:59,573 INFO dfs.DataNode (DataNode.java:run(2105)) - PacketResponder 1 for block blk_5011233294003789081 terminating
[junit] 2008-04-17 12:12:59,574 INFO dfs.DataNode (DataNode.java:run(2047)) - Received block blk_5011233294003789081 of size 100 from /127.0.0.1
[junit] 2008-04-17 12:12:59,574 INFO dfs.DataNode (DataNode.java:run(2105)) - PacketResponder 2 for block blk_5011233294003789081 terminating
[junit] 2008-04-17 12:12:59,574 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,575 INFO dfs.StateChange (FSNamesystem.java:addStoredBlock(2873)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:37830 is added to blk_5011233294003789081 size 100
[junit] 2008-04-17 12:12:59,576 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,575 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,576 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,577 INFO dfs.StateChange (FSNamesystem.java:addStoredBlock(2873)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:37826 is added to blk_5011233294003789081 size 100
[junit] 2008-04-17 12:12:59,577 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,578 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,578 INFO dfs.StateChange (FSNamesystem.java:addStoredBlock(2873)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:37828 is added to blk_5011233294003789081 size 100
[junit] 2008-04-17 12:12:59,579 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,579 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,579 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 37822, ugi=hudson,hudson
[junit] 2008-04-17 12:12:59,581 INFO dfs.DataNode (DataNode.java:offerService(676)) - BlockReport of 1 blocks got processed in 4 msecs
[junit] 2008-04-17 12:12:59,604 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,605 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 37822, ugi=hudson,hudson
[junit] 2008-04-17 12:12:59,605 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-04-17 12:12:59,625 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,690 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 37822, ugi=hudson,hudson
[junit] 2008-04-17 12:12:59,690 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-04-17 12:12:59,693 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 37822, ugi=null
[junit] 2008-04-17 12:12:59,710 INFO dfs.DataNode (DataNode.java:readBlock(1054)) - 127.0.0.1:37826 Served block blk_5011233294003789081 to /127.0.0.1
[junit] 2008-04-17 12:13:00,417 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 37822, ugi=null
[junit] 2008-04-17 12:13:00,417 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 37822, ugi=null
[junit] 2008-04-17 12:13:00,478 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 37822, ugi=Who,group1,group2
[junit] 2008-04-17 12:13:00,478 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 37822, ugi=null
[junit] 2008-04-17 12:13:00,480 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 37822, ugi=Who,group1,group2
[junit] 2008-04-17 12:13:00,480 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=Who,group1,group2
[junit] 2008-04-17 12:13:00,481 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(87)) - ACCESS CHECK: org.apache.hadoop.dfs.PermissionChecker@9c176c, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-04-17 12:13:00,481 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 37822, ugi=null
[junit] 2008-04-17 12:13:00,483 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 37822, ugi=Who,group1,group2
[junit] 2008-04-17 12:13:00,483 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=Who,group1,group2
[junit] 2008-04-17 12:13:00,484 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(87)) - ACCESS CHECK: org.apache.hadoop.dfs.PermissionChecker@1664a9b, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-04-17 12:13:00,484 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=Who,group1,group2
[junit] 2008-04-17 12:13:00,484 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(87)) - ACCESS CHECK: org.apache.hadoop.dfs.PermissionChecker@ac06d4, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-04-17 12:13:00,502 INFO ipc.Server (Server.java:run(898)) - IPC Server handler 3 on 37822, call mkdirs(/data/web2, rwxr-xr-x) from 127.0.0.1:37837: 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:173)
[junit] at org.apache.hadoop.dfs.PermissionChecker.check(PermissionChecker.java:154)
[junit] at org.apache.hadoop.dfs.PermissionChecker.checkPermission(PermissionChecker.java:102)
[junit] at org.apache.hadoop.dfs.FSNamesystem.checkPermission(FSNamesystem.java:4192)
[junit] at org.apache.hadoop.dfs.FSNamesystem.checkAncestorAccess(FSNamesystem.java:4162)
[junit] at org.apache.hadoop.dfs.FSNamesystem.mkdirsInternal(FSNamesystem.java:1613)
[junit] at org.apache.hadoop.dfs.FSNamesystem.mkdirs(FSNamesystem.java:1588)
[junit] at org.apache.hadoop.dfs.NameNode.mkdirs(NameNode.java:405)
[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:446)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:896)
[junit] 2008-04-17 12:13:00,503 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 37822, ugi=null
[junit] 2008-04-17 12:13:00,505 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 37822, ugi=Who,group1,group2
[junit] 2008-04-17 12:13:00,505 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=Who,group1,group2
[junit] 2008-04-17 12:13:00,506 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(87)) - ACCESS CHECK: org.apache.hadoop.dfs.PermissionChecker@82d603, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-04-17 12:13:00,506 INFO ipc.Server (Server.java:run(898)) - IPC Server handler 2 on 37822, call create(/data/file2, rwxr-xr-x, DFSClient_-480837530, true, 3, 67108864) from 127.0.0.1:37837: 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:173)
[junit] at org.apache.hadoop.dfs.PermissionChecker.check(PermissionChecker.java:154)
[junit] at org.apache.hadoop.dfs.PermissionChecker.checkPermission(PermissionChecker.java:102)
[junit] at org.apache.hadoop.dfs.FSNamesystem.checkPermission(FSNamesystem.java:4192)
[junit] at org.apache.hadoop.dfs.FSNamesystem.checkAncestorAccess(FSNamesystem.java:4162)
[junit] at org.apache.hadoop.dfs.FSNamesystem.startFileInternal(FSNamesystem.java:992)
[junit] at org.apache.hadoop.dfs.FSNamesystem.startFile(FSNamesystem.java:967)
[junit] at org.apache.hadoop.dfs.NameNode.create(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:585)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:446)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:896)
[junit] 2008-04-17 12:13:00,507 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 37822, ugi=null
[junit] 2008-04-17 12:13:00,511 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 37822, ugi=Who,group1,group2
[junit] 2008-04-17 12:13:00,512 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=Who,group1,group2
[junit] 2008-04-17 12:13:00,512 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(87)) - ACCESS CHECK: org.apache.hadoop.dfs.PermissionChecker@18c5e67, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=READ, subAccess=null
[junit] 2008-04-17 12:13:00,512 INFO ipc.Server (Server.java:run(898)) - IPC Server handler 7 on 37822, call getBlockLocations(/data/file1, 0, 671088640) from 127.0.0.1:37837: 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:173)
[junit] at org.apache.hadoop.dfs.PermissionChecker.checkPermission(PermissionChecker.java:108)
[junit] at org.apache.hadoop.dfs.FSNamesystem.checkPermission(FSNamesystem.java:4192)
[junit] at org.apache.hadoop.dfs.FSNamesystem.checkPathAccess(FSNamesystem.java:4152)
[junit] at org.apache.hadoop.dfs.FSNamesystem.getBlockLocations(FSNamesystem.java:786)
[junit] at org.apache.hadoop.dfs.NameNode.getBlockLocations(NameNode.java:242)
[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:446)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:896)
[junit] 2008-04-17 12:13:00,513 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 37822, ugi=null
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 2
[junit] 2008-04-17 12:13:01,487 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:37831
[junit] 2008-04-17 12:13:01,488 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@16ee3ec
[junit] 2008-04-17 12:13:01,602 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-04-17 12:13:01,677 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-04-17 12:13:01,759 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-04-17 12:13:01,759 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@189635d
[junit] 2008-04-17 12:13:01,760 INFO dfs.DataNode (DataNode.java:shutdown(503)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2008-04-17 12:13:01,761 INFO dfs.DataNode (DataNode.java:run(2685)) - 127.0.0.1:37830: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] Shutting down DataNode 1
[junit] 2008-04-17 12:13:01,761 INFO dfs.DataBlockScanner (DataBlockScanner.java:run(561)) - Exiting DataBlockScanner thread.
[junit] 2008-04-17 12:13:01,762 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:37829
[junit] 2008-04-17 12:13:01,762 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1784427
[junit] 2008-04-17 12:13:01,835 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-04-17 12:13:01,903 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-04-17 12:13:01,972 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-04-17 12:13:01,972 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@a415a3
[junit] 2008-04-17 12:13:01,973 INFO dfs.DataNode (DataNode.java:run(910)) - 127.0.0.1:37828:Exiting DataXceiveServer due to java.nio.channels.AsynchronousCloseException
[junit] 2008-04-17 12:13:01,973 INFO dfs.DataNode (DataNode.java:shutdown(503)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-04-17 12:13:01,973 INFO dfs.DataBlockScanner (DataBlockScanner.java:run(561)) - Exiting DataBlockScanner thread.
[junit] 2008-04-17 12:13:01,973 INFO dfs.DataNode (DataNode.java:run(2685)) - 127.0.0.1:37828: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] Shutting down DataNode 0
[junit] 2008-04-17 12:13:01,974 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 37822, ugi=null
[junit] 2008-04-17 12:13:01,975 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:37827
[junit] 2008-04-17 12:13:01,975 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@11b86c7
[junit] 2008-04-17 12:13:01,975 INFO util.ThreadedServer (ThreadedServer.java:run(656)) - Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=37827]
[junit] 2008-04-17 12:13:02,085 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-04-17 12:13:02,086 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 37822, ugi=null
[junit] 2008-04-17 12:13:02,155 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-04-17 12:13:02,221 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-04-17 12:13:02,221 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@2431b9
[junit] 2008-04-17 12:13:02,222 INFO dfs.DataNode (DataNode.java:shutdown(503)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2008-04-17 12:13:02,326 INFO dfs.DataBlockScanner (DataBlockScanner.java:run(561)) - Exiting DataBlockScanner thread.
[junit] 2008-04-17 12:13:02,326 INFO dfs.DataNode (DataNode.java:run(2685)) - 127.0.0.1:37826: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-04-17 12:13:02,328 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:37823
[junit] 2008-04-17 12:13:02,328 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@19b4748
[junit] 2008-04-17 12:13:02,394 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-04-17 12:13:02,458 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-04-17 12:13:02,525 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-04-17 12:13:02,525 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@22ce00
[junit] 2008-04-17 12:13:02,526 WARN fs.FSNamesystem (FSNamesystem.java:run(2280)) - ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2008-04-17 12:13:02,526 ERROR fs.FSNamesystem (FSNamesystem.java:run(1959)) - java.lang.InterruptedException
[junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:1815)
[junit] at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1850)
[junit] at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:359)
[junit] at org.apache.hadoop.dfs.FSNamesystem$ResolutionMonitor.run(FSNamesystem.java:1936)
[junit] at java.lang.Thread.run(Thread.java:595)
[junit]
[junit] 2008-04-17 12:13:02,526 INFO fs.FSNamesystem (FSEditLog.java:printStatistics(849)) - Number of transactions: 6 Total time for transactions(ms): 2 Number of syncs: 5 SyncTimes(ms): 49 41
[junit] 2008-04-17 12:13:02,527 INFO ipc.Server (Server.java:stop(1001)) - Stopping server on 37822
[junit] 2008-04-17 12:13:02,527 INFO ipc.Server (Server.java:run(928)) - IPC Server handler 0 on 37822: exiting
[junit] 2008-04-17 12:13:02,527 INFO ipc.Server (Server.java:run(928)) - IPC Server handler 6 on 37822: exiting
[junit] 2008-04-17 12:13:02,528 INFO ipc.Server (Server.java:run(928)) - IPC Server handler 8 on 37822: exiting
[junit] 2008-04-17 12:13:02,527 INFO ipc.Server (Server.java:run(928)) - IPC Server handler 9 on 37822: exiting
[junit] 2008-04-17 12:13:02,527 INFO ipc.Server (Server.java:run(928)) - IPC Server handler 7 on 37822: exiting
[junit] 2008-04-17 12:13:02,529 INFO ipc.Server (Server.java:run(329)) - Stopping IPC Server listener on 37822
[junit] 2008-04-17 12:13:02,530 INFO ipc.Server (Server.java:run(492)) - Stopping IPC Server Responder
[junit] 2008-04-17 12:13:02,527 INFO ipc.Server (Server.java:run(928)) - IPC Server handler 5 on 37822: exiting
[junit] 2008-04-17 12:13:02,527 INFO ipc.Server (Server.java:run(928)) - IPC Server handler 3 on 37822: exiting
[junit] 2008-04-17 12:13:02,527 INFO ipc.Server (Server.java:run(928)) - IPC Server handler 2 on 37822: exiting
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 21.166 sec
[junit] 2008-04-17 12:13:02,527 INFO ipc.Server (Server.java:run(928)) - IPC Server handler 1 on 37822: exiting
[junit] 2008-04-17 12:13:02,527 INFO ipc.Server (Server.java:run(928)) - IPC Server handler 4 on 37822: exiting
[junit] Running org.apache.hadoop.security.TestUnixUserGroupInformation
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.417 sec
[junit] Running org.apache.hadoop.util.TestGenericsUtil
[junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.155 sec
[junit] Running org.apache.hadoop.util.TestIndexedSort
[junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 0.585 sec
[junit] Running org.apache.hadoop.util.TestReflectionUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.646 sec
[junit] Running org.apache.hadoop.util.TestShell
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.435 sec
[junit] Running org.apache.hadoop.util.TestStringUtils
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.107 sec
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build.xml:590: Tests failed!
Total time: 62 minutes 46 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Updating HADOOP-3130
Updating HADOOP-3256
Updating HADOOP-2865
Updating HADOOP-3263
Updating HADOOP-3266
Updating HADOOP-3124
Updating HADOOP-3251
Updating HADOOP-3162
Updating HADOOP-3160
Updating HADOOP-2703
Hudson build is back to normal: Hadoop-trunk #464
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/464/changes