[BUG] 8e274732115f ("printk: extend console_lock for per-console locking")

From: Paul E. McKenney
Date: Fri Jun 10 2022 - 16:50:46 EST


Hello, John,

I have started getting rcutorture shutdown-time hangs when running
against recent mainline, and bisected back to the 8e274732115f ("printk:
extend console_lock for per-console locking") commit. These hangs go
away (or at least their probability drops dramatically) if I build with
CONFIG_PREEMPTION=n -and- CONFIG_NO_HZ=y (not n!), at least assuming
that I also boot with "nohz_full=0-N".

Attempts to debug using rcutorture's "--gdb" argument result in
"[Inferior 1 (process 1) exited normally]", but with the same truncated
console-log output as when running without "--gdb". This suggests
that the kernel really did run to completion and halt as expected,
but that the shutdown-time printk() output was lost. Furthermore, if I
use the gdb "hbreak" command to set a breakpoint at kernel_power_off(),
it really does hit that breakpoint. This would not happen in the case
of a kernel hang.

So, given that I can hit that breakpoint, what should I ask gdb to
show me?

Alternatively, this reproduces on a variety of x86 platforms, so you
should be able reproduce it as follows [1]:

git checkout v5.19-rc1
tools/testing/selftests/rcutorture/bin/kvm.sh --allcpus --duration 2 --configs "TREE01" --gdb "CONFIG_DEBUG_INFO_NONE=n CONFIG_DEBUG_INFO_DWARF_TOOLCHAIN_DEFAULT=y" --trust-make

This builds a kernel, boots it, and then tells you how to launch gdb
(presumably in some other window). Once you give launch gdb and give
it the suggested commands, the kernel runs for two minutes under qemu,
then shuts down. I used the following gdb commands to set the breakpoint
and run the kernel:

target remote :1234 # suggested by the rcutorture script
hbreak kernel_power_off # added by me
continue # suggested by the rcutorture script

Or leave out the "gdb" if you prefer some other debugging approach.

Thoughts?

Thanx, Paul

------------------------------------------------------------------------

[1] The --kconfig argument is needed due to CONFIG_DEBUG_INFO Kconfig
changes. You can instead pull in -rcu commit f9993c8413d1
("torture: Adjust to again produce debugging information"),
which allows you to omit that --kconfig argument.