[ 10.343539][ T206] ip (206) used greatest stack depth: 24368 bytes left [ 10.343556][ T206] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.343558][ T206] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 206, name: ip [ 10.343560][ T206] preempt_count: 2, expected: 0 [ 10.343560][ T206] RCU nest depth: 0, expected: 0 [ 10.343561][ T206] locks held by ip/206: 5, last CPU#3: [ 10.343563][ T206] #0: ffffffffb5e167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.343575][ T206] #1: ffffffffb5f7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.343580][ T206] #2: ffffffffb5f7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.343585][ T206] #3: ffffffffb5e9d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.343588][ T206] #4: ffffffffb5e9d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.343592][ T206] irq event stamp: 28072 [ 10.343593][ T206] hardirqs last enabled at (28071): [] __down_trylock_console_sem+0x86/0xa0 [ 10.343597][ T206] hardirqs last disabled at (28072): [] console_emit_next_record+0x3d4/0x4c0 [ 10.343599][ T206] softirqs last enabled at (27400): [] handle_softirqs+0x67c/0x900 [ 10.343601][ T206] softirqs last disabled at (27395): [] __irq_exit_rcu+0x145/0x1c0 [ 10.343604][ T206] Preemption disabled at: [ 10.343604][ T206] [<0000000000000000>] 0x0 [ 10.343611][ T206] CPU: 3 UID: 0 PID: 206 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.343614][ T206] Tainted: [W]=WARN [ 10.343615][ T206] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.343617][ T206] Call Trace: [ 10.343618][ T206] [ 10.343619][ T206] dump_stack_lvl+0x6f/0xa0 [ 10.343626][ T206] __might_resched.cold+0x1fe/0x2c1 [ 10.343630][ T206] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.343634][ T206] ? __kmalloc_noprof+0xdb/0x760 [ 10.343639][ T206] __kmalloc_noprof+0x443/0x760 [ 10.343641][ T206] ? alloc_buf.isra.0+0x4b/0x260 [ 10.343648][ T206] ? do_raw_spin_unlock+0x59/0x250 [ 10.343651][ T206] alloc_buf.isra.0+0x4b/0x260 [ 10.343655][ T206] put_chars+0x1e1/0x2f0 [ 10.343657][ T206] ? __send_to_port+0x420/0x420 [ 10.343658][ T206] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.343662][ T206] ? validate_chain+0x38b/0xc20 [ 10.343667][ T206] hvc_console_print+0x292/0x780 [ 10.343675][ T206] ? hvc_write+0x3a0/0x3a0 [ 10.343678][ T206] ? rcu_is_watching+0x16/0xd0 [ 10.343680][ T206] ? lock_acquire+0x13c/0x160 [ 10.343684][ T206] console_emit_next_record+0x22f/0x4c0 [ 10.343688][ T206] ? devkmsg_read+0x4b0/0x4b0 [ 10.343690][ T206] ? console_flush_one_record+0x106/0x710 [ 10.343693][ T206] ? rcu_is_watching+0x16/0xd0 [ 10.343695][ T206] ? lock_acquire+0x13c/0x160 [ 10.343699][ T206] console_flush_one_record+0x46f/0x710 [ 10.343703][ T206] ? console_emit_next_record+0x4c0/0x4c0 [ 10.343705][ T206] ? __lock_acquire+0x518/0xc20 [ 10.343710][ T206] console_unlock+0xee/0x1f0 [ 10.343713][ T206] ? console_flush_one_record+0x710/0x710 [ 10.343715][ T206] ? rcu_is_watching+0x16/0xd0 [ 10.343717][ T206] ? lock_acquire+0xe0/0x160 [ 10.343721][ T206] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.343722][ T206] ? vprintk_emit+0x320/0x3e0 [ 10.343725][ T206] vprintk_emit+0x37c/0x3e0 [ 10.343729][ T206] ? wake_up_klogd_work_func+0x90/0x90 [ 10.343733][ T206] ? __lock_acquire+0x518/0xc20 [ 10.343737][ T206] _printk+0xc7/0x100 [ 10.343741][ T206] ? snapshot_read.cold+0x21/0x21 [ 10.343744][ T206] ? do_raw_spin_lock+0x131/0x280 [ 10.343747][ T206] ? __rwlock_init+0x150/0x150 [ 10.343751][ T206] ? do_raw_spin_lock+0x131/0x280 [ 10.343753][ T206] do_exit.cold+0x82/0x9c [ 10.343757][ T206] ? exit_notify+0x890/0x890 [ 10.343759][ T206] ? __lock_release.isra.0+0x69/0x1a0 [ 10.343762][ T206] ? rcu_is_watching+0x16/0xd0 [ 10.343765][ T206] do_group_exit+0xb8/0x370 [ 10.343768][ T206] __x64_sys_exit_group+0x3c/0x50 [ 10.343770][ T206] x64_sys_call+0x1567/0x1570 [ 10.343772][ T206] do_syscall_64+0xff/0x530 [ 10.343776][ T206] ? exc_page_fault+0xee/0x100 [ 10.343779][ T206] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.343781][ T206] RIP: 0033:0x7fbf432e61b8 [ 10.343784][ T206] Code: Unable to access opcode bytes at 0x7fbf432e618e. [ 10.343785][ T206] RSP: 002b:00007ffca19f6e38 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.343787][ T206] RAX: ffffffffffffffda RBX: 00007fbf43416f88 RCX: 00007fbf432e61b8 [ 10.343789][ T206] RDX: 00007fbf43030fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.343790][ T206] RBP: 00007ffca19f6e90 R08: 0000000000000000 R09: 0000000000000050 [ 10.343790][ T206] R10: 00007ffca19f6c50 R11: 0000000000000246 R12: 0000000000000001 [ 10.343791][ T206] R13: 0000000000000000 R14: 00007fbf43415680 R15: 00007fbf43416fa0 [ 10.343798][ T206] [ 10.577447][ T210] ip (210) used greatest stack depth: 23952 bytes left [ 29.753458][ T2209] ip (2209) used greatest stack depth: 23792 bytes left [ 29.753477][ T2209] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 29.753480][ T2209] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 2209, name: ip [ 29.753481][ T2209] preempt_count: 2, expected: 0 [ 29.753482][ T2209] RCU nest depth: 0, expected: 0 [ 29.753483][ T2209] locks held by ip/2209: 5, last CPU#1: [ 29.753485][ T2209] #0: ffffffffb5e167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 29.753496][ T2209] #1: ffffffffb5f7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 29.753501][ T2209] #2: ffffffffb5f7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 29.753506][ T2209] #3: ffffffffb5e9d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 29.753510][ T2209] #4: ffffffffb5e9d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 29.753514][ T2209] irq event stamp: 47296 [ 29.753515][ T2209] hardirqs last enabled at (47295): [] __down_trylock_console_sem+0x86/0xa0 [ 29.753518][ T2209] hardirqs last disabled at (47296): [] console_emit_next_record+0x3d4/0x4c0 [ 29.753520][ T2209] softirqs last enabled at (47290): [] handle_softirqs+0x67c/0x900 [ 29.753523][ T2209] softirqs last disabled at (45761): [] __irq_exit_rcu+0x145/0x1c0 [ 29.753525][ T2209] Preemption disabled at: [ 29.753526][ T2209] [<0000000000000000>] 0x0 [ 29.753532][ T2209] CPU: 1 UID: 0 PID: 2209 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 29.753536][ T2209] Tainted: [W]=WARN [ 29.753537][ T2209] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 29.753538][ T2209] Call Trace: [ 29.753540][ T2209] [ 29.753541][ T2209] dump_stack_lvl+0x6f/0xa0 [ 29.753547][ T2209] __might_resched.cold+0x1fe/0x2c1 [ 29.753552][ T2209] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 29.753556][ T2209] ? __kmalloc_noprof+0xdb/0x760 [ 29.753561][ T2209] __kmalloc_noprof+0x443/0x760 [ 29.753563][ T2209] ? alloc_buf.isra.0+0x4b/0x260 [ 29.753570][ T2209] ? do_raw_spin_unlock+0x59/0x250 [ 29.753573][ T2209] alloc_buf.isra.0+0x4b/0x260 [ 29.753576][ T2209] put_chars+0x1e1/0x2f0 [ 29.753579][ T2209] ? __send_to_port+0x420/0x420 [ 29.753580][ T2209] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 29.753584][ T2209] ? validate_chain+0x38b/0xc20 [ 29.753589][ T2209] hvc_console_print+0x292/0x780 [ 29.753597][ T2209] ? hvc_write+0x3a0/0x3a0 [ 29.753600][ T2209] ? rcu_is_watching+0x16/0xd0 [ 29.753602][ T2209] ? lock_acquire+0x13c/0x160 [ 29.753606][ T2209] console_emit_next_record+0x22f/0x4c0 [ 29.753610][ T2209] ? devkmsg_read+0x4b0/0x4b0 [ 29.753612][ T2209] ? console_flush_one_record+0x106/0x710 [ 29.753616][ T2209] ? rcu_is_watching+0x16/0xd0 [ 29.753617][ T2209] ? lock_acquire+0x13c/0x160 [ 29.753622][ T2209] console_flush_one_record+0x46f/0x710 [ 29.753626][ T2209] ? console_emit_next_record+0x4c0/0x4c0 [ 29.753628][ T2209] ? __lock_acquire+0x518/0xc20 [ 29.753634][ T2209] console_unlock+0xee/0x1f0 [ 29.753637][ T2209] ? console_flush_one_record+0x710/0x710 [ 29.753639][ T2209] ? rcu_is_watching+0x16/0xd0 [ 29.753640][ T2209] ? lock_acquire+0xe0/0x160 [ 29.753644][ T2209] ? __down_trylock_console_sem+0x5e/0xa0 [ 29.753646][ T2209] ? vprintk_emit+0x320/0x3e0 [ 29.753649][ T2209] vprintk_emit+0x37c/0x3e0 [ 29.753652][ T2209] ? wake_up_klogd_work_func+0x90/0x90 [ 29.753654][ T2209] ? _raw_spin_lock+0x33/0x40 [ 29.753659][ T2209] ? __lock_acquire+0x518/0xc20 [ 29.753662][ T2209] _printk+0xc7/0x100 [ 29.753666][ T2209] ? snapshot_read.cold+0x21/0x21 [ 29.753669][ T2209] ? do_raw_spin_lock+0x131/0x280 [ 29.753672][ T2209] ? __rwlock_init+0x150/0x150 [ 29.753676][ T2209] ? do_raw_spin_lock+0x131/0x280 [ 29.753679][ T2209] do_exit.cold+0x82/0x9c [ 29.753683][ T2209] ? exit_notify+0x890/0x890 [ 29.753684][ T2209] ? __lock_release.isra.0+0x69/0x1a0 [ 29.753687][ T2209] ? rcu_is_watching+0x16/0xd0 [ 29.753691][ T2209] do_group_exit+0xb8/0x370 [ 29.753694][ T2209] __x64_sys_exit_group+0x3c/0x50 [ 29.753695][ T2209] x64_sys_call+0x1567/0x1570 [ 29.753698][ T2209] do_syscall_64+0xff/0x530 [ 29.753701][ T2209] ? exc_page_fault+0xee/0x100 [ 29.753704][ T2209] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.753707][ T2209] RIP: 0033:0x7f308c8ae1b8 [ 29.753709][ T2209] Code: Unable to access opcode bytes at 0x7f308c8ae18e. [ 29.753710][ T2209] RSP: 002b:00007fff3db4fd78 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 29.753712][ T2209] RAX: ffffffffffffffda RBX: 00007f308c9def88 RCX: 00007f308c8ae1b8 [ 29.753714][ T2209] RDX: 00007f308c5f8fc8 RSI: fffffffffffffeb8 RDI: 0000000000000002 [ 29.753715][ T2209] RBP: 00007fff3db4fdd0 R08: 0000000000000000 R09: 0000000000008010 [ 29.753716][ T2209] R10: 00007fff3db4fb90 R11: 0000000000000246 R12: 0000000000000001 [ 29.753717][ T2209] R13: 0000000000000002 R14: 00007f308c9dd680 R15: 00007f308c9defa0 [ 29.753724][ T2209] [ 30.517290][ T2358] ip (2358) used greatest stack depth: 23376 bytes left [ 85.532865][ C0] clocksource: Watchdog remote CPU 1 read timed out [ 85.533267][ C0] [ 85.533269][ C0] ======================================================== [ 85.533270][ C0] WARNING: possible irq lock inversion dependency detected [ 85.533272][ C0] 7.2.0-virtme #1 Tainted: G W [ 85.533274][ C0] -------------------------------------------------------- [ 85.533274][ C0] swapper/0/0 just changed the state of lock: [ 85.533276][ C0] ffffffffb5e9d760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 85.533289][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 85.533290][ C0] (fs_reclaim){+.+.}-{0:0} [ 85.533292][ C0] [ 85.533292][ C0] [ 85.533292][ C0] and interrupts could create inverse lock ordering between them. [ 85.533292][ C0] [ 85.533292][ C0] [ 85.533292][ C0] other info that might help us debug this: [ 85.533293][ C0] Possible interrupt unsafe locking scenario: [ 85.533293][ C0] [ 85.533293][ C0] CPU0 CPU1 [ 85.533294][ C0] ---- ---- [ 85.533294][ C0] lock(fs_reclaim); [ 85.533295][ C0] local_irq_disable(); [ 85.533296][ C0] lock(console_owner); [ 85.533297][ C0] lock(fs_reclaim); [ 85.533297][ C0] [ 85.533298][ C0] lock(console_owner); [ 85.533298][ C0] [ 85.533298][ C0] *** DEADLOCK *** [ 85.533298][ C0] [ 85.533299][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 85.533300][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 85.533306][ C0] #1: ffffffffb5fe29f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 85.533310][ C0] #2: ffffffffb5f7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 85.533313][ C0] #3: ffffffffb5f7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 85.533316][ C0] [ 85.533316][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 85.533320][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 85.533323][ C0] HARDIRQ-ON-W at: [ 85.533324][ C0] __lock_acquire+0x388/0xc20 [ 85.533327][ C0] lock_acquire.part.0+0xd4/0x280 [ 85.533329][ C0] fs_reclaim_acquire+0xd5/0x120 [ 85.533332][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 85.533334][ C0] kthread_create_worker_on_node+0xea/0x210 [ 85.533337][ C0] workqueue_init+0x2a/0x680 [ 85.533342][ C0] kernel_init_freeable+0x2fe/0x630 [ 85.533344][ C0] kernel_init+0x21/0x150 [ 85.533348][ C0] ret_from_fork+0x474/0x6b0 [ 85.533351][ C0] ret_from_fork_asm+0x11/0x20 [ 85.533354][ C0] SOFTIRQ-ON-W at: [ 85.533355][ C0] __lock_acquire+0x388/0xc20 [ 85.533357][ C0] lock_acquire.part.0+0xd4/0x280 [ 85.533358][ C0] fs_reclaim_acquire+0xd5/0x120 [ 85.533360][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 85.533361][ C0] kthread_create_worker_on_node+0xea/0x210 [ 85.533362][ C0] workqueue_init+0x2a/0x680 [ 85.533364][ C0] kernel_init_freeable+0x2fe/0x630 [ 85.533365][ C0] kernel_init+0x21/0x150 [ 85.533366][ C0] ret_from_fork+0x474/0x6b0 [ 85.533368][ C0] ret_from_fork_asm+0x11/0x20 [ 85.533369][ C0] INITIAL USE at: [ 85.533370][ C0] __lock_acquire+0x388/0xc20 [ 85.533371][ C0] lock_acquire.part.0+0xd4/0x280 [ 85.533373][ C0] fs_reclaim_acquire+0xd5/0x120 [ 85.533374][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 85.533375][ C0] kthread_create_worker_on_node+0xea/0x210 [ 85.533377][ C0] workqueue_init+0x2a/0x680 [ 85.533378][ C0] kernel_init_freeable+0x2fe/0x630 [ 85.533380][ C0] kernel_init+0x21/0x150 [ 85.533381][ C0] ret_from_fork+0x474/0x6b0 [ 85.533383][ C0] ret_from_fork_asm+0x11/0x20 [ 85.533384][ C0] } [ 85.533384][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 85.533388][ C0] ... acquired at: [ 85.533389][ C0] __lock_acquire+0x518/0xc20 [ 85.533391][ C0] lock_acquire.part.0+0xd4/0x280 [ 85.533392][ C0] fs_reclaim_acquire+0xd5/0x120 [ 85.533393][ C0] __kmalloc_noprof+0xd3/0x760 [ 85.533394][ C0] alloc_buf.isra.0+0x4b/0x260 [ 85.533398][ C0] put_chars+0x1e1/0x2f0 [ 85.533399][ C0] hvc_console_print+0x292/0x780 [ 85.533403][ C0] console_emit_next_record+0x22f/0x4c0 [ 85.533404][ C0] console_flush_one_record+0x46f/0x710 [ 85.533406][ C0] console_unlock+0xee/0x1f0 [ 85.533408][ C0] vprintk_emit+0x37c/0x3e0 [ 85.533408][ C0] _printk+0xc7/0x100 [ 85.533411][ C0] tun_init+0x24/0xb0 [ 85.533414][ C0] do_one_initcall+0x124/0x4f0 [ 85.533415][ C0] kernel_init_freeable+0x596/0x630 [ 85.533417][ C0] kernel_init+0x21/0x150 [ 85.533418][ C0] ret_from_fork+0x474/0x6b0 [ 85.533419][ C0] ret_from_fork_asm+0x11/0x20 [ 85.533421][ C0] [ 85.533421][ C0] -> (console_owner){..-.}-{0:0} { [ 85.533423][ C0] IN-SOFTIRQ-W at: [ 85.533424][ C0] __lock_acquire+0x388/0xc20 [ 85.533425][ C0] lock_acquire.part.0+0xd4/0x280 [ 85.533427][ C0] console_lock_spinning_enable+0x5c/0x60 [ 85.533428][ C0] console_emit_next_record+0x1d1/0x4c0 [ 85.533430][ C0] console_flush_one_record+0x46f/0x710 [ 85.533432][ C0] console_unlock+0xee/0x1f0 [ 85.533433][ C0] vprintk_emit+0x37c/0x3e0 [ 85.533434][ C0] _printk+0xc7/0x100 [ 85.533435][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 85.533438][ C0] call_timer_fn+0x160/0x4d0 [ 85.533440][ C0] __run_timers+0x68f/0xaa0 [ 85.533442][ C0] run_timer_softirq+0xf0/0x160 [ 85.533443][ C0] handle_softirqs+0x1d3/0x900 [ 85.533446][ C0] __irq_exit_rcu+0x145/0x1c0 [ 85.533448][ C0] irq_exit_rcu+0xe/0x30 [ 85.533449][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 85.533451][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 85.533453][ C0] pv_native_safe_halt+0xf/0x10 [ 85.533454][ C0] default_idle+0x9/0x10 [ 85.533456][ C0] default_idle_call+0x6e/0xb0 [ 85.533457][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 85.533459][ C0] do_idle+0xd8/0x190 [ 85.533461][ C0] cpu_startup_entry+0x53/0x70 [ 85.533462][ C0] rest_init+0x279/0x280 [ 85.533463][ C0] start_kernel+0x3b9/0x3c0 [ 85.533465][ C0] x86_64_start_reservations+0x24/0x30 [ 85.533467][ C0] x86_64_start_kernel+0x12b/0x130 [ 85.533468][ C0] common_startup_64+0x13e/0x148 [ 85.533471][ C0] INITIAL USE at: [ 85.533472][ C0] } [ 85.533472][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 85.533474][ C0] ... acquired at: [ 85.533475][ C0] mark_lock+0x1d7/0xa00 [ 85.533476][ C0] mark_usage+0x42/0x170 [ 85.533478][ C0] __lock_acquire+0x388/0xc20 [ 85.533479][ C0] lock_acquire.part.0+0xd4/0x280 [ 85.533480][ C0] console_lock_spinning_enable+0x5c/0x60 [ 85.533482][ C0] console_emit_next_record+0x1d1/0x4c0 [ 85.533484][ C0] console_flush_one_record+0x46f/0x710 [ 85.533485][ C0] console_unlock+0xee/0x1f0 [ 85.533487][ C0] vprintk_emit+0x37c/0x3e0 [ 85.533488][ C0] _printk+0xc7/0x100 [ 85.533489][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 85.533490][ C0] call_timer_fn+0x160/0x4d0 [ 85.533492][ C0] __run_timers+0x68f/0xaa0 [ 85.533493][ C0] run_timer_softirq+0xf0/0x160 [ 85.533500][ C0] handle_softirqs+0x1d3/0x900 [ 85.533501][ C0] __irq_exit_rcu+0x145/0x1c0 [ 85.533503][ C0] irq_exit_rcu+0xe/0x30 [ 85.533504][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 85.533505][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 85.533506][ C0] pv_native_safe_halt+0xf/0x10 [ 85.533508][ C0] default_idle+0x9/0x10 [ 85.533509][ C0] default_idle_call+0x6e/0xb0 [ 85.533510][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 85.533512][ C0] do_idle+0xd8/0x190 [ 85.533513][ C0] cpu_startup_entry+0x53/0x70 [ 85.533514][ C0] rest_init+0x279/0x280 [ 85.533515][ C0] start_kernel+0x3b9/0x3c0 [ 85.533517][ C0] x86_64_start_reservations+0x24/0x30 [ 85.533518][ C0] x86_64_start_kernel+0x12b/0x130 [ 85.533519][ C0] common_startup_64+0x13e/0x148 [ 85.533521][ C0] [ 85.533521][ C0] [ 85.533521][ C0] stack backtrace: [ 85.533524][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 85.533527][ C0] Tainted: [W]=WARN [ 85.533528][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 85.533529][ C0] Call Trace: [ 85.533531][ C0] [ 85.533532][ C0] dump_stack_lvl+0x6f/0xa0 [ 85.533536][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 85.533539][ C0] mark_lock_irq+0x989/0x9c0 [ 85.533540][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 85.533543][ C0] mark_lock+0x1d7/0xa00 [ 85.533545][ C0] mark_usage+0x42/0x170 [ 85.533547][ C0] __lock_acquire+0x388/0xc20 [ 85.533549][ C0] lock_acquire.part.0+0xd4/0x280 [ 85.533551][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 85.533553][ C0] ? rcu_is_watching+0x16/0xd0 [ 85.533555][ C0] ? lock_acquire+0x13c/0x160 [ 85.533558][ C0] console_lock_spinning_enable+0x5c/0x60 [ 85.533559][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 85.533561][ C0] console_emit_next_record+0x1d1/0x4c0 [ 85.533563][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 85.533565][ C0] ? console_flush_one_record+0x106/0x710 [ 85.533567][ C0] ? rcu_is_watching+0x16/0xd0 [ 85.533569][ C0] ? lock_acquire+0x13c/0x160 [ 85.533571][ C0] console_flush_one_record+0x46f/0x710 [ 85.533574][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 85.533576][ C0] ? __lock_acquire+0x518/0xc20 [ 85.533579][ C0] console_unlock+0xee/0x1f0 [ 85.533581][ C0] ? console_flush_one_record+0x710/0x710 [ 85.533582][ C0] ? rcu_is_watching+0x16/0xd0 [ 85.533583][ C0] ? lock_acquire+0xe0/0x160 [ 85.533586][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 85.533588][ C0] ? vprintk_emit+0x320/0x3e0 [ 85.533589][ C0] vprintk_emit+0x37c/0x3e0 [ 85.533590][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 85.533592][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 85.533594][ C0] _printk+0xc7/0x100 [ 85.533596][ C0] ? snapshot_read.cold+0x21/0x21 [ 85.533598][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 85.533600][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 85.533602][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 85.533603][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 85.533605][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 85.533607][ C0] call_timer_fn+0x160/0x4d0 [ 85.533609][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 85.533611][ C0] ? debug_object_active_state+0x430/0x430 [ 85.533614][ C0] ? find_held_lock+0x2b/0x80 [ 85.533616][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 85.533618][ C0] ? rcu_is_watching+0x16/0xd0 [ 85.533619][ C0] __run_timers+0x68f/0xaa0 [ 85.533621][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 85.533623][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 85.533625][ C0] ? __lock_acquire+0x518/0xc20 [ 85.533628][ C0] ? __rwlock_init+0x150/0x150 [ 85.533631][ C0] run_timer_softirq+0xf0/0x160 [ 85.533633][ C0] ? __run_timers+0xaa0/0xaa0 [ 85.533634][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 85.533637][ C0] ? rcu_is_watching+0x16/0xd0 [ 85.533638][ C0] handle_softirqs+0x1d3/0x900 [ 85.533640][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 85.533642][ C0] ? _local_bh_enable+0xc0/0xc0 [ 85.533644][ C0] __irq_exit_rcu+0x145/0x1c0 [ 85.533646][ C0] irq_exit_rcu+0xe/0x30 [ 85.533648][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 85.533649][ C0] [ 85.533650][ C0] [ 85.533650][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 85.533652][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 85.533654][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 85.533657][ 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 [ 85.533658][ C0] RSP: 0018:ffffffffb5c07cf8 EFLAGS: 00000296 [ 85.533661][ C0] RAX: 00000000001de33f RBX: ffffffffb5c30600 RCX: ffffffffb2306247 [ 85.533662][ C0] RDX: ffffffffb5c30600 RSI: ffffffffb5511011 RDI: ffffffffb4e949e0 [ 85.533663][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 85.533664][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff6b80fa2 [ 85.533664][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 85.533666][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 85.533668][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 85.533670][ C0] default_idle+0x9/0x10 [ 85.533671][ C0] default_idle_call+0x6e/0xb0 [ 85.533673][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 85.533675][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 85.533676][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 85.533679][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 85.533680][ C0] ? rcu_is_watching+0x16/0xd0 [ 85.533682][ C0] do_idle+0xd8/0x190 [ 85.533683][ C0] cpu_startup_entry+0x53/0x70 [ 85.533685][ C0] rest_init+0x279/0x280 [ 85.533687][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 85.533689][ C0] ? rest_init+0x280/0x280 [ 85.533691][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 85.533694][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 85.533696][ C0] ? acpi_enable+0x1e4/0x330 [ 85.533698][ C0] start_kernel+0x3b9/0x3c0 [ 85.533700][ C0] x86_64_start_reservations+0x24/0x30 [ 85.533701][ C0] x86_64_start_kernel+0x12b/0x130 [ 85.533703][ C0] common_startup_64+0x13e/0x148 [ 85.533706][ C0] [ 85.533711][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 85.533712][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 85.533713][ C0] preempt_count: 103, expected: 0 [ 85.533714][ C0] RCU nest depth: 0, expected: 0 [ 85.533715][ C0] INFO: lockdep is turned off. [ 85.533716][ C0] irq event stamp: 1958735 [ 85.533716][ C0] hardirqs last enabled at (1958734): [] __down_trylock_console_sem+0x86/0xa0 [ 85.533719][ C0] hardirqs last disabled at (1958735): [] console_emit_next_record+0x3d4/0x4c0 [ 85.533721][ C0] softirqs last enabled at (1958708): [] handle_softirqs+0x67c/0x900 [ 85.533722][ C0] softirqs last disabled at (1958721): [] __irq_exit_rcu+0x145/0x1c0 [ 85.533724][ C0] Preemption disabled at: [ 85.533725][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 85.533728][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 85.533733][ C0] Tainted: [W]=WARN [ 85.533734][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 85.533734][ C0] Call Trace: [ 85.533735][ C0] [ 85.533736][ C0] dump_stack_lvl+0x6f/0xa0 [ 85.533738][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 85.533740][ C0] __might_resched.cold+0x1fe/0x2c1 [ 85.533743][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 85.533746][ C0] ? rcu_is_watching+0x16/0xd0 [ 85.533747][ C0] __kmalloc_noprof+0x443/0x760 [ 85.533749][ C0] ? __rwlock_init+0x150/0x150 [ 85.533751][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 85.533753][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 85.533755][ C0] alloc_buf.isra.0+0x4b/0x260 [ 85.533758][ C0] put_chars+0x1e1/0x2f0 [ 85.533759][ C0] ? __send_to_port+0x420/0x420 [ 85.533761][ C0] ? mark_lock+0x1d7/0xa00 [ 85.533763][ C0] hvc_console_print+0x292/0x780 [ 85.533766][ C0] ? hvc_write+0x3a0/0x3a0 [ 85.533768][ C0] ? rcu_is_watching+0x16/0xd0 [ 85.533769][ C0] ? rcu_is_watching+0x16/0xd0 [ 85.533771][ C0] console_emit_next_record+0x22f/0x4c0 [ 85.533773][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 85.533775][ C0] ? console_flush_one_record+0x106/0x710 [ 85.533777][ C0] ? rcu_is_watching+0x16/0xd0 [ 85.533778][ C0] ? lock_acquire+0x13c/0x160 [ 85.533780][ C0] console_flush_one_record+0x46f/0x710 [ 85.533783][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 85.533785][ C0] ? __lock_acquire+0x518/0xc20 [ 85.533787][ C0] console_unlock+0xee/0x1f0 [ 85.533789][ C0] ? console_flush_one_record+0x710/0x710 [ 85.533791][ C0] ? rcu_is_watching+0x16/0xd0 [ 85.533792][ C0] ? lock_acquire+0xe0/0x160 [ 85.533794][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 85.533795][ C0] ? vprintk_emit+0x320/0x3e0 [ 85.533797][ C0] vprintk_emit+0x37c/0x3e0 [ 85.533798][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 85.533800][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 85.533802][ C0] _printk+0xc7/0x100 [ 85.533803][ C0] ? snapshot_read.cold+0x21/0x21 [ 85.533805][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 85.533807][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 85.533809][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 85.533811][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 85.533813][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 85.533814][ C0] call_timer_fn+0x160/0x4d0 [ 85.533816][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 85.533818][ C0] ? debug_object_active_state+0x430/0x430 [ 85.533820][ C0] ? find_held_lock+0x2b/0x80 [ 85.533821][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 85.533823][ C0] ? rcu_is_watching+0x16/0xd0 [ 85.533825][ C0] __run_timers+0x68f/0xaa0 [ 85.533826][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 85.533829][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 85.533830][ C0] ? __lock_acquire+0x518/0xc20 [ 85.533833][ C0] ? __rwlock_init+0x150/0x150 [ 85.533836][ C0] run_timer_softirq+0xf0/0x160 [ 85.533838][ C0] ? __run_timers+0xaa0/0xaa0 [ 85.533840][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 85.533841][ C0] ? rcu_is_watching+0x16/0xd0 [ 85.533842][ C0] handle_softirqs+0x1d3/0x900 [ 85.533844][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 85.533846][ C0] ? _local_bh_enable+0xc0/0xc0 [ 85.533848][ C0] __irq_exit_rcu+0x145/0x1c0 [ 85.533850][ C0] irq_exit_rcu+0xe/0x30 [ 85.533851][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 85.533853][ C0] [ 85.533853][ C0] [ 85.533854][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 85.533856][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 85.533857][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 85.533859][ 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 [ 85.533860][ C0] RSP: 0018:ffffffffb5c07cf8 EFLAGS: 00000296 [ 85.533861][ C0] RAX: 00000000001de33f RBX: ffffffffb5c30600 RCX: ffffffffb2306247 [ 85.533861][ C0] RDX: ffffffffb5c30600 RSI: ffffffffb5511011 RDI: ffffffffb4e949e0 [ 85.533862][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 85.533863][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff6b80fa2 [ 85.533863][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 85.533865][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 85.533867][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 85.533869][ C0] default_idle+0x9/0x10 [ 85.533870][ C0] default_idle_call+0x6e/0xb0 [ 85.533872][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 85.533873][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 85.533875][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 85.533877][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 85.533878][ C0] ? rcu_is_watching+0x16/0xd0 [ 85.533879][ C0] do_idle+0xd8/0x190 [ 85.533881][ C0] cpu_startup_entry+0x53/0x70 [ 85.533883][ C0] rest_init+0x279/0x280 [ 85.533884][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 85.533887][ C0] ? rest_init+0x280/0x280 [ 85.533889][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 85.533891][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 85.533892][ C0] ? acpi_enable+0x1e4/0x330 [ 85.533893][ C0] start_kernel+0x3b9/0x3c0 [ 85.533895][ C0] x86_64_start_reservations+0x24/0x30 [ 85.533897][ C0] x86_64_start_kernel+0x12b/0x130 [ 85.533899][ C0] common_startup_64+0x13e/0x148 [ 85.533902][ C0]