[ 11.932455][ C0] clocksource: Watchdog remote CPU 2 read timed out [ 11.932594][ C0] [ 11.932596][ C0] ======================================================== [ 11.932597][ C0] WARNING: possible irq lock inversion dependency detected [ 11.932600][ C0] 7.2.0-virtme #1 Tainted: G W [ 11.932601][ C0] -------------------------------------------------------- [ 11.932602][ C0] (udev-worker)/192 just changed the state of lock: [ 11.932604][ C0] ffffffff9c089660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.932616][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 11.932618][ C0] (fs_reclaim){+.+.}-{0:0} [ 11.932619][ C0] [ 11.932619][ C0] [ 11.932619][ C0] and interrupts could create inverse lock ordering between them. [ 11.932619][ C0] [ 11.932620][ C0] [ 11.932620][ C0] other info that might help us debug this: [ 11.932621][ C0] Possible interrupt unsafe locking scenario: [ 11.932621][ C0] [ 11.932622][ C0] CPU0 CPU1 [ 11.932622][ C0] ---- ---- [ 11.932623][ C0] lock(fs_reclaim); [ 11.932624][ C0] local_irq_disable(); [ 11.932624][ C0] lock(console_owner); [ 11.932625][ C0] lock(fs_reclaim); [ 11.932626][ C0] [ 11.932627][ C0] lock(console_owner); [ 11.932628][ C0] [ 11.932628][ C0] *** DEADLOCK *** [ 11.932628][ C0] [ 11.932628][ C0] locks held by (udev-worker)/192: 4, last CPU#0: [ 11.932629][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 11.932641][ C0] #1: ffffffff9c1ce8b8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 11.932645][ C0] #2: ffffffff9c169cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.932649][ C0] #3: ffffffff9c169d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.932652][ C0] [ 11.932652][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 11.932657][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 11.932659][ C0] HARDIRQ-ON-W at: [ 11.932661][ C0] __lock_acquire+0x388/0xc20 [ 11.932664][ C0] lock_acquire.part.0+0xd4/0x280 [ 11.932666][ C0] fs_reclaim_acquire+0xd5/0x120 [ 11.932668][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 11.932671][ C0] kthread_create_worker_on_node+0xea/0x210 [ 11.932674][ C0] workqueue_init+0x2a/0x680 [ 11.932678][ C0] kernel_init_freeable+0x2fe/0x630 [ 11.932681][ C0] kernel_init+0x21/0x150 [ 11.932686][ C0] ret_from_fork+0x474/0x6b0 [ 11.932689][ C0] ret_from_fork_asm+0x11/0x20 [ 11.932692][ C0] SOFTIRQ-ON-W at: [ 11.932693][ C0] __lock_acquire+0x388/0xc20 [ 11.932695][ C0] lock_acquire.part.0+0xd4/0x280 [ 11.932696][ C0] fs_reclaim_acquire+0xd5/0x120 [ 11.932697][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 11.932699][ C0] kthread_create_worker_on_node+0xea/0x210 [ 11.932700][ C0] workqueue_init+0x2a/0x680 [ 11.932701][ C0] kernel_init_freeable+0x2fe/0x630 [ 11.932702][ C0] kernel_init+0x21/0x150 [ 11.932704][ C0] ret_from_fork+0x474/0x6b0 [ 11.932705][ C0] ret_from_fork_asm+0x11/0x20 [ 11.932706][ C0] INITIAL USE at: [ 11.932707][ C0] __lock_acquire+0x388/0xc20 [ 11.932708][ C0] lock_acquire.part.0+0xd4/0x280 [ 11.932710][ C0] fs_reclaim_acquire+0xd5/0x120 [ 11.932711][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 11.932712][ C0] kthread_create_worker_on_node+0xea/0x210 [ 11.932713][ C0] workqueue_init+0x2a/0x680 [ 11.932715][ C0] kernel_init_freeable+0x2fe/0x630 [ 11.932716][ C0] kernel_init+0x21/0x150 [ 11.932717][ C0] ret_from_fork+0x474/0x6b0 [ 11.932718][ C0] ret_from_fork_asm+0x11/0x20 [ 11.932719][ C0] } [ 11.932720][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 11.932724][ C0] ... acquired at: [ 11.932725][ C0] __lock_acquire+0x518/0xc20 [ 11.932727][ C0] lock_acquire.part.0+0xd4/0x280 [ 11.932728][ C0] fs_reclaim_acquire+0xd5/0x120 [ 11.932729][ C0] __kmalloc_noprof+0xd3/0x760 [ 11.932730][ C0] alloc_buf.isra.0+0x4b/0x260 [ 11.932733][ C0] put_chars+0x1e1/0x2f0 [ 11.932734][ C0] hvc_console_print+0x292/0x780 [ 11.932736][ C0] console_emit_next_record+0x22f/0x4c0 [ 11.932738][ C0] console_flush_one_record+0x46f/0x710 [ 11.932740][ C0] console_unlock+0xee/0x1f0 [ 11.932741][ C0] vprintk_emit+0x37c/0x3e0 [ 11.932743][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 11.932746][ C0] dev_printk_emit+0xb9/0xee [ 11.932748][ C0] _dev_info+0xe2/0x116 [ 11.932749][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 11.932752][ C0] cmos_do_probe+0x73b/0x98a [ 11.932754][ C0] platform_probe+0xfe/0x1f0 [ 11.932757][ C0] call_driver_probe+0x61/0x1c0 [ 11.932759][ C0] really_probe+0x199/0x760 [ 11.932760][ C0] __driver_probe_device+0x24f/0x440 [ 11.932762][ C0] driver_probe_device+0x4a/0xf0 [ 11.932763][ C0] __driver_attach+0x1b8/0x540 [ 11.932765][ C0] bus_for_each_dev+0x130/0x1e0 [ 11.932766][ C0] bus_add_driver+0x2c8/0x530 [ 11.932767][ C0] driver_register+0x1a3/0x390 [ 11.932769][ C0] __platform_driver_probe+0x13f/0x270 [ 11.932770][ C0] cmos_init+0x31/0x40 [ 11.932773][ C0] do_one_initcall+0x124/0x4f0 [ 11.932775][ C0] kernel_init_freeable+0x596/0x630 [ 11.932776][ C0] kernel_init+0x21/0x150 [ 11.932777][ C0] ret_from_fork+0x474/0x6b0 [ 11.932778][ C0] ret_from_fork_asm+0x11/0x20 [ 11.932779][ C0] [ 11.932780][ C0] -> (console_owner){..-.}-{0:0} { [ 11.932782][ C0] IN-SOFTIRQ-W at: [ 11.932782][ C0] __lock_acquire+0x388/0xc20 [ 11.932784][ C0] lock_acquire.part.0+0xd4/0x280 [ 11.932785][ C0] console_lock_spinning_enable+0x5c/0x60 [ 11.932787][ C0] console_emit_next_record+0x1d1/0x4c0 [ 11.932789][ C0] console_flush_one_record+0x46f/0x710 [ 11.932790][ C0] console_unlock+0xee/0x1f0 [ 11.932792][ C0] vprintk_emit+0x37c/0x3e0 [ 11.932793][ C0] _printk+0xc7/0x100 [ 11.932796][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 11.932798][ C0] call_timer_fn+0x160/0x4d0 [ 11.932800][ C0] __run_timers+0x68f/0xaa0 [ 11.932801][ C0] run_timer_softirq+0xf0/0x160 [ 11.932803][ C0] handle_softirqs+0x1d3/0x900 [ 11.932805][ C0] __irq_exit_rcu+0x145/0x1c0 [ 11.932807][ C0] irq_exit_rcu+0xe/0x30 [ 11.932808][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 11.932810][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 11.932812][ C0] _raw_spin_unlock_irqrestore+0x36/0x80 [ 11.932814][ C0] get_from_partial_node+0x21d/0x500 [ 11.932815][ C0] ___slab_alloc+0x71/0x2f0 [ 11.932817][ C0] kmem_cache_alloc_noprof+0x2fc/0x5c0 [ 11.932818][ C0] alloc_empty_file+0x3f/0x120 [ 11.932820][ C0] path_openat+0xcb/0x3b0 [ 11.932822][ C0] do_file_open+0x209/0x480 [ 11.932824][ C0] do_sys_openat2+0xe0/0x170 [ 11.932826][ C0] __x64_sys_openat+0x10e/0x210 [ 11.932827][ C0] do_syscall_64+0xff/0x530 [ 11.932829][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.932830][ C0] INITIAL USE at: [ 11.932831][ C0] } [ 11.932832][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 11.932835][ C0] ... acquired at: [ 11.932836][ C0] mark_lock+0x1d7/0xa00 [ 11.932837][ C0] mark_usage+0x42/0x170 [ 11.932838][ C0] __lock_acquire+0x388/0xc20 [ 11.932840][ C0] lock_acquire.part.0+0xd4/0x280 [ 11.932841][ C0] console_lock_spinning_enable+0x5c/0x60 [ 11.932843][ C0] console_emit_next_record+0x1d1/0x4c0 [ 11.932844][ C0] console_flush_one_record+0x46f/0x710 [ 11.932846][ C0] console_unlock+0xee/0x1f0 [ 11.932847][ C0] vprintk_emit+0x37c/0x3e0 [ 11.932849][ C0] _printk+0xc7/0x100 [ 11.932850][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 11.932851][ C0] call_timer_fn+0x160/0x4d0 [ 11.932853][ C0] __run_timers+0x68f/0xaa0 [ 11.932854][ C0] run_timer_softirq+0xf0/0x160 [ 11.932856][ C0] handle_softirqs+0x1d3/0x900 [ 11.932857][ C0] __irq_exit_rcu+0x145/0x1c0 [ 11.932858][ C0] irq_exit_rcu+0xe/0x30 [ 11.932860][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 11.932861][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 11.932862][ C0] _raw_spin_unlock_irqrestore+0x36/0x80 [ 11.932864][ C0] get_from_partial_node+0x21d/0x500 [ 11.932865][ C0] ___slab_alloc+0x71/0x2f0 [ 11.932866][ C0] kmem_cache_alloc_noprof+0x2fc/0x5c0 [ 11.932868][ C0] alloc_empty_file+0x3f/0x120 [ 11.932869][ C0] path_openat+0xcb/0x3b0 [ 11.932870][ C0] do_file_open+0x209/0x480 [ 11.932871][ C0] do_sys_openat2+0xe0/0x170 [ 11.932873][ C0] __x64_sys_openat+0x10e/0x210 [ 11.932874][ C0] do_syscall_64+0xff/0x530 [ 11.932875][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.932876][ C0] [ 11.932876][ C0] [ 11.932876][ C0] stack backtrace: [ 11.932879][ C0] CPU: 0 UID: 0 PID: 192 Comm: (udev-worker) Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.932883][ C0] Tainted: [W]=WARN [ 11.932883][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.932885][ C0] Call Trace: [ 11.932886][ C0] [ 11.932888][ C0] dump_stack_lvl+0x6f/0xa0 [ 11.932892][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 11.932894][ C0] mark_lock_irq+0x989/0x9c0 [ 11.932897][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 11.932901][ C0] mark_lock+0x1d7/0xa00 [ 11.932904][ C0] mark_usage+0x42/0x170 [ 11.932906][ C0] __lock_acquire+0x388/0xc20 [ 11.932909][ C0] lock_acquire.part.0+0xd4/0x280 [ 11.932910][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 11.932912][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.932915][ C0] ? lock_acquire+0x13c/0x160 [ 11.932917][ C0] console_lock_spinning_enable+0x5c/0x60 [ 11.932919][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 11.932921][ C0] console_emit_next_record+0x1d1/0x4c0 [ 11.932923][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 11.932925][ C0] ? console_flush_one_record+0x106/0x710 [ 11.932927][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.932928][ C0] ? lock_acquire+0x13c/0x160 [ 11.932930][ C0] console_flush_one_record+0x46f/0x710 [ 11.932933][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 11.932934][ C0] ? __lock_acquire+0x518/0xc20 [ 11.932937][ C0] console_unlock+0xee/0x1f0 [ 11.932939][ C0] ? console_flush_one_record+0x710/0x710 [ 11.932940][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.932942][ C0] ? lock_acquire+0x60/0x160 [ 11.932944][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.932946][ C0] ? vprintk_emit+0x320/0x3e0 [ 11.932948][ C0] vprintk_emit+0x37c/0x3e0 [ 11.932950][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 11.932952][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 11.932954][ C0] _printk+0xc7/0x100 [ 11.932956][ C0] ? snapshot_read.cold+0x21/0x21 [ 11.932958][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 11.932959][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 11.932962][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 11.932963][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 11.932965][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 11.932967][ C0] call_timer_fn+0x160/0x4d0 [ 11.932969][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 11.932970][ C0] ? debug_object_active_state+0x430/0x430 [ 11.932974][ C0] ? find_held_lock+0x2b/0x80 [ 11.932976][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 11.932978][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.932980][ C0] __run_timers+0x68f/0xaa0 [ 11.932981][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 11.932984][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 11.932986][ C0] ? __lock_acquire+0x518/0xc20 [ 11.932988][ C0] ? __rwlock_init+0x150/0x150 [ 11.932991][ C0] run_timer_softirq+0xf0/0x160 [ 11.932993][ C0] ? __run_timers+0xaa0/0xaa0 [ 11.932994][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 11.932997][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.932999][ C0] handle_softirqs+0x1d3/0x900 [ 11.933000][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 11.933002][ C0] ? _local_bh_enable+0xc0/0xc0 [ 11.933004][ C0] __irq_exit_rcu+0x145/0x1c0 [ 11.933006][ C0] irq_exit_rcu+0xe/0x30 [ 11.933007][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 11.933009][ C0] [ 11.933009][ C0] [ 11.933010][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 11.933012][ C0] RIP: 0010:_raw_spin_unlock_irqrestore+0x36/0x80 [ 11.933014][ C0] Code: f5 53 48 8b 74 24 10 48 89 fb 48 83 c7 18 e8 c1 bf ba fd 48 89 df e8 c9 15 bb fd f7 c5 00 02 00 00 75 1f 9c 58 f6 c4 02 75 2f 01 00 00 00 e8 70 df ac fd 65 48 83 3d af 1b f3 01 00 74 12 5b [ 11.933016][ C0] RSP: 0018:ffa00000007979b8 EFLAGS: 00000246 [ 11.933019][ C0] RAX: 0000000000000092 RBX: ff1100000198a540 RCX: ffffffff9b3a7483 [ 11.933020][ C0] RDX: ff1100000cb72380 RSI: ffffffff9bc38b1c RDI: ffffffff9b68d8e0 [ 11.933021][ C0] RBP: 0000000000000246 R08: 0000000000000000 R09: 0000000000000000 [ 11.933022][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: ff1100000198a540 [ 11.933023][ C0] R13: ffd4000000312010 R14: ffd4000000312000 R15: ff1100000c481480 [ 11.933024][ C0] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 11.933027][ C0] get_from_partial_node+0x21d/0x500 [ 11.933029][ C0] ? find_held_lock+0x2b/0x80 [ 11.933031][ C0] ___slab_alloc+0x71/0x2f0 [ 11.933032][ C0] ? __pcs_replace_empty_main+0x42f/0x6a0 [ 11.933034][ C0] kmem_cache_alloc_noprof+0x2fc/0x5c0 [ 11.933036][ C0] ? alloc_empty_file+0x3f/0x120 [ 11.933038][ C0] alloc_empty_file+0x3f/0x120 [ 11.933039][ C0] path_openat+0xcb/0x3b0 [ 11.933041][ C0] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.933043][ C0] ? kernel_tmpfile_open+0x70/0x70 [ 11.933045][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.933048][ C0] do_file_open+0x209/0x480 [ 11.933049][ C0] ? path_openat+0x3b0/0x3b0 [ 11.933051][ C0] ? __lock_acquire+0x518/0xc20 [ 11.933054][ C0] ? _raw_spin_unlock+0x2d/0x50 [ 11.933056][ C0] ? alloc_fd+0x33a/0x600 [ 11.933060][ C0] do_sys_openat2+0xe0/0x170 [ 11.933062][ C0] ? build_open_flags+0x6e0/0x6e0 [ 11.933064][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.933065][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 11.933067][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 11.933069][ C0] __x64_sys_openat+0x10e/0x210 [ 11.933071][ C0] ? fput_close_sync+0xde/0x1b0 [ 11.933072][ C0] ? __ia32_sys_open+0x1b0/0x1b0 [ 11.933074][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.933076][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 11.933077][ C0] do_syscall_64+0xff/0x530 [ 11.933078][ C0] ? exc_page_fault+0xee/0x100 [ 11.933080][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.933081][ C0] RIP: 0033:0x7f71dff7054e [ 11.933084][ C0] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 11.933085][ C0] RSP: 002b:00007ffeea22b5a0 EFLAGS: 00000202 ORIG_RAX: 0000000000000101 [ 11.933087][ C0] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f71dff7054e [ 11.933088][ C0] RDX: 00000000002a0000 RSI: 000056216ebbd2d0 RDI: 0000000000000015 [ 11.933088][ C0] RBP: 00007ffeea22b5b0 R08: 0000000000000000 R09: 0000000000000000 [ 11.933089][ C0] R10: 0000000000000000 R11: 0000000000000202 R12: 000056216ed15411 [ 11.933090][ C0] R13: 0000000000000015 R14: 000056216ebbd2d0 R15: 0000000000000016 [ 11.933092][ C0] [ 11.933097][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.933098][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 192, name: (udev-worker) [ 11.933099][ C0] preempt_count: 103, expected: 0 [ 11.933100][ C0] RCU nest depth: 0, expected: 0 [ 11.933101][ C0] INFO: lockdep is turned off. [ 11.933102][ C0] irq event stamp: 248327 [ 11.933102][ C0] hardirqs last enabled at (248326): [] __down_trylock_console_sem+0x86/0xa0 [ 11.933105][ C0] hardirqs last disabled at (248327): [] console_emit_next_record+0x3d4/0x4c0 [ 11.933107][ C0] softirqs last enabled at (247198): [] handle_softirqs+0x67c/0x900 [ 11.933108][ C0] softirqs last disabled at (248313): [] __irq_exit_rcu+0x145/0x1c0 [ 11.933110][ C0] Preemption disabled at: [ 11.933110][ C0] [<0000000000000000>] 0x0 [ 11.933112][ C0] CPU: 0 UID: 0 PID: 192 Comm: (udev-worker) Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.933115][ C0] Tainted: [W]=WARN [ 11.933115][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.933116][ C0] Call Trace: [ 11.933117][ C0] [ 11.933117][ C0] dump_stack_lvl+0x6f/0xa0 [ 11.933119][ C0] __might_resched.cold+0x1fe/0x2c1 [ 11.933122][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.933125][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.933128][ C0] __kmalloc_noprof+0x443/0x760 [ 11.933129][ C0] ? __rwlock_init+0x150/0x150 [ 11.933131][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 11.933133][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 11.933134][ C0] alloc_buf.isra.0+0x4b/0x260 [ 11.933136][ C0] put_chars+0x1e1/0x2f0 [ 11.933138][ C0] ? __send_to_port+0x420/0x420 [ 11.933140][ C0] ? mark_lock+0x1d7/0xa00 [ 11.933142][ C0] hvc_console_print+0x292/0x780 [ 11.933144][ C0] ? hvc_write+0x3a0/0x3a0 [ 11.933146][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.933147][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.933150][ C0] console_emit_next_record+0x22f/0x4c0 [ 11.933152][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 11.933154][ C0] ? console_flush_one_record+0x106/0x710 [ 11.933155][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.933157][ C0] ? lock_acquire+0x13c/0x160 [ 11.933159][ C0] console_flush_one_record+0x46f/0x710 [ 11.933162][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 11.933163][ C0] ? __lock_acquire+0x518/0xc20 [ 11.933166][ C0] console_unlock+0xee/0x1f0 [ 11.933167][ C0] ? console_flush_one_record+0x710/0x710 [ 11.933169][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.933171][ C0] ? lock_acquire+0x60/0x160 [ 11.933173][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.933174][ C0] ? vprintk_emit+0x320/0x3e0 [ 11.933176][ C0] vprintk_emit+0x37c/0x3e0 [ 11.933179][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 11.933181][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 11.933183][ C0] _printk+0xc7/0x100 [ 11.933185][ C0] ? snapshot_read.cold+0x21/0x21 [ 11.933187][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 11.933188][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 11.933191][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 11.933192][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 11.933194][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 11.933195][ C0] call_timer_fn+0x160/0x4d0 [ 11.933198][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 11.933199][ C0] ? debug_object_active_state+0x430/0x430 [ 11.933201][ C0] ? find_held_lock+0x2b/0x80 [ 11.933203][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 11.933204][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.933207][ C0] __run_timers+0x68f/0xaa0 [ 11.933208][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 11.933211][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 11.933212][ C0] ? __lock_acquire+0x518/0xc20 [ 11.933215][ C0] ? __rwlock_init+0x150/0x150 [ 11.933217][ C0] run_timer_softirq+0xf0/0x160 [ 11.933219][ C0] ? __run_timers+0xaa0/0xaa0 [ 11.933221][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 11.933222][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.933224][ C0] handle_softirqs+0x1d3/0x900 [ 11.933226][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 11.933228][ C0] ? _local_bh_enable+0xc0/0xc0 [ 11.933230][ C0] __irq_exit_rcu+0x145/0x1c0 [ 11.933231][ C0] irq_exit_rcu+0xe/0x30 [ 11.933232][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 11.933234][ C0] [ 11.933235][ C0] [ 11.933235][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 11.933237][ C0] RIP: 0010:_raw_spin_unlock_irqrestore+0x36/0x80 [ 11.933239][ C0] Code: f5 53 48 8b 74 24 10 48 89 fb 48 83 c7 18 e8 c1 bf ba fd 48 89 df e8 c9 15 bb fd f7 c5 00 02 00 00 75 1f 9c 58 f6 c4 02 75 2f 01 00 00 00 e8 70 df ac fd 65 48 83 3d af 1b f3 01 00 74 12 5b [ 11.933240][ C0] RSP: 0018:ffa00000007979b8 EFLAGS: 00000246 [ 11.933241][ C0] RAX: 0000000000000092 RBX: ff1100000198a540 RCX: ffffffff9b3a7483 [ 11.933242][ C0] RDX: ff1100000cb72380 RSI: ffffffff9bc38b1c RDI: ffffffff9b68d8e0 [ 11.933242][ C0] RBP: 0000000000000246 R08: 0000000000000000 R09: 0000000000000000 [ 11.933243][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: ff1100000198a540 [ 11.933244][ C0] R13: ffd4000000312010 R14: ffd4000000312000 R15: ff1100000c481480 [ 11.933245][ C0] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 11.933248][ C0] get_from_partial_node+0x21d/0x500 [ 11.933249][ C0] ? find_held_lock+0x2b/0x80 [ 11.933252][ C0] ___slab_alloc+0x71/0x2f0 [ 11.933253][ C0] ? __pcs_replace_empty_main+0x42f/0x6a0 [ 11.933255][ C0] kmem_cache_alloc_noprof+0x2fc/0x5c0 [ 11.933257][ C0] ? alloc_empty_file+0x3f/0x120 [ 11.933259][ C0] alloc_empty_file+0x3f/0x120 [ 11.933260][ C0] path_openat+0xcb/0x3b0 [ 11.933262][ C0] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.933263][ C0] ? kernel_tmpfile_open+0x70/0x70 [ 11.933265][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.933267][ C0] do_file_open+0x209/0x480 [ 11.933269][ C0] ? path_openat+0x3b0/0x3b0 [ 11.933271][ C0] ? __lock_acquire+0x518/0xc20 [ 11.933274][ C0] ? _raw_spin_unlock+0x2d/0x50 [ 11.933276][ C0] ? alloc_fd+0x33a/0x600 [ 11.933279][ C0] do_sys_openat2+0xe0/0x170 [ 11.933280][ C0] ? build_open_flags+0x6e0/0x6e0 [ 11.933282][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.933284][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 11.933285][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 11.933287][ C0] __x64_sys_openat+0x10e/0x210 [ 11.933289][ C0] ? fput_close_sync+0xde/0x1b0 [ 11.933290][ C0] ? __ia32_sys_open+0x1b0/0x1b0 [ 11.933292][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.933294][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 11.933295][ C0] do_syscall_64+0xff/0x530 [ 11.933297][ C0] ? exc_page_fault+0xee/0x100 [ 11.933298][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.933300][ C0] RIP: 0033:0x7f71dff7054e [ 11.933301][ C0] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 11.933302][ C0] RSP: 002b:00007ffeea22b5a0 EFLAGS: 00000202 ORIG_RAX: 0000000000000101 [ 11.933303][ C0] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f71dff7054e [ 11.933303][ C0] RDX: 00000000002a0000 RSI: 000056216ebbd2d0 RDI: 0000000000000015 [ 11.933304][ C0] RBP: 00007ffeea22b5b0 R08: 0000000000000000 R09: 0000000000000000 [ 11.933305][ C0] R10: 0000000000000000 R11: 0000000000000202 R12: 000056216ed15411 [ 11.933305][ C0] R13: 0000000000000015 R14: 000056216ebbd2d0 R15: 0000000000000016 [ 11.933308][ C0] [ 12.404030][ T291] 8021q: 802.1Q VLAN Support v1.8 [ 12.513595][ T298] gre: GRE over IPv4 demultiplexer driver [ 12.530574][ T298] ip6_gre: GRE over IPv6 tunneling driver [ 18.826194][ T390] GACT probability NOT on [ 18.826209][ T390] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 18.826212][ T390] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 390, name: modprobe [ 18.826213][ T390] preempt_count: 1, expected: 0 [ 18.826214][ T390] RCU nest depth: 0, expected: 0 [ 18.826215][ T390] INFO: lockdep is turned off. [ 18.826217][ T390] irq event stamp: 0 [ 18.826217][ T390] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 18.826221][ T390] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 18.826228][ T390] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 18.826229][ T390] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 18.826231][ T390] Preemption disabled at: [ 18.826232][ T390] [] vprintk_emit+0x31b/0x3e0 [ 18.826240][ T390] CPU: 0 UID: 0 PID: 390 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 18.826243][ T390] Tainted: [W]=WARN [ 18.826244][ T390] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 18.826246][ T390] Call Trace: [ 18.826247][ T390] [ 18.826248][ T390] dump_stack_lvl+0x6f/0xa0 [ 18.826254][ T390] ? vprintk_emit+0x31b/0x3e0 [ 18.826256][ T390] __might_resched.cold+0x1fe/0x2c1 [ 18.826260][ T390] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 18.826263][ T390] ? rcu_is_watching+0x16/0xd0 [ 18.826267][ T390] __kmalloc_noprof+0x443/0x760 [ 18.826270][ T390] ? __rwlock_init+0x150/0x150 [ 18.826273][ T390] ? alloc_buf.isra.0+0x4b/0x260 [ 18.826277][ T390] ? do_raw_spin_unlock+0x59/0x250 [ 18.826279][ T390] alloc_buf.isra.0+0x4b/0x260 [ 18.826280][ T390] put_chars+0x1e1/0x2f0 [ 18.826282][ T390] ? desc_read_finalized_seq+0x79/0x120 [ 18.826284][ T390] ? __send_to_port+0x420/0x420 [ 18.826286][ T390] ? console_prepend_replay+0x20/0x20 [ 18.826288][ T390] hvc_console_print+0x292/0x780 [ 18.826292][ T390] ? hvc_write+0x3a0/0x3a0 [ 18.826293][ T390] ? do_raw_spin_lock+0x131/0x280 [ 18.826294][ T390] ? __rwlock_init+0x150/0x150 [ 18.826296][ T390] ? rcu_is_watching+0x16/0xd0 [ 18.826298][ T390] ? rcu_is_watching+0x16/0xd0 [ 18.826299][ T390] ? lock_acquire+0x13c/0x160 [ 18.826302][ T390] console_emit_next_record+0x22f/0x4c0 [ 18.826304][ T390] ? devkmsg_read+0x4b0/0x4b0 [ 18.826306][ T390] ? rcu_is_watching+0x16/0xd0 [ 18.826308][ T390] ? rcu_is_watching+0x16/0xd0 [ 18.826312][ T390] ? rcu_is_watching+0x16/0xd0 [ 18.826313][ T390] ? lock_acquire+0x13c/0x160 [ 18.826316][ T390] ? console_flush_one_record+0x111/0x710 [ 18.826317][ T390] console_flush_one_record+0x46f/0x710 [ 18.826320][ T390] ? console_emit_next_record+0x4c0/0x4c0 [ 18.826322][ T390] console_unlock+0xee/0x1f0 [ 18.826324][ T390] ? lock_acquire+0x13c/0x160 [ 18.826326][ T390] ? console_flush_one_record+0x710/0x710 [ 18.826327][ T390] ? rcu_is_watching+0x16/0xd0 [ 18.826329][ T390] ? lock_release+0x184/0x1f0 [ 18.826330][ T390] ? lock_acquire+0x60/0x160 [ 18.826332][ T390] ? __rcu_read_lock+0x30/0x70 [ 18.826334][ T390] ? __down_trylock_console_sem+0x5e/0xa0 [ 18.826336][ T390] ? vprintk_emit+0x320/0x3e0 [ 18.826338][ T390] vprintk_emit+0x37c/0x3e0 [ 18.826341][ T390] ? wake_up_klogd_work_func+0x90/0x90 [ 18.826344][ T390] ? gre_exit+0x3f0/0x3f0 [gre] [ 18.826346][ T390] _printk+0xc7/0x100 [ 18.826349][ T390] ? snapshot_read.cold+0x21/0x21 [ 18.826352][ T390] ? do_one_initcall+0x113/0x4f0 [ 18.826354][ T390] gact_init_module+0x15/0x1000 [act_gact] [ 18.826356][ T390] do_one_initcall+0x124/0x4f0 [ 18.826358][ T390] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 18.826360][ T390] ? do_init_module+0xae/0x8a0 [ 18.826363][ T390] ? kasan_unpoison+0x44/0x70 [ 18.826366][ T390] do_init_module+0x2ad/0x8a0 [ 18.826368][ T390] ? free_module+0x470/0x470 [ 18.826370][ T390] ? kfree+0x123/0x590 [ 18.826373][ T390] load_module+0x1424/0x1bb0 [ 18.826376][ T390] ? layout_and_allocate.isra.0+0x680/0x680 [ 18.826378][ T390] ? lock_release+0x184/0x1f0 [ 18.826380][ T390] ? __ia32_sys_fsconfig+0x180/0x180 [ 18.826383][ T390] ? lock_acquire+0x13c/0x160 [ 18.826385][ T390] init_module_from_file+0x11f/0x1a0 [ 18.826387][ T390] ? __do_sys_init_module+0x220/0x220 [ 18.826389][ T390] ? rcu_read_unlock+0x20/0x70 [ 18.826392][ T390] ? handle_pte_fault+0x426/0x710 [ 18.826395][ T390] ? rcu_is_watching+0x16/0xd0 [ 18.826396][ T390] ? ovl_mmap+0x270/0x270 [ 18.826400][ T390] ? __rwlock_init+0x150/0x150 [ 18.826402][ T390] ? rcu_is_watching+0x16/0xd0 [ 18.826404][ T390] ? lock_release+0x184/0x1f0 [ 18.826405][ T390] ? do_raw_spin_unlock+0x59/0x250 [ 18.826407][ T390] idempotent_init_module+0x37b/0x7c0 [ 18.826409][ T390] ? init_module_from_file+0x1a0/0x1a0 [ 18.826412][ T390] ? rcu_is_watching+0x16/0xd0 [ 18.826414][ T390] ? rcu_is_watching+0x16/0xd0 [ 18.826416][ T390] ? cap_capable+0x1d7/0x3d0 [ 18.826420][ T390] __x64_sys_finit_module+0xdf/0x190 [ 18.826421][ T390] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 18.826424][ T390] do_syscall_64+0xff/0x530 [ 18.826427][ T390] ? exc_page_fault+0xee/0x100 [ 18.826429][ T390] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 18.826431][ T390] RIP: 0033:0x7f566b28837d [ 18.826434][ T390] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 4b aa 0f 00 f7 d8 64 89 01 48 [ 18.826435][ T390] RSP: 002b:00007ffee79548e8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 18.826439][ T390] RAX: ffffffffffffffda RBX: 00005615c7a48810 RCX: 00007f566b28837d [ 18.826441][ T390] RDX: 0000000000000000 RSI: 00005615c02fcffd RDI: 0000000000000000 [ 18.826442][ T390] RBP: 00007ffee7954980 R08: 0000000000000000 R09: 0000000000000000 [ 18.826442][ T390] R10: 0000000000000000 R11: 0000000000000246 R12: 00005615c02fcffd [ 18.826443][ T390] R13: 00005615c7a48960 R14: 0000000000040000 R15: 0000000000000000 [ 18.826446][ T390]