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/09/09 16:25:49 UTC
Build failed in Hudson: Hadoop-trunk #598
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/598/changes
Changes:
[tomwhite] HADOOP-3605. Make EC2 scripts show an error message if AWS_ACCOUNT_ID is unset. Contributed by Al Hoang.
[ddas] HADOOP-3970. Adding the testcase I forgot to add earlier.
[ddas] HADOOP-3970. Provides a way to recover counters written to JobHistory. Contributed by Amar Kamat.
[ddas] HADOOP-4100. Removes the cleanupTask scheduling from the Scheduler implementations and moves it to the JobTracker. Contributed by Amareshwari Sriramadasu.
[ddas] HADOOP-3581. Prevents memory intensive user tasks from taking down nodes. Contributed by Vinod K V.
------------------------------------------
[...truncated 210381 lines...]
[junit] 2008-09-09 14:25:22,179 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,180 INFO DataNode.clienttrace (BlockReceiver.java:run(906)) - src: /127.0.0.1:39280, dest: /127.0.0.1:39276, bytes: 100, op: HDFS_WRITE, cliID: DFSClient_1668571107, srvID: DS-2075858696-140.211.11.106-39276-1220970321919, blockid: blk_8473221122367429264_1001
[junit] 2008-09-09 14:25:22,181 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,181 INFO hdfs.StateChange (FSNamesystem.java:addStoredBlock(2856)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:39276 is added to blk_8473221122367429264_1001 size 100
[junit] 2008-09-09 14:25:22,181 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,182 INFO datanode.DataNode (BlockReceiver.java:run(970)) - PacketResponder 1 for block blk_8473221122367429264_1001 terminating
[junit] 2008-09-09 14:25:22,182 INFO DataNode.clienttrace (BlockReceiver.java:run(906)) - src: /127.0.0.1:39279, dest: /127.0.0.1:39270, bytes: 100, op: HDFS_WRITE, cliID: DFSClient_1668571107, srvID: DS-556718894-140.211.11.106-39270-1220970319217, blockid: blk_8473221122367429264_1001
[junit] 2008-09-09 14:25:22,183 INFO datanode.DataNode (BlockReceiver.java:run(970)) - PacketResponder 2 for block blk_8473221122367429264_1001 terminating
[junit] 2008-09-09 14:25:22,184 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,185 INFO hdfs.StateChange (FSNamesystem.java:addStoredBlock(2856)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:39270 is added to blk_8473221122367429264_1001 size 100
[junit] 2008-09-09 14:25:22,185 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,192 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 39266, ugi=hudson,hudson
[junit] 2008-09-09 14:25:22,232 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,233 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 39266, ugi=hudson,hudson
[junit] 2008-09-09 14:25:22,233 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-09 14:25:22,266 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(106)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/data/file1 dst=null perm=hudson:supergroup:rw-------
[junit] 2008-09-09 14:25:22,266 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,270 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 39266, ugi=hudson,hudson
[junit] 2008-09-09 14:25:22,270 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-09 14:25:22,272 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(106)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/data/file1 dst=null perm=null
[junit] 2008-09-09 14:25:22,273 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,281 INFO DataNode.clienttrace (BlockSender.java:sendBlock(396)) - src: /127.0.0.1:39276, dest: /127.0.0.1:39282, bytes: 104, op: HDFS_READ, cliID: DFSClient_1668571107, srvID: DS-2075858696-140.211.11.106-39276-1220970321919, blockid: blk_8473221122367429264_1001
[junit] 2008-09-09 14:25:22,282 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 39266, ugi=user1983379681,group1,group2
[junit] 2008-09-09 14:25:22,283 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,284 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 39266, ugi=user1983379681,group1,group2
[junit] 2008-09-09 14:25:22,284 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1983379681,group1,group2
[junit] 2008-09-09 14:25:22,285 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@ad7d80, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-09 14:25:22,285 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(106)) - ugi=user1983379681,group1,group2 ip=/127.0.0.1 cmd=mkdirs src=/data/child1 dst=null perm=hudson:supergroup:rwxr-xr-x
[junit] 2008-09-09 14:25:22,286 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,287 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 39266, ugi=user1983379681,group1,group2
[junit] 2008-09-09 14:25:22,288 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1983379681,group1,group2
[junit] 2008-09-09 14:25:22,288 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@16d64c5, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-09 14:25:22,289 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1983379681,group1,group2
[junit] 2008-09-09 14:25:22,289 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@f2c96c, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-09 14:25:22,290 INFO ipc.Server (Server.java:run(890)) - IPC Server handler 0 on 39266, call mkdirs(/data/child2, rwxr-xr-x) from 127.0.0.1:39283: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user1983379681, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user1983379681, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:156)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:104)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4284)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkAncestorAccess(FSNamesystem.java:4254)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.mkdirsInternal(FSNamesystem.java:1708)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.mkdirs(FSNamesystem.java:1677)
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.mkdirs(NameNode.java:468)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-09-09 14:25:22,291 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,293 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 39266, ugi=user1983379681,group1,group2
[junit] 2008-09-09 14:25:22,294 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1983379681,group1,group2
[junit] 2008-09-09 14:25:22,294 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@1082823, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-09 14:25:22,295 INFO ipc.Server (Server.java:run(890)) - IPC Server handler 4 on 39266, call create(/data/file2, rwxr-xr-x, DFSClient_1120992996, true, 3, 67108864) from 127.0.0.1:39283: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user1983379681, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user1983379681, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:156)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:104)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4284)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkAncestorAccess(FSNamesystem.java:4254)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.startFileInternal(FSNamesystem.java:1029)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.startFile(FSNamesystem.java:990)
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.create(NameNode.java:298)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-09-09 14:25:22,296 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,299 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 39266, ugi=user1983379681,group1,group2
[junit] 2008-09-09 14:25:22,299 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1983379681,group1,group2
[junit] 2008-09-09 14:25:22,300 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@1c70315, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=READ, subAccess=null
[junit] 2008-09-09 14:25:22,300 INFO ipc.Server (Server.java:run(890)) - IPC Server handler 2 on 39266, call getBlockLocations(/data/file1, 0, 671088640) from 127.0.0.1:39283: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user1983379681, access=READ, inode="file1":hudson:supergroup:rw-------
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user1983379681, access=READ, inode="file1":hudson:supergroup:rw-------
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:110)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4284)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPathAccess(FSNamesystem.java:4244)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlockLocations(FSNamesystem.java:740)
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.getBlockLocations(NameNode.java:269)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-09-09 14:25:22,301 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,304 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 39266, ugi=hudson,hudson
[junit] 2008-09-09 14:25:22,305 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-09 14:25:22,340 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(106)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/data dst=null perm=hudson:supergroup:rwxr-xr-x
[junit] 2008-09-09 14:25:22,341 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,342 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 39266, ugi=hudson,hudson
[junit] 2008-09-09 14:25:22,342 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-09 14:25:22,368 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(106)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/data/child1 dst=null perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-09-09 14:25:22,368 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,369 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 39266, ugi=hudson,hudson
[junit] 2008-09-09 14:25:22,369 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-09 14:25:22,396 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(106)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/ dst=null perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-09-09 14:25:22,396 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,397 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 39266, ugi=user1983379681,group1,group2
[junit] 2008-09-09 14:25:22,397 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1983379681,group1,group2
[junit] 2008-09-09 14:25:22,398 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@e41d4a, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-09 14:25:22,398 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1983379681,group1,group2
[junit] 2008-09-09 14:25:22,398 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@3f265b, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-09 14:25:22,442 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(106)) - ugi=user1983379681,group1,group2 ip=/127.0.0.1 cmd=mkdirs src=/foo/bar dst=null perm=user1983379681:supergroup:rwxr-xr-x
[junit] 2008-09-09 14:25:22,442 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,443 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 39266, ugi=user1983379681,group1,group2
[junit] 2008-09-09 14:25:22,444 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1983379681,group1,group2
[junit] 2008-09-09 14:25:22,444 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@d1b72d, doCheckOwner=false, ancestorAccess=null, parentAccess=WRITE, access=null, subAccess=null
[junit] 2008-09-09 14:25:22,444 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1983379681,group1,group2
[junit] 2008-09-09 14:25:22,445 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@511e0a, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-09 14:25:22,470 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(106)) - ugi=user1983379681,group1,group2 ip=/127.0.0.1 cmd=rename src=/foo/bar dst=/data/child1 perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-09-09 14:25:22,470 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 39266, ugi=null
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 2
[junit] 2008-09-09 14:25:22,473 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:39277
[junit] 2008-09-09 14:25:22,654 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,655 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 39266, ugi=null
[junit] 2008-09-09 14:25:22,655 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-09 14:25:22,722 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-09 14:25:22,723 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1458dcb
[junit] 2008-09-09 14:25:22,800 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-09 14:25:22,801 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@705d28
[junit] 2008-09-09 14:25:22,801 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 39278
[junit] 2008-09-09 14:25:22,801 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 39278: exiting
[junit] 2008-09-09 14:25:22,801 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 39278: exiting
[junit] 2008-09-09 14:25:22,802 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 39278: exiting
[junit] 2008-09-09 14:25:22,802 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 39278
[junit] 2008-09-09 14:25:22,803 INFO datanode.DataNode (DataNode.java:shutdown(563)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2008-09-09 14:25:22,803 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] 2008-09-09 14:25:23,080 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] 2008-09-09 14:25:23,810 INFO datanode.DataNode (DataNode.java:shutdown(563)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-09-09 14:25:23,810 INFO datanode.DataNode (DataNode.java:run(1119)) - DatanodeRegistration(127.0.0.1:39276, storageID=DS-2075858696-140.211.11.106-39276-1220970321919, infoPort=39277, ipcPort=39278):Finishing DataNode in: FSDataset{dirpath='/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data5/current,/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data6/current'}
[junit] 2008-09-09 14:25:23,810 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 39278
[junit] 2008-09-09 14:25:23,811 INFO datanode.DataNode (DataNode.java:shutdown(563)) - Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 1
[junit] 2008-09-09 14:25:23,812 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:39274
[junit] 2008-09-09 14:25:23,879 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-09 14:25:23,946 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-09 14:25:23,946 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@53abbb
[junit] 2008-09-09 14:25:24,011 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-09 14:25:24,011 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@1443800
[junit] 2008-09-09 14:25:24,011 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 39275
[junit] 2008-09-09 14:25:24,012 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 39275: exiting
[junit] 2008-09-09 14:25:24,012 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 39275
[junit] 2008-09-09 14:25:24,012 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 39275: exiting
[junit] 2008-09-09 14:25:24,012 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 39275: exiting
[junit] 2008-09-09 14:25:24,013 INFO datanode.DataNode (DataNode.java:shutdown(563)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2008-09-09 14:25:24,014 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] 2008-09-09 14:25:24,160 INFO datanode.DataNode (DataNode.java:run(1119)) - DatanodeRegistration(127.0.0.1:39273, storageID=DS-1006245065-140.211.11.106-39273-1220970321024, infoPort=39274, ipcPort=39275):Finishing DataNode in: FSDataset{dirpath='/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data3/current,/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data4/current'}
[junit] 2008-09-09 14:25:24,160 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 39275
[junit] 2008-09-09 14:25:24,160 INFO datanode.DataNode (DataNode.java:shutdown(563)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-09-09 14:25:24,161 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] 2008-09-09 14:25:25,020 INFO datanode.DataNode (DataNode.java:shutdown(563)) - Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 0
[junit] 2008-09-09 14:25:25,022 INFO util.ThreadedServer (ThreadedServer.java:run(656)) - Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=39271]
[junit] 2008-09-09 14:25:25,022 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:39271
[junit] 2008-09-09 14:25:25,091 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-09 14:25:25,155 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-09 14:25:25,155 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@c5577c
[junit] 2008-09-09 14:25:25,238 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-09 14:25:25,238 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@425eb9
[junit] 2008-09-09 14:25:25,239 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 39272
[junit] 2008-09-09 14:25:25,239 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 39272: exiting
[junit] 2008-09-09 14:25:25,239 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 39272: exiting
[junit] 2008-09-09 14:25:25,239 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 39272
[junit] 2008-09-09 14:25:25,240 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 39272: exiting
[junit] 2008-09-09 14:25:25,240 WARN datanode.DataNode (DataXceiverServer.java:run(90)) - DatanodeRegistration(127.0.0.1:39270, storageID=DS-556718894-140.211.11.106-39270-1220970319217, infoPort=39271, ipcPort=39272):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:85)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2008-09-09 14:25:25,240 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] 2008-09-09 14:25:25,241 INFO datanode.DataNode (DataNode.java:shutdown(563)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2008-09-09 14:25:25,242 INFO datanode.DataNode (DataNode.java:run(1119)) - DatanodeRegistration(127.0.0.1:39270, storageID=DS-556718894-140.211.11.106-39270-1220970319217, infoPort=39271, ipcPort=39272):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-09-09 14:25:25,242 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 39272
[junit] 2008-09-09 14:25:25,242 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] 2008-09-09 14:25:25,242 INFO datanode.DataNode (DataNode.java:shutdown(563)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-09-09 14:25:25,243 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:39267
[junit] 2008-09-09 14:25:25,244 INFO util.ThreadedServer (ThreadedServer.java:run(656)) - Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=39267]
[junit] 2008-09-09 14:25:25,309 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-09 14:25:25,381 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-09 14:25:25,381 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1719f30
[junit] 2008-09-09 14:25:25,442 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-09 14:25:25,442 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@13e4a5a
[junit] 2008-09-09 14:25:25,442 INFO namenode.FSNamesystem (FSEditLog.java:printStatistics(959)) - Number of transactions: 12 Total time for transactions(ms): 0 Number of syncs: 9 SyncTimes(ms): 181 101
[junit] 2008-09-09 14:25:25,443 WARN namenode.FSNamesystem (FSNamesystem.java:run(2263)) - ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2008-09-09 14:25:25,478 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 39266
[junit] 2008-09-09 14:25:25,479 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 3 on 39266: exiting
[junit] 2008-09-09 14:25:25,479 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 39266: exiting
[junit] 2008-09-09 14:25:25,480 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 39266: exiting
[junit] 2008-09-09 14:25:25,481 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] 2008-09-09 14:25:25,481 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 5 on 39266: exiting
[junit] 2008-09-09 14:25:25,490 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 7 on 39266: exiting
[junit] 2008-09-09 14:25:25,490 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 9 on 39266: exiting
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 17.94 sec
[junit] 2008-09-09 14:25:25,510 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 4 on 39266: exiting
[junit] 2008-09-09 14:25:25,520 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 39266: exiting
[junit] 2008-09-09 14:25:25,520 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 6 on 39266: exiting
[junit] 2008-09-09 14:25:25,522 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 8 on 39266: exiting
[junit] Running org.apache.hadoop.security.TestUnixUserGroupInformation
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.384 sec
[junit] Running org.apache.hadoop.util.TestGenericsUtil
[junit] 2008-09-09 14:25:26,923 WARN util.GenericOptionsParser (GenericOptionsParser.java:parseGeneralOptions(318)) - options parsing failed: Missing argument for option:jt
[junit] usage: general options are:
[junit] -archives <paths> comma separated archives to be unarchived
[junit] on the compute machines.
[junit] -conf <configuration file> specify an application configuration file
[junit] -D <property=value> use value for given property
[junit] -files <paths> comma separated files to be copied to the
[junit] map reduce cluster
[junit] -fs <local|namenode:port> specify a namenode
[junit] -jt <local|jobtracker:port> specify a job tracker
[junit] -libjars <paths> comma separated jar files to include in the
[junit] classpath.
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.214 sec
[junit] Running org.apache.hadoop.util.TestIndexedSort
[junit] sortRandom seed: -1351277750590711107(org.apache.hadoop.util.QuickSort)
[junit] testSorted seed: -7404112751662239880(org.apache.hadoop.util.QuickSort)
[junit] testAllEqual setting min/max at 157/295(org.apache.hadoop.util.QuickSort)
[junit] sortWritable seed: 4402837827491448524(org.apache.hadoop.util.QuickSort)
[junit] QuickSort degen cmp/swp: 23252/3713(org.apache.hadoop.util.QuickSort)
[junit] sortRandom seed: 6299934832759358772(org.apache.hadoop.util.HeapSort)
[junit] testSorted seed: 5753940525830801395(org.apache.hadoop.util.HeapSort)
[junit] testAllEqual setting min/max at 221/320(org.apache.hadoop.util.HeapSort)
[junit] sortWritable seed: -8853658519920785252(org.apache.hadoop.util.HeapSort)
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 1.668 sec
[junit] Running org.apache.hadoop.util.TestProcfsBasedProcessTree
[junit] 2008-09-09 14:25:29,796 INFO mapred.ProcfsBasedProcessTree (ProcfsBasedProcessTree.java:isAvailable(76)) - ProcfsBasedProcfsBasedProcessTree currently is supported only on Linux and Windows
[junit] ProcfsBasedProcessTree is not available on this system. Not testing
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.053 sec
[junit] Running org.apache.hadoop.util.TestReflectionUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.612 sec
[junit] Running org.apache.hadoop.util.TestShell
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.284 sec
[junit] Running org.apache.hadoop.util.TestStringUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.069 sec
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build.xml:658: Tests failed!
Total time: 86 minutes 8 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Hudson build is still unstable: Hadoop-trunk #612
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/612/changes
Hudson build is unstable: Hadoop-trunk #611
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/611/changes
Build failed in Hudson: Hadoop-trunk #610
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/610/changes
------------------------------------------
[...truncated 234371 lines...]
[junit] 2008-09-21 13:20:10,892 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 33265, ugi=null
[junit] 2008-09-21 13:20:10,892 INFO datanode.DataNode (BlockReceiver.java:lastDataNodeRun(808)) - PacketResponder 0 for block blk_5019928557741978344_1001 terminating
[junit] 2008-09-21 13:20:10,892 INFO hdfs.StateChange (FSNamesystem.java:addStoredBlock(2881)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:33269 is added to blk_5019928557741978344_1001 size 100
[junit] 2008-09-21 13:20:10,894 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 33265, ugi=null
[junit] 2008-09-21 13:20:10,896 INFO DataNode.clienttrace (BlockReceiver.java:run(907)) - src: /127.0.0.1:33279, dest: /127.0.0.1:33272, bytes: 100, op: HDFS_WRITE, cliID: DFSClient_1418094541, srvID: DS-2017318545-140.211.11.106-33272-1222003210135, blockid: blk_5019928557741978344_1001
[junit] 2008-09-21 13:20:10,896 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 33265, ugi=null
[junit] 2008-09-21 13:20:10,896 INFO hdfs.StateChange (FSNamesystem.java:addStoredBlock(2881)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:33272 is added to blk_5019928557741978344_1001 size 100
[junit] 2008-09-21 13:20:10,896 INFO datanode.DataNode (BlockReceiver.java:run(971)) - PacketResponder 1 for block blk_5019928557741978344_1001 terminating
[junit] 2008-09-21 13:20:10,897 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 33265, ugi=null
[junit] 2008-09-21 13:20:10,899 INFO DataNode.clienttrace (BlockReceiver.java:run(907)) - src: /127.0.0.1:33278, dest: /127.0.0.1:33275, bytes: 100, op: HDFS_WRITE, cliID: DFSClient_1418094541, srvID: DS-1155788798-140.211.11.106-33275-1222003210660, blockid: blk_5019928557741978344_1001
[junit] 2008-09-21 13:20:10,899 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 33265, ugi=null
[junit] 2008-09-21 13:20:10,900 INFO hdfs.StateChange (FSNamesystem.java:addStoredBlock(2881)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:33275 is added to blk_5019928557741978344_1001 size 100
[junit] 2008-09-21 13:20:10,900 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 33265, ugi=null
[junit] 2008-09-21 13:20:10,900 INFO datanode.DataNode (BlockReceiver.java:run(971)) - PacketResponder 2 for block blk_5019928557741978344_1001 terminating
[junit] 2008-09-21 13:20:10,902 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 33265, ugi=hudson,hudson
[junit] 2008-09-21 13:20:10,928 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 33265, ugi=null
[junit] 2008-09-21 13:20:10,929 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 33265, ugi=hudson,hudson
[junit] 2008-09-21 13:20:10,929 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-21 13:20:10,946 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(108)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/data/file1 dst=null perm=hudson:supergroup:rw-------
[junit] 2008-09-21 13:20:10,946 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 33265, ugi=null
[junit] 2008-09-21 13:20:10,950 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 33265, ugi=hudson,hudson
[junit] 2008-09-21 13:20:10,950 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-21 13:20:10,952 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(108)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/data/file1 dst=null perm=null
[junit] 2008-09-21 13:20:10,953 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 33265, ugi=null
[junit] 2008-09-21 13:20:10,961 INFO DataNode.clienttrace (BlockSender.java:sendBlock(396)) - src: /127.0.0.1:33275, dest: /127.0.0.1:33281, bytes: 104, op: HDFS_READ, cliID: DFSClient_1418094541, srvID: DS-1155788798-140.211.11.106-33275-1222003210660, blockid: blk_5019928557741978344_1001
[junit] 2008-09-21 13:20:10,963 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 33265, ugi=user1936612153,group1,group2
[junit] 2008-09-21 13:20:10,964 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 33265, ugi=null
[junit] 2008-09-21 13:20:10,965 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 33265, ugi=user1936612153,group1,group2
[junit] 2008-09-21 13:20:10,965 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1936612153,group1,group2
[junit] 2008-09-21 13:20:10,966 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@a6d35, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-21 13:20:10,966 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(108)) - ugi=user1936612153,group1,group2 ip=/127.0.0.1 cmd=mkdirs src=/data/child1 dst=null perm=hudson:supergroup:rwxr-xr-x
[junit] 2008-09-21 13:20:10,967 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 33265, ugi=null
[junit] 2008-09-21 13:20:10,968 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 33265, ugi=user1936612153,group1,group2
[junit] 2008-09-21 13:20:10,969 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1936612153,group1,group2
[junit] 2008-09-21 13:20:10,969 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@d844a9, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-21 13:20:10,970 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1936612153,group1,group2
[junit] 2008-09-21 13:20:10,970 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@16d64c5, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-21 13:20:10,971 INFO ipc.Server (Server.java:run(890)) - IPC Server handler 6 on 33265, call mkdirs(/data/child2, rwxr-xr-x) from 127.0.0.1:33282: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user1936612153, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user1936612153, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:156)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:104)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4329)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkAncestorAccess(FSNamesystem.java:4299)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.mkdirsInternal(FSNamesystem.java:1732)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.mkdirs(FSNamesystem.java:1701)
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.mkdirs(NameNode.java:471)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-09-21 13:20:10,972 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 33265, ugi=null
[junit] 2008-09-21 13:20:10,973 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 33265, ugi=user1936612153,group1,group2
[junit] 2008-09-21 13:20:10,973 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1936612153,group1,group2
[junit] 2008-09-21 13:20:10,974 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@1e9f5cc, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-21 13:20:10,974 INFO ipc.Server (Server.java:run(890)) - IPC Server handler 7 on 33265, call create(/data/file2, rwxr-xr-x, DFSClient_-339199343, true, 3, 67108864) from 127.0.0.1:33282: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user1936612153, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user1936612153, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:156)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:104)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4329)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkAncestorAccess(FSNamesystem.java:4299)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.startFileInternal(FSNamesystem.java:1030)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.startFile(FSNamesystem.java:991)
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.create(NameNode.java:301)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-09-21 13:20:10,975 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 33265, ugi=null
[junit] 2008-09-21 13:20:10,977 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 33265, ugi=user1936612153,group1,group2
[junit] 2008-09-21 13:20:10,978 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1936612153,group1,group2
[junit] 2008-09-21 13:20:10,978 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@c5186e, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=READ, subAccess=null
[junit] 2008-09-21 13:20:10,979 INFO ipc.Server (Server.java:run(890)) - IPC Server handler 8 on 33265, call getBlockLocations(/data/file1, 0, 671088640) from 127.0.0.1:33282: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user1936612153, access=READ, inode="file1":hudson:supergroup:rw-------
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user1936612153, access=READ, inode="file1":hudson:supergroup:rw-------
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:110)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4329)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPathAccess(FSNamesystem.java:4289)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlockLocations(FSNamesystem.java:744)
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.getBlockLocations(NameNode.java:272)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-09-21 13:20:10,980 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 33265, ugi=null
[junit] 2008-09-21 13:20:10,981 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 33265, ugi=hudson,hudson
[junit] 2008-09-21 13:20:10,981 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-21 13:20:10,998 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(108)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/data dst=null perm=hudson:supergroup:rwxr-xr-x
[junit] 2008-09-21 13:20:10,999 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 33265, ugi=null
[junit] 2008-09-21 13:20:11,000 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 33265, ugi=hudson,hudson
[junit] 2008-09-21 13:20:11,000 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-21 13:20:11,024 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(108)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/data/child1 dst=null perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-09-21 13:20:11,025 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 33265, ugi=null
[junit] 2008-09-21 13:20:11,026 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 33265, ugi=hudson,hudson
[junit] 2008-09-21 13:20:11,026 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-21 13:20:11,044 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(108)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/ dst=null perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-09-21 13:20:11,044 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 33265, ugi=null
[junit] 2008-09-21 13:20:11,045 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 33265, ugi=user1936612153,group1,group2
[junit] 2008-09-21 13:20:11,046 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1936612153,group1,group2
[junit] 2008-09-21 13:20:11,046 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@268cc6, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-21 13:20:11,046 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1936612153,group1,group2
[junit] 2008-09-21 13:20:11,047 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@e41d4a, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-21 13:20:11,070 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(108)) - ugi=user1936612153,group1,group2 ip=/127.0.0.1 cmd=mkdirs src=/foo/bar dst=null perm=user1936612153:supergroup:rwxr-xr-x
[junit] 2008-09-21 13:20:11,070 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 33265, ugi=null
[junit] 2008-09-21 13:20:11,071 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 33265, ugi=user1936612153,group1,group2
[junit] 2008-09-21 13:20:11,072 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1936612153,group1,group2
[junit] 2008-09-21 13:20:11,072 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@1ef3a22, doCheckOwner=false, ancestorAccess=null, parentAccess=WRITE, access=null, subAccess=null
[junit] 2008-09-21 13:20:11,072 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user1936612153,group1,group2
[junit] 2008-09-21 13:20:11,072 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@d1b72d, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-21 13:20:11,101 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(108)) - ugi=user1936612153,group1,group2 ip=/127.0.0.1 cmd=rename src=/foo/bar dst=/data/child1 perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-09-21 13:20:11,101 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 33265, ugi=null
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 2
[junit] 2008-09-21 13:20:11,104 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:33276
[junit] 2008-09-21 13:20:11,295 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-21 13:20:11,364 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-21 13:20:11,365 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@7e5130
[junit] 2008-09-21 13:20:11,442 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-21 13:20:11,443 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@6798eb
[junit] 2008-09-21 13:20:11,443 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 33277
[junit] 2008-09-21 13:20:11,443 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 33277: exiting
[junit] 2008-09-21 13:20:11,443 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 33277: exiting
[junit] 2008-09-21 13:20:11,444 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 33277
[junit] 2008-09-21 13:20:11,445 INFO datanode.DataNode (DataNode.java:shutdown(574)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2008-09-21 13:20:11,444 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 33277: exiting
[junit] 2008-09-21 13:20:11,445 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] 2008-09-21 13:20:11,794 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] 2008-09-21 13:20:12,454 INFO datanode.DataNode (DataNode.java:shutdown(574)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-09-21 13:20:12,455 INFO datanode.DataNode (DataNode.java:run(1130)) - DatanodeRegistration(127.0.0.1:33275, storageID=DS-1155788798-140.211.11.106-33275-1222003210660, infoPort=33276, ipcPort=33277):Finishing DataNode in: FSDataset{dirpath='/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data5/current,/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data6/current'}
[junit] 2008-09-21 13:20:12,455 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 33277
[junit] 2008-09-21 13:20:12,455 INFO datanode.DataNode (DataNode.java:shutdown(574)) - Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 1
[junit] 2008-09-21 13:20:12,457 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:33273
[junit] 2008-09-21 13:20:12,528 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-21 13:20:12,600 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-21 13:20:12,601 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1dae160
[junit] 2008-09-21 13:20:12,679 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-21 13:20:12,680 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@11ff451
[junit] 2008-09-21 13:20:12,680 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 33274
[junit] 2008-09-21 13:20:12,680 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 33274: exiting
[junit] 2008-09-21 13:20:12,680 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 33274: exiting
[junit] 2008-09-21 13:20:12,681 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 33274: exiting
[junit] 2008-09-21 13:20:12,681 WARN datanode.DataNode (DataXceiverServer.java:run(90)) - DatanodeRegistration(127.0.0.1:33272, storageID=DS-2017318545-140.211.11.106-33272-1222003210135, infoPort=33273, ipcPort=33274):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:85)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2008-09-21 13:20:12,681 INFO datanode.DataNode (DataNode.java:shutdown(574)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-09-21 13:20:12,682 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] 2008-09-21 13:20:12,682 INFO datanode.DataNode (DataNode.java:run(1130)) - DatanodeRegistration(127.0.0.1:33272, storageID=DS-2017318545-140.211.11.106-33272-1222003210135, infoPort=33273, ipcPort=33274):Finishing DataNode in: FSDataset{dirpath='/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data3/current,/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data4/current'}
[junit] 2008-09-21 13:20:12,683 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 33274
[junit] 2008-09-21 13:20:12,682 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] 2008-09-21 13:20:12,683 INFO datanode.DataNode (DataNode.java:shutdown(574)) - Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 0
[junit] 2008-09-21 13:20:12,685 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:33270
[junit] 2008-09-21 13:20:12,757 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-21 13:20:12,824 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-21 13:20:12,825 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@6576d5
[junit] 2008-09-21 13:20:12,891 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-21 13:20:12,892 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@9a18a0
[junit] 2008-09-21 13:20:12,892 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 33271
[junit] 2008-09-21 13:20:12,892 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 33271: exiting
[junit] 2008-09-21 13:20:12,893 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 33271
[junit] 2008-09-21 13:20:12,894 INFO datanode.DataNode (DataNode.java:shutdown(574)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2008-09-21 13:20:12,895 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 33271: exiting
[junit] 2008-09-21 13:20:12,895 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 33271: exiting
[junit] 2008-09-21 13:20:12,895 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 33265, ugi=null
[junit] 2008-09-21 13:20:12,895 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] 2008-09-21 13:20:12,896 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 33265, ugi=null
[junit] 2008-09-21 13:20:15,894 INFO datanode.DataNode (DataNode.java:run(1130)) - DatanodeRegistration(127.0.0.1:33269, storageID=DS-730327164-140.211.11.106-33269-1222003209646, infoPort=33270, ipcPort=33271):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-09-21 13:20:15,894 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 33271
[junit] 2008-09-21 13:20:15,895 INFO datanode.DataNode (DataNode.java:shutdown(574)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-09-21 13:20:15,896 INFO util.ThreadedServer (ThreadedServer.java:run(656)) - Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=33266]
[junit] 2008-09-21 13:20:15,896 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:33266
[junit] 2008-09-21 13:20:15,959 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-21 13:20:16,022 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-21 13:20:16,023 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@4133b0
[junit] 2008-09-21 13:20:16,084 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-21 13:20:16,084 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@15ccfb1
[junit] 2008-09-21 13:20:16,085 WARN namenode.FSNamesystem (FSNamesystem.java:run(2271)) - ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2008-09-21 13:20:16,085 INFO namenode.FSNamesystem (FSEditLog.java:printStatistics(959)) - Number of transactions: 12 Total time for transactions(ms): 0 Number of syncs: 9 SyncTimes(ms): 94 98
[junit] 2008-09-21 13:20:16,125 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 33265
[junit] 2008-09-21 13:20:16,125 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 33265: exiting
[junit] 2008-09-21 13:20:16,125 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 5 on 33265: exiting
[junit] 2008-09-21 13:20:16,126 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 3 on 33265: exiting
[junit] 2008-09-21 13:20:16,127 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 33265
[junit] 2008-09-21 13:20:16,127 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 6 on 33265: exiting
[junit] 2008-09-21 13:20:16,127 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 7 on 33265: exiting
[junit] 2008-09-21 13:20:16,128 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 9 on 33265: exiting
[junit] 2008-09-21 13:20:16,128 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 33265: exiting
[junit] 2008-09-21 13:20:16,128 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 33265: exiting
[junit] 2008-09-21 13:20:16,129 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 4 on 33265: exiting
[junit] 2008-09-21 13:20:16,129 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 8 on 33265: exiting
[junit] 2008-09-21 13:20:16,130 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 18.063 sec
[junit] Running org.apache.hadoop.security.TestUnixUserGroupInformation
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.381 sec
[junit] Running org.apache.hadoop.util.TestGenericsUtil
[junit] 2008-09-21 13:20:17,565 WARN util.GenericOptionsParser (GenericOptionsParser.java:parseGeneralOptions(348)) - options parsing failed: Missing argument for option:jt
[junit] usage: general options are:
[junit] -archives <paths> comma separated archives to be unarchived
[junit] on the compute machines.
[junit] -conf <configuration file> specify an application configuration file
[junit] -D <property=value> use value for given property
[junit] -files <paths> comma separated files to be copied to the
[junit] map reduce cluster
[junit] -fs <local|namenode:port> specify a namenode
[junit] -jt <local|jobtracker:port> specify a job tracker
[junit] -libjars <paths> comma separated jar files to include in the
[junit] classpath.
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.185 sec
[junit] Running org.apache.hadoop.util.TestIndexedSort
[junit] sortRandom seed: 8269643582083030591(org.apache.hadoop.util.QuickSort)
[junit] testSorted seed: 3059894523937588089(org.apache.hadoop.util.QuickSort)
[junit] testAllEqual setting min/max at 123/58(org.apache.hadoop.util.QuickSort)
[junit] sortWritable seed: 3576096865965676454(org.apache.hadoop.util.QuickSort)
[junit] QuickSort degen cmp/swp: 23252/3713(org.apache.hadoop.util.QuickSort)
[junit] sortRandom seed: 7354291177066902151(org.apache.hadoop.util.HeapSort)
[junit] testSorted seed: 1861997448398884595(org.apache.hadoop.util.HeapSort)
[junit] testAllEqual setting min/max at 276/118(org.apache.hadoop.util.HeapSort)
[junit] sortWritable seed: 5319822393385491504(org.apache.hadoop.util.HeapSort)
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 1.433 sec
[junit] Running org.apache.hadoop.util.TestProcfsBasedProcessTree
[junit] 2008-09-21 13:20:19,968 INFO mapred.ProcfsBasedProcessTree (ProcfsBasedProcessTree.java:isAvailable(76)) - ProcfsBasedProcfsBasedProcessTree currently is supported only on Linux and Windows
[junit] ProcfsBasedProcessTree is not available on this system. Not testing
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.054 sec
[junit] Running org.apache.hadoop.util.TestReflectionUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.671 sec
[junit] Running org.apache.hadoop.util.TestShell
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.228 sec
[junit] Running org.apache.hadoop.util.TestStringUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.079 sec
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build.xml:661: Tests failed!
Total time: 120 minutes 28 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Build failed in Hudson: Hadoop-trunk #609
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/609/changes
Changes:
[rangadi] HADOOP-4006. Clean up FSConstants and move some of the constants to better places. (Sanjay Radia via rangadi)
[nigel] Preparing for 0.20.0 development
[nigel] Preparing for release 0.19.0
[zshao] HADOOP-4205. Reverted to 697291 because of an erroneous commit of this file.
[zshao] HADOOP 4205. Fixed compilation problems.
[zshao] HADOOP-4205. hive: metastore and ql to use the refactored SerDe library.
[cdouglas] HADOOP-3019. Missed a line
[acmurthy] HADOOP-3924. Adding the missed TestJobKillAndFail.java.
[dhruba] HADOOP-4090. The hive scripts pick up hadoop from HADOOP_HOME
and then the path. (Raghotham Murthy via dhruba)
[acmurthy] HADOOP-3924. Added a 'KILLED' job status. Contributed by Subramaniam Krishnan.
[dhruba] HADOOP-4120. Hive interactive shell records the time taken by a
query. (Raghotham Murthy via dhruba)
[rangadi] HADOOP-3938. Disk space quotas for HDFS. This is similar to namespace
quotas in 0.18. (rangadi)
[omalley] HADOOP-3019. A new library to support total order partitions.
(cdouglas via omalley)
[szetszwo] HADOOP-4227. Remove the deprecated class org.apache.hadoop.fs.ShellCommand. (szetszwo)
[omalley] HADOOP-4181. Add .svn to .gitignore.
[hairong] HADOOP-4062. IPC client does not need to be synchronized on the output stream when a connection is closed. Contributed by Hairong Kuang.
[rangadi] HADOOP-3592. Fix a couple of possible file leaks in FileUtil (Bill de hOra via rangadi)
[dhruba] HADOOP-4077. Setting access and modification time for a file
requires write permissions on the file. (dhruba)
[hairong] HADOOP-2816. Cluster summary at name node web has confusing report for space utilization. Contributed by Suresh Srinivas.
[acmurthy] HADOOP-2165. Augmented JobHistory to include the URIs to the tasks' userlogs. Contributed by Vinod Kumar Vavilapalli.
[acmurthy] HADOOP-4124. Added a command-line switch to allow users to set job priorities, also allow it to be manipulated via the web-ui. Contributed by Hemanth Yamijala.
[cdouglas] HADOOP-3975. Change test-patch script to report working the dir
modifications preventing the suite from being run. Contributed by Ramya R.
[acmurthy] HADOOP-2536. Implement a JDBC based database input and output formats to allow Map-Reduce applications to work with databases. Contributed by Fredrik Hedberg and Enis Soztutar.
[nigel] Reverted HADOOP-3924 since it broke the eclipse-plugin compile. merge -r 697068:697067
------------------------------------------
[...truncated 251599 lines...]
[junit] 2008-09-20 13:21:32,719 INFO datanode.DataNode (BlockReceiver.java:run(971)) - PacketResponder 1 for block blk_-7357771567426804400_1001 terminating
[junit] 2008-09-20 13:21:32,720 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 59257, ugi=null
[junit] 2008-09-20 13:21:32,720 INFO hdfs.StateChange (FSNamesystem.java:addStoredBlock(2881)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:59261 is added to blk_-7357771567426804400_1001 size 100
[junit] 2008-09-20 13:21:32,720 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 59257, ugi=null
[junit] 2008-09-20 13:21:32,722 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 59257, ugi=null
[junit] 2008-09-20 13:21:32,723 INFO DataNode.clienttrace (BlockReceiver.java:run(907)) - src: /127.0.0.1:59270, dest: /127.0.0.1:59267, bytes: 100, op: HDFS_WRITE, cliID: DFSClient_-80402410, srvID: DS-1926660167-140.211.11.106-59267-1221916892443, blockid: blk_-7357771567426804400_1001
[junit] 2008-09-20 13:21:32,724 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 59257, ugi=null
[junit] 2008-09-20 13:21:32,724 INFO datanode.DataNode (BlockReceiver.java:run(971)) - PacketResponder 2 for block blk_-7357771567426804400_1001 terminating
[junit] 2008-09-20 13:21:32,724 INFO hdfs.StateChange (FSNamesystem.java:addStoredBlock(2881)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:59267 is added to blk_-7357771567426804400_1001 size 100
[junit] 2008-09-20 13:21:32,725 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 59257, ugi=null
[junit] 2008-09-20 13:21:32,727 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 59257, ugi=hudson,hudson
[junit] 2008-09-20 13:21:32,764 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 59257, ugi=null
[junit] 2008-09-20 13:21:32,765 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 59257, ugi=hudson,hudson
[junit] 2008-09-20 13:21:32,765 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-20 13:21:32,781 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(108)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/data/file1 dst=null perm=hudson:supergroup:rw-------
[junit] 2008-09-20 13:21:32,782 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 59257, ugi=null
[junit] 2008-09-20 13:21:32,785 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 59257, ugi=hudson,hudson
[junit] 2008-09-20 13:21:32,786 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-20 13:21:32,788 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(108)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/data/file1 dst=null perm=null
[junit] 2008-09-20 13:21:32,788 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 59257, ugi=null
[junit] 2008-09-20 13:21:32,797 INFO DataNode.clienttrace (BlockSender.java:sendBlock(396)) - src: /127.0.0.1:59261, dest: /127.0.0.1:59273, bytes: 104, op: HDFS_READ, cliID: DFSClient_-80402410, srvID: DS-787030575-140.211.11.106-59261-1221916890856, blockid: blk_-7357771567426804400_1001
[junit] 2008-09-20 13:21:32,800 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 59257, ugi=user2046093375,group1,group2
[junit] 2008-09-20 13:21:32,801 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 59257, ugi=null
[junit] 2008-09-20 13:21:32,804 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 59257, ugi=user2046093375,group1,group2
[junit] 2008-09-20 13:21:32,804 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user2046093375,group1,group2
[junit] 2008-09-20 13:21:32,805 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@9b59a2, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-20 13:21:32,806 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(108)) - ugi=user2046093375,group1,group2 ip=/127.0.0.1 cmd=mkdirs src=/data/child1 dst=null perm=hudson:supergroup:rwxr-xr-x
[junit] 2008-09-20 13:21:32,806 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 59257, ugi=null
[junit] 2008-09-20 13:21:32,807 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 59257, ugi=user2046093375,group1,group2
[junit] 2008-09-20 13:21:32,808 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user2046093375,group1,group2
[junit] 2008-09-20 13:21:32,808 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@14bc4e6, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-20 13:21:32,808 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user2046093375,group1,group2
[junit] 2008-09-20 13:21:32,809 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@16c06dd, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-20 13:21:32,809 INFO ipc.Server (Server.java:run(890)) - IPC Server handler 6 on 59257, call mkdirs(/data/child2, rwxr-xr-x) from 127.0.0.1:59274: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user2046093375, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user2046093375, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:156)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:104)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4329)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkAncestorAccess(FSNamesystem.java:4299)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.mkdirsInternal(FSNamesystem.java:1732)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.mkdirs(FSNamesystem.java:1701)
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.mkdirs(NameNode.java:471)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-09-20 13:21:32,810 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 59257, ugi=null
[junit] 2008-09-20 13:21:32,812 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 59257, ugi=user2046093375,group1,group2
[junit] 2008-09-20 13:21:32,812 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user2046093375,group1,group2
[junit] 2008-09-20 13:21:32,813 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@268cc6, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-20 13:21:32,813 INFO ipc.Server (Server.java:run(890)) - IPC Server handler 5 on 59257, call create(/data/file2, rwxr-xr-x, DFSClient_-270090176, true, 3, 67108864) from 127.0.0.1:59274: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user2046093375, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user2046093375, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:156)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:104)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4329)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkAncestorAccess(FSNamesystem.java:4299)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.startFileInternal(FSNamesystem.java:1030)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.startFile(FSNamesystem.java:991)
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.create(NameNode.java:301)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-09-20 13:21:32,814 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 59257, ugi=null
[junit] 2008-09-20 13:21:32,816 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 59257, ugi=user2046093375,group1,group2
[junit] 2008-09-20 13:21:32,816 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user2046093375,group1,group2
[junit] 2008-09-20 13:21:32,817 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@d1b72d, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=READ, subAccess=null
[junit] 2008-09-20 13:21:32,817 INFO ipc.Server (Server.java:run(890)) - IPC Server handler 3 on 59257, call getBlockLocations(/data/file1, 0, 671088640) from 127.0.0.1:59274: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user2046093375, access=READ, inode="file1":hudson:supergroup:rw-------
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user2046093375, access=READ, inode="file1":hudson:supergroup:rw-------
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:110)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4329)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPathAccess(FSNamesystem.java:4289)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlockLocations(FSNamesystem.java:744)
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.getBlockLocations(NameNode.java:272)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-09-20 13:21:32,818 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 59257, ugi=null
[junit] 2008-09-20 13:21:32,819 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 59257, ugi=hudson,hudson
[junit] 2008-09-20 13:21:32,820 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-20 13:21:32,849 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(108)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/data dst=null perm=hudson:supergroup:rwxr-xr-x
[junit] 2008-09-20 13:21:32,850 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 59257, ugi=null
[junit] 2008-09-20 13:21:32,850 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 59257, ugi=hudson,hudson
[junit] 2008-09-20 13:21:32,851 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-20 13:21:32,866 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(108)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/data/child1 dst=null perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-09-20 13:21:32,867 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 59257, ugi=null
[junit] 2008-09-20 13:21:32,868 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 59257, ugi=hudson,hudson
[junit] 2008-09-20 13:21:32,868 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-20 13:21:32,884 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(108)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/ dst=null perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-09-20 13:21:32,884 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 59257, ugi=null
[junit] 2008-09-20 13:21:32,885 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 59257, ugi=user2046093375,group1,group2
[junit] 2008-09-20 13:21:32,885 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user2046093375,group1,group2
[junit] 2008-09-20 13:21:32,886 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@2264da, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-20 13:21:32,886 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user2046093375,group1,group2
[junit] 2008-09-20 13:21:32,886 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@b34b1, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-20 13:21:32,934 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(108)) - ugi=user2046093375,group1,group2 ip=/127.0.0.1 cmd=mkdirs src=/foo/bar dst=null perm=user2046093375:supergroup:rwxr-xr-x
[junit] 2008-09-20 13:21:32,934 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 59257, ugi=null
[junit] 2008-09-20 13:21:32,935 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 59257, ugi=user2046093375,group1,group2
[junit] 2008-09-20 13:21:32,936 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user2046093375,group1,group2
[junit] 2008-09-20 13:21:32,936 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@107dcfe, doCheckOwner=false, ancestorAccess=null, parentAccess=WRITE, access=null, subAccess=null
[junit] 2008-09-20 13:21:32,937 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user2046093375,group1,group2
[junit] 2008-09-20 13:21:32,937 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@13dcd27, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-20 13:21:32,967 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(108)) - ugi=user2046093375,group1,group2 ip=/127.0.0.1 cmd=rename src=/foo/bar dst=/data/child1 perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-09-20 13:21:32,968 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 59257, ugi=null
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 2
[junit] 2008-09-20 13:21:32,970 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:59268
[junit] 2008-09-20 13:21:33,170 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-20 13:21:33,239 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-20 13:21:33,239 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1b59919
[junit] 2008-09-20 13:21:33,318 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-20 13:21:33,318 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@b9b618
[junit] 2008-09-20 13:21:33,319 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 59269
[junit] 2008-09-20 13:21:33,319 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 59269: exiting
[junit] 2008-09-20 13:21:33,320 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 59269: exiting
[junit] 2008-09-20 13:21:33,321 INFO datanode.DataNode (DataNode.java:shutdown(574)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2008-09-20 13:21:33,321 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] 2008-09-20 13:21:33,319 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 59269: exiting
[junit] 2008-09-20 13:21:33,319 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 59269
[junit] 2008-09-20 13:21:33,646 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] 2008-09-20 13:21:33,926 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 59257, ugi=null
[junit] 2008-09-20 13:21:33,926 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 59257, ugi=null
[junit] 2008-09-20 13:21:34,326 INFO datanode.DataNode (DataNode.java:shutdown(574)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-09-20 13:21:34,326 INFO datanode.DataNode (DataNode.java:run(1130)) - DatanodeRegistration(127.0.0.1:59267, storageID=DS-1926660167-140.211.11.106-59267-1221916892443, infoPort=59268, ipcPort=59269):Finishing DataNode in: FSDataset{dirpath='/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data5/current,/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data6/current'}
[junit] 2008-09-20 13:21:34,326 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 59269
[junit] 2008-09-20 13:21:34,327 INFO datanode.DataNode (DataNode.java:shutdown(574)) - Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 1
[junit] 2008-09-20 13:21:34,329 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:59265
[junit] 2008-09-20 13:21:34,398 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-20 13:21:34,467 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-20 13:21:34,467 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@6ef7ba
[junit] 2008-09-20 13:21:34,538 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-20 13:21:34,538 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@1da87db
[junit] 2008-09-20 13:21:34,538 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 59266
[junit] 2008-09-20 13:21:34,539 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 59257, ugi=null
[junit] 2008-09-20 13:21:34,539 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 59266: exiting
[junit] 2008-09-20 13:21:34,539 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 59266: exiting
[junit] 2008-09-20 13:21:34,539 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 59257, ugi=null
[junit] 2008-09-20 13:21:34,540 INFO datanode.DataNode (DataNode.java:shutdown(574)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2008-09-20 13:21:34,541 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 59266: exiting
[junit] 2008-09-20 13:21:34,541 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] 2008-09-20 13:21:34,616 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] 2008-09-20 13:21:35,546 INFO datanode.DataNode (DataNode.java:shutdown(574)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-09-20 13:21:35,546 INFO datanode.DataNode (DataNode.java:run(1130)) - DatanodeRegistration(127.0.0.1:59264, storageID=DS-1335379609-140.211.11.106-59264-1221916891445, infoPort=59265, ipcPort=59266):Finishing DataNode in: FSDataset{dirpath='/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data3/current,/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data4/current'}
[junit] 2008-09-20 13:21:35,546 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 59266
[junit] 2008-09-20 13:21:35,547 INFO datanode.DataNode (DataNode.java:shutdown(574)) - Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 0
[junit] 2008-09-20 13:21:35,548 INFO util.ThreadedServer (ThreadedServer.java:run(656)) - Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=59262]
[junit] 2008-09-20 13:21:35,549 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:59262
[junit] 2008-09-20 13:21:35,618 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-20 13:21:35,682 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-20 13:21:35,682 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@8a2023
[junit] 2008-09-20 13:21:35,749 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-20 13:21:35,749 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@baa31b
[junit] 2008-09-20 13:21:35,750 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 59263
[junit] 2008-09-20 13:21:35,750 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 59263: exiting
[junit] 2008-09-20 13:21:35,750 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 59263
[junit] 2008-09-20 13:21:35,751 WARN datanode.DataNode (DataXceiverServer.java:run(90)) - DatanodeRegistration(127.0.0.1:59261, storageID=DS-787030575-140.211.11.106-59261-1221916890856, infoPort=59262, ipcPort=59263):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:85)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2008-09-20 13:21:35,751 INFO datanode.DataNode (DataNode.java:shutdown(574)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2008-09-20 13:21:35,752 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 59263: exiting
[junit] 2008-09-20 13:21:35,752 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] 2008-09-20 13:21:35,752 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 59263: exiting
[junit] 2008-09-20 13:21:36,196 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] 2008-09-20 13:21:36,756 INFO datanode.DataNode (DataNode.java:shutdown(574)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-09-20 13:21:36,756 INFO datanode.DataNode (DataNode.java:run(1130)) - DatanodeRegistration(127.0.0.1:59261, storageID=DS-787030575-140.211.11.106-59261-1221916890856, infoPort=59262, ipcPort=59263):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-09-20 13:21:36,757 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 59263
[junit] 2008-09-20 13:21:36,757 INFO datanode.DataNode (DataNode.java:shutdown(574)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-09-20 13:21:36,758 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:59258
[junit] 2008-09-20 13:21:36,822 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-20 13:21:36,888 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-20 13:21:36,889 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@56fc16
[junit] 2008-09-20 13:21:36,950 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-20 13:21:36,951 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@652110
[junit] 2008-09-20 13:21:36,951 WARN namenode.FSNamesystem (FSNamesystem.java:run(2271)) - ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2008-09-20 13:21:36,951 INFO namenode.FSNamesystem (FSEditLog.java:printStatistics(959)) - Number of transactions: 12 Total time for transactions(ms): 2 Number of syncs: 9 SyncTimes(ms): 153 85
[junit] 2008-09-20 13:21:37,000 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 59257
[junit] 2008-09-20 13:21:37,001 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 3 on 59257: exiting
[junit] 2008-09-20 13:21:37,001 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 6 on 59257: exiting
[junit] 2008-09-20 13:21:37,002 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 59257: exiting
[junit] 2008-09-20 13:21:37,002 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 59257: exiting
[junit] 2008-09-20 13:21:37,003 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 59257: exiting
[junit] 2008-09-20 13:21:37,002 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 59257
[junit] 2008-09-20 13:21:37,005 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 8 on 59257: exiting
[junit] 2008-09-20 13:21:37,004 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 9 on 59257: exiting
[junit] 2008-09-20 13:21:37,004 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 7 on 59257: exiting
[junit] 2008-09-20 13:21:37,004 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 5 on 59257: exiting
[junit] 2008-09-20 13:21:37,003 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 4 on 59257: exiting
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 13.885 sec
[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] 2008-09-20 13:21:38,403 WARN util.GenericOptionsParser (GenericOptionsParser.java:parseGeneralOptions(348)) - options parsing failed: Missing argument for option:jt
[junit] usage: general options are:
[junit] -archives <paths> comma separated archives to be unarchived
[junit] on the compute machines.
[junit] -conf <configuration file> specify an application configuration file
[junit] -D <property=value> use value for given property
[junit] -files <paths> comma separated files to be copied to the
[junit] map reduce cluster
[junit] -fs <local|namenode:port> specify a namenode
[junit] -jt <local|jobtracker:port> specify a job tracker
[junit] -libjars <paths> comma separated jar files to include in the
[junit] classpath.
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.184 sec
[junit] Running org.apache.hadoop.util.TestIndexedSort
[junit] sortRandom seed: -324995284491724884(org.apache.hadoop.util.QuickSort)
[junit] testSorted seed: 5162691180813237525(org.apache.hadoop.util.QuickSort)
[junit] testAllEqual setting min/max at 147/40(org.apache.hadoop.util.QuickSort)
[junit] sortWritable seed: 1926832272379234422(org.apache.hadoop.util.QuickSort)
[junit] QuickSort degen cmp/swp: 23252/3713(org.apache.hadoop.util.QuickSort)
[junit] sortRandom seed: -6388555961047791958(org.apache.hadoop.util.HeapSort)
[junit] testSorted seed: 5221113769607664700(org.apache.hadoop.util.HeapSort)
[junit] testAllEqual setting min/max at 283/95(org.apache.hadoop.util.HeapSort)
[junit] sortWritable seed: 5841441678014910709(org.apache.hadoop.util.HeapSort)
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 1.551 sec
[junit] Running org.apache.hadoop.util.TestProcfsBasedProcessTree
[junit] 2008-09-20 13:21:40,863 INFO mapred.ProcfsBasedProcessTree (ProcfsBasedProcessTree.java:isAvailable(76)) - ProcfsBasedProcfsBasedProcessTree currently is supported only on Linux and Windows
[junit] ProcfsBasedProcessTree is not available on this system. Not testing
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.054 sec
[junit] Running org.apache.hadoop.util.TestReflectionUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.684 sec
[junit] Running org.apache.hadoop.util.TestShell
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.242 sec
[junit] Running org.apache.hadoop.util.TestStringUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.083 sec
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build.xml:661: Tests failed!
Total time: 123 minutes 23 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Build failed in Hudson: Hadoop-trunk #608
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/608/changes
Changes:
[ddas] HADOOP-3924. Adds a KILLED job status. Contributed by Subramaniam Krishnan.
[ddas] HADOOP-4213. Fixes NPE in TestLimitTasksPerJobTaskScheduler. Contributed by Sreekanth Ramakrishnan.
[ddas] HADOOP-3638. Caches the iFile index files in memory to reduce seeks. Contributed by Jothi Padmanabhan.
[tomwhite] HADOOP-4070. Provide a mechanism in Hive for registering UDFs from the query language.
[acmurthy] HADOOP-249. Reuse JVMs across Map-Reduce Tasks. Contributed by Devaraj Das.
[szetszwo] HADOOP-4176. Implement getFileChecksum(Path) in HftpFileSystem. (szetszwo)
[acmurthy] HADOOP-4194. Added the JobConf and JobID to job-related methods in obTrackerInstrumentation for better metrics. Contributed by Mac Yang.
[acmurthy] HADOOP-4083. Changed the configuration attribute queue.name to mapred.job.queue.name. Contributed by Hemanth Yamijala.
[zshao] HADOOP-4106. libhdfs: add time, permission and user attribute support (part 2). (Pete Wyckoff through zshao)
[omalley] HADOOP-3930. Add common interfaces for the pluggable schedulers and the
cli & gui clients. (Sreekanth Ramakrishnan via omalley)
[acmurthy] HADOOP-4121. Preserve cause for exception if the initialization of HistoryViewer for JobHistory fails. Contributed by Amareshwari Sri Ramadasu.
[zshao] HADOOP-4104. libhdfs: add time, permission and user attribute support. (Pete Wyckoff through zshao)
[dhruba] HADOOP-4084. Add explain plan capabilities to Hive Query Language.
(Ashish Thusoo via dhruba)
[nigel] HADOOP-4200. Fix a bug in the test-patch.sh script. Contributed by Ramya R
[ddas] HADOOP-2403. Escapes some special characters before logging to history files. Contributed by Amareshwari Sriramadasu.
[ddas] HADOOP-3829. Narrown down skipped records based on user acceptable value. Contributed by Sharad Agarwal.
[tomwhite] HADOOP-2411. Add support for larger CPU EC2 instance types. Contributed by Chris K Wensel.
[tomwhite] HADOOP-4117. Improve configurability of Hadoop EC2 instances.
[yhemanth] HADOOP-3959. Pass options specified in resource_manager.options to job submission. Contributed by Craig Macdonald and Vinod Kumar Vavilapalli.
[nigel] HADOOP-4075. test-patch.sh now spits out ant commands that it runs. Contributed by Ramya R
[acmurthy] HADOOP-3722. Fixed Hadoop Streaming and Hadoop Pipes to use the Tool interface and GenericOptionsParser. Contributed by Enis Soztutar
[omalley] HADOOP-4195. Close compressor before returning to codec pool.
From: Arun Murthy <ac...@apache.org>
[omalley] HADOOP-4184. Break the module dependencies between core, hdfs, and
mapred.
From: Tom White <to...@apache.org>
[omalley] HADOOP-4186. Factor LineReader out of LineRecordReader.
From: Tom White<to...@apache.org>
[dhruba] HADOOP-4138. Refactor the Hive SerDe library to better structure
the interfaces to the serializer and de-serializer.
(Zheng Shao via dhruba)
[szetszwo] HADOOP-4197. Update DATA_TRANSFER_VERSION for HADOOP-3981. (szetszwo)
[dhruba] HADOOP-4087. Hive Metastore API for php and python clients.
(Prasad Chakka via dhruba)
[hairong] HADOOP-4007. Remove the empty file DFSFileInfo.java.
------------------------------------------
[...truncated 3932 lines...]
[exec] * [42/17] [1/32] 0.207s 9.4Kb hadoop_archives.html
[exec] * [43/16] [0/0] 0.065s 6.3Kb hadoop_archives.pdf
[exec] ^ api/index.html
[exec] ^ releasenotes.html
[exec] ^ changes.html
[exec] * [44/16] [1/33] 0.766s 37.5Kb streaming.html
[exec] * [45/15] [0/0] 0.353s 51.1Kb streaming.pdf
[exec] * [46/14] [0/0] 0.015s 214b skin/images/rc-t-r-5-1header-2searchbox-3searchbox.png
[exec] * [47/13] [0/0] 0.635s 70.4Kb hod_user_guide.pdf
[exec] * [49/11] [0/0] 0.039s 3.9Kb skin/profile.css
[exec] ^ api/index.html
[exec] ^ releasenotes.html
[exec] ^ changes.html
[exec] * [50/11] [1/32] 0.509s 18.8Kb hdfs_permissions_guide.html
[exec] * [51/10] [0/0] 0.194s 18.2Kb hdfs_permissions_guide.pdf
[exec] ^ api/index.html
[exec] ^ releasenotes.html
[exec] ^ changes.html
[exec] * [52/10] [1/32] 0.38s 21.1Kb hdfs_shell.html
[exec] * [53/9] [0/0] 0.265s 28.7Kb hdfs_shell.pdf
[exec] ^ api/index.html
[exec] ^ releasenotes.html
[exec] ^ changes.html
[exec] * [54/9] [1/32] 0.419s 16.1Kb SLG_user_guide.html
[exec] * [55/8] [0/0] 0.135s 10.7Kb SLG_user_guide.pdf
[exec] * [56/7] [0/0] 0.109s 16.6Kb native_libraries.pdf
[exec] ^ api/index.html
[exec] ^ releasenotes.html
[exec] ^ changes.html
[exec] * [57/7] [1/34] 0.226s 22.1Kb hod_config_guide.html
[exec] * [58/6] [0/0] 0.145s 18.1Kb hod_config_guide.pdf
[exec] ^ api/index.html
[exec] ^ releasenotes.html
[exec] ^ changes.html
[exec] * [59/8] [3/35] 0.471s 38.9Kb hdfs_design.html
[exec] * [60/7] [0/0] 1.614s 90.6Kb hdfs_design.pdf
[exec] * [61/6] [0/0] 0.0090s 15.6Kb images/hdfsdatanodes.gif
[exec] * [62/5] [0/0] 0.01s 17.2Kb images/hdfsarchitecture.gif
[exec] * [63/4] [0/0] 0.022s 199b skin/images/rc-t-l-5-1header-2searchbox-3searchbox.png
[exec] * [64/3] [0/0] 0.074s 4.2Kb index.pdf
[exec] ^ api/index.html
[exec] ^ releasenotes.html
[exec] ^ changes.html
[exec] * [66/2] [1/37] 0.339s 27.6Kb hdfs_user_guide.html
[exec] * [67/1] [0/0] 0.227s 33.6Kb hdfs_user_guide.pdf
[exec] ^ api/index.html
[exec] ^ releasenotes.html
[exec] ^ changes.html
[exec] * [68/1] [1/32] 0.194s 8.5Kb hdfs_quota_admin_guide.html
[exec] * [69/0] [0/0] 0.033s 3.0Kb hdfs_quota_admin_guide.pdf
[exec] Total time: 0 minutes 41 seconds, Site size: 1,324,724 Site pages: 61
[exec]
[exec] Copying broken links file to site root.
[exec]
[exec] Copying 1 file to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/src/docs/build/site
[exec]
[exec] -----------------------------
[exec] Static site was successfully generated at:
[exec] /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/src/docs/build/site
[exec] ------------------------------
[exec]
[exec]
[exec] BUILD SUCCESSFUL
[exec] Total time: 49 seconds
[copy] Copying 103 files to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/docs
[style] Warning: the task name <style> is deprecated. Use <xslt> instead.
[style] Transforming into /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/docs
changes-to-html:
clover.setup:
clover.info:
[echo]
[echo] Clover not found. Code coverage reports disabled.
[echo]
clover:
init:
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/classes
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/tools
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/src
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/webapps/task/WEB-INF
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/webapps/job/WEB-INF
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/webapps/hdfs/WEB-INF
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/webapps/datanode/WEB-INF
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/webapps/secondary/WEB-INF
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/examples
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/ant
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/c++
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/classes
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/testjar
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/testshell
[touch] Creating /var/tmp/null393400967
[delete] Deleting: /var/tmp/null393400967
[copy] Copying 7 files to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/webapps
[copy] Copying 5 files to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/conf
[copy] Copying /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/conf/hadoop-env.sh.template to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/conf/hadoop-env.sh
[copy] Copying /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/conf/capacity-scheduler.xml.template to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/conf/capacity-scheduler.xml
[copy] Copying /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/conf/slaves.template to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/conf/slaves
[copy] Copying /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/conf/masters.template to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/conf/masters
[copy] Copying /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/conf/hadoop-site.xml.template to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/conf/hadoop-site.xml
[copy] Copying 2 files to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/src/contrib
[copy] Copying /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/src/contrib/ec2/bin/hadoop-ec2-env.sh.template to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/src/contrib/ec2/bin/hadoop-ec2-env.sh
[copy] Copying /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/src/contrib/index/conf/index-config.xml.template to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/src/contrib/index/conf/index-config.xml
record-parser:
compile-rcc-compiler:
[javac] Compiling 29 source files to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/classes
compile-core-classes:
[javac] Compiling 629 source files to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/classes
[javac] Note: Some input files use or override a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
[copy] Copying 3 files to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/classes
compile-core-native:
check-c++-makefiles:
create-c++-pipes-makefile:
create-c++-utils-makefile:
compile-c++-utils:
compile-c++-pipes:
compile-c++:
compile-core:
compile-libhdfs:
compile-contrib:
compile:
check-contrib:
init:
[echo] contrib: capacity-scheduler
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/capacity-scheduler
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/capacity-scheduler/classes
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/capacity-scheduler/test
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/capacity-scheduler/examples
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/capacity-scheduler/test/logs
init-contrib:
compile:
[echo] contrib: capacity-scheduler
[javac] Compiling 3 source files to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/capacity-scheduler/classes
Trying to override old definition of task macro_tar
init:
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/chukwa
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/chukwa/test
compile:
[javac] Compiling 113 source files to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/chukwa
[javac] Note: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/src/contrib/chukwa/src/java/org/apache/hadoop/chukwa/extraction/engine/ChukwaRecordJT.java uses unchecked or unsafe operations.
[javac] Note: Recompile with -Xlint:unchecked for details.
check-contrib:
init:
[echo] contrib: datajoin
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/datajoin
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/datajoin/classes
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/datajoin/test
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/datajoin/examples
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/datajoin/test/logs
init-contrib:
compile:
[echo] contrib: datajoin
[javac] Compiling 7 source files to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/datajoin/classes
[javac] Note: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/src/contrib/data_join/src/java/org/apache/hadoop/contrib/utils/join/DataJoinJob.java uses or overrides a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
[javac] Note: Some input files use unchecked or unsafe operations.
[javac] Note: Recompile with -Xlint:unchecked for details.
check-contrib:
init:
[echo] contrib: eclipse-plugin
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/eclipse-plugin
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/eclipse-plugin/classes
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/eclipse-plugin/test
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/eclipse-plugin/examples
[mkdir] Created dir: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/eclipse-plugin/test/logs
init-contrib:
compile:
[echo] contrib: eclipse-plugin
[javac] Compiling 45 source files to /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/contrib/eclipse-plugin/classes
[javac] /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/src/contrib/eclipse-plugin/src/java/org/apache/hadoop/eclipse/server/HadoopJob.java:296: cannot find symbol
[javac] symbol : method isKilled()
[javac] location: interface org.apache.hadoop.mapred.RunningJob
[javac] this.killed = running.isKilled();
[javac] ^
[javac] Note: /zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/src/contrib/eclipse-plugin/src/java/org/apache/hadoop/eclipse/server/JarModule.java uses or overrides a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
[javac] Note: Some input files use unchecked or unsafe operations.
[javac] Note: Recompile with -Xlint:unchecked for details.
[javac] 1 error
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build.xml:415: The following error occurred while executing this line:
/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/src/contrib/build.xml:30: The following error occurred while executing this line:
/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/src/contrib/build-contrib.xml:112: Compile failed; see the compiler error output for details.
Total time: 1 minute 15 seconds
+ RESULT=1
+ '[' 1 '!=' 0 ']'
+ echo 'Build Failed: remaining tests not run'
Build Failed: remaining tests not run
+ exit 1
Recording fingerprints
Publishing Javadoc
FATAL: Unable to copy Javadoc from http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/api to /export/home/hudson/hudson/jobs/Hadoop-trunk/javadoc
hudson.util.IOException2: Failed to copy http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/api/**/* to /export/home/hudson/hudson/jobs/Hadoop-trunk/javadoc
at hudson.FilePath$22.invoke(FilePath.java:811)
at hudson.FilePath$22.invoke(FilePath.java:782)
at hudson.FilePath.act(FilePath.java:319)
at hudson.FilePath.copyRecursiveTo(FilePath.java:782)
at hudson.FilePath.copyRecursiveTo(FilePath.java:768)
at hudson.tasks.JavadocArchiver.perform(JavadocArchiver.java:68)
at hudson.model.AbstractBuild$AbstractRunner.performAllBuildStep(AbstractBuild.java:318)
at hudson.model.AbstractBuild$AbstractRunner.performAllBuildStep(AbstractBuild.java:306)
at hudson.model.Build$RunnerImpl.post2(Build.java:122)
at hudson.model.AbstractBuild$AbstractRunner.post(AbstractBuild.java:291)
at hudson.model.Run.run(Run.java:809)
at hudson.model.Build.run(Build.java:84)
at hudson.model.ResourceController.execute(ResourceController.java:70)
at hudson.model.Executor.run(Executor.java:88)
Caused by: http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/ws/trunk/api not found.
at org.apache.tools.ant.types.AbstractFileSet.getDirectoryScanner(AbstractFileSet.java:422)
at org.apache.tools.ant.taskdefs.Copy.execute(Copy.java:453)
at hudson.FilePath$22.invoke(FilePath.java:808)
... 13 more
Recording test results
Build failed in Hudson: Hadoop-trunk #606
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/606/changes
Changes:
[ddas] HADOOP-4125. Handles Reduce cleanup tip on the web ui. Contributed by Amareshwari Sriramadasu.
[szetszwo] HADOOP-3623. Refactor LeaseManager. (szetszwo)
[omalley] HADOOP-4181. Expand abbreviation of 'br' to 'branch' for the git commands in
saveVersion.sh.
[lohit] HADOOP-3911. Add a check to fsck options to make sure -files is not the first option to resolve conflicts with GenericOptionsParser
[dhruba] HADOOP-4139. Optimize Hive multi group-by.
(Namin Jain via dhruba)
[omalley] HADOOP-4181. Include a .gitignore and saveVersion.sh change to support
developing under git. (omalley)
[hairong] HADOOP-4007. Remove DFSFileInfo. Contributed by Sanjay Radia.
[szetszwo] HADOOP-4174. Move fs image/edit log methods from ClientProtocol to NamenodeProtocol. (shv via szetszwo)
[szetszwo] HADOOP-4174. Move fs image/edit log methods from ClientProtocol to NamenodeProtocol. (shv via szetszwo)
[acmurthy] HADOOP-4129. Changed memory limits of TaskTracker and Tasks to be in KiloBytes rather than bytes. Contributed by Vinod Kumar Vavilapalli.
[ddas] HADOOP-3570. Includes user specified libjar files in the client side classpath path. Contributed by Sharad Agarwal.
[ddas] HADOOP-3245. Adds the feature for supporting JobTracker restart. Running jobs can be recovered from the history file. The history file format has been modified to support recovery. The task attempt ID now has the JobTracker start time to disinguish attempts of the same TIP across restarts. Contributed by Amar Ramesh Kamat.
------------------------------------------
[...truncated 217544 lines...]
[junit] 2008-09-17 13:40:26,844 INFO hdfs.StateChange (FSNamesystem.java:addStoredBlock(2857)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:37409 is added to blk_-7169221198705558734_1001 size 100
[junit] 2008-09-17 13:40:26,845 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 37399, ugi=null
[junit] 2008-09-17 13:40:26,873 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 37399, ugi=null
[junit] 2008-09-17 13:40:26,875 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 37399, ugi=hudson,hudson
[junit] 2008-09-17 13:40:26,875 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-17 13:40:26,900 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(107)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/data/file1 dst=null perm=hudson:supergroup:rw-------
[junit] 2008-09-17 13:40:26,901 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 37399, ugi=null
[junit] 2008-09-17 13:40:26,906 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 37399, ugi=hudson,hudson
[junit] 2008-09-17 13:40:26,907 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-17 13:40:26,909 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(107)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/data/file1 dst=null perm=null
[junit] 2008-09-17 13:40:26,910 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 37399, ugi=null
[junit] 2008-09-17 13:40:26,919 INFO DataNode.clienttrace (BlockSender.java:sendBlock(396)) - src: /127.0.0.1:37409, dest: /127.0.0.1:37415, bytes: 104, op: HDFS_READ, cliID: DFSClient_1083810992, srvID: DS-1482189001-140.211.11.106-37409-1221658826557, blockid: blk_-7169221198705558734_1001
[junit] 2008-09-17 13:40:26,923 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 37399, ugi=user839534559,group1,group2
[junit] 2008-09-17 13:40:26,923 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 37399, ugi=null
[junit] 2008-09-17 13:40:26,925 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 37399, ugi=user839534559,group1,group2
[junit] 2008-09-17 13:40:26,925 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user839534559,group1,group2
[junit] 2008-09-17 13:40:26,926 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@18e899c, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-17 13:40:26,927 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(107)) - ugi=user839534559,group1,group2 ip=/127.0.0.1 cmd=mkdirs src=/data/child1 dst=null perm=hudson:supergroup:rwxr-xr-x
[junit] 2008-09-17 13:40:26,928 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 37399, ugi=null
[junit] 2008-09-17 13:40:26,930 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 37399, ugi=user839534559,group1,group2
[junit] 2008-09-17 13:40:26,930 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user839534559,group1,group2
[junit] 2008-09-17 13:40:26,931 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@1dd7736, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-17 13:40:26,931 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user839534559,group1,group2
[junit] 2008-09-17 13:40:26,931 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@bd93cd, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-17 13:40:26,932 INFO ipc.Server (Server.java:run(890)) - IPC Server handler 3 on 37399, call mkdirs(/data/child2, rwxr-xr-x) from 127.0.0.1:37416: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user839534559, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user839534559, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:156)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:104)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4284)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkAncestorAccess(FSNamesystem.java:4254)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.mkdirsInternal(FSNamesystem.java:1709)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.mkdirs(FSNamesystem.java:1678)
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.mkdirs(NameNode.java:469)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-09-17 13:40:26,933 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 37399, ugi=null
[junit] 2008-09-17 13:40:26,937 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 37399, ugi=user839534559,group1,group2
[junit] 2008-09-17 13:40:26,938 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user839534559,group1,group2
[junit] 2008-09-17 13:40:26,938 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@1f8f8c8, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-17 13:40:26,939 INFO ipc.Server (Server.java:run(890)) - IPC Server handler 4 on 37399, call create(/data/file2, rwxr-xr-x, DFSClient_1903107708, true, 3, 67108864) from 127.0.0.1:37416: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user839534559, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user839534559, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:156)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:104)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4284)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkAncestorAccess(FSNamesystem.java:4254)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.startFileInternal(FSNamesystem.java:1030)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.startFile(FSNamesystem.java:991)
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.create(NameNode.java:299)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-09-17 13:40:26,940 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 37399, ugi=null
[junit] 2008-09-17 13:40:26,943 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 37399, ugi=user839534559,group1,group2
[junit] 2008-09-17 13:40:26,944 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user839534559,group1,group2
[junit] 2008-09-17 13:40:26,945 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@1533c8, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=READ, subAccess=null
[junit] 2008-09-17 13:40:26,945 INFO ipc.Server (Server.java:run(890)) - IPC Server handler 2 on 37399, call getBlockLocations(/data/file1, 0, 671088640) from 127.0.0.1:37416: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user839534559, access=READ, inode="file1":hudson:supergroup:rw-------
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user839534559, access=READ, inode="file1":hudson:supergroup:rw-------
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:110)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4284)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPathAccess(FSNamesystem.java:4244)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlockLocations(FSNamesystem.java:741)
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.getBlockLocations(NameNode.java:270)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-09-17 13:40:26,946 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 37399, ugi=null
[junit] 2008-09-17 13:40:26,949 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 37399, ugi=hudson,hudson
[junit] 2008-09-17 13:40:26,950 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-17 13:40:26,976 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(107)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/data dst=null perm=hudson:supergroup:rwxr-xr-x
[junit] 2008-09-17 13:40:26,976 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 37399, ugi=null
[junit] 2008-09-17 13:40:26,977 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 37399, ugi=hudson,hudson
[junit] 2008-09-17 13:40:26,978 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-17 13:40:27,003 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(107)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/data/child1 dst=null perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-09-17 13:40:27,004 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 37399, ugi=null
[junit] 2008-09-17 13:40:27,010 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 37399, ugi=hudson,hudson
[junit] 2008-09-17 13:40:27,010 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-17 13:40:27,030 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(107)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/ dst=null perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-09-17 13:40:27,030 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 37399, ugi=null
[junit] 2008-09-17 13:40:27,031 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 37399, ugi=user839534559,group1,group2
[junit] 2008-09-17 13:40:27,032 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user839534559,group1,group2
[junit] 2008-09-17 13:40:27,032 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@83e5f1, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-17 13:40:27,032 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user839534559,group1,group2
[junit] 2008-09-17 13:40:27,033 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@16d64c5, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-17 13:40:27,061 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(107)) - ugi=user839534559,group1,group2 ip=/127.0.0.1 cmd=mkdirs src=/foo/bar dst=null perm=user839534559:supergroup:rwxr-xr-x
[junit] 2008-09-17 13:40:27,062 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 37399, ugi=null
[junit] 2008-09-17 13:40:27,063 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 37399, ugi=user839534559,group1,group2
[junit] 2008-09-17 13:40:27,064 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user839534559,group1,group2
[junit] 2008-09-17 13:40:27,064 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@185fe0c, doCheckOwner=false, ancestorAccess=null, parentAccess=WRITE, access=null, subAccess=null
[junit] 2008-09-17 13:40:27,064 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user839534559,group1,group2
[junit] 2008-09-17 13:40:27,065 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@1e9f5cc, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-17 13:40:27,091 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(107)) - ugi=user839534559,group1,group2 ip=/127.0.0.1 cmd=rename src=/foo/bar dst=/data/child1 perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-09-17 13:40:27,092 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 37399, ugi=null
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 2
[junit] 2008-09-17 13:40:27,105 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:37410
[junit] 2008-09-17 13:40:27,334 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-17 13:40:27,402 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-17 13:40:27,403 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@182a033
[junit] 2008-09-17 13:40:27,483 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-17 13:40:27,483 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@18600d6
[junit] 2008-09-17 13:40:27,483 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 37411
[junit] 2008-09-17 13:40:27,484 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 37411: exiting
[junit] 2008-09-17 13:40:27,484 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 37411: exiting
[junit] 2008-09-17 13:40:27,485 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 37411: exiting
[junit] 2008-09-17 13:40:27,485 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 37411
[junit] 2008-09-17 13:40:27,487 WARN datanode.DataNode (DataXceiverServer.java:run(90)) - DatanodeRegistration(127.0.0.1:37409, storageID=DS-1482189001-140.211.11.106-37409-1221658826557, infoPort=37410, ipcPort=37411):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:85)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2008-09-17 13:40:27,488 INFO datanode.DataNode (DataNode.java:shutdown(568)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-09-17 13:40:27,489 INFO datanode.DataNode (DataNode.java:run(1124)) - DatanodeRegistration(127.0.0.1:37409, storageID=DS-1482189001-140.211.11.106-37409-1221658826557, infoPort=37410, ipcPort=37411):Finishing DataNode in: FSDataset{dirpath='/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data5/current,/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data6/current'}
[junit] 2008-09-17 13:40:27,489 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 37411
[junit] 2008-09-17 13:40:27,490 INFO datanode.DataNode (DataNode.java:shutdown(568)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-09-17 13:40:27,489 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] Shutting down DataNode 1
[junit] 2008-09-17 13:40:27,492 INFO util.ThreadedServer (ThreadedServer.java:run(656)) - Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=37407]
[junit] 2008-09-17 13:40:27,495 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:37407
[junit] 2008-09-17 13:40:27,564 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-17 13:40:27,640 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-17 13:40:27,641 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@e7d53
[junit] 2008-09-17 13:40:27,719 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-17 13:40:27,720 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@553763
[junit] 2008-09-17 13:40:27,720 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 37408
[junit] 2008-09-17 13:40:27,720 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 37408: exiting
[junit] 2008-09-17 13:40:27,720 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 37408: exiting
[junit] 2008-09-17 13:40:27,721 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 37408: exiting
[junit] 2008-09-17 13:40:27,721 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 37408
[junit] 2008-09-17 13:40:27,722 WARN datanode.DataNode (DataXceiverServer.java:run(90)) - DatanodeRegistration(127.0.0.1:37406, storageID=DS-1745820022-140.211.11.106-37406-1221658825726, infoPort=37407, ipcPort=37408):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:85)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2008-09-17 13:40:27,723 INFO datanode.DataNode (DataNode.java:shutdown(568)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-09-17 13:40:27,723 INFO datanode.DataNode (DataNode.java:run(1124)) - DatanodeRegistration(127.0.0.1:37406, storageID=DS-1745820022-140.211.11.106-37406-1221658825726, infoPort=37407, ipcPort=37408):Finishing DataNode in: FSDataset{dirpath='/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data3/current,/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data4/current'}
[junit] 2008-09-17 13:40:27,724 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 37408
[junit] 2008-09-17 13:40:27,723 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] 2008-09-17 13:40:27,724 INFO datanode.DataNode (DataNode.java:shutdown(568)) - Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 0
[junit] 2008-09-17 13:40:27,726 INFO util.ThreadedServer (ThreadedServer.java:run(656)) - Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=37404]
[junit] 2008-09-17 13:40:27,727 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:37404
[junit] 2008-09-17 13:40:27,799 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-17 13:40:27,863 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-17 13:40:27,864 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@e0f0ad
[junit] 2008-09-17 13:40:27,931 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-17 13:40:27,932 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@135daf
[junit] 2008-09-17 13:40:27,932 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 37405
[junit] 2008-09-17 13:40:27,933 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 37405: exiting
[junit] 2008-09-17 13:40:27,934 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 37405
[junit] 2008-09-17 13:40:27,935 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 37405: exiting
[junit] 2008-09-17 13:40:27,935 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 37405: exiting
[junit] 2008-09-17 13:40:27,936 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 37399, ugi=null
[junit] 2008-09-17 13:40:27,936 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 37399, ugi=null
[junit] 2008-09-17 13:40:27,938 WARN datanode.DataNode (DataXceiverServer.java:run(90)) - DatanodeRegistration(127.0.0.1:37403, storageID=DS-1412993813-140.211.11.106-37403-1221658824846, infoPort=37404, ipcPort=37405):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:85)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2008-09-17 13:40:27,939 INFO datanode.DataNode (DataNode.java:shutdown(568)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-09-17 13:40:27,939 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] 2008-09-17 13:40:27,940 INFO datanode.DataNode (DataNode.java:run(1124)) - DatanodeRegistration(127.0.0.1:37403, storageID=DS-1412993813-140.211.11.106-37403-1221658824846, infoPort=37404, ipcPort=37405):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-09-17 13:40:27,941 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 37405
[junit] 2008-09-17 13:40:27,941 INFO datanode.DataNode (DataNode.java:shutdown(568)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-09-17 13:40:27,942 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:37400
[junit] 2008-09-17 13:40:28,008 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-17 13:40:28,073 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-17 13:40:28,074 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@1b11b79
[junit] 2008-09-17 13:40:28,136 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-17 13:40:28,136 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@128635
[junit] 2008-09-17 13:40:28,137 WARN namenode.FSNamesystem (FSNamesystem.java:run(2264)) - ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2008-09-17 13:40:28,137 INFO namenode.FSNamesystem (FSEditLog.java:printStatistics(945)) - Number of transactions: 12 Total time for transactions(ms): 3 Number of syncs: 9 SyncTimes(ms): 116 109
[junit] 2008-09-17 13:40:28,172 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 37399
[junit] 2008-09-17 13:40:28,172 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 37399: exiting
[junit] 2008-09-17 13:40:28,173 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 37399: exiting
[junit] 2008-09-17 13:40:28,173 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 37399: exiting
[junit] 2008-09-17 13:40:28,173 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 6 on 37399: exiting
[junit] 2008-09-17 13:40:28,174 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 3 on 37399: exiting
[junit] 2008-09-17 13:40:28,174 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 5 on 37399: exiting
[junit] 2008-09-17 13:40:28,174 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 37399
[junit] 2008-09-17 13:40:28,174 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 7 on 37399: exiting
[junit] 2008-09-17 13:40:28,175 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 8 on 37399: exiting
[junit] 2008-09-17 13:40:28,174 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 4 on 37399: exiting
[junit] 2008-09-17 13:40:28,175 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 9 on 37399: exiting
[junit] 2008-09-17 13:40:28,176 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 15.426 sec
[junit] Running org.apache.hadoop.security.TestUnixUserGroupInformation
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.484 sec
[junit] Running org.apache.hadoop.util.TestGenericsUtil
[junit] 2008-09-17 13:40:29,774 WARN util.GenericOptionsParser (GenericOptionsParser.java:parseGeneralOptions(345)) - options parsing failed: Missing argument for option:jt
[junit] usage: general options are:
[junit] -archives <paths> comma separated archives to be unarchived
[junit] on the compute machines.
[junit] -conf <configuration file> specify an application configuration file
[junit] -D <property=value> use value for given property
[junit] -files <paths> comma separated files to be copied to the
[junit] map reduce cluster
[junit] -fs <local|namenode:port> specify a namenode
[junit] -jt <local|jobtracker:port> specify a job tracker
[junit] -libjars <paths> comma separated jar files to include in the
[junit] classpath.
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.187 sec
[junit] Running org.apache.hadoop.util.TestIndexedSort
[junit] sortRandom seed: -8170522536807500069(org.apache.hadoop.util.QuickSort)
[junit] testSorted seed: -4458332847728739565(org.apache.hadoop.util.QuickSort)
[junit] testAllEqual setting min/max at 323/39(org.apache.hadoop.util.QuickSort)
[junit] sortWritable seed: -7599422933167759046(org.apache.hadoop.util.QuickSort)
[junit] QuickSort degen cmp/swp: 23252/3713(org.apache.hadoop.util.QuickSort)
[junit] sortRandom seed: 5967690104260980561(org.apache.hadoop.util.HeapSort)
[junit] testSorted seed: -2482673484242788211(org.apache.hadoop.util.HeapSort)
[junit] testAllEqual setting min/max at 180/123(org.apache.hadoop.util.HeapSort)
[junit] sortWritable seed: 3938305031454151016(org.apache.hadoop.util.HeapSort)
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 1.614 sec
[junit] Running org.apache.hadoop.util.TestProcfsBasedProcessTree
[junit] 2008-09-17 13:40:32,511 INFO mapred.ProcfsBasedProcessTree (ProcfsBasedProcessTree.java:isAvailable(76)) - ProcfsBasedProcfsBasedProcessTree currently is supported only on Linux and Windows
[junit] ProcfsBasedProcessTree is not available on this system. Not testing
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.054 sec
[junit] Running org.apache.hadoop.util.TestReflectionUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.78 sec
[junit] Running org.apache.hadoop.util.TestShell
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.283 sec
[junit] Running org.apache.hadoop.util.TestStringUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.087 sec
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build.xml:658: Tests failed!
Total time: 108 minutes 2 seconds
Recording fingerprints
Publishing Javadoc
Recording test results
Hudson build is still unstable: Hadoop-trunk #605
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/605/changes
Hudson build is still unstable: Hadoop-trunk #604
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/604/changes
Hudson build is still unstable: Hadoop-trunk #603
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/603/changes
Hudson build is unstable: Hadoop-trunk #602
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/602/changes
Build failed in Hudson: Hadoop-trunk #601
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/601/changes
------------------------------------------
started
ERROR: svn: timed out waiting for server
svn: OPTIONS request failed on '/repos/asf/hadoop/core/trunk'
org.tmatesoft.svn.core.SVNException: svn: timed out waiting for server
svn: OPTIONS request failed on '/repos/asf/hadoop/core/trunk'
at org.tmatesoft.svn.core.internal.wc.SVNErrorManager.error(SVNErrorManager.java:102)
at org.tmatesoft.svn.core.internal.wc.SVNErrorManager.error(SVNErrorManager.java:86)
at org.tmatesoft.svn.core.internal.io.dav.http.HTTPConnection.request(HTTPConnection.java:545)
at org.tmatesoft.svn.core.internal.io.dav.http.HTTPConnection.request(HTTPConnection.java:255)
at org.tmatesoft.svn.core.internal.io.dav.http.HTTPConnection.request(HTTPConnection.java:243)
at org.tmatesoft.svn.core.internal.io.dav.DAVConnection.exchangeCapabilities(DAVConnection.java:452)
at org.tmatesoft.svn.core.internal.io.dav.DAVConnection.open(DAVConnection.java:95)
at org.tmatesoft.svn.core.internal.io.dav.DAVRepository.openConnection(DAVRepository.java:642)
at org.tmatesoft.svn.core.internal.io.dav.DAVRepository.testConnection(DAVRepository.java:95)
at hudson.scm.SubversionSCM$DescriptorImpl.checkRepositoryPath(SubversionSCM.java:1308)
at hudson.scm.SubversionSCM.repositoryLocationsExist(SubversionSCM.java:1369)
at hudson.scm.SubversionSCM.checkout(SubversionSCM.java:357)
at hudson.scm.SubversionSCM.checkout(SubversionSCM.java:321)
at hudson.model.AbstractProject.checkout(AbstractProject.java:631)
at hudson.model.AbstractBuild$AbstractRunner.checkout(AbstractBuild.java:260)
at hudson.model.AbstractBuild$AbstractRunner.run(AbstractBuild.java:234)
at hudson.model.Run.run(Run.java:791)
at hudson.model.Build.run(Build.java:84)
at hudson.model.ResourceController.execute(ResourceController.java:70)
at hudson.model.Executor.run(Executor.java:88)
Caused by: java.net.SocketTimeoutException: connect timed out
at java.net.PlainSocketImpl.socketConnect(Native Method)
at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:333)
at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:195)
at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:182)
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:366)
at java.net.Socket.connect(Socket.java:519)
at org.tmatesoft.svn.core.internal.util.SVNSocketFactory.createPlainSocket(SVNSocketFactory.java:53)
at org.tmatesoft.svn.core.internal.io.dav.http.HTTPConnection.connect(HTTPConnection.java:165)
at org.tmatesoft.svn.core.internal.io.dav.http.HTTPConnection.request(HTTPConnection.java:302)
... 17 more
Recording fingerprints
Publishing Javadoc
Recording test results
Hudson build is unstable: Hadoop-trunk #600
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/600/changes
Build failed in Hudson: Hadoop-trunk #599
Posted by Apache Hudson Server <hu...@hudson.zones.apache.org>.
See http://hudson.zones.apache.org/hudson/job/Hadoop-trunk/599/changes
Changes:
[yhemanth] HADOOP-4060. Modified HOD to rotate log files on the client side. Contributed by Vinod Kumar Vavilapalli.
[omalley] HADOOP-3934 Updating to reflect application to 0.18.1.
[dhruba] HADOOP-4113. Changes to libhdfs to not exit on its own, rather return
an error code to the caller. (Pete Wyckoff via dhruba)
[dhruba] HADOOP-4097. Make hive work well with speculative execution turned on.
(Joydeep Sen Sarma via dhruba)
[ddas] HADOOP-3702. Adds ChainMapper and ChainReducer classes allow composing chains of Maps and Reduces in a single Map/Reduce job, something like MAP+ / REDUCE MAP*. Contributed by Alejandro Abdelnur.
[ddas] HADOOP-3514. Inline the CRCs in intermediate files as opposed to reading it from a different .crc files. Contributed by Jothi Padmanabhan.
------------------------------------------
[...truncated 211134 lines...]
[junit] 2008-09-10 14:15:10,832 INFO DataNode.clienttrace (BlockReceiver.java:lastDataNodeRun(783)) - src: /127.0.0.1:55299, dest: /127.0.0.1:55294, bytes: 100, op: HDFS_WRITE, cliID: DFSClient_1429183689, srvID: DS-60861169-140.211.11.106-55294-1221056110617, blockid: blk_-7108174302973688870_1001
[junit] 2008-09-10 14:15:10,832 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 55284, ugi=null
[junit] 2008-09-10 14:15:10,833 INFO datanode.DataNode (BlockReceiver.java:lastDataNodeRun(807)) - PacketResponder 0 for block blk_-7108174302973688870_1001 terminating
[junit] 2008-09-10 14:15:10,833 INFO hdfs.StateChange (FSNamesystem.java:addStoredBlock(2856)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:55294 is added to blk_-7108174302973688870_1001 size 100
[junit] 2008-09-10 14:15:10,835 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 55284, ugi=null
[junit] 2008-09-10 14:15:10,835 INFO DataNode.clienttrace (BlockReceiver.java:run(906)) - src: /127.0.0.1:55298, dest: /127.0.0.1:55291, bytes: 100, op: HDFS_WRITE, cliID: DFSClient_1429183689, srvID: DS-1173247428-140.211.11.106-55291-1221056110110, blockid: blk_-7108174302973688870_1001
[junit] 2008-09-10 14:15:10,836 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 55284, ugi=null
[junit] 2008-09-10 14:15:10,836 INFO hdfs.StateChange (FSNamesystem.java:addStoredBlock(2856)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:55291 is added to blk_-7108174302973688870_1001 size 100
[junit] 2008-09-10 14:15:10,837 INFO datanode.DataNode (BlockReceiver.java:run(970)) - PacketResponder 1 for block blk_-7108174302973688870_1001 terminating
[junit] 2008-09-10 14:15:10,838 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 55284, ugi=null
[junit] 2008-09-10 14:15:10,839 INFO DataNode.clienttrace (BlockReceiver.java:run(906)) - src: /127.0.0.1:55297, dest: /127.0.0.1:55288, bytes: 100, op: HDFS_WRITE, cliID: DFSClient_1429183689, srvID: DS-1470077644-140.211.11.106-55288-1221056109132, blockid: blk_-7108174302973688870_1001
[junit] 2008-09-10 14:15:10,840 INFO datanode.DataNode (BlockReceiver.java:run(970)) - PacketResponder 2 for block blk_-7108174302973688870_1001 terminating
[junit] 2008-09-10 14:15:10,840 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 55284, ugi=null
[junit] 2008-09-10 14:15:10,840 INFO hdfs.StateChange (FSNamesystem.java:addStoredBlock(2856)) - BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:55288 is added to blk_-7108174302973688870_1001 size 100
[junit] 2008-09-10 14:15:10,841 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 55284, ugi=null
[junit] 2008-09-10 14:15:10,842 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 55284, ugi=hudson,hudson
[junit] 2008-09-10 14:15:10,867 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 55284, ugi=null
[junit] 2008-09-10 14:15:10,868 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 55284, ugi=hudson,hudson
[junit] 2008-09-10 14:15:10,869 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-10 14:15:10,901 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(106)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/data/file1 dst=null perm=hudson:supergroup:rw-------
[junit] 2008-09-10 14:15:10,901 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 55284, ugi=null
[junit] 2008-09-10 14:15:10,905 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 55284, ugi=hudson,hudson
[junit] 2008-09-10 14:15:10,905 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-10 14:15:10,907 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(106)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/data/file1 dst=null perm=null
[junit] 2008-09-10 14:15:10,908 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 55284, ugi=null
[junit] 2008-09-10 14:15:10,916 INFO DataNode.clienttrace (BlockSender.java:sendBlock(396)) - src: /127.0.0.1:55288, dest: /127.0.0.1:55300, bytes: 104, op: HDFS_READ, cliID: DFSClient_1429183689, srvID: DS-1470077644-140.211.11.106-55288-1221056109132, blockid: blk_-7108174302973688870_1001
[junit] 2008-09-10 14:15:10,919 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 55284, ugi=user-269730858,group1,group2
[junit] 2008-09-10 14:15:10,919 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 7 on 55284, ugi=null
[junit] 2008-09-10 14:15:10,921 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 55284, ugi=user-269730858,group1,group2
[junit] 2008-09-10 14:15:10,922 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-269730858,group1,group2
[junit] 2008-09-10 14:15:10,922 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@1631573, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-10 14:15:10,923 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(106)) - ugi=user-269730858,group1,group2 ip=/127.0.0.1 cmd=mkdirs src=/data/child1 dst=null perm=hudson:supergroup:rwxr-xr-x
[junit] 2008-09-10 14:15:10,924 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 5 on 55284, ugi=null
[junit] 2008-09-10 14:15:10,926 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 55284, ugi=user-269730858,group1,group2
[junit] 2008-09-10 14:15:10,926 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-269730858,group1,group2
[junit] 2008-09-10 14:15:10,927 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@a6d35, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-10 14:15:10,927 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-269730858,group1,group2
[junit] 2008-09-10 14:15:10,928 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@87d472, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-10 14:15:10,928 INFO ipc.Server (Server.java:run(890)) - IPC Server handler 6 on 55284, call mkdirs(/data/child2, rwxr-xr-x) from 127.0.0.1:55301: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user-269730858, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user-269730858, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:156)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:104)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4284)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkAncestorAccess(FSNamesystem.java:4254)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.mkdirsInternal(FSNamesystem.java:1708)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.mkdirs(FSNamesystem.java:1677)
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.mkdirs(NameNode.java:468)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-09-10 14:15:10,929 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 6 on 55284, ugi=null
[junit] 2008-09-10 14:15:10,931 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 55284, ugi=user-269730858,group1,group2
[junit] 2008-09-10 14:15:10,931 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-269730858,group1,group2
[junit] 2008-09-10 14:15:10,932 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@1978933, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-10 14:15:10,932 INFO ipc.Server (Server.java:run(890)) - IPC Server handler 4 on 55284, call create(/data/file2, rwxr-xr-x, DFSClient_326469012, true, 3, 67108864) from 127.0.0.1:55301: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user-269730858, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user-269730858, access=WRITE, inode="data":hudson:supergroup:rwxr-xr-x
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:156)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:104)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4284)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkAncestorAccess(FSNamesystem.java:4254)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.startFileInternal(FSNamesystem.java:1029)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.startFile(FSNamesystem.java:990)
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.create(NameNode.java:298)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-09-10 14:15:10,933 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 4 on 55284, ugi=null
[junit] 2008-09-10 14:15:10,937 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 55284, ugi=user-269730858,group1,group2
[junit] 2008-09-10 14:15:10,937 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-269730858,group1,group2
[junit] 2008-09-10 14:15:10,938 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@16d64c5, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=READ, subAccess=null
[junit] 2008-09-10 14:15:10,938 INFO ipc.Server (Server.java:run(890)) - IPC Server handler 0 on 55284, call getBlockLocations(/data/file1, 0, 671088640) from 127.0.0.1:55301: error: org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user-269730858, access=READ, inode="file1":hudson:supergroup:rw-------
[junit] org.apache.hadoop.fs.permission.AccessControlException: Permission denied: user=user-269730858, access=READ, inode="file1":hudson:supergroup:rw-------
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.check(PermissionChecker.java:175)
[junit] at org.apache.hadoop.hdfs.server.namenode.PermissionChecker.checkPermission(PermissionChecker.java:110)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPermission(FSNamesystem.java:4284)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.checkPathAccess(FSNamesystem.java:4244)
[junit] at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlockLocations(FSNamesystem.java:740)
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.getBlockLocations(NameNode.java:269)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:452)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:888)
[junit] 2008-09-10 14:15:10,939 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 0 on 55284, ugi=null
[junit] 2008-09-10 14:15:10,943 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 55284, ugi=hudson,hudson
[junit] 2008-09-10 14:15:10,943 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-10 14:15:10,962 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(106)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/data dst=null perm=hudson:supergroup:rwxr-xr-x
[junit] 2008-09-10 14:15:10,963 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 2 on 55284, ugi=null
[junit] 2008-09-10 14:15:10,964 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 55284, ugi=hudson,hudson
[junit] 2008-09-10 14:15:10,964 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-10 14:15:10,997 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(106)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/data/child1 dst=null perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-09-10 14:15:10,997 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 1 on 55284, ugi=null
[junit] 2008-09-10 14:15:10,998 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 55284, ugi=hudson,hudson
[junit] 2008-09-10 14:15:10,998 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=hudson,hudson
[junit] 2008-09-10 14:15:11,014 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(106)) - ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/ dst=null perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-09-10 14:15:11,015 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 3 on 55284, ugi=null
[junit] 2008-09-10 14:15:11,016 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 55284, ugi=user-269730858,group1,group2
[junit] 2008-09-10 14:15:11,016 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-269730858,group1,group2
[junit] 2008-09-10 14:15:11,017 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@119db9e, doCheckOwner=false, ancestorAccess=null, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-10 14:15:11,017 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-269730858,group1,group2
[junit] 2008-09-10 14:15:11,018 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@a166bd, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-10 14:15:11,042 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(106)) - ugi=user-269730858,group1,group2 ip=/127.0.0.1 cmd=mkdirs src=/foo/bar dst=null perm=user-269730858:supergroup:rwxr-xr-x
[junit] 2008-09-10 14:15:11,043 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 9 on 55284, ugi=null
[junit] 2008-09-10 14:15:11,044 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 55284, ugi=user-269730858,group1,group2
[junit] 2008-09-10 14:15:11,045 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-269730858,group1,group2
[junit] 2008-09-10 14:15:11,045 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@14bc4e6, doCheckOwner=false, ancestorAccess=null, parentAccess=WRITE, access=null, subAccess=null
[junit] 2008-09-10 14:15:11,045 DEBUG security.UserGroupInformation (PermissionChecker.java:<init>(40)) - ugi=user-269730858,group1,group2
[junit] 2008-09-10 14:15:11,046 DEBUG security.UserGroupInformation (PermissionChecker.java:checkPermission(88)) - ACCESS CHECK: org.apache.hadoop.hdfs.server.namenode.PermissionChecker@16c06dd, doCheckOwner=false, ancestorAccess=WRITE, parentAccess=null, access=null, subAccess=null
[junit] 2008-09-10 14:15:11,074 INFO FSNamesystem.audit (FSNamesystem.java:logAuditEvent(106)) - ugi=user-269730858,group1,group2 ip=/127.0.0.1 cmd=rename src=/foo/bar dst=/data/child1 perm=hudson:supergroup:rwxrwxrwx
[junit] 2008-09-10 14:15:11,075 DEBUG security.UserGroupInformation (UserGroupInformation.java:setCurrentUGI(46)) - IPC Server handler 8 on 55284, ugi=null
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 2
[junit] 2008-09-10 14:15:11,077 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:55295
[junit] 2008-09-10 14:15:11,263 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-10 14:15:11,332 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-10 14:15:11,332 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@60b407
[junit] 2008-09-10 14:15:11,409 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-10 14:15:11,409 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@4600c0
[junit] 2008-09-10 14:15:11,410 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 55296
[junit] 2008-09-10 14:15:11,411 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 55296: exiting
[junit] 2008-09-10 14:15:11,411 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 55296: exiting
[junit] 2008-09-10 14:15:11,412 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 55296: exiting
[junit] 2008-09-10 14:15:11,413 WARN datanode.DataNode (DataXceiverServer.java:run(90)) - DatanodeRegistration(127.0.0.1:55294, storageID=DS-60861169-140.211.11.106-55294-1221056110617, infoPort=55295, ipcPort=55296):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:152)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:85)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit]
[junit] 2008-09-10 14:15:11,414 INFO datanode.DataNode (DataNode.java:shutdown(563)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-09-10 14:15:11,415 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 55296
[junit] 2008-09-10 14:15:11,416 INFO datanode.DataNode (DataNode.java:run(1119)) - DatanodeRegistration(127.0.0.1:55294, storageID=DS-60861169-140.211.11.106-55294-1221056110617, infoPort=55295, ipcPort=55296):Finishing DataNode in: FSDataset{dirpath='/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data5/current,/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data6/current'}
[junit] 2008-09-10 14:15:11,417 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 55296
[junit] 2008-09-10 14:15:11,416 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] 2008-09-10 14:15:11,417 INFO datanode.DataNode (DataNode.java:shutdown(563)) - Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 1
[junit] 2008-09-10 14:15:11,419 INFO util.ThreadedServer (ThreadedServer.java:run(656)) - Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=55292]
[junit] 2008-09-10 14:15:11,419 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:55292
[junit] 2008-09-10 14:15:11,486 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-10 14:15:11,552 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-10 14:15:11,553 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@e7d53
[junit] 2008-09-10 14:15:11,618 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-10 14:15:11,618 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@1c79dfc
[junit] 2008-09-10 14:15:11,618 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 55293
[junit] 2008-09-10 14:15:11,619 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 55293: exiting
[junit] 2008-09-10 14:15:11,619 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 55293: exiting
[junit] 2008-09-10 14:15:11,619 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 55293: exiting
[junit] 2008-09-10 14:15:11,620 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] 2008-09-10 14:15:11,621 INFO datanode.DataNode (DataNode.java:shutdown(563)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2008-09-10 14:15:11,620 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 55293
[junit] 2008-09-10 14:15:11,622 INFO datanode.DataNode (DataNode.java:run(1119)) - DatanodeRegistration(127.0.0.1:55291, storageID=DS-1173247428-140.211.11.106-55291-1221056110110, infoPort=55292, ipcPort=55293):Finishing DataNode in: FSDataset{dirpath='/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data3/current,/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build/test/data/dfs/data/data4/current'}
[junit] 2008-09-10 14:15:11,621 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] 2008-09-10 14:15:11,623 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 55293
[junit] 2008-09-10 14:15:11,623 INFO datanode.DataNode (DataNode.java:shutdown(563)) - Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 0
[junit] 2008-09-10 14:15:11,625 INFO util.ThreadedServer (ThreadedServer.java:run(656)) - Stopping Acceptor ServerSocket[addr=localhost/127.0.0.1,port=0,localport=55289]
[junit] 2008-09-10 14:15:11,625 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:55289
[junit] 2008-09-10 14:15:11,692 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-10 14:15:11,758 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-10 14:15:11,759 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@9b8ff9
[junit] 2008-09-10 14:15:11,822 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-10 14:15:11,823 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@16b904d
[junit] 2008-09-10 14:15:11,823 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 55290
[junit] 2008-09-10 14:15:11,823 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 55290: exiting
[junit] 2008-09-10 14:15:11,823 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 55290: exiting
[junit] 2008-09-10 14:15:11,824 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 55290
[junit] 2008-09-10 14:15:11,824 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 55290: exiting
[junit] 2008-09-10 14:15:11,824 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] 2008-09-10 14:15:11,825 INFO datanode.DataNode (DataNode.java:shutdown(563)) - Waiting for threadgroup to exit, active threads is 1
[junit] 2008-09-10 14:15:11,826 INFO datanode.DataBlockScanner (DataBlockScanner.java:run(599)) - Exiting DataBlockScanner thread.
[junit] 2008-09-10 14:15:11,826 INFO datanode.DataNode (DataNode.java:run(1119)) - DatanodeRegistration(127.0.0.1:55288, storageID=DS-1470077644-140.211.11.106-55288-1221056109132, infoPort=55289, ipcPort=55290):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-09-10 14:15:11,827 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 55290
[junit] 2008-09-10 14:15:11,827 INFO datanode.DataNode (DataNode.java:shutdown(563)) - Waiting for threadgroup to exit, active threads is 0
[junit] 2008-09-10 14:15:11,828 INFO http.SocketListener (SocketListener.java:stop(212)) - Stopped SocketListener on 127.0.0.1:55285
[junit] 2008-09-10 14:15:11,892 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/static,/static]
[junit] 2008-09-10 14:15:11,957 INFO util.Container (Container.java:stop(156)) - Stopped HttpContext[/logs,/logs]
[junit] 2008-09-10 14:15:11,957 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.servlet.WebApplicationHandler@82d603
[junit] 2008-09-10 14:15:12,018 INFO util.Container (Container.java:stop(156)) - Stopped WebApplicationContext[/,/]
[junit] 2008-09-10 14:15:12,019 INFO util.Container (Container.java:stop(156)) - Stopped org.mortbay.jetty.Server@292cb2
[junit] 2008-09-10 14:15:12,019 WARN namenode.FSNamesystem (FSNamesystem.java:run(2263)) - ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 2008-09-10 14:15:12,020 INFO namenode.FSNamesystem (FSEditLog.java:printStatistics(959)) - Number of transactions: 12 Total time for transactions(ms): 1 Number of syncs: 9 SyncTimes(ms): 106 99
[junit] 2008-09-10 14:15:12,067 INFO ipc.Server (Server.java:stop(992)) - Stopping server on 55284
[junit] 2008-09-10 14:15:12,068 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 1 on 55284: exiting
[junit] 2008-09-10 14:15:12,068 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 0 on 55284: exiting
[junit] 2008-09-10 14:15:12,068 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 5 on 55284: exiting
[junit] 2008-09-10 14:15:12,069 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 3 on 55284: exiting
[junit] 2008-09-10 14:15:12,069 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 4 on 55284: exiting
[junit] 2008-09-10 14:15:12,068 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 2 on 55284: exiting
[junit] 2008-09-10 14:15:12,069 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 6 on 55284: exiting
[junit] 2008-09-10 14:15:12,070 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 8 on 55284: exiting
[junit] 2008-09-10 14:15:12,070 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 9 on 55284: exiting
[junit] 2008-09-10 14:15:12,070 INFO ipc.Server (Server.java:run(920)) - IPC Server handler 7 on 55284: exiting
[junit] 2008-09-10 14:15:12,070 INFO ipc.Server (Server.java:run(330)) - Stopping IPC Server listener on 55284
[junit] 2008-09-10 14:15:12,071 INFO ipc.Server (Server.java:run(502)) - Stopping IPC Server Responder
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 13.746 sec
[junit] Running org.apache.hadoop.security.TestUnixUserGroupInformation
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.376 sec
[junit] Running org.apache.hadoop.util.TestGenericsUtil
[junit] 2008-09-10 14:15:13,436 WARN util.GenericOptionsParser (GenericOptionsParser.java:parseGeneralOptions(318)) - options parsing failed: Missing argument for option:jt
[junit] usage: general options are:
[junit] -archives <paths> comma separated archives to be unarchived
[junit] on the compute machines.
[junit] -conf <configuration file> specify an application configuration file
[junit] -D <property=value> use value for given property
[junit] -files <paths> comma separated files to be copied to the
[junit] map reduce cluster
[junit] -fs <local|namenode:port> specify a namenode
[junit] -jt <local|jobtracker:port> specify a job tracker
[junit] -libjars <paths> comma separated jar files to include in the
[junit] classpath.
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 0.183 sec
[junit] Running org.apache.hadoop.util.TestIndexedSort
[junit] sortRandom seed: -4951932617900845933(org.apache.hadoop.util.QuickSort)
[junit] testSorted seed: 8981650654615919852(org.apache.hadoop.util.QuickSort)
[junit] testAllEqual setting min/max at 396/246(org.apache.hadoop.util.QuickSort)
[junit] sortWritable seed: -5203912953601546031(org.apache.hadoop.util.QuickSort)
[junit] QuickSort degen cmp/swp: 23252/3713(org.apache.hadoop.util.QuickSort)
[junit] sortRandom seed: 3556890908451920248(org.apache.hadoop.util.HeapSort)
[junit] testSorted seed: 5635835550619868444(org.apache.hadoop.util.HeapSort)
[junit] testAllEqual setting min/max at 139/182(org.apache.hadoop.util.HeapSort)
[junit] sortWritable seed: -5294017429715413870(org.apache.hadoop.util.HeapSort)
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 1.383 sec
[junit] Running org.apache.hadoop.util.TestProcfsBasedProcessTree
[junit] 2008-09-10 14:15:15,740 INFO mapred.ProcfsBasedProcessTree (ProcfsBasedProcessTree.java:isAvailable(76)) - ProcfsBasedProcfsBasedProcessTree currently is supported only on Linux and Windows
[junit] ProcfsBasedProcessTree is not available on this system. Not testing
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.054 sec
[junit] Running org.apache.hadoop.util.TestReflectionUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.698 sec
[junit] Running org.apache.hadoop.util.TestShell
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.229 sec
[junit] Running org.apache.hadoop.util.TestStringUtils
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.087 sec
BUILD FAILED
/zonestorage/hudson/home/hudson/hudson/jobs/Hadoop-trunk/workspace/trunk/build.xml:658: Tests failed!
Total time: 85 minutes 13 seconds
Recording fingerprints
Publishing Javadoc
Recording test results