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