lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [day] [month] [year] [list]
Message-ID: <202404021026.f28e44bb-lkp@intel.com>
Date: Tue, 2 Apr 2024 12:55:12 +0800
From: kernel test robot <oliver.sang@...el.com>
To: Kairui Song <kasong@...cent.com>
CC: <oe-lkp@...ts.linux.dev>, <lkp@...el.com>, Linux Memory Management List
	<linux-mm@...ck.org>, Andrew Morton <akpm@...ux-foundation.org>, "Matthew
 Wilcox" <willy@...radead.org>, <linux-fsdevel@...r.kernel.org>,
	<linux-kernel@...r.kernel.org>, <oliver.sang@...el.com>
Subject: [linux-next:master] [lib/xarray]  e6c71d0899:
 WARNING:suspicious_RCU_usage



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@...el.com>
| Closes: https://lore.kernel.org/oe-lkp/202404021026.f28e44bb-lkp@intel.com


[   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@intel.com



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


Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ