[ 20.765383][ C2] [ 20.765503][ C2] ================================ [ 20.765686][ C2] WARNING: inconsistent lock state [ 20.765866][ C2] 6.15.0-virtme #1 Not tainted [ 20.766043][ C2] -------------------------------- [ 20.766218][ C2] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 20.766439][ C2] ksoftirqd/2/27 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 20.766678][ C2] ffffffff945c5af8 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 20.766988][ C2] {SOFTIRQ-ON-W} state was registered at: [ 20.767158][ C2] __lock_acquire+0x20b/0x7e0 [ 20.767347][ C2] lock_acquire.part.0+0xb6/0x240 [ 20.767515][ C2] _raw_spin_lock+0x33/0x40 [ 20.767688][ C2] simple_pin_fs+0x24/0x150 [ 20.767856][ C2] start_creating.part.0+0x52/0x310 [ 20.768027][ C2] debugfs_create_dir+0x6a/0x540 [ 20.768198][ C2] component_debug_init+0x17/0x30 [ 20.768382][ C2] do_one_initcall+0x8f/0x1e0 [ 20.768559][ C2] do_initcalls+0x176/0x280 [ 20.768733][ C2] kernel_init_freeable+0x22d/0x300 [ 20.768903][ C2] kernel_init+0x20/0x200 [ 20.769074][ C2] ret_from_fork+0x240/0x320 [ 20.769245][ C2] ret_from_fork_asm+0x1a/0x30 [ 20.769419][ C2] irq event stamp: 221030 [ 20.769547][ C2] hardirqs last enabled at (221030): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 20.769891][ C2] hardirqs last disabled at (221029): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 20.770224][ C2] softirqs last enabled at (220528): [] handle_softirqs+0x358/0x620 [ 20.770543][ C2] softirqs last disabled at (220535): [] run_ksoftirqd+0x3f/0x70 [ 20.770843][ C2] [ 20.770843][ C2] other info that might help us debug this: [ 20.771093][ C2] Possible unsafe locking scenario: [ 20.771093][ C2] [ 20.771353][ C2] CPU0 [ 20.771478][ C2] ---- [ 20.771604][ C2] lock(pin_fs_lock); [ 20.771734][ C2] [ 20.771859][ C2] lock(pin_fs_lock); [ 20.771988][ C2] [ 20.771988][ C2] *** DEADLOCK *** [ 20.771988][ C2] [ 20.772246][ C2] 1 lock held by ksoftirqd/2/27: [ 20.772426][ C2] #0: ffffffff943774c0 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 20.772729][ C2] [ 20.772729][ C2] stack backtrace: [ 20.772942][ C2] CPU: 2 UID: 0 PID: 27 Comm: ksoftirqd/2 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 20.772946][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 20.772949][ C2] Call Trace: [ 20.772951][ C2] [ 20.772953][ C2] dump_stack_lvl+0x82/0xd0 [ 20.772960][ C2] print_usage_bug.part.0+0x25f/0x360 [ 20.772965][ C2] mark_lock_irq+0x343/0x400 [ 20.772970][ C2] ? __pfx_unwind_next_frame+0x10/0x10 [ 20.772974][ C2] ? save_trace+0x52/0x170 [ 20.772980][ C2] mark_lock+0x174/0x2f0 [ 20.772984][ C2] mark_usage+0x46/0x150 [ 20.772987][ C2] __lock_acquire+0x20b/0x7e0 [ 20.772990][ C2] ? __lock_acquire+0x44d/0x7e0 [ 20.772994][ C2] lock_acquire.part.0+0xb6/0x240 [ 20.772997][ C2] ? simple_pin_fs+0x24/0x150 [ 20.773002][ C2] ? rcu_is_watching+0x12/0xc0 [ 20.773006][ C2] ? lock_acquire+0x10c/0x170 [ 20.773008][ C2] ? simple_pin_fs+0x24/0x150 [ 20.773013][ C2] _raw_spin_lock+0x33/0x40 [ 20.773016][ C2] ? simple_pin_fs+0x24/0x150 [ 20.773020][ C2] simple_pin_fs+0x24/0x150 [ 20.773024][ C2] debugfs_remove+0x35/0x70 [ 20.773028][ C2] ref_tracker_dir_exit+0xcf/0x740 [ 20.773034][ C2] ? __pfx_ref_tracker_free+0x10/0x10 [ 20.773037][ C2] ? __sk_destruct+0x383/0x780 [ 20.773043][ C2] ? rcu_do_batch+0x34c/0xf20 [ 20.773046][ C2] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 20.773049][ C2] ? smpboot_thread_fn+0x30a/0x960 [ 20.773054][ C2] ? kthread+0x37e/0x600 [ 20.773056][ C2] ? ret_from_fork+0x240/0x320 [ 20.773059][ C2] ? ret_from_fork_asm+0x1a/0x30 [ 20.773064][ C2] ? migrate_enable+0x1f2/0x270 [ 20.773067][ C2] ? __pfx_migrate_enable+0x10/0x10 [ 20.773073][ C2] __put_net+0x19/0x60 [ 20.773077][ C2] __sk_destruct+0x58a/0x780 [ 20.773081][ C2] ? rcu_do_batch+0x34a/0xf20 [ 20.773083][ C2] rcu_do_batch+0x34c/0xf20 [ 20.773088][ C2] ? __pfx_rcu_do_batch+0x10/0x10 [ 20.773092][ C2] ? rcu_is_watching+0x12/0xc0 [ 20.773095][ C2] ? note_gp_changes+0x182/0x1e0 [ 20.773098][ C2] ? lockdep_hardirqs_on+0x7c/0x110 [ 20.773102][ C2] rcu_core+0x2bb/0x500 [ 20.773106][ C2] handle_softirqs+0x218/0x620 [ 20.773110][ C2] ? __pfx_run_ksoftirqd+0x10/0x10 [ 20.773114][ C2] run_ksoftirqd+0x3f/0x70 [ 20.773116][ C2] smpboot_thread_fn+0x30a/0x960 [ 20.773120][ C2] ? __pfx_smpboot_thread_fn+0x10/0x10 [ 20.773124][ C2] kthread+0x37e/0x600 [ 20.773127][ C2] ? __pfx_kthread+0x10/0x10 [ 20.773129][ C2] ? ret_from_fork+0x1b/0x320 [ 20.773132][ C2] ? __lock_release+0x5d/0x170 [ 20.773135][ C2] ? rcu_is_watching+0x12/0xc0 [ 20.773138][ C2] ? __pfx_kthread+0x10/0x10 [ 20.773141][ C2] ret_from_fork+0x240/0x320 [ 20.773144][ C2] ? __pfx_kthread+0x10/0x10 [ 20.773146][ C2] ret_from_fork_asm+0x1a/0x30 [ 20.773153][ C2] [ 20.783309][ C2] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 20.783640][ C2] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 27, name: ksoftirqd/2 [ 20.783952][ C2] preempt_count: 100, expected: 0 [ 20.784135][ C2] RCU nest depth: 0, expected: 0 [ 20.784440][ C2] INFO: lockdep is turned off. [ 20.784622][ C2] Preemption disabled at: [ 20.784623][ C2] [] handle_softirqs+0xe5/0x620 [ 20.784982][ C2] CPU: 2 UID: 0 PID: 27 Comm: ksoftirqd/2 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 20.784986][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 20.784989][ C2] Call Trace: [ 20.784990][ C2] [ 20.784993][ C2] dump_stack_lvl+0xb0/0xd0 [ 20.785000][ C2] __might_resched+0x360/0x5a0 [ 20.785007][ C2] down_write+0x72/0x210 [ 20.785011][ C2] ? __pfx_down_write+0x10/0x10 [ 20.785014][ C2] ? simple_recursive_removal+0x33/0x610 [ 20.785018][ C2] ? rcu_is_watching+0x12/0xc0 [ 20.785023][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 20.785029][ C2] simple_recursive_removal+0x6a/0x610 [ 20.785032][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 20.785035][ C2] ? __pfx_remove_one+0x10/0x10 [ 20.785040][ C2] debugfs_remove+0x44/0x70 [ 20.785043][ C2] ref_tracker_dir_exit+0xcf/0x740 [ 20.785049][ C2] ? __pfx_ref_tracker_free+0x10/0x10 [ 20.785053][ C2] ? __sk_destruct+0x383/0x780 [ 20.785058][ C2] ? rcu_do_batch+0x34c/0xf20 [ 20.785061][ C2] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 20.785064][ C2] ? smpboot_thread_fn+0x30a/0x960 [ 20.785068][ C2] ? kthread+0x37e/0x600 [ 20.785071][ C2] ? ret_from_fork+0x240/0x320 [ 20.785075][ C2] ? ret_from_fork_asm+0x1a/0x30 [ 20.785080][ C2] ? migrate_enable+0x1f2/0x270 [ 20.785084][ C2] ? __pfx_migrate_enable+0x10/0x10 [ 20.785089][ C2] __put_net+0x19/0x60 [ 20.785093][ C2] __sk_destruct+0x58a/0x780 [ 20.785097][ C2] ? rcu_do_batch+0x34a/0xf20 [ 20.785100][ C2] rcu_do_batch+0x34c/0xf20 [ 20.785104][ C2] ? __pfx_rcu_do_batch+0x10/0x10 [ 20.785108][ C2] ? rcu_is_watching+0x12/0xc0 [ 20.785111][ C2] ? note_gp_changes+0x182/0x1e0 [ 20.785114][ C2] ? lockdep_hardirqs_on+0x7c/0x110 [ 20.785118][ C2] rcu_core+0x2bb/0x500 [ 20.785122][ C2] handle_softirqs+0x218/0x620 [ 20.785126][ C2] ? __pfx_run_ksoftirqd+0x10/0x10 [ 20.785130][ C2] run_ksoftirqd+0x3f/0x70 [ 20.785133][ C2] smpboot_thread_fn+0x30a/0x960 [ 20.785136][ C2] ? __pfx_smpboot_thread_fn+0x10/0x10 [ 20.785140][ C2] kthread+0x37e/0x600 [ 20.785143][ C2] ? __pfx_kthread+0x10/0x10 [ 20.785145][ C2] ? ret_from_fork+0x1b/0x320 [ 20.785148][ C2] ? __lock_release+0x5d/0x170 [ 20.785151][ C2] ? rcu_is_watching+0x12/0xc0 [ 20.785154][ C2] ? __pfx_kthread+0x10/0x10 [ 20.785157][ C2] ret_from_fork+0x240/0x320 [ 20.785160][ C2] ? __pfx_kthread+0x10/0x10 [ 20.785162][ C2] ret_from_fork_asm+0x1a/0x30 [ 20.785170][ C2] [ 27.786467][ C0] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 27.787412][ C0] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 219, name: ksft_runner.sh [ 27.788204][ C0] preempt_count: 100, expected: 0 [ 27.788936][ C0] RCU nest depth: 1, expected: 0 [ 27.789433][ C0] INFO: lockdep is turned off. [ 27.789902][ C0] Preemption disabled at: [ 27.789906][ C0] [] handle_softirqs+0xe5/0x620 [ 27.790881][ C0] CPU: 0 UID: 0 PID: 219 Comm: ksft_runner.sh Tainted: G W 6.15.0-virtme #1 PREEMPT(full) [ 27.790891][ C0] Tainted: [W]=WARN [ 27.790894][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.790897][ C0] Call Trace: [ 27.790900][ C0] [ 27.790904][ C0] dump_stack_lvl+0xb0/0xd0 [ 27.790916][ C0] __might_resched+0x360/0x5a0 [ 27.790927][ C0] down_write+0x72/0x210 [ 27.790935][ C0] ? __pfx_down_write+0x10/0x10 [ 27.790940][ C0] ? simple_recursive_removal+0x33/0x610 [ 27.790946][ C0] ? rcu_is_watching+0x12/0xc0 [ 27.790953][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 27.790963][ C0] simple_recursive_removal+0x6a/0x610 [ 27.790968][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 27.790974][ C0] ? __pfx_remove_one+0x10/0x10 [ 27.790984][ C0] debugfs_remove+0x44/0x70 [ 27.790989][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 27.790999][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 27.791004][ C0] ? __sk_destruct+0x383/0x780 [ 27.791012][ C0] ? rcu_do_batch+0x34c/0xf20 [ 27.791018][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 27.791024][ C0] ? irq_exit_rcu+0xe/0x30 [ 27.791029][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 27.791036][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.791043][ C0] ? stack_access_ok+0xe5/0x1f0 [ 27.791049][ C0] ? unwind_next_frame+0xb53/0x1e10 [ 27.791054][ C0] ? arch_stack_walk+0x8b/0xf0 [ 27.791060][ C0] ? stack_trace_save+0x93/0xd0 [ 27.791065][ C0] ? kasan_save_stack+0x24/0x50 [ 27.791072][ C0] ? kasan_record_aux_stack+0x8c/0xa0 [ 27.791078][ C0] ? __call_rcu_common.constprop.0+0xa1/0x610 [ 27.791085][ C0] ? __put_anon_vma+0xf4/0x320 [ 27.791092][ C0] ? bpf_sk_storage_free+0x9f/0x1c0 [ 27.791098][ C0] ? rcu_is_watching+0x12/0xc0 [ 27.791107][ C0] __put_net+0x19/0x60 [ 27.791113][ C0] __sk_destruct+0x58a/0x780 [ 27.791120][ C0] ? rcu_do_batch+0x34a/0xf20 [ 27.791125][ C0] rcu_do_batch+0x34c/0xf20 [ 27.791134][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 27.791141][ C0] ? trace_irq_enable.constprop.0+0xd4/0x130 [ 27.791150][ C0] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 27.791160][ C0] rcu_core+0x2bb/0x500 [ 27.791168][ C0] handle_softirqs+0x218/0x620 [ 27.791178][ C0] __irq_exit_rcu+0xfa/0x160 [ 27.791183][ C0] irq_exit_rcu+0xe/0x30 [ 27.791188][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 27.791195][ C0] [ 27.791197][ C0] [ 27.791200][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.791205][ C0] RIP: 0010:stack_access_ok+0xe5/0x1f0 [ 27.791211][ C0] Code: 89 fa 48 c1 ea 03 80 3c 02 00 0f 85 f1 00 00 00 48 8b 73 28 48 89 da 48 89 ef e8 66 6e f5 ff 85 c0 74 15 31 c0 48 83 c4 08 5b <5d> 41 5c 41 5d 41 5e 41 5f c3 cc cc cc cc 48 b8 00 00 00 00 00 fc [ 27.791216][ C0] RSP: 0018:ffffc90000a575e8 EFLAGS: 00000292 [ 27.791222][ C0] RAX: 0000000000000001 RBX: 1ffff9200014aecc RCX: ffffc90000a58000 [ 27.791225][ C0] RDX: ffffc90000a57e01 RSI: 0000000000000001 RDI: ffffc90000a57718 [ 27.791228][ C0] RBP: ffffc90000a57ea0 R08: 0000000000000000 R09: ffffc90000a57759 [ 27.791231][ C0] R10: ffffffff90a12687 R11: ffffc90000a57758 R12: 0000000000000008 [ 27.791233][ C0] R13: ffffc90000a57720 R14: ffffc90000a57728 R15: ffffc90000a50000 [ 27.791239][ C0] ? unwind_next_frame+0x147/0x1e10 [ 27.791289][ C0] unwind_next_frame+0xb53/0x1e10 [ 27.791295][ C0] ? exit_mm+0x146/0x1d0 [ 27.791305][ C0] ? __pfx_unwind_next_frame+0x10/0x10 [ 27.791314][ C0] ? exit_mm+0x146/0x1d0 [ 27.791319][ C0] ? kernel_text_address+0x17/0xe0 [ 27.791325][ C0] ? __pfx_stack_trace_consume_entry+0x10/0x10 [ 27.791331][ C0] arch_stack_walk+0x8b/0xf0 [ 27.791341][ C0] ? exit_mm+0x146/0x1d0 [ 27.791350][ C0] stack_trace_save+0x93/0xd0 [ 27.791355][ C0] ? __pfx_stack_trace_save+0x10/0x10 [ 27.791364][ C0] ? kasan_save_stack+0x34/0x50 [ 27.791369][ C0] ? kasan_save_stack+0x24/0x50 [ 27.791374][ C0] kasan_save_stack+0x24/0x50 [ 27.791379][ C0] ? kasan_save_stack+0x24/0x50 [ 27.791384][ C0] ? kasan_record_aux_stack+0x8c/0xa0 [ 27.791389][ C0] ? __call_rcu_common.constprop.0+0xa1/0x610 [ 27.791394][ C0] ? __put_anon_vma+0xf4/0x320 [ 27.791399][ C0] ? unlink_anon_vmas+0x4ef/0x920 [ 27.791403][ C0] ? free_pgtables+0x25b/0x6e0 [ 27.791410][ C0] ? exit_mmap+0x30d/0x800 [ 27.791416][ C0] ? mmput+0xb8/0x3f0 [ 27.791422][ C0] ? exit_mm+0x146/0x1d0 [ 27.791426][ C0] ? do_syscall_64+0xc1/0x380 [ 27.791431][ C0] ? entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 27.791438][ C0] ? do_raw_spin_lock+0x130/0x270 [ 27.791445][ C0] ? __pfx_do_raw_spin_lock+0x10/0x10 [ 27.791451][ C0] ? rcu_is_watching+0x12/0xc0 [ 27.791458][ C0] ? rcu_is_watching+0x12/0xc0 [ 27.791463][ C0] ? trace_irq_enable.constprop.0+0xd4/0x130 [ 27.791469][ C0] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 27.791476][ C0] ? debug_object_active_state+0x2f5/0x400 [ 27.791486][ C0] ? rcu_is_watching+0x12/0xc0 [ 27.791491][ C0] ? __virt_addr_valid+0x22e/0x450 [ 27.791497][ C0] ? rcu_is_watching+0x12/0xc0 [ 27.791503][ C0] ? trace_lock_release+0x2a/0xd0 [ 27.791509][ C0] ? lock_release+0x13/0xc0 [ 27.791515][ C0] ? __virt_addr_valid+0x23d/0x450 [ 27.791522][ C0] kasan_record_aux_stack+0x8c/0xa0 [ 27.791527][ C0] ? __pfx_slab_free_after_rcu_debug+0x10/0x10 [ 27.791535][ C0] __call_rcu_common.constprop.0+0xa1/0x610 [ 27.791543][ C0] __put_anon_vma+0xf4/0x320 [ 27.791550][ C0] unlink_anon_vmas+0x4ef/0x920 [ 27.791556][ C0] ? rcu_is_watching+0x12/0xc0 [ 27.791563][ C0] free_pgtables+0x25b/0x6e0 [ 27.791574][ C0] ? __pfx_free_pgtables+0x10/0x10 [ 27.791579][ C0] ? lock_acquire+0x10c/0x170 [ 27.791583][ C0] ? exit_mmap+0x190/0x800 [ 27.791592][ C0] ? down_write+0x152/0x210 [ 27.791597][ C0] ? __pfx_down_write+0x10/0x10 [ 27.791603][ C0] ? rcu_is_watching+0x12/0xc0 [ 27.791608][ C0] ? trace_lock_release+0x2a/0xd0 [ 27.791616][ C0] exit_mmap+0x30d/0x800 [ 27.791624][ C0] ? __pfx_exit_mmap+0x10/0x10 [ 27.791630][ C0] ? __mutex_lock+0x19c/0xcd0 [ 27.791639][ C0] ? lock_release+0x13/0xc0 [ 27.791644][ C0] ? __mutex_unlock_slowpath+0x144/0x400 [ 27.791661][ C0] mmput+0xb8/0x3f0 [ 27.791668][ C0] exit_mm+0x146/0x1d0 [ 27.791675][ C0] do_exit+0x433/0xc20 [ 27.791683][ C0] do_group_exit+0xb8/0x260 [ 27.791690][ C0] __x64_sys_exit_group+0x3e/0x50 [ 27.791696][ C0] x64_sys_call+0xf76/0x18a0 [ 27.791703][ C0] do_syscall_64+0xc1/0x380 [ 27.791709][ C0] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 27.791714][ C0] RIP: 0033:0x7f982bc7dabd [ 27.791720][ C0] Code: Unable to access opcode bytes at 0x7f982bc7da93. [ 27.791722][ C0] RSP: 002b:00007fffc3433a28 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 27.791728][ C0] RAX: ffffffffffffffda RBX: 00007f982bd5a9c0 RCX: 00007f982bc7dabd [ 27.791732][ C0] RDX: 00000000000000e7 RSI: ffffffffffffff80 RDI: 0000000000000000 [ 27.791735][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000038 [ 27.791738][ C0] R10: 00007fffc3433890 R11: 0000000000000246 R12: 00007f982bd5a9c0 [ 27.791741][ C0] R13: 00007f982bd5fee0 R14: 0000000000000001 R15: 00007f982bd5fec8 [ 27.791753][ C0]