[ 16.149636][ C0] [ 16.149848][ C0] ================================ [ 16.150057][ C0] WARNING: inconsistent lock state [ 16.150277][ C0] 6.15.0-rc7-virtme #1 Not tainted [ 16.150484][ C0] -------------------------------- [ 16.150687][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 16.150941][ C0] swapper/0/0 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 16.151204][ C0] ffffffffb57bff78 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 16.151575][ C0] {SOFTIRQ-ON-W} state was registered at: [ 16.151781][ C0] __lock_acquire+0x3eb/0x9d0 [ 16.151994][ C0] lock_acquire.part.0+0xb6/0x240 [ 16.152199][ C0] _raw_spin_lock+0x33/0x40 [ 16.152406][ C0] simple_pin_fs+0x24/0x150 [ 16.152659][ C0] start_creating.part.0+0x3f/0x2f0 [ 16.152873][ C0] debugfs_create_dir+0x6a/0x540 [ 16.153079][ C0] component_debug_init+0x17/0x30 [ 16.153285][ C0] do_one_initcall+0x8f/0x1e0 [ 16.153492][ C0] do_initcalls+0x176/0x280 [ 16.153699][ C0] kernel_init_freeable+0x22d/0x300 [ 16.153906][ C0] kernel_init+0x20/0x200 [ 16.154112][ C0] ret_from_fork+0x31/0x70 [ 16.154318][ C0] ret_from_fork_asm+0x1a/0x30 [ 16.154524][ C0] irq event stamp: 498330 [ 16.154680][ C0] hardirqs last enabled at (498330): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 16.155094][ C0] hardirqs last disabled at (498329): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 16.155507][ C0] softirqs last enabled at (497938): [] handle_softirqs+0x358/0x620 [ 16.155864][ C0] softirqs last disabled at (497983): [] __irq_exit_rcu+0xfa/0x160 [ 16.156220][ C0] [ 16.156220][ C0] other info that might help us debug this: [ 16.156524][ C0] Possible unsafe locking scenario: [ 16.156524][ C0] [ 16.156826][ C0] CPU0 [ 16.156980][ C0] ---- [ 16.157134][ C0] lock(pin_fs_lock); [ 16.157291][ C0] [ 16.157446][ C0] lock(pin_fs_lock); [ 16.157603][ C0] [ 16.157603][ C0] *** DEADLOCK *** [ 16.157603][ C0] [ 16.157910][ C0] 1 lock held by swapper/0/0: [ 16.158117][ C0] #0: ffffffffb55749c0 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 16.158489][ C0] [ 16.158489][ C0] stack backtrace: [ 16.158744][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Not tainted 6.15.0-rc7-virtme #1 PREEMPT(full) [ 16.158748][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.158750][ C0] Call Trace: [ 16.158752][ C0] [ 16.158754][ C0] dump_stack_lvl+0x82/0xd0 [ 16.158760][ C0] print_usage_bug.part.0+0x25f/0x360 [ 16.158765][ C0] mark_lock_irq+0x343/0x400 [ 16.158769][ C0] ? is_bpf_text_address+0x67/0x120 [ 16.158774][ C0] ? __lock_release+0x5d/0x170 [ 16.158778][ C0] ? save_trace+0x52/0x170 [ 16.158783][ C0] mark_lock+0x174/0x2f0 [ 16.158787][ C0] mark_usage+0x46/0x150 [ 16.158791][ C0] __lock_acquire+0x3eb/0x9d0 [ 16.158796][ C0] lock_acquire.part.0+0xb6/0x240 [ 16.158799][ C0] ? simple_pin_fs+0x24/0x150 [ 16.158803][ C0] ? rcu_is_watching+0x12/0xc0 [ 16.158809][ C0] ? lock_acquire+0x10c/0x170 [ 16.158811][ C0] ? simple_pin_fs+0x24/0x150 [ 16.158814][ C0] _raw_spin_lock+0x33/0x40 [ 16.158817][ C0] ? simple_pin_fs+0x24/0x150 [ 16.158819][ C0] simple_pin_fs+0x24/0x150 [ 16.158822][ C0] debugfs_remove+0x35/0x70 [ 16.158825][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 16.158830][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 16.158833][ C0] ? __sk_destruct+0x383/0x780 [ 16.158838][ C0] ? rcu_do_batch+0x34f/0xf20 [ 16.158842][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 16.158845][ C0] ? irq_exit_rcu+0xe/0x30 [ 16.158847][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.158851][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.158855][ C0] ? pv_native_safe_halt+0xf/0x20 [ 16.158858][ C0] ? default_idle+0x9/0x10 [ 16.158860][ C0] ? default_idle_call+0x6d/0xb0 [ 16.158863][ C0] ? cpuidle_idle_call+0x26f/0x3a0 [ 16.158867][ C0] ? do_idle+0xf6/0x160 [ 16.158870][ C0] ? cpu_startup_entry+0x54/0x60 [ 16.158874][ C0] ? rest_init+0x14b/0x260 [ 16.158876][ C0] ? start_kernel+0x350/0x410 [ 16.158882][ C0] __put_net+0x19/0x60 [ 16.158886][ C0] __sk_destruct+0x58a/0x780 [ 16.158889][ C0] ? rcu_do_batch+0x34a/0xf20 [ 16.158893][ C0] rcu_do_batch+0x34f/0xf20 [ 16.158896][ C0] ? find_held_lock+0x2b/0x80 [ 16.158900][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 16.158904][ C0] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 16.158908][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 16.158911][ C0] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 16.158916][ C0] rcu_core+0x2bb/0x500 [ 16.158920][ C0] handle_softirqs+0x218/0x620 [ 16.158924][ C0] __irq_exit_rcu+0xfa/0x160 [ 16.158927][ C0] irq_exit_rcu+0xe/0x30 [ 16.158929][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.158932][ C0] [ 16.158933][ C0] [ 16.158935][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.158938][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 16.158942][ C0] Code: 60 b8 00 e9 c3 57 02 00 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 c3 75 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 [ 16.158945][ C0] RSP: 0018:ffffffffb5207df8 EFLAGS: 00000242 [ 16.158948][ C0] RAX: 000000000007993d RBX: 1ffffffff6a40fc3 RCX: ffffffffb455ba19 [ 16.158950][ C0] RDX: 0000000000000000 RSI: ffffffffb5087785 RDI: ffffffffb4851260 [ 16.158952][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1006c0667a [ 16.158953][ C0] R10: ffff8880360333d3 R11: 0000000000000000 R12: 0000000000000000 [ 16.158955][ C0] R13: ffffffffb521f240 R14: dffffc0000000000 R15: 0000000000014770 [ 16.158959][ C0] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 16.158964][ C0] default_idle+0x9/0x10 [ 16.158966][ C0] default_idle_call+0x6d/0xb0 [ 16.158969][ C0] cpuidle_idle_call+0x26f/0x3a0 [ 16.158973][ C0] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 16.158977][ C0] ? tsc_verify_tsc_adjust+0x98/0x300 [ 16.158983][ C0] do_idle+0xf6/0x160 [ 16.158987][ C0] cpu_startup_entry+0x54/0x60 [ 16.158991][ C0] rest_init+0x14b/0x260 [ 16.158994][ C0] start_kernel+0x350/0x410 [ 16.158998][ C0] x86_64_start_reservations+0x18/0x30 [ 16.159003][ C0] x86_64_start_kernel+0xba/0x110 [ 16.159007][ C0] common_startup_64+0x13e/0x148 [ 16.159015][ C0] [ 16.178147][ C0] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 16.178482][ C0] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0 [ 16.178827][ C0] preempt_count: 101, expected: 0 [ 16.179017][ C0] RCU nest depth: 0, expected: 0 [ 16.179217][ C0] INFO: lockdep is turned off. [ 16.179409][ C0] Preemption disabled at: [ 16.179411][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 16.179852][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Not tainted 6.15.0-rc7-virtme #1 PREEMPT(full) [ 16.179857][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.179859][ C0] Call Trace: [ 16.179860][ C0] [ 16.179862][ C0] dump_stack_lvl+0xb0/0xd0 [ 16.179867][ C0] __might_resched+0x360/0x5a0 [ 16.179873][ C0] down_write+0x72/0x210 [ 16.179877][ C0] ? __pfx_down_write+0x10/0x10 [ 16.179880][ C0] ? simple_recursive_removal+0x3a/0x860 [ 16.179884][ C0] ? rcu_is_watching+0x12/0xc0 [ 16.179890][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 16.179894][ C0] simple_recursive_removal+0x166/0x860 [ 16.179897][ C0] ? __pfx_remove_one+0x10/0x10 [ 16.179901][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 16.179906][ C0] debugfs_remove+0x44/0x70 [ 16.179908][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 16.179913][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 16.179916][ C0] ? __sk_destruct+0x383/0x780 [ 16.179921][ C0] ? rcu_do_batch+0x34f/0xf20 [ 16.179925][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 16.179928][ C0] ? irq_exit_rcu+0xe/0x30 [ 16.179932][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.179936][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.179939][ C0] ? pv_native_safe_halt+0xf/0x20 [ 16.179943][ C0] ? default_idle+0x9/0x10 [ 16.179945][ C0] ? default_idle_call+0x6d/0xb0 [ 16.179947][ C0] ? cpuidle_idle_call+0x26f/0x3a0 [ 16.179953][ C0] ? do_idle+0xf6/0x160 [ 16.179955][ C0] ? cpu_startup_entry+0x54/0x60 [ 16.179959][ C0] ? rest_init+0x14b/0x260 [ 16.179961][ C0] ? start_kernel+0x350/0x410 [ 16.179969][ C0] __put_net+0x19/0x60 [ 16.179972][ C0] __sk_destruct+0x58a/0x780 [ 16.179976][ C0] ? rcu_do_batch+0x34a/0xf20 [ 16.179979][ C0] rcu_do_batch+0x34f/0xf20 [ 16.179983][ C0] ? find_held_lock+0x2b/0x80 [ 16.179988][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 16.179992][ C0] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 16.179996][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 16.179999][ C0] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 16.180004][ C0] rcu_core+0x2bb/0x500 [ 16.180008][ C0] handle_softirqs+0x218/0x620 [ 16.180013][ C0] __irq_exit_rcu+0xfa/0x160 [ 16.180015][ C0] irq_exit_rcu+0xe/0x30 [ 16.180018][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.180021][ C0] [ 16.180022][ C0] [ 16.180024][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.180027][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 16.180030][ C0] Code: 60 b8 00 e9 c3 57 02 00 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 c3 75 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 [ 16.180033][ C0] RSP: 0018:ffffffffb5207df8 EFLAGS: 00000242 [ 16.180036][ C0] RAX: 000000000007993d RBX: 1ffffffff6a40fc3 RCX: ffffffffb455ba19 [ 16.180038][ C0] RDX: 0000000000000000 RSI: ffffffffb5087785 RDI: ffffffffb4851260 [ 16.180040][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1006c0667a [ 16.180042][ C0] R10: ffff8880360333d3 R11: 0000000000000000 R12: 0000000000000000 [ 16.180044][ C0] R13: ffffffffb521f240 R14: dffffc0000000000 R15: 0000000000014770 [ 16.180048][ C0] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 16.180052][ C0] default_idle+0x9/0x10 [ 16.180055][ C0] default_idle_call+0x6d/0xb0 [ 16.180057][ C0] cpuidle_idle_call+0x26f/0x3a0 [ 16.180061][ C0] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 16.180065][ C0] ? tsc_verify_tsc_adjust+0x98/0x300 [ 16.180072][ C0] do_idle+0xf6/0x160 [ 16.180076][ C0] cpu_startup_entry+0x54/0x60 [ 16.180079][ C0] rest_init+0x14b/0x260 [ 16.180083][ C0] start_kernel+0x350/0x410 [ 16.180087][ C0] x86_64_start_reservations+0x18/0x30 [ 16.180091][ C0] x86_64_start_kernel+0xba/0x110 [ 16.180095][ C0] common_startup_64+0x13e/0x148 [ 16.180102][ C0] [ 18.394836][ C1] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 18.395176][ C1] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/1 [ 18.395519][ C1] preempt_count: 101, expected: 0 [ 18.395692][ C1] RCU nest depth: 0, expected: 0 [ 18.395862][ C1] INFO: lockdep is turned off. [ 18.396031][ C1] Preemption disabled at: [ 18.396033][ C1] [<0000000000000000>] 0x0 [ 18.396346][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 6.15.0-rc7-virtme #1 PREEMPT(full) [ 18.396351][ C1] Tainted: [W]=WARN [ 18.396352][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 18.396354][ C1] Call Trace: [ 18.396356][ C1] [ 18.396358][ C1] dump_stack_lvl+0xb0/0xd0 [ 18.396366][ C1] __might_resched+0x360/0x5a0 [ 18.396373][ C1] down_write+0x72/0x210 [ 18.396378][ C1] ? __pfx_down_write+0x10/0x10 [ 18.396381][ C1] ? simple_recursive_removal+0x3a/0x860 [ 18.396386][ C1] ? rcu_is_watching+0x12/0xc0 [ 18.396392][ C1] ? do_raw_spin_unlock+0x58/0x220 [ 18.396396][ C1] simple_recursive_removal+0x166/0x860 [ 18.396400][ C1] ? __pfx_remove_one+0x10/0x10 [ 18.396404][ C1] ? do_raw_spin_unlock+0x58/0x220 [ 18.396408][ C1] debugfs_remove+0x44/0x70 [ 18.396411][ C1] ref_tracker_dir_exit+0xcf/0x740 [ 18.396417][ C1] ? __pfx_ref_tracker_free+0x10/0x10 [ 18.396420][ C1] ? __sk_destruct+0x383/0x780 [ 18.396425][ C1] ? rcu_do_batch+0x34f/0xf20 [ 18.396429][ C1] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 18.396432][ C1] ? irq_exit_rcu+0xe/0x30 [ 18.396436][ C1] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 18.396441][ C1] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 18.396445][ C1] ? pv_native_safe_halt+0xf/0x20 [ 18.396448][ C1] ? default_idle+0x9/0x10 [ 18.396450][ C1] ? default_idle_call+0x6d/0xb0 [ 18.396452][ C1] ? cpuidle_idle_call+0x26f/0x3a0 [ 18.396457][ C1] ? do_idle+0xf6/0x160 [ 18.396460][ C1] ? cpu_startup_entry+0x54/0x60 [ 18.396463][ C1] ? start_secondary+0x213/0x2a0 [ 18.396468][ C1] ? common_startup_64+0x13e/0x148 [ 18.396472][ C1] ? bpf_sk_storage_free+0x9f/0x1c0 [ 18.396476][ C1] ? rcu_is_watching+0x12/0xc0 [ 18.396481][ C1] __put_net+0x19/0x60 [ 18.396484][ C1] __sk_destruct+0x58a/0x780 [ 18.396488][ C1] ? rcu_do_batch+0x34a/0xf20 [ 18.396491][ C1] rcu_do_batch+0x34f/0xf20 [ 18.396496][ C1] ? __pfx_rcu_do_batch+0x10/0x10 [ 18.396500][ C1] ? trace_irq_enable.constprop.0+0xd4/0x130 [ 18.396505][ C1] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 18.396511][ C1] rcu_core+0x2bb/0x500 [ 18.396515][ C1] handle_softirqs+0x218/0x620 [ 18.396519][ C1] __irq_exit_rcu+0xfa/0x160 [ 18.396522][ C1] irq_exit_rcu+0xe/0x30 [ 18.396524][ C1] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 18.396528][ C1] [ 18.396529][ C1] [ 18.396530][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 18.396533][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 18.396537][ C1] Code: 60 b8 00 e9 c3 57 02 00 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 c3 75 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 [ 18.396540][ C1] RSP: 0018:ffffc90000137de8 EFLAGS: 00000246 [ 18.396544][ C1] RAX: 0000000000000001 RBX: 1ffff92000026fc1 RCX: ffffffffb455ba19 [ 18.396546][ C1] RDX: ffffed1006c1667b RSI: ffffffffb48511e0 RDI: ffffffffb1d8633f [ 18.396548][ C1] RBP: 0000000000000000 R08: 0000000000000000 R09: ffffed1006c1667a [ 18.396549][ C1] R10: ffff8880360b33d3 R11: ffff888008930558 R12: 0000000000000000 [ 18.396551][ C1] R13: ffff888001bf0040 R14: dffffc0000000000 R15: 0000000000000000 [ 18.396555][ C1] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 18.396558][ C1] ? cpuidle_idle_call+0x26f/0x3a0 [ 18.396563][ C1] default_idle+0x9/0x10 [ 18.396565][ C1] default_idle_call+0x6d/0xb0 [ 18.396567][ C1] cpuidle_idle_call+0x26f/0x3a0 [ 18.396571][ C1] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 18.396575][ C1] ? tsc_verify_tsc_adjust+0x98/0x300 [ 18.396581][ C1] do_idle+0xf6/0x160 [ 18.396585][ C1] cpu_startup_entry+0x54/0x60 [ 18.396588][ C1] start_secondary+0x213/0x2a0 [ 18.396592][ C1] ? __pfx_start_secondary+0x10/0x10 [ 18.396597][ C1] common_startup_64+0x13e/0x148 [ 18.396603][ C1]