[arch-general] at times when booting system-logind fails and system hangs

Fri May 1 11:46:58 UTC 2015

I ran into the same problem this morning unfortunately :(

The relevant journalctl output (it is quite similar to Javier's)

================= journalctl ===================
May 01 12:50:13 archM systemd[1]: Listening on D-Bus System Message Bus
Socket.
May 01 12:50:13 archM systemd[1]: Starting D-Bus System Message Bus Socket.
...
May 01 12:50:13 archM systemd[1]: Starting Login Service...
...
May 01 12:50:42 archM dbus[286]: [system] Activating via systemd: service
name='org.freedesktop.PolicyKit1' unit='polkit.service'
May 01 12:50:42 archM systemd[1]: Unit systemd-logind.service entered
failed state.
May 01 12:50:42 archM systemd[1]: systemd-logind.service failed.
May 01 12:50:42 archM systemd[1]: systemd-logind.service has no holdoff
time, scheduling restart.
...
May 01 12:50:41 archM systemd-logind[279]: Failed to enable subscription:
Connection timed out
May 01 12:50:41 archM systemd-logind[279]: Failed to fully start up daemon:
Connection timed out
May 01 12:50:41 archM systemd[1]: Failed to register name: Connection timed
out
May 01 12:50:41 archM systemd[1]: Failed to set up API bus: Connection
timed out
May 01 12:50:41 archM systemd[1]: Starting D-Bus System Message Bus...
...
May 01 12:51:05 archM systemd[1]: Looping too fast. Throttling execution a
little.
May 01 12:51:07 archM systemd[1]: Looping too fast. Throttling execution a
little.
May 01 12:51:07 archM dbus[286]: [system] Failed to activate service
'org.freedesktop.PolicyKit1': timed out
May 01 12:51:07 archM ModemManager[275]: <warn>  failed to create PolicyKit
authority: 'Error initializing authority:
 Error calling StartServiceByName for org.freedesktop.PolicyKit1: Timeout
was reached'

May 01 12:51:07 archM NetworkManager[276]: <error> [1430464867.217039]
[nm-auth-manager.c:449] _dbus_new_proxy_cb():
auth: could not get polkit proxy: Error calling StartServiceByName for
org.freedesktop.PolicyKit1:
GDBus.Error:org.freedesktop.DBus.Error.TimedOut: Activation of
org.freedesktop.PolicyKit1 timed out

May 01 12:51:05 archM systemd[1]: Looping too fast. Throttling execution a
little.
May 01 12:51:07 archM systemd[1]: Looping too fast. Throttling execution a
little.
May 01 12:51:07 archM dbus[286]: [system] Failed to activate service
'org.freedesktop.PolicyKit1': timed out
....
May 01 12:51:08 archM systemd[1]: Looping too fast. Throttling execution a
little.
May 01 12:51:08 archM dbus[286]: [system] Failed to activate service
'fi.w1.wpa_supplicant1': timed out
...

====================================

Every line in /etc/systemd/logind.conf is commented out
-------
$ grep '^[^#]' /etc/systemd/logind.conf
[Login]
-------

For me, multiple (3-4 times) reboots did not fix it, the systemd-logind
service failed each time and I never saw any of the ttys or display manager
(sddm).
I was able to do some basic debugging and use pacman via the systemd debug
shell on tty9

As per the pacman logs systemd 219 was installed on 24th and I have booted
up the machine at least a couple of times since then but only hit the issue
this morning.

-----
[2015-04-24 23:31] [ALPM] upgraded systemd (219-5 -> 219-6)
-----

I am not sure whether it is related but the difference between booting the
machine yesterday and this morning, were these package upgrades from last
night.

----------------

[2015-05-01 01:12] [PACMAN] starting full system upgrade
[2015-05-01 01:12] [ALPM] transaction started
[2015-05-01 01:12] [ALPM] upgraded readline (6.3.006-1 -> 6.3.008-1)
[2015-05-01 01:12] [ALPM] upgraded kdelibs (4.14.7-1 -> 4.14.7-2)
[2015-05-01 01:12] [ALPM] upgraded kdebase-lib (15.04.0-2 -> 15.04.0-3)
[2015-05-01 01:13] [ALPM] upgraded kio (5.9.0-1 -> 5.9.0-2)
[2015-05-01 01:13] [ALPM] upgraded kdebase-dolphin (15.04.0-2 -> 15.04.0-3)
[2015-05-01 01:13] [ALPM] upgraded kdebase-kdialog (15.04.0-2 -> 15.04.0-3)
[2015-05-01 01:13] [ALPM] upgraded kdebase-keditbookmarks (15.04.0-2 ->
15.04.0-3)
[2015-05-01 01:13] [ALPM] upgraded kdebase-kfind (15.04.0-2 -> 15.04.0-3)
[2015-05-01 01:13] [ALPM] upgraded kdebase-konqueror (15.04.0-2 ->
15.04.0-3)
[2015-05-01 01:13] [ALPM] upgraded kdebase-konq-plugins (15.04.0-2 ->
15.04.0-3)
[2015-05-01 01:13] [ALPM] upgraded mlt (0.9.6-5 -> 0.9.6-6)
[2015-05-01 01:13] [ALPM] transaction completed

----------------

Using pacman via the debug shell I downgraded these packages and
reinstalled polkit, polkit-kde and polkit-kde-frameworks.
----------------
[2015-05-01 14:41] [ALPM] transaction started
[2015-05-01 14:41] [ALPM] downgraded readline (6.3.008-1 -> 6.3.006-1)
[2015-05-01 14:41] [ALPM] downgraded kdelibs (4.14.7-2 -> 4.14.7-1)
[2015-05-01 14:41] [ALPM] downgraded kdebase-lib (15.04.0-3 -> 15.04.0-2)
[2015-05-01 14:41] [ALPM] downgraded kio (5.9.0-2 -> 5.9.0-1)
[2015-05-01 14:41] [ALPM] downgraded kdebase-dolphin (15.04.0-3 ->
15.04.0-2)
[2015-05-01 14:41] [ALPM] downgraded kdebase-kdialog (15.04.0-3 ->
15.04.0-2)
[2015-05-01 14:41] [ALPM] downgraded kdebase-keditbookmarks (15.04.0-3 ->
15.04.0-2)
[2015-05-01 14:41] [ALPM] downgraded kdebase-kfind (15.04.0-3 -> 15.04.0-2)
[2015-05-01 14:41] [ALPM] downgraded kdebase-konqueror (15.04.0-3 ->
15.04.0-2)
[2015-05-01 14:41] [ALPM] downgraded kdebase-konq-plugins (15.04.0-3 ->
15.04.0-2)
[2015-05-01 14:41] [ALPM] downgraded mlt (0.9.6-6 -> 0.9.6-5)
[2015-05-01 14:41] [ALPM] transaction completed
.....
[2015-05-01 14:43] [ALPM] transaction started
[2015-05-01 14:43] [ALPM] reinstalled polkit (0.112-2)
[2015-05-01 14:43] [ALPM] reinstalled polkit-kde-frameworks (5.2.2-1)
[2015-05-01 14:43] [ALPM] reinstalled polkit-kde (0.99.0-5)
[2015-05-01 14:43] [ALPM] transaction completed
----------------

After that my system booted up normally as it had done yesterday (with the
ttys and sddm)

Sorry for the long post !

-- 
Nandan Vaidya

~ Less is more ~