See <http://hudson.zones.apache.org/hudson/job/Pig-trunk/685/changes>
Changes:
[olga] PIG-1226: suuport for additional jar files (thejas via olgan)
[rding] PIG-1194: ERROR 2055: Received Error while processing the map plan
------------------------------------------
[...truncated 240684 lines...]
[junit] 10/02/18 02:25:04 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=setPermission
src=/tmp/hadoop-hudson/mapred/system/job_20100218022432445_0002/job.xml
dst=null perm=hudson:supergroup:rw-r--r--
[junit] 10/02/18 02:25:04 INFO datanode.DataNode: Deleting block
blk_-8717085773027673757_1005 file
build/test/data/dfs/data/data4/current/blk_-8717085773027673757
[junit] 10/02/18 02:25:04 INFO datanode.DataNode: Deleting block
blk_-4113414690970244428_1006 file
build/test/data/dfs/data/data3/current/blk_-4113414690970244428
[junit] 10/02/18 02:25:04 INFO hdfs.StateChange: BLOCK*
NameSystem.allocateBlock:
/tmp/hadoop-hudson/mapred/system/job_20100218022432445_0002/job.xml.
blk_68013359739296494_1015
[junit] 10/02/18 02:25:04 INFO datanode.DataNode: Receiving block
blk_68013359739296494_1015 src: /127.0.0.1:43539 dest: /127.0.0.1:55726
[junit] 10/02/18 02:25:04 INFO datanode.DataNode: Receiving block
blk_68013359739296494_1015 src: /127.0.0.1:50495 dest: /127.0.0.1:56095
[junit] 10/02/18 02:25:04 INFO datanode.DataNode: Receiving block
blk_68013359739296494_1015 src: /127.0.0.1:52105 dest: /127.0.0.1:40309
[junit] 10/02/18 02:25:04 INFO DataNode.clienttrace: src: /127.0.0.1:52105,
dest: /127.0.0.1:40309, bytes: 48178, op: HDFS_WRITE, cliID:
DFSClient_-1684024129, srvID: DS-1948219082-127.0.1.1-40309-1266459871415,
blockid: blk_68013359739296494_1015
[junit] 10/02/18 02:25:04 INFO datanode.DataNode: PacketResponder 0 for
block blk_68013359739296494_1015 terminating
[junit] 10/02/18 02:25:04 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:40309 is added to
blk_68013359739296494_1015 size 48178
[junit] 10/02/18 02:25:04 INFO DataNode.clienttrace: src: /127.0.0.1:50495,
dest: /127.0.0.1:56095, bytes: 48178, op: HDFS_WRITE, cliID:
DFSClient_-1684024129, srvID: DS-289342816-127.0.1.1-56095-1266459870897,
blockid: blk_68013359739296494_1015
[junit] 10/02/18 02:25:04 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:56095 is added to
blk_68013359739296494_1015 size 48178
[junit] 10/02/18 02:25:04 INFO datanode.DataNode: PacketResponder 1 for
block blk_68013359739296494_1015 terminating
[junit] 10/02/18 02:25:04 INFO DataNode.clienttrace: src: /127.0.0.1:43539,
dest: /127.0.0.1:55726, bytes: 48178, op: HDFS_WRITE, cliID:
DFSClient_-1684024129, srvID: DS-1346704804-127.0.1.1-55726-1266459871874,
blockid: blk_68013359739296494_1015
[junit] 10/02/18 02:25:04 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:55726 is added to
blk_68013359739296494_1015 size 48178
[junit] 10/02/18 02:25:04 INFO datanode.DataNode: PacketResponder 2 for
block blk_68013359739296494_1015 terminating
[junit] 10/02/18 02:25:04 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/tmp/hadoop-hudson/mapred/system/job_20100218022432445_0002/job.xml is closed
by DFSClient_-1684024129
[junit] 10/02/18 02:25:04 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100218022432445_0002/job.xml
dst=null perm=null
[junit] 10/02/18 02:25:04 INFO DataNode.clienttrace: src: /127.0.0.1:56095,
dest: /127.0.0.1:50497, bytes: 48558, op: HDFS_READ, cliID:
DFSClient_-1684024129, srvID: DS-289342816-127.0.1.1-56095-1266459870897,
blockid: blk_68013359739296494_1015
[junit] 10/02/18 02:25:04 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100218022432445_0002/job.jar
dst=null perm=null
[junit] 10/02/18 02:25:04 INFO DataNode.clienttrace: src: /127.0.0.1:56095,
dest: /127.0.0.1:50498, bytes: 2892707, op: HDFS_READ, cliID:
DFSClient_-1684024129, srvID: DS-289342816-127.0.1.1-56095-1266459870897,
blockid: blk_-7664397758800478618_1013
[junit] 10/02/18 02:25:04 INFO mapred.JobTracker: Initializing
job_20100218022432445_0002
[junit] 10/02/18 02:25:04 INFO mapred.JobInProgress: Initializing
job_20100218022432445_0002
[junit] 10/02/18 02:25:04 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=create
src=/tmp/temp-1832912908/tmp-1615126932/_logs/history/localhost_1266459872468_job_20100218022432445_0002_hudson_Job2769198517474778469.jar
dst=null perm=hudson:supergroup:rw-r--r--
[junit] 10/02/18 02:25:04 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=create
src=/tmp/temp-1832912908/tmp-1615126932/_logs/history/localhost_1266459872468_job_20100218022432445_0002_conf.xml
dst=null perm=hudson:supergroup:rw-r--r--
[junit] 10/02/18 02:25:04 INFO hdfs.StateChange: BLOCK*
NameSystem.allocateBlock:
/tmp/temp-1832912908/tmp-1615126932/_logs/history/localhost_1266459872468_job_20100218022432445_0002_conf.xml.
blk_-7040213353787062094_1017
[junit] 10/02/18 02:25:04 INFO datanode.DataNode: Receiving block
blk_-7040213353787062094_1017 src: /127.0.0.1:52108 dest: /127.0.0.1:40309
[junit] 10/02/18 02:25:04 INFO datanode.DataNode: Receiving block
blk_-7040213353787062094_1017 src: /127.0.0.1:43545 dest: /127.0.0.1:55726
[junit] 10/02/18 02:25:04 INFO datanode.DataNode: Receiving block
blk_-7040213353787062094_1017 src: /127.0.0.1:50501 dest: /127.0.0.1:56095
[junit] 10/02/18 02:25:04 INFO DataNode.clienttrace: src: /127.0.0.1:50501,
dest: /127.0.0.1:56095, bytes: 48208, op: HDFS_WRITE, cliID:
DFSClient_-1684024129, srvID: DS-289342816-127.0.1.1-56095-1266459870897,
blockid: blk_-7040213353787062094_1017
[junit] 10/02/18 02:25:04 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:56095 is added to
blk_-7040213353787062094_1017 size 48208
[junit] 10/02/18 02:25:04 INFO DataNode.clienttrace: src: /127.0.0.1:43545,
dest: /127.0.0.1:55726, bytes: 48208, op: HDFS_WRITE, cliID:
DFSClient_-1684024129, srvID: DS-1346704804-127.0.1.1-55726-1266459871874,
blockid: blk_-7040213353787062094_1017
[junit] 10/02/18 02:25:04 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:55726 is added to
blk_-7040213353787062094_1017 size 48208
[junit] 10/02/18 02:25:04 INFO datanode.DataNode: PacketResponder 1 for
block blk_-7040213353787062094_1017 terminating
[junit] 10/02/18 02:25:04 INFO datanode.DataNode: PacketResponder 0 for
block blk_-7040213353787062094_1017 terminating
[junit] 10/02/18 02:25:04 INFO DataNode.clienttrace: src: /127.0.0.1:52108,
dest: /127.0.0.1:40309, bytes: 48208, op: HDFS_WRITE, cliID:
DFSClient_-1684024129, srvID: DS-1948219082-127.0.1.1-40309-1266459871415,
blockid: blk_-7040213353787062094_1017
[junit] 10/02/18 02:25:04 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:40309 is added to
blk_-7040213353787062094_1017 size 48208
[junit] 10/02/18 02:25:04 INFO datanode.DataNode: PacketResponder 2 for
block blk_-7040213353787062094_1017 terminating
[junit] 10/02/18 02:25:04 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/tmp/temp-1832912908/tmp-1615126932/_logs/history/localhost_1266459872468_job_20100218022432445_0002_conf.xml
is closed by DFSClient_-1684024129
[junit] 10/02/18 02:25:04 INFO mapReduceLayer.MapReduceLauncher: Submitting
job: job_20100218022432445_0002 to execution engine.
[junit] 10/02/18 02:25:04 INFO mapReduceLayer.MapReduceLauncher: More
information at:
http://localhost:41043/jobdetails.jsp?jobid=job_20100218022432445_0002
[junit] 10/02/18 02:25:04 INFO mapReduceLayer.MapReduceLauncher: To kill
this job, use: kill job_20100218022432445_0002
[junit] 10/02/18 02:25:04 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100218022432445_0002/job.split
dst=null perm=null
[junit] 10/02/18 02:25:04 INFO DataNode.clienttrace: src: /127.0.0.1:41953,
dest: /127.0.0.1:42195, bytes: 1605, op: HDFS_READ, cliID:
DFSClient_-1684024129, srvID: DS-458142337-127.0.1.1-41953-1266459872358,
blockid: blk_-4553958849651549543_1014
[junit] 10/02/18 02:25:04 INFO mapred.JobInProgress: Input size for job
job_20100218022432445_0002 = 12. Number of splits = 2
[junit] 10/02/18 02:25:04 INFO mapred.JobInProgress:
tip:task_20100218022432445_0002_m_000000 has split on
node:/default-rack/h7.grid.sp2.yahoo.net
[junit] 10/02/18 02:25:04 INFO mapred.JobInProgress:
tip:task_20100218022432445_0002_m_000001 has split on
node:/default-rack/h7.grid.sp2.yahoo.net
[junit] 10/02/18 02:25:04 INFO datanode.DataNode: Deleting block
blk_-497614228168617153_1007 file
build/test/data/dfs/data/data5/current/blk_-497614228168617153
[junit] 10/02/18 02:25:04 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:56095
to delete blk_-497614228168617153_1007 blk_-8717085773027673757_1005
blk_-4113414690970244428_1006
[junit] 10/02/18 02:25:04 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:41953
to delete blk_-497614228168617153_1007 blk_-8717085773027673757_1005
blk_-7664397758800478618_1013 blk_-4113414690970244428_1006
[junit] 10/02/18 02:25:05 INFO mapred.JobTracker: Adding task
'attempt_20100218022432445_0002_m_000003_0' to tip
task_20100218022432445_0002_m_000003, for tracker
'tracker_host3.foo.com:localhost/127.0.0.1:34808'
[junit] 10/02/18 02:25:05 INFO mapred.TaskTracker: LaunchTaskAction
(registerTask): attempt_20100218022432445_0002_m_000003_0 task's
state:UNASSIGNED
[junit] 10/02/18 02:25:05 INFO mapred.TaskTracker: Trying to launch :
attempt_20100218022432445_0002_m_000003_0
[junit] 10/02/18 02:25:05 INFO mapred.TaskTracker: In TaskLauncher, current
free slots : 2 and trying to launch attempt_20100218022432445_0002_m_000003_0
[junit] 10/02/18 02:25:05 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100218022432445_0002/job.xml
dst=null perm=null
[junit] 10/02/18 02:25:05 INFO DataNode.clienttrace: src: /127.0.0.1:56095,
dest: /127.0.0.1:50503, bytes: 48558, op: HDFS_READ, cliID:
DFSClient_-1684024129, srvID: DS-289342816-127.0.1.1-56095-1266459870897,
blockid: blk_68013359739296494_1015
[junit] 10/02/18 02:25:05 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100218022432445_0002/job.jar
dst=null perm=null
[junit] 10/02/18 02:25:05 INFO mapReduceLayer.MapReduceLauncher: 0% complete
[junit] 10/02/18 02:25:05 INFO DataNode.clienttrace: src: /127.0.0.1:41953,
dest: /127.0.0.1:42197, bytes: 2892707, op: HDFS_READ, cliID:
DFSClient_-1684024129, srvID: DS-458142337-127.0.1.1-41953-1266459872358,
blockid: blk_-7664397758800478618_1013
[junit] 10/02/18 02:25:05 WARN datanode.DataNode: Unexpected error trying
to delete block blk_-8717085773027673757_1005. BlockInfo not found in volumeMap.
[junit] 10/02/18 02:25:05 INFO datanode.DataNode: Deleting block
blk_-7664397758800478618_1013 file
build/test/data/dfs/data/data8/current/blk_-7664397758800478618
[junit] 10/02/18 02:25:05 INFO datanode.DataNode: Deleting block
blk_-4113414690970244428_1006 file
build/test/data/dfs/data/data8/current/blk_-4113414690970244428
[junit] 10/02/18 02:25:05 INFO datanode.DataNode: Deleting block
blk_-497614228168617153_1007 file
build/test/data/dfs/data/data7/current/blk_-497614228168617153
[junit] 10/02/18 02:25:05 WARN datanode.DataNode: Error processing datanode
Command
[junit] java.io.IOException: Error in deleting blocks.
[junit] at
org.apache.hadoop.hdfs.server.datanode.FSDataset.invalidate(FSDataset.java:1361)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataNode.processCommand(DataNode.java:868)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataNode.processCommand(DataNode.java:830)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataNode.offerService(DataNode.java:710)
[junit] at
org.apache.hadoop.hdfs.server.datanode.DataNode.run(DataNode.java:1186)
[junit] at java.lang.Thread.run(Thread.java:619)
[junit] 10/02/18 02:25:05 INFO mapred.JvmManager: In JvmRunner constructed
JVM ID: jvm_20100218022432445_0002_m_-987365087
[junit] 10/02/18 02:25:05 INFO mapred.JvmManager: JVM Runner
jvm_20100218022432445_0002_m_-987365087 spawned.
[junit] 10/02/18 02:25:06 INFO mapred.TaskTracker: JVM with ID:
jvm_20100218022432445_0002_m_-987365087 given task:
attempt_20100218022432445_0002_m_000003_0
[junit] 10/02/18 02:25:06 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=mkdirs
src=/tmp/temp-1832912908/tmp-1615126932/_temporary dst=null
perm=hudson:supergroup:rwxr-xr-x
[junit] 10/02/18 02:25:06 INFO mapred.TaskTracker:
attempt_20100218022432445_0002_m_000003_0 0.0% setup
[junit] 10/02/18 02:25:06 INFO mapred.TaskTracker: Task
attempt_20100218022432445_0002_m_000003_0 is done.
[junit] 10/02/18 02:25:06 INFO mapred.TaskTracker: reported output size for
attempt_20100218022432445_0002_m_000003_0 was 0
[junit] 10/02/18 02:25:06 INFO mapred.TaskTracker: addFreeSlot : current
free slots : 2
[junit] 10/02/18 02:25:06 INFO mapred.JvmManager: JVM :
jvm_20100218022432445_0002_m_-987365087 exited. Number of tasks it ran: 1
[junit] 10/02/18 02:25:06 INFO datanode.DataNode: Deleting block
blk_-8717085773027673757_1005 file
build/test/data/dfs/data/data2/current/blk_-8717085773027673757
[junit] 10/02/18 02:25:06 INFO datanode.DataNode: Deleting block
blk_-4113414690970244428_1006 file
build/test/data/dfs/data/data1/current/blk_-4113414690970244428
[junit] 10/02/18 02:25:06 INFO datanode.DataNode: Deleting block
blk_-497614228168617153_1007 file
build/test/data/dfs/data/data2/current/blk_-497614228168617153
[junit] 10/02/18 02:25:08 INFO mapred.TaskTracker:
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find
taskTracker/jobcache/job_20100218022432445_0002/attempt_20100218022432445_0002_m_000003_0/output/file.out
in any of the configured local directories
[junit] 10/02/18 02:25:08 INFO mapred.JobInProgress: Task
'attempt_20100218022432445_0002_m_000003_0' has completed
task_20100218022432445_0002_m_000003 successfully.
[junit] 10/02/18 02:25:08 INFO mapred.JobTracker: Adding task
'attempt_20100218022432445_0002_m_000000_0' to tip
task_20100218022432445_0002_m_000000, for tracker
'tracker_host3.foo.com:localhost/127.0.0.1:34808'
[junit] 10/02/18 02:25:08 INFO mapred.JobInProgress: Choosing rack-local
task task_20100218022432445_0002_m_000000
[junit] 10/02/18 02:25:08 INFO mapred.TaskTracker: LaunchTaskAction
(registerTask): attempt_20100218022432445_0002_m_000000_0 task's
state:UNASSIGNED
[junit] 10/02/18 02:25:08 INFO mapred.TaskTracker: Trying to launch :
attempt_20100218022432445_0002_m_000000_0
[junit] 10/02/18 02:25:08 INFO mapred.TaskTracker: In TaskLauncher, current
free slots : 2 and trying to launch attempt_20100218022432445_0002_m_000000_0
[junit] 10/02/18 02:25:08 INFO mapred.TaskTracker: Received KillTaskAction
for task: attempt_20100218022432445_0002_m_000003_0
[junit] 10/02/18 02:25:08 INFO mapred.TaskTracker: About to purge task:
attempt_20100218022432445_0002_m_000003_0
[junit] 10/02/18 02:25:08 INFO mapred.TaskRunner:
attempt_20100218022432445_0002_m_000003_0 done; removing files.
[junit] 10/02/18 02:25:08 INFO mapred.IndexCache: Map ID
attempt_20100218022432445_0002_m_000003_0 not found in cache
[junit] 10/02/18 02:25:08 INFO mapred.JvmManager: In JvmRunner constructed
JVM ID: jvm_20100218022432445_0002_m_36121755
[junit] 10/02/18 02:25:08 INFO mapred.JvmManager: JVM Runner
jvm_20100218022432445_0002_m_36121755 spawned.
[junit] 10/02/18 02:25:08 INFO mapred.TaskTracker: JVM with ID:
jvm_20100218022432445_0002_m_36121755 given task:
attempt_20100218022432445_0002_m_000000_0
[junit] 10/02/18 02:25:09 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open src=/user/hudson/input2.txt dst=null
perm=null
[junit] 10/02/18 02:25:09 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=create
src=/tmp/temp-1832912908/tmp-1615126932/_temporary/_attempt_20100218022432445_0002_m_000000_0/part-00000
dst=null perm=hudson:supergroup:rw-r--r--
[junit] 10/02/18 02:25:09 INFO DataNode.clienttrace: src: /127.0.0.1:41953,
dest: /127.0.0.1:42208, bytes: 10, op: HDFS_READ, cliID:
DFSClient_attempt_20100218022432445_0002_m_000000_0, srvID:
DS-458142337-127.0.1.1-41953-1266459872358, blockid: blk_809438958577035620_1012
[junit] 10/02/18 02:25:09 INFO hdfs.StateChange: BLOCK*
NameSystem.allocateBlock:
/tmp/temp-1832912908/tmp-1615126932/_temporary/_attempt_20100218022432445_0002_m_000000_0/part-00000.
blk_2112989940693637917_1018
[junit] 10/02/18 02:25:09 INFO datanode.DataNode: Receiving block
blk_2112989940693637917_1018 src: /127.0.0.1:52125 dest: /127.0.0.1:40309
[junit] 10/02/18 02:25:09 INFO datanode.DataNode: Receiving block
blk_2112989940693637917_1018 src: /127.0.0.1:42210 dest: /127.0.0.1:41953
[junit] 10/02/18 02:25:09 INFO datanode.DataNode: Receiving block
blk_2112989940693637917_1018 src: /127.0.0.1:50518 dest: /127.0.0.1:56095
[junit] 10/02/18 02:25:09 INFO DataNode.clienttrace: src: /127.0.0.1:50518,
dest: /127.0.0.1:56095, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20100218022432445_0002_m_000000_0, srvID:
DS-289342816-127.0.1.1-56095-1266459870897, blockid:
blk_2112989940693637917_1018
[junit] 10/02/18 02:25:09 INFO datanode.DataNode: PacketResponder 0 for
block blk_2112989940693637917_1018 terminating
[junit] 10/02/18 02:25:09 INFO DataNode.clienttrace: src: /127.0.0.1:42210,
dest: /127.0.0.1:41953, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20100218022432445_0002_m_000000_0, srvID:
DS-458142337-127.0.1.1-41953-1266459872358, blockid:
blk_2112989940693637917_1018
[junit] 10/02/18 02:25:09 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:56095 is added to
blk_2112989940693637917_1018 size 39
[junit] 10/02/18 02:25:09 INFO datanode.DataNode: PacketResponder 1 for
block blk_2112989940693637917_1018 terminating
[junit] 10/02/18 02:25:09 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:41953 is added to
blk_2112989940693637917_1018 size 39
[junit] 10/02/18 02:25:09 INFO DataNode.clienttrace: src: /127.0.0.1:52125,
dest: /127.0.0.1:40309, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20100218022432445_0002_m_000000_0, srvID:
DS-1948219082-127.0.1.1-40309-1266459871415, blockid:
blk_2112989940693637917_1018
[junit] 10/02/18 02:25:09 INFO datanode.DataNode: PacketResponder 2 for
block blk_2112989940693637917_1018 terminating
[junit] 10/02/18 02:25:09 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:40309 is added to
blk_2112989940693637917_1018 size 39
[junit] 10/02/18 02:25:09 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/tmp/temp-1832912908/tmp-1615126932/_temporary/_attempt_20100218022432445_0002_m_000000_0/part-00000
is closed by DFSClient_attempt_20100218022432445_0002_m_000000_0
[junit] 10/02/18 02:25:09 INFO mapred.TaskTracker: Task
attempt_20100218022432445_0002_m_000000_0 is in commit-pending, task
state:COMMIT_PENDING
[junit] 10/02/18 02:25:09 INFO mapred.TaskTracker:
attempt_20100218022432445_0002_m_000000_0 0.0%
[junit] 10/02/18 02:25:09 INFO mapred.JobTracker: Adding task
'attempt_20100218022432445_0002_m_000001_0' to tip
task_20100218022432445_0002_m_000001, for tracker
'tracker_host0.foo.com:localhost/127.0.0.1:33015'
[junit] 10/02/18 02:25:09 INFO mapred.JobInProgress: Choosing rack-local
task task_20100218022432445_0002_m_000001
[junit] 10/02/18 02:25:09 INFO mapred.TaskTracker: LaunchTaskAction
(registerTask): attempt_20100218022432445_0002_m_000001_0 task's
state:UNASSIGNED
[junit] 10/02/18 02:25:09 INFO mapred.TaskTracker: Trying to launch :
attempt_20100218022432445_0002_m_000001_0
[junit] 10/02/18 02:25:09 INFO mapred.TaskTracker: In TaskLauncher, current
free slots : 2 and trying to launch attempt_20100218022432445_0002_m_000001_0
[junit] 10/02/18 02:25:09 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100218022432445_0002/job.xml
dst=null perm=null
[junit] 10/02/18 02:25:09 INFO DataNode.clienttrace: src: /127.0.0.1:40309,
dest: /127.0.0.1:52128, bytes: 48558, op: HDFS_READ, cliID:
DFSClient_-1684024129, srvID: DS-1948219082-127.0.1.1-40309-1266459871415,
blockid: blk_68013359739296494_1015
[junit] 10/02/18 02:25:09 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/hadoop-hudson/mapred/system/job_20100218022432445_0002/job.jar
dst=null perm=null
[junit] 10/02/18 02:25:10 INFO DataNode.clienttrace: src: /127.0.0.1:55726,
dest: /127.0.0.1:43565, bytes: 2892707, op: HDFS_READ, cliID:
DFSClient_-1684024129, srvID: DS-1346704804-127.0.1.1-55726-1266459871874,
blockid: blk_-7664397758800478618_1013
[junit] 10/02/18 02:25:10 INFO mapred.JvmManager: In JvmRunner constructed
JVM ID: jvm_20100218022432445_0002_m_-1947481659
[junit] 10/02/18 02:25:10 INFO mapred.JvmManager: JVM Runner
jvm_20100218022432445_0002_m_-1947481659 spawned.
[junit] 10/02/18 02:25:10 INFO mapred.TaskTracker: JVM with ID:
jvm_20100218022432445_0002_m_-1947481659 given task:
attempt_20100218022432445_0002_m_000001_0
[junit] 10/02/18 02:25:11 INFO mapred.TaskTracker: Received commit task
action for attempt_20100218022432445_0002_m_000000_0
[junit] 10/02/18 02:25:11 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open src=/user/hudson/input1.txt dst=null
perm=null
[junit] 10/02/18 02:25:11 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=create
src=/tmp/temp-1832912908/tmp-1615126932/_temporary/_attempt_20100218022432445_0002_m_000001_0/part-00001
dst=null perm=hudson:supergroup:rw-r--r--
[junit] 10/02/18 02:25:11 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=listStatus
src=/tmp/temp-1832912908/tmp-1615126932/_temporary/_attempt_20100218022432445_0002_m_000000_0
dst=null perm=null
[junit] 10/02/18 02:25:11 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=mkdirs src=/tmp/temp-1832912908/tmp-1615126932
dst=null perm=hudson:supergroup:rwxr-xr-x
[junit] 10/02/18 02:25:11 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=rename
src=/tmp/temp-1832912908/tmp-1615126932/_temporary/_attempt_20100218022432445_0002_m_000000_0/part-00000
dst=/tmp/temp-1832912908/tmp-1615126932/part-00000
perm=hudson:supergroup:rw-r--r--
[junit] 10/02/18 02:25:11 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete
src=/tmp/temp-1832912908/tmp-1615126932/_temporary/_attempt_20100218022432445_0002_m_000000_0
dst=null perm=null
[junit] 10/02/18 02:25:11 INFO mapred.TaskTracker:
attempt_20100218022432445_0002_m_000000_0 1.0%
[junit] 10/02/18 02:25:11 INFO mapred.TaskTracker: Task
attempt_20100218022432445_0002_m_000000_0 is done.
[junit] 10/02/18 02:25:11 INFO mapred.TaskTracker: reported output size for
attempt_20100218022432445_0002_m_000000_0 was 0
[junit] 10/02/18 02:25:11 INFO mapred.TaskTracker: addFreeSlot : current
free slots : 2
[junit] 10/02/18 02:25:11 INFO DataNode.clienttrace: src: /127.0.0.1:55726,
dest: /127.0.0.1:43571, bytes: 10, op: HDFS_READ, cliID:
DFSClient_attempt_20100218022432445_0002_m_000001_0, srvID:
DS-1346704804-127.0.1.1-55726-1266459871874, blockid:
blk_-4999695042828863620_1011
[junit] 10/02/18 02:25:11 INFO hdfs.StateChange: BLOCK*
NameSystem.allocateBlock:
/tmp/temp-1832912908/tmp-1615126932/_temporary/_attempt_20100218022432445_0002_m_000001_0/part-00001.
blk_6324400971070706047_1019
[junit] 10/02/18 02:25:11 INFO datanode.DataNode: Receiving block
blk_6324400971070706047_1019 src: /127.0.0.1:50527 dest: /127.0.0.1:56095
[junit] 10/02/18 02:25:11 INFO datanode.DataNode: Receiving block
blk_6324400971070706047_1019 src: /127.0.0.1:43573 dest: /127.0.0.1:55726
[junit] 10/02/18 02:25:11 INFO datanode.DataNode: Receiving block
blk_6324400971070706047_1019 src: /127.0.0.1:42222 dest: /127.0.0.1:41953
[junit] 10/02/18 02:25:11 INFO DataNode.clienttrace: src: /127.0.0.1:42222,
dest: /127.0.0.1:41953, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20100218022432445_0002_m_000001_0, srvID:
DS-458142337-127.0.1.1-41953-1266459872358, blockid:
blk_6324400971070706047_1019
[junit] 10/02/18 02:25:11 INFO datanode.DataNode: PacketResponder 0 for
block blk_6324400971070706047_1019 terminating
[junit] 10/02/18 02:25:11 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:41953 is added to
blk_6324400971070706047_1019 size 39
[junit] 10/02/18 02:25:11 INFO DataNode.clienttrace: src: /127.0.0.1:43573,
dest: /127.0.0.1:55726, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20100218022432445_0002_m_000001_0, srvID:
DS-1346704804-127.0.1.1-55726-1266459871874, blockid:
blk_6324400971070706047_1019
[junit] 10/02/18 02:25:11 INFO datanode.DataNode: PacketResponder 1 for
block blk_6324400971070706047_1019 terminating
[junit] 10/02/18 02:25:11 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:55726 is added to
blk_6324400971070706047_1019 size 39
[junit] 10/02/18 02:25:11 INFO mapred.JvmManager: JVM :
jvm_20100218022432445_0002_m_36121755 exited. Number of tasks it ran: 1
[junit] 10/02/18 02:25:11 INFO DataNode.clienttrace: src: /127.0.0.1:50527,
dest: /127.0.0.1:56095, bytes: 39, op: HDFS_WRITE, cliID:
DFSClient_attempt_20100218022432445_0002_m_000001_0, srvID:
DS-289342816-127.0.1.1-56095-1266459870897, blockid:
blk_6324400971070706047_1019
[junit] 10/02/18 02:25:11 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:56095 is added to
blk_6324400971070706047_1019 size 39
[junit] 10/02/18 02:25:11 INFO datanode.DataNode: PacketResponder 2 for
block blk_6324400971070706047_1019 terminating
[junit] 10/02/18 02:25:11 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/tmp/temp-1832912908/tmp-1615126932/_temporary/_attempt_20100218022432445_0002_m_000001_0/part-00001
is closed by DFSClient_attempt_20100218022432445_0002_m_000001_0
[junit] 10/02/18 02:25:11 INFO mapred.TaskTracker: Task
attempt_20100218022432445_0002_m_000001_0 is in commit-pending, task
state:COMMIT_PENDING
[junit] 10/02/18 02:25:11 INFO mapred.TaskTracker:
attempt_20100218022432445_0002_m_000001_0 0.0%
[junit] 10/02/18 02:25:12 INFO mapred.TaskTracker: Received commit task
action for attempt_20100218022432445_0002_m_000001_0
[junit] 10/02/18 02:25:13 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=listStatus
src=/tmp/temp-1832912908/tmp-1615126932/_temporary/_attempt_20100218022432445_0002_m_000001_0
dst=null perm=null
[junit] 10/02/18 02:25:13 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=mkdirs src=/tmp/temp-1832912908/tmp-1615126932
dst=null perm=hudson:supergroup:rwxr-xr-x
[junit] 10/02/18 02:25:13 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=rename
src=/tmp/temp-1832912908/tmp-1615126932/_temporary/_attempt_20100218022432445_0002_m_000001_0/part-00001
dst=/tmp/temp-1832912908/tmp-1615126932/part-00001
perm=hudson:supergroup:rw-r--r--
[junit] 10/02/18 02:25:13 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete
src=/tmp/temp-1832912908/tmp-1615126932/_temporary/_attempt_20100218022432445_0002_m_000001_0
dst=null perm=null
[junit] 10/02/18 02:25:13 INFO mapred.TaskTracker:
attempt_20100218022432445_0002_m_000001_0 1.0%
[junit] 10/02/18 02:25:13 INFO mapred.TaskTracker: Task
attempt_20100218022432445_0002_m_000001_0 is done.
[junit] 10/02/18 02:25:13 INFO mapred.TaskTracker: reported output size for
attempt_20100218022432445_0002_m_000001_0 was 0
[junit] 10/02/18 02:25:13 INFO mapred.TaskTracker: addFreeSlot : current
free slots : 2
[junit] 10/02/18 02:25:13 INFO mapred.JvmManager: JVM :
jvm_20100218022432445_0002_m_-1947481659 exited. Number of tasks it ran: 1
[junit] 10/02/18 02:25:14 INFO mapred.TaskTracker:
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find
taskTracker/jobcache/job_20100218022432445_0002/attempt_20100218022432445_0002_m_000000_0/output/file.out
in any of the configured local directories
[junit] 10/02/18 02:25:14 INFO mapred.JobInProgress: Task
'attempt_20100218022432445_0002_m_000000_0' has completed
task_20100218022432445_0002_m_000000 successfully.
[junit] 10/02/18 02:25:14 INFO mapReduceLayer.MapReduceLauncher: 25%
complete
[junit] 10/02/18 02:25:15 INFO mapred.TaskTracker:
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find
taskTracker/jobcache/job_20100218022432445_0002/attempt_20100218022432445_0002_m_000001_0/output/file.out
in any of the configured local directories
[junit] 10/02/18 02:25:15 INFO mapred.JobInProgress: Task
'attempt_20100218022432445_0002_m_000001_0' has completed
task_20100218022432445_0002_m_000001 successfully.
[junit] 10/02/18 02:25:15 INFO mapred.JobTracker: Adding task
'attempt_20100218022432445_0002_m_000002_0' to tip
task_20100218022432445_0002_m_000002, for tracker
'tracker_host0.foo.com:localhost/127.0.0.1:33015'
[junit] 10/02/18 02:25:15 INFO mapred.TaskTracker: LaunchTaskAction
(registerTask): attempt_20100218022432445_0002_m_000002_0 task's
state:UNASSIGNED
[junit] 10/02/18 02:25:15 INFO mapred.TaskTracker: Trying to launch :
attempt_20100218022432445_0002_m_000002_0
[junit] 10/02/18 02:25:15 INFO mapred.TaskTracker: In TaskLauncher, current
free slots : 2 and trying to launch attempt_20100218022432445_0002_m_000002_0
[junit] 10/02/18 02:25:15 INFO mapred.JvmManager: In JvmRunner constructed
JVM ID: jvm_20100218022432445_0002_m_-1362403705
[junit] 10/02/18 02:25:15 INFO mapred.JvmManager: JVM Runner
jvm_20100218022432445_0002_m_-1362403705 spawned.
[junit] 10/02/18 02:25:16 INFO mapReduceLayer.MapReduceLauncher: 50%
complete
[junit] 10/02/18 02:25:16 INFO mapred.TaskTracker: JVM with ID:
jvm_20100218022432445_0002_m_-1362403705 given task:
attempt_20100218022432445_0002_m_000002_0
[junit] 10/02/18 02:25:16 INFO mapred.TaskTracker:
attempt_20100218022432445_0002_m_000002_0 0.0%
[junit] 10/02/18 02:25:16 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete
src=/tmp/temp-1832912908/tmp-1615126932/_temporary dst=null
perm=null
[junit] 10/02/18 02:25:16 INFO mapred.TaskTracker:
attempt_20100218022432445_0002_m_000002_0 0.0% cleanup
[junit] 10/02/18 02:25:16 INFO mapred.TaskTracker: Task
attempt_20100218022432445_0002_m_000002_0 is done.
[junit] 10/02/18 02:25:16 INFO mapred.TaskTracker: reported output size for
attempt_20100218022432445_0002_m_000002_0 was 0
[junit] 10/02/18 02:25:16 INFO mapred.TaskTracker: addFreeSlot : current
free slots : 2
[junit] 10/02/18 02:25:17 INFO mapred.JvmManager: JVM :
jvm_20100218022432445_0002_m_-1362403705 exited. Number of tasks it ran: 1
[junit] 10/02/18 02:25:18 INFO mapred.TaskTracker:
org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find
taskTracker/jobcache/job_20100218022432445_0002/attempt_20100218022432445_0002_m_000002_0/output/file.out
in any of the configured local directories
[junit] 10/02/18 02:25:18 INFO mapred.JobInProgress: Task
'attempt_20100218022432445_0002_m_000002_0' has completed
task_20100218022432445_0002_m_000002 successfully.
[junit] 10/02/18 02:25:18 INFO mapred.JobInProgress: Job
job_20100218022432445_0002 has completed successfully.
[junit] 10/02/18 02:25:18 INFO hdfs.StateChange: BLOCK*
NameSystem.allocateBlock:
/tmp/temp-1832912908/tmp-1615126932/_logs/history/localhost_1266459872468_job_20100218022432445_0002_hudson_Job2769198517474778469.jar.
blk_-1334475862576068645_1019
[junit] 10/02/18 02:25:18 INFO datanode.DataNode: Receiving block
blk_-1334475862576068645_1019 src: /127.0.0.1:52144 dest: /127.0.0.1:40309
[junit] 10/02/18 02:25:18 INFO datanode.DataNode: Receiving block
blk_-1334475862576068645_1019 src: /127.0.0.1:43581 dest: /127.0.0.1:55726
[junit] 10/02/18 02:25:18 INFO datanode.DataNode: Receiving block
blk_-1334475862576068645_1019 src: /127.0.0.1:42230 dest: /127.0.0.1:41953
[junit] 10/02/18 02:25:18 INFO DataNode.clienttrace: src: /127.0.0.1:42230,
dest: /127.0.0.1:41953, bytes: 6117, op: HDFS_WRITE, cliID:
DFSClient_-1684024129, srvID: DS-458142337-127.0.1.1-41953-1266459872358,
blockid: blk_-1334475862576068645_1019
[junit] 10/02/18 02:25:18 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:41953 is added to
blk_-1334475862576068645_1019 size 6117
[junit] 10/02/18 02:25:18 INFO datanode.DataNode: PacketResponder 0 for
block blk_-1334475862576068645_1019 terminating
[junit] 10/02/18 02:25:18 INFO DataNode.clienttrace: src: /127.0.0.1:43581,
dest: /127.0.0.1:55726, bytes: 6117, op: HDFS_WRITE, cliID:
DFSClient_-1684024129, srvID: DS-1346704804-127.0.1.1-55726-1266459871874,
blockid: blk_-1334475862576068645_1019
[junit] 10/02/18 02:25:18 INFO datanode.DataNode: PacketResponder 1 for
block blk_-1334475862576068645_1019 terminating
[junit] 10/02/18 02:25:18 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:55726 is added to
blk_-1334475862576068645_1019 size 6117
[junit] 10/02/18 02:25:18 INFO DataNode.clienttrace: src: /127.0.0.1:52144,
dest: /127.0.0.1:40309, bytes: 6117, op: HDFS_WRITE, cliID:
DFSClient_-1684024129, srvID: DS-1948219082-127.0.1.1-40309-1266459871415,
blockid: blk_-1334475862576068645_1019
[junit] 10/02/18 02:25:18 INFO hdfs.StateChange: BLOCK*
NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:40309 is added to
blk_-1334475862576068645_1019 size 6117
[junit] 10/02/18 02:25:18 INFO datanode.DataNode: PacketResponder 2 for
block blk_-1334475862576068645_1019 terminating
[junit] 10/02/18 02:25:18 INFO hdfs.StateChange: DIR*
NameSystem.completeFile: file
/tmp/temp-1832912908/tmp-1615126932/_logs/history/localhost_1266459872468_job_20100218022432445_0002_hudson_Job2769198517474778469.jar
is closed by DFSClient_-1684024129
[junit] 10/02/18 02:25:18 INFO mapred.JobTracker: Removed completed task
'attempt_20100218022432445_0002_m_000001_0' from
'tracker_host0.foo.com:localhost/127.0.0.1:33015'
[junit] 10/02/18 02:25:18 INFO mapred.JobTracker: Removed completed task
'attempt_20100218022432445_0002_m_000002_0' from
'tracker_host0.foo.com:localhost/127.0.0.1:33015'
[junit] 10/02/18 02:25:18 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-7664397758800478618 is added to invalidSet of
127.0.0.1:41953
[junit] 10/02/18 02:25:18 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-7664397758800478618 is added to invalidSet of
127.0.0.1:56095
[junit] 10/02/18 02:25:18 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-7664397758800478618 is added to invalidSet of
127.0.0.1:55726
[junit] 10/02/18 02:25:18 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-4553958849651549543 is added to invalidSet of
127.0.0.1:40309
[junit] 10/02/18 02:25:18 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-4553958849651549543 is added to invalidSet of
127.0.0.1:56095
[junit] 10/02/18 02:25:18 INFO mapred.TaskTracker: Received 'KillJobAction'
for job: job_20100218022432445_0002
[junit] 10/02/18 02:25:18 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_-4553958849651549543 is added to invalidSet of
127.0.0.1:41953
[junit] 10/02/18 02:25:18 INFO mapred.TaskRunner:
attempt_20100218022432445_0002_m_000001_0 done; removing files.
[junit] 10/02/18 02:25:18 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_68013359739296494 is added to invalidSet of
127.0.0.1:40309
[junit] 10/02/18 02:25:18 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_68013359739296494 is added to invalidSet of
127.0.0.1:56095
[junit] 10/02/18 02:25:18 INFO hdfs.StateChange: BLOCK*
NameSystem.addToInvalidates: blk_68013359739296494 is added to invalidSet of
127.0.0.1:55726
[junit] 10/02/18 02:25:18 INFO mapred.IndexCache: Map ID
attempt_20100218022432445_0002_m_000001_0 not found in cache
[junit] 10/02/18 02:25:18 INFO mapred.TaskRunner:
attempt_20100218022432445_0002_m_000002_0 done; removing files.
[junit] 10/02/18 02:25:18 INFO mapred.IndexCache: Map ID
attempt_20100218022432445_0002_m_000002_0 not found in cache
[junit] 10/02/18 02:25:18 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=delete
src=/tmp/hadoop-hudson/mapred/system/job_20100218022432445_0002 dst=null
perm=null
[junit] 10/02/18 02:25:19 INFO mapred.TaskTracker: Received 'KillJobAction'
for job: job_20100218022432445_0002
[junit] 10/02/18 02:25:19 WARN mapred.TaskTracker: Unknown job
job_20100218022432445_0002 being deleted.
[junit] 10/02/18 02:25:19 INFO mapred.TaskTracker: Received 'KillJobAction'
for job: job_20100218022432445_0002
[junit] 10/02/18 02:25:19 WARN mapred.TaskTracker: Unknown job
job_20100218022432445_0002 being deleted.
[junit] 10/02/18 02:25:19 INFO mapReduceLayer.MapReduceLauncher: 100%
complete
[junit] 10/02/18 02:25:19 INFO mapReduceLayer.MapReduceLauncher:
Successfully stored result in:
"hdfs://localhost:52628/tmp/temp-1832912908/tmp-1615126932"
[junit] 10/02/18 02:25:19 INFO mapReduceLayer.MapReduceLauncher: Records
written : 2
[junit] 10/02/18 02:25:19 INFO mapReduceLayer.MapReduceLauncher: Bytes
written : 78
[junit] 10/02/18 02:25:19 INFO mapReduceLayer.MapReduceLauncher: Success!
[junit] 10/02/18 02:25:19 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=listStatus src=/tmp/temp-1832912908/tmp-1615126932
dst=null perm=null
[junit] 10/02/18 02:25:19 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/temp-1832912908/tmp-1615126932/part-00000 dst=null
perm=null
[junit] 10/02/18 02:25:19 INFO DataNode.clienttrace: src: /127.0.0.1:41953,
dest: /127.0.0.1:42231, bytes: 43, op: HDFS_READ, cliID: DFSClient_-1684024129,
srvID: DS-458142337-127.0.1.1-41953-1266459872358, blockid:
blk_2112989940693637917_1018
[junit] 10/02/18 02:25:19 INFO FSNamesystem.audit: ugi=hudson,hudson
ip=/127.0.0.1 cmd=open
src=/tmp/temp-1832912908/tmp-1615126932/part-00001 dst=null
perm=null
[junit] 10/02/18 02:25:19 INFO DataNode.clienttrace: src: /127.0.0.1:55726,
dest: /127.0.0.1:43584, bytes: 43, op: HDFS_READ, cliID: DFSClient_-1684024129,
srvID: DS-1346704804-127.0.1.1-55726-1266459871874, blockid:
blk_6324400971070706047_1019
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 44.602 sec
BUILD FAILED
<http://hudson.zones.apache.org/hudson/job/Pig-trunk/ws/trunk/build.xml>:560:
The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/Pig-trunk/ws/trunk/build.xml>:493:
The following error occurred while executing this line:
<http://hudson.zones.apache.org/hudson/job/Pig-trunk/ws/trunk/build.xml>:555:
Tests failed!
Total time: 254 minutes 58 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
