samples/subsys/usb/mass slow and defective on stm32u5

[bearsh]

Hi
I'm evaluating the usb mass storage function using samples/subsys/usb/mass on the stm32u5a9j-dk. When the default (old?) usb device stack is selected, mass storage does not work at all. Which the usb_next stack, mass storage works but generates lots of warnings and error on both sides, the uC and the host.

Zephyr log:

*** Booting Zephyr OS build v4.1.0-3791-g14b4e30cdf62 ***
Mount /SD:: 0
/SD:: bsize = 512 ; frsize = 32768 ; blocks = 119280 ; bfree = 117358
/SD: opendir: 0
  F 12 TEST.TXT
  F 10485760 TEMPFILE
  F 10485760 TEMPFI~1
  F 20971520 TEMPF1
  F 20971520 TEMPF2
End of files
[00:00:00.319,000] <inf> main: The device is put in USB mass storage mode.

[00:00:00.668,000] <inf> usbd_msc: Enable
[00:00:00.668,000] <inf> usbd_msc: Bulk-Only Mass Storage Reset
[00:00:01.729,000] <inf> usbd_msc: Disable
[00:00:01.729,000] <wrn> usbd_msc: request ep 0x01, len 0 cancelled
[00:00:01.882,000] <inf> usbd_msc: Enable
[00:00:01.882,000] <inf> usbd_msc: Bulk-Only Mass Storage Reset
[00:00:01.906,000] <inf> usbd_msc: Disable
[00:00:01.906,000] <wrn> usbd_msc: request ep 0x01, len 0 cancelled
[00:00:02.058,000] <inf> usbd_msc: Enable
[00:00:02.058,000] <inf> usbd_msc: Bulk-Only Mass Storage Reset
[00:00:02.082,000] <inf> usbd_msc: Disable
[00:00:02.082,000] <wrn> usbd_msc: request ep 0x01, len 0 cancelled
[00:00:02.236,000] <inf> usbd_msc: Enable
[00:00:02.236,000] <inf> usbd_msc: Bulk-Only Mass Storage Reset
[00:00:02.260,000] <inf> usbd_msc: Disable
[00:00:02.260,000] <wrn> usbd_msc: request ep 0x01, len 0 cancelled
...

(the inf/warn msg gets repeated several times)

Linux:

Mai 12 16:39:03 grayhound kernel: usb 3-6.1.1.4: new full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:03 grayhound kernel: usb 3-6.1.1.4: New USB device found, idVendor=2fe3, idProduct=0008, bcdDevice= 4.01
Mai 12 16:39:03 grayhound kernel: usb 3-6.1.1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Mai 12 16:39:03 grayhound kernel: usb 3-6.1.1.4: Product: USBD MSC sample
Mai 12 16:39:03 grayhound kernel: usb 3-6.1.1.4: Manufacturer: Zephyr Project
Mai 12 16:39:03 grayhound kernel: usb 3-6.1.1.4: SerialNumber: 0014001C383650152032344D
Mai 12 16:39:03 grayhound kernel: usb-storage 3-6.1.1.4:1.0: USB Mass Storage device detected
Mai 12 16:39:03 grayhound kernel: scsi host1: usb-storage 3-6.1.1.4:1.0
Mai 12 16:39:04 grayhound kernel: scsi 1:0:0:0: Direct-Access     Zephyr   SD               0.00 PQ: 0 ANSI: 4
Mai 12 16:39:08 grayhound kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0
Mai 12 16:39:08 grayhound kernel: sd 1:0:0:0: [sdb] 7634944 512-byte logical blocks: (3.91 GB/3.64 GiB)
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: sd 1:0:0:0: [sdb] Write Protect is off
Mai 12 16:39:08 grayhound kernel: sd 1:0:0:0: [sdb] Mode Sense: 03 00 00 00
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: sd 1:0:0:0: [sdb] No Caching mode page found
Mai 12 16:39:08 grayhound kernel: sd 1:0:0:0: [sdb] Assuming drive cache: write through
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel: usb 3-6.1.1.4: reset full-speed USB device number 57 using xhci_hcd
Mai 12 16:39:08 grayhound kernel:  sdb:
Mai 12 16:39:08 grayhound kernel: sd 1:0:0:0: [sdb] Attached SCSI removable disk

(here we can see it takes around five seconds to settle)

Once the disc is usable, read/write access is really slow.

west command to build sample:
west build -b stm32u5a9j_dk samples/subsys/usb/mass -- -DCONFIG_APP_MSC_STORAGE_SDCARD=y -DCONFIG_DISK_DRIVER_SDMMC=y -DCONFIG_DISK_ACCESS=y -DCONFIG_DISK_DRIVERS=y -DCONFIG_SDMMC_STM32_EMMC=y -DCONFIG_FS_FATFS_MOUNT_MKFS=y -DCONF_FILE=usbd_next_prj.conf

[bearsh]

maybe a similar issue #87638