[ 19.059639][ T256] ip (256) used greatest stack depth: 24080 bytes left [ 25.567468][ T298] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. [ 26.447858][ T308] br0: port 1(c1) entered blocking state [ 26.448344][ T308] br0: port 1(c1) entered disabled state [ 26.448855][ T308] c1: entered allmulticast mode [ 26.452957][ T308] c1: entered promiscuous mode [ 27.169111][ T313] br0: port 2(c2) entered blocking state [ 27.169536][ T313] br0: port 2(c2) entered disabled state [ 27.169883][ T313] c2: entered allmulticast mode [ 27.174023][ T313] c2: entered promiscuous mode [ 27.864366][ T318] br0: port 3(c3) entered blocking state [ 27.864791][ T318] br0: port 3(c3) entered disabled state [ 27.865125][ T318] c3: entered allmulticast mode [ 27.869553][ T318] c3: entered promiscuous mode [ 28.584271][ T323] br0: port 4(sender) entered blocking state [ 28.584790][ T323] br0: port 4(sender) entered disabled state [ 28.585213][ T323] sender: entered allmulticast mode [ 28.588830][ T323] sender: entered promiscuous mode [ 28.875060][ T325] br0: port 4(sender) entered blocking state [ 28.875679][ T325] br0: port 4(sender) entered forwarding state [ 28.876654][ T325] br0: port 3(c3) entered blocking state [ 28.877026][ T325] br0: port 3(c3) entered forwarding state [ 28.877695][ T325] br0: port 2(c2) entered blocking state [ 28.878023][ T325] br0: port 2(c2) entered forwarding state [ 28.878669][ T325] br0: port 1(c1) entered blocking state [ 28.879015][ T325] br0: port 1(c1) entered forwarding state [ 29.066792][ T326] Bridge firewalling registered [ 42.997316][ T68] br0: port 1(c1) entered disabled state [ 43.098285][ T68] c1 (unregistering): left allmulticast mode [ 43.099094][ T68] c1 (unregistering): left promiscuous mode [ 43.099807][ T68] br0: port 1(c1) entered disabled state [ 43.550051][ T68] br0: port 2(c2) entered disabled state [ 43.674607][ T68] c2 (unregistering): left allmulticast mode [ 43.675051][ T68] c2 (unregistering): left promiscuous mode [ 43.675419][ T68] br0: port 2(c2) entered disabled state [ 44.090457][ T68] br0: port 3(c3) entered disabled state [ 44.223388][ T68] c3 (unregistering): left allmulticast mode [ 44.224046][ T68] c3 (unregistering): left promiscuous mode [ 44.224421][ T68] br0: port 3(c3) entered disabled state [ 44.632414][ T68] br0: port 4(sender) entered disabled state [ 44.769401][ T68] sender (unregistering): left allmulticast mode [ 44.769865][ T68] sender (unregistering): left promiscuous mode [ 44.770237][ T68] br0: port 4(sender) entered disabled state [ 44.893538][ C0] [ 44.893671][ C0] ================================ [ 44.893854][ C0] WARNING: inconsistent lock state [ 44.894030][ C0] 6.15.0-rc7-virtme #1 Not tainted [ 44.894207][ C0] -------------------------------- [ 44.894380][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 44.894596][ C0] ksoftirqd/0/14 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 44.894844][ C0] ffffffff8adcb078 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 44.895161][ C0] {SOFTIRQ-ON-W} state was registered at: [ 44.895334][ C0] __lock_acquire+0x3eb/0x9d0 [ 44.895520][ C0] lock_acquire.part.0+0xb6/0x240 [ 44.895712][ C0] _raw_spin_lock+0x33/0x40 [ 44.895891][ C0] simple_pin_fs+0x24/0x150 [ 44.896070][ C0] start_creating.part.0+0x3f/0x2f0 [ 44.896251][ C0] debugfs_create_dir+0x6a/0x570 [ 44.896430][ C0] component_debug_init+0x17/0x30 [ 44.896610][ C0] do_one_initcall+0x8f/0x1e0 [ 44.896787][ C0] do_initcalls+0x176/0x280 [ 44.896961][ C0] kernel_init_freeable+0x22d/0x300 [ 44.897141][ C0] kernel_init+0x20/0x200 [ 44.897317][ C0] ret_from_fork+0x31/0x70 [ 44.897493][ C0] ret_from_fork_asm+0x1a/0x30 [ 44.897666][ C0] irq event stamp: 429086 [ 44.897801][ C0] hardirqs last enabled at (429086): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 44.898150][ C0] hardirqs last disabled at (429085): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 44.898495][ C0] softirqs last enabled at (427856): [] handle_softirqs+0x358/0x620 [ 44.898806][ C0] softirqs last disabled at (427861): [] run_ksoftirqd+0x3f/0x70 [ 44.899128][ C0] [ 44.899128][ C0] other info that might help us debug this: [ 44.899386][ C0] Possible unsafe locking scenario: [ 44.899386][ C0] [ 44.899642][ C0] CPU0 [ 44.899770][ C0] ---- [ 44.899899][ C0] lock(pin_fs_lock); [ 44.900027][ C0] [ 44.900265][ C0] lock(pin_fs_lock); [ 44.900402][ C0] [ 44.900402][ C0] *** DEADLOCK *** [ 44.900402][ C0] [ 44.900661][ C0] 1 lock held by ksoftirqd/0/14: [ 44.900837][ C0] #0: ffffffff8ab75140 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 44.901149][ C0] [ 44.901149][ C0] stack backtrace: [ 44.901366][ C0] CPU: 0 UID: 0 PID: 14 Comm: ksoftirqd/0 Not tainted 6.15.0-rc7-virtme #1 PREEMPT(full) [ 44.901371][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 44.901373][ C0] Call Trace: [ 44.901375][ C0] [ 44.901377][ C0] dump_stack_lvl+0x82/0xd0 [ 44.901382][ C0] print_usage_bug.part.0+0x25f/0x360 [ 44.901387][ C0] mark_lock_irq+0x343/0x400 [ 44.901392][ C0] ? __pfx_unwind_next_frame+0x10/0x10 [ 44.901397][ C0] ? save_trace+0x52/0x170 [ 44.901401][ C0] mark_lock+0x174/0x2f0 [ 44.901404][ C0] mark_usage+0x46/0x150 [ 44.901407][ C0] __lock_acquire+0x3eb/0x9d0 [ 44.901412][ C0] lock_acquire.part.0+0xb6/0x240 [ 44.901415][ C0] ? simple_pin_fs+0x24/0x150 [ 44.901419][ C0] ? rcu_is_watching+0x12/0xc0 [ 44.901423][ C0] ? lock_acquire+0x10c/0x170 [ 44.901426][ C0] ? simple_pin_fs+0x24/0x150 [ 44.901429][ C0] _raw_spin_lock+0x33/0x40 [ 44.901432][ C0] ? simple_pin_fs+0x24/0x150 [ 44.901435][ C0] simple_pin_fs+0x24/0x150 [ 44.901438][ C0] debugfs_remove+0x35/0x70 [ 44.901441][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 44.901447][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 44.901450][ C0] ? __sk_destruct+0x383/0x780 [ 44.901455][ C0] ? rcu_do_batch+0x34c/0xf20 [ 44.901458][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 44.901461][ C0] ? smpboot_thread_fn+0x30a/0x960 [ 44.901466][ C0] ? kthread+0x37e/0x600 [ 44.901469][ C0] ? ret_from_fork+0x31/0x70 [ 44.901472][ C0] ? ret_from_fork_asm+0x1a/0x30 [ 44.901474][ C0] ? migrate_enable+0x1f2/0x270 [ 44.901478][ C0] ? __pfx_migrate_enable+0x10/0x10 [ 44.901484][ C0] __put_net+0x19/0x60 [ 44.901487][ C0] __sk_destruct+0x58a/0x780 [ 44.901491][ C0] ? rcu_do_batch+0x34a/0xf20 [ 44.901494][ C0] rcu_do_batch+0x34c/0xf20 [ 44.901498][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 44.901502][ C0] ? rcu_is_watching+0x12/0xc0 [ 44.901505][ C0] ? note_gp_changes+0x182/0x1e0 [ 44.901508][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 44.901514][ C0] rcu_core+0x2bb/0x500 [ 44.901519][ C0] handle_softirqs+0x21b/0x620 [ 44.901523][ C0] ? __pfx_run_ksoftirqd+0x10/0x10 [ 44.901527][ C0] run_ksoftirqd+0x3f/0x70 [ 44.901530][ C0] smpboot_thread_fn+0x30a/0x960 [ 44.901534][ C0] ? __pfx_smpboot_thread_fn+0x10/0x10 [ 44.901538][ C0] kthread+0x37e/0x600 [ 44.901541][ C0] ? __pfx_kthread+0x10/0x10 [ 44.901543][ C0] ? ret_from_fork+0x1b/0x70 [ 44.901545][ C0] ? __lock_release+0x5d/0x170 [ 44.901549][ C0] ? rcu_is_watching+0x12/0xc0 [ 44.901552][ C0] ? __pfx_kthread+0x10/0x10 [ 44.901555][ C0] ret_from_fork+0x31/0x70 [ 44.901558][ C0] ? __pfx_kthread+0x10/0x10 [ 44.901560][ C0] ret_from_fork_asm+0x1a/0x30 [ 44.901566][ C0] [ 44.912599][ C0] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 44.912890][ C0] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 14, name: ksoftirqd/0 [ 44.913266][ C0] preempt_count: 100, expected: 0 [ 44.913620][ C0] RCU nest depth: 0, expected: 0 [ 44.913796][ C0] INFO: lockdep is turned off. [ 44.913963][ C0] Preemption disabled at: [ 44.913964][ C0] [] handle_softirqs+0xe5/0x620 [ 44.914384][ C0] CPU: 0 UID: 0 PID: 14 Comm: ksoftirqd/0 Not tainted 6.15.0-rc7-virtme #1 PREEMPT(full) [ 44.914389][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 44.914390][ C0] Call Trace: [ 44.914392][ C0] [ 44.914394][ C0] dump_stack_lvl+0xb0/0xd0 [ 44.914400][ C0] __might_resched+0x360/0x5a0 [ 44.914406][ C0] down_write+0x72/0x210 [ 44.914410][ C0] ? __pfx_down_write+0x10/0x10 [ 44.914413][ C0] ? simple_recursive_removal+0x3a/0x860 [ 44.914417][ C0] ? rcu_is_watching+0x12/0xc0 [ 44.914421][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 44.914427][ C0] simple_recursive_removal+0x166/0x860 [ 44.914430][ C0] ? __pfx_remove_one+0x10/0x10 [ 44.914434][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 44.914439][ C0] debugfs_remove+0x44/0x70 [ 44.914442][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 44.914448][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 44.914451][ C0] ? __sk_destruct+0x383/0x780 [ 44.914456][ C0] ? rcu_do_batch+0x34c/0xf20 [ 44.914459][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 44.914462][ C0] ? smpboot_thread_fn+0x30a/0x960 [ 44.914467][ C0] ? kthread+0x37e/0x600 [ 44.914470][ C0] ? ret_from_fork+0x31/0x70 [ 44.914474][ C0] ? ret_from_fork_asm+0x1a/0x30 [ 44.914478][ C0] ? migrate_enable+0x1f2/0x270 [ 44.914482][ C0] ? __pfx_migrate_enable+0x10/0x10 [ 44.914487][ C0] __put_net+0x19/0x60 [ 44.914490][ C0] __sk_destruct+0x58a/0x780 [ 44.914494][ C0] ? rcu_do_batch+0x34a/0xf20 [ 44.914497][ C0] rcu_do_batch+0x34c/0xf20 [ 44.914501][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 44.914505][ C0] ? rcu_is_watching+0x12/0xc0 [ 44.914508][ C0] ? note_gp_changes+0x182/0x1e0 [ 44.914510][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 44.914516][ C0] rcu_core+0x2bb/0x500 [ 44.914520][ C0] handle_softirqs+0x21b/0x620 [ 44.914525][ C0] ? __pfx_run_ksoftirqd+0x10/0x10 [ 44.914528][ C0] run_ksoftirqd+0x3f/0x70 [ 44.914531][ C0] smpboot_thread_fn+0x30a/0x960 [ 44.914535][ C0] ? __pfx_smpboot_thread_fn+0x10/0x10 [ 44.914539][ C0] kthread+0x37e/0x600 [ 44.914542][ C0] ? __pfx_kthread+0x10/0x10 [ 44.914544][ C0] ? ret_from_fork+0x1b/0x70 [ 44.914547][ C0] ? __lock_release+0x5d/0x170 [ 44.914550][ C0] ? rcu_is_watching+0x12/0xc0 [ 44.914553][ C0] ? __pfx_kthread+0x10/0x10 [ 44.914556][ C0] ret_from_fork+0x31/0x70 [ 44.914559][ C0] ? __pfx_kthread+0x10/0x10 [ 44.914562][ C0] ret_from_fork_asm+0x1a/0x30 [ 44.914568][ C0]