[ 263.588009][ C1] [ 263.588217][ C1] ================================ [ 263.588414][ C1] WARNING: inconsistent lock state [ 263.588610][ C1] 6.15.0-virtme #1 Not tainted [ 263.588804][ C1] -------------------------------- [ 263.589000][ C1] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 263.589240][ C1] swapper/1/0 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 263.589481][ C1] ffffffff8cdd0bb8 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 263.589828][ C1] {SOFTIRQ-ON-W} state was registered at: [ 263.590023][ C1] __lock_acquire+0x20b/0x7e0 [ 263.590222][ C1] lock_acquire.part.0+0xb6/0x240 [ 263.590416][ C1] _raw_spin_lock+0x33/0x40 [ 263.590609][ C1] simple_pin_fs+0x24/0x150 [ 263.590797][ C1] start_creating.part.0+0x52/0x310 [ 263.590989][ C1] debugfs_create_dir+0x6a/0x570 [ 263.591181][ C1] component_debug_init+0x17/0x30 [ 263.591376][ C1] do_one_initcall+0x8f/0x1e0 [ 263.591571][ C1] do_initcalls+0x176/0x280 [ 263.591766][ C1] kernel_init_freeable+0x22d/0x300 [ 263.591961][ C1] kernel_init+0x20/0x200 [ 263.592153][ C1] ret_from_fork+0x240/0x320 [ 263.592343][ C1] ret_from_fork_asm+0x1a/0x30 [ 263.592541][ C1] irq event stamp: 10045030 [ 263.592735][ C1] hardirqs last enabled at (10045030): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 263.593115][ C1] hardirqs last disabled at (10045029): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 263.593498][ C1] softirqs last enabled at (10044890): [] handle_softirqs+0x358/0x620 [ 263.593831][ C1] softirqs last disabled at (10044933): [] __irq_exit_rcu+0xfa/0x160 [ 263.594165][ C1] [ 263.594165][ C1] other info that might help us debug this: [ 263.594443][ C1] Possible unsafe locking scenario: [ 263.594443][ C1] [ 263.594727][ C1] CPU0 [ 263.594868][ C1] ---- [ 263.595008][ C1] lock(pin_fs_lock); [ 263.595153][ C1] [ 263.595295][ C1] lock(pin_fs_lock); [ 263.595438][ C1] [ 263.595438][ C1] *** DEADLOCK *** [ 263.595438][ C1] [ 263.595716][ C1] 1 lock held by swapper/1/0: [ 263.595908][ C1] #0: ffffc900001c0d60 ((&icsk->icsk_retransmit_timer)){+.-.}-{0:0}, at: call_timer_fn+0xe7/0x220 [ 263.596297][ C1] [ 263.596297][ C1] stack backtrace: [ 263.596534][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 263.596538][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 263.596540][ C1] Call Trace: [ 263.596542][ C1] [ 263.596545][ C1] dump_stack_lvl+0x82/0xd0 [ 263.596551][ C1] print_usage_bug.part.0+0x25f/0x360 [ 263.596556][ C1] mark_lock_irq+0x343/0x400 [ 263.596561][ C1] ? add_lock_to_list+0x2c/0x130 [ 263.596566][ C1] mark_lock+0x174/0x2f0 [ 263.596571][ C1] mark_usage+0x46/0x150 [ 263.596574][ C1] __lock_acquire+0x20b/0x7e0 [ 263.596578][ C1] ? __lock_acquire+0x44d/0x7e0 [ 263.596582][ C1] lock_acquire.part.0+0xb6/0x240 [ 263.596586][ C1] ? simple_pin_fs+0x24/0x150 [ 263.596590][ C1] ? rcu_is_watching+0x12/0xc0 [ 263.596596][ C1] ? lock_acquire+0x10c/0x170 [ 263.596599][ C1] ? simple_pin_fs+0x24/0x150 [ 263.596604][ C1] _raw_spin_lock+0x33/0x40 [ 263.596607][ C1] ? simple_pin_fs+0x24/0x150 [ 263.596610][ C1] simple_pin_fs+0x24/0x150 [ 263.596614][ C1] debugfs_remove+0x35/0x70 [ 263.596618][ C1] ref_tracker_dir_exit+0xcf/0x740 [ 263.596623][ C1] ? __pfx_ref_tracker_free+0x10/0x10 [ 263.596626][ C1] ? __sk_destruct+0x383/0x780 [ 263.596632][ C1] ? call_timer_fn+0x13d/0x220 [ 263.596636][ C1] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 263.596639][ C1] ? handle_softirqs+0x21b/0x620 [ 263.596642][ C1] ? __irq_exit_rcu+0xfa/0x160 [ 263.596646][ C1] ? irq_exit_rcu+0xe/0x30 [ 263.596648][ C1] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 263.596652][ C1] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 263.596656][ C1] ? pv_native_safe_halt+0xf/0x20 [ 263.596659][ C1] ? default_idle+0x9/0x10 [ 263.596661][ C1] ? default_idle_call+0x6d/0xb0 [ 263.596663][ C1] ? cpuidle_idle_call+0x26f/0x3a0 [ 263.596667][ C1] ? do_idle+0xf6/0x160 [ 263.596670][ C1] ? cpu_startup_entry+0x54/0x60 [ 263.596675][ C1] __put_net+0x19/0x60 [ 263.596679][ C1] __sk_destruct+0x58a/0x780 [ 263.596683][ C1] ? __pfx_tcp_write_timer+0x10/0x10 [ 263.596687][ C1] call_timer_fn+0x13d/0x220 [ 263.596690][ C1] ? call_timer_fn+0xe7/0x220 [ 263.596692][ C1] ? call_timer_fn+0xe7/0x220 [ 263.596695][ C1] ? __pfx_call_timer_fn+0x10/0x10 [ 263.596700][ C1] ? __run_timers+0x532/0x810 [ 263.596703][ C1] ? __lock_release+0x5d/0x170 [ 263.596707][ C1] ? rcu_is_watching+0x12/0xc0 [ 263.596711][ C1] __run_timers+0x543/0x810 [ 263.596715][ C1] ? __pfx_tcp_write_timer+0x10/0x10 [ 263.596719][ C1] ? __pfx___run_timers+0x10/0x10 [ 263.596724][ C1] ? do_raw_spin_lock+0x130/0x270 [ 263.596727][ C1] ? __pfx_do_raw_spin_lock+0x10/0x10 [ 263.596730][ C1] ? lock_acquire+0x10c/0x170 [ 263.596733][ C1] ? run_timer_base+0xab/0x110 [ 263.596738][ C1] run_timer_base+0xb3/0x110 [ 263.596741][ C1] run_timer_softirq+0x1a/0x30 [ 263.596744][ C1] handle_softirqs+0x21b/0x620 [ 263.596750][ C1] __irq_exit_rcu+0xfa/0x160 [ 263.596753][ C1] irq_exit_rcu+0xe/0x30 [ 263.596755][ C1] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 263.596759][ C1] [ 263.596760][ C1] [ 263.596762][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 263.596764][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 263.596768][ C1] Code: f2 ce 00 c3 cc cc cc cc 0f 1f 00 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 f3 0f 1e fa 66 90 0f 00 2d 83 17 28 00 fb f4 fc 54 02 00 66 2e 0f 1f 84 00 00 00 00 00 66 90 90 90 90 90 90 [ 263.596771][ C1] RSP: 0018:ffffc90000137de8 EFLAGS: 00000246 [ 263.596774][ C1] RAX: 0000000000994603 RBX: 1ffff92000026fc1 RCX: ffffffff8ba125d9 [ 263.596776][ C1] RDX: 0000000000000000 RSI: ffffffff8c6a61cd RDI: ffffffff8be58be0 [ 263.596778][ C1] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1006c166fa [ 263.596780][ C1] R10: ffff8880360b37d3 R11: ffffffff8c811288 R12: 0000000000000000 [ 263.596781][ C1] R13: ffff888001bf0040 R14: dffffc0000000000 R15: 0000000000000000 [ 263.596785][ C1] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 263.596790][ C1] default_idle+0x9/0x10 [ 263.596792][ C1] default_idle_call+0x6d/0xb0 [ 263.596794][ C1] cpuidle_idle_call+0x26f/0x3a0 [ 263.596798][ C1] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 263.596802][ C1] ? tsc_verify_tsc_adjust+0x98/0x310 [ 263.596809][ C1] do_idle+0xf6/0x160 [ 263.596812][ C1] cpu_startup_entry+0x54/0x60 [ 263.596816][ C1] start_secondary+0x213/0x2a0 [ 263.596819][ C1] ? __pfx_start_secondary+0x10/0x10 [ 263.596824][ C1] common_startup_64+0x13e/0x148 [ 263.596831][ C1] [ 263.614405][ C1] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 263.614830][ C1] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/1 [ 263.615129][ C1] preempt_count: 101, expected: 0 [ 263.615305][ C1] RCU nest depth: 0, expected: 0 [ 263.615594][ C1] INFO: lockdep is turned off. [ 263.615772][ C1] Preemption disabled at: [ 263.615773][ C1] [<0000000000000000>] 0x0 [ 263.616080][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 263.616085][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 263.616086][ C1] Call Trace: [ 263.616088][ C1] [ 263.616090][ C1] dump_stack_lvl+0xb0/0xd0 [ 263.616097][ C1] __might_resched+0x360/0x5a0 [ 263.616102][ C1] down_write+0x72/0x210 [ 263.616106][ C1] ? __pfx_down_write+0x10/0x10 [ 263.616109][ C1] ? simple_recursive_removal+0x33/0x610 [ 263.616113][ C1] ? rcu_is_watching+0x12/0xc0 [ 263.616120][ C1] ? do_raw_spin_unlock+0x58/0x220 [ 263.616125][ C1] simple_recursive_removal+0x6a/0x610 [ 263.616128][ C1] ? do_raw_spin_unlock+0x58/0x220 [ 263.616130][ C1] ? __pfx_remove_one+0x10/0x10 [ 263.616136][ C1] debugfs_remove+0x44/0x70 [ 263.616139][ C1] ref_tracker_dir_exit+0xcf/0x740 [ 263.616144][ C1] ? __pfx_ref_tracker_free+0x10/0x10 [ 263.616147][ C1] ? __sk_destruct+0x383/0x780 [ 263.616153][ C1] ? call_timer_fn+0x13d/0x220 [ 263.616159][ C1] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 263.616162][ C1] ? handle_softirqs+0x21b/0x620 [ 263.616167][ C1] ? __irq_exit_rcu+0xfa/0x160 [ 263.616171][ C1] ? irq_exit_rcu+0xe/0x30 [ 263.616173][ C1] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 263.616177][ C1] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 263.616180][ C1] ? pv_native_safe_halt+0xf/0x20 [ 263.616184][ C1] ? default_idle+0x9/0x10 [ 263.616187][ C1] ? default_idle_call+0x6d/0xb0 [ 263.616189][ C1] ? cpuidle_idle_call+0x26f/0x3a0 [ 263.616193][ C1] ? do_idle+0xf6/0x160 [ 263.616196][ C1] ? cpu_startup_entry+0x54/0x60 [ 263.616201][ C1] __put_net+0x19/0x60 [ 263.616205][ C1] __sk_destruct+0x58a/0x780 [ 263.616209][ C1] ? __pfx_tcp_write_timer+0x10/0x10 [ 263.616213][ C1] call_timer_fn+0x13d/0x220 [ 263.616216][ C1] ? call_timer_fn+0xe7/0x220 [ 263.616219][ C1] ? call_timer_fn+0xe7/0x220 [ 263.616222][ C1] ? __pfx_call_timer_fn+0x10/0x10 [ 263.616226][ C1] ? __run_timers+0x532/0x810 [ 263.616229][ C1] ? __lock_release+0x5d/0x170 [ 263.616234][ C1] ? rcu_is_watching+0x12/0xc0 [ 263.616238][ C1] __run_timers+0x543/0x810 [ 263.616242][ C1] ? __pfx_tcp_write_timer+0x10/0x10 [ 263.616246][ C1] ? __pfx___run_timers+0x10/0x10 [ 263.616250][ C1] ? do_raw_spin_lock+0x130/0x270 [ 263.616253][ C1] ? __pfx_do_raw_spin_lock+0x10/0x10 [ 263.616256][ C1] ? lock_acquire+0x10c/0x170 [ 263.616259][ C1] ? run_timer_base+0xab/0x110 [ 263.616264][ C1] run_timer_base+0xb3/0x110 [ 263.616268][ C1] run_timer_softirq+0x1a/0x30 [ 263.616271][ C1] handle_softirqs+0x21b/0x620 [ 263.616276][ C1] __irq_exit_rcu+0xfa/0x160 [ 263.616280][ C1] irq_exit_rcu+0xe/0x30 [ 263.616282][ C1] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 263.616285][ C1] [ 263.616286][ C1] [ 263.616288][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 263.616290][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 263.616294][ C1] Code: f2 ce 00 c3 cc cc cc cc 0f 1f 00 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 f3 0f 1e fa 66 90 0f 00 2d 83 17 28 00 fb f4 fc 54 02 00 66 2e 0f 1f 84 00 00 00 00 00 66 90 90 90 90 90 90 [ 263.616296][ C1] RSP: 0018:ffffc90000137de8 EFLAGS: 00000246 [ 263.616299][ C1] RAX: 0000000000994603 RBX: 1ffff92000026fc1 RCX: ffffffff8ba125d9 [ 263.616301][ C1] RDX: 0000000000000000 RSI: ffffffff8c6a61cd RDI: ffffffff8be58be0 [ 263.616303][ C1] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1006c166fa [ 263.616305][ C1] R10: ffff8880360b37d3 R11: ffffffff8c811288 R12: 0000000000000000 [ 263.616306][ C1] R13: ffff888001bf0040 R14: dffffc0000000000 R15: 0000000000000000 [ 263.616310][ C1] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 263.616314][ C1] default_idle+0x9/0x10 [ 263.616317][ C1] default_idle_call+0x6d/0xb0 [ 263.616319][ C1] cpuidle_idle_call+0x26f/0x3a0 [ 263.616323][ C1] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 263.616327][ C1] ? tsc_verify_tsc_adjust+0x98/0x310 [ 263.616333][ C1] do_idle+0xf6/0x160 [ 263.616336][ C1] cpu_startup_entry+0x54/0x60 [ 263.616340][ C1] start_secondary+0x213/0x2a0 [ 263.616343][ C1] ? __pfx_start_secondary+0x10/0x10 [ 263.616348][ C1] common_startup_64+0x13e/0x148 [ 263.616356][ C1] [ 264.107215][ T2164] br0: port 1(veth0) entered blocking state [ 264.107498][ T2164] br0: port 1(veth0) entered disabled state [ 264.107756][ T2164] veth0: entered allmulticast mode [ 264.110211][ T2164] veth0: entered promiscuous mode [ 264.189462][ T2165] br0: port 2(veth1) entered blocking state [ 264.189799][ T2165] br0: port 2(veth1) entered disabled state [ 264.190055][ T2165] veth1: entered allmulticast mode [ 264.191913][ T2165] veth1: entered promiscuous mode [ 264.430222][ T341] br0: port 1(veth0) entered blocking state [ 264.430598][ T341] br0: port 1(veth0) entered forwarding state [ 264.512068][ T35] br0: port 2(veth1) entered blocking state [ 264.512333][ T35] br0: port 2(veth1) entered forwarding state [ 268.015277][ T70] veth1: left allmulticast mode [ 268.015502][ T70] veth1: left promiscuous mode [ 268.015810][ T70] br0: port 2(veth1) entered disabled state [ 268.017084][ T70] veth0: left allmulticast mode [ 268.017269][ T70] veth0: left promiscuous mode [ 268.017644][ T70] br0: port 1(veth0) entered disabled state