[DE] GELÖST // KDE5-Problem:Lange Pause zwischen Login und Erscheinen des KDE-Desktop

[cs]

Hallo,

seit dem Update auf Plasma / KDE5 habe ich mit Siduction folgendes Problem:

Nach dem Login (egal ob mit SDDM oder LightDM) scheint mein eigentlich schnelles Notebook (Betriebssystem auf SSD) eine Denkpause von ca. 30 Sekunden einzulegen, bevor es weitergeht und der KDE-Desktop erscheint.

Das Phänomen wird laut entsprechender Internet-Threads wohl verschiedentlich mit KDE5 beobachtet: Mal ist plasma-nm schuld (https://bugs.launchpad.net/ubuntu/+source/plasma-nm/+bug/1509334), mal der Boot-Splash (https://bugs.launchpad.net/ubuntu/+source/breeze/+bug/1584604). Für mich hat leider kein Workaround gewirkt, z. B. nicht das Abschalten des Bootsplash.

Hat noch jemand das Problem oder könnte mir Hinweise geben, wie ich an das Problem herankomme?

Hier mein System:

System:    Host: cord-x230 Kernel: 4.13.2-towo.1-siduction-amd64 x86_64 bits: 64 gcc: 7.2.0
           Desktop: KDE Plasma 5.10.5 (Qt 5.9.1) dm: lightdm
           Distro: siduction 16.1.0 Patience - kde - (201612232347)
Machine:   Device: laptop System: LENOVO product: 2325CN9 v: ThinkPad X230 serial: N/A
           Mobo: LENOVO model: 2325CN9 serial: N/A UEFI [Legacy]: LENOVO v: G2ETA7WW (2.67 ) date: 09/09/2016
           Chassis: type: 10 serial: N/A
Battery    BAT0: charge: 86.3 Wh 96.8% condition: 89.2/93.2 Wh (96%) volts: 12.7/11.1
           model: LGC 45N1029 Li-ion serial: 15147 status: N/A cycles: 0
CPU:       Dual core Intel Core i7-3520M (-HT-MCP-) arch: Ivy Bridge rev.9 cache: 4096 KB
           flags: (lm nx sse sse2 sse3 sse4_1 sse4_2 ssse3 vmx) bmips: 11574
           clock speeds: min/max: 1200/2901 MHz 1: 2893 MHz 2: 2893 MHz 3: 2893 MHz 4: 2893 MHz
Memory:    Using dmidecode: root required for dmidecode
Graphics:  Card: Intel 3rd Gen Core processor Graphics Controller bus-ID: 00:02.0 chip-ID: 8086:0166
           Display Server: x11 (X.Org 1.19.3 ) drivers: modesetting (unloaded: fbdev,vesa)
           Resolution: 1366x768@60.02hz
           OpenGL: renderer: Mesa DRI Intel Ivybridge Mobile
           version: 4.2 Mesa 17.2.0 (compat-v: 3.0) Direct Render: Yes

[bevo]

Ich habe das gleiche Problem sowohl auf einem Desktop System als auch einem Laptop.
Habe leider auch keine Lösung.

bevo

[unklarer]

dito bei mir, auf Desktop.

Das ist erst nach hier https://forum.siduction.org/index.php?topic=6845.0  entstanden.
Bin auch am suchen und weiß noch nicht recht, wo ich anfangen soll   

Code: [Select]

$ systemd-analyze critical-chain
The time after the unit is active or started is printed after the "@" character.
The time the unit takes to start is printed after the "+" character.

graphical.target @28.105s
└─multi-user.target @28.105s
  └─hddtemp.service @27.508s +596ms
    └─network-online.target @27.506s
      └─NetworkManager-wait-online.service @22.505s +4.999s
        └─NetworkManager.service @17.272s +5.224s
          └─dbus.service @16.458s
            └─basic.target @16.453s
              └─sockets.target @16.453s
                └─cups.socket @16.453s
                  └─sysinit.target @16.441s
                    └─systemd-timesyncd.service @14.307s +2.133s
                      └─systemd-tmpfiles-setup.service @14.146s +142ms
                        └─local-fs.target @14.063s
                          └─tmp.mount @14.059s +3ms
                            └─swap.target @13.979s
                              └─dev-disk-by\x2duuid-045398f2\x2ddfbc\x2d4cc5\x2d99c5\x2ddca098051969.swap @13.890s +88ms
                                └─dev-disk-by\x2duuid-045398f2\x2ddfbc\x2d4cc5\x2d99c5\x2ddca098051969.device @13.889s

Code: [Select]

$ systemd-analyze blame | head
         11.396s dev-sda11.device
          8.391s systemd-journal-flush.service
          8.027s ModemManager.service
          7.910s apt-daily-upgrade.service
          7.212s udisks2.service
          5.224s NetworkManager.service
          5.020s systemd-udevd.service
          4.999s NetworkManager-wait-online.service
          3.433s accounts-daemon.service
          2.133s systemd-timesyncd.service

Code: [Select]

$ systemd-analyze
Startup finished in 18.933s (kernel) + 28.112s (userspace) = 47.046s

[melmarker]

Ne mögliche Lösung wäre wahrscheinlich:

Code: [Select]

systemctl mask network-manager.wait-online.serviceeinfach mal probieren und bitte Rückmeldung

[unklarer]

Danke für den Tip. Leider keine Veränderung.   

Code: [Select]

systemctl mask NetworkManager-wait-online.service
Created symlink /etc/systemd/system/NetworkManager-wait-online.service → /dev/null.
nach reboot

Code: [Select]

systemctl status NetworkManager-wait-online.service
● NetworkManager-wait-online.service
   Loaded: masked (/dev/null; bad)
   Active: inactive (dead)
Heute Mittag hatte ich schon den ModemManager.service disabeld. Ebenso 

[bevo]

Hier gleiches Ergebnis wie bei @unklarer.

[melmarker]

Schade eigentlich - ok, ich weigere mich jetzt einfach mal, launchpad bugs, die von tiefster Sachkenntnis geprägt sind, irgendeine Releavanz beizumessen.  können wir noch mal nen blame und nen critical path zu sda11 haben?

Disclaimer - es gab da einige "kleinere" Veränderungen im Bereich udisks

[unklarer]

Code: [Select]

$ systemd-analyze critical-chain
The time after the unit is active or started is printed after the "@" character.
The time the unit takes to start is printed after the "+" character.

graphical.target @20.693s
└─udisks2.service @16.275s +4.417s
  └─basic.target @15.239s
    └─sockets.target @15.239s
      └─avahi-daemon.socket @15.238s
        └─sysinit.target @15.217s
          └─systemd-timesyncd.service @13.624s +1.592s                                                                   
            └─systemd-tmpfiles-setup.service @13.362s +244ms                                                             
              └─systemd-journal-flush.service @2.581s +10.779s                                                             
                └─systemd-remount-fs.service @2.116s +463ms                                                                 
                  └─system.slice @2.034s                                                                                   
                    └─-.slice @2.006s

Code: [Select]

$ systemd-analyze blame
         10.779s systemd-journal-flush.service
          8.515s dev-sda11.device
          4.417s udisks2.service
          3.698s NetworkManager.service
          3.656s accounts-daemon.service
          2.027s networking.service
          1.956s colord.service
          1.891s preload.service
          1.891s dns-clean.service
          1.592s systemd-timesyncd.service
          1.321s lm-sensors.service
          1.188s systemd-tmpfiles-setup-dev.service
          1.155s systemd-udevd.service
          1.155s qemu-guest-agent.service
          1.062s console-kit-log-system-start.service
          1.038s keyboard-setup.service
           930ms rpcbind.service
           905ms polkit.service
           887ms virtualbox-guest-utils.service
           886ms systemd-logind.service
           854ms pppd-dns.service
           816ms rtkit-daemon.service
           815ms avahi-daemon.service
           644ms run-rpc_pipefs.mount
           600ms gpm.service
           549ms systemd-modules-load.service
           469ms dev-mqueue.mount
           463ms systemd-remount-fs.service
           447ms sys-kernel-debug.mount
           444ms dev-hugepages.mount
           416ms loadcpufreq.service
           387ms systemd-sysctl.service
           344ms speech-dispatcher.service
           333ms upower.service
           322ms systemd-udev-trigger.service
           297ms console-setup.service
           284ms systemd-random-seed.service
           249ms lightdm.service
           249ms openvpn.service
           248ms systemd-update-utmp.service
           244ms media-Distris.mount
           244ms systemd-tmpfiles-setup.service
           243ms systemd-user-sessions.service
           240ms kmod-static-nodes.service
           233ms systemd-journald.service
           223ms hddtemp.service
           183ms cpufrequtils.service
           163ms dev-disk-by\x2duuid-045398f2\x2ddfbc\x2d4cc5\x2d99c5\x2ddca098051969.swap
           153ms console-kit-daemon.service
           151ms systemd-tmpfiles-clean.service
            84ms nfs-config.service
            71ms user@1000.service
            62ms ssh.service
            60ms media-DATEN.mount
            12ms alsa-restore.service
             6ms systemd-update-utmp-runlevel.service
             3ms tmp.mount
             2ms sys-fs-fuse-connections.mount
lines 23-58/58 (END)

[samoht]

Leider auch kein Beitrag zur Lösung, lediglich eine Bestätigung des unangenehmen Phänomens auf den Maschinen von @piper und @samoht:
https://forum.siduction.org/index.php?topic=6826.msg55632#msg55632

[bluelupo]

@unklarer evtl. ein möglicher Workaround für dich:
Wenn du den Networkmanager nicht brauchst (zB. an einem PC der nur per Kabel am LAN hängt) dann ersetze den NM durch systemd-networkd. Ich hatte hier schon mal im Forum einen Post eingestellt. Der läuft bei mir seit jahren völlig stressfrei.

In meinem Wiki habe dazu auche einen Artikel erstellt:
https://mywiki.bluelupo.net/wiki/Umstellung_des_kabelgebundenen_Netzwerkes_auf_systemd-networkd

[unklarer]

Hallo bluelupo, 

danke für deinen Hinweis und deine Arbeit.
Hier in der patience mit plasma5 war ich bisher zu faul das auch anzuwenden.

In der älteren Paint_it_Black mit LXDE, auch auf diesen Rechner, ist es Standard.  ... und, dein Wiki hat ein Lesezeichen, weil ich da sehr oft stöbere.

[der_bud]

Und bevor das hier in freundliches hijacking ausartet (die Tipps und Lösungsversuche helfen ja vielleicht auch dem Thread-Eröffner), können wir bitte von cs mal ein 'systemd-analyze blame' und 'systemd-analyze critical-chain' bekommen? Dazu vielleicht noch ein 'systemctl status sddm.service' und 'journalct -b -p err' ...

[cs]

@der_Bud: Sehr gern, siehe weiter unten!

Vom Login unter SDDM oder LightDM bis zum Erscheinen des Desktop vergehen bei mir de facto ganze 50 Sekunden, ohne dass sich das Notebook zu rühren scheint.

systemd-analyze blame:

.397s NetworkManager-wait-online.service
           777ms apt-daily.service
           726ms dev-sdb1.device
           625ms systemd-journal-flush.service
           600ms tpdaemon.service
           551ms tvheadend.service
           539ms apt-daily-upgrade.service
           508ms udisks2.service
           448ms snapd.service
           350ms wpa_supplicant.service
           300ms dev-loop1.device
           300ms dev-loop0.device
           292ms networking.service
           287ms dev-loop2.device
           265ms inputlirc.service
           265ms lircd-setup.service
           260ms ModemManager.service
           197ms loadcpufreq.service
           173ms lvm2-monitor.service
           168ms preload.service
           166ms NetworkManager.service
           165ms disks-disk1part1.mount
           162ms systemd-tmpfiles-setup-dev.service
           161ms gpm.service
           160ms systemd-logind.service
           159ms accounts-daemon.service
           144ms keyboard-setup.service
           142ms systemd-backlight@backlight:intel_backlight.service
           128ms systemd-udevd.service
           123ms snapd.autoimport.service
           119ms ssh.service
           113ms lightdm.service
           108ms dev-disk-by\x2duuid-828dbf80\x2dd1e7\x2d468b\x2dace2\x2ddbb4b20d3b0f.swap
           104ms systemd-modules-load.service
           102ms virtualbox-guest-utils.service
            98ms smbd.service
            92ms upower.service
            90ms qemu-guest-agent.service
            87ms colord.service
            81ms disks-disk1part2.mount
            78ms speech-dispatcher.service

systemd-analyze critical-chain

The time after the unit is active or started is printed after the "@" character.
The time the unit takes to start is printed after the "+" character.

graphical.target @7.225s
└─multi-user.target @7.224s
  └─smbd.service @7.126s +98ms
    └─nmbd.service @7.077s +48ms
      └─network-online.target @7.073s
        └─NetworkManager-wait-online.service @2.675s +4.397s
          └─NetworkManager.service @2.491s +166ms
            └─dbus.service @2.423s
              └─basic.target @2.409s
                └─sockets.target @2.408s
                  └─snapd.socket @2.395s +10ms
                    └─sysinit.target @2.388s
                      └─systemd-timesyncd.service @2.339s +48ms
                        └─systemd-tmpfiles-setup.service @2.326s +11ms
                          └─local-fs.target @2.323s
                            └─disks-disk1part1.mount @2.156s +165ms
                              └─dev-disk-by\x2duuid-53d1cd6b\x2dfe31\x2d469c\x2d9bfd\x2d20ef0bfc8e34.device @1.341s

systemctl status sddm.service

● sddm.service - Simple Desktop Display Manager
   Loaded: loaded (/lib/systemd/system/sddm.service; static; vendor preset: enabled)
   Active: inactive (dead)
     Docs: man:sddm(1)
           man:sddm.conf(5)

journalctl -b -p err

-- Logs begin at Thu 2017-08-24 11:21:04 CEST, end at Mon 2017-09-18 12:00:45 CEST. --
Sep 18 07:53:28 cord-x230 kernel: tpm tpm0: A TPM error (6) occurred attempting to read a pcr value
Sep 18 07:53:29 cord-x230 systemd[1]: Swap dev-disk-by\x2dlabel-swap.swap appeared twice with different device paths /dev/sdb2 and /dev/sda3
Sep 18 07:53:29 cord-x230 avahi-daemon[568]: chroot.c: open() failed: No such file or directory
Sep 18 07:53:30 cord-x230 inputlircd[633]: /dev/input/event10 does not support EV_KEY events
Sep 18 07:53:30 cord-x230 inputlircd[633]: /dev/input/event11 does not support EV_KEY events
Sep 18 07:53:30 cord-x230 inputlircd[633]: /dev/input/event12 does not support EV_KEY events
Sep 18 07:53:30 cord-x230 inputlircd[633]: /dev/input/event13 does not support EV_KEY events
Sep 18 07:53:30 cord-x230 inputlircd[633]: /dev/input/event14 does not support EV_KEY events
Sep 18 07:53:30 cord-x230 inputlircd[633]: /dev/input/event15 does not support EV_KEY events
Sep 18 07:53:30 cord-x230 inputlircd[633]: /dev/input/event2 does not support EV_KEY events
Sep 18 07:53:30 cord-x230 inputlircd[633]: /dev/input/event6 does not support EV_KEY events
Sep 18 07:53:30 cord-x230 inputlircd[633]: /dev/input/event9 does not support EV_KEY events
Sep 18 07:53:30 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:30 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:30 cord-x230 tvheadend[805]: access: No access entries loaded
Sep 18 07:53:31 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:32 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:33 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:34 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:34 cord-x230 systemd[1]: Failed to start Automatically refresh installed snaps.
Sep 18 07:53:35 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:36 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:37 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:38 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:39 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:40 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:24 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:25 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:26 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:11 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:12 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:13 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:14 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:15 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:16 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:17 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:18 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:19 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:20 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:21 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*
Sep 18 07:53:22 cord-x230 lircd-0.10.0[746]: Error: Cannot glob /sys/class/rc/rc0/input[0-9]*/event[0-9]*

[dsat]

Hi,

soeben hat's ein Update für udisk2 gegeben, vielleicht hilft das ja.

Gruß dsat

[der_bud]

cs, bei Deinem systemd-analyze fällt mir zumindest auf Anhieb erstmal nichts kritisches ins Auge, außer das lightdm.service gestartet wird und daher ein 'systemctl status lightdm.service' statt sddm sinnvoll wäre (hab aus Deinem Startpost nicht herausgelassen bei welchem Du aktuell gelandet bist).

 Im journalctl fällt auf, dass für sdb2 und sda3 gleiche Label angemeckert werden kann das sein? Das würde zumindest zu Irritationen führen. Dann hagelt es Meldungen zu lirc, hat das nicht was mit IR-Devices zu tun? Vielleicht mal dahingehend forschen, ob alles richtig installiert ist was Du brauchst (mit solchen Geräten kenn ich mich nicht aus).