[oS-en] 20 second delay to start a program.
-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1 I see this in a small laptop upgraded this afternoon to 15.3. I open an ssh session to it, as root, then run: Legolas:~ # meld /etc/ssh/sshd_config.rpmnew /etc/ssh/sshd_config & [1] 1864 Legolas:~ # [1]+ Done meld /etc/ssh/sshd_config.rpmnew /etc/ssh/sshd_config Legolas:~ # The program takes 20 seconds to appear, there is a timeout in the log <3.6> 2021-12-24 22:42:07 Legolas dbus-daemon 1029 - - [session uid=0 pid=1029] Activating via systemd: service name='org.freedesktop.portal.Desktop' unit='xdg-desktop-portal.service' requested by ':1.38' (uid=0 pid=1864 comm="/usr/bin/python3 /usr/bin/meld /etc/ssh/sshd_confi") <3.6> 2021-12-24 22:42:07 Legolas systemd 542 - - Starting Portal service... <3.6> 2021-12-24 22:42:07 Legolas dbus-daemon 1029 - - [session uid=0 pid=1029] Activating via systemd: service name='org.freedesktop.impl.portal.desktop.gtk' unit='xdg-desktop-portal-gtk.service' requested by ':1.39' (uid=0 pid=1870 comm="/usr/lib/xdg-desktop-portal ") <3.6> 2021-12-24 22:42:07 Legolas systemd 542 - - Starting Portal service (GTK+/GNOME implementation)... <3.6> 2021-12-24 22:42:07 Legolas xdg-desktop-portal-gtk 1891 - - Unable to init server: Could not connect: Connection refused <1.4> 2021-12-24 22:42:07 Legolas xdg-desktop-por 1891 - - cannot open display: <3.5> 2021-12-24 22:42:07 Legolas systemd 542 - - xdg-desktop-portal-gtk.service: Main process exited, code=exited, status=1/FAILURE <3.4> 2021-12-24 22:42:07 Legolas systemd 542 - - xdg-desktop-portal-gtk.service: Failed with result 'exit-code'. <3.3> 2021-12-24 22:42:07 Legolas systemd 542 - - Failed to start Portal service (GTK+/GNOME implementation). <3.6> 2021-12-24 22:42:33 Legolas dbus-daemon 1199 - - [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.133' (uid=0 pid=1864 comm="/usr/bin/python3 /usr/bin/meld /etc/ssh/sshd_confi") <3.6> 2021-12-24 22:42:33 Legolas systemd 1 - - Starting Hostname Service... (program closed about here) <3.6> 2021-12-24 22:42:34 Legolas dbus-daemon 1199 - - [system] Successfully activated service 'org.freedesktop.hostname1' <3.6> 2021-12-24 22:42:34 Legolas systemd 1 - - Started Hostname Service. <1.4> 2021-12-24 22:42:57 Legolas xdg-desktop-por 1870 - - Failed to create settings proxy: Error calling StartServiceByName for org.freedesktop.impl.portal.desktop.gtk: Timeout was reached <3.6> 2021-12-24 22:43:04 Legolas systemd 1 - - systemd-hostnamed.service: Succeeded. <1.4> 2021-12-24 22:43:22 Legolas xdg-desktop-por 1870 - - Failed to create file chooser proxy: Error calling StartServiceByName for org.freedesktop.impl.portal.desktop.gtk: Timeout was reached <1.4> 2021-12-24 22:43:22 Legolas xdg-desktop-por 1870 - - No skeleton to export <3.4> 2021-12-24 22:43:37 Legolas systemd 542 - - xdg-desktop-portal.service: start operation timed out. Terminating. <3.4> 2021-12-24 22:43:37 Legolas systemd 542 - - xdg-desktop-portal.service: Failed with result 'timeout'. <3.3> 2021-12-24 22:43:37 Legolas systemd 542 - - Failed to start Portal service. <4.5> 2021-12-24 22:44:07 Legolas dbus-daemon 1029 - - [session uid=0 pid=1029] Failed to activate service 'org.freedesktop.portal.Desktop': timed out (service_start_timeout=120000ms) <4.5> 2021-12-24 22:44:07 Legolas dbus-daemon 1029 - - [session uid=0 pid=1029] Failed to activate service 'org.freedesktop.impl.portal.desktop.gtk': timed out (service_start_timeout=120000ms) <3.6> 2021-12-24 22:49:56 Legolas smartd 1245 - - Device: /dev/sda [SAT], SMART Usage Attribute: 194 Temperature_Celsius changed from 72 to 73 - -- Cheers Carlos E. R. (from 15.2 x86_64 at Telcontar) -----BEGIN PGP SIGNATURE----- iHoEARECADoWIQQZEb51mJKK1KpcU/W1MxgcbY1H1QUCYcZCPhwccm9iaW4ubGlz dGFzQHRlbGVmb25pY2EubmV0AAoJELUzGBxtjUfVnboAnjdWssB5KF1TFiBYArC3 5vO7zSvIAKCYIXeJuzjiFkpKWXH8/pzP/gACyA== =wFK5 -----END PGP SIGNATURE-----
-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1 On Friday, 2021-12-24 at 22:57 +0100, Carlos E. R. wrote:
I see this in a small laptop upgraded this afternoon to 15.3. I open an ssh session to it, as root, then run:
Legolas:~ # meld /etc/ssh/sshd_config.rpmnew /etc/ssh/sshd_config & [1] 1864 Legolas:~ # [1]+ Done meld /etc/ssh/sshd_config.rpmnew /etc/ssh/sshd_config Legolas:~ #
The program takes 20 seconds to appear, there is a timeout in the log
This doesn't help: Legolas:~ # GTK_MODULES=appmenu-gtk-module meld /etc/zypp/zypp.conf.rpmnew /etc/zypp/zypp.conf & [1] 8177 Legolas:~ # Gtk-Message: 22:51:01.413: Failed to load module "appmenu-gtk-module" [1]+ Done GTK_MODULES=appmenu-gtk-module meld /etc/zypp/zypp.conf.rpmnew /etc/zypp/zypp.conf Legolas:~ # However, running the command locally (the above is remotely, via ssh), does work: <1.4> 2021-12-26 22:55:39 Legolas xfce4-power-man 2553 - - Source ID 22172 was not found when attempting to remove it <1.4> 2021-12-26 22:55:47 Legolas xfce4-power-man 2553 - - Source ID 22176 was not found when attempting to remove it <1.4> 2021-12-26 22:55:50 Legolas xfce4-power-man 2553 - - Source ID 22179 was not found when attempting to remove it <1.4> 2021-12-26 22:55:52 Legolas xfce4-power-man 2553 - - Source ID 22182 was not found when attempting to remove it <1.4> 2021-12-26 22:55:53 Legolas xfce4-power-man 2553 - - Source ID 22185 was not found when attempting to remove it <1.4> 2021-12-26 22:55:58 Legolas xfce4-power-man 2553 - - Source ID 22188 was not found when attempting to remove it <1.4> 2021-12-26 22:56:01 Legolas xfce4-power-man 2553 - - Source ID 22191 was not found when attempting to remove it <1.4> 2021-12-26 22:56:03 Legolas xfce4-power-man 2553 - - Source ID 22194 was not found when attempting to remove it <1.4> 2021-12-26 22:56:04 Legolas xfce4-power-man 2553 - - Source ID 22197 was not found when attempting to remove it <1.4> 2021-12-26 22:56:09 Legolas xfce4-power-man 2553 - - Source ID 22200 was not found when attempting to remove it <1.4> 2021-12-26 22:56:12 Legolas xfce4-power-man 2553 - - Source ID 22203 was not found when attempting to remove it <1.4> 2021-12-26 22:56:20 Legolas xfce4-power-man 2553 - - Source ID 22207 was not found when attempting to remove it <1.4> 2021-12-26 22:56:23 Legolas xfce4-power-man 2553 - - Source ID 22210 was not found when attempting to remove it <1.4> 2021-12-26 22:56:25 Legolas xfce4-power-man 2553 - - Source ID 22213 was not found when attempting to remove it <10.6> 2021-12-26 22:56:26 Legolas unix2_chkpwd - - - gkr-pam: unlocked login keyring <1.4> 2021-12-26 22:56:28 Legolas xfce4-power-man 2553 - - Source ID 22216 was not found when attempting to remove it <1.4> 2021-12-26 22:56:30 Legolas xfce4-power-man 2553 - - Source ID 22219 was not found when attempting to remove it <1.4> 2021-12-26 22:56:34 Legolas xfce4-power-man 2553 - - Source ID 22222 was not found when attempting to remove it <1.4> 2021-12-26 22:56:36 Legolas xfce4-power-man 2553 - - Source ID 22225 was not found when attempting to remove it <1.4> 2021-12-26 22:56:39 Legolas xfce4-power-man 2553 - - Source ID 22228 was not found when attempting to remove it <3.6> 2021-12-26 22:56:56 Legolas dbus-daemon 10222 - - [session uid=0 pid=10220] Activating service name='org.freedesktop.portal.Desktop' requested by ':1.0' (uid=0 pid=10213 comm="/usr/bin/python3 /usr/bin/meld /etc/ssh/sshd_confi") <3.6> 2021-12-26 22:56:56 Legolas dbus-daemon 10222 - - [session uid=0 pid=10220] Activating service name='org.freedesktop.portal.Documents' requested by ':1.1' (uid=0 pid=10225 comm="/usr/lib/xdg-desktop-portal ") <3.6> 2021-12-26 22:56:56 Legolas dbus-daemon 10222 - - [session uid=0 pid=10220] Activating service name='org.freedesktop.impl.portal.PermissionStore' requested by ':1.2' (uid=0 pid=10230 comm="/usr/lib/xdg-document-portal ") <3.6> 2021-12-26 22:56:56 Legolas dbus-daemon 10222 - - [session uid=0 pid=10220] Successfully activated service 'org.freedesktop.impl.portal.PermissionStore' <3.6> 2021-12-26 22:56:56 Legolas dbus-daemon 10222 - - [session uid=0 pid=10220] Successfully activated service 'org.freedesktop.portal.Documents' <3.6> 2021-12-26 22:56:56 Legolas dbus-daemon 10222 - - [session uid=0 pid=10220] Activating service name='org.freedesktop.impl.portal.desktop.gtk' requested by ':1.1' (uid=0 pid=10225 comm="/usr/lib/xdg-desktop-portal ") <3.6> 2021-12-26 22:56:56 Legolas dbus-daemon 10222 - - [session uid=0 pid=10220] Activating service name='ca.desrt.dconf' requested by ':1.4' (uid=0 pid=10245 comm="/usr/lib/xdg-desktop-portal-gtk ") <3.6> 2021-12-26 22:56:56 Legolas dbus-daemon 10222 - - [session uid=0 pid=10220] Successfully activated service 'ca.desrt.dconf' <3.6> 2021-12-26 22:56:56 Legolas dbus-daemon 10222 - - [session uid=0 pid=10220] Activating service name='org.gtk.vfs.Daemon' requested by ':1.4' (uid=0 pid=10245 comm="/usr/lib/xdg-desktop-portal-gtk ") <3.6> 2021-12-26 22:56:56 Legolas dbus-daemon 10222 - - [session uid=0 pid=10220] Successfully activated service 'org.gtk.vfs.Daemon' <3.6> 2021-12-26 22:56:56 Legolas dbus-daemon 10222 - - [session uid=0 pid=10220] Successfully activated service 'org.freedesktop.impl.portal.desktop.gtk' <1.4> 2021-12-26 22:56:57 Legolas xdg-desktop-por 10225 - - Failed connect to PipeWire: Couldn't connect to PipeWire <3.6> 2021-12-26 22:56:57 Legolas dbus-daemon 10222 - - [session uid=0 pid=10220] Successfully activated service 'org.freedesktop.portal.Desktop' <3.6> 2021-12-26 22:56:58 Legolas dbus-daemon 1199 - - [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.267' (uid=0 pid=10213 comm="/usr/bin/python3 /usr/bin/meld /etc/ssh/sshd_confi") <3.6> 2021-12-26 22:56:58 Legolas systemd 1 - - Starting Hostname Service... <3.6> 2021-12-26 22:56:59 Legolas dbus-daemon 1199 - - [system] Successfully activated service 'org.freedesktop.hostname1' <3.6> 2021-12-26 22:56:59 Legolas systemd 1 - - Started Hostname Service. <3.6> 2021-12-26 22:57:27 Legolas dbus-daemon 10222 - - [session uid=0 pid=10220] Activating service name='org.freedesktop.impl.portal.desktop.gtk' requested by ':1.1' (uid=0 pid=10225 comm="/usr/lib/xdg-desktop-portal ") <3.6> 2021-12-26 22:57:27 Legolas dbus-daemon 10222 - - [session uid=0 pid=10220] Successfully activated service 'org.freedesktop.impl.portal.desktop.gtk' <1.4> 2021-12-26 22:57:27 Legolas xdg-desktop-por 10225 - - Failed to get application states: GDBus.Error:org.freedesktop.portal.Error.Failed: Could not get window list <3.6> 2021-12-26 22:57:29 Legolas systemd 1 - - systemd-hostnamed.service: Succeeded. <3.6> 2021-12-26 22:57:57 Legolas dbus-daemon 10222 - - [session uid=0 pid=10220] Activating service name='org.freedesktop.impl.portal.desktop.gtk' requested by ':1.1' (uid=0 pid=10225 comm="/usr/lib/xdg-desktop-portal ") <3.6> 2021-12-26 22:57:57 Legolas dbus-daemon 10222 - - [session uid=0 pid=10220] Successfully activated service 'org.freedesktop.impl.portal.desktop.gtk' <3.6> 2021-12-26 23:00:03 Legolas systemd 1 - - Started Timeline of Snapper Snapshots. <3.6> 2021-12-26 23:00:03 Legolas dbus-daemon 1199 - - [system] Activating via systemd: service name='org.opensuse.Snapper' unit='snapperd.service' requested by ':1.271' (uid=0 pid=10522 comm="/usr/lib/snapper/systemd-helper --timeline ") <3.6> 2021-12-26 23:00:03 Legolas systemd 1 - - Starting DBus interface for snapper... <3.6> 2021-12-26 23:00:03 Legolas dbus-daemon 1199 - - [system] Successfully activated service 'org.opensuse.Snapper' <3.6> 2021-12-26 23:00:03 Legolas systemd 1 - - Started DBus interface for snapper. <3.6> 2021-12-26 23:00:03 Legolas systemd 1 - - snapper-timeline.service: Succeeded. <3.6> 2021-12-26 23:01:03 Legolas systemd 1 - - snapperd.service: Succeeded. - -- Cheers, Carlos E. R. (from openSUSE 15.2 x86_64 at Telcontar) -----BEGIN PGP SIGNATURE----- iHoEARECADoWIQQZEb51mJKK1KpcU/W1MxgcbY1H1QUCYcjmfRwccm9iaW4ubGlz dGFzQHRlbGVmb25pY2EubmV0AAoJELUzGBxtjUfV6+QAnA8cfde5kPmhD+9q6WYx TtaDssCAAJ4xjalMQHULE/2TMCt+DEzadee/jw== =6FD7 -----END PGP SIGNATURE-----
On Sat, Dec 25, 2021 at 12:58 AM Carlos E. R. <robin.listas@telefonica.net> wrote:
-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1
I see this in a small laptop upgraded this afternoon to 15.3. I open an ssh session to it, as root, then run:
Legolas:~ # meld /etc/ssh/sshd_config.rpmnew /etc/ssh/sshd_config & [1] 1864 Legolas:~ # [1]+ Done meld /etc/ssh/sshd_config.rpmnew /etc/ssh/sshd_config Legolas:~ #
The program takes 20 seconds to appear, there is a timeout in the log
There are several gaps between messages in log you quoted, which one specifically do you mean?
<3.6> 2021-12-24 22:42:07 Legolas dbus-daemon 1029 - - [session uid=0 pid=1029] Activating via systemd: service name='org.freedesktop.portal.Desktop' unit='xdg-desktop-portal.service' requested by ':1.38' (uid=0 pid=1864 comm="/usr/bin/python3 /usr/bin/meld /etc/ssh/sshd_confi")
That is not going to work. You connect via ssh and likely forward X11 connection from remote system to local display. But your login session on the remote system has its own session D-Bus instance which knows nothing about the desktop environment on your client. So your GUI application attempts to invoke services on your client desktop via connection to server D-Bus. To transparently start applications via ssh X11 forwarding one would need to also forward D-Bus connection. While it is possible to forward D-Bus socket (I tested that it works) security implications are not clear (D-Bus can no longer verify the user that makes the request. All requests will appear to come from the client ssh process).
<3.6> 2021-12-24 22:42:07 Legolas systemd 542 - - Starting Portal service... <3.6> 2021-12-24 22:42:07 Legolas dbus-daemon 1029 - - [session uid=0 pid=1029] Activating via systemd: service name='org.freedesktop.impl.portal.desktop.gtk' unit='xdg-desktop-portal-gtk.service' requested by ':1.39' (uid=0 pid=1870 comm="/usr/lib/xdg-desktop-portal ") <3.6> 2021-12-24 22:42:07 Legolas systemd 542 - - Starting Portal service (GTK+/GNOME implementation)... <3.6> 2021-12-24 22:42:07 Legolas xdg-desktop-portal-gtk 1891 - - Unable to init server: Could not connect: Connection refused <1.4> 2021-12-24 22:42:07 Legolas xdg-desktop-por 1891 - - cannot open display: <3.5> 2021-12-24 22:42:07 Legolas systemd 542 - - xdg-desktop-portal-gtk.service: Main process exited, code=exited, status=1/FAILURE <3.4> 2021-12-24 22:42:07 Legolas systemd 542 - - xdg-desktop-portal-gtk.service: Failed with result 'exit-code'. <3.3> 2021-12-24 22:42:07 Legolas systemd 542 - - Failed to start Portal service (GTK+/GNOME implementation). <3.6> 2021-12-24 22:42:33 Legolas dbus-daemon 1199 - - [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.133' (uid=0 pid=1864 comm="/usr/bin/python3 /usr/bin/meld /etc/ssh/sshd_confi") <3.6> 2021-12-24 22:42:33 Legolas systemd 1 - - Starting Hostname Service...
(program closed about here)
<3.6> 2021-12-24 22:42:34 Legolas dbus-daemon 1199 - - [system] Successfully activated service 'org.freedesktop.hostname1' <3.6> 2021-12-24 22:42:34 Legolas systemd 1 - - Started Hostname Service. <1.4> 2021-12-24 22:42:57 Legolas xdg-desktop-por 1870 - - Failed to create settings proxy: Error calling StartServiceByName for org.freedesktop.impl.portal.desktop.gtk: Timeout was reached <3.6> 2021-12-24 22:43:04 Legolas systemd 1 - - systemd-hostnamed.service: Succeeded. <1.4> 2021-12-24 22:43:22 Legolas xdg-desktop-por 1870 - - Failed to create file chooser proxy: Error calling StartServiceByName for org.freedesktop.impl.portal.desktop.gtk: Timeout was reached <1.4> 2021-12-24 22:43:22 Legolas xdg-desktop-por 1870 - - No skeleton to export <3.4> 2021-12-24 22:43:37 Legolas systemd 542 - - xdg-desktop-portal.service: start operation timed out. Terminating. <3.4> 2021-12-24 22:43:37 Legolas systemd 542 - - xdg-desktop-portal.service: Failed with result 'timeout'. <3.3> 2021-12-24 22:43:37 Legolas systemd 542 - - Failed to start Portal service. <4.5> 2021-12-24 22:44:07 Legolas dbus-daemon 1029 - - [session uid=0 pid=1029] Failed to activate service 'org.freedesktop.portal.Desktop': timed out (service_start_timeout=120000ms) <4.5> 2021-12-24 22:44:07 Legolas dbus-daemon 1029 - - [session uid=0 pid=1029] Failed to activate service 'org.freedesktop.impl.portal.desktop.gtk': timed out (service_start_timeout=120000ms) <3.6> 2021-12-24 22:49:56 Legolas smartd 1245 - - Device: /dev/sda [SAT], SMART Usage Attribute: 194 Temperature_Celsius changed from 72 to 73
- -- Cheers
Carlos E. R. (from 15.2 x86_64 at Telcontar)
-----BEGIN PGP SIGNATURE-----
iHoEARECADoWIQQZEb51mJKK1KpcU/W1MxgcbY1H1QUCYcZCPhwccm9iaW4ubGlz dGFzQHRlbGVmb25pY2EubmV0AAoJELUzGBxtjUfVnboAnjdWssB5KF1TFiBYArC3 5vO7zSvIAKCYIXeJuzjiFkpKWXH8/pzP/gACyA== =wFK5 -----END PGP SIGNATURE-----
-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1 On Monday, 2021-12-27 at 20:11 +0300, Andrei Borzenkov wrote:
On Sat, Dec 25, 2021 at 12:58 AM Carlos E. R. <> wrote:
I see this in a small laptop upgraded this afternoon to 15.3. I open an ssh session to it, as root, then run:
Legolas:~ # meld /etc/ssh/sshd_config.rpmnew /etc/ssh/sshd_config & [1] 1864 Legolas:~ # [1]+ Done meld /etc/ssh/sshd_config.rpmnew /etc/ssh/sshd_config Legolas:~ #
The program takes 20 seconds to appear, there is a timeout in the log
There are several gaps between messages in log you quoted, which one specifically do you mean?
I think the application starts just here 22:42:07 22:42:33 But I'd better do another attempt and check, because I don't remember. The machine is suspended this instant, so perhaps there may be wake up related messages inserted in the flow. (desktop is xfce, in case I forgot and is relevant) Start application here (meld), via ssh (ssh -X root@legolas.valinor): <3.6> 2021-12-27 20:05:27 Legolas systemd 6532 - - Started D-Bus User Message Bus. <3.6> 2021-12-27 20:05:27 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Activating via systemd: service name='org.a11y.Bus' unit='at-spi-dbus-bus.service' requested by ':1.1' (uid=0 pid=6647 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:05:27 Legolas systemd 6532 - - Starting Accessibility services bus... <3.6> 2021-12-27 20:05:28 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Successfully activated service 'org.a11y.Bus' <3.6> 2021-12-27 20:05:28 Legolas systemd 6532 - - Started Accessibility services bus. <3.6> 2021-12-27 20:05:28 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Activating via systemd: service name='org.gtk.vfs.Daemon' unit='gvfs-daemon.service' requested by ':1.3' (uid=0 pid=6647 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:05:28 Legolas systemd 6532 - - Starting Virtual filesystem service... <3.6> 2021-12-27 20:05:28 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Successfully activated service 'org.gtk.vfs.Daemon' <3.6> 2021-12-27 20:05:28 Legolas systemd 6532 - - Started Virtual filesystem service. <3.6> 2021-12-27 20:05:28 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Activating via systemd: service name='org.freedesktop.portal.Desktop' unit='xdg-desktop-portal.service' requested by ':1.3' (uid=0 pid=6647 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:05:28 Legolas systemd 6532 - - Starting Portal service... <3.6> 2021-12-27 20:05:28 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Activating via systemd: service name='org.freedesktop.portal.Documents' unit='xdg-document-portal.service' requested by ':1.6' (uid=0 pid=6683 comm="/usr/lib/xdg-desktop-portal ") <3.6> 2021-12-27 20:05:28 Legolas systemd 6532 - - Starting flatpak document portal service... <3.6> 2021-12-27 20:05:28 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Activating via systemd: service name='org.freedesktop.impl.portal.PermissionStore' unit='xdg-permission-store.service' requested by ':1.7' (uid=0 pid=6687 comm="/usr/lib/xdg-document-portal ") <3.6> 2021-12-27 20:05:28 Legolas systemd 6532 - - Starting sandboxed app permission store... <3.6> 2021-12-27 20:05:28 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Successfully activated service 'org.freedesktop.impl.portal.PermissionStore' <3.6> 2021-12-27 20:05:29 Legolas systemd 6532 - - Started sandboxed app permission store. <3.6> 2021-12-27 20:05:29 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Successfully activated service 'org.freedesktop.portal.Documents' <3.6> 2021-12-27 20:05:29 Legolas systemd 6532 - - Started flatpak document portal service. <3.6> 2021-12-27 20:05:29 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Activating via systemd: service name='org.freedesktop.impl.portal.desktop.gtk' unit='xdg-desktop-portal-gtk.service' requested by ':1.6' (uid=0 pid=6683 comm="/usr/lib/xdg-desktop-portal ") <3.6> 2021-12-27 20:05:29 Legolas systemd 6532 - - Starting Portal service (GTK+/GNOME implementation)... <3.6> 2021-12-27 20:05:29 Legolas xdg-desktop-portal-gtk 6699 - - Unable to init server: Could not connect: Connection refused <1.4> 2021-12-27 20:05:29 Legolas xdg-desktop-por 6699 - - cannot open display: <3.5> 2021-12-27 20:05:29 Legolas systemd 6532 - - xdg-desktop-portal-gtk.service: Main process exited, code=exited, status=1/FAILURE <3.4> 2021-12-27 20:05:29 Legolas systemd 6532 - - xdg-desktop-portal-gtk.service: Failed with result 'exit-code'. <3.3> 2021-12-27 20:05:29 Legolas systemd 6532 - - Failed to start Portal service (GTK+/GNOME implementation). Here is the delay. Then the application starts and I get all those messages below (and nothing in between): <3.6> 2021-12-27 20:05:54 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Activating via systemd: service name='org.gtk.vfs.UDisks2VolumeMonitor' unit='gvfs-udisks2-volume-monitor.service' requested by ':1.3' (uid=0 pid=6647 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:05:54 Legolas systemd 6532 - - Starting Virtual filesystem service - disk device monitor... <3.6> 2021-12-27 20:05:54 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Successfully activated service 'org.gtk.vfs.UDisks2VolumeMonitor' <3.6> 2021-12-27 20:05:54 Legolas systemd 6532 - - Started Virtual filesystem service - disk device monitor. <3.6> 2021-12-27 20:05:54 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Activating via systemd: service name='org.gtk.vfs.AfcVolumeMonitor' unit='gvfs-afc-volume-monitor.service' requested by ':1.3' (uid=0 pid=6647 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:05:54 Legolas systemd 6532 - - Starting Virtual filesystem service - Apple File Conduit monitor... <3.6> 2021-12-27 20:05:54 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Successfully activated service 'org.gtk.vfs.AfcVolumeMonitor' <3.6> 2021-12-27 20:05:54 Legolas systemd 6532 - - Started Virtual filesystem service - Apple File Conduit monitor. <3.6> 2021-12-27 20:05:54 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Activating via systemd: service name='org.gtk.vfs.GoaVolumeMonitor' unit='gvfs-goa-volume-monitor.service' requested by ':1.3' (uid=0 pid=6647 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:05:54 Legolas systemd 6532 - - Starting Virtual filesystem service - GNOME Online Accounts monitor... <3.6> 2021-12-27 20:05:54 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Activating service name='org.gnome.OnlineAccounts' requested by ':1.11' (uid=0 pid=6727 comm="/usr/lib/gvfs/gvfs-goa-volume-monitor ") <1.5> 2021-12-27 20:05:54 Legolas goa-daemon 6731 - - goa-daemon version 3.34.1 starting <3.6> 2021-12-27 20:05:54 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Activating service name='org.gnome.Identity' requested by ':1.12' (uid=0 pid=6731 comm="/usr/lib/goa-daemon ") <3.6> 2021-12-27 20:05:54 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Successfully activated service 'org.gnome.OnlineAccounts' <3.6> 2021-12-27 20:05:54 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Successfully activated service 'org.gtk.vfs.GoaVolumeMonitor' <3.6> 2021-12-27 20:05:54 Legolas systemd 6532 - - Started Virtual filesystem service - GNOME Online Accounts monitor. <3.6> 2021-12-27 20:05:54 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Activating via systemd: service name='org.gtk.vfs.GPhoto2VolumeMonitor' unit='gvfs-gphoto2-volume-monitor.service' requested by ':1.3' (uid=0 pid=6647 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:05:54 Legolas systemd 6532 - - Starting Virtual filesystem service - digital camera monitor... <3.6> 2021-12-27 20:05:54 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Successfully activated service 'org.gtk.vfs.GPhoto2VolumeMonitor' <3.6> 2021-12-27 20:05:54 Legolas systemd 6532 - - Started Virtual filesystem service - digital camera monitor. <3.6> 2021-12-27 20:05:54 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Activating via systemd: service name='org.gtk.vfs.MTPVolumeMonitor' unit='gvfs-mtp-volume-monitor.service' requested by ':1.3' (uid=0 pid=6647 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:05:54 Legolas systemd 6532 - - Starting Virtual filesystem service - Media Transfer Protocol monitor... <3.6> 2021-12-27 20:05:55 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Successfully activated service 'org.gtk.vfs.MTPVolumeMonitor' <3.6> 2021-12-27 20:05:55 Legolas systemd 6532 - - Started Virtual filesystem service - Media Transfer Protocol monitor. <3.6> 2021-12-27 20:05:55 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Successfully activated service 'org.gnome.Identity' <3.6> 2021-12-27 20:05:56 Legolas dbus-daemon 1199 - - [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.104' (uid=0 pid=6647 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:05:56 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Activating service name='ca.desrt.dconf' requested by ':1.3' (uid=0 pid=6647 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:05:56 Legolas systemd 1 - - Starting Hostname Service... <3.6> 2021-12-27 20:05:56 Legolas dbus-daemon 6651 - - [session uid=0 pid=6651] Successfully activated service 'ca.desrt.dconf' <3.6> 2021-12-27 20:05:56 Legolas at-spi-bus-launcher 6667 - - dbus-daemon[6667]: Activating service name='org.a11y.atspi.Registry' requested by ':1.0' (uid=0 pid=6647 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:05:56 Legolas at-spi-bus-launcher 6667 - - dbus-daemon[6667]: Successfully activated service 'org.a11y.atspi.Registry' <3.6> 2021-12-27 20:05:56 Legolas at-spi-bus-launcher 6771 - - SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry <3.6> 2021-12-27 20:05:57 Legolas dbus-daemon 1199 - - [system] Successfully activated service 'org.freedesktop.hostname1' <3.6> 2021-12-27 20:05:57 Legolas systemd 1 - - Started Hostname Service. About here the application is running fine. Close ssh. Another attempt some minutes later: <3.6> 2021-12-27 20:13:33 Legolas systemd 6890 - - Started D-Bus User Message Bus. <3.6> 2021-12-27 20:13:33 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Activating via systemd: service name='org.a11y.Bus' unit='at-spi-dbus-bus.service' requested by ':1.1' (uid=0 pid=7054 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:13:33 Legolas systemd 6890 - - Starting Accessibility services bus... <3.6> 2021-12-27 20:13:33 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Successfully activated service 'org.a11y.Bus' <3.6> 2021-12-27 20:13:33 Legolas systemd 6890 - - Started Accessibility services bus. <3.6> 2021-12-27 20:13:34 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Activating via systemd: service name='org.gtk.vfs.Daemon' unit='gvfs-daemon.service' requested by ':1.3' (uid=0 pid=7054 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:13:34 Legolas systemd 6890 - - Starting Virtual filesystem service... <3.6> 2021-12-27 20:13:34 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Successfully activated service 'org.gtk.vfs.Daemon' <3.6> 2021-12-27 20:13:34 Legolas systemd 6890 - - Started Virtual filesystem service. <3.6> 2021-12-27 20:13:34 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Activating via systemd: service name='org.freedesktop.portal.Desktop' unit='xdg-desktop-portal.service' requested by ':1.3' (uid=0 pid=7054 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:13:34 Legolas systemd 6890 - - Starting Portal service... <3.6> 2021-12-27 20:13:34 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Activating via systemd: service name='org.freedesktop.portal.Documents' unit='xdg-document-portal.service' requested by ':1.6' (uid=0 pid=7088 comm="/usr/lib/xdg-desktop-portal ") <3.6> 2021-12-27 20:13:34 Legolas systemd 6890 - - Starting flatpak document portal service... <3.6> 2021-12-27 20:13:34 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Activating via systemd: service name='org.freedesktop.impl.portal.PermissionStore' unit='xdg-permission-store.service' requested by ':1.7' (uid=0 pid=7092 comm="/usr/lib/xdg-document-portal ") <3.6> 2021-12-27 20:13:34 Legolas systemd 6890 - - Starting sandboxed app permission store... <3.6> 2021-12-27 20:13:34 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Successfully activated service 'org.freedesktop.impl.portal.PermissionStore' <3.6> 2021-12-27 20:13:34 Legolas systemd 6890 - - Started sandboxed app permission store. <3.6> 2021-12-27 20:13:34 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Successfully activated service 'org.freedesktop.portal.Documents' <3.6> 2021-12-27 20:13:34 Legolas systemd 6890 - - Started flatpak document portal service. <3.6> 2021-12-27 20:13:34 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Activating via systemd: service name='org.freedesktop.impl.portal.desktop.gtk' unit='xdg-desktop-portal-gtk.service' requested by ':1.6' (uid=0 pid=7088 comm="/usr/lib/xdg-desktop-portal ") <3.6> 2021-12-27 20:13:34 Legolas systemd 6890 - - Starting Portal service (GTK+/GNOME implementation)... <3.6> 2021-12-27 20:13:35 Legolas xdg-desktop-portal-gtk 7104 - - Unable to init server: Could not connect: Connection refused <1.4> 2021-12-27 20:13:35 Legolas xdg-desktop-por 7104 - - cannot open display: <3.5> 2021-12-27 20:13:35 Legolas systemd 6890 - - xdg-desktop-portal-gtk.service: Main process exited, code=exited, status=1/FAILURE <3.4> 2021-12-27 20:13:35 Legolas systemd 6890 - - xdg-desktop-portal-gtk.service: Failed with result 'exit-code'. <3.3> 2021-12-27 20:13:35 Legolas systemd 6890 - - Failed to start Portal service (GTK+/GNOME implementation). pause <3.6> 2021-12-27 20:14:00 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Activating via systemd: service name='org.gtk.vfs.UDisks2VolumeMonitor' unit='gvfs-udisks2-volume-monitor.service' requested by ':1.3' (uid=0 pid=7054 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:14:00 Legolas systemd 6890 - - Starting Virtual filesystem service - disk device monitor... <3.6> 2021-12-27 20:14:00 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Successfully activated service 'org.gtk.vfs.UDisks2VolumeMonitor' <3.6> 2021-12-27 20:14:00 Legolas systemd 6890 - - Started Virtual filesystem service - disk device monitor. <3.6> 2021-12-27 20:14:00 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Activating via systemd: service name='org.gtk.vfs.AfcVolumeMonitor' unit='gvfs-afc-volume-monitor.service' requested by ':1.3' (uid=0 pid=7054 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:14:00 Legolas systemd 6890 - - Starting Virtual filesystem service - Apple File Conduit monitor... <3.6> 2021-12-27 20:14:00 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Successfully activated service 'org.gtk.vfs.AfcVolumeMonitor' <3.6> 2021-12-27 20:14:00 Legolas systemd 6890 - - Started Virtual filesystem service - Apple File Conduit monitor. <3.6> 2021-12-27 20:14:00 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Activating via systemd: service name='org.gtk.vfs.GoaVolumeMonitor' unit='gvfs-goa-volume-monitor.service' requested by ':1.3' (uid=0 pid=7054 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:14:00 Legolas systemd 6890 - - Starting Virtual filesystem service - GNOME Online Accounts monitor... <3.6> 2021-12-27 20:14:00 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Activating service name='org.gnome.OnlineAccounts' requested by ':1.11' (uid=0 pid=7138 comm="/usr/lib/gvfs/gvfs-goa-volume-monitor ") <1.5> 2021-12-27 20:14:00 Legolas goa-daemon 7142 - - goa-daemon version 3.34.1 starting <3.6> 2021-12-27 20:14:00 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Activating service name='org.gnome.Identity' requested by ':1.12' (uid=0 pid=7142 comm="/usr/lib/goa-daemon ") <3.6> 2021-12-27 20:14:00 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Successfully activated service 'org.gnome.OnlineAccounts' <3.6> 2021-12-27 20:14:00 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Successfully activated service 'org.gtk.vfs.GoaVolumeMonitor' <3.6> 2021-12-27 20:14:00 Legolas systemd 6890 - - Started Virtual filesystem service - GNOME Online Accounts monitor. <3.6> 2021-12-27 20:14:00 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Activating via systemd: service name='org.gtk.vfs.GPhoto2VolumeMonitor' unit='gvfs-gphoto2-volume-monitor.service' requested by ':1.3' (uid=0 pid=7054 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:14:00 Legolas systemd 6890 - - Starting Virtual filesystem service - digital camera monitor... <3.6> 2021-12-27 20:14:00 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Successfully activated service 'org.gtk.vfs.GPhoto2VolumeMonitor' <3.6> 2021-12-27 20:14:00 Legolas systemd 6890 - - Started Virtual filesystem service - digital camera monitor. <3.6> 2021-12-27 20:14:00 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Activating via systemd: service name='org.gtk.vfs.MTPVolumeMonitor' unit='gvfs-mtp-volume-monitor.service' requested by ':1.3' (uid=0 pid=7054 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:14:00 Legolas systemd 6890 - - Starting Virtual filesystem service - Media Transfer Protocol monitor... <3.6> 2021-12-27 20:14:00 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Successfully activated service 'org.gtk.vfs.MTPVolumeMonitor' <3.6> 2021-12-27 20:14:00 Legolas systemd 6890 - - Started Virtual filesystem service - Media Transfer Protocol monitor. <3.6> 2021-12-27 20:14:00 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Successfully activated service 'org.gnome.Identity' <3.6> 2021-12-27 20:14:01 Legolas dbus-daemon 1199 - - [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.114' (uid=0 pid=7054 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:14:02 Legolas systemd 1 - - Starting Hostname Service... <3.6> 2021-12-27 20:14:02 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Activating service name='ca.desrt.dconf' requested by ':1.3' (uid=0 pid=7054 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:14:02 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Successfully activated service 'ca.desrt.dconf' <3.6> 2021-12-27 20:14:02 Legolas at-spi-bus-launcher 7073 - - dbus-daemon[7073]: Activating service name='org.a11y.atspi.Registry' requested by ':1.0' (uid=0 pid=7054 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:14:02 Legolas at-spi-bus-launcher 7073 - - dbus-daemon[7073]: Successfully activated service 'org.a11y.atspi.Registry' <3.6> 2021-12-27 20:14:02 Legolas at-spi-bus-launcher 7183 - - SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry <3.6> 2021-12-27 20:14:03 Legolas dbus-daemon 1199 - - [system] Successfully activated service 'org.freedesktop.hostname1' <3.6> 2021-12-27 20:14:03 Legolas systemd 1 - - Started Hostname Service. runs here. Stop it, and a bit later, few messages more: <1.4> 2021-12-27 20:14:25 Legolas xdg-desktop-por 7088 - - Failed to create settings proxy: Error calling StartServiceByName for org.freedesktop.impl.portal.desktop.gtk: Timeout was reached <3.6> 2021-12-27 20:14:33 Legolas systemd 1 - - systemd-hostnamed.service: Succeeded. <1.4> 2021-12-27 20:14:50 Legolas xdg-desktop-por 7088 - - Failed to create file chooser proxy: Error calling StartServiceByName for org.freedesktop.impl.portal.desktop.gtk: Timeout was reached <1.4> 2021-12-27 20:14:50 Legolas xdg-desktop-por 7088 - - No skeleton to export <3.4> 2021-12-27 20:15:04 Legolas systemd 6890 - - xdg-desktop-portal.service: start operation timed out. Terminating. <3.4> 2021-12-27 20:15:04 Legolas systemd 6890 - - xdg-desktop-portal.service: Failed with result 'timeout'. <3.3> 2021-12-27 20:15:04 Legolas systemd 6890 - - Failed to start Portal service. <4.5> 2021-12-27 20:15:34 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Failed to activate service 'org.freedesktop.portal.Desktop': timed out (service_start_timeout=120000ms) <4.5> 2021-12-27 20:15:34 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Failed to activate service 'org.freedesktop.impl.portal.desktop.gtk': timed out (service_start_timeout=120000ms) Without closing ssh, another attempt of the same command - there is much less noise in the log: another attempt <3.6> 2021-12-27 20:17:57 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Activating via systemd: service name='org.freedesktop.portal.Desktop' unit='xdg-desktop-portal.service' requested by ':1.27' (uid=0 pid=7322 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:17:57 Legolas systemd 6890 - - Starting Portal service... <3.6> 2021-12-27 20:17:57 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Activating via systemd: service name='org.freedesktop.impl.portal.desktop.gtk' unit='xdg-desktop-portal-gtk.service' requested by ':1.28' (uid=0 pid=7328 comm="/usr/lib/xdg-desktop-portal ") <3.6> 2021-12-27 20:17:57 Legolas systemd 6890 - - Starting Portal service (GTK+/GNOME implementation)... <3.6> 2021-12-27 20:17:57 Legolas xdg-desktop-portal-gtk 7345 - - Unable to init server: Could not connect: Connection refused <1.4> 2021-12-27 20:17:57 Legolas xdg-desktop-por 7345 - - cannot open display: <3.5> 2021-12-27 20:17:57 Legolas systemd 6890 - - xdg-desktop-portal-gtk.service: Main process exited, code=exited, status=1/FAILURE <3.4> 2021-12-27 20:17:57 Legolas systemd 6890 - - xdg-desktop-portal-gtk.service: Failed with result 'exit-code'. <3.3> 2021-12-27 20:17:57 Legolas systemd 6890 - - Failed to start Portal service (GTK+/GNOME implementation). delay here <3.6> 2021-12-27 20:18:23 Legolas dbus-daemon 1199 - - [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.118' (uid=0 pid=7322 comm="/usr/bin/python3 /usr/bin/meld _etc_samba_smb.conf") <3.6> 2021-12-27 20:18:23 Legolas systemd 1 - - Starting Hostname Service... <3.6> 2021-12-27 20:18:24 Legolas dbus-daemon 1199 - - [system] Successfully activated service 'org.freedesktop.hostname1' <3.6> 2021-12-27 20:18:24 Legolas systemd 1 - - Started Hostname Service. runs closed <1.4> 2021-12-27 20:18:47 Legolas xdg-desktop-por 7328 - - Failed to create settings proxy: Error calling StartServiceByName for org.freedesktop.impl.portal.desktop.gtk: Timeout was reached <3.6> 2021-12-27 20:18:54 Legolas systemd 1 - - systemd-hostnamed.service: Succeeded. <1.4> 2021-12-27 20:19:12 Legolas xdg-desktop-por 7328 - - Failed to create file chooser proxy: Error calling StartServiceByName for org.freedesktop.impl.portal.desktop.gtk: Timeout was reached <1.4> 2021-12-27 20:19:12 Legolas xdg-desktop-por 7328 - - No skeleton to export <3.4> 2021-12-27 20:19:27 Legolas systemd 6890 - - xdg-desktop-portal.service: start operation timed out. Terminating. <3.4> 2021-12-27 20:19:27 Legolas systemd 6890 - - xdg-desktop-portal.service: Failed with result 'timeout'. <3.3> 2021-12-27 20:19:27 Legolas systemd 6890 - - Failed to start Portal service. <4.5> 2021-12-27 20:19:57 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Failed to activate service 'org.freedesktop.portal.Desktop': timed out (service_start_timeout=120000ms) <4.5> 2021-12-27 20:19:57 Legolas dbus-daemon 7057 - - [session uid=0 pid=7057] Failed to activate service 'org.freedesktop.impl.portal.desktop.gtk': timed out (service_start_timeout=120000ms)
<3.6> 2021-12-24 22:42:07 Legolas dbus-daemon 1029 - - [session uid=0 pid=1029] Activating via systemd: service name='org.freedesktop.portal.Desktop' unit='xdg-desktop-portal.service' requested by ':1.38' (uid=0 pid=1864 comm="/usr/bin/python3 /usr/bin/meld /etc/ssh/sshd_confi")
That is not going to work. You connect via ssh and likely forward X11 connection from remote system to local display. But your login session on the remote system has its own session D-Bus instance which knows nothing about the desktop environment on your client. So your GUI application attempts to invoke services on your client desktop via connection to server D-Bus.
To transparently start applications via ssh X11 forwarding one would need to also forward D-Bus connection. While it is possible to forward D-Bus socket (I tested that it works) security implications are not clear (D-Bus can no longer verify the user that makes the request. All requests will appear to come from the client ssh process).
I confess to not know what D-Bus is, except that I see references to it in the logs since several years ago, complaining of this or that, in every computer. Thus I'm not intentionally doing anything about D-bus. The only thing I know is that there was no delay in 15.2, and there is a long delay in 15.3 :-) So if the application can work without dbus, and there is a way to tell the application to not try dbus, I suppose I would be happy. The delay doesn't happen when I run the same command locally. But the keyboard and display is too small for confort, if it can be avoided... - -- Cheers, Carlos E. R. (from openSUSE 15.2 x86_64 at Telcontar) -----BEGIN PGP SIGNATURE----- iHoEARECADoWIQQZEb51mJKK1KpcU/W1MxgcbY1H1QUCYcoS5Rwccm9iaW4ubGlz dGFzQHRlbGVmb25pY2EubmV0AAoJELUzGBxtjUfVKnwAn0ELlt5l7oe1D8/fCsJ8 Eb2WeSUIAKCOSmMN9RhxavvZpl/TVnVSf0gHSg== =/auq -----END PGP SIGNATURE-----
On Mon, Dec 27, 2021 at 10:24 PM Carlos E. R. <robin.listas@telefonica.net> wrote:
-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1
On Monday, 2021-12-27 at 20:11 +0300, Andrei Borzenkov wrote:
On Sat, Dec 25, 2021 at 12:58 AM Carlos E. R. <> wrote:
I see this in a small laptop upgraded this afternoon to 15.3. I open an ssh session to it, as root, then run:
Legolas:~ # meld /etc/ssh/sshd_config.rpmnew /etc/ssh/sshd_config & [1] 1864 Legolas:~ # [1]+ Done meld /etc/ssh/sshd_config.rpmnew /etc/ssh/sshd_config Legolas:~ #
The program takes 20 seconds to appear, there is a timeout in the log
There are several gaps between messages in log you quoted, which one specifically do you mean?
I think the application starts just here
22:42:07 22:42:33
But I'd better do another attempt and check, because I don't remember. The machine is suspended this instant, so perhaps there may be wake up related messages inserted in the flow.
(desktop is xfce, in case I forgot and is relevant)
Start application here (meld), via ssh (ssh -X root@legolas.valinor):
...
<3.6> 2021-12-27 20:05:29 Legolas systemd 6532 - - Starting Portal service (GTK+/GNOME implementation)... <3.6> 2021-12-27 20:05:29 Legolas xdg-desktop-portal-gtk 6699 - - Unable to init server: Could not connect: Connection refused <1.4> 2021-12-27 20:05:29 Legolas xdg-desktop-por 6699 - - cannot open display: <3.5> 2021-12-27 20:05:29 Legolas systemd 6532 - - xdg-desktop-portal-gtk.service: Main process exited, code=exited, status=1/FAILURE <3.4> 2021-12-27 20:05:29 Legolas systemd 6532 - - xdg-desktop-portal-gtk.service: Failed with result 'exit-code'. <3.3> 2021-12-27 20:05:29 Legolas systemd 6532 - - Failed to start Portal service (GTK+/GNOME implementation).
Here is the delay. Then the application starts and I get all those messages below (and nothing in between):
Service is started by systemd user instance which is not aware of DISPLAY exported by sshd. I tried "systemctl --user import-environment DISPAY" before starting program that triggers xdg-desktop-portal, and that gets rid of "Failed to start Portal service", but I still observe the same 20+ seconds delay before application finally launches. ...
<3.6> 2021-12-24 22:42:07 Legolas dbus-daemon 1029 - - [session uid=0 pid=1029] Activating via systemd: service name='org.freedesktop.portal.Desktop' unit='xdg-desktop-portal.service' requested by ':1.38' (uid=0 pid=1864 comm="/usr/bin/python3 /usr/bin/meld /etc/ssh/sshd_confi")
That is not going to work. You connect via ssh and likely forward X11 connection from remote system to local display. But your login session on the remote system has its own session D-Bus instance which knows nothing about the desktop environment on your client. So your GUI application attempts to invoke services on your client desktop via connection to server D-Bus.
To transparently start applications via ssh X11 forwarding one would need to also forward D-Bus connection. While it is possible to forward D-Bus socket (I tested that it works) security implications are not clear (D-Bus can no longer verify the user that makes the request. All requests will appear to come from the client ssh process).
I confess to not know what D-Bus is, except that I see references to it in the logs since several years ago, complaining of this or that, in every computer. Thus I'm not intentionally doing anything about D-bus. The only thing I know is that there was no delay in 15.2, and there is a long delay in 15.3 :-)
So if the application can work without dbus, and there is a way to tell the application to not try dbus, I suppose I would be happy.
This is the same as asking an application to not try TCP/IP protocol to work around DNS resolution issues. D-Bus is a communication protocol. It is the de-facto standard today for communication between desktop components. You cannot "tell the application to not try dbus". At most you can tell the application to not attempt to contact service that causes delay. But I do not know how to do it. For a start, I do not know why xdg-portal service is invoked at all - all I have seen so far suggests that it should be opt-in. So far the only way to eliminate this delay I found was to forward Unix socket from remote client to local user D-Bus socket and tell remote side to use it. But that sounds horribly wrong for multiple reasons. I suggest you open a bug report to clarify whether this is an upstream issue or caused by the local SUSE patch.
The delay doesn't happen when I run the same command locally.
Of course it does not. In this case you have a consistent desktop environment where your application runs.
On 28/12/2021 08.28, Andrei Borzenkov wrote:
On Mon, Dec 27, 2021 at 10:24 PM Carlos E. R. <> wrote:
On Monday, 2021-12-27 at 20:11 +0300, Andrei Borzenkov wrote:
On Sat, Dec 25, 2021 at 12:58 AM Carlos E. R. <> wrote:
...
Start application here (meld), via ssh (ssh -X root@legolas.valinor):
...
<3.6> 2021-12-27 20:05:29 Legolas systemd 6532 - - Starting Portal service (GTK+/GNOME implementation)... <3.6> 2021-12-27 20:05:29 Legolas xdg-desktop-portal-gtk 6699 - - Unable to init server: Could not connect: Connection refused <1.4> 2021-12-27 20:05:29 Legolas xdg-desktop-por 6699 - - cannot open display: <3.5> 2021-12-27 20:05:29 Legolas systemd 6532 - - xdg-desktop-portal-gtk.service: Main process exited, code=exited, status=1/FAILURE <3.4> 2021-12-27 20:05:29 Legolas systemd 6532 - - xdg-desktop-portal-gtk.service: Failed with result 'exit-code'. <3.3> 2021-12-27 20:05:29 Legolas systemd 6532 - - Failed to start Portal service (GTK+/GNOME implementation).
Here is the delay. Then the application starts and I get all those messages below (and nothing in between):
Service is started by systemd user instance which is not aware of DISPLAY exported by sshd. I tried "systemctl --user import-environment DISPAY" before starting program that triggers xdg-desktop-portal, and that gets rid of "Failed to start Portal service", but I still observe the same 20+ seconds delay before application finally launches.
ok, so that is not the cause.
I confess to not know what D-Bus is, except that I see references to it in the logs since several years ago, complaining of this or that, in every computer. Thus I'm not intentionally doing anything about D-bus. The only thing I know is that there was no delay in 15.2, and there is a long delay in 15.3 :-)
So if the application can work without dbus, and there is a way to tell the application to not try dbus, I suppose I would be happy.
This is the same as asking an application to not try TCP/IP protocol to work around DNS resolution issues.
Well, maybe I miss something obvious, but AFAIK, when working over ssh dbus doesn't work, yet the application works fine, so why even try?
D-Bus is a communication protocol. It is the de-facto standard today for communication between desktop components. You cannot "tell the application to not try dbus". At most you can tell the application to not attempt to contact service that causes delay. But I do not know how to do it. For a start, I do not know why xdg-portal service is invoked at all - all I have seen so far suggests that it should be opt-in.
So far the only way to eliminate this delay I found was to forward Unix socket from remote client to local user D-Bus socket and tell remote side to use it. But that sounds horribly wrong for multiple reasons.
I suggest you open a bug report to clarify whether this is an upstream issue or caused by the local SUSE patch.
Ok, will do.
The delay doesn't happen when I run the same command locally.
Of course it does not. In this case you have a consistent desktop environment where your application runs.
Still, desktop belongs to user "cer" and application is run as root. -- Cheers / Saludos, Carlos E. R. (from 15.2 x86_64 at Telcontar)
On Tue, Dec 28, 2021 at 2:24 PM Carlos E. R. <robin.listas@telefonica.net> wrote: ...
I confess to not know what D-Bus is, except that I see references to it in the logs since several years ago, complaining of this or that, in every computer. Thus I'm not intentionally doing anything about D-bus. The only thing I know is that there was no delay in 15.2, and there is a long delay in 15.3 :-)
So if the application can work without dbus, and there is a way to tell the application to not try dbus, I suppose I would be happy.
This is the same as asking an application to not try TCP/IP protocol to work around DNS resolution issues.
Well, maybe I miss something obvious, but AFAIK, when working over ssh dbus doesn't work, yet the application works fine, so why even try?
Complain to desktop developers. Linux desktops became rather complex and need much more than just a plain X11 server to work. Apparently nobody is motivated enough to design "desktop application forwarding". Personally when I need GUI I just start VNC. Unfortunately, even that fails because developers are not willing to support more than one graphical session per user ... I wonder if I can start "virtual seat" so that two sessions do not conflict.
D-Bus is a communication protocol. It is the de-facto standard today for communication between desktop components. You cannot "tell the application to not try dbus". At most you can tell the application to not attempt to contact service that causes delay. But I do not know how to do it. For a start, I do not know why xdg-portal service is invoked at all - all I have seen so far suggests that it should be opt-in.
So far the only way to eliminate this delay I found was to forward Unix socket from remote client to local user D-Bus socket and tell remote side to use it. But that sounds horribly wrong for multiple reasons.
I suggest you open a bug report to clarify whether this is an upstream issue or caused by the local SUSE patch.
Ok, will do.
Please post bug number here.
The delay doesn't happen when I run the same command locally.
Of course it does not. In this case you have a consistent desktop environment where your application runs.
Still, desktop belongs to user "cer" and application is run as root.
How are we supposed to know what you did? You said "I run the same command" and the command was "meld". You did not say "I used su or sudo to run this command as root" which is implied now. I can reproduce your issue when ssh into a local host and different user or doing "su - otheruser". In both cases I get the same delay. If you provide exact steps when issue does *not* happen, we could test it.
On 28/12/2021 15.50, Andrei Borzenkov wrote:
On Tue, Dec 28, 2021 at 2:24 PM Carlos E. R. <> wrote: ...
I confess to not know what D-Bus is, except that I see references to it in the logs since several years ago, complaining of this or that, in every computer. Thus I'm not intentionally doing anything about D-bus. The only thing I know is that there was no delay in 15.2, and there is a long delay in 15.3 :-)
So if the application can work without dbus, and there is a way to tell the application to not try dbus, I suppose I would be happy.
This is the same as asking an application to not try TCP/IP protocol to work around DNS resolution issues.
Well, maybe I miss something obvious, but AFAIK, when working over ssh dbus doesn't work, yet the application works fine, so why even try?
Complain to desktop developers. Linux desktops became rather complex and need much more than just a plain X11 server to work. Apparently nobody is motivated enough to design "desktop application forwarding". Personally when I need GUI I just start VNC. Unfortunately, even that fails because developers are not willing to support more than one graphical session per user ... I wonder if I can start "virtual seat" so that two sessions do not conflict.
:-( I don't want to start whole GUI sessions remotely, just terminals and applications. Can have several terminals from several machines and repeat ops on each. Several GUI sessions is a nightmare. We are going to looe a pro point against windoze :-(
D-Bus is a communication protocol. It is the de-facto standard today for communication between desktop components. You cannot "tell the application to not try dbus". At most you can tell the application to not attempt to contact service that causes delay. But I do not know how to do it. For a start, I do not know why xdg-portal service is invoked at all - all I have seen so far suggests that it should be opt-in.
So far the only way to eliminate this delay I found was to forward Unix socket from remote client to local user D-Bus socket and tell remote side to use it. But that sounds horribly wrong for multiple reasons.
I suggest you open a bug report to clarify whether this is an upstream issue or caused by the local SUSE patch.
Ok, will do.
Please post bug number here.
Yes, yes, will do, but patience, I have several problems dancing around me at the moment...
The delay doesn't happen when I run the same command locally.
Of course it does not. In this case you have a consistent desktop environment where your application runs.
Still, desktop belongs to user "cer" and application is run as root.
How are we supposed to know what you did? You said "I run the same command" and the command was "meld". You did not say "I used su or sudo to run this command as root" which is implied now.
Nonono, I don't use sudo or su (with the problem). I use "ssh -X root@machine", from another machine in same LAN. The laptop is running a local sesion as user cer, obviously, but I don't use it. It is just half a metre away, tiny keyboard and display, so I prefer big display and do maintenance on it via ssh from main computer. Have done that for many years. I simply tried meld locally on that machine for verification: on local session, as cer, do "su -", then run the same meld command as I did via ssh. It is in the bash history.
I can reproduce your issue when ssh into a local host and different user or doing "su - otheruser". In both cases I get the same delay. If you provide exact steps when issue does *not* happen, we could test it.
Ok, on local session and seat (can't copy paste, mail is on another computer): cer@legolas:~> su - legolas:~# su - legolas:~# meld /etc/ssh/sshd_config /etc/ssh/sshd_config No delay, just 2 or 3 seconds, as expected from puny CPU. Typing on main computer, use ssh to Legolas: cer@Telcontar:~> cer@Telcontar:~> ssh -X root@legolas.valinor Last login: Mon Dec 27 20:07:52 2021 from 192.168.1.14 Have a lot of fun... Legolas:~ # meld /etc/chrony.conf /etc/chrony.conf & [1] 11560 Legolas:~ # A long delay. I count to 30 on my mind. Could use a stopwatch to be precise if needed. -- Cheers / Saludos, Carlos E. R. (from 15.2 x86_64 at Telcontar)
On Tue, Dec 28, 2021 at 9:34 PM Carlos E. R. <robin.listas@telefonica.net> wrote:
I can reproduce your issue when ssh into a local host and different user or doing "su - otheruser". In both cases I get the same delay. If you provide exact steps when issue does *not* happen, we could test it.
Ok, on local session and seat (can't copy paste, mail is on another computer):
cer@legolas:~> su - legolas:~# su - legolas:~# meld /etc/ssh/sshd_config /etc/ssh/sshd_config
No delay, just 2 or 3 seconds, as expected from puny CPU.
I still observe the same delay and I do not see how it can be different. I am using Tumbleweed and not meld but another GNOME program. So it could be that GNOME tries to do something more.
On 29/12/2021 09.14, Andrei Borzenkov wrote:
On Tue, Dec 28, 2021 at 9:34 PM Carlos E. R. <robin.listas@telefonica.net> wrote:
I can reproduce your issue when ssh into a local host and different user or doing "su - otheruser". In both cases I get the same delay. If you provide exact steps when issue does *not* happen, we could test it.
Ok, on local session and seat (can't copy paste, mail is on another computer):
cer@legolas:~> su - legolas:~# su - legolas:~# meld /etc/ssh/sshd_config /etc/ssh/sshd_config
No delay, just 2 or 3 seconds, as expected from puny CPU.
I still observe the same delay and I do not see how it can be different. I am using Tumbleweed and not meld but another GNOME program. So it could be that GNOME tries to do something more.
meld is not a gnome app, I think. (rpm -qi meld) Summary : Visual diff and merge tool Description : Meld is a visual diff and merge tool. Two or three files can be compared and be edited in place. (The diffs update dynamically). Two or three directories can be compared and file comparisons be launched. The working copy directory from version control systems such as CVS, Subversion, Bazaar-ng and Mercurial can be browsed and viewed. Distribution: openSUSE Leap 15.2 Then, I'm using Leap, not TW, perhaps the feature has not been added here >;-) Something else I noticed, on my old laptop #1: meld suffers the delay on 15.2 (I tried just before upgrading). This is new, I'm certain that meld worked before without delays or I would have noticed. So there is some update that created this delay at some point during the lifetime of 15.2 I know for certain because I always use meld after upgrading from 15.1 to 15.2, say, it is part of the routine. And I have three machines in which I prefer to work remotely, even if they are half a meter away... -- Cheers / Saludos, Carlos E. R. (from 15.2 x86_64 at Telcontar)
On Wed, 29 Dec 2021 12:42:49 +0100 "Carlos E. R." <robin.listas@telefonica.net> wrote:
On 29/12/2021 09.14, Andrei Borzenkov wrote:
On Tue, Dec 28, 2021 at 9:34 PM Carlos E. R. <robin.listas@telefonica.net> wrote:
I can reproduce your issue when ssh into a local host and different user or doing "su - otheruser". In both cases I get the same delay. If you provide exact steps when issue does *not* happen, we could test it.
Ok, on local session and seat (can't copy paste, mail is on another computer):
cer@legolas:~> su - legolas:~# su - legolas:~# meld /etc/ssh/sshd_config /etc/ssh/sshd_config
No delay, just 2 or 3 seconds, as expected from puny CPU.
I still observe the same delay and I do not see how it can be different. I am using Tumbleweed and not meld but another GNOME program. So it could be that GNOME tries to do something more.
meld is not a gnome app, I think. (rpm -qi meld)
From https://meldmerge.org/ Requirements Python 3.3 (3.6 in development) GTK+ 3.14 (3.20 in development) GLib 2.36 (2.48 in development) PyGObject 3.14 (3.30 in development) GtkSourceView 3.14 (4.0 in development) pycairo (1.15 in development) Note especially everything except the first requirement!
Summary : Visual diff and merge tool Description : Meld is a visual diff and merge tool. Two or three files can be compared and be edited in place. (The diffs update dynamically). Two or three directories can be compared and file comparisons be launched. The working copy directory from version control systems such as CVS, Subversion, Bazaar-ng and Mercurial can be browsed and viewed. Distribution: openSUSE Leap 15.2
Then, I'm using Leap, not TW, perhaps the feature has not been added here >;-)
Something else I noticed, on my old laptop #1: meld suffers the delay on 15.2 (I tried just before upgrading). This is new, I'm certain that meld worked before without delays or I would have noticed. So there is some update that created this delay at some point during the lifetime of 15.2
I know for certain because I always use meld after upgrading from 15.1 to 15.2, say, it is part of the routine. And I have three machines in which I prefer to work remotely, even if they are half a meter away...
participants (3)
-
Andrei Borzenkov -
Carlos E. R. -
Dave Howorth