xhci_hcd usb storage does not boot with kernel 6.5.0-1007 raspi 4

[Juan Pedro Paredes]

Differential analisys

FAIL BOOT WITH vmlinuz-6.5.0-1007-raspi
2.411312] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 qu
2.414253] xhci hcd 0000:01:00.0: xHCI Host Controller
2.417066] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus numbe
2.418935] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
2.424202] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bc
2.426301] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=
2.428277] usb usb1: Product: xHCI Host Controller
2.429833] usb usb1: Manufacturer: Linux 6.5.0-1007-raspi xhci-hcd
2.431696] usb usb1: Serial Number: 0000:01:00.0
2.446311] hub 1-0:1.0: USB hub found
2.449294] hub 1-0:1.0: 1 port detected
2.455150] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bc
2.456938] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=
2.457289] uc2 fe980000.usb: supply vusb_d not found, using dummy regulator
2.458707] usb usb2: Product: XHCI Host Controller
2.461093] duc2 fe980000.usb: supply vusb_a not found, using dummy regulator
2.481488] usb usb2: Manufacturer: Linux 6.5.0-1007-raspi xhci-hcd
2.483294] usb usb2: SerialNumber: 0000:01:00.0
2.488946] hub 2-0:1.0: USB hub found
2.491438] hub 2-0:1.0: 4 ports detected
2.498162] [drm] Initialized u3d 1.0.0 20180419 for fec00000.v3d on minor O
2.5429891 Console: switching to colour dummy device 80x25
2.543898] vc4-drm gpu: bound fe600000.firmwarekms (ops vc4_fkms_ops [vc4])
2.544513] [drm] Initialized uc4 0.0.0 20140616 for gpu on minor 1
2.6726471 dwc2 fe980000.usb: EPs: 8, dedicated fifos, 4080 entries in SPRAM
2.672838] dwc2 fe980000.usb: DWC OTG Controller
2.672852] dwc2 fe980000.usb: new USB bus registered, assigned bus number 3
2.672876] dwc2 fe980000.usb: irq 39, io mem 0xfe980000
2.673046] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.05
2.673052] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
2.673058] usb usb3: Product: DWC OTG Controller
2.673062] usb usb3: Manufacturer: Linux 6.5.0-1007-raspi duc2_hsotg
2.673066] usb usb3: Serial Number: fe980000.usb 2.6734161 hub 3-0:1.0: USB hub found
2.673439] hub 3-0:1.0: 1 port detected
2.791029] Console: switching to colour frame buffer device 480x135 2.7989511 vc4-drm gpu: [drm] fb0: vc4drmfb frame buffer device
9.660608] xhci_hcd 0000:01:00.0: Error while assigning device slot ID: Command Aborted
9.660661] xhci_hcd 0000:01:00.0: Max number of devices this xHCI host supports is 32.
9.660686] usb usb1-port1: couldn't allocate usb_device

WORKS WITH initrd.img-6.2.0-1014-raspi
[ 2.632992] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 2.635543] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[ 2.639487] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0001e40000000890
[ 2.642231] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 2.645472] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[ 2.648041] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[ 2.653521] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0
[ 2.688606] dwc2 fe980000.usb: supply vusb_d not found, using dummy regulator
[ 2.707205] dwc2 fe980000.usb: supply vusb_a not found, using dummy regulator
[ 2.710042] Console: switching to colour dummy device 80x25
[ 2.710658] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.02
[ 2.710693] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 2.710704] usb usb1: Product: xHCI Host Controller
[ 2.710713] usb usb1: Manufacturer: Linux 6.2.0-1014-raspi xhci-hcd
[ 2.710721] usb usb1: SerialNumber: 0000:01:00.0
[ 2.713279] vc4-drm gpu: bound fe600000.firmwarekms (ops vc4_fkms_ops [vc4])
[ 2.714004] [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 1
[ 2.720612] hub 1-0:1.0: USB hub found
[ 2.720701] hub 1-0:1.0: 1 port detected
[ 2.917665] dwc2 fe980000.usb: EPs: 8, dedicated fifos, 4080 entries in SPRAM
[ 2.917858] dwc2 fe980000.usb: DWC OTG Controller
[ 2.986726] Console: switching to colour frame buffer device 480x135
[ 3.038523] vc4-drm gpu: [drm] fb0: vc4drmfb frame buffer device
[ 3.058104] dwc2 fe980000.usb: new USB bus registered, assigned bus number 3
[ 3.058223] dwc2 fe980000.usb: irq 40, io mem 0xfe980000
[ 3.058410] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.02
[ 3.058484] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 3.058541] usb usb2: Product: xHCI Host Controller
[ 3.058590] usb usb2: Manufacturer: Linux 6.2.0-1014-raspi xhci-hcd
[ 3.058647] usb usb2: SerialNumber: 0000:01:00.0
[ 3.059383] hub 2-0:1.0: USB hub found
[ 3.059545] hub 2-0:1.0: 4 ports detected
[ 3.060789] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.02
[ 3.060878] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 3.060935] usb usb3: Product: DWC OTG Controller
[ 3.060981] usb usb3: Manufacturer: Linux 6.2.0-1014-raspi dwc2_hsotg
[ 3.061033] usb usb3: SerialNumber: fe980000.usb
[ 3.061750] hub 3-0:1.0: USB hub found
[ 3.061865] hub 3-0:1.0: 1 port detected
[ 3.313645] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[ 3.441621] raid6: neonx8 gen() 3970 MB/s
[ 3.468191] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[ 3.468274] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[ 3.468336] usb 1-1: Product: USB2.0 Hub
[ 3.469584] hub 1-1:1.0: USB hub found
[ 3.469929] hub 1-1:1.0: 4 ports detected

root@controller:~# lsusb -t
/: Bus 03.Port 1: Dev 1, Class=root_hub, Driver=dwc2/1p, 480M
/: Bus 02.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/4p, 5000M
    |__ Port 1: Dev 2, If 0, Class=Mass Storage, Driver=usb-storage, 5000M
    |__ Port 2: Dev 3, If 0, Class=Mass Storage, Driver=uas, 5000M
/: Bus 01.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/1p, 480M
    |__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/4p, 480M
        |__ Port 3: Dev 3, If 0, Class=Hub, Driver=hub/4p, 480M
            |__ Port 4: Dev 5, If 0, Class=Mass Storage, Driver=usb-storage, 480M
        |__ Port 4: Dev 4, If 0, Class=Vendor Specific Class, Driver=ch341, 12M

root@controller:~# lsusb
Bus 003 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 002 Device 003: ID 174c:225c ASMedia Technology Inc. Best USB Device
Bus 002 Device 002: ID 1058:25a3 Western Digital Technologies, Inc. Elements Desktop (WDBWLG)
Bus 002 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
Bus 001 Device 004: ID 1a86:7523 QinHeng Electronics CH340 serial converter
Bus 001 Device 005: ID 0bda:8151 Realtek Semiconductor Corp. RTL8151 Adapteon Business Mobile Networks BV
Bus 001 Device 003: ID 2109:2815 VIA Labs, Inc. USB2.0 Hub
Bus 001 Device 002: ID 2109:3431 VIA Labs, Inc. Hub
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub

ProblemType: Bug
DistroRelease: Ubuntu 23.10
Package: ubuntu-release-upgrader-core 1:23.10.14
ProcVersionSignature: Ubuntu 6.2.0-1014.16-raspi 6.2.16
Uname: Linux 6.2.0-1014-raspi aarch64
ApportVersion: 2.27.0-0ubuntu5
Architecture: arm64
CasperMD5CheckResult: unknown
CloudBuildName: server
CloudSerial:

CrashDB: ubuntu
CrashReports:
 640:0:0:70275:2023-12-01 10:45:15.600416303 +0100:2023-12-01 10:45:15.604416266 +0100:/var/crash/_usr_bin_qemu-ppc64-static.0.crash
 640:0:0:54003:2023-12-01 00:32:06.566810410 +0100:2023-12-01 00:32:06.570810414 +0100:/var/crash/_usr_bin_qemu-s390x-static.0.crash
 640:0:0:339617:2023-12-01 00:00:55.877624177 +0100:2023-12-01 00:00:40.317745569 +0100:/var/crash/_usr_lib_systemd_systemd-journald.0.crash
Date: Fri Dec 1 11:42:31 2023
ImageMediaBuild: 20201022
PackageArchitecture: all
ProcEnviron:
 LANG=C.UTF-8
 PATH=(custom, no user)
 SHELL=/bin/bash
 TERM=xterm
 XDG_RUNTIME_DIR=<set>
SourcePackage: ubuntu-release-upgrader
Symptom: release-upgrade
UpgradeStatus: Upgraded to mantic on 2023-11-19 (12 days ago)
VarLogDistupgradeLspcitxt:
 00:00.0 PCI bridge [0604]: Broadcom Inc. and subsidiaries BCM2711 PCIe Bridge [14e4:2711] (rev 10)
 01:00.0 USB controller [0c03]: VIA Technologies, Inc. VL805/806 xHCI USB 3.0 Controller [1106:3483] (rev 01)

[Juan Pedro Paredes (juampe)]

With certain research about the problem, I found this:

https://<email address hidden>/T/

[Juan Pedro Paredes (juampe)]

From vanilla kernel 6.6.0 raspi USB boot, works again
with https://raw.githubusercontent.com/raspberrypi/linux/rpi-6.1.y/arch/arm64/configs/bcm2711_defconfig as config

root@controller:~# uname -a
Linux controller 6.6.0-juampe-raspi #3 SMP Mon Dec 11 16:53:54 CET 2023 aarch64 aarch64 aarch64 GNU/Linux

[ 0.684870] serial serial0: tty port ttyAMA0 registered
[ 0.685571] raspberrypi-firmware soc:firmware: Attached to firmware from 2023-03-17T10:50:39
[ 0.735617] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 0.735697] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[ 0.736160] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000000000000890
[ 0.736615] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 0.736673] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[ 0.736740] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[ 0.737010] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06
[ 0.737084] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 0.737146] usb usb1: Product: xHCI Host Controller
[ 0.737190] usb usb1: Manufacturer: Linux 6.6.0-juampe-raspi xhci-hcd
[ 0.737242] usb usb1: SerialNumber: 0000:01:00.0
[ 0.737781] hub 1-0:1.0: USB hub found
[ 0.740297] hub 1-0:1.0: 1 port detected
[ 0.743219] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06
[ 0.745647] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 0.748037] usb usb2: Product: xHCI Host Controller
[ 0.750403] usb usb2: Manufacturer: Linux 6.6.0-juampe-raspi xhci-hcd
[ 0.752795] usb usb2: SerialNumber: 0000:01:00.0
[ 0.755646] hub 2-0:1.0: USB hub found
[ 0.758050] hub 2-0:1.0: 4 ports detected