uvm_fault: softclock_process_kclock_timeout (2)

2 views
Skip to first unread message

syzbot

unread,
Oct 24, 2023, 2:10:55 AM10/24/23
to syzkaller-o...@googlegroups.com
Hello,

syzbot found the following issue on:

HEAD commit: e0c1f4798a44 Use xoff instead of *olen in the shift_right(..
git tree: openbsd
console output: https://syzkaller.appspot.com/x/log.txt?x=16a81733680000
kernel config: https://syzkaller.appspot.com/x/.config?x=7058272de1526588
dashboard link: https://syzkaller.appspot.com/bug?extid=0aec2a0a4c5591c7f96b

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

Downloadable assets:
disk image: https://storage.googleapis.com/syzbot-assets/ac66645f0d97/disk-e0c1f479.raw.xz
bsd.gdb: https://storage.googleapis.com/syzbot-assets/8610cce9f100/bsd-e0c1f479.gdb.xz
kernel image: https://storage.googleapis.com/syzbot-assets/033c51df4b71/kernel-e0c1f479.xz

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

login: uvm_fault(0xffffffff82d8afc8, 0xffffffeaa5d05520, 0, 1) -> e
kernel: page fault trap, code=0
Stopped at softclock_process_kclock_timeout+0x3e: movq timeout_kclock(%r15),%r13
TID PID UID PRFLAGS PFLAGS CPU COMMAND
softclock_process_kclock_timeout(fffffd805b8edf00,0) at softclock_process_kclock_timeout+0x3e sys/kern/kern_timeout.c:676
softclock(0) at softclock+0x11a sys/kern/kern_timeout.c:754
softintr_dispatch(0) at softintr_dispatch+0xfb sys/arch/amd64/amd64/softintr.c:90
Xsoftclock() at Xsoftclock+0x27
acpicpu_idle() at acpicpu_idle+0x312 sys/dev/acpi/acpicpu.c:1206
sched_idle(ffffffff82c0cff0) at sched_idle+0x41e sys/kern/kern_sched.c:191
end trace frame: 0x0, count: 9
https://www.openbsd.org/ddb.html describes the minimum info required in bug
reports. Insufficient info makes it difficult to find and fix bugs.
ddb{0}>
ddb{0}> set $lines = 0
ddb{0}> set $maxwidth = 0
ddb{0}> show panic
*cpu0: uvm_fault(0xffffffff82d8afc8, 0xffffffeaa5d05520, 0, 1) -> e
ddb{0}> trace
softclock_process_kclock_timeout(fffffd805b8edf00,0) at softclock_process_kclock_timeout+0x3e sys/kern/kern_timeout.c:676
softclock(0) at softclock+0x11a sys/kern/kern_timeout.c:754
softintr_dispatch(0) at softintr_dispatch+0xfb sys/arch/amd64/amd64/softintr.c:90
Xsoftclock() at Xsoftclock+0x27
acpicpu_idle() at acpicpu_idle+0x312 sys/dev/acpi/acpicpu.c:1206
sched_idle(ffffffff82c0cff0) at sched_idle+0x41e sys/kern/kern_sched.c:191
end trace frame: 0x0, count: -6
ddb{0}> show registers
rdi 0xfffffd805b8edf00
rsi 0
rbp 0xffff80002115f0f0
rbx 0
rdx 0
rcx 0xffff800021159a90
rax 0xffffffff90baabe3 end+0xddaabe3
r8 0
r9 0
r10 0xade98baca5f40608
r11 0x4f3b4595a3aead13
r12 0x8fde17b634848385
r13 0xffffffff82d0f170 rdrand_tmo
r14 0xfffffd805b8edf00
r15 0xffffffeb23003a90
rip 0xffffffff81ab9c7e softclock_process_kclock_timeout+0x3e
cs 0x8
rflags 0x10287 __ALIGN_SIZE+0xf287
rsp 0xffff80002115f0a0
ss 0x10
softclock_process_kclock_timeout+0x3e: movq timeout_kclock(%r15),%r13
ddb{0}> show proc
PROC (idle0) tid=502718 pid=11619 tcnt=1 stat=onproc
flags process=14000<NOZOMBIE,SYSTEM> proc=40000200<SYSTEM,CPUPEG>
runpri=0, usrpri=50, slppri=0, nice=20
wchan=0x0, wmesg=, ps_single=0x0
forw=0xdf3754c9a0b68448, list=0xffff800021159d38,0xffff800021159000
process=0xffff8000ffffe9e0 user=0xffff80002115a000, vmspace=0xffffffff82cf05b8
estcpu=0, cpticks=61859, pctcpu=0.0, user=0, sys=0, intr=0
ddb{0}> ps
PID TID PPID UID S FLAGS WAIT COMMAND
74352 198857 71193 0 3 0x80 fsleep syz-executor.4
74352 164134 71193 0 3 0x4000080 netio syz-executor.4
18378 451762 41962 0 3 0x82 piperd syz-executor.7
42805 244657 41962 0 3 0x82 piperd syz-executor.1
79823 327263 41962 0 3 0x82 piperd syz-executor.2
11805 393680 41962 0 3 0x82 piperd syz-executor.5
10216 155869 1 0 3 0x100083 ttyin getty
49578 92375 0 0 3 0x14280 nfsidl nfsio
37980 176045 0 0 3 0x14280 nfsidl nfsio
47848 504705 0 0 3 0x14280 nfsidl nfsio
57824 31493 0 0 3 0x14280 nfsidl nfsio
72051 346703 0 0 3 0x14280 nfsidl nfsio
22653 286501 0 0 3 0x14280 nfsidl nfsio
50059 291955 0 0 3 0x14280 nfsidl nfsio
71605 287052 0 0 3 0x14280 nfsidl nfsio
78384 459545 0 0 3 0x14280 nfsidl nfsio
44595 199264 0 0 3 0x14280 nfsidl nfsio
78606 42418 0 0 3 0x14280 nfsidl nfsio
52219 292380 0 0 3 0x14280 nfsidl nfsio
61713 286005 0 0 3 0x14280 nfsidl nfsio
28272 360916 0 0 3 0x14280 nfsidl nfsio
57268 236548 0 0 3 0x14280 nfsidl nfsio
12253 318897 0 0 3 0x14280 nfsidl nfsio
20795 149639 0 0 3 0x14280 nfsidl nfsio
89744 284944 0 0 3 0x14280 nfsidl nfsio
66121 178256 0 0 3 0x14280 nfsidl nfsio
34411 297581 0 0 3 0x14280 nfsidl nfsio
39352 52166 41962 0 3 0x82 piperd syz-executor.3
30903 72950 41962 0 3 0x82 piperd syz-executor.6
93332 413329 0 0 3 0x14200 acct acct
71193 506714 41962 0 3 0x82 nanoslp syz-executor.4
12254 344957 41962 0 3 0x82 piperd syz-executor.0
8443 145592 0 0 3 0x14200 bored sosplice
41962 509525 22114 0 3 0x2000082 wait syz-fuzzer
41962 74170 22114 0 2 0x6000482 syz-fuzzer
41962 325095 22114 0 3 0x6000082 wait syz-fuzzer
41962 98281 22114 0 3 0x6000082 wait syz-fuzzer
41962 354806 22114 0 3 0x6000082 thrsleep syz-fuzzer
41962 207279 22114 0 3 0x6000082 thrsleep syz-fuzzer
41962 308627 22114 0 3 0x6000082 thrsleep syz-fuzzer
41962 348402 22114 0 3 0x6000082 wait syz-fuzzer
41962 520992 22114 0 3 0x6000082 kqread syz-fuzzer
41962 118334 22114 0 3 0x6000082 wait syz-fuzzer
41962 454859 22114 0 3 0x6000082 thrsleep syz-fuzzer
41962 247659 22114 0 3 0x6000082 thrsleep syz-fuzzer
41962 273796 22114 0 3 0x6000082 wait syz-fuzzer
41962 369725 22114 0 3 0x6000082 thrsleep syz-fuzzer
41962 14308 22114 0 3 0x6000082 wait syz-fuzzer
41962 503636 22114 0 3 0x6000082 wait syz-fuzzer
22114 182337 52558 0 3 0x10008a sigsusp ksh
52558 500219 55923 0 3 0x9a kqread sshd
55923 459934 1 0 3 0x88 kqread sshd
62561 167263 6151 74 3 0x1100092 bpf pflogd
6151 286765 1 0 3 0x80 netio pflogd
80062 13843 49775 73 3 0x1100090 kqread syslogd
49775 245997 1 0 3 0x100082 netio syslogd
82644 429995 1 0 3 0x100080 kqread resolvd
40265 90309 52372 77 3 0x100092 kqread dhcpleased
25522 514180 52372 77 3 0x100092 kqread dhcpleased
52372 393424 1 0 3 0x80 kqread dhcpleased
9934 66916 0 0 3 0x14200 bored smr
30165 3542 0 0 3 0x14200 pgzero zerothread
75340 196813 0 0 3 0x14200 aiodoned aiodoned
60022 191358 0 0 3 0x14200 syncer update
62691 206000 0 0 3 0x14200 cleaner cleaner
38305 199059 0 0 3 0x14200 reaper reaper
25020 198782 0 0 3 0x14200 pgdaemon pagedaemon
42804 412641 0 0 3 0x14200 bored viomb
55200 69287 0 0 3 0x40014200 acpi0 acpi0
48509 265218 0 0 7 0x40014200 idle1
74468 439261 0 0 3 0x14200 bored softnet3
26803 342382 0 0 3 0x14200 bored softnet2
71863 505165 0 0 3 0x14200 bored softnet1
83584 483535 0 0 3 0x14200 bored softnet0
68083 23144 0 0 3 0x14200 bored systqmp
19531 12948 0 0 3 0x14200 bored systq
87558 92516 0 0 3 0x14200 tmoslp softclockmp
25768 202147 0 0 3 0x40014200 tmoslp softclock
*11619 502718 0 0 7 0x40014200 idle0
1 505904 0 0 3 0x82 wait init
0 0 -1 0 3 0x10200 scheduler swapper
ddb{0}> show all locks
CPU 0:
exclusive mutex /syzkaller/managers/multicore/kernel/sys/kern/kern_timeout.c:57 r = 0 (0xffffffff82be0820)
#0 witness_lock+0x447
#1 mtx_enter_try+0x104
#2 mtx_enter+0x4f sys/kern/kern_lock.c:266
#3 softclock_process_tick_timeout+0x1b2 sys/kern/kern_timeout.c:722
#4 softclock+0x130 sys/kern/kern_timeout.c:748
#5 softintr_dispatch+0xfb sys/arch/amd64/amd64/softintr.c:90
#6 Xsoftclock+0x27
#7 acpicpu_idle+0x312 sys/dev/acpi/acpicpu.c:1206
#8 sched_idle+0x41e sys/kern/kern_sched.c:191
#9 proc_trampoline+0x1c
Process 11619 (idle0) thread 0xffff800021159a90 (502718)
ddb{0}> show malloc
Type InUse MemUse HighUse Limit Requests Type Lim
devbuf 10271 6523K 8764K 78643K 47645 0
pcb 14 20K 24K 78643K 13838 0
rtable 248 15K 15K 78643K 1918 0
pf 41 11K 11K 78643K 406 0
ifaddr 49 17K 18K 78643K 331 0
ifgroup 70 2K 3K 78643K 682 0
sysctl 3 0K 0K 78643K 13 0
counters 68 36K 37K 78643K 414 0
ioctlops 0 0K 4K 78643K 2474 0
iov 0 0K 24K 78643K 1715 0
mount 1 1K 1K 78643K 1 0
log 0 0K 0K 78643K 4 0
vnodes 1607 100K 101K 78643K 13887 0
UFS quota 1 32K 32K 78643K 1 0
UFS mount 5 36K 36K 78643K 5 0
shm 2 1K 9K 78643K 193 0
VM map 2 1K 1K 78643K 2 0
sem 12 0K 0K 78643K 5145 0
dirhash 12 2K 3K 78643K 126 0
ACPI 1697 195K 286K 78643K 12548 0
file desc 11 37K 89K 78643K 18284 0
sigio 0 0K 0K 78643K 1133 0
proc 72 91K 140K 78643K 2308 0
subproc 104 6K 6K 78643K 533 0
NFS srvsock 1 0K 0K 78643K 1 0
NFS daemon 1 16K 16K 78643K 1 0
ip_moptions 0 0K 0K 78643K 1291 0
in_multi 99 7K 7K 78643K 647 0
ether_multi 1 0K 0K 78643K 23 0
mrt 1 0K 0K 78643K 18 0
ISOFS mount 1 32K 32K 78643K 1 0
MSDOSFS mount 1 16K 16K 78643K 1 0
ttys 409 1818K 1818K 78643K 409 0
exec 0 0K 1K 78643K 2518 0
pfkey data 0 0K 0K 78643K 67 0
tdb 3 0K 0K 78643K 3 0
pagedep 1 8K 8K 78643K 1 0
inodedep 1 32K 32K 78643K 1 0
newblk 1 0K 0K 78643K 1 0
VM swap 8 62K 64K 78643K 10 0
UVM amap 596 859K 876K 78643K 177333 0
UVM aobj 131 8K 8K 78643K 154 0
memdesc 1 4K 4K 78643K 1 0
crypto data 1 1K 1K 78643K 1 0
ip6_options 0 0K 0K 78643K 619 0
NDP 16 0K 1K 78643K 271 0
temp 74 5920K 6556K 78643K 212089 0
kqueue 13 20K 30K 78643K 1257 0
SYN cache 2 16K 16K 78643K 2 0
ddb{0}> show all pools
Name Size Requests Fail Releases Pgreq Pgrel Npage Hiwat Minpg Maxpg Idle
plcache 128 22 0 0 1 0 1 1 0 8 0
rtpcb 120 777 0 774 9 8 1 4 0 8 0
rtentry 112 513 0 399 4 0 4 4 0 8 0
unpcb 144 17290 0 17275 184 183 1 10 0 8 0
syncache 304 142 0 142 29 29 0 1 0 8 0
sackhl 24 1 1 1 1 1 0 1 0 8 0
tcpqe 32 233 0 233 25 25 0 1 0 8 0
tcpcb 808 4722 0 4707 138 136 2 9 0 8 0
arp 120 88 0 69 1 0 1 1 0 8 0
inpcb 368 23906 0 23884 315 312 3 19 0 8 0
nd6 136 139 0 112 2 1 1 2 0 8 0
pkpcb 40 165 0 165 11 11 0 2 0 8 0
kcovpl 48 41 0 33 1 0 1 1 0 8 0
ppxss 1256 45 0 44 12 11 1 1 0 8 0
pffrag 232 326 0 324 4 3 1 1 0 482 0
pffrnode 88 323 0 321 4 3 1 1 0 8 0
pffrent 40 806 0 804 4 3 1 1 0 8 0
pfosfp 40 1428 0 1005 5 0 5 5 0 8 0
pfosfpen 112 1428 0 714 21 0 21 21 0 8 0
pfstitem 24 475 0 450 1 0 1 1 0 8 0
pfstkey 128 475 0 450 2 0 2 2 0 8 0
pfstate 376 475 0 450 10 5 5 5 0 8 0
pfrule 1344 21 0 16 2 1 1 2 0 8 0
rttmr 136 1 0 1 1 1 0 1 0 8 0
art_heap8 4096 1 0 0 1 0 1 1 0 8 0
art_heap4 256 1999 0 1528 34 4 30 31 0 8 0
art_table 32 2000 0 1528 4 0 4 4 0 8 0
art_node 16 494 0 390 1 0 1 1 0 8 0
sysvmsgpl 40 3 0 0 1 0 1 1 0 8 0
semapl 112 5143 0 5133 1 0 1 1 0 8 0
shmpl 112 151 0 23 4 0 4 4 0 8 0
dirhash 1024 93 0 76 3 0 3 3 0 8 0
dino2pl 256 26925 0 25334 100 0 100 100 0 8 0
ffsino 272 26925 0 25334 108 1 107 107 0 8 0
nchpl 144 51373 0 49734 64 0 64 64 0 8 0
uvmvnodes 80 6684 0 0 137 0 137 137 0 8 0
vnodes 216 6684 0 0 372 0 372 372 0 8 0
namei 1024 172458 0 172458 14 13 1 2 0 8 1
percpumem 16 220 0 173 1 0 1 1 0 8 0
vcpupl 2048 123 0 0 16 0 16 16 0 8 0
vmpool 696 126 0 3 12 0 12 12 0 8 0
kstatmem 264 378 0 346 9 6 3 3 0 8 0
scxspl 216 148978 0 148978 38 37 1 8 1 8 1
plimitpl 152 2328 0 2312 1 0 1 1 0 8 0
sigapl 424 18829 0 18765 9 1 8 8 0 8 0
futexpl 64 170949 0 170948 5 4 1 1 0 8 0
knotepl 120 1068 0 0 19 4 15 19 0 8 0
kqueuepl 216 3059 0 3050 55 54 1 8 0 8 0
pipepl 320 4250 0 4222 87 84 3 8 0 8 0
fdescpl 496 18528 0 18504 5 0 5 5 0 8 0
filepl 152 127565 0 127325 203 193 10 24 0 8 0
lockfpl 104 10155 0 10153 25 24 1 4 0 8 0
lockfspl 48 4521 0 4519 5 4 1 2 0 8 0
sessionpl 144 60 0 43 1 0 1 1 0 8 0
pgrppl 48 260 0 243 1 0 1 1 0 8 0
ucredpl 104 19169 0 19149 1 0 1 1 0 8 0
zombiepl 144 18765 0 18765 2 1 1 1 0 8 1
processpl 1072 18829 0 18765 5 0 5 5 0 8 0
procpl 680 49418 0 49338 22 13 9 10 0 8 0
srpgc 96 1 0 1 1 1 0 1 0 8 0
sosppl 168 164 0 164 17 16 1 1 0 8 1
sockpl 488 42772 0 42735 867 861 6 35 0 8 0
mcl64k 65536 17 0 0 3 0 3 3 0 8 0
mcl16k 16384 18 0 0 3 0 3 3 0 8 0
mcl12k 12288 25 0 0 2 0 2 2 0 8 0
mcl9k 9216 17 0 0 2 0 2 2 0 8 0
mcl8k 8192 41 0 0 3 0 3 3 0 8 0
mcl4k 4096 41 0 0 3 0 3 3 0 8 0
mcl2k2 2112 9 0 0 1 0 1 1 0 8 0
mcl2k 2048 511 0 0 34 10 24 34 0 8 0
mtagpl 96 1866 0 0 31 0 31 31 0 8 0
mbufpl 256 4779 0 0 245 0 245 245 0 8 0
bufpl 288 34279 0 27594 479 1 478 478 0 8 0
anonpl 24 1763866 0 1748846 175 45 130 130 0 186 0
amapchunkpl 152 565436 0 564595 118 63 55 55 0 158 15
amappl16 200 34897 0 34175 148 109 39 40 0 8 0
amappl15 192 46 0 46 4 4 0 1 0 8 0
amappl14 184 268 0 252 2 1 1 2 0 8 0
amappl13 176 15 0 14 1 0 1 1 0 8 0
amappl12 168 19717 0 19689 4 2 2 3 0 8 0
amappl11 160 68 0 53 1 0 1 1 0 8 0
amappl10 152 89 0 77 1 0 1 1 0 8 0
amappl9 144 491 0 490 1 0 1 1 0 8 0
amappl8 136 900 0 701 8 0 8 8 0 8 0
amappl7 128 160 0 143 2 0 2 2 0 8 0
amappl6 120 614 0 579 6 4 2 2 0 8 0
amappl5 112 632 0 621 1 0 1 1 0 8 0
amappl4 104 1091 0 1038 3 1 2 3 0 8 0
amappl3 96 110122 0 110057 4 1 3 3 0 8 0
amappl2 88 19393 0 19318 3 1 2 3 0 8 0
amappl1 80 76373 0 75831 23 11 12 23 0 8 0
amappl 88 176011 0 175719 9 1 8 9 0 92 0
dma4096 4096 1 0 1 1 1 0 1 0 8 0
dma1024 1024 1 0 0 1 0 1 1 0 8 0
dma256 256 6 0 6 1 1 0 1 0 8 0
dma128 128 253 0 253 1 1 0 1 0 8 0
dma64 64 6 0 6 1 1 0 1 0 8 0
dma32 32 7 0 7 1 1 0 1 0 8 0
dma16 16 18 0 17 1 0 1 1 0 8 0
aobjpl 72 153 0 23 3 0 3 3 0 8 0
uaddrrnd 24 18654 0 18507 1 0 1 1 0 8 0
uaddrbest 32 2 0 0 1 0 1 1 0 8 0
uaddr 24 18654 0 18507 1 0 1 1 0 8 0
vmmpekpl 168 137053 0 136969 5 0 5 5 0 8 0
vmmpepl 168 1093022 0 1090301 505 366 139 168 0 357 3
vmsppl 464 18653 0 18507 20 0 20 20 0 8 0
rwobjpl 56 262638 0 253815 142 16 126 126 0 8 0
pdppl 4096 37316 0 37137 954 761 193 193 0 8 14
pvpl 32 4749741 0 4729357 456 239 217 351 0 265 0
pmappl 248 18653 0 18507 10 0 10 10 0 8 0
extentpl 40 56 0 38 1 0 1 1 0 8 0
phpool 112 2490 0 1264 36 0 36 36 0 8 0
ddb{0}> machine ddbcpu 0
Invalid cpu 0
ddb{0}> trace
softclock_process_kclock_timeout(fffffd805b8edf00,0) at softclock_process_kclock_timeout+0x3e sys/kern/kern_timeout.c:676
softclock(0) at softclock+0x11a sys/kern/kern_timeout.c:754
softintr_dispatch(0) at softintr_dispatch+0xfb sys/arch/amd64/amd64/softintr.c:90
Xsoftclock() at Xsoftclock+0x27
acpicpu_idle() at acpicpu_idle+0x312 sys/dev/acpi/acpicpu.c:1206
sched_idle(ffffffff82c0cff0) at sched_idle+0x41e sys/kern/kern_sched.c:191
end trace frame: 0x0, count: -6
ddb{0}> machine ddbcpu 1
Stopped at x86_ipi_db+0x1e: addq $0x8,%rsp
x86_ipi_db(ffff800020d48ff0) at x86_ipi_db+0x1e sys/arch/amd64/amd64/db_interface.c:393
x86_ipi_handler() at x86_ipi_handler+0xb7 sys/arch/amd64/amd64/ipi.c:106
Xresume_lapic_ipi() at Xresume_lapic_ipi+0x27
acpicpu_idle() at acpicpu_idle+0x312 sys/dev/acpi/acpicpu.c:1206
sched_idle(ffff800020d48ff0) at sched_idle+0x41e sys/kern/kern_sched.c:191
end trace frame: 0x0, count: 10
ddb{1}> trace
x86_ipi_db(ffff800020d48ff0) at x86_ipi_db+0x1e sys/arch/amd64/amd64/db_interface.c:393
x86_ipi_handler() at x86_ipi_handler+0xb7 sys/arch/amd64/amd64/ipi.c:106
Xresume_lapic_ipi() at Xresume_lapic_ipi+0x27
acpicpu_idle() at acpicpu_idle+0x312 sys/dev/acpi/acpicpu.c:1206
sched_idle(ffff800020d48ff0) at sched_idle+0x41e sys/kern/kern_sched.c:191
end trace frame: 0x0, count: -5


---
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.

If the bug is already fixed, let syzbot know by replying with:
#syz fix: exact-commit-title

If you want to overwrite bug's subsystems, reply with:
#syz set subsystems: new-subsystem
(See the list of subsystem names on the web dashboard)

If the bug is a duplicate of another bug, reply with:
#syz dup: exact-subject-of-another-report

If you want to undo deduplication, reply with:
#syz undup
Reply all
Reply to author
Forward
0 new messages