[ 8.640917][ T185] ip (185) used greatest stack depth: 23896 bytes left [ 10.011826][ T242] gre: GRE over IPv4 demultiplexer driver [ 10.025870][ T242] ip_gre: GRE over IPv4 tunneling driver [ 10.244350][ T252] GACT probability NOT on [ 10.323555][ T255] ip6_gre: GRE over IPv6 tunneling driver [ 10.380983][ T10] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 10.423233][ T10] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 10.518184][ T10] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 10.725295][ T270] br1: port 1(veth1) entered blocking state [ 10.725585][ T270] br1: port 1(veth1) entered disabled state [ 10.725884][ T270] veth1: entered allmulticast mode [ 10.727438][ T270] veth1: entered promiscuous mode [ 10.755442][ T39] br1: port 1(veth1) entered blocking state [ 10.755697][ T39] br1: port 1(veth1) entered forwarding state [ 10.786264][ T272] br1: port 2(veth2) entered blocking state [ 10.786458][ T272] br1: port 2(veth2) entered disabled state [ 10.786632][ T272] veth2: entered allmulticast mode [ 10.788466][ T272] veth2: entered promiscuous mode [ 10.811522][ T39] br1: port 2(veth2) entered blocking state [ 10.811720][ T39] br1: port 2(veth2) entered forwarding state [ 10.950221][ T10] ip6_tunnel: gt6 xmit: Local address not yet configured! [ 11.046218][ T12] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 11.750192][ T10] ip6_tunnel: gt6 xmit: Local address not yet configured! [ 11.814212][ T12] ip6_tunnel: gt6 xmit: Local address not yet configured! [ 12.070274][ T12] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 12.070668][ T12] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 12.838294][ T10] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 16.425231][ T313] Mirror/redirect action on [ 16.742278][ C0] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 23.222130][ C0] [ 23.222227][ C0] ================================ [ 23.222333][ C0] WARNING: inconsistent lock state [ 23.222429][ C0] 7.1.0-rc5-virtme #1 Not tainted [ 23.222525][ C0] -------------------------------- [ 23.222617][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 23.222730][ C0] mirror_gre.sh/171 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 23.222851][ C0] ffffffffc04cc7b8 (mirred_list_lock){+.?.}-{3:3}, at: tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 23.223043][ C0] {SOFTIRQ-ON-W} state was registered at: [ 23.223135][ C0] __lock_acquire+0x378/0xc10 [ 23.223233][ C0] lock_acquire.part.0+0xbc/0x260 [ 23.223323][ C0] _raw_spin_lock+0x33/0x40 [ 23.223416][ C0] tcf_mirred_init+0x844/0xd70 [act_mirred] [ 23.223529][ C0] tcf_action_init_1+0x428/0x6b0 [ 23.223621][ C0] tcf_action_init+0x3cf/0x890 [ 23.223712][ C0] tcf_exts_validate_ex+0x411/0x670 [ 23.223813][ C0] mall_change+0x3cd/0xe9b [cls_matchall] [ 23.223929][ C0] tc_new_tfilter+0x875/0x21e0 [ 23.224022][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 23.224119][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 23.224213][ C0] netlink_unicast+0x486/0x750 [ 23.224308][ C0] netlink_sendmsg+0x735/0xc60 [ 23.224402][ C0] ____sys_sendmsg+0x419/0x850 [ 23.224496][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 23.224590][ C0] __sys_sendmsg+0x145/0x1f0 [ 23.224681][ C0] do_syscall_64+0x117/0x590 [ 23.224771][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 23.224892][ C0] irq event stamp: 281234 [ 23.224962][ C0] hardirqs last enabled at (281234): [] _raw_spin_unlock_irqrestore+0x53/0x80 [ 23.225142][ C0] hardirqs last disabled at (281233): [] _raw_spin_lock_irqsave+0x50/0x60 [ 23.225320][ C0] softirqs last enabled at (281036): [] handle_softirqs+0x611/0x940 [ 23.225480][ C0] softirqs last disabled at (281089): [] __irq_exit_rcu+0x103/0x1c0 [ 23.225638][ C0] [ 23.225638][ C0] other info that might help us debug this: [ 23.225782][ C0] Possible unsafe locking scenario: [ 23.225782][ C0] [ 23.225917][ C0] CPU0 [ 23.225986][ C0] ---- [ 23.226055][ C0] lock(mirred_list_lock); [ 23.226148][ C0] [ 23.226216][ C0] lock(mirred_list_lock); [ 23.226307][ C0] [ 23.226307][ C0] *** DEADLOCK *** [ 23.226307][ C0] [ 23.226441][ C0] 5 locks held by mirror_gre.sh/171: [ 23.226534][ C0] #0: ffffffffaa6e5c10 (dup_mmap_sem){.+.+}-{0:0}, at: copy_process+0x5259/0x58e0 [ 23.226698][ C0] #1: ff1100000c153378 (&mm->mmap_lock){++++}-{4:4}, at: dup_mmap+0x15b/0x1a90 [ 23.226865][ C0] #2: ff1100000f6fbf78 (&mm->mmap_lock/1){+.+.}-{4:4}, at: dup_mmap+0x1c9/0x1a90 [ 23.227032][ C0] #3: ffffffffaa577d20 (rcu_read_lock){....}-{1:3}, at: unwind_next_frame+0x17e/0x1ea0 [ 23.227197][ C0] #4: ffffffffaa577c00 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x389/0x1020 [ 23.227359][ C0] [ 23.227359][ C0] stack backtrace: [ 23.227477][ C0] CPU: 0 UID: 0 PID: 171 Comm: mirror_gre.sh Not tainted 7.1.0-rc5-virtme #1 PREEMPT(full) [ 23.227479][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 23.227481][ C0] Call Trace: [ 23.227483][ C0] [ 23.227484][ C0] dump_stack_lvl+0x6f/0xa0 [ 23.227489][ C0] print_usage_bug.part.0.cold+0xa9/0xb7 [ 23.227492][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 23.227495][ C0] mark_lock_irq+0x4e3/0x9c0 [ 23.227497][ C0] ? sysvec_apic_timer_interrupt+0x9d/0xe0 [ 23.227499][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 23.227501][ C0] ? save_trace+0x68/0x280 [ 23.227503][ C0] ? get_user_pages_remote+0x520/0x810 [ 23.227506][ C0] mark_lock+0x1d7/0xa00 [ 23.227508][ C0] mark_usage+0x42/0x170 [ 23.227510][ C0] __lock_acquire+0x378/0xc10 [ 23.227511][ C0] ? mark_held_locks+0x40/0x70 [ 23.227513][ C0] ? rcu_do_batch+0x2b4/0x1020 [ 23.227516][ C0] lock_acquire.part.0+0xbc/0x260 [ 23.227517][ C0] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 23.227519][ C0] ? rcu_is_watching+0x15/0xd0 [ 23.227521][ C0] ? lock_acquire+0x134/0x160 [ 23.227523][ C0] _raw_spin_lock+0x33/0x40 [ 23.227525][ C0] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 23.227526][ C0] tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 23.227528][ C0] tcf_action_rcu_free+0x74/0x1a0 [ 23.227529][ C0] ? rcu_do_batch+0x2b4/0x1020 [ 23.227531][ C0] rcu_do_batch+0x2b6/0x1020 [ 23.227534][ C0] ? trace_rcu_batch_end+0x330/0x330 [ 23.227536][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 23.227537][ C0] ? lockdep_hardirqs_on+0x8c/0x130 [ 23.227539][ C0] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 23.227540][ C0] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 23.227542][ C0] rcu_core+0x2bf/0x640 [ 23.227544][ C0] handle_softirqs+0x1d8/0x940 [ 23.227546][ C0] ? find_held_lock+0x2b/0x80 [ 23.227549][ C0] ? __lock_release.isra.0+0x6b/0x1a0 [ 23.227550][ C0] ? _local_bh_enable+0xd0/0xd0 [ 23.227552][ C0] __irq_exit_rcu+0x103/0x1c0 [ 23.227554][ C0] irq_exit_rcu+0xe/0x30 [ 23.227555][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 23.227557][ C0] [ 23.227558][ C0] [ 23.227558][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 23.227560][ C0] RIP: 0010:lock_acquire.part.0+0xfa/0x260 [ 23.227563][ 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 [ 23.227564][ C0] RSP: 0018:ffa0000000857460 EFLAGS: 00000206 [ 23.227567][ C0] RAX: 0000000000000046 RBX: 0000000000000000 RCX: 32774272f7e67d87 [ 23.227568][ C0] RDX: 0000000000000001 RSI: ffffffffa9febde8 RDI: ffffffffa9a6f6c0 [ 23.227569][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ff1100000ca351a8 [ 23.227570][ C0] R10: 0000000000000200 R11: 0000000000000001 R12: ffffffffaa577d20 [ 23.227570][ C0] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000002 [ 23.227573][ C0] ? rcu_is_watching+0x15/0xd0 [ 23.227574][ C0] ? lock_acquire+0x134/0x160 [ 23.227576][ C0] unwind_next_frame+0x192/0x1ea0 [ 23.227577][ C0] ? unwind_next_frame+0x17e/0x1ea0 [ 23.227579][ C0] ? arch_stack_walk+0xb3/0x130 [ 23.227582][ C0] ? stack_access_ok+0x1e0/0x1e0 [ 23.227584][ C0] ? lock_acquire.part.0+0xbc/0x260 [ 23.227585][ C0] ? find_held_lock+0x2b/0x80 [ 23.227587][ C0] ? get_stack_info_noinstr+0x1a/0x120 [ 23.227589][ C0] __unwind_start+0x432/0x910 [ 23.227591][ C0] ? write_profile+0xf0/0xf0 [ 23.227593][ C0] arch_stack_walk+0xb3/0x130 [ 23.227595][ C0] ? arch_stack_walk+0xb3/0x130 [ 23.227598][ C0] stack_trace_save+0x93/0xc0 [ 23.227600][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 23.227602][ C0] ? __lock_release.isra.0+0x6b/0x1a0 [ 23.227603][ C0] ? check_object+0xc4/0x340 [ 23.227606][ C0] ? mark_held_locks+0x40/0x70 [ 23.227607][ C0] set_track_prepare+0x62/0x90 [ 23.227610][ C0] ___slab_alloc+0xcb/0x300 [ 23.227612][ C0] ? vm_area_dup+0x23/0x7f0 [ 23.227615][ C0] ? vm_area_dup+0x23/0x7f0 [ 23.227616][ C0] kmem_cache_alloc_noprof+0x1df/0x5f0 [ 23.227618][ C0] ? __vma_start_exclude_readers+0x670/0x6f0 [ 23.227621][ C0] vm_area_dup+0x23/0x7f0 [ 23.227623][ C0] dup_mmap+0x5fe/0x1a90 [ 23.227626][ C0] ? mmap_read_lock_maybe_expand+0x380/0x380 [ 23.227628][ C0] ? __lock_acquire+0x508/0xc10 [ 23.227629][ C0] ? rcu_read_lock_any_held+0x3c/0x90 [ 23.227631][ C0] ? validate_chain+0x38b/0xc20 [ 23.227632][ C0] ? lock_acquire.part.0+0xbc/0x260 [ 23.227634][ C0] ? find_held_lock+0x2b/0x80 [ 23.227636][ C0] ? lock_acquire.part.0+0xbc/0x260 [ 23.227638][ C0] ? copy_process+0x5259/0x58e0 [ 23.227639][ C0] ? rcu_is_watching+0x15/0xd0 [ 23.227642][ C0] copy_process+0x5268/0x58e0 [ 23.227643][ C0] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 23.227646][ C0] ? pidfd_prepare+0x160/0x160 [ 23.227649][ C0] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 23.227650][ C0] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 23.227652][ C0] ? rcu_read_lock_any_held+0x3c/0x90 [ 23.227654][ C0] kernel_clone+0x1a4/0x6e0 [ 23.227655][ C0] ? create_io_thread+0x130/0x130 [ 23.227658][ C0] ? __might_fault+0x97/0x140 [ 23.227659][ C0] ? find_held_lock+0x2b/0x80 [ 23.227662][ C0] __do_sys_clone+0xe3/0x130 [ 23.227663][ C0] ? kernel_clone+0x6e0/0x6e0 [ 23.227666][ C0] ? rcu_is_watching+0x15/0xd0 [ 23.227668][ C0] ? trace_irq_enable.constprop.0+0x9b/0x180 [ 23.227671][ C0] do_syscall_64+0x117/0x590 [ 23.227673][ C0] ? trace_hardirqs_off+0xd/0x30 [ 23.227674][ C0] ? exc_page_fault+0xee/0x100 [ 23.227676][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 23.227677][ C0] RIP: 0033:0x7f246847e226 [ 23.227680][ C0] 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 [ 23.227680][ C0] RSP: 002b:00007ffd38253f80 EFLAGS: 00000246 ORIG_RAX: 0000000000000038 [ 23.227682][ C0] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f246847e226 [ 23.227683][ C0] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000001200011 [ 23.227684][ C0] RBP: 00007ffd38253fa0 R08: 0000000000000000 R09: 0000000000000200 [ 23.227685][ C0] R10: 00007f24683bbe50 R11: 0000000000000246 R12: 00007ffd38254720 [ 23.227686][ C0] R13: 00000000ffffffff R14: 000000000000000c R15: 0000000000000000 [ 23.227688][ C0] [ 25.446288][ C0] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 42.342190][ C0] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 69.188523][ T743] br1: port 1(veth1) entered disabled state [ 69.207221][ T747] br1: port 2(veth2) entered disabled state [ 69.226644][ T748] veth2: left allmulticast mode [ 69.226755][ T748] veth2: left promiscuous mode [ 69.226894][ T748] br1: port 2(veth2) entered disabled state [ 69.231608][ T748] veth1: left allmulticast mode [ 69.231697][ T748] veth1: left promiscuous mode [ 69.231836][ T748] br1: port 1(veth1) entered disabled state