hadoop-pig-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Hudson Server <hud...@hudson.zones.apache.org>
Subject Build failed in Hudson: Pig-trunk #645
Date Sat, 12 Dec 2009 02:14:10 GMT
See <http://hudson.zones.apache.org/hudson/job/Pig-trunk/645/changes>

Changes:

[daijy] PIG-1142: Got NullPointerException merge join with pruning

[yanz] PIG-1145: Merge Join on Large Table throws an EOF exception (yanz)

------------------------------------------
[...truncated 228593 lines...]
    [junit] 09/12/12 02:12:08 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/hadoop-hudson/mapred/system/job_20091212021137281_0002/job.split.
blk_8744047869084643086_1014
    [junit] 09/12/12 02:12:08 INFO datanode.DataNode: Receiving block blk_8744047869084643086_1014
src: /127.0.0.1:34013 dest: /127.0.0.1:57637
    [junit] 09/12/12 02:12:08 INFO datanode.DataNode: Receiving block blk_8744047869084643086_1014
src: /127.0.0.1:38898 dest: /127.0.0.1:46559
    [junit] 09/12/12 02:12:08 INFO datanode.DataNode: Receiving block blk_8744047869084643086_1014
src: /127.0.0.1:53824 dest: /127.0.0.1:56621
    [junit] 09/12/12 02:12:08 INFO DataNode.clienttrace: src: /127.0.0.1:53824, dest: /127.0.0.1:56621,
bytes: 1589, op: HDFS_WRITE, cliID: DFSClient_-1419330198, srvID: DS-2034772238-127.0.1.1-56621-1260583896758,
blockid: blk_8744047869084643086_1014
    [junit] 09/12/12 02:12:08 INFO datanode.DataNode: PacketResponder 0 for block blk_8744047869084643086_1014
terminating
    [junit] 09/12/12 02:12:08 INFO DataNode.clienttrace: src: /127.0.0.1:38898, dest: /127.0.0.1:46559,
bytes: 1589, op: HDFS_WRITE, cliID: DFSClient_-1419330198, srvID: DS-815252464-127.0.1.1-46559-1260583896308,
blockid: blk_8744047869084643086_1014
    [junit] 09/12/12 02:12:08 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:56621 is added to blk_8744047869084643086_1014 size 1589
    [junit] 09/12/12 02:12:08 INFO datanode.DataNode: PacketResponder 1 for block blk_8744047869084643086_1014
terminating
    [junit] 09/12/12 02:12:08 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:46559 is added to blk_8744047869084643086_1014 size 1589
    [junit] 09/12/12 02:12:08 INFO DataNode.clienttrace: src: /127.0.0.1:34013, dest: /127.0.0.1:57637,
bytes: 1589, op: HDFS_WRITE, cliID: DFSClient_-1419330198, srvID: DS-1844402489-127.0.1.1-57637-1260583897211,
blockid: blk_8744047869084643086_1014
    [junit] 09/12/12 02:12:08 INFO datanode.DataNode: PacketResponder 2 for block blk_8744047869084643086_1014
terminating
    [junit] 09/12/12 02:12:08 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:57637 is added to blk_8744047869084643086_1014 size 1589
    [junit] 09/12/12 02:12:08 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/hadoop-hudson/mapred/system/job_20091212021137281_0002/job.split
is closed by DFSClient_-1419330198
    [junit] 09/12/12 02:12:08 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=create
src=/tmp/hadoop-hudson/mapred/system/job_20091212021137281_0002/job.xml	dst=null	perm=hudson:supergroup:rw-r--r--
    [junit] 09/12/12 02:12:08 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=setPermission
src=/tmp/hadoop-hudson/mapred/system/job_20091212021137281_0002/job.xml	dst=null	perm=hudson:supergroup:rw-r--r--
    [junit] 09/12/12 02:12:08 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/hadoop-hudson/mapred/system/job_20091212021137281_0002/job.xml.
blk_2666416874129524588_1015
    [junit] 09/12/12 02:12:08 INFO datanode.DataNode: Receiving block blk_2666416874129524588_1015
src: /127.0.0.1:53825 dest: /127.0.0.1:56621
    [junit] 09/12/12 02:12:08 INFO datanode.DataNode: Receiving block blk_2666416874129524588_1015
src: /127.0.0.1:34017 dest: /127.0.0.1:57637
    [junit] 09/12/12 02:12:08 INFO datanode.DataNode: Receiving block blk_2666416874129524588_1015
src: /127.0.0.1:59529 dest: /127.0.0.1:41270
    [junit] 09/12/12 02:12:08 INFO DataNode.clienttrace: src: /127.0.0.1:59529, dest: /127.0.0.1:41270,
bytes: 48759, op: HDFS_WRITE, cliID: DFSClient_-1419330198, srvID: DS-486837220-127.0.1.1-41270-1260583895816,
blockid: blk_2666416874129524588_1015
    [junit] 09/12/12 02:12:08 INFO datanode.DataNode: PacketResponder 0 for block blk_2666416874129524588_1015
terminating
    [junit] 09/12/12 02:12:08 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:41270 is added to blk_2666416874129524588_1015 size 48759
    [junit] 09/12/12 02:12:08 INFO DataNode.clienttrace: src: /127.0.0.1:34017, dest: /127.0.0.1:57637,
bytes: 48759, op: HDFS_WRITE, cliID: DFSClient_-1419330198, srvID: DS-1844402489-127.0.1.1-57637-1260583897211,
blockid: blk_2666416874129524588_1015
    [junit] 09/12/12 02:12:08 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:57637 is added to blk_2666416874129524588_1015 size 48759
    [junit] 09/12/12 02:12:08 INFO datanode.DataNode: PacketResponder 1 for block blk_2666416874129524588_1015
terminating
    [junit] 09/12/12 02:12:08 INFO DataNode.clienttrace: src: /127.0.0.1:53825, dest: /127.0.0.1:56621,
bytes: 48759, op: HDFS_WRITE, cliID: DFSClient_-1419330198, srvID: DS-2034772238-127.0.1.1-56621-1260583896758,
blockid: blk_2666416874129524588_1015
    [junit] 09/12/12 02:12:08 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:56621 is added to blk_2666416874129524588_1015 size 48759
    [junit] 09/12/12 02:12:08 INFO datanode.DataNode: PacketResponder 2 for block blk_2666416874129524588_1015
terminating
    [junit] 09/12/12 02:12:08 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/hadoop-hudson/mapred/system/job_20091212021137281_0002/job.xml
is closed by DFSClient_-1419330198
    [junit] 09/12/12 02:12:08 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091212021137281_0002/job.xml	dst=null	perm=null
    [junit] 09/12/12 02:12:08 INFO DataNode.clienttrace: src: /127.0.0.1:57637, dest: /127.0.0.1:34019,
bytes: 49143, op: HDFS_READ, cliID: DFSClient_-1419330198, srvID: DS-1844402489-127.0.1.1-57637-1260583897211,
blockid: blk_2666416874129524588_1015
    [junit] 09/12/12 02:12:08 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091212021137281_0002/job.jar	dst=null	perm=null
    [junit] 09/12/12 02:12:09 INFO DataNode.clienttrace: src: /127.0.0.1:56621, dest: /127.0.0.1:53829,
bytes: 2685616, op: HDFS_READ, cliID: DFSClient_-1419330198, srvID: DS-2034772238-127.0.1.1-56621-1260583896758,
blockid: blk_-5450306673347372709_1013
    [junit] 09/12/12 02:12:09 INFO mapred.JobTracker: Initializing job_20091212021137281_0002
    [junit] 09/12/12 02:12:09 INFO mapred.JobInProgress: Initializing job_20091212021137281_0002
    [junit] 09/12/12 02:12:09 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=create
src=/tmp/temp-1818856848/tmp-7263167/_logs/history/localhost_1260583897305_job_20091212021137281_0002_hudson_Job8345945091640948416.jar
dst=null	perm=hudson:supergroup:rw-r--r--
    [junit] 09/12/12 02:12:09 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=create
src=/tmp/temp-1818856848/tmp-7263167/_logs/history/localhost_1260583897305_job_20091212021137281_0002_conf.xml
dst=null	perm=hudson:supergroup:rw-r--r--
    [junit] 09/12/12 02:12:09 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp-1818856848/tmp-7263167/_logs/history/localhost_1260583897305_job_20091212021137281_0002_conf.xml.
blk_-2166918994539332138_1017
    [junit] 09/12/12 02:12:09 INFO datanode.DataNode: Receiving block blk_-2166918994539332138_1017
src: /127.0.0.1:34021 dest: /127.0.0.1:57637
    [junit] 09/12/12 02:12:09 INFO datanode.DataNode: Receiving block blk_-2166918994539332138_1017
src: /127.0.0.1:38906 dest: /127.0.0.1:46559
    [junit] 09/12/12 02:12:09 INFO datanode.DataNode: Receiving block blk_-2166918994539332138_1017
src: /127.0.0.1:53832 dest: /127.0.0.1:56621
    [junit] 09/12/12 02:12:09 INFO DataNode.clienttrace: src: /127.0.0.1:53832, dest: /127.0.0.1:56621,
bytes: 48789, op: HDFS_WRITE, cliID: DFSClient_-1419330198, srvID: DS-2034772238-127.0.1.1-56621-1260583896758,
blockid: blk_-2166918994539332138_1017
    [junit] 09/12/12 02:12:09 INFO datanode.DataNode: PacketResponder 0 for block blk_-2166918994539332138_1017
terminating
    [junit] 09/12/12 02:12:09 INFO DataNode.clienttrace: src: /127.0.0.1:38906, dest: /127.0.0.1:46559,
bytes: 48789, op: HDFS_WRITE, cliID: DFSClient_-1419330198, srvID: DS-815252464-127.0.1.1-46559-1260583896308,
blockid: blk_-2166918994539332138_1017
    [junit] 09/12/12 02:12:09 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:56621 is added to blk_-2166918994539332138_1017 size 48789
    [junit] 09/12/12 02:12:09 INFO datanode.DataNode: PacketResponder 1 for block blk_-2166918994539332138_1017
terminating
    [junit] 09/12/12 02:12:09 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:46559 is added to blk_-2166918994539332138_1017 size 48789
    [junit] 09/12/12 02:12:09 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:57637 is added to blk_-2166918994539332138_1017 size 48789
    [junit] 09/12/12 02:12:09 INFO DataNode.clienttrace: src: /127.0.0.1:34021, dest: /127.0.0.1:57637,
bytes: 48789, op: HDFS_WRITE, cliID: DFSClient_-1419330198, srvID: DS-1844402489-127.0.1.1-57637-1260583897211,
blockid: blk_-2166918994539332138_1017
    [junit] 09/12/12 02:12:09 INFO datanode.DataNode: PacketResponder 2 for block blk_-2166918994539332138_1017
terminating
    [junit] 09/12/12 02:12:09 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp-1818856848/tmp-7263167/_logs/history/localhost_1260583897305_job_20091212021137281_0002_conf.xml
is closed by DFSClient_-1419330198
    [junit] 09/12/12 02:12:09 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091212021137281_0002/job.split	dst=null	perm=null
    [junit] 09/12/12 02:12:09 INFO DataNode.clienttrace: src: /127.0.0.1:57637, dest: /127.0.0.1:34024,
bytes: 1605, op: HDFS_READ, cliID: DFSClient_-1419330198, srvID: DS-1844402489-127.0.1.1-57637-1260583897211,
blockid: blk_8744047869084643086_1014
    [junit] 09/12/12 02:12:09 INFO mapred.JobInProgress: Input size for job job_20091212021137281_0002
= 12. Number of splits = 2
    [junit] 09/12/12 02:12:09 INFO mapred.JobInProgress: tip:task_20091212021137281_0002_m_000000
has split on node:/default-rack/h7.grid.sp2.yahoo.net
    [junit] 09/12/12 02:12:09 INFO mapred.JobInProgress: tip:task_20091212021137281_0002_m_000001
has split on node:/default-rack/h7.grid.sp2.yahoo.net
    [junit] 09/12/12 02:12:09 INFO datanode.DataNode: Deleting block blk_8175446689131084455_1005
file build/test/data/dfs/data/data4/current/blk_8175446689131084455
    [junit] 09/12/12 02:12:09 INFO mapred.JobTracker: Adding task 'attempt_20091212021137281_0002_m_000003_0'
to tip task_20091212021137281_0002_m_000003, for tracker 'tracker_host2.foo.com:localhost/127.0.0.1:59514'
    [junit] 09/12/12 02:12:09 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20091212021137281_0002_m_000003_0
task's state:UNASSIGNED
    [junit] 09/12/12 02:12:09 INFO mapred.TaskTracker: Trying to launch : attempt_20091212021137281_0002_m_000003_0
    [junit] 09/12/12 02:12:09 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_20091212021137281_0002_m_000003_0
    [junit] 09/12/12 02:12:09 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091212021137281_0002/job.xml	dst=null	perm=null
    [junit] 09/12/12 02:12:09 INFO DataNode.clienttrace: src: /127.0.0.1:56621, dest: /127.0.0.1:53834,
bytes: 49143, op: HDFS_READ, cliID: DFSClient_-1419330198, srvID: DS-2034772238-127.0.1.1-56621-1260583896758,
blockid: blk_2666416874129524588_1015
    [junit] 09/12/12 02:12:09 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091212021137281_0002/job.jar	dst=null	perm=null
    [junit] 09/12/12 02:12:09 INFO DataNode.clienttrace: src: /127.0.0.1:56621, dest: /127.0.0.1:53835,
bytes: 2685616, op: HDFS_READ, cliID: DFSClient_-1419330198, srvID: DS-2034772238-127.0.1.1-56621-1260583896758,
blockid: blk_-5450306673347372709_1013
    [junit] 09/12/12 02:12:09 INFO mapReduceLayer.MapReduceLauncher: Submitting job: job_20091212021137281_0002
to execution engine.
    [junit] 09/12/12 02:12:09 INFO mapReduceLayer.MapReduceLauncher: More information at:
http://localhost:34433/jobdetails.jsp?jobid=job_20091212021137281_0002
    [junit] 09/12/12 02:12:09 INFO mapReduceLayer.MapReduceLauncher: To kill this job, use:
kill job_20091212021137281_0002
    [junit] 09/12/12 02:12:09 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20091212021137281_0002_m_1684275872
    [junit] 09/12/12 02:12:09 INFO mapred.JvmManager: JVM Runner jvm_20091212021137281_0002_m_1684275872
spawned.
    [junit] 09/12/12 02:12:10 INFO mapReduceLayer.MapReduceLauncher: 0% complete
    [junit] 09/12/12 02:12:10 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:57637 to delete
 blk_-5450306673347372709_1013
    [junit] 09/12/12 02:12:10 INFO mapred.TaskTracker: JVM with ID: jvm_20091212021137281_0002_m_1684275872
given task: attempt_20091212021137281_0002_m_000003_0
    [junit] 09/12/12 02:12:10 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=mkdirs
src=/tmp/temp-1818856848/tmp-7263167/_temporary	dst=null	perm=hudson:supergroup:rwxr-xr-x
    [junit] 09/12/12 02:12:10 INFO mapred.TaskTracker: attempt_20091212021137281_0002_m_000003_0
0.0% setup
    [junit] 09/12/12 02:12:10 INFO mapred.TaskTracker: Task attempt_20091212021137281_0002_m_000003_0
is done.
    [junit] 09/12/12 02:12:10 INFO mapred.TaskTracker: reported output size for attempt_20091212021137281_0002_m_000003_0
 was 0
    [junit] 09/12/12 02:12:10 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/12/12 02:12:10 INFO mapred.JvmManager: JVM : jvm_20091212021137281_0002_m_1684275872
exited. Number of tasks it ran: 1
    [junit] 09/12/12 02:12:12 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_20091212021137281_0002/attempt_20091212021137281_0002_m_000003_0/output/file.out
in any of the configured local directories
    [junit] 09/12/12 02:12:12 INFO mapred.JobInProgress: Task 'attempt_20091212021137281_0002_m_000003_0'
has completed task_20091212021137281_0002_m_000003 successfully.
    [junit] 09/12/12 02:12:12 INFO mapred.JobTracker: Adding task 'attempt_20091212021137281_0002_m_000000_0'
to tip task_20091212021137281_0002_m_000000, for tracker 'tracker_host2.foo.com:localhost/127.0.0.1:59514'
    [junit] 09/12/12 02:12:12 INFO mapred.JobInProgress: Choosing rack-local task task_20091212021137281_0002_m_000000
    [junit] 09/12/12 02:12:12 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20091212021137281_0002_m_000000_0
task's state:UNASSIGNED
    [junit] 09/12/12 02:12:12 INFO mapred.TaskTracker: Trying to launch : attempt_20091212021137281_0002_m_000000_0
    [junit] 09/12/12 02:12:12 INFO mapred.TaskTracker: Received KillTaskAction for task: attempt_20091212021137281_0002_m_000003_0
    [junit] 09/12/12 02:12:12 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_20091212021137281_0002_m_000000_0
    [junit] 09/12/12 02:12:12 INFO mapred.TaskTracker: About to purge task: attempt_20091212021137281_0002_m_000003_0
    [junit] 09/12/12 02:12:12 INFO mapred.TaskRunner: attempt_20091212021137281_0002_m_000003_0
done; removing files.
    [junit] 09/12/12 02:12:12 INFO mapred.IndexCache: Map ID attempt_20091212021137281_0002_m_000003_0
not found in cache
    [junit] 09/12/12 02:12:12 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20091212021137281_0002_m_1585876471
    [junit] 09/12/12 02:12:12 INFO mapred.JvmManager: JVM Runner jvm_20091212021137281_0002_m_1585876471
spawned.
    [junit] 09/12/12 02:12:12 INFO mapred.JobTracker: Adding task 'attempt_20091212021137281_0002_m_000001_0'
to tip task_20091212021137281_0002_m_000001, for tracker 'tracker_host3.foo.com:localhost/127.0.0.1:42570'
    [junit] 09/12/12 02:12:12 INFO mapred.JobInProgress: Choosing rack-local task task_20091212021137281_0002_m_000001
    [junit] 09/12/12 02:12:12 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20091212021137281_0002_m_000001_0
task's state:UNASSIGNED
    [junit] 09/12/12 02:12:12 INFO mapred.TaskTracker: Trying to launch : attempt_20091212021137281_0002_m_000001_0
    [junit] 09/12/12 02:12:12 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_20091212021137281_0002_m_000001_0
    [junit] 09/12/12 02:12:12 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091212021137281_0002/job.xml	dst=null	perm=null
    [junit] 09/12/12 02:12:12 INFO DataNode.clienttrace: src: /127.0.0.1:56621, dest: /127.0.0.1:53841,
bytes: 49143, op: HDFS_READ, cliID: DFSClient_-1419330198, srvID: DS-2034772238-127.0.1.1-56621-1260583896758,
blockid: blk_2666416874129524588_1015
    [junit] 09/12/12 02:12:12 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091212021137281_0002/job.jar	dst=null	perm=null
    [junit] 09/12/12 02:12:13 INFO DataNode.clienttrace: src: /127.0.0.1:57637, dest: /127.0.0.1:34033,
bytes: 2685616, op: HDFS_READ, cliID: DFSClient_-1419330198, srvID: DS-1844402489-127.0.1.1-57637-1260583897211,
blockid: blk_-5450306673347372709_1013
    [junit] 09/12/12 02:12:13 INFO mapred.TaskTracker: JVM with ID: jvm_20091212021137281_0002_m_1585876471
given task: attempt_20091212021137281_0002_m_000000_0
    [junit] 09/12/12 02:12:13 INFO datanode.DataNode: Deleting block blk_-5450306673347372709_1013
file build/test/data/dfs/data/data7/current/blk_-5450306673347372709
    [junit] 09/12/12 02:12:13 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20091212021137281_0002_m_-1991287883
    [junit] 09/12/12 02:12:13 INFO mapred.JvmManager: JVM Runner jvm_20091212021137281_0002_m_-1991287883
spawned.
    [junit] 09/12/12 02:12:13 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/user/hudson/input2.txt	dst=null	perm=null
    [junit] 09/12/12 02:12:13 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=create
src=/tmp/temp-1818856848/tmp-7263167/_temporary/_attempt_20091212021137281_0002_m_000000_0/part-00000
dst=null	perm=hudson:supergroup:rw-r--r--
    [junit] 09/12/12 02:12:13 INFO DataNode.clienttrace: src: /127.0.0.1:56621, dest: /127.0.0.1:53848,
bytes: 10, op: HDFS_READ, cliID: DFSClient_attempt_20091212021137281_0002_m_000000_0, srvID:
DS-2034772238-127.0.1.1-56621-1260583896758, blockid: blk_-8820812634726619302_1012
    [junit] 09/12/12 02:12:13 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp-1818856848/tmp-7263167/_temporary/_attempt_20091212021137281_0002_m_000000_0/part-00000.
blk_-520539255931906875_1018
    [junit] 09/12/12 02:12:13 INFO datanode.DataNode: Receiving block blk_-520539255931906875_1018
src: /127.0.0.1:59551 dest: /127.0.0.1:41270
    [junit] 09/12/12 02:12:13 INFO datanode.DataNode: Receiving block blk_-520539255931906875_1018
src: /127.0.0.1:38925 dest: /127.0.0.1:46559
    [junit] 09/12/12 02:12:13 INFO datanode.DataNode: Receiving block blk_-520539255931906875_1018
src: /127.0.0.1:34042 dest: /127.0.0.1:57637
    [junit] 09/12/12 02:12:13 INFO DataNode.clienttrace: src: /127.0.0.1:34042, dest: /127.0.0.1:57637,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091212021137281_0002_m_000000_0, srvID:
DS-1844402489-127.0.1.1-57637-1260583897211, blockid: blk_-520539255931906875_1018
    [junit] 09/12/12 02:12:13 INFO datanode.DataNode: PacketResponder 0 for block blk_-520539255931906875_1018
terminating
    [junit] 09/12/12 02:12:13 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:57637 is added to blk_-520539255931906875_1018 size 39
    [junit] 09/12/12 02:12:13 INFO DataNode.clienttrace: src: /127.0.0.1:38925, dest: /127.0.0.1:46559,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091212021137281_0002_m_000000_0, srvID:
DS-815252464-127.0.1.1-46559-1260583896308, blockid: blk_-520539255931906875_1018
    [junit] 09/12/12 02:12:13 INFO datanode.DataNode: PacketResponder 1 for block blk_-520539255931906875_1018
terminating
    [junit] 09/12/12 02:12:13 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:46559 is added to blk_-520539255931906875_1018 size 39
    [junit] 09/12/12 02:12:13 INFO DataNode.clienttrace: src: /127.0.0.1:59551, dest: /127.0.0.1:41270,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091212021137281_0002_m_000000_0, srvID:
DS-486837220-127.0.1.1-41270-1260583895816, blockid: blk_-520539255931906875_1018
    [junit] 09/12/12 02:12:13 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:41270 is added to blk_-520539255931906875_1018 size 39
    [junit] 09/12/12 02:12:13 INFO datanode.DataNode: PacketResponder 2 for block blk_-520539255931906875_1018
terminating
    [junit] 09/12/12 02:12:13 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp-1818856848/tmp-7263167/_temporary/_attempt_20091212021137281_0002_m_000000_0/part-00000
is closed by DFSClient_attempt_20091212021137281_0002_m_000000_0
    [junit] 09/12/12 02:12:13 INFO mapred.TaskTracker: Task attempt_20091212021137281_0002_m_000000_0
is in commit-pending, task state:COMMIT_PENDING
    [junit] 09/12/12 02:12:13 INFO mapred.TaskTracker: attempt_20091212021137281_0002_m_000000_0
0.0% 
    [junit] 09/12/12 02:12:13 INFO mapred.TaskTracker: JVM with ID: jvm_20091212021137281_0002_m_-1991287883
given task: attempt_20091212021137281_0002_m_000001_0
    [junit] 09/12/12 02:12:14 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/user/hudson/input1.txt	dst=null	perm=null
    [junit] 09/12/12 02:12:14 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=create
src=/tmp/temp-1818856848/tmp-7263167/_temporary/_attempt_20091212021137281_0002_m_000001_0/part-00001
dst=null	perm=hudson:supergroup:rw-r--r--
    [junit] 09/12/12 02:12:14 INFO DataNode.clienttrace: src: /127.0.0.1:46559, dest: /127.0.0.1:38932,
bytes: 10, op: HDFS_READ, cliID: DFSClient_attempt_20091212021137281_0002_m_000001_0, srvID:
DS-815252464-127.0.1.1-46559-1260583896308, blockid: blk_-7757999458660161036_1011
    [junit] 09/12/12 02:12:14 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp-1818856848/tmp-7263167/_temporary/_attempt_20091212021137281_0002_m_000001_0/part-00001.
blk_-8846366975967416416_1019
    [junit] 09/12/12 02:12:14 INFO datanode.DataNode: Receiving block blk_-8846366975967416416_1019
src: /127.0.0.1:59560 dest: /127.0.0.1:41270
    [junit] 09/12/12 02:12:14 INFO datanode.DataNode: Receiving block blk_-8846366975967416416_1019
src: /127.0.0.1:38934 dest: /127.0.0.1:46559
    [junit] 09/12/12 02:12:14 INFO datanode.DataNode: Receiving block blk_-8846366975967416416_1019
src: /127.0.0.1:53860 dest: /127.0.0.1:56621
    [junit] 09/12/12 02:12:14 INFO DataNode.clienttrace: src: /127.0.0.1:53860, dest: /127.0.0.1:56621,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091212021137281_0002_m_000001_0, srvID:
DS-2034772238-127.0.1.1-56621-1260583896758, blockid: blk_-8846366975967416416_1019
    [junit] 09/12/12 02:12:14 INFO datanode.DataNode: PacketResponder 0 for block blk_-8846366975967416416_1019
terminating
    [junit] 09/12/12 02:12:14 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:56621 is added to blk_-8846366975967416416_1019 size 39
    [junit] 09/12/12 02:12:14 INFO DataNode.clienttrace: src: /127.0.0.1:38934, dest: /127.0.0.1:46559,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091212021137281_0002_m_000001_0, srvID:
DS-815252464-127.0.1.1-46559-1260583896308, blockid: blk_-8846366975967416416_1019
    [junit] 09/12/12 02:12:14 INFO DataNode.clienttrace: src: /127.0.0.1:59560, dest: /127.0.0.1:41270,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091212021137281_0002_m_000001_0, srvID:
DS-486837220-127.0.1.1-41270-1260583895816, blockid: blk_-8846366975967416416_1019
    [junit] 09/12/12 02:12:14 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:46559 is added to blk_-8846366975967416416_1019 size 39
    [junit] 09/12/12 02:12:14 INFO datanode.DataNode: PacketResponder 1 for block blk_-8846366975967416416_1019
terminating
    [junit] 09/12/12 02:12:14 INFO datanode.DataNode: PacketResponder 2 for block blk_-8846366975967416416_1019
terminating
    [junit] 09/12/12 02:12:14 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:41270 is added to blk_-8846366975967416416_1019 size 39
    [junit] 09/12/12 02:12:14 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp-1818856848/tmp-7263167/_temporary/_attempt_20091212021137281_0002_m_000001_0/part-00001
is closed by DFSClient_attempt_20091212021137281_0002_m_000001_0
    [junit] 09/12/12 02:12:14 INFO mapred.TaskTracker: Task attempt_20091212021137281_0002_m_000001_0
is in commit-pending, task state:COMMIT_PENDING
    [junit] 09/12/12 02:12:14 INFO mapred.TaskTracker: attempt_20091212021137281_0002_m_000001_0
0.0% 
    [junit] 09/12/12 02:12:15 INFO mapred.TaskTracker: Received commit task action for attempt_20091212021137281_0002_m_000000_0
    [junit] 09/12/12 02:12:15 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=listStatus
src=/tmp/temp-1818856848/tmp-7263167/_temporary/_attempt_20091212021137281_0002_m_000000_0
dst=null	perm=null
    [junit] 09/12/12 02:12:15 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=mkdirs
src=/tmp/temp-1818856848/tmp-7263167	dst=null	perm=hudson:supergroup:rwxr-xr-x
    [junit] 09/12/12 02:12:15 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=rename
src=/tmp/temp-1818856848/tmp-7263167/_temporary/_attempt_20091212021137281_0002_m_000000_0/part-00000
dst=/tmp/temp-1818856848/tmp-7263167/part-00000	perm=hudson:supergroup:rw-r--r--
    [junit] 09/12/12 02:12:15 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/tmp/temp-1818856848/tmp-7263167/_temporary/_attempt_20091212021137281_0002_m_000000_0
dst=null	perm=null
    [junit] 09/12/12 02:12:15 INFO mapred.TaskTracker: attempt_20091212021137281_0002_m_000000_0
1.0% 
    [junit] 09/12/12 02:12:15 INFO mapred.TaskTracker: Task attempt_20091212021137281_0002_m_000000_0
is done.
    [junit] 09/12/12 02:12:15 INFO mapred.TaskTracker: reported output size for attempt_20091212021137281_0002_m_000000_0
 was 0
    [junit] 09/12/12 02:12:15 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/12/12 02:12:15 INFO mapred.JvmManager: JVM : jvm_20091212021137281_0002_m_1585876471
exited. Number of tasks it ran: 1
    [junit] 09/12/12 02:12:15 INFO mapred.TaskTracker: Received commit task action for attempt_20091212021137281_0002_m_000001_0
    [junit] 09/12/12 02:12:16 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=listStatus
src=/tmp/temp-1818856848/tmp-7263167/_temporary/_attempt_20091212021137281_0002_m_000001_0
dst=null	perm=null
    [junit] 09/12/12 02:12:16 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=mkdirs
src=/tmp/temp-1818856848/tmp-7263167	dst=null	perm=hudson:supergroup:rwxr-xr-x
    [junit] 09/12/12 02:12:16 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=rename
src=/tmp/temp-1818856848/tmp-7263167/_temporary/_attempt_20091212021137281_0002_m_000001_0/part-00001
dst=/tmp/temp-1818856848/tmp-7263167/part-00001	perm=hudson:supergroup:rw-r--r--
    [junit] 09/12/12 02:12:16 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/tmp/temp-1818856848/tmp-7263167/_temporary/_attempt_20091212021137281_0002_m_000001_0
dst=null	perm=null
    [junit] 09/12/12 02:12:16 INFO mapred.TaskTracker: attempt_20091212021137281_0002_m_000001_0
1.0% 
    [junit] 09/12/12 02:12:16 INFO mapred.TaskTracker: Task attempt_20091212021137281_0002_m_000001_0
is done.
    [junit] 09/12/12 02:12:16 INFO mapred.TaskTracker: reported output size for attempt_20091212021137281_0002_m_000001_0
 was 0
    [junit] 09/12/12 02:12:16 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/12/12 02:12:16 INFO mapred.JvmManager: JVM : jvm_20091212021137281_0002_m_-1991287883
exited. Number of tasks it ran: 1
    [junit] 09/12/12 02:12:18 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_20091212021137281_0002/attempt_20091212021137281_0002_m_000000_0/output/file.out
in any of the configured local directories
    [junit] 09/12/12 02:12:18 INFO mapred.JobInProgress: Task 'attempt_20091212021137281_0002_m_000000_0'
has completed task_20091212021137281_0002_m_000000 successfully.
    [junit] 09/12/12 02:12:18 INFO mapReduceLayer.MapReduceLauncher: 25% complete
    [junit] 09/12/12 02:12:18 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_20091212021137281_0002/attempt_20091212021137281_0002_m_000001_0/output/file.out
in any of the configured local directories
    [junit] 09/12/12 02:12:18 INFO mapred.JobInProgress: Task 'attempt_20091212021137281_0002_m_000001_0'
has completed task_20091212021137281_0002_m_000001 successfully.
    [junit] 09/12/12 02:12:18 INFO mapred.JobTracker: Adding task 'attempt_20091212021137281_0002_m_000002_0'
to tip task_20091212021137281_0002_m_000002, for tracker 'tracker_host3.foo.com:localhost/127.0.0.1:42570'
    [junit] 09/12/12 02:12:18 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20091212021137281_0002_m_000002_0
task's state:UNASSIGNED
    [junit] 09/12/12 02:12:18 INFO mapred.TaskTracker: Trying to launch : attempt_20091212021137281_0002_m_000002_0
    [junit] 09/12/12 02:12:18 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_20091212021137281_0002_m_000002_0
    [junit] 09/12/12 02:12:18 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20091212021137281_0002_m_1655958864
    [junit] 09/12/12 02:12:18 INFO mapred.JvmManager: JVM Runner jvm_20091212021137281_0002_m_1655958864
spawned.
    [junit] 09/12/12 02:12:19 INFO mapReduceLayer.MapReduceLauncher: 50% complete
    [junit] 09/12/12 02:12:19 INFO mapred.TaskTracker: JVM with ID: jvm_20091212021137281_0002_m_1655958864
given task: attempt_20091212021137281_0002_m_000002_0
    [junit] 09/12/12 02:12:19 INFO mapred.TaskTracker: attempt_20091212021137281_0002_m_000002_0
0.0% 
    [junit] 09/12/12 02:12:19 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/tmp/temp-1818856848/tmp-7263167/_temporary	dst=null	perm=null
    [junit] 09/12/12 02:12:19 INFO mapred.TaskTracker: attempt_20091212021137281_0002_m_000002_0
0.0% cleanup
    [junit] 09/12/12 02:12:19 INFO mapred.TaskTracker: Task attempt_20091212021137281_0002_m_000002_0
is done.
    [junit] 09/12/12 02:12:19 INFO mapred.TaskTracker: reported output size for attempt_20091212021137281_0002_m_000002_0
 was 0
    [junit] 09/12/12 02:12:19 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/12/12 02:12:20 INFO mapred.JvmManager: JVM : jvm_20091212021137281_0002_m_1655958864
exited. Number of tasks it ran: 1
    [junit] 09/12/12 02:12:21 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_20091212021137281_0002/attempt_20091212021137281_0002_m_000002_0/output/file.out
in any of the configured local directories
    [junit] 09/12/12 02:12:21 INFO mapred.JobInProgress: Task 'attempt_20091212021137281_0002_m_000002_0'
has completed task_20091212021137281_0002_m_000002 successfully.
    [junit] 09/12/12 02:12:21 INFO mapred.JobInProgress: Job job_20091212021137281_0002 has
completed successfully.
    [junit] 09/12/12 02:12:21 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp-1818856848/tmp-7263167/_logs/history/localhost_1260583897305_job_20091212021137281_0002_hudson_Job8345945091640948416.jar.
blk_3104956361138915338_1019
    [junit] 09/12/12 02:12:21 INFO datanode.DataNode: Receiving block blk_3104956361138915338_1019
src: /127.0.0.1:59568 dest: /127.0.0.1:41270
    [junit] 09/12/12 02:12:21 INFO datanode.DataNode: Receiving block blk_3104956361138915338_1019
src: /127.0.0.1:38942 dest: /127.0.0.1:46559
    [junit] 09/12/12 02:12:21 INFO datanode.DataNode: Receiving block blk_3104956361138915338_1019
src: /127.0.0.1:53868 dest: /127.0.0.1:56621
    [junit] 09/12/12 02:12:21 INFO DataNode.clienttrace: src: /127.0.0.1:53868, dest: /127.0.0.1:56621,
bytes: 6117, op: HDFS_WRITE, cliID: DFSClient_-1419330198, srvID: DS-2034772238-127.0.1.1-56621-1260583896758,
blockid: blk_3104956361138915338_1019
    [junit] 09/12/12 02:12:21 INFO datanode.DataNode: PacketResponder 0 for block blk_3104956361138915338_1019
terminating
    [junit] 09/12/12 02:12:21 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:56621 is added to blk_3104956361138915338_1019 size 6117
    [junit] 09/12/12 02:12:21 INFO DataNode.clienttrace: src: /127.0.0.1:38942, dest: /127.0.0.1:46559,
bytes: 6117, op: HDFS_WRITE, cliID: DFSClient_-1419330198, srvID: DS-815252464-127.0.1.1-46559-1260583896308,
blockid: blk_3104956361138915338_1019
    [junit] 09/12/12 02:12:21 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:46559 is added to blk_3104956361138915338_1019 size 6117
    [junit] 09/12/12 02:12:21 INFO datanode.DataNode: PacketResponder 1 for block blk_3104956361138915338_1019
terminating
    [junit] 09/12/12 02:12:21 INFO DataNode.clienttrace: src: /127.0.0.1:59568, dest: /127.0.0.1:41270,
bytes: 6117, op: HDFS_WRITE, cliID: DFSClient_-1419330198, srvID: DS-486837220-127.0.1.1-41270-1260583895816,
blockid: blk_3104956361138915338_1019
    [junit] 09/12/12 02:12:21 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:41270 is added to blk_3104956361138915338_1019 size 6117
    [junit] 09/12/12 02:12:21 INFO datanode.DataNode: PacketResponder 2 for block blk_3104956361138915338_1019
terminating
    [junit] 09/12/12 02:12:21 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp-1818856848/tmp-7263167/_logs/history/localhost_1260583897305_job_20091212021137281_0002_hudson_Job8345945091640948416.jar
is closed by DFSClient_-1419330198
    [junit] 09/12/12 02:12:21 INFO mapred.JobTracker: Removed completed task 'attempt_20091212021137281_0002_m_000001_0'
from 'tracker_host3.foo.com:localhost/127.0.0.1:42570'
    [junit] 09/12/12 02:12:21 INFO mapred.JobTracker: Removed completed task 'attempt_20091212021137281_0002_m_000002_0'
from 'tracker_host3.foo.com:localhost/127.0.0.1:42570'
    [junit] 09/12/12 02:12:21 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-5450306673347372709
is added to invalidSet of 127.0.0.1:57637
    [junit] 09/12/12 02:12:21 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-5450306673347372709
is added to invalidSet of 127.0.0.1:41270
    [junit] 09/12/12 02:12:21 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-5450306673347372709
is added to invalidSet of 127.0.0.1:56621
    [junit] 09/12/12 02:12:21 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_8744047869084643086
is added to invalidSet of 127.0.0.1:56621
    [junit] 09/12/12 02:12:21 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_8744047869084643086
is added to invalidSet of 127.0.0.1:46559
    [junit] 09/12/12 02:12:21 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20091212021137281_0002
    [junit] 09/12/12 02:12:21 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_8744047869084643086
is added to invalidSet of 127.0.0.1:57637
    [junit] 09/12/12 02:12:21 INFO mapred.TaskRunner: attempt_20091212021137281_0002_m_000002_0
done; removing files.
    [junit] 09/12/12 02:12:21 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_2666416874129524588
is added to invalidSet of 127.0.0.1:41270
    [junit] 09/12/12 02:12:21 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_2666416874129524588
is added to invalidSet of 127.0.0.1:57637
    [junit] 09/12/12 02:12:21 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_2666416874129524588
is added to invalidSet of 127.0.0.1:56621
    [junit] 09/12/12 02:12:21 INFO mapred.IndexCache: Map ID attempt_20091212021137281_0002_m_000002_0
not found in cache
    [junit] 09/12/12 02:12:21 INFO mapred.TaskRunner: attempt_20091212021137281_0002_m_000001_0
done; removing files.
    [junit] 09/12/12 02:12:21 INFO mapred.IndexCache: Map ID attempt_20091212021137281_0002_m_000001_0
not found in cache
    [junit] 09/12/12 02:12:21 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/tmp/hadoop-hudson/mapred/system/job_20091212021137281_0002	dst=null	perm=null
    [junit] 09/12/12 02:12:22 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:57637 to delete
 blk_8744047869084643086_1014 blk_2666416874129524588_1015 blk_-5450306673347372709_1013
    [junit] 09/12/12 02:12:22 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:56621 to delete
 blk_8744047869084643086_1014 blk_2666416874129524588_1015 blk_-5450306673347372709_1013
    [junit] 09/12/12 02:12:23 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20091212021137281_0002
    [junit] 09/12/12 02:12:23 WARN mapred.TaskTracker: Unknown job job_20091212021137281_0002
being deleted.
    [junit] 09/12/12 02:12:24 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20091212021137281_0002
    [junit] 09/12/12 02:12:24 WARN mapred.TaskTracker: Unknown job job_20091212021137281_0002
being deleted.
    [junit] 09/12/12 02:12:24 INFO mapReduceLayer.MapReduceLauncher: 100% complete
    [junit] 09/12/12 02:12:24 INFO mapReduceLayer.MapReduceLauncher: Successfully stored result
in: "hdfs://localhost:56397/tmp/temp-1818856848/tmp-7263167"
    [junit] 09/12/12 02:12:24 INFO mapReduceLayer.MapReduceLauncher: Records written : 2
    [junit] 09/12/12 02:12:24 INFO mapReduceLayer.MapReduceLauncher: Bytes written : 78
    [junit] 09/12/12 02:12:24 INFO mapReduceLayer.MapReduceLauncher: Success!
    [junit] 09/12/12 02:12:24 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=listStatus
src=/tmp/temp-1818856848/tmp-7263167	dst=null	perm=null
    [junit] 09/12/12 02:12:24 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/temp-1818856848/tmp-7263167/part-00000	dst=null	perm=null
    [junit] 09/12/12 02:12:24 INFO DataNode.clienttrace: src: /127.0.0.1:46559, dest: /127.0.0.1:38944,
bytes: 43, op: HDFS_READ, cliID: DFSClient_-1419330198, srvID: DS-815252464-127.0.1.1-46559-1260583896308,
blockid: blk_-520539255931906875_1018
    [junit] 09/12/12 02:12:24 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/temp-1818856848/tmp-7263167/part-00001	dst=null	perm=null
    [junit] 09/12/12 02:12:24 INFO DataNode.clienttrace: src: /127.0.0.1:56621, dest: /127.0.0.1:53870,
bytes: 43, op: HDFS_READ, cliID: DFSClient_-1419330198, srvID: DS-2034772238-127.0.1.1-56621-1260583896758,
blockid: blk_-8846366975967416416_1019
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 44.285 sec

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

Total time: 241 minutes 52 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


Mime
View raw message