Spurensuche: USB-Hub crashes

[Mat]

Manchmal geht bei meinem Laptop das USB-Hub aus. An einigen Tagen passiert nichts, an anderen Tagen ist er nur am crashen.

Ich vermute, dass ich ein Treiber- oder Energieverwaltungsproblem habe. Spannungsproblem würde mich wundern, da würde ich Abstürze erwarten.

Wie kann ich dem Problem auf die Spur kommen?

• OS: OpenSuse Tumbleweed
  • unter Windows lief es ganz normal (oder wurde immer automatisch resettet)
• Hardware:
  • HP Laptop 15-db0500ng/84AE, BIOS F.11 08/20/2018
  • Externe HDD in DVD-Slot
• Crash tritt auf:
  • mit und ohne Stromanschluss
  • bei Benutzung und Inaktivität
  • mit und ohne Hub
  • auch wenn nur eine Maus angeschlossen ist (egal welche Maus)
  • bei allen USB-Ports
• Sonstiges:
  • alle externen USB-Ports werden inaktiv
  • intern läuft zum Glück weiter (Touchpad, Tastatur usw)
  • Geräte neu einstecken hilft nicht

Hier die logs. Da ist auch schon immer ein reset durch mich drin. Normalerweise würde nach Host System Error nichts mehr mit den USB-Ports passieren:

[37174.627445] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[37174.627545] sd 0:0:0:0: [sda] Stopping disk
[37174.656019] xhci_hcd 0000:04:00.3: WARNING: Host System Error
[37176.578105] xhci_hcd 0000:04:00.3: remove, state 4
[37176.578121] usb usb2: USB disconnect, device number 1
[37176.578565] xhci_hcd 0000:04:00.3: USB bus 2 deregistered
[37176.578585] xhci_hcd 0000:04:00.3: WARNING: Host System Error
[37176.578622] xhci_hcd 0000:04:00.3: remove, state 1
[37176.578627] usb usb1: USB disconnect, device number 1
[37176.578629] usb 1-2: USB disconnect, device number 2
[37176.578631] usb 1-2.2: USB disconnect, device number 3
[37176.820862] usb 1-2.3: USB disconnect, device number 4
[37176.953453] xhci_hcd 0000:04:00.3: USB bus 1 deregistered
[37178.458374] xhci_hcd 0000:04:00.3: xHCI Host Controller
[37178.458389] xhci_hcd 0000:04:00.3: new USB bus registered, assigned bus number 1
[37178.458664] xhci_hcd 0000:04:00.3: hcc params 0x0270ffe5 hci version 0x110 quirks 0x0000000840000410
[37178.459915] xhci_hcd 0000:04:00.3: xHCI Host Controller
[37178.459924] xhci_hcd 0000:04:00.3: new USB bus registered, assigned bus number 2
[37178.459929] xhci_hcd 0000:04:00.3: Host supports USB 3.1 Enhanced SuperSpeed
[37178.460074] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.19
[37178.460078] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[37178.460081] usb usb1: Product: xHCI Host Controller
[37178.460083] usb usb1: Manufacturer: Linux 5.19.10-1-default xhci-hcd
[37178.460085] usb usb1: SerialNumber: 0000:04:00.3
[37178.460454] hub 1-0:1.0: USB hub found
[37178.460473] hub 1-0:1.0: 4 ports detected
[37178.461116] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[37178.461148] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.19
[37178.461152] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[37178.461154] usb usb2: Product: xHCI Host Controller
[37178.461156] usb usb2: Manufacturer: Linux 5.19.10-1-default xhci-hcd
[37178.461158] usb usb2: SerialNumber: 0000:04:00.3
[37178.461928] hub 2-0:1.0: USB hub found
[37178.461951] hub 2-0:1.0: 4 ports detected
[37178.719424] usb 1-2: new high-speed USB device number 2 using xhci_hcd
[37178.869699] usb 1-2: New USB device found, idVendor=1a40, idProduct=0801, bcdDevice= 1.00
[37178.869710] usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[37178.869714] usb 1-2: Product: USB 2.0 Hub
[37180.467414] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[37180.477479] ata1.00: configured for UDMA/133
[37180.487634] sd 0:0:0:0: [sda] Starting disk
[37184.043447] xhci_hcd 0000:04:00.3: xHCI host not responding to stop endpoint command
[37184.043490] xhci_hcd 0000:04:00.3: xHCI host controller not responding, assume dead
[37184.043505] xhci_hcd 0000:04:00.3: HC died; cleaning up
[37184.043712] usb 1-2: USB disconnect, device number 2
[37184.476769] xhci_hcd 0000:04:00.3: remove, state 4
[37184.476784] usb usb2: USB disconnect, device number 1
[37184.477366] xhci_hcd 0000:04:00.3: USB bus 2 deregistered
[37184.477380] xhci_hcd 0000:04:00.3: remove, state 1
[37184.477383] usb usb1: USB disconnect, device number 1
[37184.478854] xhci_hcd 0000:04:00.3: USB bus 1 deregistered
[37185.985487] xhci_hcd 0000:04:00.3: xHCI Host Controller
[37185.985507] xhci_hcd 0000:04:00.3: new USB bus registered, assigned bus number 1
[37185.985802] xhci_hcd 0000:04:00.3: hcc params 0x0270ffe5 hci version 0x110 quirks 0x0000000840000410
[37185.986587] xhci_hcd 0000:04:00.3: xHCI Host Controller
[37185.986594] xhci_hcd 0000:04:00.3: new USB bus registered, assigned bus number 2
[37185.986601] xhci_hcd 0000:04:00.3: Host supports USB 3.1 Enhanced SuperSpeed
[37185.986719] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.19
[37185.986727] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[37185.986732] usb usb1: Product: xHCI Host Controller
[37185.986736] usb usb1: Manufacturer: Linux 5.19.10-1-default xhci-hcd
[37185.986738] usb usb1: SerialNumber: 0000:04:00.3
[37185.987086] hub 1-0:1.0: USB hub found
[37185.987115] hub 1-0:1.0: 4 ports detected
[37185.989115] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[37185.989177] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.19
[37185.989184] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[37185.989188] usb usb2: Product: xHCI Host Controller
[37185.989192] usb usb2: Manufacturer: Linux 5.19.10-1-default xhci-hcd
[37185.989194] usb usb2: SerialNumber: 0000:04:00.3
[37185.990258] hub 2-0:1.0: USB hub found
[37185.990284] hub 2-0:1.0: 4 ports detected
[37186.247449] usb 1-2: new high-speed USB device number 2 using xhci_hcd
[37186.401303] usb 1-2: New USB device found, idVendor=1a40, idProduct=0801, bcdDevice= 1.00
[37186.401317] usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[37186.401322] usb 1-2: Product: USB 2.0 Hub
[37186.435092] hub 1-2:1.0: USB hub found
[37186.435347] hub 1-2:1.0: 4 ports detected
[37186.755432] usb 1-2.2: new full-speed USB device number 3 using xhci_hcd
[37186.901776] usb 1-2.2: New USB device found, idVendor=046d, idProduct=c24d, bcdDevice=80.01
[37186.901789] usb 1-2.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[37186.901792] usb 1-2.2: Product: Logitech G710 Keyboard
[37186.901794] usb 1-2.2: Manufacturer: Logitech
[37186.991268] input: Logitech Logitech G710 Keyboard as /devices/pci0000:00/0000:00:08.1/0000:04:00.3/usb1/1-2/1-2.2/1-2.2:1.0/0003:046D:C24D.01AD/input/input656
[37187.047923] hid-generic 0003:046D:C24D.01AD: input,hidraw0: USB HID v1.11 Keyboard [Logitech Logitech G710 Keyboard] on usb-0000:04:00.3-2.2/input0
[37187.054325] input: Logitech Logitech G710 Keyboard as /devices/pci0000:00/0000:00:08.1/0000:04:00.3/usb1/1-2/1-2.2/1-2.2:1.1/0003:046D:C24D.01AE/input/input657
[37187.115783] hid-generic 0003:046D:C24D.01AE: input,hiddev96,hidraw1: USB HID v1.11 Keyboard [Logitech Logitech G710 Keyboard] on usb-0000:04:00.3-2.2/input1
[37187.203432] usb 1-2.3: new full-speed USB device number 4 using xhci_hcd
[37187.401819] usb 1-2.3: New USB device found, idVendor=046d, idProduct=c24a, bcdDevice=77.02
[37187.401833] usb 1-2.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[37187.401837] usb 1-2.3: Product: Gaming Mouse G600
[37187.401841] usb 1-2.3: Manufacturer: Logitech
[37187.401843] usb 1-2.3: SerialNumber: XXX
[37187.506290] input: Logitech Gaming Mouse G600 as /devices/pci0000:00/0000:00:08.1/0000:04:00.3/usb1/1-2/1-2.3/1-2.3:1.0/0003:046D:C24A.01AF/input/input659
[37187.506470] hid-generic 0003:046D:C24A.01AF: input,hidraw2: USB HID v1.11 Mouse [Logitech Gaming Mouse G600] on usb-0000:04:00.3-2.3/input0
[37187.517547] input: Logitech Gaming Mouse G600 Keyboard as /devices/pci0000:00/0000:00:08.1/0000:04:00.3/usb1/1-2/1-2.3/1-2.3:1.1/0003:046D:C24A.01B0/input/input660
[37187.575897] input: Logitech Gaming Mouse G600 as /devices/pci0000:00/0000:00:08.1/0000:04:00.3/usb1/1-2/1-2.3/1-2.3:1.1/0003:046D:C24A.01B0/input/input661
[37187.576044] hid-generic 0003:046D:C24A.01B0: input,hiddev97,hidraw3: USB HID v1.11 Keyboard [Logitech Gaming Mouse G600] on usb-0000:04:00.3-2.3/input1

[38261.914560] ------------[ cut here ]------------
[38261.914571] firmware failed to ack driver for leaving Deep Power mode
[38261.914644] WARNING: CPU: 3 PID: 27143 at drivers/net/wireless/realtek/rtw88/ps.c:104 rtw_power_mode_change+0x103/0x110 [rtw88_core]
[38261.914673] Modules linked in: ntfs3 twofish_generic twofish_avx_x86_64 twofish_x86_64_3way twofish_x86_64 twofish_common serpent_avx2 serpent_avx_x86_64 serpent_sse2_x86_64 serpent_generic xts ecb loop rfcomm ccm xt_conntrack xt_MASQUERADE nf_conntrack_netlink xfrm_user xfrm_algo xt_addrtype af_packet br_netfilter nft_fib_inet nft_fib_ipv4 nft_fib_ipv6 nft_fib nft_reject_inet nf_reject_ipv4 nf_reject_ipv6 nft_reject bridge nft_ct stp llc nft_chain_nat overlay nf_tables ebtable_nat ebtable_broute ip6table_nat ip6table_mangle ip6table_raw ip6table_security iptable_nat nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 iptable_mangle iptable_raw iptable_security ip_set nfnetlink ebtable_filter ebtables ip6table_filter ip6_tables iptable_filter bpfilter qrtr cmac algif_hash algif_skcipher af_alg bnep dmi_sysfs xfs libcrc32c uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_common btusb videodev btrtl btbcm btintel btmtk mc bluetooth ecdh_generic
[38261.914750]  snd_sof_amd_renoir snd_sof_amd_acp intel_rapl_msr intel_rapl_common snd_sof_pci snd_sof snd_hda_codec_realtek snd_hda_codec_generic edac_mce_amd ledtrig_audio snd_sof_utils rtw88_8821ce snd_hda_codec_hdmi rtw88_8821c nls_iso8859_1 nls_cp437 rtw88_pci snd_hda_intel snd_intel_dspcfg vfat snd_soc_core snd_intel_sdw_acpi rtw88_core fat snd_compress kvm snd_hda_codec snd_pcm_dmaengine irqbypass mac80211 snd_hda_core snd_acp_pci snd_pci_acp6x snd_hwdep snd_pcm libarc4 r8169 hp_wmi snd_timer cfg80211 pcspkr snd_pci_acp5x sparse_keymap joydev efi_pstore platform_profile realtek snd_rn_pci_acp3x wmi_bmof snd mdio_devres k10temp rfkill snd_acp_config snd_soc_acpi soundcore i2c_piix4 snd_pci_acp3x libphy ac thermal i2c_scmi wireless_hotkey tiny_power_button button acpi_cpufreq fuse configfs ip_tables x_tables ext4 mbcache jbd2 dm_crypt essiv authenc trusted asn1_encoder tee hid_generic uas usbhid usb_storage amdgpu crct10dif_pclmul crc32_pclmul crc32c_intel
[38261.914851]  ghash_clmulni_intel drm_ttm_helper ttm iommu_v2 gpu_sched xhci_pci xhci_pci_renesas drm_display_helper xhci_hcd aesni_intel cec crypto_simd cryptd serio_raw usbcore rc_core sp5100_tco ccp wmi battery video sg dm_multipath dm_mod scsi_dh_rdac scsi_dh_emc scsi_dh_alua msr efivarfs
[38261.914897] Unloaded tainted modules: amd64_edac():1 kvm_amd():1 fjes():1 amd64_edac():1 fjes():1 kvm_amd():1 fjes():1 amd64_edac():1 amd64_edac():1 fjes():1 kvm_amd():1 fjes():1 pcc_cpufreq():1 kvm_amd():1 pcc_cpufreq():1 fjes():1 pcc_cpufreq():1 fjes():1 pcc_cpufreq():1 fjes():1 pcc_cpufreq():1 fjes():1 pcc_cpufreq():1 fjes():1 pcc_cpufreq():1 pcc_cpufreq():1 bbswitch(O):2
[38261.914956] CPU: 3 PID: 27143 Comm: kworker/u32:3 Tainted: G           O      5.19.10-1-default #1 openSUSE Tumbleweed a16afbdc9586ceec99df82ec5e24452efc1b6a79
[38261.914963] Hardware name: HP HP Laptop 15-db0500ng/84AE, BIOS F.11 08/20/2018
[38261.914967] Workqueue: phy0 ieee80211_beacon_connection_loss_work [mac80211]
[38261.915040] RIP: 0010:rtw_power_mode_change+0x103/0x110 [rtw88_core]
[38261.915057] Code: 53 ad 63 c8 44 30 e0 78 23 45 84 ed 48 c7 c0 15 fd 9c c1 48 c7 c6 1e fd 9c c1 48 c7 c7 c8 0a 9d c1 48 0f 45 f0 e8 f4 a5 30 c8 <0f> 0b 5b 5d 41 5c 41 5d e9 60 af 63 c8 0f 1f 44 00 00 53 48 89 fb
[38261.915059] RSP: 0018:ffffc0a9c491fd88 EFLAGS: 00010282
[38261.915062] RAX: 0000000000000000 RBX: ffff9fb1259120e0 RCX: 0000000000000027
[38261.915064] RDX: ffff9fb2272e24e8 RSI: 0000000000000001 RDI: ffff9fb2272e24e0
[38261.915066] RBP: 000022cc8bc998e1 R08: 0000000000000000 R09: ffffc0a9c491fc30
[38261.915067] R10: 0000000000000003 R11: ffff9fb2271fffe8 R12: 00000000c158e080
[38261.915069] R13: 0000000000000000 R14: ffff9fb101e7ba25 R15: 0000000000000010
[38261.915071] FS:  0000000000000000(0000) GS:ffff9fb2272c0000(0000) knlGS:0000000000000000
[38261.915073] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[38261.915075] CR2: 00001d440060c000 CR3: 0000000105a8a000 CR4: 00000000003506e0
[38261.915077] Call Trace:
[38261.915082]  <TASK>
[38261.915090]  rtw_pci_deep_ps+0xa6/0xb0 [rtw88_pci a1f3ce6d581f3f299be137d5f8b504ffda09320a]
[38261.915097]  rtw_ops_config+0x3e/0x100 [rtw88_core 368b9f6c9ecfb0de4e9f111ff4850c5c2c76eaa6]
[38261.915112]  ieee80211_hw_config+0x200/0x3f0 [mac80211 bba1769348aee41400bf6ee6d86c6d903978b212]
[38261.915168]  ieee80211_recalc_ps.part.0+0x150/0x1c0 [mac80211 bba1769348aee41400bf6ee6d86c6d903978b212]
[38261.915239]  ieee80211_mgd_probe_ap.part.0+0xc8/0x160 [mac80211 bba1769348aee41400bf6ee6d86c6d903978b212]
[38261.915309]  process_one_work+0x20f/0x3d0
[38261.915318]  worker_thread+0x4a/0x3b0
[38261.915340]  ? process_one_work+0x3d0/0x3d0
[38261.915347]  kthread+0xda/0x100
[38261.915402]  ? kthread_complete_and_exit+0x20/0x20
[38261.915406]  ret_from_fork+0x22/0x30
[38261.915416]  </TASK>
[38261.915417] ---[ end trace 0000000000000000 ]---

In sehr seltenen Fällen erscheint nicht mal ein Eintrag in dmesg

PS: Im Moment hab ich im Hintergrund ein Skript laufen, das den Hub automatisch zurücksetzt:

usb-reset/usbreset.py at main · wookiefriseur/usb-reset

Reset script for crashed USB port. Contribute to wookiefriseur/usb-reset development by creating an account on GitHub.

github.com