Apache Hudson Server
Wed, 18 Nov 2009 18:35:45 -0800
See <http://hudson.zones.apache.org/hudson/job/Pig-trunk/623/changes>
Changes:
[gates] PIG-1077: Support record(row)-based file split in Zebra's
TableInputFormat.
[olga] PIG-1053: Consider moving to Hadoop for local mode (ankit.modi via olgan)
[daijy] PIG-1060: MultiQuery optimization throws error for multi-level splits
------------------------------------------
[...truncated 206582 lines...]
[junit] 09/11/19 02:33:58 INFO datanode.DataNode: Receiving block
blk_-3388285408264901103_1014 src: /127.0.0.1:33242 dest: /127.0.0.1:39535
[junit] 09/11/19 02:33:58 INFO datanode.DataNode: Receiving block
blk_-3388285408264901103_1014 src: /127.0.0.1:32971 dest: /127.0.0.1:51645
[junit] 09/11/19 02:33:58 INFO DataNode.clienttrace: src: /127.0.0.1:32971,
dest: /127.0.0.1:51645, bytes: 1589, op: HDFS_WRITE, cliID:
DFSClient_395457836, srvID: DS-2142175154-127.0.1.1-51645-1258598005082,
blockid: blk_-3388285408264901103_1014
[junit] 09/11/19 02:33:58 INFO datanode.DataNode: PacketResponder 0 for
block blk_-3388285408264901103_1014 terminating
[junit] 09/11/19 02:33:58 INFO DataNode.clienttrace: src: /127.0.0.1:33242,
dest: /127.0.0.1:39535, bytes: 1589, op: HDFS_WRITE, cliID:
DFSClient_395457836, srvID: DS-1984890133-127.0.1.1-39535-1258598006504,
blockid: blk_-3388285408264901103_1014
[junit] 09/11/19 02:33:58 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:51645 is added to
blk_-3388285408264901103_1014 size 1589
[junit] 09/11/19 02:33:58 INFO datanode.DataNode: PacketResponder 1 for
block blk_-3388285408264901103_1014 terminating
[junit] 09/11/19 02:33:58 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:39535 is added to
blk_-3388285408264901103_1014 size 1589
[junit] 09/11/19 02:33:58 INFO DataNode.clienttrace: src: /127.0.0.1:33748,
dest: /127.0.0.1:34458, bytes: 1589, op: HDFS_WRITE, cliID:
DFSClient_395457836, srvID: DS-1649789720-127.0.1.1-34458-1258598005579,
blockid: blk_-3388285408264901103_1014
[junit] 09/11/19 02:33:58 INFO datanode.DataNode: PacketResponder 2 for
block blk_-3388285408264901103_1014 terminating
[junit] 09/11/19 02:33:58 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:34458 is added to
blk_-3388285408264901103_1014 size 1589
[junit] 09/11/19 02:33:58 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/tmp/hadoop-hudson/mapred/system/job_20091119023326575_0002/job.split is closed
by DFSClient_395457836
[junit] 09/11/19 02:33:58 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=create
src=/tmp/hadoop-hudson/mapred/system/job_20091119023326575_0002/job.xml
dst=null perm=hudson:supergroup:rw-r--r--
[junit] 09/11/19 02:33:58 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=setPermission
src=/tmp/hadoop-hudson/mapred/system/job_20091119023326575_0002/job.xml
dst=null perm=hudson:supergroup:rw-r--r--
[junit] 09/11/19 02:33:58 INFO hdfs.StateChange: BLOCK*
NameSystem.allocateBlock:
/tmp/hadoop-hudson/mapred/system/job_20091119023326575_0002/job.xml.
blk_-6813384894621786135_1015
[junit] 09/11/19 02:33:58 INFO datanode.DataNode: Receiving block
blk_-6813384894621786135_1015 src: /127.0.0.1:32972 dest: /127.0.0.1:51645
[junit] 09/11/19 02:33:58 INFO datanode.DataNode: Receiving block
blk_-6813384894621786135_1015 src: /127.0.0.1:33752 dest: /127.0.0.1:34458
[junit] 09/11/19 02:33:58 INFO datanode.DataNode: Receiving block
blk_-6813384894621786135_1015 src: /127.0.0.1:33246 dest: /127.0.0.1:39535
[junit] 09/11/19 02:33:58 INFO DataNode.clienttrace: src: /127.0.0.1:33246,
dest: /127.0.0.1:39535, bytes: 48639, op: HDFS_WRITE, cliID:
DFSClient_395457836, srvID: DS-1984890133-127.0.1.1-39535-1258598006504,
blockid: blk_-6813384894621786135_1015
[junit] 09/11/19 02:33:58 INFO datanode.DataNode: PacketResponder 0 for
block blk_-6813384894621786135_1015 terminating
[junit] 09/11/19 02:33:58 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:39535 is added to
blk_-6813384894621786135_1015 size 48639
[junit] 09/11/19 02:33:58 INFO DataNode.clienttrace: src: /127.0.0.1:33752,
dest: /127.0.0.1:34458, bytes: 48639, op: HDFS_WRITE, cliID:
DFSClient_395457836, srvID: DS-1649789720-127.0.1.1-34458-1258598005579,
blockid: blk_-6813384894621786135_1015
[junit] 09/11/19 02:33:58 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:34458 is added to
blk_-6813384894621786135_1015 size 48639
[junit] 09/11/19 02:33:58 INFO datanode.DataNode: PacketResponder 1 for
block blk_-6813384894621786135_1015 terminating
[junit] 09/11/19 02:33:58 INFO DataNode.clienttrace: src: /127.0.0.1:32972,
dest: /127.0.0.1:51645, bytes: 48639, op: HDFS_WRITE, cliID:
DFSClient_395457836, srvID: DS-2142175154-127.0.1.1-51645-1258598005082,
blockid: blk_-6813384894621786135_1015
[junit] 09/11/19 02:33:58 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:51645 is added to
blk_-6813384894621786135_1015 size 48639
[junit] 09/11/19 02:33:58 INFO datanode.DataNode: PacketResponder 2 for
block blk_-6813384894621786135_1015 terminating
[junit] 09/11/19 02:33:58 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/tmp/hadoop-hudson/mapred/system/job_20091119023326575_0002/job.xml is closed
by DFSClient_395457836
[junit] 09/11/19 02:33:58 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091119023326575_0002/job.xml
dst=null perm=null
[junit] 09/11/19 02:33:58 INFO DataNode.clienttrace: src: /127.0.0.1:34458,
dest: /127.0.0.1:33754, bytes: 49019, op: HDFS_READ, cliID:
DFSClient_395457836, srvID: DS-1649789720-127.0.1.1-34458-1258598005579,
blockid: blk_-6813384894621786135_1015
[junit] 09/11/19 02:33:58 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091119023326575_0002/job.jar
dst=null perm=null
[junit] 09/11/19 02:33:58 INFO DataNode.clienttrace: src: /127.0.0.1:51645,
dest: /127.0.0.1:32976, bytes: 2625792, op: HDFS_READ, cliID:
DFSClient_395457836, srvID: DS-2142175154-127.0.1.1-51645-1258598005082,
blockid: blk_-7392176902308286198_1013
[junit] 09/11/19 02:33:58 INFO mapred.JobTracker: Initializing
job_20091119023326575_0002
[junit] 09/11/19 02:33:58 INFO mapred.JobInProgress: Initializing
job_20091119023326575_0002
[junit] 09/11/19 02:33:58 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=create
src=/tmp/temp-883675194/tmp-436547546/_logs/history/localhost_1258598006604_job_20091119023326575_0002_hudson_Job7967489867006079740.jar
dst=null perm=hudson:supergroup:rw-r--r--
[junit] 09/11/19 02:33:58 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=create
src=/tmp/temp-883675194/tmp-436547546/_logs/history/localhost_1258598006604_job_20091119023326575_0002_conf.xml
dst=null perm=hudson:supergroup:rw-r--r--
[junit] 09/11/19 02:33:58 INFO hdfs.StateChange: BLOCK*
NameSystem.allocateBlock:
/tmp/temp-883675194/tmp-436547546/_logs/history/localhost_1258598006604_job_20091119023326575_0002_conf.xml.
blk_-8117650403175354073_1017
[junit] 09/11/19 02:33:58 INFO datanode.DataNode: Receiving block
blk_-8117650403175354073_1017 src: /127.0.0.1:33756 dest: /127.0.0.1:34458
[junit] 09/11/19 02:33:58 INFO datanode.DataNode: Receiving block
blk_-8117650403175354073_1017 src: /127.0.0.1:36397 dest: /127.0.0.1:58915
[junit] 09/11/19 02:33:58 INFO datanode.DataNode: Receiving block
blk_-8117650403175354073_1017 src: /127.0.0.1:32979 dest: /127.0.0.1:51645
[junit] 09/11/19 02:33:58 INFO DataNode.clienttrace: src: /127.0.0.1:32979,
dest: /127.0.0.1:51645, bytes: 48669, op: HDFS_WRITE, cliID:
DFSClient_395457836, srvID: DS-2142175154-127.0.1.1-51645-1258598005082,
blockid: blk_-8117650403175354073_1017
[junit] 09/11/19 02:33:58 INFO datanode.DataNode: PacketResponder 0 for
block blk_-8117650403175354073_1017 terminating
[junit] 09/11/19 02:33:58 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:51645 is added to
blk_-8117650403175354073_1017 size 48669
[junit] 09/11/19 02:33:58 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:58915 is added to
blk_-8117650403175354073_1017 size 48669
[junit] 09/11/19 02:33:58 INFO DataNode.clienttrace: src: /127.0.0.1:36397,
dest: /127.0.0.1:58915, bytes: 48669, op: HDFS_WRITE, cliID:
DFSClient_395457836, srvID: DS-1377221848-127.0.1.1-58915-1258598006052,
blockid: blk_-8117650403175354073_1017
[junit] 09/11/19 02:33:58 INFO datanode.DataNode: PacketResponder 1 for
block blk_-8117650403175354073_1017 terminating
[junit] 09/11/19 02:33:58 INFO DataNode.clienttrace: src: /127.0.0.1:33756,
dest: /127.0.0.1:34458, bytes: 48669, op: HDFS_WRITE, cliID:
DFSClient_395457836, srvID: DS-1649789720-127.0.1.1-34458-1258598005579,
blockid: blk_-8117650403175354073_1017
[junit] 09/11/19 02:33:58 INFO datanode.DataNode: PacketResponder 2 for
block blk_-8117650403175354073_1017 terminating
[junit] 09/11/19 02:33:58 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:34458 is added to
blk_-8117650403175354073_1017 size 48669
[junit] 09/11/19 02:33:58 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/tmp/temp-883675194/tmp-436547546/_logs/history/localhost_1258598006604_job_20091119023326575_0002_conf.xml
is closed by DFSClient_395457836
[junit] 09/11/19 02:33:58 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091119023326575_0002/job.split
dst=null perm=null
[junit] 09/11/19 02:33:58 INFO DataNode.clienttrace: src: /127.0.0.1:39535,
dest: /127.0.0.1:33252, bytes: 1605, op: HDFS_READ, cliID: DFSClient_395457836,
srvID: DS-1984890133-127.0.1.1-39535-1258598006504, blockid:
blk_-3388285408264901103_1014
[junit] 09/11/19 02:33:58 INFO mapred.JobInProgress: Input size for job
job_20091119023326575_0002 = 12. Number of splits = 2
[junit] 09/11/19 02:33:58 INFO mapred.JobInProgress:
tip:task_20091119023326575_0002_m_000000 has split on
node:/default-rack/h7.grid.sp2.yahoo.net
[junit] 09/11/19 02:33:58 INFO mapred.JobInProgress:
tip:task_20091119023326575_0002_m_000001 has split on
node:/default-rack/h7.grid.sp2.yahoo.net
[junit] 09/11/19 02:33:58 INFO mapred.JobTracker: Adding task
'attempt_20091119023326575_0002_m_000003_0' to tip
task_20091119023326575_0002_m_000003, for tracker
'tracker_host2.foo.com:localhost/127.0.0.1:59414'
[junit] 09/11/19 02:33:58 INFO mapred.TaskTracker: LaunchTaskAction
(registerTask): attempt_20091119023326575_0002_m_000003_0 task's
state:UNASSIGNED
[junit] 09/11/19 02:33:58 INFO mapred.TaskTracker: Trying to launch :
attempt_20091119023326575_0002_m_000003_0
[junit] 09/11/19 02:33:58 INFO mapred.TaskTracker: In TaskLauncher, current
free slots : 2 and trying to launch attempt_20091119023326575_0002_m_000003_0
[junit] 09/11/19 02:33:58 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091119023326575_0002/job.xml
dst=null perm=null
[junit] 09/11/19 02:33:58 INFO DataNode.clienttrace: src: /127.0.0.1:39535,
dest: /127.0.0.1:33253, bytes: 49019, op: HDFS_READ, cliID:
DFSClient_395457836, srvID: DS-1984890133-127.0.1.1-39535-1258598006504,
blockid: blk_-6813384894621786135_1015
[junit] 09/11/19 02:33:58 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091119023326575_0002/job.jar
dst=null perm=null
[junit] 09/11/19 02:33:58 INFO DataNode.clienttrace: src: /127.0.0.1:58915,
dest: /127.0.0.1:36401, bytes: 2625792, op: HDFS_READ, cliID:
DFSClient_395457836, srvID: DS-1377221848-127.0.1.1-58915-1258598006052,
blockid: blk_-7392176902308286198_1013
[junit] 09/11/19 02:33:59 INFO mapReduceLayer.MapReduceLauncher: Submitting
job: job_20091119023326575_0002 to execution engine.
[junit] 09/11/19 02:33:59 INFO mapReduceLayer.MapReduceLauncher: More
information at:
http://localhost:54296/jobdetails.jsp?jobid=job_20091119023326575_0002
[junit] 09/11/19 02:33:59 INFO mapReduceLayer.MapReduceLauncher: To kill
this job, use: kill job_20091119023326575_0002
[junit] 09/11/19 02:33:59 INFO mapred.JvmManager: In JvmRunner constructed
JVM ID: jvm_20091119023326575_0002_m_1153287594
[junit] 09/11/19 02:33:59 INFO mapred.JvmManager: JVM Runner
jvm_20091119023326575_0002_m_1153287594 spawned.
[junit] 09/11/19 02:33:59 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:58915
to delete blk_-7392176902308286198_1013
[junit] 09/11/19 02:33:59 INFO mapReduceLayer.MapReduceLauncher: 0% complete
[junit] 09/11/19 02:33:59 INFO mapred.TaskTracker: JVM with ID:
jvm_20091119023326575_0002_m_1153287594 given task:
attempt_20091119023326575_0002_m_000003_0
[junit] 09/11/19 02:34:00 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=mkdirs
src=/tmp/temp-883675194/tmp-436547546/_temporary dst=null
perm=hudson:supergroup:rwxr-xr-x
[junit] 09/11/19 02:34:00 INFO mapred.TaskTracker:
attempt_20091119023326575_0002_m_000003_0 0.0% setup
[junit] 09/11/19 02:34:00 INFO mapred.TaskTracker: Task
attempt_20091119023326575_0002_m_000003_0 is done.
[junit] 09/11/19 02:34:00 INFO mapred.TaskTracker: reported output size for
attempt_20091119023326575_0002_m_000003_0 was 0
[junit] 09/11/19 02:34:00 INFO mapred.TaskTracker: addFreeSlot : current
free slots : 2
[junit] 09/11/19 02:34:00 INFO mapred.JvmManager: JVM :
jvm_20091119023326575_0002_m_1153287594 exited. Number of tasks it ran: 1
[junit] 09/11/19 02:34:01 INFO mapred.TaskTracker:
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find
taskTracker/jobcache/job_20091119023326575_0002/attempt_20091119023326575_0002_m_000003_0/output/file.out
in any of the configured local directories
[junit] 09/11/19 02:34:01 INFO mapred.JobInProgress: Task
'attempt_20091119023326575_0002_m_000003_0' has completed
task_20091119023326575_0002_m_000003 successfully.
[junit] 09/11/19 02:34:01 INFO mapred.JobTracker: Adding task
'attempt_20091119023326575_0002_m_000000_0' to tip
task_20091119023326575_0002_m_000000, for tracker
'tracker_host2.foo.com:localhost/127.0.0.1:59414'
[junit] 09/11/19 02:34:01 INFO mapred.JobInProgress: Choosing rack-local
task task_20091119023326575_0002_m_000000
[junit] 09/11/19 02:34:01 INFO mapred.TaskTracker: LaunchTaskAction
(registerTask): attempt_20091119023326575_0002_m_000000_0 task's
state:UNASSIGNED
[junit] 09/11/19 02:34:01 INFO mapred.TaskTracker: Trying to launch :
attempt_20091119023326575_0002_m_000000_0
[junit] 09/11/19 02:34:01 INFO mapred.TaskTracker: In TaskLauncher, current
free slots : 2 and trying to launch attempt_20091119023326575_0002_m_000000_0
[junit] 09/11/19 02:34:01 INFO mapred.TaskTracker: Received KillTaskAction
for task: attempt_20091119023326575_0002_m_000003_0
[junit] 09/11/19 02:34:01 INFO mapred.TaskTracker: About to purge task:
attempt_20091119023326575_0002_m_000003_0
[junit] 09/11/19 02:34:01 INFO mapred.TaskRunner:
attempt_20091119023326575_0002_m_000003_0 done; removing files.
[junit] 09/11/19 02:34:01 INFO mapred.IndexCache: Map ID
attempt_20091119023326575_0002_m_000003_0 not found in cache
[junit] 09/11/19 02:34:01 INFO mapred.JvmManager: In JvmRunner constructed
JVM ID: jvm_20091119023326575_0002_m_1758403489
[junit] 09/11/19 02:34:01 INFO mapred.JvmManager: JVM Runner
jvm_20091119023326575_0002_m_1758403489 spawned.
[junit] 09/11/19 02:34:02 INFO datanode.DataNode: Deleting block
blk_-7392176902308286198_1013 file
build/test/data/dfs/data/data5/current/blk_-7392176902308286198
[junit] 09/11/19 02:34:02 INFO mapred.JobTracker: Adding task
'attempt_20091119023326575_0002_m_000001_0' to tip
task_20091119023326575_0002_m_000001, for tracker
'tracker_host3.foo.com:localhost/127.0.0.1:33169'
[junit] 09/11/19 02:34:02 INFO mapred.JobInProgress: Choosing rack-local
task task_20091119023326575_0002_m_000001
[junit] 09/11/19 02:34:02 INFO mapred.TaskTracker: LaunchTaskAction
(registerTask): attempt_20091119023326575_0002_m_000001_0 task's
state:UNASSIGNED
[junit] 09/11/19 02:34:02 INFO mapred.TaskTracker: Trying to launch :
attempt_20091119023326575_0002_m_000001_0
[junit] 09/11/19 02:34:02 INFO mapred.TaskTracker: In TaskLauncher, current
free slots : 2 and trying to launch attempt_20091119023326575_0002_m_000001_0
[junit] 09/11/19 02:34:02 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091119023326575_0002/job.xml
dst=null perm=null
[junit] 09/11/19 02:34:02 INFO DataNode.clienttrace: src: /127.0.0.1:51645,
dest: /127.0.0.1:32988, bytes: 49019, op: HDFS_READ, cliID:
DFSClient_395457836, srvID: DS-2142175154-127.0.1.1-51645-1258598005082,
blockid: blk_-6813384894621786135_1015
[junit] 09/11/19 02:34:02 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20091119023326575_0002/job.jar
dst=null perm=null
[junit] 09/11/19 02:34:02 INFO DataNode.clienttrace: src: /127.0.0.1:34458,
dest: /127.0.0.1:33768, bytes: 2625792, op: HDFS_READ, cliID:
DFSClient_395457836, srvID: DS-1649789720-127.0.1.1-34458-1258598005579,
blockid: blk_-7392176902308286198_1013
[junit] 09/11/19 02:34:02 INFO mapred.TaskTracker: JVM with ID:
jvm_20091119023326575_0002_m_1758403489 given task:
attempt_20091119023326575_0002_m_000000_0
[junit] 09/11/19 02:34:02 INFO mapred.JvmManager: In JvmRunner constructed
JVM ID: jvm_20091119023326575_0002_m_-1509526529
[junit] 09/11/19 02:34:02 INFO mapred.JvmManager: JVM Runner
jvm_20091119023326575_0002_m_-1509526529 spawned.
[junit] 09/11/19 02:34:02 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open src=/user/hudson/input2.txt dst=null
perm=null
[junit] 09/11/19 02:34:02 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=create
src=/tmp/temp-883675194/tmp-436547546/_temporary/_attempt_20091119023326575_0002_m_000000_0/part-00000
dst=null perm=hudson:supergroup:rw-r--r--
[junit] 09/11/19 02:34:03 INFO DataNode.clienttrace: src: /127.0.0.1:51645,
dest: /127.0.0.1:32995, bytes: 10, op: HDFS_READ, cliID:
DFSClient_attempt_20091119023326575_0002_m_000000_0, srvID:
DS-2142175154-127.0.1.1-51645-1258598005082, blockid:
blk_-4036976361044975465_1012
[junit] 09/11/19 02:34:03 INFO hdfs.StateChange: BLOCK*
NameSystem.allocateBlock:
/tmp/temp-883675194/tmp-436547546/_temporary/_attempt_20091119023326575_0002_m_000000_0/part-00000.
blk_1783466514706258377_1018
[junit] 09/11/19 02:34:03 INFO datanode.DataNode: Receiving block
blk_1783466514706258377_1018 src: /127.0.0.1:33268 dest: /127.0.0.1:39535
[junit] 09/11/19 02:34:03 INFO datanode.DataNode: Receiving block
blk_1783466514706258377_1018 src: /127.0.0.1:36416 dest: /127.0.0.1:58915
[junit] 09/11/19 02:34:03 INFO datanode.DataNode: Receiving block
blk_1783466514706258377_1018 src: /127.0.0.1:32998 dest: /127.0.0.1:51645
[junit] 09/11/19 02:34:03 INFO DataNode.clienttrace: src: /127.0.0.1:32998,
dest: /127.0.0.1:51645, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20091119023326575_0002_m_000000_0, srvID:
DS-2142175154-127.0.1.1-51645-1258598005082, blockid:
blk_1783466514706258377_1018
[junit] 09/11/19 02:34:03 INFO datanode.DataNode: PacketResponder 0 for
block blk_1783466514706258377_1018 terminating
[junit] 09/11/19 02:34:03 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:51645 is added to
blk_1783466514706258377_1018 size 39
[junit] 09/11/19 02:34:03 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:58915 is added to
blk_1783466514706258377_1018 size 39
[junit] 09/11/19 02:34:03 INFO DataNode.clienttrace: src: /127.0.0.1:36416,
dest: /127.0.0.1:58915, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20091119023326575_0002_m_000000_0, srvID:
DS-1377221848-127.0.1.1-58915-1258598006052, blockid:
blk_1783466514706258377_1018
[junit] 09/11/19 02:34:03 INFO DataNode.clienttrace: src: /127.0.0.1:33268,
dest: /127.0.0.1:39535, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20091119023326575_0002_m_000000_0, srvID:
DS-1984890133-127.0.1.1-39535-1258598006504, blockid:
blk_1783466514706258377_1018
[junit] 09/11/19 02:34:03 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:39535 is added to
blk_1783466514706258377_1018 size 39
[junit] 09/11/19 02:34:03 INFO datanode.DataNode: PacketResponder 1 for
block blk_1783466514706258377_1018 terminating
[junit] 09/11/19 02:34:03 INFO datanode.DataNode: PacketResponder 2 for
block blk_1783466514706258377_1018 terminating
[junit] 09/11/19 02:34:03 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/tmp/temp-883675194/tmp-436547546/_temporary/_attempt_20091119023326575_0002_m_000000_0/part-00000
is closed by DFSClient_attempt_20091119023326575_0002_m_000000_0
[junit] 09/11/19 02:34:03 INFO mapred.TaskTracker: Task
attempt_20091119023326575_0002_m_000000_0 is in commit-pending, task
state:COMMIT_PENDING
[junit] 09/11/19 02:34:03 INFO mapred.TaskTracker:
attempt_20091119023326575_0002_m_000000_0 0.0%
[junit] 09/11/19 02:34:03 INFO mapred.TaskTracker: JVM with ID:
jvm_20091119023326575_0002_m_-1509526529 given task:
attempt_20091119023326575_0002_m_000001_0
[junit] 09/11/19 02:34:03 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open src=/user/hudson/input1.txt dst=null
perm=null
[junit] 09/11/19 02:34:03 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=create
src=/tmp/temp-883675194/tmp-436547546/_temporary/_attempt_20091119023326575_0002_m_000001_0/part-00001
dst=null perm=hudson:supergroup:rw-r--r--
[junit] 09/11/19 02:34:03 INFO DataNode.clienttrace: src: /127.0.0.1:39535,
dest: /127.0.0.1:33276, bytes: 10, op: HDFS_READ, cliID:
DFSClient_attempt_20091119023326575_0002_m_000001_0, srvID:
DS-1984890133-127.0.1.1-39535-1258598006504, blockid:
blk_3688278329124920242_1011
[junit] 09/11/19 02:34:03 INFO hdfs.StateChange: BLOCK*
NameSystem.allocateBlock:
/tmp/temp-883675194/tmp-436547546/_temporary/_attempt_20091119023326575_0002_m_000001_0/part-00001.
blk_3881053833396778110_1019
[junit] 09/11/19 02:34:03 INFO datanode.DataNode: Receiving block
blk_3881053833396778110_1019 src: /127.0.0.1:33005 dest: /127.0.0.1:51645
[junit] 09/11/19 02:34:03 INFO datanode.DataNode: Receiving block
blk_3881053833396778110_1019 src: /127.0.0.1:36425 dest: /127.0.0.1:58915
[junit] 09/11/19 02:34:03 INFO datanode.DataNode: Receiving block
blk_3881053833396778110_1019 src: /127.0.0.1:33786 dest: /127.0.0.1:34458
[junit] 09/11/19 02:34:03 INFO DataNode.clienttrace: src: /127.0.0.1:33786,
dest: /127.0.0.1:34458, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20091119023326575_0002_m_000001_0, srvID:
DS-1649789720-127.0.1.1-34458-1258598005579, blockid:
blk_3881053833396778110_1019
[junit] 09/11/19 02:34:03 INFO datanode.DataNode: PacketResponder 0 for
block blk_3881053833396778110_1019 terminating
[junit] 09/11/19 02:34:03 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:34458 is added to
blk_3881053833396778110_1019 size 39
[junit] 09/11/19 02:34:03 INFO DataNode.clienttrace: src: /127.0.0.1:36425,
dest: /127.0.0.1:58915, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20091119023326575_0002_m_000001_0, srvID:
DS-1377221848-127.0.1.1-58915-1258598006052, blockid:
blk_3881053833396778110_1019
[junit] 09/11/19 02:34:03 INFO DataNode.clienttrace: src: /127.0.0.1:33005,
dest: /127.0.0.1:51645, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20091119023326575_0002_m_000001_0, srvID:
DS-2142175154-127.0.1.1-51645-1258598005082, blockid:
blk_3881053833396778110_1019
[junit] 09/11/19 02:34:03 INFO datanode.DataNode: PacketResponder 1 for
block blk_3881053833396778110_1019 terminating
[junit] 09/11/19 02:34:03 INFO datanode.DataNode: PacketResponder 2 for
block blk_3881053833396778110_1019 terminating
[junit] 09/11/19 02:34:03 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:58915 is added to
blk_3881053833396778110_1019 size 39
[junit] 09/11/19 02:34:03 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:51645 is added to
blk_3881053833396778110_1019 size 39
[junit] 09/11/19 02:34:03 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/tmp/temp-883675194/tmp-436547546/_temporary/_attempt_20091119023326575_0002_m_000001_0/part-00001
is closed by DFSClient_attempt_20091119023326575_0002_m_000001_0
[junit] 09/11/19 02:34:03 INFO mapred.TaskTracker: Task
attempt_20091119023326575_0002_m_000001_0 is in commit-pending, task
state:COMMIT_PENDING
[junit] 09/11/19 02:34:03 INFO mapred.TaskTracker:
attempt_20091119023326575_0002_m_000001_0 0.0%
[junit] 09/11/19 02:34:04 INFO mapred.TaskTracker: Received commit task
action for attempt_20091119023326575_0002_m_000000_0
[junit] 09/11/19 02:34:05 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=listStatus
src=/tmp/temp-883675194/tmp-436547546/_temporary/_attempt_20091119023326575_0002_m_000000_0
dst=null perm=null
[junit] 09/11/19 02:34:05 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=mkdirs src=/tmp/temp-883675194/tmp-436547546
dst=null perm=hudson:supergroup:rwxr-xr-x
[junit] 09/11/19 02:34:05 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=rename
src=/tmp/temp-883675194/tmp-436547546/_temporary/_attempt_20091119023326575_0002_m_000000_0/part-00000
dst=/tmp/temp-883675194/tmp-436547546/part-00000
perm=hudson:supergroup:rw-r--r--
[junit] 09/11/19 02:34:05 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete
src=/tmp/temp-883675194/tmp-436547546/_temporary/_attempt_20091119023326575_0002_m_000000_0
dst=null perm=null
[junit] 09/11/19 02:34:05 INFO mapred.TaskTracker:
attempt_20091119023326575_0002_m_000000_0 1.0%
[junit] 09/11/19 02:34:05 INFO mapred.TaskTracker: Task
attempt_20091119023326575_0002_m_000000_0 is done.
[junit] 09/11/19 02:34:05 INFO mapred.TaskTracker: reported output size for
attempt_20091119023326575_0002_m_000000_0 was 0
[junit] 09/11/19 02:34:05 INFO mapred.TaskTracker: addFreeSlot : current
free slots : 2
[junit] 09/11/19 02:34:05 INFO mapred.TaskTracker: Received commit task
action for attempt_20091119023326575_0002_m_000001_0
[junit] 09/11/19 02:34:05 INFO mapred.JvmManager: JVM :
jvm_20091119023326575_0002_m_1758403489 exited. Number of tasks it ran: 1
[junit] 09/11/19 02:34:05 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=listStatus
src=/tmp/temp-883675194/tmp-436547546/_temporary/_attempt_20091119023326575_0002_m_000001_0
dst=null perm=null
[junit] 09/11/19 02:34:05 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=mkdirs src=/tmp/temp-883675194/tmp-436547546
dst=null perm=hudson:supergroup:rwxr-xr-x
[junit] 09/11/19 02:34:05 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=rename
src=/tmp/temp-883675194/tmp-436547546/_temporary/_attempt_20091119023326575_0002_m_000001_0/part-00001
dst=/tmp/temp-883675194/tmp-436547546/part-00001
perm=hudson:supergroup:rw-r--r--
[junit] 09/11/19 02:34:05 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete
src=/tmp/temp-883675194/tmp-436547546/_temporary/_attempt_20091119023326575_0002_m_000001_0
dst=null perm=null
[junit] 09/11/19 02:34:05 INFO mapred.TaskTracker:
attempt_20091119023326575_0002_m_000001_0 1.0%
[junit] 09/11/19 02:34:05 INFO mapred.TaskTracker: Task
attempt_20091119023326575_0002_m_000001_0 is done.
[junit] 09/11/19 02:34:05 INFO mapred.TaskTracker: reported output size for
attempt_20091119023326575_0002_m_000001_0 was 0
[junit] 09/11/19 02:34:05 INFO mapred.TaskTracker: addFreeSlot : current
free slots : 2
[junit] 09/11/19 02:34:05 INFO mapred.JvmManager: JVM :
jvm_20091119023326575_0002_m_-1509526529 exited. Number of tasks it ran: 1
[junit] 09/11/19 02:34:07 INFO mapred.TaskTracker:
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find
taskTracker/jobcache/job_20091119023326575_0002/attempt_20091119023326575_0002_m_000000_0/output/file.out
in any of the configured local directories
[junit] 09/11/19 02:34:07 INFO mapred.JobInProgress: Task
'attempt_20091119023326575_0002_m_000000_0' has completed
task_20091119023326575_0002_m_000000 successfully.
[junit] 09/11/19 02:34:08 INFO mapReduceLayer.MapReduceLauncher: 25%
complete
[junit] 09/11/19 02:34:08 INFO mapred.TaskTracker:
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find
taskTracker/jobcache/job_20091119023326575_0002/attempt_20091119023326575_0002_m_000001_0/output/file.out
in any of the configured local directories
[junit] 09/11/19 02:34:08 INFO mapred.JobInProgress: Task
'attempt_20091119023326575_0002_m_000001_0' has completed
task_20091119023326575_0002_m_000001 successfully.
[junit] 09/11/19 02:34:08 INFO mapred.JobTracker: Adding task
'attempt_20091119023326575_0002_m_000002_0' to tip
task_20091119023326575_0002_m_000002, for tracker
'tracker_host3.foo.com:localhost/127.0.0.1:33169'
[junit] 09/11/19 02:34:08 INFO mapred.TaskTracker: LaunchTaskAction
(registerTask): attempt_20091119023326575_0002_m_000002_0 task's
state:UNASSIGNED
[junit] 09/11/19 02:34:08 INFO mapred.TaskTracker: Trying to launch :
attempt_20091119023326575_0002_m_000002_0
[junit] 09/11/19 02:34:08 INFO mapred.TaskTracker: In TaskLauncher, current
free slots : 2 and trying to launch attempt_20091119023326575_0002_m_000002_0
[junit] 09/11/19 02:34:08 INFO mapred.JvmManager: In JvmRunner constructed
JVM ID: jvm_20091119023326575_0002_m_-938885149
[junit] 09/11/19 02:34:08 INFO mapred.JvmManager: JVM Runner
jvm_20091119023326575_0002_m_-938885149 spawned.
[junit] 09/11/19 02:34:08 INFO mapReduceLayer.MapReduceLauncher: 50%
complete
[junit] 09/11/19 02:34:08 INFO mapred.TaskTracker: JVM with ID:
jvm_20091119023326575_0002_m_-938885149 given task:
attempt_20091119023326575_0002_m_000002_0
[junit] 09/11/19 02:34:09 INFO mapred.TaskTracker:
attempt_20091119023326575_0002_m_000002_0 0.0%
[junit] 09/11/19 02:34:09 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete
src=/tmp/temp-883675194/tmp-436547546/_temporary dst=null
perm=null
[junit] 09/11/19 02:34:09 INFO mapred.TaskTracker:
attempt_20091119023326575_0002_m_000002_0 0.0% cleanup
[junit] 09/11/19 02:34:09 INFO mapred.TaskTracker: Task
attempt_20091119023326575_0002_m_000002_0 is done.
[junit] 09/11/19 02:34:09 INFO mapred.TaskTracker: reported output size for
attempt_20091119023326575_0002_m_000002_0 was 0
[junit] 09/11/19 02:34:09 INFO mapred.TaskTracker: addFreeSlot : current
free slots : 2
[junit] 09/11/19 02:34:09 INFO mapred.JvmManager: JVM :
jvm_20091119023326575_0002_m_-938885149 exited. Number of tasks it ran: 1
[junit] 09/11/19 02:34:11 INFO mapred.TaskTracker:
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find
taskTracker/jobcache/job_20091119023326575_0002/attempt_20091119023326575_0002_m_000002_0/output/file.out
in any of the configured local directories
[junit] 09/11/19 02:34:11 INFO mapred.JobInProgress: Task
'attempt_20091119023326575_0002_m_000002_0' has completed
task_20091119023326575_0002_m_000002 successfully.
[junit] 09/11/19 02:34:11 INFO mapred.JobInProgress: Job
job_20091119023326575_0002 has completed successfully.
[junit] 09/11/19 02:34:11 INFO hdfs.StateChange: BLOCK*
NameSystem.allocateBlock:
/tmp/temp-883675194/tmp-436547546/_logs/history/localhost_1258598006604_job_20091119023326575_0002_hudson_Job7967489867006079740.jar.
blk_-4300934375526664791_1019
[junit] 09/11/19 02:34:11 INFO datanode.DataNode: Receiving block
blk_-4300934375526664791_1019 src: /127.0.0.1:33013 dest: /127.0.0.1:51645
[junit] 09/11/19 02:34:11 INFO datanode.DataNode: Receiving block
blk_-4300934375526664791_1019 src: /127.0.0.1:33793 dest: /127.0.0.1:34458
[junit] 09/11/19 02:34:11 INFO datanode.DataNode: Receiving block
blk_-4300934375526664791_1019 src: /127.0.0.1:36434 dest: /127.0.0.1:58915
[junit] 09/11/19 02:34:11 INFO DataNode.clienttrace: src: /127.0.0.1:36434,
dest: /127.0.0.1:58915, bytes: 6117, op: HDFS_WRITE, cliID:
DFSClient_395457836, srvID: DS-1377221848-127.0.1.1-58915-1258598006052,
blockid: blk_-4300934375526664791_1019
[junit] 09/11/19 02:34:11 INFO datanode.DataNode: PacketResponder 0 for
block blk_-4300934375526664791_1019 terminating
[junit] 09/11/19 02:34:11 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:58915 is added to
blk_-4300934375526664791_1019 size 6117
[junit] 09/11/19 02:34:11 INFO DataNode.clienttrace: src: /127.0.0.1:33793,
dest: /127.0.0.1:34458, bytes: 6117, op: HDFS_WRITE, cliID:
DFSClient_395457836, srvID: DS-1649789720-127.0.1.1-34458-1258598005579,
blockid: blk_-4300934375526664791_1019
[junit] 09/11/19 02:34:11 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:34458 is added to
blk_-4300934375526664791_1019 size 6117
[junit] 09/11/19 02:34:11 INFO datanode.DataNode: PacketResponder 1 for
block blk_-4300934375526664791_1019 terminating
[junit] 09/11/19 02:34:11 INFO DataNode.clienttrace: src: /127.0.0.1:33013,
dest: /127.0.0.1:51645, bytes: 6117, op: HDFS_WRITE, cliID:
DFSClient_395457836, srvID: DS-2142175154-127.0.1.1-51645-1258598005082,
blockid: blk_-4300934375526664791_1019
[junit] 09/11/19 02:34:11 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:51645 is added to
blk_-4300934375526664791_1019 size 6117
[junit] 09/11/19 02:34:11 INFO datanode.DataNode: PacketResponder 2 for
block blk_-4300934375526664791_1019 terminating
[junit] 09/11/19 02:34:11 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/tmp/temp-883675194/tmp-436547546/_logs/history/localhost_1258598006604_job_20091119023326575_0002_hudson_Job7967489867006079740.jar
is closed by DFSClient_395457836
[junit] 09/11/19 02:34:11 INFO mapred.JobTracker: Removed completed task
'attempt_20091119023326575_0002_m_000001_0' from
'tracker_host3.foo.com:localhost/127.0.0.1:33169'
[junit] 09/11/19 02:34:11 INFO mapred.JobTracker: Removed completed task
'attempt_20091119023326575_0002_m_000002_0' from
'tracker_host3.foo.com:localhost/127.0.0.1:33169'
[junit] 09/11/19 02:34:11 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-7392176902308286198 is added to invalidSet of
127.0.0.1:58915
[junit] 09/11/19 02:34:11 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-7392176902308286198 is added to invalidSet of
127.0.0.1:34458
[junit] 09/11/19 02:34:11 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-7392176902308286198 is added to invalidSet of
127.0.0.1:51645
[junit] 09/11/19 02:34:11 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-3388285408264901103 is added to invalidSet of
127.0.0.1:51645
[junit] 09/11/19 02:34:11 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-3388285408264901103 is added to invalidSet of
127.0.0.1:39535
[junit] 09/11/19 02:34:11 INFO mapred.TaskTracker: Received 'KillJobAction'
for job: job_20091119023326575_0002
[junit] 09/11/19 02:34:11 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-3388285408264901103 is added to invalidSet of
127.0.0.1:34458
[junit] 09/11/19 02:34:11 INFO mapred.TaskRunner:
attempt_20091119023326575_0002_m_000001_0 done; removing files.
[junit] 09/11/19 02:34:11 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-6813384894621786135 is added to invalidSet of
127.0.0.1:39535
[junit] 09/11/19 02:34:11 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-6813384894621786135 is added to invalidSet of
127.0.0.1:34458
[junit] 09/11/19 02:34:11 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-6813384894621786135 is added to invalidSet of
127.0.0.1:51645
[junit] 09/11/19 02:34:11 INFO mapred.IndexCache: Map ID
attempt_20091119023326575_0002_m_000001_0 not found in cache
[junit] 09/11/19 02:34:11 INFO mapred.TaskRunner:
attempt_20091119023326575_0002_m_000002_0 done; removing files.
[junit] 09/11/19 02:34:11 INFO mapred.IndexCache: Map ID
attempt_20091119023326575_0002_m_000002_0 not found in cache
[junit] 09/11/19 02:34:11 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete
src=/tmp/hadoop-hudson/mapred/system/job_20091119023326575_0002 dst=null
perm=null
[junit] 09/11/19 02:34:11 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:58915
to delete blk_-7392176902308286198_1013
[junit] 09/11/19 02:34:11 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:34458
to delete blk_-6813384894621786135_1015 blk_-7392176902308286198_1013
blk_-3388285408264901103_1014
[junit] 09/11/19 02:34:13 INFO mapred.TaskTracker: Received 'KillJobAction'
for job: job_20091119023326575_0002
[junit] 09/11/19 02:34:13 WARN mapred.TaskTracker: Unknown job
job_20091119023326575_0002 being deleted.
[junit] 09/11/19 02:34:13 INFO mapred.TaskTracker: Received 'KillJobAction'
for job: job_20091119023326575_0002
[junit] 09/11/19 02:34:13 WARN mapred.TaskTracker: Unknown job
job_20091119023326575_0002 being deleted.
[junit] 09/11/19 02:34:13 INFO datanode.DataNode: Deleting block
blk_-7392176902308286198_1013 file
build/test/data/dfs/data/data4/current/blk_-7392176902308286198
[junit] 09/11/19 02:34:13 INFO datanode.DataNode: Deleting block
blk_-6813384894621786135_1015 file
build/test/data/dfs/data/data4/current/blk_-6813384894621786135
[junit] 09/11/19 02:34:13 INFO datanode.DataNode: Deleting block
blk_-3388285408264901103_1014 file
build/test/data/dfs/data/data3/current/blk_-3388285408264901103
[junit] 09/11/19 02:34:13 INFO mapReduceLayer.MapReduceLauncher: 100%
complete
[junit] 09/11/19 02:34:13 INFO mapReduceLayer.MapReduceLauncher:
Successfully stored result in:
"hdfs://localhost:46092/tmp/temp-883675194/tmp-436547546"
[junit] 09/11/19 02:34:13 INFO mapReduceLayer.MapReduceLauncher: Records
written : 2
[junit] 09/11/19 02:34:13 INFO mapReduceLayer.MapReduceLauncher: Bytes
written : 78
[junit] 09/11/19 02:34:13 INFO mapReduceLayer.MapReduceLauncher: Success!
[junit] 09/11/19 02:34:13 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=listStatus src=/tmp/temp-883675194/tmp-436547546
dst=null perm=null
[junit] 09/11/19 02:34:13 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/temp-883675194/tmp-436547546/part-00000 dst=null
perm=null
[junit] 09/11/19 02:34:13 INFO DataNode.clienttrace: src: /127.0.0.1:39535,
dest: /127.0.0.1:33288, bytes: 43, op: HDFS_READ, cliID: DFSClient_395457836,
srvID: DS-1984890133-127.0.1.1-39535-1258598006504, blockid:
blk_1783466514706258377_1018
[junit] 09/11/19 02:34:13 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/temp-883675194/tmp-436547546/part-00001 dst=null
perm=null
[junit] 09/11/19 02:34:13 INFO DataNode.clienttrace: src: /127.0.0.1:51645,
dest: /127.0.0.1:33017, bytes: 43, op: HDFS_READ, cliID: DFSClient_395457836,
srvID: DS-2142175154-127.0.1.1-51645-1258598005082, blockid:
blk_3881053833396778110_1019
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 44.415 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: 220 minutes 3 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