[ 403.309375][ T9243] br0: port 1(veth1) entered blocking state [ 403.310269][ T9243] br0: port 1(veth1) entered disabled state [ 403.310519][ T9243] veth1: entered allmulticast mode [ 403.312026][ T9243] veth1: entered promiscuous mode [ 403.350023][ T9245] br0: port 2(veth2) entered blocking state [ 403.350213][ T9245] br0: port 2(veth2) entered disabled state [ 403.350390][ T9245] veth2: entered allmulticast mode [ 403.352314][ T9245] veth2: entered promiscuous mode [ 403.434952][ T39] br0: port 1(veth1) entered blocking state [ 403.435142][ T39] br0: port 1(veth1) entered forwarding state [ 403.456120][ T46] br0: port 2(veth2) entered blocking state [ 403.456301][ T46] br0: port 2(veth2) entered forwarding state [ 450.420810][ T9526] Mirror/redirect action on [ 451.597610][ C0] [ 451.597702][ C0] ================================ [ 451.597799][ C0] WARNING: inconsistent lock state [ 451.597888][ C0] 7.1.0-rc5-virtme #1 Not tainted [ 451.597981][ C0] -------------------------------- [ 451.598081][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 451.598193][ C0] jq/9543 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 451.598283][ C0] ffffffffc02f17b8 (mirred_list_lock){+.?.}-{3:3}, at: tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 451.598475][ C0] {SOFTIRQ-ON-W} state was registered at: [ 451.598567][ C0] __lock_acquire+0x378/0xc10 [ 451.598663][ C0] lock_acquire.part.0+0xbc/0x260 [ 451.598751][ C0] _raw_spin_lock+0x33/0x40 [ 451.598842][ C0] tcf_mirred_init+0x844/0xd70 [act_mirred] [ 451.598958][ C0] tcf_action_init_1+0x428/0x6b0 [ 451.599049][ C0] tcf_action_init+0x3cf/0x890 [ 451.599136][ C0] tcf_exts_validate_ex+0x411/0x670 [ 451.599223][ C0] fl_change+0xed4/0x4e77 [cls_flower] [ 451.599312][ C0] tc_new_tfilter+0x875/0x21e0 [ 451.599400][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 451.599490][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 451.599577][ C0] netlink_unicast+0x486/0x750 [ 451.599668][ C0] netlink_sendmsg+0x735/0xc60 [ 451.599755][ C0] ____sys_sendmsg+0x419/0x850 [ 451.599846][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 451.599931][ C0] __sys_sendmsg+0x145/0x1f0 [ 451.600019][ C0] do_syscall_64+0x117/0x590 [ 451.600111][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 451.600220][ C0] irq event stamp: 9400 [ 451.600288][ C0] hardirqs last enabled at (9400): [] _raw_spin_unlock_irqrestore+0x53/0x80 [ 451.600469][ C0] hardirqs last disabled at (9399): [] _raw_spin_lock_irqsave+0x50/0x60 [ 451.600626][ C0] softirqs last enabled at (6764): [] handle_softirqs+0x611/0x940 [ 451.600781][ C0] softirqs last disabled at (9257): [] __irq_exit_rcu+0x103/0x1c0 [ 451.600938][ C0] [ 451.600938][ C0] other info that might help us debug this: [ 451.601071][ C0] Possible unsafe locking scenario: [ 451.601071][ C0] [ 451.601202][ C0] CPU0 [ 451.601273][ C0] ---- [ 451.601339][ C0] lock(mirred_list_lock); [ 451.601474][ C0] [ 451.601537][ C0] lock(mirred_list_lock); [ 451.601627][ C0] [ 451.601627][ C0] *** DEADLOCK *** [ 451.601627][ C0] [ 451.601804][ C0] 3 locks held by jq/9543: [ 451.601890][ C0] #0: ff11000013a46608 (vm_lock){++++}-{0:0}, at: lock_vma_under_rcu+0x12a/0x410 [ 451.602053][ C0] #1: ffffffffa3577d20 (rcu_read_lock){....}-{1:3}, at: __pte_offset_map+0x2e/0x220 [ 451.602264][ C0] #2: ffffffffa3577c00 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x389/0x1020 [ 451.602421][ C0] [ 451.602421][ C0] stack backtrace: [ 451.602534][ C0] CPU: 0 UID: 0 PID: 9543 Comm: jq Not tainted 7.1.0-rc5-virtme #1 PREEMPT(full) [ 451.602537][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 451.602539][ C0] Call Trace: [ 451.602540][ C0] [ 451.602541][ C0] dump_stack_lvl+0x6f/0xa0 [ 451.602546][ C0] print_usage_bug.part.0.cold+0xa9/0xb7 [ 451.602549][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 451.602553][ C0] mark_lock_irq+0x4e3/0x9c0 [ 451.602554][ C0] ? sysvec_apic_timer_interrupt+0x9d/0xe0 [ 451.602556][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 451.602558][ C0] ? save_trace+0x68/0x280 [ 451.602560][ C0] ? 0xffffffff9fa00000 [ 451.602562][ C0] mark_lock+0x1d7/0xa00 [ 451.602564][ C0] mark_usage+0x42/0x170 [ 451.602566][ C0] __lock_acquire+0x378/0xc10 [ 451.602567][ C0] ? mark_held_locks+0x40/0x70 [ 451.602569][ C0] ? rcu_do_batch+0x2b4/0x1020 [ 451.602572][ C0] lock_acquire.part.0+0xbc/0x260 [ 451.602573][ C0] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 451.602576][ C0] ? rcu_is_watching+0x15/0xd0 [ 451.602578][ C0] ? lock_acquire+0x134/0x160 [ 451.602580][ C0] _raw_spin_lock+0x33/0x40 [ 451.602581][ C0] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 451.602583][ C0] tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 451.602585][ C0] tcf_action_rcu_free+0x74/0x1a0 [ 451.602586][ C0] ? rcu_do_batch+0x2b4/0x1020 [ 451.602588][ C0] rcu_do_batch+0x2b6/0x1020 [ 451.602591][ C0] ? trace_rcu_batch_end+0x330/0x330 [ 451.602593][ C0] ? mark_held_locks+0x40/0x70 [ 451.602595][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 451.602596][ C0] ? lockdep_hardirqs_on+0x8c/0x130 [ 451.602598][ C0] rcu_core+0x2bf/0x640 [ 451.602600][ C0] handle_softirqs+0x1d8/0x940 [ 451.602602][ C0] ? find_held_lock+0x2b/0x80 [ 451.602605][ C0] ? __lock_release.isra.0+0x6b/0x1a0 [ 451.602606][ C0] ? _local_bh_enable+0xd0/0xd0 [ 451.602608][ C0] __irq_exit_rcu+0x103/0x1c0 [ 451.602610][ C0] irq_exit_rcu+0xe/0x30 [ 451.602611][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 451.602613][ C0] [ 451.602614][ C0] [ 451.602615][ C0] ? lock_acquire.part.0+0xbc/0x260 [ 451.602616][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 451.602618][ C0] RIP: 0010:lock_acquire.part.0+0xfa/0x260 [ 451.602620][ C0] Code: ff 65 0f c1 05 37 d9 33 04 83 f8 01 4c 8b 14 24 0f 85 30 01 00 00 9c 58 f6 c4 02 0f 85 4d 01 00 00 4d 85 d2 0f 85 16 01 00 00 <48> 8b 44 24 30 65 48 2b 05 a9 92 33 04 0f 85 43 01 00 00 48 83 c4 [ 451.602622][ C0] RSP: 0000:ffa0000001c17b80 EFLAGS: 00000206 [ 451.602624][ C0] RAX: 0000000000000046 RBX: 0000000000000000 RCX: fa127a7509a75f75 [ 451.602625][ C0] RDX: 0000000000000001 RSI: ffffffffa2febde8 RDI: ffffffffa2a6f6c0 [ 451.602626][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ff1100001448ae58 [ 451.602627][ C0] R10: 0000000000000200 R11: 0000000000000001 R12: ffffffffa3577d20 [ 451.602628][ C0] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000002 [ 451.602630][ C0] ? rcu_is_watching+0x15/0xd0 [ 451.602632][ C0] ? lock_acquire+0x134/0x160 [ 451.602634][ C0] __pte_offset_map+0x42/0x220 [ 451.602636][ C0] ? __pte_offset_map+0x2e/0x220 [ 451.602637][ C0] pte_offset_map_lock+0x9f/0x2d0 [ 451.602639][ C0] ? vma_alloc_folio_noprof+0x1bd/0x300 [ 451.602642][ C0] ? pte_offset_map_rw_nolock+0x190/0x190 [ 451.602643][ C0] ? get_vma_policy+0x540/0x540 [ 451.602645][ C0] ? rcu_is_watching+0x15/0xd0 [ 451.602647][ C0] ? __lock_acquire+0x508/0xc10 [ 451.602648][ C0] ? find_held_lock+0x2b/0x80 [ 451.602650][ C0] do_anonymous_page+0x3e6/0xd30 [ 451.602653][ C0] __handle_mm_fault+0x5ed/0x840 [ 451.602655][ C0] ? __pmd_alloc+0x840/0x840 [ 451.602656][ C0] ? lock_vma_under_rcu+0x159/0x410 [ 451.602658][ C0] ? __lock_release.isra.0+0xb3/0x1a0 [ 451.602661][ C0] handle_mm_fault+0x18b/0x4b0 [ 451.602663][ C0] ? rcu_is_watching+0x15/0xd0 [ 451.602665][ C0] do_user_addr_fault+0x472/0xe30 [ 451.602668][ C0] ? irqentry_enter+0x9f/0xd0 [ 451.602670][ C0] exc_page_fault+0x87/0x100 [ 451.602672][ C0] asm_exc_page_fault+0x26/0x30 [ 451.602673][ C0] RIP: 0033:0x7f32ab72aa1b [ 451.602675][ C0] Code: 7e 08 40 0f 95 c6 48 29 d1 48 83 c0 10 40 0f b6 f6 48 83 c9 01 48 c1 e6 02 48 09 d6 8b 15 d1 57 17 00 48 83 ce 01 48 89 70 f8 <48> 89 4f 08 85 d2 0f 84 29 fa ff ff 80 f2 ff 89 d6 4c 89 c2 e9 7e [ 451.602676][ C0] RSP: 002b:00007ffe5fc9e970 EFLAGS: 00010206 [ 451.602677][ C0] RAX: 0000562cee6dc000 RBX: 00007f32ab899ad8 RCX: 000000000000aff1 [ 451.602678][ C0] RDX: 0000000000000000 RSI: 0000000000000021 RDI: 0000562cee6dc010 [ 451.602679][ C0] RBP: 00007ffe5fc9e9e0 R08: 0000000000000004 R09: 00007f32ab899ac0 [ 451.602680][ C0] R10: 0000000000000004 R11: 00007f32ab899ac8 R12: 00007f32ab899ad8 [ 451.602680][ C0] R13: 0000562cee6dbf50 R14: 00007f32ab98e818 R15: 0000562cee6da4e0 [ 451.602683][ C0] [ 456.800343][ T9566] br0: port 2(veth2) entered disabled state [ 456.813160][ T9567] br0: port 1(veth1) entered disabled state [ 456.833023][ T9568] veth2: left allmulticast mode [ 456.833180][ T9568] veth2: left promiscuous mode [ 456.833384][ T9568] br0: port 2(veth2) entered disabled state [ 456.834027][ T9568] veth1: left allmulticast mode [ 456.834162][ T9568] veth1: left promiscuous mode [ 456.834356][ T9568] br0: port 1(veth1) entered disabled state