[linux-next:master] [lib/xarray] e6c71d0899: WARNING:suspicious_RCU_usage

From: kernel test robot
Date: Tue Apr 02 2024 - 00:55:40 EST

Next message: Ron Economos: "Re: [PATCH 6.8 000/399] 6.8.3-rc1 review"
Previous message: patchwork-bot+netdevbpf: "Re: [PATCH net 0/2] mptcp: fix fallback MIB counter and wrong var in selftests"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]

Hello,

kernel test robot noticed "WARNING:suspicious_RCU_usage" on:

commit: e6c71d0899e7b0e0b120dcc1ddb8613aa1e1cd93 ("lib/xarray: introduce a new helper xas_get_order")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master

[test failed on linux-next/master a6bd6c9333397f5a0e2667d4d82fef8c970108f2]

in testcase: boot

compiler: clang-17
test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 16G

(please refer to attached dmesg/kmsg for entire log/backtrace)

+---------------------------------------------------------------------+------------+------------+
| | 5fb1b791b3 | e6c71d0899 |
+---------------------------------------------------------------------+------------+------------+
| WARNING:suspicious_RCU_usage | 0 | 6 |
| include/linux/xarray.h:#suspicious_rcu_dereference_check()usage | 0 | 6 |
| include/linux/xarray.h:#suspicious_rcu_dereference_protected()usage | 0 | 6 |
+---------------------------------------------------------------------+------------+------------+

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/202404021026.f28e44bb-lkp@xxxxxxxxx

[ 86.993728][ T1] WARNING: suspicious RCU usage
[ 86.994316][ T1] 6.9.0-rc1-00150-ge6c71d0899e7 #1 Tainted: G W T
[ 86.995290][ T1] -----------------------------
[ 86.995922][ T1] include/linux/xarray.h:1201 suspicious rcu_dereference_check() usage!
[ 86.997044][ T1]
[ 86.997044][ T1] other info that might help us debug this:
[ 86.997044][ T1]
[ 86.998358][ T1]
[ 86.998358][ T1] rcu_scheduler_active = 2, debug_locks = 1
[ 86.999400][ T1] no locks held by swapper/0/1.
[ 87.000056][ T1]
[ 87.000056][ T1] stack backtrace:
[ 87.000880][ T1] CPU: 1 PID: 1 Comm: swapper/0 Tainted: G W T 6.9.0-rc1-00150-ge6c71d0899e7 #1 e72961bb2bc89a324b194b20c4b2a631d7a91742
[ 87.002620][ T1] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-debian-1.16.2-1 04/01/2014
[ 87.003373][ T1] Call Trace:
[ 87.003373][ T1] dump_stack_lvl (lib/dump_stack.c:116)
[ 87.003373][ T1] dump_stack (lib/dump_stack.c:123)
[ 87.003373][ T1] lockdep_rcu_suspicious (include/linux/context_tracking.h:122)
[ 87.003373][ T1] xas_start (include/linux/xarray.h:?)
[ 87.003373][ T1] xas_store (lib/xarray.c:237 lib/xarray.c:789)
[ 87.006845][ T1] check_xas_get_order (lib/test_xarray.c:?)
[ 87.006845][ T1] xarray_checks (lib/test_xarray.c:2070)
[ 87.006845][ T1] do_one_initcall (init/main.c:1238)
[ 87.006845][ T1] ? xa_dump (lib/test_xarray.c:2054)
[ 87.006845][ T1] ? __lock_acquire (kernel/locking/lockdep.c:4599)
[ 87.006845][ T1] ? kvm_sched_clock_read (arch/x86/kernel/kvmclock.c:91)
[ 87.006845][ T1] ? sched_clock_noinstr (arch/x86/kernel/tsc.c:266)
[ 87.006845][ T1] ? local_clock_noinstr (kernel/sched/clock.c:269 kernel/sched/clock.c:306)
[ 87.006845][ T1] ? kvm_sched_clock_read (arch/x86/kernel/kvmclock.c:91)
[ 87.006845][ T1] ? sched_clock_noinstr (arch/x86/kernel/tsc.c:266)
[ 87.006845][ T1] ? local_clock_noinstr (kernel/sched/clock.c:269 kernel/sched/clock.c:306)
[ 87.006845][ T1] ? local_clock (arch/x86/include/asm/preempt.h:84 kernel/sched/clock.c:316)
[ 87.006845][ T1] ? ktime_get (kernel/time/timekeeping.c:254 kernel/time/timekeeping.c:254 kernel/time/timekeeping.c:388 kernel/time/timekeeping.c:848)
[ 87.014859][ T1] ? ktime_get (kernel/time/timekeeping.c:? kernel/time/timekeeping.c:255 kernel/time/timekeeping.c:388 kernel/time/timekeeping.c:848)
[ 87.014859][ T1] ? clockevents_program_event (kernel/time/clockevents.c:336)
[ 87.014859][ T1] ? update_process_times (kernel/time/timer.c:2494)
[ 87.014859][ T1] ? irqentry_exit (kernel/entry/common.c:?)
[ 87.014859][ T1] ? sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1043)
[ 87.014859][ T1] ? trace_hardirqs_on (kernel/trace/trace_preemptirq.c:63)
[ 87.014859][ T1] ? irqentry_exit (kernel/entry/common.c:?)
[ 87.014859][ T1] ? sysvec_call_function_single (arch/x86/kernel/apic/apic.c:1043)
[ 87.014859][ T1] ? sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1043)
[ 87.014859][ T1] ? handle_exception (arch/x86/entry/entry_32.S:1047)
[ 87.014859][ T1] ? next_arg (lib/cmdline.c:273)
[ 87.014859][ T1] ? parse_args (kernel/params.c:153)
[ 87.022841][ T1] do_initcall_level (init/main.c:1299)
[ 87.022841][ T1] ? kernel_init (init/main.c:1439)
[ 87.022841][ T1] do_initcalls (init/main.c:1313)
[ 87.022841][ T1] do_basic_setup (init/main.c:1336)
[ 87.022841][ T1] kernel_init_freeable (init/main.c:1552)
[ 87.022841][ T1] ? rest_init (init/main.c:1429)
[ 87.022841][ T1] kernel_init (init/main.c:1439)
[ 87.022841][ T1] ret_from_fork (arch/x86/kernel/process.c:153)
[ 87.022841][ T1] ret_from_fork_asm (arch/x86/entry/entry_32.S:737)
[ 87.022841][ T1] entry_INT80_32 (arch/x86/entry/entry_32.S:944)
[ 87.029403][ T1]
[ 87.029696][ T1] =============================
[ 87.030321][ T1] WARNING: suspicious RCU usage
[ 87.030934][ T1] 6.9.0-rc1-00150-ge6c71d0899e7 #1 Tainted: G W T
[ 87.031864][ T1] -----------------------------
[ 87.032486][ T1] include/linux/xarray.h:1217 suspicious rcu_dereference_check() usage!
[ 87.033492][ T1]
[ 87.033492][ T1] other info that might help us debug this:
[ 87.033492][ T1]
[ 87.034805][ T1]
[ 87.034805][ T1] rcu_scheduler_active = 2, debug_locks = 1
[ 87.035815][ T1] no locks held by swapper/0/1.
[ 87.036445][ T1]
[ 87.036445][ T1] stack backtrace:
[ 87.037223][ T1] CPU: 1 PID: 1 Comm: swapper/0 Tainted: G W T 6.9.0-rc1-00150-ge6c71d0899e7 #1 e72961bb2bc89a324b194b20c4b2a631d7a91742
[ 87.038825][ T1] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-debian-1.16.2-1 04/01/2014
[ 87.038825][ T1] Call Trace:
[ 87.038825][ T1] dump_stack_lvl (lib/dump_stack.c:116)
[ 87.038825][ T1] dump_stack (lib/dump_stack.c:123)
[ 87.038825][ T1] lockdep_rcu_suspicious (include/linux/context_tracking.h:122)
[ 87.038825][ T1] xas_descend (include/linux/xarray.h:?)
[ 87.038825][ T1] xas_store (lib/xarray.c:244 lib/xarray.c:789)
[ 87.038825][ T1] check_xas_get_order (lib/test_xarray.c:?)
[ 87.038825][ T1] xarray_checks (lib/test_xarray.c:2070)
[ 87.038825][ T1] do_one_initcall (init/main.c:1238)
[ 87.038825][ T1] ? xa_dump (lib/test_xarray.c:2054)
[ 87.038825][ T1] ? __lock_acquire (kernel/locking/lockdep.c:4599)
[ 87.038825][ T1] ? kvm_sched_clock_read (arch/x86/kernel/kvmclock.c:91)
[ 87.046853][ T1] ? sched_clock_noinstr (arch/x86/kernel/tsc.c:266)
[ 87.046853][ T1] ? local_clock_noinstr (kernel/sched/clock.c:269 kernel/sched/clock.c:306)
[ 87.046853][ T1] ? kvm_sched_clock_read (arch/x86/kernel/kvmclock.c:91)
[ 87.046853][ T1] ? sched_clock_noinstr (arch/x86/kernel/tsc.c:266)
[ 87.046853][ T1] ? local_clock_noinstr (kernel/sched/clock.c:269 kernel/sched/clock.c:306)
[ 87.046853][ T1] ? local_clock (arch/x86/include/asm/preempt.h:84 kernel/sched/clock.c:316)
[ 87.046853][ T1] ? ktime_get (kernel/time/timekeeping.c:254 kernel/time/timekeeping.c:254 kernel/time/timekeeping.c:388 kernel/time/timekeeping.c:848)
[ 87.046853][ T1] ? ktime_get (kernel/time/timekeeping.c:? kernel/time/timekeeping.c:255 kernel/time/timekeeping.c:388 kernel/time/timekeeping.c:848)
[ 87.046853][ T1] ? clockevents_program_event (kernel/time/clockevents.c:336)
[ 87.046853][ T1] ? update_process_times (kernel/time/timer.c:2494)
[ 87.046853][ T1] ? irqentry_exit (kernel/entry/common.c:?)
[ 87.046853][ T1] ? sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1043)
[ 87.054842][ T1] ? trace_hardirqs_on (kernel/trace/trace_preemptirq.c:63)
[ 87.054842][ T1] ? irqentry_exit (kernel/entry/common.c:?)
[ 87.054842][ T1] ? sysvec_call_function_single (arch/x86/kernel/apic/apic.c:1043)
[ 87.054842][ T1] ? sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1043)
[ 87.054842][ T1] ? handle_exception (arch/x86/entry/entry_32.S:1047)
[ 87.054842][ T1] ? next_arg (lib/cmdline.c:273)
[ 87.054842][ T1] ? parse_args (kernel/params.c:153)
[ 87.054842][ T1] do_initcall_level (init/main.c:1299)
[ 87.054842][ T1] ? kernel_init (init/main.c:1439)
[ 87.054842][ T1] do_initcalls (init/main.c:1313)
[ 87.054842][ T1] do_basic_setup (init/main.c:1336)
[ 87.054842][ T1] kernel_init_freeable (init/main.c:1552)
[ 87.054842][ T1] ? rest_init (init/main.c:1429)
[ 87.062852][ T1] ? rest_init (init/main.c:1429)
[ 87.062852][ T1] kernel_init (init/main.c:1439)
[ 87.062852][ T1] ret_from_fork (arch/x86/kernel/process.c:153)
[ 87.062852][ T1] ret_from_fork_asm (arch/x86/entry/entry_32.S:737)
[ 87.062852][ T1] entry_INT80_32 (arch/x86/entry/entry_32.S:944)
[ 87.065984][ T1]
[ 87.066262][ T1] =============================
[ 87.066890][ T1] WARNING: suspicious RCU usage
[ 87.067483][ T1] 6.9.0-rc1-00150-ge6c71d0899e7 #1 Tainted: G W T
[ 87.068496][ T1] -----------------------------
[ 87.069138][ T1] include/linux/xarray.h:1226 suspicious rcu_dereference_protected() usage!
[ 87.070297][ T1]
[ 87.070297][ T1] other info that might help us debug this:
[ 87.070297][ T1]
[ 87.071603][ T1]
[ 87.071603][ T1] rcu_scheduler_active = 2, debug_locks = 1
[ 87.072655][ T1] no locks held by swapper/0/1.
[ 87.073281][ T1]
[ 87.073281][ T1] stack backtrace:
[ 87.074029][ T1] CPU: 1 PID: 1 Comm: swapper/0 Tainted: G W T 6.9.0-rc1-00150-ge6c71d0899e7 #1 e72961bb2bc89a324b194b20c4b2a631d7a91742
[ 87.075575][ T1] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-debian-1.16.2-1 04/01/2014
[ 87.075575][ T1] Call Trace:
[ 87.075575][ T1] dump_stack_lvl (lib/dump_stack.c:116)
[ 87.075575][ T1] dump_stack (lib/dump_stack.c:123)
[ 87.075575][ T1] lockdep_rcu_suspicious (include/linux/context_tracking.h:122)
[ 87.078848][ T1] xas_store (include/linux/xarray.h:?)
[ 87.078848][ T1] check_xas_get_order (lib/test_xarray.c:?)
[ 87.078848][ T1] xarray_checks (lib/test_xarray.c:2070)
[ 87.078848][ T1] do_one_initcall (init/main.c:1238)
[ 87.078848][ T1] ? xa_dump (lib/test_xarray.c:2054)
[ 87.078848][ T1] ? __lock_acquire (kernel/locking/lockdep.c:4599)
[ 87.078848][ T1] ? kvm_sched_clock_read (arch/x86/kernel/kvmclock.c:91)
[ 87.078848][ T1] ? sched_clock_noinstr (arch/x86/kernel/tsc.c:266)
[ 87.078848][ T1] ? local_clock_noinstr (kernel/sched/clock.c:269 kernel/sched/clock.c:306)
[ 87.078848][ T1] ? kvm_sched_clock_read (arch/x86/kernel/kvmclock.c:91)
[ 87.078848][ T1] ? sched_clock_noinstr (arch/x86/kernel/tsc.c:266)
[ 87.078848][ T1] ? local_clock_noinstr (kernel/sched/clock.c:269 kernel/sched/clock.c:306)
[ 87.086847][ T1] ? local_clock (arch/x86/include/asm/preempt.h:84 kernel/sched/clock.c:316)
[ 87.086847][ T1] ? ktime_get (kernel/time/timekeeping.c:254 kernel/time/timekeeping.c:254 kernel/time/timekeeping.c:388 kernel/time/timekeeping.c:848)
[ 87.086847][ T1] ? ktime_get (kernel/time/timekeeping.c:? kernel/time/timekeeping.c:255 kernel/time/timekeeping.c:388 kernel/time/timekeeping.c:848)
[ 87.086847][ T1] ? clockevents_program_event (kernel/time/clockevents.c:336)
[ 87.086847][ T1] ? update_process_times (kernel/time/timer.c:2494)
[ 87.086847][ T1] ? irqentry_exit (kernel/entry/common.c:?)
[ 87.086847][ T1] ? sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1043)
[ 87.086847][ T1] ? trace_hardirqs_on (kernel/trace/trace_preemptirq.c:63)
[ 87.086847][ T1] ? irqentry_exit (kernel/entry/common.c:?)
[ 87.086847][ T1] ? sysvec_call_function_single (arch/x86/kernel/apic/apic.c:1043)
[ 87.086847][ T1] ? sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1043)
[ 87.086847][ T1] ? handle_exception (arch/x86/entry/entry_32.S:1047)
[ 87.094846][ T1] ? next_arg (lib/cmdline.c:273)
[ 87.094846][ T1] ? parse_args (kernel/params.c:153)
[ 87.094846][ T1] do_initcall_level (init/main.c:1299)
[ 87.094846][ T1] ? kernel_init (init/main.c:1439)
[ 87.094846][ T1] do_initcalls (init/main.c:1313)
[ 87.094846][ T1] do_basic_setup (init/main.c:1336)
[ 87.094846][ T1] kernel_init_freeable (init/main.c:1552)
[ 87.094846][ T1] ? rest_init (init/main.c:1429)
[ 87.094846][ T1] kernel_init (init/main.c:1439)
[ 87.094846][ T1] ret_from_fork (arch/x86/kernel/process.c:153)
[ 87.094846][ T1] ret_from_fork_asm (arch/x86/entry/entry_32.S:737)
[ 87.094846][ T1] entry_INT80_32 (arch/x86/entry/entry_32.S:944)
[ 87.102597][ T1]
[ 87.102922][ T1] =============================
[ 87.103563][ T1] WARNING: suspicious RCU usage
[ 87.104180][ T1] 6.9.0-rc1-00150-ge6c71d0899e7 #1 Tainted: G W T
[ 87.105106][ T1] -----------------------------
[ 87.105698][ T1] include/linux/xarray.h:1242 suspicious rcu_dereference_protected() usage!
[ 87.106878][ T1]
[ 87.106878][ T1] other info that might help us debug this:
[ 87.106878][ T1]
[ 87.108138][ T1]
[ 87.108138][ T1] rcu_scheduler_active = 2, debug_locks = 1
[ 87.109159][ T1] no locks held by swapper/0/1.
[ 87.109742][ T1]
[ 87.109742][ T1] stack backtrace:
[ 87.110445][ T1] CPU: 1 PID: 1 Comm: swapper/0 Tainted: G W T 6.9.0-rc1-00150-ge6c71d0899e7 #1 e72961bb2bc89a324b194b20c4b2a631d7a91742
[ 87.110844][ T1] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-debian-1.16.2-1 04/01/2014
[ 87.110844][ T1] Call Trace:
[ 87.110844][ T1] dump_stack_lvl (lib/dump_stack.c:116)
[ 87.110844][ T1] dump_stack (lib/dump_stack.c:123)
[ 87.110844][ T1] lockdep_rcu_suspicious (include/linux/context_tracking.h:122)
[ 87.110844][ T1] xas_store (include/linux/xarray.h:? lib/xarray.c:759 lib/xarray.c:844)
[ 87.110844][ T1] check_xas_get_order (lib/test_xarray.c:?)
[ 87.110844][ T1] xarray_checks (lib/test_xarray.c:2070)
[ 87.110844][ T1] do_one_initcall (init/main.c:1238)
[ 87.110844][ T1] ? xa_dump (lib/test_xarray.c:2054)
[ 87.110844][ T1] ? __lock_acquire (kernel/locking/lockdep.c:4599)
[ 87.118849][ T1] ? kvm_sched_clock_read (arch/x86/kernel/kvmclock.c:91)
[ 87.118849][ T1] ? sched_clock_noinstr (arch/x86/kernel/tsc.c:266)
[ 87.118849][ T1] ? local_clock_noinstr (kernel/sched/clock.c:269 kernel/sched/clock.c:306)
[ 87.118849][ T1] ? kvm_sched_clock_read (arch/x86/kernel/kvmclock.c:91)
[ 87.118849][ T1] ? sched_clock_noinstr (arch/x86/kernel/tsc.c:266)
[ 87.118849][ T1] ? local_clock_noinstr (kernel/sched/clock.c:269 kernel/sched/clock.c:306)
[ 87.118849][ T1] ? local_clock (arch/x86/include/asm/preempt.h:84 kernel/sched/clock.c:316)
[ 87.118849][ T1] ? ktime_get (kernel/time/timekeeping.c:254 kernel/time/timekeeping.c:254 kernel/time/timekeeping.c:388 kernel/time/timekeeping.c:848)
[ 87.118849][ T1] ? ktime_get (kernel/time/timekeeping.c:? kernel/time/timekeeping.c:255 kernel/time/timekeeping.c:388 kernel/time/timekeeping.c:848)
[ 87.118849][ T1] ? clockevents_program_event (kernel/time/clockevents.c:336)
[ 87.118849][ T1] ? update_process_times (kernel/time/timer.c:2494)
[ 87.118849][ T1] ? irqentry_exit (kernel/entry/common.c:?)
[ 87.118849][ T1] ? sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1043)
[ 87.126855][ T1] ? trace_hardirqs_on (kernel/trace/trace_preemptirq.c:63)
[ 87.126855][ T1] ? irqentry_exit (kernel/entry/common.c:?)
[ 87.126855][ T1] ? sysvec_call_function_single (arch/x86/kernel/apic/apic.c:1043)
[ 87.126855][ T1] ? sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1043)
[ 87.126855][ T1] ? handle_exception (arch/x86/entry/entry_32.S:1047)
[ 87.126855][ T1] ? next_arg (lib/cmdline.c:273)
[ 87.126855][ T1] ? parse_args (kernel/params.c:153)
[ 87.126855][ T1] do_initcall_level (init/main.c:1299)
[ 87.126855][ T1] ? kernel_init (init/main.c:1439)
[ 87.126855][ T1] do_initcalls (init/main.c:1313)
[ 87.134850][ T1] do_basic_setup (init/main.c:1336)
[ 87.134850][ T1] kernel_init_freeable (init/main.c:1552)
[ 87.134850][ T1] ? rest_init (init/main.c:1429)
[ 87.134850][ T1] kernel_init (init/main.c:1439)
[ 87.134850][ T1] ret_from_fork (arch/x86/kernel/process.c:153)
[ 87.134850][ T1] ret_from_fork_asm (arch/x86/entry/entry_32.S:737)
[ 87.134850][ T1] entry_INT80_32 (arch/x86/entry/entry_32.S:944)

The kernel config and materials to reproduce are available at:
https://download.01.org/0day-ci/archive/20240402/202404021026.f28e44bb-lkp@xxxxxxxxx

--
0-DAY CI Kernel Test Service
https://github.com/intel/lkp-tests/wiki

Next message: Ron Economos: "Re: [PATCH 6.8 000/399] 6.8.3-rc1 review"
Previous message: patchwork-bot+netdevbpf: "Re: [PATCH net 0/2] mptcp: fix fallback MIB counter and wrong var in selftests"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]