[ 10.507912][ T234] ip (234) used greatest stack depth: 23680 bytes left [ 10.507929][ T234] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.507930][ T234] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 234, name: ip [ 10.507932][ T234] preempt_count: 2, expected: 0 [ 10.507933][ T234] RCU nest depth: 0, expected: 0 [ 10.507933][ T234] locks held by ip/234: 5, last CPU#2: [ 10.507935][ T234] #0: ffffffff954267b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.507947][ T234] #1: ffffffff9558dcc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.507952][ T234] #2: ffffffff9558dd38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.507956][ T234] #3: ffffffff954ad660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.507960][ T234] #4: ffffffff954ad560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.507964][ T234] irq event stamp: 29450 [ 10.507964][ T234] hardirqs last enabled at (29449): [] __down_trylock_console_sem+0x86/0xa0 [ 10.507967][ T234] hardirqs last disabled at (29450): [] console_emit_next_record+0x3d4/0x4c0 [ 10.507968][ T234] softirqs last enabled at (28166): [] netlink_release+0x17b/0xcf0 [ 10.507972][ T234] softirqs last disabled at (28164): [] netlink_release+0xd2/0xcf0 [ 10.507975][ T234] Preemption disabled at: [ 10.507975][ T234] [<0000000000000000>] 0x0 [ 10.507982][ T234] CPU: 2 UID: 0 PID: 234 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.507986][ T234] Tainted: [W]=WARN [ 10.507986][ T234] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.507988][ T234] Call Trace: [ 10.507990][ T234] [ 10.507991][ T234] dump_stack_lvl+0x6f/0xa0 [ 10.507998][ T234] __might_resched.cold+0x1fe/0x2c1 [ 10.508002][ T234] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.508006][ T234] ? __kmalloc_noprof+0xdb/0x760 [ 10.508011][ T234] __kmalloc_noprof+0x443/0x760 [ 10.508013][ T234] ? alloc_buf.isra.0+0x4b/0x260 [ 10.508019][ T234] ? do_raw_spin_unlock+0x59/0x250 [ 10.508021][ T234] alloc_buf.isra.0+0x4b/0x260 [ 10.508025][ T234] put_chars+0x1e1/0x2f0 [ 10.508028][ T234] ? __send_to_port+0x420/0x420 [ 10.508030][ T234] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.508034][ T234] ? validate_chain+0x38b/0xc20 [ 10.508039][ T234] hvc_console_print+0x292/0x780 [ 10.508047][ T234] ? hvc_write+0x3a0/0x3a0 [ 10.508049][ T234] ? rcu_is_watching+0x16/0xd0 [ 10.508051][ T234] ? lock_acquire+0x13c/0x160 [ 10.508055][ T234] console_emit_next_record+0x22f/0x4c0 [ 10.508058][ T234] ? devkmsg_read+0x4b0/0x4b0 [ 10.508060][ T234] ? console_flush_one_record+0x106/0x710 [ 10.508063][ T234] ? rcu_is_watching+0x16/0xd0 [ 10.508065][ T234] ? lock_acquire+0x13c/0x160 [ 10.508068][ T234] console_flush_one_record+0x46f/0x710 [ 10.508072][ T234] ? console_emit_next_record+0x4c0/0x4c0 [ 10.508074][ T234] ? __lock_acquire+0x518/0xc20 [ 10.508079][ T234] console_unlock+0xee/0x1f0 [ 10.508081][ T234] ? console_flush_one_record+0x710/0x710 [ 10.508083][ T234] ? rcu_is_watching+0x16/0xd0 [ 10.508085][ T234] ? lock_acquire+0x60/0x160 [ 10.508088][ T234] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.508090][ T234] ? vprintk_emit+0x320/0x3e0 [ 10.508093][ T234] vprintk_emit+0x37c/0x3e0 [ 10.508096][ T234] ? wake_up_klogd_work_func+0x90/0x90 [ 10.508099][ T234] ? __lock_acquire+0x518/0xc20 [ 10.508103][ T234] _printk+0xc7/0x100 [ 10.508106][ T234] ? snapshot_read.cold+0x21/0x21 [ 10.508109][ T234] ? do_raw_spin_lock+0x131/0x280 [ 10.508111][ T234] ? __rwlock_init+0x150/0x150 [ 10.508115][ T234] ? do_raw_spin_lock+0x131/0x280 [ 10.508117][ T234] do_exit.cold+0x82/0x9c [ 10.508121][ T234] ? exit_notify+0x890/0x890 [ 10.508123][ T234] ? __lock_release.isra.0+0x69/0x1a0 [ 10.508125][ T234] ? rcu_is_watching+0x16/0xd0 [ 10.508129][ T234] do_group_exit+0xb8/0x370 [ 10.508132][ T234] __x64_sys_exit_group+0x3c/0x50 [ 10.508135][ T234] x64_sys_call+0x1567/0x1570 [ 10.508136][ T234] do_syscall_64+0xff/0x530 [ 10.508139][ T234] ? exc_page_fault+0xee/0x100 [ 10.508143][ T234] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.508145][ T234] RIP: 0033:0x7fd88cf971b8 [ 10.508147][ T234] Code: Unable to access opcode bytes at 0x7fd88cf9718e. [ 10.508148][ T234] RSP: 002b:00007ffec068fb28 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.508151][ T234] RAX: ffffffffffffffda RBX: 00007fd88d0c7f88 RCX: 00007fd88cf971b8 [ 10.508152][ T234] RDX: 00007fd88cce1fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.508153][ T234] RBP: 00007ffec068fb80 R08: 0000000000000000 R09: 0000000000000000 [ 10.508154][ T234] R10: 00007ffec068f940 R11: 0000000000000246 R12: 0000000000000001 [ 10.508155][ T234] R13: 0000000000000000 R14: 00007fd88d0c6680 R15: 00007fd88d0c7fa0 [ 10.508161][ T234] [ 59.881670][ C0] clocksource: Watchdog remote CPU 2 read timed out [ 59.881834][ C0] [ 59.881836][ C0] ======================================================== [ 59.881837][ C0] WARNING: possible irq lock inversion dependency detected [ 59.881839][ C0] 7.2.0-virtme #1 Tainted: G W [ 59.881840][ C0] -------------------------------------------------------- [ 59.881841][ C0] swapper/0/0 just changed the state of lock: [ 59.881842][ C0] ffffffff954ad660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 59.881855][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 59.881856][ C0] (fs_reclaim){+.+.}-{0:0} [ 59.881858][ C0] [ 59.881858][ C0] [ 59.881858][ C0] and interrupts could create inverse lock ordering between them. [ 59.881858][ C0] [ 59.881858][ C0] [ 59.881858][ C0] other info that might help us debug this: [ 59.881859][ C0] Possible interrupt unsafe locking scenario: [ 59.881859][ C0] [ 59.881859][ C0] CPU0 CPU1 [ 59.881860][ C0] ---- ---- [ 59.881860][ C0] lock(fs_reclaim); [ 59.881861][ C0] local_irq_disable(); [ 59.881862][ C0] lock(console_owner); [ 59.881863][ C0] lock(fs_reclaim); [ 59.881863][ C0] [ 59.881864][ C0] lock(console_owner); [ 59.881865][ C0] [ 59.881865][ C0] *** DEADLOCK *** [ 59.881865][ C0] [ 59.881865][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 59.881866][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 59.881871][ C0] #1: ffffffff955f28b8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 59.881875][ C0] #2: ffffffff9558dcc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 59.881879][ C0] #3: ffffffff9558dd38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 59.881882][ C0] [ 59.881882][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 59.881886][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 59.881888][ C0] HARDIRQ-ON-W at: [ 59.881890][ C0] __lock_acquire+0x388/0xc20 [ 59.881892][ C0] lock_acquire.part.0+0xd4/0x280 [ 59.881893][ C0] fs_reclaim_acquire+0xd5/0x120 [ 59.881897][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 59.881899][ C0] kthread_create_worker_on_node+0xea/0x210 [ 59.881902][ C0] workqueue_init+0x2a/0x680 [ 59.881906][ C0] kernel_init_freeable+0x2fe/0x630 [ 59.881908][ C0] kernel_init+0x21/0x150 [ 59.881911][ C0] ret_from_fork+0x474/0x6b0 [ 59.881915][ C0] ret_from_fork_asm+0x11/0x20 [ 59.881918][ C0] SOFTIRQ-ON-W at: [ 59.881918][ C0] __lock_acquire+0x388/0xc20 [ 59.881920][ C0] lock_acquire.part.0+0xd4/0x280 [ 59.881921][ C0] fs_reclaim_acquire+0xd5/0x120 [ 59.881922][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 59.881923][ C0] kthread_create_worker_on_node+0xea/0x210 [ 59.881925][ C0] workqueue_init+0x2a/0x680 [ 59.881926][ C0] kernel_init_freeable+0x2fe/0x630 [ 59.881927][ C0] kernel_init+0x21/0x150 [ 59.881928][ C0] ret_from_fork+0x474/0x6b0 [ 59.881930][ C0] ret_from_fork_asm+0x11/0x20 [ 59.881931][ C0] INITIAL USE at: [ 59.881932][ C0] __lock_acquire+0x388/0xc20 [ 59.881933][ C0] lock_acquire.part.0+0xd4/0x280 [ 59.881934][ C0] fs_reclaim_acquire+0xd5/0x120 [ 59.881936][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 59.881937][ C0] kthread_create_worker_on_node+0xea/0x210 [ 59.881938][ C0] workqueue_init+0x2a/0x680 [ 59.881939][ C0] kernel_init_freeable+0x2fe/0x630 [ 59.881940][ C0] kernel_init+0x21/0x150 [ 59.881942][ C0] ret_from_fork+0x474/0x6b0 [ 59.881943][ C0] ret_from_fork_asm+0x11/0x20 [ 59.881944][ C0] } [ 59.881945][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 59.881949][ C0] ... acquired at: [ 59.881950][ C0] __lock_acquire+0x518/0xc20 [ 59.881951][ C0] lock_acquire.part.0+0xd4/0x280 [ 59.881952][ C0] fs_reclaim_acquire+0xd5/0x120 [ 59.881953][ C0] __kmalloc_noprof+0xd3/0x760 [ 59.881954][ C0] alloc_buf.isra.0+0x4b/0x260 [ 59.881958][ C0] put_chars+0x1e1/0x2f0 [ 59.881959][ C0] hvc_console_print+0x292/0x780 [ 59.881962][ C0] console_emit_next_record+0x22f/0x4c0 [ 59.881964][ C0] console_flush_one_record+0x46f/0x710 [ 59.881965][ C0] console_unlock+0xee/0x1f0 [ 59.881966][ C0] vprintk_emit+0x37c/0x3e0 [ 59.881967][ C0] _printk+0xc7/0x100 [ 59.881971][ C0] nf_conntrack_irc_init+0x1d/0x130 [ 59.881973][ C0] do_one_initcall+0x124/0x4f0 [ 59.881975][ C0] kernel_init_freeable+0x596/0x630 [ 59.881976][ C0] kernel_init+0x21/0x150 [ 59.881977][ C0] ret_from_fork+0x474/0x6b0 [ 59.881979][ C0] ret_from_fork_asm+0x11/0x20 [ 59.881980][ C0] [ 59.881980][ C0] -> (console_owner){..-.}-{0:0} { [ 59.881982][ C0] IN-SOFTIRQ-W at: [ 59.881983][ C0] __lock_acquire+0x388/0xc20 [ 59.881984][ C0] lock_acquire.part.0+0xd4/0x280 [ 59.881985][ C0] console_lock_spinning_enable+0x5c/0x60 [ 59.881986][ C0] console_emit_next_record+0x1d1/0x4c0 [ 59.881988][ C0] console_flush_one_record+0x46f/0x710 [ 59.881989][ C0] console_unlock+0xee/0x1f0 [ 59.881990][ C0] vprintk_emit+0x37c/0x3e0 [ 59.881991][ C0] _printk+0xc7/0x100 [ 59.881993][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 59.881995][ C0] call_timer_fn+0x160/0x4d0 [ 59.881996][ C0] __run_timers+0x68f/0xaa0 [ 59.881998][ C0] run_timer_softirq+0xf0/0x160 [ 59.881999][ C0] handle_softirqs+0x1d3/0x900 [ 59.882001][ C0] __irq_exit_rcu+0x145/0x1c0 [ 59.882002][ C0] irq_exit_rcu+0xe/0x30 [ 59.882003][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 59.882005][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 59.882007][ C0] pv_native_safe_halt+0xf/0x10 [ 59.882008][ C0] default_idle+0x9/0x10 [ 59.882010][ C0] default_idle_call+0x6e/0xb0 [ 59.882011][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 59.882013][ C0] do_idle+0xd8/0x190 [ 59.882014][ C0] cpu_startup_entry+0x53/0x70 [ 59.882015][ C0] rest_init+0x279/0x280 [ 59.882017][ C0] start_kernel+0x3af/0x3b0 [ 59.882018][ C0] x86_64_start_reservations+0x24/0x30 [ 59.882020][ C0] x86_64_start_kernel+0x12b/0x130 [ 59.882021][ C0] common_startup_64+0x13e/0x148 [ 59.882023][ C0] INITIAL USE at: [ 59.882024][ C0] } [ 59.882024][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 59.882027][ C0] ... acquired at: [ 59.882027][ C0] mark_lock+0x1d7/0xa00 [ 59.882029][ C0] mark_usage+0x42/0x170 [ 59.882030][ C0] __lock_acquire+0x388/0xc20 [ 59.882031][ C0] lock_acquire.part.0+0xd4/0x280 [ 59.882032][ C0] console_lock_spinning_enable+0x5c/0x60 [ 59.882034][ C0] console_emit_next_record+0x1d1/0x4c0 [ 59.882035][ C0] console_flush_one_record+0x46f/0x710 [ 59.882036][ C0] console_unlock+0xee/0x1f0 [ 59.882037][ C0] vprintk_emit+0x37c/0x3e0 [ 59.882039][ C0] _printk+0xc7/0x100 [ 59.882040][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 59.882041][ C0] call_timer_fn+0x160/0x4d0 [ 59.882042][ C0] __run_timers+0x68f/0xaa0 [ 59.882043][ C0] run_timer_softirq+0xf0/0x160 [ 59.882045][ C0] handle_softirqs+0x1d3/0x900 [ 59.882046][ C0] __irq_exit_rcu+0x145/0x1c0 [ 59.882047][ C0] irq_exit_rcu+0xe/0x30 [ 59.882048][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 59.882049][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 59.882050][ C0] pv_native_safe_halt+0xf/0x10 [ 59.882051][ C0] default_idle+0x9/0x10 [ 59.882053][ C0] default_idle_call+0x6e/0xb0 [ 59.882054][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 59.882055][ C0] do_idle+0xd8/0x190 [ 59.882056][ C0] cpu_startup_entry+0x53/0x70 [ 59.882057][ C0] rest_init+0x279/0x280 [ 59.882059][ C0] start_kernel+0x3af/0x3b0 [ 59.882060][ C0] x86_64_start_reservations+0x24/0x30 [ 59.882061][ C0] x86_64_start_kernel+0x12b/0x130 [ 59.882062][ C0] common_startup_64+0x13e/0x148 [ 59.882063][ C0] [ 59.882064][ C0] [ 59.882064][ C0] stack backtrace: [ 59.882066][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 59.882069][ C0] Tainted: [W]=WARN [ 59.882070][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 59.882071][ C0] Call Trace: [ 59.882073][ C0] [ 59.882074][ C0] dump_stack_lvl+0x6f/0xa0 [ 59.882077][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 59.882079][ C0] mark_lock_irq+0x989/0x9c0 [ 59.882081][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 59.882083][ C0] mark_lock+0x1d7/0xa00 [ 59.882085][ C0] mark_usage+0x42/0x170 [ 59.882090][ C0] __lock_acquire+0x388/0xc20 [ 59.882092][ C0] lock_acquire.part.0+0xd4/0x280 [ 59.882093][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 59.882095][ C0] ? rcu_is_watching+0x16/0xd0 [ 59.882098][ C0] ? lock_acquire+0x13c/0x160 [ 59.882100][ C0] console_lock_spinning_enable+0x5c/0x60 [ 59.882101][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 59.882102][ C0] console_emit_next_record+0x1d1/0x4c0 [ 59.882104][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 59.882106][ C0] ? console_flush_one_record+0x106/0x710 [ 59.882107][ C0] ? rcu_is_watching+0x16/0xd0 [ 59.882109][ C0] ? lock_acquire+0x13c/0x160 [ 59.882111][ C0] console_flush_one_record+0x46f/0x710 [ 59.882113][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 59.882114][ C0] ? __lock_acquire+0x518/0xc20 [ 59.882116][ C0] console_unlock+0xee/0x1f0 [ 59.882118][ C0] ? console_flush_one_record+0x710/0x710 [ 59.882119][ C0] ? rcu_is_watching+0x16/0xd0 [ 59.882121][ C0] ? lock_acquire+0x60/0x160 [ 59.882123][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 59.882124][ C0] ? vprintk_emit+0x320/0x3e0 [ 59.882126][ C0] vprintk_emit+0x37c/0x3e0 [ 59.882127][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 59.882130][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 59.882131][ C0] _printk+0xc7/0x100 [ 59.882133][ C0] ? snapshot_read.cold+0x21/0x21 [ 59.882135][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 59.882136][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 59.882138][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 59.882139][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 59.882141][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 59.882142][ C0] call_timer_fn+0x160/0x4d0 [ 59.882144][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 59.882145][ C0] ? debug_object_active_state+0x430/0x430 [ 59.882149][ C0] ? find_held_lock+0x2b/0x80 [ 59.882151][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 59.882153][ C0] ? rcu_is_watching+0x16/0xd0 [ 59.882155][ C0] __run_timers+0x68f/0xaa0 [ 59.882156][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 59.882158][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 59.882160][ C0] ? __lock_acquire+0x518/0xc20 [ 59.882162][ C0] ? __rwlock_init+0x150/0x150 [ 59.882164][ C0] run_timer_softirq+0xf0/0x160 [ 59.882166][ C0] ? __run_timers+0xaa0/0xaa0 [ 59.882167][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 59.882170][ C0] ? rcu_is_watching+0x16/0xd0 [ 59.882172][ C0] handle_softirqs+0x1d3/0x900 [ 59.882173][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 59.882175][ C0] ? _local_bh_enable+0xc0/0xc0 [ 59.882176][ C0] __irq_exit_rcu+0x145/0x1c0 [ 59.882178][ C0] irq_exit_rcu+0xe/0x30 [ 59.882179][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 59.882181][ C0] [ 59.882181][ C0] [ 59.882181][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 59.882183][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 59.882185][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 59.882187][ 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 [ 59.882189][ C0] RSP: 0018:ffffffff95207cf8 EFLAGS: 00000296 [ 59.882191][ C0] RAX: 000000000035bc0f RBX: ffffffff95240600 RCX: ffffffff920f3307 [ 59.882193][ C0] RDX: ffffffff95240600 RSI: ffffffff95057566 RDI: ffffffff94a8d8e0 [ 59.882194][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 59.882195][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff2a40fa2 [ 59.882196][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 59.882197][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 59.882200][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 59.882201][ C0] default_idle+0x9/0x10 [ 59.882203][ C0] default_idle_call+0x6e/0xb0 [ 59.882205][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 59.882206][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 59.882207][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 59.882210][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 59.882212][ C0] ? rcu_is_watching+0x16/0xd0 [ 59.882214][ C0] do_idle+0xd8/0x190 [ 59.882215][ C0] cpu_startup_entry+0x53/0x70 [ 59.882216][ C0] rest_init+0x279/0x280 [ 59.882218][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 59.882221][ C0] ? rest_init+0x280/0x280 [ 59.882223][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 59.882226][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 59.882228][ C0] ? acpi_enable+0x1e4/0x330 [ 59.882230][ C0] start_kernel+0x3af/0x3b0 [ 59.882231][ C0] x86_64_start_reservations+0x24/0x30 [ 59.882233][ C0] x86_64_start_kernel+0x12b/0x130 [ 59.882235][ C0] common_startup_64+0x13e/0x148 [ 59.882237][ C0] [ 59.882242][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 59.882243][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 59.882245][ C0] preempt_count: 103, expected: 0 [ 59.882245][ C0] RCU nest depth: 0, expected: 0 [ 59.882246][ C0] INFO: lockdep is turned off. [ 59.882247][ C0] irq event stamp: 3521567 [ 59.882247][ C0] hardirqs last enabled at (3521566): [] __down_trylock_console_sem+0x86/0xa0 [ 59.882249][ C0] hardirqs last disabled at (3521567): [] console_emit_next_record+0x3d4/0x4c0 [ 59.882251][ C0] softirqs last enabled at (3521516): [] handle_softirqs+0x67c/0x900 [ 59.882252][ C0] softirqs last disabled at (3521553): [] __irq_exit_rcu+0x145/0x1c0 [ 59.882254][ C0] Preemption disabled at: [ 59.882254][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 59.882257][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 59.882259][ C0] Tainted: [W]=WARN [ 59.882259][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 59.882260][ C0] Call Trace: [ 59.882260][ C0] [ 59.882261][ C0] dump_stack_lvl+0x6f/0xa0 [ 59.882263][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 59.882264][ C0] __might_resched.cold+0x1fe/0x2c1 [ 59.882267][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 59.882270][ C0] ? rcu_is_watching+0x16/0xd0 [ 59.882272][ C0] __kmalloc_noprof+0x443/0x760 [ 59.882273][ C0] ? __rwlock_init+0x150/0x150 [ 59.882275][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 59.882277][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 59.882278][ C0] alloc_buf.isra.0+0x4b/0x260 [ 59.882281][ C0] put_chars+0x1e1/0x2f0 [ 59.882283][ C0] ? __send_to_port+0x420/0x420 [ 59.882285][ C0] ? mark_lock+0x1d7/0xa00 [ 59.882287][ C0] hvc_console_print+0x292/0x780 [ 59.882290][ C0] ? hvc_write+0x3a0/0x3a0 [ 59.882292][ C0] ? rcu_is_watching+0x16/0xd0 [ 59.882293][ C0] ? rcu_is_watching+0x16/0xd0 [ 59.882295][ C0] console_emit_next_record+0x22f/0x4c0 [ 59.882297][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 59.882298][ C0] ? console_flush_one_record+0x106/0x710 [ 59.882300][ C0] ? rcu_is_watching+0x16/0xd0 [ 59.882302][ C0] ? lock_acquire+0x13c/0x160 [ 59.882303][ C0] console_flush_one_record+0x46f/0x710 [ 59.882305][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 59.882307][ C0] ? __lock_acquire+0x518/0xc20 [ 59.882309][ C0] console_unlock+0xee/0x1f0 [ 59.882311][ C0] ? console_flush_one_record+0x710/0x710 [ 59.882312][ C0] ? rcu_is_watching+0x16/0xd0 [ 59.882313][ C0] ? lock_acquire+0x60/0x160 [ 59.882315][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 59.882316][ C0] ? vprintk_emit+0x320/0x3e0 [ 59.882318][ C0] vprintk_emit+0x37c/0x3e0 [ 59.882320][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 59.882322][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 59.882324][ C0] _printk+0xc7/0x100 [ 59.882325][ C0] ? snapshot_read.cold+0x21/0x21 [ 59.882327][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 59.882329][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 59.882331][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 59.882332][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 59.882333][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 59.882335][ C0] call_timer_fn+0x160/0x4d0 [ 59.882336][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 59.882338][ C0] ? debug_object_active_state+0x430/0x430 [ 59.882339][ C0] ? find_held_lock+0x2b/0x80 [ 59.882341][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 59.882343][ C0] ? rcu_is_watching+0x16/0xd0 [ 59.882345][ C0] __run_timers+0x68f/0xaa0 [ 59.882346][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 59.882348][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 59.882350][ C0] ? __lock_acquire+0x518/0xc20 [ 59.882352][ C0] ? __rwlock_init+0x150/0x150 [ 59.882354][ C0] run_timer_softirq+0xf0/0x160 [ 59.882356][ C0] ? __run_timers+0xaa0/0xaa0 [ 59.882357][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 59.882359][ C0] ? rcu_is_watching+0x16/0xd0 [ 59.882360][ C0] handle_softirqs+0x1d3/0x900 [ 59.882362][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 59.882363][ C0] ? _local_bh_enable+0xc0/0xc0 [ 59.882365][ C0] __irq_exit_rcu+0x145/0x1c0 [ 59.882366][ C0] irq_exit_rcu+0xe/0x30 [ 59.882367][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 59.882369][ C0] [ 59.882370][ C0] [ 59.882370][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 59.882372][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 59.882373][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 59.882375][ 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 [ 59.882376][ C0] RSP: 0018:ffffffff95207cf8 EFLAGS: 00000296 [ 59.882377][ C0] RAX: 000000000035bc0f RBX: ffffffff95240600 RCX: ffffffff920f3307 [ 59.882378][ C0] RDX: ffffffff95240600 RSI: ffffffff95057566 RDI: ffffffff94a8d8e0 [ 59.882378][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 59.882379][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff2a40fa2 [ 59.882380][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 59.882381][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 59.882383][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 59.882384][ C0] default_idle+0x9/0x10 [ 59.882386][ C0] default_idle_call+0x6e/0xb0 [ 59.882388][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 59.882389][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 59.882390][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 59.882392][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 59.882394][ C0] ? rcu_is_watching+0x16/0xd0 [ 59.882396][ C0] do_idle+0xd8/0x190 [ 59.882397][ C0] cpu_startup_entry+0x53/0x70 [ 59.882398][ C0] rest_init+0x279/0x280 [ 59.882400][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 59.882403][ C0] ? rest_init+0x280/0x280 [ 59.882405][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 59.882406][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 59.882408][ C0] ? acpi_enable+0x1e4/0x330 [ 59.882409][ C0] start_kernel+0x3af/0x3b0 [ 59.882411][ C0] x86_64_start_reservations+0x24/0x30 [ 59.882413][ C0] x86_64_start_kernel+0x12b/0x130 [ 59.882414][ C0] common_startup_64+0x13e/0x148 [ 59.882417][ C0] [ 912.933068][T27772] pm_nl_ctl (27772) used greatest stack depth: 23192 bytes left [ 912.933084][T27772] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 912.933086][T27772] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 27772, name: pm_nl_ctl [ 912.933088][T27772] preempt_count: 2, expected: 0 [ 912.933089][T27772] RCU nest depth: 0, expected: 0 [ 912.933090][T27772] INFO: lockdep is turned off. [ 912.933092][T27772] irq event stamp: 0 [ 912.933092][T27772] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 912.933098][T27772] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 912.933103][T27772] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 912.933105][T27772] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 912.933107][T27772] Preemption disabled at: [ 912.933107][T27772] [<0000000000000000>] 0x0 [ 912.933112][T27772] CPU: 1 UID: 0 PID: 27772 Comm: pm_nl_ctl Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 912.933116][T27772] Tainted: [W]=WARN [ 912.933117][T27772] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 912.933119][T27772] Call Trace: [ 912.933121][T27772] [ 912.933122][T27772] dump_stack_lvl+0x6f/0xa0 [ 912.933127][T27772] __might_resched.cold+0x1fe/0x2c1 [ 912.933132][T27772] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 912.933135][T27772] ? __asan_memcpy+0x3c/0x60 [ 912.933139][T27772] ? rcu_is_watching+0x16/0xd0 [ 912.933142][T27772] __kmalloc_noprof+0x443/0x760 [ 912.933145][T27772] ? __rwlock_init+0x150/0x150 [ 912.933148][T27772] ? alloc_buf.isra.0+0x4b/0x260 [ 912.933153][T27772] ? do_raw_spin_unlock+0x59/0x250 [ 912.933155][T27772] alloc_buf.isra.0+0x4b/0x260 [ 912.933157][T27772] put_chars+0x1e1/0x2f0 [ 912.933160][T27772] ? __send_to_port+0x420/0x420 [ 912.933162][T27772] ? console_prepend_replay+0x20/0x20 [ 912.933166][T27772] hvc_console_print+0x292/0x780 [ 912.933170][T27772] ? hvc_write+0x3a0/0x3a0 [ 912.933172][T27772] ? rcu_is_watching+0x16/0xd0 [ 912.933174][T27772] ? lock_acquire+0x13c/0x160 [ 912.933176][T27772] console_emit_next_record+0x22f/0x4c0 [ 912.933178][T27772] ? devkmsg_read+0x4b0/0x4b0 [ 912.933180][T27772] ? rcu_is_watching+0x16/0xd0 [ 912.933182][T27772] ? rcu_is_watching+0x16/0xd0 [ 912.933183][T27772] ? rcu_is_watching+0x16/0xd0 [ 912.933185][T27772] ? lock_acquire+0x13c/0x160 [ 912.933187][T27772] ? console_flush_one_record+0x111/0x710 [ 912.933188][T27772] console_flush_one_record+0x46f/0x710 [ 912.933190][T27772] ? console_emit_next_record+0x4c0/0x4c0 [ 912.933193][T27772] console_unlock+0xee/0x1f0 [ 912.933195][T27772] ? lock_acquire+0x13c/0x160 [ 912.933196][T27772] ? console_flush_one_record+0x710/0x710 [ 912.933197][T27772] ? rcu_is_watching+0x16/0xd0 [ 912.933199][T27772] ? lock_release+0x184/0x1f0 [ 912.933200][T27772] ? lock_acquire+0x60/0x160 [ 912.933201][T27772] ? __rcu_read_lock+0x30/0x70 [ 912.933204][T27772] ? __down_trylock_console_sem+0x5e/0xa0 [ 912.933205][T27772] ? vprintk_emit+0x320/0x3e0 [ 912.933207][T27772] vprintk_emit+0x37c/0x3e0 [ 912.933209][T27772] ? wake_up_klogd_work_func+0x90/0x90 [ 912.933211][T27772] ? preempt_schedule_thunk+0x16/0x30 [ 912.933213][T27772] ? preempt_schedule_thunk+0x16/0x30 [ 912.933215][T27772] _printk+0xc7/0x100 [ 912.933218][T27772] ? snapshot_read.cold+0x21/0x21 [ 912.933220][T27772] ? do_raw_spin_lock+0x131/0x280 [ 912.933222][T27772] ? do_raw_spin_lock+0x131/0x280 [ 912.933223][T27772] ? __rwlock_init+0x150/0x150 [ 912.933231][T27772] ? do_raw_spin_lock+0x131/0x280 [ 912.933234][T27772] do_exit.cold+0x82/0x9c [ 912.933237][T27772] ? exit_notify+0x890/0x890 [ 912.933240][T27772] ? posixtimer_sig_unignore.isra.0+0x5f0/0x5f0 [ 912.933243][T27772] ? rcu_is_watching+0x16/0xd0 [ 912.933245][T27772] do_group_exit+0xb8/0x370 [ 912.933247][T27772] __x64_sys_exit_group+0x3c/0x50 [ 912.933249][T27772] x64_sys_call+0x1567/0x1570 [ 912.933251][T27772] do_syscall_64+0xff/0x530 [ 912.933254][T27772] ? exc_page_fault+0xee/0x100 [ 912.933257][T27772] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 912.933259][T27772] RIP: 0033:0x7f0c3f3631b8 [ 912.933261][T27772] Code: Unable to access opcode bytes at 0x7f0c3f36318e. [ 912.933262][T27772] RSP: 002b:00007ffd6646e6d8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 912.933266][T27772] RAX: ffffffffffffffda RBX: 00007f0c3f493f88 RCX: 00007f0c3f3631b8 [ 912.933268][T27772] RDX: 00007f0c3f2a0a48 RSI: ffffffffffffffb0 RDI: 0000000000000000 [ 912.933269][T27772] RBP: 00007ffd6646e730 R08: 0000000000000000 R09: 0000000000000000 [ 912.933269][T27772] R10: 00007ffd6646e570 R11: 0000000000000246 R12: 0000000000000001 [ 912.933270][T27772] R13: 0000000000000000 R14: 00007f0c3f492680 R15: 00007f0c3f493fa0 [ 912.933273][T27772]