USB regression on Overdrive 1000

John F Carr <[email protected]>
Newsgroups gmane.os.freebsd.devel.arm
Message-ID <[email protected]> 
I updated my kernel from 13.0-STABLE from early December to 13.1-STABLE from yesterday and I am having USB problems on my Overdrive 1000 ARM box.  I see some usb_msc_auto_quirk messages I haven't seen before and there is a long delay "Root mount waiting for: usbus0".  It does boot eventually off of the internal drive, ada0, but it continues to spew USB errors to the console.  It doesn't register a "CAMuhub0" device and it never finds the USB stick plugged into the external port which should appear as da0.  Any ideas?

dmesg output attached, "good" comes from "boot kernel.old".  The if_msk error is probably caused by a mismatch between old kernel and new root filesystem.


CPU  3: ARM Cortex-A57 r1p2 affinity:  1  1
Release APs...done
usbus0: 5.0Gbps Super Speed USB v3.0
Trying to mount root from zfs:zroot/ROOT/default []...
ugen0.1: <(0x1b73) XHCI root HUB> at usbus0
uhub0 on usbus0
uhub0: <(0x1b73) XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus0
ada0 at ahcich1 bus 0 scbus1 target 0 lun 0
ada0: <Patriot P200 1TB S0424A0> ACS-2 ATA SATA 3.x device
...
Root mount waiting for: usbus0
usb_msc_auto_quirk: UQ_MSC_NO_GETMAXLUN set for USB mass storage device Generic Mass Storage Device (0x14cd:0x125d)
usb_msc_auto_quirk: UQ_MSC_NO_TEST_UNIT_READY set for USB mass storage device Generic Mass Storage Device (0x14cd:0x125d)
usb_msc_auto_quirk: UQ_MSC_NO_PREVENT_ALLOW set for USB mass storage device Generic Mass Storage Device (0x14cd:0x125d)
usb_msc_auto_quirk: UQ_MSC_NO_SYNC_CACHE set for USB mass storage device Generic Mass Storage Device (0x14cd:0x125d)
Root mount waiting for: usbus0
xhci0: Resetting controller
Root mount waiting for: usbus0
usbd_req_re_enumerate: addr=1, set address failed! (USB_ERR_TIMEOUT, ignored)
Root mount waiting for: usbus0
...
xhci0: Resetting controller
dmesg-bad.txt (text/plain, 9.3 KB) 
WARNING: Cannot find freebsd,dts-version property, cannot check DTB compliance
Copyright (c) 1992-2021 The FreeBSD Project.
Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
	The Regents of the University of California. All rights reserved.
FreeBSD is a registered trademark of The FreeBSD Foundation.
FreeBSD 13.1-STABLE #6 stable/13-n250020-5f3d952f6e6: Tue Mar 15 19:58:32 EDT 2022
    root@striatus:/usr/obj/usr/home/jfc/freebsd/src/arm64.aarch64/sys/GENERIC arm64
FreeBSD clang version 13.0.0 ([email protected]:llvm/llvm-project.git llvmorg-13.0.0-0-gd7b669b3a303)
VT: init without driver.
module firmware already present!
real memory  = 8570011648 (8173 MB)
avail memory = 8328368128 (7942 MB)
Starting CPU 1 (1)
Starting CPU 2 (100)
Starting CPU 3 (101)
FreeBSD/SMP: Multiprocessor System Detected: 4 CPUs
random: unblocking device.
random: entropy device external interface
MAP 81fb830000 mode 2 pages 992
MAP 81fbe10000 mode 2 pages 496
MAP 81fffd0000 mode 2 pages 32
kbd0 at kbdmux0
ofwbus0: <Open Firmware Device Tree>
simplebus0: <Flattened device tree simple bus> on ofwbus0
clk_fixed0: <Fixed clock> on simplebus0
clk_fixed1: <Fixed clock> on simplebus0
clk_fixed2: <Fixed clock> on simplebus0
clk_fixed3: <Fixed clock> on simplebus0
clk_fixed4: <Fixed clock> on simplebus0
clk_fixed5: <Fixed clock> on simplebus0
clk_fixed6: <Fixed clock> on simplebus0
clk_fixed7: <Fixed clock> on simplebus0
clk_fixed8: <Fixed clock> on simplebus0
clk_fixed9: <Fixed clock> on simplebus0
clk_fixed10: <Fixed clock> on simplebus0
psci0: <ARM Power State Co-ordination Interface Driver> on ofwbus0
gic0: <ARM Generic Interrupt Controller> mem 0xe1110000-0xe1110fff,0xe112f000-0xe1130fff,0xe1140000-0xe114ffff,0xe1160000-0xe116ffff irq 4 on ofwbus0
gic0: pn 0x2, arch 0x2, rev 0x1, implementer 0x43b irqs 448
gicv2m0: <ARM Generic Interrupt Controller MSI/MSIX> mem 0x80000-0x80fff on gic0
generic_timer0: <ARMv8 Generic Timer> irq 5,6,7,8 on ofwbus0
Timecounter "ARM MPCore Timecounter" frequency 250000000 Hz quality 1000
Event timer "ARM MPCore Eventtimer" frequency 250000000 Hz quality 1000
efirtc0: <EFI Realtime Clock>
efirtc0: registered as a time-of-day clock, resolution 1.000000s
cpulist0: <Open Firmware CPU Group> on ofwbus0
cpu0: <Open Firmware CPU> on cpulist0
cpu1: <Open Firmware CPU> on cpulist0
cpu2: <Open Firmware CPU> on cpulist0
cpu3: <Open Firmware CPU> on cpulist0
pmu0: <Performance Monitoring Unit> irq 0,1,2,3 on ofwbus0
pmu0: Cannot find CPU with MPIDR: 0x00000002
pmu0: Cannot parse affinity for CPUid: 2.
device_attach: pmu0 attach returned 6
ahci0: <AHCI SATA controller> mem 0xe0300000-0xe03effff irq 9 on simplebus0
ahci0: AHCI v1.30 with 8 6Gbps ports, Port Multiplier supported
ahcich0: <AHCI channel> at channel 0 on ahci0
ahcich1: <AHCI channel> at channel 1 on ahci0
uart0: <PrimeCell UART (PL011)> mem 0xe1010000-0xe1010fff irq 13 on simplebus0
uart0: console (115200,n,8,1)
pcib0: <Generic PCI host controller> mem 0xf0000000-0xffffffff on simplebus0
pci0: <PCI bus> on pcib0
pcib1: <PCI-PCI bridge> at device 2.2 on pci0
pci1: <PCI bus> on pcib1
xhci0: <XHCI (generic) USB 3.0 controller> mem 0x40100000-0x4010ffff,0x40110000-0x40110fff,0x40111000-0x40111fff at device 0.0 on pci1
xhci0: 32 bytes context size, 64-bit DMA
usbus0 on xhci0
pcib2: <PCI-PCI bridge> at device 2.3 on pci0
pci2: <PCI bus> on pcib2
mskc0: <Marvell Yukon 88E8059 Gigabit Ethernet> port 0x1000-0x10ff mem 0x40000000-0x40003fff at device 0.0 on pci2
msk0: <Marvell Technology Group Ltd. Yukon Optima Id 0xbc Rev 0x01> on mskc0
msk0: Using defaults for TSO: 65518/35/2048
msk0: Ethernet address: e0:ff:f7:00:20:f0
miibus0: <MII bus> on msk0
e1000phy0: <Marvell PHYG65G Gigabit PHY> PHY 0 on miibus0
e1000phy0:  none, 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT, 1000baseT-master, 1000baseT-FDX, 1000baseT-FDX-master, auto, auto-flow
armv8crypto0: <AES-CBC,AES-XTS,AES-GCM>
Timecounters tick every 1.000 msec
ZFS filesystem version: 5
ZFS storage pool version: features support (5000)
CPU  0: ARM Cortex-A57 r1p2 affinity:  0  0
                   Cache Type = <64 byte D-cacheline,64 byte I-cacheline,PIPT ICache,64 byte ERG,64 byte CWG>
 Instruction Set Attributes 0 = <CRC32,SHA2,SHA1,AES+PMULL>
 Instruction Set Attributes 1 = <>
         Processor Features 0 = <AdvSIMD,FP,EL3 32,EL2 32,EL1 32,EL0 32>
         Processor Features 1 = <>
      Memory Model Features 0 = <TGran4,TGran64,SNSMem,BigEnd,16bit ASID,16TB PA>
      Memory Model Features 1 = <8bit VMID>
      Memory Model Features 2 = <32bit CCIDX,48bit VA>
             Debug Features 0 = <DoubleLock,2 CTX BKPTs,4 Watchpoints,6 Breakpoints,PMUv3,Debugv8>
             Debug Features 1 = <>
         Auxiliary Features 0 = <>
         Auxiliary Features 1 = <>
AArch32 Instruction Set Attributes 5 = <CRC32,SHA2,SHA1,AES+VMULL,SEVL>
AArch32 Media and VFP Features 0 = <FPRound,FPSqrt,FPDivide,DP VFPv3+v4,SP VFPv3+v4,AdvSIMD>
AArch32 Media and VFP Features 1 = <SIMDFMAC,FPHP DP Conv,SIMDHP SP Conv,SIMDSP,SIMDInt,SIMDLS,FPDNaN,FPFtZ>
CPU  1: ARM Cortex-A57 r1p2 affinity:  0  1
CPU  2: ARM Cortex-A57 r1p2 affinity:  1  0
CPU  3: ARM Cortex-A57 r1p2 affinity:  1  1
Release APs...done
usbus0: 5.0Gbps Super Speed USB v3.0
Trying to mount root from zfs:zroot/ROOT/default []...
ugen0.1: <(0x1b73) XHCI root HUB> at usbus0
uhub0 on usbus0
uhub0: <(0x1b73) XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus0
ada0 at ahcich1 bus 0 scbus1 target 0 lun 0
ada0: <Patriot P200 1TB S0424A0> ACS-2 ATA SATA 3.x device
ada0: Serial Number AA000000000000000509
ada0: 600.000MB/s transfers (SATA 3.x, UDMA6, PIO 512bytes)
ada0: Command Queueing enabled
ada0: 976762MB (2000409264 512 byte sectors)
uhub0: 4 ports with 4 removable, self powered
Root mount waiting for: usbus0
usb_msc_auto_quirk: UQ_MSC_NO_GETMAXLUN set for USB mass storage device Generic Mass Storage Device (0x14cd:0x125d)
usb_msc_auto_quirk: UQ_MSC_NO_TEST_UNIT_READY set for USB mass storage device Generic Mass Storage Device (0x14cd:0x125d)
usb_msc_auto_quirk: UQ_MSC_NO_PREVENT_ALLOW set for USB mass storage device Generic Mass Storage Device (0x14cd:0x125d)
usb_msc_auto_quirk: UQ_MSC_NO_SYNC_CACHE set for USB mass storage device Generic Mass Storage Device (0x14cd:0x125d)
Root mount waiting for: usbus0
xhci0: Resetting controller
Root mount waiting for: usbus0
usbd_req_re_enumerate: addr=1, set address failed! (USB_ERR_TIMEOUT, ignored)
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
usbd_setup_device_desc: getting device descriptor at addr 1 failed, USB_ERR_TIMEOUT
Root mount waiting for: usbus0
Root mount waiting for: usbus0
usbd_req_re_enumerate: addr=1, set address failed! (USB_ERR_TIMEOUT, ignored)
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
usbd_setup_device_desc: getting device descriptor at addr 1 failed, USB_ERR_TIMEOUT
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
usb_alloc_device: Failure selecting configuration index 0:USB_ERR_TIMEOUT, port 2, addr 1 (ignored)
ugen0.2: <Generic Mass Storage Device> at usbus0
uhub0: at usbus0, port 1, addr 1 (disconnected)
ugen0.2: <Generic Mass Storage Device> at usbus0 (disconnected)
uhub0: detached
uhub0 on usbus0
uhub0: <(0x1b73) XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus0
warning: total configured swap (8388608 pages) exceeds maximum recommended amount (8134384 pages).
warning: increase kern.maxswzone or reduce amount of swap.
uhub0: 4 ports with 4 removable, self powered
lo0: link state changed to UP
msk0: link state changed to DOWN
usb_msc_auto_quirk: UQ_MSC_NO_GETMAXLUN set for USB mass storage device Generic Mass Storage Device (0x14cd:0x125d)
usb_msc_auto_quirk: UQ_MSC_NO_TEST_UNIT_READY set for USB mass storage device Generic Mass Storage Device (0x14cd:0x125d)
usb_msc_auto_quirk: UQ_MSC_NO_PREVENT_ALLOW set for USB mass storage device Generic Mass Storage Device (0x14cd:0x125d)
usb_msc_auto_quirk: UQ_MSC_NO_SYNC_CACHE set for USB mass storage device Generic Mass Storage Device (0x14cd:0x125d)
xhci0: Resetting controller
usbd_req_re_enumerate: addr=1, set address failed! (USB_ERR_TIMEOUT, ignored)
msk0: link state changed to UP
Security policy loaded: MAC/ntpd (mac_ntpd)
usbd_setup_device_desc: getting device descriptor at addr 1 failed, USB_ERR_TIMEOUT
usbd_req_re_enumerate: addr=1, set address failed! (USB_ERR_TIMEOUT, ignored)
dmesg-good.txt (text/plain, 7.3 KB) 
---<<BOOT>>---
WARNING: Cannot find freebsd,dts-version property, cannot check DTB compliance
Copyright (c) 1992-2021 The FreeBSD Project.
Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
	The Regents of the University of California. All rights reserved.
FreeBSD is a registered trademark of The FreeBSD Foundation.
FreeBSD 13.0-STABLE #4 stable/13-n248443-c89c8b894cf: Tue Dec  7 19:23:26 EST 2021
    root@striatus:/usr/obj/usr/home/jfc/freebsd/src/arm64.aarch64/sys/GENERIC arm64
FreeBSD clang version 12.0.1 ([email protected]:llvm/llvm-project.git llvmorg-12.0.1-0-gfed41342a82f)
VT: init without driver.
module firmware already present!
real memory  = 8570011648 (8173 MB)
avail memory = 8328228864 (7942 MB)
Starting CPU 1 (1)
Starting CPU 2 (100)
Starting CPU 3 (101)
FreeBSD/SMP: Multiprocessor System Detected: 4 CPUs
random: unblocking device.
random: entropy device external interface
MAP 81fb830000 mode 2 pages 992
MAP 81fbe10000 mode 2 pages 496
MAP 81fffd0000 mode 2 pages 32
kbd0 at kbdmux0
ofwbus0: <Open Firmware Device Tree>
simplebus0: <Flattened device tree simple bus> on ofwbus0
clk_fixed0: <Fixed clock> on simplebus0
clk_fixed1: <Fixed clock> on simplebus0
clk_fixed2: <Fixed clock> on simplebus0
clk_fixed3: <Fixed clock> on simplebus0
clk_fixed4: <Fixed clock> on simplebus0
clk_fixed5: <Fixed clock> on simplebus0
clk_fixed6: <Fixed clock> on simplebus0
clk_fixed7: <Fixed clock> on simplebus0
clk_fixed8: <Fixed clock> on simplebus0
clk_fixed9: <Fixed clock> on simplebus0
clk_fixed10: <Fixed clock> on simplebus0
psci0: <ARM Power State Co-ordination Interface Driver> on ofwbus0
gic0: <ARM Generic Interrupt Controller> mem 0xe1110000-0xe1110fff,0xe112f000-0xe1130fff,0xe1140000-0xe114ffff,0xe1160000-0xe116ffff irq 4 on ofwbus0
gic0: pn 0x2, arch 0x2, rev 0x1, implementer 0x43b irqs 448
gicv2m0: <ARM Generic Interrupt Controller MSI/MSIX> mem 0x80000-0x80fff on gic0
generic_timer0: <ARMv8 Generic Timer> irq 5,6,7,8 on ofwbus0
Timecounter "ARM MPCore Timecounter" frequency 250000000 Hz quality 1000
Event timer "ARM MPCore Eventtimer" frequency 250000000 Hz quality 1000
efirtc0: <EFI Realtime Clock>
efirtc0: registered as a time-of-day clock, resolution 1.000000s
cpulist0: <Open Firmware CPU Group> on ofwbus0
cpu0: <Open Firmware CPU> on cpulist0
cpu1: <Open Firmware CPU> on cpulist0
cpu2: <Open Firmware CPU> on cpulist0
cpu3: <Open Firmware CPU> on cpulist0
pmu0: <Performance Monitoring Unit> irq 0,1,2,3 on ofwbus0
pmu0: Cannot find CPU with MPIDR: 0x00000002
pmu0: Cannot parse affinity for CPUid: 2.
device_attach: pmu0 attach returned 6
ahci0: <AHCI SATA controller> mem 0xe0300000-0xe03effff irq 9 on simplebus0
ahci0: AHCI v1.30 with 8 6Gbps ports, Port Multiplier supported
ahcich0: <AHCI channel> at channel 0 on ahci0
ahcich1: <AHCI channel> at channel 1 on ahci0
uart0: <PrimeCell UART (PL011)> mem 0xe1010000-0xe1010fff irq 13 on simplebus0
uart0: console (115200,n,8,1)
pcib0: <Generic PCI host controller> mem 0xf0000000-0xffffffff on simplebus0
pci0: <PCI bus> on pcib0
pcib1: <PCI-PCI bridge> at device 2.2 on pci0
pci1: <PCI bus> on pcib1
xhci0: <XHCI (generic) USB 3.0 controller> mem 0x40100000-0x4010ffff,0x40110000-0x40110fff,0x40111000-0x40111fff at device 0.0 on pci1
xhci0: 32 bytes context size, 64-bit DMA
usbus0 on xhci0
pcib2: <PCI-PCI bridge> at device 2.3 on pci0
pci2: <PCI bus> on pcib2
mskc0: <Marvell Yukon 88E8059 Gigabit Ethernet> port 0x1000-0x10ff mem 0x40000000-0x40003fff at device 0.0 on pci2
msk0: <Marvell Technology Group Ltd. Yukon Optima Id 0xbc Rev 0x01> on mskc0
msk0: Using defaults for TSO: 65518/35/2048
msk0: Ethernet address: e0:ff:f7:00:20:f0
miibus0: <MII bus> on msk0
e1000phy0: <Marvell PHYG65G Gigabit PHY> PHY 0 on miibus0
e1000phy0:  none, 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT, 1000baseT-master, 1000baseT-FDX, 1000baseT-FDX-master, auto, auto-flow
armv8crypto0: <AES-CBC,AES-XTS,AES-GCM>
Timecounters tick every 1.000 msec
ZFS filesystem version: 5
ZFS storage pool version: features support (5000)
usbus0: 5.0Gbps Super Speed USB v3.0
CPU  0: ARM Cortex-A57 r1p2 affinity:  0  0
                   Cache Type = <64 byte D-cacheline,64 byte I-cacheline,PIPT ICache,64 byte ERG,64 byte CWG>
 Instruction Set Attributes 0 = <CRC32,SHA2,SHA1,AES+PMULL>
 Instruction Set Attributes 1 = <>
         Processor Features 0 = <AdvSIMD,FP,EL3 32,EL2 32,EL1 32,EL0 32>
         Processor Features 1 = <>
      Memory Model Features 0 = <TGran4,TGran64,SNSMem,BigEnd,16bit ASID,16TB PA>
      Memory Model Features 1 = <8bit VMID>
      Memory Model Features 2 = <32bit CCIDX,48bit VA>
             Debug Features 0 = <DoubleLock,2 CTX BKPTs,4 Watchpoints,6 Breakpoints,PMUv3,Debugv8>
             Debug Features 1 = <>
         Auxiliary Features 0 = <>
         Auxiliary Features 1 = <>
AArch32 Instruction Set Attributes 5 = <CRC32,SHA2,SHA1,AES+VMULL,SEVL>
AArch32 Media and VFP Features 0 = <FPRound,FPSqrt,FPDivide,DP VFPv3+v4,SP VFPv3+v4,AdvSIMD>
AArch32 Media and VFP Features 1 = <SIMDFMAC,FPHP DP Conv,SIMDHP SP Conv,SIMDSP,SIMDInt,SIMDLS,FPDNaN,FPFtZ>
CPU  1: ARM Cortex-A57 r1p2 affinity:  0  1
CPU  2: ARM Cortex-A57 r1p2 affinity:  1  0
CPU  3: ARM Cortex-A57 r1p2 affinity:  1  1
Release APs...done
ugen0.1: <0x1b73 XHCI root HUB> at usbus0
Trying to mount root from zfs:zroot/ROOT/default []...
uhub0Root mount waiting for: on usbus0
 CAMuhub0: <0x1b73 XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus0
 usbus0
ada0 at ahcich1 bus 0 scbus1 target 0 lun 0
ada0: <Patriot P200 1TB S0424A0> ACS-2 ATA SATA 3.x device
ada0: Serial Number AA000000000000000509
ada0: 600.000MB/s transfers (SATA 3.x, UDMA6, PIO 512bytes)
ada0: Command Queueing enabled
ada0: 976762MB (2000409264 512 byte sectors)
uhub0: 4 ports with 4 removable, self powered
Root mount waiting for: usbus0
ugen0.2: <Generic Mass Storage Device> at usbus0
umass0 on uhub0
umass0: <Generic Mass Storage Device, class 0/0, rev 2.00/1.00, addr 1> on usbus0
umass0:  SCSI over Bulk-Only; quirks = 0xc100
umass0:2:0: Attached to scbus2
da0 at umass-sim0 bus 0 scbus2 target 0 lun 0
da0: <Mass Storage Device > Removable Direct Access SCSI device
da0: Serial Number 125D20140310
da0: 40.000MB/s transfers
da0: Attempt to query device size failed: NOT READY, Medium not present
da0: quirks=0x2<NO_6_BYTE>
warning: total configured swap (8388608 pages) exceeds maximum recommended amount (8134384 pages).
warning: increase kern.maxswzone or reduce amount of swap.
lo0: link state changed to UP
module_register: cannot register pci/mskc from if_msk.ko; already loaded from kernel
Module pci/mskc failed to register: 17
module_register: cannot register mskc/msk from if_msk.ko; already loaded from kernel
Module mskc/msk failed to register: 17
module_register: cannot register msk/miibus from if_msk.ko; already loaded from kernel
Module msk/miibus failed to register: 17
msk0: link state changed to DOWN
module_register: cannot register pci/mskc from if_msk.ko; already loaded from kernel
Module pci/mskc failed to register: 17
module_register: cannot register mskc/msk from if_msk.ko; already loaded from kernel
Module mskc/msk failed to register: 17
module_register: cannot register msk/miibus from if_msk.ko; already loaded from kernel
Module msk/miibus failed to register: 17
msk0: link state changed to UP
Security policy loaded: MAC/ntpd (mac_ntpd)
lmpx.com only provides a reader for public news (NNTP) servers. It is not affiliated with the servers or forums shown here and is not responsible for the content of articles, which is written by their respective authors.