[ 566.077379][ T4510] ip_gre: GRE over IPv4 tunneling driver [ 566.355368][ T4529] lag: Mode changed to "loadbalance" [ 566.412411][ T4533] lag: Port device veth2 added [ 566.494349][ T4537] lag: Port device veth4 added [ 566.586068][ T4544] 8021q: adding VLAN 0 to HW filter on device lag [ 566.607464][ T4545] br1: port 1(lag) entered blocking state [ 566.607707][ T4545] br1: port 1(lag) entered disabled state [ 566.607982][ T4545] lag: entered allmulticast mode [ 566.608163][ T4545] veth2: entered allmulticast mode [ 566.608362][ T4545] veth4: entered allmulticast mode [ 566.611088][ T4545] veth2: entered promiscuous mode [ 566.611460][ T4545] veth4: entered promiscuous mode [ 566.633765][ T4546] br1: port 1(lag) entered blocking state [ 566.634031][ T4546] br1: port 1(lag) entered forwarding state [ 572.298501][ T4586] Mirror/redirect action on [ 586.197045][ T93] br1: port 1(lag) entered disabled state [ 591.524521][ T93] br1: port 1(lag) entered blocking state [ 591.524858][ T93] br1: port 1(lag) entered forwarding state [ 591.615815][ C0] [ 591.615930][ C0] ================================ [ 591.616078][ C0] WARNING: inconsistent lock state [ 591.616214][ C0] 7.1.0-rc5-virtme #1 Not tainted [ 591.616351][ C0] -------------------------------- [ 591.616483][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 591.616644][ C0] tc/4629 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 591.616778][ C0] ffffffffc03ae7b8 (mirred_list_lock){+.?.}-{3:3}, at: tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 591.617069][ C0] {SOFTIRQ-ON-W} state was registered at: [ 591.617205][ C0] __lock_acquire+0x378/0xc10 [ 591.617344][ C0] lock_acquire.part.0+0xbc/0x260 [ 591.617480][ C0] _raw_spin_lock+0x33/0x40 [ 591.617616][ C0] tcf_mirred_init+0x844/0xd70 [act_mirred] [ 591.617784][ C0] tcf_action_init_1+0x428/0x6b0 [ 591.617920][ C0] tcf_action_init+0x3cf/0x890 [ 591.618059][ C0] tcf_exts_validate_ex+0x411/0x670 [ 591.618194][ C0] fl_change+0xed4/0x4e77 [cls_flower] [ 591.618333][ C0] tc_new_tfilter+0x875/0x21e0 [ 591.618470][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 591.618608][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 591.618745][ C0] netlink_unicast+0x486/0x750 [ 591.618885][ C0] netlink_sendmsg+0x735/0xc60 [ 591.619031][ C0] ____sys_sendmsg+0x419/0x850 [ 591.619173][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 591.619313][ C0] __sys_sendmsg+0x145/0x1f0 [ 591.619454][ C0] do_syscall_64+0x117/0x590 [ 591.619592][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 591.619764][ C0] irq event stamp: 10310 [ 591.619869][ C0] hardirqs last enabled at (10310): [] _raw_spin_unlock_irqrestore+0x53/0x80 [ 591.620241][ C0] hardirqs last disabled at (10309): [] _raw_spin_lock_irqsave+0x50/0x60 [ 591.620519][ C0] softirqs last enabled at (9720): [] handle_softirqs+0x611/0x940 [ 591.620847][ C0] softirqs last disabled at (10071): [] __irq_exit_rcu+0x103/0x1c0 [ 591.621095][ C0] [ 591.621095][ C0] other info that might help us debug this: [ 591.621299][ C0] Possible unsafe locking scenario: [ 591.621299][ C0] [ 591.621591][ C0] CPU0 [ 591.621691][ C0] ---- [ 591.621789][ C0] lock(mirred_list_lock); [ 591.621926][ C0] [ 591.622125][ C0] lock(mirred_list_lock); [ 591.622261][ C0] [ 591.622261][ C0] *** DEADLOCK *** [ 591.622261][ C0] [ 591.622462][ C0] 4 locks held by tc/4629: [ 591.622603][ C0] #0: ff110000158ac608 (vm_lock){++++}-{0:0}, at: lock_vma_under_rcu+0x12a/0x410 [ 591.622955][ C0] #1: ff11000013294b78 (&mm->mmap_lock){++++}-{4:4}, at: __vmf_anon_prepare+0xce/0x2a0 [ 591.623204][ C0] #2: ffffffff97b77d20 (rcu_read_lock){....}-{1:3}, at: unwind_next_frame+0x17e/0x1ea0 [ 591.623541][ C0] #3: ffffffff97b77c00 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x389/0x1020 [ 591.623788][ C0] [ 591.623788][ C0] stack backtrace: [ 591.623961][ C0] CPU: 0 UID: 0 PID: 4629 Comm: tc Not tainted 7.1.0-rc5-virtme #1 PREEMPT(full) [ 591.623965][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 591.623968][ C0] Call Trace: [ 591.623969][ C0] [ 591.623971][ C0] dump_stack_lvl+0x6f/0xa0 [ 591.623977][ C0] print_usage_bug.part.0.cold+0xa9/0xb7 [ 591.623981][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 591.623986][ C0] mark_lock_irq+0x4e3/0x9c0 [ 591.623988][ C0] ? sysvec_apic_timer_interrupt+0x9d/0xe0 [ 591.623995][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 591.623998][ C0] ? save_trace+0x68/0x280 [ 591.624001][ C0] ? unlink_anon_vmas+0x690/0x900 [ 591.624006][ C0] mark_lock+0x1d7/0xa00 [ 591.624009][ C0] mark_usage+0x42/0x170 [ 591.624011][ C0] __lock_acquire+0x378/0xc10 [ 591.624014][ C0] ? mark_held_locks+0x40/0x70 [ 591.624016][ C0] ? rcu_do_batch+0x2b4/0x1020 [ 591.624020][ C0] lock_acquire.part.0+0xbc/0x260 [ 591.624023][ C0] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 591.624026][ C0] ? rcu_is_watching+0x15/0xd0 [ 591.624029][ C0] ? lock_acquire+0x134/0x160 [ 591.624032][ C0] _raw_spin_lock+0x33/0x40 [ 591.624035][ C0] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 591.624038][ C0] tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 591.624041][ C0] tcf_action_rcu_free+0x74/0x1a0 [ 591.624044][ C0] ? rcu_do_batch+0x2b4/0x1020 [ 591.624046][ C0] rcu_do_batch+0x2b6/0x1020 [ 591.624051][ C0] ? trace_rcu_batch_end+0x330/0x330 [ 591.624054][ C0] ? mark_held_locks+0x40/0x70 [ 591.624056][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 591.624059][ C0] ? lockdep_hardirqs_on+0x8c/0x130 [ 591.624062][ C0] rcu_core+0x2bf/0x640 [ 591.624065][ C0] handle_softirqs+0x1d8/0x940 [ 591.624068][ C0] ? find_held_lock+0x2b/0x80 [ 591.624072][ C0] ? __lock_release.isra.0+0x6b/0x1a0 [ 591.624075][ C0] ? _local_bh_enable+0xd0/0xd0 [ 591.624078][ C0] __irq_exit_rcu+0x103/0x1c0 [ 591.624080][ C0] irq_exit_rcu+0xe/0x30 [ 591.624083][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 591.624085][ C0] [ 591.624086][ C0] [ 591.624087][ C0] ? modify_user_hw_breakpoint_check+0x457/0x710 [ 591.624091][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 591.624094][ C0] RIP: 0010:lock_acquire.part.0+0xfa/0x260 [ 591.624097][ 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 [ 591.624100][ C0] RSP: 0000:ffa0000000697718 EFLAGS: 00000206 [ 591.624103][ C0] RAX: 0000000000000046 RBX: 0000000000000000 RCX: eb86d55252cb3d33 [ 591.624105][ C0] RDX: 0000000000000001 RSI: ffffffff975ebde8 RDI: ffffffff9706f6c0 [ 591.624107][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ff11000013a15180 [ 591.624108][ C0] R10: 0000000000000200 R11: 0000000000000001 R12: ffffffff97b77d20 [ 591.624109][ C0] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000002 [ 591.624114][ C0] ? rcu_is_watching+0x15/0xd0 [ 591.624117][ C0] ? lock_acquire+0x134/0x160 [ 591.624120][ C0] unwind_next_frame+0x192/0x1ea0 [ 591.624122][ C0] ? unwind_next_frame+0x17e/0x1ea0 [ 591.624124][ C0] ? __kasan_slab_alloc+0x60/0x70 [ 591.624128][ C0] ? stack_access_ok+0x1e0/0x1e0 [ 591.624132][ C0] ? __unwind_start+0x432/0x910 [ 591.624134][ C0] ? write_profile+0xf0/0xf0 [ 591.624137][ C0] arch_stack_walk+0xd7/0x130 [ 591.624143][ C0] ? __kasan_slab_alloc+0x60/0x70 [ 591.624146][ C0] stack_trace_save+0x93/0xc0 [ 591.624149][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 591.624152][ C0] ? is_bpf_text_address+0x72/0x110 [ 591.624156][ C0] ? kernel_text_address+0x149/0x170 [ 591.624159][ C0] ? __kernel_text_address+0x12/0x30 [ 591.624161][ C0] ? write_profile+0xf0/0xf0 [ 591.624164][ C0] kasan_save_stack+0x2f/0x50 [ 591.624167][ C0] ? kasan_save_stack+0x2f/0x50 [ 591.624170][ C0] ? kasan_save_track+0x14/0x30 [ 591.624173][ C0] ? __kasan_slab_alloc+0x60/0x70 [ 591.624182][ C0] kasan_save_track+0x14/0x30 [ 591.624185][ C0] __kasan_slab_alloc+0x60/0x70 [ 591.624187][ C0] kmem_cache_alloc_noprof+0x221/0x5f0 [ 591.624192][ C0] __anon_vma_prepare+0xa5/0x580 [ 591.624195][ C0] ? rcu_is_watching+0x15/0xd0 [ 591.624197][ C0] ? __lock_acquire+0x508/0xc10 [ 591.624200][ C0] __vmf_anon_prepare+0xf7/0x2a0 [ 591.624203][ C0] do_pte_missing+0x471/0xe40 [ 591.624205][ C0] ? rcu_read_unlock+0x1b/0x70 [ 591.624208][ C0] ? rcu_read_unlock+0x20/0x70 [ 591.624210][ C0] __handle_mm_fault+0x5ed/0x840 [ 591.624213][ C0] ? __pmd_alloc+0x840/0x840 [ 591.624216][ C0] ? lock_vma_under_rcu+0x159/0x410 [ 591.624218][ C0] ? __lock_release.isra.0+0xb3/0x1a0 [ 591.624224][ C0] handle_mm_fault+0x18b/0x4b0 [ 591.624227][ C0] ? rcu_is_watching+0x15/0xd0 [ 591.624230][ C0] do_user_addr_fault+0x472/0xe30 [ 591.624234][ C0] ? irqentry_enter+0x9f/0xd0 [ 591.624237][ C0] exc_page_fault+0x87/0x100 [ 591.624240][ C0] asm_exc_page_fault+0x26/0x30 [ 591.624242][ C0] RIP: 0033:0x7f9e797be9b0 [ 591.624245][ C0] Code: 30 c3 0f 1f 84 00 00 00 00 00 66 48 0f 7e c6 83 fa 08 7d 16 83 fa 04 7d 1a 83 fa 01 7f 1c 7c 03 40 88 37 c3 66 0f 1f 44 00 00 <48> 89 37 48 89 74 17 f8 c3 89 37 89 74 17 fc c3 66 89 37 40 88 74 [ 591.624247][ C0] RSP: 002b:00007fff298ac7d8 EFLAGS: 00010246 [ 591.624249][ C0] RAX: 00007f9e796ccff8 RBX: 00007f9e797907a0 RCX: 00007f9e796ccff8 [ 591.624251][ C0] RDX: 0000000000000008 RSI: 0000000000000000 RDI: 00007f9e796ccff8 [ 591.624252][ C0] RBP: 00007fff298acbb0 R08: 00007f9e796cd000 R09: 0000000000000003 [ 591.624253][ C0] R10: 00007f9e796cd000 R11: 0000000000000206 R12: 0000000000000003 [ 591.624255][ C0] R13: 00007fff298ac850 R14: 00007fff298acc50 R15: 00007f9e796cd008 [ 591.624259][ C0] [ 605.506169][ T93] br1: port 1(lag) entered disabled state [ 610.707766][ T36] br1: port 1(lag) entered blocking state [ 610.707944][ T36] br1: port 1(lag) entered forwarding state [ 610.841323][ T4694] lag: left allmulticast mode [ 610.841446][ T4694] veth2: left allmulticast mode [ 610.841542][ T4694] veth4: left allmulticast mode [ 610.841671][ T4694] br1: port 1(lag) entered disabled state [ 610.842484][ T4694] veth2: left promiscuous mode [ 610.842586][ T4694] veth4: left promiscuous mode [ 610.864289][ T4529] lag: Port device veth4 removed [ 610.865682][ T4529] lag: Port device veth2 removed