[ 855.103704][ T2086] br0: port 1(veth1) entered blocking state [ 855.103937][ T2086] br0: port 1(veth1) entered disabled state [ 855.104176][ T2086] veth1: entered allmulticast mode [ 855.105616][ T2086] veth1: entered promiscuous mode [ 855.131733][ T2088] br0: port 2(veth2) entered blocking state [ 855.131897][ T2088] br0: port 2(veth2) entered disabled state [ 855.132060][ T2088] veth2: entered allmulticast mode [ 855.133407][ T2088] veth2: entered promiscuous mode [ 855.210164][ T46] br0: port 1(veth1) entered blocking state [ 855.210362][ T46] br0: port 1(veth1) entered forwarding state [ 855.233393][ T46] br0: port 2(veth2) entered blocking state [ 855.233567][ T46] br0: port 2(veth2) entered forwarding state [ 902.319565][ T2369] Mirror/redirect action on [ 903.505465][ C2] [ 903.505553][ C2] ================================ [ 903.505659][ C2] WARNING: inconsistent lock state [ 903.505756][ C2] 7.1.0-rc5-virtme #1 Not tainted [ 903.505855][ C2] -------------------------------- [ 903.505958][ C2] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 903.506082][ C2] ip/2385 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 903.506181][ C2] ffffffffc06517b8 (mirred_list_lock){+.?.}-{3:3}, at: tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 903.506392][ C2] {SOFTIRQ-ON-W} state was registered at: [ 903.506495][ C2] __lock_acquire+0x378/0xc10 [ 903.506598][ C2] lock_acquire.part.0+0xbc/0x260 [ 903.506696][ C2] _raw_spin_lock+0x33/0x40 [ 903.506793][ C2] tcf_mirred_init+0x844/0xd70 [act_mirred] [ 903.506910][ C2] tcf_action_init_1+0x428/0x6b0 [ 903.506992][ C2] tcf_action_init+0x3cf/0x890 [ 903.507071][ C2] tcf_exts_validate_ex+0x411/0x670 [ 903.507152][ C2] fl_change+0xed4/0x4e77 [cls_flower] [ 903.507233][ C2] tc_new_tfilter+0x875/0x21e0 [ 903.507318][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 903.507406][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 903.507488][ C2] netlink_unicast+0x486/0x750 [ 903.507567][ C2] netlink_sendmsg+0x735/0xc60 [ 903.507646][ C2] ____sys_sendmsg+0x419/0x850 [ 903.507727][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 903.507811][ C2] __sys_sendmsg+0x145/0x1f0 [ 903.507893][ C2] do_syscall_64+0x117/0x590 [ 903.507975][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 903.508080][ C2] irq event stamp: 7996 [ 903.508146][ C2] hardirqs last enabled at (7996): [] _raw_spin_unlock_irqrestore+0x53/0x80 [ 903.508313][ C2] hardirqs last disabled at (7995): [] _raw_spin_lock_irqsave+0x50/0x60 [ 903.508462][ C2] softirqs last enabled at (4260): [] fpu_flush_thread+0x130/0x190 [ 903.508608][ C2] softirqs last disabled at (7935): [] __irq_exit_rcu+0x103/0x1c0 [ 903.508757][ C2] [ 903.508757][ C2] other info that might help us debug this: [ 903.508879][ C2] Possible unsafe locking scenario: [ 903.508879][ C2] [ 903.508997][ C2] CPU0 [ 903.509061][ C2] ---- [ 903.509127][ C2] lock(mirred_list_lock); [ 903.509211][ C2] [ 903.509279][ C2] lock(mirred_list_lock); [ 903.509364][ C2] [ 903.509364][ C2] *** DEADLOCK *** [ 903.509364][ C2] [ 903.509484][ C2] 1 lock held by ip/2385: [ 903.509545][ C2] #0: ffffffff88377c00 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x389/0x1020 [ 903.509694][ C2] [ 903.509694][ C2] stack backtrace: [ 903.509793][ C2] CPU: 2 UID: 0 PID: 2385 Comm: ip Not tainted 7.1.0-rc5-virtme #1 PREEMPT(full) [ 903.509795][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 903.509797][ C2] Call Trace: [ 903.509799][ C2] [ 903.509800][ C2] dump_stack_lvl+0x6f/0xa0 [ 903.509805][ C2] print_usage_bug.part.0.cold+0xa9/0xb7 [ 903.509808][ C2] ? filter_irq_stacks+0xd0/0xd0 [ 903.509811][ C2] mark_lock_irq+0x4e3/0x9c0 [ 903.509813][ C2] ? sysvec_apic_timer_interrupt+0x9d/0xe0 [ 903.509815][ C2] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 903.509817][ C2] ? save_trace+0x68/0x280 [ 903.509819][ C2] ? try_to_unlazy_next+0x280/0x7f0 [ 903.509823][ C2] mark_lock+0x1d7/0xa00 [ 903.509824][ C2] mark_usage+0x42/0x170 [ 903.509826][ C2] __lock_acquire+0x378/0xc10 [ 903.509827][ C2] ? rcu_is_watching+0x15/0xd0 [ 903.509829][ C2] ? trace_irq_enable.constprop.0+0x9b/0x180 [ 903.509833][ C2] lock_acquire.part.0+0xbc/0x260 [ 903.509834][ C2] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 903.509836][ C2] ? rcu_is_watching+0x15/0xd0 [ 903.509838][ C2] ? lock_acquire+0x134/0x160 [ 903.509840][ C2] _raw_spin_lock+0x33/0x40 [ 903.509841][ C2] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 903.509843][ C2] tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 903.509845][ C2] tcf_action_rcu_free+0x74/0x1a0 [ 903.509846][ C2] ? rcu_do_batch+0x2b4/0x1020 [ 903.509848][ C2] rcu_do_batch+0x2b6/0x1020 [ 903.509851][ C2] ? trace_rcu_batch_end+0x330/0x330 [ 903.509853][ C2] ? trace_irq_enable.constprop.0+0x9b/0x180 [ 903.509854][ C2] ? do_raw_spin_unlock+0x59/0x250 [ 903.509856][ C2] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 903.509857][ C2] ? lockdep_hardirqs_on+0x8c/0x130 [ 903.509859][ C2] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 903.509860][ C2] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 903.509862][ C2] rcu_core+0x2bf/0x640 [ 903.509864][ C2] handle_softirqs+0x1d8/0x940 [ 903.509866][ C2] ? find_held_lock+0x2b/0x80 [ 903.509869][ C2] ? __lock_release.isra.0+0x6b/0x1a0 [ 903.509870][ C2] ? _local_bh_enable+0xd0/0xd0 [ 903.509872][ C2] __irq_exit_rcu+0x103/0x1c0 [ 903.509873][ C2] irq_exit_rcu+0xe/0x30 [ 903.509875][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 903.509877][ C2] [ 903.509877][ C2] [ 903.509878][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 903.509880][ C2] RIP: 0010:_raw_spin_unlock_irqrestore+0x36/0x80 [ 903.509882][ C2] Code: f5 53 48 8b 74 24 10 48 89 fb 48 83 c7 18 e8 21 7e ca fd 48 89 df e8 a9 d4 ca fd f7 c5 00 02 00 00 75 1f 9c 58 f6 c4 02 75 2f 01 00 00 00 e8 30 62 bd fd 65 8b 05 29 0a fe 01 85 c0 74 12 5b [ 903.509884][ C2] RSP: 0018:ffa00000017ef610 EFLAGS: 00000246 [ 903.509886][ C2] RAX: 0000000000000082 RBX: ffffffff8859a080 RCX: ffffffff87490613 [ 903.509887][ C2] RDX: ff110000088b4640 RSI: ffffffff87e01bcb RDI: ffffffff8786f6c0 [ 903.509888][ C2] RBP: 0000000000000292 R08: 0000000000000000 R09: 0000000000000000 [ 903.509889][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 0000000000000000 [ 903.509890][ C2] R13: 0000000000026087 R14: 0000000000000000 R15: ffffffff85c85b0c [ 903.509891][ C2] ? __fuse_simple_request+0x31c/0xbb0 [ 903.509894][ C2] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 903.509897][ C2] delete_object_full+0x4a/0x70 [ 903.509899][ C2] kmem_cache_free+0x2e6/0x560 [ 903.509903][ C2] __fuse_simple_request+0x31c/0xbb0 [ 903.509905][ C2] fuse_readlink_folio+0x242/0x450 [ 903.509907][ C2] ? fuse_invalidate_attr_mask+0x100/0x100 [ 903.509909][ C2] ? __lock_acquire+0x508/0xc10 [ 903.509912][ C2] ? find_held_lock+0x2b/0x80 [ 903.509913][ C2] ? find_held_lock+0x2b/0x80 [ 903.509916][ C2] fuse_get_link+0x131/0x350 [ 903.509918][ C2] ovl_get_link+0x6d/0xf0 [ 903.509920][ C2] pick_link+0xf6e/0x1920 [ 903.509922][ C2] ? ovl_check_rename_whiteout+0x5d0/0x5d0 [ 903.509925][ C2] step_into_slowpath+0x4c8/0x1240 [ 903.509927][ C2] ? __lock_release.isra.0+0x6b/0x1a0 [ 903.509929][ C2] ? pick_link+0x1920/0x1920 [ 903.509931][ C2] ? ovl_dentry_revalidate_common+0x199/0x240 [ 903.509933][ C2] ? lookup_fast+0x208/0x630 [ 903.509935][ C2] open_last_lookups+0x81f/0x1d10 [ 903.509938][ C2] ? lookup_open.isra.0+0xea0/0xea0 [ 903.509940][ C2] ? __asan_memset+0x27/0x50 [ 903.509942][ C2] ? init_file.isra.0+0x30a/0x3e0 [ 903.509945][ C2] path_openat+0x136/0x3b0 [ 903.509946][ C2] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 903.509948][ C2] ? kernel_tmpfile_open+0x70/0x70 [ 903.509950][ C2] ? validate_chain+0x38b/0xc20 [ 903.509951][ C2] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 903.509953][ C2] do_file_open+0x209/0x480 [ 903.509955][ C2] ? path_openat+0x3b0/0x3b0 [ 903.509959][ C2] ? do_raw_spin_unlock+0x59/0x250 [ 903.509961][ C2] ? _raw_spin_unlock+0x2d/0x50 [ 903.509962][ C2] ? alloc_fd+0x24a/0x450 [ 903.509965][ C2] do_sys_openat2+0xde/0x170 [ 903.509967][ C2] ? build_open_flags+0x620/0x620 [ 903.509969][ C2] ? rcu_is_watching+0x15/0xd0 [ 903.509971][ C2] ? trace_irq_enable.constprop.0+0x9b/0x180 [ 903.509973][ C2] ? lockdep_hardirqs_on+0x8c/0x130 [ 903.509975][ C2] __x64_sys_openat+0x10e/0x210 [ 903.509977][ C2] ? fput_close_sync+0xde/0x1b0 [ 903.509978][ C2] ? __ia32_sys_open+0x1b0/0x1b0 [ 903.509980][ C2] ? fpregs_restore_userregs+0x14b/0x310 [ 903.509982][ C2] ? rcu_is_watching+0x15/0xd0 [ 903.509983][ C2] ? trace_irq_enable.constprop.0+0x9b/0x180 [ 903.509985][ C2] do_syscall_64+0x117/0x590 [ 903.509987][ C2] ? trace_hardirqs_off+0xd/0x30 [ 903.509988][ C2] ? exc_page_fault+0xee/0x100 [ 903.509990][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 903.509991][ C2] RIP: 0033:0x7f7bc7998625 [ 903.509993][ C2] Code: 48 89 e5 48 89 55 e0 41 83 e2 40 75 28 89 f2 f7 d2 81 e2 00 00 41 00 74 1c 89 f2 bf 9c ff ff ff 48 89 c6 b8 01 01 00 00 0f 05 <48> 3d 00 f0 ff ff 77 23 5d c3 90 48 8d 4d 10 c7 45 b8 10 00 00 00 [ 903.509994][ C2] RSP: 002b:00007fff9f1abf80 EFLAGS: 00000206 ORIG_RAX: 0000000000000101 [ 903.509996][ C2] RAX: ffffffffffffffda RBX: 00007fff9f1ac210 RCX: 00007f7bc7998625 [ 903.509997][ C2] RDX: 0000000000080000 RSI: 00007fff9f1ac000 RDI: 00000000ffffff9c [ 903.509998][ C2] RBP: 00007fff9f1abf80 R08: 00007fff9f1ac1f7 R09: 0000000000000000 [ 903.509998][ C2] R10: 0000000000000000 R11: 0000000000000206 R12: 00007fff9f1ac000 [ 903.509999][ C2] R13: 00007fff9f1ac000 R14: 00007f7bc796a000 R15: 0000000000000000 [ 903.510001][ C2] [ 908.704240][ T2409] br0: port 2(veth2) entered disabled state [ 908.728029][ T2410] br0: port 1(veth1) entered disabled state [ 908.743300][ T2411] veth2: left allmulticast mode [ 908.743477][ T2411] veth2: left promiscuous mode [ 908.743694][ T2411] br0: port 2(veth2) entered disabled state [ 908.744254][ T2411] veth1: left allmulticast mode [ 908.744373][ T2411] veth1: left promiscuous mode [ 908.744852][ T2411] br0: port 1(veth1) entered disabled state