[ 12.563218][ C0] [ 12.563371][ C0] ================================ [ 12.563578][ C0] WARNING: inconsistent lock state [ 12.563780][ C0] 6.15.0-rc5-virtme #1 Not tainted [ 12.563977][ C0] -------------------------------- [ 12.564192][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 12.564442][ C0] systemd-udevd/123 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 12.564693][ C0] ffffffffa6dbf978 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 12.565051][ C0] {SOFTIRQ-ON-W} state was registered at: [ 12.565250][ C0] __lock_acquire+0x3eb/0x9d0 [ 12.565459][ C0] lock_acquire.part.0+0xb6/0x240 [ 12.565662][ C0] _raw_spin_lock+0x33/0x40 [ 12.565865][ C0] simple_pin_fs+0x24/0x150 [ 12.566066][ C0] start_creating.part.0+0x3f/0x2f0 [ 12.566272][ C0] debugfs_create_dir+0x6a/0x540 [ 12.566473][ C0] component_debug_init+0x17/0x30 [ 12.566677][ C0] do_one_initcall+0x8c/0x1e0 [ 12.566881][ C0] do_initcalls+0x176/0x280 [ 12.567084][ C0] kernel_init_freeable+0x22d/0x300 [ 12.567285][ C0] kernel_init+0x20/0x200 [ 12.567486][ C0] ret_from_fork+0x31/0x70 [ 12.567685][ C0] ret_from_fork_asm+0x1a/0x30 [ 12.567886][ C0] irq event stamp: 272932 [ 12.568038][ C0] hardirqs last enabled at (272932): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 12.568440][ C0] hardirqs last disabled at (272931): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 12.568833][ C0] softirqs last enabled at (271336): [] handle_softirqs+0x358/0x620 [ 12.569179][ C0] softirqs last disabled at (271565): [] __irq_exit_rcu+0xfa/0x160 [ 12.569529][ C0] [ 12.569529][ C0] other info that might help us debug this: [ 12.569821][ C0] Possible unsafe locking scenario: [ 12.569821][ C0] [ 12.570125][ C0] CPU0 [ 12.570284][ C0] ---- [ 12.570442][ C0] lock(pin_fs_lock); [ 12.570599][ C0] [ 12.570752][ C0] lock(pin_fs_lock); [ 12.570910][ C0] [ 12.570910][ C0] *** DEADLOCK *** [ 12.570910][ C0] [ 12.571221][ C0] 5 locks held by systemd-udevd/123: [ 12.571436][ C0] #0: ffffffffa6cd3350 (dup_mmap_sem){.+.+}-{0:0}, at: copy_mm+0x2ad/0x5e0 [ 12.571809][ C0] #1: ffff88800270aa20 (&mm->mmap_lock){++++}-{4:4}, at: dup_mmap+0xe3/0x1820 [ 12.572177][ C0] #2: ffff888005fe8c20 (&mm->mmap_lock/1){+.+.}-{4:4}, at: dup_mmap+0x150/0x1820 [ 12.572541][ C0] #3: ffffffffa6b745a0 (rcu_read_lock){....}-{1:3}, at: unwind_next_frame+0x147/0x1e10 [ 12.572897][ C0] #4: ffffffffa6b74480 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 12.573252][ C0] [ 12.573252][ C0] stack backtrace: [ 12.573513][ C0] CPU: 0 UID: 0 PID: 123 Comm: systemd-udevd Not tainted 6.15.0-rc5-virtme #1 PREEMPT(full) [ 12.573518][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.573520][ C0] Call Trace: [ 12.573522][ C0] [ 12.573523][ C0] dump_stack_lvl+0x82/0xd0 [ 12.573530][ C0] print_usage_bug.part.0+0x25f/0x360 [ 12.573534][ C0] mark_lock_irq+0x343/0x400 [ 12.573537][ C0] ? __pfx_unwind_next_frame+0x10/0x10 [ 12.573541][ C0] ? save_trace+0x52/0x170 [ 12.573546][ C0] mark_lock+0x174/0x2f0 [ 12.573549][ C0] mark_usage+0x46/0x150 [ 12.573552][ C0] __lock_acquire+0x3eb/0x9d0 [ 12.573556][ C0] lock_acquire.part.0+0xb6/0x240 [ 12.573559][ C0] ? simple_pin_fs+0x24/0x150 [ 12.573562][ C0] ? rcu_is_watching+0x12/0xc0 [ 12.573565][ C0] ? lock_acquire+0x10c/0x170 [ 12.573567][ C0] ? simple_pin_fs+0x24/0x150 [ 12.573571][ C0] _raw_spin_lock+0x33/0x40 [ 12.573574][ C0] ? simple_pin_fs+0x24/0x150 [ 12.573576][ C0] simple_pin_fs+0x24/0x150 [ 12.573579][ C0] debugfs_remove+0x35/0x70 [ 12.573582][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 12.573588][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 12.573591][ C0] ? __sk_destruct+0x383/0x780 [ 12.573596][ C0] ? rcu_do_batch+0x34c/0xf20 [ 12.573599][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 12.573603][ C0] ? irq_exit_rcu+0xe/0x30 [ 12.573605][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 12.573609][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.573613][ C0] ? __pfx_debug_lockdep_rcu_enabled+0x10/0x10 [ 12.573616][ C0] ? unwind_next_frame+0x160/0x1e10 [ 12.573618][ C0] ? arch_stack_walk+0x8b/0xf0 [ 12.573623][ C0] ? stack_trace_save+0x93/0xd0 [ 12.573627][ C0] ? set_track_prepare+0x3a/0x70 [ 12.573633][ C0] ? __alloc_object+0xf7/0x2e0 [ 12.573635][ C0] ? __create_object+0x22/0xb0 [ 12.573637][ C0] ? kmem_cache_alloc_noprof+0x288/0x330 [ 12.573643][ C0] __put_net+0x19/0x60 [ 12.573647][ C0] __sk_destruct+0x58a/0x780 [ 12.573651][ C0] ? rcu_do_batch+0x34a/0xf20 [ 12.573654][ C0] rcu_do_batch+0x34c/0xf20 [ 12.573658][ C0] ? find_held_lock+0x2b/0x80 [ 12.573662][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 12.573666][ C0] ? mark_held_locks+0x49/0x80 [ 12.573668][ C0] ? note_gp_changes+0x182/0x1e0 [ 12.573672][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 12.573676][ C0] rcu_core+0x2bb/0x500 [ 12.573680][ C0] handle_softirqs+0x218/0x620 [ 12.573684][ C0] __irq_exit_rcu+0xfa/0x160 [ 12.573687][ C0] irq_exit_rcu+0xe/0x30 [ 12.573690][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 12.573693][ C0] [ 12.573694][ C0] [ 12.573696][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.573698][ C0] RIP: 0010:debug_lockdep_rcu_enabled+0x0/0x40 [ 12.573702][ C0] Code: 00 00 eb b6 e8 d1 f9 ff ff eb b6 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 44 00 00 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 0f 1e fa 8b 05 26 16 a7 01 85 c0 74 20 8b 05 a0 23 a7 01 85 c0 [ 12.573705][ C0] RSP: 0018:ffffc90000507630 EFLAGS: 00000296 [ 12.573709][ C0] RAX: 0000000000000000 RBX: 1ffff920000a0ed1 RCX: 0000000000000001 [ 12.573711][ C0] RDX: 0000000000000000 RSI: ffffffffa667168b RDI: ffffffffa5e50e20 [ 12.573712][ C0] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000001 [ 12.573714][ C0] R10: 0000000000000078 R11: ffffffffa6b745a0 R12: ffffc90000507798 [ 12.573716][ C0] R13: ffffc90000507740 R14: ffff88800588c640 R15: 0000000000000cc0 [ 12.573721][ C0] unwind_next_frame+0x160/0x1e10 [ 12.573724][ C0] ? unwind_next_frame+0x147/0x1e10 [ 12.573726][ C0] ? kernel_clone+0xc1/0x510 [ 12.573729][ C0] ? kmem_cache_alloc_noprof+0x10b/0x330 [ 12.573733][ C0] ? __alloc_object+0x2f/0x2e0 [ 12.573735][ C0] ? __pfx_unwind_next_frame+0x10/0x10 [ 12.573739][ C0] ? kernel_clone+0xc1/0x510 [ 12.573741][ C0] ? kernel_text_address+0x17/0xe0 [ 12.573746][ C0] ? __pfx_stack_trace_consume_entry+0x10/0x10 [ 12.573750][ C0] arch_stack_walk+0x8b/0xf0 [ 12.573755][ C0] ? kernel_clone+0xc1/0x510 [ 12.573759][ C0] stack_trace_save+0x93/0xd0 [ 12.573762][ C0] ? __pfx_stack_trace_save+0x10/0x10 [ 12.573768][ C0] set_track_prepare+0x3a/0x70 [ 12.573771][ C0] ? kmem_cache_alloc_noprof+0x288/0x330 [ 12.573774][ C0] ? vm_area_dup+0x23/0x660 [ 12.573777][ C0] ? dup_mmap+0x6b6/0x1820 [ 12.573779][ C0] ? copy_mm+0x2b8/0x5e0 [ 12.573781][ C0] ? copy_process+0x1a53/0x5190 [ 12.573784][ C0] ? kernel_clone+0xc1/0x510 [ 12.573786][ C0] ? lockdep_init_map_type+0x4b/0x280 [ 12.573791][ C0] __alloc_object+0xf7/0x2e0 [ 12.573793][ C0] __create_object+0x22/0xb0 [ 12.573797][ C0] kmem_cache_alloc_noprof+0x288/0x330 [ 12.573800][ C0] ? rcu_is_watching+0x12/0xc0 [ 12.573804][ C0] vm_area_dup+0x23/0x660 [ 12.573807][ C0] dup_mmap+0x6b6/0x1820 [ 12.573812][ C0] ? __pfx_dup_mmap+0x10/0x10 [ 12.573817][ C0] ? rcu_is_watching+0x12/0xc0 [ 12.573822][ C0] copy_mm+0x2b8/0x5e0 [ 12.573825][ C0] copy_process+0x1a53/0x5190 [ 12.573828][ C0] ? do_fault_around+0x2d0/0x4c0 [ 12.573835][ C0] ? __pfx_copy_process+0x10/0x10 [ 12.573838][ C0] ? handle_pte_fault+0x3e0/0x690 [ 12.573842][ C0] ? __pfx_handle_pte_fault+0x10/0x10 [ 12.573845][ C0] ? reacquire_held_locks+0xd1/0x200 [ 12.573847][ C0] ? do_user_addr_fault+0x461/0xe00 [ 12.573853][ C0] kernel_clone+0xc1/0x510 [ 12.573857][ C0] ? __pfx_kernel_clone+0x10/0x10 [ 12.573864][ C0] __do_sys_clone+0xb5/0x100 [ 12.573867][ C0] ? __pfx___do_sys_clone+0x10/0x10 [ 12.573870][ C0] ? find_held_lock+0x2b/0x80 [ 12.573877][ C0] ? rcu_is_watching+0x12/0xc0 [ 12.573879][ C0] ? do_syscall_64+0x85/0x1d0 [ 12.573884][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 12.573887][ C0] do_syscall_64+0xc1/0x1d0 [ 12.573891][ C0] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 12.573894][ C0] RIP: 0033:0x7f4f80472a47 [ 12.573899][ C0] Code: 00 00 00 f3 0f 1e fa 64 48 8b 04 25 10 00 00 00 45 31 c0 31 d2 31 f6 bf 11 00 20 01 4c 8d 90 d0 02 00 00 b8 38 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 39 41 89 c0 85 c0 75 2a 64 48 8b 04 25 10 00 [ 12.573901][ C0] RSP: 002b:00007ffdb0aa30f8 EFLAGS: 00000246 ORIG_RAX: 0000000000000038 [ 12.573903][ C0] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f4f80472a47 [ 12.573905][ C0] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000001200011 [ 12.573907][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 000055711e36d990 [ 12.573908][ C0] R10: 00007f4f7f9f2e10 R11: 0000000000000246 R12: 0000000000000003 [ 12.573910][ C0] R13: 00007ffdb0aa32b0 R14: 0000000000000000 R15: 00007ffdb0aa3230 [ 12.573915][ C0] [ 12.601217][ C0] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 12.601576][ C0] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 123, name: systemd-udevd [ 12.601936][ C0] preempt_count: 100, expected: 0 [ 12.602142][ C0] RCU nest depth: 1, expected: 0 [ 12.602344][ C0] INFO: lockdep is turned off. [ 12.602554][ C0] Preemption disabled at: [ 12.602556][ C0] [] handle_softirqs+0xe5/0x620 [ 12.602985][ C0] CPU: 0 UID: 0 PID: 123 Comm: systemd-udevd Not tainted 6.15.0-rc5-virtme #1 PREEMPT(full) [ 12.602989][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.602991][ C0] Call Trace: [ 12.602993][ C0] [ 12.602994][ C0] dump_stack_lvl+0xb0/0xd0 [ 12.603001][ C0] __might_resched+0x360/0x5a0 [ 12.603008][ C0] down_write+0x72/0x210 [ 12.603011][ C0] ? __pfx_down_write+0x10/0x10 [ 12.603014][ C0] ? simple_recursive_removal+0x3a/0x860 [ 12.603019][ C0] ? rcu_is_watching+0x12/0xc0 [ 12.603023][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 12.603027][ C0] simple_recursive_removal+0x166/0x860 [ 12.603030][ C0] ? __pfx_remove_one+0x10/0x10 [ 12.603035][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 12.603039][ C0] debugfs_remove+0x44/0x70 [ 12.603042][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 12.603047][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 12.603051][ C0] ? __sk_destruct+0x383/0x780 [ 12.603055][ C0] ? rcu_do_batch+0x34c/0xf20 [ 12.603060][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 12.603063][ C0] ? irq_exit_rcu+0xe/0x30 [ 12.603065][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 12.603070][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.603073][ C0] ? __pfx_debug_lockdep_rcu_enabled+0x10/0x10 [ 12.603077][ C0] ? unwind_next_frame+0x160/0x1e10 [ 12.603080][ C0] ? arch_stack_walk+0x8b/0xf0 [ 12.603084][ C0] ? stack_trace_save+0x93/0xd0 [ 12.603088][ C0] ? set_track_prepare+0x3a/0x70 [ 12.603093][ C0] ? __alloc_object+0xf7/0x2e0 [ 12.603096][ C0] ? __create_object+0x22/0xb0 [ 12.603098][ C0] ? kmem_cache_alloc_noprof+0x288/0x330 [ 12.603104][ C0] __put_net+0x19/0x60 [ 12.603107][ C0] __sk_destruct+0x58a/0x780 [ 12.603111][ C0] ? rcu_do_batch+0x34a/0xf20 [ 12.603114][ C0] rcu_do_batch+0x34c/0xf20 [ 12.603118][ C0] ? find_held_lock+0x2b/0x80 [ 12.603123][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 12.603127][ C0] ? mark_held_locks+0x49/0x80 [ 12.603129][ C0] ? note_gp_changes+0x182/0x1e0 [ 12.603133][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 12.603138][ C0] rcu_core+0x2bb/0x500 [ 12.603141][ C0] handle_softirqs+0x218/0x620 [ 12.603146][ C0] __irq_exit_rcu+0xfa/0x160 [ 12.603148][ C0] irq_exit_rcu+0xe/0x30 [ 12.603151][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 12.603154][ C0] [ 12.603155][ C0] [ 12.603156][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.603159][ C0] RIP: 0010:debug_lockdep_rcu_enabled+0x0/0x40 [ 12.603163][ C0] Code: 00 00 eb b6 e8 d1 f9 ff ff eb b6 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 44 00 00 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 0f 1e fa 8b 05 26 16 a7 01 85 c0 74 20 8b 05 a0 23 a7 01 85 c0 [ 12.603165][ C0] RSP: 0018:ffffc90000507630 EFLAGS: 00000296 [ 12.603168][ C0] RAX: 0000000000000000 RBX: 1ffff920000a0ed1 RCX: 0000000000000001 [ 12.603170][ C0] RDX: 0000000000000000 RSI: ffffffffa667168b RDI: ffffffffa5e50e20 [ 12.603172][ C0] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000001 [ 12.603174][ C0] R10: 0000000000000078 R11: ffffffffa6b745a0 R12: ffffc90000507798 [ 12.603175][ C0] R13: ffffc90000507740 R14: ffff88800588c640 R15: 0000000000000cc0 [ 12.603181][ C0] unwind_next_frame+0x160/0x1e10 [ 12.603183][ C0] ? unwind_next_frame+0x147/0x1e10 [ 12.603186][ C0] ? kernel_clone+0xc1/0x510 [ 12.603190][ C0] ? kmem_cache_alloc_noprof+0x10b/0x330 [ 12.603193][ C0] ? __alloc_object+0x2f/0x2e0 [ 12.603195][ C0] ? __pfx_unwind_next_frame+0x10/0x10 [ 12.603199][ C0] ? kernel_clone+0xc1/0x510 [ 12.603201][ C0] ? kernel_text_address+0x17/0xe0 [ 12.603205][ C0] ? __pfx_stack_trace_consume_entry+0x10/0x10 [ 12.603209][ C0] arch_stack_walk+0x8b/0xf0 [ 12.603214][ C0] ? kernel_clone+0xc1/0x510 [ 12.603218][ C0] stack_trace_save+0x93/0xd0 [ 12.603221][ C0] ? __pfx_stack_trace_save+0x10/0x10 [ 12.603227][ C0] set_track_prepare+0x3a/0x70 [ 12.603230][ C0] ? kmem_cache_alloc_noprof+0x288/0x330 [ 12.603233][ C0] ? vm_area_dup+0x23/0x660 [ 12.603235][ C0] ? dup_mmap+0x6b6/0x1820 [ 12.603238][ C0] ? copy_mm+0x2b8/0x5e0 [ 12.603240][ C0] ? copy_process+0x1a53/0x5190 [ 12.603242][ C0] ? kernel_clone+0xc1/0x510 [ 12.603245][ C0] ? lockdep_init_map_type+0x4b/0x280 [ 12.603249][ C0] __alloc_object+0xf7/0x2e0 [ 12.603252][ C0] __create_object+0x22/0xb0 [ 12.603255][ C0] kmem_cache_alloc_noprof+0x288/0x330 [ 12.603258][ C0] ? rcu_is_watching+0x12/0xc0 [ 12.603262][ C0] vm_area_dup+0x23/0x660 [ 12.603265][ C0] dup_mmap+0x6b6/0x1820 [ 12.603270][ C0] ? __pfx_dup_mmap+0x10/0x10 [ 12.603275][ C0] ? rcu_is_watching+0x12/0xc0 [ 12.603279][ C0] copy_mm+0x2b8/0x5e0 [ 12.603283][ C0] copy_process+0x1a53/0x5190 [ 12.603286][ C0] ? do_fault_around+0x2d0/0x4c0 [ 12.603293][ C0] ? __pfx_copy_process+0x10/0x10 [ 12.603296][ C0] ? handle_pte_fault+0x3e0/0x690 [ 12.603300][ C0] ? __pfx_handle_pte_fault+0x10/0x10 [ 12.603303][ C0] ? reacquire_held_locks+0xd1/0x200 [ 12.603305][ C0] ? do_user_addr_fault+0x461/0xe00 [ 12.603310][ C0] kernel_clone+0xc1/0x510 [ 12.603314][ C0] ? __pfx_kernel_clone+0x10/0x10 [ 12.603321][ C0] __do_sys_clone+0xb5/0x100 [ 12.603324][ C0] ? __pfx___do_sys_clone+0x10/0x10 [ 12.603327][ C0] ? find_held_lock+0x2b/0x80 [ 12.603334][ C0] ? rcu_is_watching+0x12/0xc0 [ 12.603336][ C0] ? do_syscall_64+0x85/0x1d0 [ 12.603341][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 12.603344][ C0] do_syscall_64+0xc1/0x1d0 [ 12.603348][ C0] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 12.603350][ C0] RIP: 0033:0x7f4f80472a47 [ 12.603357][ C0] Code: Unable to access opcode bytes at 0x7f4f80472a1d. [ 12.603358][ C0] RSP: 002b:00007ffdb0aa30f8 EFLAGS: 00000246 ORIG_RAX: 0000000000000038 [ 12.603361][ C0] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f4f80472a47 [ 12.603363][ C0] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000001200011 [ 12.603364][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 000055711e36d990 [ 12.603366][ C0] R10: 00007f4f7f9f2e10 R11: 0000000000000246 R12: 0000000000000003 [ 12.603367][ C0] R13: 00007ffdb0aa32b0 R14: 0000000000000000 R15: 00007ffdb0aa3230 [ 12.603372][ C0] [ 13.617516][ T227] setsockopt-clos (227) used greatest stack depth: 24312 bytes left