INFO: rcu detected stall in _do_fork

6 views
Skip to first unread message

syzbot

unread,
Dec 30, 2021, 3:01:20 AM12/30/21
to syzkaller...@googlegroups.com
Hello,

syzbot found the following issue on:

HEAD commit: 3f8a27f9e27b Linux 4.19.211
git tree: linux-4.19.y
console output: https://syzkaller.appspot.com/x/log.txt?x=11f3affbb00000
kernel config: https://syzkaller.appspot.com/x/.config?x=9b9277b418617afe
dashboard link: https://syzkaller.appspot.com/bug?extid=5015af38273cd497c692
compiler: gcc version 10.2.1 20210110 (Debian 10.2.1-6)

Unfortunately, I don't have any reproducer for this issue yet.

IMPORTANT: if you fix the issue, please add the following tag to the commit:
Reported-by: syzbot+5015af...@syzkaller.appspotmail.com

ieee802154 phy1 wpan1: encryption failed: -22
cgroup: fork rejected by pids controller in /system.slice/ssh.service
ieee802154 phy0 wpan0: encryption failed: -22
ieee802154 phy1 wpan1: encryption failed: -22
rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
rcu: 1-...!: (10502 ticks this GP) idle=136/1/0x4000000000000002 softirq=31092/31092 fqs=0
rcu: (detected by 0, t=10502 jiffies, g=161, q=13)
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 20833 Comm: syz-executor.0 Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:__sanitizer_cov_trace_const_cmp1+0x4/0x20 kernel/kcov.c:174
Code: 00 00 00 0f 1f 00 48 8b 0c 24 48 89 f2 48 89 fe bf 06 00 00 00 e9 2c ff ff ff 66 66 2e 0f 1f 84 00 00 00 00 00 90 48 8b 0c 24 <40> 0f b6 d6 40 0f b6 f7 bf 01 00 00 00 e9 0a ff ff ff 66 2e 0f 1f
RSP: 0018:ffff8880ba107a38 EFLAGS: 00000046
RAX: 0000000000000001 RBX: 1ffff11017420f48 RCX: ffffffff817986e2
RDX: 0000000000000000 RSI: 0000000000000001 RDI: 0000000000000000
RBP: 0000000000000001 R08: 0000000000000000 R09: ffffed101742455a
R10: ffff8880ba122ad3 R11: ffffffff8c66505b R12: ffff888017b38e80
R13: ffff8880ba107bc0 R14: ffff88804aa67978 R15: 0000000000000000
FS: 00007fd09587f700(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007fd09587f6bc CR3: 0000000047f41000 CR4: 00000000003406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
<IRQ>
rcu_read_lock include/linux/rcupdate.h:628 [inline]
__perf_event_output kernel/events/core.c:6614 [inline]
perf_event_output_forward+0x232/0x270 kernel/events/core.c:6634
__perf_event_overflow+0x13c/0x370 kernel/events/core.c:7907
perf_swevent_hrtimer+0x272/0x3e0 kernel/events/core.c:9261
__run_hrtimer kernel/time/hrtimer.c:1465 [inline]
__hrtimer_run_queues+0x3f6/0xe60 kernel/time/hrtimer.c:1527
hrtimer_interrupt+0x326/0x9e0 kernel/time/hrtimer.c:1585
local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1071 [inline]
smp_apic_timer_interrupt+0x10c/0x550 arch/x86/kernel/apic/apic.c:1096
apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:894
</IRQ>
RIP: 0010:__read_once_size include/linux/compiler.h:263 [inline]
RIP: 0010:compound_head include/linux/page-flags.h:142 [inline]
RIP: 0010:get_page include/linux/mm.h:931 [inline]
RIP: 0010:copy_one_pte mm/memory.c:1052 [inline]
RIP: 0010:copy_pte_range mm/memory.c:1114 [inline]
RIP: 0010:copy_pmd_range mm/memory.c:1165 [inline]
RIP: 0010:copy_pud_range mm/memory.c:1199 [inline]
RIP: 0010:copy_p4d_range mm/memory.c:1221 [inline]
RIP: 0010:copy_page_range+0x1012/0x2ff0 mm/memory.c:1283
Code: d0 00 00 00 e8 af 0f ff ff 48 85 c0 49 89 c6 0f 84 69 06 00 00 e8 de 12 d6 ff 4d 8d 7e 08 4c 89 f8 48 c1 e8 03 42 80 3c 28 00 <0f> 85 a1 19 00 00 49 8b 46 08 31 ff 4c 89 f5 49 89 c4 48 89 84 24
RSP: 0018:ffff88804aa67a28 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff13
RAX: 1ffffd40004b6a21 RBX: 0000000000000008 RCX: ffffffff818b840c
RDX: 0000000000000000 RSI: ffffffff818c7292 RDI: 0000000000000006
RBP: 8000000096d44007 R08: 0000000000000001 R09: 000000000023ffff
R10: 0000000000000006 R11: 0000000048102452 R12: 8000000000000007
R13: dffffc0000000000 R14: ffffea00025b5100 R15: ffffea00025b5108
dup_mmap kernel/fork.c:549 [inline]
dup_mm kernel/fork.c:1285 [inline]
copy_mm kernel/fork.c:1341 [inline]
copy_process.part.0+0x5b22/0x8260 kernel/fork.c:1913
copy_process kernel/fork.c:1710 [inline]
_do_fork+0x22f/0xf30 kernel/fork.c:2219
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7fd096faee99
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:00007fd09587f168 EFLAGS: 00000246 ORIG_RAX: 0000000000000039
RAX: ffffffffffffffda RBX: 00007fd0970c2370 RCX: 00007fd096faee99
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 00007fd097008ff1 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffeed1dacaf R14: 00007fd09587f300 R15: 0000000000022000
rcu: rcu_sched kthread starved for 10502 jiffies! g161 f0x0 RCU_GP_WAIT_FQS(5) ->state=0x402 ->cpu=1
rcu: RCU grace-period kthread stack dump:
rcu_sched I29728 11 2 0x80000000
Call Trace:
context_switch kernel/sched/core.c:2828 [inline]
__schedule+0x887/0x2040 kernel/sched/core.c:3517
schedule+0x8d/0x1b0 kernel/sched/core.c:3561
schedule_timeout+0x4cf/0xfe0 kernel/time/timer.c:1818
rcu_gp_kthread+0xdad/0x21c0 kernel/rcu/tree.c:2202
kthread+0x33f/0x460 kernel/kthread.c:259
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415
----------------
Code disassembly (best guess), 1 bytes skipped:
0: 00 00 add %al,(%rax)
2: 0f 1f 00 nopl (%rax)
5: 48 8b 0c 24 mov (%rsp),%rcx
9: 48 89 f2 mov %rsi,%rdx
c: 48 89 fe mov %rdi,%rsi
f: bf 06 00 00 00 mov $0x6,%edi
14: e9 2c ff ff ff jmpq 0xffffff45
19: 66 66 2e 0f 1f 84 00 data16 nopw %cs:0x0(%rax,%rax,1)
20: 00 00 00 00
24: 90 nop
25: 48 8b 0c 24 mov (%rsp),%rcx
* 29: 40 0f b6 d6 movzbl %sil,%edx <-- trapping instruction
2d: 40 0f b6 f7 movzbl %dil,%esi
31: bf 01 00 00 00 mov $0x1,%edi
36: e9 0a ff ff ff jmpq 0xffffff45
3b: 66 data16
3c: 2e cs
3d: 0f .byte 0xf
3e: 1f (bad)


---
This report is generated by a bot. It may contain errors.
See https://goo.gl/tpsmEJ for more information about syzbot.
syzbot engineers can be reached at syzk...@googlegroups.com.

syzbot will keep track of this issue. See:
https://goo.gl/tpsmEJ#status for how to communicate with syzbot.

syzbot

unread,
Jan 2, 2022, 12:00:21 PM1/2/22
to syzkaller...@googlegroups.com
syzbot has found a reproducer for the following issue on:

HEAD commit: 3f8a27f9e27b Linux 4.19.211
git tree: linux-4.19.y
console output: https://syzkaller.appspot.com/x/log.txt?x=10c817a3b00000
kernel config: https://syzkaller.appspot.com/x/.config?x=9b9277b418617afe
dashboard link: https://syzkaller.appspot.com/bug?extid=5015af38273cd497c692
compiler: gcc version 10.2.1 20210110 (Debian 10.2.1-6)
syz repro: https://syzkaller.appspot.com/x/repro.syz?x=12b18e35b00000

IMPORTANT: if you fix the issue, please add the following tag to the commit:
Reported-by: syzbot+5015af...@syzkaller.appspotmail.com

watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [syz-executor.0:20832]
Modules linked in:
irq event stamp: 2726218
hardirqs last enabled at (2726217): [<ffffffff81003ce4>] trace_hardirqs_on_thunk+0x1a/0x1c
hardirqs last disabled at (2726218): [<ffffffff81003d00>] trace_hardirqs_off_thunk+0x1a/0x1c
softirqs last enabled at (94154): [<ffffffff88400678>] __do_softirq+0x678/0x980 kernel/softirq.c:318
softirqs last disabled at (88817): [<ffffffff813927d5>] invoke_softirq kernel/softirq.c:372 [inline]
softirqs last disabled at (88817): [<ffffffff813927d5>] irq_exit+0x215/0x260 kernel/softirq.c:412
CPU: 0 PID: 20832 Comm: syz-executor.0 Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:pv_wait_head_or_lock kernel/locking/qspinlock_paravirt.h:435 [inline]
RIP: 0010:__pv_queued_spin_lock_slowpath+0x3a6/0xae0 kernel/locking/qspinlock.c:474
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 38 04 00 00 41 0f b6 45 00 38 d8 7f 08 84 c0 0f
RSP: 0018:ffff88822c6778c0 EFLAGS: 00000206 ORIG_RAX: ffffffffffffff13
RAX: 0000000000000003 RBX: 0000000000000000 RCX: 1ffffffff140787c
RDX: 0000000000000001 RSI: ffffffff8167a995 RDI: 0000000000000286
RBP: ffffffff8a03c3e0 R08: 0000000000000001 R09: 0000000000000000
R10: 0000000000000001 R11: 0000000000000000 R12: 0000000000000fe7
R13: fffffbfff140787c R14: 0000000000000001 R15: ffff8880ba02be00
FS: 00007fc335a66700(0000) GS:ffff8880ba000000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007fc336400000 CR3: 000000022c75f000 CR4: 00000000003406f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
pv_queued_spin_lock_slowpath arch/x86/include/asm/paravirt.h:679 [inline]
queued_spin_lock_slowpath arch/x86/include/asm/qspinlock.h:53 [inline]
queued_spin_lock include/asm-generic/qspinlock.h:88 [inline]
do_raw_spin_lock+0x189/0x220 kernel/locking/spinlock_debug.c:113
spin_lock include/linux/spinlock.h:329 [inline]
sget_userns+0xcc/0xcd0 fs/super.c:501
mount_ns+0x8d/0x1d0 fs/super.c:1068
mount_fs+0xa3/0x310 fs/super.c:1261
vfs_kern_mount.part.0+0x68/0x470 fs/namespace.c:961
vfs_kern_mount fs/namespace.c:951 [inline]
kern_mount_data+0x55/0xd0 fs/namespace.c:3278
pid_ns_prepare_proc+0x1a/0x90 fs/proc/root.c:214
alloc_pid+0x721/0x8f0 kernel/pid.c:208
copy_process.part.0+0x3bc0/0x8260 kernel/fork.c:1927
copy_process kernel/fork.c:1710 [inline]
_do_fork+0x22f/0xf30 kernel/fork.c:2219
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7fc3362f0e99
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:00007fc335a66168 EFLAGS: 00000246 ORIG_RAX: 0000000000000038
RAX: ffffffffffffffda RBX: 00007fc336403f60 RCX: 00007fc3362f0e99
RDX: 9999999999999999 RSI: 0000000000000000 RDI: 0000000020002100
RBP: 00007fc33634aff1 R08: ffffffffffffffff R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffea510a86f R14: 00007fc335a66300 R15: 0000000000022000
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 20830 Comm: syz-executor.5 Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:apic_timer_interrupt+0x5/0x20 arch/x86/entry/entry_64.S:894
Code: 28 e0 f8 48 89 e7 e8 0a 68 e0 f8 e8 8f 28 e0 f8 e9 85 f5 ff ff c3 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 68 13 ff ff ff <e8> 46 f4 ff ff e8 01 2a 00 00 e9 2b f5 ff ff 66 66 2e 0f 1f 84 00
RSP: 0018:ffff8880ba107690 EFLAGS: 00000046
RAX: dffffc0000000000 RBX: 1ffff11017420ed9 RCX: 0000000000000000
RDX: 1ffff11017420f12 RSI: ffff8880ba107f80 RDI: ffff8880ba107898
RBP: ffff8880ba107f80 R08: 0000000000000001 R09: 0000000000000001
R10: ffff8880ba1078e7 R11: 0000000000074071 R12: ffff8880ba107888
R13: ffff8880ba1078c8 R14: ffff8880ba100000 R15: ffffffff8c11cfdc
FS: 00007fae1b5a2700(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007fe882b6b000 CR3: 00000002316a4000 CR4: 00000000003406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
<IRQ>
RIP: 0010:on_stack arch/x86/include/asm/stacktrace.h:42 [inline]
RIP: 0010:stack_access_ok arch/x86/kernel/unwind_orc.c:321 [inline]
RIP: 0010:deref_stack_reg+0xb7/0x1d0 arch/x86/kernel/unwind_orc.c:331
Code: 00 00 fc ff df 48 89 fa 48 c1 ea 03 80 3c 02 00 0f 85 00 01 00 00 49 8d 7c 24 10 4d 8b 74 24 08 48 b8 00 00 00 00 00 fc ff df <48> 89 fa 48 c1 ea 03 80 3c 02 00 0f 85 c7 00 00 00 49 39 ee 49 8b
RSP: 0018:ffff8880ba1076c0 EFLAGS: 00000246
unwind_next_frame+0xf83/0x1400 arch/x86/kernel/unwind_orc.c:555
__save_stack_trace+0x9f/0x190 arch/x86/kernel/stacktrace.c:44
save_stack mm/kasan/kasan.c:448 [inline]
set_track mm/kasan/kasan.c:460 [inline]
kasan_kmalloc+0xeb/0x160 mm/kasan/kasan.c:553
slab_post_alloc_hook mm/slab.h:445 [inline]
slab_alloc_node mm/slab.c:3340 [inline]
kmem_cache_alloc_node+0x133/0x3b0 mm/slab.c:3647
__alloc_skb+0x71/0x560 net/core/skbuff.c:193
skb_copy+0x139/0x2f0 net/core/skbuff.c:1358
mac80211_hwsim_tx_frame_no_nl.isra.0+0xb8a/0x1330 drivers/net/wireless/mac80211_hwsim.c:1353
mac80211_hwsim_tx_frame+0x12b/0x210 drivers/net/wireless/mac80211_hwsim.c:1556
mac80211_hwsim_beacon_tx+0x3f8/0x680 drivers/net/wireless/mac80211_hwsim.c:1595
__iterate_interfaces+0x2e1/0x4a0 net/mac80211/util.c:614
ieee80211_iterate_active_interfaces_atomic+0x8d/0x170 net/mac80211/util.c:650
mac80211_hwsim_beacon+0xc9/0x190 drivers/net/wireless/mac80211_hwsim.c:1615
__tasklet_hrtimer_trampoline+0x29/0xa0 kernel/softirq.c:601
tasklet_action_common.constprop.0+0x265/0x360 kernel/softirq.c:522
__do_softirq+0x265/0x980 kernel/softirq.c:292
invoke_softirq kernel/softirq.c:372 [inline]
irq_exit+0x215/0x260 kernel/softirq.c:412
exiting_irq arch/x86/include/asm/apic.h:536 [inline]
smp_apic_timer_interrupt+0x136/0x550 arch/x86/kernel/apic/apic.c:1098
apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:894
</IRQ>
RIP: 0010:ns_test_super+0x2b/0x50 fs/super.c:1047
Code: 48 89 f5 53 48 89 fb e8 a3 4e bf ff 48 8d bb 80 06 00 00 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 80 3c 02 00 75 0f <31> c0 48 39 ab 80 06 00 00 5b 5d 0f 94 c0 c3 e8 61 2b f5 ff eb ea
RSP: 0018:ffff88822c6879b0 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff13
RAX: dffffc0000000000 RBX: ffff8880aa14c8c0 RCX: ffffffff81a3594b
RDX: 1ffff110154299e8 RSI: ffffffff81a336cd RDI: ffff8880aa14cf40
RBP: ffff88809d81ba18 R08: 0000000000000001 R09: 0000000000000000
R10: 0000000000000005 R11: 00000000e79d86ea R12: ffff8880aa14c8c0
R13: ffffffff8a066ca8 R14: ffff888072cf6ed0 R15: dffffc0000000000
sget_userns+0x300/0xcd0 fs/super.c:504
mount_ns+0x8d/0x1d0 fs/super.c:1068
mount_fs+0xa3/0x310 fs/super.c:1261
vfs_kern_mount.part.0+0x68/0x470 fs/namespace.c:961
vfs_kern_mount fs/namespace.c:951 [inline]
kern_mount_data+0x55/0xd0 fs/namespace.c:3278
pid_ns_prepare_proc+0x1a/0x90 fs/proc/root.c:214
alloc_pid+0x721/0x8f0 kernel/pid.c:208
copy_process.part.0+0x3bc0/0x8260 kernel/fork.c:1927
copy_process kernel/fork.c:1710 [inline]
_do_fork+0x22f/0xf30 kernel/fork.c:2219
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7fae1be2ce99
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:00007fae1b5a2168 EFLAGS: 00000246 ORIG_RAX: 0000000000000038
RAX: ffffffffffffffda RBX: 00007fae1bf3ff60 RCX: 00007fae1be2ce99
RDX: 9999999999999999 RSI: 0000000000000000 RDI: 0000000020002100
RBP: 00007fae1be86ff1 R08: ffffffffffffffff R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007fff85e9057f R14: 00007fae1b5a2300 R15: 0000000000022000
----------------
Code disassembly (best guess):
0: eb c6 jmp 0xffffffc8
2: 45 01 01 add %r8d,(%r9)
5: 41 bc 00 80 00 00 mov $0x8000,%r12d
b: 48 c1 e9 03 shr $0x3,%rcx
f: 83 e3 07 and $0x7,%ebx
12: 41 be 01 00 00 00 mov $0x1,%r14d
18: 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax
1f: fc ff df
22: 4c 8d 2c 01 lea (%rcx,%rax,1),%r13
26: eb 0c jmp 0x34
28: f3 90 pause
* 2a: 41 83 ec 01 sub $0x1,%r12d <-- trapping instruction
2e: 0f 84 38 04 00 00 je 0x46c
34: 41 0f b6 45 00 movzbl 0x0(%r13),%eax
39: 38 d8 cmp %bl,%al
3b: 7f 08 jg 0x45
3d: 84 c0 test %al,%al
3f: 0f .byte 0xf

Reply all
Reply to author
Forward
0 new messages