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

Changes:

[daijy] PIG-995: Limit Optimizer throw exception 'ERROR 2156: Error while 
fixing projections'

[daijy] PIG-894: order-by fails when input is empty

------------------------------------------
[...truncated 166540 lines...]
    [junit] 09/10/10 00:58:52 INFO DataNode.clienttrace: src: /127.0.0.1:48035, 
dest: /127.0.0.1:36513, bytes: 1859, op: HDFS_WRITE, cliID: 
DFSClient_-77174316, srvID: DS-286696190-127.0.1.1-36513-1255136299705, 
blockid: blk_-1334121746913284522_1014
    [junit] 09/10/10 00:58:52 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:36513 is added to 
blk_-1334121746913284522_1014 size 1859
    [junit] 09/10/10 00:58:52 INFO datanode.DataNode: PacketResponder 0 for 
block blk_-1334121746913284522_1014 terminating
    [junit] 09/10/10 00:58:52 INFO DataNode.clienttrace: src: /127.0.0.1:52427, 
dest: /127.0.0.1:35232, bytes: 1859, op: HDFS_WRITE, cliID: 
DFSClient_-77174316, srvID: DS-1003288558-127.0.1.1-35232-1255136299143, 
blockid: blk_-1334121746913284522_1014
    [junit] 09/10/10 00:58:52 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:35232 is added to 
blk_-1334121746913284522_1014 size 1859
    [junit] 09/10/10 00:58:52 INFO datanode.DataNode: PacketResponder 1 for 
block blk_-1334121746913284522_1014 terminating
    [junit] 09/10/10 00:58:52 INFO DataNode.clienttrace: src: /127.0.0.1:60317, 
dest: /127.0.0.1:57908, bytes: 1859, op: HDFS_WRITE, cliID: 
DFSClient_-77174316, srvID: DS-2066684421-127.0.1.1-57908-1255136298560, 
blockid: blk_-1334121746913284522_1014
    [junit] 09/10/10 00:58:52 INFO datanode.DataNode: PacketResponder 2 for 
block blk_-1334121746913284522_1014 terminating
    [junit] 09/10/10 00:58:52 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:57908 is added to 
blk_-1334121746913284522_1014 size 1859
    [junit] 09/10/10 00:58:52 INFO hdfs.StateChange: DIR* 
NameSystem.completeFile: file 
/tmp/hadoop-hudson/mapred/system/job_20091010005819797_0002/job.split is closed 
by DFSClient_-77174316
    [junit] 09/10/10 00:58:52 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=create      
src=/tmp/hadoop-hudson/mapred/system/job_20091010005819797_0002/job.xml 
dst=null        perm=hudson:supergroup:rw-r--r--
    [junit] 09/10/10 00:58:52 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=setPermission       
src=/tmp/hadoop-hudson/mapred/system/job_20091010005819797_0002/job.xml 
dst=null        perm=hudson:supergroup:rw-r--r--
    [junit] 09/10/10 00:58:52 INFO hdfs.StateChange: BLOCK* 
NameSystem.allocateBlock: 
/tmp/hadoop-hudson/mapred/system/job_20091010005819797_0002/job.xml. 
blk_8761924747304658191_1015
    [junit] 09/10/10 00:58:52 INFO datanode.DataNode: Receiving block 
blk_8761924747304658191_1015 src: /127.0.0.1:60320 dest: /127.0.0.1:57908
    [junit] 09/10/10 00:58:52 INFO datanode.DataNode: Receiving block 
blk_8761924747304658191_1015 src: /127.0.0.1:52430 dest: /127.0.0.1:35232
    [junit] 09/10/10 00:58:52 INFO datanode.DataNode: Receiving block 
blk_8761924747304658191_1015 src: /127.0.0.1:48038 dest: /127.0.0.1:36513
    [junit] 09/10/10 00:58:52 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:36513 is added to 
blk_8761924747304658191_1015 size 48254
    [junit] 09/10/10 00:58:52 INFO DataNode.clienttrace: src: /127.0.0.1:48038, 
dest: /127.0.0.1:36513, bytes: 48254, op: HDFS_WRITE, cliID: 
DFSClient_-77174316, srvID: DS-286696190-127.0.1.1-36513-1255136299705, 
blockid: blk_8761924747304658191_1015
    [junit] 09/10/10 00:58:52 INFO datanode.DataNode: PacketResponder 0 for 
block blk_8761924747304658191_1015 terminating
    [junit] 09/10/10 00:58:52 INFO DataNode.clienttrace: src: /127.0.0.1:52430, 
dest: /127.0.0.1:35232, bytes: 48254, op: HDFS_WRITE, cliID: 
DFSClient_-77174316, srvID: DS-1003288558-127.0.1.1-35232-1255136299143, 
blockid: blk_8761924747304658191_1015
    [junit] 09/10/10 00:58:52 INFO datanode.DataNode: PacketResponder 1 for 
block blk_8761924747304658191_1015 terminating
    [junit] 09/10/10 00:58:52 INFO DataNode.clienttrace: src: /127.0.0.1:60320, 
dest: /127.0.0.1:57908, bytes: 48254, op: HDFS_WRITE, cliID: 
DFSClient_-77174316, srvID: DS-2066684421-127.0.1.1-57908-1255136298560, 
blockid: blk_8761924747304658191_1015
    [junit] 09/10/10 00:58:52 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:35232 is added to 
blk_8761924747304658191_1015 size 48254
    [junit] 09/10/10 00:58:52 INFO datanode.DataNode: PacketResponder 2 for 
block blk_8761924747304658191_1015 terminating
    [junit] 09/10/10 00:58:52 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:57908 is added to 
blk_8761924747304658191_1015 size 48254
    [junit] 09/10/10 00:58:52 INFO hdfs.StateChange: DIR* 
NameSystem.completeFile: file 
/tmp/hadoop-hudson/mapred/system/job_20091010005819797_0002/job.xml is closed 
by DFSClient_-77174316
    [junit] 09/10/10 00:58:52 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/hadoop-hudson/mapred/system/job_20091010005819797_0002/job.xml 
dst=null        perm=null
    [junit] 09/10/10 00:58:52 INFO DataNode.clienttrace: src: /127.0.0.1:36513, 
dest: /127.0.0.1:48039, bytes: 48634, op: HDFS_READ, cliID: 
DFSClient_-77174316, srvID: DS-286696190-127.0.1.1-36513-1255136299705, 
blockid: blk_8761924747304658191_1015
    [junit] 09/10/10 00:58:52 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/hadoop-hudson/mapred/system/job_20091010005819797_0002/job.jar 
dst=null        perm=null
    [junit] 09/10/10 00:58:52 INFO DataNode.clienttrace: src: /127.0.0.1:57908, 
dest: /127.0.0.1:60324, bytes: 2483736, op: HDFS_READ, cliID: 
DFSClient_-77174316, srvID: DS-2066684421-127.0.1.1-57908-1255136298560, 
blockid: blk_3750071453426248789_1013
    [junit] 09/10/10 00:58:52 INFO mapred.JobTracker: Initializing 
job_20091010005819797_0002
    [junit] 09/10/10 00:58:52 INFO mapred.JobInProgress: Initializing 
job_20091010005819797_0002
    [junit] 09/10/10 00:58:52 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=create      
src=/tmp/temp-1427071197/tmp-783301132/_logs/history/localhost_1255136299820_job_20091010005819797_0002_hudson_Job3756228276918050786.jar
       dst=null        perm=hudson:supergroup:rw-r--r--
    [junit] 09/10/10 00:58:53 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=create      
src=/tmp/temp-1427071197/tmp-783301132/_logs/history/localhost_1255136299820_job_20091010005819797_0002_conf.xml
        dst=null        perm=hudson:supergroup:rw-r--r--
    [junit] 09/10/10 00:58:53 INFO hdfs.StateChange: BLOCK* 
NameSystem.allocateBlock: 
/tmp/temp-1427071197/tmp-783301132/_logs/history/localhost_1255136299820_job_20091010005819797_0002_conf.xml.
 blk_2753248026776011957_1017
    [junit] 09/10/10 00:58:53 INFO datanode.DataNode: Receiving block 
blk_2753248026776011957_1017 src: /127.0.0.1:60325 dest: /127.0.0.1:57908
    [junit] 09/10/10 00:58:53 INFO datanode.DataNode: Receiving block 
blk_2753248026776011957_1017 src: /127.0.0.1:34706 dest: /127.0.0.1:55577
    [junit] 09/10/10 00:58:53 INFO datanode.DataNode: Receiving block 
blk_2753248026776011957_1017 src: /127.0.0.1:52436 dest: /127.0.0.1:35232
    [junit] 09/10/10 00:58:53 INFO DataNode.clienttrace: src: /127.0.0.1:52436, 
dest: /127.0.0.1:35232, bytes: 48284, op: HDFS_WRITE, cliID: 
DFSClient_-77174316, srvID: DS-1003288558-127.0.1.1-35232-1255136299143, 
blockid: blk_2753248026776011957_1017
    [junit] 09/10/10 00:58:53 INFO datanode.DataNode: PacketResponder 0 for 
block blk_2753248026776011957_1017 terminating
    [junit] 09/10/10 00:58:53 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:35232 is added to 
blk_2753248026776011957_1017 size 48284
    [junit] 09/10/10 00:58:53 INFO DataNode.clienttrace: src: /127.0.0.1:34706, 
dest: /127.0.0.1:55577, bytes: 48284, op: HDFS_WRITE, cliID: 
DFSClient_-77174316, srvID: DS-763709620-127.0.1.1-55577-1255136297957, 
blockid: blk_2753248026776011957_1017
    [junit] 09/10/10 00:58:53 INFO datanode.DataNode: PacketResponder 1 for 
block blk_2753248026776011957_1017 terminating
    [junit] 09/10/10 00:58:53 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:55577 is added to 
blk_2753248026776011957_1017 size 48284
    [junit] 09/10/10 00:58:53 INFO DataNode.clienttrace: src: /127.0.0.1:60325, 
dest: /127.0.0.1:57908, bytes: 48284, op: HDFS_WRITE, cliID: 
DFSClient_-77174316, srvID: DS-2066684421-127.0.1.1-57908-1255136298560, 
blockid: blk_2753248026776011957_1017
    [junit] 09/10/10 00:58:53 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:57908 is added to 
blk_2753248026776011957_1017 size 48284
    [junit] 09/10/10 00:58:53 INFO datanode.DataNode: PacketResponder 2 for 
block blk_2753248026776011957_1017 terminating
    [junit] 09/10/10 00:58:53 INFO hdfs.StateChange: DIR* 
NameSystem.completeFile: file 
/tmp/temp-1427071197/tmp-783301132/_logs/history/localhost_1255136299820_job_20091010005819797_0002_conf.xml
 is closed by DFSClient_-77174316
    [junit] 09/10/10 00:58:53 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/hadoop-hudson/mapred/system/job_20091010005819797_0002/job.split       
dst=null        perm=null
    [junit] 09/10/10 00:58:53 INFO DataNode.clienttrace: src: /127.0.0.1:36513, 
dest: /127.0.0.1:48044, bytes: 1875, op: HDFS_READ, cliID: DFSClient_-77174316, 
srvID: DS-286696190-127.0.1.1-36513-1255136299705, blockid: 
blk_-1334121746913284522_1014
    [junit] 09/10/10 00:58:53 INFO mapred.JobInProgress: Input size for job 
job_20091010005819797_0002 = 12. Number of splits = 2
    [junit] 09/10/10 00:58:53 INFO mapred.JobInProgress: 
tip:task_20091010005819797_0002_m_000000 has split on 
node:/default-rack/h7.grid.sp2.yahoo.net
    [junit] 09/10/10 00:58:53 INFO mapred.JobInProgress: 
tip:task_20091010005819797_0002_m_000001 has split on 
node:/default-rack/h7.grid.sp2.yahoo.net
    [junit] 09/10/10 00:58:53 INFO mapReduceLayer.MapReduceLauncher: Submitting 
job: job_20091010005819797_0002 to execution engine.
    [junit] 09/10/10 00:58:53 INFO mapReduceLayer.MapReduceLauncher: More 
information at: 
http://localhost:60544/jobdetails.jsp?jobid=job_20091010005819797_0002
    [junit] 09/10/10 00:58:53 INFO mapReduceLayer.MapReduceLauncher: To kill 
this job, use: kill job_20091010005819797_0002
    [junit] 09/10/10 00:58:53 INFO datanode.DataNode: Deleting block 
blk_-4052760131583957924_1006 file 
build/test/data/dfs/data/data2/current/blk_-4052760131583957924
    [junit] 09/10/10 00:58:53 INFO datanode.DataNode: Deleting block 
blk_-1316834933025031654_1007 file 
build/test/data/dfs/data/data1/current/blk_-1316834933025031654
    [junit] 09/10/10 00:58:54 INFO mapReduceLayer.MapReduceLauncher: 0% complete
    [junit] 09/10/10 00:58:54 INFO mapred.JobTracker: Adding task 
'attempt_20091010005819797_0002_m_000003_0' to tip 
task_20091010005819797_0002_m_000003, for tracker 
'tracker_host0.foo.com:localhost/127.0.0.1:45897'
    [junit] 09/10/10 00:58:54 INFO mapred.TaskTracker: LaunchTaskAction 
(registerTask): attempt_20091010005819797_0002_m_000003_0 task's 
state:UNASSIGNED
    [junit] 09/10/10 00:58:54 INFO mapred.TaskTracker: Trying to launch : 
attempt_20091010005819797_0002_m_000003_0
    [junit] 09/10/10 00:58:54 INFO mapred.TaskTracker: In TaskLauncher, current 
free slots : 2 and trying to launch attempt_20091010005819797_0002_m_000003_0
    [junit] 09/10/10 00:58:54 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/hadoop-hudson/mapred/system/job_20091010005819797_0002/job.xml 
dst=null        perm=null
    [junit] 09/10/10 00:58:54 INFO DataNode.clienttrace: src: /127.0.0.1:35232, 
dest: /127.0.0.1:52438, bytes: 48634, op: HDFS_READ, cliID: 
DFSClient_-77174316, srvID: DS-1003288558-127.0.1.1-35232-1255136299143, 
blockid: blk_8761924747304658191_1015
    [junit] 09/10/10 00:58:54 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/hadoop-hudson/mapred/system/job_20091010005819797_0002/job.jar 
dst=null        perm=null
    [junit] 09/10/10 00:58:54 INFO DataNode.clienttrace: src: /127.0.0.1:57908, 
dest: /127.0.0.1:60330, bytes: 2483736, op: HDFS_READ, cliID: 
DFSClient_-77174316, srvID: DS-2066684421-127.0.1.1-57908-1255136298560, 
blockid: blk_3750071453426248789_1013
    [junit] 09/10/10 00:58:54 INFO mapred.JvmManager: In JvmRunner constructed 
JVM ID: jvm_20091010005819797_0002_m_1857093865
    [junit] 09/10/10 00:58:54 INFO mapred.JvmManager: JVM Runner 
jvm_20091010005819797_0002_m_1857093865 spawned.
    [junit] 09/10/10 00:58:54 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:36513 
to delete  blk_3750071453426248789_1013
    [junit] 09/10/10 00:58:55 INFO mapred.TaskTracker: JVM with ID: 
jvm_20091010005819797_0002_m_1857093865 given task: 
attempt_20091010005819797_0002_m_000003_0
    [junit] 09/10/10 00:58:55 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=mkdirs      
src=/tmp/temp-1427071197/tmp-783301132/_temporary       dst=null        
perm=hudson:supergroup:rwxr-xr-x
    [junit] 09/10/10 00:58:55 INFO mapred.TaskTracker: 
attempt_20091010005819797_0002_m_000003_0 0.0% setup
    [junit] 09/10/10 00:58:55 INFO mapred.TaskTracker: Task 
attempt_20091010005819797_0002_m_000003_0 is done.
    [junit] 09/10/10 00:58:55 INFO mapred.TaskTracker: reported output size for 
attempt_20091010005819797_0002_m_000003_0  was 0
    [junit] 09/10/10 00:58:55 INFO mapred.TaskTracker: addFreeSlot : current 
free slots : 2
    [junit] 09/10/10 00:58:55 INFO mapred.JvmManager: JVM : 
jvm_20091010005819797_0002_m_1857093865 exited. Number of tasks it ran: 1
    [junit] 09/10/10 00:58:55 INFO datanode.DataNode: Deleting block 
blk_3750071453426248789_1013 file 
build/test/data/dfs/data/data7/current/blk_3750071453426248789
    [junit] 09/10/10 00:58:57 INFO mapred.TaskTracker: 
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find 
taskTracker/jobcache/job_20091010005819797_0002/attempt_20091010005819797_0002_m_000003_0/output/file.out
 in any of the configured local directories
    [junit] 09/10/10 00:58:57 INFO mapred.JobInProgress: Task 
'attempt_20091010005819797_0002_m_000003_0' has completed 
task_20091010005819797_0002_m_000003 successfully.
    [junit] 09/10/10 00:58:57 INFO mapred.JobTracker: Adding task 
'attempt_20091010005819797_0002_m_000000_0' to tip 
task_20091010005819797_0002_m_000000, for tracker 
'tracker_host0.foo.com:localhost/127.0.0.1:45897'
    [junit] 09/10/10 00:58:57 INFO mapred.JobInProgress: Choosing rack-local 
task task_20091010005819797_0002_m_000000
    [junit] 09/10/10 00:58:57 INFO mapred.TaskTracker: LaunchTaskAction 
(registerTask): attempt_20091010005819797_0002_m_000000_0 task's 
state:UNASSIGNED
    [junit] 09/10/10 00:58:57 INFO mapred.TaskTracker: Trying to launch : 
attempt_20091010005819797_0002_m_000000_0
    [junit] 09/10/10 00:58:57 INFO mapred.TaskTracker: Received KillTaskAction 
for task: attempt_20091010005819797_0002_m_000003_0
    [junit] 09/10/10 00:58:57 INFO mapred.TaskTracker: About to purge task: 
attempt_20091010005819797_0002_m_000003_0
    [junit] 09/10/10 00:58:57 INFO mapred.TaskTracker: In TaskLauncher, current 
free slots : 2 and trying to launch attempt_20091010005819797_0002_m_000000_0
    [junit] 09/10/10 00:58:57 INFO mapred.TaskRunner: 
attempt_20091010005819797_0002_m_000003_0 done; removing files.
    [junit] 09/10/10 00:58:57 INFO mapred.IndexCache: Map ID 
attempt_20091010005819797_0002_m_000003_0 not found in cache
    [junit] 09/10/10 00:58:57 INFO mapred.JvmManager: In JvmRunner constructed 
JVM ID: jvm_20091010005819797_0002_m_-832698438
    [junit] 09/10/10 00:58:57 INFO mapred.JvmManager: JVM Runner 
jvm_20091010005819797_0002_m_-832698438 spawned.
    [junit] 09/10/10 00:58:57 INFO mapred.TaskTracker: JVM with ID: 
jvm_20091010005819797_0002_m_-832698438 given task: 
attempt_20091010005819797_0002_m_000000_0
    [junit] 09/10/10 00:58:57 INFO mapred.JobTracker: Adding task 
'attempt_20091010005819797_0002_m_000001_0' to tip 
task_20091010005819797_0002_m_000001, for tracker 
'tracker_host1.foo.com:localhost/127.0.0.1:41286'
    [junit] 09/10/10 00:58:57 INFO mapred.JobInProgress: Choosing rack-local 
task task_20091010005819797_0002_m_000001
    [junit] 09/10/10 00:58:57 INFO mapred.TaskTracker: LaunchTaskAction 
(registerTask): attempt_20091010005819797_0002_m_000001_0 task's 
state:UNASSIGNED
    [junit] 09/10/10 00:58:57 INFO mapred.TaskTracker: Trying to launch : 
attempt_20091010005819797_0002_m_000001_0
    [junit] 09/10/10 00:58:57 INFO mapred.TaskTracker: In TaskLauncher, current 
free slots : 2 and trying to launch attempt_20091010005819797_0002_m_000001_0
    [junit] 09/10/10 00:58:57 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/hadoop-hudson/mapred/system/job_20091010005819797_0002/job.xml 
dst=null        perm=null
    [junit] 09/10/10 00:58:57 INFO DataNode.clienttrace: src: /127.0.0.1:57908, 
dest: /127.0.0.1:60339, bytes: 48634, op: HDFS_READ, cliID: 
DFSClient_-77174316, srvID: DS-2066684421-127.0.1.1-57908-1255136298560, 
blockid: blk_8761924747304658191_1015
    [junit] 09/10/10 00:58:57 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/hadoop-hudson/mapred/system/job_20091010005819797_0002/job.jar 
dst=null        perm=null
    [junit] 09/10/10 00:58:57 INFO DataNode.clienttrace: src: /127.0.0.1:57908, 
dest: /127.0.0.1:60340, bytes: 2483736, op: HDFS_READ, cliID: 
DFSClient_-77174316, srvID: DS-2066684421-127.0.1.1-57908-1255136298560, 
blockid: blk_3750071453426248789_1013
    [junit] 09/10/10 00:58:58 INFO mapred.JvmManager: In JvmRunner constructed 
JVM ID: jvm_20091010005819797_0002_m_1506276631
    [junit] 09/10/10 00:58:58 INFO mapred.JvmManager: JVM Runner 
jvm_20091010005819797_0002_m_1506276631 spawned.
    [junit] 09/10/10 00:58:58 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/10 00:58:58 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=create      
src=/tmp/temp-1427071197/tmp-783301132/_temporary/_attempt_20091010005819797_0002_m_000000_0/part-00000
 dst=null        perm=hudson:supergroup:rw-r--r--
    [junit] 09/10/10 00:58:58 INFO DataNode.clienttrace: src: /127.0.0.1:55577, 
dest: /127.0.0.1:34723, bytes: 10, op: HDFS_READ, cliID: 
DFSClient_attempt_20091010005819797_0002_m_000000_0, srvID: 
DS-763709620-127.0.1.1-55577-1255136297957, blockid: 
blk_8073274333389882793_1012
    [junit] 09/10/10 00:58:58 INFO hdfs.StateChange: BLOCK* 
NameSystem.allocateBlock: 
/tmp/temp-1427071197/tmp-783301132/_temporary/_attempt_20091010005819797_0002_m_000000_0/part-00000.
 blk_7973526658410217689_1018
    [junit] 09/10/10 00:58:58 INFO datanode.DataNode: Receiving block 
blk_7973526658410217689_1018 src: /127.0.0.1:60344 dest: /127.0.0.1:57908
    [junit] 09/10/10 00:58:58 INFO datanode.DataNode: Receiving block 
blk_7973526658410217689_1018 src: /127.0.0.1:52454 dest: /127.0.0.1:35232
    [junit] 09/10/10 00:58:58 INFO datanode.DataNode: Receiving block 
blk_7973526658410217689_1018 src: /127.0.0.1:34726 dest: /127.0.0.1:55577
    [junit] 09/10/10 00:58:58 INFO DataNode.clienttrace: src: /127.0.0.1:34726, 
dest: /127.0.0.1:55577, bytes: 39, op: HDFS_WRITE, cliID: 
DFSClient_attempt_20091010005819797_0002_m_000000_0, srvID: 
DS-763709620-127.0.1.1-55577-1255136297957, blockid: 
blk_7973526658410217689_1018
    [junit] 09/10/10 00:58:58 INFO datanode.DataNode: PacketResponder 0 for 
block blk_7973526658410217689_1018 terminating
    [junit] 09/10/10 00:58:58 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:55577 is added to 
blk_7973526658410217689_1018 size 39
    [junit] 09/10/10 00:58:58 INFO DataNode.clienttrace: src: /127.0.0.1:52454, 
dest: /127.0.0.1:35232, bytes: 39, op: HDFS_WRITE, cliID: 
DFSClient_attempt_20091010005819797_0002_m_000000_0, srvID: 
DS-1003288558-127.0.1.1-35232-1255136299143, blockid: 
blk_7973526658410217689_1018
    [junit] 09/10/10 00:58:58 INFO datanode.DataNode: PacketResponder 1 for 
block blk_7973526658410217689_1018 terminating
    [junit] 09/10/10 00:58:58 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:35232 is added to 
blk_7973526658410217689_1018 size 39
    [junit] 09/10/10 00:58:58 INFO DataNode.clienttrace: src: /127.0.0.1:60344, 
dest: /127.0.0.1:57908, bytes: 39, op: HDFS_WRITE, cliID: 
DFSClient_attempt_20091010005819797_0002_m_000000_0, srvID: 
DS-2066684421-127.0.1.1-57908-1255136298560, blockid: 
blk_7973526658410217689_1018
    [junit] 09/10/10 00:58:58 INFO datanode.DataNode: PacketResponder 2 for 
block blk_7973526658410217689_1018 terminating
    [junit] 09/10/10 00:58:58 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:57908 is added to 
blk_7973526658410217689_1018 size 39
    [junit] 09/10/10 00:58:58 INFO hdfs.StateChange: DIR* 
NameSystem.completeFile: file 
/tmp/temp-1427071197/tmp-783301132/_temporary/_attempt_20091010005819797_0002_m_000000_0/part-00000
 is closed by DFSClient_attempt_20091010005819797_0002_m_000000_0
    [junit] 09/10/10 00:58:58 INFO mapred.TaskTracker: Task 
attempt_20091010005819797_0002_m_000000_0 is in commit-pending, task 
state:COMMIT_PENDING
    [junit] 09/10/10 00:58:58 INFO mapred.TaskTracker: 
attempt_20091010005819797_0002_m_000000_0 0.0% 
    [junit] 09/10/10 00:58:58 INFO mapred.TaskTracker: JVM with ID: 
jvm_20091010005819797_0002_m_1506276631 given task: 
attempt_20091010005819797_0002_m_000001_0
    [junit] 09/10/10 00:58:59 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/10 00:58:59 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=create      
src=/tmp/temp-1427071197/tmp-783301132/_temporary/_attempt_20091010005819797_0002_m_000001_0/part-00001
 dst=null        perm=hudson:supergroup:rw-r--r--
    [junit] 09/10/10 00:58:59 INFO DataNode.clienttrace: src: /127.0.0.1:55577, 
dest: /127.0.0.1:34732, bytes: 10, op: HDFS_READ, cliID: 
DFSClient_attempt_20091010005819797_0002_m_000001_0, srvID: 
DS-763709620-127.0.1.1-55577-1255136297957, blockid: 
blk_-2248922827833092161_1011
    [junit] 09/10/10 00:58:59 INFO hdfs.StateChange: BLOCK* 
NameSystem.allocateBlock: 
/tmp/temp-1427071197/tmp-783301132/_temporary/_attempt_20091010005819797_0002_m_000001_0/part-00001.
 blk_7607962732334717308_1019
    [junit] 09/10/10 00:58:59 INFO datanode.DataNode: Receiving block 
blk_7607962732334717308_1019 src: /127.0.0.1:60353 dest: /127.0.0.1:57908
    [junit] 09/10/10 00:58:59 INFO datanode.DataNode: Receiving block 
blk_7607962732334717308_1019 src: /127.0.0.1:34734 dest: /127.0.0.1:55577
    [junit] 09/10/10 00:58:59 INFO datanode.DataNode: Receiving block 
blk_7607962732334717308_1019 src: /127.0.0.1:52464 dest: /127.0.0.1:35232
    [junit] 09/10/10 00:58:59 INFO DataNode.clienttrace: src: /127.0.0.1:52464, 
dest: /127.0.0.1:35232, bytes: 39, op: HDFS_WRITE, cliID: 
DFSClient_attempt_20091010005819797_0002_m_000001_0, srvID: 
DS-1003288558-127.0.1.1-35232-1255136299143, blockid: 
blk_7607962732334717308_1019
    [junit] 09/10/10 00:58:59 INFO datanode.DataNode: PacketResponder 0 for 
block blk_7607962732334717308_1019 terminating
    [junit] 09/10/10 00:58:59 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:35232 is added to 
blk_7607962732334717308_1019 size 39
    [junit] 09/10/10 00:58:59 INFO DataNode.clienttrace: src: /127.0.0.1:34734, 
dest: /127.0.0.1:55577, bytes: 39, op: HDFS_WRITE, cliID: 
DFSClient_attempt_20091010005819797_0002_m_000001_0, srvID: 
DS-763709620-127.0.1.1-55577-1255136297957, blockid: 
blk_7607962732334717308_1019
    [junit] 09/10/10 00:58:59 INFO datanode.DataNode: PacketResponder 1 for 
block blk_7607962732334717308_1019 terminating
    [junit] 09/10/10 00:58:59 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:55577 is added to 
blk_7607962732334717308_1019 size 39
    [junit] 09/10/10 00:58:59 INFO DataNode.clienttrace: src: /127.0.0.1:60353, 
dest: /127.0.0.1:57908, bytes: 39, op: HDFS_WRITE, cliID: 
DFSClient_attempt_20091010005819797_0002_m_000001_0, srvID: 
DS-2066684421-127.0.1.1-57908-1255136298560, blockid: 
blk_7607962732334717308_1019
    [junit] 09/10/10 00:58:59 INFO datanode.DataNode: PacketResponder 2 for 
block blk_7607962732334717308_1019 terminating
    [junit] 09/10/10 00:58:59 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:57908 is added to 
blk_7607962732334717308_1019 size 39
    [junit] 09/10/10 00:58:59 INFO hdfs.StateChange: DIR* 
NameSystem.completeFile: file 
/tmp/temp-1427071197/tmp-783301132/_temporary/_attempt_20091010005819797_0002_m_000001_0/part-00001
 is closed by DFSClient_attempt_20091010005819797_0002_m_000001_0
    [junit] 09/10/10 00:58:59 INFO mapred.TaskTracker: Task 
attempt_20091010005819797_0002_m_000001_0 is in commit-pending, task 
state:COMMIT_PENDING
    [junit] 09/10/10 00:58:59 INFO mapred.TaskTracker: 
attempt_20091010005819797_0002_m_000001_0 0.0% 
    [junit] 09/10/10 00:59:00 INFO mapred.TaskTracker: Received commit task 
action for attempt_20091010005819797_0002_m_000000_0
    [junit] 09/10/10 00:59:00 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=listStatus  
src=/tmp/temp-1427071197/tmp-783301132/_temporary/_attempt_20091010005819797_0002_m_000000_0
    dst=null        perm=null
    [junit] 09/10/10 00:59:00 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=mkdirs      src=/tmp/temp-1427071197/tmp-783301132  
dst=null        perm=hudson:supergroup:rwxr-xr-x
    [junit] 09/10/10 00:59:00 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=rename      
src=/tmp/temp-1427071197/tmp-783301132/_temporary/_attempt_20091010005819797_0002_m_000000_0/part-00000
 dst=/tmp/temp-1427071197/tmp-783301132/part-00000       
perm=hudson:supergroup:rw-r--r--
    [junit] 09/10/10 00:59:00 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=delete      
src=/tmp/temp-1427071197/tmp-783301132/_temporary/_attempt_20091010005819797_0002_m_000000_0
    dst=null        perm=null
    [junit] 09/10/10 00:59:00 INFO mapred.TaskTracker: 
attempt_20091010005819797_0002_m_000000_0 1.0% 
    [junit] 09/10/10 00:59:00 INFO mapred.TaskTracker: Task 
attempt_20091010005819797_0002_m_000000_0 is done.
    [junit] 09/10/10 00:59:00 INFO mapred.TaskTracker: reported output size for 
attempt_20091010005819797_0002_m_000000_0  was 0
    [junit] 09/10/10 00:59:00 INFO mapred.TaskTracker: addFreeSlot : current 
free slots : 2
    [junit] 09/10/10 00:59:00 INFO mapred.JvmManager: JVM : 
jvm_20091010005819797_0002_m_-832698438 exited. Number of tasks it ran: 1
    [junit] 09/10/10 00:59:00 INFO mapred.TaskTracker: Received commit task 
action for attempt_20091010005819797_0002_m_000001_0
    [junit] 09/10/10 00:59:01 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=listStatus  
src=/tmp/temp-1427071197/tmp-783301132/_temporary/_attempt_20091010005819797_0002_m_000001_0
    dst=null        perm=null
    [junit] 09/10/10 00:59:01 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=mkdirs      src=/tmp/temp-1427071197/tmp-783301132  
dst=null        perm=hudson:supergroup:rwxr-xr-x
    [junit] 09/10/10 00:59:01 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=rename      
src=/tmp/temp-1427071197/tmp-783301132/_temporary/_attempt_20091010005819797_0002_m_000001_0/part-00001
 dst=/tmp/temp-1427071197/tmp-783301132/part-00001       
perm=hudson:supergroup:rw-r--r--
    [junit] 09/10/10 00:59:01 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=delete      
src=/tmp/temp-1427071197/tmp-783301132/_temporary/_attempt_20091010005819797_0002_m_000001_0
    dst=null        perm=null
    [junit] 09/10/10 00:59:01 INFO mapred.TaskTracker: 
attempt_20091010005819797_0002_m_000001_0 1.0% 
    [junit] 09/10/10 00:59:01 INFO mapred.TaskTracker: Task 
attempt_20091010005819797_0002_m_000001_0 is done.
    [junit] 09/10/10 00:59:01 INFO mapred.TaskTracker: reported output size for 
attempt_20091010005819797_0002_m_000001_0  was 0
    [junit] 09/10/10 00:59:01 INFO mapred.TaskTracker: addFreeSlot : current 
free slots : 2
    [junit] 09/10/10 00:59:01 INFO mapred.JvmManager: JVM : 
jvm_20091010005819797_0002_m_1506276631 exited. Number of tasks it ran: 1
    [junit] 09/10/10 00:59:03 INFO mapred.TaskTracker: 
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find 
taskTracker/jobcache/job_20091010005819797_0002/attempt_20091010005819797_0002_m_000000_0/output/file.out
 in any of the configured local directories
    [junit] 09/10/10 00:59:03 INFO mapred.JobInProgress: Task 
'attempt_20091010005819797_0002_m_000000_0' has completed 
task_20091010005819797_0002_m_000000 successfully.
    [junit] 09/10/10 00:59:03 INFO mapReduceLayer.MapReduceLauncher: 25% 
complete
    [junit] 09/10/10 00:59:03 INFO mapred.TaskTracker: 
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find 
taskTracker/jobcache/job_20091010005819797_0002/attempt_20091010005819797_0002_m_000001_0/output/file.out
 in any of the configured local directories
    [junit] 09/10/10 00:59:03 INFO mapred.JobInProgress: Task 
'attempt_20091010005819797_0002_m_000001_0' has completed 
task_20091010005819797_0002_m_000001 successfully.
    [junit] 09/10/10 00:59:03 INFO mapred.JobTracker: Adding task 
'attempt_20091010005819797_0002_m_000002_0' to tip 
task_20091010005819797_0002_m_000002, for tracker 
'tracker_host1.foo.com:localhost/127.0.0.1:41286'
    [junit] 09/10/10 00:59:03 INFO mapred.TaskTracker: LaunchTaskAction 
(registerTask): attempt_20091010005819797_0002_m_000002_0 task's 
state:UNASSIGNED
    [junit] 09/10/10 00:59:03 INFO mapred.TaskTracker: Trying to launch : 
attempt_20091010005819797_0002_m_000002_0
    [junit] 09/10/10 00:59:03 INFO mapred.TaskTracker: In TaskLauncher, current 
free slots : 2 and trying to launch attempt_20091010005819797_0002_m_000002_0
    [junit] 09/10/10 00:59:03 INFO mapred.JvmManager: In JvmRunner constructed 
JVM ID: jvm_20091010005819797_0002_m_2031261339
    [junit] 09/10/10 00:59:03 INFO mapred.JvmManager: JVM Runner 
jvm_20091010005819797_0002_m_2031261339 spawned.
    [junit] 09/10/10 00:59:04 INFO mapReduceLayer.MapReduceLauncher: 50% 
complete
    [junit] 09/10/10 00:59:04 INFO mapred.TaskTracker: JVM with ID: 
jvm_20091010005819797_0002_m_2031261339 given task: 
attempt_20091010005819797_0002_m_000002_0
    [junit] 09/10/10 00:59:04 INFO mapred.TaskTracker: 
attempt_20091010005819797_0002_m_000002_0 0.0% 
    [junit] 09/10/10 00:59:04 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=delete      
src=/tmp/temp-1427071197/tmp-783301132/_temporary       dst=null        
perm=null
    [junit] 09/10/10 00:59:04 INFO mapred.TaskTracker: 
attempt_20091010005819797_0002_m_000002_0 0.0% cleanup
    [junit] 09/10/10 00:59:04 INFO mapred.TaskTracker: Task 
attempt_20091010005819797_0002_m_000002_0 is done.
    [junit] 09/10/10 00:59:04 INFO mapred.TaskTracker: reported output size for 
attempt_20091010005819797_0002_m_000002_0  was 0
    [junit] 09/10/10 00:59:04 INFO mapred.TaskTracker: addFreeSlot : current 
free slots : 2
    [junit] 09/10/10 00:59:04 INFO mapred.JvmManager: JVM : 
jvm_20091010005819797_0002_m_2031261339 exited. Number of tasks it ran: 1
    [junit] 09/10/10 00:59:06 INFO mapred.TaskTracker: 
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find 
taskTracker/jobcache/job_20091010005819797_0002/attempt_20091010005819797_0002_m_000002_0/output/file.out
 in any of the configured local directories
    [junit] 09/10/10 00:59:06 INFO mapred.JobInProgress: Task 
'attempt_20091010005819797_0002_m_000002_0' has completed 
task_20091010005819797_0002_m_000002 successfully.
    [junit] 09/10/10 00:59:06 INFO mapred.JobInProgress: Job 
job_20091010005819797_0002 has completed successfully.
    [junit] 09/10/10 00:59:06 INFO hdfs.StateChange: BLOCK* 
NameSystem.allocateBlock: 
/tmp/temp-1427071197/tmp-783301132/_logs/history/localhost_1255136299820_job_20091010005819797_0002_hudson_Job3756228276918050786.jar.
 blk_-4670792676074632197_1019
    [junit] 09/10/10 00:59:06 INFO datanode.DataNode: Receiving block 
blk_-4670792676074632197_1019 src: /127.0.0.1:48077 dest: /127.0.0.1:36513
    [junit] 09/10/10 00:59:06 INFO datanode.DataNode: Receiving block 
blk_-4670792676074632197_1019 src: /127.0.0.1:52471 dest: /127.0.0.1:35232
    [junit] 09/10/10 00:59:06 INFO datanode.DataNode: Receiving block 
blk_-4670792676074632197_1019 src: /127.0.0.1:60363 dest: /127.0.0.1:57908
    [junit] 09/10/10 00:59:06 INFO DataNode.clienttrace: src: /127.0.0.1:60363, 
dest: /127.0.0.1:57908, bytes: 6117, op: HDFS_WRITE, cliID: 
DFSClient_-77174316, srvID: DS-2066684421-127.0.1.1-57908-1255136298560, 
blockid: blk_-4670792676074632197_1019
    [junit] 09/10/10 00:59:06 INFO DataNode.clienttrace: src: /127.0.0.1:52471, 
dest: /127.0.0.1:35232, bytes: 6117, op: HDFS_WRITE, cliID: 
DFSClient_-77174316, srvID: DS-1003288558-127.0.1.1-35232-1255136299143, 
blockid: blk_-4670792676074632197_1019
    [junit] 09/10/10 00:59:06 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:57908 is added to 
blk_-4670792676074632197_1019 size 6117
    [junit] 09/10/10 00:59:06 INFO datanode.DataNode: PacketResponder 0 for 
block blk_-4670792676074632197_1019 terminating
    [junit] 09/10/10 00:59:06 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:35232 is added to 
blk_-4670792676074632197_1019 size 6117
    [junit] 09/10/10 00:59:06 INFO datanode.DataNode: PacketResponder 1 for 
block blk_-4670792676074632197_1019 terminating
    [junit] 09/10/10 00:59:06 INFO DataNode.clienttrace: src: /127.0.0.1:48077, 
dest: /127.0.0.1:36513, bytes: 6117, op: HDFS_WRITE, cliID: 
DFSClient_-77174316, srvID: DS-286696190-127.0.1.1-36513-1255136299705, 
blockid: blk_-4670792676074632197_1019
    [junit] 09/10/10 00:59:06 INFO hdfs.StateChange: BLOCK* 
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:36513 is added to 
blk_-4670792676074632197_1019 size 6117
    [junit] 09/10/10 00:59:06 INFO datanode.DataNode: PacketResponder 2 for 
block blk_-4670792676074632197_1019 terminating
    [junit] 09/10/10 00:59:06 INFO hdfs.StateChange: DIR* 
NameSystem.completeFile: file 
/tmp/temp-1427071197/tmp-783301132/_logs/history/localhost_1255136299820_job_20091010005819797_0002_hudson_Job3756228276918050786.jar
 is closed by DFSClient_-77174316
    [junit] 09/10/10 00:59:06 INFO mapred.JobTracker: Removed completed task 
'attempt_20091010005819797_0002_m_000001_0' from 
'tracker_host1.foo.com:localhost/127.0.0.1:41286'
    [junit] 09/10/10 00:59:06 INFO mapred.JobTracker: Removed completed task 
'attempt_20091010005819797_0002_m_000002_0' from 
'tracker_host1.foo.com:localhost/127.0.0.1:41286'
    [junit] 09/10/10 00:59:06 INFO mapred.TaskTracker: Received 'KillJobAction' 
for job: job_20091010005819797_0002
    [junit] 09/10/10 00:59:06 INFO mapred.TaskRunner: 
attempt_20091010005819797_0002_m_000001_0 done; removing files.
    [junit] 09/10/10 00:59:06 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_3750071453426248789 is added to invalidSet of 
127.0.0.1:36513
    [junit] 09/10/10 00:59:06 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_3750071453426248789 is added to invalidSet of 
127.0.0.1:35232
    [junit] 09/10/10 00:59:06 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_3750071453426248789 is added to invalidSet of 
127.0.0.1:57908
    [junit] 09/10/10 00:59:06 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_-1334121746913284522 is added to invalidSet of 
127.0.0.1:36513
    [junit] 09/10/10 00:59:06 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_-1334121746913284522 is added to invalidSet of 
127.0.0.1:35232
    [junit] 09/10/10 00:59:06 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_-1334121746913284522 is added to invalidSet of 
127.0.0.1:57908
    [junit] 09/10/10 00:59:06 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_8761924747304658191 is added to invalidSet of 
127.0.0.1:36513
    [junit] 09/10/10 00:59:06 INFO mapred.IndexCache: Map ID 
attempt_20091010005819797_0002_m_000001_0 not found in cache
    [junit] 09/10/10 00:59:06 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_8761924747304658191 is added to invalidSet of 
127.0.0.1:35232
    [junit] 09/10/10 00:59:06 INFO hdfs.StateChange: BLOCK* 
NameSystem.addToInvalidates: blk_8761924747304658191 is added to invalidSet of 
127.0.0.1:57908
    [junit] 09/10/10 00:59:06 INFO mapred.TaskRunner: 
attempt_20091010005819797_0002_m_000002_0 done; removing files.
    [junit] 09/10/10 00:59:06 INFO mapred.IndexCache: Map ID 
attempt_20091010005819797_0002_m_000002_0 not found in cache
    [junit] 09/10/10 00:59:06 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=delete      
src=/tmp/hadoop-hudson/mapred/system/job_20091010005819797_0002 dst=null        
perm=null
    [junit] 09/10/10 00:59:06 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:35232 
to delete  blk_8761924747304658191_1015 blk_-1334121746913284522_1014 
blk_3750071453426248789_1013
    [junit] 09/10/10 00:59:06 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:57908 
to delete  blk_8761924747304658191_1015 blk_-1334121746913284522_1014 
blk_3750071453426248789_1013
    [junit] 09/10/10 00:59:07 INFO datanode.DataNode: Deleting block 
blk_-1334121746913284522_1014 file 
build/test/data/dfs/data/data5/current/blk_-1334121746913284522
    [junit] 09/10/10 00:59:07 INFO datanode.DataNode: Deleting block 
blk_3750071453426248789_1013 file 
build/test/data/dfs/data/data6/current/blk_3750071453426248789
    [junit] 09/10/10 00:59:07 INFO datanode.DataNode: Deleting block 
blk_8761924747304658191_1015 file 
build/test/data/dfs/data/data6/current/blk_8761924747304658191
    [junit] 09/10/10 00:59:07 INFO mapred.TaskTracker: Received 'KillJobAction' 
for job: job_20091010005819797_0002
    [junit] 09/10/10 00:59:07 WARN mapred.TaskTracker: Unknown job 
job_20091010005819797_0002 being deleted.
    [junit] 09/10/10 00:59:07 INFO mapred.TaskTracker: Received 'KillJobAction' 
for job: job_20091010005819797_0002
    [junit] 09/10/10 00:59:07 WARN mapred.TaskTracker: Unknown job 
job_20091010005819797_0002 being deleted.
    [junit] 09/10/10 00:59:08 INFO mapReduceLayer.MapReduceLauncher: 100% 
complete
    [junit] 09/10/10 00:59:08 INFO mapReduceLayer.MapReduceLauncher: 
Successfully stored result in: 
"hdfs://localhost:59989/tmp/temp-1427071197/tmp-783301132"
    [junit] 09/10/10 00:59:08 INFO mapReduceLayer.MapReduceLauncher: Records 
written : 2
    [junit] 09/10/10 00:59:08 INFO mapReduceLayer.MapReduceLauncher: Bytes 
written : 78
    [junit] 09/10/10 00:59:08 INFO mapReduceLayer.MapReduceLauncher: Success!
    [junit] 09/10/10 00:59:08 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=listStatus  src=/tmp/temp-1427071197/tmp-783301132  
dst=null        perm=null
    [junit] 09/10/10 00:59:08 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/temp-1427071197/tmp-783301132/part-00000       dst=null        
perm=null
    [junit] 09/10/10 00:59:08 INFO DataNode.clienttrace: src: /127.0.0.1:55577, 
dest: /127.0.0.1:34744, bytes: 43, op: HDFS_READ, cliID: DFSClient_-77174316, 
srvID: DS-763709620-127.0.1.1-55577-1255136297957, blockid: 
blk_7973526658410217689_1018
    [junit] 09/10/10 00:59:08 INFO FSNamesystem.audit: ugi=hudson,hudson        
ip=/127.0.0.1   cmd=open        
src=/tmp/temp-1427071197/tmp-783301132/part-00001       dst=null        
perm=null
    [junit] 09/10/10 00:59:08 INFO DataNode.clienttrace: src: /127.0.0.1:55577, 
dest: /127.0.0.1:34745, bytes: 43, op: HDFS_READ, cliID: DFSClient_-77174316, 
srvID: DS-763709620-127.0.1.1-55577-1255136297957, blockid: 
blk_7607962732334717308_1019
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 45.248 sec

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

Total time: 169 minutes 14 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