From nobody Wed Nov 06 23:54:47 2024 X-Original-To: freebsd-current@mlmmj.nyi.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2610:1c1:1:606c::19:1]) by mlmmj.nyi.freebsd.org (Postfix) with ESMTP id 4XkMWc4S4fz5c1gF for ; Wed, 06 Nov 2024 23:54:52 +0000 (UTC) (envelope-from rodrigo@osorio.me) Received: from smtp.osorio.me (mvd.osorio.me [37.187.111.94]) by mx1.freebsd.org (Postfix) with ESMTP id 4XkMWb6Yhrz4Psq for ; Wed, 6 Nov 2024 23:54:51 +0000 (UTC) (envelope-from rodrigo@osorio.me) Authentication-Results: mx1.freebsd.org; dkim=none; spf=pass (mx1.freebsd.org: domain of rodrigo@osorio.me designates 37.187.111.94 as permitted sender) smtp.mailfrom=rodrigo@osorio.me; dmarc=none Received: from [192.168.1.39] (lfbn-idf1-1-971-net.w86-238.abo.wanadoo.fr [86.238.50.0]) (using TLSv1.3 with cipher TLS_AES_128_GCM_SHA256 (128/128 bits) key-exchange X25519 server-signature ECDSA (secp384r1) server-digest SHA384) (No client certificate requested) by smtp.osorio.me (Postfix) with ESMTPSA id 2E900B0B68 for ; Wed, 06 Nov 2024 23:55:15 +0000 (UTC) Message-ID: <5d403c6f-760b-43c4-b595-49d2e421278c@osorio.me> Date: Thu, 7 Nov 2024 00:54:47 +0100 List-Id: Discussions about the use of FreeBSD-current List-Archive: https://lists.freebsd.org/archives/freebsd-current List-Help: List-Post: List-Subscribe: List-Unsubscribe: Sender: owner-freebsd-current@FreeBSD.org MIME-Version: 1.0 User-Agent: Mozilla Thunderbird To: freebsd-current@freebsd.org Content-Language: en-US From: Rodrigo Osorio Subject: USB massive disconnections with FreeBSD 15.0-CURRENT Content-Type: text/plain; charset=UTF-8; format=flowed Content-Transfer-Encoding: 8bit X-Spamd-Result: default: False [-1.27 / 15.00]; NEURAL_HAM_LONG(-1.00)[-1.000]; NEURAL_HAM_MEDIUM(-1.00)[-1.000]; NEURAL_SPAM_SHORT(0.82)[0.818]; R_SPF_ALLOW(-0.20)[+ip4:37.187.111.94]; RCVD_NO_TLS_LAST(0.10)[]; ONCE_RECEIVED(0.10)[]; MIME_GOOD(-0.10)[text/plain]; XM_UA_NO_VERSION(0.01)[]; RCVD_VIA_SMTP_AUTH(0.00)[]; RCPT_COUNT_ONE(0.00)[1]; ASN(0.00)[asn:16276, ipnet:37.187.0.0/16, country:FR]; MIME_TRACE(0.00)[0:+]; RCVD_COUNT_ONE(0.00)[1]; FREEFALL_USER(0.00)[rodrigo]; R_DKIM_NA(0.00)[]; ARC_NA(0.00)[]; TO_DN_NONE(0.00)[]; FROM_EQ_ENVFROM(0.00)[]; MID_RHS_MATCH_FROM(0.00)[]; TO_MATCH_ENVRCPT_ALL(0.00)[]; DMARC_NA(0.00)[osorio.me]; PREVIOUSLY_DELIVERED(0.00)[freebsd-current@freebsd.org]; MLMMJ_DEST(0.00)[freebsd-current@freebsd.org]; FROM_HAS_DN(0.00)[] X-Rspamd-Queue-Id: 4XkMWb6Yhrz4Psq X-Spamd-Bar: - 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: at usbus1 (disconnected) Nov  6 21:22:54 defiant kernel: ugen1.7: 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: 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: 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: at usbus1 (disconnected) Nov  6 21:22:54 defiant kernel: ugen1.4: at usbus1 (disconnected) Nov  6 21:22:54 defiant kernel: ugen1.5: at usbus1 (disconnected) Nov  6 21:22:54 defiant kernel: ugen1.6: 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: on usbus1 Nov  6 21:22:54 defiant kernel: uhub1 on usbus0 Nov  6 21:22:54 defiant kernel: uhub1: 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: 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: 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: 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: at usbus1 Nov  6 21:23:23 defiant kernel: uhub2 on uhub0 Nov  6 21:23:23 defiant kernel: uhub2: 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: at usbus1 Nov  6 21:23:24 defiant kernel: uhub3 on uhub0 Nov  6 21:23:24 defiant kernel: uhub3: 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: at usbus1 Nov  6 21:23:25 defiant kernel: uhub4 on uhub3 Nov  6 21:23:25 defiant kernel: uhub4: 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: at usbus1 Nov  6 21:23:27 defiant kernel: umass0 on uhub4 Nov  6 21:23:27 defiant kernel: umass0: 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: 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 Nov  6 21:23:27 defiant kernel: ugen1.9: at usbus1 Nov  6 21:23:27 defiant kernel: usbhid0 on uhub3 Nov  6 21:23:27 defiant kernel: usbhid0: on usbus1 Nov  6 21:23:27 defiant kernel: hidbus0: on usbhid0 Nov  6 21:23:27 defiant kernel: hkbd0: 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: on usbus1 Nov  6 21:23:27 defiant kernel: hidbus1: on usbhid1 Nov  6 21:23:27 defiant kernel: hms0: 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: on hidbus1 Nov  6 21:23:27 defiant kernel: hsctrl0: on hidbus1 Nov  6 21:23:28 defiant kernel: ugen1.10: at usbus1 Nov  6 21:23:28 defiant kernel: usbhid2 on uhub3 Nov  6 21:23:28 defiant kernel: usbhid2: on usbus1 Nov  6 21:23:28 defiant kernel: hidbus2: on usbhid2 Nov  6 21:23:28 defiant kernel: hms1: 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: at usbus1 Nov  6 21:23:28 defiant kernel: usbhid3 on uhub3 Nov  6 21:23:28 defiant kernel: usbhid3: on usbus1 Nov  6 21:23:28 defiant kernel: hidbus3: on usbhid3 Nov  6 21:23:28 defiant kernel: hkbd1: 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: on usbus1 Nov  6 21:23:29 defiant kernel: hidbus4: on usbhid4 Nov  6 21:23:29 defiant kernel: usbhid5 on uhub3 Nov  6 21:23:29 defiant kernel: usbhid5: on usbus1 Nov  6 21:23:29 defiant kernel: hidbus5: on usbhid5 Nov  6 21:23:29 defiant kernel: hsctrl1: on hidbus5 Nov  6 21:23:29 defiant kernel: hcons1: on hidbus5 Nov  6 21:23:29 defiant kernel: hkbd2: 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: 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: at usbus1 Nov  6 21:25:16 defiant kernel: usbhid2 on uhub3 Nov  6 21:25:16 defiant kernel: usbhid2: on usbus1 Nov  6 21:25:16 defiant kernel: hidbus2: on usbhid2 Nov  6 21:25:16 defiant kernel: hms1: 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: 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