[ 17.834016][ C3] [ 17.834236][ C3] ================================ [ 17.834552][ C3] WARNING: inconsistent lock state [ 17.834862][ C3] 6.15.0-rc7-virtme #1 Not tainted [ 17.835182][ C3] -------------------------------- [ 17.835488][ C3] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 17.835882][ C3] ksoftirqd/3/32 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 17.836282][ C3] ffffffffa15bff78 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 17.836845][ C3] {SOFTIRQ-ON-W} state was registered at: [ 17.837158][ C3] __lock_acquire+0x3eb/0x9d0 [ 17.837489][ C3] lock_acquire.part.0+0xb6/0x240 [ 17.837807][ C3] _raw_spin_lock+0x33/0x40 [ 17.838131][ C3] simple_pin_fs+0x24/0x150 [ 17.838453][ C3] start_creating.part.0+0x3f/0x2f0 [ 17.838765][ C3] debugfs_create_dir+0x6a/0x540 [ 17.839053][ C3] component_debug_init+0x17/0x30 [ 17.839340][ C3] do_one_initcall+0x8f/0x1e0 [ 17.839546][ C3] do_initcalls+0x176/0x280 [ 17.839749][ C3] kernel_init_freeable+0x22d/0x300 [ 17.839963][ C3] kernel_init+0x20/0x200 [ 17.840158][ C3] ret_from_fork+0x31/0x70 [ 17.840375][ C3] ret_from_fork_asm+0x1a/0x30 [ 17.840580][ C3] irq event stamp: 171456 [ 17.840735][ C3] hardirqs last enabled at (171456): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 17.841153][ C3] hardirqs last disabled at (171455): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 17.841579][ C3] softirqs last enabled at (171138): [] handle_softirqs+0x358/0x620 [ 17.841943][ C3] softirqs last disabled at (171151): [] run_ksoftirqd+0x3f/0x70 [ 17.842312][ C3] [ 17.842312][ C3] other info that might help us debug this: [ 17.842654][ C3] Possible unsafe locking scenario: [ 17.842654][ C3] [ 17.842933][ C3] CPU0 [ 17.843083][ C3] ---- [ 17.843224][ C3] lock(pin_fs_lock); [ 17.843393][ C3] [ 17.843538][ C3] lock(pin_fs_lock); [ 17.843690][ C3] [ 17.843690][ C3] *** DEADLOCK *** [ 17.843690][ C3] [ 17.843979][ C3] 1 lock held by ksoftirqd/3/32: [ 17.844177][ C3] #0: ffffffffa13749c0 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 17.844539][ C3] [ 17.844539][ C3] stack backtrace: [ 17.844789][ C3] CPU: 3 UID: 0 PID: 32 Comm: ksoftirqd/3 Not tainted 6.15.0-rc7-virtme #1 PREEMPT(full) [ 17.844795][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 17.844798][ C3] Call Trace: [ 17.844800][ C3] [ 17.844803][ C3] dump_stack_lvl+0x82/0xd0 [ 17.844810][ C3] print_usage_bug.part.0+0x25f/0x360 [ 17.844818][ C3] mark_lock_irq+0x343/0x400 [ 17.844823][ C3] ? __pfx_unwind_next_frame+0x10/0x10 [ 17.844830][ C3] ? save_trace+0x52/0x170 [ 17.844835][ C3] mark_lock+0x174/0x2f0 [ 17.844839][ C3] mark_usage+0x46/0x150 [ 17.844843][ C3] __lock_acquire+0x3eb/0x9d0 [ 17.844849][ C3] lock_acquire.part.0+0xb6/0x240 [ 17.844851][ C3] ? simple_pin_fs+0x24/0x150 [ 17.844856][ C3] ? rcu_is_watching+0x12/0xc0 [ 17.844863][ C3] ? lock_acquire+0x10c/0x170 [ 17.844865][ C3] ? simple_pin_fs+0x24/0x150 [ 17.844868][ C3] _raw_spin_lock+0x33/0x40 [ 17.844872][ C3] ? simple_pin_fs+0x24/0x150 [ 17.844874][ C3] simple_pin_fs+0x24/0x150 [ 17.844877][ C3] debugfs_remove+0x35/0x70 [ 17.844882][ C3] ref_tracker_dir_exit+0xcf/0x740 [ 17.844888][ C3] ? __pfx_ref_tracker_free+0x10/0x10 [ 17.844891][ C3] ? __sk_destruct+0x383/0x780 [ 17.844898][ C3] ? rcu_do_batch+0x34f/0xf20 [ 17.844902][ C3] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 17.844904][ C3] ? smpboot_thread_fn+0x307/0x960 [ 17.844909][ C3] ? kthread+0x37b/0x600 [ 17.844914][ C3] ? ret_from_fork+0x31/0x70 [ 17.844917][ C3] ? ret_from_fork_asm+0x1a/0x30 [ 17.844921][ C3] ? migrate_enable+0x1f2/0x270 [ 17.844927][ C3] ? __pfx_migrate_enable+0x10/0x10 [ 17.844933][ C3] __put_net+0x19/0x60 [ 17.844938][ C3] __sk_destruct+0x58a/0x780 [ 17.844943][ C3] ? rcu_do_batch+0x34a/0xf20 [ 17.844946][ C3] rcu_do_batch+0x34f/0xf20 [ 17.844951][ C3] ? __pfx_rcu_do_batch+0x10/0x10 [ 17.844955][ C3] ? rcu_is_watching+0x12/0xc0 [ 17.844958][ C3] ? note_gp_changes+0x182/0x1e0 [ 17.844963][ C3] ? lockdep_hardirqs_on+0x7c/0x110 [ 17.844970][ C3] rcu_core+0x2bb/0x500 [ 17.844974][ C3] handle_softirqs+0x218/0x620 [ 17.844979][ C3] ? __pfx_run_ksoftirqd+0x10/0x10 [ 17.844982][ C3] run_ksoftirqd+0x3f/0x70 [ 17.844985][ C3] smpboot_thread_fn+0x307/0x960 [ 17.844988][ C3] ? __pfx_smpboot_thread_fn+0x10/0x10 [ 17.844992][ C3] kthread+0x37b/0x600 [ 17.844995][ C3] ? __pfx_kthread+0x10/0x10 [ 17.844998][ C3] ? ret_from_fork+0x1b/0x70 [ 17.845000][ C3] ? __lock_release+0x5d/0x170 [ 17.845003][ C3] ? rcu_is_watching+0x12/0xc0 [ 17.845007][ C3] ? __pfx_kthread+0x10/0x10 [ 17.845010][ C3] ret_from_fork+0x31/0x70 [ 17.845013][ C3] ? __pfx_kthread+0x10/0x10 [ 17.845016][ C3] ret_from_fork_asm+0x1a/0x30 [ 17.845023][ C3] [ 17.863117][ C3] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 17.863604][ C3] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 32, name: ksoftirqd/3 [ 17.864095][ C3] preempt_count: 100, expected: 0 [ 17.864383][ C3] RCU nest depth: 0, expected: 0 [ 17.864664][ C3] INFO: lockdep is turned off. [ 17.865123][ C3] Preemption disabled at: [ 17.865126][ C3] [] handle_softirqs+0xe5/0x620 [ 17.865707][ C3] CPU: 3 UID: 0 PID: 32 Comm: ksoftirqd/3 Not tainted 6.15.0-rc7-virtme #1 PREEMPT(full) [ 17.865715][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 17.865719][ C3] Call Trace: [ 17.865722][ C3] [ 17.865726][ C3] dump_stack_lvl+0xb0/0xd0 [ 17.865738][ C3] __might_resched+0x360/0x5a0 [ 17.865749][ C3] down_write+0x72/0x210 [ 17.865755][ C3] ? __pfx_down_write+0x10/0x10 [ 17.865760][ C3] ? simple_recursive_removal+0x3a/0x860 [ 17.865768][ C3] ? rcu_is_watching+0x12/0xc0 [ 17.865778][ C3] ? do_raw_spin_unlock+0x58/0x220 [ 17.865786][ C3] simple_recursive_removal+0x166/0x860 [ 17.865790][ C3] ? __pfx_remove_one+0x10/0x10 [ 17.865796][ C3] ? do_raw_spin_unlock+0x58/0x220 [ 17.865805][ C3] debugfs_remove+0x44/0x70 [ 17.865810][ C3] ref_tracker_dir_exit+0xcf/0x740 [ 17.865818][ C3] ? __pfx_ref_tracker_free+0x10/0x10 [ 17.865823][ C3] ? __sk_destruct+0x383/0x780 [ 17.865831][ C3] ? rcu_do_batch+0x34f/0xf20 [ 17.865839][ C3] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 17.865844][ C3] ? smpboot_thread_fn+0x307/0x960 [ 17.865850][ C3] ? kthread+0x37b/0x600 [ 17.865856][ C3] ? ret_from_fork+0x31/0x70 [ 17.865860][ C3] ? ret_from_fork_asm+0x1a/0x30 [ 17.865869][ C3] ? migrate_enable+0x1f2/0x270 [ 17.865877][ C3] ? __pfx_migrate_enable+0x10/0x10 [ 17.865888][ C3] __put_net+0x19/0x60 [ 17.865894][ C3] __sk_destruct+0x58a/0x780 [ 17.865900][ C3] ? rcu_do_batch+0x34a/0xf20 [ 17.865906][ C3] rcu_do_batch+0x34f/0xf20 [ 17.865916][ C3] ? __pfx_rcu_do_batch+0x10/0x10 [ 17.865923][ C3] ? rcu_is_watching+0x12/0xc0 [ 17.865928][ C3] ? note_gp_changes+0x182/0x1e0 [ 17.865933][ C3] ? lockdep_hardirqs_on+0x7c/0x110 [ 17.865944][ C3] rcu_core+0x2bb/0x500 [ 17.865951][ C3] handle_softirqs+0x218/0x620 [ 17.865958][ C3] ? __pfx_run_ksoftirqd+0x10/0x10 [ 17.865964][ C3] run_ksoftirqd+0x3f/0x70 [ 17.865967][ C3] smpboot_thread_fn+0x307/0x960 [ 17.865973][ C3] ? __pfx_smpboot_thread_fn+0x10/0x10 [ 17.865980][ C3] kthread+0x37b/0x600 [ 17.865986][ C3] ? __pfx_kthread+0x10/0x10 [ 17.865991][ C3] ? ret_from_fork+0x1b/0x70 [ 17.865994][ C3] ? __lock_release+0x5d/0x170 [ 17.866000][ C3] ? rcu_is_watching+0x12/0xc0 [ 17.866005][ C3] ? __pfx_kthread+0x10/0x10 [ 17.866012][ C3] ret_from_fork+0x31/0x70 [ 17.866016][ C3] ? __pfx_kthread+0x10/0x10 [ 17.866021][ C3] ret_from_fork_asm+0x1a/0x30 [ 17.866033][ C3] [ 20.389737][ C2] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 20.390240][ C2] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 221, name: timeout [ 20.390650][ C2] preempt_count: 100, expected: 0 [ 20.390896][ C2] RCU nest depth: 1, expected: 0 [ 20.391144][ C2] INFO: lockdep is turned off. [ 20.391370][ C2] Preemption disabled at: [ 20.391372][ C2] [] handle_softirqs+0xe5/0x620 [ 20.391842][ C2] CPU: 2 UID: 0 PID: 221 Comm: timeout Tainted: G W 6.15.0-rc7-virtme #1 PREEMPT(full) [ 20.391848][ C2] Tainted: [W]=WARN [ 20.391850][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 20.391852][ C2] Call Trace: [ 20.391854][ C2] [ 20.391856][ C2] dump_stack_lvl+0xb0/0xd0 [ 20.391866][ C2] __might_resched+0x360/0x5a0 [ 20.391873][ C2] down_write+0x72/0x210 [ 20.391879][ C2] ? __pfx_down_write+0x10/0x10 [ 20.391883][ C2] ? simple_recursive_removal+0x3a/0x860 [ 20.391889][ C2] ? rcu_is_watching+0x12/0xc0 [ 20.391898][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 20.391904][ C2] simple_recursive_removal+0x166/0x860 [ 20.391907][ C2] ? __pfx_remove_one+0x10/0x10 [ 20.391913][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 20.391917][ C2] debugfs_remove+0x44/0x70 [ 20.391920][ C2] ref_tracker_dir_exit+0xcf/0x740 [ 20.391927][ C2] ? __pfx_ref_tracker_free+0x10/0x10 [ 20.391930][ C2] ? __sk_destruct+0x383/0x780 [ 20.391939][ C2] ? rcu_do_batch+0x34f/0xf20 [ 20.391944][ C2] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 20.391947][ C2] ? irq_exit_rcu+0xe/0x30 [ 20.391949][ C2] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 20.391955][ C2] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 20.391960][ C2] ? stack_access_ok+0xe8/0x1f0 [ 20.391967][ C2] ? unwind_next_frame+0xb53/0x1e10 [ 20.391970][ C2] ? arch_stack_walk+0x8b/0xf0 [ 20.391976][ C2] ? stack_trace_save+0x93/0xd0 [ 20.391982][ C2] ? kasan_save_stack+0x24/0x50 [ 20.391989][ C2] ? kasan_record_aux_stack+0x8c/0xa0 [ 20.391994][ C2] ? __call_rcu_common.constprop.0+0xa1/0x610 [ 20.391999][ C2] ? kmem_cache_free+0x221/0x330 [ 20.392006][ C2] ? bpf_sk_storage_free+0x9f/0x1c0 [ 20.392012][ C2] ? rcu_is_watching+0x12/0xc0 [ 20.392017][ C2] __put_net+0x19/0x60 [ 20.392022][ C2] __sk_destruct+0x58a/0x780 [ 20.392026][ C2] ? rcu_do_batch+0x34a/0xf20 [ 20.392029][ C2] rcu_do_batch+0x34f/0xf20 [ 20.392032][ C2] ? sched_balance_domains+0x273/0xc80 [ 20.392037][ C2] ? __pfx_rcu_do_batch+0x10/0x10 [ 20.392041][ C2] ? rcu_is_watching+0x12/0xc0 [ 20.392044][ C2] ? trace_irq_enable.constprop.0+0xd4/0x130 [ 20.392051][ C2] rcu_core+0x2bb/0x500 [ 20.392054][ C2] handle_softirqs+0x218/0x620 [ 20.392059][ C2] __irq_exit_rcu+0xfa/0x160 [ 20.392061][ C2] irq_exit_rcu+0xe/0x30 [ 20.392064][ C2] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 20.392067][ C2] [ 20.392068][ C2] [ 20.392070][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 20.392072][ C2] RIP: 0010:stack_access_ok+0xe8/0x1f0 [ 20.392076][ C2] Code: c1 ea 03 80 3c 02 00 0f 85 f1 00 00 00 48 8b 73 28 48 89 da 48 89 ef e8 86 7b f5 ff 85 c0 74 15 31 c0 48 83 c4 08 5b 5d 41 5c <41> 5d 41 5e 41 5f e9 fd 3a 97 02 48 b8 00 00 00 00 00 fc ff df 48 [ 20.392079][ C2] RSP: 0018:ffffc90000ab7778 EFLAGS: 00000296 [ 20.392084][ C2] RAX: 0000000000000001 RBX: 1ffff92000156efc RCX: ffffc90000ab8000 [ 20.392086][ C2] RDX: ffffc90000ab7b01 RSI: 0000000000000001 RDI: ffffc90000ab7898 [ 20.392087][ C2] RBP: 0000000000000001 R08: 0000000000000000 R09: ffffc90000ab78d9 [ 20.392089][ C2] R10: ffffffff9da0c3f7 R11: ffffc90000ab78d8 R12: ffffc90000ab78f0 [ 20.392091][ C2] R13: ffffc90000ab78a0 R14: ffffc90000ab78a8 R15: ffffc90000ab0000 [ 20.392094][ C2] ? unwind_next_frame+0x147/0x1e10 [ 20.392101][ C2] unwind_next_frame+0xb53/0x1e10 [ 20.392105][ C2] ? kasan_save_stack+0x24/0x50 [ 20.392109][ C2] ? __pfx_unwind_next_frame+0x10/0x10 [ 20.392114][ C2] ? kasan_save_stack+0x24/0x50 [ 20.392117][ C2] ? kernel_text_address+0x17/0xe0 [ 20.392125][ C2] ? __pfx_stack_trace_consume_entry+0x10/0x10 [ 20.392129][ C2] arch_stack_walk+0x8b/0xf0 [ 20.392134][ C2] ? kasan_save_stack+0x24/0x50 [ 20.392138][ C2] stack_trace_save+0x93/0xd0 [ 20.392142][ C2] ? __pfx_stack_trace_save+0x10/0x10 [ 20.392146][ C2] ? stack_trace_save+0x93/0xd0 [ 20.392149][ C2] ? stack_depot_save_flags+0x15a/0x7a0 [ 20.392153][ C2] kasan_save_stack+0x24/0x50 [ 20.392156][ C2] ? kasan_save_stack+0x24/0x50 [ 20.392160][ C2] ? kasan_save_stack+0x34/0x50 [ 20.392164][ C2] ? do_raw_spin_lock+0x130/0x270 [ 20.392167][ C2] ? __pfx_do_raw_spin_lock+0x10/0x10 [ 20.392170][ C2] ? rcu_is_watching+0x12/0xc0 [ 20.392174][ C2] ? rcu_is_watching+0x12/0xc0 [ 20.392178][ C2] ? trace_irq_enable.constprop.0+0xd4/0x130 [ 20.392181][ C2] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 20.392186][ C2] ? debug_object_active_state+0x2f5/0x400 [ 20.392194][ C2] ? rcu_is_watching+0x12/0xc0 [ 20.392197][ C2] ? __virt_addr_valid+0x22e/0x450 [ 20.392202][ C2] ? rcu_is_watching+0x12/0xc0 [ 20.392205][ C2] ? trace_lock_release+0x2a/0xd0 [ 20.392211][ C2] ? lock_release+0x13/0xc0 [ 20.392214][ C2] ? __virt_addr_valid+0x23d/0x450 [ 20.392216][ C2] ? rcu_is_watching+0x12/0xc0 [ 20.392221][ C2] kasan_record_aux_stack+0x8c/0xa0 [ 20.392224][ C2] ? __pfx_free_object_rcu+0x10/0x10 [ 20.392230][ C2] __call_rcu_common.constprop.0+0xa1/0x610 [ 20.392235][ C2] kmem_cache_free+0x221/0x330 [ 20.392238][ C2] ? exit_mmap+0x3cd/0x800 [ 20.392244][ C2] exit_mmap+0x3cd/0x800 [ 20.392248][ C2] ? __pfx_exit_mmap+0x10/0x10 [ 20.392250][ C2] ? __mutex_lock+0x19c/0xcd0 [ 20.392254][ C2] ? lock_release+0x13/0xc0 [ 20.392257][ C2] ? __mutex_unlock_slowpath+0x144/0x400 [ 20.392265][ C2] mmput+0xb8/0x3e0 [ 20.392271][ C2] exit_mm+0x146/0x1d0 [ 20.392276][ C2] do_exit+0x426/0xc10 [ 20.392281][ C2] do_group_exit+0xb8/0x260 [ 20.392284][ C2] __x64_sys_exit_group+0x3e/0x50 [ 20.392288][ C2] x64_sys_call+0xf76/0x18a0 [ 20.392295][ C2] do_syscall_64+0xc1/0x1d0 [ 20.392299][ C2] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 20.392302][ C2] RIP: 0033:0x7ffa9255fabd [ 20.392305][ C2] Code: Unable to access opcode bytes at 0x7ffa9255fa93. [ 20.392307][ C2] RSP: 002b:00007fff0f3d3fe8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 20.392310][ C2] RAX: ffffffffffffffda RBX: 00007ffa9263c9c0 RCX: 00007ffa9255fabd [ 20.392312][ C2] RDX: 00000000000000e7 RSI: ffffffffffffff80 RDI: 0000000000000000 [ 20.392314][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000020 [ 20.392316][ C2] R10: 00007fff0f3d3e90 R11: 0000000000000246 R12: 00007ffa9263c9c0 [ 20.392317][ C2] R13: 00007ffa92641ee0 R14: 0000000000000002 R15: 00007ffa92641ec8 [ 20.392323][ C2]