On 15/02/22 09:26  Claudio Jeker wrote:
> On Tue, Feb 15, 2022 at 02:28:15AM +0100, Tilo Stritzky wrote:
> > On 15/02/22 01:54  Tilo Stritzky wrote:
> > >
> > > I've got another one with a homebuild from -current as of 2022-02-13,
> > > will put traces in another mail.
> >
> > And here we go.
> > This is from the crash dump, I lost the console log on this one.
> > Dump is still here.
> >
>
> Did you suspend with these disks attached?

To be honest I'm not sure about this particular event, I might have
forgotten one.

But I am positive I've seen a fault like this without any USB disks
attached, not before or during the zzz.

> > sd2 at scsibus5 targ 1 lun 0: <WD, Elements 25A2, 1019> 
> > serial.105825a2373946394343
> > sd2: 1907697MB, 512 bytes/sector, 3906963456 sectors
> > softraid0: sd3 was not shutdown properly
> > softraid0: sd3 was not shutdown properly
> > sd3 at scsibus4 targ 1 lun 0: <OPENBSD, SR CRYPTO, 006>
> > sd3: 1907694MB, 512 bytes/sector, 3906959213 sectors
> > umass2 at uhub6 port 3 configuration 1 interface 0 "Genesys Logic USB 
> > Storage" rev 2.00/0.41 addr 6
> > umass2: using SCSI over Bulk-Only
> > scsibus6 at umass2: 2 targets, initiator 0
> > sd4 at scsibus6 targ 1 lun 0: <Maxtor 6, Y120P0, 0041> 
> > serial.05e30718000000000033
> > sd4: 117246MB, 512 bytes/sector, 240121728 sectors
> > softraid0: sd3 was not shutdown properly
> > softraid0: sd3 was not shutdown properly
> > softraid0: incorrect key or passphrase
> > softraid0: sd3 was not shutdown properly
> > softraid0: sd3 was not shutdown properly
> > sd5 at scsibus4 targ 2 lun 0: <OPENBSD, SR CRYPTO, 006>
> > sd5: 116212MB, 512 bytes/sector, 238002895 sectors
> > softraid0: volume sd5 is roaming, it used to be sd3, updating metadata
> > softraid0: roaming device sd2d -> sd4d
>
> I wonder if the forced unmount of umass triggers a bug in the softraid
> code. Honestly don't suspend / hibernate a system with detachable disks
> and expect it to be nice to those disks.

I don't. I fully understand that USB devices get knocked off by a suspend/
hibernate.

> > $ dmesg -M bsd.0.core -N bsd.0
> > OpenBSD 7.0-current (GENERIC.MP) #0: Sun Feb 13 14:46:40 CET 2022
> >     t...@server.my.domain:/usr/src/src/sys/arch/amd64/compile/GENERIC.MP
> > real mem = 2112094208 (2014MB)
> > avail mem = 2030948352 (1936MB)
> > random: good seed from bootblocks
> > mpath0 at root
> > scsibus0 at mpath0: 256 targets
> > mainbus0 at root
> > bios0 at mainbus0: SMBIOS rev. 3.0 @ 0x7ee42040 (9 entries)
> > bios0: vendor coreboot version "v4.12.0.5" date 09/25/2020
> > bios0: PC Engines apu1
> > acpi0 at bios0: ACPI 6.0
> > acpi0: sleep states S0 S1 S3 S4 S5
> > acpi0: tables DSDT FACP SSDT TPM2 APIC HEST SSDT SSDT HPET
> > acpi0: wakeup devices AGPB(S4) HDMI(S4) PBR4(S4) PBR5(S4) PBR6(S4) PBR7(S4) 
> > PE20(S4) PE21(S4) PE22(S4) PE23(S4) PIBR(S4) UOH1(S3) UOH2(S3) UOH3(S3) 
> > UOH4(S3) UOH5(S3) [...]
> > acpitimer0 at acpi0: 3579545 Hz, 32 bits
> > acpimadt0 at acpi0 addr 0xfee00000: PC-AT compat
> > cpu0 at mainbus0: apid 0 (boot processor)
> > cpu0: AMD G-T40E Processor, 1000.15 MHz, 14-02-00
> > cpu0: 
> > FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,MMX,FXSR,SSE,SSE2,HTT,SSE3,MWAIT,SSSE3,CX16,POPCNT,NXE,MMXX,FFXSR,PAGE1GB,RDTSCP,LONG,LAHF,CMPLEG,SVM,EAPICSP,AMCR8,ABM,SSE4A,MASSE,3DNOWP,IBS,SKINIT,ITSC
> > cpu0: 32KB 64b/line 2-way I-cache, 32KB 64b/line 8-way D-cache, 512KB 
> > 64b/line 16-way L2 cache
> > cpu0: 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 199MHz
> > cpu0: mwait min=64, max=64, IBE
> > cpu1 at mainbus0: apid 1 (application processor)
> > cpu1: AMD G-T40E Processor, 1000.01 MHz, 14-02-00
> > cpu1: 
> > FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,MMX,FXSR,SSE,SSE2,HTT,SSE3,MWAIT,SSSE3,CX16,POPCNT,NXE,MMXX,FFXSR,PAGE1GB,RDTSCP,LONG,LAHF,CMPLEG,SVM,EAPICSP,AMCR8,ABM,SSE4A,MASSE,3DNOWP,IBS,SKINIT,ITSC
> > cpu1: 32KB 64b/line 2-way I-cache, 32KB 64b/line 8-way D-cache, 512KB 
> > 64b/line 16-way L2 cache
> > cpu1: 8 4MB entries fully associative
> > cpu1: DTLB 40 4KB entries fully associative, 8 4MB entries fully associative
> > cpu1: smt 0, core 1, package 0
> > ioapic0 at mainbus0: apid 2 pa 0xfec00000, version 21, 24 pins
> > acpihpet0 at acpi0: 14318180 Hz
> > acpiprt0 at acpi0: bus 0 (PCI0)
> > acpiprt1 at acpi0: bus -1 (AGPB)
> > acpiprt2 at acpi0: bus -1 (HDMI)
> > acpiprt3 at acpi0: bus 1 (PBR4)
> > acpiprt4 at acpi0: bus 2 (PBR5)
> > acpiprt5 at acpi0: bus 3 (PBR6)
> > acpiprt6 at acpi0: bus -1 (PBR7)
> > acpiprt7 at acpi0: bus 5 (PE20)
> > acpiprt8 at acpi0: bus -1 (PE21)
> > acpiprt9 at acpi0: bus -1 (PE22)
> > acpiprt10 at acpi0: bus -1 (PE23)
> > acpiprt11 at acpi0: bus 4 (PIBR)
> > acpipci0 at acpi0 PCI0: 0x00000000 0x00000011 0x00000001
> > "PRP0001" at acpi0 not configured
> > "PRP0001" at acpi0 not configured
> > "PRP0001" at acpi0 not configured
> > "PRP0001" at acpi0 not configured
> > "PRP0001" at acpi0 not configured
> > "PRP0001" at acpi0 not configured
> > "PRP0001" at acpi0 not configured
> > acpicmos0 at acpi0
> > "BOOT0000" at acpi0 not configured
> > acpicpu0 at acpi0: C2(0@100 io@0x841), C1(@1 halt!), PSS
> > acpicpu1 at acpi0: C2(0@100 io@0x841), C1(@1 halt!), PSS
> > acpitz0 at acpi0: critical temperature is 110 degC
> > cpu0: 1000 MHz: speeds: 1000 800 MHz
> > pci0 at mainbus0 bus 0
> > pchb0 at pci0 dev 0 function 0 "AMD 14h Host" rev 0x00
> > ppb0 at pci0 dev 4 function 0 "AMD 14h PCIE" rev 0x00: msi
> > pci1 at ppb0 bus 1
> > re0 at pci1 dev 0 function 0 "Realtek 8168" rev 0x06: RTL8168E/8111E 
> > (0x2c00), msi, address 00:0d:b9:3e:8f:a4
> > rgephy0 at re0 phy 7: RTL8169S/8110S/8211 PHY, rev. 4
> > ppb1 at pci0 dev 5 function 0 "AMD 14h PCIE" rev 0x00: msi
> > pci2 at ppb1 bus 2
> > re1 at pci2 dev 0 function 0 "Realtek 8168" rev 0x06: RTL8168E/8111E 
> > (0x2c00), msi, address 00:0d:b9:3e:8f:a5
> > rgephy1 at re1 phy 7: RTL8169S/8110S/8211 PHY, rev. 4
> > ppb2 at pci0 dev 6 function 0 "AMD 14h PCIE" rev 0x00: msi
> > pci3 at ppb2 bus 3
> > re2 at pci3 dev 0 function 0 "Realtek 8168" rev 0x06: RTL8168E/8111E 
> > (0x2c00), msi, address 00:0d:b9:3e:8f:a6
> > rgephy2 at re2 phy 7: RTL8169S/8110S/8211 PHY, rev. 4
> > ahci0 at pci0 dev 17 function 0 "ATI SBx00 SATA" rev 0x40: apic 2 int 19, 
> > GHC 0x80000000<AE> AHCI 1.2
> > ahci0: capabilities 
> > 0xf322ff05<S64A,NCQ,SSNTF,SMPS,SAL,SCLO,SPM,PMD,SSC,PSC>, 6 ports, 32 cmds, 
> > gen 2 (3.0Gb/s)
> > ahci0: extended capabilities 0x0
> > ahci0: ports implemented: 0x0000003f
> > ahci0.0: port reset
> > ahci0.1: port reset
> > ahci0.2: port reset
> > ahci0.3: port reset
> > ahci0.4: port reset
> > ahci0.5: port reset
> > ahci0: no device detected on port 0
> > ahci0.1: PMP probe 2
> > ahci0.1: sending PMP reset cmd
> > ahci0.1: sending PMP probe status cmd
> > ahci0.1: ccb in slot 1 errored
> > ahci0.1: PMP probe cmd timed out
> > ahci0.1: timed out 1, active 0, active_cnt 0
> > ahci0.1: PMP probe status cmd failed
> > ahci0.1: PMP probe 1
> > ahci0.1: sending PMP reset cmd
> > ahci0.1: sending PMP probe status cmd
> > ahci0.1: ccb in slot 1 errored
> > ahci0.1: PMP probe cmd timed out
> > ahci0.1: timed out 1, active 0, active_cnt 0
> > ahci0.1: PMP probe status cmd failed
> > ahci0.1: no PMP found, resetting the port
> > ahci0: detected device on port 1; 0
> > ahci0.1: port 1: 3.0Gb/s
> > ahci0: no device detected on port 2
> > ahci0: no device detected on port 3
> > ahci0: no device detected on port 4
> > ahci0: no device detected on port 5
> > scsibus1 at ahci0: 32 targets
> > sd0 at scsibus1 targ 1 lun 0: <ATA, ST9160310AS, 0303> naa.5000c50011b8b2fa
> > sd0: 152627MB, 512 bytes/sector, 312581808 sectors
> > ohci0 at pci0 dev 18 function 0 "ATI SB700 USB" rev 0x00: apic 2 int 18, 
> > version 1.0, legacy support
> > ehci0 at pci0 dev 18 function 2 "ATI SB700 USB2" rev 0x00: apic 2 int 17
> > usb0 at ehci0: USB revision 2.0
> > uhub0 at usb0 configuration 1 interface 0 "ATI EHCI root hub" rev 2.00/1.00 
> > addr 1
> > ohci1 at pci0 dev 19 function 0 "ATI SB700 USB" rev 0x00: apic 2 int 18, 
> > version 1.0, legacy support
> > ehci1 at pci0 dev 19 function 2 "ATI SB700 USB2" rev 0x00: apic 2 int 17
> > usb1 at ehci1: USB revision 2.0
> > uhub1 at usb1 configuration 1 interface 0 "ATI EHCI root hub" rev 2.00/1.00 
> > addr 1
> > piixpm0 at pci0 dev 20 function 0 "ATI SBx00 SMBus" rev 0x42: SMI
> > iic0 at piixpm0
> > pcib0 at pci0 dev 20 function 3 "ATI SB700 ISA" rev 0x40
> > ppb3 at pci0 dev 20 function 4 "ATI SB600 PCI" rev 0x40
> > pci4 at ppb3 bus 4
> > ppb4 at pci0 dev 21 function 0 "ATI SB800 PCIE" rev 0x00
> > pci5 at ppb4 bus 5
> > ral0 at pci5 dev 0 function 0 "Ralink RT2790" rev 0x00: apic 2 int 16, 
> > address 00:22:43:5d:d4:53
> > ral0: MAC/BBP RT2872 (rev 0x0200), RF RT2720 (MIMO 1T2R)
> > ohci2 at pci0 dev 22 function 0 "ATI SB700 USB" rev 0x00: apic 2 int 18, 
> > version 1.0, legacy support
> > ehci2 at pci0 dev 22 function 2 "ATI SB700 USB2" rev 0x00: apic 2 int 17
> > usb2 at ehci2: USB revision 2.0
> > uhub2 at usb2 configuration 1 interface 0 "ATI EHCI root hub" rev 2.00/1.00 
> > addr 1
> > pchb1 at pci0 dev 24 function 0 "AMD 14h Link Cfg" rev 0x43
> > pchb2 at pci0 dev 24 function 1 "AMD 14h Address Map" rev 0x00
> > pchb3 at pci0 dev 24 function 2 "AMD 14h DRAM Cfg" rev 0x00
> > km0 at pci0 dev 24 function 3 "AMD 14h Misc Cfg" rev 0x00
> > pchb4 at pci0 dev 24 function 4 "AMD 14h CPU Power" rev 0x00
> > pchb5 at pci0 dev 24 function 5 "AMD 14h Reserved" rev 0x00
> > pchb6 at pci0 dev 24 function 6 "AMD 14h NB Power" rev 0x00
> > pchb7 at pci0 dev 24 function 7 "AMD 14h Reserved" rev 0x00
> > usb3 at ohci0: USB revision 1.0
> > uhub3 at usb3 configuration 1 interface 0 "ATI OHCI root hub" rev 1.00/1.00 
> > addr 1
> > usb4 at ohci1: USB revision 1.0
> > uhub4 at usb4 configuration 1 interface 0 "ATI OHCI root hub" rev 1.00/1.00 
> > addr 1
> > 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 0x52
> > usb5 at ohci2: USB revision 1.0
> > uhub5 at usb5 configuration 1 interface 0 "ATI OHCI root hub" rev 1.00/1.00 
> > addr 1
> > vmm0 at mainbus0: SVM/RVI
> > uvideo0 at uhub0 port 1 configuration 1 interface 0 "Trust Webcam Trust 
> > Webcam" rev 2.00/0.00 addr 2
> > video0 at uvideo0
> > uaudio0 at uhub0 port 1 configuration 1 interface 3 "Trust Webcam Trust 
> > Webcam" rev 2.00/0.00 addr 2
> > uaudio0: class v1, high-speed, sync, channels: 0 play, 1 rec, 2 ctls
> > audio0 at uaudio0
> > uhub6 at uhub0 port 5 configuration 1 interface 0 "Genesys Logic USB2.0 
> > Hub" rev 2.00/77.64 addr 3
> > umsm0 at uhub6 port 2 configuration 1 interface 0 "HUAWEI Technology HUAWEI 
> > Mobile" rev 2.00/0.00 addr 4
> > ucom0 at umsm0
> > umsm1 at uhub6 port 2 configuration 1 interface 1 "HUAWEI Technology HUAWEI 
> > Mobile" rev 2.00/0.00 addr 4
> > ucom1 at umsm1
> > umsm2 at uhub6 port 2 configuration 1 interface 2 "HUAWEI Technology HUAWEI 
> > Mobile" rev 2.00/0.00 addr 4
> > ucom2 at umsm2
> > umass0 at uhub2 port 1 configuration 1 interface 0 "Generic Flash Card 
> > Reader/Writer" rev 2.01/1.00 addr 2
> > umass0: using SCSI over Bulk-Only
> > scsibus2 at umass0: 2 targets, initiator 0
> > sd1 at scsibus2 targ 1 lun 0: <Multiple, Card Reader, 1.00> removable 
> > serial.058f6366058F63666485
> > vscsi0 at root
> > scsibus3 at vscsi0: 256 targets
> > softraid0 at root
> > scsibus4 at softraid0: 256 targets
> > root on sd0a (5e1251d77858496b.a) swap on sd0b dump on sd0b
> > umass1 at uhub6 port 3 configuration 1 interface 0 "Western Digital 
> > Elements 1042" rev 2.10/10.19 addr 5
> > umass1: using SCSI over Bulk-Only
> > scsibus5 at umass1: 2 targets, initiator 0
> > sd2 at scsibus5 targ 1 lun 0: <WD, Elements 1042, 1019> 
> > serial.10581042325937333236
> > sd2: 476938MB, 512 bytes/sector, 976769024 sectors
> > sd2 detached
> > scsibus5 detached
> > umass1 detached
> > umass1 at uhub6 port 4 configuration 1 interface 0 "Western Digital 
> > Elements 25A2" rev 2.10/10.19 addr 5
> > umass1: using SCSI over Bulk-Only
> > scsibus5 at umass1: 2 targets, initiator 0
> > sd2 at scsibus5 targ 1 lun 0: <WD, Elements 25A2, 1019> 
> > serial.105825a2373946394343
> > sd2: 1907697MB, 512 bytes/sector, 3906963456 sectors
> > softraid0: sd3 was not shutdown properly
> > softraid0: sd3 was not shutdown properly
> > sd3 at scsibus4 targ 1 lun 0: <OPENBSD, SR CRYPTO, 006>
> > sd3: 1907694MB, 512 bytes/sector, 3906959213 sectors
> > umass2 at uhub6 port 3 configuration 1 interface 0 "Genesys Logic USB 
> > Storage" rev 2.00/0.41 addr 6
> > umass2: using SCSI over Bulk-Only
> > scsibus6 at umass2: 2 targets, initiator 0
> > sd4 at scsibus6 targ 1 lun 0: <Maxtor 6, Y120P0, 0041> 
> > serial.05e30718000000000033
> > sd4: 117246MB, 512 bytes/sector, 240121728 sectors
> > softraid0: sd3 was not shutdown properly
> > softraid0: sd3 was not shutdown properly
> > softraid0: incorrect key or passphrase
> > softraid0: sd3 was not shutdown properly
> > softraid0: sd3 was not shutdown properly
> > sd5 at scsibus4 targ 2 lun 0: <OPENBSD, SR CRYPTO, 006>
> > sd5: 116212MB, 512 bytes/sector, 238002895 sectors
> > softraid0: volume sd5 is roaming, it used to be sd3, updating metadata
> > softraid0: roaming device sd2d -> sd4d
> > uid 1000 on /var: file system full
> > sd5 detached
> > sd4 detached
> > scsibus6 detached
> > umass2 detached
> > umass2 at uhub6 port 3 configuration 1 interface 0 "Genesys Logic USB 
> > Storage" rev 2.00/0.41 addr 6
> > umass2: using SCSI over Bulk-Only
> > scsibus6 at umass2: 2 targets, initiator 0
> > sd4 at scsibus6 targ 1 lun 0: <WDC WD15, EADS-00S2B0, 0041> 
> > serial.05e30718000000000033
> > sd4: 1430799MB, 512 bytes/sector, 2930277168 sectors
> > sd4 detached
> > scsibus6 detached
> > umass2 detached
> > umass2 at uhub6 port 3 configuration 1 interface 0 "Genesys Logic USB 
> > Storage" rev 2.00/0.41 addr 6
> > umass2: using SCSI over Bulk-Only
> > scsibus6 at umass2: 2 targets, initiator 0
> > sd4 at scsibus6 targ 1 lun 0: <HGST HTS, 545032A7E680, 0041> 
> > serial.05e30718000000000033
> > sd4: 305245MB, 512 bytes/sector, 625142448 sectors
> > sd4 detached
> > scsibus6 detached
> > umass2 detached
> > com0: 2 silo overflows, 0 ibuf overflows
> > com0: 6 silo overflows, 0 ibuf overflows
> > com0: 13 silo overflows, 0 ibuf overflows
> > com0: 14 silo overflows, 0 ibuf overflows
> > com0: 6 silo overflows, 0 ibuf overflows
> > com0: 38 silo overflows, 0 ibuf overflows
> > sd3 detached
> > uhub3 detached
> > video0 detached
> > uvideo0 detached
> > audio0 detached
> > uaudio0 detached
> > ucom0 detached
> > umsm0 detached
> > ucom1 detached
> > umsm1 detached
> > ucom2 detached
> > umsm2 detached
> > sd2 detached
> > scsibus5 detached
> > umass1 detached
> > uhub6 detached
> > uhub0 detached
> > uhub4 detached
> > uhub1 detached
> > uhub5 detached
> > uvm_fault(0xffffffff82389c58, 0x0, 0, 1) -> e
> > kernel: page fault trap, code=0
> > Stopped at  bufq_destroy+0x83:      movq    0(%rcx),%rcx
> >     TID    PID    UID     PRFLAGS     PFLAGS  CPU  COMMAND
> >  396043  97889     77    0x100012          0    1  dhcpleased
> > *383055  34320      0     0x14000      0x200    0K usbtask
> > bufq_destroy(ffff800000a73d10) at bufq_destroy+0x83
> > sddetach(ffff800000a73c00,1) at sddetach+0x42
> > config_detach(ffff800000a73c00,1) at config_detach+0x142
> > scsi_detach_link(ffff8000007c6d00,1) at scsi_detach_link+0x4d
> > scsibusdetach(ffff800000a86580,1) at scsibusdetach+0x54
> > config_detach(ffff800000a86580,1) at config_detach+0x142
> > umass_scsi_detach(ffff800000a73a00,1) at umass_scsi_detach+0x35
> > umass_detach(ffff800000a73a00,1) at umass_detach+0xd4
> > config_detach(ffff800000a73a00,1) at config_detach+0x142
> > usbd_detach(ffff8000007c6c00,ffff800000761a00) at usbd_detach+0x5a
> > uhub_detach(ffff800000761a00,1) at uhub_detach+0x85
> > config_detach(ffff800000761a00,1) at config_detach+0x142
> > usbd_detach(ffff800000749300,ffff800000749200) at usbd_detach+0x5a
> > usb_explore(ffff800000749200) at usb_explore+0x144
> > end trace frame: 0xffff800020fcdb30, 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.
> > ddb{0}> No such command
> > ddb{0}>
>
> --
> :wq Claudio
>

Reply via email to