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
