detached
+ kldload ext2fs
kldload: can't load ext2fs: module already loaded or in kernel
+ /root/vntool attach 0 /root/dev64.img
attached /root/dev64.img size=0
+ mkdir -p /mnt/e2
+ mount -t ext2fs -o ro /dev/vn0 /mnt/e2
+ df -k /mnt/e2
+ tail -1
/dev/vn0       65252  328 61648     1%    /mnt/e2
+ echo '=== sanity: correct block markers BEFORE reload (lbn 100,101 and 268,269)'
+ /root/trigger read /mnt/e2/f 100 2
=== sanity: correct block markers BEFORE reload (lbn 100,101 and 268,269)
lbn   100: marker=BLK00000100
lbn   101: marker=BLK00000101
READ-DEMO: 0/2 blocks bad
+ /root/trigger read /mnt/e2/f 268 2
lbn   268: marker=BLK00000268
lbn   269: marker=BLK00000269
READ-DEMO: 0/2 blocks bad
=== swap backing image: 4096-bsize fs -> 1024-bsize fs (same gcount=1)
+ echo '=== swap backing image: 4096-bsize fs -> 1024-bsize fs (same gcount=1)'
+ cat /root/imgB8.img
=== reload + statfs (adopts new bsize; um_nindir/f_iosize divergence)
+ echo '=== reload + statfs (adopts new bsize; um_nindir/f_iosize divergence)'
+ /root/trigger reload /mnt/e2
mount(MNT_UPDATE|MNT_RELOAD) rc=0 errno=0 (Undefined error: 0)
statfs rc=0 f_iosize=1024 f_bsize=1024
=== read demo: lbn 100 (in_off 88, still in-bounds) vs lbn 268+ (in_off>=256 OOB)
+ echo '=== read demo: lbn 100 (in_off 88, still in-bounds) vs lbn 268+ (in_off>=256 OOB)'
+ /root/trigger read /mnt/e2/f 100 2
lbn   100: marker=  <== NOT-A-MARKER
lbn   101: marker=  <== NOT-A-MARKER
READ-DEMO: 2/2 blocks bad
+ /root/trigger read /mnt/e2/f 268 4
lbn   268: marker=  <== NOT-A-MARKER
lbn   269: marker=  <== NOT-A-MARKER
lbn   270: marker=  <== NOT-A-MARKER
lbn   271: marker=  <== NOT-A-MARKER
READ-DEMO: 4/4 blocks bad
+ /root/trigger read /mnt/e2/f 400 2
lbn   400: short read 0
lbn   401: short read 0
READ-DEMO: 2/2 blocks bad
=== remount read-write (MNT_UPDATE WANTRDWR path, um_* still stale)
+ echo '=== remount read-write (MNT_UPDATE WANTRDWR path, um_* still stale)'
+ mount -u -o rw /mnt/e2
+ mount
+ grep e2
/dev/vn0 on /mnt/e2 (ext2fs, local)
+ echo '=== write demo: create /w, sparse-write 0xAA at lbn 268..1023, read back'
+ /root/trigger write /mnt/e2/w
=== write demo: create /w, sparse-write 0xAA at lbn 268..1023, read back
pwrite pass done (write errors: 0)
WRITE-DEMO: 0/756 blocks corrupted on readback
=== post-run dmesg tail
+ echo '=== post-run dmesg tail'
+ tail -30
+ dmesg
DF3216[balloc-OOB]: lbn=1009 level=1 in_off=997 >= nindir_real=256 (stale nindir=1024, bsize=1024)
DF3216[WRITE-OOB]: bap[997] <- 1351 at b_data+4*997=3988 (buffer size 1024, b_data=0xfffff80079376000) == OOB WRITE past buffer end
DF3216[balloc-OOB]: lbn=1010 level=1 in_off=998 >= nindir_real=256 (stale nindir=1024, bsize=1024)
DF3216[WRITE-OOB]: bap[998] <- 1352 at b_data+4*998=3992 (buffer size 1024, b_data=0xfffff80079376000) == OOB WRITE past buffer end
DF3216[balloc-OOB]: lbn=1011 level=1 in_off=999 >= nindir_real=256 (stale nindir=1024, bsize=1024)
DF3216[WRITE-OOB]: bap[999] <- 1353 at b_data+4*999=3996 (buffer size 1024, b_data=0xfffff80079376000) == OOB WRITE past buffer end
DF3216[balloc-OOB]: lbn=1012 level=1 in_off=1000 >= nindir_real=256 (stale nindir=1024, bsize=1024)
DF3216[WRITE-OOB]: bap[1000] <- 1354 at b_data+4*1000=4000 (buffer size 1024, b_data=0xfffff80079376000) == OOB WRITE past buffer end
DF3216[balloc-OOB]: lbn=1013 level=1 in_off=1001 >= nindir_real=256 (stale nindir=1024, bsize=1024)
DF3216[WRITE-OOB]: bap[1001] <- 1355 at b_data+4*1001=4004 (buffer size 1024, b_data=0xfffff80079376000) == OOB WRITE past buffer end
DF3216[balloc-OOB]: lbn=1014 level=1 in_off=1002 >= nindir_real=256 (stale nindir=1024, bsize=1024)
DF3216[WRITE-OOB]: bap[1002] <- 1356 at b_data+4*1002=4008 (buffer size 1024, b_data=0xfffff80079376000) == OOB WRITE past buffer end
DF3216[balloc-OOB]: lbn=1015 level=1 in_off=1003 >= nindir_real=256 (stale nindir=1024, bsize=1024)
DF3216[WRITE-OOB]: bap[1003] <- 1357 at b_data+4*1003=4012 (buffer size 1024, b_data=0xfffff80079376000) == OOB WRITE past buffer end
DF3216[balloc-OOB]: lbn=1016 level=1 in_off=1004 >= nindir_real=256 (stale nindir=1024, bsize=1024)
DF3216[WRITE-OOB]: bap[1004] <- 1358 at b_data+4*1004=4016 (buffer size 1024, b_data=0xfffff80079376000) == OOB WRITE past buffer end
DF3216[balloc-OOB]: lbn=1017 level=1 in_off=1005 >= nindir_real=256 (stale nindir=1024, bsize=1024)
DF3216[WRITE-OOB]: bap[1005] <- 1359 at b_data+4*1005=4020 (buffer size 1024, b_data=0xfffff80079376000) == OOB WRITE past buffer end
DF3216[balloc-OOB]: lbn=1018 level=1 in_off=1006 >= nindir_real=256 (stale nindir=1024, bsize=1024)
DF3216[WRITE-OOB]: bap[1006] <- 1360 at b_data+4*1006=4024 (buffer size 1024, b_data=0xfffff80079376000) == OOB WRITE past buffer end
DF3216[balloc-OOB]: lbn=1019 level=1 in_off=1007 >= nindir_real=256 (stale nindir=1024, bsize=1024)
DF3216[WRITE-OOB]: bap[1007] <- 1361 at b_data+4*1007=4028 (buffer size 1024, b_data=0xfffff80079376000) == OOB WRITE past buffer end
DF3216[balloc-OOB]: lbn=1020 level=1 in_off=1008 >= nindir_real=256 (stale nindir=1024, bsize=1024)
DF3216[WRITE-OOB]: bap[1008] <- 1362 at b_data+4*1008=4032 (buffer size 1024, b_data=0xfffff80079376000) == OOB WRITE past buffer end
DF3216[balloc-OOB]: lbn=1021 level=1 in_off=1009 >= nindir_real=256 (stale nindir=1024, bsize=1024)
DF3216[WRITE-OOB]: bap[1009] <- 1363 at b_data+4*1009=4036 (buffer size 1024, b_data=0xfffff80079376000) == OOB WRITE past buffer end
DF3216[balloc-OOB]: lbn=1022 level=1 in_off=1010 >= nindir_real=256 (stale nindir=1024, bsize=1024)
DF3216[WRITE-OOB]: bap[1010] <- 1364 at b_data+4*1010=4040 (buffer size 1024, b_data=0xfffff80079376000) == OOB WRITE past buffer end
DF3216[balloc-OOB]: lbn=1023 level=1 in_off=1011 >= nindir_real=256 (stale nindir=1024, bsize=1024)
DF3216[WRITE-OOB]: bap[1011] <- 1365 at b_data+4*1011=4044 (buffer size 1024, b_data=0xfffff80079376000) == OOB WRITE past buffer end
+ sync
RUN-DONE
+ echo RUN-DONE