[peterz-queue:sched/lazy] [sched, x86] 74d850cd4c: WARNING:at_kernel/rcu/rcutorture.c:#rcu_torture_stats_print[rcutorture]
From: kernel test robot
Date: Tue Oct 15 2024 - 03:48:09 EST
Hello,
with this commit, we saw below config diff between this commit and its parent.
--- /pkg/linux/x86_64-randconfig-014-20241011/clang-18/693ed5cdf46ac062aa7713700c77019c60f0ae41/.config 2024-10-13 05:54:47.077017686 +0800
+++ /pkg/linux/x86_64-randconfig-014-20241011/clang-18/74d850cd4c5bc26ce83511b4247ea786eb73217c/.config 2024-10-13 05:42:38.439123587 +0800
@@ -120,9 +120,11 @@ CONFIG_BPF_PRELOAD=y
# end of BPF subsystem
CONFIG_PREEMPT_BUILD=y
-CONFIG_PREEMPT_NONE=y
+CONFIG_ARCH_HAS_PREEMPT_LAZY=y
+# CONFIG_PREEMPT_NONE is not set
# CONFIG_PREEMPT_VOLUNTARY is not set
# CONFIG_PREEMPT is not set
+CONFIG_PREEMPT_LAZY=y
# CONFIG_PREEMPT_RT is not set
CONFIG_PREEMPT_COUNT=y
CONFIG_PREEMPTION=y
and below reported new issue is not observed on parent.
693ed5cdf46ac062 74d850cd4c5bc26ce83511b4247
---------------- ---------------------------
fail:runs %reproduction fail:runs
| | |
:6 100% 6:6 dmesg.RIP:rcu_torture_stats_print[rcutorture]
:6 100% 6:6 dmesg.WARNING:at_kernel/rcu/rcutorture.c:#rcu_torture_stats_print[rcutorture]
kernel test robot noticed "WARNING:at_kernel/rcu/rcutorture.c:#rcu_torture_stats_print[rcutorture]" on:
commit: 74d850cd4c5bc26ce83511b4247ea786eb73217c ("sched, x86: Enable Lazy preemption")
https://git.kernel.org/cgit/linux/kernel/git/peterz/queue.git sched/lazy
in testcase: rcutorture
version:
with following parameters:
runtime: 300s
test: cpuhotplug
torture_type: busted
config: x86_64-randconfig-014-20241011
compiler: clang-18
test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 16G
(please refer to attached dmesg/kmsg for entire log/backtrace)
If you fix the issue in a separate patch/commit (i.e. not just a new version of
the same patch/commit), kindly add following tags
| Reported-by: kernel test robot <oliver.sang@xxxxxxxxx>
| Closes: https://lore.kernel.org/oe-lkp/202410151544.7d2292c6-lkp@xxxxxxxxx
The kernel config and materials to reproduce are available at:
https://download.01.org/0day-ci/archive/20241015/202410151544.7d2292c6-lkp@xxxxxxxxx
[ 98.006999][ T543] busted-torture: rtc: 00000000639e821e ver: 6796 tfle: 0 rta: 6796 rtaf: 0 rtf: 6787 rtmbe: 1 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 89 barrier: 0/0:0 read-exits: 64 nocb-toggles: 0:0
[ 98.017386][ T543] busted-torture: !!!
[ 98.017662][ T543] ------------[ cut here ]------------
[ 98.019330][ T543] WARNING: CPU: 0 PID: 543 at kernel/rcu/rcutorture.c:2258 rcu_torture_stats_print+0x24c/0x610 [rcutorture]
[ 98.021831][ T543] Modules linked in: rcutorture torture
[ 98.022825][ T543] CPU: 0 UID: 0 PID: 543 Comm: rcu_torture_sta Not tainted 6.12.0-rc1-00037-g74d850cd4c5b #1
[ 98.024497][ T543] RIP: 0010:rcu_torture_stats_print+0x24c/0x610 [rcutorture]
[ 98.025764][ T543] Code: 9e 00 00 31 db 48 c7 c7 a0 ab 01 a0 44 8b 35 e7 9d 00 00 45 85 f6 0f 95 c3 31 d2 31 c9 89 de e8 9a 60 22 e1 45 85 f6 74 04 90 <0f> 0b 90 48 c7 c7 d0 ab 01 a0 89 de 31 d2 31 c9 e8 7f 60 22 e1 44
[ 98.029027][ T543] RSP: 0018:ffffc90000e0fdd0 EFLAGS: 00010202
[ 98.030159][ T543] RAX: 17b05ce78fe5d400 RBX: 0000000000000001 RCX: 0000000000000000
[ 98.031464][ T543] RDX: 0000000000000000 RSI: 0000000000000001 RDI: ffffffffa001abc0
[ 98.032751][ T543] RBP: ffffc90000e0fed0 R08: 0000000000000000 R09: ffffc90000e0fb98
[ 98.034230][ T543] R10: ffffc90000e0fb90 R11: ffffffff83caa080 R12: ffff8881423b6100
[ 98.035585][ T543] R13: 0000000080000000 R14: 0000000000000001 R15: 000000000000000a
[ 98.036942][ T543] FS: 0000000000000000(0000) GS:ffffffff83c8c000(0000) knlGS:0000000000000000
[ 98.038435][ T543] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 98.039527][ T543] CR2: 0000000000403034 CR3: 000000013e05e000 CR4: 00000000000406b0
[ 98.040903][ T543] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 98.042278][ T543] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 98.043632][ T543] Call Trace:
[ 98.044195][ T543] <TASK>
[ 98.044690][ T543] ? show_regs+0x5d/0x70
[ 98.045452][ T543] ? __warn+0xa6/0x270
[ 98.046168][ T543] ? rcu_torture_stats_print+0x24c/0x610 [rcutorture]
[ 98.047349][ T543] ? report_bug+0xba/0x160
[ 98.048112][ T543] ? handle_bug+0x55/0x80
[ 98.048860][ T543] ? exc_invalid_op+0x1b/0x50
[ 98.049716][ T543] ? asm_exc_invalid_op+0x1b/0x20
[ 98.050675][ T543] ? rcu_torture_stats_print+0x24c/0x610 [rcutorture]
[ 98.051846][ T543] rcu_torture_stats+0x29/0x70 [rcutorture]
[ 98.052839][ T543] kthread+0x18f/0x1a0
[ 98.053600][ T543] ? rcu_nocb_toggle+0x1b0/0x1b0 [rcutorture]
[ 98.054607][ T543] ? kthread_unuse_mm+0x150/0x150
[ 98.055432][ T543] ret_from_fork+0x46/0x60
[ 98.056167][ T543] ? kthread_unuse_mm+0x150/0x150
[ 98.057066][ T543] ret_from_fork_asm+0x11/0x20
[ 98.057974][ T543] </TASK>
[ 98.058501][ T543] irq event stamp: 421
[ 98.059184][ T543] hardirqs last enabled at (429): [<ffffffff811670db>] __console_unlock+0x7b/0xd0
[ 98.060748][ T543] hardirqs last disabled at (438): [<ffffffff811670c0>] __console_unlock+0x60/0xd0
[ 98.062437][ T543] softirqs last enabled at (0): [<ffffffff810eb6c6>] copy_process+0x626/0x13e0
[ 98.063962][ T543] softirqs last disabled at (0): [<0000000000000000>] 0x0
[ 98.065130][ T543] ---[ end trace 0000000000000000 ]---
[ 98.237025][ T543] ------------[ cut here ]------------
[ 98.238052][ T543] WARNING: CPU: 0 PID: 543 at kernel/rcu/rcutorture.c:2263 rcu_torture_stats_print+0x373/0x610 [rcutorture]
[ 98.240000][ T543] Modules linked in: rcutorture torture
[ 98.241012][ T543] CPU: 0 UID: 0 PID: 543 Comm: rcu_torture_sta Tainted: G W 6.12.0-rc1-00037-g74d850cd4c5b #1
[ 98.243089][ T543] Tainted: [W]=WARN
[ 98.243770][ T543] RIP: 0010:rcu_torture_stats_print+0x373/0x610 [rcutorture]
[ 98.244991][ T543] Code: d2 31 c9 e8 8f 5f 22 e1 31 db 41 83 ff 02 48 c7 c7 80 ad 01 a0 0f 9d c3 31 d2 31 c9 89 de e8 74 5f 22 e1 41 83 ff 02 7c 04 90 <0f> 0b 90 48 c7 c7 b0 ad 01 a0 89 de 31 d2 31 c9 e8 58 5f 22 e1 48
[ 98.248211][ T543] RSP: 0018:ffffc90000e0fdd0 EFLAGS: 00010202
[ 98.249268][ T543] RAX: 17b05ce78fe5d400 RBX: 0000000000000001 RCX: 0000000000000000
[ 98.250758][ T543] RDX: 0000000000000000 RSI: 0000000000000001 RDI: ffffffffa001ada0
[ 98.252089][ T543] RBP: ffffc90000e0fed0 R08: 0000000000000000 R09: ffffc90000e0fb98
[ 98.253480][ T543] R10: ffffc90000e0fb90 R11: ffffffff83caa080 R12: ffff8881423b6100
[ 98.254886][ T543] R13: 0000000080000000 R14: 0000000000000000 R15: 000000000000000a
[ 98.256171][ T543] FS: 0000000000000000(0000) GS:ffffffff83c8c000(0000) knlGS:0000000000000000
[ 98.257778][ T543] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 98.258942][ T543] CR2: 00007fdbb4f90000 CR3: 0000000140b89000 CR4: 00000000000406b0
[ 98.260150][ T543] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 98.261503][ T543] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 98.262832][ T543] Call Trace:
[ 98.263391][ T543] <TASK>
[ 98.263916][ T543] ? show_regs+0x5d/0x70
[ 98.264654][ T543] ? __warn+0xa6/0x270
[ 98.265377][ T543] ? rcu_torture_stats_print+0x373/0x610 [rcutorture]
[ 98.266551][ T543] ? report_bug+0xba/0x160
[ 98.267331][ T543] ? handle_bug+0x55/0x80
[ 98.268055][ T543] ? exc_invalid_op+0x1b/0x50
[ 98.268841][ T543] ? asm_exc_invalid_op+0x1b/0x20
[ 98.269772][ T543] ? rcu_torture_stats_print+0x373/0x610 [rcutorture]
[ 98.271002][ T543] rcu_torture_stats+0x29/0x70 [rcutorture]
[ 98.271983][ T543] kthread+0x18f/0x1a0
[ 98.272660][ T543] ? rcu_nocb_toggle+0x1b0/0x1b0 [rcutorture]
[ 98.273784][ T543] ? kthread_unuse_mm+0x150/0x150
[ 98.274621][ T543] ret_from_fork+0x46/0x60
[ 98.275361][ T543] ? kthread_unuse_mm+0x150/0x150
[ 98.276189][ T543] ret_from_fork_asm+0x11/0x20
[ 98.277038][ T543] </TASK>
[ 98.277611][ T543] irq event stamp: 811
[ 98.278304][ T543] hardirqs last enabled at (819): [<ffffffff811670db>] __console_unlock+0x7b/0xd0
[ 98.279844][ T543] hardirqs last disabled at (826): [<ffffffff811670c0>] __console_unlock+0x60/0xd0
[ 98.281441][ T543] softirqs last enabled at (0): [<ffffffff810eb6c6>] copy_process+0x626/0x13e0
[ 98.282929][ T543] softirqs last disabled at (0): [<0000000000000000>] 0x0
[ 98.284155][ T543] ---[ end trace 0000000000000000 ]---
[ 98.466994][ T543] Reader Pipe: 49418 0 1 0 1 2 0 1 0 0 47
[ 98.468093][ T543] busted-torture: Reader Batch: 49470 0 0 0 0 0 0 0 0 0 0
[ 98.469346][ T543] busted-torture: Free-Block Circulation: 6795 6795 6794 6793 6792 6791 6790 6789 6788 6787 0
--
0-DAY CI Kernel Test Service
https://github.com/intel/lkp-tests/wiki