rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
(detected by 0, t=10502 jiffies, g=317313, q=1)
rcu: All QSes seen, last rcu_preempt kthread activity 10500 (4295117168-4295106668), jiffies_till_next_fqs=1, root ->qsmask 0x0
rcu: rcu_preempt kthread starved for 10500 jiffies! g317313 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=1
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:28560 pid: 14 ppid: 2 flags:0x00004000
Call Trace:
context_switch kernel/sched/core.c:4322 [inline]
__schedule+0x911/0x21b0 kernel/sched/core.c:5073
schedule+0xcf/0x270 kernel/sched/core.c:5152
schedule_timeout+0x14a/0x250 kernel/time/timer.c:1892
rcu_gp_fqs_loop kernel/rcu/tree.c:2005 [inline]
rcu_gp_kthread+0xd07/0x2250 kernel/rcu/tree.c:2178
kthread+0x3b1/0x4a0 kernel/kthread.c:292
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 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 9079 Comm: syz-executor.2 Not tainted 5.12.0-rc4-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:__this_cpu_preempt_check+0x0/0x20 lib/smp_processor_id.c:69
Code: 2e 0f 1f 84 00 00 00 00 00 90 0f 1f 44 00 00 48 c7 c6 40 1a c2 89 48 c7 c7 80 1a c2 89 e9 98 fe ff ff 0f 1f 84 00 00 00 00 00 <55> 48 89 fd 0f 1f 44 00 00 48 89 ee 5d 48 c7 c7 c0 1a c2 89 e9 77
RSP: 0018:ffffc90000dc0d30 EFLAGS: 00000046
RAX: 0000000000000000 RBX: 0000000000000001 RCX: 0000000000000001
RDX: 0000000000000000 RSI: ffffffff8bf742a0 RDI: ffffffff896b97e0
RBP: ffffffff8bf742a0 R08: 0000000000000000 R09: ffffffff8dc5204f
R10: fffffbfff1b8a409 R11: 0000000000000000 R12: ffff888020460000
R13: 0000000000000000 R14: 00000000ffffffff R15: ffff8880204609f0
FS: 00007f90e868a700(0000) GS:ffff8880b9d00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000000513110 CR3: 000000006f6de000 CR4: 00000000001506e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
lockdep_recursion_finish kernel/locking/lockdep.c:437 [inline]
lock_is_held_type+0xd5/0x140 kernel/locking/lockdep.c:5550
lock_is_held include/linux/lockdep.h:278 [inline]
rcu_read_lock_sched_held+0x3a/0x70 kernel/rcu/update.c:125
trace_lock_acquire include/trace/events/lock.h:13 [inline]
lock_acquire+0x5e9/0x740 kernel/locking/lockdep.c:5481
seqcount_lockdep_reader_access include/linux/seqlock.h:103 [inline]
ktime_get+0x7c/0x470 kernel/time/timekeeping.c:827
clockevents_program_event+0x141/0x370 kernel/time/clockevents.c:326
tick_program_event+0xac/0x140 kernel/time/tick-oneshot.c:44
hrtimer_interrupt+0x414/0xa00 kernel/time/hrtimer.c:1676
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:pv_wait_head_or_lock kernel/locking/qspinlock_paravirt.h:434 [inline]
RIP: 0010:__pv_queued_spin_lock_slowpath+0x3ba/0xb40 kernel/locking/qspinlock.c:508
Code: eb c6 45 01 01 41 bc 00 80 00 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
RSP: 0018:ffffc9000194f670 EFLAGS: 00000206
RAX: 0000000000000003 RBX: 0000000000000000 RCX: 1ffffffff1811040
RDX: 0000000000000001 RSI: ffffffff8179e141 RDI: ffffffff813214c1
RBP: ffffffff8c088200 R08: 0000000000000001 R09: 0000000000000001
R10: ffffffff8179e109 R11: 0000000000000000 R12: 00000000000049bc
R13: fffffbfff1811040 R14: 0000000000000001 R15: ffff8880b9d35f40
pv_queued_spin_lock_slowpath arch/x86/include/asm/paravirt.h:554 [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]
alloc_vmap_area+0x1ba/0x1e10 mm/vmalloc.c:1215
__get_vm_area_node+0x128/0x380 mm/vmalloc.c:2096
__vmalloc_node_range mm/vmalloc.c:2571 [inline]
__vmalloc_node mm/vmalloc.c:2619 [inline]
vzalloc+0xf2/0x1a0 mm/vmalloc.c:2672
bpf_check+0x197/0xc850 kernel/bpf/verifier.c:12574
bpf_prog_load+0xe47/0x1f40 kernel/bpf/syscall.c:2214
__do_sys_bpf+0x1272/0x4f40 kernel/bpf/syscall.c:4393
do_syscall_64+0x2d/0x70 arch/x86/entry/common.c:46
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x466459
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:00007f90e868a188 EFLAGS: 00000246 ORIG_RAX: 0000000000000141
RAX: ffffffffffffffda RBX: 000000000056bf60 RCX: 0000000000466459
RDX: 0000000000000048 RSI: 0000000020000200 RDI: 0000000000000005
RBP: 00000000004bf9fb R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf60
R13: 00007ffe339ee97f R14: 00007f90e868a300 R15: 0000000000022000
================================
WARNING: inconsistent lock state
5.12.0-rc4-syzkaller #0 Not tainted
--------------------------------
inconsistent {IN-HARDIRQ-W} -> {HARDIRQ-ON-W} usage.
syz-executor.4/9076 [HC0[0]:SC1[1]:HE0:SE0] takes:
ffffffff8bf7c918 (rcu_node_0){?.-.}-{2:2}, at: print_other_cpu_stall kernel/rcu/tree_stall.h:541 [inline]
ffffffff8bf7c918 (rcu_node_0){?.-.}-{2:2}, at: check_cpu_stall kernel/rcu/tree_stall.h:706 [inline]
ffffffff8bf7c918 (rcu_node_0){?.-.}-{2:2}, at: rcu_pending kernel/rcu/tree.c:3830 [inline]
ffffffff8bf7c918 (rcu_node_0){?.-.}-{2:2}, at: rcu_sched_clock_irq.cold+0x2b9/0x11dd kernel/rcu/tree.c:2650
{IN-HARDIRQ-W} state was registered at:
lock_acquire kernel/locking/lockdep.c:5510 [inline]
lock_acquire+0x1ab/0x740 kernel/locking/lockdep.c:5475
__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+0x73/0x3a0 kernel/rcu/tree_exp.h:237
flush_smp_call_function_queue+0x34b/0x640 kernel/smp.c:426
__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
get_page_from_freelist+0x9fb/0x3fb0 mm/page_alloc.c:3884
__alloc_pages_nodemask+0x2d6/0x730 mm/page_alloc.c:5001
alloc_pages_current+0x18c/0x2a0 mm/mempolicy.c:2277
alloc_pages include/linux/gfp.h:561 [inline]
alloc_slab_page mm/slub.c:1638 [inline]
allocate_slab+0x2c5/0x4c0 mm/slub.c:1778
new_slab mm/slub.c:1841 [inline]
new_slab_objects mm/slub.c:2587 [inline]
___slab_alloc+0x44c/0x7a0 mm/slub.c:2750
__slab_alloc.constprop.0+0xa7/0xf0 mm/slub.c:2790
slab_alloc_node mm/slub.c:2871 [inline]
slab_alloc mm/slub.c:2915 [inline]
kmem_cache_alloc+0x324/0x370 mm/slub.c:2920
kmem_cache_zalloc include/linux/slab.h:674 [inline]
__alloc_file+0x21/0x280 fs/file_table.c:101
alloc_empty_file+0x6d/0x170 fs/file_table.c:150
path_openat+0xe3/0x27e0 fs/namei.c:3484
do_filp_open+0x17e/0x3c0 fs/namei.c:3525
do_open_execat+0x116/0x690 fs/exec.c:913
bprm_execve fs/exec.c:1805 [inline]
bprm_execve+0x4be/0x19a0 fs/exec.c:1792
kernel_execve+0x370/0x460 fs/exec.c:1973
call_usermodehelper_exec_async+0x2de/0x580 kernel/umh.c:110
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:294
irq event stamp: 9763261
hardirqs last enabled at (9763260): [] asm_sysvec_apic_timer_interrupt+0x12/0x20 arch/x86/include/asm/idtentry.h:632
hardirqs last disabled at (9763261): [] sysvec_apic_timer_interrupt+0xb/0xc0 arch/x86/kernel/apic/apic.c:1100
softirqs last enabled at (5890522): [] invoke_softirq kernel/softirq.c:221 [inline]
softirqs last enabled at (5890522): [] __irq_exit_rcu kernel/softirq.c:422 [inline]
softirqs last enabled at (5890522): [] irq_exit_rcu+0x134/0x200 kernel/softirq.c:434
softirqs last disabled at (5890525): [] invoke_softirq kernel/softirq.c:221 [inline]
softirqs last disabled at (5890525): [] __irq_exit_rcu kernel/softirq.c:422 [inline]
softirqs last disabled at (5890525): [] irq_exit_rcu+0x134/0x200 kernel/softirq.c:434
other info that might help us debug this:
Possible unsafe locking scenario:
CPU0
----
lock(rcu_node_0);
lock(rcu_node_0);
*** DEADLOCK ***
4 locks held by syz-executor.4/9076:
#0: ffffffff8c087fc8 (vmap_purge_lock){+.+.}-{3:3}, at: _vm_unmap_aliases.part.0+0x368/0x4e0 mm/vmalloc.c:1782
#1: ffffffff8bf74360 (rcu_read_lock){....}-{1:2}, at: ieee80211_iterate_active_interfaces_atomic+0x0/0x170 net/mac80211/util.c:1299
#2: ffffffff90130f98 (&hwsim_radio_lock){+.-.}-{2:2}, at: spin_lock include/linux/spinlock.h:354 [inline]
#2: ffffffff90130f98 (&hwsim_radio_lock){+.-.}-{2:2}, at: mac80211_hwsim_tx_frame_no_nl.isra.0+0x68f/0x1330 drivers/net/wireless/mac80211_hwsim.c:1514
#3: ffffffff8bf7c918 (rcu_node_0){?.-.}-{2:2}, at: print_other_cpu_stall kernel/rcu/tree_stall.h:541 [inline]
#3: ffffffff8bf7c918 (rcu_node_0){?.-.}-{2:2}, at: check_cpu_stall kernel/rcu/tree_stall.h:706 [inline]
#3: ffffffff8bf7c918 (rcu_node_0){?.-.}-{2:2}, at: rcu_pending kernel/rcu/tree.c:3830 [inline]
#3: ffffffff8bf7c918 (rcu_node_0){?.-.}-{2:2}, at: rcu_sched_clock_irq.cold+0x2b9/0x11dd kernel/rcu/tree.c:2650
stack backtrace:
CPU: 0 PID: 9076 Comm: syz-executor.4 Not tainted 5.12.0-rc4-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:202 [inline]
valid_state kernel/locking/lockdep.c:3818 [inline]
mark_lock_irq kernel/locking/lockdep.c:4021 [inline]
mark_lock.cold+0x61/0x8e kernel/locking/lockdep.c:4478
mark_held_locks+0x9f/0xe0 kernel/locking/lockdep.c:4079
__trace_hardirqs_on_caller kernel/locking/lockdep.c:4097 [inline]
lockdep_hardirqs_on_prepare kernel/locking/lockdep.c:4165 [inline]
lockdep_hardirqs_on_prepare+0x135/0x400 kernel/locking/lockdep.c:4117
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:mac80211_hwsim_tx_frame_no_nl.isra.0+0x78f/0x1330 drivers/net/wireless/mac80211_hwsim.c:1525
Code: bb a4 3c 00 00 48 89 f8 48 89 fa 48 c1 e8 03 83 e2 07 0f b6 04 28 38 d0 7f 08 84 c0 0f 85 36 09 00 00 44 0f b6 bb a4 3c 00 00 <31> ff 44 89 fe e8 17 ac 55 fc 45 84 ff 0f 84 77 ff ff ff e8 59 a5
RSP: 0018:ffffc90000007ba8 EFLAGS: 00000246
RAX: 0000000000000000 RBX: ffff88801b1431e0 RCX: 0000000000000100
RDX: 0000000000000004 RSI: ffffffff851e4c33 RDI: ffff88801b146e84
RBP: dffffc0000000000 R08: 0000000000000004 R09: 0000000000000000
R10: ffffffff851e4c69 R11: 0000000000000000 R12: ffff8880704603c0
R13: ffff88805e85b410 R14: ffff88805e85b1e0 R15: 0000000000000000
mac80211_hwsim_tx_frame+0x14f/0x1e0 drivers/net/wireless/mac80211_hwsim.c:1775
mac80211_hwsim_beacon_tx+0x4ba/0x910 drivers/net/wireless/mac80211_hwsim.c:1829
__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:345
invoke_softirq kernel/softirq.c:221 [inline]
__irq_exit_rcu kernel/softirq.c:422 [inline]
irq_exit_rcu+0x134/0x200 kernel/softirq.c:434
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:hash_64_generic include/linux/hash.h:81 [inline]
RIP: 0010:hash_ptr include/linux/hash.h:90 [inline]
RIP: 0010:pv_unhash kernel/locking/qspinlock_paravirt.h:241 [inline]
RIP: 0010:__pv_queued_spin_unlock_slowpath+0x48/0x2b0 kernel/locking/qspinlock_paravirt.h:517
Code: 9e 13 6b 0c b9 40 00 00 00 49 be eb 83 b5 80 46 86 c8 61 4c 0f af f7 44 29 e1 83 f9 3f 0f 87 1f 80 68 07 4c 8b 05 58 21 84 0e <49> d3 ee 31 ed 49 bf 00 00 00 00 00 fc ff df 41 83 e6 fc 4c 89 f3
RSP: 0018:ffffc900017df5d8 EFLAGS: 00000297
RAX: 0000000000000003 RBX: 0000000000000001 RCX: 0000000000000038
RDX: 0000000000000000 RSI: 0000000000000003 RDI: ffffffff8c088200
RBP: ffffffff8c088200 R08: ffff88823ffe60c0 R09: ffffffff8c088203
R10: fffffbfff1811040 R11: 0000000000000000 R12: 0000000000000008
R13: ffffffff8c088200 R14: 14119341d0555600 R15: dffffc0000000000
__raw_callee_save___pv_queued_spin_unlock_slowpath+0x11/0x20
.slowpath+0x9/0xe
pv_queued_spin_unlock arch/x86/include/asm/paravirt.h:559 [inline]
queued_spin_unlock arch/x86/include/asm/qspinlock.h:56 [inline]
do_raw_spin_unlock+0x171/0x230 kernel/locking/spinlock_debug.c:139
__raw_spin_unlock include/linux/spinlock_api_smp.h:151 [inline]
_raw_spin_unlock+0x1a/0x40 kernel/locking/spinlock.c:183
spin_unlock include/linux/spinlock.h:394 [inline]
__cond_resched_lock+0x91/0xf0 kernel/sched/core.c:7017
__purge_vmap_area_lazy+0x976/0x1c50 mm/vmalloc.c:1387
_vm_unmap_aliases.part.0+0x3d6/0x4e0 mm/vmalloc.c:1784
_vm_unmap_aliases mm/vmalloc.c:1758 [inline]
vm_unmap_aliases+0x47/0x50 mm/vmalloc.c:1807
change_page_attr_set_clr+0x241/0x500 arch/x86/mm/pat/set_memory.c:1732
change_page_attr_clear arch/x86/mm/pat/set_memory.c:1789 [inline]
set_memory_ro+0x78/0xa0 arch/x86/mm/pat/set_memory.c:1935
bpf_jit_binary_lock_ro include/linux/filter.h:851 [inline]
bpf_int_jit_compile+0xe11/0x11b0 arch/x86/net/bpf_jit_comp.c:2329
bpf_prog_select_runtime+0x576/0x9f0 kernel/bpf/core.c:1868
bpf_prog_load+0xe75/0x1f40 kernel/bpf/syscall.c:2218
__do_sys_bpf+0x1272/0x4f40 kernel/bpf/syscall.c:4393
do_syscall_64+0x2d/0x70 arch/x86/entry/common.c:46
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x466459
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:00007f534555e188 EFLAGS: 00000246 ORIG_RAX: 0000000000000141
RAX: ffffffffffffffda RBX: 000000000056bf60 RCX: 0000000000466459
RDX: 0000000000000048 RSI: 0000000020000200 RDI: 0000000000000005
RBP: 00000000004bf9fb R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf60
R13: 00007ffdf27f922f R14: 00007f534555e300 R15: 0000000000022000
softirq: huh, entered softirq 8 HRTIMER ffffffff8163e5f0 with preempt_count 00000101, exited with 00000102?