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 Received: from bombadil.infradead.org (bombadil.infradead.org [198.137.202.133]) (using TLSv1.2 with cipher ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)) (No client certificate requested) by smtp.lore.kernel.org (Postfix) with ESMTPS id DBA58C433F5 for ; Tue, 1 Mar 2022 18:20:52 +0000 (UTC) DKIM-Signature: v=1; a=rsa-sha256; q=dns/txt; c=relaxed/relaxed; d=lists.infradead.org; s=bombadil.20210309; h=Sender: Content-Transfer-Encoding:Content-Type:List-Subscribe:List-Help:List-Post: List-Archive:List-Unsubscribe:List-Id:In-Reply-To:MIME-Version:References: Message-ID:Subject:Cc:To:From:Date:Reply-To:Content-ID:Content-Description: Resent-Date:Resent-From:Resent-Sender:Resent-To:Resent-Cc:Resent-Message-ID: List-Owner; bh=xAQ5n2O+Z58nJB+FBEi59/W6MULMpqD/kclywBjewiA=; b=WifQqljgKzTRjZ iEfOAXpYr3LpJsgEYFWXnn9vPKk9wyNKO1a5xJDPVwcJ+F5b5pMrFgtMbEKpoK79vQ22vI7+13vWq 8NVyNLEE5mnkxXovtM3MbOM4ajkzGDr+05EkO8fLIdMakOnrYd8VSbV+wS6kOksTtts00v1ArAky9 7K2Mq8UQvyZwtnLAU/1gwt2/oirHLBlRLMHcAof0vhyRcq1bIP9N3LVUQzNCK9yfYoLnB2eo86Bvw nCTqs2gM4IvjGXAYZgjDKk+aYmgNIZcB3PgkDOYhUK0RvVFg9kkqxsKWF6fPhpkndhHbHFh7CFF9p wi5kxZ6VfaGp6WD1P/fw==; Received: from localhost ([::1] helo=bombadil.infradead.org) by bombadil.infradead.org with esmtp (Exim 4.94.2 #2 (Red Hat Linux)) id 1nP764-000AE5-Tw; Tue, 01 Mar 2022 18:19:41 +0000 Received: from mail-wm1-x32f.google.com ([2a00:1450:4864:20::32f]) by bombadil.infradead.org with esmtps (Exim 4.94.2 #2 (Red Hat Linux)) id 1nP760-000ADX-DA for linux-arm-kernel@lists.infradead.org; Tue, 01 Mar 2022 18:19:38 +0000 Received: by mail-wm1-x32f.google.com with SMTP id i66so1786496wma.5 for ; Tue, 01 Mar 2022 10:19:36 -0800 (PST) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=20210112; h=date:from:to:cc:subject:message-id:references:mime-version :content-disposition:content-transfer-encoding:in-reply-to; bh=UtPrVb0f53YYX2b6RFlODaq1yL/aXGcjZzyo6JeDgY8=; b=g95HB/jmWuwfM/wFu7blazFMp1er0UHfAATqb0J38vUtYvYMCDKjgMWv1FT0vwJWYf /GWaxy8Duco6mA8a0KGNJdOuS98FO2c013FObEhtlIfUD2yLuOd9jZ5u+o73D72zlecb SKEcdDEpQ8U4vRL6t9/YfRtn8Ss58R7+roDU6DtAXyK37ww3R8kOVPyro/dw1ftZfFZZ 3BR42Jh9cEzwFGXcBx5Ooyl5+Fvl0DC3pGFhxu+hodEWnsQWlJuzz5t0AnilHZf+01tT 421CpvAN4AkxYWIPbDmAptu04lUEokrWWBY+dIJmQH5UxhTySyWIvQOT2W2v5adCbFYC LmtQ== X-Google-DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=1e100.net; s=20210112; h=x-gm-message-state:date:from:to:cc:subject:message-id:references :mime-version:content-disposition:content-transfer-encoding :in-reply-to; bh=UtPrVb0f53YYX2b6RFlODaq1yL/aXGcjZzyo6JeDgY8=; b=UrEUha7A+DgZEyCXk1CcIe7a3gqveW3WomvcT4elHR0hwC5E5AEpud2rjBTvfE1hbb ryyS8iqPCImQ4hYxToFnoW/igXOUkYq38aRIdZ4ivd8I5C7ekszqECLAZRaAwhBvzQEP LrOlCif/TobHQ+DGsdVybsnSoLuTI1YXgAl7q1x5BWRGgb0fRTZMnvQmFRzKV9D1U89j HsPbECt4vsUtqHKlXuc2Ho3tSYPDZieZCWwUZD5QAxPGZlKFVDo6RszJlwLd4icPYB3g 1wLwCTRZ3IL3/aF1py6R2ooQyNUr0Rqn5PwTxzovlwipsH5sIOYMG34EOFOI9/SVsfH2 YUvw== X-Gm-Message-State: AOAM530q+qJDt58X84u63Ql/wQH4p9dmFmxZbBxnVGT/5y9eGq4Hf8w7 b3AeQk4CVRPsvGxmxTHoZ/w= X-Google-Smtp-Source: ABdhPJwArUy4EplXDgim3tlE+gfX5EUBMhpOuGajKvWhFwjLZvDTmdPvoA97uyTRj+kWrB2X64JA8g== X-Received: by 2002:a05:600c:1d08:b0:381:6eda:67d1 with SMTP id l8-20020a05600c1d0800b003816eda67d1mr7557489wms.88.1646158775052; Tue, 01 Mar 2022 10:19:35 -0800 (PST) Received: from Red ([2a01:cb1d:3d5:a100:264b:feff:fe03:2806]) by smtp.googlemail.com with ESMTPSA id 10-20020adf808a000000b001edd413a952sm14535256wrl.95.2022.03.01.10.19.34 (version=TLS1_3 cipher=TLS_AES_256_GCM_SHA384 bits=256/256); Tue, 01 Mar 2022 10:19:34 -0800 (PST) Date: Tue, 1 Mar 2022 19:19:33 +0100 From: Corentin Labbe To: Ard Biesheuvel Cc: "Russell King (Oracle)" , Linux ARM , Linux Kernel Mailing List Subject: Re: boot flooded with unwind: Index not found Message-ID: References: MIME-Version: 1.0 Content-Disposition: inline In-Reply-To: X-CRM114-Version: 20100106-BlameMichelson ( TRE 0.8.0 (BSD) ) MR-646709E3 X-CRM114-CacheID: sfid-20220301_101936_495697_12CDB033 X-CRM114-Status: GOOD ( 29.63 ) X-BeenThere: linux-arm-kernel@lists.infradead.org X-Mailman-Version: 2.1.34 Precedence: list List-Id: List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Content-Type: text/plain; charset="iso-8859-1" Content-Transfer-Encoding: quoted-printable Sender: "linux-arm-kernel" Errors-To: linux-arm-kernel-bounces+linux-arm-kernel=archiver.kernel.org@lists.infradead.org Le Tue, Mar 01, 2022 at 05:52:30PM +0100, Ard Biesheuvel a =E9crit : > On Tue, 1 Mar 2022 at 17:37, Ard Biesheuvel wrote: > > > > On Tue, 1 Mar 2022 at 16:52, Russell King (Oracle) > > wrote: > > > > > > On Tue, Mar 01, 2022 at 04:48:25PM +0100, Corentin Labbe wrote: > > > > Hello > > > > > > > > I booted today linux-next (20220301) and my boot is flooded with: > > > > [ 0.000000] unwind: Index not found c0f0c440 > > > > [ 0.000000] unwind: Index not found 00000000 > > > > [ 0.000000] unwind: Index not found c0f0c440 > > > > [ 0.000000] unwind: Index not found 00000000 > > > > > > > > This happen on a sun8i-a83t-bananapi-m3 > > > > > > Have you enabled vmapped stacks? > > > > > > > This is probably related to > > > > 538b9265c063 ARM: unwind: track location of LR value in stack frame > > > > which removes a kernel_text_address() check on frame->pc as it is > > essentially redundant, given that we won't find unwind data otherwise. > > Unfortunately, I failed to realise that the other check carries a > > pr_warn(), which may apparently fire spuriously in some cases. > > > > The 0x0 value can easily be filtered out, but i would be interesting > > where the other value originates from. We might be able to solve this > > with a simple .nounwind directive in a asm routine somewhere. > > > > I'll prepare a patch that disregards the 0x0 value - could you check > > in the mean time what the address 0xcf0c440 coincides with in your > > build? > = > Something like the below should restore the previous behavior, while > taking the kernel_text_address() check out of the hot path. > = > --- a/arch/arm/kernel/unwind.c > +++ b/arch/arm/kernel/unwind.c > @@ -400,7 +400,8 @@ int unwind_frame(struct stackframe *frame) > = > idx =3D unwind_find_idx(frame->pc); > if (!idx) { > - pr_warn("unwind: Index not found %08lx\n", frame->pc); > + if (frame->pc && kernel_text_address(frame->pc)) > + pr_warn("unwind: Index not found %08lx\n", frame-= >pc); > return -URC_FAILURE; > } Thanks, message are not shown anymore. But now I have other errors (perhaps not related): [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instr= uction cache [ 0.000000] OF: fdt: Machine model: Banana Pi BPI-M3 [ 0.000000] earlycon: uart0 at MMIO32 0x01c28000 (options '') [ 0.000000] printk: bootconsole [uart0] enabled [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] cma: Reserved 16 MiB at 0xbf000000 [ 0.000000] Zone ranges: [ 0.000000] Normal [mem 0x0000000040000000-0x000000006fffffff] [ 0.000000] HighMem [mem 0x0000000070000000-0x00000000bfffffff] [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x00000000bffff= fff] [ 0.000000] percpu: Embedded 16 pages/cpu s34740 r8192 d22604 u65536 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 522752 [ 0.000000] Kernel command line: console=3DttyS0,115200n8 root=3D/dev/ra= m0 earlycon=3Duart,mmio32,0x01c28000 ip=3Ddhcp [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 by= tes, linear) [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 byte= s, linear) [ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off [ 0.000000] Memory: 2016360K/2097152K available (9216K kernel code, 1534= K rwdata, 3328K rodata, 1024K init, 7189K bss, 64408K reserved, 16384K cma-= reserved, 1294336K highmem) [ 0.000000] SLUB: HWalign=3D64, Order=3D0-3, MinObjects=3D0, CPUs=3D8, N= odes=3D1 [ 0.000000] trace event string verifier disabled [ 0.000000] Running RCU self tests [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU lockdep checking is enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 1= 0 jiffies. [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contentio= n. [ 0.000000] random: get_random_bytes called from start_kernel+0x534/0x6c= c with crng_init=3D0 [ 0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (virt). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cy= cles: 0x588fe9dc0, max_idle_ns: 440795202592 ns [ 0.000003] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every = 4398046511097ns [ 0.008660] Switching to timer-based delay loop, resolution 41ns [ 0.015599] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff,= max_idle_ns: 79635851949 ns [ 0.026635] Console: colour dummy device 80x30 [ 0.031549] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc.,= Ingo Molnar [ 0.039999] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.044452] ... MAX_LOCK_DEPTH: 48 [ 0.049004] ... MAX_LOCKDEP_KEYS: 8192 [ 0.053774] ... CLASSHASH_SIZE: 4096 [ 0.058511] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.063349] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.068222] ... CHAINHASH_SIZE: 32768 [ 0.073054] memory used by lock dependency info: 4061 kB [ 0.078950] memory used for stack traces: 2112 kB [ 0.084155] per task-struct memory footprint: 1536 bytes [ 0.090209] Calibrating delay loop (skipped), value calculated using tim= er frequency.. 48.00 BogoMIPS (lpj=3D240000) [ 0.101504] pid_max: default: 32768 minimum: 301 [ 0.107656] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, = linear) [ 0.115669] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 by= tes, linear) [ 0.127831] CPU: Testing write buffer coherency: ok [ 0.135293] /cpus/cpu@0 missing clock-frequency property [ 0.141394] /cpus/cpu@1 missing clock-frequency property [ 0.147356] /cpus/cpu@2 missing clock-frequency property [ 0.153422] /cpus/cpu@3 missing clock-frequency property [ 0.159447] /cpus/cpu@100 missing clock-frequency property [ 0.165760] /cpus/cpu@101 missing clock-frequency property [ 0.172058] /cpus/cpu@102 missing clock-frequency property [ 0.178452] /cpus/cpu@103 missing clock-frequency property [ 0.184451] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.196487] Setting up static identity map for 0x40100000 - 0x40100060 [ 0.206468] ARM CCI driver probed [ 0.211477] sunxi multi cluster SMP support installed [ 0.218724] rcu: Hierarchical SRCU implementation. [ 0.229044] smp: Bringing up secondary CPUs ... [ 0.239387] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.239462] = [ 0.239468] = [ 0.239476] =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D [ 0.239479] WARNING: suspicious RCU usage [ 0.239485] 5.17.0-rc6-next-20220301-00132-gc96ac15508a4-dirty #197 Not = tainted [ 0.239493] ----------------------------- [ 0.239497] include/trace/events/lock.h:58 suspicious rcu_dereference_ch= eck() usage! [ 0.239505] = [ 0.239505] other info that might help us debug this: [ 0.239505] = [ 0.239509] = [ 0.239509] rcu_scheduler_active =3D 1, debug_locks =3D 1 [ 0.239516] RCU used illegally from extended quiescent state! [ 0.239521] 1 lock held by swapper/0/0: [ 0.239527] #0: c0f15b4c ((console_sem).lock){-...}-{2:2}, at: down_try= lock+0xc/0x2c [ 0.239598] = [ 0.239598] stack backtrace: [ 0.239607] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 5.17.0-rc6-next-20= 220301-00132-gc96ac15508a4-dirty #197 [ 0.239619] Hardware name: Allwinner A83t board [ 0.239629] unwind_backtrace from show_stack+0x10/0x14 [ 0.239654] show_stack from init_stack+0x1c54/0x2000 [ 0.343881] =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D [ 0.348205] WARNING: suspicious RCU usage [ 0.352529] 5.17.0-rc6-next-20220301-00132-gc96ac15508a4-dirty #197 Not = tainted [ 0.360417] ----------------------------- [ 0.364741] include/trace/events/lock.h:13 suspicious rcu_dereference_ch= eck() usage! [ 0.373098] = [ 0.373098] other info that might help us debug this: [ 0.373098] = [ 0.381738] = [ 0.381738] rcu_scheduler_active =3D 1, debug_locks =3D 1 [ 0.388787] RCU used illegally from extended quiescent state! [ 0.394992] no locks held by swapper/0/0. [ 0.399317] = [ 0.399317] stack backtrace: [ 0.404014] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 5.17.0-rc6-next-20= 220301-00132-gc96ac15508a4-dirty #197 [ 0.414706] Hardware name: Allwinner A83t board [ 0.419595] unwind_backtrace from show_stack+0x10/0x14 [ 0.425251] show_stack from init_stack+0x1d5c/0x2000 _______________________________________________ linux-arm-kernel mailing list linux-arm-kernel@lists.infradead.org http://lists.infradead.org/mailman/listinfo/linux-arm-kernel