I'm having a problem resuming x2go sessions which now fails 100% of the
time. The only way to use x2go is to kill all x2go processes on the
server and restart x2goserver service, which causes the suspended
session to be lost. This is driving me crazy. Any hints on how to debug
this?
Client is x2go 4.1.2.2 on windows 10
server is x2go 4.1.0.4-0 on Unbuntu 18.04
At the point the session is suspended, everything looks normal: I have a
session and the x2goagent is still running.
normg@moop>x2golistsessions
26617|normg-50-1625002538_stDXFCE_dp32|50|moop|S|2021-06-29T14:35:39|6fa8d13074afab1c2d58dee02b1085d8|10.94.155.18|43587|43588|2021-06-29T14:50:02|normg|1016|43589|-1|-1
normg@moop>cat state
SUSPENDED
normg@moop>cat options
nx/nx,link=adsl,pack=16m-jpeg-9,limit=0,root=/tmp/.x2go-normg/C-normg-50-1625002538_stDXFCE_dp32,cache=8M,images=32M,type=unix-kde-depth_32,id=normg-50-1625002538_stDXFCE_dp32,cookie=6fa8d13074afab1c2d58dee02b1085d8,errors=/tmp/.x2go-normg/C-normg-50-1625002538_stDXFCE_dp32/session.log_errors,kbtype=null/null,geometry=800x600,xinerama=0,resize=1,fullscreen=0,accept=localhost,listen=43587,clipboard=both,client=linux,menu=0,state=/tmp/.x2go-normg/C-normg-50-1625002538_stDXFCE_dp32/state:50
normg@moop>tail session.log
autograb : Ctrl+Alt+g
dump_clipboard : Ctrl+Shift+Alt+c
Info: keyboard blocking directory created:
'/home/normg/.x2go/C-normg-50-1625002538_stDXFCE_dp32/keyboard'
Info: Keycode conversion is off
Session: Session started at 'Tue Jun 29 14:35:50 2021'.
Info: Screen [0] resized to geometry [800x600] fullscreen [0].
Info: Screen [0] resized to geometry [3440x1387] fullscreen [0].
Session: Suspending session at 'Tue Jun 29 14:50:02 2021'.
Info: Waiting the cleanup timeout to complete.
Session: Session suspended at 'Tue Jun 29 14:50:05 2021'.
/tmp/.x2go-normg/C-normg-50-1625002538_stDXFCE_dp32
--------------------------------------------------------------------------
When I attempt to resume I get this error on the windows side:
The remote proxy closed the connection while negotiating the session.
When I attempt to resume there are errors in the log. For one thing, the
"options" file is now size 0.
I can't tell if I have an ssh tunnel problem or an x2go problem.
The session log after the resume looks like this.
Session: Resuming session at 'Tue Jun 29 14:54:40 2021'.
Warning: Option file doesn't contain a port specification.
Loop: PANIC! Can't read options from file
'/tmp/.x2go-normg/C-normg-50-1625002538_stDXFCE_dp32/options'. Error is
4 'Interrupted system call'.
Error: Can't read options from file
'/tmp/.x2go-normg/C-normg-50-1625002538_stDXFCE_dp32/options'. Error is
4 'Interrupted system call'.
Error: Parsing of NX transport options failed.
_X11TransSocketConnectConnInfo: Cannot create the NX transport.
Loop: WARNING! Signal 'SIGINT' not installed in process with pid '26617'.
Warning: Signal 'SIGINT' not installed in process with pid '26617'.
Loop: WARNING! Signal 'SIGTERM' not installed in process with pid '26617'.
Warning: Signal 'SIGTERM' not installed in process with pid '26617'.
Loop: WARNING! Signal 'SIGHUP' not installed in process with pid '26617'.
Warning: Signal 'SIGHUP' not installed in process with pid '26617'.
Loop: WARNING! Signal 'SIGCHLD' not installed in process with pid '26617'.
Warning: Signal 'SIGCHLD' not installed in process with pid '26617'.
Session: Display failure detected at 'Tue Jun 29 14:54:40 2021'.
Session: Suspending session at 'Tue Jun 29 14:54:40 2021'.
NXPROXY - Version 3.5.99.26
Info: Proxy running in client mode with pid '26617'.
Session: Starting session at 'Tue Jun 29 14:54:40 2021'.
Loop: PANIC! Call to bind failed for x11. Error is 98 'Address already
in use'.
Error: Call to bind failed for x11. Error is 98 'Address already in use'.
Session: Session terminated at 'Tue Jun 29 14:54:40 2021'.
Session: Suspending session at 'Tue Jun 29 14:54:40 2021'.
Session: Suspending session at 'Tue Jun 29 14:54:45 2021'.
Session: Suspending session at 'Tue Jun 29 14:54:50 2021'.
Session: Suspending session at 'Tue Jun 29 14:54:55 2021'.
cat state
RESUMING
>tail /var/log/auth.log
Jun 29 14:36:37 moop sshd[22738]: pam_unix(sshd:session): session closed
for user normg
Jun 29 14:36:37 moop su[25244]: pam_unix(su:session): session closed for
user root
Jun 29 14:36:37 moop systemd-logind[2033]: Removed session 30.
Jun 29 14:50:05 moop sshd[25879]: pam_unix(sshd:session): session closed
for user normg
Jun 29 14:52:18 moop sshd[3540]: rexec line 16: Deprecated option
UsePrivilegeSeparation
Jun 29 14:52:18 moop sshd[3540]: rexec line 19: Deprecated option
KeyRegenerationInterval
Jun 29 14:52:18 moop sshd[3540]: rexec line 20: Deprecated option
ServerKeyBits
Jun 29 14:52:18 moop sshd[3540]: rexec line 32: Deprecated option
RSAAuthentication
Jun 29 14:52:18 moop sshd[3540]: rexec line 39: Deprecated option
RhostsRSAAuthentication
Jun 29 14:52:18 moop sshd[3540]: reprocess config line 32: Deprecated
option RSAAuthentication
Jun 29 14:52:18 moop sshd[3540]: reprocess config line 39: Deprecated
option RhostsRSAAuthentication
Jun 29 14:52:18 moop sshd[3540]: Accepted publickey for normg from
10.94.155.18 port 62301 ssh2: RSA
SHA256:7IZ9shZ11Cs+7I+F9oGX+J/HwXehqo2WuO+DIqri8ag
Jun 29 14:52:18 moop sshd[3540]: pam_unix(sshd:session): session opened
for user normg by (uid=0)
Jun 29 14:52:18 moop systemd-logind[2033]: New session 39 of user normg.
Jun 29 14:54:34 moop sshd[4976]: rexec line 16: Deprecated option
UsePrivilegeSeparation
Jun 29 14:54:34 moop sshd[4976]: rexec line 19: Deprecated option
KeyRegenerationInterval
Jun 29 14:54:34 moop sshd[4976]: rexec line 20: Deprecated option
ServerKeyBits
Jun 29 14:54:34 moop sshd[4976]: rexec line 32: Deprecated option
RSAAuthentication
Jun 29 14:54:34 moop sshd[4976]: rexec line 39: Deprecated option
RhostsRSAAuthentication
Jun 29 14:54:35 moop sshd[4976]: reprocess config line 32: Deprecated
option RSAAuthentication
Jun 29 14:54:35 moop sshd[4976]: reprocess config line 39: Deprecated
option RhostsRSAAuthentication
Jun 29 14:54:36 moop sshd[4976]: Accepted publickey for normg from
10.94.155.18 port 61095 ssh2: RSA
SHA256:g5I1h8qQIEpuSkJl3+1D6VdhSAB89gJO3cGgVzZBfP8
Jun 29 14:54:36 moop sshd[4976]: pam_unix(sshd:session): session opened
for user normg by (uid=0)
Jun 29 14:54:36 moop systemd-logind[2033]: New session 40 of user normg.
Jun 29 14:54:43 moop sshd[5048]: error: connect_to localhost port 43587:
failed.
Jun 29 14:54:43 moop sshd[5048]: channel_by_id: 0: bad id: channel free
Jun 29 14:54:43 moop sshd[5048]: Disconnecting user normg 10.94.155.18
port 61095: ieof packet referred to nonexistent channel 0
Jun 29 14:54:43 moop sshd[4976]: pam_unix(sshd:session): session closed
for user normg
Jun 29 14:55:11 moop systemd-logind[2033]: Removed session 40.
>tail /var/log/syslog
Jun 29 14:54:06 moop snmpd[2635]: message repeated 3 times: [ error on
subcontainer 'ia_addr' insert (-1)]
Jun 29 14:54:36 moop systemd[1]: Started Session 40 of user normg.
Jun 29 14:54:36 moop snmpd[2635]: error on subcontainer 'ia_addr' insert
(-1)
Jun 29 14:55:06 moop snmpd[2635]: error on subcontainer 'ia_addr' insert
(-1)
Jun 29 14:55:23 moop dhclient[1959]: DHCPREQUEST of 10.94.141.31 on br0
to 10.94.141.7 port 67 (xid=0x183e67fb)
Jun 29 14:55:23 moop dhclient[1959]: DHCPACK of 10.94.141.31 from
10.94.141.7
Jun 29 14:55:23 moop dhclient[1959]: bound to 10.94.141.31 -- renewal in
3565 seconds.
Jun 29 14:55:36 moop snmpd[2635]: error on subcontainer 'ia_addr' insert
(-1)
