From issues-return-86057-archive-asf-public=cust-asf.ponee.io@ignite.apache.org Mon Dec 17 16:58:49 2018 Return-Path: X-Original-To: archive-asf-public@cust-asf.ponee.io Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by mx-eu-01.ponee.io (Postfix) with SMTP id 2DB0D180652 for ; Mon, 17 Dec 2018 16:58:48 +0100 (CET) Received: (qmail 81706 invoked by uid 500); 17 Dec 2018 15:58:47 -0000 Mailing-List: contact issues-help@ignite.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@ignite.apache.org Delivered-To: mailing list issues@ignite.apache.org Received: (qmail 81693 invoked by uid 99); 17 Dec 2018 15:58:47 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd3-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 17 Dec 2018 15:58:47 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd3-us-west.apache.org (ASF Mail Server at spamd3-us-west.apache.org) with ESMTP id ED6D91805CB for ; Mon, 17 Dec 2018 15:58:46 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd3-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: -110.301 X-Spam-Level: X-Spam-Status: No, score=-110.301 tagged_above=-999 required=6.31 tests=[ENV_AND_HDR_SPF_MATCH=-0.5, RCVD_IN_DNSWL_MED=-2.3, SPF_PASS=-0.001, USER_IN_DEF_SPF_WL=-7.5, USER_IN_WHITELIST=-100] autolearn=disabled Received: from mx1-lw-us.apache.org ([10.40.0.8]) by localhost (spamd3-us-west.apache.org [10.40.0.10]) (amavisd-new, port 10024) with ESMTP id 1t4LfmQw0lxd for ; Mon, 17 Dec 2018 15:58:45 +0000 (UTC) Received: from mailrelay1-us-west.apache.org (mailrelay1-us-west.apache.org [209.188.14.139]) by mx1-lw-us.apache.org (ASF Mail Server at mx1-lw-us.apache.org) with ESMTP id DF01B5F65D for ; Mon, 17 Dec 2018 15:50: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 EF7F4E0F39 for ; Mon, 17 Dec 2018 15:50: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 623A623FCF for ; Mon, 17 Dec 2018 15:50:00 +0000 (UTC) Date: Mon, 17 Dec 2018 15:50:00 +0000 (UTC) From: "Sergey Chugunov (JIRA)" To: issues@ignite.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Commented] (IGNITE-10238) Intermittent Client Nodes suite hang 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/IGNITE-10238?page=3Dcom.atlassi= an.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=3D16= 723096#comment-16723096 ]=20 Sergey Chugunov commented on IGNITE-10238: ------------------------------------------ [~NSAmelchev], I used test from your PR as it reproduces deadlock easily and I found the p= roblem. We already have a code in=C2=A0_CacheObjectBinaryProcessorImpl::metadata_= =C2=A0that allows to read metadata right away=C2=A0if it is requested from = discovery thread (take a look at line 639 in your PR). But for some reason it doesn't work although I know it was fixed at some mo= ment. Could you please take a look and sort out why it is broken again and how to= fix it? > Intermittent Client Nodes suite hang > ------------------------------------ > > Key: IGNITE-10238 > URL: https://issues.apache.org/jira/browse/IGNITE-10238 > Project: Ignite > Issue Type: Test > Reporter: Alexey Goncharuk > Assignee: Amelchev Nikita > Priority: Critical > Labels: MakeTeamcityGreenAgain > Fix For: 2.8 > > > There are occasional hangs of Client Nodes suite in master. A quick peek = at the thread dumps reveals an interesting deadlock (only relevant parts of= the thread dump are left): > {code} > "disco-notifier-worker-#634%internal.IgniteClientReconnectApiExceptionTes= t0%" #791 prio=3D5 os_prio=3D0 tid=3D0x00007f990c12d800 nid=3D0x11b9 waitin= g on condition [0x00007f991a0eb000] > java.lang.Thread.State: WAITING (parking) > =09at sun.misc.Unsafe.park(Native Method) > =09at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304) > =09at org.apache.ignite.internal.util.future.GridFutureAdapter.get0(GridF= utureAdapter.java:178) > =09at org.apache.ignite.internal.util.future.GridFutureAdapter.get(GridFu= tureAdapter.java:141) > =09at org.apache.ignite.internal.processors.cache.binary.CacheObjectBinar= yProcessorImpl.metadata(CacheObjectBinaryProcessorImpl.java:656) > =09at org.apache.ignite.internal.processors.cache.binary.CacheObjectBinar= yProcessorImpl$1.metadata(CacheObjectBinaryProcessorImpl.java:206) > =09at org.apache.ignite.internal.binary.BinaryContext.metadata(BinaryCont= ext.java:1293) > =09at org.apache.ignite.internal.binary.BinaryReaderExImpl.getOrCreateSch= ema(BinaryReaderExImpl.java:2007) > =09at org.apache.ignite.internal.binary.BinaryReaderExImpl.(BinaryR= eaderExImpl.java:286) > =09at org.apache.ignite.internal.binary.BinaryReaderExImpl.(BinaryR= eaderExImpl.java:185) > =09at org.apache.ignite.internal.binary.BinaryReaderExImpl.readField(Bina= ryReaderExImpl.java:1984) > =09at org.apache.ignite.internal.binary.BinaryFieldAccessor$DefaultFinalC= lassAccessor.read0(BinaryFieldAccessor.java:703) > =09at org.apache.ignite.internal.binary.BinaryFieldAccessor.read(BinaryFi= eldAccessor.java:188) > =09at org.apache.ignite.internal.binary.BinaryClassDescriptor.read(Binary= ClassDescriptor.java:874) > =09at org.apache.ignite.internal.binary.BinaryReaderExImpl.deserialize0(B= inaryReaderExImpl.java:1764) > =09at org.apache.ignite.internal.binary.BinaryReaderExImpl.deserialize(Bi= naryReaderExImpl.java:1716) > =09at org.apache.ignite.internal.binary.BinaryReaderExImpl.readField(Bina= ryReaderExImpl.java:1984) > =09at org.apache.ignite.internal.binary.BinaryFieldAccessor$DefaultFinalC= lassAccessor.read0(BinaryFieldAccessor.java:703) > =09at org.apache.ignite.internal.binary.BinaryFieldAccessor.read(BinaryFi= eldAccessor.java:188) > =09at org.apache.ignite.internal.binary.BinaryClassDescriptor.read(Binary= ClassDescriptor.java:874) > =09at org.apache.ignite.internal.binary.BinaryReaderExImpl.deserialize0(B= inaryReaderExImpl.java:1764) > =09at org.apache.ignite.internal.binary.BinaryReaderExImpl.deserialize(Bi= naryReaderExImpl.java:1716) > =09at org.apache.ignite.internal.binary.GridBinaryMarshaller.deserialize(= GridBinaryMarshaller.java:313) > =09at org.apache.ignite.internal.binary.BinaryMarshaller.unmarshal0(Binar= yMarshaller.java:101) > =09at org.apache.ignite.marshaller.AbstractNodeNameAwareMarshaller.unmars= hal(AbstractNodeNameAwareMarshaller.java:81) > =09at org.apache.ignite.internal.util.IgniteUtils.unmarshal(IgniteUtils.j= ava:10131) > =09at org.apache.ignite.internal.util.IgniteUtils.unmarshal(IgniteUtils.j= ava:10160) > =09at org.apache.ignite.internal.GridEventConsumeHandler.p2pUnmarshal(Gri= dEventConsumeHandler.java:390) > =09at org.apache.ignite.internal.processors.continuous.GridContinuousProc= essor.processStartRequest(GridContinuousProcessor.java:1362) > =09at org.apache.ignite.internal.processors.continuous.GridContinuousProc= essor.access$400(GridContinuousProcessor.java:111) > =09at org.apache.ignite.internal.processors.continuous.GridContinuousProc= essor$2.onCustomEvent(GridContinuousProcessor.java:203) > =09at org.apache.ignite.internal.processors.continuous.GridContinuousProc= essor$2.onCustomEvent(GridContinuousProcessor.java:194) > =09at org.apache.ignite.internal.managers.discovery.GridDiscoveryManager$= 4.onDiscovery0(GridDiscoveryManager.java:725) > =09at org.apache.ignite.internal.managers.discovery.GridDiscoveryManager$= 4.lambda$onDiscovery$0(GridDiscoveryManager.java:602) > =09- locked <0x00000007b62859b8> (a java.lang.Object) > =09at org.apache.ignite.internal.managers.discovery.GridDiscoveryManager$= 4$$Lambda$17/432384581.run(Unknown Source) > =09at org.apache.ignite.internal.managers.discovery.GridDiscoveryManager$= DiscoveryMessageNotifierWorker.body0(GridDiscoveryManager.java:2665) > =09at org.apache.ignite.internal.managers.discovery.GridDiscoveryManager$= DiscoveryMessageNotifierWorker.body(GridDiscoveryManager.java:2703) > =09at org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.ja= va:120) > =09at java.lang.Thread.run(Thread.java:748) > "async-callable-runner-1" #876 prio=3D5 os_prio=3D0 tid=3D0x00007f990c26a= 000 nid=3D0x120b waiting on condition [0x00007f991a2ed000] > java.lang.Thread.State: WAITING (parking) > =09at sun.misc.Unsafe.park(Native Method) > =09at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304) > =09at org.apache.ignite.internal.util.future.GridFutureAdapter.get0(GridF= utureAdapter.java:178) > =09at org.apache.ignite.internal.util.future.GridFutureAdapter.get(GridFu= tureAdapter.java:141) > =09at org.apache.ignite.internal.processors.cache.binary.CacheObjectBinar= yProcessorImpl.addMeta(CacheObjectBinaryProcessorImpl.java:495) > =09at org.apache.ignite.internal.processors.cache.binary.CacheObjectBinar= yProcessorImpl$1.addMeta(CacheObjectBinaryProcessorImpl.java:194) > =09at org.apache.ignite.internal.binary.BinaryContext.updateMetadata(Bina= ryContext.java:1332) > =09at org.apache.ignite.internal.binary.BinaryClassDescriptor.write(Binar= yClassDescriptor.java:777) > =09at org.apache.ignite.internal.binary.BinaryWriterExImpl.marshal0(Binar= yWriterExImpl.java:223) > =09at org.apache.ignite.internal.binary.BinaryWriterExImpl.marshal(Binary= WriterExImpl.java:164) > =09at org.apache.ignite.internal.binary.BinaryWriterExImpl.marshal(Binary= WriterExImpl.java:151) > =09at org.apache.ignite.internal.binary.GridBinaryMarshaller.marshal(Grid= BinaryMarshaller.java:254) > =09at org.apache.ignite.internal.binary.BinaryMarshaller.marshal0(BinaryM= arshaller.java:84) > =09at org.apache.ignite.marshaller.AbstractNodeNameAwareMarshaller.marsha= l(AbstractNodeNameAwareMarshaller.java:57) > =09at org.apache.ignite.internal.util.IgniteUtils.marshal(IgniteUtils.jav= a:10213) > =09at org.apache.ignite.internal.processors.task.GridTaskWorker.sendReque= st(GridTaskWorker.java:1387) > =09at org.apache.ignite.internal.processors.task.GridTaskWorker.processMa= ppedJobs(GridTaskWorker.java:666) > =09at org.apache.ignite.internal.processors.task.GridTaskWorker.body(Grid= TaskWorker.java:538) > =09at org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.ja= va:120) > =09at org.apache.ignite.internal.processors.task.GridTaskProcessor.startT= ask(GridTaskProcessor.java:809) > =09at org.apache.ignite.internal.processors.task.GridTaskProcessor.execut= e(GridTaskProcessor.java:476) > =09at org.apache.ignite.internal.processors.closure.GridClosureProcessor.= callAsync(GridClosureProcessor.java:449) > =09at org.apache.ignite.internal.processors.closure.GridClosureProcessor.= callAsync(GridClosureProcessor.java:420) > =09at org.apache.ignite.internal.IgniteComputeImpl.broadcastAsync0(Ignite= ComputeImpl.java:635) > =09at org.apache.ignite.internal.IgniteComputeImpl.broadcast(IgniteComput= eImpl.java:611) > =09at org.apache.ignite.internal.IgniteClientReconnectApiExceptionTest$26= .apply(IgniteClientReconnectApiExceptionTest.java:578) > =09at org.apache.ignite.internal.IgniteClientReconnectApiExceptionTest$26= .apply(IgniteClientReconnectApiExceptionTest.java:574) > =09at org.apache.ignite.internal.IgniteClientReconnectApiExceptionTest$36= .call(IgniteClientReconnectApiExceptionTest.java:853) > =09at org.apache.ignite.internal.IgniteClientReconnectApiExceptionTest$36= .call(IgniteClientReconnectApiExceptionTest.java:851) > =09at org.apache.ignite.testframework.GridTestUtils.lambda$runAsync$2(Gri= dTestUtils.java:1003) > =09at org.apache.ignite.testframework.GridTestUtils$$Lambda$133/962953099= .run(Unknown Source) > =09at org.apache.ignite.testframework.GridTestUtils$7.call(GridTestUtils.= java:1299) > =09at org.apache.ignite.testframework.GridTestThread.run(GridTestThread.j= ava:84) > "tcp-disco-msg-worker-#88%internal.IgniteClientReconnectApiExceptionTest0= %" #792 prio=3D10 os_prio=3D0 tid=3D0x00007f990c130800 nid=3D0x11ba waiting= on condition [0x00007f997fdfc000] > java.lang.Thread.State: WAITING (parking) > =09at sun.misc.Unsafe.park(Native Method) > =09at java.util.concurrent.locks.LockSupport.park(LockSupport.java:304) > =09at org.apache.ignite.internal.util.future.GridFutureAdapter.get0(GridF= utureAdapter.java:178) > =09at org.apache.ignite.internal.util.future.GridFutureAdapter.get(GridFu= tureAdapter.java:141) > =09at org.apache.ignite.internal.util.future.IgniteFutureImpl.get(IgniteF= utureImpl.java:134) > =09at org.apache.ignite.spi.discovery.tcp.ServerImpl$RingMessageWorker.no= tifyDiscoveryListener(ServerImpl.java:5648) > =09at org.apache.ignite.spi.discovery.tcp.ServerImpl$RingMessageWorker.pr= ocessCustomMessage(ServerImpl.java:5455) > =09at org.apache.ignite.spi.discovery.tcp.ServerImpl$RingMessageWorker.pr= ocessMessage(ServerImpl.java:2836) > =09at org.apache.ignite.spi.discovery.tcp.ServerImpl$RingMessageWorker.pr= ocessMessage(ServerImpl.java:2610) > =09at org.apache.ignite.spi.discovery.tcp.ServerImpl$MessageWorker.body(S= erverImpl.java:7186) > =09at org.apache.ignite.spi.discovery.tcp.ServerImpl$RingMessageWorker.bo= dy(ServerImpl.java:2699) > =09at org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.ja= va:120) > =09at org.apache.ignite.spi.discovery.tcp.ServerImpl$MessageWorkerThread.= body(ServerImpl.java:7117) > =09at org.apache.ignite.spi.IgniteSpiThread.run(IgniteSpiThread.java:61) > {code} > Need to investigate what it is that we are trying to deserialize in the d= iscovery thread. From the binary metadata workflow we should be able to des= erialize the value right away. -- This message was sent by Atlassian JIRA (v7.6.3#76005)