hadoop-common-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Hudson Server <hud...@hudson.zones.apache.org>
Subject Build failed in Hudson: Hadoop-trunk #463
Date Thu, 17 Apr 2008 12:13:56 GMT
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


Mime
View raw message