Date: Thu, 7 Nov 2024 00:54:47 +0100 From: Rodrigo Osorio <rodrigo@osorio.me> To: freebsd-current@freebsd.org Subject: USB massive disconnections with FreeBSD 15.0-CURRENT Message-ID: <5d403c6f-760b-43c4-b595-49d2e421278c@osorio.me>
next in thread | raw e-mail | index | archive | help
Hi, Just t report that since last week after my last FreeBSD upgrade to main-n273400-5e5e4e1cf0c3, I start having USB issues with all the devices disconnecting, no way to have them back and usbconfig becoming unresponsive. The system works, I can still use the laptop keyboard to do some tests, try to reset the USB stack and finally reboot. I attach part of the log messages uname -a FreeBSD defiant 15.0-CURRENT FreeBSD 15.0-CURRENT main-n273400-5e5e4e1cf0c3 GENERIC amd64 Nov 6 15:23:27 defiant dhclient[10922]: New Routers (em0): 192.168.66.1 Nov 6 18:30:15 defiant kernel: drmn0: [drm] *ERROR* Timed out waiting for DSB workload completion. Nov 6 18:30:21 defiant kernel: em0: link state changed to DOWN Nov 6 18:30:32 defiant acpi[12261]: suspend at 20241106 18:30:32 Nov 6 21:22:54 defiant kernel: uhub0: at usbus1, port 1, addr 1 (disconnected) Nov 6 21:22:54 defiant kernel: ugen1.2: <VIA Technologies Inc. Lenovo USB C to HDMI adapter> at usbus1 (disconnected) Nov 6 21:22:54 defiant kernel: ugen1.7: <GenesysLogic USB2.0 Hub> at usbus1 (disconnected) Nov 6 21:22:54 defiant kernel: uhub3: at uhub0, port 3, addr 6 (disconnected) Nov 6 21:22:54 defiant kernel: ugen1.8: <Logitech USB Optical Mouse> at usbus1 (disconnected) Nov 6 21:22:54 defiant kernel: usbhid0: at uhub3, port 1, addr 7 (disconnected) Nov 6 21:22:54 defiant kernel: hms0: detached Nov 6 21:22:54 defiant kernel: hidbus0: detached Nov 6 21:22:54 defiant kernel: usbhid0: detached Nov 6 21:22:54 defiant kernel: ugen1.9: <vendor 0x17ef Lenovo Traditional USB Keyboard> at usbus1 (disconnected) Nov 6 21:22:54 defiant kernel: usbhid1: at uhub3, port 2, addr 8 (disconnected) Nov 6 21:22:54 defiant kernel: hkbd0: detached Nov 6 21:22:54 defiant kernel: hidbus1: detached Nov 6 21:22:54 defiant kernel: usbhid1: detached Nov 6 21:22:54 defiant kernel: uhub3: detached Nov 6 21:22:54 defiant kernel: ugen1.3: <vendor 0x174f Integrated Camera> at usbus1 (disconnected) Nov 6 21:22:54 defiant kernel: ugen1.4: <Goodix FingerPrint> at usbus1 (disconnected) Nov 6 21:22:54 defiant kernel: ugen1.5: <vendor 0x8087 product 0x0026> at usbus1 (disconnected) Nov 6 21:22:54 defiant kernel: ugen1.6: <GenesysLogic USB3.0 Hub> at usbus1 (disconnected) Nov 6 21:22:54 defiant kernel: uhub2: at uhub0, port 13, addr 5 (disconnected) Nov 6 21:22:54 defiant kernel: uhub2: detached Nov 6 21:22:54 defiant kernel: uhub0: detached Nov 6 21:22:54 defiant kernel: uhub1: at usbus0, port 1, addr 1 (disconnected) Nov 6 21:22:54 defiant kernel: uhub1: detached Nov 6 21:22:54 defiant kernel: vgapci0: child drmn0 requested pci_set_powerstate Nov 6 21:22:54 defiant kernel: pci0: failed to set ACPI power state D3 on \_SB_.PC00.GFX0: AE_BAD_PARAMETER Nov 6 21:22:54 defiant kernel: acpi0: cleared fixed power button status Nov 6 21:22:54 defiant kernel: vgapci0: child drmn0 requested pci_set_powerstate Nov 6 21:22:54 defiant kernel: vgapci0: child drmn0 requested pci_enable_io Nov 6 21:22:54 defiant syslogd: last message repeated 1 times Nov 6 21:22:54 defiant kernel: nvme0: resubmitting queued i/o Nov 6 21:22:54 defiant kernel: nvme0: WRITE sqid:1 cid:0 nsid:1 lba:132600288 len:8 Nov 6 21:22:54 defiant kernel: nvme0: WRITE sqid:1 cid:0 nsid:1 lba:296994328 len:16 Nov 6 21:22:54 defiant kernel: nvme0: done resubmitting i/o Nov 6 21:22:54 defiant kernel: uhub0 on usbus1 Nov 6 21:22:54 defiant kernel: uhub0: <Intel XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus1 Nov 6 21:22:54 defiant kernel: uhub1 on usbus0 Nov 6 21:22:54 defiant kernel: uhub1: <Intel XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus0 Nov 6 21:22:54 defiant kernel: uhub1: 4 ports with 4 removable, self powered Nov 6 21:22:54 defiant kernel: uhub0: 16 ports with 16 removable, self powered Nov 6 21:22:54 defiant kernel: ugen1.2: <vendor 0x174f Integrated Camera> at usbus1 Nov 6 21:22:54 defiant kernel: sdhci_pci0-slot0: Reset 0x1 never completed. Nov 6 21:22:54 defiant kernel: sdhci_pci0-slot0: ============== REGISTER DUMP ============== Nov 6 21:22:54 defiant kernel: sdhci_pci0-slot0: Sys addr: 0xffffffff | Version: 0x0000ffff Nov 6 21:22:54 defiant kernel: sdhci_pci0-slot0: Blk size: 0x0000ffff | Blk cnt: 0x0000ffff Nov 6 21:22:54 defiant kernel: sdhci_pci0-slot0: Argument: 0xffffffff | Trn mode: 0x0000ffff Nov 6 21:22:54 defiant kernel: sdhci_pci0-slot0: Present: 0xffffffff | Host ctl: 0x000000ff Nov 6 21:22:54 defiant kernel: sdhci_pci0-slot0: Power: 0x000000ff | Blk gap: 0x000000ff Nov 6 21:22:54 defiant kernel: sdhci_pci0-slot0: Wake-up: 0x000000ff | Clock: 0x0000ffff Nov 6 21:22:54 defiant kernel: sdhci_pci0-slot0: Timeout: 0x000000ff | Int stat: 0xffffffff Nov 6 21:22:54 defiant kernel: sdhci_pci0-slot0: Int enab: 0xffffffff | Sig enab: 0xffffffff Nov 6 21:22:54 defiant kernel: sdhci_pci0-slot0: AC12 err: 0x0000ffff | Host ctl2:0x0000ffff Nov 6 21:22:54 defiant kernel: sdhci_pci0-slot0: Caps: 0xffffffff | Caps2: 0xffffffff Nov 6 21:22:54 defiant kernel: sdhci_pci0-slot0: Max curr: 0xffffffff | ADMA err: 0x000000ff Nov 6 21:22:54 defiant kernel: sdhci_pci0-slot0: ADMA addr:0xffffffff | Slot int: 0x0000ffff Nov 6 21:22:54 defiant kernel: sdhci_pci0-slot0: =========================================== Nov 6 21:22:54 defiant kernel: sdhci_pci0: detached Nov 6 21:22:54 defiant kernel: ugen1.3: <Goodix FingerPrint> at usbus1 Nov 6 21:22:54 defiant webcamd[12308]: Webcamd is already running for ugen1.2.0 Nov 6 21:22:54 defiant webcamd[12328]: Webcamd is already running for ugen1.2.0 Nov 6 21:22:54 defiant webcamd[12348]: Webcamd is already running for ugen1.2.0 Nov 6 21:22:55 defiant kernel: ugen1.4: <vendor 0x8087 product 0x0026> at usbus1 Nov 6 21:22:55 defiant acpi[12509]: resumed at 20241106 21:22:55 Nov 6 21:22:55 defiant power_profile[12563]: changed to 'economy' Nov 6 21:22:56 defiant webcamd[12593]: webcamd: Cannot find USB device Nov 6 21:23:23 defiant kernel: ugen1.5: <VLI Labs, Inc. USB 3.0 HUB> at usbus1 Nov 6 21:23:23 defiant kernel: uhub2 on uhub0 Nov 6 21:23:23 defiant kernel: uhub2: <VLI Labs, Inc. USB 3.0 HUB, class 9/0, rev 3.00/85.71, addr 4> on usbus1 Nov 6 21:23:23 defiant kernel: uhub2: 4 ports with 4 removable, self powered Nov 6 21:23:24 defiant kernel: ugen1.6: <vendor 0x2109 USB 2.0 HUB> at usbus1 Nov 6 21:23:24 defiant kernel: uhub3 on uhub0 Nov 6 21:23:24 defiant kernel: uhub3: <vendor 0x2109 USB 2.0 HUB, class 9/0, rev 2.00/85.70, addr 5> on usbus1 Nov 6 21:23:24 defiant kernel: uhub3: 4 ports with 4 removable, self powered Nov 6 21:23:25 defiant kernel: ugen1.7: <vendor 0x1a40 USB2.0 HUB> at usbus1 Nov 6 21:23:25 defiant kernel: uhub4 on uhub3 Nov 6 21:23:25 defiant kernel: uhub4: <vendor 0x1a40 USB2.0 HUB, class 9/0, rev 2.00/1.00, addr 6> on usbus1 Nov 6 21:23:26 defiant kernel: uhub4: 4 ports with 4 removable, self powered Nov 6 21:23:27 defiant kernel: usb_msc_auto_quirk: UQ_MSC_NO_GETMAXLUN set for USB mass storage device AI210 Mass Storage (0x23a9:0xef18) Nov 6 21:23:27 defiant kernel: usb_msc_auto_quirk: UQ_MSC_NO_PREVENT_ALLOW set for USB mass storage device AI210 Mass Storage (0x23a9:0xef18) Nov 6 21:23:27 defiant kernel: usb_msc_auto_quirk: UQ_MSC_NO_SYNC_CACHE set for USB mass storage device AI210 Mass Storage (0x23a9:0xef18) Nov 6 21:23:27 defiant kernel: ugen1.8: <AI210 Mass Storage> at usbus1 Nov 6 21:23:27 defiant kernel: umass0 on uhub4 Nov 6 21:23:27 defiant kernel: umass0: <AI210 Mass Storage, class 0/0, rev 2.00/1.00, addr 7> on usbus1 Nov 6 21:23:27 defiant kernel: umass0: SCSI over Bulk-Only; quirks = 0xc100 Nov 6 21:23:27 defiant kernel: umass0:1:0: Attached to scbus1 Nov 6 21:23:27 defiant kernel: da0 at umass-sim0 bus 0 scbus1 target 0 lun 0 Nov 6 21:23:27 defiant kernel: da0: <AI Mass Storage Hmw> Removable Direct Access SPC-2 SCSI device Nov 6 21:23:27 defiant kernel: da0: 40.000MB/s transfers Nov 6 21:23:27 defiant kernel: da0: 7696MB (15761504 512 byte sectors) Nov 6 21:23:27 defiant kernel: da0: quirks=0x2<NO_6_BYTE> Nov 6 21:23:27 defiant kernel: ugen1.9: <Logitech USB Receiver> at usbus1 Nov 6 21:23:27 defiant kernel: usbhid0 on uhub3 Nov 6 21:23:27 defiant kernel: usbhid0: <Logitech USB Receiver, class 0/0, rev 2.00/29.01, addr 8> on usbus1 Nov 6 21:23:27 defiant kernel: hidbus0: <HID bus> on usbhid0 Nov 6 21:23:27 defiant kernel: hkbd0: <Logitech USB Receiver Keyboard> on hidbus0 Nov 6 21:23:27 defiant kernel: kbd2 at hkbd0 Nov 6 21:23:27 defiant kernel: usbhid1 on uhub3 Nov 6 21:23:27 defiant kernel: usbhid1: <Logitech USB Receiver, class 0/0, rev 2.00/29.01, addr 8> on usbus1 Nov 6 21:23:27 defiant kernel: hidbus1: <HID bus> on usbhid1 Nov 6 21:23:27 defiant kernel: hms0: <Logitech USB Receiver Mouse> on hidbus1 Nov 6 21:23:27 defiant kernel: hms0: 16 buttons and [XYWH] coordinates ID=2 Nov 6 21:23:27 defiant kernel: hcons0: <Logitech USB Receiver Consumer Control> on hidbus1 Nov 6 21:23:27 defiant kernel: hsctrl0: <Logitech USB Receiver System Control> on hidbus1 Nov 6 21:23:28 defiant kernel: ugen1.10: <vendor 0x0000 USB OPTICAL MOUSE> at usbus1 Nov 6 21:23:28 defiant kernel: usbhid2 on uhub3 Nov 6 21:23:28 defiant kernel: usbhid2: <vendor 0x0000 USB OPTICAL MOUSE, class 0/0, rev 1.10/1.00, addr 9> on usbus1 Nov 6 21:23:28 defiant kernel: hidbus2: <HID bus> on usbhid2 Nov 6 21:23:28 defiant kernel: hms1: <vendor 0x0000 USB OPTICAL MOUSE> on hidbus2 Nov 6 21:23:28 defiant kernel: hms1: 3 buttons and [XYW] coordinates ID=1 Nov 6 21:23:28 defiant kernel: ugen1.11: <CM Storm Quickfire Rapid i> at usbus1 Nov 6 21:23:28 defiant kernel: usbhid3 on uhub3 Nov 6 21:23:28 defiant kernel: usbhid3: <CM Storm Quickfire Rapid i, class 0/0, rev 1.10/1.15, addr 10> on usbus1 Nov 6 21:23:28 defiant kernel: hidbus3: <HID bus> on usbhid3 Nov 6 21:23:28 defiant kernel: hkbd1: <CM Storm Quickfire Rapid i Keyboard> on hidbus3 Nov 6 21:23:28 defiant kernel: kbd3 at hkbd1 Nov 6 21:23:28 defiant kernel: usbhid4 on uhub3 Nov 6 21:23:29 defiant kernel: usbhid4: <CM Storm Quickfire Rapid i, class 0/0, rev 1.10/1.15, addr 10> on usbus1 Nov 6 21:23:29 defiant kernel: hidbus4: <HID bus> on usbhid4 Nov 6 21:23:29 defiant kernel: usbhid5 on uhub3 Nov 6 21:23:29 defiant kernel: usbhid5: <CM Storm Quickfire Rapid i, class 0/0, rev 1.10/1.15, addr 10> on usbus1 Nov 6 21:23:29 defiant kernel: hidbus5: <HID bus> on usbhid5 Nov 6 21:23:29 defiant kernel: hsctrl1: <CM Storm Quickfire Rapid i System Control> on hidbus5 Nov 6 21:23:29 defiant kernel: hcons1: <CM Storm Quickfire Rapid i Consumer Control> on hidbus5 Nov 6 21:23:29 defiant kernel: hkbd2: <CM Storm Quickfire Rapid i Keyboard> on hidbus5 Nov 6 21:23:29 defiant kernel: kbd4 at hkbd2 Nov 6 21:24:01 defiant power_profile[12877]: changed to 'performance' Nov 6 21:24:40 defiant kernel: em0: link state changed to UP Nov 6 21:24:41 defiant dhclient[12925]: New IP Address (em0): 192.168.1.39 Nov 6 21:24:41 defiant dhclient[12929]: New Subnet Mask (em0): 255.255.255.0 Nov 6 21:24:41 defiant dhclient[12933]: New Broadcast Address (em0): 192.168.1.255 Nov 6 21:24:41 defiant dhclient[12937]: New Routers (em0): 192.168.1.1 Nov 6 21:25:15 defiant kernel: ugen1.10: <vendor 0x0000 USB OPTICAL MOUSE> at usbus1 (disconnected) Nov 6 21:25:15 defiant kernel: usbhid2: at uhub3, port 3, addr 9 (disconnected) Nov 6 21:25:15 defiant kernel: hms1: detached Nov 6 21:25:15 defiant kernel: hidbus2: detached Nov 6 21:25:15 defiant kernel: usbhid2: detached Nov 6 21:25:16 defiant kernel: ugen1.10: <vendor 0x0000 USB OPTICAL MOUSE> at usbus1 Nov 6 21:25:16 defiant kernel: usbhid2 on uhub3 Nov 6 21:25:16 defiant kernel: usbhid2: <vendor 0x0000 USB OPTICAL MOUSE, class 0/0, rev 1.10/1.00, addr 11> on usbus1 Nov 6 21:25:16 defiant kernel: hidbus2: <HID bus> on usbhid2 Nov 6 21:25:16 defiant kernel: hms1: <vendor 0x0000 USB OPTICAL MOUSE> on hidbus2 Nov 6 21:25:16 defiant kernel: hms1: 3 buttons and [XYW] coordinates ID=1 Nov 6 21:25:19 defiant kernel: ugen1.12: <VIA Technologies Inc. Lenovo USB C to HDMI adapter> at usbus1 Nov 6 21:25:30 defiant kernel: drmn0: [drm] *ERROR* mismatch in avi infoframe Nov 6 21:25:30 defiant kernel: drmn0: [drm] *ERROR* expected: Nov 6 21:25:30 defiant kernel: drmn0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13 Nov 6 21:25:30 defiant kernel: drmn0: colorspace: RGB Nov 6 21:25:30 defiant kernel: drmn0: scan mode: Underscan Nov 6 21:25:30 defiant kernel: drmn0: colorimetry: No Data Nov 6 21:25:30 defiant kernel: drmn0: picture aspect: 16:9 Nov 6 21:25:30 defiant kernel: drmn0: active aspect: Same as Picture Nov 6 21:25:30 defiant kernel: drmn0: itc: No Data Nov 6 21:25:30 defiant kernel: drmn0: extended colorimetry: xvYCC 601 Nov 6 21:25:30 defiant kernel: drmn0: quantization range: Default Nov 6 21:25:30 defiant kernel: drmn0: nups: Horizontally Scaled Nov 6 21:25:30 defiant kernel: drmn0: video code: 0 Nov 6 21:25:30 defiant kernel: drmn0: ycc quantization range: Invalid Nov 6 21:25:30 defiant kernel: drmn0: hdmi content type: Graphics Nov 6 21:25:30 defiant kernel: drmn0: pixel repeat: 0 Nov 6 21:25:30 defiant kernel: drmn0: bar top 0, bottom 0, left 0, right 0 Nov 6 21:25:30 defiant kernel: drmn0: [drm] *ERROR* found: Nov 6 21:25:30 defiant kernel: drmn0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13 Nov 6 21:25:30 defiant kernel: drmn0: colorspace: RGB Nov 6 21:25:30 defiant kernel: drmn0: scan mode: Underscan Nov 6 21:25:30 defiant kernel: drmn0: colorimetry: No Data Nov 6 21:25:30 defiant kernel: drmn0: picture aspect: 16:9 Nov 6 21:25:30 defiant kernel: drmn0: active aspect: Same as Picture Nov 6 21:25:30 defiant kernel: drmn0: itc: No Data Nov 6 21:25:30 defiant kernel: drmn0: extended colorimetry: xvYCC 601 Nov 6 21:25:30 defiant kernel: drmn0: quantization range: Default Nov 6 21:25:30 defiant kernel: drmn0: nups: Horizontally Scaled Nov 6 21:25:30 defiant kernel: drmn0: video code: 0 Nov 6 21:25:30 defiant kernel: drmn0: ycc quantization range: Limited Nov 6 21:25:30 defiant kernel: drmn0: hdmi content type: Graphics Nov 6 21:25:30 defiant kernel: drmn0: pixel repeat: 0 Nov 6 21:25:30 defiant kernel: drmn0: bar top 0, bottom 0, left 0, right 0 Nov 6 21:25:30 defiant kernel: pipe state doesn't match! Nov 6 21:27:36 defiant reboot[13101]: rebooted by rodrigo
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?5d403c6f-760b-43c4-b595-49d2e421278c>