Paul should follow up with more details soon, but I'm relaying our findings
with his debug output as this may be important for release:
- happens with all of bsd.{rd,sp,mp}
- nothing to do with softraid
- nvme disks are fine
- any ahci disk is super slow, 'ktrace disklabel sd1':
19830 disklabel 0.000143 CALL
sysctl(6.19<hw.physmem>,0x8db33c0a078,0x7f7ffffdea90,0,0)
19830 disklabel 0.000145 RET sysctl 0
19830 disklabel 0.000147 CALL
sysctl(1.24<kern.rawpartition>,0x7f7ffffdea04,0x7f7ffffde9f8,0,0)
19830 disklabel 0.000148 RET sysctl 0
19830 disklabel 0.000151 CALL open(0x8db33c0a8b0,0<O_RDONLY>)
19830 disklabel 0.000152 NAMI "/dev/rsd1c"
19830 disklabel 120.111460 RET open 3
19830 disklabel 120.111464 CALL ioctl(3,DIOCGDINFO,0x8db33c0a0b8)
19830 disklabel 120.111466 RET ioctl 0
19830 disklabel 120.111466 CALL ioctl(3,DIOCGPDINFO,0x7f7ffffde8d8)
19830 disklabel 180.171447 RET ioctl 0
19830 disklabel 180.171449 CALL pledge(0x8db33bb55f3,0)
19830 disklabel 180.171450 STRU promise="stdio rpath wpath disklabel"
19830 disklabel 180.171451 RET pledge 0
dmesg with AHCI_DEBUG + some dkcsum.c DEBUG + "^func: msg" style printfs:
Mar 17 20:41:47 ^init_main: config_rootfound_vscsi
Mar 17 20:41:47 vscsi0 at root
Mar 17 20:41:47 scsibus4 at vscsi0: 256 targets
Mar 17 20:41:47 ^init_main: config_rootfound_softraid
Mar 17 20:41:47 softraid0 at root
Mar 17 20:41:47 scsibus5 at softraid0: 256 targets
Mar 17 20:41:47 ahci1.1: final poll of port completed command in slot 10
Mar 17 20:42:40 ahci1.1: final poll of port completed command in slot 11
Mar 17 20:43:40 ahci1.1: final poll of port completed command in slot 25
Mar 17 20:44:40 ahci1.1: final poll of port completed command in slot 26
Mar 17 20:45:40 ahci1.1: final poll of port completed command in slot 27
Mar 17 20:46:40 sd2 at scsibus5 targ 1 lun 0: <OPENBSD, SR CRYPTO, 006>
Mar 17 20:46:40 sd2: 1953247MB, 512 bytes/sector, 4000250591 sectors
Mar 17 20:46:41 ^init_main: done: config_rootfound_softraid
Mar 17 20:46:41 ^init_main: starting: diskconf
Mar 17 20:46:41 dkcsum: bootdev=0
Mar 17 20:46:41 dkcsum: BIOS drive 0x80 bsd_dev=0xa0000204 checksum=0x264590c0
Mar 17 20:46:41 dkcsum: BIOS drive 0x81 bsd_dev=0xa0010204 checksum=0xd7479677
Mar 17 20:46:41 dkcsum: sd0 checksum is 0x264590c0
Mar 17 20:46:41 dkcsum: sd0 matches BIOS drive 0x80
Mar 17 20:46:41 dkcsum: sd0 is alternate boot disk
Mar 17 20:46:41 ahci1.1: final poll of port completed command in slot 10
Mar 17 20:47:41 ahci1.1: final poll of port completed command in slot 11
Mar 17 20:48:41 ahci1.1: final poll of port completed command in slot 12
Mar 17 20:49:41 dkcsum: sd1 checksum is 0xd7479677
Mar 17 20:49:41 dkcsum: sd1 matches BIOS drive 0x81
Mar 17 20:49:41 dkcsum: sd2 checksum is 0x264590c0
Mar 17 20:49:41 dkcsum: sd2 matches BIOS drive 0x80 IGNORED
Mar 17 20:49:42 dkcsum: sd2 has no matching BIOS drive
Mar 17 20:49:42 root on sd2a (0ccea196d1e87cb6.a) swap on sd2b dump on sd2b
Mar 17 20:49:42 ^init_main: db_ctf_init
Mar 17 20:49:42 ^init_main: mountroot
Mar 17 20:49:42 drm:pid0:smu_v13_0_check_fw_version *WARNING* SMU driver if
version not matched
Mar 17 20:49:42 amdgpu0: IP DISCOVERY GC 10.3.6 2 CU rev 0x01
Mar 17 20:49:42 [drm] REG_WAIT timeout 1us * 100000 tries - optc31_disable_crtc
line:138
Mar 17 20:49:44 amdgpu0: 3840x2160, 32bpp
Mar 17 20:49:44 wsdisplay0 at amdgpu0 mux 1
Mar 17 20:49:44 wskbd0: connecting to wsdisplay0
Mar 17 20:49:44 wskbd1: connecting to wsdisplay0
Mar 17 20:49:45 wskbd2: connecting to wsdisplay0
Mar 17 20:49:45 wskbd3: connecting to wsdisplay0
Mar 17 20:49:45 wsdisplay0: screen 0-5 added (std, vt100 emulation)
Mar 17 20:49:45 Automatic boot in progress: starting file system checks.
