Created attachment 306106 [details] Linux 6.9-rc2+ messages debug v2 patch (output of `dmesg`) https://lore.kernel.org/linux-usb/1f64af9a-0618-a7da-4acc-f043b6580308@linux.intel.com/T/#t This is just for attaching trace files and Linux messages. ``` echo 'module xhci_hcd =p' >/sys/kernel/debug/dynamic_debug/control echo 'module xhci_hcd =p' | sudo tee /sys/kernel/debug/dynamic_debug/control echo 'module usbcore =p' | sudo tee /sys/kernel/debug/dynamic_debug/control echo 81920 | sudo tee /sys/kernel/debug/tracing/buffer_size_kb echo 1 | sudo tee /sys/kernel/debug/tracing/events/xhci-hcd/enable echo 1 | sudo tee /sys/kernel/debug/tracing/tracing_on echo 0 | sudo tee /sys/kernel/debug/tracing/tracing_on echo 'module usbcore -p' | sudo tee /sys/kernel/debug/dynamic_debug/control echo 0 | sudo tee /sys/kernel/debug/tracing/events/xhci-hcd/enable echo 'module xhci_hcd -p' | sudo tee /sys/kernel/debug/dynamic_debug/control ```
Created attachment 306107 [details] Compressed trace `/sys/kernel/debug/tracing/trace` Command for compression: 7z a -t7z -m0=lzma -mx=9 -mfb=64 -md=32m -ms=on trace.7z trace
The trace shows hosts sends duplicate transfer events for ISOC TRBs after a Transaction error on same device. This transaction error is probably due to device disconnect. Transfer events for TRB ..a1c0 twice, with a transaction error in between: <idle>-0 [000] d.h2. 33819.709897: xhci_handle_event: EVENT: TRB 00000032959a1c0 status 'Success' len 0 slot 6 ep 2 type 'Transfer Event' flags e:c <idle>-0 [000] d.h2. 33819.709904: xhci_handle_event: EVENT: TRB 000000041547d010 status 'USB Transaction Error' len 4 slot 6 ep 15 type 'Transfer Event' flags e:c systemd-journal-395 [000] d.H1. 33819.711886: xhci_handle_event: EVENT: TRB 000000032959a1c0 status 'Success' len 0 slot 6 ep 2 type 'Transfer Event' flags e:c Transfer events for TRB ..a1d0 twice (the next TRB) systemd-journal-395 [000] d.H1. 33819.712001: xhci_handle_event: EVENT: TRB 000000032959a1d0 status 'Success' len 0 slot 6 ep 2 type 'Transfer Event' flags e:c systemd-journal-395 [000] d.H1. 33819.712059: xhci_handle_event: EVENT: TRB 000000032959a1d0 status 'Success' len 0 slot 6 ep 2 type 'Transfer Event' flags e:c Transfer events for TRB ..a1e0 twice systemd-journal-395 [000] d.H1. 33819.712139: xhci_handle_event: EVENT: TRB 000000032959a1e0 status 'Success' len 0 slot 6 ep 2 type 'Transfer Event' flags e:c systemd-journal-395 [000] d.h1. 33819.712871: xhci_handle_event: EVENT: TRB 000000032959a1e0 status 'Success' len 0 slot 6 ep 2 type 'Transfer Event' flags e:c etc.. Driver can cope with these extra events, but if this is common we should probably handle it silently and not concern users with that ERROR message.
Created attachment 306135 [details] Output of `git grep -l 'ERROR Transfer event TRB DMA'` The Linux messages collected by *Hardware for Linux*, dated May 9th, 2023, contain 287 probes (uploads) with that error. [1]: https://github.com/linuxhw/Dmesg
Today, this also happened disconnecting a Logitech Logitech G430 Gaming Headset. ``` Feb 27 09:08:49 abreu kernel: Linux version 6.14.0-rc4-00052-gac9c34d1e45a (build@bohemianrhapsody.molgen.mpg.de) (gcc (Debian 14.2.0-17) 14.2.0, GNU ld (GNU Binutils for Debian) 2.44) #93 SMP PREEMPT_DYNAMIC Wed Feb 26 08:31:56 CET 2025 Feb 27 09:08:49 abreu kernel: Command line: BOOT_IMAGE=/vmlinuz-6.14.0-rc4-00052-gac9c34d1e45a root=UUID=32e29882-d94d-4a92-9ee4-4d03002bfa29 ro quiet pci=noaer mem_sleep_default=deep log_buf_len=8M cryptomgr.notests […] Feb 27 09:08:49 abreu kernel: DMI: Dell Inc. XPS 13 9360/0596KF, BIOS 2.21.0 06/02/2022 […] Feb 27 11:40:59 abreu kernel: usb 1-2: USB disconnect, device number 6 Feb 27 11:41:15 abreu kernel: usb 1-2: new full-speed USB device number 7 using xhci_hcd Feb 27 11:41:15 abreu kernel: usb 1-2: New USB device found, idVendor=046d, idProduct=0a4d, bcdDevice= 1.19 Feb 27 11:41:16 abreu kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Feb 27 11:41:16 abreu kernel: usb 1-2: Product: Logitech G430 Gaming Headset Feb 27 11:41:16 abreu kernel: usb 1-2: Manufacturer: Logitech Feb 27 11:41:16 abreu kernel: input: Logitech Logitech G430 Gaming Headset as /devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2:1.3/0003:046D:0A4D.0005/input/input45 Feb 27 11:41:16 abreu kernel: hid-generic 0003:046D:0A4D.0005: input,hidraw2: USB HID v1.00 Device [Logitech Logitech G430 Gaming Headset] on usb-0000:00:14.0-2/input3 Feb 27 12:11:13 abreu kernel: uvcvideo 1-5:1.1: Failed to resubmit video URB (-1). Feb 27 12:18:07 abreu kernel: usb 1-2: USB disconnect, device number 7 Feb 27 12:18:07 abreu kernel: xhci_hcd 0000:00:14.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 1 comp_code 1 Feb 27 12:18:07 abreu kernel: xhci_hcd 0000:00:14.0: Looking for event-dma 00000003fd5a5710 trb-start 00000003fd5a5720 trb-end 00000003fd5a5720 seg-start 00000003fd5a5000 seg-end 00000003fd5a5ff0 Feb 27 12:18:13 abreu kernel: usb 1-2: new full-speed USB device number 8 using xhci_hcd Feb 27 12:18:13 abreu kernel: usb 1-2: New USB device found, idVendor=046d, idProduct=0a4d, bcdDevice= 1.19 Feb 27 12:18:13 abreu kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Feb 27 12:18:13 abreu kernel: usb 1-2: Product: Logitech G430 Gaming Headset Feb 27 12:18:13 abreu kernel: usb 1-2: Manufacturer: Logitech Feb 27 12:18:13 abreu kernel: input: Logitech Logitech G430 Gaming Headset as /devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2:1.3/0003:046D:0A4D.0006/input/input46 Feb 27 12:18:13 abreu kernel: hid-generic 0003:046D:0A4D.0006: input,hidraw2: USB HID v1.00 Device [Logitech Logitech G430 Gaming Headset] on usb-0000:00:14.0-2/input3 Feb 27 12:42:09 abreu kernel: usb 1-2: USB disconnect, device number 8 Feb 27 12:48:29 abreu kernel: r8152 4-1.1.3:1.0 enx00e04cf4ead4: carrier off Feb 27 12:48:29 abreu kernel: PM: suspend entry (deep) ```