[ 14.900739][ C3] [ 14.900878][ C3] ================================ [ 14.901069][ C3] WARNING: inconsistent lock state [ 14.901261][ C3] 6.15.0-virtme #1 Not tainted [ 14.901457][ C3] -------------------------------- [ 14.901646][ C3] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 14.901876][ C3] swapper/3/0 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 14.902105][ C3] ffffffff8c3c5af8 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 14.902442][ C3] {SOFTIRQ-ON-W} state was registered at: [ 14.902628][ C3] __lock_acquire+0x20b/0x7e0 [ 14.902820][ C3] lock_acquire.part.0+0xb6/0x240 [ 14.903008][ C3] _raw_spin_lock+0x33/0x40 [ 14.903197][ C3] simple_pin_fs+0x24/0x150 [ 14.903384][ C3] start_creating.part.0+0x52/0x310 [ 14.903570][ C3] debugfs_create_dir+0x6a/0x540 [ 14.903755][ C3] component_debug_init+0x17/0x30 [ 14.903947][ C3] do_one_initcall+0x8f/0x1e0 [ 14.904137][ C3] do_initcalls+0x176/0x280 [ 14.904327][ C3] kernel_init_freeable+0x22d/0x300 [ 14.904513][ C3] kernel_init+0x20/0x200 [ 14.904701][ C3] ret_from_fork+0x240/0x320 [ 14.904889][ C3] ret_from_fork_asm+0x1a/0x30 [ 14.905077][ C3] irq event stamp: 283406 [ 14.905217][ C3] hardirqs last enabled at (283406): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 14.905582][ C3] hardirqs last disabled at (283405): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 14.905958][ C3] softirqs last enabled at (282870): [] handle_softirqs+0x358/0x620 [ 14.906281][ C3] softirqs last disabled at (282877): [] __irq_exit_rcu+0xfa/0x160 [ 14.906607][ C3] [ 14.906607][ C3] other info that might help us debug this: [ 14.906878][ C3] Possible unsafe locking scenario: [ 14.906878][ C3] [ 14.907149][ C3] CPU0 [ 14.907288][ C3] ---- [ 14.907429][ C3] lock(pin_fs_lock); [ 14.907570][ C3] [ 14.907709][ C3] lock(pin_fs_lock); [ 14.907854][ C3] [ 14.907854][ C3] *** DEADLOCK *** [ 14.907854][ C3] [ 14.908129][ C3] 1 lock held by swapper/3/0: [ 14.908317][ C3] #0: ffffffff8c1774c0 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 14.908653][ C3] [ 14.908653][ C3] stack backtrace: [ 14.908889][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 14.908893][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.908896][ C3] Call Trace: [ 14.908898][ C3] [ 14.908900][ C3] dump_stack_lvl+0x82/0xd0 [ 14.908906][ C3] print_usage_bug.part.0+0x25f/0x360 [ 14.908911][ C3] mark_lock_irq+0x343/0x400 [ 14.908915][ C3] ? __pfx_unwind_next_frame+0x10/0x10 [ 14.908920][ C3] ? save_trace+0x52/0x170 [ 14.908926][ C3] mark_lock+0x174/0x2f0 [ 14.908930][ C3] mark_usage+0x46/0x150 [ 14.908933][ C3] __lock_acquire+0x20b/0x7e0 [ 14.908936][ C3] ? __lock_acquire+0x44d/0x7e0 [ 14.908940][ C3] lock_acquire.part.0+0xb6/0x240 [ 14.908943][ C3] ? simple_pin_fs+0x24/0x150 [ 14.908947][ C3] ? rcu_is_watching+0x12/0xc0 [ 14.908951][ C3] ? lock_acquire+0x10c/0x170 [ 14.908954][ C3] ? simple_pin_fs+0x24/0x150 [ 14.908958][ C3] _raw_spin_lock+0x33/0x40 [ 14.908962][ C3] ? simple_pin_fs+0x24/0x150 [ 14.908965][ C3] simple_pin_fs+0x24/0x150 [ 14.908969][ C3] debugfs_remove+0x35/0x70 [ 14.908973][ C3] ref_tracker_dir_exit+0xcf/0x740 [ 14.908979][ C3] ? __pfx_ref_tracker_free+0x10/0x10 [ 14.908982][ C3] ? __sk_destruct+0x383/0x780 [ 14.908988][ C3] ? rcu_do_batch+0x34c/0xf20 [ 14.908991][ C3] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 14.908994][ C3] ? irq_exit_rcu+0xe/0x30 [ 14.908996][ C3] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 14.909001][ C3] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.909004][ C3] ? pv_native_safe_halt+0xf/0x20 [ 14.909008][ C3] ? default_idle+0x9/0x10 [ 14.909010][ C3] ? default_idle_call+0x6d/0xb0 [ 14.909013][ C3] ? cpuidle_idle_call+0x26f/0x3a0 [ 14.909016][ C3] ? do_idle+0xf6/0x160 [ 14.909018][ C3] ? cpu_startup_entry+0x54/0x60 [ 14.909020][ C3] ? start_secondary+0x213/0x2a0 [ 14.909023][ C3] ? common_startup_64+0x13e/0x148 [ 14.909031][ C3] __put_net+0x19/0x60 [ 14.909035][ C3] __sk_destruct+0x58a/0x780 [ 14.909039][ C3] ? rcu_do_batch+0x34a/0xf20 [ 14.909041][ C3] rcu_do_batch+0x34c/0xf20 [ 14.909044][ C3] ? find_held_lock+0x2b/0x80 [ 14.909049][ C3] ? __pfx_rcu_do_batch+0x10/0x10 [ 14.909052][ C3] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 14.909056][ C3] ? lockdep_hardirqs_on+0x7c/0x110 [ 14.909058][ C3] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 14.909063][ C3] rcu_core+0x2bb/0x500 [ 14.909067][ C3] handle_softirqs+0x218/0x620 [ 14.909073][ C3] __irq_exit_rcu+0xfa/0x160 [ 14.909076][ C3] irq_exit_rcu+0xe/0x30 [ 14.909078][ C3] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 14.909082][ C3] [ 14.909083][ C3] [ 14.909084][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.909087][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 14.909091][ C3] Code: 92 b5 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 83 37 10 00 fb f4 fc 54 02 00 66 2e 0f 1f 84 00 00 00 00 00 66 90 90 90 90 90 90 [ 14.909094][ C3] RSP: 0018:ffffc90000157de8 EFLAGS: 00000242 [ 14.909097][ C3] RAX: 00000000000450fb RBX: 1ffff9200002afc1 RCX: ffffffff8b1905d9 [ 14.909099][ C3] RDX: 0000000000000000 RSI: ffffffff8bc8f4e4 RDI: ffffffff8b453be0 [ 14.909101][ C3] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1006c366fa [ 14.909103][ C3] R10: ffff8880361b37d3 R11: ffffffff8be11288 R12: 0000000000000000 [ 14.909104][ C3] R13: ffff888001bf45c0 R14: dffffc0000000000 R15: 0000000000000000 [ 14.909108][ C3] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 14.909113][ C3] default_idle+0x9/0x10 [ 14.909115][ C3] default_idle_call+0x6d/0xb0 [ 14.909118][ C3] cpuidle_idle_call+0x26f/0x3a0 [ 14.909121][ C3] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 14.909125][ C3] ? tsc_verify_tsc_adjust+0x98/0x310 [ 14.909129][ C3] do_idle+0xf6/0x160 [ 14.909133][ C3] cpu_startup_entry+0x54/0x60 [ 14.909136][ C3] start_secondary+0x213/0x2a0 [ 14.909138][ C3] ? __pfx_start_secondary+0x10/0x10 [ 14.909143][ C3] common_startup_64+0x13e/0x148 [ 14.909150][ C3] [ 14.924241][ C3] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 14.924541][ C3] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/3 [ 14.924843][ C3] preempt_count: 101, expected: 0 [ 14.925015][ C3] RCU nest depth: 0, expected: 0 [ 14.925303][ C3] INFO: lockdep is turned off. [ 14.925476][ C3] Preemption disabled at: [ 14.925478][ C3] [<0000000000000000>] 0x0 [ 14.925778][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 14.925782][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.925784][ C3] Call Trace: [ 14.925785][ C3] [ 14.925787][ C3] dump_stack_lvl+0xb0/0xd0 [ 14.925794][ C3] __might_resched+0x360/0x5a0 [ 14.925801][ C3] down_write+0x72/0x210 [ 14.925805][ C3] ? __pfx_down_write+0x10/0x10 [ 14.925808][ C3] ? simple_recursive_removal+0x33/0x610 [ 14.925812][ C3] ? rcu_is_watching+0x12/0xc0 [ 14.925816][ C3] ? do_raw_spin_unlock+0x58/0x220 [ 14.925822][ C3] simple_recursive_removal+0x6a/0x610 [ 14.925824][ C3] ? do_raw_spin_unlock+0x58/0x220 [ 14.925828][ C3] ? __pfx_remove_one+0x10/0x10 [ 14.925833][ C3] debugfs_remove+0x44/0x70 [ 14.925836][ C3] ref_tracker_dir_exit+0xcf/0x740 [ 14.925841][ C3] ? __pfx_ref_tracker_free+0x10/0x10 [ 14.925845][ C3] ? __sk_destruct+0x383/0x780 [ 14.925850][ C3] ? rcu_do_batch+0x34c/0xf20 [ 14.925853][ C3] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 14.925856][ C3] ? irq_exit_rcu+0xe/0x30 [ 14.925859][ C3] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 14.925863][ C3] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.925866][ C3] ? pv_native_safe_halt+0xf/0x20 [ 14.925870][ C3] ? default_idle+0x9/0x10 [ 14.925873][ C3] ? default_idle_call+0x6d/0xb0 [ 14.925875][ C3] ? cpuidle_idle_call+0x26f/0x3a0 [ 14.925878][ C3] ? do_idle+0xf6/0x160 [ 14.925880][ C3] ? cpu_startup_entry+0x54/0x60 [ 14.925883][ C3] ? start_secondary+0x213/0x2a0 [ 14.925885][ C3] ? common_startup_64+0x13e/0x148 [ 14.925892][ C3] __put_net+0x19/0x60 [ 14.925896][ C3] __sk_destruct+0x58a/0x780 [ 14.925900][ C3] ? rcu_do_batch+0x34a/0xf20 [ 14.925903][ C3] rcu_do_batch+0x34c/0xf20 [ 14.925906][ C3] ? find_held_lock+0x2b/0x80 [ 14.925912][ C3] ? __pfx_rcu_do_batch+0x10/0x10 [ 14.925915][ C3] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 14.925919][ C3] ? lockdep_hardirqs_on+0x7c/0x110 [ 14.925922][ C3] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 14.925927][ C3] rcu_core+0x2bb/0x500 [ 14.925931][ C3] handle_softirqs+0x218/0x620 [ 14.925936][ C3] __irq_exit_rcu+0xfa/0x160 [ 14.925939][ C3] irq_exit_rcu+0xe/0x30 [ 14.925942][ C3] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 14.925945][ C3] [ 14.925946][ C3] [ 14.925948][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.925951][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 14.925954][ C3] Code: 92 b5 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 83 37 10 00 fb f4 fc 54 02 00 66 2e 0f 1f 84 00 00 00 00 00 66 90 90 90 90 90 90 [ 14.925957][ C3] RSP: 0018:ffffc90000157de8 EFLAGS: 00000242 [ 14.925960][ C3] RAX: 00000000000450fb RBX: 1ffff9200002afc1 RCX: ffffffff8b1905d9 [ 14.925962][ C3] RDX: 0000000000000000 RSI: ffffffff8bc8f4e4 RDI: ffffffff8b453be0 [ 14.925963][ C3] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1006c366fa [ 14.925965][ C3] R10: ffff8880361b37d3 R11: ffffffff8be11288 R12: 0000000000000000 [ 14.925967][ C3] R13: ffff888001bf45c0 R14: dffffc0000000000 R15: 0000000000000000 [ 14.925971][ C3] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 14.925975][ C3] default_idle+0x9/0x10 [ 14.925978][ C3] default_idle_call+0x6d/0xb0 [ 14.925980][ C3] cpuidle_idle_call+0x26f/0x3a0 [ 14.925983][ C3] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 14.925987][ C3] ? tsc_verify_tsc_adjust+0x98/0x310 [ 14.925991][ C3] do_idle+0xf6/0x160 [ 14.925995][ C3] cpu_startup_entry+0x54/0x60 [ 14.925997][ C3] start_secondary+0x213/0x2a0 [ 14.926000][ C3] ? __pfx_start_secondary+0x10/0x10 [ 14.926005][ C3] common_startup_64+0x13e/0x148 [ 14.926012][ C3] [ 16.527308][ T259] packetdrill (259) used greatest stack depth: 24064 bytes left [ 16.539512][ C1] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 16.539909][ C1] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/1 [ 16.540214][ C1] preempt_count: 101, expected: 0 [ 16.540397][ C1] RCU nest depth: 0, expected: 0 [ 16.540572][ C1] INFO: lockdep is turned off. [ 16.540776][ C1] Preemption disabled at: [ 16.540778][ C1] [<0000000000000000>] 0x0 [ 16.541094][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 6.15.0-virtme #1 PREEMPT(full) [ 16.541099][ C1] Tainted: [W]=WARN [ 16.541101][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.541103][ C1] Call Trace: [ 16.541105][ C1] [ 16.541107][ C1] dump_stack_lvl+0xb0/0xd0 [ 16.541116][ C1] __might_resched+0x360/0x5a0 [ 16.541123][ C1] down_write+0x72/0x210 [ 16.541128][ C1] ? __pfx_down_write+0x10/0x10 [ 16.541131][ C1] ? simple_recursive_removal+0x33/0x610 [ 16.541135][ C1] ? rcu_is_watching+0x12/0xc0 [ 16.541140][ C1] ? do_raw_spin_unlock+0x58/0x220 [ 16.541146][ C1] simple_recursive_removal+0x6a/0x610 [ 16.541149][ C1] ? do_raw_spin_unlock+0x58/0x220 [ 16.541152][ C1] ? __pfx_remove_one+0x10/0x10 [ 16.541158][ C1] debugfs_remove+0x44/0x70 [ 16.541160][ C1] ref_tracker_dir_exit+0xcf/0x740 [ 16.541167][ C1] ? __pfx_ref_tracker_free+0x10/0x10 [ 16.541170][ C1] ? __sk_destruct+0x383/0x780 [ 16.541176][ C1] ? rcu_do_batch+0x34c/0xf20 [ 16.541179][ C1] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 16.541182][ C1] ? irq_exit_rcu+0xe/0x30 [ 16.541187][ C1] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.541192][ C1] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.541196][ C1] ? pv_native_safe_halt+0xf/0x20 [ 16.541200][ C1] ? default_idle+0x9/0x10 [ 16.541202][ C1] ? default_idle_call+0x6d/0xb0 [ 16.541205][ C1] ? cpuidle_idle_call+0x26f/0x3a0 [ 16.541208][ C1] ? do_idle+0xf6/0x160 [ 16.541211][ C1] ? cpu_startup_entry+0x54/0x60 [ 16.541213][ C1] ? start_secondary+0x213/0x2a0 [ 16.541217][ C1] ? common_startup_64+0x13e/0x148 [ 16.541222][ C1] ? bpf_sk_storage_free+0x9f/0x1c0 [ 16.541226][ C1] ? rcu_is_watching+0x12/0xc0 [ 16.541234][ C1] __put_net+0x19/0x60 [ 16.541238][ C1] __sk_destruct+0x58a/0x780 [ 16.541242][ C1] ? rcu_do_batch+0x34a/0xf20 [ 16.541245][ C1] rcu_do_batch+0x34c/0xf20 [ 16.541249][ C1] ? __pfx_rcu_do_batch+0x10/0x10 [ 16.541253][ C1] ? trace_irq_enable.constprop.0+0xd4/0x130 [ 16.541259][ C1] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 16.541265][ C1] rcu_core+0x2bb/0x500 [ 16.541269][ C1] handle_softirqs+0x218/0x620 [ 16.541274][ C1] __irq_exit_rcu+0xfa/0x160 [ 16.541277][ C1] irq_exit_rcu+0xe/0x30 [ 16.541280][ C1] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.541284][ C1] [ 16.541285][ C1] [ 16.541286][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.541289][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 16.541294][ C1] Code: 92 b5 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 83 37 10 00 fb f4 fc 54 02 00 66 2e 0f 1f 84 00 00 00 00 00 66 90 90 90 90 90 90 [ 16.541296][ C1] RSP: 0018:ffffc90000137de8 EFLAGS: 00000246 [ 16.541300][ C1] RAX: 0000000000000001 RBX: 1ffff92000026fc1 RCX: ffffffff8b1905d9 [ 16.541302][ C1] RDX: ffffed1006c166fb RSI: ffffffff8b453b60 RDI: ffffffff8898d18f [ 16.541304][ C1] RBP: 0000000000000000 R08: 0000000000000000 R09: ffffed1006c166fa [ 16.541306][ C1] R10: ffff8880360b37d3 R11: ffff888005848558 R12: 0000000000000000 [ 16.541308][ C1] R13: ffff888001bf0040 R14: dffffc0000000000 R15: 0000000000000000 [ 16.541312][ C1] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 16.541315][ C1] ? cpuidle_idle_call+0x26f/0x3a0 [ 16.541319][ C1] default_idle+0x9/0x10 [ 16.541321][ C1] default_idle_call+0x6d/0xb0 [ 16.541324][ C1] cpuidle_idle_call+0x26f/0x3a0 [ 16.541327][ C1] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 16.541331][ C1] ? tsc_verify_tsc_adjust+0x98/0x310 [ 16.541335][ C1] do_idle+0xf6/0x160 [ 16.541338][ C1] cpu_startup_entry+0x54/0x60 [ 16.541341][ C1] start_secondary+0x213/0x2a0 [ 16.541344][ C1] ? __pfx_start_secondary+0x10/0x10 [ 16.541348][ C1] common_startup_64+0x13e/0x148 [ 16.541355][ C1]