Bug#788622: systemd-logind regularly eats over 20% of system resources

[Martin-Éric Racine]

2015-06-13 18:42 GMT+03:00 Michael Biebl <biebl at debian.org>:
> Am 13.06.2015 um 17:19 schrieb Martin-Éric Racine:
>> $ sudo strace -t -p 29336 -o systemd-logind.txt
>>
>> Please find the result attached
>
> So, logind seems to be continuously (re)stating and reading your drm
> devices.
>
> Can you run "udevadm monitor"

Endless series of these:

KERNEL[4312.794011] change
/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0A:00/power_supply/BAT0
(power_supply)
UDEV  [4312.799277] change
/devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0A:00/power_supply/BAT0
(power_supply)

> and check the kernel log files if there are any events for the drm subsystem.

$ cat /proc/cmdline
BOOT_IMAGE=/boot/vmlinuz-3.16.0-4-686-pae
root=UUID=cd9a1998-9fb2-4208-aba6-bc2ba8f5e36a ro panic=15
video=TV-1:d video=SVIDEO-1:d video=LVDS-1:d quiet splash

$ dmesg | grep drm
[    2.045991] [drm] Initialized drm 1.1.0 20060810
[    2.182999] [drm] Memory usable by graphics device = 256M
[    2.183009] fb: switching to inteldrmfb from simple
[    2.185187] [drm] Replacing VGA console driver
[    2.202321] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    2.202326] [drm] Driver supports precise vblank timestamp query.
[    2.513285] [drm] initialized overlay support
[    2.513297] [drm] forcing LVDS-1 connector OFF
[    2.513302] [drm] forcing SVIDEO-1 connector OFF
[    2.619568] fbcon: inteldrmfb (fb0) is primary device
[    2.637624] [drm] Setting output timings on SDVOB failed
[    2.759475] i915 0000:00:02.0: fb0: inteldrmfb frame buffer device
[    2.759495] [drm] Initialized i915 1.6.0 20080730 for 0000:00:02.0 on minor 0
[ 2859.764071] [drm:i915_irq_handler] *ERROR* pipe B underrun
[ 3057.675351] [drm:i915_irq_handler] *ERROR* pipe B underrun
[ 3839.416073] [drm:i915_irq_handler] *ERROR* pipe B underrun

$ grep drm /var/log/kern.log

Jun 13 18:21:09 suomi kernel: [17696.833541] [drm] Setting output
timings on SDVOB failed
Jun 13 18:21:09 suomi kernel: [17696.833541] [drm] Setting output
timings on SDVOB failed
Jun 13 18:21:11 suomi kernel: [17699.128518] [drm] Setting output
timings on SDVOB failed
Jun 13 18:21:11 suomi kernel: [17699.128518] [drm] Setting output
timings on SDVOB failed
Jun 13 18:22:59 suomi kernel: [    2.045991] [drm] Initialized drm
1.1.0 20060810
Jun 13 18:22:59 suomi kernel: [    2.182999] [drm] Memory usable by
graphics device = 256M
Jun 13 18:22:59 suomi kernel: [    2.183009] fb: switching to
inteldrmfb from simple
Jun 13 18:22:59 suomi kernel: [    2.185187] [drm] Replacing VGA console driver
Jun 13 18:22:59 suomi kernel: [    2.202321] [drm] Supports vblank
timestamp caching Rev 2 (21.10.2013).
Jun 13 18:22:59 suomi kernel: [    2.202326] [drm] Driver supports
precise vblank timestamp query.
Jun 13 18:22:59 suomi kernel: [    2.513285] [drm] initialized overlay support
Jun 13 18:22:59 suomi kernel: [    2.513297] [drm] forcing LVDS-1 connector OFF
Jun 13 18:22:59 suomi kernel: [    2.513302] [drm] forcing SVIDEO-1
connector OFF
Jun 13 18:22:59 suomi kernel: [    2.619568] fbcon: inteldrmfb (fb0)
is primary device
Jun 13 18:22:59 suomi kernel: [    2.637624] [drm] Setting output
timings on SDVOB failed
Jun 13 18:22:59 suomi kernel: [    2.759475] i915 0000:00:02.0: fb0:
inteldrmfb frame buffer device
Jun 13 18:22:59 suomi kernel: [    2.759495] [drm] Initialized i915
1.6.0 20080730 for 0000:00:02.0 on minor 0
Jun 13 18:22:59 suomi kernel: [    2.045991] [drm] Initialized drm
1.1.0 20060810
Jun 13 18:22:59 suomi kernel: [    2.182999] [drm] Memory usable by
graphics device = 256M
Jun 13 18:22:59 suomi kernel: [    2.183009] fb: switching to
inteldrmfb from simple
Jun 13 18:22:59 suomi kernel: [    2.185187] [drm] Replacing VGA console driver
Jun 13 18:22:59 suomi kernel: [    2.202321] [drm] Supports vblank
timestamp caching Rev 2 (21.10.2013).
Jun 13 18:22:59 suomi kernel: [    2.202326] [drm] Driver supports
precise vblank timestamp query.
Jun 13 18:22:59 suomi kernel: [    2.513285] [drm] initialized overlay support
Jun 13 18:22:59 suomi kernel: [    2.513297] [drm] forcing LVDS-1 connector OFF
Jun 13 18:22:59 suomi kernel: [    2.513302] [drm] forcing SVIDEO-1
connector OFF
Jun 13 18:22:59 suomi kernel: [    2.619568] fbcon: inteldrmfb (fb0)
is primary device
Jun 13 18:22:59 suomi kernel: [    2.637624] [drm] Setting output
timings on SDVOB failed
Jun 13 18:22:59 suomi kernel: [    2.759475] i915 0000:00:02.0: fb0:
inteldrmfb frame buffer device
Jun 13 18:22:59 suomi kernel: [    2.759495] [drm] Initialized i915
1.6.0 20080730 for 0000:00:02.0 on minor 0
Jun 13 19:09:44 suomi kernel: [ 2859.764071] [drm:i915_irq_handler]
*ERROR* pipe B underrun
Jun 13 19:09:44 suomi kernel: [ 2859.764071] [drm:i915_irq_handler]
*ERROR* pipe B underrun
Jun 13 19:13:02 suomi kernel: [ 3057.675351] [drm:i915_irq_handler]
*ERROR* pipe B underrun
Jun 13 19:13:02 suomi kernel: [ 3057.675351] [drm:i915_irq_handler]
*ERROR* pipe B underrun
Jun 13 19:26:04 suomi kernel: [ 3839.416073] [drm:i915_irq_handler]
*ERROR* pipe B underrun
Jun 13 19:26:04 suomi kernel: [ 3839.416073] [drm:i915_irq_handler]
*ERROR* pipe B underrun

If there's anything else you need, please let me know.

-- Martin-Éric