[ 9.124603][ T131] sed (131) used greatest stack depth: 24512 bytes left [ 9.124622][ T131] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.124624][ T131] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 131, name: sed [ 9.124626][ T131] preempt_count: 2, expected: 0 [ 9.124627][ T131] RCU nest depth: 0, expected: 0 [ 9.124628][ T131] locks held by sed/131: 5, last CPU#0: [ 9.124630][ T131] #0: ffffffffb4a267b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.124643][ T131] #1: ffffffffb4b8dcc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.124649][ T131] #2: ffffffffb4b8dd38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.124653][ T131] #3: ffffffffb4aad660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.124657][ T131] #4: ffffffffb4aad560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.124661][ T131] irq event stamp: 14420 [ 9.124662][ T131] hardirqs last enabled at (14419): [] __down_trylock_console_sem+0x86/0xa0 [ 9.124664][ T131] hardirqs last disabled at (14420): [] console_emit_next_record+0x3d4/0x4c0 [ 9.124666][ T131] softirqs last enabled at (12800): [] handle_softirqs+0x67c/0x900 [ 9.124668][ T131] softirqs last disabled at (12793): [] __irq_exit_rcu+0x145/0x1c0 [ 9.124670][ T131] Preemption disabled at: [ 9.124671][ T131] [<0000000000000000>] 0x0 [ 9.124677][ T131] CPU: 0 UID: 0 PID: 131 Comm: sed Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.124681][ T131] Tainted: [W]=WARN [ 9.124682][ T131] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.124684][ T131] Call Trace: [ 9.124685][ T131] [ 9.124687][ T131] dump_stack_lvl+0x6f/0xa0 [ 9.124693][ T131] __might_resched.cold+0x1fe/0x2c1 [ 9.124698][ T131] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.124702][ T131] ? __kmalloc_noprof+0xdb/0x760 [ 9.124707][ T131] __kmalloc_noprof+0x443/0x760 [ 9.124709][ T131] ? alloc_buf.isra.0+0x4b/0x260 [ 9.124721][ T131] ? do_raw_spin_unlock+0x59/0x250 [ 9.124724][ T131] alloc_buf.isra.0+0x4b/0x260 [ 9.124727][ T131] put_chars+0x1e1/0x2f0 [ 9.124730][ T131] ? __send_to_port+0x420/0x420 [ 9.124732][ T131] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.124737][ T131] ? validate_chain+0x38b/0xc20 [ 9.124743][ T131] hvc_console_print+0x292/0x780 [ 9.124750][ T131] ? hvc_write+0x3a0/0x3a0 [ 9.124753][ T131] ? rcu_is_watching+0x16/0xd0 [ 9.124755][ T131] ? lock_acquire+0x13c/0x160 [ 9.124759][ T131] console_emit_next_record+0x22f/0x4c0 [ 9.124763][ T131] ? devkmsg_read+0x4b0/0x4b0 [ 9.124765][ T131] ? console_flush_one_record+0x106/0x710 [ 9.124768][ T131] ? rcu_is_watching+0x16/0xd0 [ 9.124770][ T131] ? lock_acquire+0x13c/0x160 [ 9.124774][ T131] console_flush_one_record+0x46f/0x710 [ 9.124778][ T131] ? console_emit_next_record+0x4c0/0x4c0 [ 9.124780][ T131] ? __lock_acquire+0x518/0xc20 [ 9.124785][ T131] console_unlock+0xee/0x1f0 [ 9.124788][ T131] ? console_flush_one_record+0x710/0x710 [ 9.124790][ T131] ? rcu_is_watching+0x16/0xd0 [ 9.124792][ T131] ? lock_acquire+0x60/0x160 [ 9.124795][ T131] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.124797][ T131] ? vprintk_emit+0x320/0x3e0 [ 9.124800][ T131] vprintk_emit+0x37c/0x3e0 [ 9.124804][ T131] ? wake_up_klogd_work_func+0x90/0x90 [ 9.124807][ T131] ? __lock_acquire+0x518/0xc20 [ 9.124811][ T131] _printk+0xc7/0x100 [ 9.124815][ T131] ? snapshot_read.cold+0x21/0x21 [ 9.124818][ T131] ? do_raw_spin_lock+0x131/0x280 [ 9.124820][ T131] ? __rwlock_init+0x150/0x150 [ 9.124824][ T131] ? do_raw_spin_lock+0x131/0x280 [ 9.124826][ T131] do_exit.cold+0x82/0x9c [ 9.124830][ T131] ? exit_notify+0x890/0x890 [ 9.124832][ T131] ? __lock_release.isra.0+0x69/0x1a0 [ 9.124835][ T131] ? rcu_is_watching+0x16/0xd0 [ 9.124839][ T131] do_group_exit+0xb8/0x370 [ 9.124842][ T131] __x64_sys_exit_group+0x3c/0x50 [ 9.124845][ T131] x64_sys_call+0x1567/0x1570 [ 9.124847][ T131] do_syscall_64+0xff/0x530 [ 9.124850][ T131] ? exc_page_fault+0xee/0x100 [ 9.124854][ T131] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.124856][ T131] RIP: 0033:0x7f64ede621b8 [ 9.124858][ T131] Code: Unable to access opcode bytes at 0x7f64ede6218e. [ 9.124859][ T131] RSP: 002b:00007fffee78a4b8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.124862][ T131] RAX: ffffffffffffffda RBX: 00007f64edf92f88 RCX: 00007f64ede621b8 [ 9.124863][ T131] RDX: 00007f64edcbfb48 RSI: fffffffffffffec8 RDI: 0000000000000000 [ 9.124864][ T131] RBP: 00007fffee78a510 R08: 00000000ffffffff R09: 0000000000001010 [ 9.124865][ T131] R10: 00007fffee78a330 R11: 0000000000000246 R12: 0000000000000002 [ 9.124866][ T131] R13: 0000000000000000 R14: 00007f64edf91680 R15: 00007f64edf92fa0 [ 9.124873][ T131] [ 10.401625][ T202] ip (202) used greatest stack depth: 23856 bytes left [ 10.401643][ T202] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.401645][ T202] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 202, name: ip [ 10.401647][ T202] preempt_count: 2, expected: 0 [ 10.401647][ T202] RCU nest depth: 0, expected: 0 [ 10.401648][ T202] locks held by ip/202: 5, last CPU#3: [ 10.401651][ T202] #0: ffffffffb4a267b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.401663][ T202] #1: ffffffffb4b8dcc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.401669][ T202] #2: ffffffffb4b8dd38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.401673][ T202] #3: ffffffffb4aad660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.401677][ T202] #4: ffffffffb4aad560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.401681][ T202] irq event stamp: 26854 [ 10.401682][ T202] hardirqs last enabled at (26853): [] __down_trylock_console_sem+0x86/0xa0 [ 10.401684][ T202] hardirqs last disabled at (26854): [] console_emit_next_record+0x3d4/0x4c0 [ 10.401686][ T202] softirqs last enabled at (26786): [] handle_softirqs+0x67c/0x900 [ 10.401688][ T202] softirqs last disabled at (26781): [] __irq_exit_rcu+0x145/0x1c0 [ 10.401690][ T202] Preemption disabled at: [ 10.401691][ T202] [<0000000000000000>] 0x0 [ 10.401698][ T202] CPU: 3 UID: 0 PID: 202 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.401701][ T202] Tainted: [W]=WARN [ 10.401702][ T202] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.401704][ T202] Call Trace: [ 10.401706][ T202] [ 10.401708][ T202] dump_stack_lvl+0x6f/0xa0 [ 10.401714][ T202] __might_resched.cold+0x1fe/0x2c1 [ 10.401723][ T202] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.401727][ T202] ? __kmalloc_noprof+0xdb/0x760 [ 10.401732][ T202] __kmalloc_noprof+0x443/0x760 [ 10.401734][ T202] ? alloc_buf.isra.0+0x4b/0x260 [ 10.401740][ T202] ? do_raw_spin_unlock+0x59/0x250 [ 10.401743][ T202] alloc_buf.isra.0+0x4b/0x260 [ 10.401747][ T202] put_chars+0x1e1/0x2f0 [ 10.401750][ T202] ? __send_to_port+0x420/0x420 [ 10.401752][ T202] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.401757][ T202] ? validate_chain+0x38b/0xc20 [ 10.401763][ T202] hvc_console_print+0x292/0x780 [ 10.401770][ T202] ? hvc_write+0x3a0/0x3a0 [ 10.401773][ T202] ? rcu_is_watching+0x16/0xd0 [ 10.401775][ T202] ? lock_acquire+0x13c/0x160 [ 10.401779][ T202] console_emit_next_record+0x22f/0x4c0 [ 10.401783][ T202] ? devkmsg_read+0x4b0/0x4b0 [ 10.401785][ T202] ? console_flush_one_record+0x106/0x710 [ 10.401788][ T202] ? rcu_is_watching+0x16/0xd0 [ 10.401790][ T202] ? lock_acquire+0x13c/0x160 [ 10.401794][ T202] console_flush_one_record+0x46f/0x710 [ 10.401798][ T202] ? console_emit_next_record+0x4c0/0x4c0 [ 10.401799][ T202] ? __lock_acquire+0x518/0xc20 [ 10.401804][ T202] console_unlock+0xee/0x1f0 [ 10.401807][ T202] ? console_flush_one_record+0x710/0x710 [ 10.401809][ T202] ? rcu_is_watching+0x16/0xd0 [ 10.401811][ T202] ? lock_acquire+0x60/0x160 [ 10.401814][ T202] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.401816][ T202] ? vprintk_emit+0x320/0x3e0 [ 10.401819][ T202] vprintk_emit+0x37c/0x3e0 [ 10.401823][ T202] ? wake_up_klogd_work_func+0x90/0x90 [ 10.401826][ T202] ? __lock_acquire+0x518/0xc20 [ 10.401830][ T202] _printk+0xc7/0x100 [ 10.401834][ T202] ? snapshot_read.cold+0x21/0x21 [ 10.401836][ T202] ? do_raw_spin_lock+0x131/0x280 [ 10.401839][ T202] ? __rwlock_init+0x150/0x150 [ 10.401843][ T202] ? do_raw_spin_lock+0x131/0x280 [ 10.401845][ T202] do_exit.cold+0x82/0x9c [ 10.401849][ T202] ? exit_notify+0x890/0x890 [ 10.401851][ T202] ? __lock_release.isra.0+0x69/0x1a0 [ 10.401853][ T202] ? rcu_is_watching+0x16/0xd0 [ 10.401858][ T202] do_group_exit+0xb8/0x370 [ 10.401861][ T202] __x64_sys_exit_group+0x3c/0x50 [ 10.401863][ T202] x64_sys_call+0x1567/0x1570 [ 10.401865][ T202] do_syscall_64+0xff/0x530 [ 10.401869][ T202] ? exc_page_fault+0xee/0x100 [ 10.401872][ T202] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.401875][ T202] RIP: 0033:0x7f7c9e77a1b8 [ 10.401877][ T202] Code: Unable to access opcode bytes at 0x7f7c9e77a18e. [ 10.401878][ T202] RSP: 002b:00007ffe4ae33488 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.401881][ T202] RAX: ffffffffffffffda RBX: 00007f7c9e8aaf88 RCX: 00007f7c9e77a1b8 [ 10.401882][ T202] RDX: 00007f7c9e4c4fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.401883][ T202] RBP: 00007ffe4ae334e0 R08: 0000000000000000 R09: 0000000000000050 [ 10.401884][ T202] R10: 00007ffe4ae332a0 R11: 0000000000000246 R12: 0000000000000001 [ 10.401885][ T202] R13: 0000000000000000 R14: 00007f7c9e8a9680 R15: 00007f7c9e8aafa0 [ 10.401892][ T202] [ 105.903723][ C0] clocksource: Watchdog remote CPU 2 read timed out [ 105.904747][ C0] [ 105.904750][ C0] ======================================================== [ 105.904751][ C0] WARNING: possible irq lock inversion dependency detected [ 105.904754][ C0] 7.2.0-virtme #1 Tainted: G W [ 105.904755][ C0] -------------------------------------------------------- [ 105.904756][ C0] swapper/0/0 just changed the state of lock: [ 105.904757][ C0] ffffffffb4aad660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 105.904769][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 105.904771][ C0] (fs_reclaim){+.+.}-{0:0} [ 105.904773][ C0] [ 105.904773][ C0] [ 105.904773][ C0] and interrupts could create inverse lock ordering between them. [ 105.904773][ C0] [ 105.904774][ C0] [ 105.904774][ C0] other info that might help us debug this: [ 105.904774][ C0] Possible interrupt unsafe locking scenario: [ 105.904774][ C0] [ 105.904775][ C0] CPU0 CPU1 [ 105.904775][ C0] ---- ---- [ 105.904776][ C0] lock(fs_reclaim); [ 105.904777][ C0] local_irq_disable(); [ 105.904777][ C0] lock(console_owner); [ 105.904778][ C0] lock(fs_reclaim); [ 105.904779][ C0] [ 105.904779][ C0] lock(console_owner); [ 105.904780][ C0] [ 105.904780][ C0] *** DEADLOCK *** [ 105.904780][ C0] [ 105.904780][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 105.904782][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 105.904787][ C0] #1: ffffffffb4bf28b8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 105.904791][ C0] #2: ffffffffb4b8dcc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 105.904795][ C0] #3: ffffffffb4b8dd38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 105.904798][ C0] [ 105.904798][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 105.904803][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 105.904805][ C0] HARDIRQ-ON-W at: [ 105.904806][ C0] __lock_acquire+0x388/0xc20 [ 105.904809][ C0] lock_acquire.part.0+0xd4/0x280 [ 105.904810][ C0] fs_reclaim_acquire+0xd5/0x120 [ 105.904814][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 105.904816][ C0] kthread_create_worker_on_node+0xea/0x210 [ 105.904819][ C0] workqueue_init+0x2a/0x680 [ 105.904822][ C0] kernel_init_freeable+0x2fe/0x630 [ 105.904824][ C0] kernel_init+0x21/0x150 [ 105.904828][ C0] ret_from_fork+0x474/0x6b0 [ 105.904831][ C0] ret_from_fork_asm+0x11/0x20 [ 105.904834][ C0] SOFTIRQ-ON-W at: [ 105.904835][ C0] __lock_acquire+0x388/0xc20 [ 105.904836][ C0] lock_acquire.part.0+0xd4/0x280 [ 105.904837][ C0] fs_reclaim_acquire+0xd5/0x120 [ 105.904839][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 105.904840][ C0] kthread_create_worker_on_node+0xea/0x210 [ 105.904841][ C0] workqueue_init+0x2a/0x680 [ 105.904842][ C0] kernel_init_freeable+0x2fe/0x630 [ 105.904843][ C0] kernel_init+0x21/0x150 [ 105.904845][ C0] ret_from_fork+0x474/0x6b0 [ 105.904847][ C0] ret_from_fork_asm+0x11/0x20 [ 105.904848][ C0] INITIAL USE at: [ 105.904849][ C0] __lock_acquire+0x388/0xc20 [ 105.904850][ C0] lock_acquire.part.0+0xd4/0x280 [ 105.904851][ C0] fs_reclaim_acquire+0xd5/0x120 [ 105.904853][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 105.904854][ C0] kthread_create_worker_on_node+0xea/0x210 [ 105.904855][ C0] workqueue_init+0x2a/0x680 [ 105.904856][ C0] kernel_init_freeable+0x2fe/0x630 [ 105.904857][ C0] kernel_init+0x21/0x150 [ 105.904859][ C0] ret_from_fork+0x474/0x6b0 [ 105.904860][ C0] ret_from_fork_asm+0x11/0x20 [ 105.904862][ C0] } [ 105.904862][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 105.904866][ C0] ... acquired at: [ 105.904866][ C0] __lock_acquire+0x518/0xc20 [ 105.904868][ C0] lock_acquire.part.0+0xd4/0x280 [ 105.904869][ C0] fs_reclaim_acquire+0xd5/0x120 [ 105.904870][ C0] __kmalloc_noprof+0xd3/0x760 [ 105.904872][ C0] alloc_buf.isra.0+0x4b/0x260 [ 105.904875][ C0] put_chars+0x1e1/0x2f0 [ 105.904877][ C0] hvc_console_print+0x292/0x780 [ 105.904880][ C0] console_emit_next_record+0x22f/0x4c0 [ 105.904881][ C0] console_flush_one_record+0x46f/0x710 [ 105.904883][ C0] console_unlock+0xee/0x1f0 [ 105.904884][ C0] vprintk_emit+0x37c/0x3e0 [ 105.904885][ C0] _printk+0xc7/0x100 [ 105.904888][ C0] print_ipi_mode+0x2e/0x40 [ 105.904891][ C0] do_one_initcall+0x124/0x4f0 [ 105.904892][ C0] kernel_init_freeable+0x596/0x630 [ 105.904893][ C0] kernel_init+0x21/0x150 [ 105.904894][ C0] ret_from_fork+0x474/0x6b0 [ 105.904896][ C0] ret_from_fork_asm+0x11/0x20 [ 105.904897][ C0] [ 105.904897][ C0] -> (console_owner){..-.}-{0:0} { [ 105.904899][ C0] IN-SOFTIRQ-W at: [ 105.904900][ C0] __lock_acquire+0x388/0xc20 [ 105.904901][ C0] lock_acquire.part.0+0xd4/0x280 [ 105.904902][ C0] console_lock_spinning_enable+0x5c/0x60 [ 105.904904][ C0] console_emit_next_record+0x1d1/0x4c0 [ 105.904905][ C0] console_flush_one_record+0x46f/0x710 [ 105.904906][ C0] console_unlock+0xee/0x1f0 [ 105.904908][ C0] vprintk_emit+0x37c/0x3e0 [ 105.904909][ C0] _printk+0xc7/0x100 [ 105.904910][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 105.904913][ C0] call_timer_fn+0x160/0x4d0 [ 105.904914][ C0] __run_timers+0x68f/0xaa0 [ 105.904915][ C0] run_timer_softirq+0xf0/0x160 [ 105.904917][ C0] handle_softirqs+0x1d3/0x900 [ 105.904919][ C0] __irq_exit_rcu+0x145/0x1c0 [ 105.904920][ C0] irq_exit_rcu+0xe/0x30 [ 105.904921][ C0] common_interrupt+0xb5/0xf0 [ 105.904923][ C0] asm_common_interrupt+0x26/0x40 [ 105.904925][ C0] pv_native_safe_halt+0xf/0x10 [ 105.904927][ C0] default_idle+0x9/0x10 [ 105.904928][ C0] default_idle_call+0x6e/0xb0 [ 105.904930][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 105.904932][ C0] do_idle+0xd8/0x190 [ 105.904933][ C0] cpu_startup_entry+0x53/0x70 [ 105.904934][ C0] rest_init+0x279/0x280 [ 105.904935][ C0] start_kernel+0x3af/0x3b0 [ 105.904937][ C0] x86_64_start_reservations+0x24/0x30 [ 105.904939][ C0] x86_64_start_kernel+0x12b/0x130 [ 105.904940][ C0] common_startup_64+0x13e/0x148 [ 105.904942][ C0] INITIAL USE at: [ 105.904943][ C0] } [ 105.904944][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 105.904946][ C0] ... acquired at: [ 105.904947][ C0] mark_lock+0x1d7/0xa00 [ 105.904948][ C0] mark_usage+0x42/0x170 [ 105.904949][ C0] __lock_acquire+0x388/0xc20 [ 105.904950][ C0] lock_acquire.part.0+0xd4/0x280 [ 105.904952][ C0] console_lock_spinning_enable+0x5c/0x60 [ 105.904953][ C0] console_emit_next_record+0x1d1/0x4c0 [ 105.904954][ C0] console_flush_one_record+0x46f/0x710 [ 105.904956][ C0] console_unlock+0xee/0x1f0 [ 105.904957][ C0] vprintk_emit+0x37c/0x3e0 [ 105.904958][ C0] _printk+0xc7/0x100 [ 105.904960][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 105.904961][ C0] call_timer_fn+0x160/0x4d0 [ 105.904962][ C0] __run_timers+0x68f/0xaa0 [ 105.904963][ C0] run_timer_softirq+0xf0/0x160 [ 105.904965][ C0] handle_softirqs+0x1d3/0x900 [ 105.904966][ C0] __irq_exit_rcu+0x145/0x1c0 [ 105.904967][ C0] irq_exit_rcu+0xe/0x30 [ 105.904968][ C0] common_interrupt+0xb5/0xf0 [ 105.904969][ C0] asm_common_interrupt+0x26/0x40 [ 105.904970][ C0] pv_native_safe_halt+0xf/0x10 [ 105.904971][ C0] default_idle+0x9/0x10 [ 105.904973][ C0] default_idle_call+0x6e/0xb0 [ 105.904975][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 105.904976][ C0] do_idle+0xd8/0x190 [ 105.904976][ C0] cpu_startup_entry+0x53/0x70 [ 105.904977][ C0] rest_init+0x279/0x280 [ 105.904979][ C0] start_kernel+0x3af/0x3b0 [ 105.904980][ C0] x86_64_start_reservations+0x24/0x30 [ 105.904982][ C0] x86_64_start_kernel+0x12b/0x130 [ 105.904983][ C0] common_startup_64+0x13e/0x148 [ 105.904984][ C0] [ 105.904985][ C0] [ 105.904985][ C0] stack backtrace: [ 105.904987][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 105.904990][ C0] Tainted: [W]=WARN [ 105.904991][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 105.904993][ C0] Call Trace: [ 105.904994][ C0] [ 105.904995][ C0] dump_stack_lvl+0x6f/0xa0 [ 105.904999][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 105.905001][ C0] mark_lock_irq+0x989/0x9c0 [ 105.905003][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 105.905006][ C0] mark_lock+0x1d7/0xa00 [ 105.905008][ C0] mark_usage+0x42/0x170 [ 105.905009][ C0] __lock_acquire+0x388/0xc20 [ 105.905012][ C0] lock_acquire.part.0+0xd4/0x280 [ 105.905013][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 105.905015][ C0] ? rcu_is_watching+0x16/0xd0 [ 105.905018][ C0] ? lock_acquire+0x13c/0x160 [ 105.905020][ C0] console_lock_spinning_enable+0x5c/0x60 [ 105.905022][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 105.905023][ C0] console_emit_next_record+0x1d1/0x4c0 [ 105.905025][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 105.905027][ C0] ? console_flush_one_record+0x106/0x710 [ 105.905029][ C0] ? rcu_is_watching+0x16/0xd0 [ 105.905031][ C0] ? lock_acquire+0x13c/0x160 [ 105.905033][ C0] console_flush_one_record+0x46f/0x710 [ 105.905035][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 105.905036][ C0] ? __lock_acquire+0x518/0xc20 [ 105.905039][ C0] console_unlock+0xee/0x1f0 [ 105.905041][ C0] ? console_flush_one_record+0x710/0x710 [ 105.905042][ C0] ? rcu_is_watching+0x16/0xd0 [ 105.905044][ C0] ? lock_acquire+0x60/0x160 [ 105.905046][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 105.905047][ C0] ? vprintk_emit+0x320/0x3e0 [ 105.905049][ C0] vprintk_emit+0x37c/0x3e0 [ 105.905051][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 105.905054][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 105.905055][ C0] _printk+0xc7/0x100 [ 105.905057][ C0] ? snapshot_read.cold+0x21/0x21 [ 105.905059][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 105.905061][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 105.905063][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 105.905065][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 105.905066][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 105.905068][ C0] call_timer_fn+0x160/0x4d0 [ 105.905070][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 105.905071][ C0] ? debug_object_active_state+0x430/0x430 [ 105.905074][ C0] ? find_held_lock+0x2b/0x80 [ 105.905077][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 105.905079][ C0] ? rcu_is_watching+0x16/0xd0 [ 105.905081][ C0] __run_timers+0x68f/0xaa0 [ 105.905083][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 105.905085][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 105.905087][ C0] ? __lock_acquire+0x518/0xc20 [ 105.905088][ C0] ? trace_rcu_watching+0x80/0x1f0 [ 105.905093][ C0] ? __rwlock_init+0x150/0x150 [ 105.905095][ C0] run_timer_softirq+0xf0/0x160 [ 105.905097][ C0] ? __run_timers+0xaa0/0xaa0 [ 105.905099][ C0] ? handle_softirqs+0x19c/0x900 [ 105.905100][ C0] ? rcu_is_watching+0x16/0xd0 [ 105.905102][ C0] handle_softirqs+0x1d3/0x900 [ 105.905104][ C0] ? _local_bh_enable+0xc0/0xc0 [ 105.905105][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 105.905107][ C0] __irq_exit_rcu+0x145/0x1c0 [ 105.905109][ C0] irq_exit_rcu+0xe/0x30 [ 105.905110][ C0] common_interrupt+0xb5/0xf0 [ 105.905111][ C0] [ 105.905112][ C0] [ 105.905113][ C0] ? sysvec_apic_timer_interrupt+0xaa/0xe0 [ 105.905114][ C0] asm_common_interrupt+0x26/0x40 [ 105.905116][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 105.905119][ C0] Code: 48 8b 3d 94 a2 10 02 e8 1f 00 00 00 48 2b 05 58 f3 ab 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 d6 25 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 105.905121][ C0] RSP: 0018:ffffffffb4807cf8 EFLAGS: 00000296 [ 105.905123][ C0] RAX: 0000000000571373 RBX: ffffffffb4840600 RCX: ffffffffb16f3307 [ 105.905125][ C0] RDX: ffffffffb4840600 RSI: ffffffffb4657571 RDI: ffffffffb408d8e0 [ 105.905126][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 105.905127][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff6900fa2 [ 105.905127][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 105.905129][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 105.905132][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 105.905133][ C0] default_idle+0x9/0x10 [ 105.905135][ C0] default_idle_call+0x6e/0xb0 [ 105.905137][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 105.905138][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 105.905140][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 105.905143][ C0] ? rcu_is_watching+0x16/0xd0 [ 105.905145][ C0] do_idle+0xd8/0x190 [ 105.905146][ C0] cpu_startup_entry+0x53/0x70 [ 105.905148][ C0] rest_init+0x279/0x280 [ 105.905150][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 105.905153][ C0] ? rest_init+0x280/0x280 [ 105.905155][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 105.905159][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 105.905161][ C0] ? acpi_enable+0x1e4/0x330 [ 105.905163][ C0] start_kernel+0x3af/0x3b0 [ 105.905164][ C0] x86_64_start_reservations+0x24/0x30 [ 105.905166][ C0] x86_64_start_kernel+0x12b/0x130 [ 105.905168][ C0] common_startup_64+0x13e/0x148 [ 105.905172][ C0] [ 105.905176][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 105.905177][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 105.905178][ C0] preempt_count: 103, expected: 0 [ 105.905179][ C0] RCU nest depth: 0, expected: 0 [ 105.905180][ C0] INFO: lockdep is turned off. [ 105.905180][ C0] irq event stamp: 5715547 [ 105.905181][ C0] hardirqs last enabled at (5715546): [] asm_common_interrupt+0x26/0x40 [ 105.905183][ C0] hardirqs last disabled at (5715547): [] console_emit_next_record+0x3d4/0x4c0 [ 105.905185][ C0] softirqs last enabled at (5715446): [] handle_softirqs+0x67c/0x900 [ 105.905186][ C0] softirqs last disabled at (5715449): [] __irq_exit_rcu+0x145/0x1c0 [ 105.905187][ C0] Preemption disabled at: [ 105.905188][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 105.905191][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 105.905193][ C0] Tainted: [W]=WARN [ 105.905193][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 105.905194][ C0] Call Trace: [ 105.905194][ C0] [ 105.905195][ C0] dump_stack_lvl+0x6f/0xa0 [ 105.905197][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 105.905198][ C0] __might_resched.cold+0x1fe/0x2c1 [ 105.905201][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 105.905204][ C0] ? rcu_is_watching+0x16/0xd0 [ 105.905206][ C0] __kmalloc_noprof+0x443/0x760 [ 105.905208][ C0] ? __rwlock_init+0x150/0x150 [ 105.905209][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 105.905212][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 105.905213][ C0] alloc_buf.isra.0+0x4b/0x260 [ 105.905216][ C0] put_chars+0x1e1/0x2f0 [ 105.905218][ C0] ? __send_to_port+0x420/0x420 [ 105.905221][ C0] ? mark_lock+0x1d7/0xa00 [ 105.905223][ C0] hvc_console_print+0x292/0x780 [ 105.905226][ C0] ? hvc_write+0x3a0/0x3a0 [ 105.905228][ C0] ? rcu_is_watching+0x16/0xd0 [ 105.905230][ C0] ? rcu_is_watching+0x16/0xd0 [ 105.905232][ C0] console_emit_next_record+0x22f/0x4c0 [ 105.905234][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 105.905244][ C0] ? console_flush_one_record+0x106/0x710 [ 105.905246][ C0] ? rcu_is_watching+0x16/0xd0 [ 105.905247][ C0] ? lock_acquire+0x13c/0x160 [ 105.905249][ C0] console_flush_one_record+0x46f/0x710 [ 105.905252][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 105.905253][ C0] ? __lock_acquire+0x518/0xc20 [ 105.905256][ C0] console_unlock+0xee/0x1f0 [ 105.905257][ C0] ? console_flush_one_record+0x710/0x710 [ 105.905259][ C0] ? rcu_is_watching+0x16/0xd0 [ 105.905261][ C0] ? lock_acquire+0x60/0x160 [ 105.905263][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 105.905264][ C0] ? vprintk_emit+0x320/0x3e0 [ 105.905266][ C0] vprintk_emit+0x37c/0x3e0 [ 105.905268][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 105.905271][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 105.905272][ C0] _printk+0xc7/0x100 [ 105.905274][ C0] ? snapshot_read.cold+0x21/0x21 [ 105.905276][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 105.905277][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 105.905280][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 105.905281][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 105.905283][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 105.905285][ C0] call_timer_fn+0x160/0x4d0 [ 105.905287][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 105.905288][ C0] ? debug_object_active_state+0x430/0x430 [ 105.905289][ C0] ? find_held_lock+0x2b/0x80 [ 105.905292][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 105.905293][ C0] ? rcu_is_watching+0x16/0xd0 [ 105.905296][ C0] __run_timers+0x68f/0xaa0 [ 105.905297][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 105.905300][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 105.905301][ C0] ? __lock_acquire+0x518/0xc20 [ 105.905302][ C0] ? trace_rcu_watching+0x80/0x1f0 [ 105.905305][ C0] ? __rwlock_init+0x150/0x150 [ 105.905308][ C0] run_timer_softirq+0xf0/0x160 [ 105.905310][ C0] ? __run_timers+0xaa0/0xaa0 [ 105.905311][ C0] ? handle_softirqs+0x19c/0x900 [ 105.905313][ C0] ? rcu_is_watching+0x16/0xd0 [ 105.905314][ C0] handle_softirqs+0x1d3/0x900 [ 105.905316][ C0] ? _local_bh_enable+0xc0/0xc0 [ 105.905318][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 105.905320][ C0] __irq_exit_rcu+0x145/0x1c0 [ 105.905321][ C0] irq_exit_rcu+0xe/0x30 [ 105.905322][ C0] common_interrupt+0xb5/0xf0 [ 105.905324][ C0] [ 105.905325][ C0] [ 105.905325][ C0] ? sysvec_apic_timer_interrupt+0xaa/0xe0 [ 105.905327][ C0] asm_common_interrupt+0x26/0x40 [ 105.905328][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 105.905330][ C0] Code: 48 8b 3d 94 a2 10 02 e8 1f 00 00 00 48 2b 05 58 f3 ab 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 d6 25 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 105.905331][ C0] RSP: 0018:ffffffffb4807cf8 EFLAGS: 00000296 [ 105.905333][ C0] RAX: 0000000000571373 RBX: ffffffffb4840600 RCX: ffffffffb16f3307 [ 105.905333][ C0] RDX: ffffffffb4840600 RSI: ffffffffb4657571 RDI: ffffffffb408d8e0 [ 105.905334][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 105.905335][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff6900fa2 [ 105.905335][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 105.905337][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 105.905340][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 105.905341][ C0] default_idle+0x9/0x10 [ 105.905343][ C0] default_idle_call+0x6e/0xb0 [ 105.905345][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 105.905346][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 105.905348][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 105.905350][ C0] ? rcu_is_watching+0x16/0xd0 [ 105.905352][ C0] do_idle+0xd8/0x190 [ 105.905353][ C0] cpu_startup_entry+0x53/0x70 [ 105.905355][ C0] rest_init+0x279/0x280 [ 105.905357][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 105.905360][ C0] ? rest_init+0x280/0x280 [ 105.905362][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 105.905364][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 105.905366][ C0] ? acpi_enable+0x1e4/0x330 [ 105.905367][ C0] start_kernel+0x3af/0x3b0 [ 105.905369][ C0] x86_64_start_reservations+0x24/0x30 [ 105.905371][ C0] x86_64_start_kernel+0x12b/0x130 [ 105.905373][ C0] common_startup_64+0x13e/0x148 [ 105.905376][ C0]