[ 665.701273][T32533] br0: port 1(veth1) entered blocking state [ 665.701527][T32533] br0: port 1(veth1) entered disabled state [ 665.701718][T32533] veth1: entered allmulticast mode [ 665.703238][T32533] veth1: entered promiscuous mode [ 665.729867][T32535] br0: port 2(veth2) entered blocking state [ 665.730196][T32535] br0: port 2(veth2) entered disabled state [ 665.731583][T32535] veth2: entered allmulticast mode [ 665.734283][T32535] veth2: entered promiscuous mode [ 665.839068][ T57] br0: port 1(veth1) entered blocking state [ 665.839428][ T57] br0: port 1(veth1) entered forwarding state [ 665.885517][ T93] br0: port 2(veth2) entered blocking state [ 665.885908][ T93] br0: port 2(veth2) entered forwarding state [ 713.315091][ T349] Mirror/redirect action on [ 714.576391][ C2] [ 714.576489][ C2] ================================ [ 714.576593][ C2] WARNING: inconsistent lock state [ 714.576696][ C2] 7.1.0-rc5-virtme #1 Not tainted [ 714.576796][ C2] -------------------------------- [ 714.576894][ C2] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 714.577018][ C2] ip/365 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 714.577117][ C2] ffffffffc08757b8 (mirred_list_lock){+.?.}-{3:3}, at: tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 714.577319][ C2] {SOFTIRQ-ON-W} state was registered at: [ 714.577422][ C2] __lock_acquire+0x378/0xc10 [ 714.577532][ C2] lock_acquire.part.0+0xbc/0x260 [ 714.577632][ C2] _raw_spin_lock+0x33/0x40 [ 714.577727][ C2] tcf_mirred_init+0x844/0xd70 [act_mirred] [ 714.577846][ C2] tcf_action_init_1+0x428/0x6b0 [ 714.577948][ C2] tcf_action_init+0x3cf/0x890 [ 714.578042][ C2] tcf_exts_validate_ex+0x411/0x670 [ 714.578137][ C2] fl_change+0xed4/0x4e77 [cls_flower] [ 714.578234][ C2] tc_new_tfilter+0x875/0x21e0 [ 714.578333][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 714.578427][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 714.578533][ C2] netlink_unicast+0x486/0x750 [ 714.578628][ C2] netlink_sendmsg+0x735/0xc60 [ 714.578722][ C2] ____sys_sendmsg+0x419/0x850 [ 714.578819][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 714.578913][ C2] __sys_sendmsg+0x145/0x1f0 [ 714.579013][ C2] do_syscall_64+0x117/0x590 [ 714.579109][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 714.579231][ C2] irq event stamp: 5494 [ 714.579311][ C2] hardirqs last enabled at (5494): [] _raw_spin_unlock_irqrestore+0x53/0x80 [ 714.579512][ C2] hardirqs last disabled at (5493): [] _raw_spin_lock_irqsave+0x50/0x60 [ 714.579685][ C2] softirqs last enabled at (3674): [] handle_softirqs+0x611/0x940 [ 714.579849][ C2] softirqs last disabled at (5433): [] __irq_exit_rcu+0x103/0x1c0 [ 714.580016][ C2] [ 714.580016][ C2] other info that might help us debug this: [ 714.580156][ C2] Possible unsafe locking scenario: [ 714.580156][ C2] [ 714.580357][ C2] CPU0 [ 714.580489][ C2] ---- [ 714.580564][ C2] lock(mirred_list_lock); [ 714.580663][ C2] [ 714.580732][ C2] lock(mirred_list_lock); [ 714.580829][ C2] [ 714.580829][ C2] *** DEADLOCK *** [ 714.580829][ C2] [ 714.580971][ C2] 3 locks held by ip/365: [ 714.581043][ C2] #0: ff1100000ce88f78 (&mm->mmap_lock){++++}-{4:4}, at: vm_mmap_pgoff+0xf8/0x320 [ 714.581211][ C2] #1: ffffffff9d777d20 (rcu_read_lock){....}-{1:3}, at: unwind_next_frame+0x17e/0x1ea0 [ 714.581441][ C2] #2: ffffffff9d777c00 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x389/0x1020 [ 714.581610][ C2] [ 714.581610][ C2] stack backtrace: [ 714.581834][ C2] CPU: 2 UID: 0 PID: 365 Comm: ip Not tainted 7.1.0-rc5-virtme #1 PREEMPT(full) [ 714.581837][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 714.581839][ C2] Call Trace: [ 714.581841][ C2] [ 714.581843][ C2] dump_stack_lvl+0x6f/0xa0 [ 714.581848][ C2] print_usage_bug.part.0.cold+0xa9/0xb7 [ 714.581850][ C2] ? filter_irq_stacks+0xd0/0xd0 [ 714.581854][ C2] mark_lock_irq+0x4e3/0x9c0 [ 714.581855][ C2] ? sysvec_apic_timer_interrupt+0x9d/0xe0 [ 714.581858][ C2] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 714.581860][ C2] ? save_trace+0x68/0x280 [ 714.581861][ C2] ? calculate_order+0x110/0x170 [ 714.581865][ C2] mark_lock+0x1d7/0xa00 [ 714.581867][ C2] mark_usage+0x42/0x170 [ 714.581868][ C2] __lock_acquire+0x378/0xc10 [ 714.581870][ C2] ? mark_held_locks+0x40/0x70 [ 714.581871][ C2] ? rcu_do_batch+0x2b4/0x1020 [ 714.581874][ C2] lock_acquire.part.0+0xbc/0x260 [ 714.581875][ C2] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 714.581877][ C2] ? rcu_is_watching+0x15/0xd0 [ 714.581879][ C2] ? lock_acquire+0x134/0x160 [ 714.581881][ C2] _raw_spin_lock+0x33/0x40 [ 714.581883][ C2] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 714.581885][ C2] tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 714.581887][ C2] tcf_action_rcu_free+0x74/0x1a0 [ 714.581889][ C2] ? rcu_do_batch+0x2b4/0x1020 [ 714.581890][ C2] rcu_do_batch+0x2b6/0x1020 [ 714.581893][ C2] ? trace_rcu_batch_end+0x330/0x330 [ 714.581895][ C2] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 714.581897][ C2] ? lockdep_hardirqs_on+0x8c/0x130 [ 714.581898][ C2] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 714.581900][ C2] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 714.581902][ C2] rcu_core+0x2bf/0x640 [ 714.581904][ C2] handle_softirqs+0x1d8/0x940 [ 714.581906][ C2] ? find_held_lock+0x2b/0x80 [ 714.581909][ C2] ? __lock_release.isra.0+0x6b/0x1a0 [ 714.581910][ C2] ? _local_bh_enable+0xd0/0xd0 [ 714.581912][ C2] __irq_exit_rcu+0x103/0x1c0 [ 714.581914][ C2] irq_exit_rcu+0xe/0x30 [ 714.581915][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 714.581917][ C2] [ 714.581918][ C2] [ 714.581919][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 714.581920][ C2] RIP: 0010:lock_acquire.part.0+0xfa/0x260 [ 714.581922][ C2] 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 [ 714.581924][ C2] RSP: 0018:ffa0000000517138 EFLAGS: 00000206 [ 714.581926][ C2] RAX: 0000000000000046 RBX: 0000000000000000 RCX: b3920c865c501c38 [ 714.581928][ C2] RDX: 0000000000000001 RSI: ffffffff9d1ebde8 RDI: ffffffff9cc6f6c0 [ 714.581929][ C2] RBP: 0000000000000000 R08: 0000000000000001 R09: ff1100000c8dd158 [ 714.581929][ C2] R10: 0000000000000200 R11: 0000000000000001 R12: ffffffff9d777d20 [ 714.581930][ C2] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000002 [ 714.581933][ C2] ? rcu_is_watching+0x15/0xd0 [ 714.581934][ C2] ? lock_acquire+0x134/0x160 [ 714.581936][ C2] unwind_next_frame+0x192/0x1ea0 [ 714.581938][ C2] ? unwind_next_frame+0x17e/0x1ea0 [ 714.581939][ C2] ? ksys_mmap_pgoff+0x1ed/0x560 [ 714.581942][ C2] ? kmem_cache_alloc_noprof+0x221/0x5f0 [ 714.581944][ C2] ? stack_access_ok+0x1e0/0x1e0 [ 714.581945][ C2] ? kmem_cache_free+0x368/0x560 [ 714.581948][ C2] ? __unwind_start+0x432/0x910 [ 714.581949][ C2] ? write_profile+0xf0/0xf0 [ 714.581952][ C2] arch_stack_walk+0xd7/0x130 [ 714.581955][ C2] ? ksys_mmap_pgoff+0x1ed/0x560 [ 714.581958][ C2] stack_trace_save+0x93/0xc0 [ 714.581960][ C2] ? filter_irq_stacks+0xd0/0xd0 [ 714.581963][ C2] set_track_prepare+0x6b/0xa0 [ 714.581966][ C2] ? __kmalloc_cache_noprof+0x463/0x620 [ 714.581967][ C2] ? kmem_cache_free+0x368/0x560 [ 714.581969][ C2] ? vms_complete_munmap_vmas+0x679/0xe60 [ 714.581971][ C2] ? __mmap_region+0x749/0xc70 [ 714.581973][ C2] ? mmap_region+0x26d/0x490 [ 714.581974][ C2] ? do_mmap+0x9cc/0xf80 [ 714.581975][ C2] ? vm_mmap_pgoff+0x16c/0x320 [ 714.581977][ C2] ? ksys_mmap_pgoff+0x1ed/0x560 [ 714.581979][ C2] __alloc_object+0xe8/0x250 [ 714.581981][ C2] __create_object+0x30/0x110 [ 714.581983][ C2] __kmalloc_cache_noprof+0x463/0x620 [ 714.581985][ C2] ? vms_complete_munmap_vmas+0x679/0xe60 [ 714.581986][ C2] kmem_cache_free+0x368/0x560 [ 714.581988][ C2] ? fput+0x1f/0xa0 [ 714.581991][ C2] vms_complete_munmap_vmas+0x679/0xe60 [ 714.581993][ C2] ? __mmap_new_vma+0xc55/0x11b0 [ 714.581994][ C2] ? perf_event_mmap+0xc7/0x4f0 [ 714.581997][ C2] ? __mmap_new_vma+0x11b0/0x11b0 [ 714.582000][ C2] ? vma_set_page_prot+0xa4/0x120 [ 714.582002][ C2] __mmap_region+0x749/0xc70 [ 714.582004][ C2] ? vma_merge_new_range+0xb30/0xb30 [ 714.582005][ C2] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 714.582007][ C2] ? rcu_read_lock_any_held+0x3c/0x90 [ 714.582009][ C2] ? __lock_acquire+0x508/0xc10 [ 714.582010][ C2] ? init_data_structures_once.part.0+0x1ca/0x450 [ 714.582016][ C2] ? mas_prev+0xae/0xc0 [ 714.582022][ C2] ? vm_unmapped_area+0x181/0x1f0 [ 714.582024][ C2] ? perf_trace_mm_lru_insertion+0x20/0x20 [ 714.582027][ C2] ? arch_get_unmapped_area_topdown+0xfa/0x870 [ 714.582030][ C2] mmap_region+0x26d/0x490 [ 714.582032][ C2] ? mm_take_all_locks+0xa50/0xa50 [ 714.582033][ C2] ? find_held_lock+0x2b/0x80 [ 714.582036][ C2] ? __get_unmapped_area+0x190/0x2c0 [ 714.582038][ C2] ? ovl_file_end_write+0x70/0x70 [ 714.582041][ C2] do_mmap+0x9cc/0xf80 [ 714.582044][ C2] ? __ia32_sys_brk+0x50/0x50 [ 714.582046][ C2] ? down_write_killable+0xcd/0x260 [ 714.582048][ C2] ? down_write+0x1f0/0x1f0 [ 714.582050][ C2] vm_mmap_pgoff+0x16c/0x320 [ 714.582052][ C2] ? randomize_page+0x80/0x80 [ 714.582054][ C2] ? __fget_files+0x1e3/0x460 [ 714.582057][ C2] ksys_mmap_pgoff+0x1ed/0x560 [ 714.582059][ C2] ? rcu_is_watching+0x15/0xd0 [ 714.582061][ C2] ? lockdep_hardirqs_on+0x8c/0x130 [ 714.582063][ C2] ? do_syscall_64+0x82/0x590 [ 714.582064][ C2] do_syscall_64+0x117/0x590 [ 714.582065][ C2] ? trace_hardirqs_off+0xd/0x30 [ 714.582068][ C2] ? exc_page_fault+0xee/0x100 [ 714.582070][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 714.582071][ C2] RIP: 0033:0x7f0aeba3e85c [ 714.582074][ C2] Code: 1e fa 41 f7 c1 ff 0f 00 00 75 33 55 48 89 e5 41 54 41 89 cc 53 48 89 fb 48 85 ff 74 51 45 89 e2 48 89 df b8 09 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 24 5b 41 5c 5d c3 0f 1f 80 00 00 00 00 c7 05 [ 714.582075][ C2] RSP: 002b:00007fffc9637d50 EFLAGS: 00000206 ORIG_RAX: 0000000000000009 [ 714.582077][ C2] RAX: ffffffffffffffda RBX: 00007f0aeb9d5000 RCX: 00007f0aeba3e85c [ 714.582077][ C2] RDX: 0000000000000003 RSI: 0000000000004000 RDI: 00007f0aeb9d5000 [ 714.582078][ C2] RBP: 00007fffc9637d60 R08: 0000000000000005 R09: 0000000000069000 [ 714.582079][ C2] R10: 0000000000000812 R11: 0000000000000206 R12: 0000000000000812 [ 714.582080][ C2] R13: 00007fffc9637de0 R14: 00007fffc9638210 R15: 00000000000684fc [ 714.582082][ C2] [ 719.796054][ T389] br0: port 2(veth2) entered disabled state [ 719.818481][ T390] br0: port 1(veth1) entered disabled state [ 719.837203][ T391] veth2: left allmulticast mode [ 719.837316][ T391] veth2: left promiscuous mode [ 719.837753][ T391] br0: port 2(veth2) entered disabled state [ 719.838221][ T391] veth1: left allmulticast mode [ 719.838311][ T391] veth1: left promiscuous mode [ 719.838449][ T391] br0: port 1(veth1) entered disabled state