NetBSD Problem Report #54224
From www@netbsd.org Wed May 22 21:06:39 2019
Return-Path: <www@netbsd.org>
Received: from mail.netbsd.org (mail.netbsd.org [199.233.217.200])
(using TLSv1.2 with cipher ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits))
(Client CN "mail.NetBSD.org", Issuer "mail.NetBSD.org CA" (not verified))
by mollari.NetBSD.org (Postfix) with ESMTPS id B64747A14F
for <gnats-bugs@gnats.NetBSD.org>; Wed, 22 May 2019 21:06:39 +0000 (UTC)
Message-Id: <20190522210638.A561B7A1C8@mollari.NetBSD.org>
Date: Wed, 22 May 2019 21:06:38 +0000 (UTC)
From: perlhaq@free.fr
Reply-To: perlhaq@free.fr
To: gnats-bugs@NetBSD.org
Subject: panic: lock error: Mutex: mutex_vector_enter,552: locking against myself
X-Send-Pr-Version: www-1.0
>Number: 54224
>Category: port-evbarm
>Synopsis: panic: lock error: Mutex: mutex_vector_enter,552: locking against myself
>Confidential: no
>Severity: critical
>Priority: medium
>Responsible: port-evbarm-maintainer
>State: open
>Class: sw-bug
>Submitter-Id: net
>Arrival-Date: Wed May 22 21:10:00 +0000 2019
>Last-Modified: Sun Aug 18 18:25:01 +0000 2019
>Originator: Stephen Takacs
>Release: NetBSD 8.0
>Organization:
>Environment:
NetBSD armv7 8.0 NetBSD 8.0 (CUBIETRUCK) #0: Fri Jan 18 12:20:54 CET 2019 root@armv7:/usr/src/sys/arch/evbarm/compile/CUBIETRUCK evbarm
>Description:
This is a Cubietruck that's been running NetBSD for about 6 months, and is
seldom powered off. The problem happened twice so far. The first time was Feb
17, a bit before 03:26, according to my notes. The system panic'd and this is
what was visible on the serial console:
Mutex error: mutex_vector_enter,552: locking against myself
lock address : 0x000000009ce091ec
current cpu : 0
current lwp : 0x00000000a60e6980
owner field : 0x00000000a60e6980 wait/spin: 0/0
panic: lock error: Mutex: mutex_vector_enter,552: locking against myself: lock 0x9ce091ec cpu 0 lwp 0xa60e6980
cpu0: Begin traceback...
0x9673dbf4: netbsd:db_panic+0xc
0x9673dc0c: netbsd:vpanic+0x1a4
0x9673dc24: netbsd:snprintf
0x9673dc5c: netbsd:lockdebug_abort+0x70
0x9673dc74: netbsd:mutex_abort+0x30
0x9673dcd4: netbsd:mutex_enter+0x388
0x9673dd14: netbsd:pmap_fault_fixup+0x40
0x9673dd9c: netbsd:data_abort_handler+0x164
0x9673de14: netbsd:address_exception_entry+0x5c
0x9673df44: netbsd:uvm_fault_internal+0x32c
0x9673dfac:
And that's where the output stopped. The second event was last night, and this
time I was able to pinpoint it between 03:12 and 03:22, so the trigger appears
to be the 03:15 daily cron job (all of which is unchanged from defaults).
Here's what was on the serial console:
Mutex error: mutex_vector_enter,552: locking against myself
lock address : 0x00000000be82e93c
current cpu : 1
current lwp : 0x00000000a798edc0
owner field : 0x00000000a798edc0 wait/spin: 0/0
panic: lock error: Mutex: mutex_vector_enter,552: locking against myself: lock 0xbe82e93c cpu 1 lwp 0xa798edc0
cpu1: Begin traceback...
0xb1e83bf4: netbsd:db_panic+0xc
0xb1e83c0c: netbsd:vpanic+0x1a4
0xb1e83c24: netbsd:snprintf
0xb1e83c5c: netbsd:lockdebug_abort+0x70
0xb1e83c74: netbsd:mutex_abort+0x30
0xb1e83cd4: netbsd:mutex_enter+0x388
0xb1e83d14: netbsd:pmap_fault_fixup+0x40
0xb1e83d9c: netbsd:data_abort_handler+0x164
0xb1e83e14: netbsd:address_exception_entry+0x5c
0xb1e83f44: netbsd:uvm_fault_internal+0x32c
0xb1e83fac: netbsd:prefetch_abort_handler+0x218
cpu1: End traceback...
dumping to dev 16,1 offset 991
dump 0
And that's where the output stopped. The dump didn't work, and this
error came up when I reset the system:
savecore: kvm_read: L1 entry is invalid: Undefined error: 0
I should also mention that the CUBIETRUCK kernel config is the default one with
this additional line:
pseudo-device cgd # cryptographic disk devices
Perhaps the dump didn't work because device 16,1 (rwd0b) was assigned in
/etc/cgd/cgd.conf like so:
cgd1 /dev/wd0b /etc/cgd/swap
>How-To-Repeat:
>Fix:
>Audit-Trail:
From: Nick Hudson <nick.hudson@gmx.co.uk>
To: gnats-bugs@netbsd.org, port-evbarm-maintainer@netbsd.org,
gnats-admin@netbsd.org, netbsd-bugs@netbsd.org
Cc:
Subject: Re: port-evbarm/54224: panic: lock error: Mutex:
mutex_vector_enter,552: locking against myself
Date: Thu, 23 May 2019 08:02:25 +0100
On 22/05/2019 22:10, perlhaq@free.fr wrote:
[snip]
> Mutex error: mutex_vector_enter,552: locking against myself
>
> lock address : 0x000000009ce091ec
> current cpu : 0
> current lwp : 0x00000000a60e6980
> owner field : 0x00000000a60e6980 wait/spin: 0/0
>
> panic: lock error: Mutex: mutex_vector_enter,552: locking against myself=
: lock 0x9ce091ec cpu 0 lwp 0xa60e6980
> cpu0: Begin traceback...
> 0x9673dbf4: netbsd:db_panic+0xc
> 0x9673dc0c: netbsd:vpanic+0x1a4
> 0x9673dc24: netbsd:snprintf
> 0x9673dc5c: netbsd:lockdebug_abort+0x70
> 0x9673dc74: netbsd:mutex_abort+0x30
> 0x9673dcd4: netbsd:mutex_enter+0x388
> 0x9673dd14: netbsd:pmap_fault_fixup+0x40
> 0x9673dd9c: netbsd:data_abort_handler+0x164 > 0x9673de14: netbsd:address=
_exception_entry+0x5c
uvm_fault_internal should never fault (address_exception_entry) itself -
this is why you trigger the KASSERT. To debug this I'd turn on DDB and
UVMHIST in your kernel and when the panic triggers grab the output with
'show kernhist'
> 0x9673df44: netbsd:uvm_fault_internal+0x32c
> 0x9673dfac:
Working out where this address in the code is will be useful to. You
can do this with addr2line -e netbsd.gdb -f <address>, or gdb.
Nick
From: Stephen Takacs <perlhaq@free.fr>
To: gnats-bugs@netbsd.org
Cc: Nick Hudson <nick.hudson@gmx.co.uk>
Subject: Re: port-evbarm/54224: panic: lock error: Mutex: mutex_vector_enter,552:
locking against myself
Date: Thu, 23 May 2019 22:22:47 +0200 (CEST)
On Thu, 23 May 2019, Nick Hudson wrote:
> uvm_fault_internal should never fault (address_exception_entry) itself -
> this is why you trigger the KASSERT. To debug this I'd turn on DDB and
> UVMHIST in your kernel and when the panic triggers grab the output with
> 'show kernhist'
>
> > 0x9673df44: netbsd:uvm_fault_internal+0x32c
> > 0x9673dfac:
>
> Working out where this address in the code is will be useful to. You
> can do this with addr2line -e netbsd.gdb -f <address>, or gdb.
Unfortunately, there was no netbsd.gdb file in the compile directory, and
neither addr2line nor gdb showed anything useful with the netbsd file.
But tonight I rebuilt the kernel with these extra options:
# diff CUBIETRUCK.orig CUBIETRUCK
35a36,41
> pseudo-device cgd # cryptographic disk devices
> options UVMHIST
> options DDB
> options DDB_ONPANIC=2
> options DEBUG
> makeoptions DEBUG="-g"
So it's ready for the next time this happens...
From: Stephen Takacs <perlhaq@free.fr>
To: gnats-bugs@netbsd.org
Cc:
Subject: Re: port-evbarm/54224: panic: lock error: Mutex: mutex_vector_enter,552:
locking against myself
Date: Mon, 17 Jun 2019 13:26:35 +0200 (CEST)
This happened during last night's cron job, so it must be related. The
kernel dropped into ddb and I captured output on serial console, and
looked up the addresses in gdb. I had to cut out somewhere between
10,000-20,000 lines of ohci stuff from 'show kernhist' that preceeded
what's at the bottom of this email.
uvm_fault(0xbfcc9a28, 0, 2) -> e
Fatal kernel mode data abort: 'Translation Fault (S)'
trapframe: 0xa5af9d60
FSR=00000805, FAR=0000000c, spsr=60070013
r0 =00000000, r1 =00002000, r2 =00000004, r3 =a5af9e4c
r4 =00002000, r5 =a5af9e4c, r6 =0000fff8, r7 =a0b8c000
r8 =00000000, r9 =00000000, r10=00000000, r11=a5af9e44
r12=80017e6c, ssp=a5af9db0, slr=800d457c, pc =802b3cdc
Stopped in pid 4420.1 (find) at netbsd:ufs_readdir+0x268: strbeq r2, [r9,
#0x00c]
db{0}>
db{0}> bt
0xa5af9e44: netbsd:ufs_readdir+0xc
0xa5af9e84: netbsd:VOP_READDIR+0x58
0xa5af9efc: netbsd:vn_readdir+0xcc
0xa5af9f34: netbsd:sys___getdents30+0x7c
0xa5af9fac: netbsd:syscall+0x124
(gdb) info line *(ufs_readdir+0x268)
Line 1316 of "../../../../ufs/ufs/ufs_vnops.c" starts at address 0x802b3cdc <ufs_readdir+616>
and ends at 0x802b3ce0 <ufs_readdir+620>.
(gdb) info line *(ufs_readdir+0xc)
Line 1215 of "../../../../ufs/ufs/ufs_vnops.c" starts at address 0x802b3a74 <ufs_readdir>
and ends at 0x802b3a84 <ufs_readdir+16>.
(gdb) info line *(VOP_READDIR+0x58)
Line 1124 of "../../../../kern/vnode_if.c" starts at address 0x80385198 <VOP_READDIR+72>
and ends at 0x803851ac <VOP_READDIR+92>.
(gdb) info line *(vn_readdir+0xcc)
Line 503 of "../../../../kern/vfs_vnops.c" starts at address 0x8037eb3c <vn_readdir+200>
and ends at 0x8037eb44 <vn_readdir+208>.
(gdb) info line *(sys___getdents30+0x7c)
Line 4616 of "../../../../kern/vfs_syscalls.c" starts at address 0x80379754 <sys___getdents30+124>
and ends at 0x80379758 <sys___getdents30+128>.
(gdb) info line *(syscall+0x124)
Line 66 of "../../../../sys/syscallvar.h" starts at address 0x8001b8e8 <syscall+292>
and ends at 0x8001b8ec <syscall+296>.
db{0}> show mount
vnodecovered = 0xe1c980ba data = 0xe2862010
fs_bshift -443080704 dev_bshift = -481230848
flag = 0x3affffd1<MNT_SYMPERM,MNT_EXPUBLIC,MNT_EXNORESPORT,MNT_LOG,MNT_DISCARD,M
NT_GETARGS,MNT_UNUSED,MNT_IGNORE,MNT_FORCE,MNT_RELOAD,MNT_RELATIME,MNT_UPDATE,MN
T_NOCOREDUMP,MNT_ROOTFS,MNT_QUOTA,MNT_LOCAL,MNT_EXKERB,MNT_EXPORTANON,MNT_DEFEXP
ORTED,MNT_EXPORTED,MNT
iflag = 0xffffffffe51b1058<IMNT_DTYPE,IMNT_WANTRDONLY>
refcnt = -514785278 updating @ 0x802b45d4
statvfs cache:
bsize = 3871289138
frsize = 3850969088
iosize = 3818930176
blocks = 16258029877390295044
bfree = 16323033004816150648
bavail = 16935050455586242573
bresvd = 16361348695750934536
files = 16258012267987021823
ffree = 16321045109190172674
favail = 16935020992076587014
fresvd = 16234147161977860216
f_fsidx = { 0xe1c140f0, 0xe1d720b4 }
owner = 3789099192
namemax = 3842707564
flag = 0xa000000<MNT_EXNORESPORT,MNT_LOG>
syncwrites = 16331547588427005954
asyncwrites = 16508806915266969604
syncreads = 16542583538136326324
asyncreads = 16234147299386785792
fstypename = P0p@
mntonname = |
mntfromname = P/4?D H0H
locked vnodes =
uvm_fault(0xbfcc9a28, 5c92000, 1) -> e
Fatal kernel mode data abort: 'Translation Fault (S)'
trapframe: 0xa5af9948
FSR=00000005, FAR=05c92058, spsr=20070113
r0 =05c9200c, r1 =804889fb, r2 =00000000, r3 =80441f6c
r4 =80334648, r5 =802b3cdc, r6 =00000000, r7 =2aaaaaab
r8 =00000006, r9 =00000000, r10=05c9200c, r11=a5af99b4
r12=a5af99b8, ssp=a5af9998, slr=803743a0, pc =803857e8
Faulted in DDB; continuing...
db{0}>
db{0}> show kernhist
1560729631.939274 ohci_device_intr_start#427062@1: --- dump start ---
1560729631.939276 ohci_dump_ed#427195@1: called!
1560729631.939278 ohci_dump_ed#427195@1: ED(0xca6dcb10) at 0x433b8b10:
1560729631.939279 ohci_dump_ed#427195@1: addr=2 endpt=1 maxp=8
1560729631.939280 ohci_dump_ed#427195@1: dir=0 speed=1 skip=0 iso=0
1560729631.939281 ohci_dump_ed#427195@1: tailp=0x43427f80
1560729631.939282 ohci_dump_ed#427195@1: headp=0x43427f80 nexted=0x433b96b0 h
alted=0 carry=0
1560729631.939283 ohci_dump_td#1281851@1: called!
1560729631.939285 ohci_dump_td#1281851@1: TD(0xcd4adf80) at 0x43427f80:
1560729631.939285 ohci_dump_td#1281851@1: round=1 DP=2 DI=1 T=0
1560729631.939286 ohci_dump_td#1281851@1: EC=0 CC=15
1560729631.939286 ohci_dump_td#1281851@1: td_cbp=0x43418c80 td_nexttd=0x4342
7fc0 td_be=0x43418c87
1560729631.939287 ohci_dump_td#1281852@1: called!
1560729631.939288 ohci_dump_td#1281852@1: TD(0xcd4adfc0) at 0x43427fc0:
1560729631.939288 ohci_dump_td#1281852@1: round=0 DP=0 DI=0 T=0
1560729631.939289 ohci_dump_td#1281852@1: EC=0 CC=0
1560729631.939289 ohci_dump_td#1281852@1: td_cbp=0x00000000 td_nexttd=0x0000
0000 td_be=0x00000000
1560729631.939290 ohci_device_intr_start#427062@1: --- dump end ---
1560729631.939294 ohci_softintr#427155@1: --- ITD dump start ---
1560729631.939295 ohci_softintr#427155@1: --- ITD dump end ---
1560729631.939296 ohci_softintr#427155@1: done
1560734166.287926 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.287932 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.287938 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.287943 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.288068 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.288116 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288230 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288325 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288426 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288513 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288519 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288526 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288532 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288539 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288605 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288611 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288619 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.288626 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.288654 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288685 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288693 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288743 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288803 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288837 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288844 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288850 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.288859 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.288938 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.288965 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.288992 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.288998 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.289026 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.289053 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.289081 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.289225 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.289231 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.289271 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289278 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289284 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289289 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289297 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289303 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289310 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289316 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289323 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289387 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289393 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289480 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289569 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289612 uvm_vnp_setsize#24653692@0: called!
1560734166.289613 uvm_vnp_setsize#24653692@0: vp 0xbe5afba8 old 0xffffffffffffff
ff new 0xf
1560734166.289656 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289662 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289702 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289736 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289764 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289790 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289858 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289895 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289903 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289910 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289916 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.289982 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.290010 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.290040 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.290068 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.290110 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.290174 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.290205 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.290236 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.290328 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.290354 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.290382 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.290407 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.290433 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.290459 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.290485 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.290674 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.290732 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.290790 uvm_vnp_setsize#24653693@0: called!
1560734166.290791 uvm_vnp_setsize#24653693@0: vp 0xbe5aecd0 old 0xffffffffffffff
ff new 0x200
1560734166.290844 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.290871 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.290913 uvm_vnp_setsize#24653694@0: called!
1560734166.290914 uvm_vnp_setsize#24653694@0: vp 0xbe5af4a0 old 0xffffffffffffff
ff new 0x12e
1560734166.290958 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.291002 uvm_vnp_setsize#24653695@0: called!
1560734166.291003 uvm_vnp_setsize#24653695@0: vp 0xbe5ae1e0 old 0xffffffffffffff
ff new 0x2a0
1560734166.291045 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.291088 uvm_vnp_setsize#24653696@0: called!
1560734166.291089 uvm_vnp_setsize#24653696@0: vp 0xbe5ae050 old 0xffffffffffffff
ff new 0x314
1560734166.291131 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.291173 uvm_vnp_setsize#24653697@0: called!
1560734166.291174 uvm_vnp_setsize#24653697@0: vp 0xbe5af310 old 0xffffffffffffff
ff new 0x82
1560734166.291215 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.291246 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.291282 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.291315 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.291341 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.291387 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.291484 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.291510 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.291546 uvm_pageout#1@1: free/ftarg=9979/341
1560734166.291578 uvm_pageout#1@1: free/ftarg=9978/341
1560734166.291669 uvm_pageout#1@1: free/ftarg=9977/341
1560734166.291694 uvm_pageout#1@1: free/ftarg=9977/341
1560734166.291720 uvm_pageout#1@1: free/ftarg=9977/341
1560734166.291748 uvm_pageout#1@1: free/ftarg=9977/341
1560734166.291775 uvm_pageout#1@1: free/ftarg=9977/341
1560734166.291803 uvm_pageout#1@1: free/ftarg=9977/341
1560734166.291830 uvm_pageout#1@1: free/ftarg=9977/341
1560734166.291994 uvm_pageout#1@1: free/ftarg=9977/341
1560734166.295316 ufs_balloc_range#642205@1: called!
1560734166.295317 ufs_balloc_range#642205@1: vp 0xbf9af308 off 0x2a001 len 0x43 u
_size 0x2a001
1560734166.295329 genfs_getpages#13573136@1: called!
1560734166.295330 genfs_getpages#13573136@1: vp 0xbf9af308 off 0x0/28000 count 2
1560734166.295336 genfs_getpages#13573136@1: ridx 0 npages 2 startoff 163840 end
off 180224
1560734166.295341 uvn_findpage#34807263@1: called!
1560734166.295342 uvn_findpage#34807263@1: vp 0xbf9af308 off 0x28000
1560734166.295347 uvn_findpage#34807263@1: found 0xc12b90c0 (color 0)
1560734166.295348 uvn_findpage#34807264@1: called!
1560734166.295348 uvn_findpage#34807264@1: vp 0xbf9af308 off 0x2a000
1560734166.295349 uvn_findpage#34807264@1: found 0xc04e8360 (color 0)
1560734166.295351 genfs_getpages#13573136@1: returning cached pages
1560734166.295352 genfs_getpages#13573136@1: succeeding, npages 2
1560734166.295353 genfs_getpages#13573136@1: examining pg 0xc12b90c0 flags 0xd
1560734166.295354 genfs_getpages#13573136@1: examining pg 0xc04e8360 flags 0xd
1560734166.295362 ufs_gop_alloc#986097@1: called!
1560734166.295366 ffs_balloc#987490@1: called!
1560734166.295367 ffs_balloc#987490@1: vp 0xbf9af308 lbn 0xa size 0x2044
1560734166.295371 ufs_gop_alloc#986097@1: vp 0xbf9af308 old 0x2a001 new 0x2a044
1560734166.295377 uvm_page_unbusy#3092157@1: called!
1560734166.295379 uvm_page_unbusy#3092157@1: unbusying pg 0xc12b90c0
1560734166.295380 uvm_page_unbusy#3092157@1: unbusying pg 0xc04e8360
1560734166.295387 ubc_alloc#52286794@1: called!
1560734166.295388 ubc_alloc#52286794@1: uobj 0xbf9af308 offset 0x2a001 len 0x43
1560734166.295392 ubc_alloc#52286794@1: umap 0xc183ff40 refs 1 va 0xcaaa6000 fla
gs 0x2
1560734166.295430 ubc_fault#7940971@1: called!
1560734166.295433 ubc_fault#7940971@1: va 0xcaaa6000 ubc_offset 0x2fa000 access_
type 2
1560734166.295435 ubc_fault#7940971@1: slot_offset 0x0 writeoff 0x1 writelen 0x4
3
1560734166.295436 ubc_fault#7940971@1: getpages uobj 0xbf9af308 offset 0x2a000 n
pages 1
1560734166.295438 uvn_get#26345487@1: called!
1560734166.295439 uvn_get#26345487@1: vp 0xbf9af308 off 0x2a000
1560734166.295441 genfs_getpages#13573137@1: called!
1560734166.295441 genfs_getpages#13573137@1: vp 0xbf9af308 off 0x0/2a000 count 1
1560734166.295445 genfs_getpages#13573137@1: ridx 1 npages 1 startoff 163840 end
off 180224
1560734166.295448 uvn_findpage#34807265@1: called!
1560734166.295448 uvn_findpage#34807265@1: vp 0xbf9af308 off 0x2a000
1560734166.295449 uvn_findpage#34807265@1: found 0xc04e8360 (color 0)
1560734166.295450 genfs_getpages#13573137@1: returning cached pages
1560734166.295451 genfs_getpages#13573137@1: succeeding, npages 2
1560734166.295451 genfs_getpages#13573137@1: examining pg 0xc04e8360 flags 0x5
1560734166.295454 ubc_fault#7940971@1: getpages error 0 npages 1
1560734166.295455 ubc_fault#7940971@1: va 0xcaaa6000 eva 0xcaaa8000
1560734166.295456 ubc_fault#7940971@1: pgs[0] = 0xc04e8360
1560734166.295490 ubc_release#52286794@1: called!
1560734166.295491 ubc_release#52286794@1: va 0xcaaa6001
1560734166.295493 ubc_release#52286794@1: umap rw#jxp refs 3246653248
1560734166.295495 uvm_vnp_setsize#24653698@1: called!
1560734166.295496 uvm_vnp_setsize#24653698@1: vp 0xbf9af308 old 0x2a001 new 0x2a
044
1560734166.295561 ufs_balloc_range#642206@1: called!
1560734166.295562 ufs_balloc_range#642206@1: vp 0xbf9af308 off 0x2a044 len 0x20 u
_size 0x2a044
1560734166.295566 genfs_getpages#13573138@1: called!
1560734166.295567 genfs_getpages#13573138@1: vp 0xbf9af308 off 0x0/28000 count 2
1560734166.295569 genfs_getpages#13573138@1: ridx 0 npages 2 startoff 163840 end
off 180224
1560734166.295572 uvn_findpage#34807266@1: called!
1560734166.295573 uvn_findpage#34807266@1: vp 0xbf9af308 off 0x28000
1560734166.295574 uvn_findpage#34807266@1: found 0xc12b90c0 (color 0)
1560734166.295575 uvn_findpage#34807267@1: called!
1560734166.295575 uvn_findpage#34807267@1: vp 0xbf9af308 off 0x2a000
1560734166.295576 uvn_findpage#34807267@1: found 0xc04e8360 (color 0)
1560734166.295576 genfs_getpages#13573138@1: returning cached pages
1560734166.295577 genfs_getpages#13573138@1: succeeding, npages 2
1560734166.295577 genfs_getpages#13573138@1: examining pg 0xc12b90c0 flags 0x5
1560734166.295578 genfs_getpages#13573138@1: examining pg 0xc04e8360 flags 0x5
1560734166.295580 ufs_gop_alloc#986098@1: called!
1560734166.295582 ffs_balloc#987491@1: called!
1560734166.295583 ffs_balloc#987491@1: vp 0xbf9af308 lbn 0xa size 0x2064
1560734166.295584 ufs_gop_alloc#986098@1: vp 0xbf9af308 old 0x2a044 new 0x2a064
1560734166.295587 uvm_page_unbusy#3092158@1: called!
1560734166.295587 uvm_page_unbusy#3092158@1: unbusying pg 0xc12b90c0
1560734166.295587 uvm_page_unbusy#3092158@1: unbusying pg 0xc04e8360
1560734166.295590 ubc_alloc#52286795@1: called!
1560734166.295590 ubc_alloc#52286795@1: uobj 0xbf9af308 offset 0x2a044 len 0x20
1560734166.295592 ubc_alloc#52286795@1: umap 0xc183ff40 refs 1 va 0xcaaa6000 fla
gs 0x2
1560734166.295593 ubc_release#52286795@1: called!
1560734166.295594 ubc_release#52286795@1: va 0xcaaa6044
1560734166.295594 ubc_release#52286795@1: umap rw#jxp refs 3246653248
1560734166.295596 uvm_vnp_setsize#24653699@1: called!
1560734166.295597 uvm_vnp_setsize#24653699@1: vp 0xbf9af308 old 0x2a044 new 0x2a
064
1560734166.295702 ufs_balloc_range#642207@1: called!
1560734166.295702 ufs_balloc_range#642207@1: vp 0xbf9af308 off 0x2a064 len 0x40 u
_size 0x2a064
1560734166.295707 genfs_getpages#13573139@1: called!
1560734166.295707 genfs_getpages#13573139@1: vp 0xbf9af308 off 0x0/28000 count 2
1560734166.295709 genfs_getpages#13573139@1: ridx 0 npages 2 startoff 163840 end
off 180224
1560734166.295712 uvn_findpage#34807268@1: called!
1560734166.295712 uvn_findpage#34807268@1: vp 0xbf9af308 off 0x28000
1560734166.295714 uvn_findpage#34807268@1: found 0xc12b90c0 (color 0)
1560734166.295714 uvn_findpage#34807269@1: called!
1560734166.295714 uvn_findpage#34807269@1: vp 0xbf9af308 off 0x2a000
1560734166.295715 uvn_findpage#34807269@1: found 0xc04e8360 (color 0)
1560734166.295716 genfs_getpages#13573139@1: returning cached pages
1560734166.295716 genfs_getpages#13573139@1: succeeding, npages 2
1560734166.295717 genfs_getpages#13573139@1: examining pg 0xc12b90c0 flags 0x5
1560734166.295717 genfs_getpages#13573139@1: examining pg 0xc04e8360 flags 0x5
1560734166.295720 ufs_gop_alloc#986099@1: called!
1560734166.295721 ffs_balloc#987492@1: called!
1560734166.295722 ffs_balloc#987492@1: vp 0xbf9af308 lbn 0xa size 0x20a4
1560734166.295722 ufs_gop_alloc#986099@1: vp 0xbf9af308 old 0x2a064 new 0x2a0a4
1560734166.295725 uvm_page_unbusy#3092159@1: called!
1560734166.295725 uvm_page_unbusy#3092159@1: unbusying pg 0xc12b90c0
1560734166.295726 uvm_page_unbusy#3092159@1: unbusying pg 0xc04e8360
1560734166.295728 ubc_alloc#52286796@1: called!
1560734166.295728 ubc_alloc#52286796@1: uobj 0xbf9af308 offset 0x2a064 len 0x40
1560734166.295730 ubc_alloc#52286796@1: umap 0xc183ff40 refs 1 va 0xcaaa6000 fla
gs 0x2
1560734166.295732 ubc_release#52286796@1: called!
1560734166.295732 ubc_release#52286796@1: va 0xcaaa6064
1560734166.295733 ubc_release#52286796@1: umap rw#jxp refs 3246653248
1560734166.295734 uvm_vnp_setsize#24653700@1: called!
1560734166.295735 uvm_vnp_setsize#24653700@1: vp 0xbf9af308 old 0x2a064 new 0x2a
0a4
1560734166.299917 ufs_balloc_range#642208@1: called!
1560734166.299918 ufs_balloc_range#642208@1: vp 0xbf9af308 off 0x2a0a4 len 0x4b u
_size 0x2a0a4
1560734166.299922 genfs_getpages#13573140@1: called!
1560734166.299923 genfs_getpages#13573140@1: vp 0xbf9af308 off 0x0/28000 count 2
1560734166.299926 genfs_getpages#13573140@1: ridx 0 npages 2 startoff 163840 end
off 180224
1560734166.299929 uvn_findpage#34807270@1: called!
1560734166.299930 uvn_findpage#34807270@1: vp 0xbf9af308 off 0x28000
1560734166.299931 uvn_findpage#34807270@1: found 0xc12b90c0 (color 0)
1560734166.299932 uvn_findpage#34807271@1: called!
1560734166.299932 uvn_findpage#34807271@1: vp 0xbf9af308 off 0x2a000
1560734166.299933 uvn_findpage#34807271@1: found 0xc04e8360 (color 0)
1560734166.299934 genfs_getpages#13573140@1: returning cached pages
1560734166.299934 genfs_getpages#13573140@1: succeeding, npages 2
1560734166.299935 genfs_getpages#13573140@1: examining pg 0xc12b90c0 flags 0x5
1560734166.299936 genfs_getpages#13573140@1: examining pg 0xc04e8360 flags 0x5
1560734166.299939 ufs_gop_alloc#986100@1: called!
1560734166.299940 ffs_balloc#987493@1: called!
1560734166.299940 ffs_balloc#987493@1: vp 0xbf9af308 lbn 0xa size 0x20ef
1560734166.299942 ufs_gop_alloc#986100@1: vp 0xbf9af308 old 0x2a0a4 new 0x2a0ef
1560734166.299944 uvm_page_unbusy#3092160@1: called!
1560734166.299945 uvm_page_unbusy#3092160@1: unbusying pg 0xc12b90c0
1560734166.299945 uvm_page_unbusy#3092160@1: unbusying pg 0xc04e8360
1560734166.299948 ubc_alloc#52286797@1: called!
1560734166.299948 ubc_alloc#52286797@1: uobj 0xbf9af308 offset 0x2a0a4 len 0x4b
1560734166.299951 ubc_alloc#52286797@1: umap 0xc183ff40 refs 1 va 0xcaaa6000 fla
gs 0x2
1560734166.299953 ubc_release#52286797@1: called!
1560734166.299953 ubc_release#52286797@1: va 0xcaaa60a4
1560734166.299954 ubc_release#52286797@1: umap rw#jxp refs 3246653248
1560734166.299955 uvm_vnp_setsize#24653701@1: called!
1560734166.299956 uvm_vnp_setsize#24653701@1: vp 0xbf9af308 old 0x2a0a4 new 0x2a
0ef
1560734166.303424 ufs_balloc_range#642209@1: called!
1560734166.303425 ufs_balloc_range#642209@1: vp 0xbf9af308 off 0x2a0ef len 0x35 u
_size 0x2a0ef
1560734166.303429 genfs_getpages#13573141@1: called!
1560734166.303429 genfs_getpages#13573141@1: vp 0xbf9af308 off 0x0/28000 count 2
1560734166.303431 genfs_getpages#13573141@1: ridx 0 npages 2 startoff 163840 end
off 180224
1560734166.303434 uvn_findpage#34807272@1: called!
1560734166.303434 uvn_findpage#34807272@1: vp 0xbf9af308 off 0x28000
1560734166.303435 uvn_findpage#34807272@1: found 0xc12b90c0 (color 0)
1560734166.303436 uvn_findpage#34807273@1: called!
1560734166.303436 uvn_findpage#34807273@1: vp 0xbf9af308 off 0x2a000
1560734166.303437 uvn_findpage#34807273@1: found 0xc04e8360 (color 0)
1560734166.303438 genfs_getpages#13573141@1: returning cached pages
1560734166.303438 genfs_getpages#13573141@1: succeeding, npages 2
1560734166.303439 genfs_getpages#13573141@1: examining pg 0xc12b90c0 flags 0x5
1560734166.303439 genfs_getpages#13573141@1: examining pg 0xc04e8360 flags 0x5
1560734166.303442 ufs_gop_alloc#986101@1: called!
1560734166.303443 ffs_balloc#987494@1: called!
1560734166.303443 ffs_balloc#987494@1: vp 0xbf9af308 lbn 0xa size 0x2124
1560734166.303444 ufs_gop_alloc#986101@1: vp 0xbf9af308 old 0x2a0ef new 0x2a124
1560734166.303446 uvm_page_unbusy#3092161@1: called!
1560734166.303447 uvm_page_unbusy#3092161@1: unbusying pg 0xc12b90c0
1560734166.303447 uvm_page_unbusy#3092161@1: unbusying pg 0xc04e8360
1560734166.303449 ubc_alloc#52286798@1: called!
1560734166.303450 ubc_alloc#52286798@1: uobj 0xbf9af308 offset 0x2a0ef len 0x35
1560734166.303451 ubc_alloc#52286798@1: umap 0xc183ff40 refs 1 va 0xcaaa6000 fla
gs 0x2
1560734166.303454 ubc_release#52286798@1: called!
1560734166.303454 ubc_release#52286798@1: va 0xcaaa60ef
1560734166.303455 ubc_release#52286798@1: umap rw#jxp refs 3246653248
1560734166.303456 uvm_vnp_setsize#24653702@1: called!
1560734166.303457 uvm_vnp_setsize#24653702@1: vp 0xbf9af308 old 0x2a0ef new 0x2a
124
1560734166.305583 ufs_balloc_range#642210@1: called!
1560734166.305583 ufs_balloc_range#642210@1: vp 0xbf9af308 off 0x2a124 len 0x49 u
_size 0x2a124
1560734166.305586 genfs_getpages#13573142@1: called!
1560734166.305586 genfs_getpages#13573142@1: vp 0xbf9af308 off 0x0/28000 count 2
1560734166.305589 genfs_getpages#13573142@1: ridx 0 npages 2 startoff 163840 end
off 180224
1560734166.305592 uvn_findpage#34807274@1: called!
1560734166.305592 uvn_findpage#34807274@1: vp 0xbf9af308 off 0x28000
1560734166.305593 uvn_findpage#34807274@1: found 0xc12b90c0 (color 0)
1560734166.305594 uvn_findpage#34807275@1: called!
1560734166.305594 uvn_findpage#34807275@1: vp 0xbf9af308 off 0x2a000
1560734166.305594 uvn_findpage#34807275@1: found 0xc04e8360 (color 0)
1560734166.305595 genfs_getpages#13573142@1: returning cached pages
1560734166.305595 genfs_getpages#13573142@1: succeeding, npages 2
1560734166.305596 genfs_getpages#13573142@1: examining pg 0xc12b90c0 flags 0x5
1560734166.305597 genfs_getpages#13573142@1: examining pg 0xc04e8360 flags 0x5
1560734166.305599 ufs_gop_alloc#986102@1: called!
1560734166.305600 ffs_balloc#987495@1: called!
1560734166.305600 ffs_balloc#987495@1: vp 0xbf9af308 lbn 0xa size 0x216d
1560734166.305601 ufs_gop_alloc#986102@1: vp 0xbf9af308 old 0x2a124 new 0x2a16d
1560734166.305603 uvm_page_unbusy#3092162@1: called!
1560734166.305604 uvm_page_unbusy#3092162@1: unbusying pg 0xc12b90c0
1560734166.305604 uvm_page_unbusy#3092162@1: unbusying pg 0xc04e8360
1560734166.305607 ubc_alloc#52286799@1: called!
1560734166.305607 ubc_alloc#52286799@1: uobj 0xbf9af308 offset 0x2a124 len 0x49
1560734166.305608 ubc_alloc#52286799@1: umap 0xc183ff40 refs 1 va 0xcaaa6000 fla
gs 0x2
1560734166.305610 ubc_release#52286799@1: called!
1560734166.305611 ubc_release#52286799@1: va 0xcaaa6124
1560734166.305612 ubc_release#52286799@1: umap rw#jxp refs 3246653248
1560734166.305613 uvm_vnp_setsize#24653703@1: called!
1560734166.305614 uvm_vnp_setsize#24653703@1: vp 0xbf9af308 old 0x2a124 new 0x2a
16d
1560734166.310476 ufs_balloc_range#642211@1: called!
1560734166.310477 ufs_balloc_range#642211@1: vp 0xbf9af308 off 0x2a16d len 0x56 u
_size 0x2a16d
1560734166.310481 genfs_getpages#13573143@1: called!
1560734166.310481 genfs_getpages#13573143@1: vp 0xbf9af308 off 0x0/28000 count 2
1560734166.310484 genfs_getpages#13573143@1: ridx 0 npages 2 startoff 163840 end
off 180224
1560734166.310487 uvn_findpage#34807276@1: called!
1560734166.310487 uvn_findpage#34807276@1: vp 0xbf9af308 off 0x28000
1560734166.310488 uvn_findpage#34807276@1: found 0xc12b90c0 (color 0)
1560734166.310489 uvn_findpage#34807277@1: called!
1560734166.310489 uvn_findpage#34807277@1: vp 0xbf9af308 off 0x2a000
1560734166.310489 uvn_findpage#34807277@1: found 0xc04e8360 (color 0)
1560734166.310490 genfs_getpages#13573143@1: returning cached pages
1560734166.310490 genfs_getpages#13573143@1: succeeding, npages 2
1560734166.310491 genfs_getpages#13573143@1: examining pg 0xc12b90c0 flags 0x5
1560734166.310491 genfs_getpages#13573143@1: examining pg 0xc04e8360 flags 0x5
1560734166.310494 ufs_gop_alloc#986103@1: called!
1560734166.310495 ffs_balloc#987496@1: called!
1560734166.310495 ffs_balloc#987496@1: vp 0xbf9af308 lbn 0xa size 0x21c3
1560734166.310496 ufs_gop_alloc#986103@1: vp 0xbf9af308 old 0x2a16d new 0x2a1c3
1560734166.310499 uvm_page_unbusy#3092163@1: called!
1560734166.310499 uvm_page_unbusy#3092163@1: unbusying pg 0xc12b90c0
1560734166.310500 uvm_page_unbusy#3092163@1: unbusying pg 0xc04e8360
1560734166.310502 ubc_alloc#52286800@1: called!
1560734166.310503 ubc_alloc#52286800@1: uobj 0xbf9af308 offset 0x2a16d len 0x56
1560734166.310505 ubc_alloc#52286800@1: umap 0xc183ff40 refs 1 va 0xcaaa6000 fla
gs 0x2
1560734166.310506 ubc_release#52286800@1: called!
1560734166.310507 ubc_release#52286800@1: va 0xcaaa616d
1560734166.310508 ubc_release#52286800@1: umap rw#jxp refs 3246653248
1560734166.310509 uvm_vnp_setsize#24653704@1: called!
1560734166.310510 uvm_vnp_setsize#24653704@1: vp 0xbf9af308 old 0x2a16d new 0x2a
1c3
1560734166.310559 pmap_deactivate#238346380@1: <-- done
1560734166.310560 pmap_activate#238346380@1: called!
1560734166.310560 pmap_activate#238346380@1: (l=0xbff67360) pm=0x805214d0
1560734166.310560 pmap_activate#238346380@1: <-- (same pmap)
1560734166.315203 pmap_deactivate#238346381@1: called!
1560734166.315203 pmap_deactivate#238346381@1: (l=0xbff67360) pm=0x805214d0
1560734166.315203 pmap_md_pdetab_deactivate#238346381@1: called!
1560734166.315204 pmap_tlb_asid_deactivate#238361215@1: called!
1560734166.315204 pmap_tlb_asid_deactivate#238361215@1: <-- done (pm=0x805214d0
)
1560734166.315205 pmap_deactivate#238346381@1: <-- done
1560734166.315205 pmap_activate#238346381@1: called!
1560734166.315205 pmap_activate#238346381@1: (l=0xbfc0e640) pm=0xbfbae010
1560734166.315206 pmap_md_pdetab_activate#478158600@1: called!
1560734166.315206 pmap_tlb_asid_acquire#478158600@1: called!
1560734166.315206 pmap_tlb_asid_acquire#478158600@1: (pm=0xbfbae010, l=0xbfc0e64
0, ti=0x80521dd8)
1560734166.315207 pmap_tlb_asid_acquire#478158600@1: setting asid to 0x9f
1560734166.315207 pmap_tlb_asid_acquire#478158600@1: <-- done
1560734166.315208 pmap_md_pdetab_activate#478158600@1: pm 0xbfbae010 pm->pm_l1_
pa bf9a8000 asid 159... done
1560734166.315208 pmap_activate#238346381@1: <-- done
1560734166.315296 ufs_balloc_range#642212@1: called!
1560734166.315297 ufs_balloc_range#642212@1: vp 0xbf9af308 off 0x2a1c3 len 0x56 u
_size 0x2a1c3
1560734166.315300 genfs_getpages#13573144@1: called!
1560734166.315300 genfs_getpages#13573144@1: vp 0xbf9af308 off 0x0/28000 count 2
1560734166.315302 genfs_getpages#13573144@1: ridx 0 npages 2 startoff 163840 end
off 180224
1560734166.315304 uvn_findpage#34807278@1: called!
1560734166.315305 uvn_findpage#34807278@1: vp 0xbf9af308 off 0x28000
1560734166.315306 uvn_findpage#34807278@1: found 0xc12b90c0 (color 0)
1560734166.315307 uvn_findpage#34807279@1: called!
1560734166.315307 uvn_findpage#34807279@1: vp 0xbf9af308 off 0x2a000
1560734166.315308 uvn_findpage#34807279@1: found 0xc04e8360 (color 0)
1560734166.315308 genfs_getpages#13573144@1: returning cached pages
1560734166.315308 genfs_getpages#13573144@1: succeeding, npages 2
1560734166.315309 genfs_getpages#13573144@1: examining pg 0xc12b90c0 flags 0x5
1560734166.315310 genfs_getpages#13573144@1: examining pg 0xc04e8360 flags 0x5
1560734166.315313 ufs_gop_alloc#986104@1: called!
1560734166.315313 ffs_balloc#987497@1: called!
1560734166.315314 ffs_balloc#987497@1: vp 0xbf9af308 lbn 0xa size 0x2219
1560734166.315315 ufs_gop_alloc#986104@1: vp 0xbf9af308 old 0x2a1c3 new 0x2a219
1560734166.315317 uvm_page_unbusy#3092164@1: called!
1560734166.315317 uvm_page_unbusy#3092164@1: unbusying pg 0xc12b90c0
1560734166.315318 uvm_page_unbusy#3092164@1: unbusying pg 0xc04e8360
1560734166.315320 ubc_alloc#52286801@1: called!
1560734166.315321 ubc_alloc#52286801@1: uobj 0xbf9af308 offset 0x2a1c3 len 0x56
1560734166.315322 ubc_alloc#52286801@1: umap 0xc183ff40 refs 1 va 0xcaaa6000 fla
gs 0x2
1560734166.315324 ubc_release#52286801@1: called!
1560734166.315325 ubc_release#52286801@1: va 0xcaaa61c3
1560734166.315326 ubc_release#52286801@1: umap rw#jxp refs 3246653248
1560734166.315327 uvm_vnp_setsize#24653705@1: called!
1560734166.315328 uvm_vnp_setsize#24653705@1: vp 0xbf9af308 old 0x2a1c3 new 0x2a
219
1560734166.315370 pmap_deactivate#238346382@1: called!
1560734166.315370 pmap_deactivate#238346382@1: (l=0xbfc0e640) pm=0xbfbae010
1560734166.315371 pmap_md_pdetab_deactivate#238346382@1: called!
1560734166.315371 pmap_tlb_asid_deactivate#238361216@1: called!
1560734166.315372 pmap_tlb_asid_deactivate#238361216@1: <-- done (pm=0xbfbae010
)
1560734166.315373 pmap_deactivate#238346382@1: <-- done
1560734166.315374 pmap_activate#238346382@1: called!
1560734166.315374 pmap_activate#238346382@1: (l=0xbff67360) pm=0x805214d0
1560734166.315374 pmap_activate#238346382@1: <-- (same pmap)
1560734166.320059 pmap_deactivate#238346383@1: called!
1560734166.320059 pmap_deactivate#238346383@1: (l=0xbff67360) pm=0x805214d0
1560734166.320059 pmap_md_pdetab_deactivate#238346383@1: called!
1560734166.320060 pmap_tlb_asid_deactivate#238361217@1: called!
1560734166.320060 pmap_tlb_asid_deactivate#238361217@1: <-- done (pm=0x805214d0
)
1560734166.320060 pmap_deactivate#238346383@1: <-- done
1560734166.320061 pmap_activate#238346383@1: called!
1560734166.320061 pmap_activate#238346383@1: (l=0xbfc0e640) pm=0xbfbae010
1560734166.320061 pmap_md_pdetab_activate#478158601@1: called!
1560734166.320062 pmap_tlb_asid_acquire#478158601@1: called!
1560734166.320062 pmap_tlb_asid_acquire#478158601@1: (pm=0xbfbae010, l=0xbfc0e64
0, ti=0x80521dd8)
1560734166.320063 pmap_tlb_asid_acquire#478158601@1: setting asid to 0x9f
1560734166.320063 pmap_tlb_asid_acquire#478158601@1: <-- done
1560734166.320064 pmap_md_pdetab_activate#478158601@1: pm 0xbfbae010 pm->pm_l1_
pa bf9a8000 asid 159... done
1560734166.320064 pmap_activate#238346383@1: <-- done
1560734166.320148 ufs_balloc_range#642213@1: called!
1560734166.320148 ufs_balloc_range#642213@1: vp 0xbf9af308 off 0x2a219 len 0x56 u
_size 0x2a219
1560734166.320151 genfs_getpages#13573145@1: called!
1560734166.320152 genfs_getpages#13573145@1: vp 0xbf9af308 off 0x0/28000 count 2
1560734166.320154 genfs_getpages#13573145@1: ridx 0 npages 2 startoff 163840 end
off 180224
1560734166.320157 uvn_findpage#34807280@1: called!
1560734166.320157 uvn_findpage#34807280@1: vp 0xbf9af308 off 0x28000
1560734166.320158 uvn_findpage#34807280@1: found 0xc12b90c0 (color 0)
1560734166.320159 uvn_findpage#34807281@1: called!
1560734166.320159 uvn_findpage#34807281@1: vp 0xbf9af308 off 0x2a000
1560734166.320159 uvn_findpage#34807281@1: found 0xc04e8360 (color 0)
1560734166.320160 genfs_getpages#13573145@1: returning cached pages
1560734166.320160 genfs_getpages#13573145@1: succeeding, npages 2
1560734166.320161 genfs_getpages#13573145@1: examining pg 0xc12b90c0 flags 0x5
1560734166.320162 genfs_getpages#13573145@1: examining pg 0xc04e8360 flags 0x5
1560734166.320164 ufs_gop_alloc#986105@1: called!
1560734166.320165 ffs_balloc#987498@1: called!
1560734166.320165 ffs_balloc#987498@1: vp 0xbf9af308 lbn 0xa size 0x226f
1560734166.320166 ufs_gop_alloc#986105@1: vp 0xbf9af308 old 0x2a219 new 0x2a26f
1560734166.320168 uvm_page_unbusy#3092165@1: called!
1560734166.320169 uvm_page_unbusy#3092165@1: unbusying pg 0xc12b90c0
1560734166.320170 uvm_page_unbusy#3092165@1: unbusying pg 0xc04e8360
1560734166.320172 ubc_alloc#52286802@1: called!
1560734166.320172 ubc_alloc#52286802@1: uobj 0xbf9af308 offset 0x2a219 len 0x56
1560734166.320174 ubc_alloc#52286802@1: umap 0xc183ff40 refs 1 va 0xcaaa6000 fla
gs 0x2
1560734166.320176 ubc_release#52286802@1: called!
1560734166.320176 ubc_release#52286802@1: va 0xcaaa6219
1560734166.320177 ubc_release#52286802@1: umap rw#jxp refs 3246653248
1560734166.320178 uvm_vnp_setsize#24653706@1: called!
1560734166.320179 uvm_vnp_setsize#24653706@1: vp 0xbf9af308 old 0x2a219 new 0x2a
26f
1560734166.320222 pmap_deactivate#238346384@1: called!
1560734166.320222 pmap_deactivate#238346384@1: (l=0xbfc0e640) pm=0xbfbae010
1560734166.320223 pmap_md_pdetab_deactivate#238346384@1: called!
1560734166.320223 pmap_tlb_asid_deactivate#238361218@1: called!
1560734166.320224 pmap_tlb_asid_deactivate#238361218@1: <-- done (pm=0xbfbae010
)
1560734166.320224 pmap_deactivate#238346384@1: <-- done
1560734166.320225 pmap_activate#238346384@1: called!
1560734166.320225 pmap_activate#238346384@1: (l=0xbff67360) pm=0x805214d0
1560734166.320226 pmap_activate#238346384@1: <-- (same pmap)
1560734166.321269 pmap_deactivate#238346385@1: called!
1560734166.321269 pmap_deactivate#238346385@1: (l=0xbff67360) pm=0x805214d0
1560734166.321269 pmap_md_pdetab_deactivate#238346385@1: called!
1560734166.321269 pmap_tlb_asid_deactivate#238361219@1: called!
1560734166.321270 pmap_tlb_asid_deactivate#238361219@1: <-- done (pm=0x805214d0
)
1560734166.321270 pmap_deactivate#238346385@1: <-- done
1560734166.321271 pmap_activate#238346385@1: called!
1560734166.321271 pmap_activate#238346385@1: (l=0xbfc0e640) pm=0xbfbae010
1560734166.321271 pmap_md_pdetab_activate#478158602@1: called!
1560734166.321272 pmap_tlb_asid_acquire#478158602@1: called!
1560734166.321272 pmap_tlb_asid_acquire#478158602@1: (pm=0xbfbae010, l=0xbfc0e64
0, ti=0x80521dd8)
1560734166.321272 pmap_tlb_asid_acquire#478158602@1: setting asid to 0x9f
1560734166.321273 pmap_tlb_asid_acquire#478158602@1: <-- done
1560734166.321273 pmap_md_pdetab_activate#478158602@1: pm 0xbfbae010 pm->pm_l1_
pa bf9a8000 asid 159... done
1560734166.321274 pmap_activate#238346385@1: <-- done
1560734166.321300 pmap_deactivate#238346386@1: called!
1560734166.321300 pmap_deactivate#238346386@1: (l=0xbfc0e640) pm=0xbfbae010
1560734166.321301 pmap_md_pdetab_deactivate#238346386@1: called!
1560734166.321301 pmap_tlb_asid_deactivate#238361220@1: called!
1560734166.321302 pmap_tlb_asid_deactivate#238361220@1: <-- done (pm=0xbfbae010
)
1560734166.321302 pmap_deactivate#238346386@1: <-- done
1560734166.321303 pmap_activate#238346386@1: called!
1560734166.321303 pmap_activate#238346386@1: (l=0xbff67360) pm=0x805214d0
1560734166.321303 pmap_activate#238346386@1: <-- (same pmap)
1560734166.323521 pmap_deactivate#238346387@1: called!
1560734166.323522 pmap_deactivate#238346387@1: (l=0xbff67360) pm=0x805214d0
1560734166.323522 pmap_md_pdetab_deactivate#238346387@1: called!
1560734166.323522 pmap_tlb_asid_deactivate#238361221@1: called!
1560734166.323522 pmap_tlb_asid_deactivate#238361221@1: <-- done (pm=0x805214d0
)
1560734166.323523 pmap_deactivate#238346387@1: <-- done
1560734166.323523 pmap_activate#238346387@1: called!
1560734166.323524 pmap_activate#238346387@1: (l=0xbfc0e640) pm=0xbfbae010
1560734166.323524 pmap_md_pdetab_activate#478158603@1: called!
1560734166.323524 pmap_tlb_asid_acquire#478158603@1: called!
1560734166.323525 pmap_tlb_asid_acquire#478158603@1: (pm=0xbfbae010, l=0xbfc0e64
0, ti=0x80521dd8)
1560734166.323525 pmap_tlb_asid_acquire#478158603@1: setting asid to 0x9f
1560734166.323526 pmap_tlb_asid_acquire#478158603@1: <-- done
1560734166.323526 pmap_md_pdetab_activate#478158603@1: pm 0xbfbae010 pm->pm_l1_
pa bf9a8000 asid 159... done
1560734166.323527 pmap_activate#238346387@1: <-- done
1560734166.323553 pmap_deactivate#238346388@1: called!
1560734166.323553 pmap_deactivate#238346388@1: (l=0xbfc0e640) pm=0xbfbae010
1560734166.323553 pmap_md_pdetab_deactivate#238346388@1: called!
1560734166.323554 pmap_tlb_asid_deactivate#238361222@1: called!
1560734166.323554 pmap_tlb_asid_deactivate#238361222@1: <-- done (pm=0xbfbae010
)
1560734166.323555 pmap_deactivate#238346388@1: <-- done
1560734166.323555 pmap_activate#238346388@1: called!
1560734166.323555 pmap_activate#238346388@1: (l=0xbff67360) pm=0x805214d0
1560734166.323556 pmap_activate#238346388@1: <-- (same pmap)
db{0}>
From: Stephen Takacs <perlhaq@free.fr>
To: gnats-bugs@netbsd.org
Cc:
Subject: Re: port-evbarm/54224
Date: Sun, 18 Aug 2019 20:23:47 +0200 (CEST)
This seems to be related to the same bug. This time the trigger was a
"du -sh /usr/src/sys/" issued from the shell, while logged in over ssh.
I couldn't find the source file and line number for memset+0x48; it must
be a macro that calls another function, but I couldn't tell which.
uvm_fault(0xbfcc6bd0, 0, 2) -> e
Fatal kernel mode data abort: 'Translation Fault (S)'
trapframe: 0xbfc33a50
FSR=00000805, FAR=00000000, spsr=20010013
r0 =00000000, r1 =00000048, r2 =00000000, r3 =00000000
r4 =bfb82008, r5 =bfc93a88, r6 =80a6b000, r7 =00000000
r8 =00000000, r9 =809c4d60, r10=00000018, r11=bfc33af4
r12=00000000, ssp=bfc33aa0, slr=802a6f74, pc =80405d3c
Stopped in pid 366.1 (du) at netbsd:memset+0x48: strdge r2, [r12], #0x08
db{1}> bt
0xbfc33af4: netbsd:ffs_init_vnode+0xc
0xbfc33b2c: netbsd:ffs_loadvnode+0x40
0xbfc33b94: netbsd:vcache_get+0x2a8
0xbfc33c7c: netbsd:ufs_lookup+0x940
0xbfc33ca4: netbsd:VOP_LOOKUP+0x44
0xbfc33cf4: netbsd:lookup_once+0x178
0xbfc33dc4: netbsd:namei_tryemulroot.constprop.9+0x3f8
0xbfc33df4: netbsd:namei+0x50
0xbfc33e1c: netbsd:fd_nameiat.isra.2+0x64
0xbfc33e7c: netbsd:do_sys_statat+0x60
0xbfc33f34: netbsd:sys___lstat50+0x2c
0xbfc33fac: netbsd:syscall+0x124
(gdb) info line *(memset+0x48)
No line number information available for address 0x80405d3c <memset+72>
(gdb) info line *(ffs_init_vnode+0xc)
Line 1973 of "../../../../ufs/ffs/ffs_vfsops.c" starts at address 0x802a6e10 <ffs_init_vnode>
and ends at 0x802a6e24 <ffs_init_vnode+20>.
(gdb) info line *(ffs_loadvnode+0x40)
Line 2057 of "../../../../ufs/ffs/ffs_vfsops.c" starts at address 0x802a766c <ffs_loadvnode+64>
and ends at 0x802a7674 <ffs_loadvnode+72>.
(gdb) info line *(vcache_get+0x2a8)
Line 1344 of "../../../../kern/vfs_vnode.c" starts at address 0x8037ccdc <vcache_get+680>
and ends at 0x8037cce4 <vcache_get+688>.
(gdb) info line *(ufs_lookup+0x940)
Line 684 of "../../../../ufs/ufs/ufs_lookup.c" starts at address 0x802b0214 <ufs_lookup+2368>
and ends at 0x802b0220 <ufs_lookup+2380>.
(gdb) info line *(VOP_LOOKUP+0x44)
Line 175 of "../../../../kern/vnode_if.c" starts at address 0x8038424c <VOP_LOOKUP+52>
and ends at 0x80384260 <VOP_LOOKUP+72>.
(gdb) info line *(lookup_once+0x178)
Line 991 of "../../../../kern/vfs_lookup.c" starts at address 0x8036d3dc <lookup_once+376>
and ends at 0x8036d3e4 <lookup_once+384>.
(gdb) info line *('namei_tryemulroot.constprop.9'+0x3f8)
Line 1229 of "../../../../kern/vfs_lookup.c" starts at address 0x8036dcf0 <namei_tryemulroot+1016>
and ends at 0x8036dcf8 <namei_tryemulroot+1024>.
(gdb) info line *(namei+0x50)
Line 1558 of "../../../../kern/vfs_lookup.c" starts at address 0x8036f350 <namei+80>
and ends at 0x8036f358 <lookup_for_nfsd>.
(gdb) info line *('fd_nameiat.isra.2'+0x64)
Line 180 of "../../../../kern/vfs_syscalls.c" starts at address 0x80374ad0 <fd_nameiat+92>
and ends at 0x80374adc <fd_nameiat+104>.
(gdb) info line *(do_sys_statat+0x60)
Line 3039 of "../../../../kern/vfs_syscalls.c" starts at address 0x8037854c <do_sys_statat+96>
and ends at 0x80378554 <do_sys_statat+104>.
(gdb) info line *(sys___lstat50+0x2c)
Line 3084 of "../../../../kern/vfs_syscalls.c" starts at address 0x80378628 <sys___lstat50+44>
and ends at 0x80378630 <sys___lstat50+52>.
(gdb) info line *(syscall+0x124)
Line 66 of "../../../../sys/syscallvar.h" starts at address 0x8001b8e8 <syscall+292>
and ends at 0x8001b8ec <syscall+296>.
(gdb)
db{1}> show mount
vnodecovered = 0xa0cc20f8 data = 0xa0cc20f8
fs_bshift -1597234952 dev_bshift = -1597234952
flag = 0xffffffffa0cc20f8<MNT_SOFTDEP,MNT_SYMPERM,MNT_DISCARD,MNT_GETARGS,MNT_FO
RCE,MNT_RELOAD,MNT_QUOTA,MNT_EXRDONLY,MNT_ASYNC,MNT_UNION,MNT_NODEV,MNT_NOSUID>
iflag = 0xffffffffa0cc20f8<IMNT_HAS_TRANS,IMNT_DTYPE,IMNT_WANTRDONLY>
refcnt = -1597234952 updating @ 0x80406634
statvfs cache:
bsize = 2697732344
frsize = 3405774829
iosize = 19922718
blocks = 16307833459110383744
bfree = 11586672193539154168
bavail = 11586672193539154168
bresvd = 85567425663205369
files = 11586672194642579472
ffree = 85567424955162872
favail = 16307833338045992976
fresvd = 14627691575280152580
f_fsidx = { 0xe2622004, 0xe4cc3001 }
owner = 3762360322
namemax = 2764845057
flag = 0xa0cc20f8<MNT_SOFTDEP,MNT_SYMPERM,MNT_DISCARD,MNT_GETARGS,MNT_FO
RCE,MNT_RELOAD,MNT_QUOTA,MNT_EXRDONLY,MNT_ASYNC,MNT_UNION,MNT_NODEV,MNT_NOSUID>
syncwrites = 16379873328333061918
asyncwrites = 16226468490071781376
syncreads = 16325847733580594974
asyncreads = 11874919102160842753
fstypename = /0
mntonname = 0-L
mntfromname = /
locked vnodes =
uvm_fault(0xbfcc6bd0, 42a9a000, 1) -> e
Fatal kernel mode data abort: 'Translation Fault (S)'
trapframe: 0xbfc33638
FSR=00000005, FAR=42a9ab1b, spsr=20010113
r0 =bfc33688, r1 =ebe09517, r2 =00000000, r3 =42a9aa83
r4 =00000010, r5 =a0cc20f8, r6 =00000000, r7 =2aaaaaab
r8 =00000006, r9 =00000000, r10=a0cc20f8, r11=bfc336a4
r12=bfc336a8, ssp=bfc33688, slr=803743b8, pc =80385820
Faulted in DDB; continuing...
db{1}> show vnode
OBJECT 0x80405d3c: locked=0, pgops=0xa0cc20f8, npages=-1597234952, refs=-1597234
952
vnode 0x80405d3c flags 0xffffffffe2dd30f8<MAPPED,MPSAFE,LOCKSWORK>
tag UNKNOWN(-1300164592) type UNKNOWN(19922718) mount 0xcafffff9 typedata 0xa0cc20f8
usecount -1597234952 writecount -1597234952 holdcount -1597234952
size a0cc20f8a0cc20f8 writesize 12fff1ecaffffed numoutput -1597234952
data 0xe2511004 lock 0x80405df4
state ILLEGAL key(0xc4cc3001 -1426063422)
lrulisthd 0xe2911004
db{1}> ps
PID LID S CPU FLAGS STRUCT LWP * NAME WAIT
366 > 1 7 1 0 bfcba360 du
135 1 4 0 1000000 bf1e4ec0 lynx
747 1 3 0 80 bfc18640 pickup kqueue
780 1 3 0 80 bf1e4940 ksh pause
621 1 3 1 80 bf1e4c00 sshd select
206 1 3 0 80 bfcba0a0 sshd select
166 1 3 0 80 bf968920 mc select
39 1 3 0 80 bf1e5180 ksh pause
40 1 3 1 80 bf1e5440 sshd select
803 1 3 0 80 bf1e5700 sshd select
659 1 3 1 80 bf968ea0 ksh ttyraw
764 1 3 0 80 bf1e59c0 getty ttyraw
733 1 3 1 80 bf1e5c80 getty ttyraw
761 1 3 0 80 bfd365e0 getty ttyraw
645 1 3 1 80 bfd36b60 login wait
757 1 3 0 80 bfc18e80 cron nanoslp
671 1 3 0 80 bf9683a0 inetd kqueue
740 1 3 1 80 bfc18bc0 qmgr kqueue
593 1 3 1 80 bfc19c40 master kqueue
458 1 3 0 80 bf969c60 sshd select
446 1 3 1 80 bf968660 tor kqueue
251 1 3 1 80 bf969420 mount_mfs mfsidl
214 1 3 0 80 bfc180c0 unbound select
207 > 1 7 0 0 bfc18380 syslogd
1 1 3 1 80 bfd0c340 init wait
0 70 3 0 200 bfcbb960 physiod physiod
0 69 3 0 200 bfcbb120 aiodoned aiodoned
0 68 3 0 200 bfcbb3e0 ioflush syncer
0 67 3 1 200 bfcbb6a0 pgdaemon pgdaemon
0 62 3 1 200 bfd36e20 usb4 usbevt
0 61 3 1 200 bfd370e0 usb3 usbevt
0 60 3 1 200 bfd373a0 usb2 usbevt
0 59 3 1 200 bfd37660 usb1 usbevt
0 58 3 1 200 bfcbbc20 usb0 usbevt
0 57 3 1 200 bfd0c080 npfgc-0 npfgccv
0 56 3 0 200 bfd0c600 rt_free rt_free
0 55 3 0 200 bfd0c8c0 unpgc unpgc
0 54 3 1 200 bfd0cb80 key_timehandler key_timehandler
0 53 3 1 200 bfd0ce40 icmp6_wqinput/1 icmp6_wqinput
0 52 3 0 200 bfd0d100 icmp6_wqinput/0 icmp6_wqinput
0 51 3 0 200 bfd0d3c0 nd6_timer nd6_timer
0 50 3 1 200 bfd0d680 icmp_wqinput/1 icmp_wqinput
0 49 3 0 200 bfd0d940 icmp_wqinput/0 icmp_wqinput
0 48 3 1 200 bfd368a0 rt_timer rt_timer
0 47 3 0 200 bfd36320 vmem_rehash vmem_rehash
0 46 3 0 200 bfd0dc00 sdmmc0 mmctaskq
0 45 3 0 200 bfd36060 axp20x0 axp20x0
0 36 3 0 280 bfd37920 spkr1 bellcv
0 35 3 1 280 bfd37be0 audiomix play
0 34 3 1 280 bff20040 audiorec record
0 33 3 0 280 bff20300 spkr0 bellcv
0 32 3 1 280 bff205c0 audiomix play
0 31 3 1 280 bff20880 audiorec record
0 30 3 1 200 bff20b40 iic4 iicintr
0 29 3 1 200 bff20e00 iic3 iicintr
0 28 3 1 200 bff210c0 iic2 iicintr
0 27 3 0 200 bff21380 iic1 iicintr
0 26 3 0 200 bff21640 iic0 iicintr
0 25 3 0 200 bff21900 atabus0 atath
0 24 3 0 200 bff21bc0 usbtask-dr usbtsk
0 23 3 0 200 bff66020 usbtask-hc usbtsk
0 22 3 0 200 bff662e0 awinhdmi0 hdmihotplug
0 21 3 1 200 bff665a0 xcall/1 xcall
0 20 1 1 200 bff66860 softser/1
0 19 1 1 200 bff66b20 softclk/1
0 18 1 1 200 bff66de0 softbio/1
0 17 1 1 200 bff670a0 softnet/1
0 16 1 1 201 bff67360 idle/1
0 15 3 0 200 bff67620 sysmon smtaskq
0 14 3 1 200 bff678e0 pmfsuspend pmfsuspend
0 13 3 0 200 bff67ba0 pmfevent pmfevent
0 12 3 0 200 bff92000 sopendfree sopendfr
0 11 3 0 200 bff922c0 nfssilly nfssilly
0 10 3 0 200 bff92580 cachegc cachegc
0 9 3 0 200 bff92840 vdrain vdrain
0 8 3 0 200 bff92b00 modunload mod_unld
0 7 3 0 200 bff92dc0 xcall/0 xcall
0 6 1 0 200 bff93080 softser/0
0 5 1 0 200 bff93340 softclk/0
0 4 1 0 200 bff93600 softbio/0
0 3 1 0 200 bff938c0 softnet/0
0 > 2 1 0 201 bff93b80 idle/0
0 1 3 1 200 8054fe60 swapper uvm
db{1}> show kernhist
1566035491.859419 ohci_device_ctrl_fini#14@1: called!
1566035491.859420 ohci_device_ctrl_fini#14@1: xfer 0xbfd17ce8 nstd 1
1566035492.479217 ohci_rhsc_enable#6@0: called!
1566035492.479218 ohci_rhsc_enable#6@0: sc 0xbff28008
1566045056.715421 uvmpd_scan#9@1: starting 'free' loop
1566045056.715422 uvmpd_scan_queue#9@1: called!
1566045056.724967 uvmpd_scan_queue#9@1: met free target: exit loop
1566045056.726998 uvm_pageout#1@1: <<SLEEPING>>
1566045071.374618 uvm_pageout#1@1: <<WOKE UP>>
1566045071.374620 uvm_pageout#1@1: free/ftarg=254/341
1566045071.374622 uvmpd_scan#10@1: called!
1566045071.374623 uvmpd_scan#10@1: starting 'free' loop
1566045071.374623 uvmpd_scan_queue#10@1: called!
1566045071.385162 uvmpd_scan_queue#10@1: met free target: exit loop
1566045071.388460 uvm_pageout#1@1: <<SLEEPING>>
1566045086.069825 uvm_pageout#1@1: <<WOKE UP>>
1566045086.069827 uvm_pageout#1@1: free/ftarg=254/341
1566045086.069829 uvmpd_scan#11@1: called!
1566045086.069830 uvmpd_scan#11@1: starting 'free' loop
1566045086.069830 uvmpd_scan_queue#11@1: called!
1566045086.080475 uvmpd_scan_queue#11@1: met free target: exit loop
1566045086.083401 uvm_pageout#1@1: <<SLEEPING>>
1566045208.521058 uao_get#87@0: called!
1566045208.521058 uao_get#87@0: aobj=0xbf281818 offset=0, flags=64
1566045208.521072 uao_get#87@0: <- done (done=1)
1566045208.521089 uao_set_swslot#119@0: called!
1566045208.521089 uao_set_swslot#119@0: aobj 0xbf281818 pageidx 0 slot 0
1566045208.524778 uao_set_swslot#120@0: called!
1566045208.524779 uao_set_swslot#120@0: aobj 0xbf281818 pageidx 0 slot 0
1566045208.846656 uao_get#88@1: called!
1566045208.846657 uao_get#88@1: aobj=0x809c5280 offset=1296678912, flags=64
1566045208.846672 uao_get#88@1: <- done (done=1)
1566045210.729076 uao_get#89@1: called!
1566045210.729077 uao_get#89@1: aobj=0xbf280eb8 offset=0, flags=64
1566045210.729089 uao_get#89@1: <- done (done=1)
1566045210.729106 uao_set_swslot#121@1: called!
1566045210.729106 uao_set_swslot#121@1: aobj 0xbf280eb8 pageidx 0 slot 0
1566045210.730123 uao_get#90@1: called!
1566045210.730124 uao_get#90@1: aobj=0xbf280eb8 offset=0, flags=64
1566045210.730157 uao_get#90@1: <- done (done=1)
1566045210.734145 uao_get#91@1: called!
1566045210.734146 uao_get#91@1: aobj=0x809c5280 offset=1302134784, flags=64
1566045210.734162 uao_get#91@1: <- done (done=1)
1566045210.734208 uao_get#92@1: called!
1566045210.734209 uao_get#92@1: aobj=0x809c5280 offset=1302142976, flags=64
1566045210.734219 uao_get#92@1: <- done (done=1)
1566045210.734255 uao_get#93@1: called!
1566045210.734256 uao_get#93@1: aobj=0x809c5280 offset=1302151168, flags=64
1566045210.734265 uao_get#93@1: <- done (done=1)
1566045210.734301 uao_get#94@1: called!
1566045210.734301 uao_get#94@1: aobj=0x809c5280 offset=1302159360, flags=64
1566045210.734309 uao_get#94@1: <- done (done=1)
1566045210.734343 uao_get#95@1: called!
1566045210.734343 uao_get#95@1: aobj=0x809c5280 offset=1302167552, flags=64
1566045210.734353 uao_get#95@1: <- done (done=1)
1566045210.734388 uao_get#96@1: called!
1566045210.734388 uao_get#96@1: aobj=0x809c5280 offset=1302175744, flags=64
1566045210.734398 uao_get#96@1: <- done (done=1)
1566045210.734433 uao_get#97@1: called!
1566045210.734433 uao_get#97@1: aobj=0x809c5280 offset=1302183936, flags=64
1566045210.734441 uao_get#97@1: <- done (done=1)
1566045210.734477 uao_get#98@1: called!
1566045210.734477 uao_get#98@1: aobj=0x809c5280 offset=1302192128, flags=64
1566045210.734485 uao_get#98@1: <- done (done=1)
1566045211.479891 uao_set_swslot#122@0: called!
1566045211.479892 uao_set_swslot#122@0: aobj 0xbf280eb8 pageidx 0 slot 0
1566045211.479902 uao_set_swslot#123@0: called!
1566045211.479902 uao_set_swslot#123@0: aobj 0xbf280eb8 pageidx 1 slot 0
1566045211.479905 uao_set_swslot#124@0: called!
1566045211.479905 uao_set_swslot#124@0: aobj 0xbf280eb8 pageidx 2 slot 0
1566045211.479908 uao_set_swslot#125@0: called!
1566045211.479908 uao_set_swslot#125@0: aobj 0xbf280eb8 pageidx 3 slot 0
1566045211.479911 uao_set_swslot#126@0: called!
1566045211.479911 uao_set_swslot#126@0: aobj 0xbf280eb8 pageidx 4 slot 0
1566045211.480057 uao_set_swslot#127@1: called!
1566045211.480057 uao_set_swslot#127@1: aobj 0x809c5280 pageidx 158952 slot 0
1566045211.480065 uao_set_swslot#128@1: called!
1566045211.480065 uao_set_swslot#128@1: aobj 0x809c5280 pageidx 158953 slot 0
1566045211.480068 uao_set_swslot#129@1: called!
1566045211.480069 uao_set_swslot#129@1: aobj 0x809c5280 pageidx 158954 slot 0
1566045211.480072 uao_set_swslot#130@1: called!
1566045211.480073 uao_set_swslot#130@1: aobj 0x809c5280 pageidx 158955 slot 0
1566045211.480075 uao_set_swslot#131@1: called!
1566045211.480075 uao_set_swslot#131@1: aobj 0x809c5280 pageidx 158956 slot 0
1566045211.480079 uao_set_swslot#132@1: called!
1566045211.480079 uao_set_swslot#132@1: aobj 0x809c5280 pageidx 158957 slot 0
1566045211.480081 uao_set_swslot#133@1: called!
1566045211.480082 uao_set_swslot#133@1: aobj 0x809c5280 pageidx 158958 slot 0
1566045211.480086 uao_set_swslot#134@1: called!
1566045211.480086 uao_set_swslot#134@1: aobj 0x809c5280 pageidx 158959 slot 0
1566045227.693692 uao_get#99@0: called!
1566045227.693693 uao_get#99@0: aobj=0xbf280eb8 offset=0, flags=64
1566045227.693707 uao_get#99@0: <- done (done=1)
1566045227.693725 uao_set_swslot#135@0: called!
1566045227.693725 uao_set_swslot#135@0: aobj 0xbf280eb8 pageidx 0 slot 0
1566045227.697435 uao_set_swslot#136@0: called!
1566045227.697436 uao_set_swslot#136@0: aobj 0xbf280eb8 pageidx 0 slot 0
1566045485.882620 uvm_pageout#1@1: <<WOKE UP>>
1566045485.882623 uvm_pageout#1@1: free/ftarg=339/341
1566045485.882625 uvmpd_scan#12@1: called!
1566045485.882626 uvmpd_scan#12@1: starting 'free' loop
1566045485.882626 uvmpd_scan_queue#12@1: called!
1566045485.891926 uvmpd_scan_queue#12@1: met free target: exit loop
1566045485.894123 uvm_pageout#1@1: <<SLEEPING>>
1566045673.807871 uvm_vnp_setsize#1455240@1: called!
1566045673.807871 uvm_vnp_setsize#1455240@1: vp 0x80a730c0 old 0xfffffffffffffff
f new 0xbc1
1566045673.807946 uvm_vnp_setsize#1455241@1: called!
1566045673.807947 uvm_vnp_setsize#1455241@1: vp 0x80a72ff8 old 0xfffffffffffffff
f new 0x6b0
1566045673.808012 uvm_vnp_setsize#1455242@1: called!
1566045673.808012 uvm_vnp_setsize#1455242@1: vp 0x80a72f30 old 0xfffffffffffffff
f new 0x1251
1566045673.808180 uvm_vnp_setsize#1455243@1: called!
1566045673.808180 uvm_vnp_setsize#1455243@1: vp 0x80a72e68 old 0xfffffffffffffff
f new 0x11e8
1566045673.808253 uvm_vnp_setsize#1455244@1: called!
1566045673.808254 uvm_vnp_setsize#1455244@1: vp 0x80a72da0 old 0xfffffffffffffff
f new 0x9b6
1566045673.808336 uvm_vnp_setsize#1455245@1: called!
1566045673.808337 uvm_vnp_setsize#1455245@1: vp 0x80a72cd8 old 0xfffffffffffffff
f new 0x3046
1566045673.808403 uvm_vnp_setsize#1455246@1: called!
1566045673.808403 uvm_vnp_setsize#1455246@1: vp 0x80a72c10 old 0xfffffffffffffff
f new 0xcb1
1566045673.808469 uvm_vnp_setsize#1455247@1: called!
1566045673.808470 uvm_vnp_setsize#1455247@1: vp 0x80a72b48 old 0xfffffffffffffff
f new 0x18e6
1566045673.813024 uvm_vnp_setsize#1455248@1: called!
1566045673.813026 uvm_vnp_setsize#1455248@1: vp 0x80a72a80 old 0xfffffffffffffff
f new 0x14
1566045673.813093 uvm_vnp_setsize#1455249@1: called!
1566045673.813094 uvm_vnp_setsize#1455249@1: vp 0x80a729b8 old 0xfffffffffffffff
f new 0x17
1566045673.813157 uvm_vnp_setsize#1455250@1: called!
1566045673.813157 uvm_vnp_setsize#1455250@1: vp 0x80a728f0 old 0xfffffffffffffff
f new 0x29c6
1566045673.813219 uvm_vnp_setsize#1455251@1: called!
1566045673.813220 uvm_vnp_setsize#1455251@1: vp 0x80a72828 old 0xfffffffffffffff
f new 0x309
1566045673.814380 uvm_vnp_setsize#1455252@1: called!
1566045673.814381 uvm_vnp_setsize#1455252@1: vp 0x80a72760 old 0xfffffffffffffff
f new 0x1d22
1566045673.814457 uvm_vnp_setsize#1455253@1: called!
1566045673.814457 uvm_vnp_setsize#1455253@1: vp 0x80a72698 old 0xfffffffffffffff
f new 0x302e
1566045673.814521 uvm_vnp_setsize#1455254@1: called!
1566045673.814521 uvm_vnp_setsize#1455254@1: vp 0x80a725d0 old 0xfffffffffffffff
f new 0x85d
1566045673.814584 uvm_vnp_setsize#1455255@1: called!
1566045673.814585 uvm_vnp_setsize#1455255@1: vp 0x80a72508 old 0xfffffffffffffff
f new 0x7b9f
1566045673.814648 uvm_vnp_setsize#1455256@1: called!
1566045673.814649 uvm_vnp_setsize#1455256@1: vp 0x80a72440 old 0xfffffffffffffff
f new 0x3941
1566045673.814716 uvm_vnp_setsize#1455257@1: called!
1566045673.814716 uvm_vnp_setsize#1455257@1: vp 0x80a72378 old 0xfffffffffffffff
f new 0x72c
1566045673.814780 uvm_vnp_setsize#1455258@1: called!
1566045673.814780 uvm_vnp_setsize#1455258@1: vp 0x80a722b0 old 0xfffffffffffffff
f new 0x72c
1566045673.814844 uvm_vnp_setsize#1455259@1: called!
1566045673.814844 uvm_vnp_setsize#1455259@1: vp 0x80a721e8 old 0xfffffffffffffff
f new 0x700
1566045673.814908 uvm_vnp_setsize#1455260@1: called!
1566045673.814909 uvm_vnp_setsize#1455260@1: vp 0x80a72120 old 0xfffffffffffffff
f new 0x71f
1566045673.814973 uvm_vnp_setsize#1455261@1: called!
1566045673.814974 uvm_vnp_setsize#1455261@1: vp 0x80a72058 old 0xfffffffffffffff
f new 0x1ac3
1566045673.815048 uvm_vnp_setsize#1455262@1: called!
1566045673.815048 uvm_vnp_setsize#1455262@1: vp 0x80a6bed8 old 0xfffffffffffffff
f new 0x71b
1566045673.815131 uvm_vnp_setsize#1455263@1: called!
1566045673.815131 uvm_vnp_setsize#1455263@1: vp 0x80a6be10 old 0xfffffffffffffff
f new 0x1295
1566045673.815197 uvm_vnp_setsize#1455264@1: called!
1566045673.815198 uvm_vnp_setsize#1455264@1: vp 0x80a6bd48 old 0xfffffffffffffff
f new 0x9fa
1566045673.815264 uvm_vnp_setsize#1455265@1: called!
1566045673.815265 uvm_vnp_setsize#1455265@1: vp 0x80a6bc80 old 0xfffffffffffffff
f new 0x31ed
1566045673.815329 uvm_vnp_setsize#1455266@1: called!
1566045673.815330 uvm_vnp_setsize#1455266@1: vp 0x80a6bbb8 old 0xfffffffffffffff
f new 0x18e1
1566045673.815395 uvm_vnp_setsize#1455267@1: called!
1566045673.815395 uvm_vnp_setsize#1455267@1: vp 0x80a6baf0 old 0xfffffffffffffff
f new 0x4a02
1566045673.815460 uvm_vnp_setsize#1455268@1: called!
1566045673.815460 uvm_vnp_setsize#1455268@1: vp 0x80a6ba28 old 0xfffffffffffffff
f new 0xa45
1566045673.815525 uvm_vnp_setsize#1455269@1: called!
1566045673.815526 uvm_vnp_setsize#1455269@1: vp 0x80a6b960 old 0xfffffffffffffff
f new 0xb98
1566045673.815589 uvm_vnp_setsize#1455270@1: called!
1566045673.815590 uvm_vnp_setsize#1455270@1: vp 0x80a6b898 old 0xfffffffffffffff
f new 0xf68
1566045673.815652 uvm_vnp_setsize#1455271@1: called!
1566045673.815653 uvm_vnp_setsize#1455271@1: vp 0x80a6b7d0 old 0xfffffffffffffff
f new 0x105e
1566045673.815717 uvm_vnp_setsize#1455272@1: called!
1566045673.815717 uvm_vnp_setsize#1455272@1: vp 0x80a6b708 old 0xfffffffffffffff
f new 0x906
1566045673.815780 uvm_vnp_setsize#1455273@1: called!
1566045673.815781 uvm_vnp_setsize#1455273@1: vp 0x80a6b640 old 0xfffffffffffffff
f new 0x1dcb
1566045673.815844 uvm_vnp_setsize#1455274@1: called!
1566045673.815844 uvm_vnp_setsize#1455274@1: vp 0x80a6b578 old 0xfffffffffffffff
f new 0x920
1566045673.815909 uvm_vnp_setsize#1455275@1: called!
1566045673.815910 uvm_vnp_setsize#1455275@1: vp 0x80a6b4b0 old 0xfffffffffffffff
f new 0x17d6
1566045673.815972 uvm_vnp_setsize#1455276@1: called!
1566045673.815972 uvm_vnp_setsize#1455276@1: vp 0x80a6b3e8 old 0xfffffffffffffff
f new 0x96f
1566045673.816035 uvm_vnp_setsize#1455277@1: called!
1566045673.816035 uvm_vnp_setsize#1455277@1: vp 0x80a6b320 old 0xfffffffffffffff
f new 0x6591
1566045673.816098 uvm_vnp_setsize#1455278@1: called!
1566045673.816098 uvm_vnp_setsize#1455278@1: vp 0x80a6b258 old 0xfffffffffffffff
f new 0x11b6
1566045673.816161 uvm_vnp_setsize#1455279@1: called!
1566045673.816162 uvm_vnp_setsize#1455279@1: vp 0x80a6b190 old 0xfffffffffffffff
f new 0x8a9
1566045673.816227 uvm_vnp_setsize#1455280@1: called!
1566045673.816228 uvm_vnp_setsize#1455280@1: vp 0x80a6b0c8 old 0xfffffffffffffff
f new 0x892
1566045673.819631 ufs_balloc_range#1436815@0: called!
1566045673.819632 ufs_balloc_range#1436815@0: vp 0xbf9afad8 off 0x11c98 len 0x20
u_size 0x11c98
1566045673.819641 genfs_getpages#1723879@0: called!
1566045673.819642 genfs_getpages#1723879@0: vp 0xbf9afad8 off 0x0/10000 count 1
1566045673.819649 genfs_getpages#1723879@0: ridx 0 npages 1 startoff 65536 endof
f 73728
1566045673.819655 uvn_findpage#3571830@0: called!
1566045673.819656 uvn_findpage#3571830@0: vp 0xbf9afad8 off 0x10000
1566045673.819661 uvn_findpage#3571830@0: found 0xc0a5fc30 (color 0)
1566045673.819663 genfs_getpages#1723879@0: returning cached pages
1566045673.819665 genfs_getpages#1723879@0: succeeding, npages 1
1566045673.819666 genfs_getpages#1723879@0: examining pg 0xc0a5fc30 flags 0xd
1566045673.819675 ufs_gop_alloc#1437250@0: called!
1566045673.819679 ffs_balloc#1437262@0: called!
1566045673.819680 ffs_balloc#1437262@0: vp 0xbf9afad8 lbn 0x4 size 0x1cb8
1566045673.819684 ufs_gop_alloc#1437250@0: vp 0xbf9afad8 old 0x11c98 new 0x11cb8
1566045673.819691 uvm_page_unbusy#1472393@0: called!
1566045673.819694 uvm_page_unbusy#1472393@0: unbusying pg 0xc0a5fc30
1566045673.819699 ubc_alloc#1629183@0: called!
1566045673.819699 ubc_alloc#1629183@0: uobj 0xbf9afad8 offset 0x11c98 len 0x20
1566045673.819730 ubc_alloc#1629183@0: umap 0xc17c5b40 refs 1 va 0xcad86000 flag
s 0x2
1566045673.819762 ubc_fault#250430@0: called!
1566045673.819765 ubc_fault#250430@0: va 0xcad86000 ubc_offset 0x5da000 access_t
ype 2
1566045673.819767 ubc_fault#250430@0: slot_offset 0x0 writeoff 0x1c98 writelen 0
x20
1566045673.819768 ubc_fault#250430@0: getpages uobj 0xbf9afad8 offset 0x10000 np
ages 1
1566045673.819770 uvn_get#287065@0: called!
1566045673.819771 uvn_get#287065@0: vp 0xbf9afad8 off 0x10000
1566045673.819773 genfs_getpages#1723880@0: called!
1566045673.819774 genfs_getpages#1723880@0: vp 0xbf9afad8 off 0x0/10000 count 1
1566045673.819776 genfs_getpages#1723880@0: ridx 0 npages 1 startoff 65536 endof
f 73728
1566045673.819779 uvn_findpage#3571831@0: called!
1566045673.819780 uvn_findpage#3571831@0: vp 0xbf9afad8 off 0x10000
1566045673.819780 uvn_findpage#3571831@0: found 0xc0a5fc30 (color 0)
1566045673.819781 genfs_getpages#1723880@0: returning cached pages
1566045673.819782 genfs_getpages#1723880@0: succeeding, npages 1
1566045673.819782 genfs_getpages#1723880@0: examining pg 0xc0a5fc30 flags 0x5
1566045673.819785 ubc_fault#250430@0: getpages error 0 npages 1
1566045673.819786 ubc_fault#250430@0: va 0xcad86000 eva 0xcad88000
1566045673.819788 ubc_fault#250430@0: pgs[0] = 0xc0a5fc30
1566045673.819812 ubc_release#1629183@0: called!
1566045673.819813 ubc_release#1629183@0: va 0xcad87c98
1566045673.819816 ubc_release#1629183@0: umap w#jxp refs 3246152512
1566045673.819818 uvm_vnp_setsize#1455281@0: called!
1566045673.819818 uvm_vnp_setsize#1455281@0: vp 0xbf9afad8 old 0x11c98 new 0x11c
b8
1566045673.819951 ufs_balloc_range#1436816@0: called!
1566045673.819952 ufs_balloc_range#1436816@0: vp 0xbf9afad8 off 0x11cb8 len 0x40
u_size 0x11cb8
1566045673.819957 genfs_getpages#1723881@0: called!
1566045673.819957 genfs_getpages#1723881@0: vp 0xbf9afad8 off 0x0/10000 count 1
1566045673.819959 genfs_getpages#1723881@0: ridx 0 npages 1 startoff 65536 endof
f 73728
1566045673.819962 uvn_findpage#3571832@0: called!
1566045673.819963 uvn_findpage#3571832@0: vp 0xbf9afad8 off 0x10000
1566045673.819964 uvn_findpage#3571832@0: found 0xc0a5fc30 (color 0)
1566045673.819965 genfs_getpages#1723881@0: returning cached pages
1566045673.819965 genfs_getpages#1723881@0: succeeding, npages 1
1566045673.819966 genfs_getpages#1723881@0: examining pg 0xc0a5fc30 flags 0x5
1566045673.819969 ufs_gop_alloc#1437251@0: called!
1566045673.819970 ffs_balloc#1437263@0: called!
1566045673.819971 ffs_balloc#1437263@0: vp 0xbf9afad8 lbn 0x4 size 0x1cf8
1566045673.819974 ufs_gop_alloc#1437251@0: vp 0xbf9afad8 old 0x11cb8 new 0x11cf8
1566045673.819976 uvm_page_unbusy#1472394@0: called!
1566045673.819977 uvm_page_unbusy#1472394@0: unbusying pg 0xc0a5fc30
1566045673.819980 ubc_alloc#1629184@0: called!
1566045673.819981 ubc_alloc#1629184@0: uobj 0xbf9afad8 offset 0x11cb8 len 0x40
1566045673.819983 ubc_alloc#1629184@0: umap 0xc17c5b40 refs 1 va 0xcad86000 flag
s 0x2
1566045673.819985 ubc_release#1629184@0: called!
1566045673.819986 ubc_release#1629184@0: va 0xcad87cb8
1566045673.819987 ubc_release#1629184@0: umap w#jxp refs 3246152512
1566045673.819989 uvm_vnp_setsize#1455282@0: called!
1566045673.819989 uvm_vnp_setsize#1455282@0: vp 0xbf9afad8 old 0x11cb8 new 0x11c
f8
1566045673.824232 ufs_balloc_range#1436817@0: called!
1566045673.824232 ufs_balloc_range#1436817@0: vp 0xbf9afad8 off 0x11cf8 len 0x4a
u_size 0x11cf8
1566045673.824239 genfs_getpages#1723882@0: called!
1566045673.824239 genfs_getpages#1723882@0: vp 0xbf9afad8 off 0x0/10000 count 1
1566045673.824243 genfs_getpages#1723882@0: ridx 0 npages 1 startoff 65536 endof
f 73728
1566045673.824246 uvn_findpage#3571833@0: called!
1566045673.824247 uvn_findpage#3571833@0: vp 0xbf9afad8 off 0x10000
1566045673.824248 uvn_findpage#3571833@0: found 0xc0a5fc30 (color 0)
1566045673.824250 genfs_getpages#1723882@0: returning cached pages
1566045673.824250 genfs_getpages#1723882@0: succeeding, npages 1
1566045673.824251 genfs_getpages#1723882@0: examining pg 0xc0a5fc30 flags 0x5
1566045673.824255 ufs_gop_alloc#1437252@0: called!
1566045673.824257 ffs_balloc#1437264@0: called!
1566045673.824257 ffs_balloc#1437264@0: vp 0xbf9afad8 lbn 0x4 size 0x1d42
1566045673.824259 ufs_gop_alloc#1437252@0: vp 0xbf9afad8 old 0x11cf8 new 0x11d42
1566045673.824263 uvm_page_unbusy#1472395@0: called!
1566045673.824264 uvm_page_unbusy#1472395@0: unbusying pg 0xc0a5fc30
1566045673.824267 ubc_alloc#1629185@0: called!
1566045673.824267 ubc_alloc#1629185@0: uobj 0xbf9afad8 offset 0x11cf8 len 0x4a
1566045673.824270 ubc_alloc#1629185@0: umap 0xc17c5b40 refs 1 va 0xcad86000 flag
s 0x2
1566045673.824273 ubc_release#1629185@0: called!
1566045673.824273 ubc_release#1629185@0: va 0xcad87cf8
1566045673.824275 ubc_release#1629185@0: umap w#jxp refs 3246152512
1566045673.824276 uvm_vnp_setsize#1455283@0: called!
1566045673.824277 uvm_vnp_setsize#1455283@0: vp 0xbf9afad8 old 0x11cf8 new 0x11d
42
1566045673.827743 ufs_balloc_range#1436818@0: called!
1566045673.827743 ufs_balloc_range#1436818@0: vp 0xbf9afad8 off 0x11d42 len 0x35
u_size 0x11d42
1566045673.827748 genfs_getpages#1723883@0: called!
1566045673.827748 genfs_getpages#1723883@0: vp 0xbf9afad8 off 0x0/10000 count 1
1566045673.827751 genfs_getpages#1723883@0: ridx 0 npages 1 startoff 65536 endof
f 73728
1566045673.827754 uvn_findpage#3571834@0: called!
1566045673.827754 uvn_findpage#3571834@0: vp 0xbf9afad8 off 0x10000
1566045673.827755 uvn_findpage#3571834@0: found 0xc0a5fc30 (color 0)
1566045673.827756 genfs_getpages#1723883@0: returning cached pages
1566045673.827756 genfs_getpages#1723883@0: succeeding, npages 1
1566045673.827757 genfs_getpages#1723883@0: examining pg 0xc0a5fc30 flags 0x5
1566045673.827760 ufs_gop_alloc#1437253@0: called!
1566045673.827761 ffs_balloc#1437265@0: called!
1566045673.827762 ffs_balloc#1437265@0: vp 0xbf9afad8 lbn 0x4 size 0x1d77
1566045673.827763 ufs_gop_alloc#1437253@0: vp 0xbf9afad8 old 0x11d42 new 0x11d77
1566045673.827767 uvm_page_unbusy#1472396@0: called!
1566045673.827768 uvm_page_unbusy#1472396@0: unbusying pg 0xc0a5fc30
1566045673.827771 ubc_alloc#1629186@0: called!
1566045673.827771 ubc_alloc#1629186@0: uobj 0xbf9afad8 offset 0x11d42 len 0x35
1566045673.827774 ubc_alloc#1629186@0: umap 0xc17c5b40 refs 1 va 0xcad86000 flag
s 0x2
1566045673.827776 ubc_release#1629186@0: called!
1566045673.827777 ubc_release#1629186@0: va 0xcad87d42
1566045673.827778 ubc_release#1629186@0: umap w#jxp refs 3246152512
1566045673.827779 uvm_vnp_setsize#1455284@0: called!
1566045673.827780 uvm_vnp_setsize#1455284@0: vp 0xbf9afad8 old 0x11d42 new 0x11d
77
1566045673.829876 ufs_balloc_range#1436819@0: called!
1566045673.829877 ufs_balloc_range#1436819@0: vp 0xbf9afad8 off 0x11d77 len 0x49
u_size 0x11d77
1566045673.829881 genfs_getpages#1723884@0: called!
1566045673.829881 genfs_getpages#1723884@0: vp 0xbf9afad8 off 0x0/10000 count 1
1566045673.829883 genfs_getpages#1723884@0: ridx 0 npages 1 startoff 65536 endof
f 73728
1566045673.829886 uvn_findpage#3571835@0: called!
1566045673.829886 uvn_findpage#3571835@0: vp 0xbf9afad8 off 0x10000
1566045673.829888 uvn_findpage#3571835@0: found 0xc0a5fc30 (color 0)
1566045673.829889 genfs_getpages#1723884@0: returning cached pages
1566045673.829889 genfs_getpages#1723884@0: succeeding, npages 1
1566045673.829890 genfs_getpages#1723884@0: examining pg 0xc0a5fc30 flags 0x5
1566045673.829893 ufs_gop_alloc#1437254@0: called!
1566045673.829894 ffs_balloc#1437266@0: called!
1566045673.829895 ffs_balloc#1437266@0: vp 0xbf9afad8 lbn 0x4 size 0x1dc0
1566045673.829896 ufs_gop_alloc#1437254@0: vp 0xbf9afad8 old 0x11d77 new 0x11dc0
1566045673.829898 uvm_page_unbusy#1472397@0: called!
1566045673.829899 uvm_page_unbusy#1472397@0: unbusying pg 0xc0a5fc30
1566045673.829901 ubc_alloc#1629187@0: called!
1566045673.829902 ubc_alloc#1629187@0: uobj 0xbf9afad8 offset 0x11d77 len 0x49
1566045673.829904 ubc_alloc#1629187@0: umap 0xc17c5b40 refs 1 va 0xcad86000 flag
s 0x2
1566045673.829906 ubc_release#1629187@0: called!
1566045673.829906 ubc_release#1629187@0: va 0xcad87d77
1566045673.829907 ubc_release#1629187@0: umap w#jxp refs 3246152512
1566045673.829908 uvm_vnp_setsize#1455285@0: called!
1566045673.829909 uvm_vnp_setsize#1455285@0: vp 0xbf9afad8 old 0x11d77 new 0x11d
c0
1566045673.834770 ufs_balloc_range#1436820@0: called!
1566045673.834770 ufs_balloc_range#1436820@0: vp 0xbf9afad8 off 0x11dc0 len 0x56
u_size 0x11dc0
1566045673.834774 genfs_getpages#1723885@0: called!
1566045673.834775 genfs_getpages#1723885@0: vp 0xbf9afad8 off 0x0/10000 count 1
1566045673.834777 genfs_getpages#1723885@0: ridx 0 npages 1 startoff 65536 endof
f 73728
1566045673.834780 uvn_findpage#3571836@0: called!
1566045673.834780 uvn_findpage#3571836@0: vp 0xbf9afad8 off 0x10000
1566045673.834781 uvn_findpage#3571836@0: found 0xc0a5fc30 (color 0)
1566045673.834781 genfs_getpages#1723885@0: returning cached pages
1566045673.834782 genfs_getpages#1723885@0: succeeding, npages 1
1566045673.834783 genfs_getpages#1723885@0: examining pg 0xc0a5fc30 flags 0x5
1566045673.834786 ufs_gop_alloc#1437255@0: called!
1566045673.834787 ffs_balloc#1437267@0: called!
1566045673.834787 ffs_balloc#1437267@0: vp 0xbf9afad8 lbn 0x4 size 0x1e16
1566045673.834788 ufs_gop_alloc#1437255@0: vp 0xbf9afad8 old 0x11dc0 new 0x11e16
1566045673.834791 uvm_page_unbusy#1472398@0: called!
1566045673.834792 uvm_page_unbusy#1472398@0: unbusying pg 0xc0a5fc30
1566045673.834794 ubc_alloc#1629188@0: called!
1566045673.834795 ubc_alloc#1629188@0: uobj 0xbf9afad8 offset 0x11dc0 len 0x56
1566045673.834797 ubc_alloc#1629188@0: umap 0xc17c5b40 refs 1 va 0xcad86000 flag
s 0x2
1566045673.834799 ubc_release#1629188@0: called!
1566045673.834799 ubc_release#1629188@0: va 0xcad87dc0
1566045673.834800 ubc_release#1629188@0: umap w#jxp refs 3246152512
1566045673.834802 uvm_vnp_setsize#1455286@0: called!
1566045673.834802 uvm_vnp_setsize#1455286@0: vp 0xbf9afad8 old 0x11dc0 new 0x11e
16
1566045673.839582 ufs_balloc_range#1436821@0: called!
1566045673.839583 ufs_balloc_range#1436821@0: vp 0xbf9afad8 off 0x11e16 len 0x56
u_size 0x11e16
1566045673.839587 genfs_getpages#1723886@0: called!
1566045673.839587 genfs_getpages#1723886@0: vp 0xbf9afad8 off 0x0/10000 count 1
1566045673.839589 genfs_getpages#1723886@0: ridx 0 npages 1 startoff 65536 endof
f 73728
1566045673.839592 uvn_findpage#3571837@0: called!
1566045673.839592 uvn_findpage#3571837@0: vp 0xbf9afad8 off 0x10000
1566045673.839594 uvn_findpage#3571837@0: found 0xc0a5fc30 (color 0)
1566045673.839594 genfs_getpages#1723886@0: returning cached pages
1566045673.839595 genfs_getpages#1723886@0: succeeding, npages 1
1566045673.839595 genfs_getpages#1723886@0: examining pg 0xc0a5fc30 flags 0x5
1566045673.839598 ufs_gop_alloc#1437256@0: called!
1566045673.839599 ffs_balloc#1437268@0: called!
1566045673.839599 ffs_balloc#1437268@0: vp 0xbf9afad8 lbn 0x4 size 0x1e6c
1566045673.839601 ufs_gop_alloc#1437256@0: vp 0xbf9afad8 old 0x11e16 new 0x11e6c
1566045673.839603 uvm_page_unbusy#1472399@0: called!
1566045673.839604 uvm_page_unbusy#1472399@0: unbusying pg 0xc0a5fc30
1566045673.839606 ubc_alloc#1629189@0: called!
1566045673.839606 ubc_alloc#1629189@0: uobj 0xbf9afad8 offset 0x11e16 len 0x56
1566045673.839608 ubc_alloc#1629189@0: umap 0xc17c5b40 refs 1 va 0xcad86000 flag
s 0x2
1566045673.839610 ubc_release#1629189@0: called!
1566045673.839610 ubc_release#1629189@0: va 0xcad87e16
1566045673.839611 ubc_release#1629189@0: umap w#jxp refs 3246152512
1566045673.839613 uvm_vnp_setsize#1455287@0: called!
1566045673.839613 uvm_vnp_setsize#1455287@0: vp 0xbf9afad8 old 0x11e16 new 0x11e
6c
1566045673.844437 ufs_balloc_range#1436822@0: called!
1566045673.844437 ufs_balloc_range#1436822@0: vp 0xbf9afad8 off 0x11e6c len 0x56
u_size 0x11e6c
1566045673.844440 genfs_getpages#1723887@0: called!
1566045673.844441 genfs_getpages#1723887@0: vp 0xbf9afad8 off 0x0/10000 count 1
1566045673.844443 genfs_getpages#1723887@0: ridx 0 npages 1 startoff 65536 endof
f 73728
1566045673.844445 uvn_findpage#3571838@0: called!
1566045673.844445 uvn_findpage#3571838@0: vp 0xbf9afad8 off 0x10000
1566045673.844446 uvn_findpage#3571838@0: found 0xc0a5fc30 (color 0)
1566045673.844447 genfs_getpages#1723887@0: returning cached pages
1566045673.844448 genfs_getpages#1723887@0: succeeding, npages 1
1566045673.844449 genfs_getpages#1723887@0: examining pg 0xc0a5fc30 flags 0x5
1566045673.844451 ufs_gop_alloc#1437257@0: called!
1566045673.844452 ffs_balloc#1437269@0: called!
1566045673.844452 ffs_balloc#1437269@0: vp 0xbf9afad8 lbn 0x4 size 0x1ec2
1566045673.844453 ufs_gop_alloc#1437257@0: vp 0xbf9afad8 old 0x11e6c new 0x11ec2
1566045673.844456 uvm_page_unbusy#1472400@0: called!
1566045673.844456 uvm_page_unbusy#1472400@0: unbusying pg 0xc0a5fc30
1566045673.844459 ubc_alloc#1629190@0: called!
1566045673.844459 ubc_alloc#1629190@0: uobj 0xbf9afad8 offset 0x11e6c len 0x56
1566045673.844461 ubc_alloc#1629190@0: umap 0xc17c5b40 refs 1 va 0xcad86000 flag
s 0x2
1566045673.844463 ubc_release#1629190@0: called!
1566045673.844463 ubc_release#1629190@0: va 0xcad87e6c
1566045673.844464 ubc_release#1629190@0: umap w#jxp refs 3246152512
1566045673.844465 uvm_vnp_setsize#1455288@0: called!
1566045673.844465 uvm_vnp_setsize#1455288@0: vp 0xbf9afad8 old 0x11e6c new 0x11e
c2
1566045674.232764 pmap_fault_fixup#383954@1: no l2 for l1slot 0x42a
1566045674.232766 uvm_fault#413112@1: called!
1566045674.232766 uvm_fault#413112@1: (map=0xbfcc6bd0, vaddr=0x42a9a000, at=1, f
f=0)
1566045674.232767 uvm_fault_check#424800@1: called!
1566045674.232768 uvm_map_lookup_entry#649599@1: called!
1566045674.232768 uvm_map_lookup_entry#649599@1: (map=0xbfcc6bd0,addr=0x42a9a000
,ent=0xbfc334c8)
1566045674.232769 uvm_map_lookup_entry#649599@1: <- failed!
1566045674.232770 uvm_fault_check#424800@1: <- no mapping @ 0x0x42a9a000
1566045674.315913 data_abort_handler#369333@1: called!
1566045674.315913 data_abort_handler#369333@1: (l=0xbfcba360, far=0xe2911010, f
sr=0x5
1566045674.315914 data_abort_handler#369333@1: tf=0xbfc33698, pc=0x8040b8b0)
1566045674.315915 pmap_fault_fixup#383955@1: called!
1566045674.315916 pmap_fault_fixup#383955@1: (pm=0x805214d0, va=0xe2910000, fty
pe=0x1, user=0)
1566045674.315917 pmap_fault_fixup#383955@1: ti=0x80521dd8 pai=0x80521924 asid=
0
1566045674.315918 pmap_fault_fixup#383955@1: no l2 for l1slot 0xe29
1566045674.315920 uvm_fault#413113@1: called!
1566045674.315920 uvm_fault#413113@1: (map=0x809c5530, vaddr=0xe2910000, at=1, f
f=0)
1566045674.315921 uvm_fault_check#424801@1: called!
1566045674.315922 uvm_map_lookup_entry#649600@1: called!
1566045674.315923 uvm_map_lookup_entry#649600@1: (map=0x809c5530,addr=0xe2910000
,ent=0xbfc33528)
1566045674.315927 uvm_map_lookup_entry#649600@1: <- failed!
1566045674.315928 uvm_fault_check#424801@1: <- no mapping @ 0x0xe2910000
1566045674.713048 data_abort_handler#369334@1: called!
1566045674.713049 data_abort_handler#369334@1: (l=0xbfcba360, far=0x38e, fsr=0x
1
1566045674.713050 data_abort_handler#369334@1: tf=0xbfc33350, pc=0x80373f18)
1566045674.227814 data_abort_handler#369335@1: called!
1566045674.227815 data_abort_handler#369335@1: (l=0xbfcba360, far=0x366, fsr=0x
5
1566045674.227816 data_abort_handler#369335@1: tf=0xbfc33740, pc=0x802d2124)
1566045674.227819 pmap_fault_fixup#383956@1: called!
1566045674.227819 pmap_fault_fixup#383956@1: (pm=0xbfb6f5f8, va=0, ftype=0x1, u
ser=0)
1566045674.227820 pmap_fault_fixup#383956@1: ti=0x80521dd8 pai=0xbfb6fa4c asid=
0xc
1566045674.227822 pmap_fault_fixup#383956@1: no l2 for l1slot 0
1566045674.227827 uvm_fault#413114@1: called!
1566045674.227827 uvm_fault#413114@1: (map=0xbfcc6bd0, vaddr=0, at=1, ff=0)
1566045674.227829 uvm_fault_check#424802@1: called!
1566045674.227832 uvm_map_lookup_entry#649601@1: called!
1566045674.227833 uvm_map_lookup_entry#649601@1: (map=0xbfcc6bd0,addr=0,ent=0xbf
c335d0)
1566045674.227836 uvm_map_lookup_entry#649601@1: <- failed!
1566045674.227838 uvm_fault_check#424802@1: <- no mapping @ 0x0
1566045674.227842 prefetch_abort_handler#14623@1: called!
1566045673.957969 data_abort_handler#369336@1: called!
1566045673.957969 data_abort_handler#369336@1: (l=0xbfcba360, far=0x366, fsr=0x
5
1566045673.957970 data_abort_handler#369336@1: tf=0xbfc33740, pc=0x802d2124)
1566045673.957970 pmap_fault_fixup#383957@1: called!
1566045673.957971 pmap_fault_fixup#383957@1: (pm=0xbfb6f5f8, va=0, ftype=0x1, u
ser=0)
1566045673.957971 pmap_fault_fixup#383957@1: ti=0x80521dd8 pai=0xbfb6fa4c asid=
0xc
1566045673.957971 pmap_fault_fixup#383957@1: no l2 for l1slot 0
1566045673.957973 uvm_fault#413115@1: called!
1566045673.957973 uvm_fault#413115@1: (map=0xbfcc6bd0, vaddr=0, at=1, ff=0)
1566045673.957974 uvm_fault_check#424803@1: called!
1566045673.957974 uvm_map_lookup_entry#649602@1: called!
1566045673.957975 uvm_map_lookup_entry#649602@1: (map=0xbfcc6bd0,addr=0,ent=0xbf
c335d0)
1566045673.957975 uvm_map_lookup_entry#649602@1: <- failed!
1566045673.957976 uvm_fault_check#424803@1: <- no mapping @ 0x0
1566045673.957977 prefetch_abort_handler#14624@1: called!
1566045674.283892 data_abort_handler#369337@1: called!
1566045674.283892 data_abort_handler#369337@1: (l=0xbfcba360, far=0x366, fsr=0x
5
1566045674.283892 data_abort_handler#369337@1: tf=0xbfc33740, pc=0x802d2124)
1566045674.283893 pmap_fault_fixup#383958@1: called!
1566045674.283894 pmap_fault_fixup#383958@1: (pm=0xbfb6f5f8, va=0, ftype=0x1, u
ser=0)
1566045674.283894 pmap_fault_fixup#383958@1: ti=0x80521dd8 pai=0xbfb6fa4c asid=
0xc
1566045674.283895 pmap_fault_fixup#383958@1: no l2 for l1slot 0
1566045674.283896 uvm_fault#413116@1: called!
1566045674.283897 uvm_fault#413116@1: (map=0xbfcc6bd0, vaddr=0, at=1, ff=0)
1566045674.283897 uvm_fault_check#424804@1: called!
1566045674.283898 uvm_map_lookup_entry#649603@1: called!
1566045674.283898 uvm_map_lookup_entry#649603@1: (map=0xbfcc6bd0,addr=0,ent=0xbf
c335d0)
1566045674.283899 uvm_map_lookup_entry#649603@1: <- failed!
1566045674.283899 uvm_fault_check#424804@1: <- no mapping @ 0x0
1566045674.283900 prefetch_abort_handler#14625@1: called!
1566045674.694014 data_abort_handler#369338@1: called!
1566045674.694015 data_abort_handler#369338@1: (l=0xbfcba360, far=0x366, fsr=0x
5
1566045674.694015 data_abort_handler#369338@1: tf=0xbfc33740, pc=0x802d2124)
1566045674.694015 pmap_fault_fixup#383959@1: called!
1566045674.694016 pmap_fault_fixup#383959@1: (pm=0xbfb6f5f8, va=0, ftype=0x1, u
ser=0)
1566045674.694016 pmap_fault_fixup#383959@1: ti=0x80521dd8 pai=0xbfb6fa4c asid=
0xc
1566045674.694016 pmap_fault_fixup#383959@1: no l2 for l1slot 0
1566045674.694017 uvm_fault#413117@1: called!
1566045674.694018 uvm_fault#413117@1: (map=0xbfcc6bd0, vaddr=0, at=1, ff=0)
1566045674.694018 uvm_fault_check#424805@1: called!
1566045674.694019 uvm_map_lookup_entry#649604@1: called!
1566045674.694019 uvm_map_lookup_entry#649604@1: (map=0xbfcc6bd0,addr=0,ent=0xbf
c335d0)
1566045674.694020 uvm_map_lookup_entry#649604@1: <- failed!
1566045674.694021 uvm_fault_check#424805@1: <- no mapping @ 0x0
1566045674.694021 prefetch_abort_handler#14626@1: called!
1566045674.213157 data_abort_handler#369339@1: called!
1566045674.213159 data_abort_handler#369339@1: (l=0xbfcba360, far=0x366, fsr=0x
5
1566045674.213160 data_abort_handler#369339@1: tf=0xbfc33740, pc=0x802d2124)
1566045674.213163 pmap_fault_fixup#383960@1: called!
1566045674.213164 pmap_fault_fixup#383960@1: (pm=0xbfb6f5f8, va=0, ftype=0x1, u
ser=0)
1566045674.213165 pmap_fault_fixup#383960@1: ti=0x80521dd8 pai=0xbfb6fa4c asid=
0xc
1566045674.213167 pmap_fault_fixup#383960@1: no l2 for l1slot 0
1566045674.213171 uvm_fault#413118@1: called!
1566045674.213172 uvm_fault#413118@1: (map=0xbfcc6bd0, vaddr=0, at=1, ff=0)
1566045674.213174 uvm_fault_check#424806@1: called!
1566045674.213175 uvm_map_lookup_entry#649605@1: called!
1566045674.213176 uvm_map_lookup_entry#649605@1: (map=0xbfcc6bd0,addr=0,ent=0xbf
c335d0)
1566045674.213178 uvm_map_lookup_entry#649605@1: <- failed!
1566045674.213179 uvm_fault_check#424806@1: <- no mapping @ 0x0
1566045674.213181 prefetch_abort_handler#14627@1: called!
db{1}>
(Contact us)
$NetBSD: query-full-pr,v 1.43 2018/01/16 07:36:43 maya Exp $
$NetBSD: gnats_config.sh,v 1.9 2014/08/02 14:16:04 spz Exp $
Copyright © 1994-2017
The NetBSD Foundation, Inc. ALL RIGHTS RESERVED.
Home