Frequent kernel panic on apu2d4 and external usb3 hard drive

classic Classic list List threaded Threaded
3 messages Options
Reply | Threaded
Open this post in threaded view
|

Frequent kernel panic on apu2d4 and external usb3 hard drive

Aldo Mazzeo
I'm running OpenBSD 6.5 on my apu2d4 and I have an external USB3 hard
drive attached (it is powered by a separate cable).
The external hd is FFS-formatted with softdep enabled and the kernel
panics once every 24-36h with the following DDB details:

ddb{1}> show panic
softdep_deallocate_dependencies: unrecovered I/O error

ddb{1}> trace
db_enter() at db_enter+0x10
panic() at panic+0x128
softdep_deallocate_dependencies(fffffd810a534100) at softdep_deallocate_depende
ncies+0x4b
brelse(fffffd810a534100) at brelse+0xe0
sdstrategy(fffffd810a534100) at sdstrategy+0xa9
spec_strategy(ffff8000221eca60) at spec_strategy+0x55
ufs_strategy(ffff8000221eca60) at ufs_strategy+0xf2
VOP_STRATEGY(fffffd810a534100) at VOP_STRATEGY+0x4e
bwrite(fffffd810a534100) at bwrite+0x128
VOP_BWRITE(fffffd810a534100) at VOP_BWRITE+0x35
ffs_fsync(ffff8000221ecb88) at ffs_fsync+0x188
VOP_FSYNC(fffffd81219b9328,fffffd812e7caf00,1,ffff800022199c20) at VOP_FSYNC+0x
4b
ffs_sync_vnode(fffffd81219b9328,ffff8000221ecca0) at ffs_sync_vnode+0xd3
vfs_mount_foreach_vnode(ffff8000000d5800,ffffffff81574c10,ffff8000221ecca0) at v
fs_mount_foreach_vnode+0x4a
ffs_sync(ffff8000000d5800,1,0,fffffd812e7caf00,ffff800022199c20) at ffs_sync+0x
b4
dounmount_leaf(ffff8000000d5800,8080000,ffff800022199c20) at dounmount_leaf+0xa
a
dounmount(ffff8000000d5800,8080000,ffff800022199c20) at dounmount+0xfc
vop_generic_revoke(ffff8000221ece68) at vop_generic_revoke+0x7d
VOP_REVOKE(fffffd810f690bc8,1) at VOP_REVOKE+0x37
vdevgone(4,10,1f,3) at vdevgone+0x9a
disk_gone(ffffffff8156adc0,1) at disk_gone+0x68
sddetach(ffff800000139600,1) at sddetach+0x3a
config_detach(ffff800000139600,1) at config_detach+0x154
scsi_detach_lun(ffff800000133900,1,0,1) at scsi_detach_lun+0xc8
scsi_detach_bus(ffff800000133900,1) at scsi_detach_bus+0xcf
scsibusdetach(ffff800000133900,1) at scsibusdetach+0x2e
config_detach(ffff800000133900,1) at config_detach+0x154
umass_scsi_detach(ffff800000139200,1) at umass_scsi_detach+0x35
umass_detach(ffff800000139200,1) at umass_detach+0xd4
config_detach(ffff800000139200,1) at config_detach+0x154
usbd_detach(ffff80000013a000,ffff800000080500) at usbd_detach+0x5a
uhub_port_connect(ffff800000080500,3,2a0,202) at uhub_port_connect+0x68
uhub_explore(ffff8000000bf100) at uhub_explore+0x24d
usb_explore(ffff8000000bf000) at usb_explore+0x12b
usb_task_thread(ffff800022199c20) at usb_task_thread+0x106
end trace frame: 0x0, count: -35

ddb{1}> machine ddbcpu 0
Stopped at      x86_ipi_db+0x12:        leave
x86_ipi_db(ffffffff81ca8ff0) at x86_ipi_db+0x12
x86_ipi_handler() at x86_ipi_handler+0x80
Xresume_lapic_ipi(4,ffffffff81ca8ff0,0,0,0,0) at Xresume_lapic_ipi+0x23
_kernel_lock(0,0) at _kernel_lock+0xa2
Xsoftclock(0,ffffffff81ca8ff0,e0dc70c1000,0,ffff8000222c6bd8,1) at Xsoftclock+0
x1f
_kernel_lock(ffff800022319980,1) at _kernel_lock+0xa2
usertrap(ffff800022319980) at usertrap+0x23c
recall_trap(6,0,40000000,6,40000000,e0d71403000) at recall_trap+0x8
end of kernel
end trace frame: 0x7f7ffffcadd0, count: 7

ddb{0}> trace
x86_ipi_db(ffffffff81ca8ff0) at x86_ipi_db+0x12
x86_ipi_handler() at x86_ipi_handler+0x80
Xresume_lapic_ipi(4,ffffffff81ca8ff0,0,0,0,0) at Xresume_lapic_ipi+0x23
_kernel_lock(0,0) at _kernel_lock+0xa2
Xsoftclock(0,ffffffff81ca8ff0,e0dc70c1000,0,ffff8000222c6bd8,1) at Xsoftclock+0
x1f
_kernel_lock(ffff800022319980,1) at _kernel_lock+0xa2
usertrap(ffff800022319980) at usertrap+0x23c
recall_trap(6,0,40000000,6,40000000,e0d71403000) at recall_trap+0x8
end of kernel
end trace frame: 0x7f7ffffcadd0, count: -8

ddb{0}> machine ddbcpu 2
Stopped at      x86_ipi_db+0x12:        leave
x86_ipi_db(ffff800022009ff0) at x86_ipi_db+0x12
x86_ipi_handler() at x86_ipi_handler+0x80
Xresume_lapic_ipi(0,ffff800022009ff0,7f7ffffe7f88,0,30,ffff800022294e28) at Xre
sume_lapic_ipi+0x23
_kernel_lock(ffff8000222b10f0,ffff8000222b10f0) at _kernel_lock+0xa2
Xsyscall(0,3,60d7d90f2b0,3,60b24403ac9,60dc4000000) at Xsyscall+0x128
end of kernel
end trace frame: 0x7f7ffffe7fa0, count: 10

ddb{2}> trace
x86_ipi_db(ffff800022009ff0) at x86_ipi_db+0x12
x86_ipi_handler() at x86_ipi_handler+0x80
Xresume_lapic_ipi(0,ffff800022009ff0,7f7ffffe7f88,0,30,ffff800022294e28) at Xre
sume_lapic_ipi+0x23
_kernel_lock(ffff8000222b10f0,ffff8000222b10f0) at _kernel_lock+0xa2
Xsyscall(0,3,60d7d90f2b0,3,60b24403ac9,60dc4000000) at Xsyscall+0x128
end of kernel
end trace frame: 0x7f7ffffe7fa0, count: -5

ddb{2}> machine ddbcpu 3
Stopped at      x86_ipi_db+0x12:        leave
x86_ipi_db(ffff800022012ff0) at x86_ipi_db+0x12
x86_ipi_handler() at x86_ipi_handler+0x80
Xresume_lapic_ipi(0,ffff800022012ff0,0,0,ffff8000221c77a0,ffffffff81d70e30) at X
resume_lapic_ipi+0x23
_kernel_lock(ffffffff81d70e30,ffffffff81d70e30) at _kernel_lock+0xa2
taskq_thread(ffff800000022040) at taskq_thread+0x4d
end trace frame: 0x0, count: 10

ddb{3}> trace
x86_ipi_db(ffff800022012ff0) at x86_ipi_db+0x12
x86_ipi_handler() at x86_ipi_handler+0x80
Xresume_lapic_ipi(0,ffff800022012ff0,0,0,ffff8000221c77a0,ffffffff81d70e30) at X
resume_lapic_ipi+0x23
_kernel_lock(ffffffff81d70e30,ffffffff81d70e30) at _kernel_lock+0xa2
taskq_thread(ffff800000022040) at taskq_thread+0x4d
end trace frame: 0x0, count: -5

ddb{3}> show uvm
Current UVM status:
  pagesize=4096 (0x1000), pagemask=0xfff, pageshift=12
  1008222 VM pages: 598499 active, 299257 inactive, 0 wired, 33672 free (81346 z
ero)
  min  10% (25) anon, 10% (25) vnode, 5% (12) vtext
  freemin=33607, free-target=44809, inactive-target=299257, wired-max=336074
  faults=30459151, traps=30984849, intrs=29341612, ctxswitch=126951035 fpuswitc
h=0
  softint=12488449, syscalls=569830992, kmapent=11
  fault counts:
    noram=0, noanon=0, noamap=0, pgwait=10, pgrele=0
    ok relocks(total)=126137(126225), anget(retries)=4966720(769), amapcopy=461
0599
    neighbor anon/obj pg=5966808/8752858, gets(lock/unlock)=2500809/125460
    cases: anon=4149073, anoncow=817639, obj=2234084, prcopy=266633, przero=229
91695
  daemon and swap counts:
    woke=1357, revs=1357, scans=386072568, obscans=8041, anscans=386064527
    busy=0, freed=38417, reactivate=0, deactivate=570634
    pageouts=24127721, pending=1904, nswget=770
    nswapdev=1
    swpages=522705, swpginuse=51695, swpgonly=29641 paging=3824
  kernel pointers:
    objs(kern)=0xffffffff81d0bdb8

ddb{3}> show bcstats
Current Buffer Cache status:
numbufs 3017 busymapped 15, delwri 161
kvaslots 6553 avail kva slots 6538
bufpages 40716, dmapages 40716, dirtypages 2372
pendingreads 11, pendingwrites 0
highflips 0, highflops 0, dmaflips 0

ddb{3}> ps
   PID     TID   PPID    UID  S       FLAGS  WAIT          COMMAND
 74932   21544  92363      0  3    0x100010  anonget2      cron
 40174  427420  92363      0  3    0x100010  anonget2      cron
 84644  340484  94001    686  3           0  biowait       redis-server
 93015  120592  92363      0  3    0x100010  biowait       cron
 38450  129030   2451    503  3        0x80  select        postgres
 36876  358738  21275   1000  4     0x80883  fsleep        amuled
 36876   11219  21275   1000  3   0x4002803  bqwait        amuled
 36876   33578  21275   1000  4   0x4080803                amuled
 49910  438280  55493     67  3        0x90  netcon        php-fpm-7.2
 71957  429314  55493     67  3        0x90  netcon        php-fpm-7.2
 29396   76103   2451    503  3        0x80  poll          postgres
 32978  427245   2451    503  3        0x80  poll          postgres
 82346  350631   2451    503  3        0x80  poll          postgres
  3329   14055  55493     67  3        0x90  netio         php-fpm-7.2
 69171   25789  55493     67  3        0x90  netio         php-fpm-7.2
  4417  391975  55493     67  3        0x90  netio         php-fpm-7.2
 21275   18947  25656   1000  3    0x10008b  pause         ksh
 25656  338585      1   1000  3    0x100080  kqread        tmux
 47217   46641      1   1000  3    0x100003  biowait       ksh
 92363  122766      1      0  3    0x100098  poll          cron
  3177  236851      1    662  3        0x80  kqread        transmission-dae
  3177  183958      1    662  3   0x4000080  piperd        transmission-dae
  3177   21223      1    662  3   0x4000000  biowait       transmission-dae
  3177   71527      1    662  3   0x4000080  select        transmission-dae
 55493  153373      1      0  3        0x80  kqread        php-fpm-7.2
 94001  482588      1    686  3        0x80  kqread        redis-server
 94001  223852      1    686  3   0x4000080  fsleep        redis-server
 94001  466779      1    686  3   0x4000080  fsleep        redis-server
 94001  180641      1    686  3   0x4000080  fsleep        redis-server
 95302  284543   2451    503  3           0  biowait       postgres
 68640  248857   2451    503  3           0  bqwait        postgres
  5365  388800   2451    503  2           0                postgres
 61174  480302   2451    503  3        0x80  poll          postgres
 62043  129230   2451    503  3        0x80  poll          postgres
 96874    1533   2451    503  3           0  biowait       postgres
  2451  136913      1    503  3        0x83  select        postgres
 17687  383595      1     99  3    0x100090  poll          sndiod
 41586  468917      1    110  3    0x100090  poll          sndiod
 22691  264183      1      0  3    0x100080  kqread        httpd
 42593  457920      1     67  3    0x100092  kqread        httpd
 82705   36485      1     67  3    0x100012  biowait       httpd
 67663  205198      1     67  3    0x100012  inode         httpd
 71465  183034      1     67  7    0x100012                httpd
 16408  470421      1      0  3           0  biowait       sshd
  1670  371365  31693      0  3        0x80  nfsd          nfsd
  8616  482958  31693      0  3        0x80  nfsd          nfsd
 83701  497791  31693      0  3        0x80  nfsd          nfsd
 94180  278451  31693      0  3        0x80  nfsd          nfsd
 31693  106663      1      0  3        0x80  netcon        nfsd
 42342  148659  89771      0  3        0x80  poll          mountd
 89771  402155      1      0  3    0x100080  poll          mountd
 86122  452583      1     28  3    0x100090  poll          portmap
 11455  393953      1      0  3    0x100080  poll          ntpd
 37268  482168  61083     83  3    0x100092  poll          ntpd
 61083  154548      1     83  3    0x100012  biowait       ntpd
 91508  464909  92707     74  7    0x100012                pflogd
 92707  380285      1      0  3        0x80  netio         pflogd
  7422  123201  92718     73  2    0x100090                syslogd
 92718  227999      1      0  3    0x100082  netio         syslogd
 90973   42878      1     77  3    0x100090  poll          dhclient
 88658  417895      1      0  3        0x80  poll          dhclient
 88788  351152  39655    115  3    0x100092  kqread        slaacd
 57316  250411  39655    115  3    0x100092  kqread        slaacd
 39655  457642      1      0  3    0x100080  kqread        slaacd
 47777  486979      0      0  3     0x14200  pgzero        zerothread
 72154  375876      0      0  3     0x14200  aiodoned      aiodoned
  5699  345396      0      0  3     0x14200  bqwait        update
 17279  101462      0      0  3     0x14200  cleaner       cleaner
 84000  479284      0      0  3     0x14200  reaper        reaper
 63723   70736      0      0  3     0x14200  pgdaemon      pagedaemon
 86358  135483      0      0  3     0x14200  bored         crynlk
 14460  231996      0      0  3     0x14200  bored         crypto
 47008   96919      0      0  3     0x14200  bored         sensors
  9261  121305      0      0  2     0x14200                sdmmc0
 64352   65268      0      0  7     0x14200                usbtask
 24675  249464      0      0  3     0x14200  usbatsk       usbatsk
 62571   20494      0      0  3  0x40014200  acpi0         acpi0
 89647  116421      0      0  3  0x40014200                idle3
  1201  120409      0      0  3  0x40014200                idle2
 42617  515729      0      0  3  0x40014200                idle1
* 7219  186181      0      0  7     0x14200                softnet
 91968  351039      0      0  3     0x14200  bored         systqmp
 46276  356803      0      0  3     0x14200  bored         systq
 78176  504770      0      0  3  0x40014200  bored         softclock
  4459  513570      0      0  3  0x40014200                idle0
 36501  385249      0      0  3     0x14200  bored         smr
     1  332950      0      0  3        0x82  wait          init
     0       0     -1      0  3     0x10200  scheduler     swapper
OpenBSD 6.5 (GENERIC.MP) #1: Mon May 27 18:27:59 CEST 2019
    [hidden email]:/usr/src/sys/arch/amd64/compile/GENERIC.
MP
real mem = 4259930112 (4062MB)
avail mem = 4121190400 (3930MB)
mpath0 at root
scsibus0 at mpath0: 256 targets
mainbus0 at root
bios0 at mainbus0: SMBIOS rev. 2.7 @ 0xcfe97020 (12 entries)
bios0: vendor coreboot version "v4.9.0.6" date 06/08/2019
bios0: PC Engines apu2
acpi0 at bios0: rev 2
acpi0: sleep states S0 S1 S4 S5
acpi0: tables DSDT FACP SSDT TPM2 APIC HEST IVRS SSDT SSDT HPET
acpi0: wakeup devices PWRB(S4) PBR4(S4) PBR5(S4) PBR6(S4) PBR7(S4) PBR8(S4) UOH
1(S3) UOH2(S3) UOH3(S3) UOH4(S3) UOH5(S3) UOH6(S3) XHC0(S4)
acpitimer0 at acpi0: 3579545 Hz, 32 bits
acpimadt0 at acpi0 addr 0xfee00000: PC-AT compat
cpu0 at mainbus0: apid 0 (boot processor)
cpu0: AMD GX-412TC SOC, 998.26 MHz, 16-30-01
cpu0: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,C
FLUSH,MMX,FXSR,SSE,SSE2,HTT,SSE3,PCLMUL,MWAIT,SSSE3,CX16,SSE4.1,SSE4.2,MOVBE,PO
PCNT,AES,XSAVE,AVX,F16C,NXE,MMXX,FFXSR,PAGE1GB,RDTSCP,LONG,LAHF,CMPLEG,SVM,EAPI
CSP,AMCR8,ABM,SSE4A,MASSE,3DNOWP,OSVW,IBS,SKINIT,TOPEXT,DBKP,PERFTSC,PCTRL3,ITS
C,BMI1,XSAVEOPT
cpu0: 32KB 64b/line 2-way I-cache, 32KB 64b/line 8-way D-cache, 2MB 64b/line 16
-way L2 cache
cpu0: ITLB 32 4KB entries fully associative, 8 4MB entries fully associative
cpu0: DTLB 40 4KB entries fully associative, 8 4MB entries fully associative
cpu0: smt 0, core 0, package 0
mtrr: Pentium Pro MTRR support, 8 var ranges, 88 fixed ranges
cpu0: apic clock running at 99MHz
cpu0: mwait min=64, max=64, IBE
cpu1 at mainbus0: apid 1 (application processor)
cpu1: AMD GX-412TC SOC, 998.13 MHz, 16-30-01
cpu1: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,C
FLUSH,MMX,FXSR,SSE,SSE2,HTT,SSE3,PCLMUL,MWAIT,SSSE3,CX16,SSE4.1,SSE4.2,MOVBE,PO
PCNT,AES,XSAVE,AVX,F16C,NXE,MMXX,FFXSR,PAGE1GB,RDTSCP,LONG,LAHF,CMPLEG,SVM,EAPI
CSP,AMCR8,ABM,SSE4A,MASSE,3DNOWP,OSVW,IBS,SKINIT,TOPEXT,DBKP,PERFTSC,PCTRL3,ITS
C,BMI1,XSAVEOPT
cpu1: 32KB 64b/line 2-way I-cache, 32KB 64b/line 8-way D-cache, 2MB 64b/line 16
-way L2 cache
cpu1: ITLB 32 4KB entries fully associative, 8 4MB entries fully associative
cpu1: DTLB 40 4KB entries fully associative, 8 4MB entries fully associative
cpu1: smt 0, core 1, package 0
cpu2 at mainbus0: apid 2 (application processor)
cpu2: AMD GX-412TC SOC, 998.13 MHz, 16-30-01
cpu2: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,C
FLUSH,MMX,FXSR,SSE,SSE2,HTT,SSE3,PCLMUL,MWAIT,SSSE3,CX16,SSE4.1,SSE4.2,MOVBE,PO
PCNT,AES,XSAVE,AVX,F16C,NXE,MMXX,FFXSR,PAGE1GB,RDTSCP,LONG,LAHF,CMPLEG,SVM,EAPI
CSP,AMCR8,ABM,SSE4A,MASSE,3DNOWP,OSVW,IBS,SKINIT,TOPEXT,DBKP,PERFTSC,PCTRL3,ITS
C,BMI1,XSAVEOPT
cpu2: 32KB 64b/line 2-way I-cache, 32KB 64b/line 8-way D-cache, 2MB 64b/line 16
-way L2 cache
cpu2: ITLB 32 4KB entries fully associative, 8 4MB entries fully associative
cpu2: DTLB 40 4KB entries fully associative, 8 4MB entries fully associative
cpu2: smt 0, core 2, package 0
cpu3 at mainbus0: apid 3 (application processor)
cpu3: AMD GX-412TC SOC, 998.36 MHz, 16-30-01
cpu3: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,C
FLUSH,MMX,FXSR,SSE,SSE2,HTT,SSE3,PCLMUL,MWAIT,SSSE3,CX16,SSE4.1,SSE4.2,MOVBE,PO
PCNT,AES,XSAVE,AVX,F16C,NXE,MMXX,FFXSR,PAGE1GB,RDTSCP,LONG,LAHF,CMPLEG,SVM,EAPI
CSP,AMCR8,ABM,SSE4A,MASSE,3DNOWP,OSVW,IBS,SKINIT,TOPEXT,DBKP,PERFTSC,PCTRL3,ITS
C,BMI1,XSAVEOPT
cpu3: 32KB 64b/line 2-way I-cache, 32KB 64b/line 8-way D-cache, 2MB 64b/line 16
-way L2 cache
cpu3: ITLB 32 4KB entries fully associative, 8 4MB entries fully associative
cpu3: DTLB 40 4KB entries fully associative, 8 4MB entries fully associative
cpu3: smt 0, core 3, package 0
ioapic0 at mainbus0: apid 4 pa 0xfec00000, version 21, 24 pins
ioapic1 at mainbus0: apid 5 pa 0xfec20000, version 21, 32 pins, remapped
acpihpet0 at acpi0: 14318180 Hz
acpiprt0 at acpi0: bus 0 (PCI0)
acpiprt1 at acpi0: bus -1 (PBR4)
acpiprt2 at acpi0: bus 1 (PBR5)
acpiprt3 at acpi0: bus 2 (PBR6)
acpiprt4 at acpi0: bus 3 (PBR7)
acpiprt5 at acpi0: bus 4 (PBR8)
acpicpu0 at acpi0: C2(0@400 io@0x1771), C1(@1 halt!), PSS
acpicpu1 at acpi0: C2(0@400 io@0x1771), C1(@1 halt!), PSS
acpicpu2 at acpi0: C2(0@400 io@0x1771), C1(@1 halt!), PSS
acpicpu3 at acpi0: C2(0@400 io@0x1771), C1(@1 halt!), PSS
acpibtn0 at acpi0: PWRB
acpipci0 at acpi0 PCI0: 0x00000000 0x00000011 0x00000001
acpicmos0 at acpi0
"BOOT0000" at acpi0 not configured
cpu0: 998 MHz: speeds: 1000 800 600 MHz
pci0 at mainbus0 bus 0
pchb0 at pci0 dev 0 function 0 "AMD AMD64 16h Root Complex" rev 0x00
vendor "AMD", unknown product 0x1567 (class system subclass IOMMU, rev 0x00) at
 pci0 dev 0 function 2 not configured
pchb1 at pci0 dev 2 function 0 "AMD AMD64 16h Host" rev 0x00
ppb0 at pci0 dev 2 function 2 "AMD AMD64 16h PCIE" rev 0x00: msi
pci1 at ppb0 bus 1
em0 at pci1 dev 0 function 0 "Intel I210" rev 0x03: msi, address 00:0d:b9:4e:cc
:fc
ppb1 at pci0 dev 2 function 3 "AMD AMD64 16h PCIE" rev 0x00: msi
pci2 at ppb1 bus 2
em1 at pci2 dev 0 function 0 "Intel I210" rev 0x03: msi, address 00:0d:b9:4e:cc
:fd
ppb2 at pci0 dev 2 function 4 "AMD AMD64 16h PCIE" rev 0x00: msi
pci3 at ppb2 bus 3
em2 at pci3 dev 0 function 0 "Intel I210" rev 0x03: msi, address 00:0d:b9:4e:cc
:fe
ppb3 at pci0 dev 2 function 5 "AMD AMD64 16h PCIE" rev 0x00: msi
pci4 at ppb3 bus 4
athn0 at pci4 dev 0 function 0 "Atheros AR9281" rev 0x01: apic 5 int 16
athn0: AR9280 rev 2 (2T2R), ROM rev 22, address 04:f0:21:45:7f:34
ccp0 at pci0 dev 8 function 0 "AMD Cryptographic Co-processor v3" rev 0x00
xhci0 at pci0 dev 16 function 0 "AMD Bolton xHCI" rev 0x11: msi, xHCI 1.0
usb0 at xhci0: USB revision 3.0
uhub0 at usb0 configuration 1 interface 0 "AMD xHCI root hub" rev 3.00/1.00 add
r 1
ahci0 at pci0 dev 17 function 0 "AMD Hudson-2 SATA" rev 0x39: msi, AHCI 1.3
scsibus1 at ahci0: 32 targets
ehci0 at pci0 dev 19 function 0 "AMD Hudson-2 USB2" rev 0x39: apic 4 int 18
usb1 at ehci0: USB revision 2.0
uhub1 at usb1 configuration 1 interface 0 "AMD EHCI root hub" rev 2.00/1.00 add
r 1
piixpm0 at pci0 dev 20 function 0 "AMD Hudson-2 SMBus" rev 0x42: SMBus disabled

pcib0 at pci0 dev 20 function 3 "AMD Hudson-2 LPC" rev 0x11
sdhc0 at pci0 dev 20 function 7 "AMD Bolton SD/MMC" rev 0x01: apic 4 int 16
sdhc0: SDHC 2.0, 50 MHz base clock
sdmmc0 at sdhc0: 4-bit, sd high-speed, mmc high-speed, dma
pchb2 at pci0 dev 24 function 0 "AMD AMD64 16h Link Cfg" rev 0x00
pchb3 at pci0 dev 24 function 1 "AMD AMD64 16h Address Map" rev 0x00
pchb4 at pci0 dev 24 function 2 "AMD AMD64 16h DRAM Cfg" rev 0x00
km0 at pci0 dev 24 function 3 "AMD AMD64 16h Misc Cfg" rev 0x00
pchb5 at pci0 dev 24 function 4 "AMD AMD64 16h CPU Power" rev 0x00
pchb6 at pci0 dev 24 function 5 "AMD AMD64 16h Misc Cfg" rev 0x00
isa0 at pcib0
isadma0 at isa0
com0 at isa0 port 0x3f8/8 irq 4: ns16550a, 16 byte fifo
com0: console
com1 at isa0 port 0x2f8/8 irq 3: ns16550a, 16 byte fifo
com2 at isa0 port 0x3e8/8 irq 5: ns16550a, 16 byte fifo
pcppi0 at isa0 port 0x61
spkr0 at pcppi0
lpt0 at isa0 port 0x378/4 irq 7
wbsio0 at isa0 port 0x2e/2: NCT5104D rev 0x53
vmm0 at mainbus0: SVM/RVI
scsibus2 at sdmmc0: 2 targets, initiator 0
sd0 at scsibus2 targ 1 lun 0: <SD/MMC, SD32G, 0030> SCSI2 0/direct removable
sd0: 29808MB, 512 bytes/sector, 61046784 sectors
umass0 at uhub0 port 3 configuration 1 interface 0 "Western Digital Ext HDD 102
1" rev 2.00/20.21 addr 2
umass0: using SCSI over Bulk-Only
scsibus3 at umass0: 2 targets, initiator 0
sd1 at scsibus3 targ 1 lun 0: <WD, Ext HDD 1021, 2021> SCSI2 0/direct fixed ser
ial.10581021303230333333
sd1: 1907727MB, 512 bytes/sector, 3907024896 sectors
uhub2 at uhub1 port 1 configuration 1 interface 0 "Advanced Micro Devices produ
ct 0x7900" rev 2.00/0.18 addr 2
vscsi0 at root
scsibus4 at vscsi0: 256 targets
softraid0 at root
scsibus5 at softraid0: 256 targets
root on sd0a (4fe8cd06a9c52d24.a) swap on sd0b dump on sd0b
Process (pid 1) got signal 31
/mnt/hd: got error 6 while accessing filesystem
panic: softdep_deallocate_dependencies: unrecovered I/O error
Stopped at      db_enter+0x10:  popq    %rbp
    TID    PID    UID     PRFLAGS     PFLAGS  CPU  COMMAND
 183034  71465     67    0x100012          0    0  httpd
 464909  91508     74    0x100012          0    2  pflogd
* 65268  64352      0     0x14000      0x200    1K usbtask
 186181   7219      0     0x14000      0x200    3  softnet
db_enter() at db_enter+0x10
panic() at panic+0x128
softdep_deallocate_dependencies(fffffd810a534100) at softdep_deallocate_depende
ncies+0x4b
brelse(fffffd810a534100) at brelse+0xe0
sdstrategy(fffffd810a534100) at sdstrategy+0xa9
spec_strategy(ffff8000221eca60) at spec_strategy+0x55
ufs_strategy(ffff8000221eca60) at ufs_strategy+0xf2
VOP_STRATEGY(fffffd810a534100) at VOP_STRATEGY+0x4e
bwrite(fffffd810a534100) at bwrite+0x128
VOP_BWRITE(fffffd810a534100) at VOP_BWRITE+0x35
ffs_fsync(ffff8000221ecb88) at ffs_fsync+0x188
VOP_FSYNC(fffffd81219b9328,fffffd812e7caf00,1,ffff800022199c20) at VOP_FSYNC+0x
4b
ffs_sync_vnode(fffffd81219b9328,ffff8000221ecca0) at ffs_sync_vnode+0xd3
vfs_mount_foreach_vnode(ffff8000000d5800,ffffffff81574c10,ffff8000221ecca0) at v
fs_mount_foreach_vnode+0x4a
end trace frame: 0xffff8000221ecd40, count: 0
https://www.openbsd.org/ddb.html describes the minimum info required in bug
reports.  Insufficient info makes it difficult to find and fix bugs.

When I connected my usb-to-serial adapter in order to get some details
about the crash, I was getting no output. I started getting it
immediately after I disconnected the hard drive.

Do you have any idea of the cause of this issue? Maybe a faulty hard
drive? I was using this same hard drive with Linux and I don't
remember having issues. I am going to disable softdep to see if things
get better.

Aldo

Reply | Threaded
Open this post in threaded view
|

Re: Frequent kernel panic on apu2d4 and external usb3 hard drive

Philip Guenther-3
On Sun, 7 Jul 2019, Aldo Mazzeo wrote:
> I'm running OpenBSD 6.5 on my apu2d4 and I have an external USB3 hard
> drive attached (it is powered by a separate cable). The external hd is
> FFS-formatted with softdep enabled and the kernel panics once every
> 24-36h with the following DDB details:

As you've found, softdep is dangerous when a drive may be unexpected
disconnected as it has critical sections where it cannot tolerate the
inability to do I/O and will panic.

In this case, it appears the I/O error was caused by the drive being
disconnected at the USB level:
...

> disk_gone(ffffffff8156adc0,1) at disk_gone+0x68
> sddetach(ffff800000139600,1) at sddetach+0x3a
> config_detach(ffff800000139600,1) at config_detach+0x154
> scsi_detach_lun(ffff800000133900,1,0,1) at scsi_detach_lun+0xc8
> scsi_detach_bus(ffff800000133900,1) at scsi_detach_bus+0xcf
> scsibusdetach(ffff800000133900,1) at scsibusdetach+0x2e
> config_detach(ffff800000133900,1) at config_detach+0x154
> umass_scsi_detach(ffff800000139200,1) at umass_scsi_detach+0x35
> umass_detach(ffff800000139200,1) at umass_detach+0xd4
> config_detach(ffff800000139200,1) at config_detach+0x154
> usbd_detach(ffff80000013a000,ffff800000080500) at usbd_detach+0x5a
> uhub_port_connect(ffff800000080500,3,2a0,202) at uhub_port_connect+0x68



> When I connected my usb-to-serial adapter in order to get some details
> about the crash, I was getting no output. I started getting it
> immediately after I disconnected the hard drive.

I don't really understand the timeline of events here, but disconnecting a
harddrive with a mounted filesystem using softdeps will break things.  
Don't Do That.


> Do you have any idea of the cause of this issue? Maybe a faulty hard
> drive? I was using this same hard drive with Linux and I don't remember
> having issues. I am going to disable softdep to see if things get
> better.

That should help with the panics.  Watch your dmesg for additional reports
of I/O errors that might indicate failures of the drive itself.


Philip Guenther

Reply | Threaded
Open this post in threaded view
|

Re: Frequent kernel panic on apu2d4 and external usb3 hard drive

Aldo Mazzeo
Hi Philip,
thank you very much for your reply.

I'm not disconnecting my hard drive at all, so I think it must be a
flaky cable or a flaky USB port.

I will try to move my hard drive from the USB port to the SATA port.

Thanks again!

Il giorno mer 10 lug 2019 alle ore 10:03 Philip Guenther
<[hidden email]> ha scritto:

>
> On Sun, 7 Jul 2019, Aldo Mazzeo wrote:
> > I'm running OpenBSD 6.5 on my apu2d4 and I have an external USB3 hard
> > drive attached (it is powered by a separate cable). The external hd is
> > FFS-formatted with softdep enabled and the kernel panics once every
> > 24-36h with the following DDB details:
>
> As you've found, softdep is dangerous when a drive may be unexpected
> disconnected as it has critical sections where it cannot tolerate the
> inability to do I/O and will panic.
>
> In this case, it appears the I/O error was caused by the drive being
> disconnected at the USB level:
> ...
> > disk_gone(ffffffff8156adc0,1) at disk_gone+0x68
> > sddetach(ffff800000139600,1) at sddetach+0x3a
> > config_detach(ffff800000139600,1) at config_detach+0x154
> > scsi_detach_lun(ffff800000133900,1,0,1) at scsi_detach_lun+0xc8
> > scsi_detach_bus(ffff800000133900,1) at scsi_detach_bus+0xcf
> > scsibusdetach(ffff800000133900,1) at scsibusdetach+0x2e
> > config_detach(ffff800000133900,1) at config_detach+0x154
> > umass_scsi_detach(ffff800000139200,1) at umass_scsi_detach+0x35
> > umass_detach(ffff800000139200,1) at umass_detach+0xd4
> > config_detach(ffff800000139200,1) at config_detach+0x154
> > usbd_detach(ffff80000013a000,ffff800000080500) at usbd_detach+0x5a
> > uhub_port_connect(ffff800000080500,3,2a0,202) at uhub_port_connect+0x68
>
>
>
> > When I connected my usb-to-serial adapter in order to get some details
> > about the crash, I was getting no output. I started getting it
> > immediately after I disconnected the hard drive.
>
> I don't really understand the timeline of events here, but disconnecting a
> harddrive with a mounted filesystem using softdeps will break things.
> Don't Do That.
>
>
> > Do you have any idea of the cause of this issue? Maybe a faulty hard
> > drive? I was using this same hard drive with Linux and I don't remember
> > having issues. I am going to disable softdep to see if things get
> > better.
>
> That should help with the panics.  Watch your dmesg for additional reports
> of I/O errors that might indicate failures of the drive itself.
>
>
> Philip Guenther



--
_______________
Aldo Mazzeo
Software Engineer/Reverse Engineer
[hidden email]
AT +43 650 7969209
IT +39 389 438 7665