[
https://issues.apache.org/jira/browse/MAPREDUCE-1308?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=12791802#action_12791802
]
Brian Karlak commented on MAPREDUCE-1308:
-----------------------------------------
The relevant tasktracker logs on the affected tasktracker:
[[email protected] logs]$ grep 200912102211_1648_r_000009
hadoop-hadoop-tasktracker-hadoop0038.data.sjc1.metaweb.com.log | grep -v
file.out
2009-12-17 03:56:05,124 INFO org.apache.hadoop.mapred.TaskTracker:
LaunchTaskAction (registerTask): attempt_200912102211_1648_r_000009_0 task's
state:UNASSIGNED
2009-12-17 03:56:05,124 INFO org.apache.hadoop.mapred.TaskTracker: Trying to
launch : attempt_200912102211_1648_r_000009_0
2009-12-17 03:56:05,124 INFO org.apache.hadoop.mapred.TaskTracker: In
TaskLauncher, current free slots : 8 and trying to launch
attempt_200912102211_1648_r_000009_0
2009-12-17 03:56:06,155 INFO org.apache.hadoop.mapred.TaskTracker: JVM with ID:
jvm_200912102211_1648_r_1323622642 given task:
attempt_200912102211_1648_r_000009_0
2009-12-17 03:56:12,611 INFO org.apache.hadoop.mapred.TaskTracker:
attempt_200912102211_1648_r_000009_0 0.014619883% reduce > copy (60 of 1368 at
4.35 MB/s) >
2009-12-17 05:36:15,959 INFO org.apache.hadoop.mapred.TaskTracker:
attempt_200912102211_1648_r_000009_0: Task attempt_200912102211_1648_r_000009_0
failed to report status for 6003 seconds. Killing!
2009-12-17 05:36:16,030 INFO org.apache.hadoop.mapred.TaskTracker: About to
purge task: attempt_200912102211_1648_r_000009_0
2009-12-17 05:36:16,030 INFO org.apache.hadoop.mapred.TaskRunner:
attempt_200912102211_1648_r_000009_0 done; removing files.
2009-12-17 05:36:19,031 INFO org.apache.hadoop.mapred.TaskRunner:
attempt_200912102211_1648_r_000009_0 done; removing files.
2009-12-17 05:36:19,036 INFO org.apache.hadoop.mapred.TaskTracker:
LaunchTaskAction (registerTask): attempt_200912102211_1648_r_000009_0 task's
state:FAILED_UNCLEAN
2009-12-17 05:36:19,036 INFO org.apache.hadoop.mapred.TaskTracker: Trying to
launch : attempt_200912102211_1648_r_000009_0
2009-12-17 05:36:19,036 INFO org.apache.hadoop.mapred.TaskTracker: In
TaskLauncher, current free slots : 8 and trying to launch
attempt_200912102211_1648_r_000009_0
2009-12-17 05:36:20,230 INFO org.apache.hadoop.mapred.TaskTracker: JVM with ID:
jvm_200912102211_1648_r_2026654121 given task:
attempt_200912102211_1648_r_000009_0
2009-12-17 05:36:20,572 INFO org.apache.hadoop.mapred.TaskTracker:
attempt_200912102211_1648_r_000009_0 0.0%
2009-12-17 05:36:20,577 INFO org.apache.hadoop.mapred.TaskTracker:
attempt_200912102211_1648_r_000009_0 0.0% cleanup
2009-12-17 05:36:20,578 INFO org.apache.hadoop.mapred.TaskTracker: Task
attempt_200912102211_1648_r_000009_0 is done.
2009-12-17 05:36:20,579 INFO org.apache.hadoop.mapred.TaskTracker: reported
output size for attempt_200912102211_1648_r_000009_0 was 0
2009-12-17 05:36:20,579 INFO org.apache.hadoop.mapred.TaskRunner:
attempt_200912102211_1648_r_000009_0 done; removing files.
2009-12-17 05:36:20,583 INFO org.apache.hadoop.mapred.TaskTracker:
LaunchTaskAction (registerTask): attempt_200912102211_1648_r_000009_1 task's
state:UNASSIGNED
2009-12-17 05:36:20,584 INFO org.apache.hadoop.mapred.TaskTracker: Trying to
launch : attempt_200912102211_1648_r_000009_1
2009-12-17 05:36:20,584 INFO org.apache.hadoop.mapred.TaskTracker: In
TaskLauncher, current free slots : 8 and trying to launch
attempt_200912102211_1648_r_000009_1
2009-12-17 05:36:21,276 INFO org.apache.hadoop.mapred.TaskTracker: JVM with ID:
jvm_200912102211_1648_r_-1445045544 given task:
attempt_200912102211_1648_r_000009_1
2009-12-17 05:36:27,586 INFO org.apache.hadoop.mapred.TaskTracker:
attempt_200912102211_1648_r_000009_1 0.014619883% reduce > copy (60 of 1368 at
4.35 MB/s) >
2009-12-17 05:36:30,598 INFO org.apache.hadoop.mapred.TaskTracker:
attempt_200912102211_1648_r_000009_1 0.019493178% reduce > copy (80 of 1368 at
23.23 MB/s) >
...
The attempt_200912102211_1648_r_000009_1 then runs to completion.
> reduce tasks stall and are eventually killed
> --------------------------------------------
>
> Key: MAPREDUCE-1308
> URL: https://issues.apache.org/jira/browse/MAPREDUCE-1308
> Project: Hadoop Map/Reduce
> Issue Type: Bug
> Components: tasktracker
> Environment: 20-node cluster, 8 cores per machine, 32GB memory,
> Fedora Linux
> Reporter: Brian Karlak
>
> We recently migrated our 0.19.2 cluster from Gentoo Linux to Fedora Linux.
> Everything was running smoothly before, but now about 5%-10% of our jobs have
> at least one reduce task that stalls out and is eventually killed with the
> message:
> Task attempt_200912102211_1648_r_000009_0 failed to report status for
> 6003 seconds. Killing!
> The task is then re-launched and completes successfully, usually in a couple
> of minutes.
> This is problematic because our scheduled Hadoop jobs now take an extra
> hour-and-a-half to run (6000 seconds).
> There are no indications in the logs that anything is amiss. The task
> starts, a small amount of the copy/shuffle runs, and then nothing is else is
> heard from the task until it is killed. I will attach the relevant parts of
> the TaskTracker logs in the comments.
--
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.
