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 #644
Date Fri, 11 Dec 2009 02:14:22 GMT
See <http://hudson.zones.apache.org/hudson/job/Pig-trunk/644/changes>

Changes:

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

[olga] PIG-1135: skewed join partitioner returns negative partition index  (yinghe via olgan)

------------------------------------------
[...truncated 226691 lines...]
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: Receiving block blk_-7484173445110389036_1014
src: /127.0.0.1:43896 dest: /127.0.0.1:53521
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: Receiving block blk_-7484173445110389036_1014
src: /127.0.0.1:46932 dest: /127.0.0.1:43636
    [junit] 09/12/11 02:11:11 INFO DataNode.clienttrace: src: /127.0.0.1:46932, dest: /127.0.0.1:43636,
bytes: 1589, op: HDFS_WRITE, cliID: DFSClient_-1703283091, srvID: DS-2005575441-127.0.1.1-43636-1260497438633,
blockid: blk_-7484173445110389036_1014
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: PacketResponder 0 for block blk_-7484173445110389036_1014
terminating
    [junit] 09/12/11 02:11:11 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:43636 is added to blk_-7484173445110389036_1014 size 1589
    [junit] 09/12/11 02:11:11 INFO DataNode.clienttrace: src: /127.0.0.1:43896, dest: /127.0.0.1:53521,
bytes: 1589, op: HDFS_WRITE, cliID: DFSClient_-1703283091, srvID: DS-1004217825-127.0.1.1-53521-1260497439567,
blockid: blk_-7484173445110389036_1014
    [junit] 09/12/11 02:11:11 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:53521 is added to blk_-7484173445110389036_1014 size 1589
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: PacketResponder 1 for block blk_-7484173445110389036_1014
terminating
    [junit] 09/12/11 02:11:11 INFO DataNode.clienttrace: src: /127.0.0.1:53485, dest: /127.0.0.1:50810,
bytes: 1589, op: HDFS_WRITE, cliID: DFSClient_-1703283091, srvID: DS-1383757220-127.0.1.1-50810-1260497438128,
blockid: blk_-7484173445110389036_1014
    [junit] 09/12/11 02:11:11 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:50810 is added to blk_-7484173445110389036_1014 size 1589
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: PacketResponder 2 for block blk_-7484173445110389036_1014
terminating
    [junit] 09/12/11 02:11:11 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/hadoop-hudson/mapred/system/job_20091211021039651_0002/job.split
is closed by DFSClient_-1703283091
    [junit] 09/12/11 02:11:11 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=create
src=/tmp/hadoop-hudson/mapred/system/job_20091211021039651_0002/job.xml	dst=null	perm=hudson:supergroup:rw-r--r--
    [junit] 09/12/11 02:11:11 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=setPermission
src=/tmp/hadoop-hudson/mapred/system/job_20091211021039651_0002/job.xml	dst=null	perm=hudson:supergroup:rw-r--r--
    [junit] 09/12/11 02:11:11 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/hadoop-hudson/mapred/system/job_20091211021039651_0002/job.xml.
blk_5770296480651727355_1015
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: Receiving block blk_5770296480651727355_1015
src: /127.0.0.1:43898 dest: /127.0.0.1:53521
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: Receiving block blk_5770296480651727355_1015
src: /127.0.0.1:53489 dest: /127.0.0.1:50810
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: Receiving block blk_5770296480651727355_1015
src: /127.0.0.1:46935 dest: /127.0.0.1:43636
    [junit] 09/12/11 02:11:11 INFO DataNode.clienttrace: src: /127.0.0.1:46935, dest: /127.0.0.1:43636,
bytes: 48774, op: HDFS_WRITE, cliID: DFSClient_-1703283091, srvID: DS-2005575441-127.0.1.1-43636-1260497438633,
blockid: blk_5770296480651727355_1015
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: PacketResponder 0 for block blk_5770296480651727355_1015
terminating
    [junit] 09/12/11 02:11:11 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:43636 is added to blk_5770296480651727355_1015 size 48774
    [junit] 09/12/11 02:11:11 INFO DataNode.clienttrace: src: /127.0.0.1:53489, dest: /127.0.0.1:50810,
bytes: 48774, op: HDFS_WRITE, cliID: DFSClient_-1703283091, srvID: DS-1383757220-127.0.1.1-50810-1260497438128,
blockid: blk_5770296480651727355_1015
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: PacketResponder 1 for block blk_5770296480651727355_1015
terminating
    [junit] 09/12/11 02:11:11 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:50810 is added to blk_5770296480651727355_1015 size 48774
    [junit] 09/12/11 02:11:11 INFO DataNode.clienttrace: src: /127.0.0.1:43898, dest: /127.0.0.1:53521,
bytes: 48774, op: HDFS_WRITE, cliID: DFSClient_-1703283091, srvID: DS-1004217825-127.0.1.1-53521-1260497439567,
blockid: blk_5770296480651727355_1015
    [junit] 09/12/11 02:11:11 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:53521 is added to blk_5770296480651727355_1015 size 48774
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: PacketResponder 2 for block blk_5770296480651727355_1015
terminating
    [junit] 09/12/11 02:11:11 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/hadoop-hudson/mapred/system/job_20091211021039651_0002/job.xml
is closed by DFSClient_-1703283091
    [junit] 09/12/11 02:11:11 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091211021039651_0002/job.xml	dst=null	perm=null
    [junit] 09/12/11 02:11:11 INFO DataNode.clienttrace: src: /127.0.0.1:53521, dest: /127.0.0.1:43901,
bytes: 49158, op: HDFS_READ, cliID: DFSClient_-1703283091, srvID: DS-1004217825-127.0.1.1-53521-1260497439567,
blockid: blk_5770296480651727355_1015
    [junit] 09/12/11 02:11:11 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091211021039651_0002/job.jar	dst=null	perm=null
    [junit] 09/12/11 02:11:11 INFO DataNode.clienttrace: src: /127.0.0.1:42756, dest: /127.0.0.1:39799,
bytes: 2684649, op: HDFS_READ, cliID: DFSClient_-1703283091, srvID: DS-1303939329-127.0.1.1-42756-1260497439078,
blockid: blk_-6885823568878148974_1013
    [junit] 09/12/11 02:11:11 INFO mapred.JobTracker: Initializing job_20091211021039651_0002
    [junit] 09/12/11 02:11:11 INFO mapred.JobInProgress: Initializing job_20091211021039651_0002
    [junit] 09/12/11 02:11:11 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=create
src=/tmp/temp-2004098019/tmp-1774748346/_logs/history/localhost_1260497439674_job_20091211021039651_0002_hudson_Job2400416277762928924.jar
dst=null	perm=hudson:supergroup:rw-r--r--
    [junit] 09/12/11 02:11:11 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=create
src=/tmp/temp-2004098019/tmp-1774748346/_logs/history/localhost_1260497439674_job_20091211021039651_0002_conf.xml
dst=null	perm=hudson:supergroup:rw-r--r--
    [junit] 09/12/11 02:11:11 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp-2004098019/tmp-1774748346/_logs/history/localhost_1260497439674_job_20091211021039651_0002_conf.xml.
blk_-5492884493306836835_1017
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: Receiving block blk_-5492884493306836835_1017
src: /127.0.0.1:46938 dest: /127.0.0.1:43636
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: Receiving block blk_-5492884493306836835_1017
src: /127.0.0.1:43904 dest: /127.0.0.1:53521
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: Receiving block blk_-5492884493306836835_1017
src: /127.0.0.1:53495 dest: /127.0.0.1:50810
    [junit] 09/12/11 02:11:11 INFO DataNode.clienttrace: src: /127.0.0.1:53495, dest: /127.0.0.1:50810,
bytes: 48804, op: HDFS_WRITE, cliID: DFSClient_-1703283091, srvID: DS-1383757220-127.0.1.1-50810-1260497438128,
blockid: blk_-5492884493306836835_1017
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: PacketResponder 0 for block blk_-5492884493306836835_1017
terminating
    [junit] 09/12/11 02:11:11 INFO DataNode.clienttrace: src: /127.0.0.1:43904, dest: /127.0.0.1:53521,
bytes: 48804, op: HDFS_WRITE, cliID: DFSClient_-1703283091, srvID: DS-1004217825-127.0.1.1-53521-1260497439567,
blockid: blk_-5492884493306836835_1017
    [junit] 09/12/11 02:11:11 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:50810 is added to blk_-5492884493306836835_1017 size 48804
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: PacketResponder 1 for block blk_-5492884493306836835_1017
terminating
    [junit] 09/12/11 02:11:11 INFO DataNode.clienttrace: src: /127.0.0.1:46938, dest: /127.0.0.1:43636,
bytes: 48804, op: HDFS_WRITE, cliID: DFSClient_-1703283091, srvID: DS-2005575441-127.0.1.1-43636-1260497438633,
blockid: blk_-5492884493306836835_1017
    [junit] 09/12/11 02:11:11 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:53521 is added to blk_-5492884493306836835_1017 size 48804
    [junit] 09/12/11 02:11:11 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:43636 is added to blk_-5492884493306836835_1017 size 48804
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: PacketResponder 2 for block blk_-5492884493306836835_1017
terminating
    [junit] 09/12/11 02:11:11 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp-2004098019/tmp-1774748346/_logs/history/localhost_1260497439674_job_20091211021039651_0002_conf.xml
is closed by DFSClient_-1703283091
    [junit] 09/12/11 02:11:11 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091211021039651_0002/job.split	dst=null	perm=null
    [junit] 09/12/11 02:11:11 INFO DataNode.clienttrace: src: /127.0.0.1:50810, dest: /127.0.0.1:53496,
bytes: 1605, op: HDFS_READ, cliID: DFSClient_-1703283091, srvID: DS-1383757220-127.0.1.1-50810-1260497438128,
blockid: blk_-7484173445110389036_1014
    [junit] 09/12/11 02:11:11 INFO mapred.JobInProgress: Input size for job job_20091211021039651_0002
= 12. Number of splits = 2
    [junit] 09/12/11 02:11:11 INFO mapred.JobInProgress: tip:task_20091211021039651_0002_m_000000
has split on node:/default-rack/h7.grid.sp2.yahoo.net
    [junit] 09/12/11 02:11:11 INFO mapred.JobInProgress: tip:task_20091211021039651_0002_m_000001
has split on node:/default-rack/h7.grid.sp2.yahoo.net
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: Deleting block blk_-2848833127999126889_1005
file build/test/data/dfs/data/data4/current/blk_-2848833127999126889
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: Deleting block blk_5586370247697422379_1006
file build/test/data/dfs/data/data3/current/blk_5586370247697422379
    [junit] 09/12/11 02:11:11 INFO datanode.DataNode: Deleting block blk_6110827439246943390_1007
file build/test/data/dfs/data/data4/current/blk_6110827439246943390
    [junit] 09/12/11 02:11:11 INFO mapred.JobTracker: Adding task 'attempt_20091211021039651_0002_m_000003_0'
to tip task_20091211021039651_0002_m_000003, for tracker 'tracker_host2.foo.com:localhost/127.0.0.1:52722'
    [junit] 09/12/11 02:11:11 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20091211021039651_0002_m_000003_0
task's state:UNASSIGNED
    [junit] 09/12/11 02:11:11 INFO mapred.TaskTracker: Trying to launch : attempt_20091211021039651_0002_m_000003_0
    [junit] 09/12/11 02:11:11 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_20091211021039651_0002_m_000003_0
    [junit] 09/12/11 02:11:11 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091211021039651_0002/job.xml	dst=null	perm=null
    [junit] 09/12/11 02:11:11 INFO DataNode.clienttrace: src: /127.0.0.1:43636, dest: /127.0.0.1:46942,
bytes: 49158, op: HDFS_READ, cliID: DFSClient_-1703283091, srvID: DS-2005575441-127.0.1.1-43636-1260497438633,
blockid: blk_5770296480651727355_1015
    [junit] 09/12/11 02:11:11 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091211021039651_0002/job.jar	dst=null	perm=null
    [junit] 09/12/11 02:11:11 INFO DataNode.clienttrace: src: /127.0.0.1:42756, dest: /127.0.0.1:39805,
bytes: 2684649, op: HDFS_READ, cliID: DFSClient_-1703283091, srvID: DS-1303939329-127.0.1.1-42756-1260497439078,
blockid: blk_-6885823568878148974_1013
    [junit] 09/12/11 02:11:12 INFO mapReduceLayer.MapReduceLauncher: Submitting job: job_20091211021039651_0002
to execution engine.
    [junit] 09/12/11 02:11:12 INFO mapReduceLayer.MapReduceLauncher: More information at:
http://localhost:33902/jobdetails.jsp?jobid=job_20091211021039651_0002
    [junit] 09/12/11 02:11:12 INFO mapReduceLayer.MapReduceLauncher: To kill this job, use:
kill job_20091211021039651_0002
    [junit] 09/12/11 02:11:12 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20091211021039651_0002_m_-1764810084
    [junit] 09/12/11 02:11:12 INFO mapred.JvmManager: JVM Runner jvm_20091211021039651_0002_m_-1764810084
spawned.
    [junit] 09/12/11 02:11:12 INFO mapReduceLayer.MapReduceLauncher: 0% complete
    [junit] 09/12/11 02:11:12 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:42756 to delete
 blk_-6885823568878148974_1013
    [junit] 09/12/11 02:11:12 INFO mapred.TaskTracker: JVM with ID: jvm_20091211021039651_0002_m_-1764810084
given task: attempt_20091211021039651_0002_m_000003_0
    [junit] 09/12/11 02:11:13 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=mkdirs
src=/tmp/temp-2004098019/tmp-1774748346/_temporary	dst=null	perm=hudson:supergroup:rwxr-xr-x
    [junit] 09/12/11 02:11:13 INFO mapred.TaskTracker: attempt_20091211021039651_0002_m_000003_0
0.0% setup
    [junit] 09/12/11 02:11:13 INFO mapred.TaskTracker: Task attempt_20091211021039651_0002_m_000003_0
is done.
    [junit] 09/12/11 02:11:13 INFO mapred.TaskTracker: reported output size for attempt_20091211021039651_0002_m_000003_0
 was 0
    [junit] 09/12/11 02:11:13 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/12/11 02:11:13 INFO mapred.JvmManager: JVM : jvm_20091211021039651_0002_m_-1764810084
exited. Number of tasks it ran: 1
    [junit] 09/12/11 02:11:14 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_20091211021039651_0002/attempt_20091211021039651_0002_m_000003_0/output/file.out
in any of the configured local directories
    [junit] 09/12/11 02:11:14 INFO mapred.JobInProgress: Task 'attempt_20091211021039651_0002_m_000003_0'
has completed task_20091211021039651_0002_m_000003 successfully.
    [junit] 09/12/11 02:11:14 INFO mapred.JobTracker: Adding task 'attempt_20091211021039651_0002_m_000000_0'
to tip task_20091211021039651_0002_m_000000, for tracker 'tracker_host2.foo.com:localhost/127.0.0.1:52722'
    [junit] 09/12/11 02:11:14 INFO mapred.JobInProgress: Choosing rack-local task task_20091211021039651_0002_m_000000
    [junit] 09/12/11 02:11:14 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20091211021039651_0002_m_000000_0
task's state:UNASSIGNED
    [junit] 09/12/11 02:11:14 INFO mapred.TaskTracker: Trying to launch : attempt_20091211021039651_0002_m_000000_0
    [junit] 09/12/11 02:11:14 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_20091211021039651_0002_m_000000_0
    [junit] 09/12/11 02:11:14 INFO mapred.TaskTracker: Received KillTaskAction for task: attempt_20091211021039651_0002_m_000003_0
    [junit] 09/12/11 02:11:14 INFO mapred.TaskTracker: About to purge task: attempt_20091211021039651_0002_m_000003_0
    [junit] 09/12/11 02:11:14 INFO mapred.TaskRunner: attempt_20091211021039651_0002_m_000003_0
done; removing files.
    [junit] 09/12/11 02:11:14 INFO mapred.IndexCache: Map ID attempt_20091211021039651_0002_m_000003_0
not found in cache
    [junit] 09/12/11 02:11:14 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20091211021039651_0002_m_2090389057
    [junit] 09/12/11 02:11:14 INFO mapred.JvmManager: JVM Runner jvm_20091211021039651_0002_m_2090389057
spawned.
    [junit] 09/12/11 02:11:15 INFO datanode.DataNode: Deleting block blk_-6885823568878148974_1013
file build/test/data/dfs/data/data5/current/blk_-6885823568878148974
    [junit] 09/12/11 02:11:15 INFO mapred.JobTracker: Adding task 'attempt_20091211021039651_0002_m_000001_0'
to tip task_20091211021039651_0002_m_000001, for tracker 'tracker_host3.foo.com:localhost/127.0.0.1:47808'
    [junit] 09/12/11 02:11:15 INFO mapred.JobInProgress: Choosing rack-local task task_20091211021039651_0002_m_000001
    [junit] 09/12/11 02:11:15 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20091211021039651_0002_m_000001_0
task's state:UNASSIGNED
    [junit] 09/12/11 02:11:15 INFO mapred.TaskTracker: Trying to launch : attempt_20091211021039651_0002_m_000001_0
    [junit] 09/12/11 02:11:15 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_20091211021039651_0002_m_000001_0
    [junit] 09/12/11 02:11:15 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091211021039651_0002/job.xml	dst=null	perm=null
    [junit] 09/12/11 02:11:15 INFO mapred.TaskTracker: JVM with ID: jvm_20091211021039651_0002_m_2090389057
given task: attempt_20091211021039651_0002_m_000000_0
    [junit] 09/12/11 02:11:15 INFO DataNode.clienttrace: src: /127.0.0.1:43636, dest: /127.0.0.1:46952,
bytes: 49158, op: HDFS_READ, cliID: DFSClient_-1703283091, srvID: DS-2005575441-127.0.1.1-43636-1260497438633,
blockid: blk_5770296480651727355_1015
    [junit] 09/12/11 02:11:15 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091211021039651_0002/job.jar	dst=null	perm=null
    [junit] 09/12/11 02:11:15 INFO DataNode.clienttrace: src: /127.0.0.1:43636, dest: /127.0.0.1:46953,
bytes: 2684649, op: HDFS_READ, cliID: DFSClient_-1703283091, srvID: DS-2005575441-127.0.1.1-43636-1260497438633,
blockid: blk_-6885823568878148974_1013
    [junit] 09/12/11 02:11:15 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20091211021039651_0002_m_1283436715
    [junit] 09/12/11 02:11:15 INFO mapred.JvmManager: JVM Runner jvm_20091211021039651_0002_m_1283436715
spawned.
    [junit] 09/12/11 02:11:15 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/11 02:11:15 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=create
src=/tmp/temp-2004098019/tmp-1774748346/_temporary/_attempt_20091211021039651_0002_m_000000_0/part-00000
dst=null	perm=hudson:supergroup:rw-r--r--
    [junit] 09/12/11 02:11:16 INFO DataNode.clienttrace: src: /127.0.0.1:42756, dest: /127.0.0.1:39818,
bytes: 10, op: HDFS_READ, cliID: DFSClient_attempt_20091211021039651_0002_m_000000_0, srvID:
DS-1303939329-127.0.1.1-42756-1260497439078, blockid: blk_5699974881095968303_1012
    [junit] 09/12/11 02:11:16 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp-2004098019/tmp-1774748346/_temporary/_attempt_20091211021039651_0002_m_000000_0/part-00000.
blk_-453335940435961308_1018
    [junit] 09/12/11 02:11:16 INFO datanode.DataNode: Receiving block blk_-453335940435961308_1018
src: /127.0.0.1:43922 dest: /127.0.0.1:53521
    [junit] 09/12/11 02:11:16 INFO datanode.DataNode: Receiving block blk_-453335940435961308_1018
src: /127.0.0.1:39820 dest: /127.0.0.1:42756
    [junit] 09/12/11 02:11:16 INFO datanode.DataNode: Receiving block blk_-453335940435961308_1018
src: /127.0.0.1:53514 dest: /127.0.0.1:50810
    [junit] 09/12/11 02:11:16 INFO DataNode.clienttrace: src: /127.0.0.1:53514, dest: /127.0.0.1:50810,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091211021039651_0002_m_000000_0, srvID:
DS-1383757220-127.0.1.1-50810-1260497438128, blockid: blk_-453335940435961308_1018
    [junit] 09/12/11 02:11:16 INFO datanode.DataNode: PacketResponder 0 for block blk_-453335940435961308_1018
terminating
    [junit] 09/12/11 02:11:16 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:50810 is added to blk_-453335940435961308_1018 size 39
    [junit] 09/12/11 02:11:16 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:42756 is added to blk_-453335940435961308_1018 size 39
    [junit] 09/12/11 02:11:16 INFO DataNode.clienttrace: src: /127.0.0.1:39820, dest: /127.0.0.1:42756,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091211021039651_0002_m_000000_0, srvID:
DS-1303939329-127.0.1.1-42756-1260497439078, blockid: blk_-453335940435961308_1018
    [junit] 09/12/11 02:11:16 INFO datanode.DataNode: PacketResponder 1 for block blk_-453335940435961308_1018
terminating
    [junit] 09/12/11 02:11:16 INFO DataNode.clienttrace: src: /127.0.0.1:43922, dest: /127.0.0.1:53521,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091211021039651_0002_m_000000_0, srvID:
DS-1004217825-127.0.1.1-53521-1260497439567, blockid: blk_-453335940435961308_1018
    [junit] 09/12/11 02:11:16 INFO datanode.DataNode: PacketResponder 2 for block blk_-453335940435961308_1018
terminating
    [junit] 09/12/11 02:11:16 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:53521 is added to blk_-453335940435961308_1018 size 39
    [junit] 09/12/11 02:11:16 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp-2004098019/tmp-1774748346/_temporary/_attempt_20091211021039651_0002_m_000000_0/part-00000
is closed by DFSClient_attempt_20091211021039651_0002_m_000000_0
    [junit] 09/12/11 02:11:16 INFO mapred.TaskTracker: Task attempt_20091211021039651_0002_m_000000_0
is in commit-pending, task state:COMMIT_PENDING
    [junit] 09/12/11 02:11:16 INFO mapred.TaskTracker: attempt_20091211021039651_0002_m_000000_0
0.0% 
    [junit] 09/12/11 02:11:16 INFO mapred.TaskTracker: JVM with ID: jvm_20091211021039651_0002_m_1283436715
given task: attempt_20091211021039651_0002_m_000001_0
    [junit] 09/12/11 02:11:16 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/11 02:11:16 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=create
src=/tmp/temp-2004098019/tmp-1774748346/_temporary/_attempt_20091211021039651_0002_m_000001_0/part-00001
dst=null	perm=hudson:supergroup:rw-r--r--
    [junit] 09/12/11 02:11:16 INFO DataNode.clienttrace: src: /127.0.0.1:42756, dest: /127.0.0.1:39827,
bytes: 10, op: HDFS_READ, cliID: DFSClient_attempt_20091211021039651_0002_m_000001_0, srvID:
DS-1303939329-127.0.1.1-42756-1260497439078, blockid: blk_7867134949564120003_1011
    [junit] 09/12/11 02:11:16 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp-2004098019/tmp-1774748346/_temporary/_attempt_20091211021039651_0002_m_000001_0/part-00001.
blk_6367061818940566987_1019
    [junit] 09/12/11 02:11:16 INFO datanode.DataNode: Receiving block blk_6367061818940566987_1019
src: /127.0.0.1:43931 dest: /127.0.0.1:53521
    [junit] 09/12/11 02:11:16 INFO datanode.DataNode: Receiving block blk_6367061818940566987_1019
src: /127.0.0.1:46967 dest: /127.0.0.1:43636
    [junit] 09/12/11 02:11:16 INFO datanode.DataNode: Receiving block blk_6367061818940566987_1019
src: /127.0.0.1:53523 dest: /127.0.0.1:50810
    [junit] 09/12/11 02:11:16 INFO DataNode.clienttrace: src: /127.0.0.1:53523, dest: /127.0.0.1:50810,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091211021039651_0002_m_000001_0, srvID:
DS-1383757220-127.0.1.1-50810-1260497438128, blockid: blk_6367061818940566987_1019
    [junit] 09/12/11 02:11:16 INFO datanode.DataNode: PacketResponder 0 for block blk_6367061818940566987_1019
terminating
    [junit] 09/12/11 02:11:16 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:50810 is added to blk_6367061818940566987_1019 size 39
    [junit] 09/12/11 02:11:16 INFO DataNode.clienttrace: src: /127.0.0.1:46967, dest: /127.0.0.1:43636,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091211021039651_0002_m_000001_0, srvID:
DS-2005575441-127.0.1.1-43636-1260497438633, blockid: blk_6367061818940566987_1019
    [junit] 09/12/11 02:11:16 INFO datanode.DataNode: PacketResponder 1 for block blk_6367061818940566987_1019
terminating
    [junit] 09/12/11 02:11:16 INFO DataNode.clienttrace: src: /127.0.0.1:43931, dest: /127.0.0.1:53521,
bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091211021039651_0002_m_000001_0, srvID:
DS-1004217825-127.0.1.1-53521-1260497439567, blockid: blk_6367061818940566987_1019
    [junit] 09/12/11 02:11:16 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:43636 is added to blk_6367061818940566987_1019 size 39
    [junit] 09/12/11 02:11:16 INFO datanode.DataNode: PacketResponder 2 for block blk_6367061818940566987_1019
terminating
    [junit] 09/12/11 02:11:16 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:53521 is added to blk_6367061818940566987_1019 size 39
    [junit] 09/12/11 02:11:16 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp-2004098019/tmp-1774748346/_temporary/_attempt_20091211021039651_0002_m_000001_0/part-00001
is closed by DFSClient_attempt_20091211021039651_0002_m_000001_0
    [junit] 09/12/11 02:11:16 INFO mapred.TaskTracker: Task attempt_20091211021039651_0002_m_000001_0
is in commit-pending, task state:COMMIT_PENDING
    [junit] 09/12/11 02:11:16 INFO mapred.TaskTracker: attempt_20091211021039651_0002_m_000001_0
0.0% 
    [junit] 09/12/11 02:11:17 INFO mapred.TaskTracker: Received commit task action for attempt_20091211021039651_0002_m_000000_0
    [junit] 09/12/11 02:11:18 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=listStatus
src=/tmp/temp-2004098019/tmp-1774748346/_temporary/_attempt_20091211021039651_0002_m_000000_0
dst=null	perm=null
    [junit] 09/12/11 02:11:18 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=mkdirs
src=/tmp/temp-2004098019/tmp-1774748346	dst=null	perm=hudson:supergroup:rwxr-xr-x
    [junit] 09/12/11 02:11:18 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=rename
src=/tmp/temp-2004098019/tmp-1774748346/_temporary/_attempt_20091211021039651_0002_m_000000_0/part-00000
dst=/tmp/temp-2004098019/tmp-1774748346/part-00000	perm=hudson:supergroup:rw-r--r--
    [junit] 09/12/11 02:11:18 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/tmp/temp-2004098019/tmp-1774748346/_temporary/_attempt_20091211021039651_0002_m_000000_0
dst=null	perm=null
    [junit] 09/12/11 02:11:18 INFO mapred.TaskTracker: attempt_20091211021039651_0002_m_000000_0
1.0% 
    [junit] 09/12/11 02:11:18 INFO mapred.TaskTracker: Task attempt_20091211021039651_0002_m_000000_0
is done.
    [junit] 09/12/11 02:11:18 INFO mapred.TaskTracker: reported output size for attempt_20091211021039651_0002_m_000000_0
 was 0
    [junit] 09/12/11 02:11:18 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/12/11 02:11:18 INFO mapred.JvmManager: JVM : jvm_20091211021039651_0002_m_2090389057
exited. Number of tasks it ran: 1
    [junit] 09/12/11 02:11:18 INFO mapred.TaskTracker: Received commit task action for attempt_20091211021039651_0002_m_000001_0
    [junit] 09/12/11 02:11:18 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=listStatus
src=/tmp/temp-2004098019/tmp-1774748346/_temporary/_attempt_20091211021039651_0002_m_000001_0
dst=null	perm=null
    [junit] 09/12/11 02:11:18 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=mkdirs
src=/tmp/temp-2004098019/tmp-1774748346	dst=null	perm=hudson:supergroup:rwxr-xr-x
    [junit] 09/12/11 02:11:18 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=rename
src=/tmp/temp-2004098019/tmp-1774748346/_temporary/_attempt_20091211021039651_0002_m_000001_0/part-00001
dst=/tmp/temp-2004098019/tmp-1774748346/part-00001	perm=hudson:supergroup:rw-r--r--
    [junit] 09/12/11 02:11:18 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/tmp/temp-2004098019/tmp-1774748346/_temporary/_attempt_20091211021039651_0002_m_000001_0
dst=null	perm=null
    [junit] 09/12/11 02:11:18 INFO mapred.TaskTracker: attempt_20091211021039651_0002_m_000001_0
1.0% 
    [junit] 09/12/11 02:11:18 INFO mapred.TaskTracker: Task attempt_20091211021039651_0002_m_000001_0
is done.
    [junit] 09/12/11 02:11:18 INFO mapred.TaskTracker: reported output size for attempt_20091211021039651_0002_m_000001_0
 was 0
    [junit] 09/12/11 02:11:18 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/12/11 02:11:19 INFO mapred.JvmManager: JVM : jvm_20091211021039651_0002_m_1283436715
exited. Number of tasks it ran: 1
    [junit] 09/12/11 02:11:20 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_20091211021039651_0002/attempt_20091211021039651_0002_m_000000_0/output/file.out
in any of the configured local directories
    [junit] 09/12/11 02:11:20 INFO mapred.JobInProgress: Task 'attempt_20091211021039651_0002_m_000000_0'
has completed task_20091211021039651_0002_m_000000 successfully.
    [junit] 09/12/11 02:11:21 INFO mapReduceLayer.MapReduceLauncher: 25% complete
    [junit] 09/12/11 02:11:21 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_20091211021039651_0002/attempt_20091211021039651_0002_m_000001_0/output/file.out
in any of the configured local directories
    [junit] 09/12/11 02:11:21 INFO mapred.JobInProgress: Task 'attempt_20091211021039651_0002_m_000001_0'
has completed task_20091211021039651_0002_m_000001 successfully.
    [junit] 09/12/11 02:11:21 INFO mapred.JobTracker: Adding task 'attempt_20091211021039651_0002_m_000002_0'
to tip task_20091211021039651_0002_m_000002, for tracker 'tracker_host3.foo.com:localhost/127.0.0.1:47808'
    [junit] 09/12/11 02:11:21 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20091211021039651_0002_m_000002_0
task's state:UNASSIGNED
    [junit] 09/12/11 02:11:21 INFO mapred.TaskTracker: Trying to launch : attempt_20091211021039651_0002_m_000002_0
    [junit] 09/12/11 02:11:21 INFO mapred.TaskTracker: In TaskLauncher, current free slots
: 2 and trying to launch attempt_20091211021039651_0002_m_000002_0
    [junit] 09/12/11 02:11:21 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20091211021039651_0002_m_-354799983
    [junit] 09/12/11 02:11:21 INFO mapred.JvmManager: JVM Runner jvm_20091211021039651_0002_m_-354799983
spawned.
    [junit] 09/12/11 02:11:21 INFO mapReduceLayer.MapReduceLauncher: 50% complete
    [junit] 09/12/11 02:11:21 INFO mapred.TaskTracker: JVM with ID: jvm_20091211021039651_0002_m_-354799983
given task: attempt_20091211021039651_0002_m_000002_0
    [junit] 09/12/11 02:11:22 INFO mapred.TaskTracker: attempt_20091211021039651_0002_m_000002_0
0.0% 
    [junit] 09/12/11 02:11:22 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/tmp/temp-2004098019/tmp-1774748346/_temporary	dst=null	perm=null
    [junit] 09/12/11 02:11:22 INFO mapred.TaskTracker: attempt_20091211021039651_0002_m_000002_0
0.0% cleanup
    [junit] 09/12/11 02:11:22 INFO mapred.TaskTracker: Task attempt_20091211021039651_0002_m_000002_0
is done.
    [junit] 09/12/11 02:11:22 INFO mapred.TaskTracker: reported output size for attempt_20091211021039651_0002_m_000002_0
 was 0
    [junit] 09/12/11 02:11:22 INFO mapred.TaskTracker: addFreeSlot : current free slots :
2
    [junit] 09/12/11 02:11:22 INFO mapred.JvmManager: JVM : jvm_20091211021039651_0002_m_-354799983
exited. Number of tasks it ran: 1
    [junit] 09/12/11 02:11:24 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException:
Could not find taskTracker/jobcache/job_20091211021039651_0002/attempt_20091211021039651_0002_m_000002_0/output/file.out
in any of the configured local directories
    [junit] 09/12/11 02:11:24 INFO mapred.JobInProgress: Task 'attempt_20091211021039651_0002_m_000002_0'
has completed task_20091211021039651_0002_m_000002 successfully.
    [junit] 09/12/11 02:11:24 INFO mapred.JobInProgress: Job job_20091211021039651_0002 has
completed successfully.
    [junit] 09/12/11 02:11:24 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp-2004098019/tmp-1774748346/_logs/history/localhost_1260497439674_job_20091211021039651_0002_hudson_Job2400416277762928924.jar.
blk_-5817680193932094341_1019
    [junit] 09/12/11 02:11:24 INFO datanode.DataNode: Receiving block blk_-5817680193932094341_1019
src: /127.0.0.1:53529 dest: /127.0.0.1:50810
    [junit] 09/12/11 02:11:24 INFO datanode.DataNode: Receiving block blk_-5817680193932094341_1019
src: /127.0.0.1:43940 dest: /127.0.0.1:53521
    [junit] 09/12/11 02:11:24 INFO datanode.DataNode: Receiving block blk_-5817680193932094341_1019
src: /127.0.0.1:39838 dest: /127.0.0.1:42756
    [junit] 09/12/11 02:11:24 INFO DataNode.clienttrace: src: /127.0.0.1:39838, dest: /127.0.0.1:42756,
bytes: 6117, op: HDFS_WRITE, cliID: DFSClient_-1703283091, srvID: DS-1303939329-127.0.1.1-42756-1260497439078,
blockid: blk_-5817680193932094341_1019
    [junit] 09/12/11 02:11:24 INFO datanode.DataNode: PacketResponder 0 for block blk_-5817680193932094341_1019
terminating
    [junit] 09/12/11 02:11:24 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:42756 is added to blk_-5817680193932094341_1019 size 6117
    [junit] 09/12/11 02:11:24 INFO DataNode.clienttrace: src: /127.0.0.1:43940, dest: /127.0.0.1:53521,
bytes: 6117, op: HDFS_WRITE, cliID: DFSClient_-1703283091, srvID: DS-1004217825-127.0.1.1-53521-1260497439567,
blockid: blk_-5817680193932094341_1019
    [junit] 09/12/11 02:11:24 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:53521 is added to blk_-5817680193932094341_1019 size 6117
    [junit] 09/12/11 02:11:24 INFO datanode.DataNode: PacketResponder 1 for block blk_-5817680193932094341_1019
terminating
    [junit] 09/12/11 02:11:24 INFO DataNode.clienttrace: src: /127.0.0.1:53529, dest: /127.0.0.1:50810,
bytes: 6117, op: HDFS_WRITE, cliID: DFSClient_-1703283091, srvID: DS-1383757220-127.0.1.1-50810-1260497438128,
blockid: blk_-5817680193932094341_1019
    [junit] 09/12/11 02:11:24 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap
updated: 127.0.0.1:50810 is added to blk_-5817680193932094341_1019 size 6117
    [junit] 09/12/11 02:11:24 INFO datanode.DataNode: PacketResponder 2 for block blk_-5817680193932094341_1019
terminating
    [junit] 09/12/11 02:11:24 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp-2004098019/tmp-1774748346/_logs/history/localhost_1260497439674_job_20091211021039651_0002_hudson_Job2400416277762928924.jar
is closed by DFSClient_-1703283091
    [junit] 09/12/11 02:11:24 INFO mapred.JobTracker: Removed completed task 'attempt_20091211021039651_0002_m_000001_0'
from 'tracker_host3.foo.com:localhost/127.0.0.1:47808'
    [junit] 09/12/11 02:11:24 INFO mapred.JobTracker: Removed completed task 'attempt_20091211021039651_0002_m_000002_0'
from 'tracker_host3.foo.com:localhost/127.0.0.1:47808'
    [junit] 09/12/11 02:11:24 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-6885823568878148974
is added to invalidSet of 127.0.0.1:50810
    [junit] 09/12/11 02:11:24 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-6885823568878148974
is added to invalidSet of 127.0.0.1:43636
    [junit] 09/12/11 02:11:24 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-6885823568878148974
is added to invalidSet of 127.0.0.1:42756
    [junit] 09/12/11 02:11:24 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-7484173445110389036
is added to invalidSet of 127.0.0.1:43636
    [junit] 09/12/11 02:11:24 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-7484173445110389036
is added to invalidSet of 127.0.0.1:53521
    [junit] 09/12/11 02:11:24 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-7484173445110389036
is added to invalidSet of 127.0.0.1:50810
    [junit] 09/12/11 02:11:24 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_5770296480651727355
is added to invalidSet of 127.0.0.1:43636
    [junit] 09/12/11 02:11:24 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_5770296480651727355
is added to invalidSet of 127.0.0.1:50810
    [junit] 09/12/11 02:11:24 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_5770296480651727355
is added to invalidSet of 127.0.0.1:53521
    [junit] 09/12/11 02:11:24 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20091211021039651_0002
    [junit] 09/12/11 02:11:24 INFO mapred.TaskRunner: attempt_20091211021039651_0002_m_000002_0
done; removing files.
    [junit] 09/12/11 02:11:24 INFO mapred.IndexCache: Map ID attempt_20091211021039651_0002_m_000002_0
not found in cache
    [junit] 09/12/11 02:11:24 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=delete
src=/tmp/hadoop-hudson/mapred/system/job_20091211021039651_0002	dst=null	perm=null
    [junit] 09/12/11 02:11:24 INFO mapred.TaskRunner: attempt_20091211021039651_0002_m_000001_0
done; removing files.
    [junit] 09/12/11 02:11:24 INFO mapred.IndexCache: Map ID attempt_20091211021039651_0002_m_000001_0
not found in cache
    [junit] 09/12/11 02:11:24 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:53521 to delete
 blk_5770296480651727355_1015 blk_-7484173445110389036_1014
    [junit] 09/12/11 02:11:24 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:42756 to delete
 blk_-6885823568878148974_1013
    [junit] 09/12/11 02:11:26 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20091211021039651_0002
    [junit] 09/12/11 02:11:26 WARN mapred.TaskTracker: Unknown job job_20091211021039651_0002
being deleted.
    [junit] 09/12/11 02:11:26 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20091211021039651_0002
    [junit] 09/12/11 02:11:26 WARN mapred.TaskTracker: Unknown job job_20091211021039651_0002
being deleted.
    [junit] 09/12/11 02:11:26 INFO mapReduceLayer.MapReduceLauncher: 100% complete
    [junit] 09/12/11 02:11:26 INFO mapReduceLayer.MapReduceLauncher: Successfully stored result
in: "hdfs://localhost:49689/tmp/temp-2004098019/tmp-1774748346"
    [junit] 09/12/11 02:11:26 INFO mapReduceLayer.MapReduceLauncher: Records written : 2
    [junit] 09/12/11 02:11:26 INFO mapReduceLayer.MapReduceLauncher: Bytes written : 78
    [junit] 09/12/11 02:11:26 INFO mapReduceLayer.MapReduceLauncher: Success!
    [junit] 09/12/11 02:11:26 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=listStatus
src=/tmp/temp-2004098019/tmp-1774748346	dst=null	perm=null
    [junit] 09/12/11 02:11:26 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/temp-2004098019/tmp-1774748346/part-00000	dst=null	perm=null
    [junit] 09/12/11 02:11:26 INFO DataNode.clienttrace: src: /127.0.0.1:50810, dest: /127.0.0.1:53532,
bytes: 43, op: HDFS_READ, cliID: DFSClient_-1703283091, srvID: DS-1383757220-127.0.1.1-50810-1260497438128,
blockid: blk_-453335940435961308_1018
    [junit] 09/12/11 02:11:26 INFO FSNamesystem.audit: ugi=hudson,hudson	ip=/127.0.0.1	cmd=open
src=/tmp/temp-2004098019/tmp-1774748346/part-00001	dst=null	perm=null
    [junit] 09/12/11 02:11:26 INFO DataNode.clienttrace: src: /127.0.0.1:53521, dest: /127.0.0.1:43943,
bytes: 43, op: HDFS_READ, cliID: DFSClient_-1703283091, srvID: DS-1004217825-127.0.1.1-53521-1260497439567,
blockid: blk_6367061818940566987_1019
    [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 44.321 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 36 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