Hi everyone!

i think i got a little bit farther by disabling channels in the xrdp.ini
file.
but now starting the X11rdp session occasionaly times out. im having a hard
time investigating here because i can not reproduce the issue and after
some time it starts working again. so now i found something that might be
an indicator why the X11rdp session times out.

so first, connecting to sesman, channels are disabled:

10:52:45.205 srv1 XRDP[932] info: (932)(140270395066304)[INFO ] A
connection received from: 192.xx.xx.xx port 52860
10:52:45.206 srv1 XRDP[932] info: (932)(140270395066304)[INFO ] An
established connection closed to endpoint: 192.xx.xx.xx:52860 - socket: 8
10:52:45.206 srv1 XRDP[13257] info: (13257)(140270395066304)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 7
10:52:45.207 srv1 XRDP[13257] debug: (13257)(140270395066304)[DEBUG] Info -
All channels are disabled
10:52:45.240 srv1 XRDP[13257] info: (13257)(140270395066304)[INFO ] An
established connection closed to endpoint: 192.xx.xx.xx:52860 - socket: 8
10:52:45.355 srv1 XRDP[932] info: (932)(140270395066304)[INFO ] A
connection received from: 192.xx.xx.xx port 52861
10:52:45.355 srv1 XRDP[932] info: (932)(140270395066304)[INFO ] An
established connection closed to endpoint: 192.xx.xx.xx:52861 - socket: 8
10:52:45.356 srv1 XRDP[13258] info: (13258)(140270395066304)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 7
10:52:45.356 srv1 XRDP[13258] debug: (13258)(140270395066304)[DEBUG] Info -
All channels are disabled
10:52:45.800 srv1 XRDP[13258] debug: (13258)(140270395066304)[DEBUG]
xrdp_000033ca_wm_login_mode_event_00000001
10:52:45.805 srv1 XRDP[13258] warning: (13258)(140270395066304)[WARN ]
local keymap file for 0x0409 found and dosen't match built in keymap, using
local keymap file


then logging in with ldap user, some connection at socket 12 is tried to be
closed some times

10:53:20.142 srv1 XRDP-sesman[936] info: (936)(139898864416704)[INFO ] A
connection received from: 127.0.0.1 port 60721
10:53:20.142 srv1 XRDP[13258] debug: (13258)(140270395066304)[DEBUG]
returnvalue from xrdp_mm_connect 0
10:53:20.142 srv1 XRDP-sesman[936] info: (936)(139898864416704)[INFO ] scp
thread on sck 8 started successfully
10:53:20.144 srv1 XRDP-sesman[936] notice: pam_unix(xrdp-sesman:auth):
authentication failure; logname= uid=0 euid=0 tty= ruser= rhost=  user=usr1
10:53:20.152 srv1 XRDP-sesman[936] info: (936)(139898864195328)[INFO ] ++
created session (access granted): username usr1, ip 192.xx.xx.xx:52861 -
socket: 8
10:53:20.152 srv1 XRDP-sesman[936] info: (936)(139898864195328)[INFO ]
starting X11rdp session...
10:53:20.152 srv1 XRDP-sesman[936] info: (936)(139898864416704)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 14
10:53:20.152 srv1 XRDP-sesman[936] info: (936)(139898864416704)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 14
10:53:20.153 srv1 XRDP-sesman[936] info: (936)(139898864416704)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 14
10:53:20.153 srv1 XRDP-sesman[936] info: (936)(139898864195328)[INFO ] An
established connection closed to endpoint: 127.0.0.1:60721 - socket: 8
10:53:20.153 srv1 XRDP-sesman[14290] info: (14290)(139898864416704)[INFO ]
An established connection closed to endpoint: NULL:NULL - socket: 7
10:53:20.154 srv1 XRDP-sesman[14290] info: (14290)(139898864416704)[INFO ]
An established connection closed to endpoint: 127.0.0.1:60721 - socket: 8
10:53:20.154 srv1 XRDP[13258] info: (13258)(140270395066304)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 12
10:53:20.155 srv1 XRDP-sesman[14293] info: (14293)(139898864416704)[INFO ]
X11rdp start:X11rdp :10 -geometry 1024x768 -depth 24 -bs -ac -nolisten tcp
-uds
10:53:20.654 srv1 XRDP[13258] info: (13258)(140270395066304)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 12
10:53:21.154 srv1 XRDP[13258] info: (13258)(140270395066304)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 12


then the X11rdp session timeout is logged and connection at socket 12 ist
tried to be closed some more

10:53:29.159 srv1 XRDP[13258] info: (13258)(140270395066304)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 12
10:53:29.659 srv1 XRDP[13258] info: (13258)(140270395066304)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 12
10:53:29.918 srv1 XRDP-sesman[14290] err: (14290)(139898864416704)[ERROR] X
server for display 10 startup timeout
10:53:29.918 srv1 XRDP-sesman[14290] info: (14290)(139898864416704)[INFO ]
starting xrdp-sessvc - xpid=14293 - wmpid=14292
10:53:30.159 srv1 XRDP-sesman[14292] err: (14292)(139898864416704)[ERROR] X
server for display 10 startup timeout
10:53:30.159 srv1 XRDP[13258] info: (13258)(140270395066304)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 12
10:53:30.160 srv1 XRDP-sesman[14292] info: pam_unix(xrdp-sesman:session):
session opened for user usr1 by (uid=0)
10:53:30.162 srv1 XRDP-sesman[14799] err: (14799)(139898864416704)[ERROR]
another Xserver might already be active on display 10 - see log
10:53:30.162 srv1 XRDP-sesman[14799] debug: (14799)(139898864416704)[DEBUG]
aborting connection...
10:53:30.162 srv1 XRDP-sesman[14292] info: pam_unix(xrdp-sesman:session):
session closed for user usr1
10:53:30.164 srv1 XRDP-sesman[936] info: (936)(139898864416704)[INFO ] ++
terminated session:  username usr1, display :10.0, session_pid 14290, ip
192.xx.xx.xx:52861 - socket: 8
10:53:30.660 srv1 XRDP[13258] info: (13258)(140270395066304)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 12
10:53:31.160 srv1 XRDP[13258] info: (13258)(140270395066304)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 12


and finally, it gives up

10:53:29.159 srv1 XRDP[13258] info: (13258)(140270395066304)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 12
10:53:29.659 srv1 XRDP[13258] info: (13258)(140270395066304)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 12
10:53:29.918 srv1 XRDP-sesman[14290] err: (14290)(139898864416704)[ERROR] X
server for display 10 startup timeout
10:53:29.918 srv1 XRDP-sesman[14290] info: (14290)(139898864416704)[INFO ]
starting xrdp-sessvc - xpid=14293 - wmpid=14292
10:53:30.159 srv1 XRDP-sesman[14292] err: (14292)(139898864416704)[ERROR] X
server for display 10 startup timeout
10:53:30.159 srv1 XRDP[13258] info: (13258)(140270395066304)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 12
10:53:30.160 srv1 XRDP-sesman[14292] info: pam_unix(xrdp-sesman:session):
session opened for user usr1 by (uid=0)
10:53:30.162 srv1 XRDP-sesman[14799] err: (14799)(139898864416704)[ERROR]
another Xserver might already be active on display 10 - see log
10:53:30.162 srv1 XRDP-sesman[14799] debug: (14799)(139898864416704)[DEBUG]
aborting connection...
10:53:30.162 srv1 XRDP-sesman[14292] info: pam_unix(xrdp-sesman:session):
session closed for user usr1
10:53:30.164 srv1 XRDP-sesman[936] info: (936)(139898864416704)[INFO ] ++
terminated session:  username usr1, display :10.0, session_pid 14290, ip
192.xx.xx.xx:52861 - socket: 8
10:53:30.660 srv1 XRDP[13258] info: (13258)(140270395066304)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 12
10:53:31.160 srv1 XRDP[13258] info: (13258)(140270395066304)[INFO ] An
established connection closed to endpoint: NULL:NULL - socket: 12


i then looked into the process list and didn't see any X11rdp process so i
tried to start one by hand. it didn't start with the following errors.
after some time the command worked, the X11rdp session started. I stopped
it on the command line and tried via win7 rdp client and it worked again.

[root@srv1 ~]# X11rdp :10 -geometry 1024x768 -depth 24 -bs -ac -nolisten
tcp -uds
InitOutput:

X11rdp, an X server for xrdp
Version 0.7.0
Copyright (C) 2005-2012 Jay Sorg
See http://xrdp.sf.net for information on xrdp.
Underlying X server release 10903000, The X.Org Foundation
Screen width 1024 height 768 depth 24 bpp 32
dpix 100 dpiy 100
buffer size 3145728
rdpup_init: g_tcp_local_bind failed
rdpScreenInit: rdpup_init failed
pRRScrPriv 0x244f820
rdpScreenInit: ret 0

Fatal server error:
Couldn't add screen

ddxGiveUp:


Can anyone give me a hint where i have to look for those X11rdp startup
errors?


BR,
Lionel


---------- Forwarded message ----------
From: Lionel Rocket <achterla...@gmail.com>
Date: Tue, Dec 16, 2014 at 3:58 PM
Subject: Re: reconnection to X11rdp occasionally fails
To: xrdp-devel@lists.sourceforge.net


Hello,

Another occurrence now shows failing connects:

2014-12-16T15:33:42.274+01:00 srv1 XRDP-sesman[8213] info:
(8213)(140565769365440)[INFO ] A connection received from: 127.0.0.1 port
43983
2014-12-16T15:33:42.274+01:00 srv1 XRDP[7005] debug:
(7005)(140019385399232)[DEBUG] returnvalue from xrdp_mm_connect 0
2014-12-16T15:33:42.274+01:00 srv1 XRDP-sesman[8213] info:
(8213)(140565769365440)[INFO ] scp thread on sck 8 started successfully
2014-12-16T15:33:42.276+01:00 srv1 XRDP-sesman[8213] notice:
pam_unix(xrdp-sesman:auth): authentication failure; logname= uid=0 euid=0
tty= ruser= rhost=  user=usr1
2014-12-16T15:33:42.284+01:00 srv1 XRDP-sesman[8213] info:
(8213)(140565769144064)[INFO ] ++ reconnected session: username usr1,
display :12.0, session_pid 15489, ip 172.xx.xx.xx:56097 - socket: 8
2014-12-16T15:33:42.285+01:00 srv1 XRDP-sesman[8213] info:
(8213)(140565769144064)[INFO ] An established connection closed to
endpoint: 127.0.0.1:43983 - socket: 8
2014-12-16T15:33:42.286+01:00 srv1 XRDP[7005] info:
(7005)(140019385399232)[INFO ] lib_mod_log_peer: xrdp_pid=7005 connected to
X11rdp_pid=15491 X11rdp_uid=20991 X11rdp_gid=2991 client_ip=172.xx.xx.xx
client_port=56097
2014-12-16T15:33:43.286+01:00 srv1 XRDP[7005] err:
(7005)(140019385399232)[ERROR] xrdp_mm_connect_chansrv: connect failed
trying again...
2014-12-16T15:33:43.286+01:00 srv1 XRDP[7005] info:
(7005)(140019385399232)[INFO ] An established connection closed to
endpoint: NULL:NULL - socket: 13
2014-12-16T15:33:44.287+01:00 srv1 XRDP[7005] err:
(7005)(140019385399232)[ERROR] xrdp_mm_connect_chansrv: connect failed
trying again...
2014-12-16T15:33:44.287+01:00 srv1 XRDP[7005] info:
(7005)(140019385399232)[INFO ] An established connection closed to
endpoint: NULL:NULL - socket: 13
2014-12-16T15:33:45.287+01:00 srv1 XRDP[7005] err:
(7005)(140019385399232)[ERROR] xrdp_mm_connect_chansrv: connect failed
trying again...
2014-12-16T15:33:45.287+01:00 srv1 XRDP[7005] info:
(7005)(140019385399232)[INFO ] An established connection closed to
endpoint: NULL:NULL - socket: 13
2014-12-16T15:33:46.288+01:00 srv1 XRDP[7005] err:
(7005)(140019385399232)[ERROR] xrdp_mm_connect_chansrv: connect failed
trying again...
2014-12-16T15:33:46.288+01:00 srv1 XRDP[7005] err:
(7005)(140019385399232)[ERROR] xrdp_mm_connect_chansrv: error
intrans_connect chan
2014-12-16T15:33:46.288+01:00 srv1 XRDP[7005] info:
(7005)(140019385399232)[INFO ] An established connection closed to
endpoint: 127.0.0.1:3350 - socket: 11

any ideas that i can try?


BR,
Lionel


On Mon, Dec 15, 2014 at 3:28 PM, Lionel Rocket <achterla...@gmail.com>
wrote:
>
> Hello,
>
> I'm having problems reconnecting to X11rdp sessions. I can't reproduce
> this but its occurring quite often.
>
> Log output shows only the following lines, it finds the correct X11rdp
> session on display 11 but after that it immediately closes the connection
> and the Microsoft RDP client freezes.
>
> 2014-12-15T12:42:33.759+01:00 srv1 XRDP-sesman[8213] info:
> (8213)(140565769365440)[INFO ] A connection received from: 127.0.0.1 port
> 46705
> 2014-12-15T12:42:33.759+01:00 srv1 XRDP[25753] debug:
> (25753)(140019385399232)[DEBUG] returnvalue from xrdp_mm_connect 0
> 2014-12-15T12:42:33.759+01:00 srv1 XRDP-sesman[8213] info:
> (8213)(140565769365440)[INFO ] scp thread on sck 8 started successfully
> 2014-12-15T12:42:33.760+01:00 srv1 XRDP-sesman[8213] notice:
> pam_unix(xrdp-sesman:auth): authentication failure; logname= uid=0 euid=0
> tty= ruser= rhost=  user=usr1
> 2014-12-15T12:42:33.769+01:00 srv1 XRDP-sesman[8213] info:
> (8213)(140565769144064)[INFO ] ++ reconnected session: username usr1,
> display :11.0, session_pid 3526, ip 172.16.51.68:50662 - socket: 8
> 2014-12-15T12:42:33.770+01:00 srv1 XRDP-sesman[8213] info:
> (8213)(140565769144064)[INFO ] An established connection closed to
> endpoint: 127.0.0.1:46705 - socket: 8
>
> The process tree looks like this:
>
>     1  8209  8194  1670 ?           -1 S       0:00 /usr/sbin/xrdp
>  8209  3500  8194  1670 ?           -1 S       0:30  \_ /usr/sbin/xrdp
>  8209 13964  8194  1670 ?           -1 S       0:00  \_ /usr/sbin/xrdp
>     1  8213  8194  1670 ?           -1 S       0:00 /usr/sbin/xrdp-sesman
>  8213  3526  8194  1670 ?           -1 S       0:00  \_
> /usr/sbin/xrdp-sessvc 3528 3527
>  3526  3527  8194  1670 ?           -1 S       0:00      \_
> /usr/sbin/xrdp-sesman
>  3527  3531  8194  1670 ?           -1 S       0:00      |   \_
> /usr/bin/ck-xinit-session /usr/bin/ssh-agent /etc/X11/xinit/Xclients
>  3531  3579  8194  1670 ?           -1 Sl      0:00      |       \_
> /usr/bin/gnome-session
>  3579  3580  3580  3580 ?           -1 Ss      0:00      |           \_
> /usr/bin/ssh-agent /etc/X11/xinit/Xclients
>  3579  3607  8194  1670 ?           -1 S       0:00      |           \_
> metacity
>  3579  3612  8194  1670 ?           -1 S       0:02      |           \_
> gnome-panel
>  3579  3622  8194  1670 ?           -1 S       0:15      |           \_
> nautilus
>  3579  3628  8194  1670 ?           -1 S       0:00      |           \_
> /usr/libexec/polkit-gnome-authentication-agent-1
>  3526  3528  8194  1670 ?           -1 S       0:44      \_ X11rdp :11
> -geometry 1600x900 -depth 24 -bs -ac -nolisten tcp -uds
>  3526  3532  8194  1670 ?           -1 Sl      0:00      \_ xrdp-chansrv
>
>
> xrdp.ini content looks like this
>
> [globals]
> # xrdp.ini file version number
> ini_version=1
>
> bitmap_cache=yes
> bitmap_compression=yes
> port=3389
> crypt_level=high
> allow_channels=true
> max_bpp=24
> fork=yes
>
> # regulate if the listening socket use socket option tcp_nodelay
> # no buffering will be performed in the TCP stack
> tcp_nodelay=yes
>
> # regulate if the listening socket use socket option keepalive
> # if the network connection disappear without close messages the
> connection will be closed
> tcp_keepalive=yes
>
> #tcp_send_buffer_bytes=32768
> #tcp_recv_buffer_bytes=32768
>
> # when true, userid/password *must* be passed on cmd line
> # require_credentials=yes
>
> bulk_compression=yes
>
> # You can set the PAM error text in a gateway setup (MAX 256 chars)
> #pamerrortxt=change your password according to policy at http://url
> #new_cursors=no
> #nego_sec_layer=0
> allow_multimon=true
>
> #
> # configure login screen
> #
>
> # top level window background color in RGB format
> ls_top_window_bg_color=003399
>
> # width and height of login screen
> ls_width=350
> ls_height=430
>
> # login screen background color in RGB format
> ls_bg_color=ffffff
>
> # logo
> ls_logo_filename=/usr/share/logo.bmp
> ls_logo_x_pos=35
> ls_logo_y_pos=55
>
> # for positioning labels such as username, password etc
> ls_label_x_pos=30
> ls_label_width=60
>
> # for positioning text and combo boxes next to above labels
> ls_input_x_pos=110
> ls_input_width=210
>
> # y pos for first label and combo box
> ls_input_y_pos=220
>
> # OK button
> ls_btn_ok_x_pos=142
> ls_btn_ok_y_pos=370
> ls_btn_ok_width=85
> ls_btn_ok_height=30
>
> # Cancel button
> ls_btn_cancel_x_pos=237
> ls_btn_cancel_y_pos=370
> ls_btn_cancel_width=85
> ls_btn_cancel_height=30
>
> [Logging]
> EnableSyslog=1
> SyslogLevel=DEBUG
>
> [channels]
> # Channel names not listed here will be blocked by XRDP.
> # You can block any channel by setting its value to false.
> # IMPORTANT! All channels are not supported in all use
> # cases even if you set all values to true.
> # You can override these settings on each session type
> # These settings are only used if allow_channels=true
> rdpdr=true
> rdpsnd=true
> drdynvc=true
> cliprdr=true
> rail=true
> xrdpvr=true
>
> # for debugging xrdp, in section xrdp1, change port=-1 to this:
> # port=/tmp/.xrdp/xrdp_display_10
>
> # for debugging xrdp, add following line to section xrdp1
> # chansrvport=/tmp/.xrdp/xrdp_chansrv_socket_7210
>
> [xrdp1]
> name=sesman-X11rdp
> lib=libxup.so
> username=ask
> password=ask
> ip=127.0.0.1
> port=-1
> xserverbpp=24
> code=10
>
> # You can override the common channel settings for each session type
> #channel.rdpdr=true
> #channel.rdpsnd=true
> #channel.drdynvc=true
> #channel.cliprdr=true
> #channel.rail=true
> #channel.xrdpvr=true
>
>
> Content of sesman.ini:
>
> [Globals]
> ListenAddress=127.0.0.1
> ListenPort=3350
> EnableUserWindowManager=1
> UserWindowManager=startwm.sh
> DefaultWindowManager=startwm.sh
>
> [Security]
> AllowRootLogin=1
> MaxLoginRetry=4
> TerminalServerUsers=tsusers
> TerminalServerAdmins=tsadmins
> # When AlwaysGroupCheck = false access will be permitted
> # if the group TerminalServerUsers is not defined.
> AlwaysGroupCheck = false
>
> [Sessions]
> X11DisplayOffset=10
> MaxSessions=50
> # if 1, true, or yes, kill session after 60 seconds
> KillDisconnected=0
> # if not zero, the seconds without mouse or keyboard input before
> disconnect
> # not complete yet
> IdleTimeLimit=0
> # if not zero, the seconds before a disconnected session is killed
> # min 60 seconds
> DisconnectedTimeLimit=0
>
> [Logging]
> EnableSyslog=1
> SyslogLevel=DEBUG
>
> [X11rdp]
> param1=-bs
> param2=-ac
> param3=-nolisten
> param4=tcp
> param5=-uds
>
> [Xvnc]
> param1=-bs
> param2=-ac
> param3=-nolisten
> param4=tcp
> param5=-localhost
> param6=-dpi
> param7=96
>
> [Chansrv]
> # drive redirection, defaults to xrdp_client if not set
> FuseMountName=thinclient_drives
>
>
> I have to kill the X11rdp thats making trouble and a new Session is
> created. It is not possible to reconnect to this session any more.
> Is there any info i can provide to make debugging easier?
>
> Used software is xrdp 0.8.0 running on rhel6.3. Connected via Windows 7
> RDP client.
>
>
> BR,
> Lionel
>
------------------------------------------------------------------------------
Dive into the World of Parallel Programming. The Go Parallel Website,
sponsored by Intel and developed in partnership with Slashdot Media, is your
hub for all things parallel software development, from weekly thought
leadership blogs to news, videos, case studies, tutorials and more. Take a
look and join the conversation now. http://goparallel.sourceforge.net/
_______________________________________________
xrdp-devel mailing list
xrdp-devel@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/xrdp-devel

Reply via email to