[ 14.409413][ C0] [ 14.409562][ C0] ================================ [ 14.409770][ C0] WARNING: inconsistent lock state [ 14.409975][ C0] 6.15.0-rc5-virtme #1 Not tainted [ 14.410191][ C0] -------------------------------- [ 14.410395][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 14.410650][ C0] swapper/0/0 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 14.410906][ C0] ffffffffaefbf978 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 14.411298][ C0] {SOFTIRQ-ON-W} state was registered at: [ 14.411498][ C0] __lock_acquire+0x3eb/0x9d0 [ 14.411708][ C0] lock_acquire.part.0+0xb6/0x240 [ 14.411911][ C0] _raw_spin_lock+0x33/0x40 [ 14.412119][ C0] simple_pin_fs+0x24/0x150 [ 14.412327][ C0] start_creating.part.0+0x3f/0x2f0 [ 14.412534][ C0] debugfs_create_dir+0x6a/0x540 [ 14.412739][ C0] component_debug_init+0x17/0x30 [ 14.412952][ C0] do_one_initcall+0x8c/0x1e0 [ 14.413164][ C0] do_initcalls+0x176/0x280 [ 14.413371][ C0] kernel_init_freeable+0x22d/0x300 [ 14.413574][ C0] kernel_init+0x20/0x200 [ 14.413777][ C0] ret_from_fork+0x31/0x70 [ 14.413985][ C0] ret_from_fork_asm+0x1a/0x30 [ 14.414194][ C0] irq event stamp: 441116 [ 14.414350][ C0] hardirqs last enabled at (441116): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 14.414757][ C0] hardirqs last disabled at (441115): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 14.415156][ C0] softirqs last enabled at (439056): [] handle_softirqs+0x358/0x620 [ 14.415511][ C0] softirqs last disabled at (439087): [] __irq_exit_rcu+0xfa/0x160 [ 14.415866][ C0] [ 14.415866][ C0] other info that might help us debug this: [ 14.416170][ C0] Possible unsafe locking scenario: [ 14.416170][ C0] [ 14.416472][ C0] CPU0 [ 14.416622][ C0] ---- [ 14.416775][ C0] lock(pin_fs_lock); [ 14.416929][ C0] [ 14.417083][ C0] lock(pin_fs_lock); [ 14.417240][ C0] [ 14.417240][ C0] *** DEADLOCK *** [ 14.417240][ C0] [ 14.417548][ C0] 1 lock held by swapper/0/0: [ 14.417752][ C0] #0: ffffffffaed74480 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 14.418121][ C0] [ 14.418121][ C0] stack backtrace: [ 14.418376][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Not tainted 6.15.0-rc5-virtme #1 PREEMPT(full) [ 14.418380][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.418382][ C0] Call Trace: [ 14.418384][ C0] [ 14.418385][ C0] dump_stack_lvl+0x82/0xd0 [ 14.418391][ C0] print_usage_bug.part.0+0x25f/0x360 [ 14.418396][ C0] mark_lock_irq+0x343/0x400 [ 14.418400][ C0] ? is_bpf_text_address+0x67/0x120 [ 14.418405][ C0] ? __lock_release+0x5d/0x170 [ 14.418409][ C0] ? save_trace+0x52/0x170 [ 14.418413][ C0] mark_lock+0x174/0x2f0 [ 14.418417][ C0] mark_usage+0x46/0x150 [ 14.418421][ C0] __lock_acquire+0x3eb/0x9d0 [ 14.418426][ C0] lock_acquire.part.0+0xb6/0x240 [ 14.418430][ C0] ? simple_pin_fs+0x24/0x150 [ 14.418434][ C0] ? rcu_is_watching+0x12/0xc0 [ 14.418439][ C0] ? lock_acquire+0x10c/0x170 [ 14.418442][ C0] ? simple_pin_fs+0x24/0x150 [ 14.418447][ C0] _raw_spin_lock+0x33/0x40 [ 14.418450][ C0] ? simple_pin_fs+0x24/0x150 [ 14.418453][ C0] simple_pin_fs+0x24/0x150 [ 14.418457][ C0] debugfs_remove+0x35/0x70 [ 14.418460][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 14.418465][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 14.418468][ C0] ? __sk_destruct+0x383/0x780 [ 14.418472][ C0] ? rcu_do_batch+0x34c/0xf20 [ 14.418475][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 14.418478][ C0] ? irq_exit_rcu+0xe/0x30 [ 14.418480][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 14.418484][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.418487][ C0] ? pv_native_safe_halt+0xf/0x20 [ 14.418490][ C0] ? default_idle+0x9/0x10 [ 14.418492][ C0] ? default_idle_call+0x6d/0xb0 [ 14.418494][ C0] ? cpuidle_idle_call+0x26f/0x3a0 [ 14.418498][ C0] ? do_idle+0xf6/0x160 [ 14.418501][ C0] ? cpu_startup_entry+0x54/0x60 [ 14.418504][ C0] ? rest_init+0x14b/0x260 [ 14.418506][ C0] ? start_kernel+0x350/0x410 [ 14.418511][ C0] __put_net+0x19/0x60 [ 14.418514][ C0] __sk_destruct+0x58a/0x780 [ 14.418518][ C0] ? rcu_do_batch+0x34a/0xf20 [ 14.418521][ C0] rcu_do_batch+0x34c/0xf20 [ 14.418524][ C0] ? find_held_lock+0x2b/0x80 [ 14.418528][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 14.418532][ C0] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 14.418535][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 14.418539][ C0] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 14.418543][ C0] rcu_core+0x2bb/0x500 [ 14.418546][ C0] handle_softirqs+0x218/0x620 [ 14.418551][ C0] __irq_exit_rcu+0xfa/0x160 [ 14.418553][ C0] irq_exit_rcu+0xe/0x30 [ 14.418555][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 14.418558][ C0] [ 14.418559][ C0] [ 14.418561][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.418564][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 14.418567][ C0] Code: b0 b8 00 c3 cc cc cc cc 0f 1f 00 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 f3 0f 1e fa 66 90 0f 00 2d a3 d0 13 00 fb f4 cc cc cc cc 66 2e 0f 1f 84 00 00 00 00 00 66 90 90 90 90 90 90 [ 14.418570][ C0] RSP: 0018:ffffffffaea07df8 EFLAGS: 00000246 [ 14.418574][ C0] RAX: 000000000006b32d RBX: 1ffffffff5d40fc3 RCX: ffffffffadd55a19 [ 14.418576][ C0] RDX: 0000000000000000 RSI: ffffffffae885f31 RDI: ffffffffae050ce0 [ 14.418577][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1006c0667a [ 14.418579][ C0] R10: ffff8880360333d3 R11: 0000000000000000 R12: 0000000000000000 [ 14.418581][ C0] R13: ffffffffaea1f240 R14: dffffc0000000000 R15: 0000000000014770 [ 14.418584][ C0] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 14.418590][ C0] default_idle+0x9/0x10 [ 14.418592][ C0] default_idle_call+0x6d/0xb0 [ 14.418594][ C0] cpuidle_idle_call+0x26f/0x3a0 [ 14.418598][ C0] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 14.418602][ C0] ? tsc_verify_tsc_adjust+0x98/0x300 [ 14.418608][ C0] do_idle+0xf6/0x160 [ 14.418611][ C0] cpu_startup_entry+0x54/0x60 [ 14.418615][ C0] rest_init+0x14b/0x260 [ 14.418618][ C0] start_kernel+0x350/0x410 [ 14.418621][ C0] x86_64_start_reservations+0x18/0x30 [ 14.418626][ C0] x86_64_start_kernel+0xba/0x110 [ 14.418629][ C0] common_startup_64+0x13e/0x148 [ 14.418637][ C0] [ 14.435961][ C0] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 14.436306][ C0] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0 [ 14.436772][ C0] preempt_count: 101, expected: 0 [ 14.436966][ C0] RCU nest depth: 0, expected: 0 [ 14.437169][ C0] INFO: lockdep is turned off. [ 14.437365][ C0] Preemption disabled at: [ 14.437367][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 14.437926][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Not tainted 6.15.0-rc5-virtme #1 PREEMPT(full) [ 14.437931][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.437932][ C0] Call Trace: [ 14.437934][ C0] [ 14.437935][ C0] dump_stack_lvl+0xb0/0xd0 [ 14.437940][ C0] __might_resched+0x360/0x5a0 [ 14.437945][ C0] down_write+0x72/0x210 [ 14.437948][ C0] ? __pfx_down_write+0x10/0x10 [ 14.437951][ C0] ? simple_recursive_removal+0x3a/0x860 [ 14.437955][ C0] ? rcu_is_watching+0x12/0xc0 [ 14.437960][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 14.437965][ C0] simple_recursive_removal+0x166/0x860 [ 14.437967][ C0] ? __pfx_remove_one+0x10/0x10 [ 14.437971][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 14.437975][ C0] debugfs_remove+0x44/0x70 [ 14.437978][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 14.437985][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 14.437988][ C0] ? __sk_destruct+0x383/0x780 [ 14.437992][ C0] ? rcu_do_batch+0x34c/0xf20 [ 14.437996][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 14.437999][ C0] ? irq_exit_rcu+0xe/0x30 [ 14.438003][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 14.438007][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.438011][ C0] ? pv_native_safe_halt+0xf/0x20 [ 14.438014][ C0] ? default_idle+0x9/0x10 [ 14.438016][ C0] ? default_idle_call+0x6d/0xb0 [ 14.438018][ C0] ? cpuidle_idle_call+0x26f/0x3a0 [ 14.438022][ C0] ? do_idle+0xf6/0x160 [ 14.438025][ C0] ? cpu_startup_entry+0x54/0x60 [ 14.438028][ C0] ? rest_init+0x14b/0x260 [ 14.438030][ C0] ? start_kernel+0x350/0x410 [ 14.438037][ C0] __put_net+0x19/0x60 [ 14.438040][ C0] __sk_destruct+0x58a/0x780 [ 14.438043][ C0] ? rcu_do_batch+0x34a/0xf20 [ 14.438046][ C0] rcu_do_batch+0x34c/0xf20 [ 14.438050][ C0] ? find_held_lock+0x2b/0x80 [ 14.438054][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 14.438058][ C0] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 14.438062][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 14.438065][ C0] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 14.438070][ C0] rcu_core+0x2bb/0x500 [ 14.438073][ C0] handle_softirqs+0x218/0x620 [ 14.438077][ C0] __irq_exit_rcu+0xfa/0x160 [ 14.438080][ C0] irq_exit_rcu+0xe/0x30 [ 14.438082][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 14.438085][ C0] [ 14.438086][ C0] [ 14.438088][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.438090][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 14.438094][ C0] Code: b0 b8 00 c3 cc cc cc cc 0f 1f 00 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 f3 0f 1e fa 66 90 0f 00 2d a3 d0 13 00 fb f4 cc cc cc cc 66 2e 0f 1f 84 00 00 00 00 00 66 90 90 90 90 90 90 [ 14.438096][ C0] RSP: 0018:ffffffffaea07df8 EFLAGS: 00000246 [ 14.438099][ C0] RAX: 000000000006b32d RBX: 1ffffffff5d40fc3 RCX: ffffffffadd55a19 [ 14.438101][ C0] RDX: 0000000000000000 RSI: ffffffffae885f31 RDI: ffffffffae050ce0 [ 14.438103][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1006c0667a [ 14.438104][ C0] R10: ffff8880360333d3 R11: 0000000000000000 R12: 0000000000000000 [ 14.438106][ C0] R13: ffffffffaea1f240 R14: dffffc0000000000 R15: 0000000000014770 [ 14.438109][ C0] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 14.438115][ C0] default_idle+0x9/0x10 [ 14.438117][ C0] default_idle_call+0x6d/0xb0 [ 14.438119][ C0] cpuidle_idle_call+0x26f/0x3a0 [ 14.438123][ C0] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 14.438127][ C0] ? tsc_verify_tsc_adjust+0x98/0x300 [ 14.438133][ C0] do_idle+0xf6/0x160 [ 14.438137][ C0] cpu_startup_entry+0x54/0x60 [ 14.438141][ C0] rest_init+0x14b/0x260 [ 14.438144][ C0] start_kernel+0x350/0x410 [ 14.438147][ C0] x86_64_start_reservations+0x18/0x30 [ 14.438151][ C0] x86_64_start_kernel+0xba/0x110 [ 14.438155][ C0] common_startup_64+0x13e/0x148 [ 14.438161][ C0] [ 16.019763][ T258] packetdrill (258) used greatest stack depth: 24376 bytes left [ 16.030212][ C3] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 16.030532][ C3] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 214, name: sh [ 16.030792][ C3] preempt_count: 100, expected: 0 [ 16.030965][ C3] RCU nest depth: 0, expected: 0 [ 16.031149][ C3] INFO: lockdep is turned off. [ 16.031354][ C3] Preemption disabled at: [ 16.031356][ C3] [] handle_softirqs+0xe5/0x620 [ 16.031723][ C3] CPU: 3 UID: 0 PID: 214 Comm: sh Tainted: G W 6.15.0-rc5-virtme #1 PREEMPT(full) [ 16.031729][ C3] Tainted: [W]=WARN [ 16.031730][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.031732][ C3] Call Trace: [ 16.031734][ C3] [ 16.031736][ C3] dump_stack_lvl+0xb0/0xd0 [ 16.031745][ C3] __might_resched+0x360/0x5a0 [ 16.031752][ C3] down_write+0x72/0x210 [ 16.031757][ C3] ? __pfx_down_write+0x10/0x10 [ 16.031759][ C3] ? simple_recursive_removal+0x3a/0x860 [ 16.031765][ C3] ? rcu_is_watching+0x12/0xc0 [ 16.031773][ C3] ? do_raw_spin_unlock+0x58/0x220 [ 16.031778][ C3] simple_recursive_removal+0x166/0x860 [ 16.031781][ C3] ? __pfx_remove_one+0x10/0x10 [ 16.031787][ C3] ? do_raw_spin_unlock+0x58/0x220 [ 16.031791][ C3] debugfs_remove+0x44/0x70 [ 16.031794][ C3] ref_tracker_dir_exit+0xcf/0x740 [ 16.031800][ C3] ? __pfx_ref_tracker_free+0x10/0x10 [ 16.031803][ C3] ? __sk_destruct+0x383/0x780 [ 16.031810][ C3] ? rcu_do_batch+0x34c/0xf20 [ 16.031814][ C3] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 16.031817][ C3] ? irq_exit_rcu+0xe/0x30 [ 16.031820][ C3] ? sysvec_apic_timer_interrupt+0x59/0xc0 [ 16.031826][ C3] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.031830][ C3] ? migrate_enable+0x1f2/0x270 [ 16.031836][ C3] ? __pfx_migrate_enable+0x10/0x10 [ 16.031840][ C3] ? bpf_sk_storage_free+0x9f/0x1c0 [ 16.031845][ C3] ? rcu_is_watching+0x12/0xc0 [ 16.031850][ C3] __put_net+0x19/0x60 [ 16.031853][ C3] __sk_destruct+0x58a/0x780 [ 16.031857][ C3] ? rcu_do_batch+0x34a/0xf20 [ 16.031860][ C3] rcu_do_batch+0x34c/0xf20 [ 16.031865][ C3] ? __pfx_rcu_do_batch+0x10/0x10 [ 16.031869][ C3] ? rcu_is_watching+0x12/0xc0 [ 16.031873][ C3] ? trace_irq_enable.constprop.0+0xd4/0x130 [ 16.031880][ C3] rcu_core+0x2bb/0x500 [ 16.031883][ C3] handle_softirqs+0x218/0x620 [ 16.031888][ C3] __irq_exit_rcu+0xfa/0x160 [ 16.031891][ C3] irq_exit_rcu+0xe/0x30 [ 16.031893][ C3] sysvec_apic_timer_interrupt+0x59/0xc0 [ 16.031896][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.031899][ C3] RIP: 0033:0x7f7d6d03e535 [ 16.031903][ C3] Code: ff ff ff 49 8d 7c 24 10 48 8b 04 fa a8 0f 0f 85 91 01 00 00 48 89 c6 83 e9 01 48 c1 ee 0c 48 33 30 48 89 34 fa 66 42 89 0c 62 <48> c7 40 08 00 00 00 00 48 83 c4 10 5b 5d 41 5c c3 66 2e 0f 1f 84 [ 16.031906][ C3] RSP: 002b:00007ffc51e47f30 EFLAGS: 00010202 [ 16.031910][ C3] RAX: 000055d503fcff10 RBX: ffffffffffffffb8 RCX: 0000000000000001 [ 16.031912][ C3] RDX: 000055d503fc1010 RSI: 000055d503fd6210 RDI: 0000000000000010 [ 16.031914][ C3] RBP: 0000000000000002 R08: 0000000000000001 R09: 00007ffc51e48260 [ 16.031915][ C3] R10: 000055d503fc7610 R11: 0000000000000000 R12: 0000000000000000 [ 16.031917][ C3] R13: 0000000000000001 R14: 0000000000000000 R15: 0000000000000002 [ 16.031923][ C3]