Re: WARNING: suspicious RCU usage: race/events/tlb.h:57 suspicious rcu_dereference_check() usage!
On Mon, Sep 14, 2020 at 01:29:34PM -0400, Qian Cai wrote: > On Wed, 2020-09-09 at 10:08 +0530, Naresh Kamboju wrote: > > While booting x86_64 with Linux next 20200908 tag kernel this warning > > was noticed. > > This pretty much looks like the same issue in: > > https://lore.kernel.org/lkml/20200902035146.ga45...@roeck-us.net/ > > Can you revert the patchset to see if it is related? Don't bother.. I'll be sending patches soon.
Re: WARNING: suspicious RCU usage: race/events/tlb.h:57 suspicious rcu_dereference_check() usage!
On Wed, 2020-09-09 at 10:08 +0530, Naresh Kamboju wrote: > While booting x86_64 with Linux next 20200908 tag kernel this warning > was noticed. This pretty much looks like the same issue in: https://lore.kernel.org/lkml/20200902035146.ga45...@roeck-us.net/ Can you revert the patchset to see if it is related? > > metadata: > git branch: master > git repo: > https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git > git commit: dff9f829e5b0181d4ed9d35aa62d695292399b54 > git describe: next-20200908 > kernel-config: > http://snapshots.linaro.org/openembedded/lkft/lkft/sumo/intel-corei7-64/lkft/linux-next/853/config > > warning logs: > - > [ 18.874329] Freeing unused kernel image (rodata/data gap) memory: 2012K > [ 18.881107] Run /sbin/init as init process > [ 18.905611] > [ 18.907190] = > [ 18.911194] WARNING: suspicious RCU usage > [ 18.915199] 5.9.0-rc4-next-20200908 #1 Not tainted > [ 18.919982] - > [ 18.923984] /usr/src/kernel/include/trace/events/tlb.h:57 > suspicious rcu_dereference_check() usage! > [ 18.933016] > [ 18.933016] other info that might help us debug this: > [ 18.933016] > [ 18.941006] > [ 18.941006] rcu_scheduler_active = 2, debug_locks = 1 > [ 18.947523] RCU used illegally from extended quiescent state! > [ 18.953261] no locks held by swapper/2/0. > [ 18.957264] > [ 18.957264] stack backtrace: > [ 18.961619] CPU: 2 PID: 0 Comm: swapper/2 Not tainted > 5.9.0-rc4-next-20200908 #1 > [ 18.969007] Hardware name: Supermicro SYS-5019S-ML/X11SSH-F, BIOS > 2.2 05/23/2018 > [ 18.976392] Call Trace: > [ 18.978844] dump_stack+0x7d/0x9f > [ 18.982158] lockdep_rcu_suspicious+0xce/0xf0 > [ 18.986517] switch_mm_irqs_off+0x441/0x450 > [ 18.990702] switch_mm+0x1b/0x50 > [ 18.993936] leave_mm+0x34/0x40 > [ 18.997082] acpi_idle_enter_bm+0x23/0x120 > [ 19.001180] acpi_idle_enter+0x189/0x2a0 > [ 19.005104] ? rcu_eqs_enter.constprop.85+0xb2/0x180 > [ 19.010072] cpuidle_enter_state+0xa5/0x4b0 > [ 19.015265] cpuidle_enter+0x2e/0x40 > [ 19.015268] do_idle+0x226/0x2b0 > [ 19.015273] cpu_startup_entry+0x1d/0x20 > [ 19.015275] start_secondary+0x114/0x150 > [ 19.029943] secondary_startup_64+0xb6/0xc0 > [ 19.034141] > [ 19.034142] = > [ 19.034142] WARNING: suspicious RCU usage > [ 19.034142] 5.9.0-rc4-next-20200908 #1 Not tainted > [ 19.034143] - > [ 19.034143] /usr/src/kernel/include/trace/events/lock.h:37 > suspicious rcu_dereference_check() usage! > [ 19.034143] > [ 19.034144] other info that might help us debug this: > [ 19.034144] > [ 19.034144] > [ 19.034145] rcu_scheduler_active = 2, debug_locks = 1 > [ 19.034145] RCU used illegally from extended quiescent state! > [ 19.034146] no locks held by swapper/2/0. > [ 19.034146] > [ 19.034146] stack backtrace: > [ 19.034147] CPU: 2 PID: 0 Comm: swapper/2 Not tainted > 5.9.0-rc4-next-20200908 #1 > [ 19.034147] Hardware name: Supermicro SYS-5019S-ML/X11SSH-F, BIOS > 2.2 05/23/2018 > [ 19.034147] Call Trace: > [ 19.034148] dump_stack+0x7d/0x9f > [ 19.034148] lockdep_rcu_suspicious+0xce/0xf0 > [ 19.034148] lock_acquire+0x327/0x390 > [ 19.034149] _raw_spin_lock+0x2f/0x40 > [ 19.034149] ? vprintk_emit+0x78/0x2f0 > [ 19.034149] vprintk_emit+0x78/0x2f0 > [ 19.034149] vprintk_default+0x1f/0x30 > [ 19.034150] vprintk_func+0x51/0xf0 > [ 19.034150] printk+0x52/0x6e > [ 19.034150] ? __lock_acquire+0x32a/0x19f0 > [ 19.034151] lockdep_rcu_suspicious+0x20/0xf0 > [ 19.034151] switch_mm_irqs_off+0x441/0x450 > [ 19.034151] switch_mm+0x1b/0x50 > [ 19.034152] leave_mm+0x34/0x40 > [ 19.034152] acpi_idle_enter_bm+0x23/0x120 > [ 19.034152] acpi_idle_enter+0x189/0x2a0 > [ 19.034153] ? rcu_eqs_enter.constprop.85+0xb2/0x180 > [ 19.034153] cpuidle_enter_state+0xa5/0x4b0 > [ 19.034153] cpuidle_enter+0x2e/0x40 > [ 19.034154] do_idle+0x226/0x2b0 > [ 19.034154] cpu_startup_entry+0x1d/0x20 > [ 19.034154] start_secondary+0x114/0x150 > [ 19.034155] secondary_startup_64+0xb6/0xc0 > [ 19.034155] > [ 19.034155] = > [ 19.034156] WARNING: suspicious RCU usage > [ 19.034156] 5.9.0-rc4-next-20200908 #1 Not tainted > [ 19.034156] - > [ 19.034157] /usr/src/kernel/include/trace/events/lock.h:63 > suspicious rcu_dereference_check() usage! > [ 19.034157] > [ 19.034157] other info that might help us debug this: > [ 19.034158] > [ 19.034158] > [ 19.034158] rcu_scheduler_active = 2, debug_locks = 1 > [ 19.034159] RCU used illegally from extended quiescent state! > [ 19.034159] 1 lock held by swapper/2/0: > [ 19.034159] #0: bed25f58 (logbuf_lock){-...}-{2:2}, at: > vprintk_emit+0x78/0x2f0 > [ 19.034161] > [ 19.034161] stack backtrace: > [ 19.034162] CPU: 2 PID: 0 Comm: swapper/2 Not
WARNING: suspicious RCU usage: race/events/tlb.h:57 suspicious rcu_dereference_check() usage!
While booting x86_64 with Linux next 20200908 tag kernel this warning was noticed. metadata: git branch: master git repo: https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git git commit: dff9f829e5b0181d4ed9d35aa62d695292399b54 git describe: next-20200908 kernel-config: http://snapshots.linaro.org/openembedded/lkft/lkft/sumo/intel-corei7-64/lkft/linux-next/853/config warning logs: - [ 18.874329] Freeing unused kernel image (rodata/data gap) memory: 2012K [ 18.881107] Run /sbin/init as init process [ 18.905611] [ 18.907190] = [ 18.911194] WARNING: suspicious RCU usage [ 18.915199] 5.9.0-rc4-next-20200908 #1 Not tainted [ 18.919982] - [ 18.923984] /usr/src/kernel/include/trace/events/tlb.h:57 suspicious rcu_dereference_check() usage! [ 18.933016] [ 18.933016] other info that might help us debug this: [ 18.933016] [ 18.941006] [ 18.941006] rcu_scheduler_active = 2, debug_locks = 1 [ 18.947523] RCU used illegally from extended quiescent state! [ 18.953261] no locks held by swapper/2/0. [ 18.957264] [ 18.957264] stack backtrace: [ 18.961619] CPU: 2 PID: 0 Comm: swapper/2 Not tainted 5.9.0-rc4-next-20200908 #1 [ 18.969007] Hardware name: Supermicro SYS-5019S-ML/X11SSH-F, BIOS 2.2 05/23/2018 [ 18.976392] Call Trace: [ 18.978844] dump_stack+0x7d/0x9f [ 18.982158] lockdep_rcu_suspicious+0xce/0xf0 [ 18.986517] switch_mm_irqs_off+0x441/0x450 [ 18.990702] switch_mm+0x1b/0x50 [ 18.993936] leave_mm+0x34/0x40 [ 18.997082] acpi_idle_enter_bm+0x23/0x120 [ 19.001180] acpi_idle_enter+0x189/0x2a0 [ 19.005104] ? rcu_eqs_enter.constprop.85+0xb2/0x180 [ 19.010072] cpuidle_enter_state+0xa5/0x4b0 [ 19.015265] cpuidle_enter+0x2e/0x40 [ 19.015268] do_idle+0x226/0x2b0 [ 19.015273] cpu_startup_entry+0x1d/0x20 [ 19.015275] start_secondary+0x114/0x150 [ 19.029943] secondary_startup_64+0xb6/0xc0 [ 19.034141] [ 19.034142] = [ 19.034142] WARNING: suspicious RCU usage [ 19.034142] 5.9.0-rc4-next-20200908 #1 Not tainted [ 19.034143] - [ 19.034143] /usr/src/kernel/include/trace/events/lock.h:37 suspicious rcu_dereference_check() usage! [ 19.034143] [ 19.034144] other info that might help us debug this: [ 19.034144] [ 19.034144] [ 19.034145] rcu_scheduler_active = 2, debug_locks = 1 [ 19.034145] RCU used illegally from extended quiescent state! [ 19.034146] no locks held by swapper/2/0. [ 19.034146] [ 19.034146] stack backtrace: [ 19.034147] CPU: 2 PID: 0 Comm: swapper/2 Not tainted 5.9.0-rc4-next-20200908 #1 [ 19.034147] Hardware name: Supermicro SYS-5019S-ML/X11SSH-F, BIOS 2.2 05/23/2018 [ 19.034147] Call Trace: [ 19.034148] dump_stack+0x7d/0x9f [ 19.034148] lockdep_rcu_suspicious+0xce/0xf0 [ 19.034148] lock_acquire+0x327/0x390 [ 19.034149] _raw_spin_lock+0x2f/0x40 [ 19.034149] ? vprintk_emit+0x78/0x2f0 [ 19.034149] vprintk_emit+0x78/0x2f0 [ 19.034149] vprintk_default+0x1f/0x30 [ 19.034150] vprintk_func+0x51/0xf0 [ 19.034150] printk+0x52/0x6e [ 19.034150] ? __lock_acquire+0x32a/0x19f0 [ 19.034151] lockdep_rcu_suspicious+0x20/0xf0 [ 19.034151] switch_mm_irqs_off+0x441/0x450 [ 19.034151] switch_mm+0x1b/0x50 [ 19.034152] leave_mm+0x34/0x40 [ 19.034152] acpi_idle_enter_bm+0x23/0x120 [ 19.034152] acpi_idle_enter+0x189/0x2a0 [ 19.034153] ? rcu_eqs_enter.constprop.85+0xb2/0x180 [ 19.034153] cpuidle_enter_state+0xa5/0x4b0 [ 19.034153] cpuidle_enter+0x2e/0x40 [ 19.034154] do_idle+0x226/0x2b0 [ 19.034154] cpu_startup_entry+0x1d/0x20 [ 19.034154] start_secondary+0x114/0x150 [ 19.034155] secondary_startup_64+0xb6/0xc0 [ 19.034155] [ 19.034155] = [ 19.034156] WARNING: suspicious RCU usage [ 19.034156] 5.9.0-rc4-next-20200908 #1 Not tainted [ 19.034156] - [ 19.034157] /usr/src/kernel/include/trace/events/lock.h:63 suspicious rcu_dereference_check() usage! [ 19.034157] [ 19.034157] other info that might help us debug this: [ 19.034158] [ 19.034158] [ 19.034158] rcu_scheduler_active = 2, debug_locks = 1 [ 19.034159] RCU used illegally from extended quiescent state! [ 19.034159] 1 lock held by swapper/2/0: [ 19.034159] #0: bed25f58 (logbuf_lock){-...}-{2:2}, at: vprintk_emit+0x78/0x2f0 [ 19.034161] [ 19.034161] stack backtrace: [ 19.034162] CPU: 2 PID: 0 Comm: swapper/2 Not tainted 5.9.0-rc4-next-20200908 #1 [ 19.034162] Hardware name: Supermicro SYS-5019S-ML/X11SSH-F, BIOS 2.2 05/23/2018 [ 19.034162] Call Trace: [ 19.034163] dump_stack+0x7d/0x9f [ 19.034163] lockdep_rcu_suspicious+0xce/0xf0 [ 19.034163] ? vprintk_emit+0x9e/0x2f0 [ 19.034164] lock_release+0x246/0x270 [ 19.034164] _raw_spin_unlock+0x1a/0x30 [ 19.034164] vprintk_emit+0x9e/0x2f0 [ 19.034165] vprintk_default+0x1f/0x30 [ 19.034165]