[TEZ-526] Investigate: Unrecognized task while task sends an heartbeat

• Type: Bug
• Status: Closed
• Priority: Critical
• Resolution: Fixed
• Fix Version/s: 0.2.0
• Assignee: Hitesh Shah
• Reporter: Siddharth Seth
• Created: 04/Oct/13 03:16
• Updated: 01/Dec/13 20:21
• Resolved: 09/Oct/13 02:13

Description

Exception from task syslog

org.apache.hadoop.ipc.RemoteException(java.io.IOException): org.apache.tez.dag.api.TezException: Attempt attempt_1380841536519_0034_1_00_000003_0 is not recognized for heartbeat
  at org.apache.tez.dag.app.TaskAttemptListenerImpTezDag.heartbeat(TaskAttemptListenerImpTezDag.java:516)
  at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
  at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
  at java.lang.reflect.Method.invoke(Method.java:597)
  at org.apache.hadoop.ipc.WritableRpcEngine$Server$WritableRpcInvoker.call(WritableRpcEngine.java:481)
  at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:932)
  at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:2051)
  at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:2047)
  at java.security.AccessController.doPrivileged(Native Method)
  at javax.security.auth.Subject.doAs(Subject.java:396)
  at org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1499)
  at org.apache.hadoop.ipc.Server$Handler.run(Server.java:2045)

  at org.apache.hadoop.ipc.Client.call(Client.java:1404)
  at org.apache.hadoop.ipc.Client.call(Client.java:1357)
  at org.apache.hadoop.ipc.WritableRpcEngine$Invoker.invoke(WritableRpcEngine.java:231)
  at $Proxy9.heartbeat(Unknown Source)
  at org.apache.hadoop.mapred.YarnTezDagChild.heartbeat(YarnTezDagChild.java:194)
  at org.apache.hadoop.mapred.YarnTezDagChild.heartbeat(YarnTezDagChild.java:153)
  at org.apache.hadoop.mapred.YarnTezDagChild.access$300(YarnTezDagChild.java:96)
  at org.apache.hadoop.mapred.YarnTezDagChild$1.run(YarnTezDagChild.java:123)
  at java.lang.Thread.run(Thread.java:680)

AM log (source of the exception)

java.io.IOException: org.apache.tez.dag.api.TezException: Attempt attempt_1380841536519_0034_1_00_000003_0 is not recognized for heartbeat
  at org.apache.tez.dag.app.TaskAttemptListenerImpTezDag.heartbeat(TaskAttemptListenerImpTezDag.java:516)
  at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
  at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
  at java.lang.reflect.Method.invoke(Method.java:597)
  at org.apache.hadoop.ipc.WritableRpcEngine$Server$WritableRpcInvoker.call(WritableRpcEngine.java:481)
  at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:932)
  at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:2051)
  at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:2047)
  at java.security.AccessController.doPrivileged(Native Method)
  at javax.security.auth.Subject.doAs(Subject.java:396)
  at org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1499)
  at org.apache.hadoop.ipc.Server$Handler.run(Server.java:2045)

This was while running modified code, but nothing changed inthe entire task allocation, heartbeat etc flow.