You are not logged in.
Pages: 1
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.
Last edited by MountainX (2018-05-16 02:17:01)
Offline
It's a kernel problem:
https://bbs.archlinux.org/viewtopic.php?id=236696
Offline
It's a kernel problem:
https://bbs.archlinux.org/viewtopic.php?id=236696
Thanks.
pacman -Syu haveged
systemctl enable haveged.service
systemctl start haveged.serviceReduced this phase of startup from 13 seconds (see my prior message) to about 1 second:
May 15 22:09:31 desktop2 systemd[1]: Started Simple Desktop Display Manager.
May 15 22:09:32 desktop2 sddm[573]: Greeter session started successfullyI tested this on two different computers. This will be a satisfactory work-around for me until the kernel issue is resolved.
Last edited by MountainX (2018-05-16 02:27:19)
Offline
Pages: 1