Hi! I just noticed I hit the wrong reply button. Here's what I replied to Alexander: I just tried `echo 0 > /sys/bus/usb/devices/2-4/power/persist`, however the card reader is still missing after suspend/resume. Here's the new kernel log: [ 2241.861114] pcieport 0000:00:1c.3: Enabling MPC IRBNCE [ 2241.861120] pcieport 0000:00:1c.3: Intel PCH root port ACS workaround enabled [ 2241.915528] PM: suspend entry (deep) [ 2241.915529] PM: Syncing filesystems ... done. [ 2241.925562] Freezing user space processes ... (elapsed 0.001 seconds) done. [ 2241.926816] OOM killer disabled. [ 2241.926817] Freezing remaining freezable tasks ... (elapsed 0.000 seconds) done. [ 2241.927583] Suspending console(s) (use no_console_suspend to debug) [ 2241.927818] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 2241.931991] sd 0:0:0:0: [sda] Stopping disk [ 2244.178848] thunderbolt 0000:08:00.0: suspending... [ 2244.179533] thunderbolt 0000:08:00.0: suspend finished [ 2244.179535] thunderbolt 0000:08:00.0: stopping RX ring 0 [ 2244.179541] thunderbolt 0000:08:00.0: disabling interrupt at register 0x38200 bit 12 (0x1001 -> 0x1) [ 2244.179547] thunderbolt 0000:08:00.0: stopping TX ring 0 [ 2244.179552] thunderbolt 0000:08:00.0: disabling interrupt at register 0x38200 bit 0 (0x1 -> 0x0) [ 2244.179555] thunderbolt 0000:08:00.0: control channel stopped [ 2245.127874] ACPI: Preparing to enter system sleep state S3 [ 2245.147745] ACPI: EC: event blocked [ 2245.147746] ACPI: EC: EC stopped [ 2245.147746] PM: Saving platform NVS memory [ 2245.147751] Disabling non-boot CPUs ... [ 2245.176484] smpboot: CPU 1 is now offline [ 2245.218761] smpboot: CPU 2 is now offline [ 2245.261380] smpboot: CPU 3 is now offline [ 2245.289072] smpboot: CPU 4 is now offline [ 2245.319017] smpboot: CPU 5 is now offline [ 2245.345797] smpboot: CPU 6 is now offline [ 2245.362552] smpboot: CPU 7 is now offline [ 2245.393870] ACPI: Low-level resume complete [ 2245.393931] ACPI: EC: EC started [ 2245.393932] PM: Restoring platform NVS memory [ 2245.396492] Enabling non-boot CPUs ... [ 2245.396546] x86: Booting SMP configuration: [ 2245.396547] smpboot: Booting Node 0 Processor 1 APIC 0x2 [ 2245.476842] cache: parent cpu1 should not be sleeping [ 2245.660075] CPU1 is up [ 2245.660120] smpboot: Booting Node 0 Processor 2 APIC 0x4 [ 2245.733856] cache: parent cpu2 should not be sleeping [ 2245.908088] CPU2 is up [ 2245.908132] smpboot: Booting Node 0 Processor 3 APIC 0x6 [ 2245.977616] cache: parent cpu3 should not be sleeping [ 2246.147615] CPU3 is up [ 2246.147661] smpboot: Booting Node 0 Processor 4 APIC 0x1 [ 2246.154023] cache: parent cpu4 should not be sleeping [ 2246.154213] CPU4 is up [ 2246.154236] smpboot: Booting Node 0 Processor 5 APIC 0x3 [ 2246.218683] cache: parent cpu5 should not be sleeping [ 2246.408703] CPU5 is up [ 2246.408730] smpboot: Booting Node 0 Processor 6 APIC 0x5 [ 2246.474887] cache: parent cpu6 should not be sleeping [ 2246.691669] CPU6 is up [ 2246.691698] smpboot: Booting Node 0 Processor 7 APIC 0x7 [ 2246.762074] cache: parent cpu7 should not be sleeping [ 2247.013633] CPU7 is up [ 2247.091979] ACPI: Waking up from system sleep state S3 [ 2247.152327] pcieport 0000:00:1c.0: Enabling MPC IRBNCE [ 2247.152330] pcieport 0000:00:1c.0: Intel PCH root port ACS workaround enabled [ 2247.152552] pcieport 0000:07:04.0: quirk: waiting for thunderbolt to reestablish PCI tunnels... [ 2247.152568] pcieport 0000:07:03.0: quirk: waiting for thunderbolt to reestablish PCI tunnels... [ 2247.152570] pcieport 0000:07:05.0: quirk: waiting for thunderbolt to reestablish PCI tunnels... [ 2247.152633] pcieport 0000:07:06.0: quirk: waiting for thunderbolt to reestablish PCI tunnels... [ 2247.168603] pcieport 0000:00:1c.3: Enabling MPC IRBNCE [ 2247.168609] pcieport 0000:00:1c.2: Enabling MPC IRBNCE [ 2247.168610] pcieport 0000:00:1c.3: Intel PCH root port ACS workaround enabled [ 2247.168616] pcieport 0000:00:1c.2: Intel PCH root port ACS workaround enabled [ 2247.168730] thunderbolt 0000:08:00.0: control channel starting... [ 2247.168733] thunderbolt 0000:08:00.0: starting TX ring 0 [ 2247.168748] thunderbolt 0000:08:00.0: enabling interrupt at register 0x38200 bit 0 (0x0 -> 0x1) [ 2247.168750] thunderbolt 0000:08:00.0: starting RX ring 0 [ 2247.168766] thunderbolt 0000:08:00.0: enabling interrupt at register 0x38200 bit 12 (0x1 -> 0x1001) [ 2247.168770] thunderbolt 0000:08:00.0: resuming... [ 2247.168771] thunderbolt 0000:08:00.0: resetting switch at 0 [ 2247.176388] thunderbolt 0000:08:00.0: 0: resuming switch [ 2247.176883] thunderbolt 0000:08:00.0: resume finished [ 2247.228522] pcieport 0000:00:1c.4: Enabling MPC IRBNCE [ 2247.228525] pcieport 0000:00:1c.4: Intel PCH root port ACS workaround enabled [ 2247.229197] usb usb1: root hub lost power or was reset [ 2247.229200] usb usb2: root hub lost power or was reset [ 2247.229265] ACPI: EC: event unblocked [ 2247.229322] ACPI: button: The lid device is not compliant to SW_LID. [ 2247.239446] sd 0:0:0:0: [sda] Starting disk [ 2247.269903] thunderbolt 0000:08:00.0: resetting error on 0:b. [ 2247.269909] thunderbolt 0000:08:00.0: 0:b: hotplug: scanning [ 2247.269910] thunderbolt 0000:08:00.0: resetting error on 0:c. [ 2247.269911] thunderbolt 0000:08:00.0: 0:b: hotplug: no switch found [ 2247.269912] thunderbolt 0000:08:00.0: 0:c: hotplug: scanning [ 2247.269913] thunderbolt 0000:08:00.0: 0:c: hotplug: no switch found [ 2247.335049] pciehp 0000:07:00.0:pcie204: Device 0000:08:00.0 already exists at 0000:08:00, cannot hot-add [ 2247.335050] pciehp 0000:07:00.0:pcie204: Cannot add device at 0000:08:00 [ 2247.516486] nouveau 0000:01:00.0: devinit: 0x000066e5[0]: script needs OR link [ 2247.551596] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [ 2247.551821] ata1.00: unexpected _GTF length (8) [ 2247.552111] ata1.00: unexpected _GTF length (8) [ 2247.552184] ata1.00: configured for UDMA/133 [ 2247.578434] usb 1-12: reset full-speed USB device number 3 using xhci_hcd [ 2247.726451] xhci_hcd 0000:00:14.0: dma_pool_free buffer-512, ffff8f811c411000/ffc99000 (bad dma) [ 2247.841749] usb 1-8: reset full-speed USB device number 2 using xhci_hcd [ 2248.268557] usb 1-8.3: reset full-speed USB device number 6 using xhci_hcd [ 2250.335295] pciehp 0000:00:1c.0:pcie004: link training error: status 0x1001 [ 2250.335298] pciehp 0000:00:1c.0:pcie004: Failed to check link status [ 2250.335413] usb 1-8.3:1.0: rebind failed: -517 [ 2250.335472] usb 1-12:1.2: rebind failed: -517 [ 2250.335729] OOM killer enabled. [ 2250.335730] Restarting tasks ... [ 2250.341038] input: bcm5974 as /devices/pci0000:00/0000:00:14.0/usb1/1-12/1-12:1.2/input/input16 [ 2250.343289] done. [ 2250.456189] PM: suspend exit [ 2250.456361] Bluetooth: hci0: BCM: chip id 73 build 1068 [ 2250.457380] Bluetooth: hci0: BCM: product 05ac:8289 [ 2250.458357] Bluetooth: hci0: BCM: features 0x07 [ 2250.474387] Bluetooth: hci0: makkintosshu [ 2254.375591] usb usb2-port4: Cannot enable. Maybe the USB cable is bad? [ 2254.375607] xhci_hcd 0000:00:14.0: Cannot set link state. [ 2254.375612] usb usb2-port4: cannot disable (err = -32) [ 2254.375616] usb 2-4: USB disconnect, device number 2 [ 2258.582517] usb usb2-port4: Cannot enable. Maybe the USB cable is bad? [ 2258.582535] xhci_hcd 0000:00:14.0: Cannot set link state. [ 2258.582542] usb usb2-port4: cannot disable (err = -32) [ 2262.622680] usb usb2-port4: Cannot enable. Maybe the USB cable is bad? [ 2262.622701] xhci_hcd 0000:00:14.0: Cannot set link state. [ 2262.622710] usb usb2-port4: cannot disable (err = -32) [ 2266.662754] usb usb2-port4: Cannot enable. Maybe the USB cable is bad? [ 2266.662771] xhci_hcd 0000:00:14.0: Cannot set link state. [ 2266.662778] usb usb2-port4: cannot disable (err = -32) [ 2270.702747] usb usb2-port4: Cannot enable. Maybe the USB cable is bad? [ 2270.702757] xhci_hcd 0000:00:14.0: Cannot set link state. [ 2270.702760] usb usb2-port4: cannot disable (err = -32) [ 2274.742886] usb usb2-port4: Cannot enable. Maybe the USB cable is bad? [ 2274.742899] xhci_hcd 0000:00:14.0: Cannot set link state. [ 2274.742902] usb usb2-port4: cannot disable (err = -32) There are some messages missing in this log that were present in the original log. In particular: [ 38.002847] usb 2-4: Disable of device-initiated U1 failed. [ 38.002854] usb 2-4: Disable of device-initiated U2 failed. [ 38.002856] usb 2-4: usb_reset_and_verify_device Failed to disable LTM 2018-01-02 8:55 GMT+01:00 Alexander Kappner <agk@xxxxxxxxxxx>: > I've seen similar issues caused by USB persist. Try disabling it (echo 0 > /sys/bus/usb/devices/<your card reader>/power/persist), and then resuming. > > On 01/01/2018 10:53 PM, Samuel Sadok wrote: >> Hello! >> >> I'm observing a bug where the most prominent symptom is that the >> built-in SD card reader disappears after waking up from suspend. >> >> Laptop: MacBookPro11,3 >> USB controller: Intel Corporation 8 Series/C220 Series Chipset Family >> USB xHCI (rev 05) (prog-if 30 [XHCI]) >> SD card reader: Apple Card Reader 05ac:8406 >> >> The bug was reported previously on bugzilla but never followed up on: >> https://bugzilla.kernel.org/show_bug.cgi?id=111201 >> >> >> Steps to reproduce: >> $ lsusb >> Bus 002 Device 002: ID 05ac:8406 Apple, Inc. >> Bus 002 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub >> Bus 001 Device 006: ID 05ac:8289 Apple, Inc. >> Bus 001 Device 002: ID 0a5c:4500 Broadcom Corp. BCM2046B1 USB 2.0 Hub >> (part of BCM2046 Bluetooth) >> Bus 001 Device 003: ID 05ac:0263 Apple, Inc. Apple Internal Keyboard / >> Trackpad (MacBook Retina) >> Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub >> $ sudo systemctl suspend >> [Wait 10s, then wake the system up] >> $ lsusb >> Bus 002 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub >> Bus 001 Device 006: ID 05ac:8289 Apple, Inc. >> Bus 001 Device 002: ID 0a5c:4500 Broadcom Corp. BCM2046B1 USB 2.0 Hub >> (part of BCM2046 Bluetooth) >> Bus 001 Device 003: ID 05ac:0263 Apple, Inc. Apple Internal Keyboard / >> Trackpad (MacBook Retina) >> Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub >> $ lsusb -vvv >> >> Bus 002 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub >> [long hang, on the order of three minutes] >> can't get hub descriptor, LIBUSB_ERROR_TIMEOUT (Resource temporarily >> unavailable) >> [and a couple of more errors] >> >> Expected result: >> The output of both lsusb commands should be equal. >> >> Actual results: >> - The SD card reader (05ac:8406) is missing from the second lsusb. >> - Running lsusb -vvv hangs for a long time >> - lots of error messages in kernel log >> >> Logs: >> https://gist.github.com/samuelsadok/6d7b3e3015d3370a92ed4702e4d3c4b5 >> >> I'm happy to provide more data, do tests or patch around the kernel if it helps. >> >> Best, >> Samuel >> -- >> To unsubscribe from this list: send the line "unsubscribe linux-usb" in >> the body of a message to majordomo@xxxxxxxxxxxxxxx >> More majordomo info at http://vger.kernel.org/majordomo-info.html >> -- To unsubscribe from this list: send the line "unsubscribe linux-usb" in the body of a message to majordomo@xxxxxxxxxxxxxxx More majordomo info at http://vger.kernel.org/majordomo-info.html
