From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: X-Spam-Checker-Version: SpamAssassin 3.4.0 (2014-02-07) on aws-us-west-2-korg-lkml-1.web.codeaurora.org X-Spam-Level: X-Spam-Status: No, score=-5.3 required=3.0 tests=HEADER_FROM_DIFFERENT_DOMAINS, INCLUDES_PATCH,MAILING_LIST_MULTI,SPF_HELO_NONE,SPF_PASS,USER_AGENT_SANE_1 autolearn=ham autolearn_force=no version=3.4.0 Received: from mail.kernel.org (mail.kernel.org [198.145.29.99]) by smtp.lore.kernel.org (Postfix) with ESMTP id E6528C47404 for ; Wed, 9 Oct 2019 11:39:19 +0000 (UTC) Received: from kanga.kvack.org (kanga.kvack.org [205.233.56.17]) by mail.kernel.org (Postfix) with ESMTP id A40A721721 for ; Wed, 9 Oct 2019 11:39:19 +0000 (UTC) DMARC-Filter: OpenDMARC Filter v1.3.2 mail.kernel.org A40A721721 Authentication-Results: mail.kernel.org; dmarc=none (p=none dis=none) header.from=suse.com Authentication-Results: mail.kernel.org; spf=pass smtp.mailfrom=owner-linux-mm@kvack.org Received: by kanga.kvack.org (Postfix) id 2C75A8E0005; Wed, 9 Oct 2019 07:39:19 -0400 (EDT) Received: by kanga.kvack.org (Postfix, from userid 40) id 278838E0003; Wed, 9 Oct 2019 07:39:19 -0400 (EDT) X-Delivered-To: int-list-linux-mm@kvack.org Received: by kanga.kvack.org (Postfix, from userid 63042) id 18F3E8E0005; Wed, 9 Oct 2019 07:39:19 -0400 (EDT) X-Delivered-To: linux-mm@kvack.org Received: from forelay.hostedemail.com (smtprelay0120.hostedemail.com [216.40.44.120]) by kanga.kvack.org (Postfix) with ESMTP id E90168E0003 for ; Wed, 9 Oct 2019 07:39:18 -0400 (EDT) Received: from smtpin13.hostedemail.com (10.5.19.251.rfc1918.com [10.5.19.251]) by forelay04.hostedemail.com (Postfix) with SMTP id 80E9145A4 for ; Wed, 9 Oct 2019 11:39:18 +0000 (UTC) X-FDA: 76024050396.13.knot44_1700c34e8c33f X-HE-Tag: knot44_1700c34e8c33f X-Filterd-Recvd-Size: 7309 Received: from mx1.suse.de (mx2.suse.de [195.135.220.15]) by imf48.hostedemail.com (Postfix) with ESMTP for ; Wed, 9 Oct 2019 11:39:17 +0000 (UTC) X-Virus-Scanned: by amavisd-new at test-mx.suse.de Received: from relay2.suse.de (unknown [195.135.220.254]) by mx1.suse.de (Postfix) with ESMTP id 430DBAFD4; Wed, 9 Oct 2019 11:39:16 +0000 (UTC) Date: Wed, 9 Oct 2019 13:39:15 +0200 From: Petr Mladek To: Qian Cai Cc: Michal Hocko , Christian Borntraeger , Heiko Carstens , sergey.senozhatsky.work@gmail.com, rostedt@goodmis.org, peterz@infradead.org, linux-mm@kvack.org, john.ogness@linutronix.de, akpm@linux-foundation.org, Vasily Gorbik , Peter Oberparleiter , david@redhat.com, linux-kernel@vger.kernel.org Subject: Re: [PATCH v2] mm/page_isolation: fix a deadlock with printk() Message-ID: <20191009113915.xhjswocremwmdum7@pathway.suse.cz> References:<1570228005-24979-1-git-send-email-cai@lca.pw> <20191007143002.l37bt2lzqtnqjqxu@pathway.suse.cz> <20191007144937.GO2381@dhcp22.suse.cz> <20191008074357.f33f6pbs4cw5majk@pathway.suse.cz> <20191008082752.GB6681@dhcp22.suse.cz> <1570550917.5576.303.camel@lca.pw> <20191008183525.GQ6681@dhcp22.suse.cz> <1570561573.5576.307.camel@lca.pw> MIME-Version: 1.0 Content-Type: text/plain; charset=iso-8859-1 Content-Disposition: inline In-Reply-To:<1570561573.5576.307.camel@lca.pw> User-Agent: NeoMutt/20170912 (1.9.0) Content-Transfer-Encoding: quoted-printable X-Bogosity: Ham, tests=bogofilter, spamicity=0.000000, version=1.2.4 Sender: owner-linux-mm@kvack.org Precedence: bulk X-Loop: owner-majordomo@kvack.org List-ID: On Tue 2019-10-08 15:06:13, Qian Cai wrote: > On Tue, 2019-10-08 at 20:35 +0200, Michal Hocko wrote: > > On Tue 08-10-19 12:08:37, Qian Cai wrote: > > > On Tue, 2019-10-08 at 14:56 +0200, Christian Borntraeger wrote: > > > > Adding Peter Oberparleiter. > > > > Peter, can you have a look? > > > >=20 > > > > On 08.10.19 10:27, Michal Hocko wrote: > > > > > On Tue 08-10-19 09:43:57, Petr Mladek wrote: > > > > > > On Mon 2019-10-07 16:49:37, Michal Hocko wrote: > > > > > > > [Cc s390 maintainers - the lockdep is http://lkml.kernel.or= g/r/1570228005-24979-1-git-send-email-cai@lca.pw > > > > > > > Petr has explained it is a false positive > > > > > > > http://lkml.kernel.org/r/20191007143002.l37bt2lzqtnqjqxu@p= athway.suse.cz] > > > > > > > On Mon 07-10-19 16:30:02, Petr Mladek wrote: > > > > > > > [...] > > > > > > > > I believe that it cannot really happen because: > > > > > > > >=20 > > > > > > > > static int __init > > > > > > > > sclp_console_init(void) > > > > > > > > { > > > > > > > > [...] > > > > > > > > rc =3D sclp_rw_init(); > > > > > > > > [...] > > > > > > > > register_console(&sclp_console); > > > > > > > > return 0; > > > > > > > > } > > > > > > > >=20 > > > > > > > > sclp_rw_init() is called before register_console(). And > > > > > > > > console_unlock() will never call sclp_console_write() bef= ore > > > > > > > > the console is registered. > > > > > > > >=20 > > > > > > > > AFAIK, lockdep only compares existing chain of locks. It = does > > > > > > > > not know about console registration that would make some > > > > > > > > code paths mutually exclusive. > > > > > > > >=20 > > > > > > > > I believe that it is a false positive. I do not know how = to > > > > > > > > avoid this lockdep report. I hope that it will disappear > > > > > > > > by deferring all printk() calls rather soon. > > > > > > >=20 > > > > > > > Thanks a lot for looking into this Petr. I have also checke= d the code > > > > > > > and I really fail to see why the allocation has to be done = under the > > > > > > > lock in the first place. sclp_read_sccb and sclp_init_sccb = are global > > > > > > > variables but I strongly suspect that they need a synchroni= zation during > > > > > > > early init, callbacks are registered only later IIUC: > > > > > >=20 > > > > > > Good idea. It would work when the init function is called onl= y once. > > > > > > But see below. > > > > > >=20 > > > > > > > diff --git a/drivers/s390/char/sclp.c b/drivers/s390/char/s= clp.c > > > > > > > index d2ab3f07c008..4b1c033e3255 100644 > > > > > > > --- a/drivers/s390/char/sclp.c > > > > > > > +++ b/drivers/s390/char/sclp.c > > > > > > > @@ -1169,13 +1169,13 @@ sclp_init(void) > > > > > > > unsigned long flags; > > > > > > > int rc =3D 0; > > > > > > > =20 > > > > > > > + sclp_read_sccb =3D (void *) __get_free_page(GFP_ATOMIC | = GFP_DMA); > > > > > > > + sclp_init_sccb =3D (void *) __get_free_page(GFP_ATOMIC | = GFP_DMA); > > > > > > > spin_lock_irqsave(&sclp_lock, flags); > > > > > > > /* Check for previous or running initialization */ > > > > > > > if (sclp_init_state !=3D sclp_init_state_uninitialized) > > > > > > > goto fail_unlock; > > > > > >=20 > > > > > > It seems that sclp_init() could be called several times in pa= rallel. > > > > > > I see it called from sclp_register() and sclp_initcall(). > > > > >=20 > > > > > Interesting. Something for s390 people to answer I guess. > > > > > Anyway, this should be quite trivial to workaround by a cmpxch = or alike. > > > > >=20 > > >=20 > > > The above fix is simply insufficient, > >=20 > > Isn't this yet another init time lockdep false possitive? >=20 > Again, this is not 100% false positive for sure yet. >=20 > >=20 > > > 00: [=A0=A0=A0=A03.654337] -> #3 (console_owner){....}:=A0=A0=A0=A0= =A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0= =A0=A0=A0=A0 > > > 00: [=A0=A0=A0=A03.654343]=A0=A0=A0=A0=A0=A0=A0=A0lock_acquire+0x21= a/0x468=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0= =A0=A0=A0=A0=A0=A0=A0=A0 > > > 00: [=A0=A0=A0=A03.654345]=A0=A0=A0=A0=A0=A0=A0=A0console_unlock+0x= 3a6/0xa30=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0= =A0=A0=A0=A0=A0=A0 > > > 00: [=A0=A0=A0=A03.654346]=A0=A0=A0=A0=A0=A0=A0=A0vprintk_emit+0x18= 4/0x3c8=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0= =A0=A0=A0=A0=A0=A0=A0=A0 > > > 00: [=A0=A0=A0=A03.654348]=A0=A0=A0=A0=A0=A0=A0=A0vprintk_default+0= x44/0x50=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0= =A0=A0=A0=A0=A0=A0=A0 > > > 00: [=A0=A0=A0=A03.654349]=A0=A0=A0=A0=A0=A0=A0=A0printk+0xa8/0xc0=A0= =A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0= =A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0 > > > 00: [=A0=A0=A0=A03.654351]=A0=A0=A0=A0=A0=A0=A0=A0get_random_u64+0x= 40/0x108=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0= =A0=A0=A0=A0=A0=A0=A0 > > > 00: [=A0=A0=A0=A03.654360]=A0=A0=A0=A0=A0=A0=A0=A0add_to_free_area_= random+0x188/0x1c0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0 > > > 00: [=A0=A0=A0=A03.654364]=A0=A0=A0=A0=A0=A0=A0=A0free_one_page+0x7= 2/0x128=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0= =A0=A0=A0=A0=A0=A0=A0=A0 > > > 00: [=A0=A0=A0=A03.654366]=A0=A0=A0=A0=A0=A0=A0=A0__free_pages_ok+0= x51c/0xca0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0= =A0=A0=A0=A0=A0=A0 > > > 00: [=A0=A0=A0=A03.654368]=A0=A0=A0=A0=A0=A0=A0=A0memblock_free_all= +0x30a/0x3b0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0= =A0=A0=A0=A0 > > > 00: [=A0=A0=A0=A03.654370]=A0=A0=A0=A0=A0=A0=A0=A0mem_init+0x84/0x2= 00=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0= =A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0 > > > 00: [=A0=A0=A0=A03.654371]=A0=A0=A0=A0=A0=A0=A0=A0start_kernel+0x38= 4/0x6a0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0= =A0=A0=A0=A0=A0=A0=A0=A0 > > > 00: [=A0=A0=A0=A03.654373]=A0=A0=A0=A0=A0=A0=A0=A0startup_continue+= 0x70/0xd0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0=A0= =A0=A0=A0=A0=A0=A0 > >=20 > > This one is actually a nice example why trying to get printk out of t= he > > zone->lock is simply not viable. This one is likely a printk to warn > > that the random pool is not fully intiailized. Just because the > > allocator tries to randomize the initial free memory pool. You are no= t > > going to remove that printk, right? >=20 > Well, Sergey had a patch to convert that one to printk_deferred(), but = even with > his patch, it will still trigger the lockdep splat here because the loc= k > dependency between zone->lock --> console_owner is still there from mem= ory > offline. Is's this another printk() that might need to become printk_deferred()? Best Regards, Petr