[ 16.060318][ C2] [ 16.060462][ C2] ================================ [ 16.060659][ C2] WARNING: inconsistent lock state [ 16.060853][ C2] 6.15.0-rc7-virtme #1 Not tainted [ 16.061047][ C2] -------------------------------- [ 16.061252][ C2] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 16.061489][ C2] swapper/2/0 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 16.061733][ C2] ffffffffb8bbff78 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 16.062083][ C2] {SOFTIRQ-ON-W} state was registered at: [ 16.062277][ C2] __lock_acquire+0x3eb/0x9d0 [ 16.062481][ C2] lock_acquire.part.0+0xb6/0x240 [ 16.062677][ C2] _raw_spin_lock+0x33/0x40 [ 16.062871][ C2] simple_pin_fs+0x24/0x150 [ 16.063062][ C2] start_creating.part.0+0x3f/0x2f0 [ 16.063261][ C2] debugfs_create_dir+0x6a/0x540 [ 16.063457][ C2] component_debug_init+0x17/0x30 [ 16.063652][ C2] do_one_initcall+0x8f/0x1e0 [ 16.063849][ C2] do_initcalls+0x176/0x280 [ 16.064046][ C2] kernel_init_freeable+0x22d/0x300 [ 16.064241][ C2] kernel_init+0x20/0x200 [ 16.064435][ C2] ret_from_fork+0x31/0x70 [ 16.064626][ C2] ret_from_fork_asm+0x1a/0x30 [ 16.064818][ C2] irq event stamp: 283076 [ 16.064962][ C2] hardirqs last enabled at (283076): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 16.065353][ C2] hardirqs last disabled at (283075): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 16.065736][ C2] softirqs last enabled at (282472): [] handle_softirqs+0x358/0x620 [ 16.066077][ C2] softirqs last disabled at (282497): [] __irq_exit_rcu+0xfa/0x160 [ 16.066419][ C2] [ 16.066419][ C2] other info that might help us debug this: [ 16.066707][ C2] Possible unsafe locking scenario: [ 16.066707][ C2] [ 16.066998][ C2] CPU0 [ 16.067144][ C2] ---- [ 16.067289][ C2] lock(pin_fs_lock); [ 16.067439][ C2] [ 16.067583][ C2] lock(pin_fs_lock); [ 16.067729][ C2] [ 16.067729][ C2] *** DEADLOCK *** [ 16.067729][ C2] [ 16.068018][ C2] 1 lock held by swapper/2/0: [ 16.068216][ C2] #0: ffffffffb89749c0 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 16.068568][ C2] [ 16.068568][ C2] stack backtrace: [ 16.068810][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Not tainted 6.15.0-rc7-virtme #1 PREEMPT(full) [ 16.068815][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.068817][ C2] Call Trace: [ 16.068819][ C2] [ 16.068822][ C2] dump_stack_lvl+0x82/0xd0 [ 16.068827][ C2] print_usage_bug.part.0+0x25f/0x360 [ 16.068833][ C2] mark_lock_irq+0x343/0x400 [ 16.068838][ C2] ? __pfx_unwind_next_frame+0x10/0x10 [ 16.068844][ C2] ? save_trace+0x52/0x170 [ 16.068848][ C2] mark_lock+0x174/0x2f0 [ 16.068853][ C2] mark_usage+0x46/0x150 [ 16.068856][ C2] __lock_acquire+0x3eb/0x9d0 [ 16.068862][ C2] lock_acquire.part.0+0xb6/0x240 [ 16.068865][ C2] ? simple_pin_fs+0x24/0x150 [ 16.068868][ C2] ? rcu_is_watching+0x12/0xc0 [ 16.068873][ C2] ? lock_acquire+0x10c/0x170 [ 16.068875][ C2] ? simple_pin_fs+0x24/0x150 [ 16.068878][ C2] _raw_spin_lock+0x33/0x40 [ 16.068882][ C2] ? simple_pin_fs+0x24/0x150 [ 16.068884][ C2] simple_pin_fs+0x24/0x150 [ 16.068887][ C2] debugfs_remove+0x35/0x70 [ 16.068891][ C2] ref_tracker_dir_exit+0xcf/0x740 [ 16.068895][ C2] ? __pfx_ref_tracker_free+0x10/0x10 [ 16.068899][ C2] ? __sk_destruct+0x383/0x780 [ 16.068904][ C2] ? rcu_do_batch+0x34f/0xf20 [ 16.068907][ C2] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 16.068910][ C2] ? irq_exit_rcu+0xe/0x30 [ 16.068912][ C2] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.068916][ C2] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.068920][ C2] ? pv_native_safe_halt+0xf/0x20 [ 16.068923][ C2] ? default_idle+0x9/0x10 [ 16.068925][ C2] ? default_idle_call+0x6d/0xb0 [ 16.068928][ C2] ? cpuidle_idle_call+0x26f/0x3a0 [ 16.068932][ C2] ? do_idle+0xf6/0x160 [ 16.068935][ C2] ? cpu_startup_entry+0x54/0x60 [ 16.068938][ C2] ? start_secondary+0x213/0x2a0 [ 16.068942][ C2] ? common_startup_64+0x13e/0x148 [ 16.068948][ C2] __put_net+0x19/0x60 [ 16.068952][ C2] __sk_destruct+0x58a/0x780 [ 16.068956][ C2] ? rcu_do_batch+0x34a/0xf20 [ 16.068959][ C2] rcu_do_batch+0x34f/0xf20 [ 16.068963][ C2] ? find_held_lock+0x2b/0x80 [ 16.068967][ C2] ? __pfx_rcu_do_batch+0x10/0x10 [ 16.068971][ C2] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 16.068974][ C2] ? lockdep_hardirqs_on+0x7c/0x110 [ 16.068978][ C2] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 16.068982][ C2] rcu_core+0x2bb/0x500 [ 16.068986][ C2] handle_softirqs+0x218/0x620 [ 16.068991][ C2] __irq_exit_rcu+0xfa/0x160 [ 16.068994][ C2] irq_exit_rcu+0xe/0x30 [ 16.068996][ C2] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.069000][ C2] [ 16.069001][ C2] [ 16.069002][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.069005][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 16.069009][ C2] 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.069012][ C2] RSP: 0018:ffffc90000147de8 EFLAGS: 00000242 [ 16.069015][ C2] RAX: 0000000000044f7f RBX: 1ffff92000028fc1 RCX: ffffffffb795ba19 [ 16.069017][ C2] RDX: 0000000000000000 RSI: ffffffffb8487785 RDI: ffffffffb7c51260 [ 16.069019][ C2] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1006c2667a [ 16.069021][ C2] R10: ffff8880361333d3 R11: 0000000000000000 R12: 0000000000000000 [ 16.069023][ C2] R13: ffff888001bf2340 R14: dffffc0000000000 R15: 0000000000000000 [ 16.069027][ C2] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 16.069031][ C2] default_idle+0x9/0x10 [ 16.069034][ C2] default_idle_call+0x6d/0xb0 [ 16.069036][ C2] cpuidle_idle_call+0x26f/0x3a0 [ 16.069040][ C2] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 16.069044][ C2] ? tsc_verify_tsc_adjust+0x98/0x300 [ 16.069049][ C2] do_idle+0xf6/0x160 [ 16.069053][ C2] cpu_startup_entry+0x54/0x60 [ 16.069057][ C2] start_secondary+0x213/0x2a0 [ 16.069061][ C2] ? __pfx_start_secondary+0x10/0x10 [ 16.069066][ C2] common_startup_64+0x13e/0x148 [ 16.069072][ C2] [ 16.084247][ C2] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 16.084579][ C2] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/2 [ 16.085028][ C2] preempt_count: 101, expected: 0 [ 16.085224][ C2] RCU nest depth: 0, expected: 0 [ 16.085417][ C2] INFO: lockdep is turned off. [ 16.085609][ C2] Preemption disabled at: [ 16.085610][ C2] [<0000000000000000>] 0x0 [ 16.085962][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Not tainted 6.15.0-rc7-virtme #1 PREEMPT(full) [ 16.085966][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.085968][ C2] Call Trace: [ 16.085970][ C2] [ 16.085972][ C2] dump_stack_lvl+0xb0/0xd0 [ 16.085978][ C2] __might_resched+0x360/0x5a0 [ 16.085984][ C2] down_write+0x72/0x210 [ 16.085988][ C2] ? __pfx_down_write+0x10/0x10 [ 16.085991][ C2] ? simple_recursive_removal+0x3a/0x860 [ 16.085995][ C2] ? rcu_is_watching+0x12/0xc0 [ 16.086001][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 16.086005][ C2] simple_recursive_removal+0x166/0x860 [ 16.086008][ C2] ? __pfx_remove_one+0x10/0x10 [ 16.086012][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 16.086016][ C2] debugfs_remove+0x44/0x70 [ 16.086019][ C2] ref_tracker_dir_exit+0xcf/0x740 [ 16.086023][ C2] ? __pfx_ref_tracker_free+0x10/0x10 [ 16.086027][ C2] ? __sk_destruct+0x383/0x780 [ 16.086030][ C2] ? rcu_do_batch+0x34f/0xf20 [ 16.086034][ C2] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 16.086037][ C2] ? irq_exit_rcu+0xe/0x30 [ 16.086041][ C2] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.086044][ C2] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.086048][ C2] ? pv_native_safe_halt+0xf/0x20 [ 16.086051][ C2] ? default_idle+0x9/0x10 [ 16.086054][ C2] ? default_idle_call+0x6d/0xb0 [ 16.086056][ C2] ? cpuidle_idle_call+0x26f/0x3a0 [ 16.086060][ C2] ? do_idle+0xf6/0x160 [ 16.086063][ C2] ? cpu_startup_entry+0x54/0x60 [ 16.086066][ C2] ? start_secondary+0x213/0x2a0 [ 16.086070][ C2] ? common_startup_64+0x13e/0x148 [ 16.086076][ C2] __put_net+0x19/0x60 [ 16.086079][ C2] __sk_destruct+0x58a/0x780 [ 16.086083][ C2] ? rcu_do_batch+0x34a/0xf20 [ 16.086086][ C2] rcu_do_batch+0x34f/0xf20 [ 16.086090][ C2] ? find_held_lock+0x2b/0x80 [ 16.086094][ C2] ? __pfx_rcu_do_batch+0x10/0x10 [ 16.086098][ C2] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 16.086102][ C2] ? lockdep_hardirqs_on+0x7c/0x110 [ 16.086106][ C2] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 16.086110][ C2] rcu_core+0x2bb/0x500 [ 16.086114][ C2] handle_softirqs+0x218/0x620 [ 16.086119][ C2] __irq_exit_rcu+0xfa/0x160 [ 16.086122][ C2] irq_exit_rcu+0xe/0x30 [ 16.086124][ C2] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.086128][ C2] [ 16.086129][ C2] [ 16.086130][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.086133][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 16.086137][ C2] 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.086139][ C2] RSP: 0018:ffffc90000147de8 EFLAGS: 00000242 [ 16.086142][ C2] RAX: 0000000000044f7f RBX: 1ffff92000028fc1 RCX: ffffffffb795ba19 [ 16.086145][ C2] RDX: 0000000000000000 RSI: ffffffffb8487785 RDI: ffffffffb7c51260 [ 16.086146][ C2] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1006c2667a [ 16.086148][ C2] R10: ffff8880361333d3 R11: 0000000000000000 R12: 0000000000000000 [ 16.086150][ C2] R13: ffff888001bf2340 R14: dffffc0000000000 R15: 0000000000000000 [ 16.086154][ C2] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 16.086158][ C2] default_idle+0x9/0x10 [ 16.086161][ C2] default_idle_call+0x6d/0xb0 [ 16.086163][ C2] cpuidle_idle_call+0x26f/0x3a0 [ 16.086167][ C2] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 16.086171][ C2] ? tsc_verify_tsc_adjust+0x98/0x300 [ 16.086176][ C2] do_idle+0xf6/0x160 [ 16.086180][ C2] cpu_startup_entry+0x54/0x60 [ 16.086184][ C2] start_secondary+0x213/0x2a0 [ 16.086187][ C2] ? __pfx_start_secondary+0x10/0x10 [ 16.086193][ C2] common_startup_64+0x13e/0x148 [ 16.086199][ C2] [ 17.846463][ T257] packetdrill (257) used greatest stack depth: 24584 bytes left [ 17.853721][ C0] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 17.854188][ C0] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0 [ 17.854547][ C0] preempt_count: 101, expected: 0 [ 17.854753][ C0] RCU nest depth: 0, expected: 0 [ 17.854965][ C0] INFO: lockdep is turned off. [ 17.855172][ C0] Preemption disabled at: [ 17.855174][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 17.855647][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 6.15.0-rc7-virtme #1 PREEMPT(full) [ 17.855653][ C0] Tainted: [W]=WARN [ 17.855655][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 17.855657][ C0] Call Trace: [ 17.855659][ C0] [ 17.855661][ C0] dump_stack_lvl+0xb0/0xd0 [ 17.855669][ C0] __might_resched+0x360/0x5a0 [ 17.855677][ C0] down_write+0x72/0x210 [ 17.855682][ C0] ? __pfx_down_write+0x10/0x10 [ 17.855685][ C0] ? simple_recursive_removal+0x3a/0x860 [ 17.855691][ C0] ? rcu_is_watching+0x12/0xc0 [ 17.855699][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 17.855705][ C0] simple_recursive_removal+0x166/0x860 [ 17.855708][ C0] ? __pfx_remove_one+0x10/0x10 [ 17.855713][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 17.855718][ C0] debugfs_remove+0x44/0x70 [ 17.855721][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 17.855727][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 17.855730][ C0] ? __sk_destruct+0x383/0x780 [ 17.855737][ C0] ? rcu_do_batch+0x34f/0xf20 [ 17.855742][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 17.855745][ C0] ? irq_exit_rcu+0xe/0x30 [ 17.855751][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 17.855756][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 17.855761][ C0] ? pv_native_safe_halt+0xf/0x20 [ 17.855764][ C0] ? default_idle+0x9/0x10 [ 17.855769][ C0] ? default_idle_call+0x6d/0xb0 [ 17.855772][ C0] ? cpuidle_idle_call+0x26f/0x3a0 [ 17.855778][ C0] ? do_idle+0xf6/0x160 [ 17.855781][ C0] ? cpu_startup_entry+0x54/0x60 [ 17.855784][ C0] ? rest_init+0x14b/0x260 [ 17.855787][ C0] ? start_kernel+0x350/0x410 [ 17.855794][ C0] ? bpf_sk_storage_free+0x9f/0x1c0 [ 17.855799][ C0] ? rcu_is_watching+0x12/0xc0 [ 17.855804][ C0] __put_net+0x19/0x60 [ 17.855808][ C0] __sk_destruct+0x58a/0x780 [ 17.855812][ C0] ? rcu_do_batch+0x34a/0xf20 [ 17.855816][ C0] rcu_do_batch+0x34f/0xf20 [ 17.855821][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 17.855825][ C0] ? trace_irq_enable.constprop.0+0xd4/0x130 [ 17.855831][ C0] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 17.855837][ C0] rcu_core+0x2bb/0x500 [ 17.855842][ C0] handle_softirqs+0x218/0x620 [ 17.855847][ C0] __irq_exit_rcu+0xfa/0x160 [ 17.855849][ C0] irq_exit_rcu+0xe/0x30 [ 17.855852][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 17.855855][ C0] [ 17.855856][ C0] [ 17.855858][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 17.855861][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 17.855865][ 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 [ 17.855868][ C0] RSP: 0018:ffffffffb8607df8 EFLAGS: 00000246 [ 17.855872][ C0] RAX: 0000000000000000 RBX: 1ffffffff70c0fc3 RCX: ffffffffb795ba19 [ 17.855874][ C0] RDX: ffffed1006c0667b RSI: ffffffffb7c511e0 RDI: ffffffffb518633f [ 17.855876][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: ffffed1006c0667a [ 17.855878][ C0] R10: ffff8880360333d3 R11: ffffffffb86079e8 R12: 0000000000000000 [ 17.855880][ C0] R13: ffffffffb861f240 R14: dffffc0000000000 R15: 0000000000014770 [ 17.855884][ C0] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 17.855887][ C0] ? cpuidle_idle_call+0x26f/0x3a0 [ 17.855891][ C0] default_idle+0x9/0x10 [ 17.855894][ C0] default_idle_call+0x6d/0xb0 [ 17.855896][ C0] cpuidle_idle_call+0x26f/0x3a0 [ 17.855900][ C0] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 17.855904][ C0] ? tsc_verify_tsc_adjust+0x98/0x300 [ 17.855913][ C0] do_idle+0xf6/0x160 [ 17.855918][ C0] cpu_startup_entry+0x54/0x60 [ 17.855921][ C0] rest_init+0x14b/0x260 [ 17.855925][ C0] start_kernel+0x350/0x410 [ 17.855929][ C0] x86_64_start_reservations+0x18/0x30 [ 17.855934][ C0] x86_64_start_kernel+0xba/0x110 [ 17.855939][ C0] common_startup_64+0x13e/0x148 [ 17.855948][ C0]