[ 14.484091][ C2] [ 14.484289][ C2] ================================ [ 14.484470][ C2] WARNING: inconsistent lock state [ 14.484658][ C2] 6.15.0-virtme #1 Not tainted [ 14.484839][ C2] -------------------------------- [ 14.485016][ C2] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 14.485237][ C2] ksoftirqd/2/27 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 14.485462][ C2] ffffffffb55c5af8 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 14.485787][ C2] {SOFTIRQ-ON-W} state was registered at: [ 14.485967][ C2] __lock_acquire+0x20b/0x7e0 [ 14.486151][ C2] lock_acquire.part.0+0xb6/0x240 [ 14.486331][ C2] _raw_spin_lock+0x33/0x40 [ 14.486514][ C2] simple_pin_fs+0x24/0x150 [ 14.486693][ C2] start_creating.part.0+0x52/0x310 [ 14.486876][ C2] debugfs_create_dir+0x6a/0x540 [ 14.487057][ C2] component_debug_init+0x17/0x30 [ 14.487239][ C2] do_one_initcall+0x8f/0x1e0 [ 14.487420][ C2] do_initcalls+0x176/0x280 [ 14.487602][ C2] kernel_init_freeable+0x22d/0x300 [ 14.487782][ C2] kernel_init+0x20/0x200 [ 14.487961][ C2] ret_from_fork+0x240/0x320 [ 14.488142][ C2] ret_from_fork_asm+0x1a/0x30 [ 14.488320][ C2] irq event stamp: 223862 [ 14.488457][ C2] hardirqs last enabled at (223862): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 14.488816][ C2] hardirqs last disabled at (223861): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 14.489173][ C2] softirqs last enabled at (222646): [] handle_softirqs+0x358/0x620 [ 14.489487][ C2] softirqs last disabled at (222649): [] run_ksoftirqd+0x3f/0x70 [ 14.489810][ C2] [ 14.489810][ C2] other info that might help us debug this: [ 14.490079][ C2] Possible unsafe locking scenario: [ 14.490079][ C2] [ 14.490355][ C2] CPU0 [ 14.490490][ C2] ---- [ 14.490629][ C2] lock(pin_fs_lock); [ 14.490766][ C2] [ 14.490905][ C2] lock(pin_fs_lock); [ 14.491047][ C2] [ 14.491047][ C2] *** DEADLOCK *** [ 14.491047][ C2] [ 14.491313][ C2] 1 lock held by ksoftirqd/2/27: [ 14.491498][ C2] #0: ffffffffb53774c0 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 14.491825][ C2] [ 14.491825][ C2] stack backtrace: [ 14.492053][ C2] CPU: 2 UID: 0 PID: 27 Comm: ksoftirqd/2 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 14.492058][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.492060][ C2] Call Trace: [ 14.492062][ C2] [ 14.492064][ C2] dump_stack_lvl+0x82/0xd0 [ 14.492070][ C2] print_usage_bug.part.0+0x25f/0x360 [ 14.492075][ C2] mark_lock_irq+0x343/0x400 [ 14.492079][ C2] ? __pfx_unwind_next_frame+0x10/0x10 [ 14.492084][ C2] ? save_trace+0x52/0x170 [ 14.492090][ C2] mark_lock+0x174/0x2f0 [ 14.492093][ C2] mark_usage+0x46/0x150 [ 14.492096][ C2] __lock_acquire+0x20b/0x7e0 [ 14.492100][ C2] ? __lock_acquire+0x44d/0x7e0 [ 14.492104][ C2] lock_acquire.part.0+0xb6/0x240 [ 14.492107][ C2] ? simple_pin_fs+0x24/0x150 [ 14.492111][ C2] ? rcu_is_watching+0x12/0xc0 [ 14.492115][ C2] ? lock_acquire+0x10c/0x170 [ 14.492118][ C2] ? simple_pin_fs+0x24/0x150 [ 14.492122][ C2] _raw_spin_lock+0x33/0x40 [ 14.492125][ C2] ? simple_pin_fs+0x24/0x150 [ 14.492129][ C2] simple_pin_fs+0x24/0x150 [ 14.492133][ C2] debugfs_remove+0x35/0x70 [ 14.492136][ C2] ref_tracker_dir_exit+0xcf/0x740 [ 14.492142][ C2] ? __pfx_ref_tracker_free+0x10/0x10 [ 14.492146][ C2] ? __sk_destruct+0x383/0x780 [ 14.492151][ C2] ? rcu_do_batch+0x34c/0xf20 [ 14.492154][ C2] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 14.492157][ C2] ? smpboot_thread_fn+0x30a/0x960 [ 14.492161][ C2] ? kthread+0x37e/0x600 [ 14.492164][ C2] ? ret_from_fork+0x240/0x320 [ 14.492166][ C2] ? ret_from_fork_asm+0x1a/0x30 [ 14.492169][ C2] ? migrate_enable+0x1f2/0x270 [ 14.492173][ C2] ? __pfx_migrate_enable+0x10/0x10 [ 14.492178][ C2] __put_net+0x19/0x60 [ 14.492181][ C2] __sk_destruct+0x58a/0x780 [ 14.492185][ C2] ? rcu_do_batch+0x34a/0xf20 [ 14.492187][ C2] rcu_do_batch+0x34c/0xf20 [ 14.492192][ C2] ? __pfx_rcu_do_batch+0x10/0x10 [ 14.492195][ C2] ? rcu_is_watching+0x12/0xc0 [ 14.492198][ C2] ? note_gp_changes+0x182/0x1e0 [ 14.492201][ C2] ? lockdep_hardirqs_on+0x7c/0x110 [ 14.492205][ C2] rcu_core+0x2bb/0x500 [ 14.492209][ C2] handle_softirqs+0x218/0x620 [ 14.492213][ C2] ? __pfx_run_ksoftirqd+0x10/0x10 [ 14.492216][ C2] run_ksoftirqd+0x3f/0x70 [ 14.492219][ C2] smpboot_thread_fn+0x30a/0x960 [ 14.492223][ C2] ? __pfx_smpboot_thread_fn+0x10/0x10 [ 14.492227][ C2] kthread+0x37e/0x600 [ 14.492230][ C2] ? __pfx_kthread+0x10/0x10 [ 14.492232][ C2] ? ret_from_fork+0x1b/0x320 [ 14.492234][ C2] ? __lock_release+0x5d/0x170 [ 14.492237][ C2] ? rcu_is_watching+0x12/0xc0 [ 14.492240][ C2] ? __pfx_kthread+0x10/0x10 [ 14.492243][ C2] ret_from_fork+0x240/0x320 [ 14.492246][ C2] ? __pfx_kthread+0x10/0x10 [ 14.492248][ C2] ret_from_fork_asm+0x1a/0x30 [ 14.492256][ C2] [ 14.502366][ C2] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 14.502680][ C2] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 27, name: ksoftirqd/2 [ 14.502983][ C2] preempt_count: 100, expected: 0 [ 14.503161][ C2] RCU nest depth: 0, expected: 0 [ 14.503338][ C2] INFO: lockdep is turned off. [ 14.503527][ C2] Preemption disabled at: [ 14.503528][ C2] [] handle_softirqs+0xe5/0x620 [ 14.503910][ C2] CPU: 2 UID: 0 PID: 27 Comm: ksoftirqd/2 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 14.503914][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.503916][ C2] Call Trace: [ 14.503918][ C2] [ 14.503919][ C2] dump_stack_lvl+0xb0/0xd0 [ 14.503926][ C2] __might_resched+0x360/0x5a0 [ 14.503932][ C2] down_write+0x72/0x210 [ 14.503936][ C2] ? __pfx_down_write+0x10/0x10 [ 14.503938][ C2] ? simple_recursive_removal+0x33/0x610 [ 14.503942][ C2] ? rcu_is_watching+0x12/0xc0 [ 14.503947][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 14.503952][ C2] simple_recursive_removal+0x6a/0x610 [ 14.503955][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 14.503959][ C2] ? __pfx_remove_one+0x10/0x10 [ 14.503963][ C2] debugfs_remove+0x44/0x70 [ 14.503966][ C2] ref_tracker_dir_exit+0xcf/0x740 [ 14.503971][ C2] ? __pfx_ref_tracker_free+0x10/0x10 [ 14.503975][ C2] ? __sk_destruct+0x383/0x780 [ 14.503979][ C2] ? rcu_do_batch+0x34c/0xf20 [ 14.503982][ C2] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 14.503985][ C2] ? smpboot_thread_fn+0x30a/0x960 [ 14.503989][ C2] ? kthread+0x37e/0x600 [ 14.503991][ C2] ? ret_from_fork+0x240/0x320 [ 14.503994][ C2] ? ret_from_fork_asm+0x1a/0x30 [ 14.504000][ C2] ? migrate_enable+0x1f2/0x270 [ 14.504003][ C2] ? __pfx_migrate_enable+0x10/0x10 [ 14.504008][ C2] __put_net+0x19/0x60 [ 14.504012][ C2] __sk_destruct+0x58a/0x780 [ 14.504016][ C2] ? rcu_do_batch+0x34a/0xf20 [ 14.504018][ C2] rcu_do_batch+0x34c/0xf20 [ 14.504023][ C2] ? __pfx_rcu_do_batch+0x10/0x10 [ 14.504026][ C2] ? rcu_is_watching+0x12/0xc0 [ 14.504029][ C2] ? note_gp_changes+0x182/0x1e0 [ 14.504032][ C2] ? lockdep_hardirqs_on+0x7c/0x110 [ 14.504036][ C2] rcu_core+0x2bb/0x500 [ 14.504040][ C2] handle_softirqs+0x218/0x620 [ 14.504044][ C2] ? __pfx_run_ksoftirqd+0x10/0x10 [ 14.504048][ C2] run_ksoftirqd+0x3f/0x70 [ 14.504051][ C2] smpboot_thread_fn+0x30a/0x960 [ 14.504054][ C2] ? __pfx_smpboot_thread_fn+0x10/0x10 [ 14.504058][ C2] kthread+0x37e/0x600 [ 14.504061][ C2] ? __pfx_kthread+0x10/0x10 [ 14.504063][ C2] ? ret_from_fork+0x1b/0x320 [ 14.504066][ C2] ? __lock_release+0x5d/0x170 [ 14.504070][ C2] ? rcu_is_watching+0x12/0xc0 [ 14.504073][ C2] ? __pfx_kthread+0x10/0x10 [ 14.504076][ C2] ret_from_fork+0x240/0x320 [ 14.504078][ C2] ? __pfx_kthread+0x10/0x10 [ 14.504081][ C2] ret_from_fork_asm+0x1a/0x30 [ 14.504088][ C2] [ 16.107749][ T259] packetdrill (259) used greatest stack depth: 24584 bytes left [ 16.118806][ C3] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 16.119156][ C3] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/3 [ 16.119485][ C3] preempt_count: 101, expected: 0 [ 16.119682][ C3] RCU nest depth: 0, expected: 0 [ 16.119866][ C3] INFO: lockdep is turned off. [ 16.120053][ C3] Preemption disabled at: [ 16.120055][ C3] [<0000000000000000>] 0x0 [ 16.120393][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Tainted: G W 6.15.0-virtme #1 PREEMPT(full) [ 16.120398][ C3] Tainted: [W]=WARN [ 16.120400][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.120402][ C3] Call Trace: [ 16.120404][ C3] [ 16.120406][ C3] dump_stack_lvl+0xb0/0xd0 [ 16.120415][ C3] __might_resched+0x360/0x5a0 [ 16.120422][ C3] down_write+0x72/0x210 [ 16.120426][ C3] ? __pfx_down_write+0x10/0x10 [ 16.120429][ C3] ? simple_recursive_removal+0x33/0x610 [ 16.120433][ C3] ? rcu_is_watching+0x12/0xc0 [ 16.120439][ C3] ? do_raw_spin_unlock+0x58/0x220 [ 16.120444][ C3] simple_recursive_removal+0x6a/0x610 [ 16.120447][ C3] ? do_raw_spin_unlock+0x58/0x220 [ 16.120450][ C3] ? __pfx_remove_one+0x10/0x10 [ 16.120456][ C3] debugfs_remove+0x44/0x70 [ 16.120459][ C3] ref_tracker_dir_exit+0xcf/0x740 [ 16.120464][ C3] ? __pfx_ref_tracker_free+0x10/0x10 [ 16.120468][ C3] ? __sk_destruct+0x383/0x780 [ 16.120473][ C3] ? rcu_do_batch+0x34c/0xf20 [ 16.120476][ C3] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 16.120479][ C3] ? irq_exit_rcu+0xe/0x30 [ 16.120485][ C3] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.120490][ C3] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.120493][ C3] ? pv_native_safe_halt+0xf/0x20 [ 16.120497][ C3] ? default_idle+0x9/0x10 [ 16.120499][ C3] ? default_idle_call+0x6d/0xb0 [ 16.120502][ C3] ? cpuidle_idle_call+0x26f/0x3a0 [ 16.120505][ C3] ? do_idle+0xf6/0x160 [ 16.120508][ C3] ? cpu_startup_entry+0x54/0x60 [ 16.120510][ C3] ? start_secondary+0x213/0x2a0 [ 16.120514][ C3] ? common_startup_64+0x13e/0x148 [ 16.120520][ C3] ? bpf_sk_storage_free+0x9f/0x1c0 [ 16.120523][ C3] ? rcu_is_watching+0x12/0xc0 [ 16.120528][ C3] __put_net+0x19/0x60 [ 16.120535][ C3] __sk_destruct+0x58a/0x780 [ 16.120539][ C3] ? rcu_do_batch+0x34a/0xf20 [ 16.120542][ C3] rcu_do_batch+0x34c/0xf20 [ 16.120546][ C3] ? __pfx_rcu_do_batch+0x10/0x10 [ 16.120550][ C3] ? trace_irq_enable.constprop.0+0xd4/0x130 [ 16.120556][ C3] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 16.120562][ C3] rcu_core+0x2bb/0x500 [ 16.120566][ C3] handle_softirqs+0x218/0x620 [ 16.120572][ C3] __irq_exit_rcu+0xfa/0x160 [ 16.120575][ C3] irq_exit_rcu+0xe/0x30 [ 16.120578][ C3] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.120582][ C3] [ 16.120583][ C3] [ 16.120584][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.120587][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 16.120591][ C3] Code: a2 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 47 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.120594][ C3] RSP: 0018:ffffc90000157de8 EFLAGS: 00000246 [ 16.120597][ C3] RAX: 0000000000000003 RBX: 1ffff9200002afc1 RCX: ffffffffb438f5d9 [ 16.120599][ C3] RDX: ffffed1006c366fb RSI: ffffffffb4653b60 RDI: ffffffffb1b8d18f [ 16.120601][ C3] RBP: 0000000000000000 R08: 0000000000000000 R09: ffffed1006c366fa [ 16.120602][ C3] R10: ffff8880361b37d3 R11: ffff88800a1e8558 R12: 0000000000000000 [ 16.120604][ C3] R13: ffff888001bf45c0 R14: dffffc0000000000 R15: 0000000000000000 [ 16.120608][ C3] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 16.120611][ C3] ? cpuidle_idle_call+0x26f/0x3a0 [ 16.120615][ C3] default_idle+0x9/0x10 [ 16.120617][ C3] default_idle_call+0x6d/0xb0 [ 16.120620][ C3] cpuidle_idle_call+0x26f/0x3a0 [ 16.120623][ C3] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 16.120627][ C3] ? tsc_verify_tsc_adjust+0x98/0x310 [ 16.120631][ C3] do_idle+0xf6/0x160 [ 16.120634][ C3] cpu_startup_entry+0x54/0x60 [ 16.120637][ C3] start_secondary+0x213/0x2a0 [ 16.120640][ C3] ? __pfx_start_secondary+0x10/0x10 [ 16.120644][ C3] common_startup_64+0x13e/0x148 [ 16.120651][ C3]