[ 16.036741][ C0] [ 16.036952][ C0] ================================ [ 16.037154][ C0] WARNING: inconsistent lock state [ 16.037353][ C0] 6.15.0-virtme #1 Not tainted [ 16.037557][ C0] -------------------------------- [ 16.037755][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 16.038000][ C0] sh/234 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 16.038199][ C0] ffffffffbafd0bb8 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 16.038564][ C0] {SOFTIRQ-ON-W} state was registered at: [ 16.038768][ C0] __lock_acquire+0x20b/0x7e0 [ 16.038972][ C0] lock_acquire.part.0+0xb6/0x240 [ 16.039172][ C0] _raw_spin_lock+0x33/0x40 [ 16.039378][ C0] simple_pin_fs+0x24/0x150 [ 16.039577][ C0] start_creating.part.0+0x52/0x310 [ 16.039777][ C0] debugfs_create_dir+0x6a/0x570 [ 16.039978][ C0] component_debug_init+0x17/0x30 [ 16.040181][ C0] do_one_initcall+0x8f/0x1e0 [ 16.040380][ C0] do_initcalls+0x176/0x280 [ 16.040581][ C0] kernel_init_freeable+0x22d/0x300 [ 16.040783][ C0] kernel_init+0x20/0x200 [ 16.040988][ C0] ret_from_fork+0x240/0x320 [ 16.041190][ C0] ret_from_fork_asm+0x1a/0x30 [ 16.041398][ C0] irq event stamp: 3504 [ 16.041549][ C0] hardirqs last enabled at (3504): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 16.041950][ C0] hardirqs last disabled at (3503): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 16.042300][ C0] softirqs last enabled at (1992): [] fpu_clone+0x2f6/0x850 [ 16.042648][ C0] softirqs last disabled at (2321): [] __irq_exit_rcu+0xfa/0x160 [ 16.042995][ C0] [ 16.042995][ C0] other info that might help us debug this: [ 16.043290][ C0] Possible unsafe locking scenario: [ 16.043290][ C0] [ 16.043581][ C0] CPU0 [ 16.043730][ C0] ---- [ 16.043879][ C0] lock(pin_fs_lock); [ 16.044033][ C0] [ 16.044182][ C0] lock(pin_fs_lock); [ 16.044332][ C0] [ 16.044332][ C0] *** DEADLOCK *** [ 16.044332][ C0] [ 16.044623][ C0] 6 locks held by sh/234: [ 16.044778][ C0] #0: ffff88800a015788 (vm_lock){++++}-{0:0}, at: do_user_addr_fault+0x461/0xe00 [ 16.045133][ C0] #1: ffffffffbad77d20 (rcu_read_lock){....}-{1:3}, at: do_fault_around+0x1e9/0x4c0 [ 16.045485][ C0] #2: ffffffffbad77d20 (rcu_read_lock){....}-{1:3}, at: filemap_map_pages+0x169/0xf90 [ 16.045838][ C0] #3: ffffffffbad77d20 (rcu_read_lock){....}-{1:3}, at: ___pte_offset_map+0x2c/0x2a0 [ 16.046193][ C0] #4: ffff88800a60dbd0 (ptlock_ptr(ptdesc)#2){+.+.}-{3:3}, at: __pte_offset_map_lock+0xfa/0x280 [ 16.046594][ C0] #5: ffffffffbad77c00 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 16.046945][ C0] [ 16.046945][ C0] stack backtrace: [ 16.047194][ C0] CPU: 0 UID: 0 PID: 234 Comm: sh Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 16.047198][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.047200][ C0] Call Trace: [ 16.047202][ C0] [ 16.047204][ C0] dump_stack_lvl+0x82/0xd0 [ 16.047210][ C0] print_usage_bug.part.0+0x25f/0x360 [ 16.047215][ C0] mark_lock_irq+0x343/0x400 [ 16.047220][ C0] ? __pfx_unwind_next_frame+0x10/0x10 [ 16.047225][ C0] ? save_trace+0x52/0x170 [ 16.047229][ C0] mark_lock+0x174/0x2f0 [ 16.047233][ C0] mark_usage+0x46/0x150 [ 16.047237][ C0] __lock_acquire+0x20b/0x7e0 [ 16.047240][ C0] ? __lock_acquire+0x44d/0x7e0 [ 16.047245][ C0] lock_acquire.part.0+0xb6/0x240 [ 16.047248][ C0] ? simple_pin_fs+0x24/0x150 [ 16.047252][ C0] ? rcu_is_watching+0x12/0xc0 [ 16.047258][ C0] ? lock_acquire+0x10c/0x170 [ 16.047261][ C0] ? simple_pin_fs+0x24/0x150 [ 16.047265][ C0] _raw_spin_lock+0x33/0x40 [ 16.047268][ C0] ? simple_pin_fs+0x24/0x150 [ 16.047271][ C0] simple_pin_fs+0x24/0x150 [ 16.047275][ C0] debugfs_remove+0x35/0x70 [ 16.047278][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 16.047285][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 16.047289][ C0] ? __sk_destruct+0x383/0x780 [ 16.047294][ C0] ? rcu_do_batch+0x34f/0xf20 [ 16.047297][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 16.047301][ C0] ? irq_exit_rcu+0xe/0x30 [ 16.047303][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.047307][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.047310][ C0] ? rcu_lockdep_current_cpu_online+0xca/0x150 [ 16.047313][ C0] ? rcu_read_lock_held+0x2e/0x50 [ 16.047316][ C0] ? xas_next_entry+0x20b/0x310 [ 16.047319][ C0] ? next_uptodate_folio+0x25/0x340 [ 16.047321][ C0] ? filemap_map_pages+0x506/0xf90 [ 16.047324][ C0] ? do_fault_around+0x2a5/0x4c0 [ 16.047326][ C0] ? do_pte_missing+0x810/0xca0 [ 16.047330][ C0] ? handle_pte_fault+0x3e0/0x690 [ 16.047335][ C0] __put_net+0x19/0x60 [ 16.047339][ C0] __sk_destruct+0x58a/0x780 [ 16.047343][ C0] ? rcu_do_batch+0x34a/0xf20 [ 16.047346][ C0] rcu_do_batch+0x34f/0xf20 [ 16.047350][ C0] ? find_held_lock+0x2b/0x80 [ 16.047353][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 16.047357][ C0] ? mark_held_locks+0x49/0x80 [ 16.047361][ C0] ? note_gp_changes+0x182/0x1e0 [ 16.047364][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 16.047369][ C0] rcu_core+0x2bb/0x500 [ 16.047372][ C0] handle_softirqs+0x21b/0x620 [ 16.047378][ C0] __irq_exit_rcu+0xfa/0x160 [ 16.047382][ C0] irq_exit_rcu+0xe/0x30 [ 16.047384][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.047387][ C0] [ 16.047388][ C0] [ 16.047390][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.047393][ C0] RIP: 0010:rcu_lockdep_current_cpu_online+0xca/0x150 [ 16.047397][ C0] Code: 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 80 3c 02 00 75 76 48 b8 00 00 00 00 00 fc ff df 48 8b 5b 20 48 8d 7b 70 48 89 fa <48> c1 ea 03 80 3c 02 00 75 5e 48 8b 53 70 b8 01 00 00 00 48 85 d5 [ 16.047399][ C0] RSP: 0000:ffffc90000aa7938 EFLAGS: 00000246 [ 16.047403][ C0] RAX: dffffc0000000000 RBX: ffffffffbad8c140 RCX: 0000000000000001 [ 16.047405][ C0] RDX: ffffffffbad8c1b0 RSI: ffffffffba058b60 RDI: ffffffffbad8c1b0 [ 16.047406][ C0] RBP: 0000000000000001 R08: 0000000000000001 R09: fffff94000065d16 [ 16.047408][ C0] R10: ffffea000032e8b7 R11: ffffffffbad77d20 R12: dffffc0000000000 [ 16.047410][ C0] R13: ffffea0000d15540 R14: ffff8880051c4b48 R15: ffffc90000aa7af0 [ 16.047415][ C0] ? rcu_lockdep_current_cpu_online+0x3a/0x150 [ 16.047419][ C0] rcu_read_lock_held+0x2e/0x50 [ 16.047421][ C0] xas_next_entry+0x20b/0x310 [ 16.047426][ C0] next_uptodate_folio+0x25/0x340 [ 16.047429][ C0] filemap_map_pages+0x506/0xf90 [ 16.047437][ C0] ? __pfx_filemap_map_pages+0x10/0x10 [ 16.047441][ C0] ? rcu_is_watching+0x12/0xc0 [ 16.047445][ C0] ? lock_acquire+0x10c/0x170 [ 16.047448][ C0] ? do_fault_around+0x1e9/0x4c0 [ 16.047452][ C0] ? __pfx_filemap_map_pages+0x10/0x10 [ 16.047455][ C0] do_fault_around+0x2a5/0x4c0 [ 16.047459][ C0] do_pte_missing+0x810/0xca0 [ 16.047462][ C0] ? rcu_read_unlock+0x18/0x70 [ 16.047465][ C0] handle_pte_fault+0x3e0/0x690 [ 16.047470][ C0] ? __pfx_handle_pte_fault+0x10/0x10 [ 16.047473][ C0] ? reacquire_held_locks+0xd1/0x200 [ 16.047477][ C0] ? do_user_addr_fault+0x461/0xe00 [ 16.047481][ C0] __handle_mm_fault+0x3fc/0x5e0 [ 16.047485][ C0] ? __pfx___handle_mm_fault+0x10/0x10 [ 16.047488][ C0] ? __lock_release+0xa8/0x170 [ 16.047495][ C0] ? __pfx_lock_vma_under_rcu+0x10/0x10 [ 16.047499][ C0] handle_mm_fault+0x200/0x420 [ 16.047503][ C0] ? __pfx_handle_mm_fault+0x10/0x10 [ 16.047506][ C0] ? exc_page_fault+0x5d/0xc0 [ 16.047509][ C0] ? __lock_release+0x5d/0x170 [ 16.047514][ C0] do_user_addr_fault+0x576/0xe00 [ 16.047519][ C0] ? rcu_is_watching+0x12/0xc0 [ 16.047523][ C0] exc_page_fault+0x5d/0xc0 [ 16.047527][ C0] asm_exc_page_fault+0x26/0x30 [ 16.047530][ C0] RIP: 0033:0x5624ff8ce360 [ 16.047537][ C0] Code: Unable to access opcode bytes at 0x5624ff8ce336. [ 16.047539][ C0] RSP: 002b:00007ffcb1633a78 EFLAGS: 00010202 [ 16.047541][ C0] RAX: 0000000000000000 RBX: 00000000000000ec RCX: 0000000000000000 [ 16.047543][ C0] RDX: 00007ffcb1633aa4 RSI: 0000000000000001 RDI: 00000000000000ec [ 16.047544][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: 0000000000000000 [ 16.047546][ C0] R10: 0000000000000000 R11: 0000000000000008 R12: 0000000000000000 [ 16.047547][ C0] R13: 0000000000000000 R14: 00007ffcb1633b00 R15: 0000000000000001 [ 16.047552][ C0] [ 16.072096][ C0] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 16.072432][ C0] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 234, name: sh [ 16.072717][ C0] preempt_count: 102, expected: 0 [ 16.073022][ C0] RCU nest depth: 3, expected: 0 [ 16.073209][ C0] INFO: lockdep is turned off. [ 16.073393][ C0] Preemption disabled at: [ 16.073395][ C0] [<0000000000000000>] 0x0 [ 16.073851][ C0] CPU: 0 UID: 0 PID: 234 Comm: sh Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 16.073855][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.073857][ C0] Call Trace: [ 16.073859][ C0] [ 16.073860][ C0] dump_stack_lvl+0xb0/0xd0 [ 16.073869][ C0] __might_resched+0x360/0x5a0 [ 16.073874][ C0] down_write+0x72/0x210 [ 16.073879][ C0] ? __pfx_down_write+0x10/0x10 [ 16.073881][ C0] ? simple_recursive_removal+0x33/0x610 [ 16.073886][ C0] ? rcu_is_watching+0x12/0xc0 [ 16.073891][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 16.073896][ C0] simple_recursive_removal+0x6a/0x610 [ 16.073899][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 16.073901][ C0] ? __pfx_remove_one+0x10/0x10 [ 16.073907][ C0] debugfs_remove+0x44/0x70 [ 16.073910][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 16.073915][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 16.073919][ C0] ? __sk_destruct+0x383/0x780 [ 16.073924][ C0] ? rcu_do_batch+0x34f/0xf20 [ 16.073929][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 16.073932][ C0] ? irq_exit_rcu+0xe/0x30 [ 16.073936][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.073940][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.073943][ C0] ? rcu_lockdep_current_cpu_online+0xca/0x150 [ 16.073947][ C0] ? rcu_read_lock_held+0x2e/0x50 [ 16.073950][ C0] ? xas_next_entry+0x20b/0x310 [ 16.073954][ C0] ? next_uptodate_folio+0x25/0x340 [ 16.073956][ C0] ? filemap_map_pages+0x506/0xf90 [ 16.073959][ C0] ? do_fault_around+0x2a5/0x4c0 [ 16.073963][ C0] ? do_pte_missing+0x810/0xca0 [ 16.073966][ C0] ? handle_pte_fault+0x3e0/0x690 [ 16.073971][ C0] __put_net+0x19/0x60 [ 16.073976][ C0] __sk_destruct+0x58a/0x780 [ 16.073980][ C0] ? rcu_do_batch+0x34a/0xf20 [ 16.073983][ C0] rcu_do_batch+0x34f/0xf20 [ 16.073986][ C0] ? find_held_lock+0x2b/0x80 [ 16.073991][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 16.073995][ C0] ? mark_held_locks+0x49/0x80 [ 16.073998][ C0] ? note_gp_changes+0x182/0x1e0 [ 16.074002][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 16.074007][ C0] rcu_core+0x2bb/0x500 [ 16.074010][ C0] handle_softirqs+0x21b/0x620 [ 16.074017][ C0] __irq_exit_rcu+0xfa/0x160 [ 16.074020][ C0] irq_exit_rcu+0xe/0x30 [ 16.074023][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 16.074026][ C0] [ 16.074027][ C0] [ 16.074029][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.074031][ C0] RIP: 0010:rcu_lockdep_current_cpu_online+0xca/0x150 [ 16.074036][ C0] Code: 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 80 3c 02 00 75 76 48 b8 00 00 00 00 00 fc ff df 48 8b 5b 20 48 8d 7b 70 48 89 fa <48> c1 ea 03 80 3c 02 00 75 5e 48 8b 53 70 b8 01 00 00 00 48 85 d5 [ 16.074038][ C0] RSP: 0000:ffffc90000aa7938 EFLAGS: 00000246 [ 16.074042][ C0] RAX: dffffc0000000000 RBX: ffffffffbad8c140 RCX: 0000000000000001 [ 16.074044][ C0] RDX: ffffffffbad8c1b0 RSI: ffffffffba058b60 RDI: ffffffffbad8c1b0 [ 16.074045][ C0] RBP: 0000000000000001 R08: 0000000000000001 R09: fffff94000065d16 [ 16.074047][ C0] R10: ffffea000032e8b7 R11: ffffffffbad77d20 R12: dffffc0000000000 [ 16.074049][ C0] R13: ffffea0000d15540 R14: ffff8880051c4b48 R15: ffffc90000aa7af0 [ 16.074054][ C0] ? rcu_lockdep_current_cpu_online+0x3a/0x150 [ 16.074058][ C0] rcu_read_lock_held+0x2e/0x50 [ 16.074061][ C0] xas_next_entry+0x20b/0x310 [ 16.074065][ C0] next_uptodate_folio+0x25/0x340 [ 16.074069][ C0] filemap_map_pages+0x506/0xf90 [ 16.074077][ C0] ? __pfx_filemap_map_pages+0x10/0x10 [ 16.074081][ C0] ? rcu_is_watching+0x12/0xc0 [ 16.074085][ C0] ? lock_acquire+0x10c/0x170 [ 16.074088][ C0] ? do_fault_around+0x1e9/0x4c0 [ 16.074092][ C0] ? __pfx_filemap_map_pages+0x10/0x10 [ 16.074095][ C0] do_fault_around+0x2a5/0x4c0 [ 16.074099][ C0] do_pte_missing+0x810/0xca0 [ 16.074102][ C0] ? rcu_read_unlock+0x18/0x70 [ 16.074106][ C0] handle_pte_fault+0x3e0/0x690 [ 16.074110][ C0] ? __pfx_handle_pte_fault+0x10/0x10 [ 16.074113][ C0] ? reacquire_held_locks+0xd1/0x200 [ 16.074117][ C0] ? do_user_addr_fault+0x461/0xe00 [ 16.074123][ C0] __handle_mm_fault+0x3fc/0x5e0 [ 16.074127][ C0] ? __pfx___handle_mm_fault+0x10/0x10 [ 16.074130][ C0] ? __lock_release+0xa8/0x170 [ 16.074137][ C0] ? __pfx_lock_vma_under_rcu+0x10/0x10 [ 16.074141][ C0] handle_mm_fault+0x200/0x420 [ 16.074145][ C0] ? __pfx_handle_mm_fault+0x10/0x10 [ 16.074148][ C0] ? exc_page_fault+0x5d/0xc0 [ 16.074151][ C0] ? __lock_release+0x5d/0x170 [ 16.074156][ C0] do_user_addr_fault+0x576/0xe00 [ 16.074160][ C0] ? rcu_is_watching+0x12/0xc0 [ 16.074165][ C0] exc_page_fault+0x5d/0xc0 [ 16.074169][ C0] asm_exc_page_fault+0x26/0x30 [ 16.074171][ C0] RIP: 0033:0x5624ff8ce360 [ 16.074178][ C0] Code: Unable to access opcode bytes at 0x5624ff8ce336. [ 16.074180][ C0] RSP: 002b:00007ffcb1633a78 EFLAGS: 00010202 [ 16.074182][ C0] RAX: 0000000000000000 RBX: 00000000000000ec RCX: 0000000000000000 [ 16.074183][ C0] RDX: 00007ffcb1633aa4 RSI: 0000000000000001 RDI: 00000000000000ec [ 16.074185][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: 0000000000000000 [ 16.074186][ C0] R10: 0000000000000000 R11: 0000000000000008 R12: 0000000000000000 [ 16.074188][ C0] R13: 0000000000000000 R14: 00007ffcb1633b00 R15: 0000000000000001 [ 16.074193][ C0]