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/06/01 09:19:12 UTC
Build failed in Hudson: Hadoop-trunk #508
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/508/changes
Changes:
[dhruba] HADOOP-2669. DFSClient locks pendingCreates appropriately. (dhruba)
[hairong] HADOOP-2159. Name node should not decrment blockSafe for invalid blocks. Contributed by Hairong Kuang.
[cutting] HADOOP-3468. Use Java 1.5 versions of jars added in HADOOP-3246.
------------------------------------------
[...truncated 128837 lines...]
[junit] 2008-06-01 07:14:52,499 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-06-01 07:14:52,499 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-06-01 07:14:52,534 INFO FSNamesystem.audit (FSNamesystem.java:mkdirs(1532)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=mkdirs path=/data/child1 perm=hudson:supergroup:rwxr-xr-x
[junit] 2008-06-01 07:14:52,534 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,536 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 32908, ugi=hudson,hudson
[junit] 2008-06-01 07:14:52,536 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-06-01 07:14:52,558 INFO FSNamesystem.audit (FSNamesystem.java:startFile(894)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=create path=/data/file1 perm=hudson:supergroup:rw-r--r--
[junit] 2008-06-01 07:14:52,559 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,561 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 32908, ugi=hudson,hudson
[junit] 2008-06-01 07:14:52,562 INFO dfs.StateChange (FSNamesystem.java:allocateBlock(1229)) - BLOCK* NameSystem.allocateBlock: /data/file1. blk_4254419316695428522_1001
[junit] 2008-06-01 07:14:52,563 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,565 INFO dfs.DataNode (DataNode.java:writeBlock(1136)) - Receiving block blk_4254419316695428522_1001 src: /127.0.0.1:32921 dest: /127.0.0.1:32915
[junit] 2008-06-01 07:14:52,567 INFO dfs.DataNode (DataNode.java:writeBlock(1136)) - Receiving block blk_4254419316695428522_1001 src: /127.0.0.1:32922 dest: /127.0.0.1:32912
[junit] 2008-06-01 07:14:52,569 INFO dfs.DataNode (DataNode.java:writeBlock(1136)) - Receiving block blk_4254419316695428522_1001 src: /127.0.0.1:32923 dest: /127.0.0.1:32918
[junit] 2008-06-01 07:14:52,573 INFO dfs.DataNode (DataNode.java:lastDataNodeRun(2070)) - Received block blk_4254419316695428522_1001 of size 100 from /127.0.0.1
[junit] 2008-06-01 07:14:52,573 INFO dfs.DataNode (DataNode.java:lastDataNodeRun(2088)) - PacketResponder 0 for block blk_4254419316695428522_1001 terminating
[junit] 2008-06-01 07:14:52,574 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,574 INFO dfs.DataNode (DataNode.java:run(2175)) - Received block blk_4254419316695428522_1001 of size 100 from /127.0.0.1
[junit] 2008-06-01 07:14:52,575 INFO dfs.DataNode (DataNode.java:run(2233)) - PacketResponder 1 for block blk_4254419316695428522_1001 terminating
[junit] 2008-06-01 07:14:52,576 INFO dfs.DataNode (DataNode.java:run(2175)) - Received block blk_4254419316695428522_1001 of size 100 from /127.0.0.1
[junit] 2008-06-01 07:14:52,576 INFO dfs.DataNode (DataNode.java:run(2233)) - PacketResponder 2 for block blk_4254419316695428522_1001 terminating
[junit] 2008-06-01 07:14:52,574 INFO dfs.StateChange (FSNamesystem.java:addStoredBlock(2668)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:32918 is added to blk_4254419316695428522_1001 size 100
[junit] 2008-06-01 07:14:52,577 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,578 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,578 INFO dfs.StateChange (FSNamesystem.java:addStoredBlock(2668)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:32912 is added to blk_4254419316695428522_1001 size 100
[junit] 2008-06-01 07:14:52,578 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,579 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,579 INFO dfs.StateChange (FSNamesystem.java:addStoredBlock(2668)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:32915 is added to blk_4254419316695428522_1001 size 100
[junit] 2008-06-01 07:14:52,579 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,580 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 32908, ugi=hudson,hudson
[junit] 2008-06-01 07:14:52,581 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,582 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,582 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,583 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,583 INFO dfs.DataNode (DataNode.java:offerService(714)) - BlockReport of 1 blocks got processed in 3 msecs
[junit] 2008-06-01 07:14:52,585 INFO dfs.DataNode (DataNode.java:offerService(714)) - BlockReport of 1 blocks got processed in 5 msecs
[junit] 2008-06-01 07:14:52,601 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,602 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 32908, ugi=hudson,hudson
[junit] 2008-06-01 07:14:52,602 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-06-01 07:14:52,633 INFO FSNamesystem.audit (FSNamesystem.java:setPermission(640)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission path=/data/file1 perm=hudson:supergroup:rw-------
[junit] 2008-06-01 07:14:52,634 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,645 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 32908, ugi=hudson,hudson
[junit] 2008-06-01 07:14:52,646 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-06-01 07:14:52,649 INFO FSNamesystem.audit (FSNamesystem.java:getBlockLocations(714)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=open path=/data/file1 perm=null
[junit] 2008-06-01 07:14:52,649 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,667 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 32908, ugi=user-740521715,group1,group2
[junit] 2008-06-01 07:14:52,668 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,668 INFO dfs.DataNode (DataNode.java:readBlock(1102)) - DatanodeRegistration(127.0.0.1:32915, storageID=DS-2110726970-140.211.11.106-32915-1212304489831, infoPort=32916, ipcPort=32917) Served block blk_4254419316695428522_1001 to /127.0.0.1
[junit] 2008-06-01 07:14:52,670 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 32908, ugi=user-740521715,group1,group2
[junit] 2008-06-01 07:14:52,670 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-740521715,group1,group2
[junit] 2008-06-01 07:14:52,671 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.dfs.PermissionChecker@1719f30, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-06-01 07:14:52,672 INFO FSNamesystem.audit (FSNamesystem.java:mkdirs(1532)) - ugi=user-740521715,group1,group2 ip=/127.0.0.1 cmd=mkdirs path=/data/child1 perm=hudson:supergroup:rwxr-xr-x
[junit] 2008-06-01 07:14:52,672 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,673 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 32908, ugi=user-740521715,group1,group2
[junit] 2008-06-01 07:14:52,673 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-740521715,group1,group2
[junit] 2008-06-01 07:14:52,674 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.dfs.PermissionChecker@8de972, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-06-01 07:14:52,674 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-740521715,group1,group2
[junit] 2008-06-01 07:14:52,675 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.dfs.PermissionChecker@d964af, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-06-01 07:14:52,687 INFO ipc.Server (Server.java:run(872)) - IPC Server handler 8 on 32908, call mkdirs(/data/child2, rwxr-xr-x) from 127.0.0.1:32925: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user-740521715, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user-740521715, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] at org.apache.hadoop.dfs.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.dfs.PermissionChecker.check(PermissionChecker.java:156)
[junit] at org.apache.hadoop.dfs.PermissionChecker.checkPermission(PermissionChecker.java:104)
[junit] at org.apache.hadoop.dfs.FSNamesystem.checkPermission(FSNamesystem.java:4058)
[junit] at org.apache.hadoop.dfs.FSNamesystem.checkAncestorAccess(FSNamesystem.java:4028)
[junit] at org.apache.hadoop.dfs.FSNamesystem.mkdirsInternal(FSNamesystem.java:1561)
[junit] at org.apache.hadoop.dfs.FSNamesystem.mkdirs(FSNamesystem.java:1528)
[junit] at org.apache.hadoop.dfs.NameNode.mkdirs(NameNode.java:412)
[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:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:870)
[junit] 2008-06-01 07:14:52,688 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,690 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 32908, ugi=user-740521715,group1,group2
[junit] 2008-06-01 07:14:52,691 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-740521715,group1,group2
[junit] 2008-06-01 07:14:52,691 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.dfs.PermissionChecker@c5e2cf, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-06-01 07:14:52,692 INFO ipc.Server (Server.java:run(872)) - IPC Server handler 6 on 32908, call create(/data/file2, rwxr-xr-x, DFSClient_1573449126, true, 3, 67108864) from 127.0.0.1:32925: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user-740521715, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user-740521715, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] at org.apache.hadoop.dfs.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.dfs.PermissionChecker.check(PermissionChecker.java:156)
[junit] at org.apache.hadoop.dfs.PermissionChecker.checkPermission(PermissionChecker.java:104)
[junit] at org.apache.hadoop.dfs.FSNamesystem.checkPermission(FSNamesystem.java:4058)
[junit] at org.apache.hadoop.dfs.FSNamesystem.checkAncestorAccess(FSNamesystem.java:4028)
[junit] at org.apache.hadoop.dfs.FSNamesystem.startFileInternal(FSNamesystem.java:922)
[junit] at org.apache.hadoop.dfs.FSNamesystem.startFile(FSNamesystem.java:889)
[junit] at org.apache.hadoop.dfs.NameNode.create(NameNode.java:284)
[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:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:870)
[junit] 2008-06-01 07:14:52,692 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,694 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 32908, ugi=user-740521715,group1,group2
[junit] 2008-06-01 07:14:52,695 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-740521715,group1,group2
[junit] 2008-06-01 07:14:52,695 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.dfs.PermissionChecker@1f8d077, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=READ, subAccess=null
[junit] 2008-06-01 07:14:52,696 INFO ipc.Server (Server.java:run(872)) - IPC Server handler 4 on 32908, call getBlockLocations(/data/file1, 0, 671088640) from 127.0.0.1:32925: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user-740521715, access=READ, inode="file1":hudson:supergroup:rw-------
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user-740521715, access=READ, inode="file1":hudson:supergroup:rw-------
[junit] at org.apache.hadoop.dfs.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.dfs.PermissionChecker.checkPermission(PermissionChecker.java:110)
[junit] at org.apache.hadoop.dfs.FSNamesystem.checkPermission(FSNamesystem.java:4058)
[junit] at org.apache.hadoop.dfs.FSNamesystem.checkPathAccess(FSNamesystem.java:4018)
[junit] at org.apache.hadoop.dfs.FSNamesystem.getBlockLocations(FSNamesystem.java:684)
[junit] at org.apache.hadoop.dfs.NameNode.getBlockLocations(NameNode.java:257)
[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:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:870)
[junit] 2008-06-01 07:14:52,696 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,698 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 32908, ugi=hudson,hudson
[junit] 2008-06-01 07:14:52,698 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-06-01 07:14:52,719 INFO FSNamesystem.audit (FSNamesystem.java:setPermission(640)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission path=/data perm=hudson:supergroup:rwxr-xr-x
[junit] 2008-06-01 07:14:52,720 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,721 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 32908, ugi=hudson,hudson
[junit] 2008-06-01 07:14:52,721 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-06-01 07:14:52,746 INFO FSNamesystem.audit (FSNamesystem.java:setPermission(640)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission path=/data/child1 perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-06-01 07:14:52,746 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 32908, ugi=null
[junit] 2008-06-01 07:14:52,747 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 32908, ugi=hudson,hudson
[junit] 2008-06-01 07:14:52,748 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-06-01 07:14:52,901 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 32908, ugi=null
[junit] 2008-06-01 07:14:53,047 INFO FSNamesystem.audit (FSNamesystem.java:setPermission(640)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission path=/ perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-06-01 07:14:53,047 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 32908, ugi=null
[junit] 2008-06-01 07:14:53,047 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 32908, ugi=null
[junit] 2008-06-01 07:14:53,049 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 32908, ugi=user-740521715,group1,group2
[junit] 2008-06-01 07:14:53,049 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-740521715,group1,group2
[junit] 2008-06-01 07:14:53,049 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.dfs.PermissionChecker@1991de1, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-06-01 07:14:53,050 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-740521715,group1,group2
[junit] 2008-06-01 07:14:53,050 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.dfs.PermissionChecker@178aae1, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-06-01 07:14:53,305 INFO FSNamesystem.audit (FSNamesystem.java:mkdirs(1532)) - ugi=user-740521715,group1,group2 ip=/127.0.0.1 cmd=mkdirs path=/foo/bar perm=user-740521715:supergroup:rwxr-xr-x
[junit] 2008-06-01 07:14:53,306 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 32908, ugi=null
[junit] 2008-06-01 07:14:53,307 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 32908, ugi=user-740521715,group1,group2
[junit] 2008-06-01 07:14:53,308 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-740521715,group1,group2
[junit] 2008-06-01 07:14:53,308 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.dfs.PermissionChecker@8932e8, doCheckOwner=false, ancestorAccess=null, parentAccess=WRITE, access=null, subAccess=null
[junit] 2008-06-01 07:14:53,308 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-740521715,group1,group2
[junit] 2008-06-01 07:14:53,309 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.dfs.PermissionChecker@16d3536, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-06-01 07:14:53,431 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 32908, ugi=null
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 2
[junit] 2008-06-01 07:14:53,433 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:32919
[junit] 2008-06-01 07:14:53,434 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@6de609
[junit] 2008-06-01 07:14:53,621 INFO util.ThreadedServer (ThreadedServer.java:run(656)) - Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=32919]
[junit] 2008-06-01 07:14:53,622 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-06-01 07:14:53,701 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-06-01 07:14:53,780 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-06-01 07:14:53,781 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@3aef16
[junit] 2008-06-01 07:14:53,781 INFO ipc.Server (Server.java:stop(971)) - Stopping server on 32920
[junit] 2008-06-01 07:14:53,782 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 0 on 32920: exiting
[junit] 2008-06-01 07:14:53,783 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 1 on 32920: exiting
[junit] 2008-06-01 07:14:53,783 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 2 on 32920: exiting
[junit] 2008-06-01 07:14:53,784 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 32920
[junit] 2008-06-01 07:14:53,781 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 32908, ugi=null
[junit] 2008-06-01 07:14:53,786 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 32908, ugi=null
[junit] 2008-06-01 07:14:53,787 INFO dfs.DataNode (DataNode.java:shutdown(541)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2008-06-01 07:14:53,789 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] 2008-06-01 07:14:54,790 INFO dfs.DataNode (DataNode.java:shutdown(541)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-06-01 07:14:54,790 INFO dfs.DataBlockScanner (DataBlockScanner.java:run(571)) - Exiting DataBlockScanner thread.
[junit] 2008-06-01 07:14:54,791 INFO dfs.DataNode (DataNode.java:run(2868)) - DatanodeRegistration(127.0.0.1:32918, storageID=DS-1000056150-140.211.11.106-32918-1212304490417, infoPort=32919, ipcPort=32920):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-06-01 07:14:54,793 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:32916
[junit] 2008-06-01 07:14:54,794 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@6d3b92
[junit] 2008-06-01 07:14:54,867 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-06-01 07:14:54,938 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-06-01 07:14:55,009 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-06-01 07:14:55,010 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@180cb01
[junit] 2008-06-01 07:14:55,010 INFO ipc.Server (Server.java:stop(971)) - Stopping server on 32917
[junit] 2008-06-01 07:14:55,010 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 0 on 32917: exiting
[junit] 2008-06-01 07:14:55,011 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 1 on 32917: exiting
[junit] 2008-06-01 07:14:55,011 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 2 on 32917: exiting
[junit] 2008-06-01 07:14:55,011 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 32917
[junit] 2008-06-01 07:14:55,012 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] 2008-06-01 07:14:55,013 INFO dfs.DataNode (DataNode.java:run(956)) - DatanodeRegistration(127.0.0.1:32915, storageID=DS-2110726970-140.211.11.106-32915-1212304489831, infoPort=32916, ipcPort=32917):Exiting DataXceiveServer due to java.nio.channels.AsynchronousCloseException
[junit] 2008-06-01 07:14:55,014 INFO dfs.DataNode (DataNode.java:shutdown(541)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-06-01 07:14:55,014 INFO dfs.DataBlockScanner (DataBlockScanner.java:run(571)) - Exiting DataBlockScanner thread.
[junit] 2008-06-01 07:14:55,014 INFO dfs.DataNode (DataNode.java:run(2868)) - DatanodeRegistration(127.0.0.1:32915, storageID=DS-2110726970-140.211.11.106-32915-1212304489831, infoPort=32916, ipcPort=32917):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-06-01 07:14:55,015 INFO util.ThreadedServer (ThreadedServer.java:run(656)) - Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=32913]
[junit] 2008-06-01 07:14:55,016 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:32913
[junit] 2008-06-01 07:14:55,017 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@19ea173
[junit] 2008-06-01 07:14:55,090 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-06-01 07:14:55,160 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-06-01 07:14:55,228 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-06-01 07:14:55,229 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@14b84c7
[junit] 2008-06-01 07:14:55,229 INFO ipc.Server (Server.java:stop(971)) - Stopping server on 32914
[junit] 2008-06-01 07:14:55,229 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 0 on 32914: exiting
[junit] 2008-06-01 07:14:55,229 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 2 on 32914: exiting
[junit] 2008-06-01 07:14:55,229 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 1 on 32914: exiting
[junit] 2008-06-01 07:14:55,230 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 32914
[junit] 2008-06-01 07:14:55,231 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] 2008-06-01 07:14:55,231 INFO dfs.DataNode (DataNode.java:shutdown(541)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2008-06-01 07:14:55,232 INFO dfs.DataBlockScanner (DataBlockScanner.java:run(571)) - Exiting DataBlockScanner thread.
[junit] 2008-06-01 07:14:55,232 INFO dfs.DataNode (DataNode.java:run(2868)) - DatanodeRegistration(127.0.0.1:32912, storageID=DS-2111466912-140.211.11.106-32912-1212304489358, infoPort=32913, ipcPort=32914):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-06-01 07:14:55,234 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 0.0.0.0:32909
[junit] 2008-06-01 07:14:55,234 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@b914b3
[junit] 2008-06-01 07:14:55,303 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-06-01 07:14:55,369 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-06-01 07:14:55,434 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-06-01 07:14:55,434 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@18bbf55
[junit] 2008-06-01 07:14:55,435 ERROR fs.FSNamesystem (FSNamesystem.java:run(1731)) - 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:1708)
[junit] at java.lang.Thread.run(Thread.java:595)
[junit]
[junit] 2008-06-01 07:14:55,435 WARN fs.FSNamesystem (FSNamesystem.java:run(2053)) - ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2008-06-01 07:14:55,436 INFO fs.FSNamesystem (FSEditLog.java:printStatistics(806)) - Number of transactions: 12 Total time for transactions(ms): 2 Number of syncs: 10 SyncTimes(ms): 479 336
[junit] 2008-06-01 07:14:55,436 INFO ipc.Server (Server.java:stop(971)) - Stopping server on 32908
[junit] 2008-06-01 07:14:55,437 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 1 on 32908: exiting
[junit] 2008-06-01 07:14:55,437 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 8 on 32908: exiting
[junit] 2008-06-01 07:14:55,437 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 7 on 32908: exiting
[junit] 2008-06-01 07:14:55,438 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 6 on 32908: exiting
[junit] 2008-06-01 07:14:55,438 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 3 on 32908: exiting
[junit] 2008-06-01 07:14:55,438 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 5 on 32908: exiting
[junit] 2008-06-01 07:14:55,437 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 4 on 32908: exiting
[junit] 2008-06-01 07:14:55,439 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 9 on 32908: exiting
[junit] 2008-06-01 07:14:55,439 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 2 on 32908: exiting
[junit] 2008-06-01 07:14:55,440 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 17.114 sec
[junit] 2008-06-01 07:14:55,437 INFO ipc.Server (Server.java:run(902)) - IPC Server handler 0 on 32908: exiting
[junit] Running org.apache.hadoop.security.TestUnixUserGroupInformation
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.391 sec
[junit] Running org.apache.hadoop.util.TestGenericsUtil
[junit] 2008-06-01 07:14:58,708 WARN util.GenericOptionsParser (GenericOptionsParser.java:parseGeneralOptions(221)) - options parsing failed: Missing argument for option:jt
[junit] usage: general options are:
[junit] -conf <configuration file> specify an application configuration file
[junit] -D <property=value> use value for given property
[junit] -fs <local|namenode:port> specify a namenode
[junit] -jt <local|jobtracker:port> specify a job tracker
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.245 sec
[junit] Running org.apache.hadoop.util.TestIndexedSort
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.733 sec
[junit] Running org.apache.hadoop.util.TestReflectionUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.642 sec
[junit] Running org.apache.hadoop.util.TestShell
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.277 sec
[junit] Running org.apache.hadoop.util.TestStringUtils
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.106 sec
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build.xml:598: Tests failed!
Total time: 88 minutes 55 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Hudson build is back to normal: Hadoop-trunk #509
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/509/changes