Hi, I'm using the new Lenovo T420 port on gerrit[1] and found a bug about USB(in my post on the gerrit page). And just now I found a work around on this issue.
First I tested a USB disk on the buggy USB port before suspending the system, it works fine. Then I unplugged the USB disk, suspended my system, resume, and replugged the disk, the bug occurred. Then I unplugged the disk, reload the ehci_pci module as follows: rmmod ehci_pci modprobe ehci-pci The I plugged in the USB disk, it worked fine again. I think the kernel message[2] gives some information about it, because it gives two different port number on the USB device. But I know almost nothing about USB, so I'm not sure what's happening. [1] http://review.coreboot.org/#/c/11765/ [2] see the attached dmesg.log Iru Cai
[ 42.540979] cfg80211: Exceeded CRDA call max attempts. Not calling CRDA [ 54.182991] usb 1-1.2: new high-speed USB device number 5 using ehci-pci [ 54.367251] usb-storage 1-1.2:1.0: USB Mass Storage device detected [ 54.367474] scsi host6: usb-storage 1-1.2:1.0 [ 54.367662] usbcore: registered new interface driver usb-storage [ 54.370123] usbcore: registered new interface driver uas [ 55.526164] scsi 6:0:0:0: Direct-Access JetFlash Transcend 32GB 1100 PQ: 0 ANSI: 6 [ 55.528752] sd 6:0:0:0: [sdb] 62914560 512-byte logical blocks: (32.2 GB/30.0 GiB) [ 55.529861] sd 6:0:0:0: [sdb] Write Protect is off [ 55.529875] sd 6:0:0:0: [sdb] Mode Sense: 43 00 00 00 [ 55.530955] sd 6:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 55.539236] sdb: sdb1 [ 55.543397] sd 6:0:0:0: [sdb] Attached SCSI removable disk [ 60.603534] usb 1-1.2: USB disconnect, device number 5 [ 60.606630] sd 6:0:0:0: [sdb] Synchronizing SCSI cache [ 60.606738] sd 6:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=0x01 driverbyte=0x00 [ 60.682151] cfg80211: Exceeded CRDA call max attempts. Not calling CRDA [ 64.226309] cfg80211: Exceeded CRDA call max attempts. Not calling CRDA [ 64.592577] cfg80211: Exceeded CRDA call max attempts. Not calling CRDA [ 64.694718] cfg80211: Exceeded CRDA call max attempts. Not calling CRDA [ 66.063520] PM: Syncing filesystems ... done. [ 66.278789] PM: Preparing system for mem sleep [ 66.279386] Freezing user space processes ... (elapsed 0.001 seconds) done. [ 66.280708] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done. [ 66.281906] PM: Entering mem sleep [ 66.281936] Suspending console(s) (use no_console_suspend to debug) [ 66.282217] wlp1s0: deauthenticating from 3c:46:d8:1a:19:62 by local choice (Reason: 3=DEAUTH_LEAVING) [ 66.315814] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 66.315987] sd 0:0:0:0: [sda] Stopping disk [ 66.316053] cfg80211: Exceeded CRDA call max attempts. Not calling CRDA [ 66.332021] e1000e: EEE TX LPI TIMER: 00000011 [ 66.785234] PM: suspend of devices complete after 502.950 msecs [ 66.805226] PM: late suspend of devices complete after 19.975 msecs [ 66.806565] ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI [ 66.806829] ehci-pci 0000:00:1a.0: System wakeup enabled by ACPI [ 66.825299] PM: noirq suspend of devices complete after 20.061 msecs [ 66.825888] ACPI: Preparing to enter system sleep state S3 [ 67.505180] ACPI : EC: EC stopped [ 67.505180] PM: Saving platform NVS memory [ 67.505181] Disabling non-boot CPUs ... [ 67.505211] intel_pstate CPU 1 exiting [ 67.506406] kvm: disabling virtualization on CPU1 [ 67.506413] smpboot: CPU 1 is now offline [ 67.506682] intel_pstate CPU 2 exiting [ 67.508093] kvm: disabling virtualization on CPU2 [ 67.508107] smpboot: CPU 2 is now offline [ 67.508859] intel_pstate CPU 3 exiting [ 67.510203] kvm: disabling virtualization on CPU3 [ 67.510218] smpboot: CPU 3 is now offline [ 67.512248] ACPI: Low-level resume complete [ 67.512289] ACPI : EC: EC started [ 67.512290] PM: Restoring platform NVS memory [ 67.512604] Enabling non-boot CPUs ... [ 67.512644] x86: Booting SMP configuration: [ 67.512645] smpboot: Booting Node 0 Processor 1 APIC 0x1 [ 67.523727] kvm: enabling virtualization on CPU1 [ 67.525906] cache: parent cpu1 should not be sleeping [ 67.526052] CPU1 is up [ 67.526075] smpboot: Booting Node 0 Processor 2 APIC 0x2 [ 67.537147] kvm: enabling virtualization on CPU2 [ 67.539310] cache: parent cpu2 should not be sleeping [ 67.539448] CPU2 is up [ 67.539489] smpboot: Booting Node 0 Processor 3 APIC 0x3 [ 67.550565] kvm: enabling virtualization on CPU3 [ 67.552771] cache: parent cpu3 should not be sleeping [ 67.552922] CPU3 is up [ 67.555854] ACPI: Waking up from system sleep state S3 [ 68.067403] ehci-pci 0000:00:1a.0: System wakeup disabled by ACPI [ 68.067470] ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI [ 68.067598] sdhci-pci 0000:03:00.0: MMC controller base frequency changed to 50Mhz. [ 68.067690] PM: noirq resume of devices complete after 20.649 msecs [ 68.067771] pciehp 0000:00:1c.3:pcie04: slot(0): Link Up event [ 68.068020] PM: early resume of devices complete after 0.282 msecs [ 68.070653] iwlwifi 0000:01:00.0: RF_KILL bit toggled to enable radio. [ 68.087114] sd 0:0:0:0: [sda] Starting disk [ 68.177097] pciehp 0000:00:1c.3:pcie04: Device 0000:03:00.0 already exists at 0000:03:00, cannot hot-add [ 68.177101] pciehp 0000:00:1c.3:pcie04: Cannot add device at 0000:03:00 [ 68.287161] pciehp 0000:00:1c.3:pcie04: Device 0000:03:00.0 already exists at 0000:03:00, cannot hot-add [ 68.287164] pciehp 0000:00:1c.3:pcie04: Cannot add device at 0000:03:00 [ 68.287219] rtc_cmos 00:03: System wakeup disabled by ACPI [ 68.307153] usb 1-1: reset high-speed USB device number 2 using ehci-pci [ 68.317172] usb 2-1.5: reset full-speed USB device number 3 using ehci-pci [ 68.437177] ata6: SATA link down (SStatus 0 SControl 300) [ 68.437208] ata3: SATA link down (SStatus 0 SControl 300) [ 68.437237] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 68.437268] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [ 68.437289] ata5: SATA link down (SStatus 0 SControl 300) [ 68.439318] ata1.00: configured for UDMA/133 [ 68.442874] ata2.00: configured for UDMA/66 [ 68.757265] usb 1-1.6: reset high-speed USB device number 4 using ehci-pci [ 68.947265] usb 1-1.4: reset full-speed USB device number 3 using ehci-pci [ 69.060586] iwlwifi 0000:01:00.0: L1 Disabled - LTR Disabled [ 69.060755] iwlwifi 0000:01:00.0: Radio type=0x1-0x2-0x0 [ 69.342237] iwlwifi 0000:01:00.0: L1 Disabled - LTR Disabled [ 69.342397] iwlwifi 0000:01:00.0: Radio type=0x1-0x2-0x0 [ 69.435149] PM: resume of devices complete after 1366.884 msecs [ 69.446787] PM: Finishing wakeup. [ 69.446792] Restarting tasks ... done. [ 69.465449] video LNXVIDEO:00: Restoring backlight state [ 69.477329] systemd[1]: suspend.target: Unit is bound to inactive unit systemd-suspend.service. Stopping, too. [ 70.042984] cfg80211: Exceeded CRDA call max attempts. Not calling CRDA [ 72.259114] cfg80211: Exceeded CRDA call max attempts. Not calling CRDA [ 72.420764] cfg80211: Exceeded CRDA call max attempts. Not calling CRDA [ 72.522886] cfg80211: Exceeded CRDA call max attempts. Not calling CRDA [ 72.666191] wlp1s0: authenticate with 3c:46:d8:1a:19:62 [ 72.668433] wlp1s0: send auth to 3c:46:d8:1a:19:62 (try 1/3) [ 72.671139] wlp1s0: authenticated [ 72.677840] wlp1s0: associate with 3c:46:d8:1a:19:62 (try 1/3) [ 72.681646] wlp1s0: RX AssocResp from 3c:46:d8:1a:19:62 (capab=0x431 status=0 aid=3) [ 72.683597] wlp1s0: associated [ 85.730120] usb 1-2: new high-speed USB device number 6 using ehci-pci [ 85.860142] usb 1-2: device descriptor read/64, error -71 [ 86.100184] usb 1-2: device descriptor read/64, error -71 [ 86.330229] usb 1-2: new high-speed USB device number 7 using ehci-pci [ 86.460243] usb 1-2: device descriptor read/64, error -71 [ 86.700289] usb 1-2: device descriptor read/64, error -71 [ 86.930326] usb 1-2: new high-speed USB device number 8 using ehci-pci [ 87.350397] usb 1-2: device not accepting address 8, error -71 [ 87.470432] usb 1-2: new high-speed USB device number 9 using ehci-pci [ 87.890492] usb 1-2: device not accepting address 9, error -71 [ 87.893252] usb usb1-port2: unable to enumerate USB device [ 97.286335] ehci-pci 0000:00:1d.0: remove, state 1 [ 97.286357] usb usb2: USB disconnect, device number 1 [ 97.286365] usb 2-1: USB disconnect, device number 2 [ 97.286370] usb 2-1.5: USB disconnect, device number 3 [ 97.294383] ehci-pci 0000:00:1d.0: USB bus 2 deregistered [ 97.294649] ehci-pci 0000:00:1a.0: remove, state 1 [ 97.294665] usb usb1: USB disconnect, device number 1 [ 97.294671] usb 1-1: USB disconnect, device number 2 [ 97.294676] usb 1-1.4: USB disconnect, device number 3 [ 97.295629] usb 1-1.6: USB disconnect, device number 4 [ 97.400383] ehci-pci 0000:00:1a.0: USB bus 1 deregistered [ 101.190750] ehci-pci: EHCI PCI platform driver [ 101.191032] ehci-pci 0000:00:1a.0: EHCI Host Controller [ 101.191048] ehci-pci 0000:00:1a.0: new USB bus registered, assigned bus number 1 [ 101.191074] ehci-pci 0000:00:1a.0: debug port 2 [ 101.195008] ehci-pci 0000:00:1a.0: cache line size of 64 is not supported [ 101.195031] ehci-pci 0000:00:1a.0: irq 21, io mem 0xe1926800 [ 101.212790] ehci-pci 0000:00:1a.0: USB 2.0 started, EHCI 1.00 [ 101.213944] hub 1-0:1.0: USB hub found [ 101.213977] hub 1-0:1.0: 3 ports detected [ 101.273056] ehci-pci 0000:00:1d.0: EHCI Host Controller [ 101.273076] ehci-pci 0000:00:1d.0: new USB bus registered, assigned bus number 2 [ 101.273100] ehci-pci 0000:00:1d.0: debug port 2 [ 101.277019] ehci-pci 0000:00:1d.0: cache line size of 64 is not supported [ 101.277041] ehci-pci 0000:00:1d.0: irq 19, io mem 0xe1926c00 [ 101.292784] ehci-pci 0000:00:1d.0: USB 2.0 started, EHCI 1.00 [ 101.293975] hub 2-0:1.0: USB hub found [ 101.294025] hub 2-0:1.0: 3 ports detected [ 101.532761] usb 1-1: new high-speed USB device number 2 using ehci-pci [ 101.612840] usb 2-1: new high-speed USB device number 2 using ehci-pci [ 101.684209] hub 1-1:1.0: USB hub found [ 101.684325] hub 1-1:1.0: 6 ports detected [ 101.763844] hub 2-1:1.0: USB hub found [ 101.763942] hub 2-1:1.0: 8 ports detected [ 101.962917] usb 1-1.4: new full-speed USB device number 3 using ehci-pci [ 102.042922] usb 2-1.5: new full-speed USB device number 3 using ehci-pci [ 102.152946] usb 1-1.6: new high-speed USB device number 4 using ehci-pci [ 102.271604] uvcvideo: Found UVC 1.00 device Integrated Camera (04f2:b221) [ 102.274115] input: Integrated Camera as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.6/1-1.6:1.0/input/input17 [ 105.783567] usb 1-1.2: new high-speed USB device number 5 using ehci-pci [ 105.900996] usb-storage 1-1.2:1.0: USB Mass Storage device detected [ 105.901207] scsi host7: usb-storage 1-1.2:1.0 [ 107.056559] scsi 7:0:0:0: Direct-Access JetFlash Transcend 32GB 1100 PQ: 0 ANSI: 6 [ 107.058493] sd 7:0:0:0: [sdb] 62914560 512-byte logical blocks: (32.2 GB/30.0 GiB) [ 107.059643] sd 7:0:0:0: [sdb] Write Protect is off [ 107.059655] sd 7:0:0:0: [sdb] Mode Sense: 43 00 00 00 [ 107.060745] sd 7:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 107.069391] sdb: sdb1 [ 107.073440] sd 7:0:0:0: [sdb] Attached SCSI removable disk
-- coreboot mailing list: [email protected] http://www.coreboot.org/mailman/listinfo/coreboot
