| 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
| ||
|
Message-ID: <YtlfRmJ/ejArIui0@xsang-OptiPlex-9020>
Date: Thu, 21 Jul 2022 22:14:30 +0800
From: kernel test robot <oliver.sang@...el.com>
To: Peter Zijlstra <peterz@...radead.org>
CC: Borislav Petkov <bp@...e.de>,
Tim Chen <tim.c.chen@...ux.intel.com>,
"Josh Poimboeuf" <jpoimboe@...nel.org>,
LKML <linux-kernel@...r.kernel.org>, <linux-pm@...r.kernel.org>,
<lkp@...ts.01.org>, <lkp@...el.com>
Subject: [intel_idle] bf5835bcdb: WARNING:suspicious_RCU_usage
Greeting,
FYI, we noticed the following commit (built with gcc-11):
commit: bf5835bcdb9635c97f85120dba9bfa21e111130f ("intel_idle: Disable IBRS during long idle")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master
in testcase: kernel-selftests
version: kernel-selftests-x86_64-77b3a84e-1_20220717
with following parameters:
group: ftrace
ucode: 0xec
test-description: The kernel contains a set of "self tests" under the tools/testing/selftests/ directory. These are intended to be small unit tests to exercise individual code paths in the kernel.
test-url: https://www.kernel.org/doc/Documentation/kselftest.txt
on test machine: 8 threads Intel(R) Core(TM) i7-6700 CPU @ 3.40GHz with 16G memory
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <oliver.sang@...el.com>
[ 250.448023][ T0] WARNING: suspicious RCU usage
[ 250.448025][ T0] 5.19.0-rc4-00027-gbf5835bcdb96 #1 Tainted: G S W
[ 250.448028][ T0] -----------------------------
[ 250.448029][ T0] include/trace/events/lock.h:24 suspicious rcu_dereference_check() usage!
[ 250.448033][ T0]
[ 250.448033][ T0] other info that might help us debug this:
[ 250.448033][ T0]
[ 250.448034][ T0]
[ 250.448034][ T0] rcu_scheduler_active = 2, debug_locks = 1
[ 250.448037][ T0] RCU used illegally from extended quiescent state!
[ 250.448038][ T0] no locks held by swapper/0/0.
[ 250.448040][ T0]
[ 250.448040][ T0] stack backtrace:
[ 250.448042][ T0] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G S W 5.19.0-rc4-00027-gbf5835bcdb96 #1
[ 250.448046][ T0] Hardware name: HP HP Z240 SFF Workstation/802E, BIOS N51 Ver. 01.63 10/05/2017
[ 250.448049][ T0] Call Trace:
[ 250.448051][ T0] <TASK>
[ 250.448070][ T0] dump_stack_lvl (lib/dump_stack.c:107 (discriminator 4))
[ 250.448085][ T0] lock_acquire.cold (include/trace/events/lock.h:24 kernel/locking/lockdep.c:5636)
[ 250.448106][ T0] ? rcu_read_unlock (include/linux/rcupdate.h:724 (discriminator 5))
[ 250.448129][ T0] ? rb_commit+0xe7/0x880
[ 250.448181][ T0] ? vprintk_emit (kernel/printk/printk.c:2270)
[ 250.448214][ T0] _raw_spin_lock_irqsave (include/linux/spinlock_api_smp.h:111 kernel/locking/spinlock.c:162)
[ 250.448218][ T0] ? down_trylock (kernel/locking/semaphore.c:140)
[ 250.448229][ T0] down_trylock (kernel/locking/semaphore.c:140)
[ 250.448236][ T0] ? vprintk_emit (kernel/printk/printk.c:2270)
[ 250.448244][ T0] __down_trylock_console_sem (kernel/printk/printk.c:246)
[ 250.448255][ T0] console_trylock_spinning (kernel/printk/printk.c:2586 kernel/printk/printk.c:1870)
[ 250.448268][ T0] vprintk_emit (kernel/printk/printk.c:2270)
[ 250.448271][ T0] ? trace_event_buffer_commit (kernel/trace/trace.c:2931)
[ 250.448305][ T0] _printk (kernel/printk/printk.c:2287)
[ 250.448317][ T0] ? record_print_text.cold (kernel/printk/printk.c:2287)
[ 250.448403][ T0] lockdep_rcu_suspicious (kernel/locking/lockdep.c:6558)
[ 250.448427][ T0] trace_write_msr (arch/x86/include/asm/msr-trace.h:45 (discriminator 47))
[ 250.448447][ T0] intel_idle_ibrs (drivers/idle/intel_idle.c:186)
[ 250.448466][ T0] cpuidle_enter_state (drivers/cpuidle/cpuidle.c:237)
[ 250.448482][ T0] ? trace_event_raw_event_preemptirq_template (include/trace/events/preemptirq.h:14)
[ 250.448508][ T0] cpuidle_enter (drivers/cpuidle/cpuidle.c:353)
[ 250.448531][ T0] cpuidle_idle_call (kernel/sched/idle.c:155 kernel/sched/idle.c:236)
[ 250.448543][ T0] ? arch_cpu_idle_exit+0xc0/0xc0
[ 250.448570][ T0] ? do_idle (kernel/sched/idle.c:282 (discriminator 1))
[ 250.448574][ T0] ? tsc_verify_tsc_adjust (arch/x86/kernel/tsc_sync.c:58)
[ 250.448600][ T0] do_idle (kernel/sched/idle.c:303)
[ 250.448619][ T0] cpu_startup_entry (kernel/sched/idle.c:399 (discriminator 1))
[ 250.448627][ T0] rest_init (include/linux/rcupdate.h:696 init/main.c:697)
[ 250.448647][ T0] arch_call_rest_init+0xf/0x19
[ 250.448656][ T0] start_kernel (init/main.c:1139)
[ 250.448675][ T0] secondary_startup_64_no_verify (arch/x86/kernel/head_64.S:358)
[ 250.448757][ T0] </TASK>
[ 250.448760][ T0]
[ 250.448761][ T0] =============================
[ 250.448763][ T0] WARNING: suspicious RCU usage
[ 250.448764][ T0] 5.19.0-rc4-00027-gbf5835bcdb96 #1 Tainted: G S W
[ 250.448766][ T0] -----------------------------
[ 250.448768][ T0] include/trace/events/lock.h:69 suspicious rcu_dereference_check() usage!
[ 250.448770][ T0]
[ 250.448770][ T0] other info that might help us debug this:
[ 250.448770][ T0]
[ 250.448772][ T0]
[ 250.448772][ T0] rcu_scheduler_active = 2, debug_locks = 1
[ 250.448774][ T0] RCU used illegally from extended quiescent state!
[ 250.448775][ T0] 1 lock held by swapper/0/0:
[ 250.448778][ T0] #0: ffffffff85779558 ((console_sem).lock){-...}-{2:2}, at: down_trylock (kernel/locking/semaphore.c:140)
[ 250.448791][ T0]
[ 250.448791][ T0] stack backtrace:
[ 250.448792][ T0] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G S W 5.19.0-rc4-00027-gbf5835bcdb96 #1
[ 250.448796][ T0] Hardware name: HP HP Z240 SFF Workstation/802E, BIOS N51 Ver. 01.63 10/05/2017
[ 250.448798][ T0] Call Trace:
[ 250.448800][ T0] <TASK>
[ 250.448817][ T0] dump_stack_lvl (lib/dump_stack.c:107 (discriminator 4))
[ 250.448825][ T0] ? down_trylock (kernel/locking/semaphore.c:145)
[ 250.448828][ T0] lock_release.cold (include/trace/events/lock.h:69 kernel/locking/lockdep.c:5676)
[ 250.448843][ T0] _raw_spin_unlock_irqrestore (include/linux/spinlock_api_smp.h:150 kernel/locking/spinlock.c:194)
[ 250.448854][ T0] down_trylock (kernel/locking/semaphore.c:145)
[ 250.448861][ T0] ? vprintk_emit (kernel/printk/printk.c:2270)
[ 250.448869][ T0] __down_trylock_console_sem (kernel/printk/printk.c:246)
[ 250.448881][ T0] console_trylock_spinning (kernel/printk/printk.c:2586 kernel/printk/printk.c:1870)
[ 250.448893][ T0] vprintk_emit (kernel/printk/printk.c:2270)
[ 250.448896][ T0] ? trace_event_buffer_commit (kernel/trace/trace.c:2931)
[ 250.448933][ T0] _printk (kernel/printk/printk.c:2287)
[ 250.448945][ T0] ? record_print_text.cold (kernel/printk/printk.c:2287)
[ 250.449029][ T0] lockdep_rcu_suspicious (kernel/locking/lockdep.c:6558)
[ 250.449053][ T0] trace_write_msr (arch/x86/include/asm/msr-trace.h:45 (discriminator 47))
[ 250.449073][ T0] intel_idle_ibrs (drivers/idle/intel_idle.c:186)
[ 250.449091][ T0] cpuidle_enter_state (drivers/cpuidle/cpuidle.c:237)
[ 250.449107][ T0] ? trace_event_raw_event_preemptirq_template (include/trace/events/preemptirq.h:14)
[ 250.449134][ T0] cpuidle_enter (drivers/cpuidle/cpuidle.c:353)
[ 250.449157][ T0] cpuidle_idle_call (kernel/sched/idle.c:155 kernel/sched/idle.c:236)
[ 250.449171][ T0] ? arch_cpu_idle_exit+0xc0/0xc0
[ 250.449213][ T0] ? do_idle (kernel/sched/idle.c:282 (discriminator 1))
[ 250.449216][ T0] ? tsc_verify_tsc_adjust (arch/x86/kernel/tsc_sync.c:58)
[ 250.449245][ T0] do_idle (kernel/sched/idle.c:303)
[ 250.449265][ T0] cpu_startup_entry (kernel/sched/idle.c:399 (discriminator 1))
[ 250.449272][ T0] rest_init (include/linux/rcupdate.h:696 init/main.c:697)
[ 250.449292][ T0] arch_call_rest_init+0xf/0x19
[ 250.449300][ T0] start_kernel (init/main.c:1139)
[ 250.449320][ T0] secondary_startup_64_no_verify (arch/x86/kernel/head_64.S:358)
[ 250.449406][ T0] </TASK>
[ 250.451611][ T0]
[ 250.451613][ T0] =============================
[ 250.451614][ T0] WARNING: suspicious RCU usage
[ 250.451615][ T0] 5.19.0-rc4-00027-gbf5835bcdb96 #1 Tainted: G S W
[ 250.451617][ T0] -----------------------------
[ 250.451619][ T0] include/linux/rcupdate.h:696 rcu_read_lock() used illegally while idle!
[ 250.451621][ T0]
[ 250.451621][ T0] other info that might help us debug this:
[ 250.451621][ T0]
[ 250.451623][ T0]
[ 250.451623][ T0] rcu_scheduler_active = 2, debug_locks = 1
[ 250.451625][ T0] RCU used illegally from extended quiescent state!
[ 250.451626][ T0] 4 locks held by swapper/0/0:
[ 250.451629][ T0] #0: ffffffff857794e0 (console_lock){+.+.}-{0:0}, at: vprintk_emit (kernel/printk/printk.c:2270)
[ 250.451640][ T0] #1: ffffffff853f8e40 (console_owner){-...}-{0:0}, at: console_emit_next_record+0x220/0x740
[ 250.451651][ T0] #2: ffffffff85ca50b8 (printing_lock){....}-{2:2}, at: vt_console_print (drivers/tty/vt/vt.c:3090)
[ 250.451662][ T0] #3: ffffffff8578a280 (rcu_read_lock){....}-{1:2}, at: atomic_notifier_call_chain (kernel/notifier.c:221)
[ 250.451673][ T0]
[ 250.451673][ T0] stack backtrace:
[ 250.451675][ T0] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G S W 5.19.0-rc4-00027-gbf5835bcdb96 #1
[ 250.451678][ T0] Hardware name: HP HP Z240 SFF Workstation/802E, BIOS N51 Ver. 01.63 10/05/2017
[ 250.451680][ T0] Call Trace:
[ 250.451682][ T0] <TASK>
[ 250.451701][ T0] dump_stack_lvl (lib/dump_stack.c:107 (discriminator 4))
[ 250.451714][ T0] atomic_notifier_call_chain (include/linux/rcupdate.h:696 kernel/notifier.c:224)
[ 250.451748][ T0] vt_console_print (drivers/tty/vt/vt.c:3137)
[ 250.451790][ T0] ? __pv_queued_spin_unlock_slowpath (kernel/locking/qspinlock_paravirt.h:523)
[ 250.451803][ T0] ? lf (drivers/tty/vt/vt.c:3079)
[ 250.451806][ T0] ? rwlock_bug+0xc0/0xc0
[ 250.451827][ T0] ? console_emit_next_record+0x24d/0x740
[ 250.451870][ T0] console_emit_next_record+0x2dd/0x740
[ 250.451891][ T0] ? __lock_acquire (kernel/locking/lockdep.c:5053)
[ 250.451903][ T0] ? devkmsg_read (kernel/printk/printk.c:2676)
[ 250.451936][ T0] ? find_held_lock (kernel/locking/lockdep.c:5156)
[ 250.452013][ T0] ? rcu_read_unlock (include/linux/rcupdate.h:724 (discriminator 5))
[ 250.452016][ T0] ? secondary_startup_64_no_verify (arch/x86/kernel/head_64.S:358)
[ 250.452054][ T0] console_unlock (kernel/printk/printk.c:2794 kernel/printk/printk.c:2861)
[ 250.452075][ T0] ? console_emit_next_record+0x740/0x740
[ 250.452088][ T0] ? vprintk_emit (kernel/printk/printk.c:2270)
[ 250.452141][ T0] vprintk_emit (kernel/printk/printk.c:2271)
[ 250.452145][ T0] ? trace_event_buffer_commit (kernel/trace/trace.c:2931)
[ 250.452194][ T0] _printk (kernel/printk/printk.c:2287)
[ 250.452211][ T0] ? record_print_text.cold (kernel/printk/printk.c:2287)
[ 250.452302][ T0] lockdep_rcu_suspicious (kernel/locking/lockdep.c:6558)
[ 250.452327][ T0] trace_write_msr (arch/x86/include/asm/msr-trace.h:45 (discriminator 47))
[ 250.452348][ T0] intel_idle_ibrs (drivers/idle/intel_idle.c:186)
[ 250.452368][ T0] cpuidle_enter_state (drivers/cpuidle/cpuidle.c:237)
[ 250.452384][ T0] ? trace_event_raw_event_preemptirq_template (include/trace/events/preemptirq.h:14)
[ 250.452415][ T0] cpuidle_enter (drivers/cpuidle/cpuidle.c:353)
[ 250.452439][ T0] cpuidle_idle_call (kernel/sched/idle.c:155 kernel/sched/idle.c:236)
[ 250.452451][ T0] ? arch_cpu_idle_exit+0xc0/0xc0
[ 250.452480][ T0] ? do_idle (kernel/sched/idle.c:282 (discriminator 1))
[ 250.452483][ T0] ? tsc_verify_tsc_adjust (arch/x86/kernel/tsc_sync.c:58)
[ 250.452513][ T0] do_idle (kernel/sched/idle.c:303)
[ 250.452533][ T0] cpu_startup_entry (kernel/sched/idle.c:399 (discriminator 1))
[ 250.452541][ T0] rest_init (include/linux/rcupdate.h:696 init/main.c:697)
[ 250.452561][ T0] arch_call_rest_init+0xf/0x19
[ 250.452569][ T0] start_kernel (init/main.c:1139)
[ 250.452589][ T0] secondary_startup_64_no_verify (arch/x86/kernel/head_64.S:358)
[ 250.452681][ T0] </TASK>
[ 250.452682][ T0]
[ 250.452684][ T0] =============================
[ 250.452685][ T0] WARNING: suspicious RCU usage
[ 250.452686][ T0] 5.19.0-rc4-00027-gbf5835bcdb96 #1 Tainted: G S W
[ 250.452688][ T0] -----------------------------
[ 250.452690][ T0] include/linux/rcupdate.h:724 rcu_read_unlock() used illegally while idle!
[ 250.452692][ T0]
[ 250.452692][ T0] other info that might help us debug this:
[ 250.452692][ T0]
[ 250.452693][ T0]
[ 250.452693][ T0] rcu_scheduler_active = 2, debug_locks = 1
[ 250.452695][ T0] RCU used illegally from extended quiescent state!
[ 250.452697][ T0] 4 locks held by swapper/0/0:
[ 250.452699][ T0] #0: ffffffff857794e0 (console_lock){+.+.}-{0:0}, at: vprintk_emit (kernel/printk/printk.c:2270)
[ 250.452710][ T0] #1: ffffffff853f8e40 (console_owner){-...}-{0:0}, at: console_emit_next_record+0x220/0x740
[ 250.452721][ T0] #2: ffffffff85ca50b8 (printing_lock){....}-{2:2}, at: vt_console_print (drivers/tty/vt/vt.c:3090)
[ 250.452731][ T0] #3: ffffffff8578a280 (rcu_read_lock){....}-{1:2}, at: atomic_notifier_call_chain (kernel/notifier.c:221)
[ 250.452741][ T0]
[ 250.452741][ T0] stack backtrace:
[ 250.452743][ T0] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G S W 5.19.0-rc4-00027-gbf5835bcdb96 #1
[ 250.452746][ T0] Hardware name: HP HP Z240 SFF Workstation/802E, BIOS N51 Ver. 01.63 10/05/2017
[ 250.452748][ T0] Call Trace:
[ 250.452750][ T0] <TASK>
[ 250.452768][ T0] dump_stack_lvl (lib/dump_stack.c:107 (discriminator 4))
[ 250.452780][ T0] atomic_notifier_call_chain (include/linux/rcupdate.h:724 kernel/notifier.c:226)
[ 250.452813][ T0] vt_console_print (drivers/tty/vt/vt.c:3137)
[ 250.452855][ T0] ? __pv_queued_spin_unlock_slowpath (kernel/locking/qspinlock_paravirt.h:523)
[ 250.452868][ T0] ? lf (drivers/tty/vt/vt.c:3079)
[ 250.452871][ T0] ? rwlock_bug+0xc0/0xc0
[ 250.452893][ T0] ? console_emit_next_record+0x24d/0x740
[ 250.452934][ T0] console_emit_next_record+0x2dd/0x740
[ 250.452955][ T0] ? __lock_acquire (kernel/locking/lockdep.c:5053)
[ 250.452967][ T0] ? devkmsg_read (kernel/printk/printk.c:2676)
[ 250.453001][ T0] ? find_held_lock (kernel/locking/lockdep.c:5156)
[ 250.453074][ T0] ? rcu_read_unlock (include/linux/rcupdate.h:724 (discriminator 5))
[ 250.453077][ T0] ? secondary_startup_64_no_verify (arch/x86/kernel/head_64.S:358)
[ 250.453116][ T0] console_unlock (kernel/printk/printk.c:2794 kernel/printk/printk.c:2861)
[ 250.453137][ T0] ? console_emit_next_record+0x740/0x740
[ 250.453149][ T0] ? vprintk_emit (kernel/printk/printk.c:2270)
[ 250.453219][ T0] vprintk_emit (kernel/printk/printk.c:2271)
[ 250.453223][ T0] ? trace_event_buffer_commit (kernel/trace/trace.c:2931)
[ 250.453261][ T0] _printk (kernel/printk/printk.c:2287)
[ 250.453273][ T0] ? record_print_text.cold (kernel/printk/printk.c:2287)
[ 250.453366][ T0] lockdep_rcu_suspicious (kernel/locking/lockdep.c:6558)
[ 250.453391][ T0] trace_write_msr (arch/x86/include/asm/msr-trace.h:45 (discriminator 47))
[ 250.453412][ T0] intel_idle_ibrs (drivers/idle/intel_idle.c:186)
[ 250.453432][ T0] cpuidle_enter_state (drivers/cpuidle/cpuidle.c:237)
[ 250.453449][ T0] ? trace_event_raw_event_preemptirq_template (include/trace/events/preemptirq.h:14)
[ 250.453478][ T0] cpuidle_enter (drivers/cpuidle/cpuidle.c:353)
[ 250.453502][ T0] cpuidle_idle_call (kernel/sched/idle.c:155 kernel/sched/idle.c:236)
[ 250.453514][ T0] ? arch_cpu_idle_exit+0xc0/0xc0
[ 250.453544][ T0] ? do_idle (kernel/sched/idle.c:282 (discriminator 1))
[ 250.453547][ T0] ? tsc_verify_tsc_adjust (arch/x86/kernel/tsc_sync.c:58)
[ 250.453576][ T0] do_idle (kernel/sched/idle.c:303)
[ 250.453597][ T0] cpu_startup_entry (kernel/sched/idle.c:399 (discriminator 1))
[ 250.453605][ T0] rest_init (include/linux/rcupdate.h:696 init/main.c:697)
[ 250.453625][ T0] arch_call_rest_init+0xf/0x19
[ 250.453633][ T0] start_kernel (init/main.c:1139)
[ 250.453653][ T0] secondary_startup_64_no_verify (arch/x86/kernel/head_64.S:358)
[ 250.453746][ T0] </TASK>
[ 251.576978][ T0] =============================
[ 251.581751][ T0] WARNING: suspicious RCU usage
[ 251.586537][ T0] 5.19.0-rc4-00027-gbf5835bcdb96 #1 Tainted: G S W
[ 251.594132][ T0] -----------------------------
[ 251.598911][ T0] arch/x86/include/asm/msr-trace.h:45 suspicious rcu_dereference_check() usage!
[ 251.607906][ T0]
[ 251.607906][ T0] other info that might help us debug this:
[ 251.607906][ T0]
[ 251.618130][ T0]
[ 251.618130][ T0] rcu_scheduler_active = 2, debug_locks = 1
[ 251.626147][ T0] RCU used illegally from extended quiescent state!
[ 251.632677][ T0] no locks held by swapper/0/0.
[ 251.637453][ T0]
[ 251.637453][ T0] stack backtrace:
[ 251.643291][ T0] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G S W 5.19.0-rc4-00027-gbf5835bcdb96 #1
[ 251.653519][ T0] Hardware name: HP HP Z240 SFF Workstation/802E, BIOS N51 Ver. 01.63 10/05/2017
[ 251.662596][ T0] Call Trace:
[ 251.665798][ T0] <TASK>
[ 251.668669][ T0] dump_stack_lvl (lib/dump_stack.c:107 (discriminator 4))
[ 251.673106][ T0] trace_write_msr (arch/x86/include/asm/msr-trace.h:45 (discriminator 47))
[ 251.677813][ T0] intel_idle_ibrs (drivers/idle/intel_idle.c:186)
[ 251.682525][ T0] cpuidle_enter_state (drivers/cpuidle/cpuidle.c:237)
[ 251.687579][ T0] ? trace_event_raw_event_preemptirq_template (include/trace/events/preemptirq.h:14)
[ 251.694756][ T0] cpuidle_enter (drivers/cpuidle/cpuidle.c:353)
[ 251.699116][ T0] cpuidle_idle_call (kernel/sched/idle.c:155 kernel/sched/idle.c:236)
[ 251.703996][ T0] ? arch_cpu_idle_exit+0xc0/0xc0
[ 251.708979][ T0] ? do_idle (kernel/sched/idle.c:282 (discriminator 1))
[ 251.713053][ T0] ? tsc_verify_tsc_adjust (arch/x86/kernel/tsc_sync.c:58)
[ 251.718387][ T0] do_idle (kernel/sched/idle.c:303)
[ 251.722307][ T0] cpu_startup_entry (kernel/sched/idle.c:399 (discriminator 1))
[ 251.727004][ T0] rest_init (include/linux/rcupdate.h:696 init/main.c:697)
[ 251.731200][ T0] arch_call_rest_init+0xf/0x19
[ 251.735991][ T0] start_kernel (init/main.c:1139)
[ 251.740438][ T0] secondary_startup_64_no_verify (arch/x86/kernel/head_64.S:358)
[ 251.746359][ T0] </TASK>
[ 253.000234][ T404] # [20] event tracing - enable/disable with top level files [PASS]
[ 253.000250][ T404]
[ 255.064405][ T404] # [21] Test trace_printk from module [PASS]
[ 255.064421][ T404]
[ 259.279697][ T404] # [22] ftrace - function graph filters with stack tracer [PASS]
[ 259.279713][ T404]
[ 261.592044][ T404] # [23] ftrace - function graph filters [PASS]
[ 261.592060][ T404]
[ 269.628751][ T404] # [24] ftrace - function glob filters [PASS]
[ 269.628767][ T404]
[ 271.433852][ T404] # [25] ftrace - function pid notrace filters [PASS]
[ 271.433868][ T404]
[ 273.247819][ T404] # [26] ftrace - function pid filters [PASS]
[ 273.247835][ T404]
[ 275.502197][ T404] # [27] ftrace - stacktrace filter command [PASS]
[ 275.502212][ T404]
[ 276.980986][ T404] # [28] ftrace - function trace with cpumask [PASS]
[ 276.981028][ T404]
[ 283.552493][ T404] # [29] ftrace - test for function event triggers [PASS]
[ 283.552510][ T404]
[ 285.579611][ T404] # [30] ftrace - function trace on module [PASS]
[ 285.579627][ T404]
[ 291.123872][ T404] # [31] ftrace - function profiling [PASS]
[ 291.123888][ T404]
[ 303.056632][ T404] # [32] ftrace - function profiler with function tracing [PASS]
[ 303.056773][ T404]
[ 312.651769][ T404] # [33] ftrace - test reading of set_ftrace_filter [PASS]
[ 312.651793][ T404]
[ 315.825762][ T404] # [34] ftrace - Max stack tracer [PASS]
[ 315.825787][ T404]
[ 319.682645][ T404] # [35] ftrace - test for function traceon/off triggers [PASS]
[ 319.682668][ T404]
[ 320.649090][ T404] # [36] ftrace - test tracing error log support [PASS]
[ 320.649114][ T404]
[ 330.688068][ T404] # [37] Test creation and deletion of trace instances while setting an event [PASS]
[ 330.688093][ T404]
[ 335.983846][ T404] # [38] Test creation and deletion of trace instances [PASS]
[ 335.983869][ T404]
[ 337.015965][ T404] # [39] Kprobe dynamic event - adding and removing [PASS]
[ 337.015989][ T404]
[ 337.862864][ T404] # [40] Kprobe dynamic event - busy event check [PASS]
[ 337.862888][ T404]
[ 338.899629][ T404] # [41] Kprobe dynamic event with arguments [PASS]
[ 338.899652][ T404]
[ 339.747001][ T404] # [42] Kprobe event with comm arguments [PASS]
[ 339.747024][ T404]
[ 340.763891][ T404] # [43] Kprobe event string type argument [PASS]
[ 340.763914][ T404]
[ 342.099964][ T404] # [44] Kprobe event symbol argument [PASS]
[ 342.099987][ T404]
[ 343.912626][ T404] # [45] Kprobe event argument syntax [PASS]
[ 343.912648][ T404]
[ 345.232611][ T404] # [46] Kprobes event arguments with types [PASS]
[ 345.232634][ T404]
[ 346.188564][ T404] # [47] Kprobe event user-memory access [PASS]
[ 346.188587][ T404]
[ 347.868806][ T404] # [48] Kprobe event auto/manual naming [PASS]
[ 347.868830][ T404]
[ 349.432511][ T404] # [49] Kprobe dynamic event with function tracer [PASS]
[ 349.432534][ T404]
[ 350.663270][ T5344] trace_kprobe: This probe might be able to register after target module is loaded. Continue.
[ 350.861687][ T404] # [50] Kprobe dynamic event - probing module [PASS]
[ 350.861710][ T404]
[ 351.807167][ T404] # [51] Create/delete multiprobe on kprobe event [PASS]
[ 351.807212][ T404]
[ 356.872233][ T404] # [52] Kprobe event parser error log check [PASS]
[ 356.872256][ T404]
[ 357.769917][ T404] # [53] Kretprobe dynamic event with arguments [PASS]
[ 357.769940][ T404]
[ 358.801301][ T404] # [54] Kretprobe dynamic event with maxactive [PASS]
[ 358.801324][ T404]
[ 359.815385][ T404] # [55] Kretprobe %return suffix test [PASS]
[ 359.815408][ T404]
[ 360.614263][ T6217] trace_kprobe: Could not probe notrace function startup_64
[ 360.623434][ T6217] trace_kprobe: Could not probe notrace function _stext
[ 360.631437][ T6217] trace_kprobe: Could not probe notrace function _text
[ 360.639384][ T6217] trace_kprobe: Could not probe notrace function secondary_startup_64
[ 360.648676][ T6217] trace_kprobe: Could not probe notrace function secondary_startup_64_no_verify
[ 360.658774][ T6217] trace_kprobe: Could not probe notrace function verify_cpu
[ 360.667158][ T6217] trace_kprobe: Could not probe notrace function sev_verify_cbit
[ 360.675982][ T6217] trace_kprobe: Could not probe notrace function start_cpu0
[ 360.684410][ T6217] trace_kprobe: Could not probe notrace function __startup_64
[ 360.693036][ T6217] trace_kprobe: Could not probe notrace function startup_64_setup_env
[ 360.702377][ T6217] trace_kprobe: Could not probe notrace function early_setup_idt
[ 360.725684][ T6217] trace_kprobe: Could not probe notrace function cpu_online
[ 360.735089][ T6217] trace_kprobe: Could not probe notrace function rcu_read_unlock
[ 360.743769][ T6217] trace_kprobe: Could not probe notrace function perf_trace_initcall_level
[ 360.753307][ T6217] trace_kprobe: Could not probe notrace function perf_trace_initcall_start
[ 360.762884][ T6217] trace_kprobe: Could not probe notrace function perf_trace_initcall_finish
[ 360.772676][ T6217] trace_kprobe: Could not probe notrace function trace_event_raw_event_initcall_start
[ 360.784661][ T6217] trace_kprobe: Could not probe notrace function trace_event_raw_event_initcall_finish
[ 360.795300][ T6217] trace_kprobe: Could not probe notrace function trace_raw_output_initcall_level
[ 360.805485][ T6217] trace_kprobe: Could not probe notrace function trace_raw_output_initcall_start
[ 360.815642][ T6217] trace_kprobe: Could not probe notrace function trace_raw_output_initcall_finish
[ 360.825869][ T6217] trace_kprobe: Could not probe notrace function __bpf_trace_initcall_level
[ 360.835954][ T6217] trace_kprobe: Could not probe notrace function __bpf_trace_initcall_start
[ 360.845636][ T6217] trace_kprobe: Could not probe notrace function __bpf_trace_initcall_finish
[ 360.856425][ T6217] trace_kprobe: Could not probe notrace function trace_event_raw_event_initcall_level
[ 360.878115][ T6217] trace_kprobe: Could not probe notrace function __switch_to_asm
[ 360.886734][ T6217] trace_kprobe: Could not probe notrace function ret_from_fork
[ 360.895232][ T6217] trace_kprobe: Could not probe notrace function rewind_stack_and_make_dead
[ 360.904820][ T6217] trace_kprobe: Could not probe notrace function __do_sys_ni_syscall
[ 360.913817][ T6217] trace_kprobe: Could not probe notrace function __ia32_sys_ni_syscall
[ 360.922965][ T6217] trace_kprobe: Could not probe notrace function __x64_sys_ni_syscall
[ 360.944790][ T6217] trace_kprobe: Could not probe notrace function perf_trace_emulate_vsyscall
[ 360.954582][ T6217] trace_kprobe: Could not probe notrace function trace_event_raw_event_emulate_vsyscall
[ 360.965306][ T6217] trace_kprobe: Could not probe notrace function trace_raw_output_emulate_vsyscall
[ 360.975589][ T6217] trace_kprobe: Could not probe notrace function __bpf_trace_emulate_vsyscall
[ 360.987193][ T6217] trace_kprobe: Could not probe notrace function trace_emulate_vsyscall
[ 361.016809][ T6217] trace_kprobe: Could not probe notrace function native_read_msr
[ 361.098936][ T6217] trace_kprobe: Could not probe notrace function amd_pmu_addr_offset
[ 361.165556][ T6217] trace_kprobe: Could not probe notrace function intel_pmu_disable_all
[ 361.249143][ T6217] trace_kprobe: Could not probe notrace function x86_pmu_disable_event
[ 367.951272][ T404] # [56] Register/unregister many kprobe events [PASS]
[ 367.951296][ T404]
[ 369.076958][ T404] # [57] Kprobe events - probe points [PASS]
[ 369.076981][ T404]
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
sudo bin/lkp install job.yaml # job file is attached in this email
bin/lkp split-job --compatible job.yaml # generate the yaml file for lkp run
sudo bin/lkp run generated-yaml-file
# if come across any failure that blocks the test,
# please remove ~/.lkp and /lkp dir to run from a clean state.
--
0-DAY CI Kernel Test Service
https://01.org/lkp
View attachment "config-5.19.0-rc4-00027-gbf5835bcdb96" of type "text/plain" (168494 bytes)
View attachment "job-script" of type "text/plain" (6334 bytes)
Download attachment "dmesg.xz" of type "application/x-xz" (28820 bytes)
View attachment "kernel-selftests" of type "text/plain" (8080 bytes)
View attachment "job.yaml" of type "text/plain" (5249 bytes)
View attachment "reproduce" of type "text/plain" (152 bytes)
Powered by blists - more mailing lists