Re: Dell XPS 13 9360: PM: Device 0000:39:00.0 failed to resume async: error -19

2020-07-08 Thread Paul Menzel

Dear Greg,


Am 30.06.20 um 10:42 schrieb Greg KH:

On Mon, Jun 29, 2020 at 10:30:59PM +0200, Paul Menzel wrote:



On the Dell XPS 13 9360 with Ubuntu 20.04 LTS and Linux 5.4.0-39-generic,


That is an old kernel (and a distro one), can you please try 5.7.6 from
kernel.org?


Trying Linux 5.8-rc4 from the Ubuntu Linux Kernel Mainline PPA [1], 
Linux logged the messages below for device :39:00.0 on each of the 
50 resumes.


[   96.057592] xhci_hcd :39:00.0: Timeout while waiting for 
setup device command
[  101.433589] xhci_hcd :39:00.0: Timeout while waiting for 
setup device command


In seven of the 50 cases, the timeout messages below was also logged.

[   51.431713] xhci_hcd :00:14.0: port 2 resume PLC timeout

Please find the log messages attached.


Kind regards,

Paul


[1]: https://kernel.ubuntu.com/~kernel-ppa/mainline/v5.8-rc4/
# suspend-070820-152008 Ixpees mem 5.8.0-050800-generic
# sysinfo | man:Dell Inc. | plat:XPS 13 9360 | cpu:Intel(R) Core(TM) i7-7500U 
CPU @ 2.70GHz | bios:2.13.0 | biosdate:11/14/2019 | numcpu:4 | memsz:15896892 | 
memfr:14152840
# command | sleepgraph.py -config config/suspend.cfg -multi 50 15
# fwsuspend 0 fwresume 2706432
[   49.291995] PM: suspend entry (deep)
[   49.319081] Filesystems sync: 0.027 seconds
[   49.319084] PM: Preparing system for sleep (deep)
[   49.320115] Freezing user space processes ... (elapsed 0.001 seconds) done.
[   49.322052] OOM killer disabled.
[   49.322055] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) 
done.
[   49.323356] PM: Suspending system (deep)
[   49.323405] printk: Suspending console(s) (use no_console_suspend to debug)
[   49.324078] input input32: calling input_dev_suspend+0x0/0x50 @ 2478, 
parent: 1-5:1.0
[   49.324081] input input32: input_dev_suspend+0x0/0x50 returned 0 after 0 
usecs
[   49.324085] input input30: calling input_dev_suspend+0x0/0x50 @ 2478, 
parent: 0003:04F3:2234.0002
[   49.324087] input input30: input_dev_suspend+0x0/0x50 returned 0 after 0 
usecs
[   49.324089] input input29: calling input_dev_suspend+0x0/0x50 @ 2478, 
parent: 0003:04F3:2234.0002
[   49.324091] input input29: input_dev_suspend+0x0/0x50 returned 0 after 0 
usecs
[   49.324093] input input28: calling input_dev_suspend+0x0/0x50 @ 2478, 
parent: 0003:04F3:2234.0002
[   49.324095] input input28: input_dev_suspend+0x0/0x50 returned 0 after 0 
usecs
[   49.324099] input input26: calling input_dev_suspend+0x0/0x50 @ 2478, 
parent: 0018:06CB:76AF.0001
[   49.324101] input input26: input_dev_suspend+0x0/0x50 returned 0 after 0 
usecs
[   49.324103] input input25: calling input_dev_suspend+0x0/0x50 @ 2478, 
parent: 0018:06CB:76AF.0001
[   49.324105] input input25: input_dev_suspend+0x0/0x50 returned 0 after 0 
usecs
[   49.324108] rfkill rfkill1: calling rfkill_suspend+0x0/0x20 @ 2478, parent: 
hci0
[   49.324110] rfkill rfkill1: rfkill_suspend+0x0/0x20 returned 0 after 0 usecs
[   49.324155] i2c_hid i2c-DLL075B:01: calling acpi_subsys_suspend+0x0/0x60 @ 
8, parent: i2c-8
[   49.324720] i2c_hid i2c-DLL075B:01: acpi_subsys_suspend+0x0/0x60 returned 0 
after 548 usecs
[   49.324734] coretemp coretemp.0: calling platform_pm_suspend+0x0/0x50 @ 
2478, parent: platform
[   49.324737] coretemp coretemp.0: platform_pm_suspend+0x0/0x50 returned 0 
after 1 usecs
[   49.324742] rfkill rfkill0: calling rfkill_suspend+0x0/0x20 @ 2478, parent: 
phy0
[   49.324744] usb 3-1.3: calling usb_dev_suspend+0x0/0x20 @ 115, parent: 3-1
[   49.324745] rfkill rfkill0: rfkill_suspend+0x0/0x20 returned 0 after 0 usecs
[   49.324749] usb 3-1.3: usb_dev_suspend+0x0/0x20 returned 0 after 3 usecs
[   49.324770] ieee80211 phy0: calling wiphy_suspend+0x0/0x290 [cfg80211] @ 
115, parent: :3a:00.0
[   49.324772] usb 4-1.4: calling usb_dev_suspend+0x0/0x20 @ 8, parent: 4-1
[   49.324775] leds dell::kbd_backlight: calling led_suspend+0x0/0x30 @ 2478, 
parent: dell-laptop
[   49.324776] usb 1-4: calling usb_dev_suspend+0x0/0x20 @ 341, parent: usb1
[   49.324777] wlp58s0: deauthenticating from 6c:f3:7f:10:a0:fa by local choice 
(Reason: 3=DEAUTH_LEAVING)
[   49.324778] leds dell::kbd_backlight: led_suspend+0x0/0x30 returned 0 after 
0 usecs
[   49.324787] dell-laptop dell-laptop: calling platform_pm_suspend+0x0/0x50 @ 
2478, parent: platform
[   49.324797] usb 1-5: calling usb_dev_suspend+0x0/0x20 @ 201, parent: usb1
[   49.324800] usb 1-5: usb_dev_suspend+0x0/0x20 returned 0 after 1 usecs
[   49.324803] dell-laptop dell-laptop: platform_pm_suspend+0x0/0x50 returned 0 
after 0 usecs
[   49.324805] usb 3-1: calling usb_dev_suspend+0x0/0x20 @ 201, parent: usb3
[   49.324806] usb 1-4: usb_dev_suspend+0x0/0x20 returned 0 after 26 usecs
[   49.324809] input input17: calling input_dev_suspend+0x0/0x50 @ 2478, 
parent: 9DBB5994-A997-11DA-B012-B622A1EF5492
[   49.324812] input input17: input_dev_suspend+0x0/0x50 returned 0 after 1 
usecs
[   49.324814] usb 1-3: calling usb_dev_suspend+0x0/0x20 @ 341, parent: usb1
[   49.324816] dell-smbios 

Re: Dell XPS 13 9360: PM: Device 0000:39:00.0 failed to resume async: error -19

2020-06-30 Thread Greg KH
On Mon, Jun 29, 2020 at 10:30:59PM +0200, Paul Menzel wrote:
> Dear Linux folks,
> 
> 
> On the Dell XPS 13 9360 with Ubuntu 20.04 LTS and Linux 5.4.0-39-generic,

That is an old kernel (and a distro one), can you please try 5.7.6 from
kernel.org?

thanks,

greg k-h


Dell XPS 13 9360: PM: Device 0000:39:00.0 failed to resume async: error -19

2020-06-29 Thread Paul Menzel

Dear Linux folks,


On the Dell XPS 13 9360 with Ubuntu 20.04 LTS and Linux 
5.4.0-39-generic, testing suspend/resume with `sudo ./sleepgraph.py 
-config config/suspend.cfg -multi 50 15` the failure below happened *once*.



[  535.034086] xhci_hcd :39:00.0: calling pci_pm_resume+0x0/0xa0 @ 2584, 
parent: :02:02.0
[  535.034172] rtsx_pci :3b:00.0: pci_pm_resume+0x0/0xa0 returned 0 after 
988 usecs
[  535.036252] mei_me :00:16.0: pci_pm_resume+0x0/0xa0 returned 0 after 
3253 usecs
[  535.053868] xhci_hcd :39:00.0: Refused to change power state, currently 
in D3
[  535.053890] xhci_hcd :39:00.0: Controller not ready at resume -19
[  535.053891] xhci_hcd :39:00.0: PCI post-resume error -19!
[  535.053892] xhci_hcd :39:00.0: HC died; cleaning up
[  535.053907] PM: dpm_run_callback(): pci_pm_resume+0x0/0xa0 returns -19
[  535.053910] xhci_hcd :39:00.0: pci_pm_resume+0x0/0xa0 returned -19 after 
19366 usecs
[  535.053917] PM: Device :39:00.0 failed to resume async: error -19


[…]


[  535.992968] PM: Finishing wakeup.
[  535.992972] OOM killer enabled.
[  535.992973] Restarting tasks ... 
[  535.992991] xhci_hcd :39:00.0: remove, state 4

[  535.993000] usb usb4: USB disconnect, device number 1
[  535.993494] xhci_hcd :39:00.0: USB bus 4 deregistered
[  535.993509] xhci_hcd :39:00.0: remove, state 4
[  535.993515] usb usb3: USB disconnect, device number 1
[  535.998941] done.
[  536.002354] xhci_hcd :39:00.0: Host halt failed, -19
[  536.002357] xhci_hcd :39:00.0: Host not accessible, reset failed.
[  536.002443] xhci_hcd :39:00.0: USB bus 3 deregistered
[  536.158698] PM: suspend exit


Is that a Linux driver, or device/firmware problem?


Kind regards,

Paul
# suspend-062920-134258 Ixpees mem 5.4.0-39-generic
# sysinfo | man:Dell Inc. | plat:XPS 13 9360 | cpu:Intel(R) Core(TM) i7-7500U 
CPU @ 2.70GHz | bios:2.13.0 | biosdate:11/14/2019 | numcpu:4 | memsz:15901316 | 
memfr:13430088
# command | sleepgraph.py -config config/suspend.cfg -multi 50 15
# fwsuspend 0 fwresume 1548751
[  533.119362] PM: suspend entry (deep)
[  533.154898] Filesystems sync: 0.035 seconds
[  533.154905] PM: Preparing system for sleep (deep)
[  533.155595] Freezing user space processes ... (elapsed 0.004 seconds) done.
[  533.159624] OOM killer disabled.
[  533.159631] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) 
done.
[  533.161050] PM: Suspending system (deep)
[  533.161138] printk: Suspending console(s) (use no_console_suspend to debug)
[  533.179681] rfkill rfkill10: calling rfkill_suspend+0x0/0x20 @ 2968, parent: 
hci0
[  533.179693] rfkill rfkill10: rfkill_suspend+0x0/0x20 returned 0 after 4 usecs
[  533.179734] serio serio1: calling serio_suspend+0x0/0x20 @ 2968, parent: 
i8042
[  533.179745] serio serio1: serio_suspend+0x0/0x20 returned 0 after 3 usecs
[  533.179771] usb usb4: calling usb_dev_suspend+0x0/0x20 @ 2559, parent: 
:39:00.0
[  533.179786] input input31: calling input_dev_suspend+0x0/0x50 @ 2968, 
parent: 0018:06CB:76AF.0002
[  533.179799] input input31: input_dev_suspend+0x0/0x50 returned 0 after 4 
usecs
[  533.179810] thunderbolt :03:00.0: calling pci_pm_suspend+0x0/0x150 @ 
2573, parent: :02:00.0
[  533.179834] input input30: calling input_dev_suspend+0x0/0x50 @ 2968, 
parent: 0018:06CB:76AF.0002
[  533.179846] pcieport :02:01.0: calling pci_pm_suspend+0x0/0x150 @ 2576, 
parent: :01:00.0
[  533.179853] input input30: input_dev_suspend+0x0/0x50 returned 0 after 3 
usecs
[  533.179865] pcieport :02:01.0: pci_pm_suspend+0x0/0x150 returned 0 after 
7 usecs
[  533.179873] input input28: calling input_dev_suspend+0x0/0x50 @ 2968, 
parent: 0003:04F3:2234.0001
[  533.179884] input input28: input_dev_suspend+0x0/0x50 returned 0 after 2 
usecs
[  533.179895] input input27: calling input_dev_suspend+0x0/0x50 @ 2968, 
parent: 0003:04F3:2234.0001
[  533.179902] usb usb3: calling usb_dev_suspend+0x0/0x20 @ 2545, parent: 
:39:00.0
[  533.179908] input input27: input_dev_suspend+0x0/0x50 returned 0 after 2 
usecs
[  533.179921] input input26: calling input_dev_suspend+0x0/0x50 @ 2968, 
parent: 0003:04F3:2234.0001
[  533.179933] input input26: input_dev_suspend+0x0/0x50 returned 0 after 2 
usecs
[  533.179957] leds platform::micmute: calling led_suspend+0x0/0x30 @ 2968, 
parent: dell-laptop
[  533.179963] leds platform::micmute: led_suspend+0x0/0x30 returned 0 after 2 
usecs
[  533.179971] leds dell::kbd_backlight: calling led_suspend+0x0/0x30 @ 2968, 
parent: dell-laptop
[  533.179979] leds dell::kbd_backlight: led_suspend+0x0/0x30 returned 0 after 
2 usecs
[  533.179994] rfkill rfkill1: calling rfkill_suspend+0x0/0x20 @ 2968, parent: 
phy0
[  533.180004] rfkill rfkill1: rfkill_suspend+0x0/0x20 returned 0 after 2 usecs
[  533.180053] i2c_hid i2c-DLL075B:01: calling acpi_subsys_suspend+0x0/0x60 @ 
2576, parent: i2c-8
[  533.180113] ieee80211 phy0: calling wiphy_suspend+0x0/0x290 [cfg80211] @ 
2580, parent: :3a:00.0
[