[ 484.162504][T17476] br: port 1(veth1) entered blocking state [ 484.162848][T17476] br: port 1(veth1) entered disabled state [ 484.163033][T17476] veth1: entered allmulticast mode [ 484.172753][T17476] veth1: entered promiscuous mode [ 484.174974][ T36] br: port 1(veth1) entered blocking state [ 484.175200][ T36] br: port 1(veth1) entered forwarding state [ 484.231719][T17482] br: port 2(veth3) entered blocking state [ 484.232025][T17482] br: port 2(veth3) entered disabled state [ 484.232381][T17482] veth3: entered allmulticast mode [ 484.235371][T17482] veth3: entered promiscuous mode [ 484.237671][ T36] br: port 2(veth3) entered blocking state [ 484.237954][ T36] br: port 2(veth3) entered forwarding state [ 484.288473][T17487] br: port 3(veth4) entered blocking state [ 484.288668][T17487] br: port 3(veth4) entered disabled state [ 484.289041][T17487] veth4: entered allmulticast mode [ 484.291351][T17487] veth4: entered promiscuous mode [ 484.292868][ T36] br: port 3(veth4) entered blocking state [ 484.293039][ T36] br: port 3(veth4) entered forwarding state [ 563.026874][T17919] Mirror/redirect action on [ 576.464561][ C0] [ 576.464647][ C0] ================================ [ 576.464752][ C0] WARNING: inconsistent lock state [ 576.464853][ C0] 7.1.0-rc5-virtme #1 Not tainted [ 576.464944][ C0] -------------------------------- [ 576.465032][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 576.465149][ C0] jq/17992 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 576.465270][ C0] ffffffffc08af7b8 (mirred_list_lock){+.?.}-{3:3}, at: tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 576.465527][ C0] {SOFTIRQ-ON-W} state was registered at: [ 576.465651][ C0] __lock_acquire+0x378/0xc10 [ 576.465794][ C0] lock_acquire.part.0+0xbc/0x260 [ 576.465912][ C0] _raw_spin_lock+0x33/0x40 [ 576.466055][ C0] tcf_mirred_init+0x844/0xd70 [act_mirred] [ 576.466228][ C0] tcf_action_init_1+0x428/0x6b0 [ 576.466366][ C0] tcf_action_init+0x3cf/0x890 [ 576.466514][ C0] tcf_exts_validate_ex+0x411/0x670 [ 576.466656][ C0] mall_change+0x3cd/0xe9b [cls_matchall] [ 576.466836][ C0] tc_new_tfilter+0x875/0x21e0 [ 576.466982][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 576.467122][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 576.467256][ C0] netlink_unicast+0x486/0x750 [ 576.467399][ C0] netlink_sendmsg+0x735/0xc60 [ 576.467528][ C0] ____sys_sendmsg+0x419/0x850 [ 576.467659][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 576.467799][ C0] __sys_sendmsg+0x145/0x1f0 [ 576.467942][ C0] do_syscall_64+0x117/0x590 [ 576.468085][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 576.468270][ C0] irq event stamp: 8100 [ 576.468378][ C0] hardirqs last enabled at (8100): [] _raw_spin_unlock_irqrestore+0x53/0x80 [ 576.468662][ C0] hardirqs last disabled at (8099): [] _raw_spin_lock_irqsave+0x50/0x60 [ 576.468906][ C0] softirqs last enabled at (5508): [] handle_softirqs+0x611/0x940 [ 576.469158][ C0] softirqs last disabled at (7391): [] __irq_exit_rcu+0x103/0x1c0 [ 576.469403][ C0] [ 576.469403][ C0] other info that might help us debug this: [ 576.469612][ C0] Possible unsafe locking scenario: [ 576.469612][ C0] [ 576.469819][ C0] CPU0 [ 576.469924][ C0] ---- [ 576.470026][ C0] lock(mirred_list_lock); [ 576.470176][ C0] [ 576.470280][ C0] lock(mirred_list_lock); [ 576.470418][ C0] [ 576.470418][ C0] *** DEADLOCK *** [ 576.470418][ C0] [ 576.470627][ C0] 3 locks held by jq/17992: [ 576.470768][ C0] #0: ff1100000ce84b78 (&mm->mmap_lock){++++}-{4:4}, at: do_mprotect_pkey+0x227/0xc10 [ 576.471017][ C0] #1: ff1100000a06bc10 (&mapping->i_mmap_rwsem){++++}-{4:4}, at: vma_prepare+0x1b0/0x7f0 [ 576.471254][ C0] #2: ffffffff8b977c00 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x389/0x1020 [ 576.471495][ C0] [ 576.471495][ C0] stack backtrace: [ 576.471665][ C0] CPU: 0 UID: 0 PID: 17992 Comm: jq Not tainted 7.1.0-rc5-virtme #1 PREEMPT(full) [ 576.471669][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 576.471672][ C0] Call Trace: [ 576.471674][ C0] [ 576.471676][ C0] dump_stack_lvl+0x6f/0xa0 [ 576.471682][ C0] print_usage_bug.part.0.cold+0xa9/0xb7 [ 576.471685][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 576.471690][ C0] mark_lock_irq+0x4e3/0x9c0 [ 576.471693][ C0] ? sysvec_apic_timer_interrupt+0x9d/0xe0 [ 576.471696][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 576.471699][ C0] ? save_trace+0x68/0x280 [ 576.471701][ C0] ? __noinstr_text_start+0xce0/0xce0 [ 576.471705][ C0] mark_lock+0x1d7/0xa00 [ 576.471708][ C0] mark_usage+0x42/0x170 [ 576.471710][ C0] __lock_acquire+0x378/0xc10 [ 576.471712][ C0] ? mark_held_locks+0x40/0x70 [ 576.471714][ C0] ? rcu_do_batch+0x2b4/0x1020 [ 576.471718][ C0] lock_acquire.part.0+0xbc/0x260 [ 576.471721][ C0] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 576.471724][ C0] ? rcu_is_watching+0x15/0xd0 [ 576.471727][ C0] ? lock_acquire+0x134/0x160 [ 576.471730][ C0] _raw_spin_lock+0x33/0x40 [ 576.471733][ C0] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 576.471735][ C0] tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 576.471738][ C0] tcf_action_rcu_free+0x74/0x1a0 [ 576.471741][ C0] ? rcu_do_batch+0x2b4/0x1020 [ 576.471743][ C0] rcu_do_batch+0x2b6/0x1020 [ 576.471747][ C0] ? trace_rcu_batch_end+0x330/0x330 [ 576.471750][ C0] ? mark_held_locks+0x40/0x70 [ 576.471752][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 576.471755][ C0] ? lockdep_hardirqs_on+0x8c/0x130 [ 576.471758][ C0] rcu_core+0x2bf/0x640 [ 576.471760][ C0] handle_softirqs+0x1d8/0x940 [ 576.471763][ C0] ? find_held_lock+0x2b/0x80 [ 576.471767][ C0] ? __lock_release.isra.0+0x6b/0x1a0 [ 576.471769][ C0] ? _local_bh_enable+0xd0/0xd0 [ 576.471772][ C0] __irq_exit_rcu+0x103/0x1c0 [ 576.471774][ C0] irq_exit_rcu+0xe/0x30 [ 576.471777][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 576.471779][ C0] [ 576.471780][ C0] [ 576.471782][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 576.471784][ C0] RIP: 0010:__up_write+0xb4/0x520 [ 576.471788][ C0] Code: 34 04 48 39 5b 60 0f 85 4e 02 00 00 be 08 00 00 00 48 89 ef e8 9d a0 81 00 48 89 ea 48 b8 00 00 00 00 00 fc ff df 48 c1 ea 03 <80> 3c 02 00 0f 85 aa 03 00 00 48 8b 43 08 48 83 e0 fc 49 39 c4 0f [ 576.471790][ C0] RSP: 0018:ffa00000005f7790 EFLAGS: 00000212 [ 576.471794][ C0] RAX: dffffc0000000000 RBX: ff1100000a480158 RCX: 0000000000000001 [ 576.471796][ C0] RDX: 1fe220000149002c RSI: 0000000000000008 RDI: ff1100000a480160 [ 576.471798][ C0] RBP: ff1100000a480160 R08: ffffffff8872a4d3 R09: 1fe220000149002c [ 576.471799][ C0] R10: ffe21c000149002d R11: ffe21c000149002d R12: ff1100000cc48040 [ 576.471801][ C0] R13: ff1100000a4801b8 R14: ffffffff8c23fac0 R15: ffa00000005f78d0 [ 576.471803][ C0] ? __up_write+0xa3/0x520 [ 576.471808][ C0] vma_complete+0x365/0x1260 [ 576.471811][ C0] ? init_multi_vma_prep+0x2b/0x780 [ 576.471814][ C0] __split_vma+0x7fd/0xbc0 [ 576.471817][ C0] ? commit_merge+0xeb0/0xeb0 [ 576.471821][ C0] ? mark_usage+0x61/0x170 [ 576.471823][ C0] ? __lock_acquire+0x508/0xc10 [ 576.471826][ C0] vma_modify+0x315/0x430 [ 576.471829][ C0] vma_modify_flags+0x20c/0x3a0 [ 576.471831][ C0] ? do_raw_spin_lock+0x131/0x280 [ 576.471834][ C0] ? folio_add_file_rmap_ptes+0x574/0x760 [ 576.471837][ C0] ? do_vmi_munmap+0x2d0/0x2d0 [ 576.471839][ C0] ? folio_add_new_anon_rmap+0xb90/0xb90 [ 576.471844][ C0] ? filemap_map_pages+0x9d9/0xeb0 [ 576.471848][ C0] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 576.471851][ C0] ? rcu_read_lock_any_held+0x3c/0x90 [ 576.471853][ C0] ? validate_chain+0x38b/0xc20 [ 576.471856][ C0] mprotect_fixup+0x226/0xbd0 [ 576.471859][ C0] ? __lock_acquire+0x508/0xc10 [ 576.471861][ C0] ? change_protection+0x110/0x110 [ 576.471864][ C0] ? mas_prev_slot+0x31b/0x10e0 [ 576.471869][ C0] ? mas_next_slot+0x54a/0x1690 [ 576.471874][ C0] do_mprotect_pkey+0x5a5/0xc10 [ 576.471876][ C0] ? do_pte_missing+0x9ae/0xe40 [ 576.471881][ C0] ? mprotect_fixup+0xbd0/0xbd0 [ 576.471884][ C0] ? __pmd_alloc+0x840/0x840 [ 576.471886][ C0] ? lock_vma_under_rcu+0x159/0x410 [ 576.471895][ C0] __x64_sys_mprotect+0x78/0xd0 [ 576.471898][ C0] ? lockdep_hardirqs_on+0x8c/0x130 [ 576.471900][ C0] ? do_syscall_64+0x82/0x590 [ 576.471902][ C0] do_syscall_64+0x117/0x590 [ 576.471904][ C0] ? trace_hardirqs_off+0xd/0x30 [ 576.471908][ C0] ? exc_page_fault+0xee/0x100 [ 576.471910][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 576.471913][ C0] RIP: 0033:0x7faef6b7b8eb [ 576.471916][ C0] Code: 00 41 83 ca 40 0f 05 48 3d 00 f0 ff ff 76 94 f7 d8 89 05 c8 49 01 00 e9 72 ff ff ff 0f 1f 00 f3 0f 1e fa b8 0a 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8d 0d a5 49 01 00 f7 d8 89 01 48 83 [ 576.471918][ C0] RSP: 002b:00007ffeef055b88 EFLAGS: 00000206 ORIG_RAX: 000000000000000a [ 576.471921][ C0] RAX: ffffffffffffffda RBX: 00007faef6b4e270 RCX: 00007faef6b7b8eb [ 576.471922][ C0] RDX: 0000000000000001 RSI: 0000000000004000 RDI: 00007faef6a4d000 [ 576.471924][ C0] RBP: 00007ffeef055ba0 R08: 00007faef6a4d000 R09: 0000000000000000 [ 576.471925][ C0] R10: 00007faef6b4e270 R11: 0000000000000206 R12: 0000000000000001 [ 576.471926][ C0] R13: 0000000000000001 R14: 00007faef6b902e0 R15: 00007faef6b8fda0 [ 576.471931][ C0] [ 592.412240][T18097] br: port 3(veth4) entered disabled state [ 592.412822][T18097] veth4: left allmulticast mode [ 592.412916][T18097] veth4: left promiscuous mode [ 592.413059][T18097] br: port 3(veth4) entered disabled state [ 592.434747][T18099] br: port 2(veth3) entered disabled state [ 592.435008][T18099] veth3: left allmulticast mode [ 592.435098][T18099] veth3: left promiscuous mode [ 592.435232][T18099] br: port 2(veth3) entered disabled state [ 592.451560][T18101] br: port 1(veth1) entered disabled state [ 592.451813][T18101] veth1: left allmulticast mode [ 592.451905][T18101] veth1: left promiscuous mode [ 592.452037][T18101] br: port 1(veth1) entered disabled state