[ 16.147065][ T228] packetdrill (228) used greatest stack depth: 24264 bytes left [ 16.154194][ C0] [ 16.154341][ C0] ================================ [ 16.154587][ C0] WARNING: inconsistent lock state [ 16.154824][ C0] 6.15.0-virtme #1 Not tainted [ 16.155061][ C0] -------------------------------- [ 16.155296][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 16.155592][ C0] swapper/0/0 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 16.155886][ C0] ffffffff9f9c5af8 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 16.156300][ C0] {SOFTIRQ-ON-W} state was registered at: [ 16.156528][ C0] __lock_acquire+0x20b/0x7e0 [ 16.156769][ C0] lock_acquire.part.0+0xb6/0x240 [ 16.157001][ C0] _raw_spin_lock+0x33/0x40 [ 16.157232][ C0] simple_pin_fs+0x24/0x150 [ 16.157465][ C0] start_creating.part.0+0x52/0x310 [ 16.157713][ C0] debugfs_create_dir+0x6a/0x540 [ 16.157946][ C0] component_debug_init+0x17/0x30 [ 16.158181][ C0] do_one_initcall+0x8f/0x1e0 [ 16.158419][ C0] do_initcalls+0x176/0x280 [ 16.158656][ C0] kernel_init_freeable+0x22d/0x300 [ 16.158892][ C0] kernel_init+0x20/0x200 [ 16.159129][ C0] ret_from_fork+0x240/0x320 [ 16.159372][ C0] ret_from_fork_asm+0x1a/0x30 [ 16.159608][ C0] irq event stamp: 463058 [ 16.159788][ C0] hardirqs last enabled at (463058): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 16.160285][ C0] hardirqs last disabled at (463057): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 16.160779][ C0] softirqs last enabled at (461050): [] handle_softirqs+0x358/0x620 [ 16.161190][ C0] softirqs last disabled at (461057): [] __irq_exit_rcu+0xfa/0x160 [ 16.161605][ C0] [ 16.161605][ C0] other info that might help us debug this: [ 16.161956][ C0] Possible unsafe locking scenario: [ 16.161956][ C0] [ 16.162308][ C0] CPU0 [ 16.162487][ C0] ---- [ 16.162664][ C0] lock(pin_fs_lock); [ 16.162848][ C0] [ 16.163022][ C0] lock(pin_fs_lock); [ 16.163202][ C0] [ 16.163202][ C0] *** DEADLOCK *** [ 16.163202][ C0] [ 16.163552][ C0] 1 lock held by swapper/0/0: [ 16.163792][ C0] #0: ffffffff9f7774c0 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 16.164217][ C0] [ 16.164217][ C0] stack backtrace: [ 16.164505][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 16.164509][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.164512][ C0] Call Trace: [ 16.164513][ C0] [ 16.164515][ C0] dump_stack_lvl+0x82/0xd0 [ 16.164521][ C0] print_usage_bug.part.0+0x25f/0x360 [ 16.164526][ C0] mark_lock_irq+0x343/0x400 [ 16.164529][ C0] ? is_bpf_text_address+0x67/0x120 [ 16.164534][ C0] ? __lock_release+0x5d/0x170 [ 16.164538][ C0] ? save_trace+0x52/0x170 [ 16.164544][ C0] mark_lock+0x174/0x2f0 [ 16.164547][ C0] mark_usage+0x46/0x150 [ 16.164550][ C0] __lock_acquire+0x20b/0x7e0 [ 16.164553][ C0] ? __lock_acquire+0x44d/0x7e0 [ 16.164557][ C0] lock_acquire.part.0+0xb6/0x240 [ 16.164560][ C0] ? simple_pin_fs+0x24/0x150 [ 16.164565][ C0] ? rcu_is_watching+0x12/0xc0 [ 16.164569][ C0] ? lock_acquire+0x10c/0x170 [ 16.164572][ C0] ? simple_pin_fs+0x24/0x150 [ 16.164576][ C0] _raw_spin_lock+0x33/0x40 [ 16.164580][ C0] ? simple_pin_fs+0x24/0x150 [ 16.164583][ C0] simple_pin_fs+0x24/0x150 [ 16.164587][ C0] debugfs_remove+0x35/0x70 [ 16.164590][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 16.164596][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 16.164599][ C0] ? __sk_destruct+0x383/0x780 [ 16.164605][ C0] ? rcu_do_batch+0x34c/0xf20 [ 16.164608][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 16.164611][ C0] ? irq_exit_rcu+0xe/0x30 [ 16.164614][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.164618][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.164621][ C0] ? pv_native_safe_halt+0xf/0x20 [ 16.164624][ C0] ? default_idle+0x9/0x10 [ 16.164627][ C0] ? default_idle_call+0x6d/0xb0 [ 16.164630][ C0] ? cpuidle_idle_call+0x26f/0x3a0 [ 16.164633][ C0] ? do_idle+0xf6/0x160 [ 16.164635][ C0] ? cpu_startup_entry+0x54/0x60 [ 16.164638][ C0] ? rest_init+0x14b/0x260 [ 16.164640][ C0] ? start_kernel+0x350/0x410 [ 16.164646][ C0] __put_net+0x19/0x60 [ 16.164650][ C0] __sk_destruct+0x58a/0x780 [ 16.164654][ C0] ? rcu_do_batch+0x34a/0xf20 [ 16.164656][ C0] rcu_do_batch+0x34c/0xf20 [ 16.164659][ C0] ? find_held_lock+0x2b/0x80 [ 16.164664][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 16.164667][ C0] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 16.164670][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 16.164673][ C0] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 16.164678][ C0] rcu_core+0x2bb/0x500 [ 16.164682][ C0] handle_softirqs+0x218/0x620 [ 16.164688][ C0] __irq_exit_rcu+0xfa/0x160 [ 16.164691][ C0] irq_exit_rcu+0xe/0x30 [ 16.164693][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.164697][ C0] [ 16.164698][ C0] [ 16.164700][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.164702][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 16.164706][ C0] 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.164709][ C0] RSP: 0018:ffffffff9f407df8 EFLAGS: 00000246 [ 16.164713][ C0] RAX: 00000000000708ff RBX: 1ffffffff3e80fc3 RCX: ffffffff9e7905d9 [ 16.164715][ C0] RDX: 0000000000000000 RSI: ffffffff9f28f4e4 RDI: ffffffff9ea53be0 [ 16.164716][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1006c066fa [ 16.164718][ C0] R10: ffff8880360337d3 R11: ffffffff9f411288 R12: 0000000000000000 [ 16.164720][ C0] R13: ffffffff9f41f240 R14: dffffc0000000000 R15: 0000000000014770 [ 16.164724][ C0] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 16.164728][ C0] default_idle+0x9/0x10 [ 16.164731][ C0] default_idle_call+0x6d/0xb0 [ 16.164733][ C0] cpuidle_idle_call+0x26f/0x3a0 [ 16.164736][ C0] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 16.164740][ C0] ? tsc_verify_tsc_adjust+0x98/0x310 [ 16.164745][ C0] do_idle+0xf6/0x160 [ 16.164748][ C0] cpu_startup_entry+0x54/0x60 [ 16.164751][ C0] rest_init+0x14b/0x260 [ 16.164754][ C0] start_kernel+0x350/0x410 [ 16.164758][ C0] x86_64_start_reservations+0x18/0x30 [ 16.164763][ C0] x86_64_start_kernel+0xf8/0x150 [ 16.164767][ C0] common_startup_64+0x13e/0x148 [ 16.164776][ C0] [ 16.184983][ C0] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 16.185496][ C0] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0 [ 16.185887][ C0] preempt_count: 101, expected: 0 [ 16.186111][ C0] RCU nest depth: 0, expected: 0 [ 16.186334][ C0] INFO: lockdep is turned off. [ 16.186691][ C0] Preemption disabled at: [ 16.186693][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 16.187198][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 16.187202][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.187204][ C0] Call Trace: [ 16.187205][ C0] [ 16.187207][ C0] dump_stack_lvl+0xb0/0xd0 [ 16.187213][ C0] __might_resched+0x360/0x5a0 [ 16.187219][ C0] down_write+0x72/0x210 [ 16.187223][ C0] ? __pfx_down_write+0x10/0x10 [ 16.187226][ C0] ? simple_recursive_removal+0x33/0x610 [ 16.187230][ C0] ? rcu_is_watching+0x12/0xc0 [ 16.187235][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 16.187240][ C0] simple_recursive_removal+0x6a/0x610 [ 16.187243][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 16.187246][ C0] ? __pfx_remove_one+0x10/0x10 [ 16.187252][ C0] debugfs_remove+0x44/0x70 [ 16.187255][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 16.187261][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 16.187264][ C0] ? __sk_destruct+0x383/0x780 [ 16.187269][ C0] ? rcu_do_batch+0x34c/0xf20 [ 16.187272][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 16.187275][ C0] ? irq_exit_rcu+0xe/0x30 [ 16.187279][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.187283][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.187287][ C0] ? pv_native_safe_halt+0xf/0x20 [ 16.187290][ C0] ? default_idle+0x9/0x10 [ 16.187293][ C0] ? default_idle_call+0x6d/0xb0 [ 16.187296][ C0] ? cpuidle_idle_call+0x26f/0x3a0 [ 16.187298][ C0] ? do_idle+0xf6/0x160 [ 16.187301][ C0] ? cpu_startup_entry+0x54/0x60 [ 16.187303][ C0] ? rest_init+0x14b/0x260 [ 16.187306][ C0] ? start_kernel+0x350/0x410 [ 16.187313][ C0] __put_net+0x19/0x60 [ 16.187316][ C0] __sk_destruct+0x58a/0x780 [ 16.187320][ C0] ? rcu_do_batch+0x34a/0xf20 [ 16.187323][ C0] rcu_do_batch+0x34c/0xf20 [ 16.187326][ C0] ? find_held_lock+0x2b/0x80 [ 16.187331][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 16.187335][ C0] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 16.187339][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 16.187341][ C0] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 16.187346][ C0] rcu_core+0x2bb/0x500 [ 16.187350][ C0] handle_softirqs+0x218/0x620 [ 16.187356][ C0] __irq_exit_rcu+0xfa/0x160 [ 16.187359][ C0] irq_exit_rcu+0xe/0x30 [ 16.187361][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.187365][ C0] [ 16.187366][ C0] [ 16.187368][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.187370][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 16.187374][ C0] 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.187377][ C0] RSP: 0018:ffffffff9f407df8 EFLAGS: 00000246 [ 16.187380][ C0] RAX: 00000000000708ff RBX: 1ffffffff3e80fc3 RCX: ffffffff9e7905d9 [ 16.187382][ C0] RDX: 0000000000000000 RSI: ffffffff9f28f4e4 RDI: ffffffff9ea53be0 [ 16.187384][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1006c066fa [ 16.187386][ C0] R10: ffff8880360337d3 R11: ffffffff9f411288 R12: 0000000000000000 [ 16.187387][ C0] R13: ffffffff9f41f240 R14: dffffc0000000000 R15: 0000000000014770 [ 16.187391][ C0] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 16.187396][ C0] default_idle+0x9/0x10 [ 16.187398][ C0] default_idle_call+0x6d/0xb0 [ 16.187401][ C0] cpuidle_idle_call+0x26f/0x3a0 [ 16.187404][ C0] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 16.187407][ C0] ? tsc_verify_tsc_adjust+0x98/0x310 [ 16.187412][ C0] do_idle+0xf6/0x160 [ 16.187416][ C0] cpu_startup_entry+0x54/0x60 [ 16.187419][ C0] rest_init+0x14b/0x260 [ 16.187422][ C0] start_kernel+0x350/0x410 [ 16.187426][ C0] x86_64_start_reservations+0x18/0x30 [ 16.187430][ C0] x86_64_start_kernel+0xf8/0x150 [ 16.187434][ C0] common_startup_64+0x13e/0x148 [ 16.187443][ C0] [ 18.549774][ C2] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 18.550238][ C2] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 215, name: sh [ 18.550533][ C2] preempt_count: 101, expected: 0 [ 18.550745][ C2] RCU nest depth: 0, expected: 0 [ 18.550950][ C2] INFO: lockdep is turned off. [ 18.551149][ C2] Preemption disabled at: [ 18.551151][ C2] [] copy_fpstate_to_sigframe+0x240/0x530 [ 18.551606][ C2] CPU: 2 UID: 0 PID: 215 Comm: sh Tainted: G W 6.15.0-virtme #1 PREEMPT(full) [ 18.551611][ C2] Tainted: [W]=WARN [ 18.551613][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 18.551615][ C2] Call Trace: [ 18.551617][ C2] [ 18.551619][ C2] dump_stack_lvl+0xb0/0xd0 [ 18.551626][ C2] __might_resched+0x360/0x5a0 [ 18.551632][ C2] down_write+0x72/0x210 [ 18.551637][ C2] ? __pfx_down_write+0x10/0x10 [ 18.551642][ C2] ? simple_recursive_removal+0x33/0x610 [ 18.551647][ C2] ? rcu_is_watching+0x12/0xc0 [ 18.551652][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 18.551658][ C2] simple_recursive_removal+0x6a/0x610 [ 18.551661][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 18.551664][ C2] ? __pfx_remove_one+0x10/0x10 [ 18.551669][ C2] debugfs_remove+0x44/0x70 [ 18.551673][ C2] ref_tracker_dir_exit+0xcf/0x740 [ 18.551678][ C2] ? __pfx_ref_tracker_free+0x10/0x10 [ 18.551682][ C2] ? __sk_destruct+0x383/0x780 [ 18.551687][ C2] ? rcu_do_batch+0x34c/0xf20 [ 18.551691][ C2] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 18.551694][ C2] ? __local_bh_enable_ip+0x105/0x130 [ 18.551698][ C2] ? copy_fpstate_to_sigframe+0x1ca/0x530 [ 18.551701][ C2] ? get_sigframe+0x283/0xa60 [ 18.551706][ C2] ? x64_setup_rt_frame+0xfa/0x1110 [ 18.551709][ C2] ? handle_signal+0x295/0x370 [ 18.551713][ C2] ? arch_do_signal_or_restart+0x299/0x2f0 [ 18.551716][ C2] ? exit_to_user_mode_loop+0x88/0xe0 [ 18.551720][ C2] ? do_syscall_64+0x2fe/0x380 [ 18.551723][ C2] ? entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 18.551727][ C2] ? bpf_sk_storage_free+0x9f/0x1c0 [ 18.551731][ C2] ? rcu_is_watching+0x12/0xc0 [ 18.551735][ C2] __put_net+0x19/0x60 [ 18.551739][ C2] __sk_destruct+0x58a/0x780 [ 18.551743][ C2] ? rcu_do_batch+0x34a/0xf20 [ 18.551746][ C2] rcu_do_batch+0x34c/0xf20 [ 18.551749][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 18.551753][ C2] ? __pfx_rcu_do_batch+0x10/0x10 [ 18.551757][ C2] ? rcu_is_watching+0x12/0xc0 [ 18.551760][ C2] ? trace_irq_enable.constprop.0+0xd4/0x130 [ 18.551767][ C2] rcu_core+0x2bb/0x500 [ 18.551772][ C2] handle_softirqs+0x218/0x620 [ 18.551776][ C2] ? copy_fpstate_to_sigframe+0x1c5/0x530 [ 18.551779][ C2] do_softirq+0xb1/0xe0 [ 18.551782][ C2] [ 18.551783][ C2] [ 18.551785][ C2] __local_bh_enable_ip+0x105/0x130 [ 18.551788][ C2] copy_fpstate_to_sigframe+0x1ca/0x530 [ 18.551792][ C2] ? fpu__alloc_mathframe+0x2e/0x120 [ 18.551795][ C2] get_sigframe+0x283/0xa60 [ 18.551799][ C2] ? rcu_is_watching+0x12/0xc0 [ 18.551803][ C2] ? __pfx_get_sigframe+0x10/0x10 [ 18.551806][ C2] ? rcu_is_watching+0x12/0xc0 [ 18.551809][ C2] ? rcu_is_watching+0x12/0xc0 [ 18.551812][ C2] ? trace_rseq_update+0xd2/0x130 [ 18.551819][ C2] x64_setup_rt_frame+0xfa/0x1110 [ 18.551823][ C2] ? __pfx___rseq_handle_notify_resume+0x10/0x10 [ 18.551827][ C2] ? __pfx_x64_setup_rt_frame+0x10/0x10 [ 18.551834][ C2] handle_signal+0x295/0x370 [ 18.551838][ C2] arch_do_signal_or_restart+0x299/0x2f0 [ 18.551841][ C2] ? __pfx_arch_do_signal_or_restart+0x10/0x10 [ 18.551848][ C2] ? do_user_addr_fault+0x959/0xe00 [ 18.551851][ C2] ? rcu_is_watching+0x12/0xc0 [ 18.551855][ C2] exit_to_user_mode_loop+0x88/0xe0 [ 18.551859][ C2] do_syscall_64+0x2fe/0x380 [ 18.551862][ C2] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 18.551865][ C2] RIP: 0033:0x7f0cdfbcd83b [ 18.551869][ C2] Code: 48 85 f6 74 15 48 b9 00 00 00 80 01 00 00 00 48 8b 06 48 85 c8 75 48 49 89 f0 41 ba 08 00 00 00 4c 89 c6 b8 0e 00 00 00 0f 05 <89> c2 f7 da 3d 00 f0 ff ff b8 00 00 00 00 0f 47 c2 48 8b 94 24 88 [ 18.551871][ C2] RSP: 002b:00007ffff87e1570 EFLAGS: 00000246 ORIG_RAX: 000000000000000e [ 18.551875][ C2] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 00007f0cdfbcd83b [ 18.551877][ C2] RDX: 0000000000000000 RSI: 00007ffff87e1630 RDI: 0000000000000002 [ 18.551879][ C2] RBP: 0000000000000000 R08: 00007ffff87e1630 R09: 0000000000000000 [ 18.551880][ C2] R10: 0000000000000008 R11: 0000000000000246 R12: 0000560d9d060620 [ 18.551882][ C2] R13: 0000560d9d0ba440 R14: 00007ffff87e1630 R15: 0000000000000000 [ 18.551888][ C2]