Systemd 240 update breaks graphical login

systemd
gnome
update
xfce
xfce4-gtk3

#21

tested this morning, still broken…

déc. 23 08:46:14 manjaro systemd-udevd[242]: Failed to wait spawned command '/usr/bin/alsactl restore 1': Input/output error
déc. 23 08:46:17 manjaro systemd-udevd[242]: Failed to wait spawned command '/usr/bin/alsactl restore 0': Input/output error
déc. 23 08:45:44 manjaro kernel: traps: light-locker[679] trap int3 ip:7fe19f317c36 sp:7ffc9121cc50 error:0
déc. 23 09:01:58 manjaro pulseaudio[734]: ICE default IO error handler doing an exit(), pid = 734, errno = 11


#22

could this be related to dbus-x11 vs dbus?

No issues on *gnome unstable w/ wayland; upgrade via gnome-software.

except...
-- Logs begin at Sat 2018-09-22 11:33:23 CEST, end at Sun 2018-12-23 11:54:31 CET. --
Dez 23 11:53:46 balder systemd-udevd[433]: Failed to wait spawned command '/usr/bin/set-wireless-regdom': Input/output error
Dez 23 11:53:46 balder systemd-udevd[428]: Failed to wait spawned command '/usr/bin/alsactl restore 1': Input/output error
Dez 23 11:53:46 balder systemd-udevd[474]: Failed to wait spawned command '/usr/bin/alsactl restore 2': Input/output error
Dez 23 11:53:46 balder systemd-udevd[558]: Failed to wait spawned command '/usr/bin/alsactl restore 0': Input/output error
Dez 23 11:53:51 balder colord-sane[767]: io/hpmud/pp.c 627: unable to read device-id ret=-1
Dez 23 11:53:58 balder bluetoothd[667]: RFCOMM server failed for Headset Voice gateway: rfcomm_bind: Address already in use (98)
Dez 23 11:53:58 balder bluetoothd[667]: RFCOMM server failed for :1.221/Profile/HSPHSProfile/00001108-0000-1000-8000-00805f9b34fb: rfcomm_bind: Address already in use (98)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:03 balder bluetoothd[667]: bt_uhid_send: Invalid argument (22)
Dez 23 11:54:07 balder gnome-session-binary[3357]: Unrecoverable failure in required component org.gnome.Shell.desktop

That bluetooth thing is just normal for my system. Everything else is not. But no issues at the graphicsl session or boot at all.


#23

My investigations this morning don’t lead me very far, just 2 things found.

1] It is only the ‘systemd’ package itself that causes the problem, I have updated the other 3 (lib32-systemd, libsystemd and systemd-sysvcompat with no ill effects).

2] Unlike bogdancovaciu in his post above I am unable to reach a graphical login from a TTY.
Running ‘startx’ just drops me back to the non-graphical login, and running ‘startxfce4’ generates the following error message:

[ 341.513] _XSERVTransSocketUNIXCreateListener: …SocketCreateListener() failed
[ 341.513] _XSERVTransMakeAllCOTSServerListeners: server already running
[ 341.513] (EE)
Fatal server error:
[ 341.513] (EE) Cannot establish any listening sockets - Make sure an X server isn’t already running(EE)
[ 341.513] (EE)
Please consult the The X.Org Foundation support
at http://wiki.x.org
for help.
[ 341.513] (EE) Please also check the log file at “/home/manjaro/.local/share/xorg/Xorg.0.log” for additional information.
[ 341.513] (EE)
[ 341.513] (EE) Server terminated with error (1). Closing log file.


#24

could you try to install dbus-x11, if dbus is installed or dbus, if dbus-x11 is installed?


#25

Has this been reported upstream yet?


#26

I’d be happy to, but I have another process running at the moment and have to wait until that finishes first.

I will let you know as soon as I can.


#27

Indeed this fixes the issue, but i think was mentioned before and i thought was already addressed/fixed … but i might be wrong tho.


#28

BUY THAT MAN A BEER!
That fixes it for me Tids - changed from dbus to dbus-x11, installed latest systemd and all is well.

Thank you for your input.


#29

The question now is: why?
I dont need dbus-x11. Not in Wayland, not for xorg-only. Is it desktop related? Do some desktop depend on on dbus-x11?


#30

There’s a version difference in the repo packages:

core/dbus 1.12.12-1
    Freedesktop.org message bus system
...
core/dbus-x11 1.12.10-1
    Freedesktop.org message bus system

I have the dbus package on the X230 with MATE, same DBUS error as bogdancovaciu’s post above.

This is the same error you get if you try to log in twice to the same session, e.g. log in via a remote x2go session then try to log in locally.


I wonder whether dbus needs to be rebuilt against systemd=240…

Edit: Nope, rebuild doesn’t help.

LightDM maybe?


#31

Ok, just checked and those 3 installs still have dbus installed and no issue. I’m a bit confused as those have the dbus 1.12.12-1 and systemd 240.1-2 installed, same as the other 3 installs that did not worked without the switch to dbus-x11.

I kept Budgie as it is and i try to see what’s different. It seems the journal is truncated somehow:

dec 23 15:23:36 bb gnome-session[555]: libEGL warning: DRI2: failed to authenticate
dec 23 15:23:36 bb gnome-session[555]: gnome-session-check-accelerated: GLES Helper exited with code 512
dec 23 15:23:36 bb gnome-session[555]: gnome-session-binary[555]: WARNING: Could not make bus activated clients aware of XDG_MENU_PREFIX=gnome- environment variable: >
dec 23 15:23:36 bb gnome-session-binary[555]: WARNING: Could not make bus activated clients aware of XDG_MENU_PREFIX=gnome- environment variable: Could not connect: C>
dec 23 15:23:36 bb gnome-session[555]: gnome-session-binary[555]: WARNING: Lost name on bus: org.gnome.SessionManager
dec 23 15:23:36 bb gnome-session-binary[555]: WARNING: Lost name on bus: org.gnome.SessionManager
dec 23 15:23:36 bb gnome-session[555]: Unable to init server: Could not connect: Connection refused
dec 23 15:23:36 bb gnome-session-f[716]: Cannot open display:

I’m tempted to think the same thing as those installs that failed to load are all using it.


#32

dbus-x11 hasn’t been rebuilt since Aug-2018.

Are apples being compared to oranges here, given were built against quite different systemd versions?

lightdm was last built in Sept-2018, and is dependent on polkit, which is dependent on systemd.

polkit the culprit?


#33

Rebuild polkit, same errors. Digging into some log files now.

Trying to log in to MATE, .xsession-errors:

mate-session[1530]: WARNING: Could not make bus activated clients aware of DISPLAY=:0 environment variable: Could not connect: Connection refused
mate-session[1530]: WARNING: Could not make bus activated clients aware of MATE_DESKTOP_SESSION_ID=this-is-deprecated environment variable: Could not connect: Connection refused

(mate-session:1530): dconf-WARNING **: 14:33:46.124: failed to commit changes to dconf: Could not connect: Connection refused
mate-session[1530]: WARNING: Could not make bus activated clients aware of SESSION_MANAGER=local/x230:@/tmp/.ICE-unix/1530,unix/x230:/tmp/.ICE-unix/1530 environment variable: Could not connect: Connection refused
mate-session[1530]: Gtk-CRITICAL: gtk_main_quit: assertion 'main_loops != NULL' failed
mate-session[1530]: CRITICAL: dbus_g_proxy_new_for_name: assertion 'connection != NULL' failed
mate-session[1530]: Gtk-CRITICAL: gtk_main_quit: assertion 'main_loops != NULL' failed
mate-session[1530]: GLib-GObject-CRITICAL: g_object_unref: assertion 'G_IS_OBJECT (object)' failed
mate-session[1530]: WARNING: Could not make bus activated clients aware of GTK_OVERLAY_SCROLLING=0 environment variable: Could not connect: Connection refused
mate-session[1530]: CRITICAL: error getting session bus: Failed to connect to socket /tmp/dbus-CQ3epkXkoI: Connection refused
mate-session[1530]: WARNING: Unable to register presence with session bus
mate-session[1530]: CRITICAL: dbus_g_proxy_new_for_name: assertion 'connection != NULL' failed
mate-session[1530]: CRITICAL: error getting session bus: Failed to connect to socket /tmp/dbus-CQ3epkXkoI: Connection refused

journalctl -f:

Dec 23 14:39:20 x230 systemd[1]: session-c5.scope: Killing process 1698 (lightdm) with signal SIGTERM.
Dec 23 14:39:20 x230 systemd[1]: session-c5.scope: Killing process 1701 (lightdm-gtk-gre) with signal SIGTERM.
Dec 23 14:39:20 x230 systemd[1]: Stopping Session c5 of user lightdm.
Dec 23 14:39:20 x230 lightdm[1698]: pam_unix(lightdm-greeter:session): session closed for user lightdm
Dec 23 14:39:20 x230 systemd[1]: session-c5.scope: Succeeded.
Dec 23 14:39:20 x230 systemd[1]: Stopped Session c5 of user lightdm.
Dec 23 14:39:20 x230 systemd-logind[725]: Removed session c5.
Dec 23 14:39:20 x230 kernel: audit: type=1006 audit(1545575960.353:112): pid=1709 uid=0 old-auid=4294967295 auid=1000 tty=(none) old-ses=4294967295 ses=10 res=1
Dec 23 14:39:20 x230 lightdm[1709]: pam_unix(lightdm:session): session opened for user jonathon by (uid=0)
Dec 23 14:39:20 x230 systemd[1]: Started Session 10 of user jonathon.
Dec 23 14:39:20 x230 systemd-logind[725]: New session 10 of user jonathon.
Dec 23 14:39:20 x230 mate-session[1805]: WARNING: Could not make bus activated clients aware of DISPLAY=:0 environment variable: Could not connect: Connection refused
Dec 23 14:39:20 x230 mate-session[1805]: WARNING: Could not make bus activated clients aware of MATE_DESKTOP_SESSION_ID=this-is-deprecated environment variable: Could not connect: Connection refused
Dec 23 14:39:20 x230 mate-session[1805]: WARNING: Could not make bus activated clients aware of SESSION_MANAGER=local/x230:@/tmp/.ICE-unix/1805,unix/x230:/tmp/.ICE-unix/1805 environment variable: Could not connect: Connection refused
Dec 23 14:39:20 x230 audit[1805]: ANOM_ABEND auid=1000 uid=1000 gid=1000 ses=10 pid=1805 comm="mate-session" exe="/usr/bin/mate-session" sig=11 res=1
Dec 23 14:39:20 x230 kernel: mate-session[1805]: segfault at 20 ip 00007f73ebb4233d sp 00007ffc5e659928 error 4 in libgobject-2.0.so.0.5800.2[7f73ebb3c000+33000]
Dec 23 14:39:20 x230 kernel: Code: 48 63 c9 48 39 44 ca 48 0f 94 c0 0f b6 c0 c3 66 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 00 48 85 ff 74 4b 48 8b 07 48 85 c0 74 43 <48> 8b 00 48 3d fc 03 00 00 76 18 48 83 e0 fc 48 89 c2 0f b6 42 14
Dec 23 14:39:20 x230 kernel: audit: type=1701 audit(1545575960.826:113): auid=1000 uid=1000 gid=1000 ses=10 pid=1805 comm="mate-session" exe="/usr/bin/mate-session" sig=11 res=1
Dec 23 14:39:20 x230 systemd[1]: Created slice system-systemd\x2dcoredump.slice.
Dec 23 14:39:20 x230 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-coredump@0-1812-0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Dec 23 14:39:20 x230 systemd[1]: Started Process Core Dump (PID 1812/UID 0).
Dec 23 14:39:20 x230 kernel: audit: type=1130 audit(1545575960.836:114): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-coredump@0-1812-0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Dec 23 14:39:21 x230 lightdm[1709]: pam_unix(lightdm:session): session closed for user jonathon
Dec 23 14:39:21 x230 systemd-logind[725]: Session 10 logged out. Waiting for processes to exit.
Dec 23 14:39:21 x230 systemd-coredump[1813]: Process 1805 (mate-session) of user 1000 dumped core.
                                             
                                             Stack trace of thread 1805:
                                             #0  0x00007f73ebb4233d g_type_check_instance_is_fundamentally_a (libgobject-2.0.so.0)
                                             #1  0x00007f73ebb5afef g_object_ref (libgobject-2.0.so.0)
                                             #2  0x00007f73ebb5b0a7 g_weak_ref_get (libgobject-2.0.so.0)
                                             #3  0x00007f73ebe87bd3 n/a (libgio-2.0.so.0)
                                             #4  0x00007f73eba798d1 g_main_context_dispatch (libglib-2.0.so.0)
                                             #5  0x00007f73eba7b5e9 n/a (libglib-2.0.so.0)
                                             #6  0x00007f73eba7c5c2 g_main_loop_run (libglib-2.0.so.0)
                                             #7  0x00007f73ec38f7df gtk_dialog_run (libgtk-3.so.0)
                                             #8  0x000055d5f249d2c8 n/a (mate-session)
                                             #9  0x000055d5f248108f n/a (mate-session)
                                             #10 0x00007f73eb7cd223 __libc_start_main (libc.so.6)
                                             #11 0x000055d5f248168a n/a (mate-session)
                                             
                                             Stack trace of thread 1810:
                                             #0  0x00007f73eb899c21 __poll (libc.so.6)
                                             #1  0x00007f73eba7b540 n/a (libglib-2.0.so.0)
                                             #2  0x00007f73eba7b62e g_main_context_iteration (libglib-2.0.so.0)
                                             #3  0x00007f73eba7b682 n/a (libglib-2.0.so.0)
                                             #4  0x00007f73eba5667b n/a (libglib-2.0.so.0)
                                             #5  0x00007f73eadafa9d start_thread (libpthread.so.0)
                                             #6  0x00007f73eb8a4b23 __clone (libc.so.6)
                                             
                                             Stack trace of thread 1811:
                                             #0  0x00007f73eb899c21 __poll (libc.so.6)
                                             #1  0x00007f73eba7b540 n/a (libglib-2.0.so.0)
                                             #2  0x00007f73eba7b62e g_main_context_iteration (libglib-2.0.so.0)
                                             #3  0x00007f73e7e22bde n/a (libdconfsettings.so)
                                             #4  0x00007f73eba5667b n/a (libglib-2.0.so.0)
                                             #5  0x00007f73eadafa9d start_thread (libpthread.so.0)
                                             #6  0x00007f73eb8a4b23 __clone (libc.so.6)
Dec 23 14:39:21 x230 systemd[1]: systemd-coredump@0-1812-0.service: Succeeded.
Dec 23 14:39:21 x230 kernel: audit: type=1131 audit(1545575961.383:115): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-coredump@0-1812-0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Dec 23 14:39:21 x230 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-coredump@0-1812-0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Dec 23 14:39:22 x230 lightdm[1829]: pam_unix(lightdm-greeter:session): session opened for user lightdm by (uid=0)
Dec 23 14:39:22 x230 systemd-logind[725]: New session c6 of user lightdm.
Dec 23 14:39:22 x230 systemd[1]: Started Session c6 of user lightdm.
Dec 23 14:39:22 x230 polkitd[737]: /usr/share/polkit-1/rules.d/lightdm.rules:3: action=[Action id='org.freedesktop.DisplayManager.AccountsService.ReadAny']
Dec 23 14:39:22 x230 polkitd[737]: /usr/share/polkit-1/rules.d/lightdm.rules:4: subject=[Subject pid=1832 user='lightdm' groups=lightdm seat='seat0' session='c6' local=true active=true]
Dec 23 14:39:22 x230 polkitd[737]: /usr/share/polkit-1/rules.d/lightdm.rules:3: action=[Action id='org.freedesktop.DisplayManager.AccountsService.ReadAny']
Dec 23 14:39:22 x230 polkitd[737]: /usr/share/polkit-1/rules.d/lightdm.rules:4: subject=[Subject pid=1832 user='lightdm' groups=lightdm seat='seat0' session='c6' local=true active=true]
Dec 23 14:39:23 x230 systemd[1]: session-10.scope: Succeeded.
Dec 23 14:39:23 x230 systemd-logind[725]: Removed session 10.

There’s a segfault in here.

and sudo journalctl -f

Dec 23 14:50:40 x230 systemd[1]: Reloading.
Dec 23 14:50:40 x230 systemd[1]: /usr/lib/systemd/system/auditd.service:12: PIDFile= references path below legacy directory /var/run/, updating /var/run/auditd.pid → /run/auditd.pid; please update the unit file accordingly.
Dec 23 14:50:40 x230 systemd[1]: /usr/lib/systemd/system/rpc-statd.service:12: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly.
Dec 23 14:50:40 x230 sudo[2013]: pam_unix(sudo:session): session closed for user root
Dec 23 14:54:33 x230 systemd[772]: Stopping D-Bus User Message Bus...
Dec 23 14:54:33 x230 systemd[772]: dbus.service: Succeeded.
Dec 23 14:54:33 x230 systemd[772]: Stopped D-Bus User Message Bus.
Dec 23 14:54:33 x230 systemd[772]: Started D-Bus User Message Bus.
Dec 23 14:54:55 x230 sudo[2181]: jonathon : TTY=pts/0 ; PWD=/home/jonathon ; USER=root ; COMMAND=/usr/bin/journalctl -f
Dec 23 14:54:55 x230 sudo[2181]: pam_unix(sudo:session): session opened for user root by jonathon(uid=0)
Dec 23 14:55:02 x230 pulseaudio[1343]: E: [pulseaudio] stdin-util.c: Unable to read or parse data from client.
Dec 23 14:55:02 x230 systemd[1]: session-c6.scope: Killing process 1829 (lightdm) with signal SIGTERM.
Dec 23 14:55:02 x230 systemd[1]: session-c6.scope: Killing process 1832 (lightdm-gtk-gre) with signal SIGTERM.
Dec 23 14:55:02 x230 systemd[1]: Stopping Session c6 of user lightdm.
Dec 23 14:55:02 x230 lightdm[1829]: pam_unix(lightdm-greeter:session): session closed for user lightdm
Dec 23 14:55:02 x230 systemd[1]: session-c6.scope: Succeeded.
Dec 23 14:55:02 x230 systemd[1]: Stopped Session c6 of user lightdm.
Dec 23 14:55:02 x230 systemd-logind[725]: Removed session c6.
Dec 23 14:55:02 x230 kernel: audit: type=1006 audit(1545576902.958:118): pid=1840 uid=0 old-auid=4294967295 auid=1000 tty=(none) old-ses=4294967295 ses=11 res=1
Dec 23 14:55:02 x230 lightdm[1840]: pam_unix(lightdm:session): session opened for user jonathon by (uid=0)
Dec 23 14:55:02 x230 systemd-logind[725]: New session 11 of user jonathon.
Dec 23 14:55:02 x230 systemd[1]: Started Session 11 of user jonathon.
Dec 23 14:55:03 x230 mate-session[2204]: WARNING: Could not make bus activated clients aware of DISPLAY=:0 environment variable: Could not connect: Connection refused
Dec 23 14:55:03 x230 mate-session[2204]: WARNING: Could not make bus activated clients aware of MATE_DESKTOP_SESSION_ID=this-is-deprecated environment variable: Could not connect: Connection refused
Dec 23 14:55:03 x230 mate-session[2204]: WARNING: Could not make bus activated clients aware of SESSION_MANAGER=local/x230:@/tmp/.ICE-unix/2204,unix/x230:/tmp/.ICE-unix/2204 environment variable: Could not connect: Connection refused
Dec 23 14:55:13 x230 mate-session[2204]: Gtk-CRITICAL: gtk_main_quit: assertion 'main_loops != NULL' failed
Dec 23 14:55:13 x230 mate-session[2204]: CRITICAL: dbus_g_proxy_new_for_name: assertion 'connection != NULL' failed
Dec 23 14:55:13 x230 systemd[1]: Stopping User Manager for UID 620...
Dec 23 14:55:13 x230 systemd[1559]: Stopping D-Bus User Message Bus...
Dec 23 14:55:13 x230 systemd[1559]: Stopped target Default.
Dec 23 14:55:13 x230 systemd[1559]: Stopping Virtual filesystem service...
Dec 23 14:55:13 x230 systemd[1559]: Stopping Accessibility services bus...
Dec 23 14:55:13 x230 systemd[1559]: dbus.service: Succeeded.
Dec 23 14:55:13 x230 systemd[1559]: Stopped D-Bus User Message Bus.
Dec 23 14:55:13 x230 systemd[1559]: gvfs-daemon.service: Main process exited, code=killed, status=15/TERM
Dec 23 14:55:13 x230 systemd[1559]: run-user-620-gvfs.mount: Succeeded.
Dec 23 14:55:13 x230 systemd[1]: run-user-620-gvfs.mount: Succeeded.
Dec 23 14:55:13 x230 systemd[1559]: at-spi-dbus-bus.service: Succeeded.
Dec 23 14:55:13 x230 systemd[1559]: Stopped Accessibility services bus.
Dec 23 14:55:13 x230 systemd[1559]: gvfs-daemon.service: Succeeded.
Dec 23 14:55:13 x230 systemd[1559]: Stopped Virtual filesystem service.
Dec 23 14:55:13 x230 systemd[1559]: Stopped target Basic System.
Dec 23 14:55:13 x230 systemd[1559]: Stopped target Sockets.
Dec 23 14:55:13 x230 systemd[1559]: p11-kit-server.socket: Succeeded.
Dec 23 14:55:13 x230 systemd[1559]: Closed p11-kit server.
Dec 23 14:55:13 x230 systemd[1559]: gpg-agent.socket: Succeeded.
Dec 23 14:55:13 x230 systemd[1559]: Closed GnuPG cryptographic agent and passphrase cache.
Dec 23 14:55:13 x230 systemd[1559]: pulseaudio.socket: Succeeded.
Dec 23 14:55:13 x230 systemd[1559]: Closed Sound System.
Dec 23 14:55:13 x230 systemd[1559]: dirmngr.socket: Succeeded.
Dec 23 14:55:13 x230 systemd[1559]: Closed GnuPG network certificate management daemon.
Dec 23 14:55:13 x230 systemd[1559]: gpg-agent-browser.socket: Succeeded.
Dec 23 14:55:13 x230 systemd[1559]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers).
Dec 23 14:55:13 x230 systemd[1559]: gpg-agent-extra.socket: Succeeded.
Dec 23 14:55:13 x230 systemd[1559]: Closed GnuPG cryptographic agent and passphrase cache (restricted).
Dec 23 14:55:13 x230 systemd[1559]: dbus.socket: Succeeded.
Dec 23 14:55:13 x230 systemd[1559]: Closed D-Bus User Message Bus Socket.
Dec 23 14:55:13 x230 systemd[1559]: pipewire.socket: Succeeded.
Dec 23 14:55:13 x230 systemd[1559]: Closed Multimedia System.
Dec 23 14:55:13 x230 systemd[1559]: gpg-agent-ssh.socket: Succeeded.
Dec 23 14:55:13 x230 systemd[1559]: Closed GnuPG cryptographic agent (ssh-agent emulation).
Dec 23 14:55:13 x230 systemd[1559]: Stopped target Timers.
Dec 23 14:55:13 x230 systemd[1559]: Stopped target Paths.
Dec 23 14:55:13 x230 systemd[1559]: Reached target Shutdown.
Dec 23 14:55:13 x230 systemd[1559]: systemd-exit.service: Succeeded.
Dec 23 14:55:13 x230 systemd[1559]: Started Exit the Session.
Dec 23 14:55:13 x230 systemd[1559]: Reached target Exit the Session.
Dec 23 14:55:13 x230 systemd[1]: user@620.service: Succeeded.
Dec 23 14:55:13 x230 systemd[1]: Stopped User Manager for UID 620.
Dec 23 14:55:13 x230 kernel: audit: type=1131 audit(1545576913.218:119): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@620 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Dec 23 14:55:13 x230 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@620 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Dec 23 14:55:13 x230 systemd[772]: run-user-620-gvfs.mount: Succeeded.
Dec 23 14:55:13 x230 systemd[1]: Stopping User Runtime Directory /run/user/620...
Dec 23 14:55:13 x230 systemd[772]: run-user-620.mount: Succeeded.
Dec 23 14:55:13 x230 systemd[1]: run-user-620.mount: Succeeded.
Dec 23 14:55:13 x230 systemd[1]: user-runtime-dir@620.service: Succeeded.
Dec 23 14:55:13 x230 systemd[1]: Stopped User Runtime Directory /run/user/620.
Dec 23 14:55:13 x230 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@620 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Dec 23 14:55:13 x230 systemd[1]: Removed slice User Slice of UID 620.
Dec 23 14:55:13 x230 kernel: audit: type=1131 audit(1545576913.241:120): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@620 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Dec 23 14:55:29 x230 mate-session[2204]: Gtk-CRITICAL: gtk_main_quit: assertion 'main_loops != NULL' failed
Dec 23 14:55:29 x230 mate-session[2204]: GLib-GObject-CRITICAL: g_object_unref: assertion 'G_IS_OBJECT (object)' failed
Dec 23 14:55:29 x230 gnome-keyring-daemon[2188]: couldn't connect to dbus session bus: Could not connect: Connection refused
Dec 23 14:55:29 x230 gnome-keyring-daemon[2188]: couldn't connect to dbus session bus: Could not connect: Connection refused
Dec 23 14:55:29 x230 mate-session[2204]: WARNING: Could not make bus activated clients aware of GTK_OVERLAY_SCROLLING=0 environment variable: Could not connect: Connection refused
Dec 23 14:55:29 x230 mate-session[2204]: CRITICAL: error getting session bus: Failed to connect to socket /tmp/dbus-OYPvl3z2S8: Connection refused
Dec 23 14:55:29 x230 mate-session[2204]: WARNING: Unable to register presence with session bus
Dec 23 14:55:29 x230 mate-session[2204]: CRITICAL: dbus_g_proxy_new_for_name: assertion 'connection != NULL' failed
Dec 23 14:55:29 x230 mate-session[2204]: CRITICAL: error getting session bus: Failed to connect to socket /tmp/dbus-OYPvl3z2S8: Connection refused
Dec 23 14:55:29 x230 lightdm[1840]: pam_unix(lightdm:session): session closed for user jonathon
Dec 23 14:55:29 x230 systemd-logind[725]: Session 11 logged out. Waiting for processes to exit.
Dec 23 14:55:31 x230 lightdm[2226]: pam_unix(lightdm-greeter:session): session opened for user lightdm by (uid=0)
Dec 23 14:55:31 x230 systemd[1]: Created slice User Slice of UID 620.
Dec 23 14:55:31 x230 systemd[1]: Starting User Runtime Directory /run/user/620...
Dec 23 14:55:31 x230 systemd-logind[725]: New session c7 of user lightdm.
Dec 23 14:55:31 x230 systemd[1]: Started User Runtime Directory /run/user/620.
Dec 23 14:55:31 x230 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@620 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Dec 23 14:55:31 x230 systemd[1]: session-11.scope: Succeeded.
Dec 23 14:55:31 x230 systemd[1]: Starting User Manager for UID 620...
Dec 23 14:55:31 x230 systemd-logind[725]: Removed session 11.
Dec 23 14:55:31 x230 kernel: audit: type=1130 audit(1545576931.201:121): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@620 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Dec 23 14:55:31 x230 kernel: audit: type=1006 audit(1545576931.238:122): pid=2230 uid=0 old-auid=4294967295 auid=620 tty=(none) old-ses=4294967295 ses=12 res=1
Dec 23 14:55:31 x230 systemd[2230]: pam_unix(systemd-user:session): session opened for user lightdm by (uid=0)
Dec 23 14:55:31 x230 systemd[2230]: Listening on GnuPG network certificate management daemon.
Dec 23 14:55:31 x230 systemd[2230]: Condition check resulted in Mark boot as successful after the user session has run 2 minutes being skipped.
Dec 23 14:55:31 x230 systemd[2230]: Reached target Timers.
Dec 23 14:55:31 x230 systemd[2230]: Listening on GnuPG cryptographic agent and passphrase cache.
Dec 23 14:55:31 x230 systemd[2230]: Starting D-Bus User Message Bus Socket.
Dec 23 14:55:31 x230 systemd[2230]: Listening on Sound System.
Dec 23 14:55:31 x230 systemd[2230]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers).
Dec 23 14:55:31 x230 systemd[2230]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Dec 23 14:55:31 x230 systemd[2230]: Listening on p11-kit server.
Dec 23 14:55:31 x230 systemd[2230]: Listening on Multimedia System.
Dec 23 14:55:31 x230 systemd[2230]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Dec 23 14:55:31 x230 systemd[2230]: Reached target Paths.
Dec 23 14:55:31 x230 systemd[2230]: Listening on D-Bus User Message Bus Socket.
Dec 23 14:55:31 x230 systemd[2230]: Reached target Sockets.
Dec 23 14:55:31 x230 systemd[2230]: Reached target Basic System.
Dec 23 14:55:31 x230 systemd[1]: Started User Manager for UID 620.
Dec 23 14:55:31 x230 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@620 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Dec 23 14:55:31 x230 systemd[1]: Started Session c7 of user lightdm.
Dec 23 14:55:31 x230 systemd[2230]: Starting Update XDG user dir configuration...
Dec 23 14:55:31 x230 kernel: audit: type=1130 audit(1545576931.288:123): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@620 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Dec 23 14:55:31 x230 systemd[2230]: xdg-user-dirs-update.service: Succeeded.
Dec 23 14:55:31 x230 systemd[2230]: Started Update XDG user dir configuration.
Dec 23 14:55:31 x230 systemd[2230]: Reached target Default.
Dec 23 14:55:31 x230 systemd[2230]: Startup finished in 48ms.
Dec 23 14:55:31 x230 systemd[2230]: Started D-Bus User Message Bus.
Dec 23 14:55:31 x230 dbus-daemon[2240]: [session uid=620 pid=2240] Activating via systemd: service name='org.a11y.Bus' unit='at-spi-dbus-bus.service' requested by ':1.1' (uid=620 pid=2237 comm="/usr/bin/lightdm-gtk-greeter ")
Dec 23 14:55:31 x230 systemd[2230]: Starting Accessibility services bus...
Dec 23 14:55:31 x230 dbus-daemon[2240]: [session uid=620 pid=2240] Successfully activated service 'org.a11y.Bus'
Dec 23 14:55:31 x230 systemd[2230]: Started Accessibility services bus.
Dec 23 14:55:31 x230 at-spi-bus-launcher[2241]: dbus-daemon[2247]: Activating service name='org.a11y.atspi.Registry' requested by ':1.0' (uid=620 pid=2237 comm="/usr/bin/lightdm-gtk-greeter ")
Dec 23 14:55:31 x230 dbus-daemon[2240]: [session uid=620 pid=2240] Activating via systemd: service name='org.gtk.vfs.Daemon' unit='gvfs-daemon.service' requested by ':1.4' (uid=620 pid=2237 comm="/usr/bin/lightdm-gtk-greeter ")
Dec 23 14:55:31 x230 at-spi-bus-launcher[2241]: dbus-daemon[2247]: Successfully activated service 'org.a11y.atspi.Registry'
Dec 23 14:55:31 x230 systemd[2230]: Starting Virtual filesystem service...
Dec 23 14:55:31 x230 at-spi-bus-launcher[2241]: SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry
Dec 23 14:55:31 x230 dbus-daemon[2240]: [session uid=620 pid=2240] Successfully activated service 'org.gtk.vfs.Daemon'
Dec 23 14:55:31 x230 systemd[2230]: Started Virtual filesystem service.
Dec 23 14:55:31 x230 polkitd[737]: /usr/share/polkit-1/rules.d/lightdm.rules:3: action=[Action id='org.freedesktop.DisplayManager.AccountsService.ReadAny']
Dec 23 14:55:31 x230 polkitd[737]: /usr/share/polkit-1/rules.d/lightdm.rules:4: subject=[Subject pid=2237 user='lightdm' groups=lightdm seat='seat0' session='c7' local=true active=true]
Dec 23 14:55:31 x230 polkitd[737]: /usr/share/polkit-1/rules.d/lightdm.rules:3: action=[Action id='org.freedesktop.DisplayManager.AccountsService.ReadAny']
Dec 23 14:55:31 x230 polkitd[737]: /usr/share/polkit-1/rules.d/lightdm.rules:4: subject=[Subject pid=2237 user='lightdm' groups=lightdm seat='seat0' session='c7' local=true active=true]

I suspect this is the main cause:

CRITICAL: error getting session bus: Failed to connect to socket /tmp/dbus-OYPvl3z2S8: Connection refused

The file/directory isn’t present in /tmp so either the location/socket has changed and/or something is setting an incorrect environment variable.


From https://bbs.archlinux.org/viewtopic.php?pid=1659106#p1659106:

I don’t have any pacnew etc. files.


#34

Well, Arch also updated systemd to v240 series in testing. What would be the output of:

printenv DBUS_SESSION_BUS_ADDRESS
echo ${DBUS_SESSION_BUS_ADDRESS}

Will update dbus-x11 now also in unstable.


#35

On a working i3 install with dbus 1.12.12-1 and systemd 240.1-2 installed i get nothing from any of those commands.

On Gnome and KDE unstable with dbus 1.12.12-1 and systemd 240.1-2 installed i get the correct unix path:
unix:path=/run/user/1000/bus

On Budgie that also has dbus installed and i run the desktop session by startx via TTY2, the commands print:

[b@bb ~]$ printenv DBUS_SESSION_BUS_ADDRESS
unix:abstract=/tmp/dbus-hHFuXbkk3W,guid=be7f3bb11edecd6643cc23825c1fa825
[b@bb ~]$ echo ${DBUS_SESSION_BUS_ADDRESS}
unix:abstract=/tmp/dbus-hHFuXbkk3W,guid=be7f3bb11edecd6643cc23825c1fa825

and change if i log out and log back in.

On XFCE where i installed dbus-x11 i get:

unix:abstract=/tmp/dbus-CJmXiUHsHH,guid=26738806a294918029ff3d655c1fa8e1

that also will change if i log out and back in.


#36

Adding

export DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/$UID/bus

to my $HOME/.profile allows me to log in correctly.

So, what is setting this correctly for GNOME&KDE but is not setting it for other DEs?


#37

With dbus-x11 I can start XFCE, however I was not able to do the same with dbus yet. On dbus-x11 I already have the correct path:

phil@development ~ $ printenv DBUS_SESSION_BUS_ADDRESS
unix:path=/run/user/1000/bus


#38

I suspect this is the cause:

I’m not sure if that means it might be a bug in the DEs/WMs as they’re not looking in the correct location or in dbus if it’s not (or no longer) looking there.


#39

.profile
in Budgie that does’t work and i didn;t replace anything yet:

export GTK2_RC_FILES="$HOME/.gtkrc-2.0"
export QT_QPA_PLATFORMTHEME="qt5ct"
export EDITOR=/usr/bin/nano

in i3 that works and prints no output

export QT_QPA_PLATFORMTHEME="qt5ct"
export EDITOR=/usr/bin/nano
export GTK2_RC_FILES="$HOME/.gtkrc-2.0"
export BROWSER=/usr/bin/palemoon

On Gnome that works:

export QT_QPA_PLATFORMTHEME="qt5ct"
export EDITOR=/usr/bin/nano

on KDE Plasma that works:

export EDITOR=/usr/bin/nano
export GTK_MODULES=appmenu-gtk-module
export GTK_CSD=0
export GTK_USE_PORTAL=1

that is different than the .profile i have on my main rig KDE Plasma (that seems to not be able to run 5 VBox installs simultaneously :slight_smile: without a bit of hiccup … The XFCE VBox hanged now):

[[ "$XDG_CURRENT_DESKTOP" == "KDE" ]] || export QT_QPA_PLATFORMTHEME="qt5ct"

[[ -f ~/.extend.profile ]] && . ~/.extend.profile
export WINEARCH = "win64"

Now i’m definitely confused.


#40

For reference, diff between compilation flags for dbus-x11 and dbus:

--- https://gitlab.manjaro.org/packages/core/dbus-x11/raw/master/PKGBUILD
+++ https://git.archlinux.org/svntogit/packages.git/plain/trunk/PKGBUILD?h=packages/dbus
 build() {
-  cd $_pkgname
+  cd dbus
   ./configure \
     --prefix=/usr \
     --sysconfdir=/etc \
     --localstatedir=/var \
+    runstatedir=/run \
     --libexecdir=/usr/lib/dbus-1.0 \
     --with-system-socket=/run/dbus/system_bus_socket \
+    --with-dbus-session-bus-connect-address=unix:runtime=yes \
     --with-dbus-user=dbus \
-    --with-system-pid-file=/run/dbus/pid \
-    --with-console-auth-dir=/run/console/ \
-    --enable-inotify \
-    --disable-verbose-mode --disable-asserts \
-    --with-systemdsystemunitdir=/usr/lib/systemd/system \
-    --enable-systemd \
     --enable-user-session \
     --disable-static \
-    --enable-x11-autolaunch
+    --without-x
   make
 }

Aside: pretty awesome that meld will open files directly from the web. :smiley: