Connection timeout on one node after server reboot

ThinLinc
1

Hi,

I have trouble with one of my ThinLinc nodes after restarting our physical servers that host them. Before the reboot, everything worked fine and users were able to login to both nodes. After the reboot, users are only able to connect to the first node (that is also the master) but all users that are delegated to the second node (by the master due to load-balancing) will receive a timeout message in their ThinLinc client.

The setup is the following:

  • two physical servers (head01 and head02)
  • each runs a LXC container (desk01 and desk02)
  • desk01 is the master, desk02 is an agent
  • head01 and head02 have external IPs for clients to connect over the internet
  • desk01 and desk02 both have IP 10.10.1.2 for the container network and their respective heads have 10.10.1.1 and are configured as the gateway in the /etc/network/interfaces of the desks

Whenever a user is delegated to desk01, everything works. If they are delegated to desk02, the following happens in the /var/log/vsmserver.log of the master:

2023-04-03 09:48:43 INFO vsmserver.session: User with uid 1337 (dummy.user) requested a new session
2023-04-03 09:48:45 INFO vsmserver.session: Session desk02.domain.de:12 created for user dummy.user

and in the master’s systemd journal:

Apr 03 09:48:43 desk01 sshd(pam_google_auth)[127001]: Accepted google_authenticator for dummy.user
Apr 03 09:48:43 desk01 sshd[126999]: Accepted keyboard-interactive/pam for dummy.user from 10.0.80.254 port 51039 ssh2
Apr 03 09:48:43 desk01 sshd[126999]: pam_unix(sshd:session): session opened for user dummy.user by (uid=0)
Apr 03 09:48:43 desk01 systemd[1]: Created slice User Slice of UID 1337.
Apr 03 09:48:43 desk01 systemd[1]: Starting User Runtime Directory /run/user/1337...
Apr 03 09:48:43 desk01 systemd-logind[119]: New session 5140 of user dummy.user.
Apr 03 09:48:43 desk01 udisksd[2028]: Error statting none: No such file or directory
Apr 03 09:48:43 desk01 systemd[1]: Started User Runtime Directory /run/user/1337.
Apr 03 09:48:43 desk01 systemd[1]: Starting User Manager for UID 1337...
Apr 03 09:48:43 desk01 systemd[127119]: pam_unix(systemd-user:session): session opened for user dummy.user by (uid=0)
Apr 03 09:48:43 desk01 systemd[127119]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Apr 03 09:48:43 desk01 systemd[127119]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers).
Apr 03 09:48:43 desk01 systemd[127119]: Listening on GnuPG network certificate management daemon.
Apr 03 09:48:43 desk01 systemd[127119]: Reached target Timers.
Apr 03 09:48:43 desk01 systemd[127119]: Listening on Sound System.
Apr 03 09:48:43 desk01 systemd[127119]: Reached target Paths.
Apr 03 09:48:43 desk01 systemd[127119]: Listening on GnuPG cryptographic agent and passphrase cache.
Apr 03 09:48:43 desk01 systemd[127119]: Starting D-Bus User Message Bus Socket.
Apr 03 09:48:43 desk01 systemd[127119]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Apr 03 09:48:43 desk01 systemd[127119]: Listening on D-Bus User Message Bus Socket.
Apr 03 09:48:43 desk01 systemd[127119]: Reached target Sockets.
Apr 03 09:48:43 desk01 systemd[127119]: Reached target Basic System.
Apr 03 09:48:43 desk01 systemd[127119]: Reached target Default.
Apr 03 09:48:43 desk01 systemd[127119]: Startup finished in 55ms.
Apr 03 09:48:43 desk01 systemd[1]: Started User Manager for UID 1337.
Apr 03 09:48:43 desk01 systemd[1]: Started Session 5140 of user dummy.user.
Apr 03 09:48:45 desk01 sshd[126999]: pam_unix(sshd:session): session closed for user dummy.user
Apr 03 09:48:45 desk01 systemd-logind[119]: Session 5140 logged out. Waiting for processes to exit.
Apr 03 09:48:45 desk01 systemd[1]: session-5140.scope: Succeeded.
Apr 03 09:48:45 desk01 systemd-logind[119]: Removed session 5140.
Apr 03 09:48:56 desk01 systemd[1]: Stopping User Manager for UID 1337...
Apr 03 09:48:56 desk01 systemd[127119]: Stopped target Default.
Apr 03 09:48:56 desk01 systemd[127119]: Stopped target Basic System.
Apr 03 09:48:56 desk01 systemd[127119]: Stopped target Timers.
Apr 03 09:48:56 desk01 systemd[127119]: Stopped target Paths.
Apr 03 09:48:56 desk01 systemd[127119]: Stopped target Sockets.
Apr 03 09:48:56 desk01 systemd[127119]: dbus.socket: Succeeded.
Apr 03 09:48:56 desk01 systemd[127119]: Closed D-Bus User Message Bus Socket.
Apr 03 09:48:56 desk01 systemd[127119]: gpg-agent-browser.socket: Succeeded.
Apr 03 09:48:56 desk01 systemd[127119]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers).
Apr 03 09:48:56 desk01 systemd[127119]: gpg-agent-ssh.socket: Succeeded.
Apr 03 09:48:56 desk01 systemd[127119]: Closed GnuPG cryptographic agent (ssh-agent emulation).
Apr 03 09:48:56 desk01 systemd[127119]: pulseaudio.socket: Succeeded.
Apr 03 09:48:56 desk01 systemd[127119]: Closed Sound System.
Apr 03 09:48:56 desk01 systemd[127119]: gpg-agent.socket: Succeeded.
Apr 03 09:48:56 desk01 systemd[127119]: Closed GnuPG cryptographic agent and passphrase cache.
Apr 03 09:48:56 desk01 systemd[127119]: dirmngr.socket: Succeeded.
Apr 03 09:48:56 desk01 systemd[127119]: Closed GnuPG network certificate management daemon.
Apr 03 09:48:56 desk01 systemd[127119]: gpg-agent-extra.socket: Succeeded.
Apr 03 09:48:56 desk01 systemd[127119]: Closed GnuPG cryptographic agent and passphrase cache (restricted).
Apr 03 09:48:56 desk01 systemd[127119]: Reached target Shutdown.
Apr 03 09:48:56 desk01 systemd[127119]: systemd-exit.service: Succeeded.
Apr 03 09:48:56 desk01 systemd[127119]: Started Exit the Session.
Apr 03 09:48:56 desk01 systemd[127119]: Reached target Exit the Session.
Apr 03 09:48:56 desk01 systemd[127120]: pam_unix(systemd-user:session): session closed for user dummy.user
Apr 03 09:48:56 desk01 systemd[1]: user@1337.service: Succeeded.
Apr 03 09:48:56 desk01 systemd[1]: Stopped User Manager for UID 1337.
Apr 03 09:48:56 desk01 systemd[1]: Stopping User Runtime Directory /run/user/1337...
Apr 03 09:48:56 desk01 systemd[64381]: run-user-1337.mount: Succeeded.
Apr 03 09:48:56 desk01 systemd[36782]: run-user-1337.mount: Succeeded.
Apr 03 09:48:56 desk01 udisksd[2028]: Error statting none: No such file or directory
Apr 03 09:48:56 desk01 systemd[2406]: run-user-1337.mount: Succeeded.
Apr 03 09:48:56 desk01 systemd[5866]: run-user-1337.mount: Succeeded.
Apr 03 09:48:56 desk01 systemd[1409]: run-user-1337.mount: Succeeded.
Apr 03 09:48:56 desk01 systemd[3508]: run-user-1337.mount: Succeeded.
Apr 03 09:48:56 desk01 systemd[130168]: run-user-1337.mount: Succeeded.
Apr 03 09:48:56 desk01 systemd[102376]: run-user-1337.mount: Succeeded.
Apr 03 09:48:56 desk01 systemd[4421]: run-user-1337.mount: Succeeded.
Apr 03 09:48:56 desk01 systemd[1]: run-user-1337.mount: Succeeded.
Apr 03 09:48:56 desk01 systemd[763]: run-user-1337.mount: Succeeded.
Apr 03 09:48:56 desk01 systemd[76771]: run-user-1337.mount: Succeeded.
Apr 03 09:48:56 desk01 systemd[27814]: run-user-1337.mount: Succeeded.
Apr 03 09:48:56 desk01 systemd[18704]: run-user-1337.mount: Succeeded.
Apr 03 09:48:56 desk01 systemd[69966]: run-user-1337.mount: Succeeded.
Apr 03 09:48:56 desk01 systemd[1]: user-runtime-dir@1337.service: Succeeded.
Apr 03 09:48:56 desk01 systemd[1]: Stopped User Runtime Directory /run/user/1337.
Apr 03 09:48:56 desk01 systemd[1]: Removed slice User Slice of UID 1337.

Looks like everything is okay here. At the same time on desk02 (the agent not working) the following happens in the systemd journal:

Apr 03 09:48:43 desk02 tl-session[37245]: pam_unix(thinlinc:session): session opened for user dummy.user by (uid=0)
Apr 03 09:48:43 desk02 systemd-logind[103]: New session 228 of user dummy.user.
Apr 03 09:48:43 desk02 systemd[1]: Started Session 228 of user dummy.user.
Apr 03 09:48:50 desk02 dbus-daemon[5193]: [session uid=1337 pid=5193] Activating service name='org.kde.kglobalaccel' requested by ':1.86' (uid=1337 pid=37390 comm="kded5 [kdeinit5]                                  ")
Apr 03 09:48:50 desk02 dbus-daemon[5193]: [session uid=1337 pid=5193] Successfully activated service 'org.kde.kglobalaccel'
Apr 03 09:48:50 desk02 org.kde.kglobalaccel[5193]: qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 176, resource id: 4194443, major code: 20 (GetProperty), minor code: 0
Apr 03 09:48:50 desk02 dbus-daemon[5193]: [session uid=1337 pid=5193] Activating service name='org.kde.KScreen' requested by ':1.86' (uid=1337 pid=37390 comm="kded5 [kdeinit5]                                  ")
Apr 03 09:48:50 desk02 dbus-daemon[5193]: [session uid=1337 pid=5193] Successfully activated service 'org.kde.KScreen'
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 176, resource id: 4194443, major code: 20 (GetProperty), minor code: 0
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr: Connected output 60 to CRTC 59
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper: Detected XRandR 1.6
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper: Event Base:  92
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper: Event Error:  145
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr: XRandR::setConfig
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr: Requested screen size is QSize(1920, 1080)
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr: Needed CRTCs:  1
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr: Actions to perform:
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         Primary Output: true
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:                 Old: 0
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:                 New: 60
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         Change Screen Size: false
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         Disable outputs: false
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         Change outputs: false
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         Enable outputs: false
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr: RRSetOutputPrimary
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         New primary: 60
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr: XRandR::setConfig done!
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper: RRNotify_OutputChange
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         Output:  60
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         CRTC:  59
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         Mode:  62
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         Rotation:  "Rotate_0"
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         Connection:  "Connected"
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         Subpixel Order:  0
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper: RRScreenChangeNotify
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         Window: 29360133
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         Root: 252
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         Rotation:  "Rotate_0"
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         Size ID: 0
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         Size:  1920 1080
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         SizeMM:  508 286
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper: RRNotify_OutputChange
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         Output:  60
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         CRTC:  59
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         Mode:  62
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         Rotation:  "Rotate_0"
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         Connection:  "Connected"
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xcb.helper:         Subpixel Order:  0
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr: XRandROutput 60 update
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         m_connected: 0
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         m_crtc XRandRCrtc(0x55f6c6063850)
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         CRTC: 59
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         MODE: 62
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         Connection: 0
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         Primary: true
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr: Output 60 : connected = true , enabled = true
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr: XRandROutput 60 update
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         m_connected: 0
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         m_crtc XRandRCrtc(0x55f6c6063850)
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         CRTC: 59
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         MODE: 62
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         Connection: 0
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr:         Primary: true
Apr 03 09:48:50 desk02 org.kde.KScreen[5193]: kscreen.xrandr: Output 60 : connected = true , enabled = true
Apr 03 09:48:50 desk02 dbus-daemon[5193]: [session uid=1337 pid=5193] Activating service name='org.kde.ActivityManager' requested by ':1.99' (uid=1337 pid=37432 comm="/usr/bin/plasmashell ")
Apr 03 09:48:50 desk02 polkitd(authority=local)[192]: Registered Authentication Agent for unix-session:228 (system bus name :1.337 [/usr/lib/x86_64-linux-gnu/libexec/polkit-kde-authentication-agent-1], object path /org/kde/PolicyKit1/AuthenticationAgent, locale en_US.UTF-8)
Apr 03 09:48:50 desk02 org.kde.ActivityManager[5193]: qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 176, resource id: 4194443, major code: 20 (GetProperty), minor code: 0
Apr 03 09:48:50 desk02 dbus-daemon[5193]: [session uid=1337 pid=5193] Successfully activated service 'org.kde.ActivityManager'
Apr 03 09:48:50 desk02 org.kde.ActivityManager[5193]: qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 176, resource id: 4194443, major code: 20 (GetProperty), minor code: 0
Apr 03 09:48:50 desk02 org.kde.ActivityManager[5193]: Service started, version: 7.0.0
Apr 03 09:48:51 desk02 dbus-daemon[85]: [system] Activating service name='org.kde.powerdevil.discretegpuhelper' requested by ':1.339' (uid=1337 pid=37466 comm="/usr/lib/x86_64-linux-gnu/libexec/org_kde_powerdev") (using servicehelper)
Apr 03 09:48:51 desk02 org.kde.powerdevil.discretegpuhelper[37662]: QDBusArgument: read from a write-only object
Apr 03 09:48:51 desk02 org.kde.powerdevil.discretegpuhelper[37662]: QDBusArgument: read from a write-only object
Apr 03 09:48:51 desk02 org.kde.powerdevil.discretegpuhelper[37662]: QDBusArgument: read from a write-only object
Apr 03 09:48:51 desk02 dbus-daemon[85]: [system] Successfully activated service 'org.kde.powerdevil.discretegpuhelper'
Apr 03 09:48:51 desk02 dbus-daemon[85]: [system] Activating service name='org.kde.powerdevil.backlighthelper' requested by ':1.339' (uid=1337 pid=37466 comm="/usr/lib/x86_64-linux-gnu/libexec/org_kde_powerdev") (using servicehelper)
Apr 03 09:48:51 desk02 org.kde.powerdevil.backlighthelper[85]: powerdevil: no kernel backlight interface found
Apr 03 09:48:51 desk02 org.kde.powerdevil.backlighthelper[37667]: QDBusArgument: read from a write-only object
Apr 03 09:48:51 desk02 org.kde.powerdevil.backlighthelper[37667]: QDBusArgument: read from a write-only object
Apr 03 09:48:51 desk02 org.kde.powerdevil.backlighthelper[37667]: QDBusArgument: read from a write-only object
Apr 03 09:48:51 desk02 dbus-daemon[85]: [system] Successfully activated service 'org.kde.powerdevil.backlighthelper'
Apr 03 09:48:51 desk02 org.kde.KScreen[5193]: kscreen.xrandr: Emitting configChanged()
Apr 03 09:48:51 desk02 dbus-daemon[85]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.334' (uid=1337 pid=37390 comm="kded5 [kdeinit5]                                  ")
Apr 03 09:48:51 desk02 systemd[1]: Condition check resulted in Bluetooth service being skipped.
Apr 03 09:48:52 desk02 dbus-daemon[5193]: [session uid=1337 pid=5193] Activating service name='org.kde.kuiserver' requested by ':1.99' (uid=1337 pid=37432 comm="/usr/bin/plasmashell ")
Apr 03 09:48:52 desk02 dbus-daemon[5193]: [session uid=1337 pid=5193] Successfully activated service 'org.kde.kuiserver'
Apr 03 09:49:16 desk02 dbus-daemon[85]: [system] Failed to activate service 'org.bluez': timed out (service_start_timeout=25000ms)

Also looks okay to me. The /var/log/vsmagent.log on desk02 also looks promising:

2023-04-03 09:48:43 DEBUG vsmagent: Got encrypted vnc password for dummy.user
2023-04-03 09:48:43 DEBUG vsmagent: Got encrypted shadower vnc password for dummy.user
2023-04-03 09:48:43 DEBUG vsmagent: Cannot find font path element /usr/share/X11/fonts/misc, skipping
2023-04-03 09:48:43 DEBUG vsmagent: Cannot find font path element /usr/share/X11/fonts/75dpi, skipping
2023-04-03 09:48:43 DEBUG vsmagent: Cannot find font path element /usr/share/X11/fonts/100dpi, skipping
2023-04-03 09:48:43 DEBUG vsmagent: Cannot find font path element /usr/share/X11/fonts/Type1, skipping
2023-04-03 09:48:43 DEBUG vsmagent: Cannot find font path element /usr/share/X11/fonts/TTF, skipping
2023-04-03 09:48:43 DEBUG vsmagent: Cannot find font path element /usr/share/fonts/default/Type1, skipping
2023-04-03 09:48:43 DEBUG vsmagent: Cannot find font path element /usr/share/fonts/misc, skipping
2023-04-03 09:48:43 DEBUG vsmagent: Cannot find font path element /usr/share/fonts/75dpi, skipping
2023-04-03 09:48:43 DEBUG vsmagent: Cannot find font path element /usr/share/fonts/100dpi, skipping
2023-04-03 09:48:43 DEBUG vsmagent: Cannot find font path element /usr/share/fonts/Type1, skipping
2023-04-03 09:48:43 DEBUG vsmagent: Cannot find font path element /usr/share/fonts/URW, skipping
2023-04-03 09:48:43 DEBUG vsmagent: Cannot find font path element /usr/share/fonts/Speedo, skipping
2023-04-03 09:48:43 DEBUG vsmagent: Cannot find font path element /usr/share/fonts/cyrillic, skipping
2023-04-03 09:48:43 DEBUG vsmagent: Cannot find font path element /usr/share/fonts/misc, skipping
2023-04-03 09:48:45 INFO vsmagent.session: Verified connectivity to newly started Xvnc for dummy.user
2023-04-03 09:48:45 DEBUG vsmagent: Handling connection from ('10.0.81.11', 1023)
2023-04-03 09:48:45 DEBUG vsmagent.extproc: Starting process ['ss', '-n', '-t', '-a', '-p', '(', 'sport', '>=', ':5020', 'and', 'sport', '<', ':5026', ')', 'or', '(', 'dport', '>=', ':5020', 'and', 'dport', '<', ':5026', ')']
2023-04-03 09:48:45 DEBUG vsmagent.extproc: ['ss', '-n', '-t', '-a', '-p', '(', 'sport', '>=', ':5020', 'and', 'sport', '<', ':5026', ')', 'or', '(', 'dport', '>=', ':5020', 'and', 'dport', '<', ':5026', ')'] exited with exit code 0

continued in next post…

2

as does the xinit.log from the user’s session:

root@desk02:/var/opt/thinlinc/sessions/dummy.user/12# cat xinit.log 
xauth:  file /var/opt/thinlinc/sessions/dummy.user/12/Xauthority does not exist
tl-xinit: Waiting for Xserver to accept connections...

Xvnc ThinLinc release 4.12.0 build 6517 - built Jun 25 2020 10:15:03
Copyright (C) 1999-2020 TigerVNC Team and many others (see README.rst)
See https://www.tigervnc.org for information on TigerVNC.
Underlying X server release 12001000, The X.Org Foundation

(II) Initializing extension VNC-EXTENSION

Mon Apr  3 09:48:45 2023
 vncext:      VNC extension running!
 vncext:      Listening for VNC connections on local interface(s), port 5912
 vncext:      created VNC server for screen 0
(II) Initializing extension Generic Event Extension
(II) Initializing extension SHAPE
(II) Initializing extension MIT-SHM
(II) Initializing extension XInputExtension
(II) Initializing extension XTEST
(II) Initializing extension BIG-REQUESTS
(II) Initializing extension SYNC
(II) Initializing extension XKEYBOARD
(II) Initializing extension XC-MISC
(II) Initializing extension XFIXES
(II) Initializing extension RENDER
(II) Initializing extension RANDR
(II) Initializing extension COMPOSITE
(II) Initializing extension DAMAGE
(II) Initializing extension MIT-SCREEN-SAVER
(II) Initializing extension DOUBLE-BUFFER
(II) Initializing extension RECORD
(II) Initializing extension DPMS
(II) Initializing extension Present
(II) Initializing extension DRI3
(II) Initializing extension X-Resource
(II) Initializing extension XVideo
(II) Initializing extension XVideo-MotionCompensation
(II) Initializing extension GLX
(II) IGLX: Loaded and initialized swrast
(II) GLX: Initialized DRISWRAST GL provider for screen 0
The XKEYBOARD keymap compiler (xkbcomp) reports:
> Warning:          Unsupported high keycode 372 for name <I372> ignored
>                   X11 cannot support keycodes above 255.
>                   This warning only shows for the first high keycode.
Errors from xkbcomp are not fatal to the X server
 Connections: accepted: 127.0.0.1::38864
 VNCSConnST:  closing 127.0.0.1::38864: Clean disconnection
 EncodeManager: Framebuffer updates: 0
 EncodeManager:   Total: 0 rects, 0 pixels
 EncodeManager:          0 B (1:-nan ratio)
 TcpSocket:   unable to get peer name for socket
 Connections: closed: ::0
 ComparingUpdateTracker: 0 pixels in / 0 pixels out
 ComparingUpdateTracker: (1:-nan ratio)
tl-xinit: Xserver ready for clients.
--- System information ---
ThinLinc 4.12.0
Linux desk02 4.19.0-23-amd64 #1 SMP Debian 4.19.269-1 (2022-12-20) x86_64 GNU/Linux
Debian GNU/Linux 10 \n \l

No LSB modules are available.
--- End system information ---
Cannot find xsri. Background image support not available.
Running /opt/thinlinc/etc/xstartup.d/01-tl-kinit.sh (Obtaining Kerberos ticket)
Running /opt/thinlinc/etc/xstartup.d/02-tl-dbus-launch.sh (Starting session D-Bus server)
Existing session D-Bus server found.
Setting initial D-Bus and systemd environment...
Running /opt/thinlinc/etc/xstartup.d/11-tl-set-title (Setting client window title)
/opt/thinlinc/libexec/tl-run-xstartup-feedback:61: GtkWarning: Unable to locate theme engine in module_path: "adwaita",
  o0oOo0Ooo0O = tlgtk . gtk . Window ( type = tlgtk . gtk . WINDOW_TOPLEVEL )
Running /opt/thinlinc/etc/xstartup.d/12-tl-default-keyboard (Configuring default keyboard layout)
Setting default keyboard layout to "us"
The XKEYBOARD keymap compiler (xkbcomp) reports:
> Warning:          Unsupported high keycode 372 for name <I372> ignored
>                   X11 cannot support keycodes above 255.
>                   This warning only shows for the first high keycode.
Errors from xkbcomp are not fatal to the X server
Running /opt/thinlinc/etc/xstartup.d/15-tl-shadow-notify
Running /opt/thinlinc/etc/xstartup.d/20-tl-select-profile.sh (Choosing a profile)
Running /opt/thinlinc/etc/xstartup.d/30-tl-xdg-user-dirs (Translating standard folders)
Running /opt/thinlinc/etc/xstartup.d/40-tl-mount-localdrives (Mounting local drives)
Running /opt/thinlinc/etc/xstartup.d/41-tl-lp-redir-launch (Setting up printer port redirection)
Running /opt/thinlinc/etc/xstartup.d/43-tl-pulseaudio-launch.sh (Starting session PulseAudio server)
W: [pulseaudio] main.c: Couldn't canonicalize binary path, cannot self execute.
Session PulseAudio server started. Redirecting applications...
Running /opt/thinlinc/etc/xstartup.d/50-tl-wait-smartcard (Waiting for smart card connection)
Client has no smartcard capability
Stopping initial window manager...
Done.
Executing profile: kde
Using XDG session: plasma
Updating D-Bus and systemd environment...
Executing XDG session command: /usr/bin/startkde
startkde: Starting up...
kdeinit5: preparing to launch '/usr/lib/x86_64-linux-gnu/libexec/kf5/klauncher'
kdeinit5: Launched KLauncher, pid = 37370, result = 0
Connecting to deprecated signal QDBusConnectionInterface::serviceOwnerChanged(QString,QString,QString)
kdeinit5: opened connection to :12
qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 176, resource id: 4194443, major code: 20 (GetProperty), minor code: 0
kdeinit5: preparing to launch 'libkdeinit5_kded5'
kdeinit5: Launched KDED, pid = 37390 result = 0
kdeinit5: preparing to launch 'libkdeinit5_kcminit_startup'
kdeinit5: Launched 'kcminit_startup', pid = 37391 result = 0
Initializing  "kcm_access" :  "kcminit_access"
kdeinit5: Got EXEC_NEW '/usr/bin/kaccess' from launcher.
kdeinit5: preparing to launch '/usr/bin/kaccess'
Initializing  "kcm_style" :  "kcminit_style"
Xlib XKB extension major= 1  minor= 0
kdeinit5: Got EXEC_NEW '/usr/lib/x86_64-linux-gnu/libexec/kf5/kconf_update' from launcher.
kdeinit5: preparing to launch '/usr/lib/x86_64-linux-gnu/libexec/kf5/kconf_update'
2023-04-03T09:48:50 Checking update-file '/usr/share/kconf_update/disable_kmix.upd' for new updates
2023-04-03T09:48:50 Checking update-file '/usr/share/kconf_update/filepicker.upd' for new updates
2023-04-03T09:48:50 Checking update-file '/usr/share/kconf_update/fonts_akregator.upd' for new updates
2023-04-03T09:48:50 Checking update-file '/usr/share/kconf_update/fonts_global.upd' for new updates
2023-04-03T09:48:50 Checking update-file '/usr/share/kconf_update/fonts_global_toolbar.upd' for new updates
2023-04-03T09:48:50 Checking update-file '/usr/share/kconf_update/fonts_kate.upd' for new updates
2023-04-03T09:48:50 Checking update-file '/usr/share/kconf_update/gtkbreeze5.5.upd' for new updates
2023-04-03T09:48:50 Checking update-file '/usr/share/kconf_update/gwenview.upd' for new updates
2023-04-03T09:48:50 Checking update-file '/usr/share/kconf_update/kcalcrc.upd' for new updates
"Missing \"Version=5\", file '/usr/share/kconf_update/kcalcrc.upd' will be skipped."
2023-04-03T09:48:50 Checking update-file '/usr/share/kconf_update/kde4breeze.upd' for new updates
2023-04-03T09:48:50 Checking update-file '/usr/share/kconf_update/kile.upd' for new updates
"Missing \"Version=5\", file '/usr/share/kconf_update/kile.upd' will be skipped."
2023-04-03T09:48:50 Checking update-file '/usr/share/kconf_update/krdb_libpathwipe.upd' for new updates
2023-04-03T09:48:50 Checking update-file '/usr/share/kconf_update/krunnerplugins.upd' for new updates
2023-04-03T09:48:50 Checking update-file '/usr/share/kconf_update/kscreenlocker.upd' for new updates
2023-04-03T09:48:50 Checking update-file '/usr/share/kconf_update/kwin.upd' for new updates
2023-04-03T09:48:50 Checking update-file '/usr/share/kconf_update/okular.upd' for new updates
"Missing \"Version=5\", file '/usr/share/kconf_update/okular.upd' will be skipped."
kdeinit5: PID 37401 terminated.
qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 176, resource id: 4194443, major code: 20 (GetProperty), minor code: 0
kdeinit5: Got SETENV 'GTK_RC_FILES=/etc/gtk/gtkrc:/home/dummy.user/.gtkrc:/home/dummy.user/.config/gtkrc' from launcher.
kdeinit5: Got SETENV 'GTK2_RC_FILES=/etc/gtk-2.0/gtkrc:/home/dummy.user/.gtkrc-2.0:/home/dummy.user/.config/gtkrc-2.0' from launcher.
Initializing  "kcm_mouse" :  "kcminit_mouse"
X server XKB extension major= 1  minor= 0
qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 176, resource id: 4194443, major code: 20 (GetProperty), minor code: 0
qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 176, resource id: 4194443, major code: 20 (GetProperty), minor code: 0
kdeinit5: PID 37391 terminated.
kdeinit5: Got KWRAPPER '/usr/bin/ksmserver' from wrapper.
kdeinit5: preparing to launch '/usr/bin/ksmserver'
Qt: Session management error: networkIdsList argument is NULL
Configuring Lock Action
kdeinit5: Got SETENV 'SESSION_MANAGER=local/desk02:@/tmp/.ICE-unix/37412,unix/desk02:/tmp/.ICE-unix/37412' from launcher.
qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 176, resource id: 4194443, major code: 20 (GetProperty), minor code: 0
org.kde.kf5.ksmserver: Starting autostart service  "/etc/xdg/autostart/baloo_file.desktop" ("/usr/bin/baloo_file")
kscreen.kded: PowerDevil SuspendSession action not available!
org.kde.kf5.ksmserver: Starting autostart service  "/etc/xdg/autostart/krunner.desktop" ("/usr/bin/krunner")
kscreen: Requesting missing EDID for outputs (60)
org.kde.kf5.ksmserver: Starting autostart service  "/etc/xdg/autostart/org.kde.plasmashell.desktop" ("/usr/bin/plasmashell")
org.kde.kf5.ksmserver: Starting autostart service  "/etc/xdg/autostart/pam_kwallet_init.desktop" ("/usr/share/libpam-kwallet-common/pam_kwallet_init")
org.kde.kf5.ksmserver: Starting autostart service  "/etc/xdg/autostart/polkit-kde-authentication-agent-1.desktop" ("/usr/lib/x86_64-linux-gnu/libexec/polkit-kde-authentication-agent-1")
Baloo File Indexing has been disabled
org.kde.kf5.ksmserver: Starting autostart service  "/etc/xdg/autostart/xembedsniproxy.desktop" ("/usr/bin/xembedsniproxy")
Session path: "/org/freedesktop/login1/session/_3228"
Initializing  "kcm_kgamma" :  "kcminit_kgamma"
Initializing  "kded_touchpad" :  "kcminit_touchpad"
kcm_touchpad: Using X11 backend
org.kde.kf5.ksmserver: Starting autostart service  "/etc/xdg/autostart/gmenudbusmenuproxy.desktop" ("/usr/bin/gmenudbusmenuproxy")
org.kde.kf5.ksmserver: Starting autostart service  "/etc/xdg/autostart/kdeconnectd.desktop" ("/usr/lib/x86_64-linux-gnu/libexec/kdeconnectd")
org.kde.kf5.ksmserver: Starting autostart service  "/etc/xdg/autostart/pulseaudio.desktop" ("/usr/bin/start-pulseaudio-x11")
org.kde.kf5.ksmserver: Starting autostart service  "/etc/xdg/autostart/xdg-user-dirs.desktop" ("/usr/bin/xdg-user-dirs-update")
E: [pulseaudio] module.c: Failed to open module "module-x11-publish".
Failure: Module initialization failed
org.kde.kf5.ksmserver: Starting autostart service  "/etc/xdg/autostart/at-spi-dbus-bus.desktop" ("/usr/lib/at-spi2-core/at-spi-bus-launcher", "--launch-immediately")
org.kde.kf5.ksmserver: Starting autostart service  "/etc/xdg/autostart/geoclue-demo-agent.desktop" ("/usr/lib/geoclue-2.0/demos/agent")
org.kde.kf5.ksmserver: Starting autostart service  "/etc/xdg/autostart/powerdevil.desktop" ("/usr/lib/x86_64-linux-gnu/libexec/org_kde_powerdevil")
qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 176, resource id: 4194443, major code: 20 (GetProperty), minor code: 0
qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 176, resource id: 4194443, major code: 20 (GetProperty), minor code: 0
qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 176, resource id: 4194443, major code: 20 (GetProperty), minor code: 0
org.kde.bluez: Cannot open /dev/rfkill for reading!
qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 176, resource id: 4194443, major code: 20 (GetProperty), minor code: 0
bluedevil: Created
New PolkitAgentListener  0x5618745dd480
Adding new listener  PolkitQt1::Agent::Listener(0x7fc1ec013850) for  0x5618745dd480
Listener online
Authentication agent result: true
qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 176, resource id: 4194443, major code: 20 (GetProperty), minor code: 0
kdeconnect.core: KdeConnect daemon starting
kdeconnect.core: onStart
kdeconnect.core: KdeConnect daemon started
qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 176, resource id: 4194443, major code: 20 (GetProperty), minor code: 0
kdeconnect.core: Broadcasting identity packet
kdeconnect.core: Starting client ssl (but I'm the server TCP socket)
kdeconnect.core: Starting client ssl (but I'm the server TCP socket)
kdeconnect.core: Starting client ssl (but I'm the server TCP socket)
kdeconnect.core: Starting client ssl (but I'm the server TCP socket)
kdeconnect.core: Socket successfully established an SSL connection
kdeconnect.core: It is a new device "other.user@desk02"
kdeconnect.core: Not loading plugin "kdeconnect_telephony" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_findmyphone" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_battery" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_runcommand" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_pausemusic" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_mpriscontrol" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_sftp" because device doesn't support it
kdeconnect.core: Socket successfully established an SSL connection
kdeconnect.core: It is a new device "other.user@desk02"
kdeconnect.core: Not loading plugin "kdeconnect_telephony" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_findmyphone" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_battery" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_runcommand" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_pausemusic" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_mpriscontrol" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_sftp" because device doesn't support it
kdeconnect.core: Socket successfully established an SSL connection
kdeconnect.core: It is a new device "other.user@desk02"
kdeconnect.core: Not loading plugin "kdeconnect_telephony" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_findmyphone" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_battery" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_runcommand" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_pausemusic" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_mpriscontrol" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_sftp" because device doesn't support it
kdeconnect.core: Socket successfully established an SSL connection
kdeconnect.core: It is a new device "other.user@desk02"
kdeconnect.core: Not loading plugin "kdeconnect_telephony" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_findmyphone" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_battery" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_runcommand" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_pausemusic" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_mpriscontrol" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_sftp" because device doesn't support it
QDBusArgument: read from a write-only object
QDBusArgument: read from a write-only object
QDBusArgument: read from a write-only object
qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 176, resource id: 4194443, major code: 20 (GetProperty), minor code: 0
Aborting shell load: The activity manager daemon (kactivitymanagerd) is not running.
If this Plasma has been installed into a custom prefix, verify that its D-Bus services dir is known to the system for the daemon to be activatable.
org.kde.plasmaquick: Applet preload policy set to 1
qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 176, resource id: 4194443, major code: 20 (GetProperty), minor code: 0
powerdevil: Backend loaded, loading core
powerdevil: Core loaded, initializing backend
powerdevil: No outputs have backlight property
powerdevil: Xrandr not supported, trying ddc, helper
powerdevil: [DDCutilBrightness] compiled without DDC/CI support
powerdevil: Falling back to helper to get brightness
OpenGL vendor string:                   VMware, Inc.
OpenGL renderer string:                 llvmpipe (LLVM 7.0, 128 bits)
OpenGL version string:                  3.1 Mesa 18.3.6
OpenGL shading language version string: 1.40
Driver:                                 LLVMpipe
GPU class:                              Unknown
OpenGL version:                         3.1
GLSL version:                           1.40
Mesa version:                           18.3.6
Linux kernel version:                   4.19
Requires strict binding:                yes
GLSL shaders:                           yes
Texture NPOT support:                   yes
Virtual Machine:                        no
powerdevil: org.kde.powerdevil.discretegpuhelper.hasdualgpu failed
powerdevil: ""
powerdevil: org.kde.powerdevil.backlighthelper.brightness failed
powerdevil: ""
powerdevil: Backend is ready, KDE Power Management system initialized
powerdevil: Session path: "/org/freedesktop/login1/session/_3228"
powerdevil: Unable to associate systemd session with a seat "/"
powerdevil: Got a valid offer for  "DPMSControl"
qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 796, resource id: 35651592, major code: 3 (GetWindowAttributes), minor code: 0
qt.qpa.xcb: QXcbConnection: XCB error: 9 (BadDrawable), sequence: 797, resource id: 35651592, major code: 14 (GetGeometry), minor code: 0
org.kde.bluez: Cannot open /dev/rfkill for reading!
Installing the delayed initialization callback.
powerdevil: Core is ready, registering various services on the bus...
powerdevil: Can't contact systemd
powerdevil: Can't contact ck
powerdevil: We are now into activity  "6c0ddefb-b45a-4e47-8b86-ef3b7ec93b95"
powerdevil: () ()
powerdevil: () ()
powerdevil: No batteries found, loading AC
powerdevil: Activity is not forcing a profile
The X11 connection broke: Unsupported extension used (code 2)
XIO:  fatal IO error 2 (No such file or directory) on X server ":12"
      after 415 requests (415 known processed) with 0 events remaining.
kcm_touchpad: Using X11 backend
Delayed initialization.
Reloading the khotkeys configuration
Version 2 File!
true
Imported file "/usr/share/khotkeys/spectacle.khotkeys"
Imported file "/usr/share/khotkeys/konqueror_gestures_kde321.khotkeys"
Imported file "/usr/share/khotkeys/kde32b1.khotkeys"
Imported file "/usr/share/khotkeys/defaults.khotkeys"
bluedevil: Bluetooth operational changed false
bluedevil: ObexManager operational changed true
bluedevil: ObexAgent registered
file:///usr/share/plasma/plasmoids/org.kde.desktopcontainment/contents/ui/main.qml:62:28: Unable to assign [undefined] to int
file:///usr/share/plasma/plasmoids/org.kde.desktopcontainment/contents/ui/main.qml:53:27: Unable to assign [undefined] to int
trying to show an empty dialog
kdeinit5: Got EXEC_NEW '/usr/lib/x86_64-linux-gnu/qt5/plugins/kf5/kio/desktop.so' from launcher.
kdeinit5: preparing to launch '/usr/lib/x86_64-linux-gnu/qt5/plugins/kf5/kio/desktop.so'
kscreen: Requesting missing EDID for outputs (60)
kdeinit5: Got EXEC_NEW '/usr/lib/x86_64-linux-gnu/qt5/plugins/kf5/kio/file.so' from launcher.
kdeinit5: preparing to launch '/usr/lib/x86_64-linux-gnu/qt5/plugins/kf5/kio/file.so'
Connecting to deprecated signal QDBusConnectionInterface::serviceOwnerChanged(QString,QString,QString)
Trying to use rootObject before initialization is completed, whilst using setInitializationDelayed. Forcing completion
Both point size and pixel size set. Using pixel size.
Both point size and pixel size set. Using pixel size.
Both point size and pixel size set. Using pixel size.
trying to show an empty dialog
trying to show an empty dialog
Registering "org.kde.StatusNotifierHost-37432" as system tray
kdeconnect.core: Sending onNetworkChange to  1  LinkProviders
kdeconnect.core: Broadcasting identity packet
kdeconnect.core: Starting client ssl (but I'm the server TCP socket)
kdeconnect.core: Starting client ssl (but I'm the server TCP socket)
kdeconnect.core: Starting client ssl (but I'm the server TCP socket)
kdeconnect.core: Starting client ssl (but I'm the server TCP socket)
kdeconnect.core: Socket successfully established an SSL connection
kdeconnect.core: It is a new device "other.user@desk02"
kdeconnect.core: Not loading plugin "kdeconnect_telephony" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_findmyphone" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_battery" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_runcommand" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_pausemusic" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_mpriscontrol" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_sftp" because device doesn't support it
kdeconnect.core: Socket successfully established an SSL connection
kdeconnect.core: It is a known device "other.user@desk02"
kdeconnect.core: Socket successfully established an SSL connection
kdeconnect.core: It is a known device "other.user@desk02"
kdeconnect.core: Socket successfully established an SSL connection
kdeconnect.core: It is a new device "other.user@desk02"
kdeconnect.core: Not loading plugin "kdeconnect_telephony" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_findmyphone" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_battery" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_runcommand" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_pausemusic" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_mpriscontrol" because device doesn't support it
kdeconnect.core: Not loading plugin "kdeconnect_sftp" because device doesn't support it
Plasma Shell startup completed
org.kde.plasmaquick: New Applet  "Application Launcher" with a weight of 100
org.kde.plasmaquick: New Applet  "Digital Clock" with a weight of 50
qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 2243, resource id: 4194319, major code: 2 (ChangeWindowAttributes), minor code: 0
qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 2248, resource id: 4194334, major code: 18 (ChangeProperty), minor code: 0
trying to show an empty dialog
kdeinit5: Got EXEC_NEW '/usr/lib/x86_64-linux-gnu/qt5/plugins/kf5/kio/file.so' from launcher.
kdeinit5: preparing to launch '/usr/lib/x86_64-linux-gnu/qt5/plugins/kf5/kio/file.so'
org.kde.plasmaquick: New Applet  "Audio Volume" with a weight of 50
org.kde.plasmaquick: New Applet  "Clipboard" with a weight of 50
org.kde.plasmaquick: New Applet  "Device Notifier" with a weight of 50
org.kde.plasmaquick: New Applet  "KDE Connect" with a weight of 50
org.kde.plasmaquick: New Applet  "Notifications" with a weight of 50
org.kde.plasmaquick: Delayed preload of  "Application Launcher" after 0 seconds
Empty filename passed to function
KAStatsFavoritesModel::setFavorites is ignored
KActivities: Database connection:  "kactivities_db_resources_140495438002240_readonly" 
    query_only:          QVariant(qlonglong, 1) 
    journal_mode:        QVariant(QString, "wal") 
    wal_autocheckpoint:  QVariant(qlonglong, 100) 
    synchronous:         QVariant(qlonglong, 0)
Entry is not valid "kontact.desktop" 0x561fc0193600
Entry is not valid "ktp-contactlist.desktop" 0x561fc0193600
Entry is not valid "org.kde.discover.desktop" 0x561fc0193600
Entry is not valid "kontact.desktop" 0x561fc0193600
Entry is not valid "ktp-contactlist.desktop" 0x561fc0193600
Entry is not valid "org.kde.discover.desktop" 0x561fc0193600
Closing SQL connection:  "kactivities_db_resources_140495438002240_readonly"
trying to show an empty dialog
org.kde.plasmaquick: Applet "Application Launcher" loaded after 58 msec
kdeinit5: Got EXEC_NEW '/usr/lib/x86_64-linux-gnu/qt5/plugins/kf5/kio/file.so' from launcher.
kdeinit5: preparing to launch '/usr/lib/x86_64-linux-gnu/qt5/plugins/kf5/kio/file.so'
kdeinit5: Got EXEC_NEW '/usr/lib/x86_64-linux-gnu/qt5/plugins/kf5/kio/file.so' from launcher.
kdeinit5: preparing to launch '/usr/lib/x86_64-linux-gnu/qt5/plugins/kf5/kio/file.so'
kdeinit5: Got EXEC_NEW '/usr/lib/x86_64-linux-gnu/qt5/plugins/kf5/kio/file.so' from launcher.
kdeinit5: preparing to launch '/usr/lib/x86_64-linux-gnu/qt5/plugins/kf5/kio/file.so'
kdeinit5: Got EXEC_NEW '/usr/lib/x86_64-linux-gnu/qt5/plugins/kf5/kio/file.so' from launcher.
kdeinit5: preparing to launch '/usr/lib/x86_64-linux-gnu/qt5/plugins/kf5/kio/file.so'
org.kde.plasma.pulseaudio: No object for name "auto_null.monitor"
org.kde.plasmaquick: Delayed preload of  "Digital Clock" after 1.154 seconds
Both point size and pixel size set. Using pixel size.
Both point size and pixel size set. Using pixel size.
Both point size and pixel size set. Using pixel size.
Both point size and pixel size set. Using pixel size.
Both point size and pixel size set. Using pixel size.
Both point size and pixel size set. Using pixel size.
Both point size and pixel size set. Using pixel size.
Both point size and pixel size set. Using pixel size.
trying to show an empty dialog
org.kde.plasmaquick: Applet "Digital Clock" loaded after 83 msec
org.kde.plasmaquick: Delayed preload of  "Audio Volume" after 1.118 seconds
qrc:/plasma/plasmoids/org.kde.plasma.volume/contents/ui/main.qml:415:17: QML ColumnLayout: Binding loop detected for property "maximumWidth"
org.kde.plasmaquick: Applet "Audio Volume" loaded after 30 msec
org.kde.plasmaquick: Delayed preload of  "Device Notifier" after 2.504 seconds
org.kde.plasmaquick: Applet "Device Notifier" loaded after 7 msec
org.kde.plasmaquick: Delayed preload of  "KDE Connect" after 2.87 seconds
org.kde.plasmaquick: Applet "KDE Connect" loaded after 6 msec
org.kde.plasmaquick: Delayed preload of  "Notifications" after 2.755 seconds
org.kde.plasmaquick: Applet "Notifications" loaded after 0 msec
org.kde.plasmaquick: Delayed preload of  "Clipboard" after 3.077 seconds
org.kde.plasmaquick: Applet "Clipboard" loaded after 13 msec
org.kde.bluez: PendingCall Error: "Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken."
kdeinit5: Got EXEC_NEW '/usr/lib/x86_64-linux-gnu/qt5/plugins/kf5/kio/file.so' from launcher.
kdeinit5: preparing to launch '/usr/lib/x86_64-linux-gnu/qt5/plugins/kf5/kio/file.so'

However, ~2 minutes after this output (when the user has long received their timeout), the following output is added to the xinint.log:

kdeinit5: PID 37702 terminated.
kdeinit5: PID 37698 terminated.
kdeinit5: PID 37699 terminated.
kdeinit5: PID 37700 terminated.
kdeinit5: PID 37701 terminated.
kdeinit5: PID 37691 terminated.
kdeinit5: PID 37693 terminated.

and another ~2 minutes later:

lock called
Lock window Id:  27262983
CreateNotify: 27262983
CreateNotify: 27262985
Grab Released
Greeter Process not available

Looks like the session is created but now quickly enough??

When I try connecting again after ~20-30 minutes, only the /var/log/vsmserver.log on the master says anything:

2023-04-03 10:16:12 INFO vsmserver.session: User with uid 1337 requested a reconnection to desk02.domain.de:12
2023-04-03 10:16:12 INFO vsmserver: Verifying session desk02.domain.de:12 for dummy.user
2023-04-03 10:16:12 INFO vsmserver: Session desk02.domain.de:12 for dummy.user is alive and ready for reconnection

while the /var/log/vsmagent.log and the systemd journal on desk02 (and also the xinit.log of the user’s session) do not generate any new output on this re-connection attempt.

Any ideas what is happening here? Everything worked perfectly fine before the server reboot and nothing was changed on these two servers during the downtime.

If any more logs/config info is required, please let me know!

(split into two posts because post was over the character limit with all the logs…)

3

No, not really, but it feels like something goes wrong with KDE Plasma. Have you tried any other DE, just for reference?
Are you using KDE Plasma on desk01 as well ?

4

I have not tried any other DEs but desk01 is running KDE as well.

5

That’s the only issue I can find from the session log. This should not be a critical error, seems to be the default gtk theme missing from the system. This is needed when displaying the Profile Selection dialog. The session startup continues as expected, from a ThinLinc perspective:

Stopping initial window manager...
Done.
Executing profile: kde
Using XDG session: plasma

Then it’s up to KDE to do its job, and for whatever reason it fails to do so proplery…

6

I found out something new today, however it still didn’t solve the problem:

When I try to connect via a machine that is physically connected to the same VLAN (10.0.81.0/24), I can connect to desk02. From any other LAN (including the internet) I can’t.

Connections to desk01 work regardless whether they’re coming from 10.0.81.0/24 or anywhere else.

So it has to be something with desk02’s network config (or the vsmagent.hconf??). But I can’t figure out what… the configs are the same as on desk01 just with different IPs…

7

What error is the client presenting? And what is the value of agent_hostname in vsmagent.hconf in desk01 and desk02, respectively?

Due to the nature of how ThinLinc is designed, where the client first authenticates to the master, which then responds to the client with the value of agent_hostname from the agent, the client needs to be able to make an direct (ssh) connection to whatever is defined in agent_hostname.

If agent_hostname is empty, it will default to the ipaddress of the primary network interface.

/Martin

8

The client just says: “Connection to server desk02.domain .de timed out. Check your settings.”

The vsmagent.hconf on desk01 is set to “desk01.domain.de”
The vsmagent.hconf on desk02 is set to “desk02.domain.de”

I tested the SSH part by trying to simply connect to these two domains via Putty over the internet and desk01 works but desk02doesn’t! I can ping neither from the internet.

Inside the 10.0.81.0/24 VLAN both domains work with SSH and with ping.

So, somehow desk02 is not accessible from outside the VLAN, I suppose? Sounds like a firewall issue, maybe.

9

Okay, the attached session log made me believe that the client actually got connected but then timed out for some reason. With this new information provided, As you wrote, this is more of a connectivity issue, outside of ThinLinc.

You will need to review any firewall, external or locally on desk02.

Kind regards,
Martin