See <http://hudson.zones.apache.org/hudson/job/Pig-trunk/602/>

------------------------------------------
[...truncated 176569 lines...]
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: Receiving block 
blk_-4558730747491505513_1014 src: /127.0.0.1:42899 dest: /127.0.0.1:40327
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: Receiving block 
blk_-4558730747491505513_1014 src: /127.0.0.1:45680 dest: /127.0.0.1:42261
    [junit] 09/10/26 12:36:28 INFO DataNode.clienttrace: src: /127.0.0.1:45680, 
dest: /127.0.0.1:42261, bytes: 1589, op: HDFS_WRITE, cliID: 
DFSClient_-158476400, srvID: DS-1904490549-127.0.1.1-42261-1256560555548, 
blockid: blk_-4558730747491505513_1014
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: PacketResponder 0 for 
block blk_-4558730747491505513_1014 terminating
    [junit] 09/10/26 12:36:28 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:42261 is added to 
blk_-4558730747491505513_1014 size 1589
    [junit] 09/10/26 12:36:28 INFO DataNode.clienttrace: src: /127.0.0.1:42899, 
dest: /127.0.0.1:40327, bytes: 1589, op: HDFS_WRITE, cliID: 
DFSClient_-158476400, srvID: DS-395625956-127.0.1.1-40327-1256560556478, 
blockid: blk_-4558730747491505513_1014
    [junit] 09/10/26 12:36:28 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:40327 is added to 
blk_-4558730747491505513_1014 size 1589
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: PacketResponder 1 for 
block blk_-4558730747491505513_1014 terminating
    [junit] 09/10/26 12:36:28 INFO DataNode.clienttrace: src: /127.0.0.1:50126, 
dest: /127.0.0.1:48298, bytes: 1589, op: HDFS_WRITE, cliID: 
DFSClient_-158476400, srvID: DS-1760089493-127.0.1.1-48298-1256560556920, 
blockid: blk_-4558730747491505513_1014
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: PacketResponder 2 for 
block blk_-4558730747491505513_1014 terminating
    [junit] 09/10/26 12:36:28 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:48298 is added to 
blk_-4558730747491505513_1014 size 1589
    [junit] 09/10/26 12:36:28 INFO hdfs.StateChange: DIR* 
NameSystem.completeFile: file 
/tmp/hadoop-hudson/mapred/system/job_20091026123556993_0002/job.split is closed 
by DFSClient_-158476400
    [junit] 09/10/26 12:36:28 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=create      
src=/tmp/hadoop-hudson/mapred/system/job_20091026123556993_0002/job.xml 
dst=null        perm=hudson:supergroup:rw-r--r--
    [junit] 09/10/26 12:36:28 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=setPermission       
src=/tmp/hadoop-hudson/mapred/system/job_20091026123556993_0002/job.xml 
dst=null        perm=hudson:supergroup:rw-r--r--
    [junit] 09/10/26 12:36:28 INFO hdfs.StateChange: BLOCK* 
NameSystem.allocateBlock: 
/tmp/hadoop-hudson/mapred/system/job_20091026123556993_0002/job.xml. 
blk_-1182561414455898029_1015
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: Receiving block 
blk_-1182561414455898029_1015 src: /127.0.0.1:44302 dest: /127.0.0.1:34597
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: Receiving block 
blk_-1182561414455898029_1015 src: /127.0.0.1:42902 dest: /127.0.0.1:40327
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: Receiving block 
blk_-1182561414455898029_1015 src: /127.0.0.1:50131 dest: /127.0.0.1:48298
    [junit] 09/10/26 12:36:28 INFO DataNode.clienttrace: src: /127.0.0.1:50131, 
dest: /127.0.0.1:48298, bytes: 48148, op: HDFS_WRITE, cliID: 
DFSClient_-158476400, srvID: DS-1760089493-127.0.1.1-48298-1256560556920, 
blockid: blk_-1182561414455898029_1015
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: PacketResponder 0 for 
block blk_-1182561414455898029_1015 terminating
    [junit] 09/10/26 12:36:28 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:48298 is added to 
blk_-1182561414455898029_1015 size 48148
    [junit] 09/10/26 12:36:28 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:40327 is added to 
blk_-1182561414455898029_1015 size 48148
    [junit] 09/10/26 12:36:28 INFO DataNode.clienttrace: src: /127.0.0.1:42902, 
dest: /127.0.0.1:40327, bytes: 48148, op: HDFS_WRITE, cliID: 
DFSClient_-158476400, srvID: DS-395625956-127.0.1.1-40327-1256560556478, 
blockid: blk_-1182561414455898029_1015
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: PacketResponder 1 for 
block blk_-1182561414455898029_1015 terminating
    [junit] 09/10/26 12:36:28 INFO DataNode.clienttrace: src: /127.0.0.1:44302, 
dest: /127.0.0.1:34597, bytes: 48148, op: HDFS_WRITE, cliID: 
DFSClient_-158476400, srvID: DS-1480869609-127.0.1.1-34597-1256560556024, 
blockid: blk_-1182561414455898029_1015
    [junit] 09/10/26 12:36:28 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:34597 is added to 
blk_-1182561414455898029_1015 size 48148
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: PacketResponder 2 for 
block blk_-1182561414455898029_1015 terminating
    [junit] 09/10/26 12:36:28 INFO hdfs.StateChange: DIR* 
NameSystem.completeFile: file 
/tmp/hadoop-hudson/mapred/system/job_20091026123556993_0002/job.xml is closed 
by DFSClient_-158476400
    [junit] 09/10/26 12:36:28 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/hadoop-hudson/mapred/system/job_20091026123556993_0002/job.xml 
dst=null        perm=null
    [junit] 09/10/26 12:36:28 INFO DataNode.clienttrace: src: /127.0.0.1:34597, 
dest: /127.0.0.1:44305, bytes: 48528, op: HDFS_READ, cliID: 
DFSClient_-158476400, srvID: DS-1480869609-127.0.1.1-34597-1256560556024, 
blockid: blk_-1182561414455898029_1015
    [junit] 09/10/26 12:36:28 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/hadoop-hudson/mapred/system/job_20091026123556993_0002/job.jar 
dst=null        perm=null
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: Deleting block 
blk_-2268180539882663756_1005 file 
build/test/data/dfs/data/data1/current/blk_-2268180539882663756
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: Deleting block 
blk_-1200204484891870422_1007 file 
build/test/data/dfs/data/data2/current/blk_-1200204484891870422
    [junit] 09/10/26 12:36:28 INFO DataNode.clienttrace: src: /127.0.0.1:34597, 
dest: /127.0.0.1:44306, bytes: 2488229, op: HDFS_READ, cliID: 
DFSClient_-158476400, srvID: DS-1480869609-127.0.1.1-34597-1256560556024, 
blockid: blk_4359668011237125897_1013
    [junit] 09/10/26 12:36:28 INFO mapred.JobTracker: Initializing 
job_20091026123556993_0002
    [junit] 09/10/26 12:36:28 INFO mapred.JobInProgress: Initializing 
job_20091026123556993_0002
    [junit] 09/10/26 12:36:28 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=create      
src=/tmp/temp-1454024289/tmp1131273110/_logs/history/localhost_1256560557017_job_20091026123556993_0002_hudson_Job1440731154939357928.jar
       dst=null        perm=hudson:supergroup:rw-r--r--
    [junit] 09/10/26 12:36:28 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=create      
src=/tmp/temp-1454024289/tmp1131273110/_logs/history/localhost_1256560557017_job_20091026123556993_0002_conf.xml
        dst=null        perm=hudson:supergroup:rw-r--r--
    [junit] 09/10/26 12:36:28 INFO hdfs.StateChange: BLOCK* 
NameSystem.allocateBlock: 
/tmp/temp-1454024289/tmp1131273110/_logs/history/localhost_1256560557017_job_20091026123556993_0002_conf.xml.
 blk_-2543514727044868284_1017
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: Receiving block 
blk_-2543514727044868284_1017 src: /127.0.0.1:42906 dest: /127.0.0.1:40327
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: Receiving block 
blk_-2543514727044868284_1017 src: /127.0.0.1:44308 dest: /127.0.0.1:34597
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: Receiving block 
blk_-2543514727044868284_1017 src: /127.0.0.1:50136 dest: /127.0.0.1:48298
    [junit] 09/10/26 12:36:28 INFO DataNode.clienttrace: src: /127.0.0.1:50136, 
dest: /127.0.0.1:48298, bytes: 48178, op: HDFS_WRITE, cliID: 
DFSClient_-158476400, srvID: DS-1760089493-127.0.1.1-48298-1256560556920, 
blockid: blk_-2543514727044868284_1017
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: PacketResponder 0 for 
block blk_-2543514727044868284_1017 terminating
    [junit] 09/10/26 12:36:28 INFO DataNode.clienttrace: src: /127.0.0.1:44308, 
dest: /127.0.0.1:34597, bytes: 48178, op: HDFS_WRITE, cliID: 
DFSClient_-158476400, srvID: DS-1480869609-127.0.1.1-34597-1256560556024, 
blockid: blk_-2543514727044868284_1017
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: PacketResponder 1 for 
block blk_-2543514727044868284_1017 terminating
    [junit] 09/10/26 12:36:28 INFO DataNode.clienttrace: src: /127.0.0.1:42906, 
dest: /127.0.0.1:40327, bytes: 48178, op: HDFS_WRITE, cliID: 
DFSClient_-158476400, srvID: DS-395625956-127.0.1.1-40327-1256560556478, 
blockid: blk_-2543514727044868284_1017
    [junit] 09/10/26 12:36:28 INFO datanode.DataNode: PacketResponder 2 for 
block blk_-2543514727044868284_1017 terminating
    [junit] 09/10/26 12:36:28 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:40327 is added to 
blk_-2543514727044868284_1017 size 48178
    [junit] 09/10/26 12:36:28 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:48298 is added to 
blk_-2543514727044868284_1017 size 48178
    [junit] 09/10/26 12:36:28 INFO hdfs.StateChange: DIR* 
NameSystem.completeFile: file 
/tmp/temp-1454024289/tmp1131273110/_logs/history/localhost_1256560557017_job_20091026123556993_0002_conf.xml
 is closed by DFSClient_-158476400
    [junit] 09/10/26 12:36:28 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:34597 is added to 
blk_-2543514727044868284_1017 size 48178
    [junit] 09/10/26 12:36:28 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/hadoop-hudson/mapred/system/job_20091026123556993_0002/job.split       
dst=null        perm=null
    [junit] 09/10/26 12:36:28 INFO DataNode.clienttrace: src: /127.0.0.1:40327, 
dest: /127.0.0.1:42909, bytes: 1605, op: HDFS_READ, cliID: 
DFSClient_-158476400, srvID: DS-395625956-127.0.1.1-40327-1256560556478, 
blockid: blk_-4558730747491505513_1014
    [junit] 09/10/26 12:36:28 INFO mapred.JobInProgress: Input size for job 
job_20091026123556993_0002 = 12. Number of splits = 2
    [junit] 09/10/26 12:36:28 INFO mapred.JobInProgress: 
tip:task_20091026123556993_0002_m_000000 has split on 
node:/default-rack/h7.grid.sp2.yahoo.net
    [junit] 09/10/26 12:36:28 INFO mapred.JobInProgress: 
tip:task_20091026123556993_0002_m_000001 has split on 
node:/default-rack/h7.grid.sp2.yahoo.net
    [junit] 09/10/26 12:36:28 INFO mapred.JobTracker: Adding task 
'attempt_20091026123556993_0002_m_000003_0' to tip 
task_20091026123556993_0002_m_000003, for tracker 
'tracker_host1.foo.com:localhost/127.0.0.1:56031'
    [junit] 09/10/26 12:36:28 INFO mapred.TaskTracker: LaunchTaskAction 
(registerTask): attempt_20091026123556993_0002_m_000003_0 task's 
state:UNASSIGNED
    [junit] 09/10/26 12:36:28 INFO mapred.TaskTracker: Trying to launch : 
attempt_20091026123556993_0002_m_000003_0
    [junit] 09/10/26 12:36:28 INFO mapred.TaskTracker: In TaskLauncher, current 
free slots : 2 and trying to launch attempt_20091026123556993_0002_m_000003_0
    [junit] 09/10/26 12:36:29 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/hadoop-hudson/mapred/system/job_20091026123556993_0002/job.xml 
dst=null        perm=null
    [junit] 09/10/26 12:36:29 INFO DataNode.clienttrace: src: /127.0.0.1:40327, 
dest: /127.0.0.1:42910, bytes: 48528, op: HDFS_READ, cliID: 
DFSClient_-158476400, srvID: DS-395625956-127.0.1.1-40327-1256560556478, 
blockid: blk_-1182561414455898029_1015
    [junit] 09/10/26 12:36:29 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/hadoop-hudson/mapred/system/job_20091026123556993_0002/job.jar 
dst=null        perm=null
    [junit] 09/10/26 12:36:29 INFO DataNode.clienttrace: src: /127.0.0.1:42261, 
dest: /127.0.0.1:45691, bytes: 2488229, op: HDFS_READ, cliID: 
DFSClient_-158476400, srvID: DS-1904490549-127.0.1.1-42261-1256560555548, 
blockid: blk_4359668011237125897_1013
    [junit] 09/10/26 12:36:29 INFO mapReduceLayer.MapReduceLauncher: Submitting 
job: job_20091026123556993_0002 to execution engine.
    [junit] 09/10/26 12:36:29 INFO mapReduceLayer.MapReduceLauncher: More 
information at: 
http://localhost:33717/jobdetails.jsp?jobid=job_20091026123556993_0002
    [junit] 09/10/26 12:36:29 INFO mapReduceLayer.MapReduceLauncher: To kill 
this job, use: kill job_20091026123556993_0002
    [junit] 09/10/26 12:36:29 INFO datanode.DataNode: Deleting block 
blk_-2207214537836291059_1006 file 
build/test/data/dfs/data/data6/current/blk_-2207214537836291059
    [junit] 09/10/26 12:36:29 INFO datanode.DataNode: Deleting block 
blk_-1200204484891870422_1007 file 
build/test/data/dfs/data/data5/current/blk_-1200204484891870422
    [junit] 09/10/26 12:36:29 INFO mapred.JvmManager: In JvmRunner constructed 
JVM ID: jvm_20091026123556993_0002_m_-403272642
    [junit] 09/10/26 12:36:29 INFO mapred.JvmManager: JVM Runner 
jvm_20091026123556993_0002_m_-403272642 spawned.
    [junit] 09/10/26 12:36:29 INFO mapReduceLayer.MapReduceLauncher: 0% complete
    [junit] 09/10/26 12:36:29 INFO mapred.TaskTracker: JVM with ID: 
jvm_20091026123556993_0002_m_-403272642 given task: 
attempt_20091026123556993_0002_m_000003_0
    [junit] 09/10/26 12:36:30 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:40327 
to delete  blk_4359668011237125897_1013
    [junit] 09/10/26 12:36:30 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=mkdirs      
src=/tmp/temp-1454024289/tmp1131273110/_temporary       dst=null        
perm=hudson:supergroup:rwxr-xr-x
    [junit] 09/10/26 12:36:30 INFO mapred.TaskTracker: 
attempt_20091026123556993_0002_m_000003_0 0.0% setup
    [junit] 09/10/26 12:36:30 INFO mapred.TaskTracker: Task 
attempt_20091026123556993_0002_m_000003_0 is done.
    [junit] 09/10/26 12:36:30 INFO mapred.TaskTracker: reported output size for 
attempt_20091026123556993_0002_m_000003_0  was 0
    [junit] 09/10/26 12:36:30 INFO mapred.TaskTracker: addFreeSlot : current 
free slots : 2
    [junit] 09/10/26 12:36:30 INFO mapred.JvmManager: JVM : 
jvm_20091026123556993_0002_m_-403272642 exited. Number of tasks it ran: 1
    [junit] 09/10/26 12:36:31 INFO mapred.TaskTracker: 
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find 
taskTracker/jobcache/job_20091026123556993_0002/attempt_20091026123556993_0002_m_000003_0/output/file.out
 in any of the configured local directories
    [junit] 09/10/26 12:36:31 INFO mapred.JobInProgress: Task 
'attempt_20091026123556993_0002_m_000003_0' has completed 
task_20091026123556993_0002_m_000003 successfully.
    [junit] 09/10/26 12:36:31 INFO mapred.JobTracker: Adding task 
'attempt_20091026123556993_0002_m_000000_0' to tip 
task_20091026123556993_0002_m_000000, for tracker 
'tracker_host1.foo.com:localhost/127.0.0.1:56031'
    [junit] 09/10/26 12:36:31 INFO mapred.JobInProgress: Choosing rack-local 
task task_20091026123556993_0002_m_000000
    [junit] 09/10/26 12:36:31 INFO mapred.TaskTracker: LaunchTaskAction 
(registerTask): attempt_20091026123556993_0002_m_000000_0 task's 
state:UNASSIGNED
    [junit] 09/10/26 12:36:31 INFO mapred.TaskTracker: Trying to launch : 
attempt_20091026123556993_0002_m_000000_0
    [junit] 09/10/26 12:36:31 INFO mapred.TaskTracker: Received KillTaskAction 
for task: attempt_20091026123556993_0002_m_000003_0
    [junit] 09/10/26 12:36:31 INFO mapred.TaskTracker: About to purge task: 
attempt_20091026123556993_0002_m_000003_0
    [junit] 09/10/26 12:36:31 INFO mapred.TaskTracker: In TaskLauncher, current 
free slots : 2 and trying to launch attempt_20091026123556993_0002_m_000000_0
    [junit] 09/10/26 12:36:31 INFO mapred.TaskRunner: 
attempt_20091026123556993_0002_m_000003_0 done; removing files.
    [junit] 09/10/26 12:36:31 INFO mapred.IndexCache: Map ID 
attempt_20091026123556993_0002_m_000003_0 not found in cache
    [junit] 09/10/26 12:36:31 INFO mapred.JvmManager: In JvmRunner constructed 
JVM ID: jvm_20091026123556993_0002_m_-594129508
    [junit] 09/10/26 12:36:31 INFO mapred.JvmManager: JVM Runner 
jvm_20091026123556993_0002_m_-594129508 spawned.
    [junit] 09/10/26 12:36:32 INFO mapred.JobTracker: Adding task 
'attempt_20091026123556993_0002_m_000001_0' to tip 
task_20091026123556993_0002_m_000001, for tracker 
'tracker_host2.foo.com:localhost/127.0.0.1:38104'
    [junit] 09/10/26 12:36:32 INFO mapred.JobInProgress: Choosing rack-local 
task task_20091026123556993_0002_m_000001
    [junit] 09/10/26 12:36:32 INFO mapred.TaskTracker: LaunchTaskAction 
(registerTask): attempt_20091026123556993_0002_m_000001_0 task's 
state:UNASSIGNED
    [junit] 09/10/26 12:36:32 INFO mapred.TaskTracker: Trying to launch : 
attempt_20091026123556993_0002_m_000001_0
    [junit] 09/10/26 12:36:32 INFO mapred.TaskTracker: In TaskLauncher, current 
free slots : 2 and trying to launch attempt_20091026123556993_0002_m_000001_0
    [junit] 09/10/26 12:36:32 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/hadoop-hudson/mapred/system/job_20091026123556993_0002/job.xml 
dst=null        perm=null
    [junit] 09/10/26 12:36:32 INFO DataNode.clienttrace: src: /127.0.0.1:48298, 
dest: /127.0.0.1:50145, bytes: 48528, op: HDFS_READ, cliID: 
DFSClient_-158476400, srvID: DS-1760089493-127.0.1.1-48298-1256560556920, 
blockid: blk_-1182561414455898029_1015
    [junit] 09/10/26 12:36:32 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/hadoop-hudson/mapred/system/job_20091026123556993_0002/job.jar 
dst=null        perm=null
    [junit] 09/10/26 12:36:32 INFO DataNode.clienttrace: src: /127.0.0.1:34597, 
dest: /127.0.0.1:44319, bytes: 2488229, op: HDFS_READ, cliID: 
DFSClient_-158476400, srvID: DS-1480869609-127.0.1.1-34597-1256560556024, 
blockid: blk_4359668011237125897_1013
    [junit] 09/10/26 12:36:32 INFO mapred.TaskTracker: JVM with ID: 
jvm_20091026123556993_0002_m_-594129508 given task: 
attempt_20091026123556993_0002_m_000000_0
    [junit] 09/10/26 12:36:32 INFO datanode.DataNode: Deleting block 
blk_4359668011237125897_1013 file 
build/test/data/dfs/data/data5/current/blk_4359668011237125897
    [junit] 09/10/26 12:36:32 INFO mapred.JvmManager: In JvmRunner constructed 
JVM ID: jvm_20091026123556993_0002_m_1231389631
    [junit] 09/10/26 12:36:32 INFO mapred.JvmManager: JVM Runner 
jvm_20091026123556993_0002_m_1231389631 spawned.
    [junit] 09/10/26 12:36:32 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        src=/user/hudson/input2.txt     dst=null        
perm=null
    [junit] 09/10/26 12:36:33 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=create      
src=/tmp/temp-1454024289/tmp1131273110/_temporary/_attempt_20091026123556993_0002_m_000000_0/part-00000
 dst=null        perm=hudson:supergroup:rw-r--r--
    [junit] 09/10/26 12:36:33 INFO DataNode.clienttrace: src: /127.0.0.1:34597, 
dest: /127.0.0.1:44325, bytes: 10, op: HDFS_READ, cliID: 
DFSClient_attempt_20091026123556993_0002_m_000000_0, srvID: 
DS-1480869609-127.0.1.1-34597-1256560556024, blockid: 
blk_-2384769744491014972_1012
    [junit] 09/10/26 12:36:33 INFO hdfs.StateChange: BLOCK* 
NameSystem.allocateBlock: 
/tmp/temp-1454024289/tmp1131273110/_temporary/_attempt_20091026123556993_0002_m_000000_0/part-00000.
 blk_4886628476092042578_1018
    [junit] 09/10/26 12:36:33 INFO datanode.DataNode: Receiving block 
blk_4886628476092042578_1018 src: /127.0.0.1:45705 dest: /127.0.0.1:42261
    [junit] 09/10/26 12:36:33 INFO datanode.DataNode: Receiving block 
blk_4886628476092042578_1018 src: /127.0.0.1:44327 dest: /127.0.0.1:34597
    [junit] 09/10/26 12:36:33 INFO datanode.DataNode: Receiving block 
blk_4886628476092042578_1018 src: /127.0.0.1:50155 dest: /127.0.0.1:48298
    [junit] 09/10/26 12:36:33 INFO DataNode.clienttrace: src: /127.0.0.1:50155, 
dest: /127.0.0.1:48298, bytes: 39, op: HDFS_WRITE, cliID: 
DFSClient_attempt_20091026123556993_0002_m_000000_0, srvID: 
DS-1760089493-127.0.1.1-48298-1256560556920, blockid: 
blk_4886628476092042578_1018
    [junit] 09/10/26 12:36:33 INFO datanode.DataNode: PacketResponder 0 for 
block blk_4886628476092042578_1018 terminating
    [junit] 09/10/26 12:36:33 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:48298 is added to 
blk_4886628476092042578_1018 size 39
    [junit] 09/10/26 12:36:33 INFO DataNode.clienttrace: src: /127.0.0.1:44327, 
dest: /127.0.0.1:34597, bytes: 39, op: HDFS_WRITE, cliID: 
DFSClient_attempt_20091026123556993_0002_m_000000_0, srvID: 
DS-1480869609-127.0.1.1-34597-1256560556024, blockid: 
blk_4886628476092042578_1018
    [junit] 09/10/26 12:36:33 INFO datanode.DataNode: PacketResponder 1 for 
block blk_4886628476092042578_1018 terminating
    [junit] 09/10/26 12:36:33 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:34597 is added to 
blk_4886628476092042578_1018 size 39
    [junit] 09/10/26 12:36:33 INFO DataNode.clienttrace: src: /127.0.0.1:45705, 
dest: /127.0.0.1:42261, bytes: 39, op: HDFS_WRITE, cliID: 
DFSClient_attempt_20091026123556993_0002_m_000000_0, srvID: 
DS-1904490549-127.0.1.1-42261-1256560555548, blockid: 
blk_4886628476092042578_1018
    [junit] 09/10/26 12:36:33 INFO datanode.DataNode: PacketResponder 2 for 
block blk_4886628476092042578_1018 terminating
    [junit] 09/10/26 12:36:33 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:42261 is added to 
blk_4886628476092042578_1018 size 39
    [junit] 09/10/26 12:36:33 INFO hdfs.StateChange: DIR* 
NameSystem.completeFile: file 
/tmp/temp-1454024289/tmp1131273110/_temporary/_attempt_20091026123556993_0002_m_000000_0/part-00000
 is closed by DFSClient_attempt_20091026123556993_0002_m_000000_0
    [junit] 09/10/26 12:36:33 INFO mapred.TaskTracker: Task 
attempt_20091026123556993_0002_m_000000_0 is in commit-pending, task 
state:COMMIT_PENDING
    [junit] 09/10/26 12:36:33 INFO mapred.TaskTracker: 
attempt_20091026123556993_0002_m_000000_0 0.0% 
    [junit] 09/10/26 12:36:33 INFO mapred.TaskTracker: JVM with ID: 
jvm_20091026123556993_0002_m_1231389631 given task: 
attempt_20091026123556993_0002_m_000001_0
    [junit] 09/10/26 12:36:33 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        src=/user/hudson/input1.txt     dst=null        
perm=null
    [junit] 09/10/26 12:36:33 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=create      
src=/tmp/temp-1454024289/tmp1131273110/_temporary/_attempt_20091026123556993_0002_m_000001_0/part-00001
 dst=null        perm=hudson:supergroup:rw-r--r--
    [junit] 09/10/26 12:36:33 INFO DataNode.clienttrace: src: /127.0.0.1:48298, 
dest: /127.0.0.1:50161, bytes: 10, op: HDFS_READ, cliID: 
DFSClient_attempt_20091026123556993_0002_m_000001_0, srvID: 
DS-1760089493-127.0.1.1-48298-1256560556920, blockid: 
blk_-8662412230748757221_1011
    [junit] 09/10/26 12:36:33 INFO hdfs.StateChange: BLOCK* 
NameSystem.allocateBlock: 
/tmp/temp-1454024289/tmp1131273110/_temporary/_attempt_20091026123556993_0002_m_000001_0/part-00001.
 blk_-1455514437250338788_1019
    [junit] 09/10/26 12:36:33 INFO datanode.DataNode: Receiving block 
blk_-1455514437250338788_1019 src: /127.0.0.1:42934 dest: /127.0.0.1:40327
    [junit] 09/10/26 12:36:33 INFO datanode.DataNode: Receiving block 
blk_-1455514437250338788_1019 src: /127.0.0.1:45715 dest: /127.0.0.1:42261
    [junit] 09/10/26 12:36:33 INFO datanode.DataNode: Receiving block 
blk_-1455514437250338788_1019 src: /127.0.0.1:50164 dest: /127.0.0.1:48298
    [junit] 09/10/26 12:36:33 INFO DataNode.clienttrace: src: /127.0.0.1:50164, 
dest: /127.0.0.1:48298, bytes: 39, op: HDFS_WRITE, cliID: 
DFSClient_attempt_20091026123556993_0002_m_000001_0, srvID: 
DS-1760089493-127.0.1.1-48298-1256560556920, blockid: 
blk_-1455514437250338788_1019
    [junit] 09/10/26 12:36:33 INFO datanode.DataNode: PacketResponder 0 for 
block blk_-1455514437250338788_1019 terminating
    [junit] 09/10/26 12:36:33 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:48298 is added to 
blk_-1455514437250338788_1019 size 39
    [junit] 09/10/26 12:36:33 INFO DataNode.clienttrace: src: /127.0.0.1:45715, 
dest: /127.0.0.1:42261, bytes: 39, op: HDFS_WRITE, cliID: 
DFSClient_attempt_20091026123556993_0002_m_000001_0, srvID: 
DS-1904490549-127.0.1.1-42261-1256560555548, blockid: 
blk_-1455514437250338788_1019
    [junit] 09/10/26 12:36:33 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:42261 is added to 
blk_-1455514437250338788_1019 size 39
    [junit] 09/10/26 12:36:33 INFO datanode.DataNode: PacketResponder 1 for 
block blk_-1455514437250338788_1019 terminating
    [junit] 09/10/26 12:36:33 INFO DataNode.clienttrace: src: /127.0.0.1:42934, 
dest: /127.0.0.1:40327, bytes: 39, op: HDFS_WRITE, cliID: 
DFSClient_attempt_20091026123556993_0002_m_000001_0, srvID: 
DS-395625956-127.0.1.1-40327-1256560556478, blockid: 
blk_-1455514437250338788_1019
    [junit] 09/10/26 12:36:33 INFO datanode.DataNode: PacketResponder 2 for 
block blk_-1455514437250338788_1019 terminating
    [junit] 09/10/26 12:36:33 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:40327 is added to 
blk_-1455514437250338788_1019 size 39
    [junit] 09/10/26 12:36:33 INFO hdfs.StateChange: DIR* 
NameSystem.completeFile: file 
/tmp/temp-1454024289/tmp1131273110/_temporary/_attempt_20091026123556993_0002_m_000001_0/part-00001
 is closed by DFSClient_attempt_20091026123556993_0002_m_000001_0
    [junit] 09/10/26 12:36:33 INFO mapred.TaskTracker: Task 
attempt_20091026123556993_0002_m_000001_0 is in commit-pending, task 
state:COMMIT_PENDING
    [junit] 09/10/26 12:36:33 INFO mapred.TaskTracker: 
attempt_20091026123556993_0002_m_000001_0 0.0% 
    [junit] 09/10/26 12:36:34 INFO mapred.TaskTracker: Received commit task 
action for attempt_20091026123556993_0002_m_000000_0
    [junit] 09/10/26 12:36:35 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=listStatus  
src=/tmp/temp-1454024289/tmp1131273110/_temporary/_attempt_20091026123556993_0002_m_000000_0
    dst=null        perm=null
    [junit] 09/10/26 12:36:35 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=mkdirs      src=/tmp/temp-1454024289/tmp1131273110  
dst=null        perm=hudson:supergroup:rwxr-xr-x
    [junit] 09/10/26 12:36:35 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=rename      
src=/tmp/temp-1454024289/tmp1131273110/_temporary/_attempt_20091026123556993_0002_m_000000_0/part-00000
 dst=/tmp/temp-1454024289/tmp1131273110/part-00000       
perm=hudson:supergroup:rw-r--r--
    [junit] 09/10/26 12:36:35 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=delete      
src=/tmp/temp-1454024289/tmp1131273110/_temporary/_attempt_20091026123556993_0002_m_000000_0
    dst=null        perm=null
    [junit] 09/10/26 12:36:35 INFO mapred.TaskTracker: 
attempt_20091026123556993_0002_m_000000_0 1.0% 
    [junit] 09/10/26 12:36:35 INFO mapred.TaskTracker: Task 
attempt_20091026123556993_0002_m_000000_0 is done.
    [junit] 09/10/26 12:36:35 INFO mapred.TaskTracker: reported output size for 
attempt_20091026123556993_0002_m_000000_0  was 0
    [junit] 09/10/26 12:36:35 INFO mapred.TaskTracker: addFreeSlot : current 
free slots : 2
    [junit] 09/10/26 12:36:35 INFO mapred.TaskTracker: Received commit task 
action for attempt_20091026123556993_0002_m_000001_0
    [junit] 09/10/26 12:36:35 INFO mapred.JvmManager: JVM : 
jvm_20091026123556993_0002_m_-594129508 exited. Number of tasks it ran: 1
    [junit] 09/10/26 12:36:35 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=listStatus  
src=/tmp/temp-1454024289/tmp1131273110/_temporary/_attempt_20091026123556993_0002_m_000001_0
    dst=null        perm=null
    [junit] 09/10/26 12:36:35 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=mkdirs      src=/tmp/temp-1454024289/tmp1131273110  
dst=null        perm=hudson:supergroup:rwxr-xr-x
    [junit] 09/10/26 12:36:35 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=rename      
src=/tmp/temp-1454024289/tmp1131273110/_temporary/_attempt_20091026123556993_0002_m_000001_0/part-00001
 dst=/tmp/temp-1454024289/tmp1131273110/part-00001       
perm=hudson:supergroup:rw-r--r--
    [junit] 09/10/26 12:36:35 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=delete      
src=/tmp/temp-1454024289/tmp1131273110/_temporary/_attempt_20091026123556993_0002_m_000001_0
    dst=null        perm=null
    [junit] 09/10/26 12:36:35 INFO mapred.TaskTracker: 
attempt_20091026123556993_0002_m_000001_0 1.0% 
    [junit] 09/10/26 12:36:35 INFO mapred.TaskTracker: Task 
attempt_20091026123556993_0002_m_000001_0 is done.
    [junit] 09/10/26 12:36:35 INFO mapred.TaskTracker: reported output size for 
attempt_20091026123556993_0002_m_000001_0  was 0
    [junit] 09/10/26 12:36:35 INFO mapred.TaskTracker: addFreeSlot : current 
free slots : 2
    [junit] 09/10/26 12:36:36 INFO mapred.JvmManager: JVM : 
jvm_20091026123556993_0002_m_1231389631 exited. Number of tasks it ran: 1
    [junit] 09/10/26 12:36:37 INFO mapred.TaskTracker: 
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find 
taskTracker/jobcache/job_20091026123556993_0002/attempt_20091026123556993_0002_m_000000_0/output/file.out
 in any of the configured local directories
    [junit] 09/10/26 12:36:37 INFO mapred.JobInProgress: Task 
'attempt_20091026123556993_0002_m_000000_0' has completed 
task_20091026123556993_0002_m_000000 successfully.
    [junit] 09/10/26 12:36:38 INFO mapred.TaskTracker: 
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find 
taskTracker/jobcache/job_20091026123556993_0002/attempt_20091026123556993_0002_m_000001_0/output/file.out
 in any of the configured local directories
    [junit] 09/10/26 12:36:38 INFO mapred.JobInProgress: Task 
'attempt_20091026123556993_0002_m_000001_0' has completed 
task_20091026123556993_0002_m_000001 successfully.
    [junit] 09/10/26 12:36:38 INFO mapred.JobTracker: Adding task 
'attempt_20091026123556993_0002_m_000002_0' to tip 
task_20091026123556993_0002_m_000002, for tracker 
'tracker_host2.foo.com:localhost/127.0.0.1:38104'
    [junit] 09/10/26 12:36:38 INFO mapred.TaskTracker: LaunchTaskAction 
(registerTask): attempt_20091026123556993_0002_m_000002_0 task's 
state:UNASSIGNED
    [junit] 09/10/26 12:36:38 INFO mapred.TaskTracker: Trying to launch : 
attempt_20091026123556993_0002_m_000002_0
    [junit] 09/10/26 12:36:38 INFO mapred.TaskTracker: In TaskLauncher, current 
free slots : 2 and trying to launch attempt_20091026123556993_0002_m_000002_0
    [junit] 09/10/26 12:36:38 INFO mapred.JvmManager: In JvmRunner constructed 
JVM ID: jvm_20091026123556993_0002_m_1386085618
    [junit] 09/10/26 12:36:38 INFO mapReduceLayer.MapReduceLauncher: 50% 
complete
    [junit] 09/10/26 12:36:38 INFO mapred.JvmManager: JVM Runner 
jvm_20091026123556993_0002_m_1386085618 spawned.
    [junit] 09/10/26 12:36:38 INFO mapred.TaskTracker: JVM with ID: 
jvm_20091026123556993_0002_m_1386085618 given task: 
attempt_20091026123556993_0002_m_000002_0
    [junit] 09/10/26 12:36:39 INFO mapred.TaskTracker: 
attempt_20091026123556993_0002_m_000002_0 0.0% 
    [junit] 09/10/26 12:36:39 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=delete      
src=/tmp/temp-1454024289/tmp1131273110/_temporary       dst=null        
perm=null
    [junit] 09/10/26 12:36:39 INFO mapred.TaskTracker: 
attempt_20091026123556993_0002_m_000002_0 0.0% cleanup
    [junit] 09/10/26 12:36:39 INFO mapred.TaskTracker: Task 
attempt_20091026123556993_0002_m_000002_0 is done.
    [junit] 09/10/26 12:36:39 INFO mapred.TaskTracker: reported output size for 
attempt_20091026123556993_0002_m_000002_0  was 0
    [junit] 09/10/26 12:36:39 INFO mapred.TaskTracker: addFreeSlot : current 
free slots : 2
    [junit] 09/10/26 12:36:39 INFO mapred.JvmManager: JVM : 
jvm_20091026123556993_0002_m_1386085618 exited. Number of tasks it ran: 1
    [junit] 09/10/26 12:36:41 INFO mapred.TaskTracker: 
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find 
taskTracker/jobcache/job_20091026123556993_0002/attempt_20091026123556993_0002_m_000002_0/output/file.out
 in any of the configured local directories
    [junit] 09/10/26 12:36:41 INFO mapred.JobInProgress: Task 
'attempt_20091026123556993_0002_m_000002_0' has completed 
task_20091026123556993_0002_m_000002 successfully.
    [junit] 09/10/26 12:36:41 INFO mapred.JobInProgress: Job 
job_20091026123556993_0002 has completed successfully.
    [junit] 09/10/26 12:36:41 INFO hdfs.StateChange: BLOCK* 
NameSystem.allocateBlock: 
/tmp/temp-1454024289/tmp1131273110/_logs/history/localhost_1256560557017_job_20091026123556993_0002_hudson_Job1440731154939357928.jar.
 blk_7338095954172661854_1019
    [junit] 09/10/26 12:36:41 INFO datanode.DataNode: Receiving block 
blk_7338095954172661854_1019 src: /127.0.0.1:42942 dest: /127.0.0.1:40327
    [junit] 09/10/26 12:36:41 INFO datanode.DataNode: Receiving block 
blk_7338095954172661854_1019 src: /127.0.0.1:44344 dest: /127.0.0.1:34597
    [junit] 09/10/26 12:36:41 INFO datanode.DataNode: Receiving block 
blk_7338095954172661854_1019 src: /127.0.0.1:50172 dest: /127.0.0.1:48298
    [junit] 09/10/26 12:36:41 INFO DataNode.clienttrace: src: /127.0.0.1:50172, 
dest: /127.0.0.1:48298, bytes: 6117, op: HDFS_WRITE, cliID: 
DFSClient_-158476400, srvID: DS-1760089493-127.0.1.1-48298-1256560556920, 
blockid: blk_7338095954172661854_1019
    [junit] 09/10/26 12:36:41 INFO datanode.DataNode: PacketResponder 0 for 
block blk_7338095954172661854_1019 terminating
    [junit] 09/10/26 12:36:41 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:48298 is added to 
blk_7338095954172661854_1019 size 6117
    [junit] 09/10/26 12:36:41 INFO DataNode.clienttrace: src: /127.0.0.1:44344, 
dest: /127.0.0.1:34597, bytes: 6117, op: HDFS_WRITE, cliID: 
DFSClient_-158476400, srvID: DS-1480869609-127.0.1.1-34597-1256560556024, 
blockid: blk_7338095954172661854_1019
    [junit] 09/10/26 12:36:41 INFO datanode.DataNode: PacketResponder 1 for 
block blk_7338095954172661854_1019 terminating
    [junit] 09/10/26 12:36:41 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:34597 is added to 
blk_7338095954172661854_1019 size 6117
    [junit] 09/10/26 12:36:41 INFO DataNode.clienttrace: src: /127.0.0.1:42942, 
dest: /127.0.0.1:40327, bytes: 6117, op: HDFS_WRITE, cliID: 
DFSClient_-158476400, srvID: DS-395625956-127.0.1.1-40327-1256560556478, 
blockid: blk_7338095954172661854_1019
    [junit] 09/10/26 12:36:41 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:40327 is added to 
blk_7338095954172661854_1019 size 6117
    [junit] 09/10/26 12:36:41 INFO datanode.DataNode: PacketResponder 2 for 
block blk_7338095954172661854_1019 terminating
    [junit] 09/10/26 12:36:41 INFO hdfs.StateChange: DIR* 
NameSystem.completeFile: file 
/tmp/temp-1454024289/tmp1131273110/_logs/history/localhost_1256560557017_job_20091026123556993_0002_hudson_Job1440731154939357928.jar
 is closed by DFSClient_-158476400
    [junit] 09/10/26 12:36:41 INFO mapred.JobTracker: Removed completed task 
'attempt_20091026123556993_0002_m_000001_0' from 
'tracker_host2.foo.com:localhost/127.0.0.1:38104'
    [junit] 09/10/26 12:36:41 INFO mapred.JobTracker: Removed completed task 
'attempt_20091026123556993_0002_m_000002_0' from 
'tracker_host2.foo.com:localhost/127.0.0.1:38104'
    [junit] 09/10/26 12:36:41 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_4359668011237125897 is added to invalidSet of 
127.0.0.1:40327
    [junit] 09/10/26 12:36:41 INFO mapred.TaskTracker: Received 'KillJobAction' 
for job: job_20091026123556993_0002
    [junit] 09/10/26 12:36:41 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_4359668011237125897 is added to invalidSet of 
127.0.0.1:34597
    [junit] 09/10/26 12:36:41 INFO mapred.TaskRunner: 
attempt_20091026123556993_0002_m_000002_0 done; removing files.
    [junit] 09/10/26 12:36:41 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_4359668011237125897 is added to invalidSet of 
127.0.0.1:42261
    [junit] 09/10/26 12:36:41 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_-4558730747491505513 is added to invalidSet of 
127.0.0.1:42261
    [junit] 09/10/26 12:36:41 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_-4558730747491505513 is added to invalidSet of 
127.0.0.1:40327
    [junit] 09/10/26 12:36:41 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_-4558730747491505513 is added to invalidSet of 
127.0.0.1:48298
    [junit] 09/10/26 12:36:41 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_-1182561414455898029 is added to invalidSet of 
127.0.0.1:48298
    [junit] 09/10/26 12:36:41 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_-1182561414455898029 is added to invalidSet of 
127.0.0.1:40327
    [junit] 09/10/26 12:36:41 INFO mapred.IndexCache: Map ID 
attempt_20091026123556993_0002_m_000002_0 not found in cache
    [junit] 09/10/26 12:36:41 INFO mapred.TaskRunner: 
attempt_20091026123556993_0002_m_000001_0 done; removing files.
    [junit] 09/10/26 12:36:41 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_-1182561414455898029 is added to invalidSet of 
127.0.0.1:34597
    [junit] 09/10/26 12:36:41 INFO mapred.IndexCache: Map ID 
attempt_20091026123556993_0002_m_000001_0 not found in cache
    [junit] 09/10/26 12:36:41 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=delete      
src=/tmp/hadoop-hudson/mapred/system/job_20091026123556993_0002 dst=null        
perm=null
    [junit] 09/10/26 12:36:41 INFO mapred.TaskTracker: Received 'KillJobAction' 
for job: job_20091026123556993_0002
    [junit] 09/10/26 12:36:41 WARN mapred.TaskTracker: Unknown job 
job_20091026123556993_0002 being deleted.
    [junit] 09/10/26 12:36:42 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:34597 
to delete  blk_4359668011237125897_1013 blk_-1182561414455898029_1015
    [junit] 09/10/26 12:36:42 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:48298 
to delete  blk_-4558730747491505513_1014 blk_-1182561414455898029_1015
    [junit] 09/10/26 12:36:43 INFO mapred.TaskTracker: Received 'KillJobAction' 
for job: job_20091026123556993_0002
    [junit] 09/10/26 12:36:43 WARN mapred.TaskTracker: Unknown job 
job_20091026123556993_0002 being deleted.
    [junit] 09/10/26 12:36:43 INFO mapReduceLayer.MapReduceLauncher: 100% 
complete
    [junit] 09/10/26 12:36:43 INFO mapReduceLayer.MapReduceLauncher: 
Successfully stored result in: 
"hdfs://localhost:44167/tmp/temp-1454024289/tmp1131273110"
    [junit] 09/10/26 12:36:43 INFO mapReduceLayer.MapReduceLauncher: Records 
written : 2
    [junit] 09/10/26 12:36:43 INFO mapReduceLayer.MapReduceLauncher: Bytes 
written : 78
    [junit] 09/10/26 12:36:43 INFO mapReduceLayer.MapReduceLauncher: Success!
    [junit] 09/10/26 12:36:43 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=listStatus  src=/tmp/temp-1454024289/tmp1131273110  
dst=null        perm=null
    [junit] 09/10/26 12:36:43 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/temp-1454024289/tmp1131273110/part-00000       dst=null        
perm=null
    [junit] 09/10/26 12:36:43 INFO DataNode.clienttrace: src: /127.0.0.1:42261, 
dest: /127.0.0.1:45725, bytes: 43, op: HDFS_READ, cliID: DFSClient_-158476400, 
srvID: DS-1904490549-127.0.1.1-42261-1256560555548, blockid: 
blk_4886628476092042578_1018
    [junit] 09/10/26 12:36:43 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/temp-1454024289/tmp1131273110/part-00001       dst=null        
perm=null
    [junit] 09/10/26 12:36:43 INFO DataNode.clienttrace: src: /127.0.0.1:48298, 
dest: /127.0.0.1:50174, bytes: 43, op: HDFS_READ, cliID: DFSClient_-158476400, 
srvID: DS-1760089493-127.0.1.1-48298-1256560556920, blockid: 
blk_-1455514437250338788_1019
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 44.181 sec

BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/Pig-trunk/ws/trunk/build.xml>:549: 
The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/Pig-trunk/ws/trunk/build.xml>:483: 
The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/Pig-trunk/ws/trunk/build.xml>:544: 
Tests failed!

Total time: 181 minutes 0 seconds
Publishing Javadoc
Archiving artifacts
Recording test results
Recording fingerprints
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure

Reply via email to