1

This question already has an answer here:

Recently sddm has become very slow to show the login screen on Arch Linux. After I see the bootup message "Reached Graphical User Interface target" (or similar), there is a long delay of more than 10 seconds before the sddm greeter is displayed.

The logs below show that the whole bootup process is slow:

Startup finished in 17.085s (firmware) + 4.763s (loader) + 6.253s (kernel) + 15.786s (userspace) = 43.890s.

However, from "Started Simple Desktop Display Manager" (May 15 17:53:36) to "Greeter session started successfully" (May 15 17:53:59) is 13 seconds. The greatly increased delay seems to be related to the display of user .face.icon files, although that's just a guess.

Hopefully someone will see some clues in the log messages below.

May 15 17:54:01 desktop1 sddm-greeter[660]: Message received from daemon: HostName
May 15 17:54:01 desktop1 sddm-greeter[660]: Message received from daemon: Capabilities
May 15 17:54:01 desktop1 sddm-greeter[660]: QDBusConnection: name 'org.freedesktop.UDisks2' had owner '' but we thought it was ':1.54'
May 15 17:54:01 desktop1 sddm-greeter[660]: Adding view for "HDMI-2" QRect(0,0 2560x1440)
May 15 17:54:01 desktop1 sddm-greeter[660]: file:///usr/share/sddm/themes/breeze/components/VirtualKeyboard.qml:20:1: module "QtQuick.VirtualKeyboard" is not installed
May 15 17:54:01 desktop1 sddm-greeter[660]: inotify_add_watch("/etc/fstab") failed: "Permission denied"
May 15 17:54:01 desktop1 systemd[1]: Started Daemon for power management.
May 15 17:54:01 desktop1 dbus-daemon[410]: [system] Successfully activated service 'org.freedesktop.UPower'
May 15 17:54:00 desktop1 udisksd[666]: Acquired the name org.freedesktop.UDisks2 on the system message bus
May 15 17:54:00 desktop1 systemd[1]: Starting Daemon for power management...
May 15 17:54:00 desktop1 dbus-daemon[410]: [system] Activating via systemd: service name='org.freedesktop.UPower' unit='upower.service' requested by ':1.53' (uid=995 pid=660 comm="/usr/b>
May 15 17:54:00 desktop1 systemd[1]: Started Disk Manager.
May 15 17:54:00 desktop1 dbus-daemon[410]: [system] Successfully activated service 'org.freedesktop.UDisks2'
May 15 17:54:00 desktop1 udisksd[666]: udisks daemon version 2.7.6 starting
May 15 17:54:00 desktop1 systemd[1]: Starting Disk Manager...
May 15 17:54:00 desktop1 dbus-daemon[410]: [system] Activating via systemd: service name='org.freedesktop.UDisks2' unit='udisks2.service' requested by ':1.53' (uid=995 pid=660 comm="/usr>
May 15 17:54:00 desktop1 sddm-greeter[660]: Cannot watch QRC-like path ":/icons/hicolor/index.theme"
May 15 17:54:00 desktop1 sddm-greeter[660]: QObject::installEventFilter(): Cannot filter events for objects in a different thread.
May 15 17:54:00 desktop1 sddm-greeter[660]: QObject: Cannot create children for a parent that is in a different thread.
                                        (Parent is SDDM::GreeterApp(0x7fff5551e800), parent's thread is QThread(0x55fe866984a0), current thread is QThread(0x55fe866f9ae0)
May 15 17:53:59 desktop1 systemd[654]: Started D-Bus User Message Bus.
May 15 17:53:59 desktop1 sddm-greeter[660]: QObject: Cannot create children for a parent that is in a different thread.
                                        (Parent is SDDM::GreeterApp(0x7fff5551e800), parent's thread is QThread(0x55fe866984a0), current thread is QThread(0x55fe866f9ae0)
May 15 17:53:59 desktop1 sddm-greeter[660]: QObject::installEventFilter(): Cannot filter events for objects in a different thread.
May 15 17:53:59 desktop1 sddm-greeter[660]: QObject: Cannot create children for a parent that is in a different thread.
                                        (Parent is SDDM::GreeterApp(0x7fff5551e800), parent's thread is QThread(0x55fe866984a0), current thread is QThread(0x55fe866f9ae0)
May 15 17:53:59 desktop1 sddm-greeter[660]: QObject: Cannot create children for a parent that is in a different thread.
                                        (Parent is SDDM::GreeterApp(0x7fff5551e800), parent's thread is QThread(0x55fe866984a0), current thread is QThread(0x55fe866f9ae0)
May 15 17:53:59 desktop1 sddm-greeter[660]: QObject: Cannot create children for a parent that is in a different thread.
                                        (Parent is SDDM::GreeterApp(0x7fff5551e800), parent's thread is QThread(0x55fe866984a0), current thread is QThread(0x55fe866f9ae0)
May 15 17:53:59 desktop1 sddm-greeter[660]: QObject: Cannot create children for a parent that is in a different thread.
                                        (Parent is SDDM::GreeterApp(0x7fff5551e800), parent's thread is QThread(0x55fe866984a0), current thread is QThread(0x55fe866f9ae0)
May 15 17:53:59 desktop1 sddm-greeter[660]: Loading file:///usr/share/sddm/themes/breeze/Main.qml...
May 15 17:53:59 desktop1 sddm[630]: Message received from greeter: Connect
May 15 17:53:59 desktop1 sddm-greeter[660]: Connected to the daemon.
May 15 17:53:59 desktop1 sddm-greeter[660]: inotify_add_watch("/usr/share/wayland-sessions") failed: "No such file or directory"
May 15 17:53:59 desktop1 sddm-greeter[660]: Reading from "/usr/share/xsessions/plasma.desktop"
May 15 17:53:59 desktop1 sddm-greeter[660]: Loading theme configuration from "/usr/share/sddm/themes/breeze/theme.conf"
May 15 17:53:59 desktop1 sddm-greeter[660]: High-DPI autoscaling not Enabled
May 15 17:53:59 desktop1 sddm[630]: Greeter session started successfully
May 15 17:53:59 desktop1 systemd[1]: Started User Manager for UID 995.
May 15 17:53:59 desktop1 systemd[654]: Startup finished in 82ms.
May 15 17:53:59 desktop1 systemd[654]: Reached target Default.
May 15 17:53:59 desktop1 systemd[654]: Reached target Basic System.
May 15 17:53:59 desktop1 systemd[654]: Reached target Sockets.
May 15 17:53:59 desktop1 systemd[654]: Listening on D-Bus User Message Bus Socket.
May 15 17:53:59 desktop1 systemd[654]: Reached target Paths.
May 15 17:53:59 desktop1 systemd[654]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
May 15 17:53:59 desktop1 systemd[654]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
May 15 17:53:59 desktop1 systemd[654]: Listening on GnuPG network certificate management daemon.
May 15 17:53:59 desktop1 systemd[654]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers).
May 15 17:53:59 desktop1 systemd[654]: Listening on GnuPG cryptographic agent and passphrase cache.
May 15 17:53:59 desktop1 systemd[654]: Listening on Sound System.
May 15 17:53:59 desktop1 systemd[654]: Reached target Timers.
May 15 17:53:59 desktop1 systemd[654]: Starting D-Bus User Message Bus Socket.
May 15 17:53:58 desktop1 systemd[654]: pam_unix(systemd-user:session): session opened for user sddm by (uid=0)
May 15 17:53:58 desktop1 systemd[1]: Started Session c1 of user sddm.
May 15 17:53:58 desktop1 systemd-logind[409]: New session c1 of user sddm.
May 15 17:53:58 desktop1 systemd[1]: Starting User Manager for UID 995...
May 15 17:53:58 desktop1 systemd[1]: Created slice User Slice of sddm.
May 15 17:53:58 desktop1 sddm-helper[652]: pam_unix(sddm-greeter:session): session opened for user sddm by (uid=0)
May 15 17:53:58 desktop1 sddm-helper[652]: [PAM] returning.
May 15 17:53:58 desktop1 sddm-helper[652]: [PAM] Authenticating...
May 15 17:53:58 desktop1 sddm-helper[652]: [PAM] Starting...
May 15 17:53:58 desktop1 sddm[630]: Adding cookie to "/var/run/sddm/{d4e3bc53-809f-3ca5-a1e-b1d287e870b1}"
May 15 17:53:58 desktop1 sddm[630]: Greeter starting...
May 15 17:53:58 desktop1 sddm[630]: Loading theme configuration from "/usr/share/sddm/themes/breeze/theme.conf"
May 15 17:53:58 desktop1 sddm[630]: Socket server started.
May 15 17:53:58 desktop1 sddm[630]: Socket server starting...
May 15 17:53:58 desktop1 sddm[630]: Display server started.
May 15 17:53:58 desktop1 sddm[630]: Running display setup script  "/usr/share/sddm/scripts/Xsetup"
May 15 17:53:58 desktop1 sddm[630]: Setting default cursor
May 15 17:53:57 desktop1 sddm[630]: Running: /usr/bin/X -nolisten tcp -auth /var/run/sddm/{d4e3bc53-809f-3ca5-a1e-b1d287e870b1} -background none -noreset -displayfd 17 -seat seat0 vt1
May 15 17:53:57 desktop1 sddm[630]: Display server starting...
May 15 17:53:57 desktop1 sddm[630]: Loading theme configuration from ""
May 15 17:53:57 desktop1 sddm[630]: Adding new display on vt 1 ...
May 15 17:53:57 desktop1 sddm[630]: Starting...
May 15 17:53:57 desktop1 sddm[630]: Logind interface found
May 15 17:53:57 desktop1 sddm[630]: Initializing...
May 15 17:53:57 desktop1 kernel: random: 6 urandom warning(s) missed due to ratelimiting
May 15 17:53:57 desktop1 kernel: random: crng init done
May 15 17:53:42 desktop1 dhcpcd[507]: eth0: no IPv6 Routers available
May 15 17:53:37 desktop1 systemd[1]: Startup finished in 17.085s (firmware) + 4.763s (loader) + 6.253s (kernel) + 15.786s (userspace) = 43.890s.
May 15 17:53:37 desktop1 systemd[1]: Reached target Graphical Interface.
May 15 17:53:37 desktop1 systemd[1]: Reached target Multi-User System.
May 15 17:53:37 desktop1 systemd[1]: Started Make remote CUPS printers available locally.
May 15 17:53:37 desktop1 systemd[1]: Started CUPS Scheduler.
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 systemd[1]: Started Simple Desktop Display Manager.
May 15 17:53:36 desktop1 systemd[1]: Started Permit User Sessions.
May 15 17:53:36 desktop1 systemd[1]: Starting Permit User Sessions...
May 15 17:53:36 desktop1 systemd[1]: Reached target Remote File Systems.
May 15 17:53:36 desktop1 systemd[1]: Started Manage, Install and Generate Color Profiles.
May 15 17:53:36 desktop1 systemd[1]: Mounted /home/usercommon/Finance/Syncd.
May 15 17:53:36 desktop1 systemd[1]: Mounted /var/cache/pacman.
May 15 17:53:36 desktop1 systemd[1]: Mounted /kit.
May 15 17:53:36 desktop1 systemd[1]: Mounted /home/mari/fileserver/Desktop.
May 15 17:53:36 desktop1 systemd[1]: Mounted /home/usercommon/Finance/Receipts.
May 15 17:53:36 desktop1 systemd[1]: Mounted /home/jessica/Documents.
May 15 17:53:36 desktop1 systemd[1]: Mounted /backup/admins.
May 15 17:53:36 desktop1 systemd[1]: Mounted /home/natasha/Documents.
May 15 17:53:36 desktop1 systemd[1]: Mounted /backup/files.
May 15 17:53:36 desktop1 systemd[1]: Mounted /home/usercommon/Ventures.
May 15 17:53:58 desktop1 sddm[630]: Setting default cursor
May 15 17:53:57 desktop1 sddm[630]: Running: /usr/bin/X -nolisten tcp -auth /var/run/sddm/{d4e3bc53-809f-3ca5-a1e-b1d287e870b1} -background none -noreset -displayfd 17 -seat seat0 vt1
May 15 17:53:57 desktop1 sddm[630]: Display server starting...
May 15 17:53:57 desktop1 sddm[630]: Loading theme configuration from ""
May 15 17:53:57 desktop1 sddm[630]: Adding new display on vt 1 ...
May 15 17:53:57 desktop1 sddm[630]: Starting...
May 15 17:53:57 desktop1 sddm[630]: Logind interface found
May 15 17:53:57 desktop1 sddm[630]: Initializing...
May 15 17:53:57 desktop1 kernel: random: 6 urandom warning(s) missed due to ratelimiting
May 15 17:53:57 desktop1 kernel: random: crng init done
May 15 17:53:42 desktop1 dhcpcd[507]: eth0: no IPv6 Routers available
May 15 17:53:37 desktop1 systemd[1]: Startup finished in 17.085s (firmware) + 4.763s (loader) + 6.253s (kernel) + 15.786s (userspace) = 43.890s.
May 15 17:53:37 desktop1 systemd[1]: Reached target Graphical Interface.
May 15 17:53:37 desktop1 systemd[1]: Reached target Multi-User System.
May 15 17:53:37 desktop1 systemd[1]: Started Make remote CUPS printers available locally.
May 15 17:53:37 desktop1 systemd[1]: Started CUPS Scheduler.
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 colord[615]: failed to get session [pid 562]: No data available
May 15 17:53:36 desktop1 systemd[1]: Started Simple Desktop Display Manager.

I'm seeing the same issue on multiple computers. All run Arch Linux KDE with plasmashell 5.12.5 or newer. Linux 4.16.8-1-ARCH #1 SMP PREEMPT Wed May 9 11:25:02 UTC 2018 x86_64 GNU/Linux.

marked as duplicate by Sparhawk, Fabby, Shadur, Jeff Schaller Jul 21 at 13:16

This question has been asked before and already has an answer. If those answers do not fully address your question, please ask a new question.

  • 1
    You may be suffering from this recent crng kernel bug. – meuh May 16 '18 at 7:58
  • I'm also on Arch, see this problem, and the fix works. IMO it's definitely the same bug. You can see in the log that kernel: random: crng init done occurs just before Greeter session started successfully. On my system it's even more obvious, with a 2 minute delay from crng. Also note that the Startup finished in… line is not relevant here, as it's displayed before this long crng delay. – Sparhawk Jul 14 at 6:11
1

Over on the Arch Linux BBS, Haller wrote:

It's a kernel problem:
https://bbs.archlinux.org/viewtopic.php?id=236696

This resolved it for me:

pacman -Syu haveged
systemctl enable haveged.service
systemctl start haveged.service

That change reduced this phase of startup from 13 seconds to about 1 second:

I tested this on two different computers and got very positive results on both. This will be a satisfactory work-around for me until the kernel issue is resolved.

  • I had very similar problem on Debian with Kernel 4.16.0. Installing haveged solved the problem. – Trismegistos Jul 12 '18 at 19:10

Not the answer you're looking for? Browse other questions tagged or ask your own question.