Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

White screen when resizing RDP window session #3316

Open
geragio opened this issue Nov 17, 2024 · 19 comments
Open

White screen when resizing RDP window session #3316

geragio opened this issue Nov 17, 2024 · 19 comments
Labels

Comments

@geragio
Copy link

geragio commented Nov 17, 2024

xrdp version

0.10.1

Detailed xrdp version, build options

xrdp 0.10.1
  A Remote Desktop Protocol Server.
  Copyright (C) 2004-2024 Jay Sorg, Neutrino Labs, and all contributors.
  See https://github.com/neutrinolabs/xrdp for more information.

  Configure options:
      --build=x86_64-redhat-linux-gnu
      --host=x86_64-redhat-linux-gnu
      --program-prefix=
      --disable-dependency-tracking
      --prefix=/usr
      --exec-prefix=/usr
      --bindir=/usr/bin
      --sbindir=/usr/sbin
      --sysconfdir=/etc
      --datadir=/usr/share
      --includedir=/usr/include
      --libdir=/usr/lib64
      --libexecdir=/usr/libexec
      --localstatedir=/var
      --sharedstatedir=/var/lib
      --mandir=/usr/share/man
      --infodir=/usr/share/info
      --enable-fuse
      --enable-pixman
      --enable-painter
      --enable-vsock
      --enable-ipv6
      --with-socketdir=/run/xrdp
      --with-imlib2
      build_alias=x86_64-redhat-linux-gnu
      host_alias=x86_64-redhat-linux-gnu
      CC=gcc
      CFLAGS=-O2 -flto=auto -ffat-lto-objects -fexceptions -g -grecord-gcc-switches -pipe -Wall -Werror=format-security -Wp,-D_FORTIFY_SOURCE=2 -Wp,-D_GLIBCXX_ASSERTIONS -specs=/usr/lib/rpm/redhat/redhat-hardened-cc1 -fstack-protector-strong -specs=/usr/lib/rpm/redhat/redhat-annobin-cc1  -m64 -march=x86-64-v2 -mtune=generic -fasynchronous-unwind-tables -fstack-clash-protection -fcf-protection 
      LDFLAGS=-Wl,-z,relro -Wl,--as-needed  -Wl,-z,now -specs=/usr/lib/rpm/redhat/redhat-hardened-ld -specs=/usr/lib/rpm/redhat/redhat-annobin-cc1 
      CXX=g++
      CXXFLAGS=-O2 -flto=auto -ffat-lto-objects -fexceptions -g -grecord-gcc-switches -pipe -Wall -Werror=format-security -Wp,-D_FORTIFY_SOURCE=2 -Wp,-D_GLIBCXX_ASSERTIONS -specs=/usr/lib/rpm/redhat/redhat-hardened-cc1 -fstack-protector-strong -specs=/usr/lib/rpm/redhat/redhat-annobin-cc1  -m64 -march=x86-64-v2 -mtune=generic -fasynchronous-unwind-tables -fstack-clash-protection -fcf-protection
      LT_SYS_LIBRARY_PATH=/usr/lib64:
      PKG_CONFIG_PATH=:/usr/lib64/pkgconfig:/usr/share/pkgconfig

  Compiled with OpenSSL 3.0.7 1 Nov 2022

Operating system & version

AlmaLinux 9

Installation method

dnf / apt / zypper / pkg / etc

Which backend do you use?

xorgxrdp

What desktop environment do you use?

GNOME

Environment xrdp running on

VM in Proxmox VE

What's your client?

Remmina 1.4.35

Area(s) with issue?

Graphic glitches

Steps to reproduce

I'm facing some graphic glitches every time I try to resize the RDP window on AlmaLinux 9.4(fresh installation)
This issue can be reproduced also on RHEL 9.5

The initial connection works fine until I try to resize Remmina window, the RDP session "crashes" and all I can see is a white screen.
When this happens, I have to kill the RDP session with loginctl kill-session <session-id> before attempting a new connection.

The server I'm trying to connect is a VM hosted inside Proxmox VE

I can't see any relevant error messages logged by xrdp

# Initial connection

Nov 17 20:08:44 host.example.com xrdp[966]: [INFO ] Socket 14: connection accepted from [::ffff:10.10.10.1]:60866
Nov 17 20:08:44 host.example.com xrdp[1469]: [INFO ] Using default X.509 certificate: /etc/xrdp/cert.pem
Nov 17 20:08:44 host.example.com xrdp[1469]: [INFO ] Using default X.509 key file: /etc/xrdp/key.pem
Nov 17 20:08:44 host.example.com xrdp[1469]: [INFO ] Security protocol: configured [SSL|RDP], requested [SSL|HYBRID|RDP], selected [SSL]
Nov 17 20:08:45 host.example.com xrdp[1469]: [INFO ] Connected client computer name: xps
Nov 17 20:08:45 host.example.com xrdp[1469]: [INFO ] client supports gfx protocol
Nov 17 20:08:45 host.example.com xrdp[1469]: [WARN ] Physical desktop dimensions (0x0) are invalid
Nov 17 20:08:45 host.example.com xrdp[1469]: [WARN ] Received [MS-RDPBCGR] TS_UD_HEADER type 0xc006 is unknown (ignored)
Nov 17 20:08:45 host.example.com xrdp[1469]: [WARN ] Received [MS-RDPBCGR] TS_UD_HEADER type 0xc00a is unknown (ignored)
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_load_keyboard_layout: Keyboard information sent by the RDP client, keyboard_type:[0x04], keyboard_subtype:[0x00], keylayout:[0x00000410]
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_load_keyboard_layout: model [] variant [] layout [it] options []
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] TLS connection established from [::ffff:10.10.10.1]:60866 TLSv1.3 with cipher TLS_AES_256_GCM_SHA384
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] Client supports single-screen resizes by xrdp
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_caps_process_pointer: client supports new(color) cursor
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_process_offscreen_bmpcache: support level 1 cache size 7864320 MB cache entries 2000
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_caps_process_codecs: RemoteFX(76772F12-BD72-4463-AFB3-B73C9C6F7886), codec id [3], properties len [49]
Nov 17 20:08:46 host.example.com xrdp[1469]: [WARN ] Client Capability: not enough orders supported by client, client wants off screen bitmap but offscreen bitmaps disabled
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] Client Capability: LARGE_POINTER_FLAG_96x96 supported
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] Loading keymap file /etc/xrdp/km-00000410.ini
Nov 17 20:08:46 host.example.com xrdp[1469]: [WARN ] local keymap file for 0x00000410 found and doesn't match built in keymap, using local keymap file
Nov 17 20:08:46 host.example.com xrdp[1469]: [WARN ] No information is available to determine login screen DPI
Nov 17 20:08:46 host.example.com xrdp[1469]: [WARN ] No DPI value is available to find login font
Nov 17 20:08:46 host.example.com xrdp[1469]: [WARN ] Using the default_dpi of 96
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_egfx_create: error 0 channel_id 1
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_mm_egfx_caps_advertise:
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ]   version 0x00080004 flags 0x00000002 (index: 0)
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ]   version 0x00080105 flags 0x00000012 (index: 1)
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ]   version 0x000a0002 flags 0x00000002 (index: 2)
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ]   version 0x000a0200 flags 0x00000002 (index: 3)
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ]   version 0x000a0301 flags 0x00000000 (index: 4)
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ]   version 0x000a0400 flags 0x00000002 (index: 5)
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ]   version 0x000a0502 flags 0x00000002 (index: 6)
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ]   version 0x000a0600 flags 0x00000002 (index: 7)
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ]   version 0x000a0601 flags 0x00000002 (index: 8)
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] unknown version 0x000a0601
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ]   version 0x000a0701 flags 0x00000002 (index: 9)
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ]   replying version 0x000a0701 flags 0x00000002
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_mm_egfx_caps_advertise: xrdp_egfx_send_capsconfirm error 0 best_index 9
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_egfx_reset_graphics:
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_egfx_reset_graphics: width 2520 height 1368 monitorcount 1
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_mm_egfx_caps_advertise: xrdp_egfx_send_reset_graphics error 0 monitorCount 0
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_mm_egfx_create_surfaces: map surface_id 0 left 0 top 0 width 2520 height 1368
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_encoder_create: starting gfx rfx pro codec session
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_mm_egfx_invalidate_wm_screen:
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_mm_egfx_caps_advertise: egfx created.
Nov 17 20:08:46 host.example.com xrdp[1469]: [WARN ] Ignoring obsolete SCP port value '3350'
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_wm_log_msg: sesman connect ok
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_wm_log_msg: Performing login request for gg
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] xrdp_wm_log_msg: login was successful - creating session
Nov 17 20:08:46 host.example.com xrdp[1469]: [INFO ] Not allowing resize. Login in progress.
Nov 17 20:08:47 host.example.com xrdp[1469]: [INFO ] xrdp_wm_log_msg: session is available on display 10 for user gg
Nov 17 20:08:47 host.example.com xrdp[1469]: [INFO ] xrdp_wm_log_msg: Connecting to session
Nov 17 20:08:47 host.example.com xrdp[1469]: [INFO ] loaded module 'libvnc.so' ok, interface size 4832, version 4
Nov 17 20:08:47 host.example.com xrdp[1469]: [INFO ] VNC: Clipboard (if available) is provided by chansrv facility
Nov 17 20:08:47 host.example.com xrdp[1469]: [INFO ] xrdp_wm_log_msg: Connecting to chansrv
Nov 17 20:08:47 host.example.com xrdp[1469]: [INFO ] Layout from ClientLayout (geom=2520x1368 #screens=1) : 0:(2520x1368+0+0)
Nov 17 20:08:47 host.example.com xrdp[1469]: [INFO ] Layout from OldServerLayout (geom=2520x1368 #screens=1) : 1804289383:(2520x1368+0+0)

# After resizing Remmina window

Nov 17 20:16:24 host.example.com xrdp[1469]: [INFO ] Layout from NewLayout (geom=2520x1371 #screens=1) : 1804289383:(2520x1371+0+0)
Nov 17 20:16:24 host.example.com xrdp[1469]: [INFO ] server_monitor_resize_done: Advancing server monitor resized.
Nov 17 20:16:24 host.example.com xrdp[1469]: [INFO ] Client supports single-screen resizes by xrdp
Nov 17 20:16:24 host.example.com xrdp[1469]: [INFO ] xrdp_caps_process_pointer: client supports new(color) cursor
Nov 17 20:16:24 host.example.com xrdp[1469]: [INFO ] xrdp_process_offscreen_bmpcache: support level 1 cache size 7864320 MB cache entries 2000
Nov 17 20:16:24 host.example.com xrdp[1469]: [INFO ] xrdp_caps_process_codecs: RemoteFX(76772F12-BD72-4463-AFB3-B73C9C6F7886), codec id [3], properties len [49]
Nov 17 20:16:24 host.example.com xrdp[1469]: [WARN ] Client Capability: not enough orders supported by client, client wants off screen bitmap but offscreen bitmaps disabled
Nov 17 20:16:24 host.example.com xrdp[1469]: [INFO ] Client Capability: LARGE_POINTER_FLAG_96x96 supported

Not sure why this happens, I believe this could be a bug

✔️ Expected Behavior

Screen resizing working

❌ Actual Behavior

White screen, need to kill the session with loginctl kill-session to make a new successfull connection

Anything else?

No response

@geragio geragio added the bug label Nov 17, 2024
@matt335672
Copy link
Member

Your report shows a backend of xorgxrdp and your log shows you're using a VNC backend. Could you clarify which you are using please?

I've just reproduced your setup, and I'm not seeing any white screens here on a vanilla installation with VNC.

After you get one, can you list a complete system log for the last minute with :-

sudo journalctl -S -1m

@geragio
Copy link
Author

geragio commented Nov 18, 2024

@matt335672 how can I double-check that? xorgxrdp package is installed on the system.

Here is system log:

Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] Connected client computer name: t14s
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] client supports gfx protocol
Nov 18 14:37:06 host.example.com xrdp[1692]: [WARN ] Physical desktop dimensions (0x0) are invalid
Nov 18 14:37:06 host.example.com xrdp[1692]: [WARN ] Received [MS-RDPBCGR] TS_UD_HEADER type 0xc006 is unknown (ignored)
Nov 18 14:37:06 host.example.com xrdp[1692]: [WARN ] Received [MS-RDPBCGR] TS_UD_HEADER type 0xc00a is unknown (ignored)
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_load_keyboard_layout: Keyboard information sent by the RDP client, keyboard_type:[0x04], keyboard_subtype:[0x00], keylayout:[0x00000410]
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_load_keyboard_layout: model [] variant [] layout [it] options []
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] TLS connection established from [::ffff:10.10.10.1]:56206 TLSv1.3 with cipher TLS_AES_256_GCM_SHA384
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] Client supports single-screen resizes by xrdp
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_caps_process_pointer: client supports new(color) cursor
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_process_offscreen_bmpcache: support level 1 cache size 7864320 MB cache entries 2000
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_caps_process_codecs: RemoteFX(76772F12-BD72-4463-AFB3-B73C9C6F7886), codec id [3], properties len [49]
Nov 18 14:37:06 host.example.com xrdp[1692]: [WARN ] Client Capability: not enough orders supported by client, client wants off screen bitmap but offscreen bitmaps disabled
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] Client Capability: LARGE_POINTER_FLAG_96x96 supported
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] Loading keymap file /etc/xrdp/km-00000410.ini
Nov 18 14:37:06 host.example.com xrdp[1692]: [WARN ] local keymap file for 0x00000410 found and doesn't match built in keymap, using local keymap file
Nov 18 14:37:06 host.example.com xrdp[1692]: [WARN ] No information is available to determine login screen DPI
Nov 18 14:37:06 host.example.com xrdp[1692]: [WARN ] No DPI value is available to find login font
Nov 18 14:37:06 host.example.com xrdp[1692]: [WARN ] Using the default_dpi of 96
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_egfx_create: error 0 channel_id 1
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_mm_egfx_caps_advertise:
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ]   version 0x00080004 flags 0x00000002 (index: 0)
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ]   version 0x00080105 flags 0x00000012 (index: 1)
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ]   version 0x000a0002 flags 0x00000002 (index: 2)
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ]   version 0x000a0200 flags 0x00000002 (index: 3)
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ]   version 0x000a0301 flags 0x00000000 (index: 4)
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ]   version 0x000a0400 flags 0x00000002 (index: 5)
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ]   version 0x000a0502 flags 0x00000002 (index: 6)
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ]   version 0x000a0600 flags 0x00000002 (index: 7)
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ]   version 0x000a0601 flags 0x00000002 (index: 8)
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] unknown version 0x000a0601
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ]   version 0x000a0701 flags 0x00000002 (index: 9)
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ]   replying version 0x000a0701 flags 0x00000002
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_mm_egfx_caps_advertise: xrdp_egfx_send_capsconfirm error 0 best_index 9
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_egfx_reset_graphics:
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_egfx_reset_graphics: width 2520 height 1368 monitorcount 1
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_mm_egfx_caps_advertise: xrdp_egfx_send_reset_graphics error 0 monitorCount 0
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_mm_egfx_create_surfaces: map surface_id 0 left 0 top 0 width 2520 height 1368
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_encoder_create: starting gfx rfx pro codec session
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_mm_egfx_invalidate_wm_screen:
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_mm_egfx_caps_advertise: egfx created.
Nov 18 14:37:06 host.example.com xrdp[1692]: [WARN ] Ignoring obsolete SCP port value '3350'
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_wm_log_msg: sesman connect ok
Nov 18 14:37:06 host.example.com xrdp-sesman[1153]: [INFO ] Socket 13: connection accepted from AF_UNIX
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_wm_log_msg: Performing login request for gg
Nov 18 14:37:06 host.example.com xrdp-sesman[1153]: [INFO ] Received system login request from xrdp for user: gg IP: ::ffff:10.10.10.1
Nov 18 14:37:06 host.example.com xrdp-sesexec[1697]: [INFO ] starting xrdp-sesexec with pid 1697
Nov 18 14:37:06 host.example.com xrdp-sesexec[1697]: [INFO ] TerminalServerUsers group tsusers doesn't exist. Access granted for gg
Nov 18 14:37:06 host.example.com xrdp-sesexec[1697]: [INFO ] Access permitted for user: gg
Nov 18 14:37:06 host.example.com xrdp-sesman[1153]: [INFO ] Received sys login status for gg : logged in
Nov 18 14:37:06 host.example.com xrdp[1692]: [INFO ] xrdp_wm_log_msg: login was successful - creating session
Nov 18 14:37:06 host.example.com xrdp-sesman[1153]: [INFO ] Received request from xrdp to create a session for user gg
Nov 18 14:37:06 host.example.com systemd-logind[743]: New session c2 of user gg.
Nov 18 14:37:06 host.example.com systemd[1]: Started Session c2 of User gg.
Nov 18 14:37:06 host.example.com xrdp-sesexec[1697]: pam_unix(xrdp-sesman:session): session opened for user gg(uid=1000) by (uid=0)
Nov 18 14:37:06 host.example.com xrdp-sesexec[1703]: [INFO ] Starting X server on display 10: Xvnc :10 -auth .Xauthority -geometry 2520x1368 -depth 32 -rfbauth /home/gg/.vnc/[email protected]:10 -bs -nolisten tcp -localhost -dpi 96
Nov 18 14:37:07 host.example.com xrdp-sesman[1703]: Xvnc TigerVNC 1.13.1 - built Apr 16 2024 00:00:00
Nov 18 14:37:07 host.example.com xrdp-sesman[1703]: Copyright (C) 1999-2022 TigerVNC Team and many others (see README.rst)
Nov 18 14:37:07 host.example.com xrdp-sesman[1703]: See https://www.tigervnc.org for information on TigerVNC.
Nov 18 14:37:07 host.example.com xrdp-sesman[1703]: Underlying X server release 12011000
Nov 18 14:37:07 host.example.com xrdp-sesman[1703]: Mon Nov 18 14:37:07 2024
Nov 18 14:37:07 host.example.com xrdp-sesman[1703]:  vncext:      VNC extension running!
Nov 18 14:37:07 host.example.com xrdp-sesman[1703]:  vncext:      Listening for VNC connections on local interface(s), port 5910
Nov 18 14:37:07 host.example.com xrdp-sesman[1703]:  vncext:      created VNC server for screen 0
Nov 18 14:37:07 host.example.com xrdp[1692]: [INFO ] Not allowing resize. Login in progress.
Nov 18 14:37:07 host.example.com xrdp-sesexec[1697]: [INFO ] X server :10 is working
Nov 18 14:37:07 host.example.com xrdp-sesexec[1697]: [INFO ] Starting window manager for display :10
Nov 18 14:37:07 host.example.com xrdp-sesexec[1697]: [INFO ] Starting the xrdp channel server for display :10
Nov 18 14:37:07 host.example.com xrdp-sesexec[1723]: [INFO ] Using the default window manager on display 10: /usr/libexec/xrdp/startwm-bash.sh
Nov 18 14:37:07 host.example.com xrdp-sesexec[1697]: [INFO ] Session in progress on display :10. Waiting until the window manager (pid 1723) exits to end the session
Nov 18 14:37:07 host.example.com xrdp[1692]: [INFO ] xrdp_wm_log_msg: session is available on display 10 for user gg
Nov 18 14:37:07 host.example.com xrdp[1692]: [INFO ] xrdp_wm_log_msg: Connecting to session
Nov 18 14:37:07 host.example.com xrdp[1692]: [INFO ] loaded module 'libvnc.so' ok, interface size 4832, version 4
Nov 18 14:37:07 host.example.com xrdp-sesman[1703]:  Connections: accepted: [::1]::55266
Nov 18 14:37:07 host.example.com xrdp-sesman[1703]:  SConnection: Client needs protocol version 3.3
Nov 18 14:37:08 host.example.com xrdp-sesman[1703]: Mon Nov 18 14:37:08 2024
Nov 18 14:37:08 host.example.com xrdp-sesman[1703]:  VNCSConnST:  Server default pixel format depth 32 (32bpp) little-endian rgb
Nov 18 14:37:08 host.example.com xrdp-sesman[1703]:               max 255,255,255 shift 16,8,0
Nov 18 14:37:08 host.example.com xrdp-sesman[1703]:  VNCSConnST:  Client pixel format depth 24 (32bpp) little-endian rgb888
Nov 18 14:37:08 host.example.com xrdp[1692]: [INFO ] VNC: Clipboard (if available) is provided by chansrv facility
Nov 18 14:37:08 host.example.com xrdp[1692]: [INFO ] xrdp_wm_log_msg: Connecting to chansrv
Nov 18 14:37:08 host.example.com xrdp-chansrv[1724]: [INFO ] Socket 14: connection accepted from AF_UNIX
Nov 18 14:37:08 host.example.com systemd[1]: Starting Hostname Service...
Nov 18 14:37:08 host.example.com xrdp[1692]: [INFO ] Layout from ClientLayout (geom=2520x1368 #screens=1) : 0:(2520x1368+0+0)
Nov 18 14:37:08 host.example.com systemd[1]: Started Hostname Service.
Nov 18 14:37:08 host.example.com xrdp[1692]: [INFO ] Layout from OldServerLayout (geom=2520x1368 #screens=1) : 1804289383:(2520x1368+0+0)
Nov 18 14:37:08 host.example.com systemd[1592]: Starting D-Bus User Message Bus...
Nov 18 14:37:08 host.example.com dbus-broker-launch[1779]: Policy to allow eavesdropping in /usr/share/dbus-1/session.conf +31: Eavesdropping is deprecated and ignored
Nov 18 14:37:08 host.example.com dbus-broker-launch[1779]: Policy to allow eavesdropping in /usr/share/dbus-1/session.conf +33: Eavesdropping is deprecated and ignored
Nov 18 14:37:08 host.example.com systemd[1592]: Started D-Bus User Message Bus.
Nov 18 14:37:08 host.example.com dbus-broker-lau[1779]: Ready
Nov 18 14:37:08 host.example.com systemd[1592]: Starting Accessibility services bus...
Nov 18 14:37:08 host.example.com systemd[1592]: Started Accessibility services bus.
Nov 18 14:37:08 host.example.com at-spi-bus-launcher[1817]: Policy to allow eavesdropping in /usr/share/defaults/at-spi2/accessibility.conf +15: Eavesdropping is deprecated and ignored
Nov 18 14:37:08 host.example.com at-spi-bus-launcher[1817]: Policy to allow eavesdropping in /usr/share/defaults/at-spi2/accessibility.conf +17: Eavesdropping is deprecated and ignored
Nov 18 14:37:08 host.example.com dbus-broker-lau[1817]: Ready
Nov 18 14:37:08 host.example.com gnome-session[1811]: gnome-session-check-accelerated: GL Helper exited with code 512
Nov 18 14:37:08 host.example.com gnome-session[1811]: gnome-session-check-accelerated: GLES Helper exited with code 512
Nov 18 14:37:08 host.example.com systemd[1592]: Created slice Slice /app/gnome-session-manager.
Nov 18 14:37:08 host.example.com systemd[1592]: Created slice User Core Session Slice.
Nov 18 14:37:08 host.example.com systemd[1592]: Reached target GNOME X11 Session.
Nov 18 14:37:08 host.example.com systemd[1592]: Reached target GNOME Shell.
Nov 18 14:37:08 host.example.com systemd[1592]: GNOME Initial Setup Copy Worker was skipped because of an unmet condition check (ConditionPathExists=!/home/gg/.config/gnome-initial-setup-done).
Nov 18 14:37:08 host.example.com systemd[1592]: Reached target Session services which should run early before the graphical session is brought up.
Nov 18 14:37:08 host.example.com systemd[1592]: Starting Monitor Session leader for GNOME Session...
Nov 18 14:37:08 host.example.com systemd[1592]: Started Monitor Session leader for GNOME Session.
Nov 18 14:37:08 host.example.com systemd[1592]: Reached target Tasks to be run before GNOME Session starts.
Nov 18 14:37:08 host.example.com systemd[1592]: Starting GNOME Session Manager (session: gnome)...
Nov 18 14:37:08 host.example.com gnome-keyring-daemon[1851]: couldn't access control socket: /run/user/1000/keyring/control: No such file or directory
Nov 18 14:37:08 host.example.com gnome-keyring-daemon[1852]: couldn't access control socket: /run/user/1000/keyring/control: No such file or directory
Nov 18 14:37:08 host.example.com gnome-keyring-d[1852]: couldn't access control socket: /run/user/1000/keyring/control: No such file or directory
Nov 18 14:37:08 host.example.com gnome-keyring-d[1851]: couldn't access control socket: /run/user/1000/keyring/control: No such file or directory
Nov 18 14:37:08 host.example.com gnome-keyring-daemon[1853]: couldn't access control socket: /run/user/1000/keyring/control: No such file or directory
Nov 18 14:37:08 host.example.com gnome-keyring-d[1853]: couldn't access control socket: /run/user/1000/keyring/control: No such file or directory
Nov 18 14:37:08 host.example.com systemd[1592]: Started Application launched by gnome-session-binary.
Nov 18 14:37:08 host.example.com systemd[1592]: app-gnome-gnome\x2dkeyring\x2dsecrets-1847.scope: Couldn't move process 1847 to requested cgroup '/user.slice/user-1000.slice/[email protected]/app.slice/app-gnome-gnome\x2dkeyring\x2dsecrets-1847.scope': No such process
Nov 18 14:37:08 host.example.com systemd[1592]: app-gnome-gnome\x2dkeyring\x2dsecrets-1847.scope: Failed to add PIDs to scope's control group: No such process
Nov 18 14:37:08 host.example.com systemd[1592]: app-gnome-gnome\x2dkeyring\x2dsecrets-1847.scope: Failed with result 'resources'.
Nov 18 14:37:08 host.example.com systemd[1592]: Failed to start Application launched by gnome-session-binary.
Nov 18 14:37:08 host.example.com systemd[1592]: Started Application launched by gnome-session-binary.
Nov 18 14:37:08 host.example.com gnome-keyring-ssh.desktop[1853]: SSH_AUTH_SOCK=/run/user/1000/keyring/ssh
Nov 18 14:37:08 host.example.com gnome-keyring-pkcs11.desktop[1851]: SSH_AUTH_SOCK=/run/user/1000/keyring/ssh
Nov 18 14:37:08 host.example.com systemd[1592]: Started GNOME Session Manager (session: gnome).
Nov 18 14:37:08 host.example.com systemd[1592]: Reached target GNOME Session Manager is ready.
Nov 18 14:37:08 host.example.com systemd[1592]: Starting GNOME Shell on Wayland...
Nov 18 14:37:08 host.example.com systemd[1592]: Starting GNOME Shell on X11...
Nov 18 14:37:08 host.example.com gnome-session[1842]: gnome-session-binary[1842]: GnomeDesktop-WARNING: Could not create transient scope for PID 1861: GDBus.Error:org.freedesktop.DBus.Error.UnixProcessIdUnknown: Process with ID 1861 does not exist.
Nov 18 14:37:08 host.example.com gnome-session-binary[1842]: GnomeDesktop-WARNING: Could not create transient scope for PID 1861: GDBus.Error:org.freedesktop.DBus.Error.UnixProcessIdUnknown: Process with ID 1861 does not exist.
Nov 18 14:37:08 host.example.com systemd[1592]: [email protected]: Skipped due to 'exec-condition'.
Nov 18 14:37:08 host.example.com systemd[1592]: Condition check resulted in GNOME Shell on Wayland being skipped.
Nov 18 14:37:08 host.example.com systemd[1592]: Starting Virtual filesystem service...
Nov 18 14:37:08 host.example.com systemd[1592]: Started Virtual filesystem service.
Nov 18 14:37:08 host.example.com gnome-shell[1865]: Xlib:  extension "DPMS" missing on display ":10.0".
Nov 18 14:37:08 host.example.com xrdp-sesman[1703]:  ComparingUpdateTracker: 0 pixels in / 0 pixels out
Nov 18 14:37:08 host.example.com xrdp-sesman[1703]:  ComparingUpdateTracker: (1:-nan ratio)
Nov 18 14:37:08 host.example.com gnome-shell[1865]: Xlib:  extension "DPMS" missing on display ":10.0".
Nov 18 14:37:08 host.example.com gnome-shell[1865]: ATK Bridge is disabled but a11y has already been enabled.
Nov 18 14:37:09 host.example.com gnome-shell[1865]: Skipping parental controls support as it’s disabled
Nov 18 14:37:09 host.example.com gnome-shell[1865]: Unset XDG_SESSION_ID, getCurrentSessionProxy() called outside a user session. Asking logind directly.
Nov 18 14:37:09 host.example.com gnome-shell[1865]: Will monitor session c2
Nov 18 14:37:09 host.example.com systemd[1592]: Created slice Slice /app/dbus-:1.2-org.freedesktop.portal.IBus.
Nov 18 14:37:09 host.example.com systemd[1592]: Started dbus-:[email protected].
Nov 18 14:37:09 host.example.com systemd[1592]: Starting sandboxed app permission store...
Nov 18 14:37:09 host.example.com systemd[1592]: Created slice Slice /app/dbus-:1.2-org.gnome.Shell.CalendarServer.
Nov 18 14:37:09 host.example.com systemd[1592]: Started dbus-:[email protected].
Nov 18 14:37:09 host.example.com systemd[1592]: Started sandboxed app permission store.
Nov 18 14:37:09 host.example.com systemd[1592]: Created slice Slice /app/dbus-:1.7-org.a11y.atspi.Registry.
Nov 18 14:37:09 host.example.com systemd[1592]: Started dbus-:[email protected].
Nov 18 14:37:09 host.example.com at-spi2-registryd[1924]: SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry
Nov 18 14:37:09 host.example.com systemd[1592]: Starting Evolution source registry...
Nov 18 14:37:09 host.example.com systemd[1592]: Started PipeWire Multimedia Service.
Nov 18 14:37:09 host.example.com systemd[1592]: Started Multimedia Service Session Manager.
Nov 18 14:37:09 host.example.com rtkit-daemon[735]: Supervising 6 threads of 3 processes of 1 users.
Nov 18 14:37:09 host.example.com rtkit-daemon[735]: Supervising 6 threads of 3 processes of 1 users.
Nov 18 14:37:09 host.example.com systemd[1592]: Started PipeWire PulseAudio.
Nov 18 14:37:09 host.example.com rtkit-daemon[735]: Supervising 6 threads of 3 processes of 1 users.
Nov 18 14:37:09 host.example.com gnome-shell[1865]: g_strsplit: assertion 'string != NULL' failed
Nov 18 14:37:09 host.example.com systemd[1592]: Created slice Slice /app/dbus-:1.2-org.gnome.OnlineAccounts.
Nov 18 14:37:09 host.example.com systemd[1592]: Started dbus-:[email protected].
Nov 18 14:37:09 host.example.com rtkit-daemon[735]: Supervising 6 threads of 3 processes of 2 users.
Nov 18 14:37:09 host.example.com wireplumber[1939]: SPA handle 'api.libcamera.enum.manager' could not be loaded; is it installed?
Nov 18 14:37:09 host.example.com wireplumber[1939]: PipeWire's libcamera SPA missing or broken. libcamera not supported.
Nov 18 14:37:09 host.example.com systemd[1]: Starting Location Lookup Service...
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ] sound_process_output_format:
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ]       wFormatNo       0
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ]       wFormatTag      WAVE_FORMAT_PCM
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ]       nChannels       2
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ]       nSamplesPerSec  44100
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ]       nAvgBytesPerSec 176400
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ]       nBlockAlign     4
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ]       wBitsPerSample  16
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ]       cbSize          0
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ] sound_process_output_format:
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ]       wFormatNo       1
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ]       wFormatTag      WAVE_FORMAT_PCM
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ]       nChannels       2
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ]       nSamplesPerSec  22050
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ]       nAvgBytesPerSec 88200
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ]       nBlockAlign     4
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ]       wBitsPerSample  16
Nov 18 14:37:09 host.example.com xrdp-chansrv[1724]: [INFO ]       cbSize          0
Nov 18 14:37:09 host.example.com goa-daemon[1947]: goa-daemon version 3.40.0 starting
Nov 18 14:37:09 host.example.com systemd[1592]: Started Evolution source registry.
Nov 18 14:37:09 host.example.com wireplumber[1939]: GetManagedObjects() failed: org.freedesktop.DBus.Error.NameHasNoOwner
Nov 18 14:37:09 host.example.com systemd[1592]: Created slice Slice /app/dbus-:1.2-org.gnome.Identity.
Nov 18 14:37:09 host.example.com systemd[1592]: Started dbus-:[email protected].
Nov 18 14:37:09 host.example.com systemd[1592]: Starting Evolution calendar service...
Nov 18 14:37:09 host.example.com systemd[1]: Started Location Lookup Service.
Nov 18 14:37:09 host.example.com systemd[1]: Starting SSSD Kerberos Cache Manager...
Nov 18 14:37:09 host.example.com rtkit-daemon[735]: Supervising 6 threads of 3 processes of 2 users.
Nov 18 14:37:09 host.example.com rtkit-daemon[735]: Supervising 6 threads of 3 processes of 2 users.
Nov 18 14:37:09 host.example.com rtkit-daemon[735]: Supervising 6 threads of 3 processes of 2 users.
Nov 18 14:37:09 host.example.com rtkit-daemon[735]: Supervising 6 threads of 3 processes of 2 users.
Nov 18 14:37:09 host.example.com rtkit-daemon[735]: Supervising 6 threads of 3 processes of 2 users.
Nov 18 14:37:09 host.example.com systemd[1592]: Started Evolution calendar service.
Nov 18 14:37:09 host.example.com systemd[1]: Started SSSD Kerberos Cache Manager.
Nov 18 14:37:09 host.example.com systemd[1592]: Starting User preferences database...
Nov 18 14:37:09 host.example.com systemd[1592]: Started User preferences database.
Nov 18 14:37:09 host.example.com sssd_kcm[1995]: Starting up
Nov 18 14:37:09 host.example.com goa-identity-se[1971]: GoaKerberosIdentityManager: Using polling for change notification for credential cache type 'KCM'
Nov 18 14:37:09 host.example.com systemd[1592]: Starting Evolution address book service...
Nov 18 14:37:09 host.example.com polkitd[734]: Registered Authentication Agent for unix-session:c2 (system bus name :1.76 [/usr/bin/gnome-shell], object path /org/freedesktop/PolicyKit1/AuthenticationAgent, locale en_US.UTF-8)
Nov 18 14:37:09 host.example.com gnome-shell[1865]: Telepathy is not available, chat integration will be disabled.
Nov 18 14:37:09 host.example.com systemd[1592]: Starting Virtual filesystem service - disk device monitor...
Nov 18 14:37:09 host.example.com systemd[1592]: Started Evolution address book service.
Nov 18 14:37:09 host.example.com systemd[1592]: Started Virtual filesystem service - disk device monitor.
Nov 18 14:37:09 host.example.com systemd[1592]: Starting Virtual filesystem service - Media Transfer Protocol monitor...
Nov 18 14:37:09 host.example.com systemd[1592]: Started Virtual filesystem service - Media Transfer Protocol monitor.
Nov 18 14:37:09 host.example.com systemd[1592]: Starting Virtual filesystem service - digital camera monitor...
Nov 18 14:37:09 host.example.com systemd[1592]: Started Virtual filesystem service - digital camera monitor.
Nov 18 14:37:09 host.example.com systemd[1592]: Starting Virtual filesystem service - GNOME Online Accounts monitor...
Nov 18 14:37:09 host.example.com systemd[1592]: Started Virtual filesystem service - GNOME Online Accounts monitor.
Nov 18 14:37:10 host.example.com systemd[1592]: Created slice Slice /app/dbus-:1.2-org.gnome.Shell.Notifications.
Nov 18 14:37:10 host.example.com systemd[1592]: Started dbus-:[email protected].
Nov 18 14:37:10 host.example.com gnome-shell[1865]: Xlib:  extension "DPMS" missing on display ":10.0".
Nov 18 14:37:10 host.example.com systemd[1592]: Started GNOME Shell on X11.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME Session is initialized.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME session X11 services.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME Session (session: gnome).
Nov 18 14:37:10 host.example.com systemd[1592]: Starting Signal initialization done to GNOME Session Manager...
Nov 18 14:37:10 host.example.com systemd[1592]: Starting GNOME accessibility service...
Nov 18 14:37:10 host.example.com systemd[1592]: Starting GNOME color management service...
Nov 18 14:37:10 host.example.com systemd[1592]: Starting GNOME date & time service...
Nov 18 14:37:10 host.example.com systemd[1592]: Starting GNOME maintenance of expirable data service...
Nov 18 14:37:10 host.example.com systemd[1592]: Starting GNOME keyboard configuration service...
Nov 18 14:37:10 host.example.com systemd[1592]: Starting GNOME keyboard shortcuts service...
Nov 18 14:37:10 host.example.com spice-vdagent[2076]: vdagent virtio channel /dev/virtio-ports/com.redhat.spice.0 does not exist, exiting
Nov 18 14:37:10 host.example.com systemd[1592]: Starting GNOME power management service...
Nov 18 14:37:10 host.example.com systemd[1592]: Starting GNOME printer notifications service...
Nov 18 14:37:10 host.example.com systemd[1592]: Starting GNOME RFKill support service...
Nov 18 14:37:10 host.example.com systemd[1592]: Starting GNOME FreeDesktop screensaver service...
Nov 18 14:37:10 host.example.com systemd[1592]: Starting GNOME file sharing service...
Nov 18 14:37:10 host.example.com systemd[1592]: Starting GNOME smartcard service...
Nov 18 14:37:10 host.example.com systemd[1592]: Starting GNOME sound sample caching service...
Nov 18 14:37:10 host.example.com xrdp-chansrv[1724]: [INFO ] sound_process_training: round trip time 720
Nov 18 14:37:10 host.example.com vmware-user.desktop[2110]: vmware-user: could not open /proc/fs/vmblock/dev
Nov 18 14:37:10 host.example.com systemd[1592]: Starting GNOME USB protection service...
Nov 18 14:37:10 host.example.com systemd[1592]: Starting GNOME Wacom tablet support service...
Nov 18 14:37:10 host.example.com systemd[1592]: Starting GNOME XSettings service...
Nov 18 14:37:10 host.example.com systemd[1592]: Finished Signal initialization done to GNOME Session Manager.
Nov 18 14:37:10 host.example.com gnome-session-binary[1842]: Entering running state
Nov 18 14:37:10 host.example.com gsd-rfkill[2088]: Could not open rfkill device: Could not open RFKILL control device, please verify your installation
Nov 18 14:37:10 host.example.com gnome-session[1842]: gnome-session-binary[1842]: GnomeDesktop-WARNING: Could not create transient scope for PID 2076: GDBus.Error:org.freedesktop.DBus.Error.UnixProcessIdUnknown: Process with ID 2076 does not exist.
Nov 18 14:37:10 host.example.com gnome-session-binary[1842]: GnomeDesktop-WARNING: Could not create transient scope for PID 2076: GDBus.Error:org.freedesktop.DBus.Error.UnixProcessIdUnknown: Process with ID 2076 does not exist.
Nov 18 14:37:10 host.example.com systemd[1]: Starting Locale Service...
Nov 18 14:37:10 host.example.com systemd[1592]: Started GNOME FreeDesktop screensaver service.
Nov 18 14:37:10 host.example.com systemd[1592]: Started GNOME accessibility service.
Nov 18 14:37:10 host.example.com systemd[1592]: Started GNOME file sharing service.
Nov 18 14:37:10 host.example.com systemd[1592]: Started GNOME date & time service.
Nov 18 14:37:10 host.example.com gnome-session[1842]: gnome-session-binary[1842]: GnomeDesktop-WARNING: Could not create transient scope for PID 2083: GDBus.Error:org.freedesktop.DBus.Error.UnixProcessIdUnknown: Process with ID 2083 does not exist.
Nov 18 14:37:10 host.example.com gnome-session-binary[1842]: GnomeDesktop-WARNING: Could not create transient scope for PID 2083: GDBus.Error:org.freedesktop.DBus.Error.UnixProcessIdUnknown: Process with ID 2083 does not exist.
Nov 18 14:37:10 host.example.com systemd[1592]: Started GNOME RFKill support service.
Nov 18 14:37:10 host.example.com gnome-session[1842]: gnome-session-binary[1842]: GnomeDesktop-WARNING: Could not create transient scope for PID 2091: GDBus.Error:org.freedesktop.DBus.Error.UnixProcessIdUnknown: Process with ID 2091 does not exist.
Nov 18 14:37:10 host.example.com gnome-session-binary[1842]: GnomeDesktop-WARNING: Could not create transient scope for PID 2091: GDBus.Error:org.freedesktop.DBus.Error.UnixProcessIdUnknown: Process with ID 2091 does not exist.
Nov 18 14:37:10 host.example.com systemd[1592]: Started GNOME keyboard configuration service.
Nov 18 14:37:10 host.example.com systemd[1592]: Started GNOME sound sample caching service.
Nov 18 14:37:10 host.example.com systemd[1592]: Started GNOME USB protection service.
Nov 18 14:37:10 host.example.com systemd[1592]: Started GNOME keyboard shortcuts service.
Nov 18 14:37:10 host.example.com systemd[1592]: Started GNOME color management service.
Nov 18 14:37:10 host.example.com systemd[1592]: Started GNOME maintenance of expirable data service.
Nov 18 14:37:10 host.example.com systemd[1592]: Started GNOME power management service.
Nov 18 14:37:10 host.example.com systemd[1592]: Started Application launched by gnome-session-binary.
Nov 18 14:37:10 host.example.com systemd[1592]: Started Application launched by gnome-session-binary.
Nov 18 14:37:10 host.example.com systemd[1592]: Started Application launched by gnome-session-binary.
Nov 18 14:37:10 host.example.com systemd[1592]: Started Application launched by gnome-session-binary.
Nov 18 14:37:10 host.example.com systemd[1592]: Started Application launched by gnome-session-binary.
Nov 18 14:37:10 host.example.com systemd[1592]: Created slice Slice /app/dbus-:1.2-org.gnome.ScreenSaver.
Nov 18 14:37:10 host.example.com systemd[1592]: Created slice Slice /app/dbus-:1.2-org.gnome.Shell.Screencast.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME accessibility target.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME color management target.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME date & time target.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME maintenance of expirable data target.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME keyboard configuration target.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME keyboard shortcuts target.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME power management target.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME RFKill support target.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME FreeDesktop screensaver target.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME file sharing target.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME sound sample caching target.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME USB protection target.
Nov 18 14:37:10 host.example.com systemd[1592]: Started dbus-:[email protected].
Nov 18 14:37:10 host.example.com systemd[1592]: Started dbus-:[email protected].
Nov 18 14:37:10 host.example.com systemd[1592]: Started GNOME Wacom tablet support service.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME Wacom tablet support target.
Nov 18 14:37:10 host.example.com gnome-shell[1865]: Unable to mount volume AlmaLinux-9-4-x86_64-dvd: Gio.IOErrorEnum: Not authorized to perform operation
Nov 18 14:37:10 host.example.com systemd[1592]: Started GNOME smartcard service.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME smartcard target.
Nov 18 14:37:10 host.example.com systemd[1]: Started Locale Service.
Nov 18 14:37:10 host.example.com cupsd[866]: REQUEST localhost - - "POST / HTTP/1.1" 200 358 Create-Printer-Subscriptions successful-ok
Nov 18 14:37:10 host.example.com gsd-usb-protect[2119]: Failed to fetch USBGuard parameters: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name is not activatable
Nov 18 14:37:10 host.example.com systemd[1592]: Started GNOME printer notifications service.
Nov 18 14:37:10 host.example.com systemd[1592]: Reached target GNOME printer notifications target.
Nov 18 14:37:10 host.example.com gnome-shell[1865]: Error looking up permission: GDBus.Error:org.freedesktop.portal.Error.NotFound: No entry for geolocation
Nov 18 14:37:11 host.example.com gsd-media-keys[2080]: Failed to grab accelerator for keybinding settings:playback-random
Nov 18 14:37:11 host.example.com gsd-media-keys[2080]: Failed to grab accelerator for keybinding settings:hibernate
Nov 18 14:37:11 host.example.com gsd-media-keys[2080]: Failed to grab accelerator for keybinding settings:rotate-video-lock
Nov 18 14:37:11 host.example.com gsd-media-keys[2080]: Failed to grab accelerator for keybinding settings:playback-repeat
Nov 18 14:37:11 host.example.com systemd[1592]: Started GNOME XSettings service.
Nov 18 14:37:11 host.example.com systemd[1592]: GNOME session X11 services is inactive.
Nov 18 14:37:11 host.example.com systemd[1592]: Reached target GNOME XSettings target.
Nov 18 14:37:11 host.example.com systemd[1592]: Reached target GNOME Session.
Nov 18 14:37:11 host.example.com systemd[1592]: Reached target GNOME X11 Session (session: gnome).
Nov 18 14:37:11 host.example.com systemd[1592]: Reached target Current graphical user session.
Nov 18 14:37:11 host.example.com systemd[1592]: GNOME Initial Setup was skipped because of an unmet condition check (ConditionPathExists=!/home/gg/.config/gnome-initial-setup-done).
Nov 18 14:37:11 host.example.com gsd-media-keys[2080]: Unable to inhibit keypresses: GDBus.Error:org.freedesktop.DBus.Error.AccessDenied: Permission denied
Nov 18 14:37:11 host.example.com NetworkManager[846]: <info>  [1731937031.7565] agent-manager: agent[bddd294ea9920bba,:1.76/org.gnome.Shell.NetworkAgent/1000]: agent registered
Nov 18 14:37:11 host.example.com systemd[1]: Starting Firmware update daemon...
Nov 18 14:37:11 host.example.com gsd-color[2070]: no xrandr-Red Hat, Inc.-QEMU Monitor device found: Failed to find output xrandr-Red Hat, Inc.-QEMU Monitor
Nov 18 14:37:12 host.example.com gnome-shell[1865]: GNOME Shell started at Mon Nov 18 2024 14:37:10 GMT+0100 (Central European Standard Time)
Nov 18 14:37:12 host.example.com gnome-shell[1865]: Registering session with GDM
Nov 18 14:37:12 host.example.com fwupd[2374]: 13:37:12.175 FuMain               Daemon ready for requests (locale en_US.UTF-8)
Nov 18 14:37:12 host.example.com systemd[1]: Started Firmware update daemon.
Nov 18 14:37:12 host.example.com gnome-software[2108]: enabled plugins: appstream, fwupd, os-release, packagekit-refresh, flatpak, hardcoded-blocklist, hardcoded-popular, modalias, packagekit, rewrite-resource, provenance, repos, systemd-updates, generic-updates, packagekit-refine-repos, provenance-license, icons
Nov 18 14:37:12 host.example.com gnome-software[2108]: disabled plugins: dummy, fedora-langpacks, fedora-pkgdb-collections
Nov 18 14:37:12 host.example.com PackageKit[1273]: uid 1000 is trying to obtain org.freedesktop.packagekit.system-sources-refresh auth (only_trusted:0)
Nov 18 14:37:12 host.example.com PackageKit[1273]: uid 1000 obtained auth for org.freedesktop.packagekit.system-sources-refresh
Nov 18 14:37:12 host.example.com PackageKit[1273]: refresh-cache transaction /41_cdbcbeac from uid 1000 finished with success after 15ms
Nov 18 14:37:12 host.example.com gnome-software[2108]: Only 0 apps for recent list, hiding
Nov 18 14:37:13 host.example.com PackageKit[1273]: resolve transaction /42_beeedceb from uid 1000 finished with success after 815ms
Nov 18 14:37:13 host.example.com PackageKit[1273]: resolve transaction /43_daadacda from uid 1000 finished with success after 3ms
Nov 18 14:37:13 host.example.com gnome-software[2108]: Only 3 apps for popular list, hiding
Nov 18 14:37:14 host.example.com PackageKit[1273]: get-updates transaction /44_aedccbdd from uid 1000 finished with success after 1114ms
Nov 18 14:37:14 host.example.com PackageKit[1273]: resolve transaction /45_ebbaecdd from uid 1000 finished with success after 12ms
Nov 18 14:37:14 host.example.com PackageKit[1273]: resolve transaction /46_eccdaecd from uid 1000 finished with success after 3ms
Nov 18 14:37:14 host.example.com PackageKit[1273]: get-details transaction /47_bbddbbce from uid 1000 finished with success after 16ms
Nov 18 14:37:14 host.example.com PackageKit[1273]: get-update-detail transaction /48_eaabddac from uid 1000 finished with success after 181ms
Nov 18 14:37:15 host.example.com packagekitd[1273]: Failed to get cache filename for epel-release
Nov 18 14:37:15 host.example.com PackageKit[1273]: get-details transaction /49_cbeaccca from uid 1000 finished with success after 4ms
Nov 18 14:37:29 host.example.com gnome-shell[1865]: Xlib:  extension "DPMS" missing on display ":10.0".
Nov 18 14:37:29 host.example.com xrdp-sesman[1703]: Mon Nov 18 14:37:29 2024
Nov 18 14:37:29 host.example.com xrdp-sesman[1703]:  ComparingUpdateTracker: 52.1966 Mpixels in / 16.1148 Mpixels out
Nov 18 14:37:29 host.example.com xrdp-sesman[1703]:  ComparingUpdateTracker: (1:3.23905 ratio)
Nov 18 14:37:29 host.example.com xrdp[1692]: [INFO ] Layout from NewLayout (geom=2520x1371 #screens=1) : 1804289383:(2520x1371+0+0)
Nov 18 14:37:29 host.example.com xrdp[1692]: [INFO ] server_monitor_resize_done: Advancing server monitor resized.
Nov 18 14:37:29 host.example.com gnome-shell[1865]: Xlib:  extension "DPMS" missing on display ":10.0".
Nov 18 14:37:29 host.example.com xrdp[1692]: [INFO ] Client supports single-screen resizes by xrdp
Nov 18 14:37:29 host.example.com gsd-color[2070]: could not find device: property match 'XRANDR_name'='VNC-0' does not exist
Nov 18 14:37:29 host.example.com xrdp[1692]: [INFO ] xrdp_caps_process_pointer: client supports new(color) cursor
Nov 18 14:37:29 host.example.com gsd-color[2070]: could not find device: property match 'XRANDR_name'='VNC-0' does not exist
Nov 18 14:37:29 host.example.com xrdp[1692]: [INFO ] xrdp_process_offscreen_bmpcache: support level 1 cache size 7864320 MB cache entries 2000
Nov 18 14:37:29 host.example.com xrdp[1692]: [INFO ] xrdp_caps_process_codecs: RemoteFX(76772F12-BD72-4463-AFB3-B73C9C6F7886), codec id [3], properties len [49]
Nov 18 14:37:29 host.example.com xrdp[1692]: [WARN ] Client Capability: not enough orders supported by client, client wants off screen bitmap but offscreen bitmaps disabled
Nov 18 14:37:29 host.example.com xrdp[1692]: [INFO ] Client Capability: LARGE_POINTER_FLAG_96x96 supported
Nov 18 14:37:40 host.example.com systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Nov 18 14:37:40 host.example.com systemd[1]: systemd-localed.service: Deactivated successfully.

@matt335672
Copy link
Member

You're definitely using the VNC backend, as that's what's shown in the log:-

Nov 18 14:37:06 host.example.com xrdp-sesexec[1703]: [INFO ] Starting X server on display 10: Xvnc :10 -auth .Xauthority -geometry 2520x1368 -depth 32 -rfbauth /home/gg/.vnc/[email protected]:10 -bs -nolisten tcp -localhost -dpi 96
Nov 18 14:37:07 host.example.com xrdp-sesman[1703]: Xvnc TigerVNC 1.13.1 - built Apr 16 2024 00:00:00

I'm unable to see anything in the log at the moment related to your problem however.

  1. Once you get the white screen, do you get any more messages in xrdp,.log on further resizes ?
  2. In Remmina, what is the Colour Depth field set to?

@geragio
Copy link
Author

geragio commented Nov 18, 2024

Once I get the white screen, I don't see any other relevant message in xrdp.
In Remmina, the Colour Depth is currently set to: Automatic (32 bpp)(Sever chooses its best format)

@geragio
Copy link
Author

geragio commented Nov 18, 2024

I noticed something interesting: the resizing works perfectly when using the laptop screen(resolution 1920x1080).
I see xrdp reacting and adapting the resolution according to the window size

The resizing no longer works when I use my external monitor which has a higher resolution(2560x1440).

@matt335672
Copy link
Member

Thanks for that.

I'm running 2560x1440, and I'm still unable to provoke this fault.

How are you resizing? Just grabbing the sides of the window and moving it?

Let's try something else:-

  1. Set LogLevel=DEBUG in xrdp.ini and restart xrdp. This will slow things down a bit.
  2. Log in without any resizing at all.
  3. Make a note of the exact time with the date command
  4. Wait a couple of seconds
  5. Resize the screen
  6. If the white screen problem doesn't appear, go back to 3)
  7. When the white screen appears, paste the contents of the xrdp.log from the last time noted to the end.

@geragio
Copy link
Author

geragio commented Nov 19, 2024

I'm resizing using Remmina full-screen button(so the RDP session takes the whole screen)

I reproduced the issue with debug log enabled, let me share the content of xrdp.log file:

$ date
Tue Nov 19 04:16:57 PM CET 2024

[2024-11-19T16:14:58.281+0100] [ERROR] xrdp_channel_send: xrdp_sec_send failed
[2024-11-19T16:14:58.282+0100] [ERROR] xrdp_channel_drdynvc_data: xrdp_channel_send failed
[2024-11-19T16:14:58.282+0100] [DEBUG] xrdp_egfx_shutdown_delete_surface: xrdp_egfx_send_delete_surface failed 1
[2024-11-19T16:14:58.283+0100] [DEBUG] xrdp_egfx_shutdown_full: xrdp_egfx_shutdown_delete_surface failed 1
[2024-11-19T16:15:06.143+0100] [INFO ] Received termination signal, stopping the server accept new connections thread
[2024-11-19T16:15:06.169+0100] [DEBUG] Closed socket 13 ([::]:3389)
[2024-11-19T16:15:25.350+0100] [INFO ] starting xrdp with pid 1142
[2024-11-19T16:15:25.355+0100] [INFO ] address [0.0.0.0] port [3389] mode 1
[2024-11-19T16:15:25.358+0100] [INFO ] listening to port 3389 on 0.0.0.0
[2024-11-19T16:15:25.360+0100] [INFO ] xrdp_listen_pp done
[2024-11-19T16:17:06.296+0100] [INFO ] Socket 14: connection accepted from [::ffff:10.243.160.139]:49850
[2024-11-19T16:17:06.298+0100] [DEBUG] Closed socket 14 ([::ffff:10.243.89.93]:3389)
[2024-11-19T16:17:06.299+0100] [DEBUG] Closed socket 13 ([::]:3389)
[2024-11-19T16:17:06.300+0100] [DEBUG] item ini_version, value 1
[2024-11-19T16:17:06.301+0100] [DEBUG] item fork, value true
[2024-11-19T16:17:06.301+0100] [DEBUG] item port, value 3389
[2024-11-19T16:17:06.302+0100] [DEBUG] item use_vsock, value false
[2024-11-19T16:17:06.302+0100] [DEBUG] item tcp_nodelay, value true
[2024-11-19T16:17:06.303+0100] [DEBUG] item tcp_keepalive, value true
[2024-11-19T16:17:06.303+0100] [DEBUG] item security_layer, value negotiate
[2024-11-19T16:17:06.303+0100] [DEBUG] item crypt_level, value high
[2024-11-19T16:17:06.304+0100] [DEBUG] item certificate, value 
[2024-11-19T16:17:06.304+0100] [INFO ] Using default X.509 certificate: /etc/xrdp/cert.pem
[2024-11-19T16:17:06.305+0100] [DEBUG] item key_file, value 
[2024-11-19T16:17:06.305+0100] [INFO ] Using default X.509 key file: /etc/xrdp/key.pem
[2024-11-19T16:17:06.305+0100] [DEBUG] item ssl_protocols, value TLSv1.2, TLSv1.3
[2024-11-19T16:17:06.306+0100] [DEBUG] TLSv1.3 enabled
[2024-11-19T16:17:06.306+0100] [DEBUG] TLSv1.2 enabled
[2024-11-19T16:17:06.307+0100] [DEBUG] item autorun, value 
[2024-11-19T16:17:06.307+0100] [DEBUG] item allow_channels, value true
[2024-11-19T16:17:06.308+0100] [DEBUG] item allow_multimon, value true
[2024-11-19T16:17:06.308+0100] [DEBUG] item bitmap_cache, value true
[2024-11-19T16:17:06.308+0100] [DEBUG] item bitmap_compression, value true
[2024-11-19T16:17:06.309+0100] [DEBUG] item bulk_compression, value true
[2024-11-19T16:17:06.309+0100] [DEBUG] item max_bpp, value 32
[2024-11-19T16:17:06.310+0100] [DEBUG] item new_cursors, value true
[2024-11-19T16:17:06.310+0100] [DEBUG] item use_fastpath, value both
[2024-11-19T16:17:06.311+0100] [DEBUG] item grey, value e1e1e1
[2024-11-19T16:17:06.312+0100] [DEBUG] item dark_grey, value b4b4b4
[2024-11-19T16:17:06.312+0100] [DEBUG] item blue, value 0078d7
[2024-11-19T16:17:06.313+0100] [DEBUG] item dark_blue, value 0078d7
[2024-11-19T16:17:06.313+0100] [DEBUG] item ls_top_window_bg_color, value 003057
[2024-11-19T16:17:06.314+0100] [DEBUG] item ls_width, value 350
[2024-11-19T16:17:06.314+0100] [DEBUG] item ls_height, value 360
[2024-11-19T16:17:06.315+0100] [DEBUG] item ls_bg_color, value f0f0f0
[2024-11-19T16:17:06.316+0100] [DEBUG] item ls_logo_filename, value 
[2024-11-19T16:17:06.316+0100] [DEBUG] item ls_logo_transform, value scale
[2024-11-19T16:17:06.317+0100] [DEBUG] item ls_logo_width, value 250
[2024-11-19T16:17:06.317+0100] [DEBUG] item ls_logo_height, value 110
[2024-11-19T16:17:06.318+0100] [DEBUG] item ls_logo_x_pos, value 55
[2024-11-19T16:17:06.318+0100] [DEBUG] item ls_logo_y_pos, value 35
[2024-11-19T16:17:06.318+0100] [DEBUG] item ls_label_x_pos, value 30
[2024-11-19T16:17:06.319+0100] [DEBUG] item ls_label_width, value 68
[2024-11-19T16:17:06.319+0100] [DEBUG] item ls_input_x_pos, value 110
[2024-11-19T16:17:06.320+0100] [DEBUG] item ls_input_width, value 210
[2024-11-19T16:17:06.320+0100] [DEBUG] item ls_input_y_pos, value 158
[2024-11-19T16:17:06.320+0100] [DEBUG] item ls_btn_ok_x_pos, value 142
[2024-11-19T16:17:06.321+0100] [DEBUG] item ls_btn_ok_y_pos, value 308
[2024-11-19T16:17:06.321+0100] [DEBUG] item ls_btn_ok_width, value 85
[2024-11-19T16:17:06.321+0100] [DEBUG] item ls_btn_ok_height, value 30
[2024-11-19T16:17:06.322+0100] [DEBUG] item ls_btn_cancel_x_pos, value 237
[2024-11-19T16:17:06.323+0100] [DEBUG] item ls_btn_cancel_y_pos, value 308
[2024-11-19T16:17:06.323+0100] [DEBUG] item ls_btn_cancel_width, value 85
[2024-11-19T16:17:06.323+0100] [DEBUG] item ls_btn_cancel_height, value 30
[2024-11-19T16:17:06.324+0100] [INFO ] Security protocol: configured [SSL|RDP], requested [SSL|HYBRID|RDP], selected [SSL]
[2024-11-19T16:17:07.678+0100] [DEBUG] Using TLS security, and setting RDP security crypto to LEVEL_NONE and METHOD_NONE
[2024-11-19T16:17:07.679+0100] [DEBUG] [MCS Connection Sequence] receive connection request
[2024-11-19T16:17:07.699+0100] [INFO ] Connected client computer name: t14s
[2024-11-19T16:17:07.700+0100] [INFO ] client supports gfx protocol
[2024-11-19T16:17:07.701+0100] [WARN ] Physical desktop dimensions (0x0) are invalid
[2024-11-19T16:17:07.701+0100] [DEBUG] The connection is using TLS, skipping RDP crypto negotiation
[2024-11-19T16:17:07.702+0100] [DEBUG] Adding channel: name rdpdr, channel id 1004, flags 0xc0800000
[2024-11-19T16:17:07.702+0100] [DEBUG] Adding channel: name rdpsnd, channel id 1005, flags 0xc0000000
[2024-11-19T16:17:07.703+0100] [DEBUG] Adding channel: name cliprdr, channel id 1006, flags 0xc0a00000
[2024-11-19T16:17:07.704+0100] [DEBUG] Adding channel: name drdynvc, channel id 1007, flags 0xc0800000
[2024-11-19T16:17:07.704+0100] [WARN ] Received [MS-RDPBCGR] TS_UD_HEADER type 0xc006 is unknown (ignored)
[2024-11-19T16:17:07.705+0100] [WARN ] Received [MS-RDPBCGR] TS_UD_HEADER type 0xc00a is unknown (ignored)
[2024-11-19T16:17:07.705+0100] [DEBUG] [MCS Connection Sequence] construct connection response
[2024-11-19T16:17:07.706+0100] [DEBUG] using no security
[2024-11-19T16:17:07.706+0100] [DEBUG] [MCS Connection Sequence] send connection response
[2024-11-19T16:17:07.707+0100] [DEBUG] [MCS Connection Sequence] receive erect domain request
[2024-11-19T16:17:07.751+0100] [DEBUG] [MCS Connection Sequence] receive attach user request
[2024-11-19T16:17:07.754+0100] [DEBUG] [MCS Connection Sequence] send attach user confirm
[2024-11-19T16:17:07.754+0100] [DEBUG] [MCS Connection Sequence (TLS)] receive channel join request
[2024-11-19T16:17:07.798+0100] [DEBUG] [MCS Connection Sequence (TLS)] send channel join confirm
[2024-11-19T16:17:07.799+0100] [DEBUG] [MCS Connection Sequence (TLS)] receive channel join request
[2024-11-19T16:17:07.845+0100] [DEBUG] [MCS Connection Sequence (TLS)] send channel join confirm
[2024-11-19T16:17:07.846+0100] [DEBUG] [MCS Connection Sequence (TLS)] receive channel join request
[2024-11-19T16:17:07.891+0100] [DEBUG] [MCS Connection Sequence (TLS)] send channel join confirm
[2024-11-19T16:17:07.892+0100] [DEBUG] [MCS Connection Sequence (TLS)] receive channel join request
[2024-11-19T16:17:07.936+0100] [DEBUG] [MCS Connection Sequence (TLS)] send channel join confirm
[2024-11-19T16:17:07.937+0100] [DEBUG] [MCS Connection Sequence (TLS)] receive channel join request
[2024-11-19T16:17:07.981+0100] [DEBUG] [MCS Connection Sequence (TLS)] send channel join confirm
[2024-11-19T16:17:07.984+0100] [DEBUG] [MCS Connection Sequence (TLS)] receive channel join request
[2024-11-19T16:17:08.289+0100] [DEBUG] [MCS Connection Sequence (TLS)] send channel join confirm
[2024-11-19T16:17:08.299+0100] [DEBUG] [MCS Connection Sequence (TLS)] completed
[2024-11-19T16:17:08.312+0100] [INFO ] xrdp_load_keyboard_layout: Keyboard information sent by the RDP client, keyboard_type:[0x04], keyboard_subtype:[0x00], keylayout:[0x00000410]
[2024-11-19T16:17:08.334+0100] [DEBUG] keyboard_cfg_file /etc/xrdp/xrdp_keyboard.ini
[2024-11-19T16:17:08.351+0100] [DEBUG] xrdp_load_keyboard_layout: item rdp_layout_us value 0x00000409
[2024-11-19T16:17:08.360+0100] [DEBUG] xrdp_load_keyboard_layout: skipping configuration item - rdp_layout_us, continuing to next section
[2024-11-19T16:17:08.365+0100] [DEBUG] xrdp_load_keyboard_layout: item rdp_layout_us value us
[2024-11-19T16:17:08.372+0100] [DEBUG] xrdp_load_keyboard_layout: skipping configuration item - rdp_layout_us, continuing to next section
[2024-11-19T16:17:08.378+0100] [DEBUG] xrdp_load_keyboard_layout: item keyboard_type value 4
[2024-11-19T16:17:08.383+0100] [DEBUG] xrdp_load_keyboard_layout: item keyboard_subtype value 3
[2024-11-19T16:17:08.388+0100] [DEBUG] xrdp_load_keyboard_layout: item keyboard_type value 7
[2024-11-19T16:17:08.394+0100] [DEBUG] xrdp_load_keyboard_layout: item keyboard_subtype value 2
[2024-11-19T16:17:08.399+0100] [DEBUG] xrdp_load_keyboard_layout: item model value pc105
[2024-11-19T16:17:08.404+0100] [DEBUG] xrdp_load_keyboard_layout: item rdp_layouts value default_rdp_layouts
[2024-11-19T16:17:08.409+0100] [DEBUG] xrdp_load_keyboard_layout: item layouts_map value default_layouts_map
[2024-11-19T16:17:08.414+0100] [DEBUG] xrdp_load_keyboard_layout: item rdp_layout_us value us
[2024-11-19T16:17:08.427+0100] [DEBUG] xrdp_load_keyboard_layout: skipping configuration item - rdp_layout_us, continuing to next section
[2024-11-19T16:17:08.436+0100] [INFO ] xrdp_load_keyboard_layout: model [] variant [] layout [it] options []
[2024-11-19T16:17:08.449+0100] [INFO ] TLS connection established from [::ffff:10.243.160.139]:49850 TLSv1.3 with cipher TLS_AES_256_GCM_SHA384
[2024-11-19T16:17:08.740+0100] [DEBUG] Client requested auto logon.
[2024-11-19T16:17:08.753+0100] [DEBUG] Client requested compression enabled.
[2024-11-19T16:17:08.759+0100] [DEBUG] Client supplied domain: 
[2024-11-19T16:17:08.767+0100] [DEBUG] Client supplied username: gg
[2024-11-19T16:17:08.777+0100] [DEBUG] Client supplied password: <omitted from log>
[2024-11-19T16:17:08.787+0100] [DEBUG] Client supplied program: 
[2024-11-19T16:17:08.796+0100] [DEBUG] Client supplied directory: 
[2024-11-19T16:17:08.125+0100] [INFO ] Client supports single-screen resizes by xrdp
[2024-11-19T16:17:08.126+0100] [INFO ] xrdp_caps_process_pointer: client supports new(color) cursor
[2024-11-19T16:17:08.126+0100] [INFO ] xrdp_process_offscreen_bmpcache: support level 1 cache size 7864320 MB cache entries 2000
[2024-11-19T16:17:08.127+0100] [INFO ] xrdp_caps_process_codecs: RemoteFX(76772F12-BD72-4463-AFB3-B73C9C6F7886), codec id [3], properties len [49]
[2024-11-19T16:17:08.127+0100] [WARN ] Client Capability: not enough orders supported by client, client wants off screen bitmap but offscreen bitmaps disabled
[2024-11-19T16:17:08.128+0100] [INFO ] Client Capability: LARGE_POINTER_FLAG_96x96 supported
[2024-11-19T16:17:08.129+0100] [DEBUG] xrdp_0000085e_wm_login_state_event_00000001
[2024-11-19T16:17:08.134+0100] [INFO ] Loading keymap file /etc/xrdp/km-00000410.ini
[2024-11-19T16:17:08.137+0100] [WARN ] local keymap file for 0x00000410 found and doesn't match built in keymap, using local keymap file
[2024-11-19T16:17:08.138+0100] [DEBUG] Login state change request WMLS_RESET -> WMLS_RESET
[2024-11-19T16:17:08.139+0100] [DEBUG] libxrdp_query_channel - Channel 0 name rdpdr
[2024-11-19T16:17:08.139+0100] [DEBUG] xrdp_wm_load_channel_config: channel rdpdr channel id 0 is enabled
[2024-11-19T16:17:08.140+0100] [DEBUG] Enabling channel 1004 (rdpdr)
[2024-11-19T16:17:08.140+0100] [DEBUG] libxrdp_query_channel - Channel 1 name rdpsnd
[2024-11-19T16:17:08.141+0100] [DEBUG] xrdp_wm_load_channel_config: channel rdpsnd channel id 1 is enabled
[2024-11-19T16:17:08.141+0100] [DEBUG] Enabling channel 1005 (rdpsnd)
[2024-11-19T16:17:08.142+0100] [DEBUG] libxrdp_query_channel - Channel 2 name cliprdr
[2024-11-19T16:17:08.142+0100] [DEBUG] xrdp_wm_load_channel_config: channel cliprdr channel id 2 is enabled
[2024-11-19T16:17:08.143+0100] [DEBUG] Enabling channel 1006 (cliprdr)
[2024-11-19T16:17:08.143+0100] [DEBUG] libxrdp_query_channel - Channel 3 name drdynvc
[2024-11-19T16:17:08.144+0100] [DEBUG] xrdp_wm_load_channel_config: channel drdynvc channel id 3 is enabled
[2024-11-19T16:17:08.144+0100] [DEBUG] Enabling channel 1007 (drdynvc)
[2024-11-19T16:17:08.145+0100] [DEBUG] Login state has changed to WMLS_RESET
[2024-11-19T16:17:08.146+0100] [DEBUG] Login state change request WMLS_RESET -> WMLS_USER_PROMPT
[2024-11-19T16:17:08.147+0100] [DEBUG] in xrdp_wm_init: 
[2024-11-19T16:17:08.148+0100] [DEBUG] ini_version:             1
[2024-11-19T16:17:08.149+0100] [DEBUG] use_bitmap_cache:        1
[2024-11-19T16:17:08.149+0100] [DEBUG] use_bitmap_compression:  1
[2024-11-19T16:17:08.150+0100] [DEBUG] port:                    3389
[2024-11-19T16:17:08.151+0100] [DEBUG] crypt_level:             3
[2024-11-19T16:17:08.152+0100] [DEBUG] allow_channels:          1
[2024-11-19T16:17:08.152+0100] [DEBUG] max_bpp:                 32
[2024-11-19T16:17:08.153+0100] [DEBUG] fork:                    1
[2024-11-19T16:17:08.153+0100] [DEBUG] tcp_nodelay:             1
[2024-11-19T16:17:08.154+0100] [DEBUG] tcp_keepalive:           1
[2024-11-19T16:17:08.155+0100] [DEBUG] tcp_send_buffer_bytes:   0
[2024-11-19T16:17:08.156+0100] [DEBUG] tcp_recv_buffer_bytes:   0
[2024-11-19T16:17:08.159+0100] [DEBUG] grey:                    14803425
[2024-11-19T16:17:08.160+0100] [DEBUG] black:                   0
[2024-11-19T16:17:08.161+0100] [DEBUG] dark_grey:               11842740
[2024-11-19T16:17:08.162+0100] [DEBUG] blue:                    30935
[2024-11-19T16:17:08.163+0100] [DEBUG] dark_blue:               30935
[2024-11-19T16:17:08.164+0100] [DEBUG] white:                   0
[2024-11-19T16:17:08.164+0100] [DEBUG] red:                     0
[2024-11-19T16:17:08.165+0100] [DEBUG] green:                   0
[2024-11-19T16:17:08.165+0100] [DEBUG] background:              0
[2024-11-19T16:17:08.166+0100] [DEBUG] autorun:                 
[2024-11-19T16:17:08.167+0100] [DEBUG] hidelogwindow:           0
[2024-11-19T16:17:08.167+0100] [DEBUG] require_credentials:     0
[2024-11-19T16:17:08.168+0100] [DEBUG] bulk_compression:        1
[2024-11-19T16:17:08.168+0100] [DEBUG] new_cursors:             1
[2024-11-19T16:17:08.169+0100] [DEBUG] nego_sec_layer:          0
[2024-11-19T16:17:08.170+0100] [DEBUG] allow_multimon:          1
[2024-11-19T16:17:08.170+0100] [DEBUG] enable_token_login:      0
[2024-11-19T16:17:08.171+0100] [DEBUG] ls_top_window_bg_color:  573000
[2024-11-19T16:17:08.171+0100] [DEBUG] ls_width (unscaled):     350
[2024-11-19T16:17:08.172+0100] [DEBUG] ls_height (unscaled):    360
[2024-11-19T16:17:08.172+0100] [DEBUG] ls_bg_color:             f0f0f0
[2024-11-19T16:17:08.173+0100] [DEBUG] ls_title:                
[2024-11-19T16:17:08.173+0100] [DEBUG] ls_logo_filename:        
[2024-11-19T16:17:08.174+0100] [DEBUG] ls_logo_x_pos :          55
[2024-11-19T16:17:08.174+0100] [DEBUG] ls_logo_y_pos :          35
[2024-11-19T16:17:08.175+0100] [DEBUG] ls_label_x_pos :         30
[2024-11-19T16:17:08.176+0100] [DEBUG] ls_label_width :         68
[2024-11-19T16:17:08.176+0100] [DEBUG] ls_input_x_pos :         110
[2024-11-19T16:17:08.177+0100] [DEBUG] ls_input_width :         210
[2024-11-19T16:17:08.177+0100] [DEBUG] ls_input_y_pos :         158
[2024-11-19T16:17:08.178+0100] [DEBUG] ls_btn_ok_x_pos :        142
[2024-11-19T16:17:08.178+0100] [DEBUG] ls_btn_ok_y_pos :        308
[2024-11-19T16:17:08.178+0100] [DEBUG] ls_btn_ok_width :        85
[2024-11-19T16:17:08.179+0100] [DEBUG] ls_btn_ok_height :       30
[2024-11-19T16:17:08.180+0100] [DEBUG] ls_btn_cancel_x_pos :    237
[2024-11-19T16:17:08.180+0100] [DEBUG] ls_btn_cancel_y_pos :    308
[2024-11-19T16:17:08.180+0100] [DEBUG] ls_btn_cancel_width :    85
[2024-11-19T16:17:08.181+0100] [DEBUG] ls_btn_cancel_height :   30
[2024-11-19T16:17:08.181+0100] [WARN ] No information is available to determine login screen DPI
[2024-11-19T16:17:08.182+0100] [WARN ] No DPI value is available to find login font
[2024-11-19T16:17:08.182+0100] [WARN ] Using the default_dpi of 96
[2024-11-19T16:17:08.186+0100] [DEBUG] Login screen scale factor 1.000000
[2024-11-19T16:17:08.187+0100] [DEBUG] out xrdp_wm_init: 
[2024-11-19T16:17:08.188+0100] [DEBUG] Login state has changed to WMLS_USER_PROMPT
[2024-11-19T16:17:08.191+0100] [INFO ] xrdp_egfx_create: error 0 channel_id 1
[2024-11-19T16:17:08.293+0100] [INFO ] xrdp_mm_egfx_caps_advertise:
[2024-11-19T16:17:08.294+0100] [INFO ]   version 0x00080004 flags 0x00000002 (index: 0)
[2024-11-19T16:17:08.294+0100] [INFO ]   version 0x00080105 flags 0x00000012 (index: 1)
[2024-11-19T16:17:08.295+0100] [INFO ]   version 0x000a0002 flags 0x00000002 (index: 2)
[2024-11-19T16:17:08.295+0100] [INFO ]   version 0x000a0200 flags 0x00000002 (index: 3)
[2024-11-19T16:17:08.296+0100] [INFO ]   version 0x000a0301 flags 0x00000000 (index: 4)
[2024-11-19T16:17:08.296+0100] [INFO ]   version 0x000a0400 flags 0x00000002 (index: 5)
[2024-11-19T16:17:08.297+0100] [INFO ]   version 0x000a0502 flags 0x00000002 (index: 6)
[2024-11-19T16:17:08.297+0100] [INFO ]   version 0x000a0600 flags 0x00000002 (index: 7)
[2024-11-19T16:17:08.298+0100] [INFO ]   version 0x000a0601 flags 0x00000002 (index: 8)
[2024-11-19T16:17:08.300+0100] [INFO ] unknown version 0x000a0601
[2024-11-19T16:17:08.300+0100] [INFO ]   version 0x000a0701 flags 0x00000002 (index: 9)
[2024-11-19T16:17:08.301+0100] [INFO ]   replying version 0x000a0701 flags 0x00000002
[2024-11-19T16:17:08.301+0100] [DEBUG] xrdp_egfx_send_capsconfirm: xrdp_egfx_send_s error 0
[2024-11-19T16:17:08.302+0100] [INFO ] xrdp_mm_egfx_caps_advertise: xrdp_egfx_send_capsconfirm error 0 best_index 9
[2024-11-19T16:17:08.302+0100] [INFO ] xrdp_egfx_reset_graphics:
[2024-11-19T16:17:08.303+0100] [INFO ] xrdp_egfx_reset_graphics: width 2520 height 1368 monitorcount 1
[2024-11-19T16:17:08.304+0100] [DEBUG] xrdp_egfx_send_reset_graphics: xrdp_egfx_send_s error 0
[2024-11-19T16:17:08.305+0100] [INFO ] xrdp_mm_egfx_caps_advertise: xrdp_egfx_send_reset_graphics error 0 monitorCount 0
[2024-11-19T16:17:08.305+0100] [DEBUG] xrdp_egfx_send_map_surface: xrdp_egfx_send_s error 0
[2024-11-19T16:17:08.306+0100] [INFO ] xrdp_mm_egfx_create_surfaces: map surface_id 0 left 0 top 0 width 2520 height 1368
[2024-11-19T16:17:08.307+0100] [INFO ] xrdp_encoder_create: starting gfx rfx pro codec session
[2024-11-19T16:17:08.308+0100] [INFO ] xrdp_mm_egfx_invalidate_wm_screen:
[2024-11-19T16:17:08.308+0100] [INFO ] xrdp_mm_egfx_caps_advertise: egfx created.
[2024-11-19T16:17:08.308+0100] [DEBUG] Login state change request WMLS_USER_PROMPT -> WMLS_START_CONNECT
[2024-11-19T16:17:08.309+0100] [DEBUG] Login state has changed to WMLS_START_CONNECT
[2024-11-19T16:17:08.310+0100] [DEBUG] Login state change request WMLS_START_CONNECT -> WMLS_CONNECT_IN_PROGRESS
[2024-11-19T16:17:08.310+0100] [DEBUG] xrdp_wm_log_msg: connecting to sesman on sesman.socket
[2024-11-19T16:17:08.311+0100] [WARN ] Ignoring obsolete SCP port value '3350'
[2024-11-19T16:17:08.312+0100] [INFO ] xrdp_wm_log_msg: sesman connect ok
[2024-11-19T16:17:08.312+0100] [INFO ] xrdp_wm_log_msg: Performing login request for gg
[2024-11-19T16:17:08.313+0100] [DEBUG] xrdp_wm_log_msg: sending login info to session manager, please wait...
[2024-11-19T16:17:08.352+0100] [DEBUG] Login state has changed to WMLS_CONNECT_IN_PROGRESS
[2024-11-19T16:17:08.352+0100] [INFO ] xrdp_wm_log_msg: login was successful - creating session
[2024-11-19T16:17:08.353+0100] [DEBUG] xrdp_wm_log_msg: sending create session request to session manager. Please wait...
[2024-11-19T16:17:08.793+0100] [DEBUG] libxrdp_process_monitor_stream: The number of monitors received is: 1
[2024-11-19T16:17:08.794+0100] [DEBUG] dynamic_monitor_data: received width 2520, received height 1371.
[2024-11-19T16:17:08.794+0100] [INFO ] Not allowing resize. Login in progress.
[2024-11-19T16:17:09.388+0100] [INFO ] xrdp_wm_log_msg: session is available on display 10 for user gg
[2024-11-19T16:17:09.388+0100] [INFO ] xrdp_wm_log_msg: Connecting to session
[2024-11-19T16:17:09.390+0100] [INFO ] loaded module 'libvnc.so' ok, interface size 4832, version 4
[2024-11-19T16:17:09.407+0100] [DEBUG] Layout from client_info (geom=2520x1368 #screens=1) : 0:(2520x1368+0+0)
[2024-11-19T16:17:09.408+0100] [DEBUG] xrdp_wm_log_msg: VNC started connecting
[2024-11-19T16:17:09.408+0100] [DEBUG] xrdp_wm_log_msg: VNC connecting to 127.0.0.1 5910
[2024-11-19T16:17:09.409+0100] [DEBUG] xrdp_wm_log_msg: VNC tcp connected
[2024-11-19T16:17:09.409+0100] [DEBUG] xrdp_wm_log_msg: VNC security level is 2 (1 = none, 2 = standard)
[2024-11-19T16:17:09.415+0100] [DEBUG] xrdp_wm_log_msg: VNC password ok
[2024-11-19T16:17:09.416+0100] [DEBUG] xrdp_wm_log_msg: VNC sending share flag
[2024-11-19T16:17:09.416+0100] [DEBUG] xrdp_wm_log_msg: VNC receiving server init
[2024-11-19T16:17:09.419+0100] [DEBUG] xrdp_wm_log_msg: VNC receiving pixel format
[2024-11-19T16:17:09.420+0100] [DEBUG] xrdp_wm_log_msg: VNC receiving name length
[2024-11-19T16:17:09.420+0100] [DEBUG] xrdp_wm_log_msg: VNC receiving name
[2024-11-19T16:17:09.421+0100] [DEBUG] xrdp_wm_log_msg: VNC sending pixel format
[2024-11-19T16:17:09.421+0100] [DEBUG] xrdp_wm_log_msg: VNC sending cursor
[2024-11-19T16:17:09.422+0100] [DEBUG] xrdp_wm_log_msg: VNC connection complete, connected ok
[2024-11-19T16:17:09.422+0100] [INFO ] VNC: Clipboard (if available) is provided by chansrv facility
[2024-11-19T16:17:09.423+0100] [DEBUG] xrdp_wm_log_msg: connected ok
[2024-11-19T16:17:09.424+0100] [INFO ] xrdp_wm_log_msg: Connecting to chansrv
[2024-11-19T16:17:09.424+0100] [DEBUG] libxrdp_query_channel - Channel 0 name rdpdr
[2024-11-19T16:17:09.425+0100] [DEBUG] libxrdp_query_channel - Channel 1 name rdpsnd
[2024-11-19T16:17:09.425+0100] [DEBUG] libxrdp_query_channel - Channel 2 name cliprdr
[2024-11-19T16:17:09.426+0100] [DEBUG] libxrdp_query_channel - Channel 3 name drdynvc
[2024-11-19T16:17:09.427+0100] [DEBUG] xrdp_mm_chansrv_connect: chansrv connect successful
[2024-11-19T16:17:09.428+0100] [DEBUG] status from xrdp_mm_connect() : 0
[2024-11-19T16:17:09.429+0100] [DEBUG] Login state change request WMLS_CONNECT_IN_PROGRESS -> WMLS_CLEANUP
[2024-11-19T16:17:09.429+0100] [DEBUG] Closed socket 28 (AF_UNIX)
[2024-11-19T16:17:09.481+0100] [DEBUG] Login state has changed to WMLS_CLEANUP
[2024-11-19T16:17:09.482+0100] [DEBUG] Login state change request WMLS_CLEANUP -> WMLS_INACTIVE
[2024-11-19T16:17:09.483+0100] [DEBUG] Skipping RFB_ENC_CURSOR encoding
[2024-11-19T16:17:09.483+0100] [DEBUG] VNC matched ExtendedDesktopSize rectangle x=0, y=0 geom=2520x1368
[2024-11-19T16:17:09.484+0100] [DEBUG] VNC server supports resizing
[2024-11-19T16:17:09.484+0100] [INFO ] Layout from ClientLayout (geom=2520x1368 #screens=1) : 0:(2520x1368+0+0)
[2024-11-19T16:17:09.485+0100] [INFO ] Layout from OldServerLayout (geom=2520x1368 #screens=1) : 1804289383:(2520x1368+0+0)
[2024-11-19T16:17:09.486+0100] [DEBUG] VNC setting screen id to 1804289383 from server
[2024-11-19T16:17:09.486+0100] [DEBUG] Server layout is the same as the client layout
[2024-11-19T16:17:09.487+0100] [DEBUG] Login state has changed to WMLS_INACTIVE
[2024-11-19T16:17:09.794+0100] [DEBUG] VNC got clip data
[2024-11-19T16:17:09.795+0100] [DEBUG] Skipping 103 clip bytes from RFB
[2024-11-19T16:17:22.203+0100] [DEBUG] libxrdp_process_monitor_stream: The number of monitors received is: 1
[2024-11-19T16:17:22.204+0100] [DEBUG] dynamic_monitor_data: received width 2560, received height 1440.
[2024-11-19T16:17:22.205+0100] [DEBUG] dynamic_monitor_process_queue: Not allowing resize due to invalid dimensions (w: 0 x h: 0)
[2024-11-19T16:17:22.254+0100] [DEBUG] xrdp_egfx_close_response: egfx deleted.
[2024-11-19T16:17:22.255+0100] [DEBUG] VNC setting screen id to 1804289383 from server
[2024-11-19T16:17:22.256+0100] [DEBUG] Changing server layout
[2024-11-19T16:17:22.257+0100] [DEBUG] VNC Sending SetDesktopSize
[2024-11-19T16:17:22.258+0100] [DEBUG] Skipping RFB_ENC_EXTENDED_DESKTOP_SIZE encoding x=0, y=0 geom=2520x1371
[2024-11-19T16:17:22.260+0100] [DEBUG] Skipping RFB_ENC_EXTENDED_DESKTOP_SIZE encoding x=0, y=0 geom=2520x1371
[2024-11-19T16:17:22.263+0100] [DEBUG] Skipping RFB_ENC_EXTENDED_DESKTOP_SIZE encoding x=0, y=0 geom=2520x1371
[2024-11-19T16:17:22.264+0100] [DEBUG] VNC matched ExtendedDesktopSize rectangle x=1, y=0 geom=2520x1371
[2024-11-19T16:17:22.264+0100] [DEBUG] Skipping RFB_ENC_EXTENDED_DESKTOP_SIZE encoding x=0, y=0 geom=2520x1371
[2024-11-19T16:17:22.265+0100] [DEBUG] VNC server successfully resized
[2024-11-19T16:17:22.266+0100] [INFO ] Layout from NewLayout (geom=2520x1371 #screens=1) : 1804289383:(2520x1371+0+0)
[2024-11-19T16:17:22.267+0100] [INFO ] server_monitor_resize_done: Advancing server monitor resized.
[2024-11-19T16:17:22.318+0100] [INFO ] Client supports single-screen resizes by xrdp
[2024-11-19T16:17:22.319+0100] [INFO ] xrdp_caps_process_pointer: client supports new(color) cursor
[2024-11-19T16:17:22.320+0100] [INFO ] xrdp_process_offscreen_bmpcache: support level 1 cache size 7864320 MB cache entries 2000
[2024-11-19T16:17:22.321+0100] [INFO ] xrdp_caps_process_codecs: RemoteFX(76772F12-BD72-4463-AFB3-B73C9C6F7886), codec id [3], properties len [49]
[2024-11-19T16:17:22.321+0100] [WARN ] Client Capability: not enough orders supported by client, client wants off screen bitmap but offscreen bitmaps disabled
[2024-11-19T16:17:22.322+0100] [INFO ] Client Capability: LARGE_POINTER_FLAG_96x96 supported

@matt335672
Copy link
Member

Still not managed to reproduce this, but I can compare the logging on my system with yours.

The next messages to be expected in this mode are:-

2024-11-20T12:45:52.952+0000] [INFO ] xrdp_mm_up_and_running: Core reset done.
[2024-11-20T12:45:52.954+0000] [INFO ] xrdp_egfx_create: error 0 channel_id 1

These are generated when the client (remmina in this case) sends a 'Font List PDU' as part of the "Deactivation-Reactivation Sequence" specified in section 1.3.1.3 of "[MS-RDPBCGR]". Until we receive this message, we cannot complete the resize, and we do not seem to be getting it. We are getting a 'confirm active PDU', so the client has responded to the resize request. It does not however seem to be finishing it.

@geragio - can you find the config file for this client for remmina and post it? It should be in something like ~/.local/share/remmina/<group>_<protocol>_<hostname>.remmina.

Also, when the problem happens you should be able to disconnect the client and reconnect to the session. Can you try this please?

@matt335672
Copy link
Member

Also, can you try running remmina from the command line? You should get some client-side logging. If you can provoke the problem you may get a client-side log message to indicate where the problem may be.

@matt335672
Copy link
Member

See also https://gitlab.com/Remmina/Remmina/-/issues/3086 which may be related. I'll set up a gitlab account and try to touch base with the remmina developers.

@geragio
Copy link
Author

geragio commented Nov 20, 2024

Interesting finding, it seems this is a client issue indeed.
I did the same test using Gnome Connections, the resizing works perfectly even with 2560x1440 resolution.

This leads me to think the issue is specific to Remmina, perhaps I'm using a wrong configuration or it's a bug.
This is my current config:

[remmina]
monitorids=
restricted-admin=0
exec=
quality=2
group=
disablesmoothscrolling=0
ssh_passphrase=
scale=2
encodings=
gateway_username=
serialpermissive=0
keymap=
disableautoreconnect=0
postcommand=
rdp_mouse_jitter=No
showcursor=0
ssh_color_scheme=0
run_line=
sshsavesession=0
assistance_mode=0
rdp2tcp=
security=
disableclipboard=0
ssh_proxycommand=
notes_text=
domain=
window_maximize=1
gateway_usage=0
ssh_tunnel_certfile=
ssh_stricthostkeycheck=0
ssh_auth=3
ssh_tunnel_password=
parallelname=
ssh_tunnel_enabled=0
ssh_tunnel_privatekey=
profile-lock=0
ssh_privatekey=
enable-autostart=0
multimon=0
smartcardname=
resolution_mode=2
colordepth=99
labels=
name=host.example.com
disable_fastpath=0
ssh_kex_algorithms=
cert_ignore=0
audio-output=
parallelpath=
disablepasswordstoring=0
sshlogname=
serialdriver=
execpath=
viewonly=0
no-suppress=0
disableserverbell=0
sshlogfolder=
gwtransp=http
ssh_tunnel_auth=0
proxy=
multitransport=0
ssh_ciphers=
serialname=
left-handed=0
gateway_password=
network=autodetect
resolution_height=0
ssh_tunnel_passphrase=
sound=off
freerdp_log_filters=
shareparallel=0
usb=
tightencoding=0
websockets=0
serialpath=
disableserverinput=0
keyboard_grab=0
rdp_reconnect_attempts=
ssh_certfile=
dvc=
old-license=0
ignore-tls-errors=1
ssh_tunnel_loopback=0
pth=
freerdp_log_level=INFO
sshlogenabled=0
sharesmartcard=0
shareserial=0
disableencryption=0
password=.
tls-seclevel=
window_width=1280
loadbalanceinfo=
shareprinter=0
ssh_compression=0
ssh_tunnel_command=
printer_overrides=
server=host.example.com
drive=
ssh_charset=
vc=
base-cred-for-gw=0
username=<username>
audiblebell=0
ssh_tunnel_server=
disable-smooth-scrolling=0
resolution_width=0
disconnect-prompt=0
ssh_hostkeytypes=
viewmode=1
ssh_tunnel_username=
microphone=
span=0
preferipv6=0
clientname=
ssh_forward_x11=0
window_height=1375
glyph-cache=0
console=0
gateway_server=
precommand=
useproxyenv=0
aspect_ratio=
timeout=
clientbuild=
gateway_domain=
relax-order-checks=0
protocol=RDP

@matt335672
Copy link
Member

Looking at the Remmina bug report, It looks like I need a twin monitor setup to provoke this. I'll set something up and try again with your config above.

@matt335672
Copy link
Member

Well, I've got a twin monitor Ubuntu 22.04 setup now, and I still can't reproduce this!

Can you give me the output of xrandr when your second monitor is connected, and also exactly how you are starting remmina before you press the full-screen button.

Thanks.

@matt335672
Copy link
Member

Also, what platform are you running Remmina on, and what version of freerdp is installed on that platform?

On the same platform, do you see the same issue if you connect with the xfreerdp command with the +dynamic-resolution argument? I can't find any code in Remmina itself to handle the sequence which is causing this, so unless I'm missing something, this could be a problem with the version of the FreeRDP library you have on the client system.

@geragio
Copy link
Author

geragio commented Nov 22, 2024

I'm using Fedora 41 Workstation, this is the version of freerdp libs on the system(xfreerdp is not present):

$ rpm -qa | grep freerdp
freerdp-libs-3.9.0-1.fc41.x86_64

This is xrandr output(this is a 3 monitor setup):

Screen 0: minimum 16 x 16, current 6200 x 1920, maximum 32767 x 32767
DP-3 connected 1080x1920+0+0 right (normal left inverted right x axis y axis) 530mm x 300mm
   1920x1080     59.96*+
   1440x1080     59.99  
   1400x1050     59.98  
   1280x1024     59.89  
   1280x960      59.94  
   1152x864      59.96  
   1024x768      59.92  
   800x600       59.86  
   640x480       59.38  
   320x240       59.52  
   1680x1050     59.95  
   1440x900      59.89  
   1280x800      59.81  
   1152x720      59.97  
   960x600       59.63  
   928x580       59.88  
   800x500       59.50  
   768x480       59.90  
   720x480       59.71  
   640x400       59.95  
   320x200       58.96  
   1600x900      59.95  
   1368x768      59.88  
   1280x720      59.86  
   1024x576      59.90  
   864x486       59.92  
   720x400       59.55  
   640x350       59.77  
DP-4 connected 2560x1440+3640+0 (normal left inverted right x axis y axis) 600mm x 340mm
   2560x1440     59.91*+
   1920x1440     59.90  
   1600x1200     59.87  
   1440x1080     59.87  
   1400x1050     59.86  
   1280x1024     59.76  
   1280x960      59.94  
   1152x864      59.78  
   1024x768      59.68  
   800x600       59.86  
   640x480       59.38  
   320x240       59.52  
   1920x1200     59.88  
   1680x1050     59.85  
   1440x900      59.89  
   1280x800      59.81  
   1152x720      59.75  
   960x600       59.63  
   928x580       59.88  
   800x500       59.50  
   768x480       59.38  
   720x480       59.71  
   640x400       59.20  
   320x200       58.96  
   2048x1152     59.90  
   1920x1080     59.88  
   1600x900      59.82  
   1368x768      59.88  
   1280x720      59.86  
   1024x576      59.90  
   864x486       59.45  
   720x400       59.55  
   640x350       59.77  
DP-5 connected primary 2560x1440+1080+0 (normal left inverted right x axis y axis) 600mm x 340mm
   2560x1440     59.91*+
   1920x1440     59.90  
   1600x1200     59.87  
   1440x1080     59.87  
   1400x1050     59.86  
   1280x1024     59.76  
   1280x960      59.94  
   1152x864      59.78  
   1024x768      59.68  
   800x600       59.86  
   640x480       59.38  
   320x240       59.52  
   1920x1200     59.88  
   1680x1050     59.85  
   1440x900      59.89  
   1280x800      59.81  
   1152x720      59.75  
   960x600       59.63  
   928x580       59.88  
   800x500       59.50  
   768x480       59.38  
   720x480       59.71  
   640x400       59.20  
   320x200       58.96  
   2048x1152     59.90  
   1920x1080     59.88  
   1600x900      59.82  
   1368x768      59.88  
   1280x720      59.86  
   1024x576      59.90  
   864x486       59.45  
   720x400       59.55  
   640x350       59.77  

I've started Remmina from command line and noticed multiple warnings when starting the RDP connection:

[17:14:47:124] [418679:000666ac] [WARN][com.freerdp.core.fastpath] - [fastpath_send_multiple_input_pdu]: called before activation [CONNECTION_STATE_CAPABILITIES_EXCHANGE_MONITOR_LAYOUT]

When using just my laptop screen, that warning is not present and everything works fine. It's probably related to this issue.

@matt335672
Copy link
Member

That's great - I'll build a F41 client instead.

Are you running Wayland or Xorg on the F41 machine? I assume you're logging in to the console?

Apologies for all the questions, but this is proving to be harder to reproduce than I'd expected.

@geragio
Copy link
Author

geragio commented Nov 24, 2024

There is no need to apologize, I appreciate all the effort you're putting on this issue 😄
Yes, I'm running Wayland and logging in to the console

@matt335672
Copy link
Member

I've managed to reproduce this, and I can see the problem is a combination of the way Remmina works, and a tightening up of the adherence to the RDP specification in FreeRDP.

This message:-

[17:14:47:124] [418679:000666ac] [WARN][com.freerdp.core.fastpath] - [fastpath_send_multiple_input_pdu]: called before activation [CONNECTION_STATE_CAPABILITIES_EXCHANGE_MONITOR_LAYOUT]

is generated by FreeRDP during the resize when it is asked by Remmina to send an input PDU (keyboard or mouse) to xrdp from FreeRDP, but the state of the connection is such that the PDU should not be sent.

The connection gets into this state in a couple of places:-

  1. At startup
  2. When the server invokes a 'Deactivation-reactivation sequence', documented in [MS-RDPBCGR] 1.3.1.3

xrdp implements a resize using 2) above.

You can see this by setting a breakpoint on fastpath.c:1029, which is where the message is generated. Then, if the fault is provoked:-

gdb) where
#0  fastpath_send_multiple_input_pdu
    (fastpath=0x55e337abd610, s=0x55e337b56ac0, iNumEvents=1)
    at /usr/src/debug/freerdp-3.9.0-1.fc41.x86_64/libfreerdp/core/fastpath.c:1029
#1  0x00007ff69eaa00b3 in rf_process_event_queue (gp=0x55e337a33ec0)
    at /usr/src/debug/remmina-1.4.35-3.fc41.x86_64/plugins/rdp/rdp_plugin.c:293
#2  remmina_rdp_main_loop (gp=gp@entry=0x55e337a33ec0)
    at /usr/src/debug/remmina-1.4.35-3.fc41.x86_64/plugins/rdp/rdp_plugin.c:1248
#3  0x00007ff69eaa4118 in remmina_rdp_main (gp=0x55e337a33ec0)
    at /usr/src/debug/remmina-1.4.35-3.fc41.x86_64/plugins/rdp/rdp_plugin.c:2575
#4  0x00007ff69eaa58e3 in remmina_rdp_main_thread (data=0x55e337a33ec0)
    at /usr/src/debug/remmina-1.4.35-3.fc41.x86_64/plugins/rdp/rdp_plugin.c:2655
#5  0x00007ff6a40cdcd7 in start_thread (arg=<optimized out>)
    at pthread_create.c:448
#6  0x00007ff6a4151c8c in __GI___clone3 ()
    at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:78

This particular call comes from here:-

https://gitlab.com/Remmina/Remmina/-/blob/master/plugins/rdp/rdp_plugin.c?ref_type=heads#L287-296

I started trying to fix this by wrapping these calls with a guard based on freerdp_is_active_state() but I got in a bit of a mess, so it's not something I could do very quickly. It may not be a simple fix I'm afraid.

The problem does not happen with xfreerdp. It may be a proper solution would need to look at how xfreerdp guards against this particular issue.

@matt335672
Copy link
Member

@geragio - if you're able to access gitlab, this may be of interest:-

https://gitlab.com/Remmina/Remmina/-/issues/3086#note_2240545587

I'll reproduce the text here for you. This is posted by one of the Remmina developers.

Hi all. I've pushed a quick fix that seems to resolve the issue for me without causing any issues. I'd appreciate it if someone else could test and confirm if this resolves the issue for them. The code can either be compiled from source, or as an easier option the newest master version of Remmina can be installed from our PPA here.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Projects
None yet
Development

No branches or pull requests

2 participants