[ 27.497915][ C0] clocksource: Watchdog remote CPU 1 read timed out [ 27.498117][ C0] [ 27.498119][ C0] ======================================================== [ 27.498120][ C0] WARNING: possible irq lock inversion dependency detected [ 27.498122][ C0] 7.2.0-virtme #1 Tainted: G W [ 27.498124][ C0] -------------------------------------------------------- [ 27.498124][ C0] ipvtap_test.sh/1437 just changed the state of lock: [ 27.498126][ C0] ffffffff8f09d760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 27.498139][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.498140][ C0] (fs_reclaim){+.+.}-{0:0} [ 27.498142][ C0] [ 27.498142][ C0] [ 27.498142][ C0] and interrupts could create inverse lock ordering between them. [ 27.498142][ C0] [ 27.498142][ C0] [ 27.498142][ C0] other info that might help us debug this: [ 27.498143][ C0] Possible interrupt unsafe locking scenario: [ 27.498143][ C0] [ 27.498143][ C0] CPU0 CPU1 [ 27.498144][ C0] ---- ---- [ 27.498144][ C0] lock(fs_reclaim); [ 27.498145][ C0] local_irq_disable(); [ 27.498146][ C0] lock(console_owner); [ 27.498147][ C0] lock(fs_reclaim); [ 27.498148][ C0] [ 27.498148][ C0] lock(console_owner); [ 27.498149][ C0] [ 27.498149][ C0] *** DEADLOCK *** [ 27.498149][ C0] [ 27.498149][ C0] locks held by ipvtap_test.sh/1437: 5, last CPU#0: [ 27.498151][ C0] #0: ffffffff8f194c00 (rcu_read_lock){....}-{1:3}, at: unwind_next_frame+0x17e/0x1ea0 [ 27.498156][ C0] #1: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.498161][ C0] #2: ffffffff8f1e29f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 27.498164][ C0] #3: ffffffff8f17ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 27.498168][ C0] #4: ffffffff8f17de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 27.498171][ C0] [ 27.498171][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 27.498176][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 27.498179][ C0] HARDIRQ-ON-W at: [ 27.498180][ C0] __lock_acquire+0x388/0xc20 [ 27.498184][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.498186][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.498189][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.498191][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.498194][ C0] workqueue_init+0x2a/0x680 [ 27.498198][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.498201][ C0] kernel_init+0x21/0x150 [ 27.498209][ C0] ret_from_fork+0x474/0x6b0 [ 27.498212][ C0] ret_from_fork_asm+0x11/0x20 [ 27.498216][ C0] SOFTIRQ-ON-W at: [ 27.498216][ C0] __lock_acquire+0x388/0xc20 [ 27.498218][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.498220][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.498221][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.498222][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.498224][ C0] workqueue_init+0x2a/0x680 [ 27.498225][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.498227][ C0] kernel_init+0x21/0x150 [ 27.498228][ C0] ret_from_fork+0x474/0x6b0 [ 27.498229][ C0] ret_from_fork_asm+0x11/0x20 [ 27.498231][ C0] INITIAL USE at: [ 27.498232][ C0] __lock_acquire+0x388/0xc20 [ 27.498233][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.498234][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.498236][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.498237][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.498238][ C0] workqueue_init+0x2a/0x680 [ 27.498240][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.498241][ C0] kernel_init+0x21/0x150 [ 27.498243][ C0] ret_from_fork+0x474/0x6b0 [ 27.498244][ C0] ret_from_fork_asm+0x11/0x20 [ 27.498245][ C0] } [ 27.498245][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.498250][ C0] ... acquired at: [ 27.498251][ C0] __lock_acquire+0x518/0xc20 [ 27.498252][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.498253][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.498255][ C0] __kmalloc_noprof+0xd3/0x760 [ 27.498256][ C0] alloc_buf.isra.0+0x4b/0x260 [ 27.498260][ C0] put_chars+0x1e1/0x2f0 [ 27.498261][ C0] hvc_console_print+0x292/0x780 [ 27.498264][ C0] console_emit_next_record+0x22f/0x4c0 [ 27.498265][ C0] console_flush_one_record+0x46f/0x710 [ 27.498267][ C0] console_unlock+0xee/0x1f0 [ 27.498269][ C0] vprintk_emit+0x37c/0x3e0 [ 27.498269][ C0] _printk+0xc7/0x100 [ 27.498272][ C0] i8042_pnp_init+0xf7/0x3c0 [ 27.498275][ C0] i8042_platform_init+0x3f9/0x460 [ 27.498277][ C0] i8042_init+0x45/0x130 [ 27.498278][ C0] do_one_initcall+0x124/0x4f0 [ 27.498279][ C0] kernel_init_freeable+0x596/0x630 [ 27.498281][ C0] kernel_init+0x21/0x150 [ 27.498282][ C0] ret_from_fork+0x474/0x6b0 [ 27.498283][ C0] ret_from_fork_asm+0x11/0x20 [ 27.498285][ C0] [ 27.498285][ C0] -> (console_owner){..-.}-{0:0} { [ 27.498287][ C0] IN-SOFTIRQ-W at: [ 27.498288][ C0] __lock_acquire+0x388/0xc20 [ 27.498289][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.498290][ C0] console_lock_spinning_enable+0x5c/0x60 [ 27.498292][ C0] console_emit_next_record+0x1d1/0x4c0 [ 27.498294][ C0] console_flush_one_record+0x46f/0x710 [ 27.498295][ C0] console_unlock+0xee/0x1f0 [ 27.498297][ C0] vprintk_emit+0x37c/0x3e0 [ 27.498298][ C0] _printk+0xc7/0x100 [ 27.498299][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 27.498302][ C0] call_timer_fn+0x160/0x4d0 [ 27.498303][ C0] __run_timers+0x68f/0xaa0 [ 27.498305][ C0] run_timer_softirq+0xf0/0x160 [ 27.498307][ C0] handle_softirqs+0x1d3/0x900 [ 27.498309][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.498310][ C0] irq_exit_rcu+0xe/0x30 [ 27.498312][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.498313][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.498315][ C0] stack_access_ok+0x92/0x1e0 [ 27.498316][ C0] unwind_next_frame+0x13ec/0x1ea0 [ 27.498318][ C0] arch_stack_walk+0xd7/0x130 [ 27.498319][ C0] stack_trace_save+0x93/0xc0 [ 27.498321][ C0] kasan_save_stack+0x2f/0x50 [ 27.498323][ C0] kasan_save_track+0x14/0x30 [ 27.498324][ C0] kasan_save_free_info+0x3b/0x60 [ 27.498326][ C0] __kasan_slab_free+0x43/0x70 [ 27.498327][ C0] kmem_cache_free+0xf8/0x550 [ 27.498328][ C0] fuse_chan_send+0x550/0x7a0 [ 27.498331][ C0] fuse_send_open+0x222/0x350 [ 27.498333][ C0] fuse_file_open+0x31d/0x8f0 [ 27.498335][ C0] fuse_open+0x1b9/0x800 [ 27.498336][ C0] do_dentry_open+0x413/0x1270 [ 27.498338][ C0] vfs_open+0x7a/0x440 [ 27.498339][ C0] backing_file_open+0x83/0xf0 [ 27.498342][ C0] ovl_open_realfile+0x2bb/0x370 [ 27.498345][ C0] ovl_open+0x1ab/0x2f0 [ 27.498346][ C0] do_dentry_open+0x413/0x1270 [ 27.498347][ C0] vfs_open+0x7a/0x440 [ 27.498348][ C0] do_open+0x565/0xd50 [ 27.498351][ C0] path_openat+0x1d0/0x3b0 [ 27.498352][ C0] do_file_open+0x209/0x480 [ 27.498353][ C0] do_open_execat+0xa7/0x300 [ 27.498355][ C0] alloc_bprm+0x24/0x6f0 [ 27.498356][ C0] do_execveat_common.isra.0+0x16e/0x4f0 [ 27.498357][ C0] __x64_sys_execve+0x91/0xc0 [ 27.498359][ C0] do_syscall_64+0xff/0x530 [ 27.498361][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.498362][ C0] INITIAL USE at: [ 27.498363][ C0] } [ 27.498363][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.498366][ C0] ... acquired at: [ 27.498367][ C0] mark_lock+0x1d7/0xa00 [ 27.498368][ C0] mark_usage+0x42/0x170 [ 27.498369][ C0] __lock_acquire+0x388/0xc20 [ 27.498371][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.498372][ C0] console_lock_spinning_enable+0x5c/0x60 [ 27.498374][ C0] console_emit_next_record+0x1d1/0x4c0 [ 27.498376][ C0] console_flush_one_record+0x46f/0x710 [ 27.498377][ C0] console_unlock+0xee/0x1f0 [ 27.498379][ C0] vprintk_emit+0x37c/0x3e0 [ 27.498380][ C0] _printk+0xc7/0x100 [ 27.498381][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 27.498382][ C0] call_timer_fn+0x160/0x4d0 [ 27.498384][ C0] __run_timers+0x68f/0xaa0 [ 27.498385][ C0] run_timer_softirq+0xf0/0x160 [ 27.498387][ C0] handle_softirqs+0x1d3/0x900 [ 27.498388][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.498389][ C0] irq_exit_rcu+0xe/0x30 [ 27.498391][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.498392][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.498393][ C0] stack_access_ok+0x92/0x1e0 [ 27.498394][ C0] unwind_next_frame+0x13ec/0x1ea0 [ 27.498395][ C0] arch_stack_walk+0xd7/0x130 [ 27.498396][ C0] stack_trace_save+0x93/0xc0 [ 27.498398][ C0] kasan_save_stack+0x2f/0x50 [ 27.498398][ C0] kasan_save_track+0x14/0x30 [ 27.498399][ C0] kasan_save_free_info+0x3b/0x60 [ 27.498401][ C0] __kasan_slab_free+0x43/0x70 [ 27.498402][ C0] kmem_cache_free+0xf8/0x550 [ 27.498403][ C0] fuse_chan_send+0x550/0x7a0 [ 27.498404][ C0] fuse_send_open+0x222/0x350 [ 27.498405][ C0] fuse_file_open+0x31d/0x8f0 [ 27.498406][ C0] fuse_open+0x1b9/0x800 [ 27.498407][ C0] do_dentry_open+0x413/0x1270 [ 27.498409][ C0] vfs_open+0x7a/0x440 [ 27.498410][ C0] backing_file_open+0x83/0xf0 [ 27.498411][ C0] ovl_open_realfile+0x2bb/0x370 [ 27.498412][ C0] ovl_open+0x1ab/0x2f0 [ 27.498414][ C0] do_dentry_open+0x413/0x1270 [ 27.498415][ C0] vfs_open+0x7a/0x440 [ 27.498416][ C0] do_open+0x565/0xd50 [ 27.498417][ C0] path_openat+0x1d0/0x3b0 [ 27.498418][ C0] do_file_open+0x209/0x480 [ 27.498419][ C0] do_open_execat+0xa7/0x300 [ 27.498420][ C0] alloc_bprm+0x24/0x6f0 [ 27.498421][ C0] do_execveat_common.isra.0+0x16e/0x4f0 [ 27.498422][ C0] __x64_sys_execve+0x91/0xc0 [ 27.498423][ C0] do_syscall_64+0xff/0x530 [ 27.498425][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.498426][ C0] [ 27.498426][ C0] [ 27.498426][ C0] stack backtrace: [ 27.498429][ C0] CPU: 0 UID: 0 PID: 1437 Comm: ipvtap_test.sh Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.498432][ C0] Tainted: [W]=WARN [ 27.498433][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.498435][ C0] Call Trace: [ 27.498436][ C0] [ 27.498437][ C0] dump_stack_lvl+0x6f/0xa0 [ 27.498442][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.498444][ C0] mark_lock_irq+0x989/0x9c0 [ 27.498446][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 27.498449][ C0] mark_lock+0x1d7/0xa00 [ 27.498451][ C0] mark_usage+0x42/0x170 [ 27.498453][ C0] __lock_acquire+0x388/0xc20 [ 27.498455][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.498457][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 27.498459][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.498461][ C0] ? lock_acquire+0x13c/0x160 [ 27.498463][ C0] console_lock_spinning_enable+0x5c/0x60 [ 27.498465][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 27.498467][ C0] console_emit_next_record+0x1d1/0x4c0 [ 27.498469][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 27.498471][ C0] ? console_flush_one_record+0x106/0x710 [ 27.498473][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.498474][ C0] ? lock_acquire+0x13c/0x160 [ 27.498476][ C0] console_flush_one_record+0x46f/0x710 [ 27.498478][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 27.498480][ C0] ? __lock_acquire+0x518/0xc20 [ 27.498482][ C0] console_unlock+0xee/0x1f0 [ 27.498484][ C0] ? console_flush_one_record+0x710/0x710 [ 27.498486][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.498487][ C0] ? lock_acquire+0xe0/0x160 [ 27.498489][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.498491][ C0] ? vprintk_emit+0x320/0x3e0 [ 27.498492][ C0] vprintk_emit+0x37c/0x3e0 [ 27.498494][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 27.498496][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 27.498497][ C0] _printk+0xc7/0x100 [ 27.498499][ C0] ? snapshot_read.cold+0x21/0x21 [ 27.498501][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 27.498503][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.498505][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 27.498506][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 27.498508][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 27.498510][ C0] call_timer_fn+0x160/0x4d0 [ 27.498512][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 27.498514][ C0] ? debug_object_active_state+0x430/0x430 [ 27.498517][ C0] ? find_held_lock+0x2b/0x80 [ 27.498519][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.498521][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.498522][ C0] __run_timers+0x68f/0xaa0 [ 27.498524][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 27.498526][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.498528][ C0] ? __lock_acquire+0x518/0xc20 [ 27.498531][ C0] ? __rwlock_init+0x150/0x150 [ 27.498534][ C0] run_timer_softirq+0xf0/0x160 [ 27.498536][ C0] ? __run_timers+0xaa0/0xaa0 [ 27.498538][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.498539][ C0] handle_softirqs+0x1d3/0x900 [ 27.498541][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.498543][ C0] ? _local_bh_enable+0xc0/0xc0 [ 27.498545][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.498546][ C0] irq_exit_rcu+0xe/0x30 [ 27.498548][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.498549][ C0] [ 27.498550][ C0] [ 27.498550][ C0] ? __print_lock_name+0x2/0x80 [ 27.498552][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.498553][ C0] RIP: 0010:stack_access_ok+0x92/0x1e0 [ 27.498556][ C0] Code: 00 00 00 fc ff df 48 89 f9 48 c1 e9 03 80 3c 01 00 0f 85 11 01 00 00 48 8b 43 10 48 39 ea 77 1a 48 39 e8 76 15 4a 8d 4c 35 00 <48> 39 c8 0f 93 c0 48 39 ca 0f 92 c2 20 d0 75 38 48 8d 7b 28 48 8d [ 27.498557][ C0] RSP: 0018:ffa00000032d70c0 EFLAGS: 00000206 [ 27.498560][ C0] RAX: ffa00000032d8000 RBX: ffa00000032d71e8 RCX: ffa00000032d7a68 [ 27.498561][ C0] RDX: ffa00000032d0000 RSI: ffa00000032d7a60 RDI: ffa00000032d71f8 [ 27.498562][ C0] RBP: ffa00000032d7a60 R08: ffffffff8feef2bc R09: ffffffff8fc72da4 [ 27.498562][ C0] R10: ffffffff8be24a6a R11: 0000000000000000 R12: ffa00000032d7a60 [ 27.498563][ C0] R13: ffa00000032d71e8 R14: 0000000000000008 R15: ffffffff8c13cfe0 [ 27.498564][ C0] ? fuse_chan_send+0x550/0x7a0 [ 27.498566][ C0] ? vfs_open+0x7a/0x440 [ 27.498568][ C0] unwind_next_frame+0x13ec/0x1ea0 [ 27.498570][ C0] ? do_dentry_open+0x413/0x1270 [ 27.498573][ C0] ? stack_access_ok+0x1e0/0x1e0 [ 27.498574][ C0] ? __unwind_start+0x42e/0x900 [ 27.498576][ C0] ? write_profile+0xf0/0xf0 [ 27.498578][ C0] arch_stack_walk+0xd7/0x130 [ 27.498579][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 27.498581][ C0] ? vfs_open+0x7a/0x440 [ 27.498582][ C0] stack_trace_save+0x93/0xc0 [ 27.498584][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 27.498585][ C0] ? validate_chain+0x38b/0xc20 [ 27.498587][ C0] ? fuse_chan_send+0x550/0x7a0 [ 27.498589][ C0] ? kasan_save_stack+0x3d/0x50 [ 27.498590][ C0] kasan_save_stack+0x2f/0x50 [ 27.498591][ C0] ? kasan_save_stack+0x2f/0x50 [ 27.498592][ C0] ? kasan_save_track+0x14/0x30 [ 27.498593][ C0] ? kasan_save_free_info+0x3b/0x60 [ 27.498595][ C0] ? __kasan_slab_free+0x43/0x70 [ 27.498596][ C0] ? kmem_cache_free+0xf8/0x550 [ 27.498597][ C0] ? fuse_chan_send+0x550/0x7a0 [ 27.498599][ C0] ? fuse_send_open+0x222/0x350 [ 27.498600][ C0] ? fuse_file_open+0x31d/0x8f0 [ 27.498601][ C0] ? fuse_open+0x1b9/0x800 [ 27.498602][ C0] ? do_dentry_open+0x413/0x1270 [ 27.498603][ C0] ? vfs_open+0x7a/0x440 [ 27.498604][ C0] ? backing_file_open+0x83/0xf0 [ 27.498606][ C0] ? ovl_open_realfile+0x2bb/0x370 [ 27.498607][ C0] ? ovl_open+0x1ab/0x2f0 [ 27.498608][ C0] ? do_dentry_open+0x413/0x1270 [ 27.498613][ C0] kasan_save_track+0x14/0x30 [ 27.498614][ C0] kasan_save_free_info+0x3b/0x60 [ 27.498616][ C0] __kasan_slab_free+0x43/0x70 [ 27.498617][ C0] kmem_cache_free+0xf8/0x550 [ 27.498619][ C0] fuse_chan_send+0x550/0x7a0 [ 27.498621][ C0] fuse_send_open+0x222/0x350 [ 27.498623][ C0] ? fuse_folios_need_send+0x440/0x440 [ 27.498626][ C0] ? __init_waitqueue_head+0xcb/0x160 [ 27.498629][ C0] ? fuse_file_alloc+0x1c1/0x2a0 [ 27.498630][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 27.498632][ C0] fuse_file_open+0x31d/0x8f0 [ 27.498634][ C0] fuse_open+0x1b9/0x800 [ 27.498635][ C0] ? fuse_finish_open+0x2a0/0x2a0 [ 27.498637][ C0] do_dentry_open+0x413/0x1270 [ 27.498640][ C0] vfs_open+0x7a/0x440 [ 27.498641][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 27.498643][ C0] backing_file_open+0x83/0xf0 [ 27.498645][ C0] ? ns_capable+0x59/0xb0 [ 27.498647][ C0] ovl_open_realfile+0x2bb/0x370 [ 27.498649][ C0] ovl_open+0x1ab/0x2f0 [ 27.498650][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 27.498652][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 27.498654][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 27.498655][ C0] do_dentry_open+0x413/0x1270 [ 27.498657][ C0] ? inode_permission+0x2d8/0x510 [ 27.498659][ C0] vfs_open+0x7a/0x440 [ 27.498660][ C0] ? security_inode_permission+0x5b/0x90 [ 27.498663][ C0] ? may_open+0xe6/0x330 [ 27.498664][ C0] do_open+0x565/0xd50 [ 27.498666][ C0] ? open_last_lookups+0x410/0x1030 [ 27.498669][ C0] path_openat+0x1d0/0x3b0 [ 27.498670][ C0] ? find_held_lock+0x2b/0x80 [ 27.498671][ C0] ? kernel_tmpfile_open+0x70/0x70 [ 27.498673][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.498675][ C0] do_file_open+0x209/0x480 [ 27.498676][ C0] ? path_openat+0x3b0/0x3b0 [ 27.498677][ C0] ? __kernel_text_address+0x12/0x30 [ 27.498679][ C0] ? write_profile+0xf0/0xf0 [ 27.498680][ C0] ? unwind_get_return_address+0x67/0xd0 [ 27.498684][ C0] ? __lock_acquire+0x518/0xc20 [ 27.498686][ C0] do_open_execat+0xa7/0x300 [ 27.498688][ C0] ? count.constprop.0.isra.0+0x170/0x170 [ 27.498690][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.498692][ C0] alloc_bprm+0x24/0x6f0 [ 27.498694][ C0] do_execveat_common.isra.0+0x16e/0x4f0 [ 27.498696][ C0] __x64_sys_execve+0x91/0xc0 [ 27.498698][ C0] do_syscall_64+0xff/0x530 [ 27.498700][ C0] ? exc_page_fault+0xee/0x100 [ 27.498701][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.498703][ C0] RIP: 0033:0x7f65b00a7f4b [ 27.498705][ C0] Code: 0f 1e fa 48 8b 05 75 e0 12 00 48 8b 10 e9 0d 00 00 00 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 00 f3 0f 1e fa b8 3b 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 7d de 12 00 f7 d8 64 89 01 48 [ 27.498706][ C0] RSP: 002b:00007ffe502fe398 EFLAGS: 00000206 ORIG_RAX: 000000000000003b [ 27.498708][ C0] RAX: ffffffffffffffda RBX: 000055a4eb774b00 RCX: 00007f65b00a7f4b [ 27.498709][ C0] RDX: 000055a4eb775b20 RSI: 000055a4eb76dec0 RDI: 000055a4eb774b00 [ 27.498710][ C0] RBP: 00007ffe502fe480 R08: 000055a4eb7667b0 R09: 0000000000000020 [ 27.498711][ C0] R10: 0000000000000020 R11: 0000000000000206 R12: 000055a4eb774b00 [ 27.498711][ C0] R13: 0000000000000000 R14: 000055a4eb76dec0 R15: 000055a4eb775b20 [ 27.498714][ C0] [ 27.498718][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.498719][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1437, name: ipvtap_test.sh [ 27.498720][ C0] preempt_count: 102, expected: 0 [ 27.498721][ C0] RCU nest depth: 1, expected: 0 [ 27.498722][ C0] INFO: lockdep is turned off. [ 27.498723][ C0] irq event stamp: 481 [ 27.498723][ C0] hardirqs last enabled at (480): [] __down_trylock_console_sem+0x86/0xa0 [ 27.498726][ C0] hardirqs last disabled at (481): [] console_emit_next_record+0x3d4/0x4c0 [ 27.498728][ C0] softirqs last enabled at (0): [] copy_process+0x1d8f/0x62b0 [ 27.498730][ C0] softirqs last disabled at (467): [] __irq_exit_rcu+0x145/0x1c0 [ 27.498732][ C0] Preemption disabled at: [ 27.498732][ C0] [] handle_softirqs+0xf6/0x900 [ 27.498735][ C0] CPU: 0 UID: 0 PID: 1437 Comm: ipvtap_test.sh Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.498737][ C0] Tainted: [W]=WARN [ 27.498738][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.498738][ C0] Call Trace: [ 27.498739][ C0] [ 27.498740][ C0] dump_stack_lvl+0x6f/0xa0 [ 27.498742][ C0] ? handle_softirqs+0xf6/0x900 [ 27.498743][ C0] __might_resched.cold+0x1fe/0x2c1 [ 27.498746][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.498749][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.498751][ C0] __kmalloc_noprof+0x443/0x760 [ 27.498752][ C0] ? __rwlock_init+0x150/0x150 [ 27.498754][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 27.498756][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 27.498758][ C0] alloc_buf.isra.0+0x4b/0x260 [ 27.498760][ C0] put_chars+0x1e1/0x2f0 [ 27.498762][ C0] ? __send_to_port+0x420/0x420 [ 27.498764][ C0] ? mark_lock+0x1d7/0xa00 [ 27.498768][ C0] hvc_console_print+0x292/0x780 [ 27.498771][ C0] ? hvc_write+0x3a0/0x3a0 [ 27.498773][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.498774][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.498776][ C0] console_emit_next_record+0x22f/0x4c0 [ 27.498778][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 27.498780][ C0] ? console_flush_one_record+0x106/0x710 [ 27.498782][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.498783][ C0] ? lock_acquire+0x13c/0x160 [ 27.498785][ C0] console_flush_one_record+0x46f/0x710 [ 27.498787][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 27.498789][ C0] ? __lock_acquire+0x518/0xc20 [ 27.498791][ C0] console_unlock+0xee/0x1f0 [ 27.498793][ C0] ? console_flush_one_record+0x710/0x710 [ 27.498795][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.498796][ C0] ? lock_acquire+0xe0/0x160 [ 27.498798][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.498800][ C0] ? vprintk_emit+0x320/0x3e0 [ 27.498801][ C0] vprintk_emit+0x37c/0x3e0 [ 27.498802][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 27.498804][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 27.498806][ C0] _printk+0xc7/0x100 [ 27.498808][ C0] ? snapshot_read.cold+0x21/0x21 [ 27.498810][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 27.498811][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.498814][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 27.498815][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 27.498817][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 27.498818][ C0] call_timer_fn+0x160/0x4d0 [ 27.498820][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 27.498822][ C0] ? debug_object_active_state+0x430/0x430 [ 27.498824][ C0] ? find_held_lock+0x2b/0x80 [ 27.498825][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.498827][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.498828][ C0] __run_timers+0x68f/0xaa0 [ 27.498830][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 27.498832][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.498834][ C0] ? __lock_acquire+0x518/0xc20 [ 27.498837][ C0] ? __rwlock_init+0x150/0x150 [ 27.498839][ C0] run_timer_softirq+0xf0/0x160 [ 27.498841][ C0] ? __run_timers+0xaa0/0xaa0 [ 27.498843][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.498844][ C0] handle_softirqs+0x1d3/0x900 [ 27.498846][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.498848][ C0] ? _local_bh_enable+0xc0/0xc0 [ 27.498850][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.498852][ C0] irq_exit_rcu+0xe/0x30 [ 27.498853][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.498855][ C0] [ 27.498855][ C0] [ 27.498856][ C0] ? __print_lock_name+0x2/0x80 [ 27.498857][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.498858][ C0] RIP: 0010:stack_access_ok+0x92/0x1e0 [ 27.498860][ C0] Code: 00 00 00 fc ff df 48 89 f9 48 c1 e9 03 80 3c 01 00 0f 85 11 01 00 00 48 8b 43 10 48 39 ea 77 1a 48 39 e8 76 15 4a 8d 4c 35 00 <48> 39 c8 0f 93 c0 48 39 ca 0f 92 c2 20 d0 75 38 48 8d 7b 28 48 8d [ 27.498861][ C0] RSP: 0018:ffa00000032d70c0 EFLAGS: 00000206 [ 27.498862][ C0] RAX: ffa00000032d8000 RBX: ffa00000032d71e8 RCX: ffa00000032d7a68 [ 27.498863][ C0] RDX: ffa00000032d0000 RSI: ffa00000032d7a60 RDI: ffa00000032d71f8 [ 27.498864][ C0] RBP: ffa00000032d7a60 R08: ffffffff8feef2bc R09: ffffffff8fc72da4 [ 27.498864][ C0] R10: ffffffff8be24a6a R11: 0000000000000000 R12: ffa00000032d7a60 [ 27.498865][ C0] R13: ffa00000032d71e8 R14: 0000000000000008 R15: ffffffff8c13cfe0 [ 27.498866][ C0] ? fuse_chan_send+0x550/0x7a0 [ 27.498868][ C0] ? vfs_open+0x7a/0x440 [ 27.498870][ C0] unwind_next_frame+0x13ec/0x1ea0 [ 27.498872][ C0] ? do_dentry_open+0x413/0x1270 [ 27.498874][ C0] ? stack_access_ok+0x1e0/0x1e0 [ 27.498876][ C0] ? __unwind_start+0x42e/0x900 [ 27.498877][ C0] ? write_profile+0xf0/0xf0 [ 27.498879][ C0] arch_stack_walk+0xd7/0x130 [ 27.498880][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 27.498882][ C0] ? vfs_open+0x7a/0x440 [ 27.498883][ C0] stack_trace_save+0x93/0xc0 [ 27.498885][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 27.498886][ C0] ? validate_chain+0x38b/0xc20 [ 27.498888][ C0] ? fuse_chan_send+0x550/0x7a0 [ 27.498889][ C0] ? kasan_save_stack+0x3d/0x50 [ 27.498891][ C0] kasan_save_stack+0x2f/0x50 [ 27.498892][ C0] ? kasan_save_stack+0x2f/0x50 [ 27.498893][ C0] ? kasan_save_track+0x14/0x30 [ 27.498894][ C0] ? kasan_save_free_info+0x3b/0x60 [ 27.498895][ C0] ? __kasan_slab_free+0x43/0x70 [ 27.498896][ C0] ? kmem_cache_free+0xf8/0x550 [ 27.498897][ C0] ? fuse_chan_send+0x550/0x7a0 [ 27.498899][ C0] ? fuse_send_open+0x222/0x350 [ 27.498900][ C0] ? fuse_file_open+0x31d/0x8f0 [ 27.498901][ C0] ? fuse_open+0x1b9/0x800 [ 27.498902][ C0] ? do_dentry_open+0x413/0x1270 [ 27.498904][ C0] ? vfs_open+0x7a/0x440 [ 27.498905][ C0] ? backing_file_open+0x83/0xf0 [ 27.498907][ C0] ? ovl_open_realfile+0x2bb/0x370 [ 27.498908][ C0] ? ovl_open+0x1ab/0x2f0 [ 27.498909][ C0] ? do_dentry_open+0x413/0x1270 [ 27.498914][ C0] kasan_save_track+0x14/0x30 [ 27.498915][ C0] kasan_save_free_info+0x3b/0x60 [ 27.498916][ C0] __kasan_slab_free+0x43/0x70 [ 27.498918][ C0] kmem_cache_free+0xf8/0x550 [ 27.498920][ C0] fuse_chan_send+0x550/0x7a0 [ 27.498922][ C0] fuse_send_open+0x222/0x350 [ 27.498924][ C0] ? fuse_folios_need_send+0x440/0x440 [ 27.498927][ C0] ? __init_waitqueue_head+0xcb/0x160 [ 27.498928][ C0] ? fuse_file_alloc+0x1c1/0x2a0 [ 27.498930][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 27.498931][ C0] fuse_file_open+0x31d/0x8f0 [ 27.498933][ C0] fuse_open+0x1b9/0x800 [ 27.498935][ C0] ? fuse_finish_open+0x2a0/0x2a0 [ 27.498936][ C0] do_dentry_open+0x413/0x1270 [ 27.498939][ C0] vfs_open+0x7a/0x440 [ 27.498940][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 27.498942][ C0] backing_file_open+0x83/0xf0 [ 27.498944][ C0] ? ns_capable+0x59/0xb0 [ 27.498946][ C0] ovl_open_realfile+0x2bb/0x370 [ 27.498948][ C0] ovl_open+0x1ab/0x2f0 [ 27.498949][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 27.498951][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 27.498952][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 27.498954][ C0] do_dentry_open+0x413/0x1270 [ 27.498956][ C0] ? inode_permission+0x2d8/0x510 [ 27.498958][ C0] vfs_open+0x7a/0x440 [ 27.498959][ C0] ? security_inode_permission+0x5b/0x90 [ 27.498960][ C0] ? may_open+0xe6/0x330 [ 27.498962][ C0] do_open+0x565/0xd50 [ 27.498964][ C0] ? open_last_lookups+0x410/0x1030 [ 27.498966][ C0] path_openat+0x1d0/0x3b0 [ 27.498967][ C0] ? find_held_lock+0x2b/0x80 [ 27.498968][ C0] ? kernel_tmpfile_open+0x70/0x70 [ 27.498970][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.498972][ C0] do_file_open+0x209/0x480 [ 27.498973][ C0] ? path_openat+0x3b0/0x3b0 [ 27.498974][ C0] ? __kernel_text_address+0x12/0x30 [ 27.498975][ C0] ? write_profile+0xf0/0xf0 [ 27.498977][ C0] ? unwind_get_return_address+0x67/0xd0 [ 27.498981][ C0] ? __lock_acquire+0x518/0xc20 [ 27.498983][ C0] do_open_execat+0xa7/0x300 [ 27.498984][ C0] ? count.constprop.0.isra.0+0x170/0x170 [ 27.498986][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.498988][ C0] alloc_bprm+0x24/0x6f0 [ 27.498990][ C0] do_execveat_common.isra.0+0x16e/0x4f0 [ 27.498992][ C0] __x64_sys_execve+0x91/0xc0 [ 27.498993][ C0] do_syscall_64+0xff/0x530 [ 27.498995][ C0] ? exc_page_fault+0xee/0x100 [ 27.498997][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.498998][ C0] RIP: 0033:0x7f65b00a7f4b [ 27.498999][ C0] Code: 0f 1e fa 48 8b 05 75 e0 12 00 48 8b 10 e9 0d 00 00 00 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 00 f3 0f 1e fa b8 3b 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 7d de 12 00 f7 d8 64 89 01 48 [ 27.499000][ C0] RSP: 002b:00007ffe502fe398 EFLAGS: 00000206 ORIG_RAX: 000000000000003b [ 27.499001][ C0] RAX: ffffffffffffffda RBX: 000055a4eb774b00 RCX: 00007f65b00a7f4b [ 27.499002][ C0] RDX: 000055a4eb775b20 RSI: 000055a4eb76dec0 RDI: 000055a4eb774b00 [ 27.499002][ C0] RBP: 00007ffe502fe480 R08: 000055a4eb7667b0 R09: 0000000000000020 [ 27.499003][ C0] R10: 0000000000000020 R11: 0000000000000206 R12: 000055a4eb774b00 [ 27.499004][ C0] R13: 0000000000000000 R14: 000055a4eb76dec0 R15: 000055a4eb775b20 [ 27.499006][ C0]