[ 8.647834][ T141] mkdir (141) used greatest stack depth: 24840 bytes left [ 8.647851][ T141] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 8.647853][ T141] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 141, name: mkdir [ 8.647855][ T141] preempt_count: 2, expected: 0 [ 8.647855][ T141] RCU nest depth: 0, expected: 0 [ 8.647856][ T141] locks held by mkdir/141: 5, last CPU#0: [ 8.647858][ T141] #0: ffffffff884167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 8.647870][ T141] #1: ffffffff8857ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 8.647875][ T141] #2: ffffffff8857de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 8.647879][ T141] #3: ffffffff8849d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 8.647882][ T141] #4: ffffffff8849d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 8.647886][ T141] irq event stamp: 11694 [ 8.647887][ T141] hardirqs last enabled at (11693): [] __down_trylock_console_sem+0x86/0xa0 [ 8.647890][ T141] hardirqs last disabled at (11694): [] console_emit_next_record+0x3d4/0x4c0 [ 8.647892][ T141] softirqs last enabled at (11288): [] handle_softirqs+0x67c/0x900 [ 8.647894][ T141] softirqs last disabled at (9713): [] __irq_exit_rcu+0x145/0x1c0 [ 8.647897][ T141] Preemption disabled at: [ 8.647898][ T141] [<0000000000000000>] 0x0 [ 8.647904][ T141] CPU: 0 UID: 0 PID: 141 Comm: mkdir Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 8.647908][ T141] Tainted: [W]=WARN [ 8.647909][ T141] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 8.647910][ T141] Call Trace: [ 8.647912][ T141] [ 8.647913][ T141] dump_stack_lvl+0x6f/0xa0 [ 8.647920][ T141] __might_resched.cold+0x1fe/0x2c1 [ 8.647924][ T141] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 8.647928][ T141] ? __kmalloc_noprof+0xdb/0x760 [ 8.647932][ T141] __kmalloc_noprof+0x443/0x760 [ 8.647934][ T141] ? alloc_buf.isra.0+0x4b/0x260 [ 8.647941][ T141] ? do_raw_spin_unlock+0x59/0x250 [ 8.647944][ T141] alloc_buf.isra.0+0x4b/0x260 [ 8.647947][ T141] put_chars+0x1e1/0x2f0 [ 8.647949][ T141] ? __send_to_port+0x420/0x420 [ 8.647951][ T141] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 8.647954][ T141] ? validate_chain+0x38b/0xc20 [ 8.647959][ T141] hvc_console_print+0x292/0x780 [ 8.647967][ T141] ? hvc_write+0x3a0/0x3a0 [ 8.647969][ T141] ? rcu_is_watching+0x16/0xd0 [ 8.647971][ T141] ? lock_acquire+0x13c/0x160 [ 8.647975][ T141] console_emit_next_record+0x22f/0x4c0 [ 8.647979][ T141] ? devkmsg_read+0x4b0/0x4b0 [ 8.647981][ T141] ? console_flush_one_record+0x106/0x710 [ 8.647984][ T141] ? rcu_is_watching+0x16/0xd0 [ 8.647986][ T141] ? lock_acquire+0x13c/0x160 [ 8.647990][ T141] console_flush_one_record+0x46f/0x710 [ 8.647994][ T141] ? console_emit_next_record+0x4c0/0x4c0 [ 8.647996][ T141] ? __lock_acquire+0x518/0xc20 [ 8.648001][ T141] console_unlock+0xee/0x1f0 [ 8.648003][ T141] ? console_flush_one_record+0x710/0x710 [ 8.648005][ T141] ? rcu_is_watching+0x16/0xd0 [ 8.648007][ T141] ? lock_acquire+0xe0/0x160 [ 8.648010][ T141] ? __down_trylock_console_sem+0x5e/0xa0 [ 8.648012][ T141] ? vprintk_emit+0x320/0x3e0 [ 8.648015][ T141] vprintk_emit+0x37c/0x3e0 [ 8.648018][ T141] ? wake_up_klogd_work_func+0x90/0x90 [ 8.648021][ T141] ? __lock_acquire+0x518/0xc20 [ 8.648025][ T141] _printk+0xc7/0x100 [ 8.648028][ T141] ? snapshot_read.cold+0x21/0x21 [ 8.648031][ T141] ? do_raw_spin_lock+0x131/0x280 [ 8.648034][ T141] ? __rwlock_init+0x150/0x150 [ 8.648037][ T141] ? do_raw_spin_lock+0x131/0x280 [ 8.648040][ T141] do_exit.cold+0x82/0x9c [ 8.648044][ T141] ? exit_notify+0x890/0x890 [ 8.648045][ T141] ? __lock_release.isra.0+0x69/0x1a0 [ 8.648048][ T141] ? rcu_is_watching+0x16/0xd0 [ 8.648051][ T141] do_group_exit+0xb8/0x370 [ 8.648054][ T141] __x64_sys_exit_group+0x3c/0x50 [ 8.648056][ T141] x64_sys_call+0x1567/0x1570 [ 8.648058][ T141] do_syscall_64+0xff/0x530 [ 8.648062][ T141] ? exc_page_fault+0xee/0x100 [ 8.648065][ T141] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 8.648067][ T141] RIP: 0033:0x7f2c0892b1b8 [ 8.648069][ T141] Code: Unable to access opcode bytes at 0x7f2c0892b18e. [ 8.648070][ T141] RSP: 002b:00007fffaca78a08 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 8.648072][ T141] RAX: ffffffffffffffda RBX: 00007f2c08a5bf88 RCX: 00007f2c0892b1b8 [ 8.648074][ T141] RDX: 00007f2c0878c848 RSI: fffffffffffffec8 RDI: 0000000000000000 [ 8.648075][ T141] RBP: 00007fffaca78a60 R08: 0000000000000000 R09: 0000000000000001 [ 8.648075][ T141] R10: 00007fffaca78880 R11: 0000000000000246 R12: 0000000000000002 [ 8.648076][ T141] R13: 0000000000000000 R14: 00007f2c08a5a680 R15: 00007f2c08a5bfa0 [ 8.648083][ T141] [ 9.755451][ T205] tun (205) used greatest stack depth: 24736 bytes left [ 9.755467][ T205] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.755469][ T205] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 205, name: tun [ 9.755470][ T205] preempt_count: 2, expected: 0 [ 9.755471][ T205] RCU nest depth: 0, expected: 0 [ 9.755472][ T205] locks held by tun/205: 5, last CPU#3: [ 9.755474][ T205] #0: ffffffff884167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.755485][ T205] #1: ffffffff8857ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.755490][ T205] #2: ffffffff8857de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.755495][ T205] #3: ffffffff8849d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.755498][ T205] #4: ffffffff8849d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.755503][ T205] irq event stamp: 231312 [ 9.755504][ T205] hardirqs last enabled at (231311): [] __down_trylock_console_sem+0x86/0xa0 [ 9.755507][ T205] hardirqs last disabled at (231312): [] console_emit_next_record+0x3d4/0x4c0 [ 9.755509][ T205] softirqs last enabled at (229238): [] netlink_release+0x17b/0xcf0 [ 9.755513][ T205] softirqs last disabled at (229236): [] netlink_release+0xd2/0xcf0 [ 9.755516][ T205] Preemption disabled at: [ 9.755516][ T205] [<0000000000000000>] 0x0 [ 9.755523][ T205] CPU: 3 UID: 0 PID: 205 Comm: tun Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.755526][ T205] Tainted: [W]=WARN [ 9.755527][ T205] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.755529][ T205] Call Trace: [ 9.755530][ T205] [ 9.755532][ T205] dump_stack_lvl+0x6f/0xa0 [ 9.755538][ T205] __might_resched.cold+0x1fe/0x2c1 [ 9.755543][ T205] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.755547][ T205] ? __kmalloc_noprof+0xdb/0x760 [ 9.755552][ T205] __kmalloc_noprof+0x443/0x760 [ 9.755554][ T205] ? alloc_buf.isra.0+0x4b/0x260 [ 9.755560][ T205] ? do_raw_spin_unlock+0x59/0x250 [ 9.755563][ T205] alloc_buf.isra.0+0x4b/0x260 [ 9.755566][ T205] put_chars+0x1e1/0x2f0 [ 9.755569][ T205] ? __send_to_port+0x420/0x420 [ 9.755570][ T205] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.755573][ T205] ? validate_chain+0x38b/0xc20 [ 9.755578][ T205] hvc_console_print+0x292/0x780 [ 9.755586][ T205] ? hvc_write+0x3a0/0x3a0 [ 9.755588][ T205] ? rcu_is_watching+0x16/0xd0 [ 9.755590][ T205] ? lock_acquire+0x13c/0x160 [ 9.755594][ T205] console_emit_next_record+0x22f/0x4c0 [ 9.755598][ T205] ? devkmsg_read+0x4b0/0x4b0 [ 9.755600][ T205] ? console_flush_one_record+0x106/0x710 [ 9.755603][ T205] ? rcu_is_watching+0x16/0xd0 [ 9.755605][ T205] ? lock_acquire+0x13c/0x160 [ 9.755608][ T205] console_flush_one_record+0x46f/0x710 [ 9.755613][ T205] ? console_emit_next_record+0x4c0/0x4c0 [ 9.755615][ T205] ? __lock_acquire+0x518/0xc20 [ 9.755620][ T205] console_unlock+0xee/0x1f0 [ 9.755623][ T205] ? console_flush_one_record+0x710/0x710 [ 9.755625][ T205] ? rcu_is_watching+0x16/0xd0 [ 9.755626][ T205] ? lock_acquire+0xe0/0x160 [ 9.755630][ T205] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.755632][ T205] ? vprintk_emit+0x320/0x3e0 [ 9.755634][ T205] vprintk_emit+0x37c/0x3e0 [ 9.755637][ T205] ? wake_up_klogd_work_func+0x90/0x90 [ 9.755640][ T205] ? __lock_acquire+0x518/0xc20 [ 9.755644][ T205] _printk+0xc7/0x100 [ 9.755647][ T205] ? snapshot_read.cold+0x21/0x21 [ 9.755650][ T205] ? do_raw_spin_lock+0x131/0x280 [ 9.755653][ T205] ? __rwlock_init+0x150/0x150 [ 9.755657][ T205] ? do_raw_spin_lock+0x131/0x280 [ 9.755659][ T205] do_exit.cold+0x82/0x9c [ 9.755663][ T205] ? exit_notify+0x890/0x890 [ 9.755665][ T205] ? __lock_release.isra.0+0x69/0x1a0 [ 9.755667][ T205] ? rcu_is_watching+0x16/0xd0 [ 9.755671][ T205] do_group_exit+0xb8/0x370 [ 9.755674][ T205] __x64_sys_exit_group+0x3c/0x50 [ 9.755675][ T205] x64_sys_call+0x1567/0x1570 [ 9.755678][ T205] do_syscall_64+0xff/0x530 [ 9.755682][ T205] ? exc_page_fault+0xee/0x100 [ 9.755685][ T205] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.755687][ T205] RIP: 0033:0x7f36527511b8 [ 9.755689][ T205] Code: Unable to access opcode bytes at 0x7f365275118e. [ 9.755690][ T205] RSP: 002b:00007ffde53d8618 EFLAGS: 00000206 ORIG_RAX: 00000000000000e7 [ 9.755692][ T205] RAX: ffffffffffffffda RBX: 00007f3652688000 RCX: 00007f36527511b8 [ 9.755694][ T205] RDX: 0000000000040271 RSI: ffffffffffffffb0 RDI: 0000000000000000 [ 9.755695][ T205] RBP: 00007ffde53d8640 R08: 00007f3652880ac8 R09: 0000000000040270 [ 9.755696][ T205] R10: 0000000000000000 R11: 0000000000000206 R12: 000000000047b0a0 [ 9.755697][ T205] R13: 000000000047b0e0 R14: 0000000000000006 R15: 00007f3652688000 [ 9.755703][ T205] [ 10.041802][ T210] tun (210) used greatest stack depth: 24376 bytes left [ 21.991425][ C0] clocksource: Watchdog remote CPU 2 read timed out [ 21.991610][ C0] [ 21.991611][ C0] ======================================================== [ 21.991612][ C0] WARNING: possible irq lock inversion dependency detected [ 21.991614][ C0] 7.2.0-virtme #1 Tainted: G W [ 21.991616][ C0] -------------------------------------------------------- [ 21.991616][ C0] swapper/0/0 just changed the state of lock: [ 21.991617][ C0] ffffffff8849d760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 21.991630][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 21.991631][ C0] (fs_reclaim){+.+.}-{0:0} [ 21.991632][ C0] [ 21.991632][ C0] [ 21.991632][ C0] and interrupts could create inverse lock ordering between them. [ 21.991632][ C0] [ 21.991633][ C0] [ 21.991633][ C0] other info that might help us debug this: [ 21.991634][ C0] Possible interrupt unsafe locking scenario: [ 21.991634][ C0] [ 21.991634][ C0] CPU0 CPU1 [ 21.991635][ C0] ---- ---- [ 21.991635][ C0] lock(fs_reclaim); [ 21.991636][ C0] local_irq_disable(); [ 21.991636][ C0] lock(console_owner); [ 21.991637][ C0] lock(fs_reclaim); [ 21.991638][ C0] [ 21.991639][ C0] lock(console_owner); [ 21.991639][ C0] [ 21.991639][ C0] *** DEADLOCK *** [ 21.991639][ C0] [ 21.991640][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 21.991641][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 21.991647][ C0] #1: ffffffff885e29f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 21.991651][ C0] #2: ffffffff8857ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 21.991653][ C0] #3: ffffffff8857de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 21.991657][ C0] [ 21.991657][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 21.991661][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 21.991663][ C0] HARDIRQ-ON-W at: [ 21.991664][ C0] __lock_acquire+0x388/0xc20 [ 21.991667][ C0] lock_acquire.part.0+0xd4/0x280 [ 21.991669][ C0] fs_reclaim_acquire+0xd5/0x120 [ 21.991672][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 21.991674][ C0] kthread_create_worker_on_node+0xea/0x210 [ 21.991677][ C0] workqueue_init+0x2a/0x680 [ 21.991681][ C0] kernel_init_freeable+0x2fe/0x630 [ 21.991684][ C0] kernel_init+0x21/0x150 [ 21.991688][ C0] ret_from_fork+0x474/0x6b0 [ 21.991691][ C0] ret_from_fork_asm+0x11/0x20 [ 21.991694][ C0] SOFTIRQ-ON-W at: [ 21.991695][ C0] __lock_acquire+0x388/0xc20 [ 21.991696][ C0] lock_acquire.part.0+0xd4/0x280 [ 21.991698][ C0] fs_reclaim_acquire+0xd5/0x120 [ 21.991699][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 21.991700][ C0] kthread_create_worker_on_node+0xea/0x210 [ 21.991701][ C0] workqueue_init+0x2a/0x680 [ 21.991703][ C0] kernel_init_freeable+0x2fe/0x630 [ 21.991704][ C0] kernel_init+0x21/0x150 [ 21.991706][ C0] ret_from_fork+0x474/0x6b0 [ 21.991707][ C0] ret_from_fork_asm+0x11/0x20 [ 21.991708][ C0] INITIAL USE at: [ 21.991709][ C0] __lock_acquire+0x388/0xc20 [ 21.991710][ C0] lock_acquire.part.0+0xd4/0x280 [ 21.991712][ C0] fs_reclaim_acquire+0xd5/0x120 [ 21.991713][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 21.991714][ C0] kthread_create_worker_on_node+0xea/0x210 [ 21.991716][ C0] workqueue_init+0x2a/0x680 [ 21.991717][ C0] kernel_init_freeable+0x2fe/0x630 [ 21.991718][ C0] kernel_init+0x21/0x150 [ 21.991720][ C0] ret_from_fork+0x474/0x6b0 [ 21.991721][ C0] ret_from_fork_asm+0x11/0x20 [ 21.991722][ C0] } [ 21.991723][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 21.991726][ C0] ... acquired at: [ 21.991727][ C0] __lock_acquire+0x518/0xc20 [ 21.991729][ C0] lock_acquire.part.0+0xd4/0x280 [ 21.991730][ C0] fs_reclaim_acquire+0xd5/0x120 [ 21.991732][ C0] __kmalloc_noprof+0xd3/0x760 [ 21.991733][ C0] alloc_buf.isra.0+0x4b/0x260 [ 21.991737][ C0] put_chars+0x1e1/0x2f0 [ 21.991738][ C0] hvc_console_print+0x292/0x780 [ 21.991741][ C0] console_emit_next_record+0x22f/0x4c0 [ 21.991743][ C0] console_flush_one_record+0x46f/0x710 [ 21.991744][ C0] console_unlock+0xee/0x1f0 [ 21.991746][ C0] vprintk_emit+0x37c/0x3e0 [ 21.991746][ C0] _printk+0xc7/0x100 [ 21.991749][ C0] i8042_pnp_init+0xf7/0x3c0 [ 21.991752][ C0] i8042_platform_init+0x3f9/0x460 [ 21.991753][ C0] i8042_init+0x45/0x130 [ 21.991755][ C0] do_one_initcall+0x124/0x4f0 [ 21.991756][ C0] kernel_init_freeable+0x596/0x630 [ 21.991758][ C0] kernel_init+0x21/0x150 [ 21.991759][ C0] ret_from_fork+0x474/0x6b0 [ 21.991760][ C0] ret_from_fork_asm+0x11/0x20 [ 21.991761][ C0] [ 21.991762][ C0] -> (console_owner){..-.}-{0:0} { [ 21.991763][ C0] IN-SOFTIRQ-W at: [ 21.991764][ C0] __lock_acquire+0x388/0xc20 [ 21.991765][ C0] lock_acquire.part.0+0xd4/0x280 [ 21.991767][ C0] console_lock_spinning_enable+0x5c/0x60 [ 21.991768][ C0] console_emit_next_record+0x1d1/0x4c0 [ 21.991770][ C0] console_flush_one_record+0x46f/0x710 [ 21.991772][ C0] console_unlock+0xee/0x1f0 [ 21.991773][ C0] vprintk_emit+0x37c/0x3e0 [ 21.991774][ C0] _printk+0xc7/0x100 [ 21.991775][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 21.991778][ C0] call_timer_fn+0x160/0x4d0 [ 21.991779][ C0] __run_timers+0x68f/0xaa0 [ 21.991781][ C0] run_timer_softirq+0xf0/0x160 [ 21.991782][ C0] handle_softirqs+0x1d3/0x900 [ 21.991785][ C0] __irq_exit_rcu+0x145/0x1c0 [ 21.991786][ C0] irq_exit_rcu+0xe/0x30 [ 21.991787][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 21.991789][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 21.991791][ C0] pv_native_safe_halt+0xf/0x10 [ 21.991792][ C0] default_idle+0x9/0x10 [ 21.991793][ C0] default_idle_call+0x6e/0xb0 [ 21.991794][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 21.991797][ C0] do_idle+0xd8/0x190 [ 21.991798][ C0] cpu_startup_entry+0x53/0x70 [ 21.991799][ C0] rest_init+0x279/0x280 [ 21.991801][ C0] start_kernel+0x3b9/0x3c0 [ 21.991802][ C0] x86_64_start_reservations+0x24/0x30 [ 21.991804][ C0] x86_64_start_kernel+0x12b/0x130 [ 21.991805][ C0] common_startup_64+0x13e/0x148 [ 21.991808][ C0] INITIAL USE at: [ 21.991809][ C0] } [ 21.991809][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 21.991811][ C0] ... acquired at: [ 21.991812][ C0] mark_lock+0x1d7/0xa00 [ 21.991813][ C0] mark_usage+0x42/0x170 [ 21.991814][ C0] __lock_acquire+0x388/0xc20 [ 21.991816][ C0] lock_acquire.part.0+0xd4/0x280 [ 21.991817][ C0] console_lock_spinning_enable+0x5c/0x60 [ 21.991819][ C0] console_emit_next_record+0x1d1/0x4c0 [ 21.991820][ C0] console_flush_one_record+0x46f/0x710 [ 21.991822][ C0] console_unlock+0xee/0x1f0 [ 21.991823][ C0] vprintk_emit+0x37c/0x3e0 [ 21.991824][ C0] _printk+0xc7/0x100 [ 21.991825][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 21.991826][ C0] call_timer_fn+0x160/0x4d0 [ 21.991828][ C0] __run_timers+0x68f/0xaa0 [ 21.991829][ C0] run_timer_softirq+0xf0/0x160 [ 21.991831][ C0] handle_softirqs+0x1d3/0x900 [ 21.991832][ C0] __irq_exit_rcu+0x145/0x1c0 [ 21.991833][ C0] irq_exit_rcu+0xe/0x30 [ 21.991834][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 21.991836][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 21.991837][ C0] pv_native_safe_halt+0xf/0x10 [ 21.991838][ C0] default_idle+0x9/0x10 [ 21.991839][ C0] default_idle_call+0x6e/0xb0 [ 21.991840][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 21.991841][ C0] do_idle+0xd8/0x190 [ 21.991843][ C0] cpu_startup_entry+0x53/0x70 [ 21.991844][ C0] rest_init+0x279/0x280 [ 21.991845][ C0] start_kernel+0x3b9/0x3c0 [ 21.991846][ C0] x86_64_start_reservations+0x24/0x30 [ 21.991847][ C0] x86_64_start_kernel+0x12b/0x130 [ 21.991849][ C0] common_startup_64+0x13e/0x148 [ 21.991850][ C0] [ 21.991850][ C0] [ 21.991850][ C0] stack backtrace: [ 21.991853][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 21.991856][ C0] Tainted: [W]=WARN [ 21.991857][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 21.991858][ C0] Call Trace: [ 21.991859][ C0] [ 21.991860][ C0] dump_stack_lvl+0x6f/0xa0 [ 21.991865][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 21.991867][ C0] mark_lock_irq+0x989/0x9c0 [ 21.991869][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 21.991872][ C0] mark_lock+0x1d7/0xa00 [ 21.991874][ C0] mark_usage+0x42/0x170 [ 21.991875][ C0] __lock_acquire+0x388/0xc20 [ 21.991878][ C0] lock_acquire.part.0+0xd4/0x280 [ 21.991879][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 21.991881][ C0] ? rcu_is_watching+0x16/0xd0 [ 21.991884][ C0] ? lock_acquire+0x13c/0x160 [ 21.991885][ C0] console_lock_spinning_enable+0x5c/0x60 [ 21.991887][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 21.991889][ C0] console_emit_next_record+0x1d1/0x4c0 [ 21.991891][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 21.991893][ C0] ? console_flush_one_record+0x106/0x710 [ 21.991895][ C0] ? rcu_is_watching+0x16/0xd0 [ 21.991896][ C0] ? lock_acquire+0x13c/0x160 [ 21.991898][ C0] console_flush_one_record+0x46f/0x710 [ 21.991900][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 21.991902][ C0] ? __lock_acquire+0x518/0xc20 [ 21.991904][ C0] console_unlock+0xee/0x1f0 [ 21.991906][ C0] ? console_flush_one_record+0x710/0x710 [ 21.991908][ C0] ? rcu_is_watching+0x16/0xd0 [ 21.991909][ C0] ? lock_acquire+0xe0/0x160 [ 21.991910][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 21.991912][ C0] ? vprintk_emit+0x320/0x3e0 [ 21.991913][ C0] vprintk_emit+0x37c/0x3e0 [ 21.991915][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 21.991917][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 21.991919][ C0] _printk+0xc7/0x100 [ 21.991920][ C0] ? snapshot_read.cold+0x21/0x21 [ 21.991922][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 21.991924][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 21.991926][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 21.991927][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 21.991929][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 21.991931][ C0] call_timer_fn+0x160/0x4d0 [ 21.991933][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 21.991934][ C0] ? debug_object_active_state+0x430/0x430 [ 21.991937][ C0] ? find_held_lock+0x2b/0x80 [ 21.991939][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 21.991941][ C0] ? rcu_is_watching+0x16/0xd0 [ 21.991942][ C0] __run_timers+0x68f/0xaa0 [ 21.991944][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 21.991946][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 21.991948][ C0] ? __lock_acquire+0x518/0xc20 [ 21.991951][ C0] ? __rwlock_init+0x150/0x150 [ 21.991953][ C0] run_timer_softirq+0xf0/0x160 [ 21.991955][ C0] ? __run_timers+0xaa0/0xaa0 [ 21.991957][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 21.991960][ C0] ? rcu_is_watching+0x16/0xd0 [ 21.991961][ C0] handle_softirqs+0x1d3/0x900 [ 21.991963][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 21.991965][ C0] ? _local_bh_enable+0xc0/0xc0 [ 21.991967][ C0] __irq_exit_rcu+0x145/0x1c0 [ 21.991968][ C0] irq_exit_rcu+0xe/0x30 [ 21.991970][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 21.991971][ C0] [ 21.991972][ C0] [ 21.991972][ C0] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 21.991974][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 21.991976][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 21.991978][ C0] Code: 48 8b 3d 94 82 68 02 e8 1f 00 00 00 48 2b 05 58 d3 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 06 0e 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 21.991980][ C0] RSP: 0018:ffffffff88207cf8 EFLAGS: 00000296 [ 21.991982][ C0] RAX: 00000000000998b3 RBX: ffffffff88230600 RCX: ffffffff84906247 [ 21.991983][ C0] RDX: ffffffff88230600 RSI: ffffffff87b11027 RDI: ffffffff874949e0 [ 21.991984][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 21.991985][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff1040fa2 [ 21.991985][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 21.991987][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 21.991989][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 21.991990][ C0] default_idle+0x9/0x10 [ 21.991992][ C0] default_idle_call+0x6e/0xb0 [ 21.991993][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 21.991995][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 21.991997][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 21.991999][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 21.992000][ C0] ? rcu_is_watching+0x16/0xd0 [ 21.992002][ C0] do_idle+0xd8/0x190 [ 21.992003][ C0] cpu_startup_entry+0x53/0x70 [ 21.992005][ C0] rest_init+0x279/0x280 [ 21.992006][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 21.992009][ C0] ? rest_init+0x280/0x280 [ 21.992010][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 21.992014][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 21.992016][ C0] ? acpi_enable+0x1e4/0x330 [ 21.992018][ C0] start_kernel+0x3b9/0x3c0 [ 21.992020][ C0] x86_64_start_reservations+0x24/0x30 [ 21.992021][ C0] x86_64_start_kernel+0x12b/0x130 [ 21.992023][ C0] common_startup_64+0x13e/0x148 [ 21.992026][ C0] [ 21.992030][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 21.992031][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 21.992033][ C0] preempt_count: 103, expected: 0 [ 21.992033][ C0] RCU nest depth: 0, expected: 0 [ 21.992034][ C0] INFO: lockdep is turned off. [ 21.992034][ C0] irq event stamp: 628931 [ 21.992035][ C0] hardirqs last enabled at (628930): [] __down_trylock_console_sem+0x86/0xa0 [ 21.992038][ C0] hardirqs last disabled at (628931): [] console_emit_next_record+0x3d4/0x4c0 [ 21.992040][ C0] softirqs last enabled at (628910): [] handle_softirqs+0x67c/0x900 [ 21.992041][ C0] softirqs last disabled at (628917): [] __irq_exit_rcu+0x145/0x1c0 [ 21.992043][ C0] Preemption disabled at: [ 21.992043][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 21.992046][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 21.992048][ C0] Tainted: [W]=WARN [ 21.992049][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 21.992049][ C0] Call Trace: [ 21.992050][ C0] [ 21.992050][ C0] dump_stack_lvl+0x6f/0xa0 [ 21.992053][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 21.992054][ C0] __might_resched.cold+0x1fe/0x2c1 [ 21.992057][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 21.992060][ C0] ? rcu_is_watching+0x16/0xd0 [ 21.992061][ C0] __kmalloc_noprof+0x443/0x760 [ 21.992062][ C0] ? __rwlock_init+0x150/0x150 [ 21.992064][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 21.992066][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 21.992068][ C0] alloc_buf.isra.0+0x4b/0x260 [ 21.992070][ C0] put_chars+0x1e1/0x2f0 [ 21.992072][ C0] ? __send_to_port+0x420/0x420 [ 21.992074][ C0] ? mark_lock+0x1d7/0xa00 [ 21.992076][ C0] hvc_console_print+0x292/0x780 [ 21.992079][ C0] ? hvc_write+0x3a0/0x3a0 [ 21.992081][ C0] ? rcu_is_watching+0x16/0xd0 [ 21.992081][ C0] ? rcu_is_watching+0x16/0xd0 [ 21.992083][ C0] console_emit_next_record+0x22f/0x4c0 [ 21.992085][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 21.992087][ C0] ? console_flush_one_record+0x106/0x710 [ 21.992089][ C0] ? rcu_is_watching+0x16/0xd0 [ 21.992090][ C0] ? lock_acquire+0x13c/0x160 [ 21.992092][ C0] console_flush_one_record+0x46f/0x710 [ 21.992094][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 21.992096][ C0] ? __lock_acquire+0x518/0xc20 [ 21.992098][ C0] console_unlock+0xee/0x1f0 [ 21.992100][ C0] ? console_flush_one_record+0x710/0x710 [ 21.992102][ C0] ? rcu_is_watching+0x16/0xd0 [ 21.992103][ C0] ? lock_acquire+0xe0/0x160 [ 21.992105][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 21.992107][ C0] ? vprintk_emit+0x320/0x3e0 [ 21.992108][ C0] vprintk_emit+0x37c/0x3e0 [ 21.992109][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 21.992111][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 21.992113][ C0] _printk+0xc7/0x100 [ 21.992114][ C0] ? snapshot_read.cold+0x21/0x21 [ 21.992116][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 21.992118][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 21.992120][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 21.992122][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 21.992123][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 21.992125][ C0] call_timer_fn+0x160/0x4d0 [ 21.992127][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 21.992129][ C0] ? debug_object_active_state+0x430/0x430 [ 21.992130][ C0] ? find_held_lock+0x2b/0x80 [ 21.992132][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 21.992133][ C0] ? rcu_is_watching+0x16/0xd0 [ 21.992135][ C0] __run_timers+0x68f/0xaa0 [ 21.992137][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 21.992139][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 21.992140][ C0] ? __lock_acquire+0x518/0xc20 [ 21.992143][ C0] ? __rwlock_init+0x150/0x150 [ 21.992145][ C0] run_timer_softirq+0xf0/0x160 [ 21.992147][ C0] ? __run_timers+0xaa0/0xaa0 [ 21.992149][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 21.992150][ C0] ? rcu_is_watching+0x16/0xd0 [ 21.992152][ C0] handle_softirqs+0x1d3/0x900 [ 21.992153][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 21.992155][ C0] ? _local_bh_enable+0xc0/0xc0 [ 21.992157][ C0] __irq_exit_rcu+0x145/0x1c0 [ 21.992159][ C0] irq_exit_rcu+0xe/0x30 [ 21.992160][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 21.992161][ C0] [ 21.992162][ C0] [ 21.992162][ C0] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 21.992164][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 21.992165][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 21.992167][ C0] Code: 48 8b 3d 94 82 68 02 e8 1f 00 00 00 48 2b 05 58 d3 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 06 0e 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 21.992168][ C0] RSP: 0018:ffffffff88207cf8 EFLAGS: 00000296 [ 21.992169][ C0] RAX: 00000000000998b3 RBX: ffffffff88230600 RCX: ffffffff84906247 [ 21.992170][ C0] RDX: ffffffff88230600 RSI: ffffffff87b11027 RDI: ffffffff874949e0 [ 21.992170][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 21.992171][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff1040fa2 [ 21.992172][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 21.992173][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 21.992175][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 21.992177][ C0] default_idle+0x9/0x10 [ 21.992178][ C0] default_idle_call+0x6e/0xb0 [ 21.992180][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 21.992181][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 21.992186][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 21.992188][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 21.992189][ C0] ? rcu_is_watching+0x16/0xd0 [ 21.992191][ C0] do_idle+0xd8/0x190 [ 21.992192][ C0] cpu_startup_entry+0x53/0x70 [ 21.992194][ C0] rest_init+0x279/0x280 [ 21.992195][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 21.992198][ C0] ? rest_init+0x280/0x280 [ 21.992199][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 21.992201][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 21.992202][ C0] ? acpi_enable+0x1e4/0x330 [ 21.992204][ C0] start_kernel+0x3b9/0x3c0 [ 21.992206][ C0] x86_64_start_reservations+0x24/0x30 [ 21.992207][ C0] x86_64_start_kernel+0x12b/0x130 [ 21.992209][ C0] common_startup_64+0x13e/0x148 [ 21.992212][ C0]