See <http://hudson.zones.apache.org/hudson/job/Pig-trunk/580/changes>
Changes: [pradeepkth] PERFORMANCE: multi-query optimization on multiple group bys following a join or cogroup (rding via pradeepkth) ------------------------------------------ [...truncated 167006 lines...] [junit] 09/10/07 01:16:28 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:60366 is added to blk_6792183205926187173_1014 size 1859 [junit] 09/10/07 01:16:28 INFO datanode.DataNode: PacketResponder 2 for block blk_6792183205926187173_1014 terminating [junit] 09/10/07 01:16:28 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:51295 is added to blk_6792183205926187173_1014 size 1859 [junit] 09/10/07 01:16:28 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/hadoop-hudson/mapred/system/job_20091007011555276_0002/job.split is closed by DFSClient_-1468843592 [junit] 09/10/07 01:16:28 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=create src=/tmp/hadoop-hudson/mapred/system/job_20091007011555276_0002/job.xml dst=null perm=hudson:supergroup:rw-r--r-- [junit] 09/10/07 01:16:28 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/tmp/hadoop-hudson/mapred/system/job_20091007011555276_0002/job.xml dst=null perm=hudson:supergroup:rw-r--r-- [junit] 09/10/07 01:16:28 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/hadoop-hudson/mapred/system/job_20091007011555276_0002/job.xml. blk_-6478926164587938265_1015 [junit] 09/10/07 01:16:28 INFO datanode.DataNode: Receiving block blk_-6478926164587938265_1015 src: /127.0.0.1:34216 dest: /127.0.0.1:59951 [junit] 09/10/07 01:16:28 INFO datanode.DataNode: Receiving block blk_-6478926164587938265_1015 src: /127.0.0.1:35478 dest: /127.0.0.1:51295 [junit] 09/10/07 01:16:28 INFO datanode.DataNode: Receiving block blk_-6478926164587938265_1015 src: /127.0.0.1:45552 dest: /127.0.0.1:49650 [junit] 09/10/07 01:16:28 INFO DataNode.clienttrace: src: /127.0.0.1:45552, dest: /127.0.0.1:49650, bytes: 48254, op: HDFS_WRITE, cliID: DFSClient_-1468843592, srvID: DS-1821165369-127.0.1.1-49650-1254878155200, blockid: blk_-6478926164587938265_1015 [junit] 09/10/07 01:16:28 INFO datanode.DataNode: PacketResponder 0 for block blk_-6478926164587938265_1015 terminating [junit] 09/10/07 01:16:28 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:49650 is added to blk_-6478926164587938265_1015 size 48254 [junit] 09/10/07 01:16:28 INFO DataNode.clienttrace: src: /127.0.0.1:35478, dest: /127.0.0.1:51295, bytes: 48254, op: HDFS_WRITE, cliID: DFSClient_-1468843592, srvID: DS-1845303905-127.0.1.1-51295-1254878153423, blockid: blk_-6478926164587938265_1015 [junit] 09/10/07 01:16:28 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:51295 is added to blk_-6478926164587938265_1015 size 48254 [junit] 09/10/07 01:16:28 INFO datanode.DataNode: PacketResponder 1 for block blk_-6478926164587938265_1015 terminating [junit] 09/10/07 01:16:28 INFO DataNode.clienttrace: src: /127.0.0.1:34216, dest: /127.0.0.1:59951, bytes: 48254, op: HDFS_WRITE, cliID: DFSClient_-1468843592, srvID: DS-632073239-127.0.1.1-59951-1254878154621, blockid: blk_-6478926164587938265_1015 [junit] 09/10/07 01:16:28 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:59951 is added to blk_-6478926164587938265_1015 size 48254 [junit] 09/10/07 01:16:28 INFO datanode.DataNode: PacketResponder 2 for block blk_-6478926164587938265_1015 terminating [junit] 09/10/07 01:16:28 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/hadoop-hudson/mapred/system/job_20091007011555276_0002/job.xml is closed by DFSClient_-1468843592 [junit] 09/10/07 01:16:28 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/hadoop-hudson/mapred/system/job_20091007011555276_0002/job.xml dst=null perm=null [junit] 09/10/07 01:16:28 INFO DataNode.clienttrace: src: /127.0.0.1:59951, dest: /127.0.0.1:34219, bytes: 48634, op: HDFS_READ, cliID: DFSClient_-1468843592, srvID: DS-632073239-127.0.1.1-59951-1254878154621, blockid: blk_-6478926164587938265_1015 [junit] 09/10/07 01:16:28 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/hadoop-hudson/mapred/system/job_20091007011555276_0002/job.jar dst=null perm=null [junit] 09/10/07 01:16:28 INFO DataNode.clienttrace: src: /127.0.0.1:49650, dest: /127.0.0.1:45554, bytes: 2482874, op: HDFS_READ, cliID: DFSClient_-1468843592, srvID: DS-1821165369-127.0.1.1-49650-1254878155200, blockid: blk_590227262299005753_1013 [junit] 09/10/07 01:16:28 INFO mapred.JobTracker: Initializing job_20091007011555276_0002 [junit] 09/10/07 01:16:28 INFO mapred.JobInProgress: Initializing job_20091007011555276_0002 [junit] 09/10/07 01:16:28 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=create src=/tmp/temp1186025101/tmp-1903547126/_logs/history/localhost_1254878155299_job_20091007011555276_0002_hudson_Job5315036418806591989.jar dst=null perm=hudson:supergroup:rw-r--r-- [junit] 09/10/07 01:16:28 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=create src=/tmp/temp1186025101/tmp-1903547126/_logs/history/localhost_1254878155299_job_20091007011555276_0002_conf.xml dst=null perm=hudson:supergroup:rw-r--r-- [junit] 09/10/07 01:16:28 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp1186025101/tmp-1903547126/_logs/history/localhost_1254878155299_job_20091007011555276_0002_conf.xml. blk_6973206901898089780_1017 [junit] 09/10/07 01:16:28 INFO datanode.DataNode: Receiving block blk_6973206901898089780_1017 src: /127.0.0.1:35482 dest: /127.0.0.1:51295 [junit] 09/10/07 01:16:28 INFO datanode.DataNode: Receiving block blk_6973206901898089780_1017 src: /127.0.0.1:51588 dest: /127.0.0.1:60366 [junit] 09/10/07 01:16:28 INFO datanode.DataNode: Receiving block blk_6973206901898089780_1017 src: /127.0.0.1:45557 dest: /127.0.0.1:49650 [junit] 09/10/07 01:16:28 INFO DataNode.clienttrace: src: /127.0.0.1:45557, dest: /127.0.0.1:49650, bytes: 48284, op: HDFS_WRITE, cliID: DFSClient_-1468843592, srvID: DS-1821165369-127.0.1.1-49650-1254878155200, blockid: blk_6973206901898089780_1017 [junit] 09/10/07 01:16:28 INFO datanode.DataNode: PacketResponder 0 for block blk_6973206901898089780_1017 terminating [junit] 09/10/07 01:16:28 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:49650 is added to blk_6973206901898089780_1017 size 48284 [junit] 09/10/07 01:16:28 INFO DataNode.clienttrace: src: /127.0.0.1:51588, dest: /127.0.0.1:60366, bytes: 48284, op: HDFS_WRITE, cliID: DFSClient_-1468843592, srvID: DS-86156232-127.0.1.1-60366-1254878154028, blockid: blk_6973206901898089780_1017 [junit] 09/10/07 01:16:28 INFO datanode.DataNode: PacketResponder 1 for block blk_6973206901898089780_1017 terminating [junit] 09/10/07 01:16:28 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:60366 is added to blk_6973206901898089780_1017 size 48284 [junit] 09/10/07 01:16:28 INFO DataNode.clienttrace: src: /127.0.0.1:35482, dest: /127.0.0.1:51295, bytes: 48284, op: HDFS_WRITE, cliID: DFSClient_-1468843592, srvID: DS-1845303905-127.0.1.1-51295-1254878153423, blockid: blk_6973206901898089780_1017 [junit] 09/10/07 01:16:28 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:51295 is added to blk_6973206901898089780_1017 size 48284 [junit] 09/10/07 01:16:28 INFO datanode.DataNode: PacketResponder 2 for block blk_6973206901898089780_1017 terminating [junit] 09/10/07 01:16:28 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp1186025101/tmp-1903547126/_logs/history/localhost_1254878155299_job_20091007011555276_0002_conf.xml is closed by DFSClient_-1468843592 [junit] 09/10/07 01:16:28 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/hadoop-hudson/mapred/system/job_20091007011555276_0002/job.split dst=null perm=null [junit] 09/10/07 01:16:28 INFO DataNode.clienttrace: src: /127.0.0.1:60366, dest: /127.0.0.1:51590, bytes: 1875, op: HDFS_READ, cliID: DFSClient_-1468843592, srvID: DS-86156232-127.0.1.1-60366-1254878154028, blockid: blk_6792183205926187173_1014 [junit] 09/10/07 01:16:28 INFO mapred.JobInProgress: Input size for job job_20091007011555276_0002 = 12. Number of splits = 2 [junit] 09/10/07 01:16:28 INFO mapred.JobInProgress: tip:task_20091007011555276_0002_m_000000 has split on node:/default-rack/h7.grid.sp2.yahoo.net [junit] 09/10/07 01:16:28 INFO mapred.JobInProgress: tip:task_20091007011555276_0002_m_000001 has split on node:/default-rack/h7.grid.sp2.yahoo.net [junit] 09/10/07 01:16:29 INFO mapReduceLayer.MapReduceLauncher: Submitting job: job_20091007011555276_0002 to execution engine. [junit] 09/10/07 01:16:29 INFO mapReduceLayer.MapReduceLauncher: More information at: http://localhost:52492/jobdetails.jsp?jobid=job_20091007011555276_0002 [junit] 09/10/07 01:16:29 INFO mapReduceLayer.MapReduceLauncher: To kill this job, use: kill job_20091007011555276_0002 [junit] 09/10/07 01:16:29 INFO mapReduceLayer.MapReduceLauncher: 0% complete [junit] 09/10/07 01:16:29 INFO mapred.JobTracker: Adding task 'attempt_20091007011555276_0002_m_000003_0' to tip task_20091007011555276_0002_m_000003, for tracker 'tracker_host0.foo.com:localhost/127.0.0.1:46963' [junit] 09/10/07 01:16:29 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20091007011555276_0002_m_000003_0 task's state:UNASSIGNED [junit] 09/10/07 01:16:29 INFO mapred.TaskTracker: Trying to launch : attempt_20091007011555276_0002_m_000003_0 [junit] 09/10/07 01:16:29 INFO mapred.TaskTracker: In TaskLauncher, current free slots : 2 and trying to launch attempt_20091007011555276_0002_m_000003_0 [junit] 09/10/07 01:16:29 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/hadoop-hudson/mapred/system/job_20091007011555276_0002/job.xml dst=null perm=null [junit] 09/10/07 01:16:29 INFO DataNode.clienttrace: src: /127.0.0.1:59951, dest: /127.0.0.1:34225, bytes: 48634, op: HDFS_READ, cliID: DFSClient_-1468843592, srvID: DS-632073239-127.0.1.1-59951-1254878154621, blockid: blk_-6478926164587938265_1015 [junit] 09/10/07 01:16:29 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/hadoop-hudson/mapred/system/job_20091007011555276_0002/job.jar dst=null perm=null [junit] 09/10/07 01:16:29 INFO DataNode.clienttrace: src: /127.0.0.1:51295, dest: /127.0.0.1:35487, bytes: 2482874, op: HDFS_READ, cliID: DFSClient_-1468843592, srvID: DS-1845303905-127.0.1.1-51295-1254878153423, blockid: blk_590227262299005753_1013 [junit] 09/10/07 01:16:30 INFO datanode.DataNode: Deleting block blk_-2672158033425783055_1005 file build/test/data/dfs/data/data3/current/blk_-2672158033425783055 [junit] 09/10/07 01:16:30 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20091007011555276_0002_m_306870518 [junit] 09/10/07 01:16:30 INFO mapred.JvmManager: JVM Runner jvm_20091007011555276_0002_m_306870518 spawned. [junit] 09/10/07 01:16:30 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:49650 to delete blk_590227262299005753_1013 [junit] 09/10/07 01:16:30 INFO mapred.TaskTracker: JVM with ID: jvm_20091007011555276_0002_m_306870518 given task: attempt_20091007011555276_0002_m_000003_0 [junit] 09/10/07 01:16:31 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=mkdirs src=/tmp/temp1186025101/tmp-1903547126/_temporary dst=null perm=hudson:supergroup:rwxr-xr-x [junit] 09/10/07 01:16:31 INFO mapred.TaskTracker: attempt_20091007011555276_0002_m_000003_0 0.0% setup [junit] 09/10/07 01:16:31 INFO mapred.TaskTracker: Task attempt_20091007011555276_0002_m_000003_0 is done. [junit] 09/10/07 01:16:31 INFO mapred.TaskTracker: reported output size for attempt_20091007011555276_0002_m_000003_0 was 0 [junit] 09/10/07 01:16:31 INFO mapred.TaskTracker: addFreeSlot : current free slots : 2 [junit] 09/10/07 01:16:31 INFO mapred.JvmManager: JVM : jvm_20091007011555276_0002_m_306870518 exited. Number of tasks it ran: 1 [junit] 09/10/07 01:16:31 INFO datanode.DataNode: Deleting block blk_590227262299005753_1013 file build/test/data/dfs/data/data8/current/blk_590227262299005753 [junit] 09/10/07 01:16:32 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find taskTracker/jobcache/job_20091007011555276_0002/attempt_20091007011555276_0002_m_000003_0/output/file.out in any of the configured local directories [junit] 09/10/07 01:16:32 INFO mapred.JobInProgress: Task 'attempt_20091007011555276_0002_m_000003_0' has completed task_20091007011555276_0002_m_000003 successfully. [junit] 09/10/07 01:16:32 INFO mapred.JobTracker: Adding task 'attempt_20091007011555276_0002_m_000000_0' to tip task_20091007011555276_0002_m_000000, for tracker 'tracker_host0.foo.com:localhost/127.0.0.1:46963' [junit] 09/10/07 01:16:32 INFO mapred.JobInProgress: Choosing rack-local task task_20091007011555276_0002_m_000000 [junit] 09/10/07 01:16:32 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20091007011555276_0002_m_000000_0 task's state:UNASSIGNED [junit] 09/10/07 01:16:32 INFO mapred.TaskTracker: Trying to launch : attempt_20091007011555276_0002_m_000000_0 [junit] 09/10/07 01:16:32 INFO mapred.TaskTracker: Received KillTaskAction for task: attempt_20091007011555276_0002_m_000003_0 [junit] 09/10/07 01:16:32 INFO mapred.TaskTracker: About to purge task: attempt_20091007011555276_0002_m_000003_0 [junit] 09/10/07 01:16:32 INFO mapred.TaskTracker: In TaskLauncher, current free slots : 2 and trying to launch attempt_20091007011555276_0002_m_000000_0 [junit] 09/10/07 01:16:32 INFO mapred.TaskRunner: attempt_20091007011555276_0002_m_000003_0 done; removing files. [junit] 09/10/07 01:16:32 INFO mapred.IndexCache: Map ID attempt_20091007011555276_0002_m_000003_0 not found in cache [junit] 09/10/07 01:16:32 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20091007011555276_0002_m_718716239 [junit] 09/10/07 01:16:32 INFO mapred.JvmManager: JVM Runner jvm_20091007011555276_0002_m_718716239 spawned. [junit] 09/10/07 01:16:33 INFO mapred.JobTracker: Adding task 'attempt_20091007011555276_0002_m_000001_0' to tip task_20091007011555276_0002_m_000001, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:59956' [junit] 09/10/07 01:16:33 INFO mapred.JobInProgress: Choosing rack-local task task_20091007011555276_0002_m_000001 [junit] 09/10/07 01:16:33 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20091007011555276_0002_m_000001_0 task's state:UNASSIGNED [junit] 09/10/07 01:16:33 INFO mapred.TaskTracker: Trying to launch : attempt_20091007011555276_0002_m_000001_0 [junit] 09/10/07 01:16:33 INFO mapred.TaskTracker: In TaskLauncher, current free slots : 2 and trying to launch attempt_20091007011555276_0002_m_000001_0 [junit] 09/10/07 01:16:33 INFO mapred.TaskTracker: JVM with ID: jvm_20091007011555276_0002_m_718716239 given task: attempt_20091007011555276_0002_m_000000_0 [junit] 09/10/07 01:16:33 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/hadoop-hudson/mapred/system/job_20091007011555276_0002/job.xml dst=null perm=null [junit] 09/10/07 01:16:33 INFO DataNode.clienttrace: src: /127.0.0.1:59951, dest: /127.0.0.1:34235, bytes: 48634, op: HDFS_READ, cliID: DFSClient_-1468843592, srvID: DS-632073239-127.0.1.1-59951-1254878154621, blockid: blk_-6478926164587938265_1015 [junit] 09/10/07 01:16:33 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/hadoop-hudson/mapred/system/job_20091007011555276_0002/job.jar dst=null perm=null [junit] 09/10/07 01:16:33 INFO DataNode.clienttrace: src: /127.0.0.1:60366, dest: /127.0.0.1:51602, bytes: 2482874, op: HDFS_READ, cliID: DFSClient_-1468843592, srvID: DS-86156232-127.0.1.1-60366-1254878154028, blockid: blk_590227262299005753_1013 [junit] 09/10/07 01:16:33 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20091007011555276_0002_m_1177281530 [junit] 09/10/07 01:16:33 INFO mapred.JvmManager: JVM Runner jvm_20091007011555276_0002_m_1177281530 spawned. [junit] 09/10/07 01:16:33 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/user/hudson/input2.txt dst=null perm=null [junit] 09/10/07 01:16:33 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=create src=/tmp/temp1186025101/tmp-1903547126/_temporary/_attempt_20091007011555276_0002_m_000000_0/part-00000 dst=null perm=hudson:supergroup:rw-r--r-- [junit] 09/10/07 01:16:33 INFO DataNode.clienttrace: src: /127.0.0.1:49650, dest: /127.0.0.1:45573, bytes: 10, op: HDFS_READ, cliID: DFSClient_attempt_20091007011555276_0002_m_000000_0, srvID: DS-1821165369-127.0.1.1-49650-1254878155200, blockid: blk_1736505653576937991_1012 [junit] 09/10/07 01:16:33 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp1186025101/tmp-1903547126/_temporary/_attempt_20091007011555276_0002_m_000000_0/part-00000. blk_3008009106904142079_1018 [junit] 09/10/07 01:16:34 INFO datanode.DataNode: Receiving block blk_3008009106904142079_1018 src: /127.0.0.1:51606 dest: /127.0.0.1:60366 [junit] 09/10/07 01:16:34 INFO datanode.DataNode: Receiving block blk_3008009106904142079_1018 src: /127.0.0.1:35502 dest: /127.0.0.1:51295 [junit] 09/10/07 01:16:34 INFO datanode.DataNode: Receiving block blk_3008009106904142079_1018 src: /127.0.0.1:34242 dest: /127.0.0.1:59951 [junit] 09/10/07 01:16:34 INFO DataNode.clienttrace: src: /127.0.0.1:34242, dest: /127.0.0.1:59951, bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091007011555276_0002_m_000000_0, srvID: DS-632073239-127.0.1.1-59951-1254878154621, blockid: blk_3008009106904142079_1018 [junit] 09/10/07 01:16:34 INFO datanode.DataNode: PacketResponder 0 for block blk_3008009106904142079_1018 terminating [junit] 09/10/07 01:16:34 INFO DataNode.clienttrace: src: /127.0.0.1:35502, dest: /127.0.0.1:51295, bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091007011555276_0002_m_000000_0, srvID: DS-1845303905-127.0.1.1-51295-1254878153423, blockid: blk_3008009106904142079_1018 [junit] 09/10/07 01:16:34 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:59951 is added to blk_3008009106904142079_1018 size 39 [junit] 09/10/07 01:16:34 INFO datanode.DataNode: PacketResponder 1 for block blk_3008009106904142079_1018 terminating [junit] 09/10/07 01:16:34 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:51295 is added to blk_3008009106904142079_1018 size 39 [junit] 09/10/07 01:16:34 INFO DataNode.clienttrace: src: /127.0.0.1:51606, dest: /127.0.0.1:60366, bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091007011555276_0002_m_000000_0, srvID: DS-86156232-127.0.1.1-60366-1254878154028, blockid: blk_3008009106904142079_1018 [junit] 09/10/07 01:16:34 INFO datanode.DataNode: PacketResponder 2 for block blk_3008009106904142079_1018 terminating [junit] 09/10/07 01:16:34 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:60366 is added to blk_3008009106904142079_1018 size 39 [junit] 09/10/07 01:16:34 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp1186025101/tmp-1903547126/_temporary/_attempt_20091007011555276_0002_m_000000_0/part-00000 is closed by DFSClient_attempt_20091007011555276_0002_m_000000_0 [junit] 09/10/07 01:16:34 INFO mapred.TaskTracker: Task attempt_20091007011555276_0002_m_000000_0 is in commit-pending, task state:COMMIT_PENDING [junit] 09/10/07 01:16:34 INFO mapred.TaskTracker: attempt_20091007011555276_0002_m_000000_0 0.0% [junit] 09/10/07 01:16:34 INFO mapred.TaskTracker: JVM with ID: jvm_20091007011555276_0002_m_1177281530 given task: attempt_20091007011555276_0002_m_000001_0 [junit] 09/10/07 01:16:34 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/user/hudson/input1.txt dst=null perm=null [junit] 09/10/07 01:16:34 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=create src=/tmp/temp1186025101/tmp-1903547126/_temporary/_attempt_20091007011555276_0002_m_000001_0/part-00001 dst=null perm=hudson:supergroup:rw-r--r-- [junit] 09/10/07 01:16:34 INFO DataNode.clienttrace: src: /127.0.0.1:51295, dest: /127.0.0.1:35509, bytes: 10, op: HDFS_READ, cliID: DFSClient_attempt_20091007011555276_0002_m_000001_0, srvID: DS-1845303905-127.0.1.1-51295-1254878153423, blockid: blk_-7249088620562996186_1011 [junit] 09/10/07 01:16:34 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp1186025101/tmp-1903547126/_temporary/_attempt_20091007011555276_0002_m_000001_0/part-00001. blk_4240013041726573616_1019 [junit] 09/10/07 01:16:34 INFO datanode.DataNode: Receiving block blk_4240013041726573616_1019 src: /127.0.0.1:51615 dest: /127.0.0.1:60366 [junit] 09/10/07 01:16:34 INFO datanode.DataNode: Receiving block blk_4240013041726573616_1019 src: /127.0.0.1:35511 dest: /127.0.0.1:51295 [junit] 09/10/07 01:16:34 INFO datanode.DataNode: Receiving block blk_4240013041726573616_1019 src: /127.0.0.1:34251 dest: /127.0.0.1:59951 [junit] 09/10/07 01:16:34 INFO DataNode.clienttrace: src: /127.0.0.1:34251, dest: /127.0.0.1:59951, bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091007011555276_0002_m_000001_0, srvID: DS-632073239-127.0.1.1-59951-1254878154621, blockid: blk_4240013041726573616_1019 [junit] 09/10/07 01:16:34 INFO datanode.DataNode: PacketResponder 0 for block blk_4240013041726573616_1019 terminating [junit] 09/10/07 01:16:34 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:59951 is added to blk_4240013041726573616_1019 size 39 [junit] 09/10/07 01:16:34 INFO DataNode.clienttrace: src: /127.0.0.1:35511, dest: /127.0.0.1:51295, bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091007011555276_0002_m_000001_0, srvID: DS-1845303905-127.0.1.1-51295-1254878153423, blockid: blk_4240013041726573616_1019 [junit] 09/10/07 01:16:34 INFO datanode.DataNode: PacketResponder 1 for block blk_4240013041726573616_1019 terminating [junit] 09/10/07 01:16:34 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:51295 is added to blk_4240013041726573616_1019 size 39 [junit] 09/10/07 01:16:34 INFO DataNode.clienttrace: src: /127.0.0.1:51615, dest: /127.0.0.1:60366, bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20091007011555276_0002_m_000001_0, srvID: DS-86156232-127.0.1.1-60366-1254878154028, blockid: blk_4240013041726573616_1019 [junit] 09/10/07 01:16:34 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:60366 is added to blk_4240013041726573616_1019 size 39 [junit] 09/10/07 01:16:34 INFO datanode.DataNode: PacketResponder 2 for block blk_4240013041726573616_1019 terminating [junit] 09/10/07 01:16:34 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp1186025101/tmp-1903547126/_temporary/_attempt_20091007011555276_0002_m_000001_0/part-00001 is closed by DFSClient_attempt_20091007011555276_0002_m_000001_0 [junit] 09/10/07 01:16:34 INFO mapred.TaskTracker: Task attempt_20091007011555276_0002_m_000001_0 is in commit-pending, task state:COMMIT_PENDING [junit] 09/10/07 01:16:34 INFO mapred.TaskTracker: attempt_20091007011555276_0002_m_000001_0 0.0% [junit] 09/10/07 01:16:35 INFO mapred.TaskTracker: Received commit task action for attempt_20091007011555276_0002_m_000000_0 [junit] 09/10/07 01:16:36 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=listStatus src=/tmp/temp1186025101/tmp-1903547126/_temporary/_attempt_20091007011555276_0002_m_000000_0 dst=null perm=null [junit] 09/10/07 01:16:36 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=mkdirs src=/tmp/temp1186025101/tmp-1903547126 dst=null perm=hudson:supergroup:rwxr-xr-x [junit] 09/10/07 01:16:36 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=rename src=/tmp/temp1186025101/tmp-1903547126/_temporary/_attempt_20091007011555276_0002_m_000000_0/part-00000 dst=/tmp/temp1186025101/tmp-1903547126/part-00000 perm=hudson:supergroup:rw-r--r-- [junit] 09/10/07 01:16:36 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=delete src=/tmp/temp1186025101/tmp-1903547126/_temporary/_attempt_20091007011555276_0002_m_000000_0 dst=null perm=null [junit] 09/10/07 01:16:36 INFO mapred.TaskTracker: attempt_20091007011555276_0002_m_000000_0 1.0% [junit] 09/10/07 01:16:36 INFO mapred.TaskTracker: Task attempt_20091007011555276_0002_m_000000_0 is done. [junit] 09/10/07 01:16:36 INFO mapred.TaskTracker: reported output size for attempt_20091007011555276_0002_m_000000_0 was 0 [junit] 09/10/07 01:16:36 INFO mapred.TaskTracker: addFreeSlot : current free slots : 2 [junit] 09/10/07 01:16:36 INFO mapred.JvmManager: JVM : jvm_20091007011555276_0002_m_718716239 exited. Number of tasks it ran: 1 [junit] 09/10/07 01:16:36 INFO mapred.TaskTracker: Received commit task action for attempt_20091007011555276_0002_m_000001_0 [junit] 09/10/07 01:16:36 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=listStatus src=/tmp/temp1186025101/tmp-1903547126/_temporary/_attempt_20091007011555276_0002_m_000001_0 dst=null perm=null [junit] 09/10/07 01:16:36 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=mkdirs src=/tmp/temp1186025101/tmp-1903547126 dst=null perm=hudson:supergroup:rwxr-xr-x [junit] 09/10/07 01:16:36 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=rename src=/tmp/temp1186025101/tmp-1903547126/_temporary/_attempt_20091007011555276_0002_m_000001_0/part-00001 dst=/tmp/temp1186025101/tmp-1903547126/part-00001 perm=hudson:supergroup:rw-r--r-- [junit] 09/10/07 01:16:36 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=delete src=/tmp/temp1186025101/tmp-1903547126/_temporary/_attempt_20091007011555276_0002_m_000001_0 dst=null perm=null [junit] 09/10/07 01:16:36 INFO mapred.TaskTracker: attempt_20091007011555276_0002_m_000001_0 1.0% [junit] 09/10/07 01:16:36 INFO mapred.TaskTracker: Task attempt_20091007011555276_0002_m_000001_0 is done. [junit] 09/10/07 01:16:36 INFO mapred.TaskTracker: reported output size for attempt_20091007011555276_0002_m_000001_0 was 0 [junit] 09/10/07 01:16:36 INFO mapred.TaskTracker: addFreeSlot : current free slots : 2 [junit] 09/10/07 01:16:36 INFO mapred.JvmManager: JVM : jvm_20091007011555276_0002_m_1177281530 exited. Number of tasks it ran: 1 [junit] 09/10/07 01:16:38 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find taskTracker/jobcache/job_20091007011555276_0002/attempt_20091007011555276_0002_m_000000_0/output/file.out in any of the configured local directories [junit] 09/10/07 01:16:38 INFO mapred.JobInProgress: Task 'attempt_20091007011555276_0002_m_000000_0' has completed task_20091007011555276_0002_m_000000 successfully. [junit] 09/10/07 01:16:39 INFO mapReduceLayer.MapReduceLauncher: 25% complete [junit] 09/10/07 01:16:39 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find taskTracker/jobcache/job_20091007011555276_0002/attempt_20091007011555276_0002_m_000001_0/output/file.out in any of the configured local directories [junit] 09/10/07 01:16:39 INFO mapred.JobInProgress: Task 'attempt_20091007011555276_0002_m_000001_0' has completed task_20091007011555276_0002_m_000001 successfully. [junit] 09/10/07 01:16:39 INFO mapred.JobTracker: Adding task 'attempt_20091007011555276_0002_m_000002_0' to tip task_20091007011555276_0002_m_000002, for tracker 'tracker_host1.foo.com:localhost/127.0.0.1:59956' [junit] 09/10/07 01:16:39 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20091007011555276_0002_m_000002_0 task's state:UNASSIGNED [junit] 09/10/07 01:16:39 INFO mapred.TaskTracker: Trying to launch : attempt_20091007011555276_0002_m_000002_0 [junit] 09/10/07 01:16:39 INFO mapred.TaskTracker: In TaskLauncher, current free slots : 2 and trying to launch attempt_20091007011555276_0002_m_000002_0 [junit] 09/10/07 01:16:39 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20091007011555276_0002_m_-1708528427 [junit] 09/10/07 01:16:39 INFO mapred.JvmManager: JVM Runner jvm_20091007011555276_0002_m_-1708528427 spawned. [junit] 09/10/07 01:16:39 INFO mapReduceLayer.MapReduceLauncher: 50% complete [junit] 09/10/07 01:16:39 INFO mapred.TaskTracker: JVM with ID: jvm_20091007011555276_0002_m_-1708528427 given task: attempt_20091007011555276_0002_m_000002_0 [junit] 09/10/07 01:16:40 INFO mapred.TaskTracker: attempt_20091007011555276_0002_m_000002_0 0.0% [junit] 09/10/07 01:16:40 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=delete src=/tmp/temp1186025101/tmp-1903547126/_temporary dst=null perm=null [junit] 09/10/07 01:16:40 INFO mapred.TaskTracker: attempt_20091007011555276_0002_m_000002_0 0.0% cleanup [junit] 09/10/07 01:16:40 INFO mapred.TaskTracker: Task attempt_20091007011555276_0002_m_000002_0 is done. [junit] 09/10/07 01:16:40 INFO mapred.TaskTracker: reported output size for attempt_20091007011555276_0002_m_000002_0 was 0 [junit] 09/10/07 01:16:40 INFO mapred.TaskTracker: addFreeSlot : current free slots : 2 [junit] 09/10/07 01:16:40 INFO mapred.JvmManager: JVM : jvm_20091007011555276_0002_m_-1708528427 exited. Number of tasks it ran: 1 [junit] 09/10/07 01:16:42 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find taskTracker/jobcache/job_20091007011555276_0002/attempt_20091007011555276_0002_m_000002_0/output/file.out in any of the configured local directories [junit] 09/10/07 01:16:42 INFO mapred.JobInProgress: Task 'attempt_20091007011555276_0002_m_000002_0' has completed task_20091007011555276_0002_m_000002 successfully. [junit] 09/10/07 01:16:42 INFO mapred.JobInProgress: Job job_20091007011555276_0002 has completed successfully. [junit] 09/10/07 01:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp1186025101/tmp-1903547126/_logs/history/localhost_1254878155299_job_20091007011555276_0002_hudson_Job5315036418806591989.jar. blk_7119939735785681774_1019 [junit] 09/10/07 01:16:42 INFO datanode.DataNode: Receiving block blk_7119939735785681774_1019 src: /127.0.0.1:51623 dest: /127.0.0.1:60366 [junit] 09/10/07 01:16:42 INFO datanode.DataNode: Receiving block blk_7119939735785681774_1019 src: /127.0.0.1:35519 dest: /127.0.0.1:51295 [junit] 09/10/07 01:16:42 INFO datanode.DataNode: Receiving block blk_7119939735785681774_1019 src: /127.0.0.1:34259 dest: /127.0.0.1:59951 [junit] 09/10/07 01:16:42 INFO DataNode.clienttrace: src: /127.0.0.1:34259, dest: /127.0.0.1:59951, bytes: 6117, op: HDFS_WRITE, cliID: DFSClient_-1468843592, srvID: DS-632073239-127.0.1.1-59951-1254878154621, blockid: blk_7119939735785681774_1019 [junit] 09/10/07 01:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:59951 is added to blk_7119939735785681774_1019 size 6117 [junit] 09/10/07 01:16:42 INFO datanode.DataNode: PacketResponder 0 for block blk_7119939735785681774_1019 terminating [junit] 09/10/07 01:16:42 INFO DataNode.clienttrace: src: /127.0.0.1:35519, dest: /127.0.0.1:51295, bytes: 6117, op: HDFS_WRITE, cliID: DFSClient_-1468843592, srvID: DS-1845303905-127.0.1.1-51295-1254878153423, blockid: blk_7119939735785681774_1019 [junit] 09/10/07 01:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:51295 is added to blk_7119939735785681774_1019 size 6117 [junit] 09/10/07 01:16:42 INFO datanode.DataNode: PacketResponder 1 for block blk_7119939735785681774_1019 terminating [junit] 09/10/07 01:16:42 INFO DataNode.clienttrace: src: /127.0.0.1:51623, dest: /127.0.0.1:60366, bytes: 6117, op: HDFS_WRITE, cliID: DFSClient_-1468843592, srvID: DS-86156232-127.0.1.1-60366-1254878154028, blockid: blk_7119939735785681774_1019 [junit] 09/10/07 01:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:60366 is added to blk_7119939735785681774_1019 size 6117 [junit] 09/10/07 01:16:42 INFO datanode.DataNode: PacketResponder 2 for block blk_7119939735785681774_1019 terminating [junit] 09/10/07 01:16:42 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp1186025101/tmp-1903547126/_logs/history/localhost_1254878155299_job_20091007011555276_0002_hudson_Job5315036418806591989.jar is closed by DFSClient_-1468843592 [junit] 09/10/07 01:16:42 INFO mapred.JobTracker: Removed completed task 'attempt_20091007011555276_0002_m_000001_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:59956' [junit] 09/10/07 01:16:42 INFO mapred.JobTracker: Removed completed task 'attempt_20091007011555276_0002_m_000002_0' from 'tracker_host1.foo.com:localhost/127.0.0.1:59956' [junit] 09/10/07 01:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_590227262299005753 is added to invalidSet of 127.0.0.1:49650 [junit] 09/10/07 01:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_590227262299005753 is added to invalidSet of 127.0.0.1:51295 [junit] 09/10/07 01:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_590227262299005753 is added to invalidSet of 127.0.0.1:60366 [junit] 09/10/07 01:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_6792183205926187173 is added to invalidSet of 127.0.0.1:59951 [junit] 09/10/07 01:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_6792183205926187173 is added to invalidSet of 127.0.0.1:60366 [junit] 09/10/07 01:16:42 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20091007011555276_0002 [junit] 09/10/07 01:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_6792183205926187173 is added to invalidSet of 127.0.0.1:51295 [junit] 09/10/07 01:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-6478926164587938265 is added to invalidSet of 127.0.0.1:49650 [junit] 09/10/07 01:16:42 INFO mapred.TaskRunner: attempt_20091007011555276_0002_m_000001_0 done; removing files. [junit] 09/10/07 01:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-6478926164587938265 is added to invalidSet of 127.0.0.1:51295 [junit] 09/10/07 01:16:42 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-6478926164587938265 is added to invalidSet of 127.0.0.1:59951 [junit] 09/10/07 01:16:42 INFO mapred.IndexCache: Map ID attempt_20091007011555276_0002_m_000001_0 not found in cache [junit] 09/10/07 01:16:42 INFO mapred.TaskRunner: attempt_20091007011555276_0002_m_000002_0 done; removing files. [junit] 09/10/07 01:16:42 INFO mapred.IndexCache: Map ID attempt_20091007011555276_0002_m_000002_0 not found in cache [junit] 09/10/07 01:16:42 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=delete src=/tmp/hadoop-hudson/mapred/system/job_20091007011555276_0002 dst=null perm=null [junit] 09/10/07 01:16:42 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:49650 to delete blk_-6478926164587938265_1015 blk_590227262299005753_1013 [junit] 09/10/07 01:16:42 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:51295 to delete blk_6792183205926187173_1014 blk_-6478926164587938265_1015 blk_590227262299005753_1013 [junit] 09/10/07 01:16:42 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20091007011555276_0002 [junit] 09/10/07 01:16:42 WARN mapred.TaskTracker: Unknown job job_20091007011555276_0002 being deleted. [junit] 09/10/07 01:16:43 INFO datanode.DataNode: Deleting block blk_-6478926164587938265_1015 file build/test/data/dfs/data/data7/current/blk_-6478926164587938265 [junit] 09/10/07 01:16:43 WARN datanode.DataNode: Unexpected error trying to delete block blk_590227262299005753_1013. BlockInfo not found in volumeMap. [junit] 09/10/07 01:16:43 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] 09/10/07 01:16:43 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20091007011555276_0002 [junit] 09/10/07 01:16:43 WARN mapred.TaskTracker: Unknown job job_20091007011555276_0002 being deleted. [junit] 09/10/07 01:16:43 INFO mapReduceLayer.MapReduceLauncher: 100% complete [junit] 09/10/07 01:16:43 INFO mapReduceLayer.MapReduceLauncher: Successfully stored result in: "hdfs://localhost:52958/tmp/temp1186025101/tmp-1903547126" [junit] 09/10/07 01:16:43 INFO mapReduceLayer.MapReduceLauncher: Records written : 2 [junit] 09/10/07 01:16:43 INFO mapReduceLayer.MapReduceLauncher: Bytes written : 78 [junit] 09/10/07 01:16:43 INFO mapReduceLayer.MapReduceLauncher: Success! [junit] 09/10/07 01:16:43 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=listStatus src=/tmp/temp1186025101/tmp-1903547126 dst=null perm=null [junit] 09/10/07 01:16:43 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/temp1186025101/tmp-1903547126/part-00000 dst=null perm=null [junit] 09/10/07 01:16:43 INFO DataNode.clienttrace: src: /127.0.0.1:60366, dest: /127.0.0.1:51626, bytes: 43, op: HDFS_READ, cliID: DFSClient_-1468843592, srvID: DS-86156232-127.0.1.1-60366-1254878154028, blockid: blk_3008009106904142079_1018 [junit] 09/10/07 01:16:43 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/temp1186025101/tmp-1903547126/part-00001 dst=null perm=null [junit] 09/10/07 01:16:43 INFO DataNode.clienttrace: src: /127.0.0.1:59951, dest: /127.0.0.1:34261, bytes: 43, op: HDFS_READ, cliID: DFSClient_-1468843592, srvID: DS-632073239-127.0.1.1-59951-1254878154621, blockid: blk_4240013041726573616_1019 [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 45.306 sec BUILD FAILED <http://hudson.zones.apache.org/hudson/job/Pig-trunk/ws/trunk/build.xml>:494: The following error occurred while executing this line: <http://hudson.zones.apache.org/hudson/job/Pig-trunk/ws/trunk/build.xml>:428: The following error occurred while executing this line: <http://hudson.zones.apache.org/hudson/job/Pig-trunk/ws/trunk/build.xml>:489: Tests failed! Total time: 174 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