search

neutrinolabs / xrdp

xrdp: an open source RDP server
http://www.xrdp.org/
Apache License 2.0
5.59k stars 1.73k forks source link

VNC error - problem connecting after "VNC sending framebuffer update request" #1519

Closed zhiqiuhu closed 1 month ago

zhiqiuhu commented 4 years ago

Hi Linux Experts,

I followed the step in the following instruction to enable remote desktop access from windows 10 to centos 8 https://www.itzgeek.com/how-tos/linux/centos-how-tos/how-to-install-xrdp-on-red-hat-enterprise-linux-8.html

However, the following error was encountered when try to login from windows client. Not sure what to do for the next step. Please let me know if other information is needed to investigate the issue. Any suggestion will be appreciated!

Remote desktop from win10 to centos8 by xRDP

Command 'systemctl status xrdp' generated the following content 

`[root@t1650-centos8 ~]# systemctl status xrdp
● xrdp.service - xrdp daemon
   Loaded: loaded (/usr/lib/systemd/system/xrdp.service; enabled; vendor preset: disabled)
   Active: active (running) since Wed 2020-03-18 13:11:47 MDT; 10min ago
     Docs: man:xrdp(8)
           man:xrdp.ini(5)
 Main PID: 29802 (xrdp)
    Tasks: 2 (limit: 26213)
   Memory: 14.8M
   CGroup: /system.slice/xrdp.service
           ├─29802 /usr/sbin/xrdp --nodaemon
           └─29804 /usr/sbin/xrdp --nodaemon
Mar 18 13:11:59 t1650-centos8 xrdp[29804]: (29804)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC receiving name
Mar 18 13:11:59 t1650-centos8 xrdp[29804]: (29804)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC sending pixel format
Mar 18 13:11:59 t1650-centos8 xrdp[29804]: (29804)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC sending encodings
Mar 18 13:11:59 t1650-centos8 xrdp[29804]: (29804)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC sending framebuffer update request
Mar 18 13:11:59 t1650-centos8 xrdp[29804]: (29804)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC error - problem connecting
Mar 18 13:11:59 t1650-centos8 xrdp[29804]: (29804)(140246638015936)[DEBUG] Closed socket 19 (AF_INET 127.0.0.1:39562)
Mar 18 13:11:59 t1650-centos8 xrdp[29804]: (29804)(140246638015936)[DEBUG] xrdp_wm_log_msg: some problem
Mar 18 13:11:59 t1650-centos8 xrdp[29804]: (29804)(140246638015936)[DEBUG] xrdp_mm_module_cleanup
Mar 18 13:11:59 t1650-centos8 xrdp[29804]: (29804)(140246638015936)[DEBUG] VNC mod_exit
Mar 18 13:11:59 t1650-centos8 xrdp[29804]: (29804)(140246638015936)[DEBUG] Closed socket 18 (AF_INET 127.0.0.1:46968)`
matt335672 commented 4 years ago

Looking at the code, it seems this happens if the Xvnc server disappears while XRDP is talking to it.

This is normally caused by the session failing to start correctly. When the session stops, the Xvnc is killed and that could be causing the above error.

Have you get a desktop installed and working normally on the machine? Is there a graphical login prompt?

Also, on CentOS, the following command dumps out the system journal over the last minute:-

sudo journalctl -S -1m

If you could try to log in and then immediately post the output of the above command, that could be useful.

zhiqiuhu commented 4 years ago

@matt335672 Thank you very much for the help.

The linux system do has graph desktop installed, unless you mean something XRDP specific. FYI, I have also tried to login from this linux computer to other windows 10 computers via xrdp and it works smoothly.

The following are the information provided by sudo journalctl -S -1m. 

[myadminuser@t1650-centos8 ~]$ sudo journalctl -S -1m
-- Logs begin at Sun 2020-03-15 12:44:31 MDT, end at Thu 2020-03-19 08:30:42 MDT. --
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:22:54] [INFO ] Loading keymap file /etc/xrdp/km-00000409.ini
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:22:54] [WARN ] local keymap file for 0x00000409 found and doesn't match built in keymap, using local keymap file
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_wm_login_mode_changed: login_mode is 0
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: in xrdp_wm_init:
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_wm_init: channel rdpdr channel id 0 is allowed
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_wm_init: channel rdpsnd channel id 1 is allowed
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_wm_init: channel cliprdr channel id 2 is allowed
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_wm_init: channel drdynvc channel id 3 is allowed
Mar 19 08:30:27 t1650-centos8 xrdp[29802]:    xrdp_wm_init: no autologin / auto run detected, draw login window
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp:xrdp_cache [4079809577]: xrdp_cache_add_bitmap: reset detected cache_id 2
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp:xrdp_cache [4079809578]: xrdp_cache_add_bitmap: reset detected cache_id 1
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: out xrdp_wm_init:
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_wm_login_mode_changed: login_mode is 1
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: drdynvc_process_capability_response: DVC version 3 selected
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp:xrdp_mm [4079809578]: xrdp_mm_drdynvc_up:
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_wm_login_mode_changed: login_mode is 2
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:45] [DEBUG] xrdp_wm_log_msg: connecting to sesman ip 127.0.0.1 port 3350
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: connecting to sesman ip 127.0.0.1 port 3350
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:45] [INFO ] xrdp_wm_log_msg: sesman connect ok
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: sesman connect ok
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:45] [DEBUG] xrdp_wm_log_msg: sending login info to session manager, please wait...
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: sending login info to session manager, please wait...
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:45] [DEBUG] return value from xrdp_mm_connect 0
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_wm_login_mode_changed: login_mode is 3
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:45] [INFO ] xrdp_wm_log_msg: login successful for display 10
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: login successful for display 10
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: loaded module 'libvnc.so' ok, interface size 4040, version 4
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:45] [DEBUG] xrdp_wm_log_msg: VNC started connecting
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: VNC started connecting
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:45] [DEBUG] xrdp_wm_log_msg: VNC connecting to 127.0.0.1 5910
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: VNC connecting to 127.0.0.1 5910
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:45] [DEBUG] xrdp_wm_log_msg: VNC tcp connected
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: VNC tcp connected
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:45] [DEBUG] xrdp_wm_log_msg: VNC security level is 2 (1 = none, 2 = standard)
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: VNC security level is 2 (1 = none, 2 = standard)
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:45] [DEBUG] xrdp_wm_log_msg: VNC password ok
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: VNC password ok
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:45] [DEBUG] xrdp_wm_log_msg: VNC sending share flag
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: VNC sending share flag
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:46] [DEBUG] xrdp_wm_log_msg: VNC receiving server init
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: VNC receiving server init
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:46] [DEBUG] xrdp_wm_log_msg: VNC receiving pixel format
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: VNC receiving pixel format
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:46] [DEBUG] xrdp_wm_log_msg: VNC receiving name length
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: VNC receiving name length
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:46] [DEBUG] xrdp_wm_log_msg: VNC receiving name
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: VNC receiving name
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:46] [DEBUG] xrdp_wm_log_msg: VNC sending pixel format
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: VNC sending pixel format
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:46] [DEBUG] xrdp_wm_log_msg: VNC sending encodings
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: VNC sending encodings
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: got XRDP SIGPIPE(13)
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:46] [DEBUG] xrdp_wm_log_msg: VNC error - problem connecting
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: VNC error - problem connecting
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:46] [DEBUG] Closed socket 19 (AF_INET 127.0.0.1:32814)
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:46] [DEBUG] xrdp_wm_log_msg: some problem
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: some problem
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:46] [DEBUG] xrdp_mm_module_cleanup
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:46] [DEBUG] VNC mod_exit
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: [20200319-08:23:46] [DEBUG] Closed socket 18 (AF_INET 127.0.0.1:40220)
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_wm_login_mode_changed: login_mode is 11
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_rdp_process_suppress: allowDisplayUpdates 0 bytes left 3
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_rdp_process_suppress: suppress_output 1
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp:xrdp_mm [4079865426]: xrdp_mm_suppress_output: suppress 1 left 0 top 0 right 0 bottom 0
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_rdp_process_screen_update: num_rects 1
Mar 19 08:30:27 t1650-centos8 xrdp[29802]:   left 0 top 0 right 1918 bottom 1198
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_rdp_process_suppress: allowDisplayUpdates 1 bytes left 11
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_rdp_process_suppress: suppress_output 0 left 0 top 0 right 1920 bottom 1200
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp:xrdp_mm [4080248671]: xrdp_mm_suppress_output: suppress 0 left 0 top 0 right 1920 bottom 1200
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_wm_login_mode_changed: login_mode is 0
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: in xrdp_wm_init:
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_wm_init: channel rdpdr channel id 0 is allowed
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_wm_init: channel rdpsnd channel id 1 is allowed
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_wm_init: channel cliprdr channel id 2 is allowed
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_wm_init: channel drdynvc channel id 3 is allowed
Mar 19 08:30:27 t1650-centos8 xrdp[29802]:    xrdp_wm_init: no autologin / auto run detected, draw login window
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: out xrdp_wm_init:
Mar 19 08:30:27 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] xrdp_wm_log_msg: connecting to sesman ip 127.0.0.1 port 3350
Mar 19 08:30:27 t1650-centos8 xrdp[29802]: xrdp_wm_login_mode_changed: login_mode is 1
Mar 19 08:30:27 t1650-centos8 xrdp-sesman[29801]: (29801)(140422369433408)[INFO ] A connection received from 127.0.0.1 port 41520
Mar 19 08:30:27 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[INFO ] xrdp_wm_log_msg: sesman connect ok
Mar 19 08:30:27 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] xrdp_wm_log_msg: sending login info to session manager, please wait...
Mar 19 08:30:27 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] return value from xrdp_mm_connect 0
Mar 19 08:30:27 t1650-centos8 xrdp-sesman[29801]: (29801)(140422369433408)[INFO ] ++ created session (access granted): username myadminuser, ip 142.244.108.82:58919 - socket: 12
Mar 19 08:30:27 t1650-centos8 xrdp-sesman[29801]: (29801)(140422369433408)[INFO ] starting Xvnc session...
Mar 19 08:30:27 t1650-centos8 xrdp-sesman[29801]: (29801)(140422369433408)[DEBUG] Closed socket 12 (AF_INET 0.0.0.0:5910)
Mar 19 08:30:27 t1650-centos8 xrdp-sesman[29801]: (29801)(140422369433408)[DEBUG] Closed socket 12 (AF_INET 0.0.0.0:6010)
Mar 19 08:30:27 t1650-centos8 xrdp-sesman[29801]: (29801)(140422369433408)[DEBUG] Closed socket 12 (AF_INET 0.0.0.0:6210)
Mar 19 08:30:27 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[INFO ] xrdp_wm_log_msg: login successful for display 10
Mar 19 08:30:27 t1650-centos8 xrdp-sesman[29801]: (29801)(140422369433408)[DEBUG] Closed socket 10 (AF_INET 127.0.0.1:3350)
Mar 19 08:30:27 t1650-centos8 xrdp-sesman[10967]: (10967)(140422369433408)[INFO ] calling auth_start_session from pid 10967
Mar 19 08:30:28 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC started connecting
Mar 19 08:30:28 t1650-centos8 systemd-logind[2572]: New session c22 of user myadminuser.
Mar 19 08:30:28 t1650-centos8 systemd[1]: Started Session c22 of user myadminuser.
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[10967]: pam_unix(xrdp-sesman:session): session opened for user myadminuser by (uid=0)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[10967]: (10967)(140422369433408)[DEBUG] Closed socket 9 (AF_INET 127.0.0.1:3350)
Mar 19 08:30:28 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC connecting to 127.0.0.1 5910
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[10967]: (10967)(140422369433408)[DEBUG] Closed socket 10 (AF_INET 127.0.0.1:3350)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[10980]: (10980)(140422369433408)[INFO ] Xvnc :10 -auth .Xauthority -geometry 1920x1200 -depth 32 -rfbauth /home/myadminuser/.vnc/sesman_passwd-myadminuser@t1650-centos8:10 -bs -nolisten tcp -localhost ->
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: Xvnc TigerVNC 1.9.0 - built Feb  4 2020 16:05:33
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: Copyright (C) 1999-2018 TigerVNC Team and many others (see README.rst)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: See http://www.tigervnc.org for information on TigerVNC.
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: Underlying X server release 12003000, The X.Org Foundation
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: Thu Mar 19 08:30:28 2020
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:  vncext:      VNC extension running!
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:  vncext:      Listening for VNC connections on local interface(s), port 5910
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:  vncext:      created VNC server for screen 0
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:  Connections: accepted: 127.0.0.1::34116
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[10967]: (10967)(140422369433408)[CORE ] waiting for window manager (pid 10979) to exit
Mar 19 08:30:28 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC tcp connected
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:  SConnection: Client needs protocol version 3.3
Mar 19 08:30:28 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC security level is 2 (1 = none, 2 = standard)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:  VNCSConnST:  Server default pixel format depth 32 (32bpp) little-endian rgb
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:               max 255,255,255 shift 16,8,0
Mar 19 08:30:28 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC password ok
Mar 19 08:30:28 t1650-centos8 gnome-session[10979]: gnome-session-binary[10979]: WARNING: software acceleration check failed: Child process exited with code 1
Mar 19 08:30:28 t1650-centos8 gnome-session-binary[10979]: WARNING: software acceleration check failed: Child process exited with code 1
Mar 19 08:30:28 t1650-centos8 gnome-session[10979]: Unable to init server: Could not connect: Connection refused
Mar 19 08:30:28 t1650-centos8 gnome-session-f[11115]: Cannot open display:
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[10967]: (10967)(140422369433408)[CORE ] window manager (pid 10979) did exit, cleaning up session
Mar 19 08:30:28 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC sending share flag
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[10967]: (10967)(140422369433408)[INFO ] calling auth_stop_session and auth_end from pid 10967
Mar 19 08:30:28 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC receiving server init
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[10967]: pam_unix(xrdp-sesman:session): session closed for user myadminuser
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[10967]: (10967)(140422369433408)[DEBUG] cleanup_sockets:
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: starting sesman in foreground...
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: Global configuration:
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     ListenAddress:            127.0.0.1
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     ListenPort:               3350
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     EnableUserWindowManager:  1
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     UserWindowManager:        startwm.sh
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     DefaultWindowManager:     /usr/libexec/xrdp/startwm-bash.sh
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     ReconnectScript:          /usr/libexec/xrdp/reconnectwm.sh
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     AuthFilePath:             disabled
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: Session configuration:
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     MaxSessions:              50
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     X11DisplayOffset:         10
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     KillDisconnected:         0
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     IdleTimeLimit:            0
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     DisconnectedTimeLimit:    0
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     Policy:                   0
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: Security configuration:
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     AllowRootLogin:           1
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     MaxLoginRetry:            4
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     AlwaysGroupCheck:         0
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     RestrictOutboundClipboard: 0
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     TSUsersGroup:             (not defined)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     TSAdminsGroup:            (not defined)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: Xorg parameters:
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     Parameter 00              Xorg
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     Parameter 01              -config
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     Parameter 02              xrdp/xorg.conf
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     Parameter 03              -noreset
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     Parameter 04              -nolisten
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     Parameter 05              tcp
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     Parameter 06              -logfile
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     Parameter 07              .xorgxrdp.%s.log
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: Xvnc parameters:
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     Parameter 00              Xvnc
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     Parameter 01              -bs
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     Parameter 02              -nolisten
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     Parameter 03              tcp
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     Parameter 04              -localhost
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     Parameter 05              -dpi
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     Parameter 06              96
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: SessionVariables parameters:
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:     Parameter 00              PULSE_SCRIPT=/etc/xrdp/pulse/default.pa
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: logging configuration:
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:         LogFile:       /var/log/xrdp-sesman.log
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:         LogLevel:      4
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:         EnableSyslog:  1
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:         SyslogLevel:   4
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200318-13:11:47] [DEBUG] libscp initialized
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200318-13:11:47] [INFO ] starting xrdp-sesman with pid 29801
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200318-13:11:47] [INFO ] listening to port 3350 on 127.0.0.1
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200318-13:11:58] [INFO ] A connection received from 127.0.0.1 port 46968
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200318-13:11:58] [INFO ] ++ created session (access granted): username myadminuser, ip 142.244.108.82:57921 - socket: 12
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200318-13:11:59] [INFO ] starting Xvnc session...
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200318-13:11:59] [DEBUG] Closed socket 12 (AF_INET 0.0.0.0:5910)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200318-13:11:59] [DEBUG] Closed socket 12 (AF_INET 0.0.0.0:6010)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200318-13:11:59] [DEBUG] Closed socket 12 (AF_INET 0.0.0.0:6210)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200318-13:11:59] [DEBUG] Closed socket 10 (AF_INET 127.0.0.1:3350)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200318-13:11:59] [INFO ] ++ terminated session:  username myadminuser, display :10.0, session_pid 29816, ip 142.244.108.82:57921 - socket: 12
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:23:45] [INFO ] A connection received from 127.0.0.1 port 40220
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:23:45] [INFO ] ++ created session (access granted): username myadminuser, ip 142.244.108.82:58919 - socket: 12
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:23:45] [INFO ] starting Xvnc session...
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:23:45] [DEBUG] Closed socket 12 (AF_INET 0.0.0.0:5910)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:23:45] [DEBUG] Closed socket 12 (AF_INET 0.0.0.0:6010)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:23:45] [DEBUG] Closed socket 12 (AF_INET 0.0.0.0:6210)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:23:45] [DEBUG] Closed socket 10 (AF_INET 127.0.0.1:3350)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:23:46] [INFO ] ++ terminated session:  username myadminuser, display :10.0, session_pid 10652, ip 142.244.108.82:58919 - socket: 12
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:27] [INFO ] A connection received from 127.0.0.1 port 41520
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:27] [INFO ] ++ created session (access granted): username myadminuser, ip 142.244.108.82:58919 - socket: 12
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:27] [INFO ] starting Xvnc session...
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:27] [DEBUG] Closed socket 12 (AF_INET 0.0.0.0:5910)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:27] [DEBUG] Closed socket 12 (AF_INET 0.0.0.0:6010)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:27] [DEBUG] Closed socket 12 (AF_INET 0.0.0.0:6210)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:27] [INFO ] calling auth_start_session from pid 10967
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:28] [DEBUG] Closed socket 9 (AF_INET 127.0.0.1:3350)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:28] [DEBUG] Closed socket 10 (AF_INET 127.0.0.1:3350)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:28] [CORE ] waiting for window manager (pid 10979) to exit
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:28] [CORE ] window manager (pid 10979) did exit, cleaning up session
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:28] [INFO ] calling auth_stop_session and auth_end from pid 10967
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:28] [DEBUG] cleanup_s Connections: closed: 127.0.0.1::34116 (Server shutdown)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:  EncodeManager: Framebuffer updates: 0
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:  EncodeManager:   Total: 0 rects, 0 pixels
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:  EncodeManager:          0 B (1:-nan ratio)
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:  ComparingUpdateTracker: 0 pixels in / 0 pixels out
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]:  ComparingUpdateTracker: (1:-nan ratio)
Mar 19 08:30:28 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC receiving pixel format
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[10967]: (10967)(140422369433408)[DEBUG] cleanup_sockets: deleting /tmp/.xrdp/xrdp_chansrv_socket_10
Mar 19 08:30:28 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC receiving name length
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[10967]: (10967)(140422369433408)[DEBUG] cleanup_sockets: failed to delete /tmp/.xrdp/xrdp_chansrv_socket_10
Mar 19 08:30:28 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC receiving name
Mar 19 08:30:28 t1650-centos8 xrdp-sesman[29801]: (29801)(140422369433408)[INFO ] ++ terminated session:  username myadminuser, display :10.0, session_pid 10967, ip 142.244.108.82:58919 - socket: 12
Mar 19 08:30:28 t1650-centos8 systemd-logind[2572]: Session c22 logged out. Waiting for processes to exit.
Mar 19 08:30:29 t1650-centos8 xrdp-sesman[29801]: chansrv::main: using log file [/home/myadminuser/.local/share/xrdp/xrdp-chansrv.10.log]
Mar 19 08:30:29 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:28] [CORE ] main: app started pid 11026(0x00002b12)
Mar 19 08:30:29 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:28] [INFO ] main: DISPLAY env var set to :10.0
Mar 19 08:30:29 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:28] [INFO ] main: using DISPLAY 10
Mar 19 08:30:29 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:28] [INFO ] channel_thread_loop: thread start
Mar 19 08:30:29 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:28] [INFO ] term_signal_handler: got signal 15
Mar 19 08:30:29 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:28] [INFO ] channel_thread_loop: g_term_event set
Mar 19 08:30:29 t1650-centos8 xrdp-sesman[29801]: xrdp-chansrv [4080263951]: scard_deinit:
Mar 19 08:30:29 t1650-centos8 xrdp-sesman[29801]: chansrv:smartcard_pcsc [4080263951]: scard_pcsc_deinit:
Mar 19 08:30:29 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:28] [INFO ] channel_thread_loop: thread stop
Mar 19 08:30:29 t1650-centos8 xrdp-sesman[29801]: [20200319-08:30:28] [INFO ] main: app exiting pid 11026(0x00002b12)
Mar 19 08:30:29 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC sending pixel format
Mar 19 08:30:29 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC sending encodings
Mar 19 08:30:29 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] xrdp_wm_log_msg: VNC error - problem connecting
Mar 19 08:30:29 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] Closed socket 19 (AF_INET 127.0.0.1:34116)
Mar 19 08:30:29 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] xrdp_wm_log_msg: some problem
Mar 19 08:30:29 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] xrdp_mm_module_cleanup
Mar 19 08:30:29 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] VNC mod_exit
Mar 19 08:30:29 t1650-centos8 xrdp[10555]: (10555)(140246638015936)[DEBUG] Closed socket 18 (AF_INET 127.0.0.1:41520)
Mar 19 08:30:37 t1650-centos8 sudo[10921]: pam_unix(sudo:session): session closed for user root
Mar 19 08:30:38 t1650-centos8 systemd-logind[2572]: Removed session c22.
Mar 19 08:30:42 t1650-centos8 sudo[11163]:  myadminuser : TTY=pts/0 ; PWD=/home/myadminuser ; USER=root ; COMMAND=/bin/journalctl -S -1m
Mar 19 08:30:42 t1650-centos8 sudo[11163]: pam_systemd(sudo:session): Cannot create session: Already running in a session or user slice
Mar 19 08:30:42 t1650-centos8 sudo[11163]: pam_unix(sudo:session): session opened for user root by myadminuser(uid=0)
zhiqiuhu commented 4 years ago

@matt335672 Forgot to mention that the graphic desktop on the linux computer is Gnome.

matt335672 commented 4 years ago

That's useful - thanks.

I think the problem might be here:-

gnome-session-binary[10979]: WARNING: software acceleration check failed: Child process exited with code 1

The Xvnc server needs to provide the GLX extension, otherwise GNOME is unable to function. It's possible something is interfering with that.

Do you by any chance have an NVidia driver installed? This caused problems with VNC on CentOS 7, but I don't know what the CentOS 8 situation is. See (for example):-

https://serverfault.com/questions/877930/cant-get-vnc-server-to-work-in-centos-7-4

If you do have an NVidia driver installed, I'd suggest temporarily removing that to see what happens.

Failing that, you can check for the GLX extension by running the following commands in separate windows. The first command starts an Xvnc server, and the second command queries that server. You might need to install the xorg-x11-utils RPM to get the xdpyinfo command.

Window 1

Xvnc -ac :11

Window 2

xdpyinfo -display :11 | less

About 20 lines down the xdpyinfo output you should get a list of the extensions supported by the X server. I get the following on a CentOS 8 VM:-

number of extensions:    23
    BIG-REQUESTS
    Composite
    DAMAGE
    DOUBLE-BUFFER
    GLX
    Generic Event Extension
    MIT-SCREEN-SAVER
    MIT-SHM
    Present
    RANDR
    RECORD
    RENDER
    SHAPE
    SYNC
    VNC-EXTENSION
    X-Resource
    XC-MISC
    XFIXES
    XINERAMA
    XInputExtension
    XKEYBOARD
    XTEST
    XVideo

The important thing for GNOME is that GLX is present.

zhiqiuhu commented 4 years ago

@matt335672 Thank you very much. It indeed have NVidia driver installed because the default driver (xorg-x11-drv-nouveau) does not support a software that is required by the server.

As I do not have access to the linux server by now, I will try you suggestions once I get connected. Thank you very much for help. I really appreciate your time and efforts to help with the matters.

zhiqiuhu commented 4 years ago

@matt335672 Seems GLX was installed. Please see the following for the list.

number of extensions: 23 BIG-REQUESTS Composite DAMAGE DOUBLE-BUFFER GLX Generic Event Extension MIT-SCREEN-SAVER MIT-SHM Present RANDR RECORD RENDER SHAPE SYNC VNC-EXTENSION X-Resource XC-MISC XFIXES XINERAMA XInputExtension XKEYBOARD XTEST XVideo default screen number: 0 number of screens: 1

matt335672 commented 4 years ago

That's interesting - thanks.

It seems somewhat contrary to my suspicions about the NVidia driver, but at the moment I don't have any other ideas. Also, I no longer work in an environment where I have NVidia cards lying about to play with.

If you get a chance to remove the driver and try it, that would be useful. Also, can you add some info to the thread about:-

zhiqiuhu commented 4 years ago

@matt335672 The following is the information. Please let me know if there is any other information will be useful. 

[myadminuser@t1650-centos8 Downloads]$ lspci -vnn | grep VGA
01:00.0 VGA compatible controller [0300]: NVIDIA Corporation GT218 [NVS 300] [10de:10d8] (rev a2) (prog-if 00 [VGA controller])

It was directly installed with following driver downloaded from 

https://www.nvidia.com/Download/driverResults.aspx/156163/en-us

NVIDIA-Linux-x86_64-340.108.run P1 p2 FYI, the reason for me to install the NVIDIA driver is that the nouveau driver does not compatible with the RStudio Desktop software. With the previous driver, the software will quit at a few seconds after it started. After the new driver installed and the nouveau driver disabled on the computer, the RStudio software works fine, and seems the new driver also provided much smooth graphics comparing with the old one.

matt335672 commented 4 years ago

I've had a look at the above, and I'm pretty sure the problem is with the NVidia driver, or its installation. There's a shim called libglvnd which should make all of this stuff work properly. It's possible that during installation the interface to this was not built successfully.

I can install the NVidia package above with --no-kernel-module on a working VM. This gives exactly the same problem as was posted, but as the installation is incomplete, it's hard to take this any further.

In general, for RHEL/CentOS it's a good idea to install the NVidia driver from either elrepo.org or the RPM fusion nonfree-updates repository. Both of these package up the driver in a way which makes the installation foolproof. However, the NVS300 needs the 340.xx version driver which is quite old now. Both the repos above have the driver for CentOS 7, but not for CentOS 8.

After all that, I can offer you two possibilities which are worth trying:-

Other more disruptive possibilities are:-

It's difficult for me to suggest anything else at present as I don't have suitable hardware to reproduce this.

zhiqiuhu commented 4 years ago

@matt335672 Thank you very much for the provide such detailed explanations and guidelines. Really appreciate your time and efforts with my issues. Regards.