uvm_fault: pvclock_get (2)

[syzbot]

Hello,

syzbot found the following issue on:

HEAD commit: f3ad7971a235 Ql -> Fl, from arza at arza dot us in GitHub ..
git tree: openbsd
console output: https://syzkaller.appspot.com/x/log.txt?x=1477a606580000
kernel config: https://syzkaller.appspot.com/x/.config?x=1bc15e68cd2a49e5
dashboard link: https://syzkaller.appspot.com/bug?extid=3177d0e000d22a077fe0

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

Downloadable assets:
disk image: https://storage.googleapis.com/syzbot-assets/0b13d4b1231c/disk-f3ad7971.raw.xz
bsd.gdb: https://storage.googleapis.com/syzbot-assets/c60064f7994f/bsd-f3ad7971.gdb.xz
kernel image: https://storage.googleapis.com/syzbot-assets/cb1b13b46a28/kernel-f3ad7971.xz

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

uvm_fault(0xffffffff83a77d60, 0xffff800029ef5000, 0, 1) -> e
kernel: page fault trap, code=0
Stopped at pvclock_get+0x53: movl 0(%r15),%r12d
TID PID UID PRFLAGS PFLAGS CPU COMMAND
pvclock_get(ffffffff838acd00) at pvclock_get+0x53 pvclock_read_begin sys/dev/pv/pvclock.c:278 [inline]
pvclock_get(ffffffff838acd00) at pvclock_get+0x53 sys/dev/pv/pvclock.c:333
nanouptime(ffff80003a8ebb50) at nanouptime+0x61 tc_delta sys/kern/kern_tc.c:138 [inline]
nanouptime(ffff80003a8ebb50) at nanouptime+0x61 binuptime sys/kern/kern_tc.c:191 [inline]
nanouptime(ffff80003a8ebb50) at nanouptime+0x61 sys/kern/kern_tc.c:217
tuagg_add_runtime() at tuagg_add_runtime+0x40 sys/kern/kern_resource.c:449
mi_switch() at mi_switch+0xb1 sys/kern/sched_bsd.c:372
ast(ffff80003a8ebc20) at ast+0x13d mi_ast sys/sys/syscall_mi.h:252 [inline]
ast(ffff80003a8ebc20) at ast+0x13d sys/arch/amd64/amd64/trap.c:747
Xsyscall() at Xsyscall+0x156
end of kernel
end trace frame: 0x177cb7e6e80, 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>
ddb> set $lines = 0
ddb> set $maxwidth = 0
ddb> show panic
*cpu0: uvm_fault(0xffffffff83a77d60, 0xffff800029ef5000, 0, 1) -> e
ddb> trace
pvclock_get(ffffffff838acd00) at pvclock_get+0x53 pvclock_read_begin sys/dev/pv/pvclock.c:278 [inline]
pvclock_get(ffffffff838acd00) at pvclock_get+0x53 sys/dev/pv/pvclock.c:333
nanouptime(ffff80003a8ebb50) at nanouptime+0x61 tc_delta sys/kern/kern_tc.c:138 [inline]
nanouptime(ffff80003a8ebb50) at nanouptime+0x61 binuptime sys/kern/kern_tc.c:191 [inline]
nanouptime(ffff80003a8ebb50) at nanouptime+0x61 sys/kern/kern_tc.c:217
tuagg_add_runtime() at tuagg_add_runtime+0x40 sys/kern/kern_resource.c:449
mi_switch() at mi_switch+0xb1 sys/kern/sched_bsd.c:372
ast(ffff80003a8ebc20) at ast+0x13d mi_ast sys/sys/syscall_mi.h:252 [inline]
ast(ffff80003a8ebc20) at ast+0x13d sys/arch/amd64/amd64/trap.c:747
Xsyscall() at Xsyscall+0x156
end of kernel
end trace frame: 0x177cb7e6e80, count: -6
ddb> show registers
rdi 0xffff80002b8b6000
rsi 0x48668 acpi_pdirpa+0x344d9
rbp 0xffff80003a8ebad0
rbx 0xffffffff838b1598 th1
rdx 0xffff80002b8b6000
rcx 0x48667 acpi_pdirpa+0x344d8
rax 0xc
r8 0
r9 0x201
r10 0xe600dec359d96938
r11 0x4b149d6663090a7
r12 0
r13 0x10d0 __ALIGN_SIZE+0xd0
r14 0xffffffff838acd00 pvclock_timecounter
r15 0xffff800029ef5000
rip 0xffffffff82615f83 pvclock_get+0x53
cs 0x8
rflags 0x10246 __ALIGN_SIZE+0xf246
rsp 0xffff80003a8eba80
ss 0x10
pvclock_get+0x53: movl 0(%r15),%r12d
ddb> show proc
PROC (syz-executor) tid=145871 pid=21251 tcnt=2 stat=run
flags process=0 proc=4000000<THREAD>
runpri=86, usrpri=86, slppri=32, nice=20
wchan=0x0, wmesg=, ps_single=0x0 scnt=0 ecnt=0
forw=0x0, list=0xffff80003c9394e8,0xffffffff839f2700
process=0xffff8000ffffa418 user=0xffff80003a8e6000, vmspace=0xfffffd806ccfc180
estcpu=36, cpticks=1, pctcpu=0.0, user=0, sys=1, intr=0
ddb> ps
PID TID PPID UID S FLAGS WAIT COMMAND
21251 429126 83119 0 2 0 syz-executor
*21251 145871 83119 0 2 0x4000000 syz-executor
10974 108087 51511 0 2 0 syz-executor
10974 91563 51511 0 3 0x4000080 fsleep syz-executor
48926 428327 54750 0 2 0x10 syz-executor
48926 242181 54750 0 3 0x4000090 ttyin syz-executor
48926 394133 54750 0 3 0x4000090 ttyin syz-executor
48926 165264 54750 0 3 0x4000090 fsleep syz-executor
60572 78812 96407 0 3 0x81 nanoslp syz-executor
60572 291960 96407 0 3 0x4000081 lockf syz-executor
60572 485501 96407 0 3 0x4000081 fsleep syz-executor
88961 317327 0 0 3 0x14200 acct acct
28885 92013 71958 0 3 0x82 wait syz-executor
17327 179784 71958 0 3 0x82 wait syz-executor
96407 390755 71958 0 3 0x82 nanoslp syz-executor
39698 132683 71958 0 3 0x82 wait syz-executor
54750 58098 71958 0 3 0x82 nanoslp syz-executor
51511 252954 71958 0 2 0x2 syz-executor
83119 489917 71958 0 3 0x82 nanoslp syz-executor
14230 267764 71958 0 2 0x2 syz-executor
71958 109266 17792 0 3 0x82 kqread syz-executor
17792 409877 60456 0 3 0x10008a sigsusp ksh
60456 40618 88333 0 3 0x98 kqread sshd-session
88333 421333 65587 0 3 0x92 kqread sshd-session
41155 421324 1 0 3 0x100083 ttyin getty
65587 171973 1 0 3 0x88 kqread sshd
10312 71854 82654 73 3 0x1100090 kqread syslogd
82654 418053 1 0 3 0x100082 sbwait syslogd
86803 292278 1 0 3 0x100080 kqread resolvd
32670 469377 64342 77 3 0x100092 kqread dhcpleased
597 457464 64342 77 3 0x100092 kqread dhcpleased
64342 449374 1 0 3 0x80 kqread dhcpleased
44603 267351 0 0 3 0x14200 bored smr
54235 130051 0 0 2 0x14200 zerothread
78637 224000 0 0 3 0x14200 aiodoned aiodoned
90159 19645 0 0 3 0x14200 syncer update
10189 354931 0 0 3 0x14200 cleaner cleaner
40143 428919 0 0 3 0x14200 reaper reaper
60653 280049 0 0 3 0x14200 pgdaemon pagedaemon
44640 494271 0 0 3 0x14200 bored viomb
45705 421026 0 0 3 0x40014200 acpi0 acpi0
61353 233401 0 0 3 0x14200 bored softnet0
30160 319104 0 0 3 0x14200 bored systqmp
17925 171036 0 0 3 0x14200 bored systq
82506 221715 0 0 3 0x40014200 tmoslp softclock
84664 486188 0 0 3 0x40014200 idle0
1 455840 0 0 3 0x82 wait init
0 0 -1 0 3 0x10010200 scheduler swapper
ddb> show all locks
No such command
ddb> show malloc
Type InUse MemUse HighUse Limit Requests Type Lim
devbuf 11047 12142K 13468K 166960K 13767 0
pcb 19 12K 12K 166960K 200 0
rtable 240 11K 12K 166960K 460 0
pf 32 13K 16K 166960K 69 0
ifaddr 40 7K 7K 166960K 57 0
ifgroup 46 2K 2K 166960K 67 0
sysctl 4 1K 9K 166960K 11 0
counters 32 17K 18K 166960K 44 0
ioctlops 0 0K 4K 166960K 254 0
iov 0 0K 16K 166960K 22 0
mount 1 1K 1K 166960K 1 0
log 0 0K 0K 166960K 4 0
vnodes 1421 89K 90K 166960K 2215 0
UFS quota 1 32K 32K 166960K 1 0
UFS mount 5 36K 36K 166960K 5 0
shm 2 1K 5K 166960K 9 0
VM map 2 1K 1K 166960K 2 0
sem 22 641K 641K 166960K 36 0
dirhash 12 2K 2K 166960K 30 0
ACPI 1692 195K 286K 166960K 12470 0
file desc 17 61K 97K 166960K 708 0
sigio 0 0K 0K 166960K 7 0
proc 60 59K 108K 166960K 546 0
subproc 72 4K 4K 166960K 72 0
NFS srvsock 1 0K 0K 166960K 1 0
NFS daemon 1 16K 16K 166960K 1 0
ip_moptions 0 0K 0K 166960K 96 0
in_multi 91 6K 7K 166960K 130 0
ether_multi 1 0K 0K 166960K 8 0
mrt 0 0K 0K 166960K 21 0
ISOFS mount 1 32K 32K 166960K 1 0
MSDOSFS mount 1 16K 16K 166960K 1 0
ttys 103 466K 466K 166960K 103 0
exec 0 0K 1K 166960K 420 0
fusefs mount 1 32K 32K 166960K 1 0
tdb 3 0K 0K 166960K 3 0
VM swap 8 62K 64K 166960K 10 0
UVM amap 221 151K 169K 166960K 8009 0
UVM aobj 49 4K 4K 166960K 50 0
pinsyscall 38 76K 96K 166960K 1809 0
memdesc 1 4K 4K 166960K 1 0
crypto data 1 1K 1K 166960K 1 0
ip6_options 0 0K 0K 166960K 23 0
NDP 10 0K 2K 166960K 35 0
temp 76 9076K 9140K 166960K 21978 0
kqueue 16 22K 33K 166960K 158 0
SYN cache 2 16K 16K 166960K 2 0
ddb> show all pools
Name Size Requests Fail Releases Pgreq Pgrel Npage Hiwat Minpg Maxpg Idle
rtpcb 120 76 0 73 1 0 1 1 0 8 0
rtentry 136 132 0 36 4 0 4 4 0 8 0
unpcb 144 560 0 544 4 0 4 4 0 8 3
syncache 336 5 0 5 1 0 1 1 0 8 1
tcpqe 32 2 0 2 1 0 1 1 0 8 1
tcpcb 736 208 0 202 4 0 4 4 0 8 3
arp 96 22 0 4 1 0 1 1 0 8 0
ipq 40 2 0 0 1 0 1 1 0 8 0
ipqe 40 4 0 0 1 0 1 1 0 8 0
inpcb 328 642 0 630 7 0 7 7 0 8 5
ip6q 72 7 0 0 1 0 1 1 0 8 0
ip6af 40 10 0 1 1 0 1 1 0 8 0
nd6 112 28 0 5 1 0 1 1 0 8 0
pkpcb 40 1 0 1 1 0 1 1 0 8 1
kcovpl 48 8 0 0 1 0 1 1 0 8 0
ppxss 1072 10 0 10 1 0 1 1 0 8 1
pfstscr 40 1 0 0 1 0 1 1 0 8 0
pfosfp 40 1 0 0 1 0 1 1 0 8 0
pfosfpen 112 1 0 0 1 0 1 1 0 8 0
pfrktable 1344 9 0 3 1 0 1 1 0 8 0
pfanchor 1288 1 0 0 1 0 1 1 0 8 0
pftag 88 4 0 0 1 0 1 1 0 8 0
pfqueue 320 1 0 0 1 0 1 1 0 8 0
pfstitem 24 2 0 0 1 0 1 1 0 8 0
pfstkey 128 2 0 0 1 0 1 1 0 8 0
pfstate 384 1 0 0 1 0 1 1 0 8 0
pfrule 1360 7 0 5 1 0 1 1 0 8 0
art_heap8 4096 2 0 0 2 0 2 2 0 8 0
art_heap4 256 524 0 116 29 0 29 29 0 8 3
art_table 40 526 0 116 5 0 5 5 0 8 0
art_node 32 129 0 44 1 0 1 1 0 8 0
sysvmsgpl 40 6 0 5 1 0 1 1 0 8 0
semupl 112 3 0 3 1 0 1 1 0 8 1
semapl 112 30 0 10 1 0 1 1 0 8 0
shmpl 112 47 0 1 2 0 2 2 0 8 0
dirhash 1024 29 0 12 3 0 3 3 0 8 0
dino2pl 256 2730 0 1279 92 0 92 92 0 8 0
ffsino 256 2730 0 1279 92 0 92 92 0 8 0
nchpl 144 3741 0 2047 64 0 64 64 0 8 0
rtmask 32 4 0 4 1 0 1 1 0 8 1
vnodes 216 3405 0 0 190 0 190 190 0 8 0
namei 1024 13144 0 13143 2 0 2 2 0 8 1
pfiaddrpl 120 2 0 0 1 0 1 1 0 8 0
kstatmem 264 36 0 16 2 0 2 2 0 8 0
acpiwqpl 32 1 0 1 1 0 1 1 1 8 1
scsiplug 72 3 0 3 1 0 1 1 0 8 1
scxspl 216 17849 0 17849 8 0 8 8 1 8 8
plimitpl 152 291 0 275 1 0 1 1 0 8 0
sigapl 424 1004 0 961 6 0 6 6 0 8 1
knotepl 120 24015 0 23966 17 7 10 17 0 8 8
kqueuepl 184 202 0 192 1 0 1 1 0 8 0
pipepl 304 146 0 119 3 0 3 3 0 8 0
fdescpl 448 989 0 960 5 0 5 5 0 8 1
filepl 120 6196 0 5971 14 0 14 14 0 8 6
lockfpl 104 430 0 426 2 0 2 2 0 8 1
lockfspl 48 130 0 127 1 0 1 1 0 8 0
sessionpl 144 53 0 44 1 0 1 1 0 8 0
pgrppl 48 70 0 53 1 0 1 1 0 8 0
ucredpl 104 942 0 929 1 0 1 1 0 8 0
zombiepl 144 964 0 961 1 0 1 1 0 8 0
processpl 1152 1004 0 961 4 0 4 4 0 8 0
procpl 664 1889 0 1839 6 0 6 6 0 8 1
sosppl 176 7 0 6 1 0 1 1 0 8 0
sockpl 552 1314 0 1283 8 0 8 8 0 8 4
mcl64k 65536 119 0 118 1 0 1 1 0 8 0
mcl8k 8192 11 0 11 1 0 1 1 0 8 1
mcl4k 4096 3298 0 3245 13 0 13 13 0 8 5
mcl2k 2048 747 0 741 2 0 2 2 0 8 1
mtagpl 96 58 0 40 2 0 2 2 0 8 1
mbufpl 256 10216 0 10058 18 0 18 18 0 8 5
bufpl 280 6515 0 301 444 0 444 444 0 8 0
anonpl 24 164312 0 156957 90 0 90 90 0 187 28
amapchunkpl 152 25612 0 25133 34 0 34 34 0 158 12
amappl16 200 3139 0 2861 31 4 27 31 0 8 8
amappl15 192 3 0 3 1 0 1 1 0 8 1
amappl14 184 407 0 406 1 0 1 1 0 8 0
amappl13 176 117 0 107 1 0 1 1 0 8 0
amappl12 168 1243 0 1213 2 0 2 2 0 8 0
amappl11 160 47 0 47 1 0 1 1 0 8 1
amappl10 152 61 0 51 1 0 1 1 0 8 0
amappl9 144 256 0 255 1 0 1 1 0 8 0
amappl8 136 151 0 149 1 0 1 1 0 8 0
amappl7 128 145 0 133 1 0 1 1 0 8 0
amappl6 120 151 0 149 1 0 1 1 0 8 0
amappl5 112 102 0 94 1 0 1 1 0 8 0
amappl4 104 306 0 290 1 0 1 1 0 8 0
amappl3 96 5139 0 5031 4 0 4 4 0 8 0
amappl2 88 541 0 487 2 0 2 2 0 8 0
amappl1 80 12183 0 11634 16 0 16 16 0 8 3
amappl 88 7206 0 7049 5 0 5 5 0 92 0
uvmvnodes 80 119 0 0 3 0 3 3 0 8 0
dma4096 4096 1 0 1 1 0 1 1 0 8 1
dma1024 1024 1 0 0 1 0 1 1 0 8 0
dma256 256 6 0 6 1 0 1 1 0 8 1
dma128 128 254 0 254 1 0 1 1 0 8 1
dma64 64 6 0 6 1 0 1 1 0 8 1
dma32 32 7 0 7 1 0 1 1 0 8 1
dma16 16 19 0 18 1 0 1 1 0 8 0
aobjpl 72 49 0 1 1 0 1 1 0 8 0
uaddrrnd 24 989 0 960 1 0 1 1 0 8 0
uaddrbest 32 2 0 0 1 0 1 1 0 8 0
uaddr 24 989 0 960 1 0 1 1 0 8 0
vmmpekpl 168 9299 0 9271 2 0 2 2 0 8 0
vmmpepl 168 69841 0 67803 107 0 107 107 0 357 7
vmsppl 368 988 0 960 4 0 4 4 0 8 1
rwobjpl 40 21040 0 19775 17 0 17 17 0 8 0
pdppl 4096 1984 0 1920 98 32 66 82 0 8 2
pvpl 32 450513 0 435851 190 0 190 190 0 265 33
pmappl 216 988 0 960 3 0 3 3 0 8 0
extentpl 40 45 0 27 1 0 1 1 0 8 0
phpool 112 371 0 32 10 0 10 10 0 8 0
ddb> machine ddbcpu 0
No such command
ddb> trace
pvclock_get(ffffffff838acd00) at pvclock_get+0x53 pvclock_read_begin sys/dev/pv/pvclock.c:278 [inline]
pvclock_get(ffffffff838acd00) at pvclock_get+0x53 sys/dev/pv/pvclock.c:333
nanouptime(ffff80003a8ebb50) at nanouptime+0x61 tc_delta sys/kern/kern_tc.c:138 [inline]
nanouptime(ffff80003a8ebb50) at nanouptime+0x61 binuptime sys/kern/kern_tc.c:191 [inline]
nanouptime(ffff80003a8ebb50) at nanouptime+0x61 sys/kern/kern_tc.c:217
tuagg_add_runtime() at tuagg_add_runtime+0x40 sys/kern/kern_resource.c:449
mi_switch() at mi_switch+0xb1 sys/kern/sched_bsd.c:372
ast(ffff80003a8ebc20) at ast+0x13d mi_ast sys/sys/syscall_mi.h:252 [inline]
ast(ffff80003a8ebc20) at ast+0x13d sys/arch/amd64/amd64/trap.c:747
Xsyscall() at Xsyscall+0x156
end of kernel
end trace frame: 0x177cb7e6e80, count: -6
ddb> machine ddbcpu 1
No such command
ddb> trace
pvclock_get(ffffffff838acd00) at pvclock_get+0x53 pvclock_read_begin sys/dev/pv/pvclock.c:278 [inline]
pvclock_get(ffffffff838acd00) at pvclock_get+0x53 sys/dev/pv/pvclock.c:333
nanouptime(ffff80003a8ebb50) at nanouptime+0x61 tc_delta sys/kern/kern_tc.c:138 [inline]
nanouptime(ffff80003a8ebb50) at nanouptime+0x61 binuptime sys/kern/kern_tc.c:191 [inline]
nanouptime(ffff80003a8ebb50) at nanouptime+0x61 sys/kern/kern_tc.c:217
tuagg_add_runtime() at tuagg_add_runtime+0x40 sys/kern/kern_resource.c:449
mi_switch() at mi_switch+0xb1 sys/kern/sched_bsd.c:372
ast(ffff80003a8ebc20) at ast+0x13d mi_ast sys/sys/syscall_mi.h:252 [inline]
ast(ffff80003a8ebc20) at ast+0x13d sys/arch/amd64/amd64/trap.c:747
Xsyscall() at Xsyscall+0x156
end of kernel
end trace frame: 0x177cb7e6e80, count: -6


---
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 report is already addressed, let syzbot know by replying with:
#syz fix: exact-commit-title

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

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

If you want to undo deduplication, reply with:
#syz undup