[ 13.654399][ T186] ip (186) used greatest stack depth: 24072 bytes left [ 16.346122][ T259] br1: port 1(veth1) entered blocking state [ 16.346537][ T259] br1: port 1(veth1) entered disabled state [ 16.346825][ T259] veth1: entered allmulticast mode [ 16.349631][ T259] veth1: entered promiscuous mode [ 16.404249][ T38] br1: port 1(veth1) entered blocking state [ 16.404628][ T38] br1: port 1(veth1) entered forwarding state [ 16.440199][ T261] br1: port 2(veth2) entered blocking state [ 16.440561][ T261] br1: port 2(veth2) entered disabled state [ 16.440953][ T261] veth2: entered allmulticast mode [ 16.444422][ T261] veth2: entered promiscuous mode [ 16.499515][ T36] br1: port 2(veth2) entered blocking state [ 16.499855][ T36] br1: port 2(veth2) entered forwarding state [ 16.638796][ T265] 8021q: 802.1Q VLAN Support v1.8 [ 17.042158][ T276] GACT probability NOT on [ 23.313496][ T320] Mirror/redirect action on [ 30.626061][ C2] [ 30.626180][ C2] ================================ [ 30.626330][ C2] WARNING: inconsistent lock state [ 30.626472][ C2] 7.1.0-rc5-virtme #1 Not tainted [ 30.626617][ C2] -------------------------------- [ 30.626771][ C2] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 30.626947][ C2] mirror_vlan.sh/172 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 30.627126][ C2] ffffffffc08117b8 (mirred_list_lock){+.?.}-{3:3}, at: tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 30.627424][ C2] {SOFTIRQ-ON-W} state was registered at: [ 30.627565][ C2] __lock_acquire+0x378/0xc10 [ 30.627727][ C2] lock_acquire.part.0+0xbc/0x260 [ 30.627870][ C2] _raw_spin_lock+0x33/0x40 [ 30.628016][ C2] tcf_mirred_init+0x844/0xd70 [act_mirred] [ 30.628192][ C2] tcf_action_init_1+0x428/0x6b0 [ 30.628334][ C2] tcf_action_init+0x3cf/0x890 [ 30.628475][ C2] tcf_exts_validate_ex+0x411/0x670 [ 30.628619][ C2] mall_change+0x3cd/0xe9b [cls_matchall] [ 30.628803][ C2] tc_new_tfilter+0x875/0x21e0 [ 30.628944][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 30.629088][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 30.629230][ C2] netlink_unicast+0x486/0x750 [ 30.629370][ C2] netlink_sendmsg+0x735/0xc60 [ 30.629507][ C2] ____sys_sendmsg+0x419/0x850 [ 30.629651][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 30.629800][ C2] __sys_sendmsg+0x145/0x1f0 [ 30.629942][ C2] do_syscall_64+0x117/0x590 [ 30.630086][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 30.630259][ C2] irq event stamp: 271098 [ 30.630366][ C2] hardirqs last enabled at (271098): [] _raw_spin_unlock_irqrestore+0x53/0x80 [ 30.630649][ C2] hardirqs last disabled at (271097): [] _raw_spin_lock_irqsave+0x50/0x60 [ 30.630931][ C2] softirqs last enabled at (270590): [] handle_softirqs+0x611/0x940 [ 30.631176][ C2] softirqs last disabled at (271089): [] __irq_exit_rcu+0x103/0x1c0 [ 30.631418][ C2] [ 30.631418][ C2] other info that might help us debug this: [ 30.631627][ C2] Possible unsafe locking scenario: [ 30.631627][ C2] [ 30.631845][ C2] CPU0 [ 30.631951][ C2] ---- [ 30.632056][ C2] lock(mirred_list_lock); [ 30.632195][ C2] [ 30.632301][ C2] lock(mirred_list_lock); [ 30.632443][ C2] [ 30.632443][ C2] *** DEADLOCK *** [ 30.632443][ C2] [ 30.632656][ C2] 2 locks held by mirror_vlan.sh/172: [ 30.632804][ C2] #0: ffffffffa67905f8 (remove_cache_srcu){.+.+}-{0:0}, at: kasan_quarantine_reduce+0x84/0x240 [ 30.633094][ C2] #1: ffffffffa6577c00 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x389/0x1020 [ 30.633345][ C2] [ 30.633345][ C2] stack backtrace: [ 30.633521][ C2] CPU: 2 UID: 0 PID: 172 Comm: mirror_vlan.sh Not tainted 7.1.0-rc5-virtme #1 PREEMPT(full) [ 30.633525][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 30.633528][ C2] Call Trace: [ 30.633530][ C2] [ 30.633531][ C2] dump_stack_lvl+0x6f/0xa0 [ 30.633538][ C2] print_usage_bug.part.0.cold+0xa9/0xb7 [ 30.633541][ C2] ? filter_irq_stacks+0xd0/0xd0 [ 30.633546][ C2] mark_lock_irq+0x4e3/0x9c0 [ 30.633549][ C2] ? trace_pelt_se_tp+0x9b/0x1b0 [ 30.633553][ C2] ? save_trace+0x68/0x280 [ 30.633557][ C2] mark_lock+0x1d7/0xa00 [ 30.633560][ C2] mark_usage+0x42/0x170 [ 30.633562][ C2] __lock_acquire+0x378/0xc10 [ 30.633565][ C2] ? mark_held_locks+0x40/0x70 [ 30.633568][ C2] lock_acquire.part.0+0xbc/0x260 [ 30.633571][ C2] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 30.633574][ C2] ? rcu_is_watching+0x15/0xd0 [ 30.633577][ C2] ? lock_acquire+0x134/0x160 [ 30.633580][ C2] _raw_spin_lock+0x33/0x40 [ 30.633583][ C2] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 30.633586][ C2] tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 30.633589][ C2] tcf_action_rcu_free+0x74/0x1a0 [ 30.633592][ C2] ? rcu_do_batch+0x2b4/0x1020 [ 30.633595][ C2] rcu_do_batch+0x2b6/0x1020 [ 30.633599][ C2] ? trace_rcu_batch_end+0x330/0x330 [ 30.633603][ C2] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 30.633605][ C2] ? lockdep_hardirqs_on+0x8c/0x130 [ 30.633608][ C2] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 30.633611][ C2] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 30.633614][ C2] rcu_core+0x2bf/0x640 [ 30.633617][ C2] handle_softirqs+0x1d8/0x940 [ 30.633620][ C2] ? find_held_lock+0x2b/0x80 [ 30.633625][ C2] ? __lock_release.isra.0+0x6b/0x1a0 [ 30.633628][ C2] ? _local_bh_enable+0xd0/0xd0 [ 30.633632][ C2] __irq_exit_rcu+0x103/0x1c0 [ 30.633635][ C2] irq_exit_rcu+0xe/0x30 [ 30.633637][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 30.633640][ C2] [ 30.633641][ C2] [ 30.633643][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 30.633645][ C2] RIP: 0010:lock_release+0xdc/0x1f0 [ 30.633649][ C2] Code: d1 33 04 83 f8 01 0f 85 fd 00 00 00 9c 58 f6 c4 02 0f 85 12 01 00 00 41 f7 c7 00 02 00 00 0f 84 bf 00 00 00 fb 4c 8b 7c 24 18 <48> 8b 5c 24 08 4c 8b 74 24 10 48 83 c4 20 c3 65 8b 05 42 8b 33 04 [ 30.633652][ C2] RSP: 0018:ffa00000008676a0 EFLAGS: 00000206 [ 30.633655][ C2] RAX: 0000000000000046 RBX: ffffffffa6577d20 RCX: 0000000000000001 [ 30.633657][ C2] RDX: 0000000000000001 RSI: ffffffffa5febde8 RDI: ffffffffa5a6f6c0 [ 30.633658][ C2] RBP: ffa00000008678c0 R08: 0000000000000001 R09: ffffffffa6ecee44 [ 30.633660][ C2] R10: 0000000000000002 R11: 0000000000000000 R12: ffa0000000867801 [ 30.633661][ C2] R13: ffa0000000867818 R14: ffffffffa314a6e1 R15: ffa0000000867818 [ 30.633663][ C2] ? unwind_next_frame+0x691/0x1ea0 [ 30.633670][ C2] unwind_next_frame+0x696/0x1ea0 [ 30.633672][ C2] ? arch_stack_walk+0xb3/0x130 [ 30.633677][ C2] ? stack_access_ok+0x1e0/0x1e0 [ 30.633681][ C2] ? get_stack_info_noinstr+0x1a/0x120 [ 30.633689][ C2] __unwind_start+0x432/0x910 [ 30.633692][ C2] ? write_profile+0xf0/0xf0 [ 30.633695][ C2] arch_stack_walk+0xb3/0x130 [ 30.633700][ C2] ? stack_trace_save+0x93/0xc0 [ 30.633703][ C2] stack_trace_save+0x93/0xc0 [ 30.633706][ C2] ? filter_irq_stacks+0xd0/0xd0 [ 30.633711][ C2] set_track_prepare+0x62/0x90 [ 30.633717][ C2] free_to_partial_list+0x1c1/0x300 [ 30.633720][ C2] ? qlist_free_all+0x57/0x110 [ 30.633724][ C2] qlist_free_all+0x5c/0x110 [ 30.633727][ C2] kasan_quarantine_reduce+0x196/0x240 [ 30.633730][ C2] __kasan_slab_alloc+0x4b/0x70 [ 30.633733][ C2] kmem_cache_alloc_node_noprof+0x224/0x640 [ 30.633737][ C2] dup_task_struct+0x2e/0x5e0 [ 30.633740][ C2] ? lockdep_hardirqs_on+0x8c/0x130 [ 30.633743][ C2] copy_process+0x3bd/0x58e0 [ 30.633746][ C2] ? restore_fpregs_from_user+0x490/0x490 [ 30.633750][ C2] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 30.633753][ C2] ? rcu_read_lock_any_held+0x3c/0x90 [ 30.633756][ C2] ? validate_chain+0x38b/0xc20 [ 30.633759][ C2] ? pidfd_prepare+0x160/0x160 [ 30.633762][ C2] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 30.633765][ C2] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 30.633768][ C2] ? rcu_read_lock_any_held+0x3c/0x90 [ 30.633771][ C2] kernel_clone+0x1a4/0x6e0 [ 30.633773][ C2] ? create_io_thread+0x130/0x130 [ 30.633777][ C2] ? __might_fault+0x97/0x140 [ 30.633780][ C2] ? find_held_lock+0x2b/0x80 [ 30.633784][ C2] __do_sys_clone+0xe3/0x130 [ 30.633787][ C2] ? kernel_clone+0x6e0/0x6e0 [ 30.633792][ C2] ? rcu_is_watching+0x15/0xd0 [ 30.633795][ C2] ? trace_irq_enable.constprop.0+0x9b/0x180 [ 30.633799][ C2] do_syscall_64+0x117/0x590 [ 30.633801][ C2] ? trace_hardirqs_off+0xd/0x30 [ 30.633803][ C2] ? exc_page_fault+0xee/0x100 [ 30.633806][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 30.633809][ C2] RIP: 0033:0x7fe09f1ac226 [ 30.633812][ C2] Code: 7d e0 e8 7d a4 f5 ff 45 31 c0 31 d2 31 f6 64 48 8b 04 25 10 00 00 00 bf 11 00 20 01 4c 8d 90 d0 02 00 00 b8 38 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 5a 89 c3 85 c0 75 2f 64 48 8b 04 25 10 00 00 [ 30.633814][ C2] RSP: 002b:00007fffa11f6520 EFLAGS: 00000246 ORIG_RAX: 0000000000000038 [ 30.633817][ C2] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fe09f1ac226 [ 30.633818][ C2] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000001200011 [ 30.633819][ C2] RBP: 00007fffa11f6540 R08: 0000000000000000 R09: 0000000000000030 [ 30.633821][ C2] R10: 00007fe09f0e9e50 R11: 0000000000000246 R12: 000055c9ebbd0ca0 [ 30.633822][ C2] R13: 000055c9ebbd72c0 R14: 0000000000000000 R15: 00000000ffffffff [ 30.633827][ C2] [ 50.979421][ T507] br1: port 1(veth1) entered disabled state [ 50.999816][ T508] br1: port 2(veth2) entered disabled state [ 51.018543][ T509] veth2: left allmulticast mode [ 51.018690][ T509] veth2: left promiscuous mode [ 51.018889][ T509] br1: port 2(veth2) entered disabled state [ 51.019683][ T509] veth1: left allmulticast mode [ 51.019807][ T509] veth1: left promiscuous mode [ 51.020006][ T509] br1: port 1(veth1) entered disabled state