rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
(detected by 1, t=10502 jiffies, g=326265, q=286)
rcu: All QSes seen, last rcu_preempt kthread activity 10497 (4295168737-4295158240), jiffies_till_next_fqs=1, root ->qsmask 0x0
rcu: rcu_preempt kthread starved for 10498 jiffies! g326265 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
rcu: Unless rcu_preempt kthread gets sufficient CPU time, OOM is now expected behavior.
rcu: RCU grace-period kthread stack dump:
task:rcu_preempt state:R running task stack:28816 pid: 14 ppid: 2 flags:0x00004000
Call Trace:
context_switch kernel/sched/core.c:4339 [inline]
__schedule+0x916/0x23e0 kernel/sched/core.c:5147
schedule+0xcf/0x270 kernel/sched/core.c:5226
schedule_timeout+0x14a/0x250 kernel/time/timer.c:1892
rcu_gp_fqs_loop kernel/rcu/tree.c:2004 [inline]
rcu_gp_kthread+0xd07/0x2300 kernel/rcu/tree.c:2177
kthread+0x3b1/0x4a0 kernel/kthread.c:313
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:294
rcu: Stack dump where RCU GP kthread last ran:
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 7337 Comm: syz-executor.0 Not tainted 5.12.0-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:cpu_clock_event_read+0x0/0x50 kernel/events/core.c:10649
Code: 41 5c 41 5d e9 81 16 28 00 e8 fc ab 28 00 eb a0 48 89 ef e8 f2 ab 28 00 e9 6d ff ff ff e8 e8 ab 28 00 eb b9 66 0f 1f 44 00 00 <55> 53 48 89 fb e8 36 b4 e3 ff 65 8b 3d e7 86 70 7e e8 fa 1c c0 ff
RSP: 0018:ffffc90000007bf8 EFLAGS: 00000046
RAX: dffffc0000000000 RBX: 1ffff92000000f84 RCX: 0000000000000000
RDX: 1ffffffff1808429 RSI: ffffffff8191d889 RDI: ffff8880531c28b0
RBP: ffffc90000007e20 R08: 0000000000000001 R09: 0000000000000001
R10: ffffffff8191d87a R11: 0000000000000000 R12: ffff8880531c2a18
R13: ffffffff8c0420a0 R14: ffff8880531c28b0 R15: ffffffff8191d7c0
FS: 00007f1a1dcc4700(0000) GS:ffff8880b9c00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000000568000 CR3: 0000000079e9a000 CR4: 00000000001506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
perf_swevent_hrtimer+0x128/0x3f0 kernel/events/core.c:10533
__run_hrtimer kernel/time/hrtimer.c:1537 [inline]
__hrtimer_run_queues+0x1c0/0xe40 kernel/time/hrtimer.c:1601
hrtimer_interrupt+0x330/0xa00 kernel/time/hrtimer.c:1663
local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1089 [inline]
__sysvec_apic_timer_interrupt+0x146/0x540 arch/x86/kernel/apic/apic.c:1106
sysvec_apic_timer_interrupt+0x8e/0xc0 arch/x86/kernel/apic/apic.c:1100
asm_sysvec_apic_timer_interrupt+0x12/0x20 arch/x86/include/asm/idtentry.h:632
RIP: 0010:trylock_clear_pending kernel/locking/qspinlock_paravirt.h:121 [inline]
RIP: 0010:pv_wait_head_or_lock kernel/locking/qspinlock_paravirt.h:435 [inline]
RIP: 0010:__pv_queued_spin_lock_slowpath+0x3c4/0xb40 kernel/locking/qspinlock.c:508
Code: 00 48 c1 e9 03 83 e3 07 41 be 01 00 00 00 48 b8 00 00 00 00 00 fc ff df 4c 8d 2c 01 eb 0c f3 90 41 83 ec 01 0f 84 72 04 00 00 <41> 0f b6 45 00 38 d8 7f 08 84 c0 0f 85 91 05 00 00 0f b6 45 00 84
RSP: 0018:ffffc90001a4f730 EFLAGS: 00000206
RAX: 0000000000000003 RBX: 0000000000000000 RCX: 1ffffffff1812788
RDX: 0000000000000001 RSI: ffffffff817acfb1 RDI: ffffffff8132b571
RBP: ffffffff8c093c40 R08: 0000000000000001 R09: 0000000000000001
R10: ffffffff817acf79 R11: 1ffffffff17f0481 R12: 000000000000077b
R13: fffffbfff1812788 R14: 0000000000000001 R15: ffff8880b9c36000
pv_queued_spin_lock_slowpath arch/x86/include/asm/paravirt.h:585 [inline]
queued_spin_lock_slowpath arch/x86/include/asm/qspinlock.h:51 [inline]
queued_spin_lock include/asm-generic/qspinlock.h:85 [inline]
do_raw_spin_lock+0x200/0x2b0 kernel/locking/spinlock_debug.c:113
spin_lock include/linux/spinlock.h:354 [inline]
preload_this_cpu_lock mm/vmalloc.c:1450 [inline]
alloc_vmap_area+0xba3/0x1e10 mm/vmalloc.c:1491
__get_vm_area_node+0x128/0x380 mm/vmalloc.c:2370
__vmalloc_node_range+0x135/0x970 mm/vmalloc.c:2906
__vmalloc_node mm/vmalloc.c:2963 [inline]
__vmalloc+0x69/0x80 mm/vmalloc.c:2977
bpf_prog_alloc_no_stats+0x39/0x360 kernel/bpf/core.c:85
bpf_prog_alloc+0x28/0x1a0 kernel/bpf/core.c:119
bpf_prog_load+0x656/0x1f40 kernel/bpf/syscall.c:2164
__do_sys_bpf+0x1272/0x4f40 kernel/bpf/syscall.c:4410
do_syscall_64+0x3a/0xb0 arch/x86/entry/common.c:47
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665d9
Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 bc ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007f1a1dcc4188 EFLAGS: 00000246 ORIG_RAX: 0000000000000141
RAX: ffffffffffffffda RBX: 000000000056bf60 RCX: 00000000004665d9
RDX: 0000000000000048 RSI: 000000002000e000 RDI: 0000000000000005
RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf60
R13: 00007ffe15ae645f R14: 00007f1a1dcc4300 R15: 0000000000022000
================================
WARNING: inconsistent lock state
5.12.0-syzkaller #0 Not tainted
--------------------------------
inconsistent {IN-HARDIRQ-W} -> {HARDIRQ-ON-W} usage.
syz-executor.2/7336 [HC0[0]:SC1[1]:HE0:SE0] takes:
ffffffff8bf82358 (rcu_node_0){?.-.}-{2:2}, at: print_other_cpu_stall kernel/rcu/tree_stall.h:542 [inline]
ffffffff8bf82358 (rcu_node_0){?.-.}-{2:2}, at: check_cpu_stall kernel/rcu/tree_stall.h:708 [inline]
ffffffff8bf82358 (rcu_node_0){?.-.}-{2:2}, at: rcu_pending kernel/rcu/tree.c:3911 [inline]
ffffffff8bf82358 (rcu_node_0){?.-.}-{2:2}, at: rcu_sched_clock_irq+0xc63/0x2080 kernel/rcu/tree.c:2649
{IN-HARDIRQ-W} state was registered at:
lock_acquire kernel/locking/lockdep.c:5512 [inline]
lock_acquire+0x1ab/0x740 kernel/locking/lockdep.c:5477
__raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
_raw_spin_lock_irqsave+0x39/0x50 kernel/locking/spinlock.c:159
rcu_report_exp_cpu_mult+0x1c/0x280 kernel/rcu/tree_exp.h:237
flush_smp_call_function_queue+0x34b/0x640 kernel/smp.c:663
__sysvec_call_function_single+0x95/0x3d0 arch/x86/kernel/smp.c:248
sysvec_call_function_single+0x8e/0xc0 arch/x86/kernel/smp.c:243
asm_sysvec_call_function_single+0x12/0x20 arch/x86/include/asm/idtentry.h:640
lock_acquire+0x1ef/0x740 kernel/locking/lockdep.c:5480
down_write_killable+0x95/0x170 kernel/locking/rwsem.c:1417
mmap_write_lock_killable include/linux/mmap_lock.h:87 [inline]
__bprm_mm_init fs/exec.c:260 [inline]
bprm_mm_init fs/exec.c:379 [inline]
alloc_bprm+0x3be/0x8f0 fs/exec.c:1520
kernel_execve+0x55/0x460 fs/exec.c:1940
call_usermodehelper_exec_async+0x2e3/0x580 kernel/umh.c:112
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:294
irq event stamp: 9988909
hardirqs last enabled at (9988908): [] asm_sysvec_apic_timer_interrupt+0x12/0x20 arch/x86/include/asm/idtentry.h:632
hardirqs last disabled at (9988909): [] sysvec_apic_timer_interrupt+0xb/0xc0 arch/x86/kernel/apic/apic.c:1100
softirqs last enabled at (8499376): [] invoke_softirq kernel/softirq.c:433 [inline]
softirqs last enabled at (8499376): [] __irq_exit_rcu+0x136/0x200 kernel/softirq.c:637
softirqs last disabled at (8499379): [] invoke_softirq kernel/softirq.c:433 [inline]
softirqs last disabled at (8499379): [] __irq_exit_rcu+0x136/0x200 kernel/softirq.c:637
other info that might help us debug this:
Possible unsafe locking scenario:
CPU0
----
lock(rcu_node_0);
lock(rcu_node_0);
*** DEADLOCK ***
5 locks held by syz-executor.2/7336:
#0: ffffffff8c093a08 (vmap_purge_lock){+.+.}-{3:3}, at: _vm_unmap_aliases.part.0+0x36f/0x4f0 mm/vmalloc.c:2053
#1: ffffffff8c093c58 (free_vmap_area_lock){+.+.}-{2:2}, at: spin_lock include/linux/spinlock.h:354 [inline]
#1: ffffffff8c093c58 (free_vmap_area_lock){+.+.}-{2:2}, at: __cond_resched_lock+0xa0/0xf0 kernel/sched/core.c:7029
#2: ffffffff8bf79760 (rcu_read_lock){....}-{1:2}, at: ieee80211_iterate_active_interfaces_atomic+0x0/0x170 net/mac80211/util.c:1291
#3: ffffffff8bf79760 (rcu_read_lock){....}-{1:2}, at: __ieee80211_beacon_get+0x0/0x1aa0 net/mac80211/tx.c:4568
#4: ffffffff8bf82358 (rcu_node_0){?.-.}-{2:2}, at: print_other_cpu_stall kernel/rcu/tree_stall.h:542 [inline]
#4: ffffffff8bf82358 (rcu_node_0){?.-.}-{2:2}, at: check_cpu_stall kernel/rcu/tree_stall.h:708 [inline]
#4: ffffffff8bf82358 (rcu_node_0){?.-.}-{2:2}, at: rcu_pending kernel/rcu/tree.c:3911 [inline]
#4: ffffffff8bf82358 (rcu_node_0){?.-.}-{2:2}, at: rcu_sched_clock_irq+0xc63/0x2080 kernel/rcu/tree.c:2649
stack backtrace:
CPU: 1 PID: 7336 Comm: syz-executor.2 Not tainted 5.12.0-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
__dump_stack lib/dump_stack.c:79 [inline]
dump_stack+0x141/0x1d7 lib/dump_stack.c:120
print_usage_bug kernel/locking/lockdep.c:203 [inline]
valid_state kernel/locking/lockdep.c:3820 [inline]
mark_lock_irq kernel/locking/lockdep.c:4023 [inline]
mark_lock.cold+0x61/0x8e kernel/locking/lockdep.c:4480
mark_held_locks+0x9f/0xe0 kernel/locking/lockdep.c:4081
__trace_hardirqs_on_caller kernel/locking/lockdep.c:4099 [inline]
lockdep_hardirqs_on_prepare kernel/locking/lockdep.c:4167 [inline]
lockdep_hardirqs_on_prepare+0x135/0x400 kernel/locking/lockdep.c:4119
trace_hardirqs_on+0x5b/0x1c0 kernel/trace/trace_preemptirq.c:49
asm_sysvec_apic_timer_interrupt+0x12/0x20 arch/x86/include/asm/idtentry.h:632
RIP: 0010:orc_ip arch/x86/kernel/unwind_orc.c:30 [inline]
RIP: 0010:__orc_find+0x83/0xf0 arch/x86/kernel/unwind_orc.c:52
Code: 01 d0 48 d1 f8 48 8d 5c 85 00 48 89 d8 48 c1 e8 03 42 0f b6 14 38 48 89 d8 83 e0 07 83 c0 03 38 d0 7c 04 84 d2 75 48 48 63 03 <48> 01 d8 48 39 c1 73 b0 4c 8d 63 fc 49 39 ec 73 b3 4d 29 ee 49 c1
RSP: 0018:ffffc90000dc05e0 EFLAGS: 00000246
RAX: fffffffff35650e2 RBX: ffffffff8dd53ae4 RCX: ffffffff812b8b6b
RDX: 0000000000000000 RSI: ffffffff8e412846 RDI: ffffffff8dd53ad0
RBP: ffffffff8dd53ad0 R08: 0000000000000000 R09: ffffffff8e412846
R10: fffff520001b80f5 R11: 0000000000084087 R12: ffffffff8dd53af8
R13: ffffffff8dd53ad0 R14: ffffffff8dd53ad0 R15: dffffc0000000000
orc_find arch/x86/kernel/unwind_orc.c:173 [inline]
unwind_next_frame+0x32a/0x1ce0 arch/x86/kernel/unwind_orc.c:443
__unwind_start+0x51b/0x800 arch/x86/kernel/unwind_orc.c:699
unwind_start arch/x86/include/asm/unwind.h:60 [inline]
arch_stack_walk+0x5c/0xe0 arch/x86/kernel/stacktrace.c:24
stack_trace_save+0x8c/0xc0 kernel/stacktrace.c:121
kasan_save_stack+0x1b/0x40 mm/kasan/common.c:38
kasan_set_track mm/kasan/common.c:46 [inline]
set_alloc_info mm/kasan/common.c:428 [inline]
__kasan_slab_alloc+0x84/0xa0 mm/kasan/common.c:461
kasan_slab_alloc include/linux/kasan.h:236 [inline]
slab_post_alloc_hook mm/slab.h:524 [inline]
slab_alloc_node mm/slub.c:2912 [inline]
kmem_cache_alloc_node+0x269/0x3e0 mm/slub.c:2948
__alloc_skb+0x20b/0x340 net/core/skbuff.c:413
__netdev_alloc_skb+0x70/0x400 net/core/skbuff.c:492
netdev_alloc_skb include/linux/skbuff.h:2867 [inline]
dev_alloc_skb include/linux/skbuff.h:2880 [inline]
__ieee80211_beacon_get+0xebe/0x1aa0 net/mac80211/tx.c:4836
ieee80211_beacon_get_tim+0x88/0x910 net/mac80211/tx.c:4950
ieee80211_beacon_get include/net/mac80211.h:4938 [inline]
mac80211_hwsim_beacon_tx+0x111/0x910 drivers/net/wireless/mac80211_hwsim.c:1799
__iterate_interfaces+0x1e5/0x520 net/mac80211/util.c:793
ieee80211_iterate_active_interfaces_atomic+0x8d/0x170 net/mac80211/util.c:829
mac80211_hwsim_beacon+0xd5/0x1a0 drivers/net/wireless/mac80211_hwsim.c:1852
__run_hrtimer kernel/time/hrtimer.c:1537 [inline]
__hrtimer_run_queues+0x609/0xe40 kernel/time/hrtimer.c:1601
hrtimer_run_softirq+0x17b/0x360 kernel/time/hrtimer.c:1618
__do_softirq+0x29b/0x9f6 kernel/softirq.c:559
invoke_softirq kernel/softirq.c:433 [inline]
__irq_exit_rcu+0x136/0x200 kernel/softirq.c:637
irq_exit_rcu+0x5/0x20 kernel/softirq.c:649
sysvec_apic_timer_interrupt+0x93/0xc0 arch/x86/kernel/apic/apic.c:1100
asm_sysvec_apic_timer_interrupt+0x12/0x20 arch/x86/include/asm/idtentry.h:632
RIP: 0010:check_kcov_mode kernel/kcov.c:163 [inline]
RIP: 0010:__sanitizer_cov_trace_pc+0x1c/0x60 kernel/kcov.c:197
Code: 45 00 48 63 c9 e9 64 ff ff ff 0f 1f 40 00 65 8b 05 09 2d 8d 7e 89 c1 48 8b 34 24 81 e1 00 01 00 00 65 48 8b 14 25 00 f0 01 00 00 01 ff 00 74 0e 85 c9 74 35 8b 82 3c 15 00 00 85 c0 74 2b 8b
RSP: 0018:ffffc90001daf6b8 EFLAGS: 00000246
RAX: 0000000080000001 RBX: ffff888028162370 RCX: 0000000000000000
RDX: ffff88801c793880 RSI: ffffffff81ae562a RDI: 0000000000000003
RBP: ffff888028162378 R08: ffffc90001071000 R09: ffffffff8c093c43
R10: ffffffff81ae5617 R11: 0000000000000000 R12: ffffc90001071000
R13: ffffc9000106d000 R14: 0000000000000000 R15: dffffc0000000000
merge_or_add_vmap_area mm/vmalloc.c:1076 [inline]
merge_or_add_vmap_area_augment mm/vmalloc.c:1111 [inline]
__purge_vmap_area_lazy+0x4fa/0x1c50 mm/vmalloc.c:1645
_vm_unmap_aliases.part.0+0x3dd/0x4f0 mm/vmalloc.c:2055
_vm_unmap_aliases mm/vmalloc.c:2029 [inline]
vm_unmap_aliases+0x47/0x50 mm/vmalloc.c:2078
change_page_attr_set_clr+0x241/0x500 arch/x86/mm/pat/set_memory.c:1740
change_page_attr_clear arch/x86/mm/pat/set_memory.c:1797 [inline]
set_memory_ro+0x78/0xa0 arch/x86/mm/pat/set_memory.c:1943
bpf_jit_binary_lock_ro include/linux/filter.h:852 [inline]
bpf_int_jit_compile+0xe11/0x11b0 arch/x86/net/bpf_jit_comp.c:2329
bpf_prog_select_runtime+0x464/0x6a0 kernel/bpf/core.c:1867
bpf_prog_load+0xe75/0x1f40 kernel/bpf/syscall.c:2220
__do_sys_bpf+0x1272/0x4f40 kernel/bpf/syscall.c:4410
do_syscall_64+0x3a/0xb0 arch/x86/entry/common.c:47
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665d9
Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 bc ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007fa796c0d188 EFLAGS: 00000246 ORIG_RAX: 0000000000000141
RAX: ffffffffffffffda RBX: 000000000056bf60 RCX: 00000000004665d9
RDX: 0000000000000048 RSI: 000000002000e000 RDI: 0000000000000005
RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf60
R13: 00007fffdc49faff R14: 00007fa796c0d300 R15: 0000000000022000
softirq: huh, entered softirq 8 HRTIMER ffffffff8164b050 with preempt_count 00000101, exited with 00000102?