[ 22.085890][ T226] packetdrill (226) used greatest stack depth: 24664 bytes left [ 22.099620][ C2] [ 22.099841][ C2] ================================ [ 22.100152][ C2] WARNING: inconsistent lock state [ 22.100469][ C2] 6.15.0-virtme #1 Not tainted [ 22.100784][ C2] -------------------------------- [ 22.101120][ C2] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 22.101533][ C2] swapper/2/0 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 22.101926][ C2] ffffffff9c7c5af8 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 22.102521][ C2] {SOFTIRQ-ON-W} state was registered at: [ 22.102839][ C2] __lock_acquire+0x20b/0x7e0 [ 22.103178][ C2] lock_acquire.part.0+0xb6/0x240 [ 22.103488][ C2] _raw_spin_lock+0x33/0x40 [ 22.103854][ C2] simple_pin_fs+0x24/0x150 [ 22.104168][ C2] start_creating.part.0+0x52/0x310 [ 22.104488][ C2] debugfs_create_dir+0x6a/0x540 [ 22.104824][ C2] component_debug_init+0x17/0x30 [ 22.105147][ C2] do_one_initcall+0x8f/0x1e0 [ 22.105463][ C2] do_initcalls+0x176/0x280 [ 22.105781][ C2] kernel_init_freeable+0x22d/0x300 [ 22.106096][ C2] kernel_init+0x20/0x200 [ 22.106407][ C2] ret_from_fork+0x240/0x320 [ 22.106734][ C2] ret_from_fork_asm+0x1a/0x30 [ 22.107049][ C2] irq event stamp: 311730 [ 22.107291][ C2] hardirqs last enabled at (311730): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 22.107925][ C2] hardirqs last disabled at (311729): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 22.108556][ C2] softirqs last enabled at (311200): [] handle_softirqs+0x358/0x620 [ 22.109103][ C2] softirqs last disabled at (311207): [] __irq_exit_rcu+0xfa/0x160 [ 22.109658][ C2] [ 22.109658][ C2] other info that might help us debug this: [ 22.110118][ C2] Possible unsafe locking scenario: [ 22.110118][ C2] [ 22.110578][ C2] CPU0 [ 22.110809][ C2] ---- [ 22.111048][ C2] lock(pin_fs_lock); [ 22.111293][ C2] [ 22.111526][ C2] lock(pin_fs_lock); [ 22.111764][ C2] [ 22.111764][ C2] *** DEADLOCK *** [ 22.111764][ C2] [ 22.112225][ C2] 1 lock held by swapper/2/0: [ 22.112540][ C2] #0: ffffffff9c5774c0 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 22.113095][ C2] [ 22.113095][ C2] stack backtrace: [ 22.113488][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 22.113496][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 22.113500][ C2] Call Trace: [ 22.113503][ C2] [ 22.113508][ C2] dump_stack_lvl+0x82/0xd0 [ 22.113519][ C2] print_usage_bug.part.0+0x25f/0x360 [ 22.113527][ C2] mark_lock_irq+0x343/0x400 [ 22.113535][ C2] ? __pfx_unwind_next_frame+0x10/0x10 [ 22.113544][ C2] ? save_trace+0x52/0x170 [ 22.113553][ C2] mark_lock+0x174/0x2f0 [ 22.113560][ C2] mark_usage+0x46/0x150 [ 22.113565][ C2] __lock_acquire+0x20b/0x7e0 [ 22.113570][ C2] ? __lock_acquire+0x44d/0x7e0 [ 22.113577][ C2] lock_acquire.part.0+0xb6/0x240 [ 22.113582][ C2] ? simple_pin_fs+0x24/0x150 [ 22.113590][ C2] ? rcu_is_watching+0x12/0xc0 [ 22.113597][ C2] ? lock_acquire+0x10c/0x170 [ 22.113601][ C2] ? simple_pin_fs+0x24/0x150 [ 22.113609][ C2] _raw_spin_lock+0x33/0x40 [ 22.113614][ C2] ? simple_pin_fs+0x24/0x150 [ 22.113619][ C2] simple_pin_fs+0x24/0x150 [ 22.113626][ C2] debugfs_remove+0x35/0x70 [ 22.113631][ C2] ref_tracker_dir_exit+0xcf/0x740 [ 22.113642][ C2] ? __pfx_ref_tracker_free+0x10/0x10 [ 22.113647][ C2] ? __sk_destruct+0x383/0x780 [ 22.113655][ C2] ? rcu_do_batch+0x34c/0xf20 [ 22.113660][ C2] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 22.113665][ C2] ? irq_exit_rcu+0xe/0x30 [ 22.113669][ C2] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 22.113677][ C2] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 22.113682][ C2] ? pv_native_safe_halt+0xf/0x20 [ 22.113687][ C2] ? default_idle+0x9/0x10 [ 22.113692][ C2] ? default_idle_call+0x6d/0xb0 [ 22.113696][ C2] ? cpuidle_idle_call+0x26f/0x3a0 [ 22.113701][ C2] ? do_idle+0xf6/0x160 [ 22.113705][ C2] ? cpu_startup_entry+0x54/0x60 [ 22.113709][ C2] ? start_secondary+0x213/0x2a0 [ 22.113714][ C2] ? common_startup_64+0x13e/0x148 [ 22.113726][ C2] __put_net+0x19/0x60 [ 22.113732][ C2] __sk_destruct+0x58a/0x780 [ 22.113738][ C2] ? rcu_do_batch+0x34a/0xf20 [ 22.113742][ C2] rcu_do_batch+0x34c/0xf20 [ 22.113748][ C2] ? find_held_lock+0x2b/0x80 [ 22.113755][ C2] ? __pfx_rcu_do_batch+0x10/0x10 [ 22.113761][ C2] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 22.113767][ C2] ? lockdep_hardirqs_on+0x7c/0x110 [ 22.113772][ C2] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 22.113780][ C2] rcu_core+0x2bb/0x500 [ 22.113787][ C2] handle_softirqs+0x218/0x620 [ 22.113796][ C2] __irq_exit_rcu+0xfa/0x160 [ 22.113801][ C2] irq_exit_rcu+0xe/0x30 [ 22.113805][ C2] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 22.113811][ C2] [ 22.113813][ C2] [ 22.113816][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 22.113821][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 22.113827][ C2] 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 [ 22.113831][ C2] RSP: 0000:ffffc90000147de8 EFLAGS: 00000246 [ 22.113836][ C2] RAX: 000000000004bfa5 RBX: 1ffff92000028fc1 RCX: ffffffff9b5905d9 [ 22.113839][ C2] RDX: 0000000000000000 RSI: ffffffff9c08f4e4 RDI: ffffffff9b853be0 [ 22.113842][ C2] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1005ea66fa [ 22.113845][ C2] R10: ffff88802f5337d3 R11: ffffffff9c211288 R12: 0000000000000000 [ 22.113848][ C2] R13: ffff888001bf2300 R14: dffffc0000000000 R15: 0000000000000000 [ 22.113855][ C2] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 22.113862][ C2] default_idle+0x9/0x10 [ 22.113866][ C2] default_idle_call+0x6d/0xb0 [ 22.113871][ C2] cpuidle_idle_call+0x26f/0x3a0 [ 22.113876][ C2] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 22.113883][ C2] ? tsc_verify_tsc_adjust+0x98/0x310 [ 22.113890][ C2] do_idle+0xf6/0x160 [ 22.113896][ C2] cpu_startup_entry+0x54/0x60 [ 22.113901][ C2] start_secondary+0x213/0x2a0 [ 22.113906][ C2] ? __pfx_start_secondary+0x10/0x10 [ 22.113915][ C2] common_startup_64+0x13e/0x148 [ 22.113928][ C2] [ 22.139658][ C2] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 22.140264][ C2] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/2 [ 22.140847][ C2] preempt_count: 101, expected: 0 [ 22.141176][ C2] RCU nest depth: 0, expected: 0 [ 22.141500][ C2] INFO: lockdep is turned off. [ 22.141826][ C2] Preemption disabled at: [ 22.141830][ C2] [<0000000000000000>] 0x0 [ 22.142394][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 22.142402][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 22.142405][ C2] Call Trace: [ 22.142408][ C2] [ 22.142412][ C2] dump_stack_lvl+0xb0/0xd0 [ 22.142424][ C2] __might_resched+0x360/0x5a0 [ 22.142436][ C2] down_write+0x72/0x210 [ 22.142443][ C2] ? __pfx_down_write+0x10/0x10 [ 22.142448][ C2] ? simple_recursive_removal+0x33/0x610 [ 22.142455][ C2] ? rcu_is_watching+0x12/0xc0 [ 22.142462][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 22.142472][ C2] simple_recursive_removal+0x6a/0x610 [ 22.142477][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 22.142481][ C2] ? __pfx_remove_one+0x10/0x10 [ 22.142490][ C2] debugfs_remove+0x44/0x70 [ 22.142494][ C2] ref_tracker_dir_exit+0xcf/0x740 [ 22.142502][ C2] ? __pfx_ref_tracker_free+0x10/0x10 [ 22.142507][ C2] ? __sk_destruct+0x383/0x780 [ 22.142515][ C2] ? rcu_do_batch+0x34c/0xf20 [ 22.142520][ C2] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 22.142525][ C2] ? irq_exit_rcu+0xe/0x30 [ 22.142531][ C2] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 22.142537][ C2] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 22.142542][ C2] ? pv_native_safe_halt+0xf/0x20 [ 22.142547][ C2] ? default_idle+0x9/0x10 [ 22.142551][ C2] ? default_idle_call+0x6d/0xb0 [ 22.142555][ C2] ? cpuidle_idle_call+0x26f/0x3a0 [ 22.142561][ C2] ? do_idle+0xf6/0x160 [ 22.142564][ C2] ? cpu_startup_entry+0x54/0x60 [ 22.142568][ C2] ? start_secondary+0x213/0x2a0 [ 22.142573][ C2] ? common_startup_64+0x13e/0x148 [ 22.142585][ C2] __put_net+0x19/0x60 [ 22.142591][ C2] __sk_destruct+0x58a/0x780 [ 22.142598][ C2] ? rcu_do_batch+0x34a/0xf20 [ 22.142602][ C2] rcu_do_batch+0x34c/0xf20 [ 22.142608][ C2] ? find_held_lock+0x2b/0x80 [ 22.142616][ C2] ? __pfx_rcu_do_batch+0x10/0x10 [ 22.142622][ C2] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 22.142628][ C2] ? lockdep_hardirqs_on+0x7c/0x110 [ 22.142633][ C2] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 22.142640][ C2] rcu_core+0x2bb/0x500 [ 22.142647][ C2] handle_softirqs+0x218/0x620 [ 22.142656][ C2] __irq_exit_rcu+0xfa/0x160 [ 22.142661][ C2] irq_exit_rcu+0xe/0x30 [ 22.142665][ C2] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 22.142671][ C2] [ 22.142672][ C2] [ 22.142675][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 22.142680][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 22.142686][ C2] 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 [ 22.142690][ C2] RSP: 0000:ffffc90000147de8 EFLAGS: 00000246 [ 22.142696][ C2] RAX: 000000000004bfa5 RBX: 1ffff92000028fc1 RCX: ffffffff9b5905d9 [ 22.142699][ C2] RDX: 0000000000000000 RSI: ffffffff9c08f4e4 RDI: ffffffff9b853be0 [ 22.142702][ C2] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1005ea66fa [ 22.142705][ C2] R10: ffff88802f5337d3 R11: ffffffff9c211288 R12: 0000000000000000 [ 22.142708][ C2] R13: ffff888001bf2300 R14: dffffc0000000000 R15: 0000000000000000 [ 22.142714][ C2] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 22.142724][ C2] default_idle+0x9/0x10 [ 22.142728][ C2] default_idle_call+0x6d/0xb0 [ 22.142732][ C2] cpuidle_idle_call+0x26f/0x3a0 [ 22.142738][ C2] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 22.142744][ C2] ? tsc_verify_tsc_adjust+0x98/0x310 [ 22.142751][ C2] do_idle+0xf6/0x160 [ 22.142757][ C2] cpu_startup_entry+0x54/0x60 [ 22.142762][ C2] start_secondary+0x213/0x2a0 [ 22.142767][ C2] ? __pfx_start_secondary+0x10/0x10 [ 22.142775][ C2] common_startup_64+0x13e/0x148 [ 22.142787][ C2] [ 27.553678][ C2] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 27.554383][ C2] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/2 [ 27.554988][ C2] preempt_count: 101, expected: 0 [ 27.555296][ C2] RCU nest depth: 0, expected: 0 [ 27.555604][ C2] INFO: lockdep is turned off. [ 27.555975][ C2] Preemption disabled at: [ 27.555978][ C2] [<0000000000000000>] 0x0 [ 27.556532][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 6.15.0-virtme #1 PREEMPT(full) [ 27.556542][ C2] Tainted: [W]=WARN [ 27.556545][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.556548][ C2] Call Trace: [ 27.556552][ C2] [ 27.556557][ C2] dump_stack_lvl+0xb0/0xd0 [ 27.556572][ C2] __might_resched+0x360/0x5a0 [ 27.556584][ C2] down_write+0x72/0x210 [ 27.556591][ C2] ? __pfx_down_write+0x10/0x10 [ 27.556596][ C2] ? simple_recursive_removal+0x33/0x610 [ 27.556604][ C2] ? rcu_is_watching+0x12/0xc0 [ 27.556612][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 27.556621][ C2] simple_recursive_removal+0x6a/0x610 [ 27.556625][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 27.556629][ C2] ? __pfx_remove_one+0x10/0x10 [ 27.556639][ C2] debugfs_remove+0x44/0x70 [ 27.556644][ C2] ref_tracker_dir_exit+0xcf/0x740 [ 27.556653][ C2] ? __pfx_ref_tracker_free+0x10/0x10 [ 27.556658][ C2] ? __sk_destruct+0x383/0x780 [ 27.556667][ C2] ? rcu_do_batch+0x34c/0xf20 [ 27.556672][ C2] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 27.556677][ C2] ? irq_exit_rcu+0xe/0x30 [ 27.556685][ C2] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 27.556692][ C2] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.556699][ C2] ? pv_native_safe_halt+0xf/0x20 [ 27.556703][ C2] ? default_idle+0x9/0x10 [ 27.556707][ C2] ? default_idle_call+0x6d/0xb0 [ 27.556711][ C2] ? cpuidle_idle_call+0x26f/0x3a0 [ 27.556720][ C2] ? do_idle+0xf6/0x160 [ 27.556724][ C2] ? cpu_startup_entry+0x54/0x60 [ 27.556729][ C2] ? start_secondary+0x213/0x2a0 [ 27.556735][ C2] ? common_startup_64+0x13e/0x148 [ 27.556744][ C2] ? bpf_sk_storage_free+0x9f/0x1c0 [ 27.556752][ C2] ? rcu_is_watching+0x12/0xc0 [ 27.556761][ C2] __put_net+0x19/0x60 [ 27.556767][ C2] __sk_destruct+0x58a/0x780 [ 27.556774][ C2] ? rcu_do_batch+0x34a/0xf20 [ 27.556779][ C2] rcu_do_batch+0x34c/0xf20 [ 27.556787][ C2] ? __pfx_rcu_do_batch+0x10/0x10 [ 27.556793][ C2] ? rcu_is_watching+0x12/0xc0 [ 27.556800][ C2] rcu_core+0x2bb/0x500 [ 27.556808][ C2] handle_softirqs+0x218/0x620 [ 27.556816][ C2] __irq_exit_rcu+0xfa/0x160 [ 27.556821][ C2] irq_exit_rcu+0xe/0x30 [ 27.556826][ C2] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 27.556833][ C2] [ 27.556835][ C2] [ 27.556838][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.556844][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 27.556851][ C2] 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 [ 27.556855][ C2] RSP: 0018:ffffc90000147de8 EFLAGS: 00000246 [ 27.556862][ C2] RAX: 0000000000000002 RBX: 1ffff92000028fc1 RCX: ffffffff9b5905d9 [ 27.556866][ C2] RDX: ffffed1005ea66fb RSI: ffffffff9b853b60 RDI: ffffffff98d8d18f [ 27.556870][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: ffffed1005ea66fa [ 27.556873][ C2] R10: ffff88802f5337d3 R11: ffff888001bdcad8 R12: 0000000000000000 [ 27.556876][ C2] R13: ffff888001bf2300 R14: dffffc0000000000 R15: 0000000000000000 [ 27.556882][ C2] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 27.556886][ C2] ? cpuidle_idle_call+0x26f/0x3a0 [ 27.556892][ C2] default_idle+0x9/0x10 [ 27.556897][ C2] default_idle_call+0x6d/0xb0 [ 27.556901][ C2] cpuidle_idle_call+0x26f/0x3a0 [ 27.556906][ C2] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 27.556911][ C2] ? tsc_verify_tsc_adjust+0x98/0x310 [ 27.556918][ C2] do_idle+0xf6/0x160 [ 27.556924][ C2] cpu_startup_entry+0x54/0x60 [ 27.556929][ C2] start_secondary+0x213/0x2a0 [ 27.556934][ C2] ? __pfx_start_secondary+0x10/0x10 [ 27.556942][ C2] common_startup_64+0x13e/0x148 [ 27.556956][ C2]