[ 31.034604][ T2656] ip (2656) used greatest stack depth: 23312 bytes left [ 31.034625][ T2656] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 31.034627][ T2656] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 2656, name: ip [ 31.034629][ T2656] preempt_count: 2, expected: 0 [ 31.034629][ T2656] RCU nest depth: 0, expected: 0 [ 31.034630][ T2656] locks held by ip/2656: 5, last CPU#3: [ 31.034632][ T2656] #0: ffffffff8e6167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 31.034644][ T2656] #1: ffffffff8e77ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 31.034648][ T2656] #2: ffffffff8e77de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 31.034653][ T2656] #3: ffffffff8e69d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 31.034656][ T2656] #4: ffffffff8e69d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 31.034660][ T2656] irq event stamp: 25140 [ 31.034661][ T2656] hardirqs last enabled at (25139): [] __down_trylock_console_sem+0x86/0xa0 [ 31.034664][ T2656] hardirqs last disabled at (25140): [] console_emit_next_record+0x3d4/0x4c0 [ 31.034666][ T2656] softirqs last enabled at (23802): [] netlink_release+0x17b/0xcf0 [ 31.034670][ T2656] softirqs last disabled at (23800): [] netlink_release+0xd2/0xcf0 [ 31.034673][ T2656] Preemption disabled at: [ 31.034674][ T2656] [<0000000000000000>] 0x0 [ 31.034680][ T2656] CPU: 3 UID: 0 PID: 2656 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 31.034685][ T2656] Tainted: [W]=WARN [ 31.034686][ T2656] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 31.034688][ T2656] Call Trace: [ 31.034690][ T2656] [ 31.034691][ T2656] dump_stack_lvl+0x6f/0xa0 [ 31.034698][ T2656] __might_resched.cold+0x1fe/0x2c1 [ 31.034702][ T2656] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 31.034706][ T2656] ? __kmalloc_noprof+0xdb/0x760 [ 31.034710][ T2656] __kmalloc_noprof+0x443/0x760 [ 31.034712][ T2656] ? alloc_buf.isra.0+0x4b/0x260 [ 31.034718][ T2656] ? do_raw_spin_unlock+0x59/0x250 [ 31.034721][ T2656] alloc_buf.isra.0+0x4b/0x260 [ 31.034724][ T2656] put_chars+0x1e1/0x2f0 [ 31.034727][ T2656] ? __send_to_port+0x420/0x420 [ 31.034728][ T2656] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 31.034731][ T2656] ? validate_chain+0x38b/0xc20 [ 31.034737][ T2656] hvc_console_print+0x292/0x780 [ 31.034744][ T2656] ? hvc_write+0x3a0/0x3a0 [ 31.034747][ T2656] ? rcu_is_watching+0x16/0xd0 [ 31.034749][ T2656] ? lock_acquire+0x13c/0x160 [ 31.034753][ T2656] console_emit_next_record+0x22f/0x4c0 [ 31.034757][ T2656] ? devkmsg_read+0x4b0/0x4b0 [ 31.034759][ T2656] ? console_flush_one_record+0x106/0x710 [ 31.034762][ T2656] ? rcu_is_watching+0x16/0xd0 [ 31.034764][ T2656] ? lock_acquire+0x13c/0x160 [ 31.034768][ T2656] console_flush_one_record+0x46f/0x710 [ 31.034772][ T2656] ? console_emit_next_record+0x4c0/0x4c0 [ 31.034774][ T2656] ? __lock_acquire+0x518/0xc20 [ 31.034779][ T2656] console_unlock+0xee/0x1f0 [ 31.034782][ T2656] ? console_flush_one_record+0x710/0x710 [ 31.034784][ T2656] ? rcu_is_watching+0x16/0xd0 [ 31.034786][ T2656] ? lock_acquire+0xe0/0x160 [ 31.034789][ T2656] ? __down_trylock_console_sem+0x5e/0xa0 [ 31.034791][ T2656] ? vprintk_emit+0x320/0x3e0 [ 31.034794][ T2656] vprintk_emit+0x37c/0x3e0 [ 31.034797][ T2656] ? wake_up_klogd_work_func+0x90/0x90 [ 31.034800][ T2656] ? __lock_acquire+0x518/0xc20 [ 31.034804][ T2656] _printk+0xc7/0x100 [ 31.034807][ T2656] ? snapshot_read.cold+0x21/0x21 [ 31.034810][ T2656] ? do_raw_spin_lock+0x131/0x280 [ 31.034817][ T2656] ? __rwlock_init+0x150/0x150 [ 31.034821][ T2656] ? do_raw_spin_lock+0x131/0x280 [ 31.034824][ T2656] do_exit.cold+0x82/0x9c [ 31.034827][ T2656] ? exit_notify+0x890/0x890 [ 31.034829][ T2656] ? __lock_release.isra.0+0x69/0x1a0 [ 31.034832][ T2656] ? rcu_is_watching+0x16/0xd0 [ 31.034835][ T2656] do_group_exit+0xb8/0x370 [ 31.034838][ T2656] __x64_sys_exit_group+0x3c/0x50 [ 31.034840][ T2656] x64_sys_call+0x1567/0x1570 [ 31.034842][ T2656] do_syscall_64+0xff/0x530 [ 31.034846][ T2656] ? exc_page_fault+0xee/0x100 [ 31.034849][ T2656] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 31.034851][ T2656] RIP: 0033:0x7efe8f2ae1b8 [ 31.034853][ T2656] Code: Unable to access opcode bytes at 0x7efe8f2ae18e. [ 31.034854][ T2656] RSP: 002b:00007ffcc90ca458 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 31.034857][ T2656] RAX: ffffffffffffffda RBX: 00007efe8f3def88 RCX: 00007efe8f2ae1b8 [ 31.034858][ T2656] RDX: 00007efe8eff8fc8 RSI: fffffffffffffeb8 RDI: 0000000000000002 [ 31.034859][ T2656] RBP: 00007ffcc90ca4b0 R08: 0000000000000000 R09: 0000000000008010 [ 31.034860][ T2656] R10: 00007ffcc90ca270 R11: 0000000000000246 R12: 0000000000000001 [ 31.034861][ T2656] R13: 0000000000000002 R14: 00007efe8f3dd680 R15: 00007efe8f3defa0 [ 31.034868][ T2656] [ 46.499821][ C0] clocksource: Watchdog remote CPU 3 read timed out [ 46.500006][ C0] [ 46.500008][ C0] ======================================================== [ 46.500009][ C0] WARNING: possible irq lock inversion dependency detected [ 46.500011][ C0] 7.2.0-virtme #1 Tainted: G W [ 46.500012][ C0] -------------------------------------------------------- [ 46.500013][ C0] ip/5250 just changed the state of lock: [ 46.500014][ C0] ffffffff8e69d760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 46.500027][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 46.500028][ C0] (fs_reclaim){+.+.}-{0:0} [ 46.500029][ C0] [ 46.500029][ C0] [ 46.500029][ C0] and interrupts could create inverse lock ordering between them. [ 46.500029][ C0] [ 46.500030][ C0] [ 46.500030][ C0] other info that might help us debug this: [ 46.500031][ C0] Possible interrupt unsafe locking scenario: [ 46.500031][ C0] [ 46.500031][ C0] CPU0 CPU1 [ 46.500032][ C0] ---- ---- [ 46.500032][ C0] lock(fs_reclaim); [ 46.500033][ C0] local_irq_disable(); [ 46.500034][ C0] lock(console_owner); [ 46.500034][ C0] lock(fs_reclaim); [ 46.500035][ C0] [ 46.500036][ C0] lock(console_owner); [ 46.500036][ C0] [ 46.500036][ C0] *** DEADLOCK *** [ 46.500036][ C0] [ 46.500037][ C0] locks held by ip/5250: 4, last CPU#0: [ 46.500038][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 46.500044][ C0] #1: ffffffff8e7e29f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 46.500048][ C0] #2: ffffffff8e77ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 46.500051][ C0] #3: ffffffff8e77de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 46.500054][ C0] [ 46.500054][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 46.500058][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 46.500060][ C0] HARDIRQ-ON-W at: [ 46.500062][ C0] __lock_acquire+0x388/0xc20 [ 46.500065][ C0] lock_acquire.part.0+0xd4/0x280 [ 46.500067][ C0] fs_reclaim_acquire+0xd5/0x120 [ 46.500070][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 46.500072][ C0] kthread_create_worker_on_node+0xea/0x210 [ 46.500075][ C0] workqueue_init+0x2a/0x680 [ 46.500079][ C0] kernel_init_freeable+0x2fe/0x630 [ 46.500082][ C0] kernel_init+0x21/0x150 [ 46.500086][ C0] ret_from_fork+0x474/0x6b0 [ 46.500089][ C0] ret_from_fork_asm+0x11/0x20 [ 46.500092][ C0] SOFTIRQ-ON-W at: [ 46.500093][ C0] __lock_acquire+0x388/0xc20 [ 46.500095][ C0] lock_acquire.part.0+0xd4/0x280 [ 46.500096][ C0] fs_reclaim_acquire+0xd5/0x120 [ 46.500097][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 46.500098][ C0] kthread_create_worker_on_node+0xea/0x210 [ 46.500100][ C0] workqueue_init+0x2a/0x680 [ 46.500101][ C0] kernel_init_freeable+0x2fe/0x630 [ 46.500103][ C0] kernel_init+0x21/0x150 [ 46.500104][ C0] ret_from_fork+0x474/0x6b0 [ 46.500105][ C0] ret_from_fork_asm+0x11/0x20 [ 46.500107][ C0] INITIAL USE at: [ 46.500107][ C0] __lock_acquire+0x388/0xc20 [ 46.500109][ C0] lock_acquire.part.0+0xd4/0x280 [ 46.500110][ C0] fs_reclaim_acquire+0xd5/0x120 [ 46.500112][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 46.500113][ C0] kthread_create_worker_on_node+0xea/0x210 [ 46.500114][ C0] workqueue_init+0x2a/0x680 [ 46.500116][ C0] kernel_init_freeable+0x2fe/0x630 [ 46.500117][ C0] kernel_init+0x21/0x150 [ 46.500119][ C0] ret_from_fork+0x474/0x6b0 [ 46.500120][ C0] ret_from_fork_asm+0x11/0x20 [ 46.500121][ C0] } [ 46.500122][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 46.500126][ C0] ... acquired at: [ 46.500127][ C0] __lock_acquire+0x518/0xc20 [ 46.500128][ C0] lock_acquire.part.0+0xd4/0x280 [ 46.500129][ C0] fs_reclaim_acquire+0xd5/0x120 [ 46.500131][ C0] __kmalloc_noprof+0xd3/0x760 [ 46.500132][ C0] alloc_buf.isra.0+0x4b/0x260 [ 46.500136][ C0] put_chars+0x1e1/0x2f0 [ 46.500137][ C0] hvc_console_print+0x292/0x780 [ 46.500140][ C0] console_emit_next_record+0x22f/0x4c0 [ 46.500142][ C0] console_flush_one_record+0x46f/0x710 [ 46.500143][ C0] console_unlock+0xee/0x1f0 [ 46.500145][ C0] vprintk_emit+0x37c/0x3e0 [ 46.500146][ C0] _printk+0xc7/0x100 [ 46.500149][ C0] i8042_pnp_init+0xf7/0x3c0 [ 46.500151][ C0] i8042_platform_init+0x3f9/0x460 [ 46.500153][ C0] i8042_init+0x45/0x130 [ 46.500154][ C0] do_one_initcall+0x124/0x4f0 [ 46.500155][ C0] kernel_init_freeable+0x596/0x630 [ 46.500157][ C0] kernel_init+0x21/0x150 [ 46.500158][ C0] ret_from_fork+0x474/0x6b0 [ 46.500159][ C0] ret_from_fork_asm+0x11/0x20 [ 46.500161][ C0] [ 46.500161][ C0] -> (console_owner){..-.}-{0:0} { [ 46.500163][ C0] IN-SOFTIRQ-W at: [ 46.500164][ C0] __lock_acquire+0x388/0xc20 [ 46.500165][ C0] lock_acquire.part.0+0xd4/0x280 [ 46.500167][ C0] console_lock_spinning_enable+0x5c/0x60 [ 46.500168][ C0] console_emit_next_record+0x1d1/0x4c0 [ 46.500170][ C0] console_flush_one_record+0x46f/0x710 [ 46.500171][ C0] console_unlock+0xee/0x1f0 [ 46.500173][ C0] vprintk_emit+0x37c/0x3e0 [ 46.500174][ C0] _printk+0xc7/0x100 [ 46.500175][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 46.500180][ C0] call_timer_fn+0x160/0x4d0 [ 46.500181][ C0] __run_timers+0x68f/0xaa0 [ 46.500183][ C0] run_timer_softirq+0xf0/0x160 [ 46.500185][ C0] handle_softirqs+0x1d3/0x900 [ 46.500187][ C0] __irq_exit_rcu+0x145/0x1c0 [ 46.500189][ C0] irq_exit_rcu+0xe/0x30 [ 46.500190][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 46.500192][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 46.500193][ C0] _raw_spin_unlock_irqrestore+0x36/0x80 [ 46.500195][ C0] debug_object_free+0x270/0x530 [ 46.500198][ C0] percpu_counter_destroy_many+0x92/0x4f0 [ 46.500201][ C0] __mmdrop+0x23d/0x430 [ 46.500203][ C0] setup_new_exec+0x2db/0x490 [ 46.500205][ C0] load_elf_binary+0x492/0x2cd0 [ 46.500208][ C0] exec_binprm+0x27a/0x750 [ 46.500209][ C0] bprm_execve.part.0+0x13a/0x400 [ 46.500210][ C0] do_execveat_common.isra.0+0x3e4/0x4f0 [ 46.500211][ C0] __x64_sys_execve+0x91/0xc0 [ 46.500213][ C0] do_syscall_64+0xff/0x530 [ 46.500215][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 46.500216][ C0] INITIAL USE at: [ 46.500217][ C0] } [ 46.500218][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 46.500220][ C0] ... acquired at: [ 46.500221][ C0] mark_lock+0x1d7/0xa00 [ 46.500222][ C0] mark_usage+0x42/0x170 [ 46.500223][ C0] __lock_acquire+0x388/0xc20 [ 46.500225][ C0] lock_acquire.part.0+0xd4/0x280 [ 46.500226][ C0] console_lock_spinning_enable+0x5c/0x60 [ 46.500228][ C0] console_emit_next_record+0x1d1/0x4c0 [ 46.500229][ C0] console_flush_one_record+0x46f/0x710 [ 46.500231][ C0] console_unlock+0xee/0x1f0 [ 46.500232][ C0] vprintk_emit+0x37c/0x3e0 [ 46.500233][ C0] _printk+0xc7/0x100 [ 46.500234][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 46.500235][ C0] call_timer_fn+0x160/0x4d0 [ 46.500237][ C0] __run_timers+0x68f/0xaa0 [ 46.500238][ C0] run_timer_softirq+0xf0/0x160 [ 46.500240][ C0] handle_softirqs+0x1d3/0x900 [ 46.500241][ C0] __irq_exit_rcu+0x145/0x1c0 [ 46.500242][ C0] irq_exit_rcu+0xe/0x30 [ 46.500243][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 46.500244][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 46.500245][ C0] _raw_spin_unlock_irqrestore+0x36/0x80 [ 46.500247][ C0] debug_object_free+0x270/0x530 [ 46.500248][ C0] percpu_counter_destroy_many+0x92/0x4f0 [ 46.500249][ C0] __mmdrop+0x23d/0x430 [ 46.500250][ C0] setup_new_exec+0x2db/0x490 [ 46.500251][ C0] load_elf_binary+0x492/0x2cd0 [ 46.500253][ C0] exec_binprm+0x27a/0x750 [ 46.500254][ C0] bprm_execve.part.0+0x13a/0x400 [ 46.500255][ C0] do_execveat_common.isra.0+0x3e4/0x4f0 [ 46.500256][ C0] __x64_sys_execve+0x91/0xc0 [ 46.500257][ C0] do_syscall_64+0xff/0x530 [ 46.500259][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 46.500260][ C0] [ 46.500260][ C0] [ 46.500260][ C0] stack backtrace: [ 46.500263][ C0] CPU: 0 UID: 0 PID: 5250 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 46.500266][ C0] Tainted: [W]=WARN [ 46.500267][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 46.500268][ C0] Call Trace: [ 46.500269][ C0] [ 46.500270][ C0] dump_stack_lvl+0x6f/0xa0 [ 46.500275][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 46.500277][ C0] mark_lock_irq+0x989/0x9c0 [ 46.500279][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 46.500282][ C0] mark_lock+0x1d7/0xa00 [ 46.500283][ C0] mark_usage+0x42/0x170 [ 46.500285][ C0] __lock_acquire+0x388/0xc20 [ 46.500287][ C0] lock_acquire.part.0+0xd4/0x280 [ 46.500289][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 46.500291][ C0] ? rcu_is_watching+0x16/0xd0 [ 46.500293][ C0] ? lock_acquire+0x13c/0x160 [ 46.500295][ C0] console_lock_spinning_enable+0x5c/0x60 [ 46.500297][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 46.500299][ C0] console_emit_next_record+0x1d1/0x4c0 [ 46.500301][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 46.500303][ C0] ? console_flush_one_record+0x106/0x710 [ 46.500305][ C0] ? rcu_is_watching+0x16/0xd0 [ 46.500306][ C0] ? lock_acquire+0x13c/0x160 [ 46.500308][ C0] console_flush_one_record+0x46f/0x710 [ 46.500310][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 46.500312][ C0] ? __lock_acquire+0x518/0xc20 [ 46.500314][ C0] console_unlock+0xee/0x1f0 [ 46.500316][ C0] ? console_flush_one_record+0x710/0x710 [ 46.500318][ C0] ? rcu_is_watching+0x16/0xd0 [ 46.500319][ C0] ? lock_acquire+0xe0/0x160 [ 46.500321][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 46.500323][ C0] ? vprintk_emit+0x320/0x3e0 [ 46.500324][ C0] vprintk_emit+0x37c/0x3e0 [ 46.500326][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 46.500328][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 46.500329][ C0] _printk+0xc7/0x100 [ 46.500331][ C0] ? snapshot_read.cold+0x21/0x21 [ 46.500333][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 46.500335][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 46.500337][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 46.500338][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 46.500340][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 46.500342][ C0] call_timer_fn+0x160/0x4d0 [ 46.500344][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 46.500346][ C0] ? debug_object_active_state+0x430/0x430 [ 46.500347][ C0] ? find_held_lock+0x2b/0x80 [ 46.500349][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 46.500351][ C0] ? rcu_is_watching+0x16/0xd0 [ 46.500353][ C0] __run_timers+0x68f/0xaa0 [ 46.500354][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 46.500357][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 46.500359][ C0] ? __lock_acquire+0x518/0xc20 [ 46.500361][ C0] ? __rwlock_init+0x150/0x150 [ 46.500364][ C0] run_timer_softirq+0xf0/0x160 [ 46.500366][ C0] ? __run_timers+0xaa0/0xaa0 [ 46.500367][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 46.500371][ C0] ? rcu_is_watching+0x16/0xd0 [ 46.500372][ C0] handle_softirqs+0x1d3/0x900 [ 46.500374][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 46.500375][ C0] ? _local_bh_enable+0xc0/0xc0 [ 46.500377][ C0] __irq_exit_rcu+0x145/0x1c0 [ 46.500379][ C0] irq_exit_rcu+0xe/0x30 [ 46.500380][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 46.500382][ C0] [ 46.500383][ C0] [ 46.500383][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 46.500385][ C0] RIP: 0010:_raw_spin_unlock_irqrestore+0x36/0x80 [ 46.500387][ C0] Code: f5 53 48 8b 74 24 10 48 89 fb 48 83 c7 18 e8 41 80 78 fd 48 89 df e8 49 d6 78 fd f7 c5 00 02 00 00 75 1f 9c 58 f6 c4 02 75 2f 01 00 00 00 e8 d0 9e 6a fd 65 48 83 3d af ab 65 02 00 74 12 5b [ 46.500389][ C0] RSP: 0018:ffa0000006557a28 EFLAGS: 00000246 [ 46.500391][ C0] RAX: 0000000000000092 RBX: ffffffff916eb3e8 RCX: ffffffff8d3e1483 [ 46.500393][ C0] RDX: ff11000020a523c0 RSI: ffffffff8dd11011 RDI: ffffffff8d6949e0 [ 46.500393][ C0] RBP: 0000000000000216 R08: 0000000000000000 R09: 0000000000000000 [ 46.500394][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: ff1100001203aed0 [ 46.500395][ C0] R13: ff11000009a1c958 R14: ffffffff916eb3e0 R15: ffffffff8d699720 [ 46.500397][ C0] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 46.500399][ C0] debug_object_free+0x270/0x530 [ 46.500401][ C0] ? debug_object_init_on_stack+0x30/0x30 [ 46.500403][ C0] ? debug_object_active_state+0x430/0x430 [ 46.500405][ C0] ? rcu_is_watching+0x16/0xd0 [ 46.500406][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 46.500408][ C0] percpu_counter_destroy_many+0x92/0x4f0 [ 46.500409][ C0] ? free_percpu.part.0+0x5f7/0xc70 [ 46.500412][ C0] ? __percpu_counter_sum+0x149/0x2a0 [ 46.500413][ C0] ? kfree+0x22/0x590 [ 46.500415][ C0] __mmdrop+0x23d/0x430 [ 46.500417][ C0] setup_new_exec+0x2db/0x490 [ 46.500418][ C0] load_elf_binary+0x492/0x2cd0 [ 46.500421][ C0] ? __lock_acquire+0x518/0xc20 [ 46.500423][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 46.500424][ C0] ? find_held_lock+0x2b/0x80 [ 46.500426][ C0] ? load_elf_interp.isra.0+0xaa0/0xaa0 [ 46.500428][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 46.500429][ C0] ? exec_binprm+0x25c/0x750 [ 46.500431][ C0] exec_binprm+0x27a/0x750 [ 46.500433][ C0] ? check_unsafe_exec+0x540/0x540 [ 46.500435][ C0] bprm_execve.part.0+0x13a/0x400 [ 46.500437][ C0] do_execveat_common.isra.0+0x3e4/0x4f0 [ 46.500439][ C0] __x64_sys_execve+0x91/0xc0 [ 46.500441][ C0] do_syscall_64+0xff/0x530 [ 46.500443][ C0] ? exc_page_fault+0xee/0x100 [ 46.500444][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 46.500446][ C0] RIP: 0033:0x7fcf526d9f4b [ 46.500450][ C0] Code: Unable to access opcode bytes at 0x7fcf526d9f21. [ 46.500451][ C0] RSP: 002b:00007ffe8ef22a98 EFLAGS: 00000206 ORIG_RAX: 000000000000003b [ 46.500452][ C0] RAX: ffffffffffffffda RBX: 000055b52dff88f0 RCX: 00007fcf526d9f4b [ 46.500453][ C0] RDX: 000055b52e000b20 RSI: 000055b52dff8ec0 RDI: 000055b52dff88f0 [ 46.500454][ C0] RBP: 00007ffe8ef22b80 R08: 000055b52dff17b0 R09: 0000000000000020 [ 46.500455][ C0] R10: 0000000000000021 R11: 0000000000000206 R12: 000055b52dff88f0 [ 46.500455][ C0] R13: 0000000000000000 R14: 000055b52dff8ec0 R15: 000055b52e000b20 [ 46.500458][ C0] [ 46.500461][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 46.500463][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 5250, name: ip [ 46.500464][ C0] preempt_count: 103, expected: 0 [ 46.500465][ C0] RCU nest depth: 0, expected: 0 [ 46.500466][ C0] INFO: lockdep is turned off. [ 46.500466][ C0] irq event stamp: 4215 [ 46.500467][ C0] hardirqs last enabled at (4214): [] __down_trylock_console_sem+0x86/0xa0 [ 46.500469][ C0] hardirqs last disabled at (4215): [] console_emit_next_record+0x3d4/0x4c0 [ 46.500471][ C0] softirqs last enabled at (2448): [] fpu_flush_thread+0x130/0x190 [ 46.500473][ C0] softirqs last disabled at (4201): [] __irq_exit_rcu+0x145/0x1c0 [ 46.500475][ C0] Preemption disabled at: [ 46.500475][ C0] [<0000000000000000>] 0x0 [ 46.500477][ C0] CPU: 0 UID: 0 PID: 5250 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 46.500479][ C0] Tainted: [W]=WARN [ 46.500480][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 46.500480][ C0] Call Trace: [ 46.500481][ C0] [ 46.500481][ C0] dump_stack_lvl+0x6f/0xa0 [ 46.500484][ C0] __might_resched.cold+0x1fe/0x2c1 [ 46.500486][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 46.500489][ C0] ? rcu_is_watching+0x16/0xd0 [ 46.500491][ C0] __kmalloc_noprof+0x443/0x760 [ 46.500492][ C0] ? __rwlock_init+0x150/0x150 [ 46.500494][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 46.500496][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 46.500498][ C0] alloc_buf.isra.0+0x4b/0x260 [ 46.500500][ C0] put_chars+0x1e1/0x2f0 [ 46.500502][ C0] ? __send_to_port+0x420/0x420 [ 46.500503][ C0] ? mark_lock+0x1d7/0xa00 [ 46.500506][ C0] hvc_console_print+0x292/0x780 [ 46.500509][ C0] ? hvc_write+0x3a0/0x3a0 [ 46.500511][ C0] ? rcu_is_watching+0x16/0xd0 [ 46.500512][ C0] ? rcu_is_watching+0x16/0xd0 [ 46.500513][ C0] console_emit_next_record+0x22f/0x4c0 [ 46.500516][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 46.500518][ C0] ? console_flush_one_record+0x106/0x710 [ 46.500520][ C0] ? rcu_is_watching+0x16/0xd0 [ 46.500521][ C0] ? lock_acquire+0x13c/0x160 [ 46.500523][ C0] console_flush_one_record+0x46f/0x710 [ 46.500525][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 46.500527][ C0] ? __lock_acquire+0x518/0xc20 [ 46.500529][ C0] console_unlock+0xee/0x1f0 [ 46.500531][ C0] ? console_flush_one_record+0x710/0x710 [ 46.500533][ C0] ? rcu_is_watching+0x16/0xd0 [ 46.500534][ C0] ? lock_acquire+0xe0/0x160 [ 46.500535][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 46.500537][ C0] ? vprintk_emit+0x320/0x3e0 [ 46.500538][ C0] vprintk_emit+0x37c/0x3e0 [ 46.500540][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 46.500542][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 46.500543][ C0] _printk+0xc7/0x100 [ 46.500545][ C0] ? snapshot_read.cold+0x21/0x21 [ 46.500547][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 46.500548][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 46.500551][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 46.500552][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 46.500554][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 46.500555][ C0] call_timer_fn+0x160/0x4d0 [ 46.500558][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 46.500559][ C0] ? debug_object_active_state+0x430/0x430 [ 46.500561][ C0] ? find_held_lock+0x2b/0x80 [ 46.500562][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 46.500564][ C0] ? rcu_is_watching+0x16/0xd0 [ 46.500566][ C0] __run_timers+0x68f/0xaa0 [ 46.500567][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 46.500570][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 46.500571][ C0] ? __lock_acquire+0x518/0xc20 [ 46.500574][ C0] ? __rwlock_init+0x150/0x150 [ 46.500576][ C0] run_timer_softirq+0xf0/0x160 [ 46.500578][ C0] ? __run_timers+0xaa0/0xaa0 [ 46.500580][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 46.500581][ C0] ? rcu_is_watching+0x16/0xd0 [ 46.500583][ C0] handle_softirqs+0x1d3/0x900 [ 46.500584][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 46.500586][ C0] ? _local_bh_enable+0xc0/0xc0 [ 46.500588][ C0] __irq_exit_rcu+0x145/0x1c0 [ 46.500590][ C0] irq_exit_rcu+0xe/0x30 [ 46.500591][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 46.500593][ C0] [ 46.500593][ C0] [ 46.500594][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 46.500595][ C0] RIP: 0010:_raw_spin_unlock_irqrestore+0x36/0x80 [ 46.500597][ C0] Code: f5 53 48 8b 74 24 10 48 89 fb 48 83 c7 18 e8 41 80 78 fd 48 89 df e8 49 d6 78 fd f7 c5 00 02 00 00 75 1f 9c 58 f6 c4 02 75 2f 01 00 00 00 e8 d0 9e 6a fd 65 48 83 3d af ab 65 02 00 74 12 5b [ 46.500598][ C0] RSP: 0018:ffa0000006557a28 EFLAGS: 00000246 [ 46.500599][ C0] RAX: 0000000000000092 RBX: ffffffff916eb3e8 RCX: ffffffff8d3e1483 [ 46.500599][ C0] RDX: ff11000020a523c0 RSI: ffffffff8dd11011 RDI: ffffffff8d6949e0 [ 46.500600][ C0] RBP: 0000000000000216 R08: 0000000000000000 R09: 0000000000000000 [ 46.500601][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: ff1100001203aed0 [ 46.500601][ C0] R13: ff11000009a1c958 R14: ffffffff916eb3e0 R15: ffffffff8d699720 [ 46.500603][ C0] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 46.500605][ C0] debug_object_free+0x270/0x530 [ 46.500607][ C0] ? debug_object_init_on_stack+0x30/0x30 [ 46.500609][ C0] ? debug_object_active_state+0x430/0x430 [ 46.500611][ C0] ? rcu_is_watching+0x16/0xd0 [ 46.500612][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 46.500613][ C0] percpu_counter_destroy_many+0x92/0x4f0 [ 46.500615][ C0] ? free_percpu.part.0+0x5f7/0xc70 [ 46.500617][ C0] ? __percpu_counter_sum+0x149/0x2a0 [ 46.500618][ C0] ? kfree+0x22/0x590 [ 46.500619][ C0] __mmdrop+0x23d/0x430 [ 46.500621][ C0] setup_new_exec+0x2db/0x490 [ 46.500623][ C0] load_elf_binary+0x492/0x2cd0 [ 46.500625][ C0] ? __lock_acquire+0x518/0xc20 [ 46.500627][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 46.500629][ C0] ? find_held_lock+0x2b/0x80 [ 46.500630][ C0] ? load_elf_interp.isra.0+0xaa0/0xaa0 [ 46.500632][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 46.500634][ C0] ? exec_binprm+0x25c/0x750 [ 46.500635][ C0] exec_binprm+0x27a/0x750 [ 46.500637][ C0] ? check_unsafe_exec+0x540/0x540 [ 46.500639][ C0] bprm_execve.part.0+0x13a/0x400 [ 46.500641][ C0] do_execveat_common.isra.0+0x3e4/0x4f0 [ 46.500643][ C0] __x64_sys_execve+0x91/0xc0 [ 46.500645][ C0] do_syscall_64+0xff/0x530 [ 46.500646][ C0] ? exc_page_fault+0xee/0x100 [ 46.500648][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 46.500649][ C0] RIP: 0033:0x7fcf526d9f4b [ 46.500651][ C0] Code: Unable to access opcode bytes at 0x7fcf526d9f21. [ 46.500651][ C0] RSP: 002b:00007ffe8ef22a98 EFLAGS: 00000206 ORIG_RAX: 000000000000003b [ 46.500652][ C0] RAX: ffffffffffffffda RBX: 000055b52dff88f0 RCX: 00007fcf526d9f4b [ 46.500653][ C0] RDX: 000055b52e000b20 RSI: 000055b52dff8ec0 RDI: 000055b52dff88f0 [ 46.500654][ C0] RBP: 00007ffe8ef22b80 R08: 000055b52dff17b0 R09: 0000000000000020 [ 46.500654][ C0] R10: 0000000000000021 R11: 0000000000000206 R12: 000055b52dff88f0 [ 46.500655][ C0] R13: 0000000000000000 R14: 000055b52dff8ec0 R15: 000055b52e000b20 [ 46.500657][ C0]