[ 14.891770][ C0] [ 14.891918][ C0] ================================ [ 14.892139][ C0] WARNING: inconsistent lock state [ 14.892348][ C0] 6.15.0-virtme #1 Not tainted [ 14.892557][ C0] -------------------------------- [ 14.892771][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 14.893026][ C0] swapper/0/0 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 14.893282][ C0] ffffffffa0dc5af8 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 14.893671][ C0] {SOFTIRQ-ON-W} state was registered at: [ 14.893880][ C0] __lock_acquire+0x20b/0x7e0 [ 14.894099][ C0] lock_acquire.part.0+0xb6/0x240 [ 14.894314][ C0] _raw_spin_lock+0x33/0x40 [ 14.894532][ C0] simple_pin_fs+0x24/0x150 [ 14.894741][ C0] start_creating.part.0+0x52/0x310 [ 14.894951][ C0] debugfs_create_dir+0x6a/0x540 [ 14.895156][ C0] component_debug_init+0x17/0x30 [ 14.895369][ C0] do_one_initcall+0x8f/0x1e0 [ 14.895581][ C0] do_initcalls+0x176/0x280 [ 14.895791][ C0] kernel_init_freeable+0x22d/0x300 [ 14.895995][ C0] kernel_init+0x20/0x200 [ 14.896201][ C0] ret_from_fork+0x240/0x320 [ 14.896409][ C0] ret_from_fork_asm+0x1a/0x30 [ 14.896613][ C0] irq event stamp: 459848 [ 14.896770][ C0] hardirqs last enabled at (459848): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 14.897180][ C0] hardirqs last disabled at (459847): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 14.897594][ C0] softirqs last enabled at (457860): [] handle_softirqs+0x358/0x620 [ 14.897964][ C0] softirqs last disabled at (457891): [] __irq_exit_rcu+0xfa/0x160 [ 14.898321][ C0] [ 14.898321][ C0] other info that might help us debug this: [ 14.898628][ C0] Possible unsafe locking scenario: [ 14.898628][ C0] [ 14.898934][ C0] CPU0 [ 14.899088][ C0] ---- [ 14.899243][ C0] lock(pin_fs_lock); [ 14.899402][ C0] [ 14.899556][ C0] lock(pin_fs_lock); [ 14.899714][ C0] [ 14.899714][ C0] *** DEADLOCK *** [ 14.899714][ C0] [ 14.900014][ C0] 1 lock held by swapper/0/0: [ 14.900219][ C0] #0: ffffffffa0b774c0 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 14.900586][ C0] [ 14.900586][ C0] stack backtrace: [ 14.900841][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 14.900846][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.900848][ C0] Call Trace: [ 14.900850][ C0] [ 14.900851][ C0] dump_stack_lvl+0x82/0xd0 [ 14.900859][ C0] print_usage_bug.part.0+0x25f/0x360 [ 14.900863][ C0] mark_lock_irq+0x343/0x400 [ 14.900867][ C0] ? is_bpf_text_address+0x67/0x120 [ 14.900874][ C0] ? __lock_release+0x5d/0x170 [ 14.900878][ C0] ? save_trace+0x52/0x170 [ 14.900884][ C0] mark_lock+0x174/0x2f0 [ 14.900887][ C0] mark_usage+0x46/0x150 [ 14.900890][ C0] __lock_acquire+0x20b/0x7e0 [ 14.900893][ C0] ? __lock_acquire+0x44d/0x7e0 [ 14.900897][ C0] lock_acquire.part.0+0xb6/0x240 [ 14.900901][ C0] ? simple_pin_fs+0x24/0x150 [ 14.900905][ C0] ? rcu_is_watching+0x12/0xc0 [ 14.900909][ C0] ? lock_acquire+0x10c/0x170 [ 14.900911][ C0] ? simple_pin_fs+0x24/0x150 [ 14.900916][ C0] _raw_spin_lock+0x33/0x40 [ 14.900919][ C0] ? simple_pin_fs+0x24/0x150 [ 14.900923][ C0] simple_pin_fs+0x24/0x150 [ 14.900927][ C0] debugfs_remove+0x35/0x70 [ 14.900930][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 14.900938][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 14.900941][ C0] ? __sk_destruct+0x383/0x780 [ 14.900951][ C0] ? rcu_do_batch+0x34c/0xf20 [ 14.900954][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 14.900957][ C0] ? irq_exit_rcu+0xe/0x30 [ 14.900960][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 14.900966][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.900969][ C0] ? pv_native_safe_halt+0xf/0x20 [ 14.900972][ C0] ? default_idle+0x9/0x10 [ 14.900975][ C0] ? default_idle_call+0x6d/0xb0 [ 14.900978][ C0] ? cpuidle_idle_call+0x26f/0x3a0 [ 14.900981][ C0] ? do_idle+0xf6/0x160 [ 14.900983][ C0] ? cpu_startup_entry+0x54/0x60 [ 14.900986][ C0] ? rest_init+0x14b/0x260 [ 14.900988][ C0] ? start_kernel+0x350/0x410 [ 14.900994][ C0] __put_net+0x19/0x60 [ 14.900997][ C0] __sk_destruct+0x58a/0x780 [ 14.901001][ C0] ? rcu_do_batch+0x34a/0xf20 [ 14.901004][ C0] rcu_do_batch+0x34c/0xf20 [ 14.901007][ C0] ? find_held_lock+0x2b/0x80 [ 14.901011][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 14.901015][ C0] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 14.901018][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 14.901021][ C0] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 14.901026][ C0] rcu_core+0x2bb/0x500 [ 14.901030][ C0] handle_softirqs+0x218/0x620 [ 14.901036][ C0] __irq_exit_rcu+0xfa/0x160 [ 14.901039][ C0] irq_exit_rcu+0xe/0x30 [ 14.901042][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 14.901045][ C0] [ 14.901047][ C0] [ 14.901048][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.901051][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 14.901056][ 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 [ 14.901058][ C0] RSP: 0018:ffffffffa0807df8 EFLAGS: 00000242 [ 14.901063][ C0] RAX: 000000000006fca1 RBX: 1ffffffff4100fc3 RCX: ffffffff9fb905d9 [ 14.901065][ C0] RDX: 0000000000000000 RSI: ffffffffa068f4e4 RDI: ffffffff9fe53be0 [ 14.901067][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1006c066fa [ 14.901068][ C0] R10: ffff8880360337d3 R11: ffffffffa0811288 R12: 0000000000000000 [ 14.901070][ C0] R13: ffffffffa081f240 R14: dffffc0000000000 R15: 0000000000014770 [ 14.901074][ C0] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 14.901079][ C0] default_idle+0x9/0x10 [ 14.901081][ C0] default_idle_call+0x6d/0xb0 [ 14.901084][ C0] cpuidle_idle_call+0x26f/0x3a0 [ 14.901087][ C0] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 14.901091][ C0] ? tsc_verify_tsc_adjust+0x98/0x310 [ 14.901096][ C0] do_idle+0xf6/0x160 [ 14.901100][ C0] cpu_startup_entry+0x54/0x60 [ 14.901103][ C0] rest_init+0x14b/0x260 [ 14.901107][ C0] start_kernel+0x350/0x410 [ 14.901111][ C0] x86_64_start_reservations+0x18/0x30 [ 14.901115][ C0] x86_64_start_kernel+0xf8/0x150 [ 14.901119][ C0] common_startup_64+0x13e/0x148 [ 14.901129][ C0] [ 14.918202][ C0] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 14.918554][ C0] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0 [ 14.918890][ C0] preempt_count: 101, expected: 0 [ 14.919092][ C0] RCU nest depth: 0, expected: 0 [ 14.919286][ C0] INFO: lockdep is turned off. [ 14.919483][ C0] Preemption disabled at: [ 14.919485][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 14.920050][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 14.920054][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.920056][ C0] Call Trace: [ 14.920057][ C0] [ 14.920059][ C0] dump_stack_lvl+0xb0/0xd0 [ 14.920064][ C0] __might_resched+0x360/0x5a0 [ 14.920072][ C0] down_write+0x72/0x210 [ 14.920075][ C0] ? __pfx_down_write+0x10/0x10 [ 14.920078][ C0] ? simple_recursive_removal+0x33/0x610 [ 14.920082][ C0] ? rcu_is_watching+0x12/0xc0 [ 14.920086][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 14.920092][ C0] simple_recursive_removal+0x6a/0x610 [ 14.920095][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 14.920098][ C0] ? __pfx_remove_one+0x10/0x10 [ 14.920104][ C0] debugfs_remove+0x44/0x70 [ 14.920106][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 14.920111][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 14.920114][ C0] ? __sk_destruct+0x383/0x780 [ 14.920119][ C0] ? rcu_do_batch+0x34c/0xf20 [ 14.920122][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 14.920126][ C0] ? irq_exit_rcu+0xe/0x30 [ 14.920130][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 14.920134][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.920137][ C0] ? pv_native_safe_halt+0xf/0x20 [ 14.920141][ C0] ? default_idle+0x9/0x10 [ 14.920143][ C0] ? default_idle_call+0x6d/0xb0 [ 14.920146][ C0] ? cpuidle_idle_call+0x26f/0x3a0 [ 14.920149][ C0] ? do_idle+0xf6/0x160 [ 14.920151][ C0] ? cpu_startup_entry+0x54/0x60 [ 14.920153][ C0] ? rest_init+0x14b/0x260 [ 14.920156][ C0] ? start_kernel+0x350/0x410 [ 14.920163][ C0] __put_net+0x19/0x60 [ 14.920166][ C0] __sk_destruct+0x58a/0x780 [ 14.920170][ C0] ? rcu_do_batch+0x34a/0xf20 [ 14.920172][ C0] rcu_do_batch+0x34c/0xf20 [ 14.920176][ C0] ? find_held_lock+0x2b/0x80 [ 14.920181][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 14.920184][ C0] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 14.920189][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 14.920191][ C0] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 14.920196][ C0] rcu_core+0x2bb/0x500 [ 14.920200][ C0] handle_softirqs+0x218/0x620 [ 14.920205][ C0] __irq_exit_rcu+0xfa/0x160 [ 14.920208][ C0] irq_exit_rcu+0xe/0x30 [ 14.920211][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 14.920215][ C0] [ 14.920216][ C0] [ 14.920217][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.920220][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 14.920223][ 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 [ 14.920226][ C0] RSP: 0018:ffffffffa0807df8 EFLAGS: 00000242 [ 14.920229][ C0] RAX: 000000000006fca1 RBX: 1ffffffff4100fc3 RCX: ffffffff9fb905d9 [ 14.920231][ C0] RDX: 0000000000000000 RSI: ffffffffa068f4e4 RDI: ffffffff9fe53be0 [ 14.920232][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1006c066fa [ 14.920234][ C0] R10: ffff8880360337d3 R11: ffffffffa0811288 R12: 0000000000000000 [ 14.920236][ C0] R13: ffffffffa081f240 R14: dffffc0000000000 R15: 0000000000014770 [ 14.920240][ C0] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 14.920245][ C0] default_idle+0x9/0x10 [ 14.920247][ C0] default_idle_call+0x6d/0xb0 [ 14.920250][ C0] cpuidle_idle_call+0x26f/0x3a0 [ 14.920253][ C0] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 14.920257][ C0] ? tsc_verify_tsc_adjust+0x98/0x310 [ 14.920261][ C0] do_idle+0xf6/0x160 [ 14.920265][ C0] cpu_startup_entry+0x54/0x60 [ 14.920268][ C0] rest_init+0x14b/0x260 [ 14.920271][ C0] start_kernel+0x350/0x410 [ 14.920275][ C0] x86_64_start_reservations+0x18/0x30 [ 14.920279][ C0] x86_64_start_kernel+0xf8/0x150 [ 14.920282][ C0] common_startup_64+0x13e/0x148 [ 14.920290][ C0] [ 16.532297][ T257] packetdrill (257) used greatest stack depth: 24584 bytes left [ 16.544793][ C2] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 16.545153][ C2] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/2 [ 16.545478][ C2] preempt_count: 101, expected: 0 [ 16.545668][ C2] RCU nest depth: 0, expected: 0 [ 16.545851][ C2] INFO: lockdep is turned off. [ 16.546036][ C2] Preemption disabled at: [ 16.546038][ C2] [<0000000000000000>] 0x0 [ 16.546382][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 6.15.0-virtme #1 PREEMPT(full) [ 16.546388][ C2] Tainted: [W]=WARN [ 16.546389][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.546391][ C2] Call Trace: [ 16.546393][ C2] [ 16.546395][ C2] dump_stack_lvl+0xb0/0xd0 [ 16.546403][ C2] __might_resched+0x360/0x5a0 [ 16.546410][ C2] down_write+0x72/0x210 [ 16.546415][ C2] ? __pfx_down_write+0x10/0x10 [ 16.546418][ C2] ? simple_recursive_removal+0x33/0x610 [ 16.546422][ C2] ? rcu_is_watching+0x12/0xc0 [ 16.546428][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 16.546433][ C2] simple_recursive_removal+0x6a/0x610 [ 16.546436][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 16.546439][ C2] ? __pfx_remove_one+0x10/0x10 [ 16.546446][ C2] debugfs_remove+0x44/0x70 [ 16.546449][ C2] ref_tracker_dir_exit+0xcf/0x740 [ 16.546455][ C2] ? __pfx_ref_tracker_free+0x10/0x10 [ 16.546458][ C2] ? __sk_destruct+0x383/0x780 [ 16.546464][ C2] ? rcu_do_batch+0x34c/0xf20 [ 16.546467][ C2] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 16.546471][ C2] ? irq_exit_rcu+0xe/0x30 [ 16.546476][ C2] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.546481][ C2] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.546485][ C2] ? pv_native_safe_halt+0xf/0x20 [ 16.546488][ C2] ? default_idle+0x9/0x10 [ 16.546491][ C2] ? default_idle_call+0x6d/0xb0 [ 16.546494][ C2] ? cpuidle_idle_call+0x26f/0x3a0 [ 16.546497][ C2] ? do_idle+0xf6/0x160 [ 16.546499][ C2] ? cpu_startup_entry+0x54/0x60 [ 16.546502][ C2] ? start_secondary+0x213/0x2a0 [ 16.546505][ C2] ? common_startup_64+0x13e/0x148 [ 16.546510][ C2] ? bpf_sk_storage_free+0x9f/0x1c0 [ 16.546517][ C2] ? rcu_is_watching+0x12/0xc0 [ 16.546521][ C2] __put_net+0x19/0x60 [ 16.546525][ C2] __sk_destruct+0x58a/0x780 [ 16.546529][ C2] ? rcu_do_batch+0x34a/0xf20 [ 16.546531][ C2] rcu_do_batch+0x34c/0xf20 [ 16.546536][ C2] ? __pfx_rcu_do_batch+0x10/0x10 [ 16.546540][ C2] ? trace_irq_enable.constprop.0+0xd4/0x130 [ 16.546546][ C2] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 16.546551][ C2] rcu_core+0x2bb/0x500 [ 16.546556][ C2] handle_softirqs+0x218/0x620 [ 16.546561][ C2] __irq_exit_rcu+0xfa/0x160 [ 16.546564][ C2] irq_exit_rcu+0xe/0x30 [ 16.546567][ C2] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.546571][ C2] [ 16.546572][ C2] [ 16.546574][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.546577][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 16.546581][ 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 [ 16.546584][ C2] RSP: 0018:ffffc90000147de8 EFLAGS: 00000246 [ 16.546587][ C2] RAX: 0000000000000002 RBX: 1ffff92000028fc1 RCX: ffffffff9fb905d9 [ 16.546589][ C2] RDX: ffffed1006c266fb RSI: ffffffff9fe53b60 RDI: ffffffff9d38d18f [ 16.546591][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: ffffed1006c266fa [ 16.546593][ C2] R10: ffff8880361337d3 R11: ffff888001ee2818 R12: 0000000000000000 [ 16.546594][ C2] R13: ffff888001bf2300 R14: dffffc0000000000 R15: 0000000000000000 [ 16.546598][ C2] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 16.546601][ C2] ? cpuidle_idle_call+0x26f/0x3a0 [ 16.546605][ C2] default_idle+0x9/0x10 [ 16.546608][ C2] default_idle_call+0x6d/0xb0 [ 16.546611][ C2] cpuidle_idle_call+0x26f/0x3a0 [ 16.546614][ C2] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 16.546618][ C2] ? tsc_verify_tsc_adjust+0x98/0x310 [ 16.546622][ C2] do_idle+0xf6/0x160 [ 16.546626][ C2] cpu_startup_entry+0x54/0x60 [ 16.546628][ C2] start_secondary+0x213/0x2a0 [ 16.546631][ C2] ? __pfx_start_secondary+0x10/0x10 [ 16.546636][ C2] common_startup_64+0x13e/0x148 [ 16.546643][ C2]