[X2Go-User] x2go session resume always fails (long post)

[Ulrich Sibiller]

Honestly, I have no idea what's going on here. As this used to work
one question can not be omitted: what has changed on the system
recently?

Generally nx is a bit flaky when the network connection gets lost. It
is always better to suspend the session manually and then close the
network connection (VPN, dialup, whatever).

Uli

On Wed, Jun 30, 2021 at 12:13 AM Norm Green
<norm.green at gemtalksystems.com> wrote:
>
> 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 at 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 at moop>cat state
> SUSPENDED
>
> normg at 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 at 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)
>
> _______________________________________________
> x2go-user mailing list
> x2go-user at lists.x2go.org
> https://lists.x2go.org/listinfo/x2go-user