Re: SOSEND_LOAN problems in MIPS

To: tech-kern%netbsd.org@localhost
Subject: Re: SOSEND_LOAN problems in MIPS
From: coypu%SDF.ORG@localhost
Date: Tue, 21 Jun 2016 08:49:44 +0000

Replying to myself on tech-kern for the sake of completeness, in case
anyone has similar issues.

For the failure of `cat hugefile.gz | gunzip -` I must use both
PIPE_SOCKETPAIR and SOSEND_LOAN (default on)

I've built a kernel with UVMHIST and DEBUG (and fixed/hacked some of the
fallout) and ran tests which reliably trigger a panic.

Attached is the output. extra messages originate in GXemul, the emulator
I am using.

MIPS panic with DEBUG and UVMHIST
(without options SOSEND_NO_LOAN or PIPE_SOCKETPAIR)
running `gxemul -e 3max -d ~/test2.img`


# cd /usr/tests/dev/raidframe/
# atf-run
Content-Type: application/X-atf-tps; version="3"

info: atf.version, Automated Testing Framework 0.20 (atf-0.20)
info: tests.root, /usr/tests/dev/raidframe
info: time.start, Tue Jun 21 00:08:16 UTC 2016
info: uname.sysname, NetBSD
info: uname.nodename, 
info: uname.release, 7.99.31
info: uname.version, NetBSD 7.99.31 (GENERIC) #0: Tue Jun 21 00:39:23 IDT 2016 fly%net.Home@localhost:/usr/obj.pmax/sys/arch/pmax/compile/GENERIC
info: uname.machine, pmax
info: env, ENV=/root/.shrc
info: env, BLOCKSIZE=1k
info: env, PWD=/usr/tests/dev/raidframe
info: env, HOME=/root
info: env, PATH=/sbin:/usr/sbin:/bin:/usr/bin:/usr/pkg/sbin:/usr/pkg/bin:/usr/X11R7/bin:/usr/X11R6/bin:/usr/local/sbin:/usr/local/bin
info: env, HOST=
info: env, TERM=vt220
info: env, OLDPWD=/root
info: env, USER=root
info: env, LOGNAME=root
info: env, SHELL=/bin/sh
tps-count: 1
tp-start: 1466467701.417968, t_raid, 6
tc-start: 1466467701.417968, raid1_comp0fail
[ WARNING! SPECIAL3 opcode used, but the R3000 processor does not implement such instructions. Only printing this warning once. ]
tc-so:Executing command [ rump_server -lrumpvfs -lrumpdev -lrumpdev_disk -lrumpdev_raidframe -d key=/disk0,hostpath=disk0.img,size=32m -d key=/disk1,hostpath=disk1.img,size=32m unix://sock ]
tc-so:Executing command [ rump.raidctl -C raid.conf raid0 ]
tc-so:Executing command [ rump.raidctl -I 12345 raid0 ]
tc-so:Executing command [ rump.raidctl -iv raid0 ]
DIV by zero !!!!
tc-so:Executing command [ rump_server -lrumpvfs -lrumpdev -lrumpdev_disk -lrumpdev_raidframe -d key=/disk0,hostpath=disk0.img,size=32m -d key=/disk1,hostpath=disk1.img,size=32m unix://sock ]
tc-so:Executing command [ rump.raidctl -c raid.conf raid0 ]
tc-end: 1466467719.488281, raid1_comp0fail, passed
tc-start: 1466467719.535156, raid1_compfail
tc-so:Executing command [ rump_server -lrumpvfs -lrumpdev -lrumpdev_disk -lrumpdev_raidframe -d key=/disk0,hostpath=disk0.img,size=32m -d key=/disk1,hostpath=disk1.img,size=32m unix://sock ]
tc-so:Executing command [ rump.raidctl -C raid.conf raid0 ]
tc-so:Executing command [ rump.raidctl -I 12345 raid0 ]
tc-so:Executing command [ rump.raidctl -iv raid0 ]
DIV by zero !!!!
tc-so:Executing command [ dd if=/usr/tests/dev/raidframe/t_raid of=testfile count=4 ]
tc-so:Executing command [ /bin/sh -c dd if=testfile | rump.dd of=/dev/rraid0c conv=sync ]
tc-so:Executing command [ rump_server -lrumpvfs -lrumpdev -lrumpdev_disk -lrumpdev_raidframe -d key=/disk0,hostpath=disk0.img,size=32m -d key=/disk1,hostpath=disk1.img,size=32m unix://sock ]
tc-so:Executing command [ rump.raidctl -c raid.conf raid0 ]
tc-so:Executing command [ rump.dd if=/dev/rraid0c count=4 ]
tc-end: 1466467737.785156, raid1_compfail, passed
tc-start: 1466467737.828125, raid1_normal
tc-so:Executing command [ rump_server -lrumpvfs -lrumpdev -lrumpdev_disk -lrumpdev_raidframe -d key=/disk0,hostpath=disk0.img,size=32m -d key=/disk1,hostpath=disk1.img,size=32m unix://sock ]
tc-so:Executing command [ rump.raidctl -C raid.conf raid0 ]
tc-so:Executing command [ rump.raidctl -I 12345 raid0 ]
tc-so:Executing command [ rump.raidctl -iv raid0 ]
DIV by zero !!!!
tc-so:Executing command [ dd if=/usr/tests/dev/raidframe/t_raid of=testfile count=4 ]
tc-so:Executing command [ /bin/sh -c dd if=testfile | rump.dd of=/dev/rraid0c conv=sync ]
tc-so:Executing command [ rump_server -lrumpvfs -lrumpdev -lrumpdev_disk -lrumpdev_raidframe -d key=/disk0,hostpath=disk0.img,size=32m -d key=/disk1,hostpath=disk1.img,size=32m unix://sock ]
tc-so:Executing command [ rump.raidctl -c raid.conf raid0 ]
tc-so:Executing command [ rump.dd if=/dev/rraid0c count=4 ]
tc-end: 1466467753.109375, raid1_normal, passed
tc-start: 1466467753.187500, raid5_compfail
tc-so:Executing command [ rump_server -lrumpvfs -lrumpdev -lrumpdev_disk -lrumpdev_raidframe -d key=/disk0,hostpath=disk0.img,size=32m -d key=/disk1,hostpath=disk1.img,size=32m -d key=/disk2,hostpath=disk2.img,size=32m unix://sock ]
tc-so:Executing command [ rump.raidctl -C raid.conf raid0 ]
tc-so:Executing command [ rump.raidctl -I 12345 raid0 ]
tc-so:Executing command [ rump.raidctl -iv raid0 ]
DIV by zero !!!!
panic: kernel debugging assertion "asid == curcpu()->ci_pmap_asid_cur" failed: file "/usr/src/sys/arch/mips/mips/pmap_tlb.c", line 1047 tlb_hi (0xc32cb000) asid (0) != current asid (0x23)
kernel: breakpoint trap
Stopped in pid 1047.28 (rump_server) at netbsd:cpu_Debugger+0x4:        jr      r
a
                bdslot: nop

db> show kernhist
1466467702.542968 uvm_loan#2@0: called!
1466467702.542968 uvm_loanentry#2@0: called!
1466467702.542968 uvm_loananon#3@0: called!
1466467702.542968 uvm_loananon#3@0: ->K done
1466467702.542968 uvm_loananon#4@0: called!
1466467702.542968 uvm_loananon#4@0: ->K done
1466467702.542968 uvm_loananon#5@0: called!
1466467702.542968 uvm_loananon#5@0: ->K done
1466467702.542968 uvm_loanentry#2@0: done 3
1466467702.542968 uvm_loan#2@0: success
1466467702.542968 uvm_loan#3@0: called!
1466467702.542968 uvm_loanentry#3@0: called!
1466467702.542968 uvm_loananon#6@0: called!
1466467702.542968 uvm_loananon#6@0: ->K done
1466467702.542968 uvm_loananon#7@0: called!
1466467702.542968 uvm_loananon#7@0: ->K done
1466467702.542968 uvm_loanentry#3@0: done 2
1466467702.542968 uvm_loan#3@0: success
1466467702.542968 uvm_loan#4@0: called!
1466467702.542968 uvm_loanentry#4@0: called!
1466467702.542968 uvm_loananon#8@0: called!
1466467702.542968 uvm_loananon#8@0: ->K done
1466467702.542968 uvm_loananon#9@0: called!
1466467702.542968 uvm_loananon#9@0: ->K done
1466467702.542968 uvm_loanentry#4@0: done 2
1466467702.542968 uvm_loan#4@0: success
1466467702.542968 uvm_loan#5@0: called!
1466467702.542968 uvm_loanentry#5@0: called!
1466467702.542968 uvm_loananon#10@0: called!
1466467702.542968 uvm_loananon#10@0: ->K done
1466467702.542968 uvm_loananon#11@0: called!
1466467702.542968 uvm_loananon#11@0: ->K done
1466467702.542968 uvm_loanentry#5@0: done 2
1466467702.542968 uvm_loan#5@0: success
1466467718.812500 uvm_loan#6@0: called!
1466467718.812500 uvm_loanentry#6@0: called!
1466467718.812500 uvm_loananon#12@0: called!
1466467718.812500 uvm_loananon#12@0: ->K done
1466467718.812500 uvm_loananon#13@0: called!
1466467718.812500 uvm_loananon#13@0: ->K done
1466467718.812500 uvm_loanentry#6@0: done 2
1466467718.812500 uvm_loan#6@0: success
1466467718.812500 uvm_loan#7@0: called!
1466467718.812500 uvm_loanentry#7@0: called!
1466467718.812500 uvm_loananon#14@0: called!
1466467718.812500 uvm_loananon#14@0: ->K done
1466467718.812500 uvm_loananon#15@0: called!
1466467718.812500 uvm_loananon#15@0: ->K done
1466467718.812500 uvm_loananon#16@0: called!
1466467718.812500 uvm_loananon#16@0: ->K done
1466467718.812500 uvm_loanentry#7@0: done 3
1466467718.812500 uvm_loan#7@0: success
1466467718.812500 uvm_loan#8@0: called!
1466467718.812500 uvm_loanentry#8@0: called!
1466467718.812500 uvm_loananon#17@0: called!
1466467718.812500 uvm_loananon#17@0: ->K done
1466467718.812500 uvm_loananon#18@0: called!
1466467718.812500 uvm_loananon#18@0: ->K done
1466467718.812500 uvm_loanentry#8@0: done 2
1466467718.812500 uvm_loan#8@0: success
1466467718.812500 uvm_loan#9@0: called!
1466467718.812500 uvm_loanentry#9@0: called!
1466467718.812500 uvm_loananon#19@0: called!
1466467718.812500 uvm_loananon#19@0: ->K done
1466467718.812500 uvm_loananon#20@0: called!
1466467718.812500 uvm_loananon#20@0: ->K done
1466467718.812500 uvm_loanentry#9@0: done 2
1466467718.812500 uvm_loan#9@0: success
1466467718.812500 uvm_loan#10@0: called!
1466467718.812500 uvm_loanentry#10@0: called!
1466467718.812500 uvm_loananon#21@0: called!
1466467718.812500 uvm_loananon#21@0: ->K done
1466467718.812500 uvm_loananon#22@0: called!
1466467718.812500 uvm_loananon#22@0: ->K done
1466467718.812500 uvm_loanentry#10@0: done 2
1466467718.812500 uvm_loan#10@0: success
1466467721.050781 uvm_loan#11@0: called!
1466467721.050781 uvm_loanentry#11@0: called!
1466467721.050781 uvm_loananon#23@0: called!
1466467721.050781 uvm_loananon#23@0: ->K done
1466467721.050781 uvm_loananon#24@0: called!
1466467721.050781 uvm_loananon#24@0: ->K done
1466467721.050781 uvm_loanentry#11@0: done 2
1466467721.050781 uvm_loan#11@0: success
1466467721.050781 uvm_loan#12@0: called!
1466467721.050781 uvm_loanentry#12@0: called!
1466467721.050781 uvm_loananon#25@0: called!
1466467721.050781 uvm_loananon#25@0: ->K done
1466467721.050781 uvm_loananon#26@0: called!
1466467721.050781 uvm_loananon#26@0: ->K done
1466467721.050781 uvm_loananon#27@0: called!
1466467721.050781 uvm_loananon#27@0: ->K done
1466467721.050781 uvm_loanentry#12@0: done 3
1466467721.050781 uvm_loan#12@0: success
1466467721.050781 uvm_loan#13@0: called!
1466467721.050781 uvm_loanentry#13@0: called!
1466467721.050781 uvm_loananon#28@0: called!
1466467721.050781 uvm_loananon#28@0: ->K done
1466467721.050781 uvm_loananon#29@0: called!
1466467721.050781 uvm_loananon#29@0: ->K done
1466467721.050781 uvm_loanentry#13@0: done 2
1466467721.050781 uvm_loan#13@0: success
1466467721.050781 uvm_loan#14@0: called!
1466467721.050781 uvm_loanentry#14@0: called!
1466467721.050781 uvm_loananon#30@0: called!
1466467721.050781 uvm_loananon#30@0: ->K done
1466467721.050781 uvm_loananon#31@0: called!
1466467721.050781 uvm_loananon#31@0: ->K done
1466467721.050781 uvm_loanentry#14@0: done 2
1466467721.050781 uvm_loan#14@0: success
1466467721.050781 uvm_loan#15@0: called!
1466467721.050781 uvm_loanentry#15@0: called!
1466467721.050781 uvm_loananon#32@0: called!
1466467721.050781 uvm_loananon#32@0: ->K done
1466467721.050781 uvm_loananon#33@0: called!
1466467721.050781 uvm_loananon#33@0: ->K done
1466467721.050781 uvm_loanentry#15@0: done 2
1466467721.050781 uvm_loan#15@0: success
1466467736.453125 uvm_loan#16@0: called!
1466467736.453125 uvm_loanentry#16@0: called!
1466467736.453125 uvm_loananon#34@0: called!
1466467736.453125 uvm_loananon#34@0: ->K done
1466467736.453125 uvm_loananon#35@0: called!
1466467736.453125 uvm_loananon#35@0: ->K done
1466467736.453125 uvm_loanentry#16@0: done 2
1466467736.453125 uvm_loan#16@0: success
1466467736.453125 uvm_loan#17@0: called!
1466467736.453125 uvm_loanentry#17@0: called!
1466467736.453125 uvm_loananon#36@0: called!
1466467736.453125 uvm_loananon#36@0: ->K done
1466467736.453125 uvm_loananon#37@0: called!
1466467736.453125 uvm_loananon#37@0: ->K done
1466467736.453125 uvm_loananon#38@0: called!
1466467736.453125 uvm_loananon#38@0: ->K done
1466467736.453125 uvm_loanentry#17@0: done 3
1466467736.453125 uvm_loan#17@0: success
1466467736.453125 uvm_loan#18@0: called!
1466467736.453125 uvm_loanentry#18@0: called!
1466467736.453125 uvm_loananon#39@0: called!
1466467736.453125 uvm_loananon#39@0: ->K done
1466467736.453125 uvm_loananon#40@0: called!
1466467736.453125 uvm_loananon#40@0: ->K done
1466467736.453125 uvm_loanentry#18@0: done 2
1466467736.453125 uvm_loan#18@0: success
1466467736.453125 uvm_loan#19@0: called!
1466467736.453125 uvm_loanentry#19@0: called!
1466467736.453125 uvm_loananon#41@0: called!
1466467736.453125 uvm_loananon#41@0: ->K done
1466467736.453125 uvm_loananon#42@0: called!
1466467736.453125 uvm_loananon#42@0: ->K done
1466467736.453125 uvm_loanentry#19@0: done 2
1466467736.453125 uvm_loan#19@0: success
1466467736.453125 uvm_loan#20@0: called!
1466467736.453125 uvm_loanentry#20@0: called!
1466467736.453125 uvm_loananon#43@0: called!
1466467736.453125 uvm_loananon#43@0: ->K done
1466467736.453125 uvm_loananon#44@0: called!
1466467736.453125 uvm_loananon#44@0: ->K done
1466467736.453125 uvm_loanentry#20@0: done 2
1466467736.453125 uvm_loan#20@0: success
1466467738.843750 uvm_loan#21@0: called!
1466467738.843750 uvm_loanentry#21@0: called!
1466467738.843750 uvm_loananon#45@0: called!
1466467738.843750 uvm_loananon#45@0: ->K done
1466467738.843750 uvm_loananon#46@0: called!
1466467738.843750 uvm_loananon#46@0: ->K done
1466467738.843750 uvm_loanentry#21@0: done 2
1466467738.843750 uvm_loan#21@0: success
1466467738.843750 uvm_loan#22@0: called!
1466467738.843750 uvm_loanentry#22@0: called!
1466467738.843750 uvm_loananon#47@0: called!
1466467738.843750 uvm_loananon#47@0: ->K done
1466467738.843750 uvm_loananon#48@0: called!
1466467738.843750 uvm_loananon#48@0: ->K done
1466467738.843750 uvm_loananon#49@0: called!
1466467738.843750 uvm_loananon#49@0: ->K done
1466467738.843750 uvm_loanentry#22@0: done 3
1466467738.843750 uvm_loan#22@0: success
1466467738.843750 uvm_loan#23@0: called!
1466467738.843750 uvm_loanentry#23@0: called!
1466467738.843750 uvm_loananon#50@0: called!
1466467738.843750 uvm_loananon#50@0: ->K done
1466467738.843750 uvm_loananon#51@0: called!
1466467738.843750 uvm_loananon#51@0: ->K done
1466467738.843750 uvm_loanentry#23@0: done 2
1466467738.843750 uvm_loan#23@0: success
1466467738.843750 uvm_loan#24@0: called!
1466467738.843750 uvm_loanentry#24@0: called!
1466467738.843750 uvm_loananon#52@0: called!
1466467738.843750 uvm_loananon#52@0: ->K done
1466467738.843750 uvm_loananon#53@0: called!
1466467738.843750 uvm_loananon#53@0: ->K done
1466467738.843750 uvm_loanentry#24@0: done 2
1466467738.843750 uvm_loan#24@0: success
1466467738.843750 uvm_loan#25@0: called!
1466467738.843750 uvm_loanentry#25@0: called!
1466467738.843750 uvm_loananon#54@0: called!
1466467738.843750 uvm_loananon#54@0: ->K done
1466467738.843750 uvm_loananon#55@0: called!
1466467738.843750 uvm_loananon#55@0: ->K done
1466467738.843750 uvm_loanentry#25@0: done 2
1466467738.843750 uvm_loan#25@0: success
1466467751.656250 uvm_loan#26@0: called!
1466467751.656250 uvm_loanentry#26@0: called!
1466467751.656250 uvm_loananon#56@0: called!
1466467751.656250 uvm_loananon#56@0: ->K done
1466467751.656250 uvm_loananon#57@0: called!
1466467751.656250 uvm_loananon#57@0: ->K done
1466467751.656250 uvm_loanentry#26@0: done 2
1466467751.656250 uvm_loan#26@0: success
1466467751.656250 uvm_loan#27@0: called!
1466467751.656250 uvm_loanentry#27@0: called!
1466467751.656250 uvm_loananon#58@0: called!
1466467751.656250 uvm_loananon#58@0: ->K done
1466467751.656250 uvm_loananon#59@0: called!
1466467751.656250 uvm_loananon#59@0: ->K done
1466467751.656250 uvm_loananon#60@0: called!
1466467751.656250 uvm_loananon#60@0: ->K done
1466467751.656250 uvm_loanentry#27@0: done 3
1466467751.656250 uvm_loan#27@0: success
1466467751.656250 uvm_loan#28@0: called!
1466467751.656250 uvm_loanentry#28@0: called!
1466467751.656250 uvm_loananon#61@0: called!
1466467751.656250 uvm_loananon#61@0: ->K done
1466467751.656250 uvm_loananon#62@0: called!
1466467751.656250 uvm_loananon#62@0: ->K done
1466467751.656250 uvm_loanentry#28@0: done 2
1466467751.656250 uvm_loan#28@0: success
1466467751.656250 uvm_loan#29@0: called!
1466467751.656250 uvm_loanentry#29@0: called!
1466467751.656250 uvm_loananon#63@0: called!
1466467751.656250 uvm_loananon#63@0: ->K done
1466467751.656250 uvm_loananon#64@0: called!
1466467751.656250 uvm_loananon#64@0: ->K done
1466467751.656250 uvm_loanentry#29@0: done 2
1466467751.656250 uvm_loan#29@0: success
1466467751.656250 uvm_loan#30@0: called!
1466467751.656250 uvm_loanentry#30@0: called!
1466467751.656250 uvm_loananon#65@0: called!
1466467751.656250 uvm_loananon#65@0: ->K done
1466467751.656250 uvm_loananon#66@0: called!
1466467751.656250 uvm_loananon#66@0: ->K done
1466467751.656250 uvm_loanentry#30@0: done 2
1466467751.656250 uvm_loan#30@0: success
1466467754.203125 uvm_loan#31@0: called!
1466467754.203125 uvm_loanentry#31@0: called!
1466467754.203125 uvm_loananon#67@0: called!
1466467754.203125 uvm_loananon#67@0: ->K done
1466467754.203125 uvm_loananon#68@0: called!
1466467754.203125 uvm_loananon#68@0: ->K done
1466467754.203125 uvm_loanentry#31@0: done 2
1466467754.203125 uvm_loan#31@0: success
1466467754.203125 uvm_loan#32@0: called!
1466467754.203125 uvm_loanentry#32@0: called!
1466467754.203125 uvm_loananon#69@0: called!
1466467754.203125 uvm_loananon#69@0: ->K done
1466467754.203125 uvm_loananon#70@0: called!
1466467754.203125 uvm_loananon#70@0: ->K done
1466467754.203125 uvm_loananon#71@0: called!
1466467754.203125 uvm_loananon#71@0: ->K done
1466467754.203125 uvm_loanentry#32@0: done 3
1466467754.203125 uvm_loan#32@0: success
1466467754.203125 uvm_loan#33@0: called!
1466467754.203125 uvm_loanentry#33@0: called!
1466467754.203125 uvm_loananon#72@0: called!
1466467754.203125 uvm_loananon#72@0: ->K done
1466467754.203125 uvm_loananon#73@0: called!
1466467754.203125 uvm_loananon#73@0: ->K done
1466467754.203125 uvm_loanentry#33@0: done 2
1466467754.203125 uvm_loan#33@0: success
1466467754.203125 uvm_loan#34@0: called!
1466467754.203125 uvm_loanentry#34@0: called!
1466467754.203125 uvm_loananon#74@0: called!
1466467754.203125 uvm_loananon#74@0: ->K done
1466467754.203125 uvm_loananon#75@0: called!
1466467754.203125 uvm_loananon#75@0: ->K done
1466467754.203125 uvm_loanentry#34@0: done 2
1466467754.203125 uvm_loan#34@0: success
1466467754.203125 uvm_loan#35@0: called!
1466467754.203125 uvm_loanentry#35@0: called!
1466467754.203125 uvm_loananon#76@0: called!
1466467754.203125 uvm_loananon#76@0: ->K done
1466467754.203125 uvm_loananon#77@0: called!
1466467754.203125 uvm_loananon#77@0: ->K done
1466467754.203125 uvm_loanentry#35@0: done 2
1466467754.203125 uvm_loan#35@0: success
1466467772.324218 uvmpd_scan_queue#121@0:   met free target: exit loop
1466467772.324218 uvm_pageout#1@0:   <<SLEEPING>>
1466467772.417968 uvm_pageout#1@0:   <<WOKE UP>>
1466467772.417968 uvm_pageout#1@0:   free/ftarg=68/97
1466467772.417968 uvmpd_scan#122@0: called!
1466467772.417968 uvmpd_scan#122@0:   starting 'free' loop
1466467772.417968 uvmpd_scan_queue#122@0: called!
1466467772.417968 uvmpd_scan_queue#122@0:   met free target: exit loop
1466467772.417968 uvm_pageout#1@0:   <<SLEEPING>>
1466467772.847656 uvm_pageout#1@0:   <<WOKE UP>>
1466467772.847656 uvm_pageout#1@0:   free/ftarg=68/97
1466467772.847656 uvmpd_scan#123@0: called!
1466467772.847656 uvmpd_scan#123@0:   starting 'free' loop
1466467772.847656 uvmpd_scan_queue#123@0: called!
1466467772.847656 uvmpd_scan_queue#123@0:   met free target: exit loop
1466467772.847656 uvm_pageout#1@0:   <<SLEEPING>>
1466467772.972656 uvm_pageout#1@0:   <<WOKE UP>>
1466467772.972656 uvm_pageout#1@0:   free/ftarg=68/97
1466467772.972656 uvmpd_scan#124@0: called!
1466467772.972656 uvmpd_scan#124@0:   starting 'free' loop
1466467772.972656 uvmpd_scan_queue#124@0: called!
1466467772.988281 uvmpd_scan_queue#124@0:   met free target: exit loop
1466467772.988281 uvm_pageout#1@0:   <<SLEEPING>>
1466467775.039062 uvm_pageout#1@0:   <<WOKE UP>>
1466467775.039062 uvm_pageout#1@0:   free/ftarg=68/97
1466467775.039062 uvmpd_scan#125@0: called!
1466467775.039062 uvmpd_scan#125@0:   starting 'free' loop
1466467775.039062 uvmpd_scan_queue#125@0: called!
1466467775.039062 uvmpd_scan_queue#125@0:   met free target: exit loop
1466467775.039062 uvm_pageout#1@0:   <<SLEEPING>>
1466467775.132812 uvm_pageout#1@0:   <<WOKE UP>>
1466467775.132812 uvm_pageout#1@0:   free/ftarg=68/97
1466467775.132812 uvmpd_scan#126@0: called!
1466467775.132812 uvmpd_scan#126@0:   starting 'free' loop
1466467775.132812 uvmpd_scan_queue#126@0: called!
1466467775.148437 uvmpd_scan_queue#126@0:   met free target: exit loop
1466467775.148437 uvm_pageout#1@0:   <<SLEEPING>>
1466467775.269531 uvm_pageout#1@0:   <<WOKE UP>>
1466467775.269531 uvm_pageout#1@0:   free/ftarg=68/97
1466467775.269531 uvmpd_scan#127@0: called!
1466467775.269531 uvmpd_scan#127@0:   starting 'free' loop
1466467775.269531 uvmpd_scan_queue#127@0: called!
1466467775.269531 uvmpd_scan_queue#127@0:   met free target: exit loop
1466467775.269531 uvm_pageout#1@0:   <<SLEEPING>>
1466467775.378906 uvm_pageout#1@0:   <<WOKE UP>>
1466467775.378906 uvm_pageout#1@0:   free/ftarg=68/97
1466467775.378906 uvmpd_scan#128@0: called!
1466467775.378906 uvmpd_scan#128@0:   starting 'free' loop
1466467775.378906 uvmpd_scan_queue#128@0: called!
1466467775.378906 uvmpd_scan_queue#128@0:   met free target: exit loop
1466467775.378906 uvm_pageout#1@0:   <<SLEEPING>>
1466467775.472656 uvm_pageout#1@0:   <<WOKE UP>>
1466467775.472656 uvm_pageout#1@0:   free/ftarg=68/97
1466467775.472656 uvmpd_scan#129@0: called!
1466467775.472656 uvmpd_scan#129@0:   starting 'free' loop
1466467775.472656 uvmpd_scan_queue#129@0: called!
1466467775.484375 uvmpd_scan_queue#129@0:   met free target: exit loop
1466467775.484375 uvm_pageout#1@0:   <<SLEEPING>>
1466467775.730468 uvm_pageout#1@0:   <<WOKE UP>>
1466467775.730468 uvm_pageout#1@0:   free/ftarg=68/97
1466467775.730468 uvmpd_scan#130@0: called!
1466467775.730468 uvmpd_scan#130@0:   starting 'free' loop
1466467775.730468 uvmpd_scan_queue#130@0: called!
1466467775.730468 uvmpd_scan_queue#130@0:   met free target: exit loop
1466467775.730468 uvm_pageout#1@0:   <<SLEEPING>>
1466467775.835937 uvm_pageout#1@0:   <<WOKE UP>>
1466467775.835937 uvm_pageout#1@0:   free/ftarg=68/97
1466467775.835937 uvmpd_scan#131@0: called!
1466467775.835937 uvmpd_scan#131@0:   starting 'free' loop
1466467775.835937 uvmpd_scan_queue#131@0: called!
1466467775.839843 uvmpd_scan_queue#131@0:   met free target: exit loop
1466467775.839843 uvm_pageout#1@0:   <<SLEEPING>>
1466467775.933593 uvm_pageout#1@0:   <<WOKE UP>>
1466467775.933593 uvm_pageout#1@0:   free/ftarg=68/97
1466467775.933593 uvmpd_scan#132@0: called!
1466467775.933593 uvmpd_scan#132@0:   starting 'free' loop
1466467775.933593 uvmpd_scan_queue#132@0: called!
1466467775.949218 uvmpd_scan_queue#132@0:   met free target: exit loop
1466467775.949218 uvm_pageout#1@0:   <<SLEEPING>>
1466467778.855468 uvm_pageout#1@0:   <<WOKE UP>>
1466467778.855468 uvm_pageout#1@0:   free/ftarg=68/97
1466467778.855468 uvmpd_scan#133@0: called!
1466467778.855468 uvmpd_scan#133@0:   starting 'free' loop
1466467778.855468 uvmpd_scan_queue#133@0: called!
1466467778.871093 uvmpd_scan_queue#133@0:   met free target: exit loop
1466467778.871093 uvm_pageout#1@0:   <<SLEEPING>>
1466467778.960937 uvm_pageout#1@0:   <<WOKE UP>>
1466467778.960937 uvm_pageout#1@0:   free/ftarg=68/97
1466467778.960937 uvmpd_scan#134@0: called!
1466467778.960937 uvmpd_scan#134@0:   starting 'free' loop
1466467778.960937 uvmpd_scan_queue#134@0: called!
1466467778.960937 uvmpd_scan_queue#134@0:   met free target: exit loop
1466467778.960937 uvm_pageout#1@0:   <<SLEEPING>>
1466467779.070312 uvm_pageout#1@0:   <<WOKE UP>>
1466467779.070312 uvm_pageout#1@0:   free/ftarg=68/97
1466467779.070312 uvmpd_scan#135@0: called!
1466467779.070312 uvmpd_scan#135@0:   starting 'free' loop
1466467779.070312 uvmpd_scan_queue#135@0: called!
1466467779.070312 uvmpd_scan_queue#135@0:   met free target: exit loop
1466467779.070312 uvm_pageout#1@0:   <<SLEEPING>>
1466467779.160156 uvm_map_prepare#20978@0:   uobj/offset 0x0/-1
1466467779.160156 uvm_map_findspace#20978@0: called!
1466467779.160156 uvm_map_findspace#20978@0: (map=0x83ff8f08, hint=0, len=65536,
 flags=0x4040000)
1466467779.160156 uvm_map_findspace#20978@0: <- got it!  (result=0xc1011000)
1466467779.160156 uvm_map_prepare#20978@0: <- done!
1466467779.160156 uvm_map_enter#20978@0: called!
1466467779.160156 uvm_map_enter#20978@0: (map=0x83ff8f08, start=0xc1011000, size
=65536, flags=0x4040000)
1466467779.160156 uvm_map_enter#20978@0:   uobj/offset 0x0/0
1466467779.160156 uvm_map_enter#20978@0:   allocating new map entry
1466467779.160156 uvm_map_enter#20978@0: <- done!
1466467779.160156 uvm_km_check_empty#12302@0: called!
1466467779.160156 uvm_pagermapin#6734@0: <- done (KVA=0xc1011000)
1466467779.160156 uvm_fault#164090@0: called!
1466467779.160156 uvm_fault#164090@0: (map=0x8049ead0, vaddr=0xc2f49000, at=1, f
f=0)       
1466467779.160156 uvm_fault_check#176529@0: called!
1466467779.160156 uvm_map_lookup_entry#221643@0: called!
1466467779.160156 uvm_map_lookup_entry#221643@0: (map=0x8049ead0,addr=0xc2f49000
,ent=0xc32cb9fc)
1466467779.160156 uvm_map_lookup_entry#221643@0: <- got it via hint (0x83ff9d68)
           
1466467779.160156 uvm_fault_check#176529@0:   narrow=1, back=0, forw=0, startva=
0xc2f49000 
1466467779.160156 uvm_fault_check#176529@0:   entry=0x83ff9d68, amap=0x0, obj=0x
8049e0f8   
1466467779.160156 uvm_fault_upper_lookup#164089@0: called!
1466467779.160156 uvm_fault_upper_lookup#164089@0:   shadowed=0, will_get=0
1466467779.160156 uvm_pagermapout#6733@0: called!
1466467779.160156 uvm_pagermapout#6733@0:  (kva=0xc1001000, npages=16)
1466467779.160156 uvm_unmap_remove#16432@0: called!
1466467779.160156 uvm_unmap_remove#16432@0: (map=0x83ff8f08, start=0xc1001000, e
nd=0xc1011000)
1466467779.160156 uvm_map_lookup_entry#221644@0: called!
1466467779.160156 uvm_map_lookup_entry#221644@0: (map=0x83ff8f08,addr=0xc1001000
,ent=0x80821df8)
1466467779.160156 uvm_map_lookup_entry#221644@0: <- got it via hint (0x81fd18a8)
           
1466467779.160156 uvm_km_pgremove_intrsafe#6814@0: called!
1466467779.160156 uvm_unmap_remove#16432@0:   removed map entry 0x81fd18a8
1466467779.160156 uvm_unmap_remove#16432@0: <- done!
1466467779.160156 uvm_unmap_detach#16432@0: called!
1466467779.160156 uvm_unmap_detach#16432@0:   detach 0x81fd18a8: amap=0x0, obj=0
x0, submap?=0
1466467779.160156 uvm_mapent_free#42026@0: called!
1466467779.160156 uvm_mapent_free#42026@0: <- freeing map entry=0x81fd18a8 [flag
s=8]       
1466467779.160156 uvm_unmap_detach#16432@0: <- done
1466467779.160156 uvm_pagermapout#6733@0: <- done
1466467779.160156 uvm_pagermapout#6734@0: called!
1466467779.160156 uvm_pagermapout#6734@0:  (kva=0xc1011000, npages=16)
1466467779.160156 uvm_unmap_remove#16433@0: called!
1466467779.160156 uvm_unmap_remove#16433@0: (map=0x83ff8f08, start=0xc1011000, e
nd=0xc1021000)
1466467779.160156 uvm_map_lookup_entry#221645@0: called!
1466467779.160156 uvm_map_lookup_entry#221645@0: (map=0x83ff8f08,addr=0xc1011000
,ent=0x80821df8)
1466467779.160156 uvm_map_lookup_entry#221645@0: <- got it via hint (0x828b31b8)
           
1466467779.160156 uvm_km_pgremove_intrsafe#6815@0: called!
1466467779.160156 uvm_unmap_remove#16433@0:   removed map entry 0x828b31b8
1466467779.160156 uvm_unmap_remove#16433@0: <- done!
1466467779.160156 uvm_unmap_detach#16433@0: called!
1466467779.160156 uvm_unmap_detach#16433@0:   detach 0x828b31b8: amap=0x0, obj=0
x0, submap?=0
1466467779.160156 uvm_mapent_free#42027@0: called!
1466467779.160156 uvm_mapent_free#42027@0: <- freeing map entry=0x828b31b8 [flag
s=8]       
1466467779.160156 uvm_unmap_detach#16433@0: <- done
1466467779.160156 uvm_pagermapout#6734@0: <- done
1466467779.160156 uvm_fault#164091@0: called!
1466467779.160156 uvm_fault#164091@0: (map=0x8049ead0, vaddr=0xc2f4b000, at=1, f
f=0)       
1466467779.160156 uvm_fault_check#176530@0: called!
1466467779.160156 uvm_map_lookup_entry#221646@0: called!
1466467779.160156 uvm_map_lookup_entry#221646@0: (map=0x8049ead0,addr=0xc2f4b000
,ent=0xc32cb9fc)
1466467779.160156 uvm_map_lookup_entry#221646@0: <- got it via hint (0x83ff9d68)
           
1466467779.160156 uvm_fault_check#176530@0:   narrow=1, back=0, forw=0, startva=
0xc2f4b000 
1466467779.160156 uvm_fault_check#176530@0:   entry=0x83ff9d68, amap=0x0, obj=0x
8049e0f8   
1466467779.160156 uvm_fault_upper_lookup#164090@0: called!
1466467779.160156 uvm_fault_upper_lookup#164090@0:   shadowed=0, will_get=0
1466467779.160156 uvm_pagermapin#6735@0: called!
1466467779.160156 uvm_pagermapin#6735@0: (pps=0x83df39c0, npages=16, first_color
=0)        
1466467779.160156 uvm_mapent_alloc#42547@0: called!
1466467779.160156 uvm_mapent_alloc#42547@0: <- new entry=0x828b31b8 [kentry=0]
1466467779.160156 uvm_map_prepare#20979@0: called!
1466467779.160156 uvm_map_prepare#20979@0: (map=0x83ff8f08, start=0, size=65536,
 flags=0x4040000)
1466467779.160156 uvm_map_prepare#20979@0:   uobj/offset 0x0/-1
1466467779.160156 uvm_map_findspace#20979@0: called!
1466467779.160156 uvm_map_findspace#20979@0: (map=0x83ff8f08, hint=0, len=65536,
 flags=0x4040000)
1466467779.160156 uvm_map_findspace#20979@0: <- got it!  (result=0xc1001000)
1466467779.160156 uvm_map_prepare#20979@0: <- done!
1466467779.160156 uvm_map_enter#20979@0: called!
1466467779.160156 uvm_map_enter#20979@0: (map=0x83ff8f08, start=0xc1001000, size
=65536, flags=0x4040000)
1466467779.160156 uvm_map_enter#20979@0:   uobj/offset 0x0/0
1466467779.160156 uvm_map_enter#20979@0:   allocating new map entry
1466467779.160156 uvm_map_enter#20979@0: <- done!
1466467779.160156 uvm_km_check_empty#12303@0: called!
1466467779.160156 uvm_pagermapin#6735@0: <- done (KVA=0xc1001000)
1466467779.160156 uvm_pagermapin#6736@0: called!
1466467779.160156 uvm_pagermapin#6736@0: (pps=0x83df39c0, npages=16, first_color
=0)        
1466467779.160156 uvm_mapent_alloc#42548@0: called!
1466467779.160156 uvm_mapent_alloc#42548@0: <- new entry=0x81fd18a8 [kentry=0]
1466467779.160156 uvm_map_prepare#20980@0: called!
1466467779.160156 uvm_map_prepare#20980@0: (map=0x83ff8f08, start=0, size=65536,
 flags=0x4040000)
1466467779.160156 uvm_map_prepare#20980@0:   uobj/offset 0x0/-1
1466467779.160156 uvm_map_findspace#20980@0: called!
1466467779.160156 uvm_map_findspace#20980@0: (map=0x83ff8f08, hint=0, len=65536,
 flags=0x4040000)
1466467779.160156 uvm_map_findspace#20980@0: <- got it!  (result=0xc1011000)
1466467779.160156 uvm_map_prepare#20980@0: <- done!
1466467779.160156 uvm_map_enter#20980@0: called!
1466467779.160156 uvm_map_enter#20980@0: (map=0x83ff8f08, start=0xc1011000, size
=65536, flags=0x4040000)
1466467779.160156 uvm_map_enter#20980@0:   uobj/offset 0x0/0
1466467779.160156 uvm_map_enter#20980@0:   allocating new map entry
1466467779.160156 uvm_map_enter#20980@0: <- done!
1466467779.160156 uvm_km_check_empty#12304@0: called!
1466467779.160156 uvm_pagermapin#6736@0: <- done (KVA=0xc1011000)
1466467779.160156 uvn_findpage#484543@0: called!
1466467779.160156 uvn_findpage#484543@0: vp 0x8381c960 off 0x1f80000
1466467779.160156 uvn_findpage#484543@0: found 0x8060c630 (color 0)
1466467779.160156 uvn_findpage#484544@0: called!
1466467779.160156 uvn_findpage#484544@0: vp 0x8381c960 off 0x1f81000
1466467779.160156 uvn_findpage#484544@0: found 0x80579790 (color 0)
1466467779.160156 genfs_getpages#91072@0: returning cached pages
1466467779.160156 genfs_getpages#91072@0: succeeding, npages 2
1466467779.160156 genfs_getpages#91072@0: examining pg 0x8060c630 flags 0x8d
1466467779.160156 genfs_getpages#91072@0: examining pg 0x80579790 flags 0x8d
1466467779.160156 ubc_fault#37449@0: getpages error 0 npages 2
1466467779.160156 ubc_fault#37449@0: va 0xc2f47000 eva 0xc2f49000
1466467779.160156 ubc_fault#37449@0: pgs[0] = 0x8060c630
1466467779.160156 ubc_fault#37449@0: pgs[1] = 0x80579790
1466467779.160156 ubc_release#65440@0: called!
1466467779.160156 ubc_release#65440@0: va 0xc2f47000
1466467779.160156 ubc_release#65440@0: umap 0xc0045340 refs 0
1466467779.160156 ubc_alloc#65441@0: called!
1466467779.160156 ubc_alloc#65441@0: uobj 0x8381c960 offset 0x1f82000 len 0x2000
           
1466467779.160156 ubc_alloc#65441@0: umap 0xc0045380 refs 1 va 0xc2f49000 flags 0
x101       
1466467779.160156 ubc_fault#37450@0: called!
1466467779.160156 ubc_fault#37450@0: va 0xc2f49000 ubc_offset 0x51c000 access_ty
pe 1       
1466467779.160156 ubc_fault#37450@0: slot_offset 0x0 writeoff 0x0 writelen 0x0
1466467779.160156 ubc_fault#37450@0: getpages uobj 0x8381c960 offset 0x1f82000 n
pages 2    
1466467779.160156 uvn_get#90577@0: called!
1466467779.160156 uvn_get#90577@0: vp 0x8381c960 off 0x1f82000
1466467779.160156 genfs_getpages#91073@0: called!
1466467779.160156 genfs_getpages#91073@0: vp 0x8381c960 off 0x0/1f82000 count 2
1466467779.160156 genfs_getpages#91073@0: ridx 2 npages 2 startoff 33030144 endo
ff 33046528
1466467779.160156 uvn_findpage#484545@0: called!
1466467779.160156 uvn_findpage#484545@0: vp 0x8381c960 off 0x1f82000
1466467779.160156 uvn_findpage#484545@0: found 0x8060c090 (color 0)
1466467779.160156 uvn_findpage#484546@0: called!
1466467779.160156 uvn_findpage#484546@0: vp 0x8381c960 off 0x1f83000
1466467779.160156 uvn_findpage#484546@0: found 0x8060c0e0 (color 0)
1466467779.160156 genfs_getpages#91073@0: returning cached pages
1466467779.160156 genfs_getpages#91073@0: succeeding, npages 4
1466467779.160156 genfs_getpages#91073@0: examining pg 0x8060c090 flags 0x8d
1466467779.160156 genfs_getpages#91073@0: examining pg 0x8060c0e0 flags 0x8d
1466467779.160156 ubc_fault#37450@0: getpages error 0 npages 2
1466467779.160156 ubc_fault#37450@0: va 0xc2f49000 eva 0xc2f4b000
1466467779.160156 ubc_fault#37450@0: pgs[0] = 0x8060c090
1466467779.160156 ubc_fault#37450@0: pgs[1] = 0x8060c0e0
1466467779.160156 ubc_release#65441@0: called!
1466467779.160156 ubc_release#65441@0: va 0xc2f49000
1466467779.160156 ubc_release#65441@0: umap 0xc0045380 refs 0
1466467779.160156 uvm_aio_aiodone#4985@0: called!
1466467779.160156 uvm_aio_aiodone#4985@0: bp 0x83e9acc0
1466467779.160156 uvm_aio_aiodone#4985@0: pgs[0] = 0x80595850
1466467779.160156 uvm_aio_aiodone#4985@0: pgs[1] = 0x8058f590
1466467779.160156 uvm_aio_aiodone#4985@0: pgs[2] = 0x80593af0
1466467779.160156 uvm_aio_aiodone#4985@0: pgs[3] = 0x80593b40
1466467779.160156 uvm_aio_aiodone#4985@0: pgs[4] = 0x80593b90
1466467779.160156 uvm_aio_aiodone#4985@0: pgs[5] = 0x80596c50
1466467779.160156 uvm_aio_aiodone#4985@0: pgs[6] = 0x80589000
1466467779.160156 uvm_aio_aiodone#4985@0: pgs[7] = 0x80589050
1466467779.160156 uvm_aio_aiodone#4985@0: pgs[8] = 0x805890a0
1466467779.160156 uvm_aio_aiodone#4985@0: pgs[9] = 0x805c9e20
1466467779.160156 uvm_aio_aiodone#4985@0: pgs[10] = 0x8058cca0
1466467779.160156 uvm_aio_aiodone#4985@0: pgs[11] = 0x8058c2f0
1466467779.160156 uvm_aio_aiodone#4985@0: pgs[12] = 0x8058c340
1466467779.160156 uvm_aio_aiodone#4985@0: pgs[13] = 0x805885b0
1466467779.160156 uvm_aio_aiodone#4985@0: pgs[14] = 0x80594db0
1466467779.160156 uvm_aio_aiodone#4985@0: pgs[15] = 0x80594e00
1466467779.160156 uvm_aio_aiodone_pages#4985@0: called!
1466467779.160156 uvm_aio_aiodone_pages#4985@0: pg 0x80595850
1466467779.160156 uvm_aio_aiodone_pages#4985@0: pg 0x8058f590
1466467779.160156 uvm_aio_aiodone_pages#4985@0: pg 0x80593af0
1466467779.160156 uvm_aio_aiodone_pages#4985@0: pg 0x80593b40
1466467779.160156 uvm_aio_aiodone_pages#4985@0: pg 0x80593b90
1466467779.160156 uvm_aio_aiodone_pages#4985@0: pg 0x80596c50
1466467779.160156 uvm_aio_aiodone_pages#4985@0: pg 0x80589000
1466467779.160156 uvm_aio_aiodone_pages#4985@0: pg 0x80589050
1466467779.160156 uvm_aio_aiodone_pages#4985@0: pg 0x805890a0
1466467779.160156 uvm_aio_aiodone_pages#4985@0: pg 0x805c9e20
1466467779.160156 uvm_aio_aiodone_pages#4985@0: pg 0x8058cca0
1466467779.160156 uvm_aio_aiodone_pages#4985@0: pg 0x8058c2f0
1466467779.160156 uvm_aio_aiodone_pages#4985@0: pg 0x8058c340
1466467779.160156 uvm_aio_aiodone_pages#4985@0: pg 0x805885b0
1466467779.160156 uvm_aio_aiodone_pages#4985@0: pg 0x80594db0
1466467779.160156 uvm_aio_aiodone_pages#4985@0: pg 0x80594e00
1466467779.160156 uvm_page_unbusy#7937@0: called!
1466467779.160156 uvm_page_unbusy#7937@0: unbusying pg 0x80595850
1466467779.160156 uvm_page_unbusy#7937@0: unbusying pg 0x8058f590
1466467779.160156 uvm_page_unbusy#7937@0: unbusying pg 0x80593af0
1466467779.160156 uvm_page_unbusy#7937@0: unbusying pg 0x80593b40
1466467779.160156 uvm_page_unbusy#7937@0: unbusying pg 0x80593b90
1466467779.160156 uvm_page_unbusy#7937@0: unbusying pg 0x80596c50
1466467779.160156 uvm_page_unbusy#7937@0: unbusying pg 0x80589000
1466467779.160156 uvm_page_unbusy#7937@0: unbusying pg 0x80589050
1466467779.160156 uvm_page_unbusy#7937@0: unbusying pg 0x805890a0
1466467779.160156 uvm_page_unbusy#7937@0: unbusying pg 0x805c9e20
1466467779.160156 uvm_page_unbusy#7937@0: unbusying pg 0x8058cca0
1466467779.160156 uvm_page_unbusy#7937@0: unbusying pg 0x8058c2f0
1466467779.160156 uvm_page_unbusy#7937@0: unbusying pg 0x8058c340
1466467779.160156 uvm_page_unbusy#7937@0: unbusying pg 0x805885b0
1466467779.160156 uvm_page_unbusy#7937@0: unbusying pg 0x80594db0
1466467779.160156 uvm_page_unbusy#7937@0: unbusying pg 0x80594e00
1466467779.160156 uvm_aio_aiodone#4986@0: called!
1466467779.160156 uvm_aio_aiodone#4986@0: bp 0x83e9ab40
1466467779.160156 uvm_aio_aiodone#4986@0: pgs[0] = 0x80594e50
1466467779.160156 uvm_aio_aiodone#4986@0: pgs[1] = 0x805963e0
1466467779.160156 uvm_aio_aiodone#4986@0: pgs[2] = 0x805c9fb0
1466467779.160156 uvm_aio_aiodone#4986@0: pgs[3] = 0x805c9f60
1466467779.160156 uvm_aio_aiodone#4986@0: pgs[4] = 0x805f6b50
1466467779.160156 uvm_aio_aiodone#4986@0: pgs[5] = 0x805977e0
1466467779.160156 uvm_aio_aiodone#4986@0: pgs[6] = 0x8058df60
1466467779.160156 uvm_aio_aiodone#4986@0: pgs[7] = 0x8058d5b0
1466467779.160156 uvm_aio_aiodone#4986@0: pgs[8] = 0x8058d600
1466467779.160156 uvm_aio_aiodone#4986@0: pgs[9] = 0x80585ef0
1466467779.160156 uvm_aio_aiodone#4986@0: pgs[10] = 0x805817b0
1466467779.160156 uvm_aio_aiodone#4986@0: pgs[11] = 0x80581800
1466467779.160156 uvm_aio_aiodone#4986@0: pgs[12] = 0x80581850
1466467779.160156 uvm_aio_aiodone#4986@0: pgs[13] = 0x805dc840
1466467779.160156 uvm_aio_aiodone#4986@0: pgs[14] = 0x80580db0
1466467779.160156 uvm_aio_aiodone#4986@0: pgs[15] = 0x80580e00
1466467779.160156 uvm_aio_aiodone_pages#4986@0: called!
1466467779.160156 uvm_aio_aiodone_pages#4986@0: pg 0x80594e50
1466467779.160156 uvm_aio_aiodone_pages#4986@0: pg 0x805963e0
1466467779.160156 uvm_aio_aiodone_pages#4986@0: pg 0x805c9fb0
1466467779.160156 uvm_aio_aiodone_pages#4986@0: pg 0x805c9f60
1466467779.160156 uvm_aio_aiodone_pages#4986@0: pg 0x805f6b50
1466467779.160156 uvm_aio_aiodone_pages#4986@0: pg 0x805977e0
1466467779.160156 uvm_aio_aiodone_pages#4986@0: pg 0x8058df60
1466467779.160156 uvm_aio_aiodone_pages#4986@0: pg 0x8058d5b0
1466467779.160156 uvm_aio_aiodone_pages#4986@0: pg 0x8058d600
1466467779.160156 uvm_aio_aiodone_pages#4986@0: pg 0x80585ef0
1466467779.160156 uvm_aio_aiodone_pages#4986@0: pg 0x805817b0
1466467779.160156 uvm_aio_aiodone_pages#4986@0: pg 0x80581800
1466467779.160156 uvm_aio_aiodone_pages#4986@0: pg 0x80581850
1466467779.160156 uvm_aio_aiodone_pages#4986@0: pg 0x805dc840
1466467779.160156 uvm_aio_aiodone_pages#4986@0: pg 0x80580db0
1466467779.160156 uvm_aio_aiodone_pages#4986@0: pg 0x80580e00
1466467779.160156 uvm_page_unbusy#7938@0: called!
1466467779.160156 uvm_page_unbusy#7938@0: unbusying pg 0x80594e50
1466467779.160156 uvm_page_unbusy#7938@0: unbusying pg 0x805963e0
1466467779.160156 uvm_page_unbusy#7938@0: unbusying pg 0x805c9fb0
1466467779.160156 uvm_page_unbusy#7938@0: unbusying pg 0x805c9f60
1466467779.160156 uvm_page_unbusy#7938@0: unbusying pg 0x805f6b50
1466467779.160156 uvm_page_unbusy#7938@0: unbusying pg 0x805977e0
1466467779.160156 uvm_page_unbusy#7938@0: unbusying pg 0x8058df60
1466467779.160156 uvm_page_unbusy#7938@0: unbusying pg 0x8058d5b0
1466467779.160156 uvm_page_unbusy#7938@0: unbusying pg 0x8058d600
1466467779.160156 uvm_page_unbusy#7938@0: unbusying pg 0x80585ef0
1466467779.160156 uvm_page_unbusy#7938@0: unbusying pg 0x805817b0
1466467779.160156 uvm_page_unbusy#7938@0: unbusying pg 0x80581800
1466467779.160156 uvm_page_unbusy#7938@0: unbusying pg 0x80581850
1466467779.160156 uvm_page_unbusy#7938@0: unbusying pg 0x805dc840
1466467779.160156 uvm_page_unbusy#7938@0: unbusying pg 0x80580db0
1466467779.160156 uvm_page_unbusy#7938@0: unbusying pg 0x80580e00
1466467779.160156 ubc_alloc#65442@0: called!
1466467779.160156 ubc_alloc#65442@0: uobj 0x8381ca28 offset 0x1f80000 len 0x4000

1466467779.160156 ubc_alloc#65442@0: umap 0xc00453c0 refs 1 va 0xc2f4b000 flags 0
x101       
1466467779.160156 ubc_fault#37451@0: called!
1466467779.160156 ubc_fault#37451@0: va 0xc2f4b000 ubc_offset 0x51e000 access_ty
pe 1
1466467779.160156 ubc_fault#37451@0: slot_offset 0x0 writeoff 0x0 writelen 0x0
1466467779.160156 ubc_fault#37451@0: getpages uobj 0x8381ca28 offset 0x1f80000 n
pages 2
1466467779.160156 uvn_get#90578@0: called!
1466467779.160156 uvn_get#90578@0: vp 0x8381ca28 off 0x1f80000
1466467779.160156 uvn_get#90579@0: called!
1466467779.160156 uvn_get#90579@0: vp 0x8381ca28 off 0x1fe0000
1466467779.160156 genfs_getpages#91074@0: called!
1466467779.160156 genfs_getpages#91074@0: vp 0x8381ca28 off 0x0/1fe0000 count 16

1466467779.160156 genfs_getpages#91074@0: ridx 0 npages 16 startoff 33423360 end
off 33488896
1466467779.160156 uvn_findpage#484547@0: called!
1466467779.160156 uvn_findpage#484547@0: vp 0x8381ca28 off 0x1fe0000
1466467779.160156 uvn_findpage#484547@0: alloced 0x80580e50 (color 0)
1466467779.160156 uvn_findpage#484548@0: called!
1466467779.160156 uvn_findpage#484548@0: vp 0x8381ca28 off 0x1fe1000
1466467779.160156 uvn_findpage#484548@0: alloced 0x8058aef0 (color 0)
1466467779.160156 uvn_findpage#484549@0: called!
1466467779.160156 uvn_findpage#484549@0: vp 0x8381ca28 off 0x1fe2000
1466467779.160156 uvn_findpage#484549@0: alloced 0x805dcbb0 (color 0)
1466467779.160156 uvn_findpage#484550@0: called!
1466467779.160156 uvn_findpage#484550@0: vp 0x8381ca28 off 0x1fe3000
1466467779.160156 uvn_findpage#484550@0: alloced 0x805dcb10 (color 0)
1466467779.160156 uvn_findpage#484551@0: called!
1466467779.160156 uvn_findpage#484551@0: vp 0x8381ca28 off 0x1fe4000
1466467779.160156 uvn_findpage#484551@0: alloced 0x805dcb60 (color 0)
1466467779.160156 uvn_findpage#484552@0: called!
1466467779.160156 uvn_findpage#484552@0: vp 0x8381ca28 off 0x1fe5000
1466467779.160156 uvn_findpage#484552@0: alloced 0x8058a4f0 (color 0)
1466467779.160156 uvn_findpage#484553@0: called!
1466467779.160156 uvn_findpage#484553@0: vp 0x8381ca28 off 0x1fe6000
1466467779.160156 uvn_findpage#484553@0: alloced 0x805804f0 (color 0)
1466467779.160156 uvn_findpage#484554@0: called!
1466467779.160156 uvn_findpage#484554@0: vp 0x8381ca28 off 0x1fe7000
1466467779.160156 uvn_findpage#484554@0: alloced 0x80580540 (color 0)
1466467779.160156 uvn_findpage#484555@0: called!
1466467779.160156 uvn_findpage#484555@0: vp 0x8381ca28 off 0x1fe8000
1466467779.160156 uvn_findpage#484555@0: alloced 0x80580590 (color 0)
1466467779.160156 uvn_findpage#484556@0: called!
1466467779.160156 uvn_findpage#484556@0: vp 0x8381ca28 off 0x1fe9000
1466467779.160156 uvn_findpage#484556@0: alloced 0x805e6750 (color 0)
1466467779.160156 uvn_findpage#484557@0: called!
1466467779.160156 uvn_findpage#484557@0: vp 0x8381ca28 off 0x1fea000
1466467779.160156 uvn_findpage#484557@0: alloced 0x8057faf0 (color 0)
1466467779.160156 uvn_findpage#484558@0: called!
1466467779.160156 uvn_findpage#484558@0: vp 0x8381ca28 off 0x1feb000
1466467779.160156 uvn_findpage#484558@0: alloced 0x8057fb40 (color 0)
1466467779.160156 uvn_findpage#484559@0: called!
1466467779.160156 uvn_findpage#484559@0: vp 0x8381ca28 off 0x1fec000
1466467779.160156 uvn_findpage#484559@0: alloced 0x8057fb90 (color 0)
1466467779.160156 uvn_findpage#484560@0: called!
1466467779.160156 uvn_findpage#484560@0: vp 0x8381ca28 off 0x1fed000
1466467779.160156 uvn_findpage#484560@0: alloced 0x805823e0 (color 0)
1466467779.160156 uvn_findpage#484561@0: called!
1466467779.160156 uvn_findpage#484561@0: vp 0x8381ca28 off 0x1fee000
1466467779.160156 uvn_findpage#484561@0: alloced 0x805e83c0 (color 0)
1466467779.160156 uvn_findpage#484562@0: called!
1466467779.160156 uvn_findpage#484562@0: vp 0x8381ca28 off 0x1fef000
1466467779.160156 uvn_findpage#484562@0: alloced 0x805e8320 (color 0)
1466467779.160156 genfs_getpages_read#6403@0: called!
1466467779.160156 genfs_getpages_read#6403@0: lbn 0x7f8 -> HOLE
1466467779.160156 genfs_getpages_read#6403@0: lbn 0x7f9 -> HOLE
1466467779.160156 genfs_getpages_read#6403@0: lbn 0x7fa -> HOLE
1466467779.160156 genfs_getpages_read#6403@0: lbn 0x7fb -> HOLE
1466467779.160156 genfs_getpages_read#6403@0: returning 0 (async)
1466467779.160156 uvn_get#90580@0: called!
1466467779.160156 uvn_get#90580@0: vp 0x8381ca28 off 0x1ff0000
1466467779.160156 genfs_getpages#91075@0: called!
1466467779.160156 genfs_getpages#91075@0: vp 0x8381ca28 off 0x0/1ff0000 count 16

1466467779.160156 genfs_getpages#91075@0: ridx 0 npages 16 startoff 33488896 end
off 33554432
1466467779.160156 uvn_findpage#484563@0: called!
1466467779.160156 uvn_findpage#484563@0: vp 0x8381ca28 off 0x1ff0000
1466467779.160156 uvn_findpage#484563@0: alloced 0x805e8370 (color 0)
1466467779.160156 uvn_findpage#484564@0: called!
1466467779.160156 uvn_findpage#484564@0: vp 0x8381ca28 off 0x1ff1000
1466467779.160156 uvn_findpage#484564@0: alloced 0x8058b7b0 (color 0)
1466467779.160156 uvn_findpage#484565@0: called!
1466467779.160156 uvn_findpage#484565@0: vp 0x8381ca28 off 0x1ff2000
1466467779.160156 uvn_findpage#484565@0: alloced 0x805dc980 (color 0)
1466467779.160156 uvn_findpage#484566@0: called!
1466467779.160156 uvn_findpage#484566@0: vp 0x8381ca28 off 0x1ff3000
1466467779.160156 uvn_findpage#484566@0: alloced 0x805dca70 (color 0)
1466467779.160156 uvn_findpage#484567@0: called!
1466467779.160156 uvn_findpage#484567@0: vp 0x8381ca28 off 0x1ff4000
1466467779.160156 uvn_findpage#484567@0: alloced 0x805dc9d0 (color 0)
1466467779.160156 uvn_findpage#484568@0: called!
1466467779.160156 uvn_findpage#484568@0: vp 0x8381ca28 off 0x1ff5000
1466467779.160156 uvn_findpage#484568@0: alloced 0x805dd970 (color 0)
1466467779.160156 uvn_findpage#484569@0: called!
1466467779.160156 uvn_findpage#484569@0: vp 0x8381ca28 off 0x1ff6000
1466467779.160156 uvn_findpage#484569@0: alloced 0x80582cf0 (color 0)
1466467779.160156 uvn_findpage#484570@0: called!
1466467779.160156 uvn_findpage#484570@0: vp 0x8381ca28 off 0x1ff7000
1466467779.160156 uvn_findpage#484570@0: alloced 0x80582d40 (color 0)
1466467779.160156 uvn_findpage#484571@0: called!
1466467779.160156 uvn_findpage#484571@0: vp 0x8381ca28 off 0x1ff8000
1466467779.160156 uvn_findpage#484571@0: alloced 0x80582d90 (color 0)
1466467779.160156 uvn_findpage#484572@0: called!
1466467779.160156 uvn_findpage#484572@0: vp 0x8381ca28 off 0x1ff9000
1466467779.160156 uvn_findpage#484572@0: alloced 0x805e73d0 (color 0)
1466467779.160156 uvn_findpage#484573@0: called!
1466467779.160156 uvn_findpage#484573@0: vp 0x8381ca28 off 0x1ffa000
1466467779.160156 uvn_findpage#484573@0: alloced 0x805e1840 (color 0)
1466467779.160156 uvn_findpage#484574@0: called!
1466467779.160156 uvn_findpage#484574@0: vp 0x8381ca28 off 0x1ffb000
1466467779.160156 uvn_findpage#484574@0: alloced 0x805e1890 (color 0)
1466467779.160156 uvn_findpage#484575@0: called!
1466467779.160156 uvn_findpage#484575@0: vp 0x8381ca28 off 0x1ffc000
1466467779.160156 uvn_findpage#484575@0: alloced 0x805e18e0 (color 0)
1466467779.160156 uvn_findpage#484576@0: called!
1466467779.160156 uvn_findpage#484576@0: vp 0x8381ca28 off 0x1ffd000
1466467779.160156 uvn_findpage#484576@0: alloced 0x805818a0 (color 0)
1466467779.160156 uvn_findpage#484577@0: called!
1466467779.160156 uvn_findpage#484577@0: vp 0x8381ca28 off 0x1ffe000
1466467779.160156 uvn_findpage#484577@0: alloced 0x805e8190 (color 0)
1466467779.160156 uvn_findpage#484578@0: called!
1466467779.160156 uvn_findpage#484578@0: vp 0x8381ca28 off 0x1fff000
1466467779.160156 uvn_findpage#484578@0: alloced 0x805e8280 (color 0)
1466467779.160156 genfs_getpages_read#6404@0: called!
1466467779.160156 genfs_getpages_read#6404@0: lbn 0x7fc -> HOLE
1466467779.160156 genfs_getpages_read#6404@0: lbn 0x7fd -> HOLE
1466467779.160156 genfs_getpages_read#6404@0: lbn 0x7fe -> HOLE
1466467779.160156 genfs_getpages_read#6404@0: vp 0x8381ca28 bp 0x0 num now 0
1466467779.160156 genfs_getpages_read#6404@0: bp 0x83e9ac00 offset 0x1ffc000 bco
unt 0x4000 blkno 0x2292a0
1466467779.160156 genfs_getpages_read#6404@0: returning 0 (async)
1466467779.160156 genfs_getpages#91076@0: called!
1466467779.160156 genfs_getpages#91076@0: vp 0x8381ca28 off 0x0/1f80000 count 2
1466467779.160156 genfs_getpages#91076@0: ridx 0 npages 2 startoff 33030144 endo
ff 33046528
1466467779.160156 uvn_findpage#484579@0: called!
1466467779.160156 uvn_findpage#484579@0: vp 0x8381ca28 off 0x1f80000
1466467779.160156 uvn_findpage#484579@0: found 0x80609160 (color 0)
1466467779.160156 uvn_findpage#484580@0: called!
1466467779.160156 uvn_findpage#484580@0: vp 0x8381ca28 off 0x1f81000
1466467779.160156 uvn_findpage#484580@0: found 0x8060c680 (color 0)
1466467779.160156 genfs_getpages#91076@0: returning cached pages
1466467779.160156 genfs_getpages#91076@0: succeeding, npages 2
1466467779.160156 genfs_getpages#91076@0: examining pg 0x80609160 flags 0x8d
1466467779.160156 genfs_getpages#91076@0: examining pg 0x8060c680 flags 0x8d
1466467779.160156 ubc_fault#37451@0: getpages error 0 npages 2
1466467779.160156 ubc_fault#37451@0: va 0xc2f4b000 eva 0xc2f4d000
1466467779.160156 ubc_fault#37451@0: pgs[0] = 0x80609160
1466467779.160156 ubc_fault#37451@0: pgs[1] = 0x8060c680
1466467779.160156 ubc_release#65442@0: called!
1466467779.160156 ubc_release#65442@0: va 0xc2f4b000
1466467779.160156 ubc_release#65442@0: umap 0xc00453c0 refs 0
1466467779.160156 ubc_alloc#65443@0: called!
1466467779.160156 ubc_alloc#65443@0: uobj 0x8381ca28 offset 0x1f82000 len 0x2000
db> quit    
0xc32cbbd0: cpu_reboot+38 (104,0,803f01b9,8046c9a0) ra 802dd048 sz 24
0xc32cbbe8: vpanic+1b0 (104,0,803f01b9,8046c9a0) ra 803b3f30 sz 48
0xc32cbc18: kern_assert+40 (104,803e486c,803e4878,803e489c) ra 8004ae64 sz 32
0xc32cbc38: pmap_tlb_asid_check+98 (104,803e486c,803e4878,803e489c) ra 8004af8c sz 40
0xc32cbc60: pmap_tlb_invalidate_addr+70 (104,803e486c,803e4878,803e489c) ra 8004912c sz 32
0xc32cbc80: pmap_remove+1d4 (104,803e486c,803e4878,803e489c) ra 802573e4 sz 64
0xc32cbcc0: ubc_alloc+93c (104,803e486c,803e4878,803e489c) ra 80257b44 sz 136
0xc32cbd48: ubc_uiomove+80 (104,c32cbe58,803e4878,803e489c) ra 80207548 sz 88
0xc32cbda0: ffs_read+1d0 (104,c32cbe58,803e4878,803e489c) ra 8033b6b8 sz 64
0xc32cbde0: VOP_READ+34 (8381ca28,c32cbe58,0,83f7bbd0) ra 80334b74 sz 48
0xc32cbe10: vn_read+b4 (8381ca28,c32cbe58,0,83f7bbd0) ra 802e78e4 sz 48
0xc32cbe40: dofileread+a4 (8381ca28,c32cbe58,76ac2000,2000) ra 8032e18c sz 88
0xc32cbe98: sys_pread+144 (8381ca28,c32cbe58,76ac2000,2000) ra 8004c8b0 sz 64
0xc32cbed8: syscall+150 (8381ca28,c32cbe58,76ac2000,2000) ra 80041128 sz 112
0xc32cbf48: mips1_systemcall+ec (8381ca28,c32cbe58,76ac2000,2000) ra 7dce5750 sz 0
PC 0x7dce5750: not in kernel space
0xc32cbf48: 0+7dce5750 (8381ca28,c32cbe58,76ac2000,2000) ra 0 sz 0
User-level: pid 1047.28

dumping to dev 19,1 offset 131072
dump 64 63 62 61 60 59 58 57 56 55 54 53 52 51 50 49 48 47 46 45 44 43 42 41 40 39 38 37 36 35 34 33 32 31 30 29 28 27 26 25 24 23 22 21 20 19 18 17 16 15 14 13 12 11 10 9 8 7 6 5 4 3 2 1 succeeded


rebooting...

Follow-Ups:
- Re: SOSEND_LOAN problems in MIPS
  - From: Michael

Prev by Date: Re: COP0_SYNC definition
Next by Date: Re: SOSEND_LOAN problems in MIPS
Previous by Thread: CI20 atf results
Next by Thread: Re: SOSEND_LOAN problems in MIPS
Indexes:

Home | Main Index | Thread Index | Old Index