[ 12.069560][ T212] ip (212) used greatest stack depth: 24384 bytes left [ 12.069579][ T212] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.069581][ T212] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 212, name: ip [ 12.069583][ T212] preempt_count: 2, expected: 0 [ 12.069584][ T212] RCU nest depth: 0, expected: 0 [ 12.069585][ T212] locks held by ip/212: 5, last CPU#3: [ 12.069587][ T212] #0: ffffffffa82167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 12.069599][ T212] #1: ffffffffa837ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.069604][ T212] #2: ffffffffa837de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.069609][ T212] #3: ffffffffa829d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.069612][ T212] #4: ffffffffa829d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.069617][ T212] irq event stamp: 39948 [ 12.069618][ T212] hardirqs last enabled at (39947): [] __down_trylock_console_sem+0x86/0xa0 [ 12.069621][ T212] hardirqs last disabled at (39948): [] console_emit_next_record+0x3d4/0x4c0 [ 12.069623][ T212] softirqs last enabled at (38618): [] handle_softirqs+0x67c/0x900 [ 12.069626][ T212] softirqs last disabled at (37287): [] __irq_exit_rcu+0x145/0x1c0 [ 12.069628][ T212] Preemption disabled at: [ 12.069629][ T212] [<0000000000000000>] 0x0 [ 12.069636][ T212] CPU: 3 UID: 0 PID: 212 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.069639][ T212] Tainted: [W]=WARN [ 12.069640][ T212] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.069642][ T212] Call Trace: [ 12.069644][ T212] [ 12.069645][ T212] dump_stack_lvl+0x6f/0xa0 [ 12.069652][ T212] __might_resched.cold+0x1fe/0x2c1 [ 12.069656][ T212] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.069660][ T212] ? __kmalloc_noprof+0xdb/0x760 [ 12.069665][ T212] __kmalloc_noprof+0x443/0x760 [ 12.069667][ T212] ? alloc_buf.isra.0+0x4b/0x260 [ 12.069674][ T212] ? do_raw_spin_unlock+0x59/0x250 [ 12.069677][ T212] alloc_buf.isra.0+0x4b/0x260 [ 12.069680][ T212] put_chars+0x1e1/0x2f0 [ 12.069683][ T212] ? __send_to_port+0x420/0x420 [ 12.069684][ T212] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.069688][ T212] ? validate_chain+0x38b/0xc20 [ 12.069693][ T212] hvc_console_print+0x292/0x780 [ 12.069701][ T212] ? hvc_write+0x3a0/0x3a0 [ 12.069704][ T212] ? rcu_is_watching+0x16/0xd0 [ 12.069706][ T212] ? lock_acquire+0x13c/0x160 [ 12.069710][ T212] console_emit_next_record+0x22f/0x4c0 [ 12.069714][ T212] ? devkmsg_read+0x4b0/0x4b0 [ 12.069716][ T212] ? console_flush_one_record+0x106/0x710 [ 12.069719][ T212] ? rcu_is_watching+0x16/0xd0 [ 12.069721][ T212] ? lock_acquire+0x13c/0x160 [ 12.069725][ T212] console_flush_one_record+0x46f/0x710 [ 12.069729][ T212] ? console_emit_next_record+0x4c0/0x4c0 [ 12.069731][ T212] ? __lock_acquire+0x518/0xc20 [ 12.069737][ T212] console_unlock+0xee/0x1f0 [ 12.069739][ T212] ? console_flush_one_record+0x710/0x710 [ 12.069741][ T212] ? rcu_is_watching+0x16/0xd0 [ 12.069743][ T212] ? lock_acquire+0xe0/0x160 [ 12.069747][ T212] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.069749][ T212] ? vprintk_emit+0x320/0x3e0 [ 12.069752][ T212] vprintk_emit+0x37c/0x3e0 [ 12.069755][ T212] ? wake_up_klogd_work_func+0x90/0x90 [ 12.069758][ T212] ? __lock_acquire+0x518/0xc20 [ 12.069762][ T212] _printk+0xc7/0x100 [ 12.069765][ T212] ? snapshot_read.cold+0x21/0x21 [ 12.069775][ T212] ? do_raw_spin_lock+0x131/0x280 [ 12.069778][ T212] ? __rwlock_init+0x150/0x150 [ 12.069782][ T212] ? do_raw_spin_lock+0x131/0x280 [ 12.069785][ T212] do_exit.cold+0x82/0x9c [ 12.069789][ T212] ? exit_notify+0x890/0x890 [ 12.069790][ T212] ? __lock_release.isra.0+0x69/0x1a0 [ 12.069793][ T212] ? rcu_is_watching+0x16/0xd0 [ 12.069797][ T212] do_group_exit+0xb8/0x370 [ 12.069800][ T212] __x64_sys_exit_group+0x3c/0x50 [ 12.069802][ T212] x64_sys_call+0x1567/0x1570 [ 12.069805][ T212] do_syscall_64+0xff/0x530 [ 12.069809][ T212] ? exc_page_fault+0xee/0x100 [ 12.069812][ T212] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.069814][ T212] RIP: 0033:0x7fab341ea1b8 [ 12.069816][ T212] Code: Unable to access opcode bytes at 0x7fab341ea18e. [ 12.069818][ T212] RSP: 002b:00007fff996a69d8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 12.069820][ T212] RAX: ffffffffffffffda RBX: 00007fab3431af88 RCX: 00007fab341ea1b8 [ 12.069821][ T212] RDX: 00007fab33f34fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 12.069822][ T212] RBP: 00007fff996a6a30 R08: 0000000000000000 R09: 0000000000000000 [ 12.069823][ T212] R10: 00007fff996a67f0 R11: 0000000000000246 R12: 0000000000000001 [ 12.069824][ T212] R13: 0000000000000000 R14: 00007fab34319680 R15: 00007fab3431afa0 [ 12.069831][ T212] [ 12.524494][ C0] clocksource: Watchdog remote CPU 3 read timed out [ 12.524569][ C0] [ 12.524571][ C0] ======================================================== [ 12.524572][ C0] WARNING: possible irq lock inversion dependency detected [ 12.524575][ C0] 7.2.0-virtme #1 Tainted: G W [ 12.524576][ C0] -------------------------------------------------------- [ 12.524577][ C0] swapper/0/0 just changed the state of lock: [ 12.524578][ C0] ffffffffa829d760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.524592][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 12.524594][ C0] (fs_reclaim){+.+.}-{0:0} [ 12.524595][ C0] [ 12.524595][ C0] [ 12.524595][ C0] and interrupts could create inverse lock ordering between them. [ 12.524595][ C0] [ 12.524596][ C0] [ 12.524596][ C0] other info that might help us debug this: [ 12.524596][ C0] Possible interrupt unsafe locking scenario: [ 12.524596][ C0] [ 12.524597][ C0] CPU0 CPU1 [ 12.524598][ C0] ---- ---- [ 12.524598][ C0] lock(fs_reclaim); [ 12.524599][ C0] local_irq_disable(); [ 12.524600][ C0] lock(console_owner); [ 12.524601][ C0] lock(fs_reclaim); [ 12.524602][ C0] [ 12.524602][ C0] lock(console_owner); [ 12.524603][ C0] [ 12.524603][ C0] *** DEADLOCK *** [ 12.524603][ C0] [ 12.524603][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 12.524605][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 12.524611][ C0] #1: ffffffffa83e29f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 12.524615][ C0] #2: ffffffffa837ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.524618][ C0] #3: ffffffffa837de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.524622][ C0] [ 12.524622][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 12.524626][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 12.524629][ C0] HARDIRQ-ON-W at: [ 12.524630][ C0] __lock_acquire+0x388/0xc20 [ 12.524634][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.524635][ C0] fs_reclaim_acquire+0xd5/0x120 [ 12.524639][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 12.524641][ C0] kthread_create_worker_on_node+0xea/0x210 [ 12.524644][ C0] workqueue_init+0x2a/0x680 [ 12.524649][ C0] kernel_init_freeable+0x2fe/0x630 [ 12.524652][ C0] kernel_init+0x21/0x150 [ 12.524656][ C0] ret_from_fork+0x474/0x6b0 [ 12.524659][ C0] ret_from_fork_asm+0x11/0x20 [ 12.524662][ C0] SOFTIRQ-ON-W at: [ 12.524663][ C0] __lock_acquire+0x388/0xc20 [ 12.524665][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.524666][ C0] fs_reclaim_acquire+0xd5/0x120 [ 12.524668][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 12.524669][ C0] kthread_create_worker_on_node+0xea/0x210 [ 12.524670][ C0] workqueue_init+0x2a/0x680 [ 12.524672][ C0] kernel_init_freeable+0x2fe/0x630 [ 12.524674][ C0] kernel_init+0x21/0x150 [ 12.524675][ C0] ret_from_fork+0x474/0x6b0 [ 12.524676][ C0] ret_from_fork_asm+0x11/0x20 [ 12.524678][ C0] INITIAL USE at: [ 12.524678][ C0] __lock_acquire+0x388/0xc20 [ 12.524680][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.524681][ C0] fs_reclaim_acquire+0xd5/0x120 [ 12.524683][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 12.524684][ C0] kthread_create_worker_on_node+0xea/0x210 [ 12.524685][ C0] workqueue_init+0x2a/0x680 [ 12.524687][ C0] kernel_init_freeable+0x2fe/0x630 [ 12.524689][ C0] kernel_init+0x21/0x150 [ 12.524690][ C0] ret_from_fork+0x474/0x6b0 [ 12.524692][ C0] ret_from_fork_asm+0x11/0x20 [ 12.524693][ C0] } [ 12.524693][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 12.524697][ C0] ... acquired at: [ 12.524699][ C0] __lock_acquire+0x518/0xc20 [ 12.524700][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.524702][ C0] fs_reclaim_acquire+0xd5/0x120 [ 12.524703][ C0] __kmalloc_noprof+0xd3/0x760 [ 12.524704][ C0] alloc_buf.isra.0+0x4b/0x260 [ 12.524708][ C0] put_chars+0x1e1/0x2f0 [ 12.524709][ C0] hvc_console_print+0x292/0x780 [ 12.524713][ C0] console_emit_next_record+0x22f/0x4c0 [ 12.524715][ C0] console_flush_one_record+0x46f/0x710 [ 12.524716][ C0] console_unlock+0xee/0x1f0 [ 12.524718][ C0] vprintk_emit+0x37c/0x3e0 [ 12.524719][ C0] _printk+0xc7/0x100 [ 12.524722][ C0] tun_init+0x24/0xb0 [ 12.524725][ C0] do_one_initcall+0x124/0x4f0 [ 12.524726][ C0] kernel_init_freeable+0x596/0x630 [ 12.524728][ C0] kernel_init+0x21/0x150 [ 12.524729][ C0] ret_from_fork+0x474/0x6b0 [ 12.524731][ C0] ret_from_fork_asm+0x11/0x20 [ 12.524732][ C0] [ 12.524732][ C0] -> (console_owner){..-.}-{0:0} { [ 12.524734][ C0] IN-SOFTIRQ-W at: [ 12.524735][ C0] __lock_acquire+0x388/0xc20 [ 12.524737][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.524738][ C0] console_lock_spinning_enable+0x5c/0x60 [ 12.524740][ C0] console_emit_next_record+0x1d1/0x4c0 [ 12.524741][ C0] console_flush_one_record+0x46f/0x710 [ 12.524743][ C0] console_unlock+0xee/0x1f0 [ 12.524745][ C0] vprintk_emit+0x37c/0x3e0 [ 12.524746][ C0] _printk+0xc7/0x100 [ 12.524747][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 12.524750][ C0] call_timer_fn+0x160/0x4d0 [ 12.524751][ C0] __run_timers+0x68f/0xaa0 [ 12.524753][ C0] run_timer_softirq+0xf0/0x160 [ 12.524755][ C0] handle_softirqs+0x1d3/0x900 [ 12.524758][ C0] __irq_exit_rcu+0x145/0x1c0 [ 12.524759][ C0] irq_exit_rcu+0xe/0x30 [ 12.524760][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 12.524762][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.524764][ C0] pv_native_safe_halt+0xf/0x10 [ 12.524765][ C0] default_idle+0x9/0x10 [ 12.524767][ C0] default_idle_call+0x6e/0xb0 [ 12.524768][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.524777][ C0] do_idle+0xd8/0x190 [ 12.524778][ C0] cpu_startup_entry+0x53/0x70 [ 12.524780][ C0] rest_init+0x279/0x280 [ 12.524781][ C0] start_kernel+0x3b9/0x3c0 [ 12.524783][ C0] x86_64_start_reservations+0x24/0x30 [ 12.524785][ C0] x86_64_start_kernel+0x12b/0x130 [ 12.524786][ C0] common_startup_64+0x13e/0x148 [ 12.524789][ C0] INITIAL USE at: [ 12.524790][ C0] } [ 12.524790][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 12.524793][ C0] ... acquired at: [ 12.524793][ C0] mark_lock+0x1d7/0xa00 [ 12.524795][ C0] mark_usage+0x42/0x170 [ 12.524796][ C0] __lock_acquire+0x388/0xc20 [ 12.524798][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.524799][ C0] console_lock_spinning_enable+0x5c/0x60 [ 12.524801][ C0] console_emit_next_record+0x1d1/0x4c0 [ 12.524802][ C0] console_flush_one_record+0x46f/0x710 [ 12.524804][ C0] console_unlock+0xee/0x1f0 [ 12.524806][ C0] vprintk_emit+0x37c/0x3e0 [ 12.524807][ C0] _printk+0xc7/0x100 [ 12.524808][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 12.524809][ C0] call_timer_fn+0x160/0x4d0 [ 12.524811][ C0] __run_timers+0x68f/0xaa0 [ 12.524812][ C0] run_timer_softirq+0xf0/0x160 [ 12.524814][ C0] handle_softirqs+0x1d3/0x900 [ 12.524815][ C0] __irq_exit_rcu+0x145/0x1c0 [ 12.524816][ C0] irq_exit_rcu+0xe/0x30 [ 12.524818][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 12.524819][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.524820][ C0] pv_native_safe_halt+0xf/0x10 [ 12.524821][ C0] default_idle+0x9/0x10 [ 12.524823][ C0] default_idle_call+0x6e/0xb0 [ 12.524824][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.524825][ C0] do_idle+0xd8/0x190 [ 12.524827][ C0] cpu_startup_entry+0x53/0x70 [ 12.524828][ C0] rest_init+0x279/0x280 [ 12.524829][ C0] start_kernel+0x3b9/0x3c0 [ 12.524831][ C0] x86_64_start_reservations+0x24/0x30 [ 12.524832][ C0] x86_64_start_kernel+0x12b/0x130 [ 12.524833][ C0] common_startup_64+0x13e/0x148 [ 12.524835][ C0] [ 12.524835][ C0] [ 12.524835][ C0] stack backtrace: [ 12.524838][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.524841][ C0] Tainted: [W]=WARN [ 12.524842][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.524844][ C0] Call Trace: [ 12.524846][ C0] [ 12.524847][ C0] dump_stack_lvl+0x6f/0xa0 [ 12.524852][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 12.524854][ C0] mark_lock_irq+0x989/0x9c0 [ 12.524856][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 12.524859][ C0] mark_lock+0x1d7/0xa00 [ 12.524861][ C0] mark_usage+0x42/0x170 [ 12.524863][ C0] __lock_acquire+0x388/0xc20 [ 12.524866][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.524867][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 12.524870][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.524872][ C0] ? lock_acquire+0x13c/0x160 [ 12.524874][ C0] console_lock_spinning_enable+0x5c/0x60 [ 12.524876][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 12.524878][ C0] console_emit_next_record+0x1d1/0x4c0 [ 12.524880][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 12.524882][ C0] ? console_flush_one_record+0x106/0x710 [ 12.524884][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.524885][ C0] ? lock_acquire+0x13c/0x160 [ 12.524887][ C0] console_flush_one_record+0x46f/0x710 [ 12.524890][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 12.524892][ C0] ? __lock_acquire+0x518/0xc20 [ 12.524894][ C0] console_unlock+0xee/0x1f0 [ 12.524896][ C0] ? console_flush_one_record+0x710/0x710 [ 12.524898][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.524899][ C0] ? lock_acquire+0xe0/0x160 [ 12.524901][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.524904][ C0] ? vprintk_emit+0x320/0x3e0 [ 12.524905][ C0] vprintk_emit+0x37c/0x3e0 [ 12.524906][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 12.524908][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 12.524910][ C0] _printk+0xc7/0x100 [ 12.524912][ C0] ? snapshot_read.cold+0x21/0x21 [ 12.524914][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 12.524916][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 12.524918][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 12.524920][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 12.524922][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 12.524924][ C0] call_timer_fn+0x160/0x4d0 [ 12.524926][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 12.524928][ C0] ? debug_object_active_state+0x430/0x430 [ 12.524931][ C0] ? find_held_lock+0x2b/0x80 [ 12.524933][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 12.524935][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.524936][ C0] __run_timers+0x68f/0xaa0 [ 12.524938][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 12.524941][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 12.524943][ C0] ? __lock_acquire+0x518/0xc20 [ 12.524945][ C0] ? __rwlock_init+0x150/0x150 [ 12.524948][ C0] run_timer_softirq+0xf0/0x160 [ 12.524951][ C0] ? __run_timers+0xaa0/0xaa0 [ 12.524952][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.524955][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.524957][ C0] handle_softirqs+0x1d3/0x900 [ 12.524959][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 12.524960][ C0] ? _local_bh_enable+0xc0/0xc0 [ 12.524963][ C0] __irq_exit_rcu+0x145/0x1c0 [ 12.524965][ C0] irq_exit_rcu+0xe/0x30 [ 12.524966][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 12.524968][ C0] [ 12.524968][ C0] [ 12.524969][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 12.524971][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.524973][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 12.524975][ 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 [ 12.524977][ C0] RSP: 0018:ffffffffa8007cf8 EFLAGS: 00000296 [ 12.524979][ C0] RAX: 000000000004e7bf RBX: ffffffffa8030600 RCX: ffffffffa4706247 [ 12.524981][ C0] RDX: ffffffffa8030600 RSI: ffffffffa7911011 RDI: ffffffffa72949e0 [ 12.524981][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 12.524982][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff5000fa2 [ 12.524983][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 12.524985][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.524987][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 12.524989][ C0] default_idle+0x9/0x10 [ 12.524990][ C0] default_idle_call+0x6e/0xb0 [ 12.524992][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.524994][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 12.524996][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 12.524998][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.525000][ C0] do_idle+0xd8/0x190 [ 12.525002][ C0] cpu_startup_entry+0x53/0x70 [ 12.525003][ C0] rest_init+0x279/0x280 [ 12.525005][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 12.525008][ C0] ? rest_init+0x280/0x280 [ 12.525010][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 12.525013][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 12.525015][ C0] ? acpi_enable+0x1e4/0x330 [ 12.525017][ C0] start_kernel+0x3b9/0x3c0 [ 12.525019][ C0] x86_64_start_reservations+0x24/0x30 [ 12.525021][ C0] x86_64_start_kernel+0x12b/0x130 [ 12.525022][ C0] common_startup_64+0x13e/0x148 [ 12.525026][ C0] [ 273.977947][T18140] ip (18140) used greatest stack depth: 24344 bytes left [ 273.977963][T18140] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 273.977965][T18140] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 18140, name: ip [ 273.977967][T18140] preempt_count: 2, expected: 0 [ 273.977968][T18140] RCU nest depth: 0, expected: 0 [ 273.977969][T18140] INFO: lockdep is turned off. [ 273.977970][T18140] irq event stamp: 0 [ 273.977971][T18140] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 273.977976][T18140] hardirqs last disabled at (0): [] copy_process+0x1d3b/0x62b0 [ 273.977982][T18140] softirqs last enabled at (0): [] copy_process+0x1d8f/0x62b0 [ 273.977984][T18140] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 273.977986][T18140] Preemption disabled at: [ 273.977987][T18140] [<0000000000000000>] 0x0 [ 273.977991][T18140] CPU: 2 UID: 0 PID: 18140 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 273.977995][T18140] Tainted: [W]=WARN [ 273.977996][T18140] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 273.977998][T18140] Call Trace: [ 273.978000][T18140] [ 273.978001][T18140] dump_stack_lvl+0x6f/0xa0 [ 273.978006][T18140] __might_resched.cold+0x1fe/0x2c1 [ 273.978010][T18140] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 273.978014][T18140] ? __asan_memcpy+0x3c/0x60 [ 273.978017][T18140] ? rcu_is_watching+0x16/0xd0 [ 273.978020][T18140] __kmalloc_noprof+0x443/0x760 [ 273.978023][T18140] ? __rwlock_init+0x150/0x150 [ 273.978026][T18140] ? alloc_buf.isra.0+0x4b/0x260 [ 273.978031][T18140] ? do_raw_spin_unlock+0x59/0x250 [ 273.978033][T18140] alloc_buf.isra.0+0x4b/0x260 [ 273.978035][T18140] put_chars+0x1e1/0x2f0 [ 273.978037][T18140] ? __send_to_port+0x420/0x420 [ 273.978039][T18140] ? console_prepend_replay+0x20/0x20 [ 273.978042][T18140] hvc_console_print+0x292/0x780 [ 273.978047][T18140] ? hvc_write+0x3a0/0x3a0 [ 273.978049][T18140] ? rcu_is_watching+0x16/0xd0 [ 273.978050][T18140] ? lock_acquire+0x13c/0x160 [ 273.978052][T18140] console_emit_next_record+0x22f/0x4c0 [ 273.978055][T18140] ? devkmsg_read+0x4b0/0x4b0 [ 273.978057][T18140] ? rcu_is_watching+0x16/0xd0 [ 273.978058][T18140] ? rcu_is_watching+0x16/0xd0 [ 273.978059][T18140] ? rcu_is_watching+0x16/0xd0 [ 273.978060][T18140] ? lock_acquire+0x13c/0x160 [ 273.978062][T18140] ? console_flush_one_record+0x111/0x710 [ 273.978064][T18140] console_flush_one_record+0x46f/0x710 [ 273.978066][T18140] ? console_emit_next_record+0x4c0/0x4c0 [ 273.978069][T18140] console_unlock+0xee/0x1f0 [ 273.978071][T18140] ? lock_acquire+0x13c/0x160 [ 273.978073][T18140] ? console_flush_one_record+0x710/0x710 [ 273.978074][T18140] ? rcu_is_watching+0x16/0xd0 [ 273.978075][T18140] ? lock_release+0x184/0x1f0 [ 273.978077][T18140] ? lock_acquire+0xe0/0x160 [ 273.978079][T18140] ? __rcu_read_lock+0x70/0x70 [ 273.978080][T18140] ? __down_trylock_console_sem+0x5e/0xa0 [ 273.978082][T18140] ? vprintk_emit+0x320/0x3e0 [ 273.978084][T18140] vprintk_emit+0x37c/0x3e0 [ 273.978086][T18140] ? wake_up_klogd_work_func+0x90/0x90 [ 273.978087][T18140] ? do_raw_read_unlock+0x70/0x70 [ 273.978089][T18140] ? forget_original_parent+0x5c/0xdb0 [ 273.978092][T18140] _printk+0xc7/0x100 [ 273.978095][T18140] ? snapshot_read.cold+0x21/0x21 [ 273.978096][T18140] ? do_raw_spin_lock+0x131/0x280 [ 273.978098][T18140] ? do_raw_spin_lock+0x131/0x280 [ 273.978100][T18140] ? __rwlock_init+0x150/0x150 [ 273.978102][T18140] ? do_raw_spin_lock+0x131/0x280 [ 273.978104][T18140] do_exit.cold+0x82/0x9c [ 273.978107][T18140] ? exit_notify+0x890/0x890 [ 273.978108][T18140] ? posixtimer_sig_unignore.isra.0+0x5f0/0x5f0 [ 273.978111][T18140] ? rcu_is_watching+0x16/0xd0 [ 273.978112][T18140] do_group_exit+0xb8/0x370 [ 273.978114][T18140] __x64_sys_exit_group+0x3c/0x50 [ 273.978116][T18140] x64_sys_call+0x1567/0x1570 [ 273.978118][T18140] do_syscall_64+0xff/0x530 [ 273.978122][T18140] ? exc_page_fault+0xee/0x100 [ 273.978123][T18140] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 273.978126][T18140] RIP: 0033:0x7fee7579b1b8 [ 273.978127][T18140] Code: Unable to access opcode bytes at 0x7fee7579b18e. [ 273.978129][T18140] RSP: 002b:00007fffc68087c8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 273.978133][T18140] RAX: ffffffffffffffda RBX: 00007fee758cbf88 RCX: 00007fee7579b1b8 [ 273.978134][T18140] RDX: 00007fee754e5fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 273.978135][T18140] RBP: 00007fffc6808820 R08: 0000000000000000 R09: 0000000000008040 [ 273.978136][T18140] R10: 00007fffc68085e0 R11: 0000000000000246 R12: 0000000000000001 [ 273.978137][T18140] R13: 0000000000000000 R14: 00007fee758ca680 R15: 00007fee758cbfa0 [ 273.978139][T18140]