Return-Path: Delivered-To: apmail-hadoop-pig-dev-archive@www.apache.org Received: (qmail 4732 invoked from network); 22 Jan 2010 14:24:04 -0000 Received: from hermes.apache.org (HELO mail.apache.org) (140.211.11.3) by minotaur.apache.org with SMTP; 22 Jan 2010 14:24:04 -0000 Received: (qmail 58977 invoked by uid 500); 22 Jan 2010 14:24:04 -0000 Delivered-To: apmail-hadoop-pig-dev-archive@hadoop.apache.org Received: (qmail 58939 invoked by uid 500); 22 Jan 2010 14:24:03 -0000 Mailing-List: contact pig-dev-help@hadoop.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: pig-dev@hadoop.apache.org Delivered-To: mailing list pig-dev@hadoop.apache.org Received: (qmail 58929 invoked by uid 99); 22 Jan 2010 14:24:03 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 22 Jan 2010 14:24:03 +0000 X-ASF-Spam-Status: No, hits=-1998.5 required=10.0 tests=ALL_TRUSTED,WEIRD_PORT X-Spam-Check-By: apache.org Received: from [140.211.11.106] (HELO hudson.zones.apache.org) (140.211.11.106) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 22 Jan 2010 14:23:53 +0000 Received: from hudson.zones.apache.org (localhost [127.0.0.1]) by hudson.zones.apache.org (8.13.8+Sun/8.13.8) with ESMTP id o0MENUXJ013539 for ; Fri, 22 Jan 2010 09:23:30 -0500 (EST) Date: Fri, 22 Jan 2010 14:22:59 +0000 (UTC) From: Apache Hudson Server To: pig-dev@hadoop.apache.org Message-ID: <5151231.2661264170210543.JavaMail.hudson@hudson.zones.apache.org> In-Reply-To: <320849.2411264127318036.JavaMail.hudson@hudson.zones.apache.org> References: <320849.2411264127318036.JavaMail.hudson@hudson.zones.apache.org> Subject: Build failed in Hudson: Pig-trunk #669 MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Transfer-Encoding: 7bit See Changes: [daijy] PIG-1184: PruneColumns optimization does not handle the case of foreach flatten correctly if flattened bag is not used later [olga] PIG-1192: Pig 0.6 Docs fixes (chandec via olgan) ------------------------------------------ [...truncated 238840 lines...] [junit] 10/01/22 14:20:34 INFO DataNode.clienttrace: src: /127.0.0.1:35986, dest: /127.0.0.1:38103, bytes: 1589, op: HDFS_WRITE, cliID: DFSClient_-1427623467, srvID: DS-1463347349-127.0.1.1-38103-1264170000919, blockid: blk_-4519883474485258605_1014 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: PacketResponder 2 for block blk_-4519883474485258605_1014 terminating [junit] 10/01/22 14:20:34 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:38103 is added to blk_-4519883474485258605_1014 size 1589 [junit] 10/01/22 14:20:34 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/hadoop-hudson/mapred/system/job_20100122142002425_0002/job.split is closed by DFSClient_-1427623467 [junit] 10/01/22 14:20:34 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=create src=/tmp/hadoop-hudson/mapred/system/job_20100122142002425_0002/job.xml dst=null perm=hudson:supergroup:rw-r--r-- [junit] 10/01/22 14:20:34 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=setPermission src=/tmp/hadoop-hudson/mapred/system/job_20100122142002425_0002/job.xml dst=null perm=hudson:supergroup:rw-r--r-- [junit] 10/01/22 14:20:34 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/hadoop-hudson/mapred/system/job_20100122142002425_0002/job.xml. blk_-8373979260215840694_1015 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: Receiving block blk_-8373979260215840694_1015 src: /127.0.0.1:35989 dest: /127.0.0.1:38103 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: Receiving block blk_-8373979260215840694_1015 src: /127.0.0.1:41259 dest: /127.0.0.1:38080 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: Receiving block blk_-8373979260215840694_1015 src: /127.0.0.1:47941 dest: /127.0.0.1:36031 [junit] 10/01/22 14:20:34 INFO DataNode.clienttrace: src: /127.0.0.1:47941, dest: /127.0.0.1:36031, bytes: 48822, op: HDFS_WRITE, cliID: DFSClient_-1427623467, srvID: DS-575681396-127.0.1.1-36031-1264170001864, blockid: blk_-8373979260215840694_1015 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: PacketResponder 0 for block blk_-8373979260215840694_1015 terminating [junit] 10/01/22 14:20:34 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:36031 is added to blk_-8373979260215840694_1015 size 48822 [junit] 10/01/22 14:20:34 INFO DataNode.clienttrace: src: /127.0.0.1:41259, dest: /127.0.0.1:38080, bytes: 48822, op: HDFS_WRITE, cliID: DFSClient_-1427623467, srvID: DS-1992836685-127.0.1.1-38080-1264170001426, blockid: blk_-8373979260215840694_1015 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: PacketResponder 1 for block blk_-8373979260215840694_1015 terminating [junit] 10/01/22 14:20:34 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:38080 is added to blk_-8373979260215840694_1015 size 48822 [junit] 10/01/22 14:20:34 INFO DataNode.clienttrace: src: /127.0.0.1:35989, dest: /127.0.0.1:38103, bytes: 48822, op: HDFS_WRITE, cliID: DFSClient_-1427623467, srvID: DS-1463347349-127.0.1.1-38103-1264170000919, blockid: blk_-8373979260215840694_1015 [junit] 10/01/22 14:20:34 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:38103 is added to blk_-8373979260215840694_1015 size 48822 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: PacketResponder 2 for block blk_-8373979260215840694_1015 terminating [junit] 10/01/22 14:20:34 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/hadoop-hudson/mapred/system/job_20100122142002425_0002/job.xml is closed by DFSClient_-1427623467 [junit] 10/01/22 14:20:34 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/hadoop-hudson/mapred/system/job_20100122142002425_0002/job.xml dst=null perm=null [junit] 10/01/22 14:20:34 INFO DataNode.clienttrace: src: /127.0.0.1:38103, dest: /127.0.0.1:35992, bytes: 49206, op: HDFS_READ, cliID: DFSClient_-1427623467, srvID: DS-1463347349-127.0.1.1-38103-1264170000919, blockid: blk_-8373979260215840694_1015 [junit] 10/01/22 14:20:34 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/hadoop-hudson/mapred/system/job_20100122142002425_0002/job.jar dst=null perm=null [junit] 10/01/22 14:20:34 INFO DataNode.clienttrace: src: /127.0.0.1:38103, dest: /127.0.0.1:35993, bytes: 2742642, op: HDFS_READ, cliID: DFSClient_-1427623467, srvID: DS-1463347349-127.0.1.1-38103-1264170000919, blockid: blk_3903073668373300337_1013 [junit] 10/01/22 14:20:34 INFO mapred.JobTracker: Initializing job_20100122142002425_0002 [junit] 10/01/22 14:20:34 INFO mapred.JobInProgress: Initializing job_20100122142002425_0002 [junit] 10/01/22 14:20:34 INFO datanode.DataBlockScanner: Verification succeeded for blk_-3685041014394738560_1011 [junit] 10/01/22 14:20:34 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=create src=/tmp/temp-406619199/tmp-961107684/_logs/history/localhost_1264170002449_job_20100122142002425_0002_hudson_Job8574906685669331476.jar dst=null perm=hudson:supergroup:rw-r--r-- [junit] 10/01/22 14:20:34 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=create src=/tmp/temp-406619199/tmp-961107684/_logs/history/localhost_1264170002449_job_20100122142002425_0002_conf.xml dst=null perm=hudson:supergroup:rw-r--r-- [junit] 10/01/22 14:20:34 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp-406619199/tmp-961107684/_logs/history/localhost_1264170002449_job_20100122142002425_0002_conf.xml. blk_8298733218035429289_1017 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: Receiving block blk_8298733218035429289_1017 src: /127.0.0.1:34046 dest: /127.0.0.1:45314 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: Deleting block blk_6760959224182101687_1007 file build/test/data/dfs/data/data3/current/blk_6760959224182101687 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: Deleting block blk_8009935584258537881_1006 file build/test/data/dfs/data/data4/current/blk_8009935584258537881 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: Receiving block blk_8298733218035429289_1017 src: /127.0.0.1:47945 dest: /127.0.0.1:36031 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: Receiving block blk_8298733218035429289_1017 src: /127.0.0.1:35996 dest: /127.0.0.1:38103 [junit] 10/01/22 14:20:34 INFO DataNode.clienttrace: src: /127.0.0.1:35996, dest: /127.0.0.1:38103, bytes: 48852, op: HDFS_WRITE, cliID: DFSClient_-1427623467, srvID: DS-1463347349-127.0.1.1-38103-1264170000919, blockid: blk_8298733218035429289_1017 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: PacketResponder 0 for block blk_8298733218035429289_1017 terminating [junit] 10/01/22 14:20:34 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:38103 is added to blk_8298733218035429289_1017 size 48852 [junit] 10/01/22 14:20:34 INFO DataNode.clienttrace: src: /127.0.0.1:47945, dest: /127.0.0.1:36031, bytes: 48852, op: HDFS_WRITE, cliID: DFSClient_-1427623467, srvID: DS-575681396-127.0.1.1-36031-1264170001864, blockid: blk_8298733218035429289_1017 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: PacketResponder 1 for block blk_8298733218035429289_1017 terminating [junit] 10/01/22 14:20:34 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:36031 is added to blk_8298733218035429289_1017 size 48852 [junit] 10/01/22 14:20:34 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:45314 is added to blk_8298733218035429289_1017 size 48852 [junit] 10/01/22 14:20:34 INFO DataNode.clienttrace: src: /127.0.0.1:34046, dest: /127.0.0.1:45314, bytes: 48852, op: HDFS_WRITE, cliID: DFSClient_-1427623467, srvID: DS-1167207178-127.0.1.1-45314-1264170002336, blockid: blk_8298733218035429289_1017 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: PacketResponder 2 for block blk_8298733218035429289_1017 terminating [junit] 10/01/22 14:20:34 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp-406619199/tmp-961107684/_logs/history/localhost_1264170002449_job_20100122142002425_0002_conf.xml is closed by DFSClient_-1427623467 [junit] 10/01/22 14:20:34 INFO mapReduceLayer.MapReduceLauncher: Submitting job: job_20100122142002425_0002 to execution engine. [junit] 10/01/22 14:20:34 INFO mapReduceLayer.MapReduceLauncher: More information at: http://localhost:59794/jobdetails.jsp?jobid=job_20100122142002425_0002 [junit] 10/01/22 14:20:34 INFO mapReduceLayer.MapReduceLauncher: To kill this job, use: kill job_20100122142002425_0002 [junit] 10/01/22 14:20:34 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/hadoop-hudson/mapred/system/job_20100122142002425_0002/job.split dst=null perm=null [junit] 10/01/22 14:20:34 INFO DataNode.clienttrace: src: /127.0.0.1:36031, dest: /127.0.0.1:47947, bytes: 1605, op: HDFS_READ, cliID: DFSClient_-1427623467, srvID: DS-575681396-127.0.1.1-36031-1264170001864, blockid: blk_-4519883474485258605_1014 [junit] 10/01/22 14:20:34 INFO mapred.JobInProgress: Input size for job job_20100122142002425_0002 = 12. Number of splits = 2 [junit] 10/01/22 14:20:34 INFO mapred.JobInProgress: tip:task_20100122142002425_0002_m_000000 has split on node:/default-rack/h7.grid.sp2.yahoo.net [junit] 10/01/22 14:20:34 INFO mapred.JobInProgress: tip:task_20100122142002425_0002_m_000001 has split on node:/default-rack/h7.grid.sp2.yahoo.net [junit] 10/01/22 14:20:34 INFO mapred.JobTracker: Adding task 'attempt_20100122142002425_0002_m_000003_0' to tip task_20100122142002425_0002_m_000003, for tracker 'tracker_host2.foo.com:localhost/127.0.0.1:35598' [junit] 10/01/22 14:20:34 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20100122142002425_0002_m_000003_0 task's state:UNASSIGNED [junit] 10/01/22 14:20:34 INFO mapred.TaskTracker: Trying to launch : attempt_20100122142002425_0002_m_000003_0 [junit] 10/01/22 14:20:34 INFO mapred.TaskTracker: In TaskLauncher, current free slots : 2 and trying to launch attempt_20100122142002425_0002_m_000003_0 [junit] 10/01/22 14:20:34 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/hadoop-hudson/mapred/system/job_20100122142002425_0002/job.xml dst=null perm=null [junit] 10/01/22 14:20:34 INFO DataNode.clienttrace: src: /127.0.0.1:38080, dest: /127.0.0.1:41267, bytes: 49206, op: HDFS_READ, cliID: DFSClient_-1427623467, srvID: DS-1992836685-127.0.1.1-38080-1264170001426, blockid: blk_-8373979260215840694_1015 [junit] 10/01/22 14:20:34 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/hadoop-hudson/mapred/system/job_20100122142002425_0002/job.jar dst=null perm=null [junit] 10/01/22 14:20:34 INFO DataNode.clienttrace: src: /127.0.0.1:36031, dest: /127.0.0.1:47949, bytes: 2742642, op: HDFS_READ, cliID: DFSClient_-1427623467, srvID: DS-575681396-127.0.1.1-36031-1264170001864, blockid: blk_3903073668373300337_1013 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: Deleting block blk_-1868591793377613991_1005 file build/test/data/dfs/data/data6/current/blk_-1868591793377613991 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: Deleting block blk_6760959224182101687_1007 file build/test/data/dfs/data/data6/current/blk_6760959224182101687 [junit] 10/01/22 14:20:34 INFO datanode.DataNode: Deleting block blk_8009935584258537881_1006 file build/test/data/dfs/data/data5/current/blk_8009935584258537881 [junit] 10/01/22 14:20:35 INFO mapReduceLayer.MapReduceLauncher: 0% complete [junit] 10/01/22 14:20:35 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20100122142002425_0002_m_1134719225 [junit] 10/01/22 14:20:35 INFO mapred.JvmManager: JVM Runner jvm_20100122142002425_0002_m_1134719225 spawned. [junit] 10/01/22 14:20:35 INFO mapred.TaskTracker: JVM with ID: jvm_20100122142002425_0002_m_1134719225 given task: attempt_20100122142002425_0002_m_000003_0 [junit] 10/01/22 14:20:36 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=mkdirs src=/tmp/temp-406619199/tmp-961107684/_temporary dst=null perm=hudson:supergroup:rwxr-xr-x [junit] 10/01/22 14:20:36 INFO mapred.TaskTracker: attempt_20100122142002425_0002_m_000003_0 0.0% setup [junit] 10/01/22 14:20:36 INFO mapred.TaskTracker: Task attempt_20100122142002425_0002_m_000003_0 is done. [junit] 10/01/22 14:20:36 INFO mapred.TaskTracker: reported output size for attempt_20100122142002425_0002_m_000003_0 was 0 [junit] 10/01/22 14:20:36 INFO mapred.TaskTracker: addFreeSlot : current free slots : 2 [junit] 10/01/22 14:20:36 INFO mapred.JvmManager: JVM : jvm_20100122142002425_0002_m_1134719225 exited. Number of tasks it ran: 1 [junit] 10/01/22 14:20:37 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:45314 to delete blk_3903073668373300337_1013 [junit] 10/01/22 14:20:37 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find taskTracker/jobcache/job_20100122142002425_0002/attempt_20100122142002425_0002_m_000003_0/output/file.out in any of the configured local directories [junit] 10/01/22 14:20:37 INFO mapred.JobInProgress: Task 'attempt_20100122142002425_0002_m_000003_0' has completed task_20100122142002425_0002_m_000003 successfully. [junit] 10/01/22 14:20:37 INFO mapred.JobTracker: Adding task 'attempt_20100122142002425_0002_m_000000_0' to tip task_20100122142002425_0002_m_000000, for tracker 'tracker_host2.foo.com:localhost/127.0.0.1:35598' [junit] 10/01/22 14:20:37 INFO mapred.JobInProgress: Choosing rack-local task task_20100122142002425_0002_m_000000 [junit] 10/01/22 14:20:37 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20100122142002425_0002_m_000000_0 task's state:UNASSIGNED [junit] 10/01/22 14:20:37 INFO mapred.TaskTracker: Trying to launch : attempt_20100122142002425_0002_m_000000_0 [junit] 10/01/22 14:20:37 INFO mapred.TaskTracker: Received KillTaskAction for task: attempt_20100122142002425_0002_m_000003_0 [junit] 10/01/22 14:20:37 INFO mapred.TaskTracker: About to purge task: attempt_20100122142002425_0002_m_000003_0 [junit] 10/01/22 14:20:37 INFO mapred.TaskTracker: In TaskLauncher, current free slots : 2 and trying to launch attempt_20100122142002425_0002_m_000000_0 [junit] 10/01/22 14:20:37 INFO mapred.TaskRunner: attempt_20100122142002425_0002_m_000003_0 done; removing files. [junit] 10/01/22 14:20:37 INFO mapred.IndexCache: Map ID attempt_20100122142002425_0002_m_000003_0 not found in cache [junit] 10/01/22 14:20:37 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20100122142002425_0002_m_643524202 [junit] 10/01/22 14:20:37 INFO mapred.JvmManager: JVM Runner jvm_20100122142002425_0002_m_643524202 spawned. [junit] 10/01/22 14:20:38 INFO mapred.JobTracker: Adding task 'attempt_20100122142002425_0002_m_000001_0' to tip task_20100122142002425_0002_m_000001, for tracker 'tracker_host3.foo.com:localhost/127.0.0.1:32987' [junit] 10/01/22 14:20:38 INFO mapred.JobInProgress: Choosing rack-local task task_20100122142002425_0002_m_000001 [junit] 10/01/22 14:20:38 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20100122142002425_0002_m_000001_0 task's state:UNASSIGNED [junit] 10/01/22 14:20:38 INFO mapred.TaskTracker: Trying to launch : attempt_20100122142002425_0002_m_000001_0 [junit] 10/01/22 14:20:38 INFO mapred.TaskTracker: In TaskLauncher, current free slots : 2 and trying to launch attempt_20100122142002425_0002_m_000001_0 [junit] 10/01/22 14:20:38 INFO mapred.TaskTracker: JVM with ID: jvm_20100122142002425_0002_m_643524202 given task: attempt_20100122142002425_0002_m_000000_0 [junit] 10/01/22 14:20:38 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/hadoop-hudson/mapred/system/job_20100122142002425_0002/job.xml dst=null perm=null [junit] 10/01/22 14:20:38 INFO DataNode.clienttrace: src: /127.0.0.1:36031, dest: /127.0.0.1:47958, bytes: 49206, op: HDFS_READ, cliID: DFSClient_-1427623467, srvID: DS-575681396-127.0.1.1-36031-1264170001864, blockid: blk_-8373979260215840694_1015 [junit] 10/01/22 14:20:38 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/hadoop-hudson/mapred/system/job_20100122142002425_0002/job.jar dst=null perm=null [junit] 10/01/22 14:20:38 INFO DataNode.clienttrace: src: /127.0.0.1:36031, dest: /127.0.0.1:47959, bytes: 2742642, op: HDFS_READ, cliID: DFSClient_-1427623467, srvID: DS-575681396-127.0.1.1-36031-1264170001864, blockid: blk_3903073668373300337_1013 [junit] 10/01/22 14:20:38 INFO datanode.DataNode: Deleting block blk_3903073668373300337_1013 file build/test/data/dfs/data/data8/current/blk_3903073668373300337 [junit] 10/01/22 14:20:38 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20100122142002425_0002_m_1901236542 [junit] 10/01/22 14:20:38 INFO mapred.JvmManager: JVM Runner jvm_20100122142002425_0002_m_1901236542 spawned. [junit] 10/01/22 14:20:38 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/user/hudson/input2.txt dst=null perm=null [junit] 10/01/22 14:20:38 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=create src=/tmp/temp-406619199/tmp-961107684/_temporary/_attempt_20100122142002425_0002_m_000000_0/part-00000 dst=null perm=hudson:supergroup:rw-r--r-- [junit] 10/01/22 14:20:38 INFO DataNode.clienttrace: src: /127.0.0.1:45314, dest: /127.0.0.1:34064, bytes: 10, op: HDFS_READ, cliID: DFSClient_attempt_20100122142002425_0002_m_000000_0, srvID: DS-1167207178-127.0.1.1-45314-1264170002336, blockid: blk_-4502338686410540282_1012 [junit] 10/01/22 14:20:38 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp-406619199/tmp-961107684/_temporary/_attempt_20100122142002425_0002_m_000000_0/part-00000. blk_7213492376637073855_1018 [junit] 10/01/22 14:20:38 INFO datanode.DataNode: Receiving block blk_7213492376637073855_1018 src: /127.0.0.1:41282 dest: /127.0.0.1:38080 [junit] 10/01/22 14:20:38 INFO datanode.DataNode: Receiving block blk_7213492376637073855_1018 src: /127.0.0.1:34066 dest: /127.0.0.1:45314 [junit] 10/01/22 14:20:38 INFO datanode.DataNode: Receiving block blk_7213492376637073855_1018 src: /127.0.0.1:36015 dest: /127.0.0.1:38103 [junit] 10/01/22 14:20:38 INFO DataNode.clienttrace: src: /127.0.0.1:36015, dest: /127.0.0.1:38103, bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20100122142002425_0002_m_000000_0, srvID: DS-1463347349-127.0.1.1-38103-1264170000919, blockid: blk_7213492376637073855_1018 [junit] 10/01/22 14:20:38 INFO datanode.DataNode: PacketResponder 0 for block blk_7213492376637073855_1018 terminating [junit] 10/01/22 14:20:38 INFO DataNode.clienttrace: src: /127.0.0.1:34066, dest: /127.0.0.1:45314, bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20100122142002425_0002_m_000000_0, srvID: DS-1167207178-127.0.1.1-45314-1264170002336, blockid: blk_7213492376637073855_1018 [junit] 10/01/22 14:20:38 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:38103 is added to blk_7213492376637073855_1018 size 39 [junit] 10/01/22 14:20:38 INFO datanode.DataNode: PacketResponder 1 for block blk_7213492376637073855_1018 terminating [junit] 10/01/22 14:20:38 INFO DataNode.clienttrace: src: /127.0.0.1:41282, dest: /127.0.0.1:38080, bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20100122142002425_0002_m_000000_0, srvID: DS-1992836685-127.0.1.1-38080-1264170001426, blockid: blk_7213492376637073855_1018 [junit] 10/01/22 14:20:38 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:45314 is added to blk_7213492376637073855_1018 size 39 [junit] 10/01/22 14:20:38 INFO datanode.DataNode: PacketResponder 2 for block blk_7213492376637073855_1018 terminating [junit] 10/01/22 14:20:38 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:38080 is added to blk_7213492376637073855_1018 size 39 [junit] 10/01/22 14:20:38 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp-406619199/tmp-961107684/_temporary/_attempt_20100122142002425_0002_m_000000_0/part-00000 is closed by DFSClient_attempt_20100122142002425_0002_m_000000_0 [junit] 10/01/22 14:20:38 INFO mapred.TaskTracker: Task attempt_20100122142002425_0002_m_000000_0 is in commit-pending, task state:COMMIT_PENDING [junit] 10/01/22 14:20:38 INFO mapred.TaskTracker: attempt_20100122142002425_0002_m_000000_0 0.0% [junit] 10/01/22 14:20:39 INFO mapred.TaskTracker: JVM with ID: jvm_20100122142002425_0002_m_1901236542 given task: attempt_20100122142002425_0002_m_000001_0 [junit] 10/01/22 14:20:39 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/user/hudson/input1.txt dst=null perm=null [junit] 10/01/22 14:20:39 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=create src=/tmp/temp-406619199/tmp-961107684/_temporary/_attempt_20100122142002425_0002_m_000001_0/part-00001 dst=null perm=hudson:supergroup:rw-r--r-- [junit] 10/01/22 14:20:39 INFO DataNode.clienttrace: src: /127.0.0.1:38103, dest: /127.0.0.1:36021, bytes: 10, op: HDFS_READ, cliID: DFSClient_attempt_20100122142002425_0002_m_000001_0, srvID: DS-1463347349-127.0.1.1-38103-1264170000919, blockid: blk_-3685041014394738560_1011 [junit] 10/01/22 14:20:39 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp-406619199/tmp-961107684/_temporary/_attempt_20100122142002425_0002_m_000001_0/part-00001. blk_2219336130341092980_1019 [junit] 10/01/22 14:20:39 INFO datanode.DataNode: Receiving block blk_2219336130341092980_1019 src: /127.0.0.1:47972 dest: /127.0.0.1:36031 [junit] 10/01/22 14:20:39 INFO datanode.DataNode: Receiving block blk_2219336130341092980_1019 src: /127.0.0.1:34075 dest: /127.0.0.1:45314 [junit] 10/01/22 14:20:39 INFO datanode.DataNode: Receiving block blk_2219336130341092980_1019 src: /127.0.0.1:36024 dest: /127.0.0.1:38103 [junit] 10/01/22 14:20:39 INFO DataNode.clienttrace: src: /127.0.0.1:36024, dest: /127.0.0.1:38103, bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20100122142002425_0002_m_000001_0, srvID: DS-1463347349-127.0.1.1-38103-1264170000919, blockid: blk_2219336130341092980_1019 [junit] 10/01/22 14:20:39 INFO datanode.DataNode: PacketResponder 0 for block blk_2219336130341092980_1019 terminating [junit] 10/01/22 14:20:39 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:38103 is added to blk_2219336130341092980_1019 size 39 [junit] 10/01/22 14:20:39 INFO DataNode.clienttrace: src: /127.0.0.1:34075, dest: /127.0.0.1:45314, bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20100122142002425_0002_m_000001_0, srvID: DS-1167207178-127.0.1.1-45314-1264170002336, blockid: blk_2219336130341092980_1019 [junit] 10/01/22 14:20:39 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:45314 is added to blk_2219336130341092980_1019 size 39 [junit] 10/01/22 14:20:39 INFO datanode.DataNode: PacketResponder 1 for block blk_2219336130341092980_1019 terminating [junit] 10/01/22 14:20:39 INFO DataNode.clienttrace: src: /127.0.0.1:47972, dest: /127.0.0.1:36031, bytes: 39, op: HDFS_WRITE, cliID: DFSClient_attempt_20100122142002425_0002_m_000001_0, srvID: DS-575681396-127.0.1.1-36031-1264170001864, blockid: blk_2219336130341092980_1019 [junit] 10/01/22 14:20:39 INFO datanode.DataNode: PacketResponder 2 for block blk_2219336130341092980_1019 terminating [junit] 10/01/22 14:20:39 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:36031 is added to blk_2219336130341092980_1019 size 39 [junit] 10/01/22 14:20:39 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp-406619199/tmp-961107684/_temporary/_attempt_20100122142002425_0002_m_000001_0/part-00001 is closed by DFSClient_attempt_20100122142002425_0002_m_000001_0 [junit] 10/01/22 14:20:39 INFO mapred.TaskTracker: Task attempt_20100122142002425_0002_m_000001_0 is in commit-pending, task state:COMMIT_PENDING [junit] 10/01/22 14:20:39 INFO mapred.TaskTracker: attempt_20100122142002425_0002_m_000001_0 0.0% [junit] 10/01/22 14:20:40 INFO mapred.TaskTracker: Received commit task action for attempt_20100122142002425_0002_m_000000_0 [junit] 10/01/22 14:20:40 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=listStatus src=/tmp/temp-406619199/tmp-961107684/_temporary/_attempt_20100122142002425_0002_m_000000_0 dst=null perm=null [junit] 10/01/22 14:20:40 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=mkdirs src=/tmp/temp-406619199/tmp-961107684 dst=null perm=hudson:supergroup:rwxr-xr-x [junit] 10/01/22 14:20:40 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=rename src=/tmp/temp-406619199/tmp-961107684/_temporary/_attempt_20100122142002425_0002_m_000000_0/part-00000 dst=/tmp/temp-406619199/tmp-961107684/part-00000 perm=hudson:supergroup:rw-r--r-- [junit] 10/01/22 14:20:40 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=delete src=/tmp/temp-406619199/tmp-961107684/_temporary/_attempt_20100122142002425_0002_m_000000_0 dst=null perm=null [junit] 10/01/22 14:20:40 INFO mapred.TaskTracker: attempt_20100122142002425_0002_m_000000_0 1.0% [junit] 10/01/22 14:20:40 INFO mapred.TaskTracker: Task attempt_20100122142002425_0002_m_000000_0 is done. [junit] 10/01/22 14:20:40 INFO mapred.TaskTracker: reported output size for attempt_20100122142002425_0002_m_000000_0 was 0 [junit] 10/01/22 14:20:40 INFO mapred.TaskTracker: addFreeSlot : current free slots : 2 [junit] 10/01/22 14:20:41 INFO mapred.JvmManager: JVM : jvm_20100122142002425_0002_m_643524202 exited. Number of tasks it ran: 1 [junit] 10/01/22 14:20:41 INFO mapred.TaskTracker: Received commit task action for attempt_20100122142002425_0002_m_000001_0 [junit] 10/01/22 14:20:41 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=listStatus src=/tmp/temp-406619199/tmp-961107684/_temporary/_attempt_20100122142002425_0002_m_000001_0 dst=null perm=null [junit] 10/01/22 14:20:41 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=mkdirs src=/tmp/temp-406619199/tmp-961107684 dst=null perm=hudson:supergroup:rwxr-xr-x [junit] 10/01/22 14:20:41 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=rename src=/tmp/temp-406619199/tmp-961107684/_temporary/_attempt_20100122142002425_0002_m_000001_0/part-00001 dst=/tmp/temp-406619199/tmp-961107684/part-00001 perm=hudson:supergroup:rw-r--r-- [junit] 10/01/22 14:20:41 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=delete src=/tmp/temp-406619199/tmp-961107684/_temporary/_attempt_20100122142002425_0002_m_000001_0 dst=null perm=null [junit] 10/01/22 14:20:41 INFO mapred.TaskTracker: attempt_20100122142002425_0002_m_000001_0 1.0% [junit] 10/01/22 14:20:41 INFO mapred.TaskTracker: Task attempt_20100122142002425_0002_m_000001_0 is done. [junit] 10/01/22 14:20:41 INFO mapred.TaskTracker: reported output size for attempt_20100122142002425_0002_m_000001_0 was 0 [junit] 10/01/22 14:20:41 INFO mapred.TaskTracker: addFreeSlot : current free slots : 2 [junit] 10/01/22 14:20:41 INFO mapred.JvmManager: JVM : jvm_20100122142002425_0002_m_1901236542 exited. Number of tasks it ran: 1 [junit] 10/01/22 14:20:43 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find taskTracker/jobcache/job_20100122142002425_0002/attempt_20100122142002425_0002_m_000000_0/output/file.out in any of the configured local directories [junit] 10/01/22 14:20:43 INFO mapred.JobInProgress: Task 'attempt_20100122142002425_0002_m_000000_0' has completed task_20100122142002425_0002_m_000000 successfully. [junit] 10/01/22 14:20:44 INFO mapReduceLayer.MapReduceLauncher: 25% complete [junit] 10/01/22 14:20:44 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find taskTracker/jobcache/job_20100122142002425_0002/attempt_20100122142002425_0002_m_000001_0/output/file.out in any of the configured local directories [junit] 10/01/22 14:20:44 INFO mapred.JobInProgress: Task 'attempt_20100122142002425_0002_m_000001_0' has completed task_20100122142002425_0002_m_000001 successfully. [junit] 10/01/22 14:20:44 INFO mapred.JobTracker: Adding task 'attempt_20100122142002425_0002_m_000002_0' to tip task_20100122142002425_0002_m_000002, for tracker 'tracker_host3.foo.com:localhost/127.0.0.1:32987' [junit] 10/01/22 14:20:44 INFO mapred.TaskTracker: LaunchTaskAction (registerTask): attempt_20100122142002425_0002_m_000002_0 task's state:UNASSIGNED [junit] 10/01/22 14:20:44 INFO mapred.TaskTracker: Trying to launch : attempt_20100122142002425_0002_m_000002_0 [junit] 10/01/22 14:20:44 INFO mapred.TaskTracker: In TaskLauncher, current free slots : 2 and trying to launch attempt_20100122142002425_0002_m_000002_0 [junit] 10/01/22 14:20:44 INFO mapred.JvmManager: In JvmRunner constructed JVM ID: jvm_20100122142002425_0002_m_1681253248 [junit] 10/01/22 14:20:44 INFO mapred.JvmManager: JVM Runner jvm_20100122142002425_0002_m_1681253248 spawned. [junit] 10/01/22 14:20:44 INFO mapReduceLayer.MapReduceLauncher: 50% complete [junit] 10/01/22 14:20:44 INFO mapred.TaskTracker: JVM with ID: jvm_20100122142002425_0002_m_1681253248 given task: attempt_20100122142002425_0002_m_000002_0 [junit] 10/01/22 14:20:45 INFO mapred.TaskTracker: attempt_20100122142002425_0002_m_000002_0 0.0% [junit] 10/01/22 14:20:45 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=delete src=/tmp/temp-406619199/tmp-961107684/_temporary dst=null perm=null [junit] 10/01/22 14:20:45 INFO mapred.TaskTracker: attempt_20100122142002425_0002_m_000002_0 0.0% cleanup [junit] 10/01/22 14:20:45 INFO mapred.TaskTracker: Task attempt_20100122142002425_0002_m_000002_0 is done. [junit] 10/01/22 14:20:45 INFO mapred.TaskTracker: reported output size for attempt_20100122142002425_0002_m_000002_0 was 0 [junit] 10/01/22 14:20:45 INFO mapred.TaskTracker: addFreeSlot : current free slots : 2 [junit] 10/01/22 14:20:45 INFO mapred.JvmManager: JVM : jvm_20100122142002425_0002_m_1681253248 exited. Number of tasks it ran: 1 [junit] 10/01/22 14:20:47 INFO mapred.TaskTracker: org.apache.hadoop.util.DiskChecker$DiskErrorException: Could not find taskTracker/jobcache/job_20100122142002425_0002/attempt_20100122142002425_0002_m_000002_0/output/file.out in any of the configured local directories [junit] 10/01/22 14:20:47 INFO mapred.JobInProgress: Task 'attempt_20100122142002425_0002_m_000002_0' has completed task_20100122142002425_0002_m_000002 successfully. [junit] 10/01/22 14:20:47 INFO mapred.JobInProgress: Job job_20100122142002425_0002 has completed successfully. [junit] 10/01/22 14:20:47 INFO hdfs.StateChange: BLOCK* NameSystem.allocateBlock: /tmp/temp-406619199/tmp-961107684/_logs/history/localhost_1264170002449_job_20100122142002425_0002_hudson_Job8574906685669331476.jar. blk_-5803266619274250679_1019 [junit] 10/01/22 14:20:47 INFO datanode.DataNode: Receiving block blk_-5803266619274250679_1019 src: /127.0.0.1:43924 dest: /127.0.0.1:38080 [junit] 10/01/22 14:20:47 INFO datanode.DataNode: Receiving block blk_-5803266619274250679_1019 src: /127.0.0.1:35599 dest: /127.0.0.1:36031 [junit] 10/01/22 14:20:47 INFO datanode.DataNode: Receiving block blk_-5803266619274250679_1019 src: /127.0.0.1:48555 dest: /127.0.0.1:45314 [junit] 10/01/22 14:20:47 INFO DataNode.clienttrace: src: /127.0.0.1:48555, dest: /127.0.0.1:45314, bytes: 6117, op: HDFS_WRITE, cliID: DFSClient_-1427623467, srvID: DS-1167207178-127.0.1.1-45314-1264170002336, blockid: blk_-5803266619274250679_1019 [junit] 10/01/22 14:20:47 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:45314 is added to blk_-5803266619274250679_1019 size 6117 [junit] 10/01/22 14:20:47 INFO datanode.DataNode: PacketResponder 0 for block blk_-5803266619274250679_1019 terminating [junit] 10/01/22 14:20:47 INFO DataNode.clienttrace: src: /127.0.0.1:35599, dest: /127.0.0.1:36031, bytes: 6117, op: HDFS_WRITE, cliID: DFSClient_-1427623467, srvID: DS-575681396-127.0.1.1-36031-1264170001864, blockid: blk_-5803266619274250679_1019 [junit] 10/01/22 14:20:47 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:36031 is added to blk_-5803266619274250679_1019 size 6117 [junit] 10/01/22 14:20:47 INFO datanode.DataNode: PacketResponder 1 for block blk_-5803266619274250679_1019 terminating [junit] 10/01/22 14:20:47 INFO hdfs.StateChange: BLOCK* NameSystem.addStoredBlock: blockMap updated: 127.0.0.1:38080 is added to blk_-5803266619274250679_1019 size 6117 [junit] 10/01/22 14:20:47 INFO DataNode.clienttrace: src: /127.0.0.1:43924, dest: /127.0.0.1:38080, bytes: 6117, op: HDFS_WRITE, cliID: DFSClient_-1427623467, srvID: DS-1992836685-127.0.1.1-38080-1264170001426, blockid: blk_-5803266619274250679_1019 [junit] 10/01/22 14:20:47 INFO datanode.DataNode: PacketResponder 2 for block blk_-5803266619274250679_1019 terminating [junit] 10/01/22 14:20:47 INFO hdfs.StateChange: DIR* NameSystem.completeFile: file /tmp/temp-406619199/tmp-961107684/_logs/history/localhost_1264170002449_job_20100122142002425_0002_hudson_Job8574906685669331476.jar is closed by DFSClient_-1427623467 [junit] 10/01/22 14:20:47 INFO mapred.JobTracker: Removed completed task 'attempt_20100122142002425_0002_m_000001_0' from 'tracker_host3.foo.com:localhost/127.0.0.1:32987' [junit] 10/01/22 14:20:47 INFO mapred.JobTracker: Removed completed task 'attempt_20100122142002425_0002_m_000002_0' from 'tracker_host3.foo.com:localhost/127.0.0.1:32987' [junit] 10/01/22 14:20:47 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_3903073668373300337 is added to invalidSet of 127.0.0.1:45314 [junit] 10/01/22 14:20:47 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_3903073668373300337 is added to invalidSet of 127.0.0.1:38103 [junit] 10/01/22 14:20:47 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_3903073668373300337 is added to invalidSet of 127.0.0.1:36031 [junit] 10/01/22 14:20:47 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-4519883474485258605 is added to invalidSet of 127.0.0.1:36031 [junit] 10/01/22 14:20:47 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-4519883474485258605 is added to invalidSet of 127.0.0.1:45314 [junit] 10/01/22 14:20:47 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-4519883474485258605 is added to invalidSet of 127.0.0.1:38103 [junit] 10/01/22 14:20:47 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-8373979260215840694 is added to invalidSet of 127.0.0.1:36031 [junit] 10/01/22 14:20:47 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-8373979260215840694 is added to invalidSet of 127.0.0.1:38080 [junit] 10/01/22 14:20:47 INFO hdfs.StateChange: BLOCK* NameSystem.addToInvalidates: blk_-8373979260215840694 is added to invalidSet of 127.0.0.1:38103 [junit] 10/01/22 14:20:47 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20100122142002425_0002 [junit] 10/01/22 14:20:47 INFO mapred.TaskRunner: attempt_20100122142002425_0002_m_000001_0 done; removing files. [junit] 10/01/22 14:20:47 INFO mapred.IndexCache: Map ID attempt_20100122142002425_0002_m_000001_0 not found in cache [junit] 10/01/22 14:20:47 INFO mapred.TaskRunner: attempt_20100122142002425_0002_m_000002_0 done; removing files. [junit] 10/01/22 14:20:47 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=delete src=/tmp/hadoop-hudson/mapred/system/job_20100122142002425_0002 dst=null perm=null [junit] 10/01/22 14:20:47 INFO mapred.IndexCache: Map ID attempt_20100122142002425_0002_m_000002_0 not found in cache [junit] 10/01/22 14:20:48 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20100122142002425_0002 [junit] 10/01/22 14:20:48 WARN mapred.TaskTracker: Unknown job job_20100122142002425_0002 being deleted. [junit] 10/01/22 14:20:49 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:45314 to delete blk_-4519883474485258605_1014 blk_3903073668373300337_1013 [junit] 10/01/22 14:20:49 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:38103 to delete blk_-8373979260215840694_1015 blk_-4519883474485258605_1014 blk_3903073668373300337_1013 [junit] 10/01/22 14:20:49 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20100122142002425_0002 [junit] 10/01/22 14:20:49 WARN mapred.TaskTracker: Unknown job job_20100122142002425_0002 being deleted. [junit] 10/01/22 14:20:49 INFO mapReduceLayer.MapReduceLauncher: 100% complete [junit] 10/01/22 14:20:49 INFO mapReduceLayer.MapReduceLauncher: Successfully stored result in: "hdfs://localhost:47577/tmp/temp-406619199/tmp-961107684" [junit] 10/01/22 14:20:49 INFO mapReduceLayer.MapReduceLauncher: Records written : 2 [junit] 10/01/22 14:20:49 INFO mapReduceLayer.MapReduceLauncher: Bytes written : 78 [junit] 10/01/22 14:20:49 INFO mapReduceLayer.MapReduceLauncher: Success! [junit] 10/01/22 14:20:49 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=listStatus src=/tmp/temp-406619199/tmp-961107684 dst=null perm=null [junit] 10/01/22 14:20:49 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/temp-406619199/tmp-961107684/part-00000 dst=null perm=null [junit] 10/01/22 14:20:49 INFO DataNode.clienttrace: src: /127.0.0.1:38103, dest: /127.0.0.1:58009, bytes: 43, op: HDFS_READ, cliID: DFSClient_-1427623467, srvID: DS-1463347349-127.0.1.1-38103-1264170000919, blockid: blk_7213492376637073855_1018 [junit] 10/01/22 14:20:49 INFO FSNamesystem.audit: ugi=hudson,hudson ip=/127.0.0.1 cmd=open src=/tmp/temp-406619199/tmp-961107684/part-00001 dst=null perm=null [junit] 10/01/22 14:20:49 INFO DataNode.clienttrace: src: /127.0.0.1:38103, dest: /127.0.0.1:58010, bytes: 43, op: HDFS_READ, cliID: DFSClient_-1427623467, srvID: DS-1463347349-127.0.1.1-38103-1264170000919, blockid: blk_2219336130341092980_1019 [junit] 10/01/22 14:20:49 INFO mapred.JobTracker: Removed completed task 'attempt_20100122142002425_0002_m_000000_0' from 'tracker_host2.foo.com:localhost/127.0.0.1:35598' [junit] 10/01/22 14:20:49 INFO mapred.JobTracker: Removed completed task 'attempt_20100122142002425_0002_m_000003_0' from 'tracker_host2.foo.com:localhost/127.0.0.1:35598' [junit] 10/01/22 14:20:49 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20100122142002425_0002 [junit] 10/01/22 14:20:49 INFO mapred.TaskRunner: attempt_20100122142002425_0002_m_000000_0 done; removing files. [junit] 10/01/22 14:20:49 INFO mapred.IndexCache: Map ID attempt_20100122142002425_0002_m_000000_0 not found in cache [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 44.49 sec BUILD FAILED :560: The following error occurred while executing this line: :493: The following error occurred while executing this line: :555: Tests failed! Total time: 249 minutes 35 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