[ 17.995761][ T278] ip (278) used greatest stack depth: 24704 bytes left [ 21.812619][ T310] br0: port 1(gw_l) entered blocking state [ 21.813451][ T310] br0: port 1(gw_l) entered disabled state [ 21.813886][ T310] gw_l: entered allmulticast mode [ 21.816110][ T310] gw_l: entered promiscuous mode [ 21.818872][ T310] br0: port 1(gw_l) entered blocking state [ 21.819319][ T310] br0: port 1(gw_l) entered forwarding state [ 22.561410][ T312] br0: port 2(amtg) entered blocking state [ 22.562030][ T312] br0: port 2(amtg) entered disabled state [ 22.562486][ T312] amtg: entered allmulticast mode [ 22.564940][ T312] amtg: entered promiscuous mode [ 24.891356][ T324] br0: port 2(amtg) entered blocking state [ 24.891973][ T324] br0: port 2(amtg) entered forwarding state [ 26.249817][ T332] amtr: entered allmulticast mode [ 26.250955][ T332] relay_gw: entered allmulticast mode [ 26.251654][ T332] relay_src: entered allmulticast mode [ 1876.953521][ T68] br0: port 1(gw_l) entered disabled state [ 1877.036515][ T68] gw_l (unregistering): left allmulticast mode [ 1877.036984][ T68] gw_l (unregistering): left promiscuous mode [ 1877.037323][ T68] br0: port 1(gw_l) entered disabled state [ 1877.097256][ T68] amtg: left allmulticast mode [ 1877.097556][ T68] amtg: left promiscuous mode [ 1877.098133][ T68] br0: port 2(amtg) entered disabled state [ 1877.386461][ T68] relay_gw (unregistering): left allmulticast mode [ 1877.412999][ T68] amtr (unregistering): left allmulticast mode [ 1877.436734][ T332] relay_src: left allmulticast mode [ 1877.461632][ C1] [ 1877.461783][ C1] ================================ [ 1877.461977][ C1] WARNING: inconsistent lock state [ 1877.462162][ C1] 6.15.0-virtme #1 Not tainted [ 1877.462355][ C1] -------------------------------- [ 1877.462540][ C1] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 1877.462769][ C1] swapper/1/0 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 1877.463001][ C1] ffffffff915daf78 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 1877.463333][ C1] {SOFTIRQ-ON-W} state was registered at: [ 1877.463518][ C1] __lock_acquire+0x20b/0x7e0 [ 1877.463709][ C1] lock_acquire.part.0+0xb6/0x240 [ 1877.463900][ C1] _raw_spin_lock+0x33/0x40 [ 1877.464087][ C1] simple_pin_fs+0x24/0x150 [ 1877.464281][ C1] start_creating.part.0+0x52/0x310 [ 1877.464494][ C1] debugfs_create_dir+0x6a/0x540 [ 1877.464680][ C1] component_debug_init+0x17/0x30 [ 1877.464872][ C1] do_one_initcall+0x8f/0x1e0 [ 1877.465063][ C1] do_initcalls+0x176/0x280 [ 1877.465256][ C1] kernel_init_freeable+0x22d/0x300 [ 1877.465448][ C1] kernel_init+0x20/0x200 [ 1877.465635][ C1] ret_from_fork+0x240/0x320 [ 1877.465824][ C1] ret_from_fork_asm+0x1a/0x30 [ 1877.466011][ C1] irq event stamp: 20555048 [ 1877.466198][ C1] hardirqs last enabled at (20555048): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 1877.466572][ C1] hardirqs last disabled at (20555047): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 1877.467035][ C1] softirqs last enabled at (20554132): [] handle_softirqs+0x358/0x620 [ 1877.467365][ C1] softirqs last disabled at (20554139): [] __irq_exit_rcu+0xfa/0x160 [ 1877.467690][ C1] [ 1877.467690][ C1] other info that might help us debug this: [ 1877.467969][ C1] Possible unsafe locking scenario: [ 1877.467969][ C1] [ 1877.468243][ C1] CPU0 [ 1877.468388][ C1] ---- [ 1877.468526][ C1] lock(pin_fs_lock); [ 1877.468668][ C1] [ 1877.468807][ C1] lock(pin_fs_lock); [ 1877.468948][ C1] [ 1877.468948][ C1] *** DEADLOCK *** [ 1877.468948][ C1] [ 1877.469221][ C1] 1 lock held by swapper/1/0: [ 1877.469407][ C1] #0: ffffffff9138b540 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 1877.469743][ C1] [ 1877.469743][ C1] stack backtrace: [ 1877.469974][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 1877.469979][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 1877.469981][ C1] Call Trace: [ 1877.469984][ C1] [ 1877.469986][ C1] dump_stack_lvl+0x82/0xd0 [ 1877.469993][ C1] print_usage_bug.part.0+0x25f/0x360 [ 1877.469998][ C1] mark_lock_irq+0x343/0x400 [ 1877.470002][ C1] ? __pfx_unwind_next_frame+0x10/0x10 [ 1877.470007][ C1] ? save_trace+0x52/0x170 [ 1877.470012][ C1] mark_lock+0x174/0x2f0 [ 1877.470016][ C1] mark_usage+0x46/0x150 [ 1877.470019][ C1] __lock_acquire+0x20b/0x7e0 [ 1877.470022][ C1] ? __lock_acquire+0x44d/0x7e0 [ 1877.470026][ C1] lock_acquire.part.0+0xb6/0x240 [ 1877.470028][ C1] ? simple_pin_fs+0x24/0x150 [ 1877.470032][ C1] ? rcu_is_watching+0x12/0xc0 [ 1877.470036][ C1] ? lock_acquire+0x10c/0x170 [ 1877.470038][ C1] ? simple_pin_fs+0x24/0x150 [ 1877.470042][ C1] _raw_spin_lock+0x33/0x40 [ 1877.470045][ C1] ? simple_pin_fs+0x24/0x150 [ 1877.470047][ C1] simple_pin_fs+0x24/0x150 [ 1877.470050][ C1] debugfs_remove+0x35/0x70 [ 1877.470054][ C1] ref_tracker_dir_exit+0xcf/0x740 [ 1877.470059][ C1] ? __pfx_ref_tracker_free+0x10/0x10 [ 1877.470061][ C1] ? __sk_destruct+0x383/0x780 [ 1877.470065][ C1] ? rcu_do_batch+0x34f/0xf20 [ 1877.470069][ C1] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 1877.470072][ C1] ? irq_exit_rcu+0xe/0x30 [ 1877.470075][ C1] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 1877.470078][ C1] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 1877.470082][ C1] ? pv_native_safe_halt+0xf/0x20 [ 1877.470085][ C1] ? default_idle+0x9/0x10 [ 1877.470087][ C1] ? default_idle_call+0x6d/0xb0 [ 1877.470089][ C1] ? cpuidle_idle_call+0x26f/0x3a0 [ 1877.470093][ C1] ? do_idle+0xf6/0x160 [ 1877.470095][ C1] ? cpu_startup_entry+0x54/0x60 [ 1877.470097][ C1] ? start_secondary+0x213/0x2a0 [ 1877.470102][ C1] ? common_startup_64+0x13e/0x148 [ 1877.470109][ C1] __put_net+0x19/0x60 [ 1877.470114][ C1] __sk_destruct+0x58a/0x780 [ 1877.470118][ C1] ? rcu_do_batch+0x34a/0xf20 [ 1877.470121][ C1] rcu_do_batch+0x34f/0xf20 [ 1877.470126][ C1] ? find_held_lock+0x2b/0x80 [ 1877.470130][ C1] ? __pfx_rcu_do_batch+0x10/0x10 [ 1877.470135][ C1] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 1877.470138][ C1] ? lockdep_hardirqs_on+0x7c/0x110 [ 1877.470142][ C1] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 1877.470147][ C1] rcu_core+0x2bb/0x500 [ 1877.470151][ C1] handle_softirqs+0x21b/0x620 [ 1877.470156][ C1] __irq_exit_rcu+0xfa/0x160 [ 1877.470159][ C1] irq_exit_rcu+0xe/0x30 [ 1877.470162][ C1] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 1877.470166][ C1] [ 1877.470166][ C1] [ 1877.470168][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 1877.470171][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 1877.470175][ C1] Code: 92 d0 00 e9 03 55 02 00 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 63 97 22 00 fb f4 cc cc cc cc 66 2e 0f 1f 84 00 00 00 00 00 66 90 90 90 90 90 90 [ 1877.470178][ C1] RSP: 0018:ffffc90000137de8 EFLAGS: 00000246 [ 1877.470181][ C1] RAX: 000000000139a199 RBX: 1ffff92000026fc1 RCX: ffffffff8fc6a5f9 [ 1877.470183][ C1] RDX: 0000000000000000 RSI: ffffffff90915cea RDI: ffffffff900568c0 [ 1877.470185][ C1] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed100d9d66fa [ 1877.470186][ C1] R10: ffff88806ceb37d3 R11: ffffffff91011288 R12: 0000000000000000 [ 1877.470188][ C1] R13: ffff888001b60040 R14: dffffc0000000000 R15: 0000000000000000 [ 1877.470192][ C1] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 1877.470198][ C1] default_idle+0x9/0x10 [ 1877.470200][ C1] default_idle_call+0x6d/0xb0 [ 1877.470202][ C1] cpuidle_idle_call+0x26f/0x3a0 [ 1877.470205][ C1] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 1877.470209][ C1] ? tsc_verify_tsc_adjust+0x98/0x310 [ 1877.470213][ C1] do_idle+0xf6/0x160 [ 1877.470216][ C1] cpu_startup_entry+0x54/0x60 [ 1877.470219][ C1] start_secondary+0x213/0x2a0 [ 1877.470223][ C1] ? __pfx_start_secondary+0x10/0x10 [ 1877.470228][ C1] common_startup_64+0x13e/0x148 [ 1877.470235][ C1] [ 1877.486243][ C1] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 1877.486553][ C1] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/1 [ 1877.486974][ C1] preempt_count: 101, expected: 0 [ 1877.487149][ C1] RCU nest depth: 0, expected: 0 [ 1877.487325][ C1] INFO: lockdep is turned off. [ 1877.487610][ C1] Preemption disabled at: [ 1877.487612][ C1] [<0000000000000000>] 0x0 [ 1877.487928][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 1877.487933][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 1877.487935][ C1] Call Trace: [ 1877.487937][ C1] [ 1877.487939][ C1] dump_stack_lvl+0xb0/0xd0 [ 1877.487947][ C1] __might_resched+0x360/0x5a0 [ 1877.487954][ C1] down_write+0x72/0x210 [ 1877.487958][ C1] ? __pfx_down_write+0x10/0x10 [ 1877.487961][ C1] ? simple_recursive_removal+0x33/0x610 [ 1877.487966][ C1] ? rcu_is_watching+0x12/0xc0 [ 1877.487971][ C1] ? do_raw_spin_unlock+0x58/0x220 [ 1877.487976][ C1] simple_recursive_removal+0x6a/0x610 [ 1877.487979][ C1] ? do_raw_spin_unlock+0x58/0x220 [ 1877.487983][ C1] ? __pfx_remove_one+0x10/0x10 [ 1877.487990][ C1] debugfs_remove+0x44/0x70 [ 1877.487993][ C1] ref_tracker_dir_exit+0xcf/0x740 [ 1877.487999][ C1] ? __pfx_ref_tracker_free+0x10/0x10 [ 1877.488002][ C1] ? __sk_destruct+0x383/0x780 [ 1877.488005][ C1] ? rcu_do_batch+0x34f/0xf20 [ 1877.488010][ C1] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 1877.488013][ C1] ? irq_exit_rcu+0xe/0x30 [ 1877.488017][ C1] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 1877.488021][ C1] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 1877.488025][ C1] ? pv_native_safe_halt+0xf/0x20 [ 1877.488028][ C1] ? default_idle+0x9/0x10 [ 1877.488031][ C1] ? default_idle_call+0x6d/0xb0 [ 1877.488033][ C1] ? cpuidle_idle_call+0x26f/0x3a0 [ 1877.488036][ C1] ? do_idle+0xf6/0x160 [ 1877.488038][ C1] ? cpu_startup_entry+0x54/0x60 [ 1877.488041][ C1] ? start_secondary+0x213/0x2a0 [ 1877.488045][ C1] ? common_startup_64+0x13e/0x148 [ 1877.488052][ C1] __put_net+0x19/0x60 [ 1877.488057][ C1] __sk_destruct+0x58a/0x780 [ 1877.488060][ C1] ? rcu_do_batch+0x34a/0xf20 [ 1877.488064][ C1] rcu_do_batch+0x34f/0xf20 [ 1877.488068][ C1] ? find_held_lock+0x2b/0x80 [ 1877.488073][ C1] ? __pfx_rcu_do_batch+0x10/0x10 [ 1877.488078][ C1] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 1877.488082][ C1] ? lockdep_hardirqs_on+0x7c/0x110 [ 1877.488085][ C1] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 1877.488090][ C1] rcu_core+0x2bb/0x500 [ 1877.488094][ C1] handle_softirqs+0x21b/0x620 [ 1877.488100][ C1] __irq_exit_rcu+0xfa/0x160 [ 1877.488102][ C1] irq_exit_rcu+0xe/0x30 [ 1877.488105][ C1] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 1877.488109][ C1] [ 1877.488109][ C1] [ 1877.488111][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 1877.488114][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 1877.488117][ C1] Code: 92 d0 00 e9 03 55 02 00 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 63 97 22 00 fb f4 cc cc cc cc 66 2e 0f 1f 84 00 00 00 00 00 66 90 90 90 90 90 90 [ 1877.488120][ C1] RSP: 0018:ffffc90000137de8 EFLAGS: 00000246 [ 1877.488124][ C1] RAX: 000000000139a199 RBX: 1ffff92000026fc1 RCX: ffffffff8fc6a5f9 [ 1877.488126][ C1] RDX: 0000000000000000 RSI: ffffffff90915cea RDI: ffffffff900568c0 [ 1877.488128][ C1] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed100d9d66fa [ 1877.488129][ C1] R10: ffff88806ceb37d3 R11: ffffffff91011288 R12: 0000000000000000 [ 1877.488131][ C1] R13: ffff888001b60040 R14: dffffc0000000000 R15: 0000000000000000 [ 1877.488135][ C1] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 1877.488140][ C1] default_idle+0x9/0x10 [ 1877.488142][ C1] default_idle_call+0x6d/0xb0 [ 1877.488144][ C1] cpuidle_idle_call+0x26f/0x3a0 [ 1877.488148][ C1] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 1877.488151][ C1] ? tsc_verify_tsc_adjust+0x98/0x310 [ 1877.488155][ C1] do_idle+0xf6/0x160 [ 1877.488158][ C1] cpu_startup_entry+0x54/0x60 [ 1877.488161][ C1] start_secondary+0x213/0x2a0 [ 1877.488164][ C1] ? __pfx_start_secondary+0x10/0x10 [ 1877.488170][ C1] common_startup_64+0x13e/0x148 [ 1877.488177][ C1]