[ 114.102552][ T2707] br: port 1(veth1) entered blocking state [ 114.102777][ T2707] br: port 1(veth1) entered disabled state [ 114.102951][ T2707] veth1: entered allmulticast mode [ 114.104354][ T2707] veth1: entered promiscuous mode [ 114.109775][ T188] br: port 1(veth1) entered blocking state [ 114.109957][ T188] br: port 1(veth1) entered forwarding state [ 114.158546][ T2712] br: port 2(veth3) entered blocking state [ 114.158747][ T2712] br: port 2(veth3) entered disabled state [ 114.158925][ T2712] veth3: entered allmulticast mode [ 114.160332][ T2712] veth3: entered promiscuous mode [ 114.161993][ T188] br: port 2(veth3) entered blocking state [ 114.162184][ T188] br: port 2(veth3) entered forwarding state [ 114.211278][ T2717] br: port 3(veth4) entered blocking state [ 114.211521][ T2717] br: port 3(veth4) entered disabled state [ 114.212184][ T2717] veth4: entered allmulticast mode [ 114.213669][ T2717] veth4: entered promiscuous mode [ 114.215042][ T62] br: port 3(veth4) entered blocking state [ 114.215230][ T62] br: port 3(veth4) entered forwarding state [ 191.287159][ T3149] Mirror/redirect action on [ 204.379816][ C3] [ 204.379904][ C3] ================================ [ 204.380001][ C3] WARNING: inconsistent lock state [ 204.380091][ C3] 7.1.0-rc5-virtme #1 Not tainted [ 204.380184][ C3] -------------------------------- [ 204.380273][ C3] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 204.380388][ C3] ip/3220 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 204.380477][ C3] ffffffffc08327b8 (mirred_list_lock){+.?.}-{3:3}, at: tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 204.380666][ C3] {SOFTIRQ-ON-W} state was registered at: [ 204.380754][ C3] __lock_acquire+0x378/0xc10 [ 204.380848][ C3] lock_acquire.part.0+0xbc/0x260 [ 204.380935][ C3] _raw_spin_lock+0x33/0x40 [ 204.381030][ C3] tcf_mirred_init+0x844/0xd70 [act_mirred] [ 204.381140][ C3] tcf_action_init_1+0x428/0x6b0 [ 204.381230][ C3] tcf_action_init+0x3cf/0x890 [ 204.381317][ C3] tcf_exts_validate_ex+0x411/0x670 [ 204.381409][ C3] mall_change+0x3cd/0xe9b [cls_matchall] [ 204.381525][ C3] tc_new_tfilter+0x875/0x21e0 [ 204.381619][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 204.381710][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 204.381799][ C3] netlink_unicast+0x486/0x750 [ 204.381890][ C3] netlink_sendmsg+0x735/0xc60 [ 204.381979][ C3] ____sys_sendmsg+0x419/0x850 [ 204.382121][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 204.382211][ C3] __sys_sendmsg+0x145/0x1f0 [ 204.382302][ C3] do_syscall_64+0x117/0x590 [ 204.382393][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 204.382504][ C3] irq event stamp: 12316 [ 204.382574][ C3] hardirqs last enabled at (12316): [] _raw_spin_unlock_irqrestore+0x53/0x80 [ 204.382752][ C3] hardirqs last disabled at (12315): [] _raw_spin_lock_irqsave+0x50/0x60 [ 204.382930][ C3] softirqs last enabled at (11012): [] handle_softirqs+0x611/0x940 [ 204.383084][ C3] softirqs last disabled at (11911): [] __irq_exit_rcu+0x103/0x1c0 [ 204.383287][ C3] [ 204.383287][ C3] other info that might help us debug this: [ 204.383421][ C3] Possible unsafe locking scenario: [ 204.383421][ C3] [ 204.383551][ C3] CPU0 [ 204.383676][ C3] ---- [ 204.383741][ C3] lock(mirred_list_lock); [ 204.383830][ C3] [ 204.383897][ C3] lock(mirred_list_lock); [ 204.384035][ C3] [ 204.384035][ C3] *** DEADLOCK *** [ 204.384035][ C3] [ 204.384165][ C3] 2 locks held by ip/3220: [ 204.384255][ C3] #0: ffffffffa2177d20 (rcu_read_lock){....}-{1:3}, at: unwind_next_frame+0x17e/0x1ea0 [ 204.384464][ C3] #1: ffffffffa2177c00 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x389/0x1020 [ 204.384625][ C3] [ 204.384625][ C3] stack backtrace: [ 204.384735][ C3] CPU: 3 UID: 0 PID: 3220 Comm: ip Not tainted 7.1.0-rc5-virtme #1 PREEMPT(full) [ 204.384738][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 204.384739][ C3] Call Trace: [ 204.384741][ C3] [ 204.384742][ C3] dump_stack_lvl+0x6f/0xa0 [ 204.384747][ C3] print_usage_bug.part.0.cold+0xa9/0xb7 [ 204.384750][ C3] ? filter_irq_stacks+0xd0/0xd0 [ 204.384753][ C3] mark_lock_irq+0x4e3/0x9c0 [ 204.384754][ C3] ? sysvec_apic_timer_interrupt+0x9d/0xe0 [ 204.384756][ C3] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 204.384758][ C3] ? save_trace+0x68/0x280 [ 204.384760][ C3] ? calculate_order+0x110/0x170 [ 204.384764][ C3] mark_lock+0x1d7/0xa00 [ 204.384765][ C3] mark_usage+0x42/0x170 [ 204.384767][ C3] __lock_acquire+0x378/0xc10 [ 204.384768][ C3] ? rcu_is_watching+0x15/0xd0 [ 204.384770][ C3] ? rcu_do_batch+0x2b4/0x1020 [ 204.384773][ C3] lock_acquire.part.0+0xbc/0x260 [ 204.384774][ C3] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 204.384776][ C3] ? rcu_is_watching+0x15/0xd0 [ 204.384777][ C3] ? lock_acquire+0x134/0x160 [ 204.384779][ C3] _raw_spin_lock+0x33/0x40 [ 204.384781][ C3] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 204.384782][ C3] tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 204.384784][ C3] tcf_action_rcu_free+0x74/0x1a0 [ 204.384785][ C3] ? rcu_do_batch+0x2b4/0x1020 [ 204.384787][ C3] rcu_do_batch+0x2b6/0x1020 [ 204.384790][ C3] ? trace_rcu_batch_end+0x330/0x330 [ 204.384792][ C3] ? rcu_is_watching+0x15/0xd0 [ 204.384793][ C3] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 204.384795][ C3] ? lockdep_hardirqs_on+0x8c/0x130 [ 204.384797][ C3] rcu_core+0x2bf/0x640 [ 204.384798][ C3] handle_softirqs+0x1d8/0x940 [ 204.384800][ C3] ? find_held_lock+0x2b/0x80 [ 204.384803][ C3] ? __lock_release.isra.0+0x6b/0x1a0 [ 204.384804][ C3] ? _local_bh_enable+0xd0/0xd0 [ 204.384806][ C3] __irq_exit_rcu+0x103/0x1c0 [ 204.384808][ C3] irq_exit_rcu+0xe/0x30 [ 204.384809][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 204.384811][ C3] [ 204.384811][ C3] [ 204.384812][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 204.384814][ C3] RIP: 0010:lock_acquire.part.0+0xfa/0x260 [ 204.384815][ C3] 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 [ 204.384817][ C3] RSP: 0018:ffa0000000617918 EFLAGS: 00000206 [ 204.384819][ C3] RAX: 0000000000000046 RBX: 0000000000000000 RCX: 0ac0d5affe09d4ff [ 204.384821][ C3] RDX: 0000000000000001 RSI: ffffffffa1bebde8 RDI: ffffffffa166f6c0 [ 204.384822][ C3] RBP: 0000000000000000 R08: 0000000000000001 R09: ff1100001066ae30 [ 204.384822][ C3] R10: 0000000000000200 R11: 0000000000000001 R12: ffffffffa2177d20 [ 204.384823][ C3] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000002 [ 204.384826][ C3] ? rcu_is_watching+0x15/0xd0 [ 204.384827][ C3] ? lock_acquire+0x134/0x160 [ 204.384829][ C3] unwind_next_frame+0x192/0x1ea0 [ 204.384830][ C3] ? unwind_next_frame+0x17e/0x1ea0 [ 204.384831][ C3] ? arch_stack_walk+0xb3/0x130 [ 204.384835][ C3] ? stack_access_ok+0x1e0/0x1e0 [ 204.384837][ C3] ? kasan_save_stack+0x3d/0x50 [ 204.384840][ C3] ? kasan_save_stack+0x2f/0x50 [ 204.384841][ C3] ? kasan_save_track+0x14/0x30 [ 204.384843][ C3] ? kmem_cache_alloc_noprof+0x221/0x5f0 [ 204.384845][ C3] ? __alloc_object+0x30/0x250 [ 204.384847][ C3] ? __create_object+0x30/0x110 [ 204.384848][ C3] ? get_stack_info_noinstr+0x1a/0x120 [ 204.384849][ C3] ? alloc_empty_file+0x3f/0x120 [ 204.384852][ C3] __unwind_start+0x432/0x910 [ 204.384853][ C3] ? write_profile+0xf0/0xf0 [ 204.384856][ C3] arch_stack_walk+0xb3/0x130 [ 204.384858][ C3] ? arch_stack_walk+0xb3/0x130 [ 204.384860][ C3] stack_trace_save+0x93/0xc0 [ 204.384862][ C3] ? filter_irq_stacks+0xd0/0xd0 [ 204.384865][ C3] set_track_prepare+0x6b/0xa0 [ 204.384868][ C3] __alloc_object+0xe8/0x250 [ 204.384869][ C3] __create_object+0x30/0x110 [ 204.384870][ C3] ? kasan_save_track+0x14/0x30 [ 204.384872][ C3] kmem_cache_alloc_noprof+0x445/0x5f0 [ 204.384875][ C3] alloc_empty_file+0x3f/0x120 [ 204.384876][ C3] alloc_file_pseudo+0x12b/0x1f0 [ 204.384878][ C3] ? alloc_empty_file+0x120/0x120 [ 204.384880][ C3] ? do_raw_spin_unlock+0x59/0x250 [ 204.384881][ C3] ? _raw_spin_unlock+0x2d/0x50 [ 204.384883][ C3] sock_alloc_file+0x48/0x1e0 [ 204.384886][ C3] __sys_socket+0x14f/0x1d0 [ 204.384888][ C3] ? update_socket_protocol+0x10/0x10 [ 204.384890][ C3] ? rcu_is_watching+0x15/0xd0 [ 204.384892][ C3] __x64_sys_socket+0x72/0xd0 [ 204.384893][ C3] ? lockdep_hardirqs_on+0x8c/0x130 [ 204.384895][ C3] ? do_syscall_64+0x82/0x590 [ 204.384896][ C3] do_syscall_64+0x117/0x590 [ 204.384897][ C3] ? trace_hardirqs_off+0xd/0x30 [ 204.384900][ C3] ? exc_page_fault+0xee/0x100 [ 204.384902][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 204.384903][ C3] RIP: 0033:0x7f518ec8c89b [ 204.384905][ C3] Code: 8b 55 f8 64 48 2b 14 25 28 00 00 00 75 02 c9 c3 e8 ea ad 00 00 66 2e 0f 1f 84 00 00 00 00 00 f3 0f 1e fa b8 29 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 2d 75 0f 00 f7 d8 64 89 01 48 [ 204.384906][ C3] RSP: 002b:00007fff0d31c778 EFLAGS: 00000206 ORIG_RAX: 0000000000000029 [ 204.384908][ C3] RAX: ffffffffffffffda RBX: 000000000049f620 RCX: 00007f518ec8c89b [ 204.384909][ C3] RDX: 0000000000000000 RSI: 0000000000080003 RDI: 0000000000000010 [ 204.384909][ C3] RBP: 0000000000000005 R08: 00007f518ed84680 R09: 00007f518ed85fa0 [ 204.384910][ C3] R10: 0000000000000000 R11: 0000000000000206 R12: 0000000000000000 [ 204.384911][ C3] R13: 00007fff0d31e832 R14: 0000000000000000 R15: 000000000049e138 [ 204.384913][ C3] [ 220.107837][ T3326] br: port 3(veth4) entered disabled state [ 220.108133][ T3326] veth4: left allmulticast mode [ 220.108226][ T3326] veth4: left promiscuous mode [ 220.108363][ T3326] br: port 3(veth4) entered disabled state [ 220.128447][ T3328] br: port 2(veth3) entered disabled state [ 220.128975][ T3328] veth3: left allmulticast mode [ 220.129061][ T3328] veth3: left promiscuous mode [ 220.129190][ T3328] br: port 2(veth3) entered disabled state [ 220.147467][ T3330] br: port 1(veth1) entered disabled state [ 220.147725][ T3330] veth1: left allmulticast mode [ 220.147814][ T3330] veth1: left promiscuous mode [ 220.147943][ T3330] br: port 1(veth1) entered disabled state