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)