Bug 205013 - psmouse (serioN) occasionally takes over 50ms to suspend
Summary: psmouse (serioN) occasionally takes over 50ms to suspend
Status: NEW
Alias: None
Product: Drivers
Classification: Unclassified
Component: Input Devices (show other bugs)
Hardware: All Linux
: P1 normal
Assignee: Dmitry Torokhov
Depends on:
Blocks: 178231
  Show dependency tree
Reported: 2019-09-27 00:50 UTC by Todd Brandt
Modified: 2020-05-13 17:47 UTC (History)
1 user (show)

See Also:
Kernel Version: 5.3.0, 5.6.0, 5.7-rc4
Regression: No
Bisected commit-id:

otcpl-dell-p5510-xeon-2_mem_psmouse.html (1.11 MB, text/html)
2019-09-27 00:50 UTC, Todd Brandt
issue.def (472 bytes, text/plain)
2019-09-27 00:51 UTC, Todd Brandt
dell-9360-kbl_mem_#1_with_issue.html (787.94 KB, text/html)
2019-10-04 23:42 UTC, Todd Brandt
dell-9360-kbl_mem_#2_without_issue.html (507.68 KB, text/html)
2019-10-04 23:43 UTC, Todd Brandt
sleepgraph showing failure in Linux-5.7-rc4 (659.92 KB, text/html)
2020-05-08 17:53 UTC, Len Brown
sleepgraph showing SUCCES in Linux-5.7-rc4 on Dell 9300 (710.99 KB, text/html)
2020-05-08 18:18 UTC, Len Brown
issue.def -- for recording known suspend/resume stress test issues (412 bytes, text/plain)
2020-05-11 04:25 UTC, Len Brown

Description Todd Brandt 2019-09-27 00:50:09 UTC
Created attachment 285197 [details]

The psmouse on the Dell p5510 xeon 2 occasionally takes almost a second to suspend. This bug will monitor other systems for this issue as well.
Comment 1 Todd Brandt 2019-09-27 00:51:02 UTC
Created attachment 285199 [details]

monitor all psmouse devices that take longer than 50 ms to suspend
Comment 2 Todd Brandt 2019-10-04 23:37:36 UTC
On most machines, this issue only occurs on the first suspend (mem or freeze) and goes away for the rest. e.g.

Kernel  Machine                 mode-count      Issue Test #
5.3.0+	otcpl-dell-p5510-xeon-2	mem-x570	1
5.3.0+	otcpl-dell-p5510-xeon-2	freeze-x1093	1
5.3.0+	otcpl-dell-9380-cfl	mem-x2053	1
5.3.0+	otcpl-dell-p5510-xeon-1	mem-x2209	1
5.3.0+	otcpl-dell-7390-cmlu	mem-x2386	1
5.3.0+	otcpl-dell-p5510-xeon-1	freeze-x2431	1
5.3.0+	otcpl-dell-9380-cfl	freeze-x2441	1
5.3.0+	otcpl-dell-7390-cmlu	freeze-x2618	1
5.3.0+	otcpl-dell-9360-kbl	mem-x2821	1
5.3.0+	otcpl-dell-9360-kbl	freeze-x2859	1
Comment 3 Todd Brandt 2019-10-04 23:42:38 UTC
Created attachment 285341 [details]
Comment 4 Todd Brandt 2019-10-04 23:43:01 UTC
Created attachment 285343 [details]
Comment 5 Len Brown 2020-05-08 17:46:16 UTC
On my Dell 9300, the 1st suspend after reboot always gets this:

psmouse serio1: Failed to disable mouse on isa0060/serio1

and the device delays the suspend flow by 800 ms.
Comment 6 Len Brown 2020-05-08 17:53:15 UTC
Created attachment 289013 [details]
sleepgraph showing failure in Linux-5.7-rc4

In the attached sleepgraph output, you can hover over the schedule_timeout and udelay boxes in the psmouse1 section.

ps2_do_sendbyte() 201ms
i8042_waitwrite() 184ms
ps2_do_sendbyte() 203ms
ps2_do_sendbyte() 203ms
ps2_do_sendbyte() 203ms

All of these delays are synchronous and delay the entire machine's suspend progress, as we don't have enough work to run in parallel with these long delays.
Comment 7 Len Brown 2020-05-08 18:18:47 UTC
Created attachment 289015 [details]
sleepgraph showing SUCCES in Linux-5.7-rc4 on Dell 9300

Here is a successful sleepgraph result for a subsequent suspend cycle on
the same machine.  It was successful, in that serio1 did not add a measurable delay.

Indeed, you zoom in all the way, you can observe a very small block just before serio0, and it is serio1, which took only 0.001 msec!

All subsequent tests will be fast like this one.  But the problem will be reproduced every time on the 1st suspend after a reboot.
Comment 8 Len Brown 2020-05-11 04:25:28 UTC
Created attachment 289073 [details]
issue.def -- for recording known suspend/resume stress test issues

Note You need to log in before you can comment on or make changes to this bug.