[ 12.820501][ C1] [ 12.820657][ C1] ================================ [ 12.820876][ C1] WARNING: inconsistent lock state [ 12.821090][ C1] 6.15.0-virtme #1 Not tainted [ 12.821298][ C1] -------------------------------- [ 12.821508][ C1] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 12.821767][ C1] kworker/1:2/231 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 12.822021][ C1] ffffffffb2dc5af8 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 12.822387][ C1] {SOFTIRQ-ON-W} state was registered at: [ 12.822588][ C1] __lock_acquire+0x20b/0x7e0 [ 12.822803][ C1] lock_acquire.part.0+0xb6/0x240 [ 12.823009][ C1] _raw_spin_lock+0x33/0x40 [ 12.823217][ C1] simple_pin_fs+0x24/0x150 [ 12.823420][ C1] start_creating.part.0+0x52/0x310 [ 12.823627][ C1] debugfs_create_dir+0x6a/0x540 [ 12.823829][ C1] component_debug_init+0x17/0x30 [ 12.824035][ C1] do_one_initcall+0x8f/0x1e0 [ 12.824241][ C1] do_initcalls+0x176/0x280 [ 12.824455][ C1] kernel_init_freeable+0x22d/0x300 [ 12.824662][ C1] kernel_init+0x20/0x200 [ 12.824867][ C1] ret_from_fork+0x240/0x320 [ 12.825077][ C1] ret_from_fork_asm+0x1a/0x30 [ 12.825281][ C1] irq event stamp: 6874 [ 12.825443][ C1] hardirqs last enabled at (6874): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 12.825855][ C1] hardirqs last disabled at (6873): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 12.826211][ C1] softirqs last enabled at (2672): [] handle_softirqs+0x358/0x620 [ 12.826573][ C1] softirqs last disabled at (6423): [] __irq_exit_rcu+0xfa/0x160 [ 12.826930][ C1] [ 12.826930][ C1] other info that might help us debug this: [ 12.827239][ C1] Possible unsafe locking scenario: [ 12.827239][ C1] [ 12.827555][ C1] CPU0 [ 12.827709][ C1] ---- [ 12.827864][ C1] lock(pin_fs_lock); [ 12.828023][ C1] [ 12.828175][ C1] lock(pin_fs_lock); [ 12.828334][ C1] [ 12.828334][ C1] *** DEADLOCK *** [ 12.828334][ C1] [ 12.828637][ C1] 3 locks held by kworker/1:2/231: [ 12.828845][ C1] #0: ffff8880010a1548 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x7e5/0x1660 [ 12.829263][ C1] #1: ffffc90000b47d40 ((work_completion)(&fsvq->done_work)){+.+.}-{0:0}, at: process_one_work+0xdf6/0x1660 [ 12.829730][ C1] #2: ffffffffb2b774c0 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 12.830097][ C1] [ 12.830097][ C1] stack backtrace: [ 12.830357][ C1] CPU: 1 UID: 0 PID: 231 Comm: kworker/1:2 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 12.830362][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.830365][ C1] Workqueue: events virtio_fs_requests_done_work [ 12.830371][ C1] Call Trace: [ 12.830373][ C1] [ 12.830376][ C1] dump_stack_lvl+0x82/0xd0 [ 12.830382][ C1] print_usage_bug.part.0+0x25f/0x360 [ 12.830387][ C1] mark_lock_irq+0x343/0x400 [ 12.830392][ C1] ? __pfx_unwind_next_frame+0x10/0x10 [ 12.830398][ C1] ? save_trace+0x52/0x170 [ 12.830402][ C1] mark_lock+0x174/0x2f0 [ 12.830406][ C1] mark_usage+0x46/0x150 [ 12.830410][ C1] __lock_acquire+0x20b/0x7e0 [ 12.830413][ C1] ? __lock_acquire+0x44d/0x7e0 [ 12.830417][ C1] lock_acquire.part.0+0xb6/0x240 [ 12.830421][ C1] ? simple_pin_fs+0x24/0x150 [ 12.830424][ C1] ? rcu_is_watching+0x12/0xc0 [ 12.830431][ C1] ? lock_acquire+0x10c/0x170 [ 12.830433][ C1] ? simple_pin_fs+0x24/0x150 [ 12.830437][ C1] _raw_spin_lock+0x33/0x40 [ 12.830441][ C1] ? simple_pin_fs+0x24/0x150 [ 12.830443][ C1] simple_pin_fs+0x24/0x150 [ 12.830446][ C1] debugfs_remove+0x35/0x70 [ 12.830450][ C1] ref_tracker_dir_exit+0xcf/0x740 [ 12.830455][ C1] ? __pfx_ref_tracker_free+0x10/0x10 [ 12.830458][ C1] ? __sk_destruct+0x383/0x780 [ 12.830465][ C1] ? rcu_do_batch+0x34c/0xf20 [ 12.830468][ C1] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 12.830470][ C1] ? irq_exit_rcu+0xe/0x30 [ 12.830473][ C1] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 12.830479][ C1] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.830483][ C1] ? unwind_next_frame+0x1f/0x1e10 [ 12.830486][ C1] ? arch_stack_walk+0x8b/0xf0 [ 12.830490][ C1] ? stack_trace_save+0x93/0xd0 [ 12.830494][ C1] ? kasan_save_stack+0x24/0x50 [ 12.830500][ C1] ? kasan_save_track+0x14/0x30 [ 12.830503][ C1] ? kasan_save_free_info+0x3b/0x60 [ 12.830508][ C1] ? __kasan_slab_free+0x38/0x50 [ 12.830511][ C1] ? kfree+0x144/0x320 [ 12.830521][ C1] __put_net+0x19/0x60 [ 12.830526][ C1] __sk_destruct+0x58a/0x780 [ 12.830530][ C1] ? rcu_do_batch+0x34a/0xf20 [ 12.830532][ C1] rcu_do_batch+0x34c/0xf20 [ 12.830537][ C1] ? __pfx_rcu_do_batch+0x10/0x10 [ 12.830541][ C1] ? mark_held_locks+0x49/0x80 [ 12.830544][ C1] ? note_gp_changes+0x182/0x1e0 [ 12.830548][ C1] ? lockdep_hardirqs_on+0x7c/0x110 [ 12.830553][ C1] rcu_core+0x2bb/0x500 [ 12.830558][ C1] handle_softirqs+0x218/0x620 [ 12.830563][ C1] __irq_exit_rcu+0xfa/0x160 [ 12.830567][ C1] irq_exit_rcu+0xe/0x30 [ 12.830570][ C1] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 12.830573][ C1] [ 12.830574][ C1] [ 12.830576][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.830579][ C1] RIP: 0010:unwind_next_frame+0x1f/0x1e10 [ 12.830582][ C1] Code: 90 90 90 90 90 90 90 90 90 90 90 f3 0f 1e fa 0f 1f 44 00 00 48 ba 00 00 00 00 00 fc ff df 41 57 41 56 41 55 49 89 fd 41 54 55 <53> 48 81 ec c8 00 00 00 48 c7 44 24 48 b3 8a b5 41 48 8d 5c 24 48 [ 12.830585][ C1] RSP: 0018:ffffc90000b476a8 EFLAGS: 00000202 [ 12.830589][ C1] RAX: 0000000000000001 RBX: ffffffffaf4c8380 RCX: ffffc90000b47584 [ 12.830591][ C1] RDX: dffffc0000000000 RSI: ffffffffafe5ef23 RDI: ffffc90000b476d8 [ 12.830593][ C1] RBP: ffffc90000b47770 R08: 0000000000000000 R09: ffffc90000b47719 [ 12.830594][ C1] R10: 0000000000000000 R11: ffffc90000b47718 R12: ffffc90000b477a0 [ 12.830596][ C1] R13: ffffc90000b476d8 R14: ffff88800b8745c0 R15: ffff888001042dc0 [ 12.830599][ C1] ? __pfx_stack_trace_consume_entry+0x10/0x10 [ 12.830603][ C1] ? virtio_fs_request_complete+0x463/0xc10 [ 12.830608][ C1] arch_stack_walk+0x8b/0xf0 [ 12.830613][ C1] ? virtio_fs_request_complete+0x463/0xc10 [ 12.830616][ C1] stack_trace_save+0x93/0xd0 [ 12.830619][ C1] ? __pfx_stack_trace_save+0x10/0x10 [ 12.830623][ C1] ? validate_chain+0x166/0x4d0 [ 12.830628][ C1] kasan_save_stack+0x24/0x50 [ 12.830631][ C1] ? kasan_save_stack+0x24/0x50 [ 12.830634][ C1] ? kasan_save_track+0x14/0x30 [ 12.830637][ C1] ? kasan_save_free_info+0x3b/0x60 [ 12.830640][ C1] ? __kasan_slab_free+0x38/0x50 [ 12.830643][ C1] ? kfree+0x144/0x320 [ 12.830646][ C1] ? fuse_request_end+0x233/0x7a0 [ 12.830652][ C1] ? virtio_fs_request_complete+0x463/0xc10 [ 12.830656][ C1] ? find_held_lock+0x2b/0x80 [ 12.830660][ C1] ? mark_held_locks+0x49/0x80 [ 12.830663][ C1] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 12.830667][ C1] ? lockdep_hardirqs_on+0x7c/0x110 [ 12.830670][ C1] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 12.830674][ C1] ? __debug_check_no_obj_freed+0x252/0x520 [ 12.830678][ C1] ? find_held_lock+0x2b/0x80 [ 12.830682][ C1] ? __pfx___debug_check_no_obj_freed+0x10/0x10 [ 12.830687][ C1] ? __call_rcu_common.constprop.0+0x2b1/0x610 [ 12.830690][ C1] ? lockdep_hardirqs_on+0x7c/0x110 [ 12.830694][ C1] kasan_save_track+0x14/0x30 [ 12.830697][ C1] kasan_save_free_info+0x3b/0x60 [ 12.830700][ C1] __kasan_slab_free+0x38/0x50 [ 12.830704][ C1] kfree+0x144/0x320 [ 12.830707][ C1] ? fuse_readpages_end+0x3b5/0x600 [ 12.830711][ C1] ? fuse_request_end+0x233/0x7a0 [ 12.830714][ C1] ? __pfx_fuse_readpages_end+0x10/0x10 [ 12.830719][ C1] fuse_request_end+0x233/0x7a0 [ 12.830724][ C1] virtio_fs_request_complete+0x463/0xc10 [ 12.830729][ C1] virtio_fs_requests_done_work+0x4d5/0x890 [ 12.830734][ C1] ? __pfx_virtio_fs_requests_done_work+0x10/0x10 [ 12.830738][ C1] ? rcu_is_watching+0x12/0xc0 [ 12.830742][ C1] process_one_work+0xe40/0x1660 [ 12.830748][ C1] ? __pfx_process_one_work+0x10/0x10 [ 12.830754][ C1] ? assign_work+0x16c/0x240 [ 12.830758][ C1] worker_thread+0x591/0xcf0 [ 12.830763][ C1] ? __pfx_worker_thread+0x10/0x10 [ 12.830766][ C1] kthread+0x37e/0x600 [ 12.830770][ C1] ? __pfx_kthread+0x10/0x10 [ 12.830772][ C1] ? ret_from_fork+0x1b/0x320 [ 12.830776][ C1] ? __lock_release+0x5d/0x170 [ 12.830780][ C1] ? rcu_is_watching+0x12/0xc0 [ 12.830783][ C1] ? __pfx_kthread+0x10/0x10 [ 12.830786][ C1] ret_from_fork+0x240/0x320 [ 12.830789][ C1] ? __pfx_kthread+0x10/0x10 [ 12.830792][ C1] ret_from_fork_asm+0x1a/0x30 [ 12.830798][ C1] [ 12.856009][ C1] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 12.856348][ C1] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 231, name: kworker/1:2 [ 12.856677][ C1] preempt_count: 100, expected: 0 [ 12.856870][ C1] RCU nest depth: 0, expected: 0 [ 12.857067][ C1] INFO: lockdep is turned off. [ 12.857255][ C1] Preemption disabled at: [ 12.857257][ C1] [] handle_softirqs+0xe5/0x620 [ 12.857658][ C1] CPU: 1 UID: 0 PID: 231 Comm: kworker/1:2 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 12.857663][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.857666][ C1] Workqueue: events virtio_fs_requests_done_work [ 12.857673][ C1] Call Trace: [ 12.857675][ C1] [ 12.857677][ C1] dump_stack_lvl+0xb0/0xd0 [ 12.857685][ C1] __might_resched+0x360/0x5a0 [ 12.857694][ C1] down_write+0x72/0x210 [ 12.857698][ C1] ? __pfx_down_write+0x10/0x10 [ 12.857701][ C1] ? simple_recursive_removal+0x33/0x610 [ 12.857706][ C1] ? rcu_is_watching+0x12/0xc0 [ 12.857713][ C1] ? do_raw_spin_unlock+0x58/0x220 [ 12.857717][ C1] simple_recursive_removal+0x6a/0x610 [ 12.857720][ C1] ? do_raw_spin_unlock+0x58/0x220 [ 12.857723][ C1] ? __pfx_remove_one+0x10/0x10 [ 12.857729][ C1] debugfs_remove+0x44/0x70 [ 12.857732][ C1] ref_tracker_dir_exit+0xcf/0x740 [ 12.857738][ C1] ? __pfx_ref_tracker_free+0x10/0x10 [ 12.857740][ C1] ? __sk_destruct+0x383/0x780 [ 12.857746][ C1] ? rcu_do_batch+0x34c/0xf20 [ 12.857751][ C1] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 12.857754][ C1] ? irq_exit_rcu+0xe/0x30 [ 12.857757][ C1] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 12.857762][ C1] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.857766][ C1] ? unwind_next_frame+0x1f/0x1e10 [ 12.857771][ C1] ? arch_stack_walk+0x8b/0xf0 [ 12.857774][ C1] ? stack_trace_save+0x93/0xd0 [ 12.857778][ C1] ? kasan_save_stack+0x24/0x50 [ 12.857784][ C1] ? kasan_save_track+0x14/0x30 [ 12.857787][ C1] ? kasan_save_free_info+0x3b/0x60 [ 12.857791][ C1] ? __kasan_slab_free+0x38/0x50 [ 12.857795][ C1] ? kfree+0x144/0x320 [ 12.857803][ C1] __put_net+0x19/0x60 [ 12.857808][ C1] __sk_destruct+0x58a/0x780 [ 12.857812][ C1] ? rcu_do_batch+0x34a/0xf20 [ 12.857815][ C1] rcu_do_batch+0x34c/0xf20 [ 12.857819][ C1] ? __pfx_rcu_do_batch+0x10/0x10 [ 12.857823][ C1] ? mark_held_locks+0x49/0x80 [ 12.857829][ C1] ? note_gp_changes+0x182/0x1e0 [ 12.857832][ C1] ? lockdep_hardirqs_on+0x7c/0x110 [ 12.857837][ C1] rcu_core+0x2bb/0x500 [ 12.857843][ C1] handle_softirqs+0x218/0x620 [ 12.857849][ C1] __irq_exit_rcu+0xfa/0x160 [ 12.857852][ C1] irq_exit_rcu+0xe/0x30 [ 12.857855][ C1] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 12.857859][ C1] [ 12.857860][ C1] [ 12.857862][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.857865][ C1] RIP: 0010:unwind_next_frame+0x1f/0x1e10 [ 12.857868][ C1] Code: 90 90 90 90 90 90 90 90 90 90 90 f3 0f 1e fa 0f 1f 44 00 00 48 ba 00 00 00 00 00 fc ff df 41 57 41 56 41 55 49 89 fd 41 54 55 <53> 48 81 ec c8 00 00 00 48 c7 44 24 48 b3 8a b5 41 48 8d 5c 24 48 [ 12.857871][ C1] RSP: 0018:ffffc90000b476a8 EFLAGS: 00000202 [ 12.857874][ C1] RAX: 0000000000000001 RBX: ffffffffaf4c8380 RCX: ffffc90000b47584 [ 12.857876][ C1] RDX: dffffc0000000000 RSI: ffffffffafe5ef23 RDI: ffffc90000b476d8 [ 12.857878][ C1] RBP: ffffc90000b47770 R08: 0000000000000000 R09: ffffc90000b47719 [ 12.857880][ C1] R10: 0000000000000000 R11: ffffc90000b47718 R12: ffffc90000b477a0 [ 12.857881][ C1] R13: ffffc90000b476d8 R14: ffff88800b8745c0 R15: ffff888001042dc0 [ 12.857884][ C1] ? __pfx_stack_trace_consume_entry+0x10/0x10 [ 12.857888][ C1] ? virtio_fs_request_complete+0x463/0xc10 [ 12.857893][ C1] arch_stack_walk+0x8b/0xf0 [ 12.857898][ C1] ? virtio_fs_request_complete+0x463/0xc10 [ 12.857901][ C1] stack_trace_save+0x93/0xd0 [ 12.857904][ C1] ? __pfx_stack_trace_save+0x10/0x10 [ 12.857908][ C1] ? validate_chain+0x166/0x4d0 [ 12.857912][ C1] kasan_save_stack+0x24/0x50 [ 12.857915][ C1] ? kasan_save_stack+0x24/0x50 [ 12.857918][ C1] ? kasan_save_track+0x14/0x30 [ 12.857921][ C1] ? kasan_save_free_info+0x3b/0x60 [ 12.857924][ C1] ? __kasan_slab_free+0x38/0x50 [ 12.857927][ C1] ? kfree+0x144/0x320 [ 12.857930][ C1] ? fuse_request_end+0x233/0x7a0 [ 12.857934][ C1] ? virtio_fs_request_complete+0x463/0xc10 [ 12.857938][ C1] ? find_held_lock+0x2b/0x80 [ 12.857942][ C1] ? mark_held_locks+0x49/0x80 [ 12.857945][ C1] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 12.857949][ C1] ? lockdep_hardirqs_on+0x7c/0x110 [ 12.857953][ C1] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 12.857956][ C1] ? __debug_check_no_obj_freed+0x252/0x520 [ 12.857960][ C1] ? find_held_lock+0x2b/0x80 [ 12.857964][ C1] ? __pfx___debug_check_no_obj_freed+0x10/0x10 [ 12.857970][ C1] ? __call_rcu_common.constprop.0+0x2b1/0x610 [ 12.857973][ C1] ? lockdep_hardirqs_on+0x7c/0x110 [ 12.857977][ C1] kasan_save_track+0x14/0x30 [ 12.857980][ C1] kasan_save_free_info+0x3b/0x60 [ 12.857983][ C1] __kasan_slab_free+0x38/0x50 [ 12.857987][ C1] kfree+0x144/0x320 [ 12.857990][ C1] ? fuse_readpages_end+0x3b5/0x600 [ 12.857994][ C1] ? fuse_request_end+0x233/0x7a0 [ 12.857997][ C1] ? __pfx_fuse_readpages_end+0x10/0x10 [ 12.858004][ C1] fuse_request_end+0x233/0x7a0 [ 12.858009][ C1] virtio_fs_request_complete+0x463/0xc10 [ 12.858015][ C1] virtio_fs_requests_done_work+0x4d5/0x890 [ 12.858019][ C1] ? __pfx_virtio_fs_requests_done_work+0x10/0x10 [ 12.858024][ C1] ? rcu_is_watching+0x12/0xc0 [ 12.858028][ C1] process_one_work+0xe40/0x1660 [ 12.858035][ C1] ? __pfx_process_one_work+0x10/0x10 [ 12.858040][ C1] ? assign_work+0x16c/0x240 [ 12.858044][ C1] worker_thread+0x591/0xcf0 [ 12.858049][ C1] ? __pfx_worker_thread+0x10/0x10 [ 12.858053][ C1] kthread+0x37e/0x600 [ 12.858056][ C1] ? __pfx_kthread+0x10/0x10 [ 12.858059][ C1] ? ret_from_fork+0x1b/0x320 [ 12.858064][ C1] ? __lock_release+0x5d/0x170 [ 12.858067][ C1] ? rcu_is_watching+0x12/0xc0 [ 12.858071][ C1] ? __pfx_kthread+0x10/0x10 [ 12.858074][ C1] ret_from_fork+0x240/0x320 [ 12.858077][ C1] ? __pfx_kthread+0x10/0x10 [ 12.858080][ C1] ret_from_fork_asm+0x1a/0x30 [ 12.858088][ C1]