[ 11.255006][ T250] gre: GRE over IPv4 demultiplexer driver [ 11.263125][ T250] ip_gre: GRE over IPv4 tunneling driver [ 11.499394][ T260] GACT probability NOT on [ 11.583233][ T262] ip6_gre: GRE over IPv6 tunneling driver [ 11.636781][ T10] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 11.680236][ T10] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 11.967998][ T276] br1: port 1(veth1) entered blocking state [ 11.968376][ T276] br1: port 1(veth1) entered disabled state [ 11.969016][ T276] veth1: entered allmulticast mode [ 11.970519][ T276] veth1: entered promiscuous mode [ 12.000835][ T38] br1: port 1(veth1) entered blocking state [ 12.001099][ T38] br1: port 1(veth1) entered forwarding state [ 12.021313][ T278] br1: port 2(veth2) entered blocking state [ 12.021530][ T278] br1: port 2(veth2) entered disabled state [ 12.022186][ T278] veth2: entered allmulticast mode [ 12.023910][ T278] veth2: entered promiscuous mode [ 12.046902][ T38] br1: port 2(veth2) entered blocking state [ 12.047082][ T38] br1: port 2(veth2) entered forwarding state [ 12.185700][ T10] ip6_tunnel: gt6 xmit: Local address not yet configured! [ 12.292847][ T12] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 12.298849][ T10] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 12.340745][ T10] ip6_tunnel: gt6 xmit: Local address not yet configured! [ 12.444710][ T12] ip6_tunnel: gt6 xmit: Local address not yet configured! [ 13.317824][ T12] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 13.318343][ T12] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 14.340844][ T10] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 17.092753][ C0] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 17.742787][ T320] Mirror/redirect action on [ 24.793590][ C0] [ 24.793681][ C0] ================================ [ 24.793797][ C0] WARNING: inconsistent lock state [ 24.793910][ C0] 7.1.0-rc5-virtme #1 Not tainted [ 24.794025][ C0] -------------------------------- [ 24.794134][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 24.794285][ C0] ip/368 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 24.794397][ C0] ffffffffc03367b8 (mirred_list_lock){+.?.}-{3:3}, at: tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 24.794621][ C0] {SOFTIRQ-ON-W} state was registered at: [ 24.794736][ C0] __lock_acquire+0x378/0xc10 [ 24.794850][ C0] lock_acquire.part.0+0xbc/0x260 [ 24.794962][ C0] _raw_spin_lock+0x33/0x40 [ 24.795076][ C0] tcf_mirred_init+0x844/0xd70 [act_mirred] [ 24.795210][ C0] tcf_action_init_1+0x428/0x6b0 [ 24.795323][ C0] tcf_action_init+0x3cf/0x890 [ 24.795437][ C0] tcf_exts_validate_ex+0x411/0x670 [ 24.795547][ C0] mall_change+0x3cd/0xe9b [cls_matchall] [ 24.795681][ C0] tc_new_tfilter+0x875/0x21e0 [ 24.795794][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 24.795908][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 24.796018][ C0] netlink_unicast+0x486/0x750 [ 24.796126][ C0] netlink_sendmsg+0x735/0xc60 [ 24.796235][ C0] ____sys_sendmsg+0x419/0x850 [ 24.796351][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 24.796460][ C0] __sys_sendmsg+0x145/0x1f0 [ 24.796574][ C0] do_syscall_64+0x117/0x590 [ 24.796684][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 24.796826][ C0] irq event stamp: 4934 [ 24.796908][ C0] hardirqs last enabled at (4934): [] _raw_spin_unlock_irqrestore+0x53/0x80 [ 24.797124][ C0] hardirqs last disabled at (4933): [] _raw_spin_lock_irqsave+0x50/0x60 [ 24.797316][ C0] softirqs last enabled at (3058): [] handle_softirqs+0x611/0x940 [ 24.797505][ C0] softirqs last disabled at (4907): [] __irq_exit_rcu+0x103/0x1c0 [ 24.797693][ C0] [ 24.797693][ C0] other info that might help us debug this: [ 24.797862][ C0] Possible unsafe locking scenario: [ 24.797862][ C0] [ 24.798025][ C0] CPU0 [ 24.798108][ C0] ---- [ 24.798190][ C0] lock(mirred_list_lock); [ 24.798304][ C0] [ 24.798386][ C0] lock(mirred_list_lock); [ 24.798494][ C0] [ 24.798494][ C0] *** DEADLOCK *** [ 24.798494][ C0] [ 24.798654][ C0] 3 locks held by ip/368: [ 24.798743][ C0] #0: ff11000005d785d8 (&fsvq->lock){+.+.}-{3:3}, at: virtio_fs_enqueue_req+0x5a2/0xfa0 [ 24.798938][ C0] #1: ffffffffadd77d20 (rcu_read_lock){....}-{1:3}, at: unwind_next_frame+0x17e/0x1ea0 [ 24.799136][ C0] #2: ffffffffadd77c00 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x389/0x1020 [ 24.799332][ C0] [ 24.799332][ C0] stack backtrace: [ 24.799476][ C0] CPU: 0 UID: 0 PID: 368 Comm: ip Not tainted 7.1.0-rc5-virtme #1 PREEMPT(full) [ 24.799479][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 24.799481][ C0] Call Trace: [ 24.799483][ C0] [ 24.799484][ C0] dump_stack_lvl+0x6f/0xa0 [ 24.799489][ C0] print_usage_bug.part.0.cold+0xa9/0xb7 [ 24.799492][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 24.799496][ C0] mark_lock_irq+0x4e3/0x9c0 [ 24.799497][ C0] ? sysvec_apic_timer_interrupt+0x9d/0xe0 [ 24.799500][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 24.799502][ C0] ? save_trace+0x68/0x280 [ 24.799503][ C0] ? migrate_vma_insert_page.isra.0+0x5d0/0x10d0 [ 24.799508][ C0] mark_lock+0x1d7/0xa00 [ 24.799509][ C0] mark_usage+0x42/0x170 [ 24.799511][ C0] __lock_acquire+0x378/0xc10 [ 24.799512][ C0] ? mark_held_locks+0x40/0x70 [ 24.799514][ C0] ? rcu_do_batch+0x2b4/0x1020 [ 24.799516][ C0] lock_acquire.part.0+0xbc/0x260 [ 24.799518][ C0] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 24.799520][ C0] ? rcu_is_watching+0x15/0xd0 [ 24.799522][ C0] ? lock_acquire+0x134/0x160 [ 24.799524][ C0] _raw_spin_lock+0x33/0x40 [ 24.799526][ C0] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 24.799527][ C0] tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 24.799529][ C0] tcf_action_rcu_free+0x74/0x1a0 [ 24.799531][ C0] ? rcu_do_batch+0x2b4/0x1020 [ 24.799533][ C0] rcu_do_batch+0x2b6/0x1020 [ 24.799535][ C0] ? trace_rcu_batch_end+0x330/0x330 [ 24.799538][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 24.799539][ C0] ? lockdep_hardirqs_on+0x8c/0x130 [ 24.799541][ C0] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 24.799542][ C0] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 24.799545][ C0] rcu_core+0x2bf/0x640 [ 24.799547][ C0] handle_softirqs+0x1d8/0x940 [ 24.799548][ C0] ? find_held_lock+0x2b/0x80 [ 24.799551][ C0] ? __lock_release.isra.0+0x6b/0x1a0 [ 24.799553][ C0] ? _local_bh_enable+0xd0/0xd0 [ 24.799555][ C0] __irq_exit_rcu+0x103/0x1c0 [ 24.799556][ C0] irq_exit_rcu+0xe/0x30 [ 24.799558][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 24.799560][ C0] [ 24.799560][ C0] [ 24.799561][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 24.799563][ C0] RIP: 0010:lock_acquire.part.0+0xfa/0x260 [ 24.799565][ 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 [ 24.799567][ C0] RSP: 0018:ffa0000000676b88 EFLAGS: 00000206 [ 24.799569][ C0] RAX: 0000000000000046 RBX: 0000000000000000 RCX: c61a23518730cab9 [ 24.799571][ C0] RDX: 0000000000000001 RSI: ffffffffad7ebde8 RDI: ffffffffad26f6c0 [ 24.799572][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ff110000117aae58 [ 24.799573][ C0] R10: 0000000000000200 R11: 0000000000000001 R12: ffffffffadd77d20 [ 24.799573][ C0] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000002 [ 24.799576][ C0] ? rcu_is_watching+0x15/0xd0 [ 24.799578][ C0] ? lock_acquire+0x134/0x160 [ 24.799580][ C0] unwind_next_frame+0x192/0x1ea0 [ 24.799581][ C0] ? unwind_next_frame+0x17e/0x1ea0 [ 24.799582][ C0] ? path_openat+0x1d0/0x3b0 [ 24.799586][ C0] ? stack_access_ok+0x1e0/0x1e0 [ 24.799587][ C0] ? mark_usage+0x61/0x170 [ 24.799589][ C0] ? __unwind_start+0x432/0x910 [ 24.799590][ C0] ? write_profile+0xf0/0xf0 [ 24.799593][ C0] arch_stack_walk+0xd7/0x130 [ 24.799597][ C0] ? path_openat+0x1d0/0x3b0 [ 24.799599][ C0] stack_trace_save+0x93/0xc0 [ 24.799601][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 24.799603][ C0] ? unwind_next_frame+0x69b/0x1ea0 [ 24.799605][ C0] kasan_save_stack+0x2f/0x50 [ 24.799608][ C0] ? kasan_save_stack+0x2f/0x50 [ 24.799610][ C0] ? kasan_save_track+0x14/0x30 [ 24.799611][ C0] ? __kasan_slab_alloc+0x60/0x70 [ 24.799613][ C0] ? kmem_cache_alloc_noprof+0x221/0x5f0 [ 24.799616][ C0] ? __alloc_object+0x30/0x250 [ 24.799617][ C0] ? __create_object+0x30/0x110 [ 24.799618][ C0] ? __kmalloc_noprof+0x502/0x760 [ 24.799620][ C0] ? virtqueue_add_split+0xca9/0x1df0 [ 24.799623][ C0] ? virtqueue_add_sgs+0x136/0x280 [ 24.799625][ C0] ? virtio_fs_enqueue_req+0x639/0xfa0 [ 24.799627][ C0] ? virtio_fs_send_req+0xf6/0x480 [ 24.799628][ C0] ? __fuse_simple_request+0x227/0xbb0 [ 24.799631][ C0] ? fuse_send_open+0x21e/0x350 [ 24.799632][ C0] ? fuse_file_open+0x31d/0x8f0 [ 24.799634][ C0] ? fuse_open+0x1b9/0x810 [ 24.799636][ C0] ? do_dentry_open+0x408/0x1310 [ 24.799638][ C0] ? vfs_open+0x7a/0x440 [ 24.799639][ C0] ? backing_file_open+0x4d/0x70 [ 24.799641][ C0] ? ovl_open_realfile+0x2bb/0x370 [ 24.799643][ C0] ? ovl_open+0x1ab/0x2f0 [ 24.799645][ C0] ? do_dentry_open+0x408/0x1310 [ 24.799646][ C0] ? vfs_open+0x7a/0x440 [ 24.799648][ C0] ? do_open+0x671/0xda0 [ 24.799650][ C0] ? path_openat+0x1d0/0x3b0 [ 24.799654][ C0] kasan_save_track+0x14/0x30 [ 24.799656][ C0] __kasan_slab_alloc+0x60/0x70 [ 24.799658][ C0] kmem_cache_alloc_noprof+0x221/0x5f0 [ 24.799660][ C0] ? ovl_open+0x150/0x2f0 [ 24.799661][ C0] ? vfs_open+0x7a/0x440 [ 24.799663][ C0] __alloc_object+0x30/0x250 [ 24.799665][ C0] ? virtqueue_add_split+0xca9/0x1df0 [ 24.799667][ C0] __create_object+0x30/0x110 [ 24.799668][ C0] ? virtqueue_add_split+0xca9/0x1df0 [ 24.799670][ C0] __kmalloc_noprof+0x502/0x760 [ 24.799672][ C0] virtqueue_add_split+0xca9/0x1df0 [ 24.799675][ C0] ? virtqueue_add_packed+0x16e0/0x16e0 [ 24.799677][ C0] ? __lock_acquire+0x508/0xc10 [ 24.799680][ C0] virtqueue_add_sgs+0x136/0x280 [ 24.799683][ C0] virtio_fs_enqueue_req+0x639/0xfa0 [ 24.799685][ C0] ? __lock_release.isra.0+0x6b/0x1a0 [ 24.799686][ C0] ? fuse_open+0x1b9/0x810 [ 24.799688][ C0] ? virtio_fs_probe+0x310/0x310 [ 24.799690][ C0] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 24.799692][ C0] ? rcu_is_watching+0x15/0xd0 [ 24.799694][ C0] ? __create_object+0x78/0x110 [ 24.799695][ C0] ? trace_kmem_cache_alloc+0xde/0x120 [ 24.799698][ C0] ? __rwlock_init+0x150/0x150 [ 24.799707][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 24.799709][ C0] virtio_fs_send_req+0xf6/0x480 [ 24.799711][ C0] __fuse_simple_request+0x227/0xbb0 [ 24.799713][ C0] fuse_send_open+0x21e/0x350 [ 24.799716][ C0] ? fuse_folios_need_send+0x440/0x440 [ 24.799719][ C0] ? __init_waitqueue_head+0xcb/0x160 [ 24.799722][ C0] ? fuse_file_alloc+0x1c1/0x2a0 [ 24.799724][ C0] fuse_file_open+0x31d/0x8f0 [ 24.799727][ C0] fuse_open+0x1b9/0x810 [ 24.799729][ C0] ? fuse_finish_open+0x2a0/0x2a0 [ 24.799731][ C0] do_dentry_open+0x408/0x1310 [ 24.799733][ C0] vfs_open+0x7a/0x440 [ 24.799735][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 24.799737][ C0] backing_file_open+0x4d/0x70 [ 24.799739][ C0] ovl_open_realfile+0x2bb/0x370 [ 24.799741][ C0] ovl_open+0x1ab/0x2f0 [ 24.799743][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 24.799745][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 24.799746][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 24.799748][ C0] do_dentry_open+0x408/0x1310 [ 24.799751][ C0] vfs_open+0x7a/0x440 [ 24.799753][ C0] ? may_open+0xe6/0x330 [ 24.799754][ C0] ? lookup_open.isra.0+0xea0/0xea0 [ 24.799756][ C0] do_open+0x671/0xda0 [ 24.799758][ C0] ? init_file.isra.0+0x30a/0x3e0 [ 24.799760][ C0] path_openat+0x1d0/0x3b0 [ 24.799761][ C0] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 24.799763][ C0] ? kernel_tmpfile_open+0x70/0x70 [ 24.799765][ C0] ? validate_chain+0x38b/0xc20 [ 24.799767][ C0] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 24.799769][ C0] do_file_open+0x209/0x480 [ 24.799771][ C0] ? path_openat+0x3b0/0x3b0 [ 24.799774][ C0] ? page_get_link_raw+0x70/0x70 [ 24.799776][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 24.799778][ C0] ? _raw_spin_unlock+0x2d/0x50 [ 24.799779][ C0] ? alloc_fd+0x24a/0x450 [ 24.799782][ C0] do_sys_openat2+0xde/0x170 [ 24.799784][ C0] ? build_open_flags+0x620/0x620 [ 24.799786][ C0] ? rcu_is_watching+0x15/0xd0 [ 24.799788][ C0] ? trace_irq_enable.constprop.0+0x9b/0x180 [ 24.799791][ C0] ? lockdep_hardirqs_on+0x8c/0x130 [ 24.799793][ C0] __x64_sys_openat+0x10e/0x210 [ 24.799795][ C0] ? fput_close_sync+0xde/0x1b0 [ 24.799797][ C0] ? __ia32_sys_open+0x1b0/0x1b0 [ 24.799799][ C0] ? fpregs_restore_userregs+0x14b/0x310 [ 24.799801][ C0] ? rcu_is_watching+0x15/0xd0 [ 24.799803][ C0] ? trace_irq_enable.constprop.0+0x9b/0x180 [ 24.799804][ C0] do_syscall_64+0x117/0x590 [ 24.799806][ C0] ? trace_hardirqs_off+0xd/0x30 [ 24.799807][ C0] ? exc_page_fault+0xee/0x100 [ 24.799809][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 24.799811][ C0] RIP: 0033:0x7f96c8386625 [ 24.799813][ C0] Code: 48 89 e5 48 89 55 e0 41 83 e2 40 75 28 89 f2 f7 d2 81 e2 00 00 41 00 74 1c 89 f2 bf 9c ff ff ff 48 89 c6 b8 01 01 00 00 0f 05 <48> 3d 00 f0 ff ff 77 23 5d c3 90 48 8d 4d 10 c7 45 b8 10 00 00 00 [ 24.799814][ C0] RSP: 002b:00007ffca0de8d90 EFLAGS: 00000206 ORIG_RAX: 0000000000000101 [ 24.799816][ C0] RAX: ffffffffffffffda RBX: 00007ffca0de9020 RCX: 00007f96c8386625 [ 24.799817][ C0] RDX: 0000000000080000 RSI: 00007ffca0de8e10 RDI: 00000000ffffff9c [ 24.799818][ C0] RBP: 00007ffca0de8d90 R08: 00007ffca0de9007 R09: 0000000000000000 [ 24.799818][ C0] R10: 0000000000000000 R11: 0000000000000206 R12: 00007ffca0de8e10 [ 24.799819][ C0] R13: 00007ffca0de8e10 R14: 00007f96c8358000 R15: 0000000000000000 [ 24.799822][ C0] [ 24.964786][ C0] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 39.812699][ C0] ip6_tunnel: h3-gt6 xmit: Local address not yet configured! [ 44.766311][ T527] br1: port 1(veth1) entered disabled state [ 44.779510][ T528] br1: port 2(veth2) entered disabled state [ 44.795779][ T529] veth2: left allmulticast mode [ 44.795886][ T529] veth2: left promiscuous mode [ 44.796022][ T529] br1: port 2(veth2) entered disabled state [ 44.796491][ T529] veth1: left allmulticast mode [ 44.796577][ T529] veth1: left promiscuous mode [ 44.796704][ T529] br1: port 1(veth1) entered disabled state