Black screen after login, followed by disconnect

[blaizmannen]

Running on ubuntu 22.04 on an EC2 instance.

Was running fine for a few months, but following a recent apt update on this instance, xrdp fails to work. I would be met with a black screen for a few seconds, follow by an abrupt exit of my window. I am using Microsoft Remote Desktop from a macOS machine.

xrdp-sesman.log excerpts from reboot up until an xrdp login attempt:

[20230221-09:21:21] [DEBUG] libscp initialized
[20230221-09:21:21] [DEBUG] Testing if xrdp-sesman can listen on 127.0.0.1 port 3350.
[20230221-09:21:21] [DEBUG] Closed socket 6 (AF_INET6 ::1 port 3350)
[20230221-09:21:21] [INFO ] starting xrdp-sesman with pid 577
[20230221-09:21:21] [DEBUG] sesman_main_loop: address 127.0.0.1 port 3350
[20230221-09:25:28] [INFO ] Socket 9: AF_INET6 connection received from ::1 port 35178
[20230221-09:25:45] [DEBUG] session_get_bydata: search policy 0 U user.name W 1512 H 944 bpp 24 T 3 IP 10.50.1.112:57836 - socket: 12
[20230221-09:25:45] [INFO ] Terminal Server Users group is disabled, allowing authentication
[20230221-09:25:45] [INFO ] ++ created session (access granted): username user.name, ip 10.50.1.112:57836 - socket: 12
[20230221-09:25:45] [INFO ] starting Xorg session...
[20230221-09:25:45] [DEBUG] Did not find a running X server at /tmp/.X11-unix/X10
[20230221-09:25:45] [DEBUG] Did not find a running X server at /tmp/.X10-lock
[20230221-09:25:45] [DEBUG] Closed socket 14 (AF_INET6 :: port 5910)
[20230221-09:25:45] [DEBUG] Did not find a running X server at 5910
[20230221-09:25:45] [DEBUG] Closed socket 14 (AF_INET6 :: port 6010)
[20230221-09:25:45] [DEBUG] Did not find a running X server at 6010
[20230221-09:25:45] [DEBUG] Closed socket 14 (AF_INET6 :: port 6210)
[20230221-09:25:45] [DEBUG] Did not find a running X server at 6210
[20230221-09:25:45] [DEBUG] Did not find a running X server at /run/xrdp/sockdir/xrdp_chansrv_socket_10
[20230221-09:25:45] [DEBUG] Did not find a running X server at /run/xrdp/sockdir/xrdp_chansrv_audio_out_socket_10
[20230221-09:25:45] [DEBUG] Did not find a running X server at /run/xrdp/sockdir/xrdp_chansrv_audio_in_socket_10
[20230221-09:25:45] [DEBUG] Did not find a running X server at /run/xrdp/sockdir/xrdpapi_10
[20230221-09:25:45] [INFO ] Starting session: session_pid 1168, display :10.0, width 1512, height 944, bpp 24, client ip 10.50.1.112:57836 - socket: 12, user name user.name
[20230221-09:25:45] [INFO ] [session start] (display 10): calling auth_start_session from pid 1168
[20230221-09:25:45] [ERROR] sesman_data_in: scp_process_msg failed
[20230221-09:25:45] [ERROR] sesman_main_loop: trans_check_wait_objs failed, removing trans
[20230221-09:25:45] [DEBUG] Closed socket 9 (AF_INET6 ::1 port 3350)
[20230221-09:25:45] [DEBUG] Closed socket 8 (AF_INET6 ::1 port 3350)
[20230221-09:25:45] [DEBUG] Closed socket 9 (AF_INET6 ::1 port 3350)
[20230221-09:25:45] [DEBUG] Waiting for X server to start on display 10
[20230221-09:25:45] [DEBUG] Did not find a running X server at /tmp/.X11-unix/X10
[20230221-09:25:45] [DEBUG] Waiting for X server to start on display 10
[20230221-09:25:45] [DEBUG] Did not find a running X server at /tmp/.X11-unix/X10
[20230221-09:25:45] [DEBUG] Did not find a running X server at /tmp/.X10-lock
[20230221-09:25:45] [DEBUG] Did not find a running X server at /tmp/.X10-lock
[20230221-09:25:45] [INFO ] Starting X server on display 10: /usr/lib/xorg/Xorg :10 -auth .Xauthority -config xrdp/xorg.conf -noreset -nolisten tcp -logfile .xorgxrdp.%s.log  
[20230221-09:25:45] [DEBUG] Calling exec (excutable: /usr/lib/xorg/Xorg, arguments: /usr/lib/xorg/Xorg :10 -auth .Xauthority -config xrdp/xorg.conf -noreset -nolisten tcp -logfile .xorgxrdp.%s.log)
[20230221-09:25:45] [INFO ] Found X server running at /tmp/.X11-unix/X10
[20230221-09:25:45] [INFO ] Session started successfully for user user.name on display 10
[20230221-09:25:45] [INFO ] Found X server running at /tmp/.X11-unix/X10
[20230221-09:25:45] [INFO ] Session in progress on display 10, waiting until the window manager (pid 1181) exits to end the session
[20230221-09:25:45] [DEBUG] waiting for pid 1181 to exit
[20230221-09:25:45] [INFO ] Found X server running at /tmp/.X11-unix/X10
[20230221-09:25:45] [DEBUG] The user session on display 10 did not request a specific window manager
[20230221-09:25:45] [DEBUG] The user home directory window manager configuration is enabled but window manager program does not exist: /home/user.name/startwm.sh
[20230221-09:25:45] [INFO ] Starting the xrdp channel server for display 10
[20230221-09:25:45] [INFO ] Starting the default window manager on display 10: /etc/xrdp/startwm.sh
[20230221-09:25:45] [DEBUG] Calling exec (excutable: /etc/xrdp/startwm.sh, arguments: /etc/xrdp/startwm.sh )
[20230221-09:25:45] [DEBUG] Calling exec (excutable: /usr/sbin/xrdp-chansrv, arguments: /usr/sbin/xrdp-chansrv)
[20230221-09:25:45] [WARN ] Window manager (pid 1181, display 10) exited with non-zero exit code 2 and signal 0. This could indicate a window manager config problem
[20230221-09:25:45] [WARN ] Window manager (pid 1181, display 10) exited quickly (0 secs). This could indicate a window manager config problem
[20230221-09:25:45] [INFO ] Calling auth_stop_session and auth_end from pid 1168
[20230221-09:25:45] [INFO ] Terminating X server (pid 1182) on display 10
[20230221-09:25:45] [INFO ] Terminating the xrdp channel server (pid 1200) on display 10
[20230221-09:25:45] [DEBUG] waiting for pid 1182 to exit
[20230221-09:25:46] [INFO ] X server on display 10 (pid 1182) returned exit code 0 and signal number 0
[20230221-09:25:46] [DEBUG] waiting for pid 1200 to exit
[20230221-09:25:46] [INFO ] xrdp channel server for display 10 (pid 1200) exit code 0 and signal number 0
[20230221-09:25:46] [INFO ] cleanup_sockets:
[20230221-09:25:46] [DEBUG] cleanup_sockets: deleting /run/xrdp/sockdir/xrdpapi_10
[20230221-09:25:46] [INFO ] ++ terminated session:  username user.name, display :10.0, session_pid 1168, ip 10.50.1.112:57836 - socket: 12

The fact that the apt update was what triggered this issue is a hunch, but everything was working fine prior to 20 February.

excerpt from /var/log/apt/history.log

Start-Date: 2023-02-20  00:26:22
Commandline: /usr/bin/apt-get -y -o Dpkg::Options::=--force-confdef -o Dpkg::Options::=--force-confold upgrade --with-new-pkgs
Requested-By: ubuntu (1000)
Upgrade: python3-distupgrade:amd64 (1:22.04.15, 1:22.04.16), ubuntu-release-upgrader-gtk:amd64 (1:22.04.15, 1:22.04.16), ubuntu-advantage-desktop-daemon:amd64 (1.9~22.04.1, 1.10~22.04.1), squid:amd64 (5.2-1ubuntu4.2, 5.2-1ubuntu4.3), python-apt-common:amd64 (2.4.0, 2.4.0ubuntu1), squid-common:amd64 (5.2-1ubuntu4.2, 5.2-1ubuntu4.3), motd-news-config:amd64 (12ubuntu4.2, 12ubuntu4.3), fwupd-signed:amd64 (1.44+1.2-3, 1.51~22.04.1+1.2-3ubuntu0.2), update-notifier:amd64 (3.192.54, 3.192.54.5), base-files:amd64 (12ubuntu4.2, 12ubuntu4.3), python3-apt:amd64 (2.4.0, 2.4.0ubuntu1), ubuntu-release-upgrader-core:amd64 (1:22.04.15, 1:22.04.16), ubuntu-advantage-tools:amd64 (27.12~22.04.1, 27.13.5~22.04.1), update-notifier-common:amd64 (3.192.54, 3.192.54.5)
End-Date: 2023-02-20  00:27:16

What I also did notice that removing the below code from startwm.sh...

if test -r /etc/profile; then
	. /etc/profile
fi

... would enable me to be able to log in. But I then get some GNOME related error messages on the display, and some unexpected behaviour like the keyring/auth related errors when using tableplus for example.

Do let me know if there is any more information I can provide.

[matt335672]

@blaizmannen - there's definitely a problem with your session starting:-

[20230221-09:25:45] [WARN ] Window manager (pid 1181, display 10) exited with non-zero exit code 2 and signal 0. This could indicate a window manager config problem
[20230221-09:25:45] [WARN ] Window manager (pid 1181, display 10) exited quickly (0 secs). This could indicate a window manager config problem

It's also possible your X server is starting and then exiting quickly. I think this is unlikely however, given your description of the problem.

For the session, have a look in~/.xsession-errors and also at the output of sudo journalctl -S -2m just after a login attempt.

For the X server, have a look in ~/.xorgxrdp.10.log

Let us know what you find.

[blaizmannen]

journalctl:

Feb 21 14:08:00 ip-10-51-98-26 sssd_be[575]: Backend is online
Feb 21 14:08:35 ip-10-51-98-26 xrdp[614]: [INFO ] Socket 12: AF_INET connection received from 10.50.1.112 port 58878
Feb 21 14:08:35 ip-10-51-98-26 xrdp[614]: [DEBUG] Closed socket 12 (AF_INET 10.51.98.26:3389)
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] Closed socket 11 (AF_INET 0.0.0.0:3389)
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ini_version, value 1
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item fork, value true
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item port, value tcp://:3389
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item use_vsock, value false
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item tcp_nodelay, value true
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item tcp_keepalive, value true
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item security_layer, value tls
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item crypt_level, value fips
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ssl_protocols, value TLSv1.2, TLSv1.3
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] TLSv1.3 enabled
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] TLSv1.2 enabled
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item tls_ciphers, value HIGH
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item certificate, value /etc/le-ssl/certs/mydomain.com.pem
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item key_file, value /etc/le-ssl/private/key.pem
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item autorun, value
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item allow_channels, value true
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item allow_multimon, value true
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item bitmap_cache, value true
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item bitmap_compression, value true
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item bulk_compression, value true
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item max_bpp, value 32
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item new_cursors, value true
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item use_fastpath, value both
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item blue, value 009cb5
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item grey, value dedede
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_top_window_bg_color, value 009cb5
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_width, value 350
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_height, value 430
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_bg_color, value dedede
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_logo_filename, value
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_logo_x_pos, value 55
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_logo_y_pos, value 50
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_label_x_pos, value 30
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_label_width, value 65
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_input_x_pos, value 110
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_input_width, value 210
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_input_y_pos, value 220
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_btn_ok_x_pos, value 142
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_btn_ok_y_pos, value 370
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_btn_ok_width, value 85
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_btn_ok_height, value 30
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_btn_cancel_x_pos, value 237
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_btn_cancel_y_pos, value 370
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_btn_cancel_width, value 85
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] item ls_btn_cancel_height, value 30
Feb 21 14:08:35 ip-10-51-98-26 xrdp[1635]: [DEBUG] Security layer: requested 11, selected 1
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Using TLS security, and setting RDP security crypto to LEVEL_NONE and METHOD_NONE
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] receive connection request
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [INFO ] Connected client computer name: Johns-MacBook-P
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Client supports 40 bit encryption
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Client supports 128 bit encryption
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Client supports 56 bit encryption
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Client supports fips encryption
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] The connection is using TLS, skipping RDP crypto negotiation
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Adding channel: name rdpdr, channel id 1004, flags 0x80800000
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Adding channel: name rdpsnd, channel id 1005, flags 0xc0000000
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Adding channel: name cliprdr, channel id 1006, flags 0xc0a00000
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Adding channel: name drdynvc, channel id 1007, flags 0xc0800000
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [WARN ] Received [MS-RDPBCGR] TS_UD_HEADER type 0xc006 is unknown (ignored)
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [WARN ] Received [MS-RDPBCGR] TS_UD_HEADER type 0xc00a is unknown (ignored)
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] construct connection reponse
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] using no security
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] send connection reponse
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] receive erect domain request
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] receive attach user request
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] send attach user confirm
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] receive channel join request
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] send channel join confirm
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] receive channel join request
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] send channel join confirm
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] receive channel join request
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] send channel join confirm
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] receive channel join request
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] send channel join confirm
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] receive channel join request
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] send channel join confirm
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] receive channel join request
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] send channel join confirm
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] [MCS Connection Sequence] completed
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [INFO ] xrdp_load_keyboard_layout: Keyboard information sent by the RDP client, keyboard_type:[0x04], keyboard_subtype:[0x00], keylayout:[0x00000000]
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] keyboard_cfg_file /etc/xrdp/xrdp_keyboard.ini
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_load_keyboard_layout: item rdp_layout_us value 0x00000409
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_load_keyboard_layout: skipping configuration item - rdp_layout_us, continuing to next section
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_load_keyboard_layout: item rdp_layout_us value us
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_load_keyboard_layout: skipping configuration item - rdp_layout_us, continuing to next section
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_load_keyboard_layout: item keyboard_type value 4
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_load_keyboard_layout: item keyboard_subtype value 3
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_load_keyboard_layout: item keyboard_type value 7
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_load_keyboard_layout: item keyboard_subtype value 2
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_load_keyboard_layout: item model value pc105
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_load_keyboard_layout: item rdp_layouts value default_rdp_layouts
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_load_keyboard_layout: item layouts_map value default_layouts_map
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_load_keyboard_layout: item rdp_layout_us value us
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_load_keyboard_layout: skipping configuration item - rdp_layout_us, continuing to next section
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [INFO ] xrdp_load_keyboard_layout: model [] variant [] layout [us] options []
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [INFO ] TLS connection established from 10.50.1.112 port 58878: TLSv1.3 with cipher TLS_AES_256_GCM_SHA384
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Client requested auto logon.
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Client requested compression enabled.
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Client supplied domain:
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Client supplied username: user.name
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Client supplied password: <omitted from log>
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Client supplied program:
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Client supplied directory:
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [INFO ] xrdp_caps_process_pointer: client supports new(color) cursor
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [INFO ] xrdp_process_offscreen_bmpcache: support level 0 cache size 0 MB cache entries 0
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [INFO ] xrdp_caps_process_codecs: nscodec, codec id 1, properties len 3
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_00000663_wm_login_state_event_00000001
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [WARN ] Cannot find keymap file /etc/xrdp/km-00000000.ini
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [WARN ] Cannot find keymap file /etc/xrdp/km-00000000.ini
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [INFO ] Loading keymap file /etc/xrdp/km-00000409.ini
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [WARN ] local keymap file for 0x00000000 found and doesn't match built in keymap, using local keymap file
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Login state change request WMLS_RESET -> WMLS_RESET
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_wm_login_mode_changed: login_mode is 0
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Login state change request WMLS_RESET -> WMLS_USER_PROMPT
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] in xrdp_wm_init:
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ini_version:             1
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] use_bitmap_cache:        1
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] use_bitmap_compression:  1
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] port:                    0
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] crypt_level:             3
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] allow_channels:          1
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] max_bpp:                 32
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] fork:                    1
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] tcp_nodelay:             1
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] tcp_keepalive:           1
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] tcp_send_buffer_bytes:   0
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] tcp_recv_buffer_bytes:   0
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] new_cursors:             1
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] allow_multimon:          1
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] grey:                    14606046
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] black:                   0
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] dark_grey:               0
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] blue:                    40117
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] dark_blue:               0
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] white:                   0
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] red:                     0
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] green:                   0
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] background:              0
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] autorun:
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] hidelogwindow:           0
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] require_credentials:     0
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] bulk_compression:        1
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] new_cursors:             1
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] nego_sec_layer:          0
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] allow_multimon:          1
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] enable_token_login:      0
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_top_window_bg_color:  4f6
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_width:                350
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_height:               430
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_bg_color:             defb
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_title:
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_logo_filename:
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_logo_x_pos:           55
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_logo_y_pos:           50
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_label_x_pos:          30
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_label_width:          65
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_input_x_pos:          110
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_input_width:          210
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_input_y_pos:          220
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_btn_ok_x_pos:         142
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_btn_ok_y_pos:         370
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_btn_ok_width:         85
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_btn_ok_height:        30
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_btn_cancel_x_pos:     237
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_btn_cancel_y_pos:     370
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_btn_cancel_width:     85
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] ls_btn_cancel_height:    30
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] libxrdp_query_channel - Channel 0 name rdpdr
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_wm_init: channel rdpdr channel id 0 is disabled
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Disabling channel 1004 (rdpdr)
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] libxrdp_query_channel - Channel 1 name rdpsnd
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_wm_init: channel rdpsnd channel id 1 is enabled
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Enabling channel 1005 (rdpsnd)
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] libxrdp_query_channel - Channel 2 name cliprdr
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_wm_init: channel cliprdr channel id 2 is enabled
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Enabling channel 1006 (cliprdr)
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] libxrdp_query_channel - Channel 3 name drdynvc
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_wm_init: channel drdynvc channel id 3 is enabled
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Enabling channel 1007 (drdynvc)
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Login state change request WMLS_USER_PROMPT -> WMLS_START_CONNECT
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] out xrdp_wm_init:
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_wm_login_mode_changed: login_mode is 2
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_wm_log_msg: connecting to sesman ip 127.0.0.1 port 3350
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [INFO ] connecting to sesman ip 127.0.0.1 port 3350
Feb 21 14:08:39 ip-10-51-98-26 xrdp-sesman[592]: [INFO ] Socket 9: AF_INET6 connection received from ::1 port 47972
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [INFO ] xrdp_wm_log_msg: sesman connect ok
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [INFO ] sesman connect ok
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_wm_log_msg: sending login info to session manager, please wait...
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [INFO ] sending login info to session manager, please wait...
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] return value from xrdp_mm_connect 0
Feb 21 14:08:39 ip-10-51-98-26 xrdp-sesman[592]: pam_unix(xrdp-sesman:auth): authentication failure; logname= uid=0 euid=0 tty=xrdp-sesman ruser= rhost=  user=user.name
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] Login state change request WMLS_START_CONNECT -> WMLS_CONNECT_IN_PROGRESS
Feb 21 14:08:39 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_wm_login_mode_changed: login_mode is 3
Feb 21 14:08:48 ip-10-51-98-26 xrdp-sesman[592]: pam_sss(xrdp-sesman:auth): authentication success; logname= uid=0 euid=0 tty=xrdp-sesman ruser= rhost= user=user.name
Feb 21 14:08:50 ip-10-51-98-26 audit[592]: USER_AUTH pid=592 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='op=PAM:authentication grantors=pam_permit,pam_cap acct="user.name" exe="/usr/sbin/xrdp-sesman" hostname=? addr=? terminal=xrdp-sesman res=success'
Feb 21 14:08:57 ip-10-51-98-26 audit[592]: USER_ACCT pid=592 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='op=PAM:accounting grantors=pam_permit,pam_sss acct="user.name" exe="/usr/sbin/xrdp-sesman" hostname=? addr=? terminal=xrdp-sesman res=success'
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [DEBUG] session_get_bydata: search policy 0 U user.name W 1512 H 944 bpp 24 T 3 IP 10.50.1.112:58878 - socket: 12
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [INFO ] Terminal Server Users group is disabled, allowing authentication
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [INFO ] ++ created session (access granted): username user.name, ip 10.50.1.112:58878 - socket: 12
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [INFO ] starting Xorg session...
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [DEBUG] Did not find a running X server at /tmp/.X11-unix/X10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [DEBUG] Did not find a running X server at /tmp/.X10-lock
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [DEBUG] Closed socket 14 (AF_INET6 :: port 5910)
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [DEBUG] Did not find a running X server at 5910
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [DEBUG] Closed socket 14 (AF_INET6 :: port 6010)
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [DEBUG] Did not find a running X server at 6010
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [DEBUG] Closed socket 14 (AF_INET6 :: port 6210)
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [DEBUG] Did not find a running X server at 6210
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [DEBUG] Did not find a running X server at /run/xrdp/sockdir/xrdp_chansrv_socket_10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [DEBUG] Did not find a running X server at /run/xrdp/sockdir/xrdp_chansrv_audio_out_socket_10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [DEBUG] Did not find a running X server at /run/xrdp/sockdir/xrdp_chansrv_audio_in_socket_10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [DEBUG] Did not find a running X server at /run/xrdp/sockdir/xrdpapi_10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [INFO ] Starting session: session_pid 1638, display :10.0, width 1512, height 944, bpp 24, client ip 10.50.1.112:58878 - socket: 12, user name user.name
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [INFO ] [session start] (display 10): calling auth_start_session from pid 1638
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [ERROR] sesman_data_in: scp_process_msg failed
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [INFO ] xrdp_wm_log_msg: login successful for display 10
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [INFO ] login successful for display 10
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [INFO ] loaded module 'libxup.so' ok, interface size 10296, version 4
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [ERROR] sesman_main_loop: trans_check_wait_objs failed, removing trans
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [DEBUG] Closed socket 9 (AF_INET6 ::1 port 3350)
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_wm_log_msg: started connecting
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [INFO ] started connecting
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [INFO ] lib_mod_connect: connecting via UNIX socket
Feb 21 14:08:57 ip-10-51-98-26 audit[1638]: CRED_ACQ pid=1638 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='op=PAM:setcred grantors=pam_permit,pam_cap acct="user.name" exe="/usr/sbin/xrdp-sesman" hostname=? addr=? terminal=:10 res=success'
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: pam_unix(xrdp-sesman:session): session opened for user user.name(uid=1257699886) by (uid=0)
Feb 21 14:08:57 ip-10-51-98-26 systemd[1]: Created slice User Slice of UID 1257699886.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1]: Starting User Runtime Directory /run/user/1257699886...
Feb 21 14:08:57 ip-10-51-98-26 systemd-logind[627]: New session c3 of user user.name.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1]: Finished User Runtime Directory /run/user/1257699886.
Feb 21 14:08:57 ip-10-51-98-26 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='unit=user-runtime-dir@1257699886 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 21 14:08:57 ip-10-51-98-26 systemd[1]: Starting User Manager for UID 1257699886...
Feb 21 14:08:57 ip-10-51-98-26 audit[1640]: USER_ACCT pid=1640 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='op=PAM:accounting grantors=pam_permit,pam_sss acct="user.name" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 21 14:08:57 ip-10-51-98-26 audit[1640]: CRED_ACQ pid=1640 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='op=PAM:setcred grantors=pam_permit,pam_cap acct="user.name" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 21 14:08:57 ip-10-51-98-26 audit[1640]: SYSCALL arch=c000003e syscall=1 success=yes exit=10 a0=c a1=7fffefa54400 a2=a a3=0 items=0 ppid=1 pid=1640 auid=1257699886 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=15 comm="(systemd)" exe="/usr/lib/systemd/systemd" subj=unconfined key=(null)
Feb 21 14:08:57 ip-10-51-98-26 audit: PROCTITLE proctitle="(systemd)"
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: pam_unix(systemd-user:session): session opened for user user.name(uid=1257699886) by (uid=0)
Feb 21 14:08:57 ip-10-51-98-26 audit[1640]: USER_START pid=1640 uid=0 auid=1257699886 ses=15 subj=unconfined msg='op=PAM:session_open grantors=pam_selinux,pam_selinux,pam_loginuid,pam_limits,pam_permit,pam_umask,pam_unix,pam_keyinit,pam_systemd acct="user.name" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Queued start job for default target Main User Target.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Created slice User Application Slice.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Created slice User Background Tasks Slice.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Created slice User Core Session Slice.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Reached target Paths.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Reached target Timers.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Starting D-Bus User Message Bus Socket...
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Listening on GnuPG network certificate management daemon.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers).
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Listening on GnuPG cryptographic agent and passphrase cache.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Listening on PipeWire Multimedia System Socket.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Listening on debconf communication socket.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Listening on Sound System.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Listening on REST API socket for snapd user session agent.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Listening on D-Bus User Message Bus Socket.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Reached target Sockets.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Reached target Basic System.
Feb 21 14:08:57 ip-10-51-98-26 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='unit=user@1257699886 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 21 14:08:57 ip-10-51-98-26 systemd[1]: Started User Manager for UID 1257699886.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Started PipeWire Multimedia Service.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Started PipeWire Media Session Manager.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1]: Started Session c3 of User user.name.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Starting Sound Service...
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Starting Tracker metadata extractor...
Feb 21 14:08:57 ip-10-51-98-26 audit[1638]: USER_START pid=1638 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='op=PAM:session_open grantors=pam_permit,pam_umask,pam_unix,pam_sss,pam_mkhomedir,pam_systemd acct="user.name" exe="/usr/sbin/xrdp-sesman" hostname=? addr=? terminal=:10 res=success'
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [DEBUG] Closed socket 8 (AF_INET6 ::1 port 3350)
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [DEBUG] Closed socket 9 (AF_INET6 ::1 port 3350)
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [DEBUG] Waiting for X server to start on display 10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [DEBUG] Did not find a running X server at /tmp/.X11-unix/X10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [DEBUG] Did not find a running X server at /tmp/.X10-lock
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1651]: [DEBUG] Waiting for X server to start on display 10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1651]: [DEBUG] Did not find a running X server at /tmp/.X11-unix/X10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1651]: [DEBUG] Did not find a running X server at /tmp/.X10-lock
Feb 21 14:08:57 ip-10-51-98-26 pipewire[1647]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown
Feb 21 14:08:57 ip-10-51-98-26 pipewire[1647]: mod.rt: could not set nice-level to -11: No such file or directory
Feb 21 14:08:57 ip-10-51-98-26 pipewire[1647]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Started D-Bus User Message Bus.
Feb 21 14:08:57 ip-10-51-98-26 pipewire-media-session[1648]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1652]: [INFO ] Starting X server on display 10: /usr/lib/xorg/Xorg :10 -auth .Xauthority -config xrdp/xorg.conf -noreset -nolisten tcp -logfile .xorgxrdp.%s.log
Feb 21 14:08:57 ip-10-51-98-26 pipewire-media-session[1648]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown
Feb 21 14:08:57 ip-10-51-98-26 pipewire-media-session[1648]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1652]: [DEBUG] Calling exec (excutable: /usr/lib/xorg/Xorg, arguments: /usr/lib/xorg/Xorg :10 -auth .Xauthority -config xrdp/xorg.conf -noreset -nolisten tcp -logfile .xorgxrdp.%s.log)
Feb 21 14:08:57 ip-10-51-98-26 pipewire-media-session[1648]: mod.rt: could not make thread 1656 realtime using RTKit: No such file or directory
Feb 21 14:08:57 ip-10-51-98-26 dbus-daemon[1654]: [session uid=1257699886 pid=1654] AppArmor D-Bus mediation is enabled
Feb 21 14:08:57 ip-10-51-98-26 pipewire[1647]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown
Feb 21 14:08:57 ip-10-51-98-26 pipewire[1647]: mod.rt: RTKit error: org.freedesktop.DBus.Error.ServiceUnknown
Feb 21 14:08:57 ip-10-51-98-26 pipewire[1647]: mod.rt: could not make thread 1657 realtime using RTKit: No such file or directory
Feb 21 14:08:57 ip-10-51-98-26 dbus-daemon[1654]: [session uid=1257699886 pid=1654] Activating via systemd: service name='org.gtk.vfs.Daemon' unit='gvfs-daemon.service' requested by ':1.3' (uid=1257699886 pid=1650 comm="/usr/libexec/tracker-extract-3 " label="unconfined")
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Starting Virtual filesystem service...
Feb 21 14:08:57 ip-10-51-98-26 dbus-daemon[1654]: [session uid=1257699886 pid=1654] Successfully activated service 'org.gtk.vfs.Daemon'
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Started Virtual filesystem service.
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Started Sound Service.
Feb 21 14:08:57 ip-10-51-98-26 dbus-daemon[1654]: [session uid=1257699886 pid=1654] Activating via systemd: service name='org.freedesktop.Tracker3.Miner.Files' unit='tracker-miner-fs-3.service' requested by ':1.3' (uid=1257699886 pid=1650 comm="/usr/libexec/tracker-extract-3 " label="unconfined")
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Starting Tracker file system data miner...
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [INFO ] lib_mod_log_peer: xrdp_pid=1635 connected to X11rdp_pid=1652 X11rdp_uid=1257699886 X11rdp_gid=1257699886 client_ip=10.50.1.112 client_port=58878
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_wm_log_msg: connected ok
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [INFO ] connected ok
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [DEBUG] Login state change request WMLS_CONNECT_IN_PROGRESS -> WMLS_CLEANUP
Feb 21 14:08:57 ip-10-51-98-26 dbus-daemon[1654]: [session uid=1257699886 pid=1654] Activating via systemd: service name='org.gtk.vfs.UDisks2VolumeMonitor' unit='gvfs-udisks2-volume-monitor.service' requested by ':1.6' (uid=1257699886 pid=1672 comm="/usr/libexec/tracker-miner-fs-3 " label="unconfined")
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Starting Virtual filesystem service - disk device monitor...
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [INFO ] Found X server running at /tmp/.X11-unix/X10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [INFO ] Session started successfully for user user.name on display 10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1681]: [INFO ] Starting the xrdp channel server for display 10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [INFO ] Session in progress on display 10, waiting until the window manager (pid 1651) exits to end the session
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1681]: [DEBUG] Calling exec (excutable: /usr/sbin/xrdp-chansrv, arguments: /usr/sbin/xrdp-chansrv)
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [DEBUG] waiting for pid 1651 to exit
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1651]: [INFO ] Found X server running at /tmp/.X11-unix/X10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1651]: [INFO ] Found X server running at /tmp/.X11-unix/X10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1651]: [DEBUG] The user session on display 10 did not request a specific window manager
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1651]: [DEBUG] The user home directory window manager configuration is enabled but window manager program does not exist: /home/user.name/startwm.sh
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1651]: [INFO ] Starting the default window manager on display 10: /etc/xrdp/startwm.sh
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1651]: [DEBUG] Calling exec (excutable: /etc/xrdp/startwm.sh, arguments: /etc/xrdp/startwm.sh )
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [DEBUG] libxrdp_query_channel - Channel 0 name rdpdr
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [DEBUG] libxrdp_query_channel - Channel 1 name rdpsnd
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [DEBUG] libxrdp_query_channel - Channel 2 name cliprdr
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [DEBUG] libxrdp_query_channel - Channel 3 name drdynvc
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_mm_connect_chansrv: chansrv connect successful
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [DEBUG] Closed socket 18 (AF_INET6 ::1 port 47972)
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_wm_login_mode_changed: login_mode is 4
Feb 21 14:08:57 ip-10-51-98-26 dbus-daemon[1654]: [session uid=1257699886 pid=1654] Successfully activated service 'org.gtk.vfs.UDisks2VolumeMonitor'
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [DEBUG] Login state change request WMLS_CLEANUP -> WMLS_INACTIVE
Feb 21 14:08:57 ip-10-51-98-26 systemd[1640]: Started Virtual filesystem service - disk device monitor.
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_wm_login_mode_changed: login_mode is 5
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [DEBUG] Request to send a message to the disabled channel rdpdr (0)
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [WARN ] Window manager (pid 1651, display 10) exited with non-zero exit code 2 and signal 0. This could indicate a window manager config problem
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [WARN ] Window manager (pid 1651, display 10) exited quickly (0 secs). This could indicate a window manager config problem
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [INFO ] Calling auth_stop_session and auth_end from pid 1638
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: pam_unix(xrdp-sesman:session): session closed for user user.name
Feb 21 14:08:57 ip-10-51-98-26 audit[1638]: USER_END pid=1638 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='op=PAM:session_close grantors=pam_permit,pam_umask,pam_unix,pam_sss,pam_mkhomedir,pam_systemd acct="user.name" exe="/usr/sbin/xrdp-sesman" hostname=? addr=? terminal=:10 res=success'
Feb 21 14:08:57 ip-10-51-98-26 audit[1638]: CRED_DISP pid=1638 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='op=PAM:setcred grantors=pam_permit acct="user.name" exe="/usr/sbin/xrdp-sesman" hostname=? addr=? terminal=:10 res=success'
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [INFO ] Terminating X server (pid 1652) on display 10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [INFO ] Terminating the xrdp channel server (pid 1681) on display 10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [DEBUG] waiting for pid 1652 to exit
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [DEBUG] Closed socket 12 (AF_INET 10.51.98.26:3389)
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [DEBUG] xrdp_mm_module_cleanup
Feb 21 14:08:57 ip-10-51-98-26 systemd[1]: home-user.name-thinclient_drives.mount: Deactivated successfully.
Feb 21 14:08:57 ip-10-51-98-26 systemd-logind[627]: Failed to restore VT, ignoring: Bad file descriptor
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [DEBUG] Closed socket 19 (AF_UNIX)
Feb 21 14:08:57 ip-10-51-98-26 xrdp[1635]: [DEBUG] Closed socket 20 (AF_UNIX)
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [INFO ] X server on display 10 (pid 1652) returned exit code 0 and signal number 0
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [DEBUG] waiting for pid 1681 to exit
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [INFO ] xrdp channel server for display 10 (pid 1681) exit code 0 and signal number 0
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [INFO ] cleanup_sockets:
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [DEBUG] cleanup_sockets: deleting /run/xrdp/sockdir/xrdp_chansrv_audio_out_socket_10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [DEBUG] cleanup_sockets: deleting /run/xrdp/sockdir/xrdp_chansrv_audio_in_socket_10
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[1638]: [DEBUG] cleanup_sockets: deleting /run/xrdp/sockdir/xrdpapi_10
Feb 21 14:08:57 ip-10-51-98-26 systemd-logind[627]: Session c3 logged out. Waiting for processes to exit.
Feb 21 14:08:57 ip-10-51-98-26 xrdp-sesman[592]: [INFO ] ++ terminated session:  username user.name, display :10.0, session_pid 1638, ip 10.50.1.112:58878 - socket: 12
Feb 21 14:08:57 ip-10-51-98-26 systemd[1]: session-c3.scope: Deactivated successfully.
Feb 21 14:08:57 ip-10-51-98-26 systemd-logind[627]: Removed session c3.
Feb 21 14:08:58 ip-10-51-98-26 dbus-daemon[1654]: [session uid=1257699886 pid=1654] Successfully activated service 'org.freedesktop.Tracker3.Miner.Files'
Feb 21 14:08:58 ip-10-51-98-26 systemd[1640]: Started Tracker file system data miner.
Feb 21 14:08:58 ip-10-51-98-26 systemd[1640]: Started Tracker metadata extractor.
Feb 21 14:08:58 ip-10-51-98-26 systemd[1640]: Reached target Main User Target.
Feb 21 14:08:58 ip-10-51-98-26 systemd[1640]: Startup finished in 803ms.

xorgxrdp.10.log:

[ 15339.436] 
X.Org X Server 1.21.1.3
X Protocol Version 11, Revision 0
[ 15339.436] Current Operating System: Linux ip-10-51-98-26 5.15.0-1030-aws #34-Ubuntu SMP Mon Jan 23 20:13:32 UTC 2023 x86_64
[ 15339.436] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-5.15.0-1030-aws root=PARTUUID=cbb5015f-ca94-467b-91ae-cce97828a042 ro console=tty1 console=ttyS0 nvme_core.io_timeout=4294967295 panic=-1
[ 15339.436] xorg-server 2:21.1.3-2ubuntu2.7 (For technical support please see http://www.ubuntu.com/support) 
[ 15339.436] Current version of pixman: 0.40.0
[ 15339.436]    Before reporting problems, check http://wiki.x.org
        to make sure that you have the latest version.
[ 15339.436] Markers: (--) probed, (**) from config file, (==) default setting,
        (++) from command line, (!!) notice, (II) informational,
        (WW) warning, (EE) error, (NI) not implemented, (??) unknown.
[ 15339.436] (++) Log file: ".xorgxrdp.10.log", Time: Tue Feb 21 14:08:57 2023
[ 15339.436] (++) Using config file: "/etc/X11/xrdp/xorg.conf"
[ 15339.436] (==) Using system config directory "/usr/share/X11/xorg.conf.d"
[ 15339.436] (**) Option "defaultserverlayout" "X11 Server"
[ 15339.436] (**) ServerLayout "X11 Server"
[ 15339.436] (**) |-->Screen "Screen (xrdpdev)" (0)
[ 15339.436] (**) |   |-->Monitor "Monitor"
[ 15339.439] (**) |   |-->Device "Video Card (xrdpdev)"
[ 15339.439] (**) |-->Input Device "xrdpMouse"
[ 15339.439] (**) |-->Input Device "xrdpKeyboard"
[ 15339.439] (**) Option "DontVTSwitch" "on"
[ 15339.439] (**) Option "AutoAddDevices" "off"
[ 15339.439] (**) Not automatically adding devices
[ 15339.439] (==) Automatically enabling devices
[ 15339.439] (==) Automatically adding GPU devices
[ 15339.439] (==) Automatically binding GPU devices
[ 15339.439] (==) Max clients allowed: 256, resource mask: 0x1fffff
[ 15339.439] (WW) The directory "/usr/share/fonts/X11/cyrillic" does not exist.
[ 15339.439]    Entry deleted from font path.
[ 15339.439] (WW) The directory "/usr/share/fonts/X11/100dpi/" does not exist.
[ 15339.439]    Entry deleted from font path.
[ 15339.439] (WW) The directory "/usr/share/fonts/X11/75dpi/" does not exist.
[ 15339.439]    Entry deleted from font path.
[ 15339.439] (WW) The directory "/usr/share/fonts/X11/Type1" does not exist.
[ 15339.439]    Entry deleted from font path.
[ 15339.439] (WW) The directory "/usr/share/fonts/X11/100dpi" does not exist.
[ 15339.439]    Entry deleted from font path.
[ 15339.439] (WW) The directory "/usr/share/fonts/X11/75dpi" does not exist.
[ 15339.439]    Entry deleted from font path.
[ 15339.439] (==) FontPath set to:
        /usr/share/fonts/X11/misc,
        built-ins
[ 15339.439] (==) ModulePath set to "/usr/lib/xorg/modules"
[ 15339.439] (II) Loader magic: 0x5a681c523020
[ 15339.439] (II) Module ABI versions:
[ 15339.439]    X.Org ANSI C Emulation: 0.4
[ 15339.439]    X.Org Video Driver: 25.2
[ 15339.439]    X.Org XInput driver : 24.4
[ 15339.439]    X.Org Server Extension : 10.0
[ 15339.455] (II) systemd-logind: took control of session /org/freedesktop/login1/session/c3
[ 15339.459] (--) PCI:*(0@0:3:0) 1d0f:1111:0000:0000 rev 0, Mem @ 0xfe400000/4194304, BIOS @ 0x????????/131072
[ 15339.459] (II) "glx" will be loaded. This was enabled by default and also specified in the config file.
[ 15339.459] (II) LoadModule: "dbe"
[ 15339.459] (II) Module "dbe" already built-in
[ 15339.459] (II) LoadModule: "ddc"
[ 15339.459] (II) Module "ddc" already built-in
[ 15339.459] (II) LoadModule: "extmod"
[ 15339.459] (II) Module "extmod" already built-in
[ 15339.459] (II) LoadModule: "glx"
[ 15339.459] (II) Loading /usr/lib/xorg/modules/extensions/libglx.so
[ 15339.465] (II) Module glx: vendor="X.Org Foundation"
[ 15339.465]    compiled for 1.21.1.3, module version = 1.0.0
[ 15339.465]    ABI class: X.Org Server Extension, version 10.0
[ 15339.465] (II) LoadModule: "int10"
[ 15339.465] (II) Loading /usr/lib/xorg/modules/libint10.so
[ 15339.465] (II) Module int10: vendor="X.Org Foundation"
[ 15339.466]    compiled for 1.21.1.3, module version = 1.0.0
[ 15339.466]    ABI class: X.Org Video Driver, version 25.2
[ 15339.466] (II) LoadModule: "record"
[ 15339.466] (II) Module "record" already built-in
[ 15339.466] (II) LoadModule: "vbe"
[ 15339.466] (II) Loading /usr/lib/xorg/modules/libint10.so
[ 15339.466] (II) Module int10: vendor="X.Org Foundation"
[ 15339.466]    compiled for 1.21.1.3, module version = 1.0.0
[ 15339.466]    ABI class: X.Org Video Driver, version 25.2
[ 15339.466] (II) LoadModule: "glamoregl"
[ 15339.466] (II) Loading /usr/lib/xorg/modules/libglamoregl.so
[ 15339.470] (II) Module glamoregl: vendor="X.Org Foundation"
[ 15339.470]    compiled for 1.21.1.3, module version = 1.0.1
[ 15339.470]    ABI class: X.Org ANSI C Emulation, version 0.4
[ 15339.470] (II) LoadModule: "xorgxrdp"
[ 15339.470] (II) Loading /usr/lib/xorg/modules/libxorgxrdp.so
[ 15339.471] (II) Module XORGXRDP: vendor="X.Org Foundation"
[ 15339.471]    compiled for 1.21.1.3, module version = 0.2.17
[ 15339.471]    ABI class: X.Org Video Driver, version 25.2
[ 15339.471] xorgxrdpSetup:
[ 15339.471] (II) LoadModule: "fb"
[ 15339.471] (II) Module "fb" already built-in
[ 15339.471] (II) LoadModule: "xrdpdev"
[ 15339.471] (II) Loading /usr/lib/xorg/modules/drivers/xrdpdev_drv.so
[ 15339.471] (II) Module XRDPDEV: vendor="X.Org Foundation"
[ 15339.471]    compiled for 1.21.1.3, module version = 0.2.17
[ 15339.471]    ABI class: X.Org Video Driver, version 25.2
[ 15339.471] xrdpdevSetup:
[ 15339.472] (II) LoadModule: "xrdpmouse"
[ 15339.472] (II) Loading /usr/lib/xorg/modules/input/xrdpmouse_drv.so
[ 15339.472] (II) Module XRDPMOUSE: vendor="X.Org Foundation"
[ 15339.472]    compiled for 1.21.1.3, module version = 0.2.17
[ 15339.472]    Module class: X.Org XInput Driver
[ 15339.472]    ABI class: X.Org XInput driver, version 24.4
[ 15339.472] rdpmousePlug:
[ 15339.472] (II) LoadModule: "xrdpkeyb"
[ 15339.472] (II) Loading /usr/lib/xorg/modules/input/xrdpkeyb_drv.so
[ 15339.472] (II) Module XRDPKEYB: vendor="X.Org Foundation"
[ 15339.472]    compiled for 1.21.1.3, module version = 0.2.17
[ 15339.472]    Module class: X.Org XInput Driver
[ 15339.472]    ABI class: X.Org XInput driver, version 24.4
[ 15339.472] rdpkeybPlug:
[ 15339.473] rdpIdentify:
[ 15339.473] (II) XRDPDEV: driver for xrdp: XRDPDEV
[ 15339.473] rdpDriverFunc: op 10
[ 15339.473] (WW) Falling back to old probe method for XRDPDEV
[ 15339.473] rdpProbe:
[ 15339.473] (II) Loading sub module "fb"
[ 15339.473] (II) LoadModule: "fb"
[ 15339.473] (II) Module "fb" already built-in
[ 15339.473] rdpProbe: found DRMDevice xorg.conf value [/dev/dri/renderD128]
[ 15339.473] rdpProbe: found DRI3 xorg.conf value [1]
[ 15339.473] (II) XRDPDEV(0): using default device
[ 15339.481] (WW) VGA arbiter: cannot open kernel arbiter, no multi-card support
[ 15339.481] rdpPreInit:
[ 15339.481] rdpPreInit: /dev/dri/renderD128 open failed
[ 15339.481] (**) XRDPDEV(0): Depth 24, (--) framebuffer bpp 32
[ 15339.481] (==) XRDPDEV(0): RGB weight 888
[ 15339.481] (==) XRDPDEV(0): Using gamma correction (1.0, 1.0, 1.0)
[ 15339.481] (==) XRDPDEV(0): Default visual is TrueColor
[ 15339.481] (==) XRDPDEV(0): DPI set to (96, 96)
[ 15339.481] (II) XRDPDEV(0):   mode "640x480" ok
[ 15339.481] (II) XRDPDEV(0):   mode "800x600" ok
[ 15339.481] (II) XRDPDEV(0): Virtual size is 800x600 (pitch 800)
[ 15339.481] (**) XRDPDEV(0):  Default mode "800x600": 36.0 MHz (scaled from 0.0 MHz), 35.2 kHz, 56.2 Hz
[ 15339.481] (II) XRDPDEV(0): Modeline "800x600"x0.0   36.00  800 824 896 1024  600 601 603 625 +hsync +vsync (35.2 kHz d)
[ 15339.481] rdpScreenInit: virtualX 800 virtualY 600 rgbBits 8 depth 24
[ 15339.481] rdpScreenInit: pfbMemory bytes 1920000
[ 15339.481] rdpScreenInit: pfbMemory 0x75cfbe428010
[ 15339.481] rdpSimdInit: assigning yuv functions
[ 15339.481] rdpSimdInit: cpuid ax 1 cx 0 return ax 0x00800f12 bx 0x01040800 cx 0xfed83203 dx 0x178bfbff
[ 15339.481] rdpSimdInit: sse2 amd64 yuv functions assigned
[ 15339.481] (==) XRDPDEV(0): Backing store enabled
[ 15339.481] rdpClientConInit: disconnect idle session after [600] sec
[ 15339.481] rdpClientConInit: kill disconnected [1] timeout [14400] sec
[ 15339.481] rdpXvInit: depth 24
[ 15339.481] rdpScreenInit: out
[ 15339.481] (II) Initializing extension Generic Event Extension
[ 15339.481] (II) Initializing extension SHAPE
[ 15339.482] (II) Initializing extension MIT-SHM
[ 15339.482] (II) Initializing extension XInputExtension
[ 15339.482] (II) Initializing extension XTEST
[ 15339.482] (II) Initializing extension BIG-REQUESTS
[ 15339.482] (II) Initializing extension SYNC
[ 15339.482] (II) Initializing extension XKEYBOARD
[ 15339.483] (II) Initializing extension XC-MISC
[ 15339.483] (II) Initializing extension SECURITY
[ 15339.483] (II) Initializing extension XFIXES
[ 15339.483] (II) Initializing extension RENDER
[ 15339.483] (II) Initializing extension RANDR
[ 15339.483] (II) Initializing extension COMPOSITE
[ 15339.484] (II) Initializing extension DAMAGE
[ 15339.484] (II) Initializing extension MIT-SCREEN-SAVER
[ 15339.484] (II) Initializing extension DOUBLE-BUFFER
[ 15339.484] (II) Initializing extension RECORD
[ 15339.484] (II) Initializing extension DPMS
[ 15339.484] (II) Initializing extension Present
[ 15339.484] (II) Initializing extension DRI3
[ 15339.484] (II) Initializing extension X-Resource
[ 15339.485] (II) Initializing extension XVideo
[ 15339.485] (II) Initializing extension XVideo-MotionCompensation
[ 15339.485] (II) Initializing extension SELinux
[ 15339.485] (II) SELinux: Disabled on system
[ 15339.485] (II) Initializing extension GLX
[ 15339.489] (II) AIGLX: Screen 0 is not DRI2 capable
[ 15339.536] (II) IGLX: Loaded and initialized swrast
[ 15339.536] (II) GLX: Initialized DRISWRAST GL provider for screen 0
[ 15339.536] (II) Initializing extension XFree86-VidModeExtension
[ 15339.536] (II) Initializing extension XFree86-DGA
[ 15339.536] (II) Initializing extension XFree86-DRI
[ 15339.536] (II) Initializing extension DRI2
[ 15339.537] rdpCreateScreenResources:
[ 15339.574] (II) Using input driver 'XRDPMOUSE' for 'xrdpMouse'
[ 15339.574] (**) Option "CorePointer"
[ 15339.574] (**) xrdpMouse: always reports core events
[ 15339.574] rdpmousePreInit: drv 0x5a681d834750 info 0x5a681d99d6a0, flags 0x0
[ 15339.574] (II) XINPUT: Adding extended input device "xrdpMouse" (type: Mouse, id 6)
[ 15339.574] rdpmouseControl: what 0
[ 15339.574] rdpmouseDeviceInit:
[ 15339.574] rdpmouseCtrl:
[ 15339.574] rdpRegisterInputCallback: type 1 proc 0x75cfbeaff3b0
[ 15339.574] (**) xrdpMouse: (accel) keeping acceleration scheme 1
[ 15339.574] (**) xrdpMouse: (accel) acceleration profile 0
[ 15339.574] (**) xrdpMouse: (accel) acceleration factor: 2.000
[ 15339.574] (**) xrdpMouse: (accel) acceleration threshold: 4
[ 15339.574] rdpmouseControl: what 1
[ 15339.574] rdpmouseDeviceOn:
[ 15339.574] (II) Using input driver 'XRDPKEYB' for 'xrdpKeyboard'
[ 15339.574] (**) Option "CoreKeyboard"
[ 15339.574] (**) xrdpKeyboard: always reports core events
[ 15339.574] rdpkeybPreInit: drv 0x5a681d833ec0 info 0x5a681db27850, flags 0x0
[ 15339.574] (II) XINPUT: Adding extended input device "xrdpKeyboard" (type: Keyboard, id 7)
[ 15339.574] rdpkeybControl: what 0
[ 15339.574] rdpkeybDeviceInit:
[ 15339.603] rdpkeybChangeKeyboardControl:
[ 15339.603] rdpkeybChangeKeyboardControl: autoRepeat on
[ 15339.603] rdpRegisterInputCallback: type 0 proc 0x75cfbeaf9d50
[ 15339.603] rdpkeybControl: what 1
[ 15339.603] rdpkeybDeviceOn:
[ 15339.614] (II) config/udev: Adding input device Power Button (/dev/input/event0)
[ 15339.614] (II) AutoAddDevices is off - not adding device.
[ 15339.617] (II) config/udev: Adding input device Sleep Button (/dev/input/event1)
[ 15339.617] (II) AutoAddDevices is off - not adding device.
[ 15339.618] (II) config/udev: Adding input device AT Translated Set 2 keyboard (/dev/input/event2)
[ 15339.618] (II) AutoAddDevices is off - not adding device.
[ 15339.619] (II) config/udev: Adding input device ImPS/2 Generic Wheel Mouse (/dev/input/event3)
[ 15339.619] (II) AutoAddDevices is off - not adding device.
[ 15339.620] (II) config/udev: Adding input device ImPS/2 Generic Wheel Mouse (/dev/input/mouse0)
[ 15339.620] (II) AutoAddDevices is off - not adding device.
[ 15339.630] rdpDeferredRandR:
[ 15339.630] rdpResizeSession: width 1024 height 768
[ 15339.631]   calling RRScreenSizeSet
[ 15339.631] rdpRRScreenSetSize: width 1024 height 768 mmWidth 271 mmHeight 203
[ 15339.632] rdpRRGetInfo:
[ 15339.632]   screen resized to 1024x768
[ 15339.636]   RRScreenSizeSet ok 1
[ 15339.636] rdpResizeSession: width 1512 height 944
[ 15339.636]   calling RRScreenSizeSet
[ 15339.636] rdpRRScreenSetSize: width 1512 height 944 mmWidth 400 mmHeight 250
[ 15339.636] rdpRRGetInfo:
[ 15339.636]   screen resized to 1512x944
[ 15339.641]   RRScreenSizeSet ok 1
[ 15339.641] rdpClientConGotConnection:
[ 15339.641] rdpClientConGotConnection: g_sck_accept ok new_sck 9
[ 15339.641] rdpClientConGetConnection: engaging idle timer, timeout [600] sec
[ 15339.641] rdpAddClientConToDev: adding first clientCon 0x5a681db65310
[ 15339.641] rdpClientConProcessMsgVersion: version 0 0 0 1
[ 15339.641] rdpClientConProcessScreenSizeMsg: set width 1512 height 944 bpp 16
[ 15339.641] rdpClientConProcessScreenSizeMsg: shmemid 1 shmemptr 0x75cfb26d1000
[ 15339.641] rdpClientConProcessMsgClientInput: invalidate x 0 y 0 cx 1512 cy 944
[ 15339.686] rdpClientConProcessMsgClientInfo:
[ 15339.686]   got client info bytes 7072
[ 15339.686]   jpeg support 0
[ 15339.686]   offscreen support 0
[ 15339.686]   offscreen size 0
[ 15339.686]   offscreen entries 0
[ 15339.686]   client can not do offscreen to offscreen blits
[ 15339.686]   client can do new(color) cursor
[ 15339.686]   client can not do multimon
[ 15339.686] rdpRRSetRdpOutputs: numCrtcs 0 numOutputs 0 monitorCount 0
[ 15339.686] rdpRRSetRdpOutputs: add output 0 left 0 top 0 width 1512 height 944
[ 15339.686] rdpLoadLayout: keylayout 0x00000000 variant  display 10
[ 15339.686] rdpkeybChangeKeyboardControl:
[ 15339.686] rdpkeybChangeKeyboardControl: autoRepeat on
[ 15339.687] rdpkeybChangeKeyboardControl:
[ 15339.687] rdpkeybChangeKeyboardControl: autoRepeat on
[ 15339.705] rdpInDeferredRepeatCallback:
[ 15339.705] rdpkeybChangeKeyboardControl:
[ 15339.705] rdpkeybChangeKeyboardControl: autoRepeat off
[ 15339.739] rdpmouseControl: what 2
[ 15339.739] rdpmouseDeviceOff:
[ 15339.739] rdpkeybControl: what 2
[ 15339.739] rdpkeybDeviceOff:
[ 15339.740] rdpkeybControl: what 3
[ 15339.741] rdpkeybUnInit: drv 0x5a681d833ec0 info 0x5a681db27850, flags 0x0
[ 15339.741] rdpUnregisterInputCallback: proc 0x75cfbeaf9d50
[ 15339.741] rdpmouseControl: what 3
[ 15339.741] rdpmouseUnInit: drv 0x5a681d834750 info 0x5a681d99d6a0, flags 0x0
[ 15339.741] rdpUnregisterInputCallback: proc 0x75cfbeaff3b0
[ 15339.742] rdpCloseScreen:
[ 15339.742] xorgxrdpDownDown:
[ 15339.742] xorgxrdpDownDown: 1
[ 15339.742] rdpClientConDeinit:
[ 15339.742] rdpClientConDeinit: disconnecting clientCon
[ 15339.742] rdpClientConDisconnect:
[ 15339.742] rdpClientConDisconnect: disconnected, idle timer disengaged
[ 15339.742] rdpClientConDisconnect: engaging disconnect timer, exit after 14400 seconds
[ 15339.742] rdpRemoveClientConFromDev: removing clientCon 0x5a681db65310
[ 15339.747] rdpClientConDeinit: deleting file /run/xrdp/sockdir/xrdp_display_10
[ 15339.747] rdpClientConDeinit: deleting file /run/xrdp/sockdir/xrdp_disconnect_display_10
[ 15339.749] (II) Server terminated successfully (0). Closing log file.

xsession-errors oddly did not update during the session attempt.

[matt335672]

Well the X server looks fine. The problem seems to be with the session.

Based on what you've said earlier, and the xsession-errors not updating, it seems possible the problem is down to something in /etc/profile or /etc/profile.d which isn't expecting to be run with a login shell of sh. This is something we've seen before.

Lets try to get some logging from /etc/xrdp/startwm.sh. If you edit that and add these lines near the top:-

set -x
exec >/tmp/startwm_`id -un`.log 2>&1

you should get a log file called /tmp/startwm_user.name.log which may help.

[blaizmannen]

Contents of /tmp/startwm_user.name.log

+ . /etc/environment
+ https_proxy=http://127.0.0.1:3128
+ http_proxy=http://127.0.0.1:3128
+ PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin
+ test -r /etc/profile
+ . /etc/profile
+ [ $  ]
+ [  ]
+ id -u
+ [ 1257699886 -eq 0 ]
+ PS1=$ 
+ [ -d /etc/profile.d ]
+ [ -r /etc/profile.d/01-locale-fix.sh ]
+ . /etc/profile.d/01-locale-fix.sh
+ /usr/bin/locale-check C.UTF-8
+ eval
+ [ -r /etc/profile.d/Z97-byobu.sh ]
+ . /etc/profile.d/Z97-byobu.sh
+ [ -r /usr/bin/byobu-launch ]
+ [  = 0 ]
+ [  = 1 ]
+ [ -e /etc/byobu/autolaunch ]
+ [  = byobu ]
+ [  = byobu-screen ]
+ [  = byobu-tmux ]
+ [ -r /etc/profile.d/Z99-cloud-locale-test.sh ]
+ . /etc/profile.d/Z99-cloud-locale-test.sh
+ [ -f /home/user.name/.cloud-locale-test.skip -o -f /var/lib/cloud/instance/locale-check.skip ]
+ locale
+ locale_warn
+ command -v local
+ local _local=local
+ local bad_names= bad_lcs= key= val= var= vars= bad_kv=
+ local w1 w2 w3 w4 remain
+ [  = zsh ]
+ read -r w1 w2 w3 w4 remain
+ key=LANG
+ val=C.UTF-8
+ val=C.UTF-8
+ val=C.UTF-8
+ vars= LANG=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LANGUAGE
+ val=
+ val=
+ val=
+ vars= LANG=C.UTF-8 LANGUAGE=
+ read -r w1 w2 w3 w4 remain
+ key=LC_CTYPE
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_NUMERIC
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_TIME
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_COLLATE
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_MONETARY
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_MESSAGES
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8 LC_MESSAGES=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_PAPER
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8 LC_MESSAGES=C.UTF-8 LC_PAPER=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_NAME
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8 LC_MESSAGES=C.UTF-8 LC_PAPER=C.UTF-8 LC_NAME=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_ADDRESS
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8 LC_MESSAGES=C.UTF-8 LC_PAPER=C.UTF-8 LC_NAME=C.UTF-8 LC_ADDRESS=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_TELEPHONE
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8 LC_MESSAGES=C.UTF-8 LC_PAPER=C.UTF-8 LC_NAME=C.UTF-8 LC_ADDRESS=C.UTF-8 LC_TELEPHONE=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_MEASUREMENT
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8 LC_MESSAGES=C.UTF-8 LC_PAPER=C.UTF-8 LC_NAME=C.UTF-8 LC_ADDRESS=C.UTF-8 LC_TELEPHONE=C.UTF-8 LC_MEASUREMENT=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_IDENTIFICATION
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8 LC_MESSAGES=C.UTF-8 LC_PAPER=C.UTF-8 LC_NAME=C.UTF-8 LC_ADDRESS=C.UTF-8 LC_TELEPHONE=C.UTF-8 LC_MEASUREMENT=C.UTF-8 LC_IDENTIFICATION=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_ALL
+ val=
+ val=
+ val=
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8 LC_MESSAGES=C.UTF-8 LC_PAPER=C.UTF-8 LC_NAME=C.UTF-8 LC_ADDRESS=C.UTF-8 LC_TELEPHONE=C.UTF-8 LC_MEASUREMENT=C.UTF-8 LC_IDENTIFICATION=C.UTF-8 LC_ALL=
+ read -r w1 w2 w3 w4 remain
+ bad_lcs=
+ bad_kv=
+ [ -n  ]
+ return 0
+ unset locale_warn
+ [ -r /etc/profile.d/Z99-cloudinit-warnings.sh ]
+ . /etc/profile.d/Z99-cloudinit-warnings.sh
+ cloud_init_warnings
+ command -v local
+ local _local=local
+ local warning= idir=/var/lib/cloud/instance n=0
+ local warndir=/var/lib/cloud/instance/warnings
+ local ufile=/home/user.name/.cloud-warnings.skip sfile=/var/lib/cloud/instance/warnings/.skip
+ [ -d /var/lib/cloud/instance/warnings ]
+ return 0
+ unset cloud_init_warnings
+ [ -r /etc/profile.d/apps-bin-path.sh ]
+ . /etc/profile.d/apps-bin-path.sh
+ snap_bin_path=/snap/bin
+ [ -n  ]
+ [ -z  ]
+ export XDG_DATA_DIRS=/usr/local/share:/usr/share
+ snap_xdg_path=/var/lib/snapd/desktop
+ [ -n /usr/local/share:/usr/share ]
+ [ -n /usr/local/share:/usr/share ]
+ export XDG_DATA_DIRS=/usr/local/share:/usr/share:/var/lib/snapd/desktop
+ [ -r /etc/profile.d/autologout.sh ]
+ . /etc/profile.d/autologout.sh
+ TMOUT=900
+ readonly TMOUT
+ export TMOUT
+ [ -r /etc/profile.d/bash_completion.sh ]
+ . /etc/profile.d/bash_completion.sh
+ [ x != x -a x$  != x -a x = x ]
+ [ -r /etc/profile.d/cedilla-portuguese.sh ]
+ . /etc/profile.d/cedilla-portuguese.sh
+ [  = pt -a C.UTF-8 != pt ]
+ [ -r /etc/profile.d/gawk.sh ]
+ . /etc/profile.d/gawk.sh
+ [ -r /etc/profile.d/gnome-session_gnomerc.sh ]
+ . /etc/profile.d/gnome-session_gnomerc.sh
+ [ x11 = wayland ]
+ [ -z /usr/local/share:/usr/share:/var/lib/snapd/desktop ]
+ [ -n /usr/local/share:/usr/share:/var/lib/snapd/desktop ]
+ XDG_DATA_DIRS=/usr/share/gnome:/usr/local/share:/usr/share:/var/lib/snapd/desktop
+ export XDG_DATA_DIRS
+ [ -r /etc/profile.d/im-config_wayland.sh ]
+ . /etc/profile.d/im-config_wayland.sh
+ [ x11 != wayland ]
+ return
+ [ -r /etc/profile.d/pinerolo_profile.sh ]
+ [ -r /etc/profile.d/vte-2.91.sh ]
+ . /etc/profile.d/vte-2.91.sh
+ [ -n  -o -n  ]
+ return 0
+ [ -r /etc/profile.d/xdg_dirs_desktop_session.sh ]
+ . /etc/profile.d/xdg_dirs_desktop_session.sh
+ DEFAULT_XDG_CONFIG_DIRS=/etc/xdg
+ DEFAULT_XDG_DATA_DIRS=/usr/local/share/:/usr/share/
+ [ -n  ]
+ unset i
+ test -r /etc/default/locale
+ . /etc/default/locale
+ LANG=C.UTF-8
+ test -z x
+ export LANG
+ test -z 
+ test -z 
+ test -z 
+ test -z 
+ test -z 
+ test -z 
+ test -z 
+ test -z 
+ test -z 
+ test -z 
+ test -z 
+ test -z 
+ test -z 
+ test -z 
+ test -z 
+ test -r /etc/profile
+ . /etc/profile
+ [ $  ]
+ [  ]
+ id -u
+ [ 1257699886 -eq 0 ]
+ PS1=$ 
+ [ -d /etc/profile.d ]
+ [ -r /etc/profile.d/01-locale-fix.sh ]
+ . /etc/profile.d/01-locale-fix.sh
+ /usr/bin/locale-check C.UTF-8
+ eval
+ [ -r /etc/profile.d/Z97-byobu.sh ]
+ . /etc/profile.d/Z97-byobu.sh
+ [ -r /usr/bin/byobu-launch ]
+ [  = 0 ]
+ [  = 1 ]
+ [ -e /etc/byobu/autolaunch ]
+ [  = byobu ]
+ [  = byobu-screen ]
+ [  = byobu-tmux ]
+ [ -r /etc/profile.d/Z99-cloud-locale-test.sh ]
+ . /etc/profile.d/Z99-cloud-locale-test.sh
+ [ -f /home/user.name/.cloud-locale-test.skip -o -f /var/lib/cloud/instance/locale-check.skip ]
+ locale
+ locale_warn
+ command -v local
+ local _local=local
+ local bad_names= bad_lcs= key= val= var= vars= bad_kv=
+ local w1 w2 w3 w4 remain
+ [  = zsh ]
+ read -r w1 w2 w3 w4 remain
+ key=LANG
+ val=C.UTF-8
+ val=C.UTF-8
+ val=C.UTF-8
+ vars= LANG=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LANGUAGE
+ val=
+ val=
+ val=
+ vars= LANG=C.UTF-8 LANGUAGE=
+ read -r w1 w2 w3 w4 remain
+ key=LC_CTYPE
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_NUMERIC
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_TIME
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_COLLATE
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_MONETARY
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_MESSAGES
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8 LC_MESSAGES=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_PAPER
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8 LC_MESSAGES=C.UTF-8 LC_PAPER=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_NAME
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8 LC_MESSAGES=C.UTF-8 LC_PAPER=C.UTF-8 LC_NAME=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_ADDRESS
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8 LC_MESSAGES=C.UTF-8 LC_PAPER=C.UTF-8 LC_NAME=C.UTF-8 LC_ADDRESS=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_TELEPHONE
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8 LC_MESSAGES=C.UTF-8 LC_PAPER=C.UTF-8 LC_NAME=C.UTF-8 LC_ADDRESS=C.UTF-8 LC_TELEPHONE=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_MEASUREMENT
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8 LC_MESSAGES=C.UTF-8 LC_PAPER=C.UTF-8 LC_NAME=C.UTF-8 LC_ADDRESS=C.UTF-8 LC_TELEPHONE=C.UTF-8 LC_MEASUREMENT=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_IDENTIFICATION
+ val="C.UTF-8"
+ val=C.UTF-8"
+ val=C.UTF-8
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8 LC_MESSAGES=C.UTF-8 LC_PAPER=C.UTF-8 LC_NAME=C.UTF-8 LC_ADDRESS=C.UTF-8 LC_TELEPHONE=C.UTF-8 LC_MEASUREMENT=C.UTF-8 LC_IDENTIFICATION=C.UTF-8
+ read -r w1 w2 w3 w4 remain
+ key=LC_ALL
+ val=
+ val=
+ val=
+ vars= LANG=C.UTF-8 LANGUAGE= LC_CTYPE=C.UTF-8 LC_NUMERIC=C.UTF-8 LC_TIME=C.UTF-8 LC_COLLATE=C.UTF-8 LC_MONETARY=C.UTF-8 LC_MESSAGES=C.UTF-8 LC_PAPER=C.UTF-8 LC_NAME=C.UTF-8 LC_ADDRESS=C.UTF-8 LC_TELEPHONE=C.UTF-8 LC_MEASUREMENT=C.UTF-8 LC_IDENTIFICATION=C.UTF-8 LC_ALL=
+ read -r w1 w2 w3 w4 remain
+ bad_lcs=
+ bad_kv=
+ [ -n  ]
+ return 0
+ unset locale_warn
+ [ -r /etc/profile.d/Z99-cloudinit-warnings.sh ]
+ . /etc/profile.d/Z99-cloudinit-warnings.sh
+ cloud_init_warnings
+ command -v local
+ local _local=local
+ local warning= idir=/var/lib/cloud/instance n=0
+ local warndir=/var/lib/cloud/instance/warnings
+ local ufile=/home/user.name/.cloud-warnings.skip sfile=/var/lib/cloud/instance/warnings/.skip
+ [ -d /var/lib/cloud/instance/warnings ]
+ return 0
+ unset cloud_init_warnings
+ [ -r /etc/profile.d/apps-bin-path.sh ]
+ . /etc/profile.d/apps-bin-path.sh
+ snap_bin_path=/snap/bin
+ [ -n  ]
+ [ -z /usr/share/gnome:/usr/local/share:/usr/share:/var/lib/snapd/desktop ]
+ snap_xdg_path=/var/lib/snapd/desktop
+ [ -n  ]
+ [ -r /etc/profile.d/autologout.sh ]
+ . /etc/profile.d/autologout.sh
/etc/xrdp/startwm.sh: 1: /etc/profile.d/autologout.sh: TMOUT: is read only

[matt335672]

What's in /etc/profile.d/autologout.sh? That looks like a site special.

[blaizmannen]

root@ip-10-51-98-26:/home/ubuntu# cat /etc/profile.d/autologout.sh 
TMOUT=900
readonly TMOUT
export TMOUT
root@ip-10-51-98-26:/home/ubuntu# ls -l /etc/profile.d/autologout.sh 
-rwxr-xr-x 1 root root 38 Feb 14 01:36 /etc/profile.d/autologout.sh

[matt335672]

I think (somehow) /etc/profile.d/autologout.sh is being read twice. It's not suitable for this, as this example shows:-

$ cd /tmp
$ cat >autologout.sh
TMOUT=900
readonly TMOUT
export TMOUT
$ sh
$ . ./autologout.sh
$ . ./autologout.sh
sh: 1: ./autologout.sh: TMOUT: is read only

You can either check your login scripts to figure out why, or modify autologout.sh:-

if [ -z "$TMOUT" ]; then
    TMOUT=900
    readonly TMOUT
    export TMOUT
fi

[blaizmannen]

@matt335672 , did the above fix, and there was no change.

[blaizmannen]

ok nevermind, that seems to have fixed the problem.

I saw that .xsession-errors was having this error message:

dbus-update-activation-environment: error: unable to connect to D-Bus: /usr/bin/dbus-launch terminated abnormally without any error message

** (process:1077): WARNING **: 02:26:09.818: Could not make bus activated clients aware of XDG_CURRENT_DESKTOP=GNOME environment variable: Failed to execute child process ?dbus-launch? (No such file or directory)
Xsession: X session started for john.cada at Thu Feb 23 05:59:18 UTC 2023
dbus-update-activation-environment: error: unable to connect to D-Bus: /usr/bin/dbus-launch terminated abnormally without any error message
localuser:john.cada being added to access control list
dbus-update-activation-environment: error: unable to connect to D-Bus: /usr/bin/dbus-launch terminated abnormally without any error message
dbus-update-activation-environment: error: unable to connect to D-Bus: /usr/bin/dbus-launch terminated abnormally without any error message

** (process:1672): WARNING **: 05:59:18.932: Could not make bus activated clients aware of XDG_CURRENT_DESKTOP=GNOME environment variable: Failed to execute child process ?dbus-launch? (No such file or directory)

This was after I reverted the changes from autologout.sh. I proceeded to apply the fix again and voila, it worked.

This was the log in .xsession-errors when it worked:

Xsession: X session started for john.cada at Thu Feb 23 06:25:22 UTC 2023
dbus-update-activation-environment: setting DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1257699886/bus
dbus-update-activation-environment: setting DISPLAY=:10.0
localuser:john.cada being added to access control list
dbus-update-activation-environment: setting GTK_MODULES=gail:atk-bridge
dbus-update-activation-environment: setting QT_ACCESSIBILITY=1
dbus-update-activation-environment: setting USER=john.cada
dbus-update-activation-environment: setting XDG_SESSION_TYPE=x11
dbus-update-activation-environment: setting HOME=/home/john.cada
dbus-update-activation-environment: setting GTK_MODULES=gail:atk-bridge
dbus-update-activation-environment: setting XRDP_PULSE_SINK_SOCKET=xrdp_chansrv_audio_out_socket_10
dbus-update-activation-environment: setting XRDP_PULSE_SOURCE_SOCKET=xrdp_chansrv_audio_in_socket_10
dbus-update-activation-environment: setting DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1257699886/bus
dbus-update-activation-environment: setting PULSE_SCRIPT=/etc/xrdp/pulse/default.pa
dbus-update-activation-environment: setting https_proxy=http://127.0.0.1:3128
dbus-update-activation-environment: setting IM_CONFIG_PHASE=1
dbus-update-activation-environment: setting LOGNAME=john.cada
dbus-update-activation-environment: setting http_proxy=http://127.0.0.1:3128
dbus-update-activation-environment: setting XDG_SESSION_CLASS=user
dbus-update-activation-environment: setting PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin
dbus-update-activation-environment: setting XRDP_SOCKET_PATH=/run/xrdp/sockdir
dbus-update-activation-environment: setting XDG_RUNTIME_DIR=/run/user/1257699886
dbus-update-activation-environment: setting DISPLAY=:10.0
dbus-update-activation-environment: setting LANG=C.UTF-8
dbus-update-activation-environment: setting UID=1257699886
dbus-update-activation-environment: setting SHELL=/bin/bash
dbus-update-activation-environment: setting QT_ACCESSIBILITY=1
dbus-update-activation-environment: setting XRDP_SESSION=1
dbus-update-activation-environment: setting GPG_AGENT_INFO=/run/user/1257699886/gnupg/S.gpg-agent:0:1
dbus-update-activation-environment: setting PWD=/home/john.cada
dbus-update-activation-environment: setting XDG_DATA_DIRS=/usr/share/gnome:/usr/local/share:/usr/share:/var/lib/snapd/desktop
dbus-update-activation-environment: setting TMOUT=900

Thanks for the help. I request to keep this issue open for a little longer while I check that the fix is really stable.

[matt335672]

That's fine. I'll close it in a couple of weeks if nothing more is added.

You need to find out where autologout.sh is coming from - it looks like a site special to me.

[blaizmannen]

Hi @matt335672, this definitely still works as a solution. I just have some questions:

I believe that the default contents of startwm.sh is as such:

#!/bin/sh
# xrdp X session start script (c) 2015, 2017 mirabilos
# published under The MirOS Licence

if test -r /etc/profile; then
    . /etc/profile
fi

if test -r /etc/default/locale; then
    . /etc/default/locale
    test -z "${LANG+x}" || export LANG
    test -z "${LANGUAGE+x}" || export LANGUAGE
    test -z "${LC_ADDRESS+x}" || export LC_ADDRESS
    test -z "${LC_ALL+x}" || export LC_ALL
    test -z "${LC_COLLATE+x}" || export LC_COLLATE
    test -z "${LC_CTYPE+x}" || export LC_CTYPE
    test -z "${LC_IDENTIFICATION+x}" || export LC_IDENTIFICATION
    test -z "${LC_MEASUREMENT+x}" || export LC_MEASUREMENT
    test -z "${LC_MESSAGES+x}" || export LC_MESSAGES
    test -z "${LC_MONETARY+x}" || export LC_MONETARY
    test -z "${LC_NAME+x}" || export LC_NAME
    test -z "${LC_NUMERIC+x}" || export LC_NUMERIC
    test -z "${LC_PAPER+x}" || export LC_PAPER
    test -z "${LC_TELEPHONE+x}" || export LC_TELEPHONE
    test -z "${LC_TIME+x}" || export LC_TIME
    test -z "${LOCPATH+x}" || export LOCPATH
fi

if test -r /etc/profile; then
    . /etc/profile
fi

test -x /etc/X11/Xsession && exec /etc/X11/Xsession
exec /bin/sh /etc/X11/Xsession

where . /etc/profile is being ran twice. Why is there a need to do so? During the earlier investigations, I did notice that removing one of them would cause undesirable behaviours 🤔

[matt335672]

That file is nothing to do with us - it's part of the xrdp Debian Package where thid has been reported and fixed:-

https://salsa.debian.org/debian-remote-team/xrdp/-/commit/6901a93cd9d6f83a671307821aedded4389f1292