From yarn-issues-return-135496-archive-asf-public=cust-asf.ponee.io@hadoop.apache.org Mon Jan 22 14:56:06 2018 Return-Path: X-Original-To: archive-asf-public@eu.ponee.io Delivered-To: archive-asf-public@eu.ponee.io Received: from cust-asf.ponee.io (cust-asf.ponee.io [163.172.22.183]) by mx-eu-01.ponee.io (Postfix) with ESMTP id AB61A180609 for ; Mon, 22 Jan 2018 14:56:06 +0100 (CET) Received: by cust-asf.ponee.io (Postfix) id 9AF7A160C4B; Mon, 22 Jan 2018 13:56:06 +0000 (UTC) Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by cust-asf.ponee.io (Postfix) with SMTP id 99E5F160C3A for ; Mon, 22 Jan 2018 14:56:05 +0100 (CET) Received: (qmail 33380 invoked by uid 500); 22 Jan 2018 13:56:04 -0000 Mailing-List: contact yarn-issues-help@hadoop.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Delivered-To: mailing list yarn-issues@hadoop.apache.org Received: (qmail 33369 invoked by uid 99); 22 Jan 2018 13:56:04 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd1-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 22 Jan 2018 13:56:04 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd1-us-west.apache.org (ASF Mail Server at spamd1-us-west.apache.org) with ESMTP id 2B503C3969 for ; Mon, 22 Jan 2018 13:56:04 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd1-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: -107.911 X-Spam-Level: X-Spam-Status: No, score=-107.911 tagged_above=-999 required=6.31 tests=[ENV_AND_HDR_SPF_MATCH=-0.5, KAM_ASCII_DIVIDERS=0.8, RCVD_IN_DNSWL_LOW=-0.7, SPF_PASS=-0.001, T_RP_MATCHES_RCVD=-0.01, USER_IN_DEF_SPF_WL=-7.5, USER_IN_WHITELIST=-100] autolearn=disabled Received: from mx1-lw-eu.apache.org ([10.40.0.8]) by localhost (spamd1-us-west.apache.org [10.40.0.7]) (amavisd-new, port 10024) with ESMTP id fMabLMreijDf for ; Mon, 22 Jan 2018 13:56:02 +0000 (UTC) Received: from mailrelay1-us-west.apache.org (mailrelay1-us-west.apache.org [209.188.14.139]) by mx1-lw-eu.apache.org (ASF Mail Server at mx1-lw-eu.apache.org) with ESMTP id DF8095FAEF for ; Mon, 22 Jan 2018 13:56:01 +0000 (UTC) Received: from jira-lw-us.apache.org (unknown [207.244.88.139]) by mailrelay1-us-west.apache.org (ASF Mail Server at mailrelay1-us-west.apache.org) with ESMTP id D3CDBE0F83 for ; Mon, 22 Jan 2018 13:56:00 +0000 (UTC) Received: from jira-lw-us.apache.org (localhost [127.0.0.1]) by jira-lw-us.apache.org (ASF Mail Server at jira-lw-us.apache.org) with ESMTP id 43E38241C6 for ; Mon, 22 Jan 2018 13:56:00 +0000 (UTC) Date: Mon, 22 Jan 2018 13:56:00 +0000 (UTC) From: "lujie (JIRA)" To: yarn-issues@hadoop.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Updated] (YARN-7176) Similar to YARN-2387:Resource Manager crashes with NPE due to lack of synchronization MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 [ https://issues.apache.org/jira/browse/YARN-7176?page=3Dcom.atlassian= .jira.plugin.system.issuetabpanels:all-tabpanel ] lujie updated YARN-7176: ------------------------ Attachment: YARN-7176_3.patch > Similar to YARN-2387:Resource Manager crashes with NPE due to lack of syn= chronization > -------------------------------------------------------------------------= ------------ > > Key: YARN-7176 > URL: https://issues.apache.org/jira/browse/YARN-7176 > Project: Hadoop YARN > Issue Type: Bug > Components: RM > Affects Versions: 2.6.0 > Reporter: lujie > Assignee: lujie > Priority: Blocker > Attachments: YARN-7176.patch, YARN-7176_3.patch, YARN_7176_2.patc= h, logs.rar > > > submit a job, when the job is starting Appmaster Containers(eg. startCont= ainers) , then send kill command.=C2=A0 After RM receive kill command, it w= ill perform state store(eg.updateApplicationStateInternal). > the startContainers=C2=A0 process and updateApplicationStateInternal will= call the same method=C2=A0=C2=A0ContainerLaunchContextPBImpl.getProto whic= h lack of the=C2=A0synchronization(also can be called in reInitializeContai= ner method), the RM log will show below.=C2=A0 > {code:java} > 2017-09-08 02:34:37,967 INFO org.apache.hadoop.yarn.server.resourcemanage= r.amlauncher.AMLauncher: Error launching appattempt_1504809243340_0001_0000= 01. Got exception: java.lang.ArrayIndexOutOfBoundsException: 3 > =09at java.util.ArrayList.add(ArrayList.java:441) > =09at com.google.protobuf.AbstractMessageLite$Builder.addAll(AbstractMess= ageLite.java:330) > =09at org.apache.hadoop.yarn.proto.YarnProtos$ContainerLaunchContextProto= $Builder.addAllApplicationACLs(YarnProtos.java:39956) > =09at org.apache.hadoop.yarn.api.records.impl.pb.ContainerLaunchContextPB= Impl.addApplicationACLs(ContainerLaunchContextPBImpl.java:446) > =09at org.apache.hadoop.yarn.api.records.impl.pb.ContainerLaunchContextPB= Impl.mergeLocalToBuilder(ContainerLaunchContextPBImpl.java:121) > =09at org.apache.hadoop.yarn.api.records.impl.pb.ContainerLaunchContextPB= Impl.mergeLocalToProto(ContainerLaunchContextPBImpl.java:128) > =09at org.apache.hadoop.yarn.api.records.impl.pb.ContainerLaunchContextPB= Impl.getProto(ContainerLaunchContextPBImpl.java:70) > =09at org.apache.hadoop.yarn.api.protocolrecords.impl.pb.StartContainerRe= questPBImpl.convertToProtoFormat(StartContainerRequestPBImpl.java:156) > =09at org.apache.hadoop.yarn.api.protocolrecords.impl.pb.StartContainerRe= questPBImpl.mergeLocalToBuilder(StartContainerRequestPBImpl.java:85) > =09at org.apache.hadoop.yarn.api.protocolrecords.impl.pb.StartContainerRe= questPBImpl.mergeLocalToProto(StartContainerRequestPBImpl.java:95) > =09at org.apache.hadoop.yarn.api.protocolrecords.impl.pb.StartContainerRe= questPBImpl.getProto(StartContainerRequestPBImpl.java:57) > =09at org.apache.hadoop.yarn.api.protocolrecords.impl.pb.StartContainersR= equestPBImpl.convertToProtoFormat(StartContainersRequestPBImpl.java:137) > =09at org.apache.hadoop.yarn.api.protocolrecords.impl.pb.StartContainersR= equestPBImpl.addLocalRequestsToProto(StartContainersRequestPBImpl.java:97) > =09at org.apache.hadoop.yarn.api.protocolrecords.impl.pb.StartContainersR= equestPBImpl.mergeLocalToBuilder(StartContainersRequestPBImpl.java:79) > =09at org.apache.hadoop.yarn.api.protocolrecords.impl.pb.StartContainersR= equestPBImpl.mergeLocalToProto(StartContainersRequestPBImpl.java:72) > =09at org.apache.hadoop.yarn.api.protocolrecords.impl.pb.StartContainersR= equestPBImpl.getProto(StartContainersRequestPBImpl.java:48) > =09at org.apache.hadoop.yarn.api.impl.pb.client.ContainerManagementProtoc= olPBClientImpl.startContainers(ContainerManagementProtocolPBClientImpl.java= :93) > =09at org.apache.hadoop.yarn.server.resourcemanager.amlauncher.AMLauncher= .launch(AMLauncher.java:119) > =09at org.apache.hadoop.yarn.server.resourcemanager.amlauncher.AMLauncher= .run(AMLauncher.java:254) > =09at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecuto= r.java:1145) > =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecut= or.java:615) > =09at java.lang.Thread.run(Thread.java:745) > 2017-09-08 02:34:37,968 ERROR org.apache.hadoop.yarn.server.resourcemanag= er.recovery.RMStateStore: Error updating app: application_1504809243340_000= 1 > java.lang.NullPointerException > =09at com.google.protobuf.CodedOutputStream.computeMessageSizeNoTag(Coded= OutputStream.java:749) > =09at com.google.protobuf.CodedOutputStream.computeMessageSize(CodedOutpu= tStream.java:530) > =09at org.apache.hadoop.yarn.proto.YarnProtos$ContainerLaunchContextProto= .getSerializedSize(YarnProtos.java:38512) > =09at com.google.protobuf.CodedOutputStream.computeMessageSizeNoTag(Coded= OutputStream.java:749) > =09at com.google.protobuf.CodedOutputStream.computeMessageSize(CodedOutpu= tStream.java:530) > =09at org.apache.hadoop.yarn.proto.YarnProtos$ApplicationSubmissionContex= tProto.getSerializedSize(YarnProtos.java:28481) > =09at com.google.protobuf.CodedOutputStream.computeMessageSizeNoTag(Coded= OutputStream.java:749) > =09at com.google.protobuf.CodedOutputStream.computeMessageSize(CodedOutpu= tStream.java:530) > =09at org.apache.hadoop.yarn.proto.YarnServerResourceManagerRecoveryProto= s$ApplicationStateDataProto.getSerializedSize(YarnServerResourceManagerReco= veryProtos.java:816) > =09at com.google.protobuf.AbstractMessageLite.toByteArray(AbstractMessage= Lite.java:62) > =09at org.apache.hadoop.yarn.server.resourcemanager.recovery.FileSystemRM= StateStore.updateApplicationStateInternal(FileSystemRMStateStore.java:426) > =09at org.apache.hadoop.yarn.server.resourcemanager.recovery.RMStateStore= $UpdateAppTransition.transition(RMStateStore.java:163) > =09at org.apache.hadoop.yarn.server.resourcemanager.recovery.RMStateStore= $UpdateAppTransition.transition(RMStateStore.java:148) > =09at org.apache.hadoop.yarn.state.StateMachineFactory$SingleInternalArc.= doTransition(StateMachineFactory.java:362) > =09at org.apache.hadoop.yarn.state.StateMachineFactory.doTransition(State= MachineFactory.java:302) > =09at org.apache.hadoop.yarn.state.StateMachineFactory.access$300(StateMa= chineFactory.java:46) > =09at org.apache.hadoop.yarn.state.StateMachineFactory$InternalStateMachi= ne.doTransition(StateMachineFactory.java:448) > =09at org.apache.hadoop.yarn.server.resourcemanager.recovery.RMStateStore= .handleStoreEvent(RMStateStore.java:810) > =09at org.apache.hadoop.yarn.server.resourcemanager.recovery.RMStateStore= $ForwardingEventHandler.handle(RMStateStore.java:864) > =09at org.apache.hadoop.yarn.server.resourcemanager.recovery.RMStateStore= $ForwardingEventHandler.handle(RMStateStore.java:859) > =09at org.apache.hadoop.yarn.event.AsyncDispatcher.dispatch(AsyncDispatch= er.java:173) > =09at org.apache.hadoop.yarn.event.AsyncDispatcher$1.run(AsyncDispatcher.= java:106) > =09at java.lang.Thread.run(Thread.java:745) > 2017-09-08 02:34:37,978 FATAL org.apache.hadoop.yarn.server.resourcemanag= er.ResourceManager: Received a org.apache.hadoop.yarn.server.resourcemanage= r.RMFatalEvent of type STATE_STORE_OP_FAILED. Cause: > java.lang.NullPointerException > =09at com.google.protobuf.CodedOutputStream.computeMessageSizeNoTag(Coded= OutputStream.java:749) > =09at com.google.protobuf.CodedOutputStream.computeMessageSize(CodedOutpu= tStream.java:530) > =09at org.apache.hadoop.yarn.proto.YarnProtos$ContainerLaunchContextProto= .getSerializedSize(YarnProtos.java:38512) > =09at com.google.protobuf.CodedOutputStream.computeMessageSizeNoTag(Coded= OutputStream.java:749) > =09at com.google.protobuf.CodedOutputStream.computeMessageSize(CodedOutpu= tStream.java:530) > =09at org.apache.hadoop.yarn.proto.YarnProtos$ApplicationSubmissionContex= tProto.getSerializedSize(YarnProtos.java:28481) > =09at com.google.protobuf.CodedOutputStream.computeMessageSizeNoTag(Coded= OutputStream.java:749) > =09at com.google.protobuf.CodedOutputStream.computeMessageSize(CodedOutpu= tStream.java:530) > =09at org.apache.hadoop.yarn.proto.YarnServerResourceManagerRecoveryProto= s$ApplicationStateDataProto.getSerializedSize(YarnServerResourceManagerReco= veryProtos.java:816) > =09at com.google.protobuf.AbstractMessageLite.toByteArray(AbstractMessage= Lite.java:62) > =09at org.apache.hadoop.yarn.server.resourcemanager.recovery.FileSystemRM= StateStore.updateApplicationStateInternal(FileSystemRMStateStore.java:426) > =09at org.apache.hadoop.yarn.server.resourcemanager.recovery.RMStateStore= $UpdateAppTransition.transition(RMStateStore.java:163) > =09at org.apache.hadoop.yarn.server.resourcemanager.recovery.RMStateStore= $UpdateAppTransition.transition(RMStateStore.java:148) > =09at org.apache.hadoop.yarn.state.StateMachineFactory$SingleInternalArc.= doTransition(StateMachineFactory.java:362) > =09at org.apache.hadoop.yarn.state.StateMachineFactory.doTransition(State= MachineFactory.java:302) > =09at org.apache.hadoop.yarn.state.StateMachineFactory.access$300(StateMa= chineFactory.java:46) > =09at org.apache.hadoop.yarn.state.StateMachineFactory$InternalStateMachi= ne.doTransition(StateMachineFactory.java:448) > =09at org.apache.hadoop.yarn.server.resourcemanager.recovery.RMStateStore= .handleStoreEvent(RMStateStore.java:810) > =09at org.apache.hadoop.yarn.server.resourcemanager.recovery.RMStateStore= $ForwardingEventHandler.handle(RMStateStore.java:864) > =09at org.apache.hadoop.yarn.server.resourcemanager.recovery.RMStateStore= $ForwardingEventHandler.handle(RMStateStore.java:859) > =09at org.apache.hadoop.yarn.event.AsyncDispatcher.dispatch(AsyncDispatch= er.java:173) > =09at org.apache.hadoop.yarn.event.AsyncDispatcher$1.run(AsyncDispatcher.= java:106) > =09at java.lang.Thread.run(Thread.java:745) > 2017-09-08 02:34:37,987 INFO org.apache.hadoop.yarn.server.resourcemanage= r.rmcontainer.RMContainerImpl: container_1504809243340_0001_01_000001 Conta= iner Transitioned from ACQUIRED to KILLED > 2017-09-08 02:34:37,987 INFO org.apache.hadoop.yarn.server.resourcemanage= r.scheduler.common.fica.FiCaSchedulerApp: Completed container: container_15= 04809243340_0001_01_000001 in state: KILLED event:KILL > 2017-09-08 02:34:37,987 INFO org.apache.hadoop.yarn.server.resourcemanage= r.RMAuditLogger: USER=3Dhires=09OPERATION=3DAM Released Container=09TARGET= =3DSchedulerApp=09RESULT=3DSUCCESS=09APPID=3Dapplication_1504809243340_0001= =09CONTAINERID=3Dcontainer_1504809243340_0001_01_000001 > 2017-09-08 02:34:37,988 INFO org.apache.hadoop.yarn.server.resourcemanage= r.scheduler.SchedulerNode: Released container container_1504809243340_0001_= 01_000001 of capacity on host hadoop11:45454, which= currently has 0 containers, used and available, release resources=3Dtrue > 2017-09-08 02:34:37,988 INFO org.apache.hadoop.util.ExitUtil: Exiting wit= h status 1 > 2017-09-08 02:34:37,988 INFO org.apache.hadoop.yarn.server.resourcemanage= r.scheduler.capacity.LeafQueue: default used=3D numCont= ainers=3D0 user=3Dhires user-resources=3D > 2017-09-08 02:34:37,989 INFO org.apache.hadoop.yarn.server.resourcemanage= r.scheduler.capacity.LeafQueue: completedContainer container=3DContainer: [= ContainerId: container_1504809243340_0001_01_000001, NodeId: hadoop11:45454= , NodeHttpAddress: hadoop11:8042, Resource: , Priori= ty: 0, Token: Token { kind: ContainerToken, service: 10.3.1.11:45454 }, ] q= ueue=3Ddefault: capacity=3D1.0, absoluteCapacity=3D1.0, usedResources=3D, usedCapacity=3D0.0, absoluteUsedCapacity=3D0.0, numApps= =3D1, numContainers=3D0 cluster=3D > 2017-09-08 02:34:37,989 INFO org.apache.hadoop.yarn.server.resourcemanage= r.scheduler.capacity.ParentQueue: completedContainer queue=3Droot usedCapac= ity=3D0.0 absoluteUsedCapacity=3D0.0 used=3D cluster=3D= > 2017-09-08 02:34:37,990 INFO org.apache.hadoop.yarn.server.resourcemanage= r.scheduler.capacity.ParentQueue: Re-sorting completed queue: root.default = stats: default: capacity=3D1.0, absoluteCapacity=3D1.0, usedResources=3D, usedCapacity=3D0.0, absoluteUsedCapacity=3D0.0, numApps= =3D1, numContainers=3D0 > 2017-09-08 02:34:37,990 INFO org.apache.hadoop.yarn.server.resourcemanage= r.scheduler.capacity.CapacityScheduler: Application attempt appattempt_1504= 809243340_0001_000001 released container container_1504809243340_0001_01_00= 0001 on node: host: hadoop11:45454 #containers=3D0 available=3D8096 used=3D= 0 with event: KILL > 2017-09-08 02:34:37,990 INFO org.apache.hadoop.yarn.server.resourcemanage= r.scheduler.AppSchedulingInfo: Application application_1504809243340_0001 r= equests cleared > 2017-09-08 02:34:37,990 INFO org.apache.hadoop.yarn.server.resourcemanage= r.scheduler.capacity.LeafQueue: Application removed - appId: application_15= 04809243340_0001 user: hires queue: default #user-pending-applications: 0 #= user-active-applications: 0 #queue-pending-applications: 0 #queue-active-ap= plications: 0 > 2017-09-08 02:34:38,001 ERROR org.apache.hadoop.security.token.delegation= .AbstractDelegationTokenSecretManager: ExpiredTokenRemover received java.la= ng.InterruptedException: sleep interrupted > 2017-09-08 02:34:38,005 INFO org.mortbay.log: Stopped HttpServer2$SelectC= hannelConnectorWithSafeStartup@hadoop11:8088 > 2017-09-08 02:34:38,005 ERROR org.apache.hadoop.security.token.delegation= .AbstractDelegationTokenSecretManager: ExpiredTokenRemover received java.la= ng.InterruptedException: sleep interrupted > 2017-09-08 02:34:38,006 ERROR org.apache.hadoop.security.token.delegation= .AbstractDelegationTokenSecretManager: ExpiredTokenRemover received java.la= ng.InterruptedException: sleep interrupted > 2017-09-08 02:34:38,108 INFO org.apache.hadoop.ipc.Server: Stopping serve= r on 8032 > 2017-09-08 02:34:38,113 INFO org.apache.hadoop.ipc.Server: Stopping IPC S= erver listener on 8032 > 2017-09-08 02:34:38,113 INFO org.apache.hadoop.ipc.Server: Stopping serve= r on 8033 > 2017-09-08 02:34:38,114 INFO org.apache.hadoop.ipc.Server: Stopping IPC S= erver Responder > 2017-09-08 02:34:38,114 INFO org.apache.hadoop.ipc.Server: Stopping IPC S= erver listener on 8033 > 2017-09-08 02:34:38,114 INFO org.apache.hadoop.ipc.Server: Stopping IPC S= erver Responder > {code} -- This message was sent by Atlassian JIRA (v7.6.3#76005) --------------------------------------------------------------------- To unsubscribe, e-mail: yarn-issues-unsubscribe@hadoop.apache.org For additional commands, e-mail: yarn-issues-help@hadoop.apache.org