[ 22.492351][ C0] clocksource: Watchdog remote CPU 3 read timed out [ 22.492551][ C0] [ 22.492553][ C0] ======================================================== [ 22.492554][ C0] WARNING: possible irq lock inversion dependency detected [ 22.492556][ C0] 7.2.0-virtme #1 Tainted: G W [ 22.492557][ C0] -------------------------------------------------------- [ 22.492557][ C0] modprobe/340 just changed the state of lock: [ 22.492559][ C0] ffffffff8f09d760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 22.492571][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 22.492572][ C0] (fs_reclaim){+.+.}-{0:0} [ 22.492573][ C0] [ 22.492573][ C0] [ 22.492573][ C0] and interrupts could create inverse lock ordering between them. [ 22.492573][ C0] [ 22.492574][ C0] [ 22.492574][ C0] other info that might help us debug this: [ 22.492575][ C0] Possible interrupt unsafe locking scenario: [ 22.492575][ C0] [ 22.492575][ C0] CPU0 CPU1 [ 22.492576][ C0] ---- ---- [ 22.492576][ C0] lock(fs_reclaim); [ 22.492577][ C0] local_irq_disable(); [ 22.492578][ C0] lock(console_owner); [ 22.492578][ C0] lock(fs_reclaim); [ 22.492579][ C0] [ 22.492580][ C0] lock(console_owner); [ 22.492580][ C0] [ 22.492580][ C0] *** DEADLOCK *** [ 22.492580][ C0] [ 22.492581][ C0] locks held by modprobe/340: 6, last CPU#0: [ 22.492582][ C0] #0: ff1100000fd99d78 (&mm->mmap_lock){++++}-{4:4}, at: exit_mmap+0x2fc/0x960 [ 22.492588][ C0] #1: ffffffff8f194c00 (rcu_read_lock){....}-{1:3}, at: is_module_text_address+0x26/0x250 [ 22.492592][ C0] #2: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 22.492596][ C0] #3: ffffffff8f1e29f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 22.492599][ C0] #4: ffffffff8f17ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 22.492602][ C0] #5: ffffffff8f17de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 22.492605][ C0] [ 22.492605][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 22.492610][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 22.492612][ C0] HARDIRQ-ON-W at: [ 22.492614][ C0] __lock_acquire+0x388/0xc20 [ 22.492617][ C0] lock_acquire.part.0+0xd4/0x280 [ 22.492619][ C0] fs_reclaim_acquire+0xd5/0x120 [ 22.492621][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 22.492623][ C0] kthread_create_worker_on_node+0xea/0x210 [ 22.492626][ C0] workqueue_init+0x2a/0x680 [ 22.492630][ C0] kernel_init_freeable+0x2fe/0x630 [ 22.492633][ C0] kernel_init+0x21/0x150 [ 22.492637][ C0] ret_from_fork+0x474/0x6b0 [ 22.492640][ C0] ret_from_fork_asm+0x11/0x20 [ 22.492643][ C0] SOFTIRQ-ON-W at: [ 22.492644][ C0] __lock_acquire+0x388/0xc20 [ 22.492645][ C0] lock_acquire.part.0+0xd4/0x280 [ 22.492647][ C0] fs_reclaim_acquire+0xd5/0x120 [ 22.492648][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 22.492649][ C0] kthread_create_worker_on_node+0xea/0x210 [ 22.492651][ C0] workqueue_init+0x2a/0x680 [ 22.492652][ C0] kernel_init_freeable+0x2fe/0x630 [ 22.492654][ C0] kernel_init+0x21/0x150 [ 22.492655][ C0] ret_from_fork+0x474/0x6b0 [ 22.492656][ C0] ret_from_fork_asm+0x11/0x20 [ 22.492657][ C0] INITIAL USE at: [ 22.492658][ C0] __lock_acquire+0x388/0xc20 [ 22.492660][ C0] lock_acquire.part.0+0xd4/0x280 [ 22.492661][ C0] fs_reclaim_acquire+0xd5/0x120 [ 22.492663][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 22.492664][ C0] kthread_create_worker_on_node+0xea/0x210 [ 22.492665][ C0] workqueue_init+0x2a/0x680 [ 22.492667][ C0] kernel_init_freeable+0x2fe/0x630 [ 22.492668][ C0] kernel_init+0x21/0x150 [ 22.492669][ C0] ret_from_fork+0x474/0x6b0 [ 22.492670][ C0] ret_from_fork_asm+0x11/0x20 [ 22.492672][ C0] } [ 22.492672][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 22.492676][ C0] ... acquired at: [ 22.492677][ C0] __lock_acquire+0x518/0xc20 [ 22.492678][ C0] lock_acquire.part.0+0xd4/0x280 [ 22.492679][ C0] fs_reclaim_acquire+0xd5/0x120 [ 22.492681][ C0] __kmalloc_noprof+0xd3/0x760 [ 22.492681][ C0] alloc_buf.isra.0+0x4b/0x260 [ 22.492685][ C0] put_chars+0x1e1/0x2f0 [ 22.492686][ C0] hvc_console_print+0x292/0x780 [ 22.492689][ C0] console_emit_next_record+0x22f/0x4c0 [ 22.492691][ C0] console_flush_one_record+0x46f/0x710 [ 22.492693][ C0] console_unlock+0xee/0x1f0 [ 22.492694][ C0] vprintk_emit+0x37c/0x3e0 [ 22.492695][ C0] _printk+0xc7/0x100 [ 22.492698][ C0] dm_interface_init+0x50/0x60 [ 22.492701][ C0] dm_init+0x51/0xd0 [ 22.492702][ C0] do_one_initcall+0x124/0x4f0 [ 22.492703][ C0] kernel_init_freeable+0x596/0x630 [ 22.492705][ C0] kernel_init+0x21/0x150 [ 22.492706][ C0] ret_from_fork+0x474/0x6b0 [ 22.492707][ C0] ret_from_fork_asm+0x11/0x20 [ 22.492708][ C0] [ 22.492709][ C0] -> (console_owner){..-.}-{0:0} { [ 22.492710][ C0] IN-SOFTIRQ-W at: [ 22.492711][ C0] __lock_acquire+0x388/0xc20 [ 22.492713][ C0] lock_acquire.part.0+0xd4/0x280 [ 22.492714][ C0] console_lock_spinning_enable+0x5c/0x60 [ 22.492716][ C0] console_emit_next_record+0x1d1/0x4c0 [ 22.492717][ C0] console_flush_one_record+0x46f/0x710 [ 22.492719][ C0] console_unlock+0xee/0x1f0 [ 22.492720][ C0] vprintk_emit+0x37c/0x3e0 [ 22.492721][ C0] _printk+0xc7/0x100 [ 22.492722][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 22.492725][ C0] call_timer_fn+0x160/0x4d0 [ 22.492726][ C0] __run_timers+0x68f/0xaa0 [ 22.492728][ C0] run_timer_softirq+0xf0/0x160 [ 22.492729][ C0] handle_softirqs+0x1d3/0x900 [ 22.492732][ C0] __irq_exit_rcu+0x145/0x1c0 [ 22.492733][ C0] irq_exit_rcu+0xe/0x30 [ 22.492735][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 22.492736][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 22.492738][ C0] lock_acquire.part.0+0x112/0x280 [ 22.492739][ C0] is_module_text_address+0x3a/0x250 [ 22.492740][ C0] kernel_text_address+0x97/0x170 [ 22.492742][ C0] __kernel_text_address+0x12/0x30 [ 22.492743][ C0] unwind_get_return_address+0x67/0xd0 [ 22.492745][ C0] arch_stack_walk+0xed/0x130 [ 22.492747][ C0] stack_trace_save+0x93/0xc0 [ 22.492748][ C0] set_track_prepare+0x6b/0xa0 [ 22.492751][ C0] __alloc_object+0xf9/0x260 [ 22.492752][ C0] __create_object+0x30/0x110 [ 22.492753][ C0] __kmalloc_cache_noprof+0x4a9/0x620 [ 22.492754][ C0] kmem_cache_free+0x365/0x550 [ 22.492755][ C0] tear_down_vmas+0x175/0x520 [ 22.492756][ C0] exit_mmap+0x486/0x960 [ 22.492758][ C0] mmput+0xa9/0x450 [ 22.492760][ C0] exit_mm+0x134/0x200 [ 22.492761][ C0] do_exit+0x548/0xdc0 [ 22.492762][ C0] do_group_exit+0xb8/0x370 [ 22.492763][ C0] __x64_sys_exit_group+0x3c/0x50 [ 22.492764][ C0] x64_sys_call+0x1567/0x1570 [ 22.492766][ C0] do_syscall_64+0xff/0x530 [ 22.492768][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 22.492769][ C0] INITIAL USE at: [ 22.492770][ C0] } [ 22.492770][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 22.492773][ C0] ... acquired at: [ 22.492773][ C0] mark_lock+0x1d7/0xa00 [ 22.492774][ C0] mark_usage+0x42/0x170 [ 22.492776][ C0] __lock_acquire+0x388/0xc20 [ 22.492777][ C0] lock_acquire.part.0+0xd4/0x280 [ 22.492778][ C0] console_lock_spinning_enable+0x5c/0x60 [ 22.492780][ C0] console_emit_next_record+0x1d1/0x4c0 [ 22.492781][ C0] console_flush_one_record+0x46f/0x710 [ 22.492783][ C0] console_unlock+0xee/0x1f0 [ 22.492784][ C0] vprintk_emit+0x37c/0x3e0 [ 22.492785][ C0] _printk+0xc7/0x100 [ 22.492786][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 22.492787][ C0] call_timer_fn+0x160/0x4d0 [ 22.492789][ C0] __run_timers+0x68f/0xaa0 [ 22.492790][ C0] run_timer_softirq+0xf0/0x160 [ 22.492792][ C0] handle_softirqs+0x1d3/0x900 [ 22.492793][ C0] __irq_exit_rcu+0x145/0x1c0 [ 22.492794][ C0] irq_exit_rcu+0xe/0x30 [ 22.492796][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 22.492797][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 22.492798][ C0] lock_acquire.part.0+0x112/0x280 [ 22.492799][ C0] is_module_text_address+0x3a/0x250 [ 22.492800][ C0] kernel_text_address+0x97/0x170 [ 22.492801][ C0] __kernel_text_address+0x12/0x30 [ 22.492802][ C0] unwind_get_return_address+0x67/0xd0 [ 22.492803][ C0] arch_stack_walk+0xed/0x130 [ 22.492804][ C0] stack_trace_save+0x93/0xc0 [ 22.492805][ C0] set_track_prepare+0x6b/0xa0 [ 22.492806][ C0] __alloc_object+0xf9/0x260 [ 22.492807][ C0] __create_object+0x30/0x110 [ 22.492808][ C0] __kmalloc_cache_noprof+0x4a9/0x620 [ 22.492809][ C0] kmem_cache_free+0x365/0x550 [ 22.492810][ C0] tear_down_vmas+0x175/0x520 [ 22.492811][ C0] exit_mmap+0x486/0x960 [ 22.492813][ C0] mmput+0xa9/0x450 [ 22.492814][ C0] exit_mm+0x134/0x200 [ 22.492815][ C0] do_exit+0x548/0xdc0 [ 22.492816][ C0] do_group_exit+0xb8/0x370 [ 22.492817][ C0] __x64_sys_exit_group+0x3c/0x50 [ 22.492818][ C0] x64_sys_call+0x1567/0x1570 [ 22.492819][ C0] do_syscall_64+0xff/0x530 [ 22.492820][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 22.492821][ C0] [ 22.492822][ C0] [ 22.492822][ C0] stack backtrace: [ 22.492824][ C0] CPU: 0 UID: 0 PID: 340 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 22.492827][ C0] Tainted: [W]=WARN [ 22.492828][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 22.492830][ C0] Call Trace: [ 22.492831][ C0] [ 22.492832][ C0] dump_stack_lvl+0x6f/0xa0 [ 22.492836][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 22.492839][ C0] mark_lock_irq+0x989/0x9c0 [ 22.492841][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 22.492843][ C0] mark_lock+0x1d7/0xa00 [ 22.492845][ C0] mark_usage+0x42/0x170 [ 22.492847][ C0] __lock_acquire+0x388/0xc20 [ 22.492849][ C0] lock_acquire.part.0+0xd4/0x280 [ 22.492851][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 22.492853][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.492855][ C0] ? lock_acquire+0x13c/0x160 [ 22.492857][ C0] console_lock_spinning_enable+0x5c/0x60 [ 22.492859][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 22.492861][ C0] console_emit_next_record+0x1d1/0x4c0 [ 22.492863][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 22.492864][ C0] ? console_flush_one_record+0x106/0x710 [ 22.492866][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.492867][ C0] ? lock_acquire+0x13c/0x160 [ 22.492869][ C0] console_flush_one_record+0x46f/0x710 [ 22.492872][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 22.492873][ C0] ? __lock_acquire+0x518/0xc20 [ 22.492875][ C0] ? write_ibpb+0xb/0x30 [ 22.492877][ C0] console_unlock+0xee/0x1f0 [ 22.492879][ C0] ? console_flush_one_record+0x710/0x710 [ 22.492881][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.492882][ C0] ? lock_acquire+0xe0/0x160 [ 22.492884][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 22.492886][ C0] ? vprintk_emit+0x320/0x3e0 [ 22.492887][ C0] vprintk_emit+0x37c/0x3e0 [ 22.492889][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 22.492890][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 22.492892][ C0] _printk+0xc7/0x100 [ 22.492894][ C0] ? snapshot_read.cold+0x21/0x21 [ 22.492896][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 22.492897][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 22.492900][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 22.492901][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 22.492903][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 22.492904][ C0] call_timer_fn+0x160/0x4d0 [ 22.492906][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 22.492908][ C0] ? debug_object_active_state+0x430/0x430 [ 22.492911][ C0] ? find_held_lock+0x2b/0x80 [ 22.492913][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 22.492915][ C0] ? mark_held_locks+0x40/0x70 [ 22.492917][ C0] __run_timers+0x68f/0xaa0 [ 22.492918][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 22.492921][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 22.492922][ C0] ? __lock_acquire+0x518/0xc20 [ 22.492925][ C0] ? __rwlock_init+0x150/0x150 [ 22.492927][ C0] run_timer_softirq+0xf0/0x160 [ 22.492929][ C0] ? __run_timers+0xaa0/0xaa0 [ 22.492931][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.492932][ C0] handle_softirqs+0x1d3/0x900 [ 22.492934][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 22.492936][ C0] ? _local_bh_enable+0xc0/0xc0 [ 22.492938][ C0] __irq_exit_rcu+0x145/0x1c0 [ 22.492939][ C0] irq_exit_rcu+0xe/0x30 [ 22.492941][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 22.492943][ C0] [ 22.492943][ C0] [ 22.492944][ C0] ? find_held_lock+0x2b/0x80 [ 22.492945][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 22.492946][ C0] RIP: 0010:lock_acquire.part.0+0x112/0x280 [ 22.492949][ C0] Code: ff 65 0f c1 05 7f 77 ed 04 83 f8 01 4c 8b 14 24 0f 85 35 01 00 00 9c 58 f6 c4 02 0f 85 52 01 00 00 4d 85 d2 0f 85 1b 01 00 00 <48> 8b 44 24 30 65 48 2b 05 51 32 ed 04 0f 85 48 01 00 00 48 83 c4 [ 22.492951][ C0] RSP: 0018:ffa0000001947790 EFLAGS: 00000206 [ 22.492953][ C0] RAX: 0000000000000046 RBX: 0000000000000000 RCX: fd2b5e2d9a0fc7b1 [ 22.492954][ C0] RDX: 0000000000000000 RSI: ffffffff8e6f9f03 RDI: ffffffff8e0949e0 [ 22.492955][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ff1100000f3c2ef8 [ 22.492956][ C0] R10: 0000000000000200 R11: 0000000000000001 R12: ffffffff8f194c00 [ 22.492956][ C0] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000002 [ 22.492959][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.492960][ C0] ? lock_acquire+0x13c/0x160 [ 22.492962][ C0] is_module_text_address+0x3a/0x250 [ 22.492963][ C0] ? is_module_text_address+0x26/0x250 [ 22.492964][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.492965][ C0] kernel_text_address+0x97/0x170 [ 22.492967][ C0] __kernel_text_address+0x12/0x30 [ 22.492968][ C0] ? write_profile+0xf0/0xf0 [ 22.492970][ C0] unwind_get_return_address+0x67/0xd0 [ 22.492971][ C0] arch_stack_walk+0xed/0x130 [ 22.492973][ C0] stack_trace_save+0x93/0xc0 [ 22.492975][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 22.492977][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.492978][ C0] set_track_prepare+0x6b/0xa0 [ 22.492979][ C0] ? __kmalloc_cache_noprof+0x4a9/0x620 [ 22.492980][ C0] ? kmem_cache_free+0x365/0x550 [ 22.492981][ C0] ? tear_down_vmas+0x175/0x520 [ 22.492983][ C0] ? exit_mmap+0x486/0x960 [ 22.492984][ C0] ? mmput+0xa9/0x450 [ 22.492986][ C0] ? exit_mm+0x134/0x200 [ 22.492987][ C0] ? do_exit+0x548/0xdc0 [ 22.492988][ C0] ? do_group_exit+0xb8/0x370 [ 22.492989][ C0] ? __x64_sys_exit_group+0x3c/0x50 [ 22.492990][ C0] ? x64_sys_call+0x1567/0x1570 [ 22.492991][ C0] ? do_syscall_64+0xff/0x530 [ 22.492993][ C0] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 22.492994][ C0] __alloc_object+0xf9/0x260 [ 22.492996][ C0] __create_object+0x30/0x110 [ 22.492997][ C0] __kmalloc_cache_noprof+0x4a9/0x620 [ 22.492999][ C0] ? kmem_cache_free+0x365/0x550 [ 22.493000][ C0] ? tear_down_vmas+0x175/0x520 [ 22.493002][ C0] kmem_cache_free+0x365/0x550 [ 22.493003][ C0] tear_down_vmas+0x175/0x520 [ 22.493006][ C0] exit_mmap+0x486/0x960 [ 22.493008][ C0] ? vm_brk_flags+0x4d0/0x4d0 [ 22.493009][ C0] ? lock_acquire+0x13c/0x160 [ 22.493011][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.493013][ C0] ? uprobe_clear_state+0x49/0x1e0 [ 22.493015][ C0] ? __lock_acquire+0x518/0xc20 [ 22.493019][ C0] mmput+0xa9/0x450 [ 22.493021][ C0] exit_mm+0x134/0x200 [ 22.493022][ C0] do_exit+0x548/0xdc0 [ 22.493024][ C0] ? exit_notify+0x890/0x890 [ 22.493025][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 22.493026][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.493028][ C0] do_group_exit+0xb8/0x370 [ 22.493029][ C0] __x64_sys_exit_group+0x3c/0x50 [ 22.493031][ C0] x64_sys_call+0x1567/0x1570 [ 22.493032][ C0] do_syscall_64+0xff/0x530 [ 22.493034][ C0] ? exc_page_fault+0xee/0x100 [ 22.493035][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 22.493037][ C0] RIP: 0033:0x7f32e68981b8 [ 22.493039][ C0] Code: Unable to access opcode bytes at 0x7f32e689818e. [ 22.493040][ C0] RSP: 002b:00007fff2932f5c8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 22.493041][ C0] RAX: ffffffffffffffda RBX: 00007f32e69c8f88 RCX: 00007f32e68981b8 [ 22.493042][ C0] RDX: 00007f32e67d3a88 RSI: ffffffffffffffa8 RDI: 0000000000000001 [ 22.493043][ C0] RBP: 00007fff2932f620 R08: 0000000000000000 R09: 00000000000000a0 [ 22.493044][ C0] R10: 00007fff2932f420 R11: 0000000000000246 R12: 0000000000000001 [ 22.493044][ C0] R13: 0000000000000001 R14: 00007f32e69c7680 R15: 00007f32e69c8fa0 [ 22.493046][ C0] [ 22.493050][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 22.493051][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 340, name: modprobe [ 22.493053][ C0] preempt_count: 102, expected: 0 [ 22.493053][ C0] RCU nest depth: 1, expected: 0 [ 22.493054][ C0] INFO: lockdep is turned off. [ 22.493055][ C0] irq event stamp: 17977 [ 22.493055][ C0] hardirqs last enabled at (17976): [] irqentry_exit+0x21c/0x790 [ 22.493057][ C0] hardirqs last disabled at (17977): [] console_emit_next_record+0x3d4/0x4c0 [ 22.493059][ C0] softirqs last enabled at (16368): [] handle_softirqs+0x67c/0x900 [ 22.493061][ C0] softirqs last disabled at (17961): [] __irq_exit_rcu+0x145/0x1c0 [ 22.493062][ C0] Preemption disabled at: [ 22.493063][ C0] [] handle_softirqs+0xf6/0x900 [ 22.493066][ C0] CPU: 0 UID: 0 PID: 340 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 22.493067][ C0] Tainted: [W]=WARN [ 22.493068][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 22.493068][ C0] Call Trace: [ 22.493069][ C0] [ 22.493069][ C0] dump_stack_lvl+0x6f/0xa0 [ 22.493072][ C0] ? handle_softirqs+0xf6/0x900 [ 22.493073][ C0] __might_resched.cold+0x1fe/0x2c1 [ 22.493076][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 22.493079][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.493081][ C0] __kmalloc_noprof+0x443/0x760 [ 22.493082][ C0] ? __rwlock_init+0x150/0x150 [ 22.493084][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 22.493086][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 22.493088][ C0] alloc_buf.isra.0+0x4b/0x260 [ 22.493090][ C0] put_chars+0x1e1/0x2f0 [ 22.493092][ C0] ? __send_to_port+0x420/0x420 [ 22.493094][ C0] ? mark_lock+0x1d7/0xa00 [ 22.493096][ C0] hvc_console_print+0x292/0x780 [ 22.493099][ C0] ? hvc_write+0x3a0/0x3a0 [ 22.493100][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.493101][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.493103][ C0] console_emit_next_record+0x22f/0x4c0 [ 22.493105][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 22.493107][ C0] ? console_flush_one_record+0x106/0x710 [ 22.493109][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.493110][ C0] ? lock_acquire+0x13c/0x160 [ 22.493112][ C0] console_flush_one_record+0x46f/0x710 [ 22.493114][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 22.493116][ C0] ? __lock_acquire+0x518/0xc20 [ 22.493117][ C0] ? write_ibpb+0xb/0x30 [ 22.493120][ C0] console_unlock+0xee/0x1f0 [ 22.493122][ C0] ? console_flush_one_record+0x710/0x710 [ 22.493123][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.493124][ C0] ? lock_acquire+0xe0/0x160 [ 22.493126][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 22.493128][ C0] ? vprintk_emit+0x320/0x3e0 [ 22.493129][ C0] vprintk_emit+0x37c/0x3e0 [ 22.493131][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 22.493132][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 22.493134][ C0] _printk+0xc7/0x100 [ 22.493136][ C0] ? snapshot_read.cold+0x21/0x21 [ 22.493137][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 22.493139][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 22.493141][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 22.493143][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 22.493144][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 22.493146][ C0] call_timer_fn+0x160/0x4d0 [ 22.493148][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 22.493150][ C0] ? debug_object_active_state+0x430/0x430 [ 22.493151][ C0] ? find_held_lock+0x2b/0x80 [ 22.493153][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 22.493154][ C0] ? mark_held_locks+0x40/0x70 [ 22.493156][ C0] __run_timers+0x68f/0xaa0 [ 22.493158][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 22.493160][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 22.493162][ C0] ? __lock_acquire+0x518/0xc20 [ 22.493168][ C0] ? __rwlock_init+0x150/0x150 [ 22.493171][ C0] run_timer_softirq+0xf0/0x160 [ 22.493173][ C0] ? __run_timers+0xaa0/0xaa0 [ 22.493175][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.493176][ C0] handle_softirqs+0x1d3/0x900 [ 22.493178][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 22.493179][ C0] ? _local_bh_enable+0xc0/0xc0 [ 22.493181][ C0] __irq_exit_rcu+0x145/0x1c0 [ 22.493183][ C0] irq_exit_rcu+0xe/0x30 [ 22.493184][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 22.493186][ C0] [ 22.493186][ C0] [ 22.493187][ C0] ? find_held_lock+0x2b/0x80 [ 22.493188][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 22.493189][ C0] RIP: 0010:lock_acquire.part.0+0x112/0x280 [ 22.493191][ C0] Code: ff 65 0f c1 05 7f 77 ed 04 83 f8 01 4c 8b 14 24 0f 85 35 01 00 00 9c 58 f6 c4 02 0f 85 52 01 00 00 4d 85 d2 0f 85 1b 01 00 00 <48> 8b 44 24 30 65 48 2b 05 51 32 ed 04 0f 85 48 01 00 00 48 83 c4 [ 22.493192][ C0] RSP: 0018:ffa0000001947790 EFLAGS: 00000206 [ 22.493193][ C0] RAX: 0000000000000046 RBX: 0000000000000000 RCX: fd2b5e2d9a0fc7b1 [ 22.493194][ C0] RDX: 0000000000000000 RSI: ffffffff8e6f9f03 RDI: ffffffff8e0949e0 [ 22.493194][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ff1100000f3c2ef8 [ 22.493195][ C0] R10: 0000000000000200 R11: 0000000000000001 R12: ffffffff8f194c00 [ 22.493196][ C0] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000002 [ 22.493198][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.493199][ C0] ? lock_acquire+0x13c/0x160 [ 22.493201][ C0] is_module_text_address+0x3a/0x250 [ 22.493202][ C0] ? is_module_text_address+0x26/0x250 [ 22.493203][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.493204][ C0] kernel_text_address+0x97/0x170 [ 22.493206][ C0] __kernel_text_address+0x12/0x30 [ 22.493207][ C0] ? write_profile+0xf0/0xf0 [ 22.493208][ C0] unwind_get_return_address+0x67/0xd0 [ 22.493210][ C0] arch_stack_walk+0xed/0x130 [ 22.493212][ C0] stack_trace_save+0x93/0xc0 [ 22.493214][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 22.493215][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.493217][ C0] set_track_prepare+0x6b/0xa0 [ 22.493218][ C0] ? __kmalloc_cache_noprof+0x4a9/0x620 [ 22.493219][ C0] ? kmem_cache_free+0x365/0x550 [ 22.493220][ C0] ? tear_down_vmas+0x175/0x520 [ 22.493223][ C0] ? exit_mmap+0x486/0x960 [ 22.493224][ C0] ? mmput+0xa9/0x450 [ 22.493226][ C0] ? exit_mm+0x134/0x200 [ 22.493226][ C0] ? do_exit+0x548/0xdc0 [ 22.493227][ C0] ? do_group_exit+0xb8/0x370 [ 22.493228][ C0] ? __x64_sys_exit_group+0x3c/0x50 [ 22.493230][ C0] ? x64_sys_call+0x1567/0x1570 [ 22.493231][ C0] ? do_syscall_64+0xff/0x530 [ 22.493233][ C0] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 22.493234][ C0] __alloc_object+0xf9/0x260 [ 22.493235][ C0] __create_object+0x30/0x110 [ 22.493237][ C0] __kmalloc_cache_noprof+0x4a9/0x620 [ 22.493238][ C0] ? kmem_cache_free+0x365/0x550 [ 22.493240][ C0] ? tear_down_vmas+0x175/0x520 [ 22.493242][ C0] kmem_cache_free+0x365/0x550 [ 22.493243][ C0] tear_down_vmas+0x175/0x520 [ 22.493246][ C0] exit_mmap+0x486/0x960 [ 22.493248][ C0] ? vm_brk_flags+0x4d0/0x4d0 [ 22.493249][ C0] ? lock_acquire+0x13c/0x160 [ 22.493251][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.493252][ C0] ? uprobe_clear_state+0x49/0x1e0 [ 22.493253][ C0] ? __lock_acquire+0x518/0xc20 [ 22.493258][ C0] mmput+0xa9/0x450 [ 22.493259][ C0] exit_mm+0x134/0x200 [ 22.493261][ C0] do_exit+0x548/0xdc0 [ 22.493262][ C0] ? exit_notify+0x890/0x890 [ 22.493263][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 22.493265][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.493266][ C0] do_group_exit+0xb8/0x370 [ 22.493268][ C0] __x64_sys_exit_group+0x3c/0x50 [ 22.493269][ C0] x64_sys_call+0x1567/0x1570 [ 22.493271][ C0] do_syscall_64+0xff/0x530 [ 22.493272][ C0] ? exc_page_fault+0xee/0x100 [ 22.493274][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 22.493275][ C0] RIP: 0033:0x7f32e68981b8 [ 22.493276][ C0] Code: Unable to access opcode bytes at 0x7f32e689818e. [ 22.493276][ C0] RSP: 002b:00007fff2932f5c8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 22.493278][ C0] RAX: ffffffffffffffda RBX: 00007f32e69c8f88 RCX: 00007f32e68981b8 [ 22.493278][ C0] RDX: 00007f32e67d3a88 RSI: ffffffffffffffa8 RDI: 0000000000000001 [ 22.493279][ C0] RBP: 00007fff2932f620 R08: 0000000000000000 R09: 00000000000000a0 [ 22.493280][ C0] R10: 00007fff2932f420 R11: 0000000000000246 R12: 0000000000000001 [ 22.493280][ C0] R13: 0000000000000001 R14: 00007f32e69c7680 R15: 00007f32e69c8fa0 [ 22.493282][ C0]