[ 16.120081][ T227] packetdrill (227) used greatest stack depth: 24088 bytes left [ 16.129070][ C2] [ 16.129253][ C2] ================================ [ 16.129562][ C2] WARNING: inconsistent lock state [ 16.129860][ C2] 6.15.0-virtme #1 Not tainted [ 16.130162][ C2] -------------------------------- [ 16.130452][ C2] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 16.130819][ C2] ksoftirqd/2/27 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 16.131186][ C2] ffffffffaedc5af8 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 16.131717][ C2] {SOFTIRQ-ON-W} state was registered at: [ 16.132014][ C2] __lock_acquire+0x20b/0x7e0 [ 16.132318][ C2] lock_acquire.part.0+0xb6/0x240 [ 16.132616][ C2] _raw_spin_lock+0x33/0x40 [ 16.132915][ C2] simple_pin_fs+0x24/0x150 [ 16.133216][ C2] start_creating.part.0+0x52/0x310 [ 16.133489][ C2] debugfs_create_dir+0x6a/0x540 [ 16.133671][ C2] component_debug_init+0x17/0x30 [ 16.133853][ C2] do_one_initcall+0x8f/0x1e0 [ 16.134048][ C2] do_initcalls+0x176/0x280 [ 16.134233][ C2] kernel_init_freeable+0x22d/0x300 [ 16.134415][ C2] kernel_init+0x20/0x200 [ 16.134596][ C2] ret_from_fork+0x240/0x320 [ 16.134778][ C2] ret_from_fork_asm+0x1a/0x30 [ 16.134960][ C2] irq event stamp: 242520 [ 16.135109][ C2] hardirqs last enabled at (242520): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 16.135469][ C2] hardirqs last disabled at (242519): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 16.135829][ C2] softirqs last enabled at (242046): [] handle_softirqs+0x358/0x620 [ 16.136157][ C2] softirqs last disabled at (242051): [] run_ksoftirqd+0x3f/0x70 [ 16.136474][ C2] [ 16.136474][ C2] other info that might help us debug this: [ 16.136744][ C2] Possible unsafe locking scenario: [ 16.136744][ C2] [ 16.137043][ C2] CPU0 [ 16.137181][ C2] ---- [ 16.137315][ C2] lock(pin_fs_lock); [ 16.137457][ C2] [ 16.137593][ C2] lock(pin_fs_lock); [ 16.137732][ C2] [ 16.137732][ C2] *** DEADLOCK *** [ 16.137732][ C2] [ 16.138013][ C2] 1 lock held by ksoftirqd/2/27: [ 16.138200][ C2] #0: ffffffffaeb774c0 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 16.138528][ C2] [ 16.138528][ C2] stack backtrace: [ 16.138754][ C2] CPU: 2 UID: 0 PID: 27 Comm: ksoftirqd/2 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 16.138759][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.138762][ C2] Call Trace: [ 16.138764][ C2] [ 16.138766][ C2] dump_stack_lvl+0x82/0xd0 [ 16.138772][ C2] print_usage_bug.part.0+0x25f/0x360 [ 16.138778][ C2] mark_lock_irq+0x343/0x400 [ 16.138783][ C2] ? __pfx_unwind_next_frame+0x10/0x10 [ 16.138787][ C2] ? save_trace+0x52/0x170 [ 16.138793][ C2] mark_lock+0x174/0x2f0 [ 16.138797][ C2] mark_usage+0x46/0x150 [ 16.138800][ C2] __lock_acquire+0x20b/0x7e0 [ 16.138803][ C2] ? __lock_acquire+0x44d/0x7e0 [ 16.138807][ C2] lock_acquire.part.0+0xb6/0x240 [ 16.138810][ C2] ? simple_pin_fs+0x24/0x150 [ 16.138815][ C2] ? rcu_is_watching+0x12/0xc0 [ 16.138819][ C2] ? lock_acquire+0x10c/0x170 [ 16.138822][ C2] ? simple_pin_fs+0x24/0x150 [ 16.138826][ C2] _raw_spin_lock+0x33/0x40 [ 16.138830][ C2] ? simple_pin_fs+0x24/0x150 [ 16.138833][ C2] simple_pin_fs+0x24/0x150 [ 16.138837][ C2] debugfs_remove+0x35/0x70 [ 16.138841][ C2] ref_tracker_dir_exit+0xcf/0x740 [ 16.138847][ C2] ? __pfx_ref_tracker_free+0x10/0x10 [ 16.138850][ C2] ? __sk_destruct+0x383/0x780 [ 16.138856][ C2] ? rcu_do_batch+0x34c/0xf20 [ 16.138858][ C2] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 16.138862][ C2] ? smpboot_thread_fn+0x30a/0x960 [ 16.138866][ C2] ? kthread+0x37e/0x600 [ 16.138869][ C2] ? ret_from_fork+0x240/0x320 [ 16.138871][ C2] ? ret_from_fork_asm+0x1a/0x30 [ 16.138875][ C2] ? migrate_enable+0x1f2/0x270 [ 16.138880][ C2] ? __pfx_migrate_enable+0x10/0x10 [ 16.138885][ C2] __put_net+0x19/0x60 [ 16.138889][ C2] __sk_destruct+0x58a/0x780 [ 16.138893][ C2] ? rcu_do_batch+0x34a/0xf20 [ 16.138895][ C2] rcu_do_batch+0x34c/0xf20 [ 16.138898][ C2] ? lockdep_hardirqs_on+0x7c/0x110 [ 16.138902][ C2] ? __pfx_rcu_do_batch+0x10/0x10 [ 16.138905][ C2] ? __pfx_tmigr_handle_remote+0x10/0x10 [ 16.138909][ C2] ? rcu_is_watching+0x12/0xc0 [ 16.138912][ C2] ? note_gp_changes+0x182/0x1e0 [ 16.138915][ C2] ? lockdep_hardirqs_on+0x7c/0x110 [ 16.138919][ C2] rcu_core+0x2bb/0x500 [ 16.138923][ C2] handle_softirqs+0x218/0x620 [ 16.138927][ C2] ? __pfx_run_ksoftirqd+0x10/0x10 [ 16.138931][ C2] run_ksoftirqd+0x3f/0x70 [ 16.138934][ C2] smpboot_thread_fn+0x30a/0x960 [ 16.138937][ C2] ? __pfx_smpboot_thread_fn+0x10/0x10 [ 16.138941][ C2] kthread+0x37e/0x600 [ 16.138944][ C2] ? __pfx_kthread+0x10/0x10 [ 16.138947][ C2] ? ret_from_fork+0x1b/0x320 [ 16.138949][ C2] ? __lock_release+0x5d/0x170 [ 16.138953][ C2] ? rcu_is_watching+0x12/0xc0 [ 16.138956][ C2] ? __pfx_kthread+0x10/0x10 [ 16.138961][ C2] ret_from_fork+0x240/0x320 [ 16.138966][ C2] ? __pfx_kthread+0x10/0x10 [ 16.138970][ C2] ret_from_fork_asm+0x1a/0x30 [ 16.138982][ C2] [ 16.150048][ C2] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 16.150362][ C2] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 27, name: ksoftirqd/2 [ 16.150680][ C2] preempt_count: 100, expected: 0 [ 16.150862][ C2] RCU nest depth: 0, expected: 0 [ 16.151065][ C2] INFO: lockdep is turned off. [ 16.151246][ C2] Preemption disabled at: [ 16.151248][ C2] [] handle_softirqs+0xe5/0x620 [ 16.151629][ C2] CPU: 2 UID: 0 PID: 27 Comm: ksoftirqd/2 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 16.151634][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.151636][ C2] Call Trace: [ 16.151637][ C2] [ 16.151639][ C2] dump_stack_lvl+0xb0/0xd0 [ 16.151646][ C2] __might_resched+0x360/0x5a0 [ 16.151653][ C2] down_write+0x72/0x210 [ 16.151657][ C2] ? __pfx_down_write+0x10/0x10 [ 16.151660][ C2] ? simple_recursive_removal+0x33/0x610 [ 16.151664][ C2] ? rcu_is_watching+0x12/0xc0 [ 16.151668][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 16.151673][ C2] simple_recursive_removal+0x6a/0x610 [ 16.151676][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 16.151679][ C2] ? __pfx_remove_one+0x10/0x10 [ 16.151685][ C2] debugfs_remove+0x44/0x70 [ 16.151688][ C2] ref_tracker_dir_exit+0xcf/0x740 [ 16.151693][ C2] ? __pfx_ref_tracker_free+0x10/0x10 [ 16.151697][ C2] ? __sk_destruct+0x383/0x780 [ 16.151702][ C2] ? rcu_do_batch+0x34c/0xf20 [ 16.151705][ C2] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 16.151708][ C2] ? smpboot_thread_fn+0x30a/0x960 [ 16.151712][ C2] ? kthread+0x37e/0x600 [ 16.151714][ C2] ? ret_from_fork+0x240/0x320 [ 16.151718][ C2] ? ret_from_fork_asm+0x1a/0x30 [ 16.151723][ C2] ? migrate_enable+0x1f2/0x270 [ 16.151726][ C2] ? __pfx_migrate_enable+0x10/0x10 [ 16.151732][ C2] __put_net+0x19/0x60 [ 16.151735][ C2] __sk_destruct+0x58a/0x780 [ 16.151739][ C2] ? rcu_do_batch+0x34a/0xf20 [ 16.151742][ C2] rcu_do_batch+0x34c/0xf20 [ 16.151745][ C2] ? lockdep_hardirqs_on+0x7c/0x110 [ 16.151749][ C2] ? __pfx_rcu_do_batch+0x10/0x10 [ 16.151751][ C2] ? __pfx_tmigr_handle_remote+0x10/0x10 [ 16.151755][ C2] ? rcu_is_watching+0x12/0xc0 [ 16.151758][ C2] ? note_gp_changes+0x182/0x1e0 [ 16.151761][ C2] ? lockdep_hardirqs_on+0x7c/0x110 [ 16.151765][ C2] rcu_core+0x2bb/0x500 [ 16.151769][ C2] handle_softirqs+0x218/0x620 [ 16.151773][ C2] ? __pfx_run_ksoftirqd+0x10/0x10 [ 16.151777][ C2] run_ksoftirqd+0x3f/0x70 [ 16.151779][ C2] smpboot_thread_fn+0x30a/0x960 [ 16.151783][ C2] ? __pfx_smpboot_thread_fn+0x10/0x10 [ 16.151787][ C2] kthread+0x37e/0x600 [ 16.151790][ C2] ? __pfx_kthread+0x10/0x10 [ 16.151792][ C2] ? ret_from_fork+0x1b/0x320 [ 16.151795][ C2] ? __lock_release+0x5d/0x170 [ 16.151798][ C2] ? rcu_is_watching+0x12/0xc0 [ 16.151801][ C2] ? __pfx_kthread+0x10/0x10 [ 16.151804][ C2] ret_from_fork+0x240/0x320 [ 16.151807][ C2] ? __pfx_kthread+0x10/0x10 [ 16.151810][ C2] ret_from_fork_asm+0x1a/0x30 [ 16.151817][ C2] [ 18.586097][ C2] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 18.586539][ C2] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 217, name: timeout [ 18.586915][ C2] preempt_count: 101, expected: 0 [ 18.587247][ C2] RCU nest depth: 0, expected: 0 [ 18.587598][ C2] INFO: lockdep is turned off. [ 18.587813][ C2] Preemption disabled at: [ 18.587815][ C2] [<0000000000000000>] 0x0 [ 18.588214][ C2] CPU: 2 UID: 0 PID: 217 Comm: timeout Tainted: G W 6.15.0-virtme #1 PREEMPT(full) [ 18.588220][ C2] Tainted: [W]=WARN [ 18.588222][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 18.588223][ C2] Call Trace: [ 18.588225][ C2] [ 18.588227][ C2] dump_stack_lvl+0xb0/0xd0 [ 18.588236][ C2] __might_resched+0x360/0x5a0 [ 18.588243][ C2] down_write+0x72/0x210 [ 18.588248][ C2] ? __pfx_down_write+0x10/0x10 [ 18.588251][ C2] ? simple_recursive_removal+0x33/0x610 [ 18.588256][ C2] ? rcu_is_watching+0x12/0xc0 [ 18.588261][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 18.588267][ C2] simple_recursive_removal+0x6a/0x610 [ 18.588269][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 18.588273][ C2] ? __pfx_remove_one+0x10/0x10 [ 18.588278][ C2] debugfs_remove+0x44/0x70 [ 18.588281][ C2] ref_tracker_dir_exit+0xcf/0x740 [ 18.588287][ C2] ? __pfx_ref_tracker_free+0x10/0x10 [ 18.588290][ C2] ? __sk_destruct+0x383/0x780 [ 18.588295][ C2] ? rcu_do_batch+0x34c/0xf20 [ 18.588299][ C2] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 18.588302][ C2] ? irq_exit_rcu+0xe/0x30 [ 18.588307][ C2] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 18.588312][ C2] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 18.588316][ C2] ? _raw_spin_unlock_irqrestore+0x41/0x80 [ 18.588320][ C2] ? __create_object+0x5e/0xb0 [ 18.588325][ C2] ? __kmalloc_cache_noprof+0x2bb/0x360 [ 18.588329][ C2] ? kmem_cache_free+0xf3/0x330 [ 18.588333][ C2] ? exit_mmap+0x3cd/0x800 [ 18.588338][ C2] ? mmput+0xb8/0x3f0 [ 18.588341][ C2] ? exit_mm+0x146/0x1d0 [ 18.588345][ C2] ? do_exit+0x433/0xc20 [ 18.588348][ C2] ? bpf_sk_storage_free+0x9f/0x1c0 [ 18.588353][ C2] ? rcu_is_watching+0x12/0xc0 [ 18.588357][ C2] __put_net+0x19/0x60 [ 18.588361][ C2] __sk_destruct+0x58a/0x780 [ 18.588365][ C2] ? rcu_do_batch+0x34a/0xf20 [ 18.588368][ C2] rcu_do_batch+0x34c/0xf20 [ 18.588372][ C2] ? __pfx_rcu_do_batch+0x10/0x10 [ 18.588376][ C2] ? rcu_is_watching+0x12/0xc0 [ 18.588379][ C2] ? trace_irq_enable.constprop.0+0xd4/0x130 [ 18.588386][ C2] rcu_core+0x2bb/0x500 [ 18.588390][ C2] handle_softirqs+0x218/0x620 [ 18.588396][ C2] __irq_exit_rcu+0xfa/0x160 [ 18.588398][ C2] irq_exit_rcu+0xe/0x30 [ 18.588401][ C2] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 18.588405][ C2] [ 18.588406][ C2] [ 18.588407][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 18.588410][ C2] RIP: 0010:_raw_spin_unlock_irqrestore+0x41/0x80 [ 18.588414][ C2] Code: 74 24 10 e8 c1 2e 83 fd 48 89 ef e8 79 81 83 fd 81 e3 00 02 00 00 75 29 9c 58 f6 c4 02 75 35 48 85 db 74 01 fb bf 01 00 00 00 fa 7e 77 fd 65 8b 05 13 7d 97 02 85 c0 74 0e 5b 5d c3 cc cc cc [ 18.588417][ C2] RSP: 0018:ffffc90000ab7b90 EFLAGS: 00000206 [ 18.588421][ C2] RAX: 0000000000000046 RBX: 0000000000000200 RCX: ffffffffab643b9f [ 18.588422][ C2] RDX: 0000000000000000 RSI: ffffffffade53b60 RDI: 0000000000000001 [ 18.588424][ C2] RBP: ffffffffaed89020 R08: 0000000000000000 R09: 0000000000000000 [ 18.588426][ C2] R10: ffffffffaf5e68d7 R11: ffffc90000ab7a00 R12: ffff888004e6ee60 [ 18.588427][ C2] R13: 0000000000000020 R14: 0000000000000001 R15: 0000000000000286 [ 18.588431][ C2] ? trace_irq_enable.constprop.0+0x2f/0x130 [ 18.588437][ C2] __create_object+0x5e/0xb0 [ 18.588441][ C2] __kmalloc_cache_noprof+0x2bb/0x360 [ 18.588446][ C2] kmem_cache_free+0xf3/0x330 [ 18.588449][ C2] ? exit_mmap+0x3cd/0x800 [ 18.588454][ C2] exit_mmap+0x3cd/0x800 [ 18.588458][ C2] ? __pfx_exit_mmap+0x10/0x10 [ 18.588462][ C2] ? __mutex_lock+0x19c/0xcd0 [ 18.588466][ C2] ? lock_release+0x13/0xc0 [ 18.588469][ C2] ? __mutex_unlock_slowpath+0x144/0x400 [ 18.588478][ C2] mmput+0xb8/0x3f0 [ 18.588483][ C2] exit_mm+0x146/0x1d0 [ 18.588487][ C2] do_exit+0x433/0xc20 [ 18.588491][ C2] do_group_exit+0xb8/0x260 [ 18.588495][ C2] __x64_sys_exit_group+0x3e/0x50 [ 18.588499][ C2] x64_sys_call+0xf76/0x18a0 [ 18.588505][ C2] do_syscall_64+0xc1/0x380 [ 18.588508][ C2] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 18.588511][ C2] RIP: 0033:0x7f61bc9caabd [ 18.588513][ C2] Code: Unable to access opcode bytes at 0x7f61bc9caa93. [ 18.588514][ C2] RSP: 002b:00007ffe3260d8f8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 18.588517][ C2] RAX: ffffffffffffffda RBX: 00007f61bcaa79c0 RCX: 00007f61bc9caabd [ 18.588519][ C2] RDX: 00000000000000e7 RSI: ffffffffffffff80 RDI: 0000000000000000 [ 18.588520][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000020 [ 18.588522][ C2] R10: 00007ffe3260d7a0 R11: 0000000000000246 R12: 00007f61bcaa79c0 [ 18.588524][ C2] R13: 00007f61bcaacee0 R14: 0000000000000002 R15: 00007f61bcaacec8 [ 18.588530][ C2]