[ 15.850177][ T285] GACT probability NOT on [ 18.373150][ T507] Mirror/redirect action on [ 18.528760][ C0] [ 18.528840][ C0] ================================ [ 18.528940][ C0] WARNING: inconsistent lock state [ 18.529033][ C0] 7.1.0-rc5-virtme #1 Not tainted [ 18.529130][ C0] -------------------------------- [ 18.529220][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 18.529333][ C0] tc/515 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 18.529424][ C0] ffffffffc04b47b8 (mirred_list_lock){+.?.}-{3:3}, at: tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 18.529616][ C0] {SOFTIRQ-ON-W} state was registered at: [ 18.529717][ C0] __lock_acquire+0x378/0xc10 [ 18.529815][ C0] lock_acquire.part.0+0xbc/0x260 [ 18.529908][ C0] _raw_spin_lock+0x33/0x40 [ 18.530003][ C0] tcf_mirred_init+0x844/0xd70 [act_mirred] [ 18.530116][ C0] tcf_action_init_1+0x428/0x6b0 [ 18.530209][ C0] tcf_action_init+0x3cf/0x890 [ 18.530301][ C0] tcf_exts_validate_ex+0x411/0x670 [ 18.530393][ C0] fl_change+0xed4/0x4e77 [cls_flower] [ 18.530487][ C0] tc_new_tfilter+0x875/0x21e0 [ 18.530578][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 18.530671][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 18.530767][ C0] netlink_unicast+0x486/0x750 [ 18.530857][ C0] netlink_sendmsg+0x735/0xc60 [ 18.530948][ C0] ____sys_sendmsg+0x419/0x850 [ 18.531041][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 18.531133][ C0] __sys_sendmsg+0x145/0x1f0 [ 18.531225][ C0] do_syscall_64+0x117/0x590 [ 18.531319][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 18.531434][ C0] irq event stamp: 9664 [ 18.531504][ C0] hardirqs last enabled at (9664): [] _raw_spin_unlock_irqrestore+0x53/0x80 [ 18.531694][ C0] hardirqs last disabled at (9663): [] _raw_spin_lock_irqsave+0x50/0x60 [ 18.531854][ C0] softirqs last enabled at (6822): [] handle_softirqs+0x611/0x940 [ 18.532016][ C0] softirqs last disabled at (9457): [] __irq_exit_rcu+0x103/0x1c0 [ 18.532178][ C0] [ 18.532178][ C0] other info that might help us debug this: [ 18.532315][ C0] Possible unsafe locking scenario: [ 18.532315][ C0] [ 18.532451][ C0] CPU0 [ 18.532520][ C0] ---- [ 18.532589][ C0] lock(mirred_list_lock); [ 18.532687][ C0] [ 18.532757][ C0] lock(mirred_list_lock); [ 18.532848][ C0] [ 18.532848][ C0] *** DEADLOCK *** [ 18.532848][ C0] [ 18.532983][ C0] 2 locks held by tc/515: [ 18.533052][ C0] #0: ff1100000c48d778 (&mm->mmap_lock){++++}-{4:4}, at: do_mprotect_pkey+0x227/0xc10 [ 18.533213][ C0] #1: ffffffffb6f77c00 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x389/0x1020 [ 18.533377][ C0] [ 18.533377][ C0] stack backtrace: [ 18.533491][ C0] CPU: 0 UID: 0 PID: 515 Comm: tc Not tainted 7.1.0-rc5-virtme #1 PREEMPT(full) [ 18.533494][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 18.533496][ C0] Call Trace: [ 18.533498][ C0] [ 18.533499][ C0] dump_stack_lvl+0x6f/0xa0 [ 18.533504][ C0] print_usage_bug.part.0.cold+0xa9/0xb7 [ 18.533507][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 18.533510][ C0] mark_lock_irq+0x4e3/0x9c0 [ 18.533512][ C0] ? sysvec_apic_timer_interrupt+0x9d/0xe0 [ 18.533514][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 18.533516][ C0] ? save_trace+0x68/0x280 [ 18.533517][ C0] ? do_mmap+0xd30/0xf80 [ 18.533521][ C0] mark_lock+0x1d7/0xa00 [ 18.533522][ C0] mark_usage+0x42/0x170 [ 18.533524][ C0] __lock_acquire+0x378/0xc10 [ 18.533525][ C0] ? mark_held_locks+0x40/0x70 [ 18.533527][ C0] ? rcu_do_batch+0x2b4/0x1020 [ 18.533529][ C0] lock_acquire.part.0+0xbc/0x260 [ 18.533531][ C0] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 18.533533][ C0] ? rcu_is_watching+0x15/0xd0 [ 18.533535][ C0] ? lock_acquire+0x134/0x160 [ 18.533536][ C0] _raw_spin_lock+0x33/0x40 [ 18.533538][ C0] ? tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 18.533540][ C0] tcf_mirred_release+0x1d/0x2e0 [act_mirred] [ 18.533541][ C0] tcf_action_rcu_free+0x74/0x1a0 [ 18.533543][ C0] ? rcu_do_batch+0x2b4/0x1020 [ 18.533545][ C0] rcu_do_batch+0x2b6/0x1020 [ 18.533548][ C0] ? trace_rcu_batch_end+0x330/0x330 [ 18.533550][ C0] ? mark_held_locks+0x40/0x70 [ 18.533551][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 18.533553][ C0] ? lockdep_hardirqs_on+0x8c/0x130 [ 18.533555][ C0] rcu_core+0x2bf/0x640 [ 18.533557][ C0] handle_softirqs+0x1d8/0x940 [ 18.533558][ C0] ? find_held_lock+0x2b/0x80 [ 18.533561][ C0] ? __lock_release.isra.0+0x6b/0x1a0 [ 18.533563][ C0] ? _local_bh_enable+0xd0/0xd0 [ 18.533565][ C0] __irq_exit_rcu+0x103/0x1c0 [ 18.533566][ C0] irq_exit_rcu+0xe/0x30 [ 18.533568][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 18.533569][ C0] [ 18.533570][ C0] [ 18.533571][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 18.533572][ C0] RIP: 0010:_raw_spin_unlock_irqrestore+0x36/0x80 [ 18.533575][ C0] Code: f5 53 48 8b 74 24 10 48 89 fb 48 83 c7 18 e8 21 7e ca fd 48 89 df e8 a9 d4 ca fd f7 c5 00 02 00 00 75 1f 9c 58 f6 c4 02 75 2f 01 00 00 00 e8 30 62 bd fd 65 8b 05 29 0a fe 01 85 c0 74 12 5b [ 18.533577][ C0] RSP: 0018:ffa0000000ca7678 EFLAGS: 00000246 [ 18.533579][ C0] RAX: 0000000000000092 RBX: ffffffffb719a080 RCX: ffffffffb6090613 [ 18.533581][ C0] RDX: ff11000004fc8040 RSI: ffffffffb6a01bcb RDI: ffffffffb646f6c0 [ 18.533581][ C0] RBP: 0000000000000286 R08: 0000000000000000 R09: 0000000000000000 [ 18.533582][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: ff11000008d59f00 [ 18.533583][ C0] R13: 0000000000000286 R14: 0000000000000000 R15: 0000000000000001 [ 18.533585][ C0] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 18.533587][ C0] __create_object+0x78/0x110 [ 18.533590][ C0] kmem_cache_alloc_noprof+0x445/0x5f0 [ 18.533593][ C0] ? lock_acquire.part.0+0xbc/0x260 [ 18.533595][ C0] mas_preallocate+0x9d6/0x10f0 [ 18.533598][ C0] ? mark_held_locks+0x40/0x70 [ 18.533600][ C0] ? mas_wr_slot_store+0xc20/0xc20 [ 18.533602][ C0] ? lockdep_hardirqs_on+0x8c/0x130 [ 18.533604][ C0] ? kmem_cache_alloc_noprof+0x265/0x5f0 [ 18.533606][ C0] ? lockdep_init_map_type+0x5d/0x250 [ 18.533608][ C0] __split_vma+0x2f6/0xbc0 [ 18.533610][ C0] ? commit_merge+0xeb0/0xeb0 [ 18.533612][ C0] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 18.533614][ C0] ? rcu_read_lock_any_held+0x3c/0x90 [ 18.533616][ C0] ? validate_chain+0x38b/0xc20 [ 18.533618][ C0] vma_modify+0x315/0x430 [ 18.533619][ C0] vma_modify_flags+0x20c/0x3a0 [ 18.533621][ C0] ? do_vmi_munmap+0x2d0/0x2d0 [ 18.533623][ C0] ? do_raw_spin_lock+0x131/0x280 [ 18.533624][ C0] ? percpu_counter_add_batch+0x89/0x280 [ 18.533629][ C0] ? filemap_map_pages+0x9d9/0xeb0 [ 18.533632][ C0] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 18.533634][ C0] ? rcu_read_lock_any_held+0x3c/0x90 [ 18.533635][ C0] ? validate_chain+0x38b/0xc20 [ 18.533637][ C0] mprotect_fixup+0x226/0xbd0 [ 18.533639][ C0] ? __lock_acquire+0x508/0xc10 [ 18.533641][ C0] ? change_protection+0x110/0x110 [ 18.533642][ C0] ? mas_prev_slot+0x31b/0x10e0 [ 18.533645][ C0] ? mas_next_slot+0x54a/0x1690 [ 18.533649][ C0] do_mprotect_pkey+0x5a5/0xc10 [ 18.533650][ C0] ? do_pte_missing+0x9ae/0xe40 [ 18.533653][ C0] ? mprotect_fixup+0xbd0/0xbd0 [ 18.533655][ C0] ? __pmd_alloc+0x840/0x840 [ 18.533656][ C0] ? lock_vma_under_rcu+0x159/0x410 [ 18.533662][ C0] __x64_sys_mprotect+0x78/0xd0 [ 18.533664][ C0] ? lockdep_hardirqs_on+0x8c/0x130 [ 18.533665][ C0] ? do_syscall_64+0x82/0x590 [ 18.533667][ C0] do_syscall_64+0x117/0x590 [ 18.533668][ C0] ? trace_hardirqs_off+0xd/0x30 [ 18.533674][ C0] ? exc_page_fault+0xee/0x100 [ 18.533676][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 18.533677][ C0] RIP: 0033:0x7f9755d918eb [ 18.533679][ 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 [ 18.533680][ C0] RSP: 002b:00007ffc2de40c18 EFLAGS: 00000202 ORIG_RAX: 000000000000000a [ 18.533682][ C0] RAX: ffffffffffffffda RBX: 00007f9755c99a60 RCX: 00007f9755d918eb [ 18.533683][ C0] RDX: 0000000000000001 RSI: 0000000000004000 RDI: 00007f9755b67000 [ 18.533684][ C0] RBP: 00007ffc2de40c30 R08: 00007f9755b67000 R09: 0000000000000000 [ 18.533684][ C0] R10: 00007f9755c99a60 R11: 0000000000000202 R12: 0000000000000001 [ 18.533685][ C0] R13: 0000000000000001 R14: 00007f9755da62e0 R15: 00007f9755da5da0 [ 18.533687][ C0] [ 25.232869][ C3] BUG: sleeping function called from invalid context at kernel/locking/mutex.c:623 [ 25.233082][ C3] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/3 [ 25.233230][ C3] preempt_count: 101, expected: 0 [ 25.233318][ C3] RCU nest depth: 0, expected: 0 [ 25.233413][ C3] INFO: lockdep is turned off. [ 25.233494][ C3] Preemption disabled at: [ 25.233495][ C3] [<0000000000000000>] 0x0 [ 25.233654][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Not tainted 7.1.0-rc5-virtme #1 PREEMPT(full) [ 25.233658][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 25.233660][ C3] Call Trace: [ 25.233661][ C3] [ 25.233663][ C3] dump_stack_lvl+0x6f/0xa0 [ 25.233670][ C3] __might_resched.cold+0x20d/0x2d6 [ 25.233674][ C3] ? default_idle_call+0x6e/0xb0 [ 25.233678][ C3] ? perf_trace_sched_switch+0x7d0/0x7d0 [ 25.233682][ C3] ? start_secondary+0x204/0x2b0 [ 25.233685][ C3] __mutex_lock+0x118/0x2010 [ 25.233687][ C3] ? __tcf_chain_put+0x5f/0xb20 [ 25.233692][ C3] ? ww_mutex_lock+0x160/0x160 [ 25.233695][ C3] ? __tcf_chain_put+0x5f/0xb20 [ 25.233697][ C3] __tcf_chain_put+0x5f/0xb20 [ 25.233698][ C3] ? free_percpu.part.0+0x5f7/0xc80 [ 25.233702][ C3] tcf_action_rcu_free+0x14f/0x1a0 [ 25.233704][ C3] ? rcu_do_batch+0x2b4/0x1020 [ 25.233707][ C3] rcu_do_batch+0x2b6/0x1020 [ 25.233710][ C3] ? trace_rcu_batch_end+0x330/0x330 [ 25.233712][ C3] ? trace_irq_enable.constprop.0+0x9b/0x180 [ 25.233715][ C3] ? do_raw_spin_unlock+0x59/0x250 [ 25.233717][ C3] ? trace_hardirqs_on+0x36/0x40 [ 25.233719][ C3] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 25.233721][ C3] rcu_core+0x2bf/0x640 [ 25.233723][ C3] handle_softirqs+0x1d8/0x940 [ 25.233731][ C3] ? clockevents_tick_resume+0x60/0x60 [ 25.233734][ C3] ? _local_bh_enable+0xd0/0xd0 [ 25.233736][ C3] ? rcu_is_watching+0x15/0xd0 [ 25.233738][ C3] ? lock_release+0x17c/0x1f0 [ 25.233740][ C3] __irq_exit_rcu+0x103/0x1c0 [ 25.233741][ C3] irq_exit_rcu+0xe/0x30 [ 25.233743][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 25.233745][ C3] [ 25.233745][ C3] [ 25.233746][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 25.233749][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 25.233751][ C3] Code: 48 8b 3d 44 d3 00 02 e8 1f 00 00 00 48 2b 05 c8 71 a3 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 43 f4 22 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 25.233753][ C3] RSP: 0018:ffa0000000167de8 EFLAGS: 00000246 [ 25.233756][ C3] RAX: 0000000000000003 RBX: ff11000001bd0040 RCX: 0000000000000001 [ 25.233758][ C3] RDX: 0000000000000003 RSI: ffffffffb646f640 RDI: ffffffffb3cd9667 [ 25.233759][ C3] RBP: 0000000000000000 R08: ffffffffb60694f1 R09: 1fe2200006a7639a [ 25.233759][ C3] R10: 0000000000000003 R11: 0000000000000000 R12: 1ff400000002cfc0 [ 25.233760][ C3] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 25.233761][ C3] ? ct_kernel_exit.constprop.0+0x101/0x160 [ 25.233763][ C3] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 25.233766][ C3] default_idle+0x9/0x10 [ 25.233768][ C3] default_idle_call+0x6e/0xb0 [ 25.233770][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 25.233771][ C3] ? arch_cpu_idle_exit+0x40/0x40 [ 25.233773][ C3] ? mark_tsc_async_resets+0x30/0x30 [ 25.233775][ C3] ? trace_irq_enable.constprop.0+0x9b/0x180 [ 25.233776][ C3] ? rcu_is_watching+0x15/0xd0 [ 25.233778][ C3] do_idle+0xf5/0x160 [ 25.233780][ C3] cpu_startup_entry+0x53/0x70 [ 25.233781][ C3] start_secondary+0x204/0x2b0 [ 25.233783][ C3] ? set_cpu_sibling_map+0x1fb0/0x1fb0 [ 25.233785][ C3] common_startup_64+0x13e/0x148 [ 25.233789][ C3]