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

[Ulrich Sibiller]

Does reconnect work if you suspend the x2goagent by sending a SIGHUP to it?

You can usually see the apt install history in
/var/log/apt/history.log, maybe you can oll back some packages and see
if that helps.

Uli

On Wed, Jun 30, 2021 at 5:28 PM Norm Green
<norm.green at gemtalksystems.com> wrote:
>
> Fair question. We do the normal "apt-get upgrade" on these systems to
> keep packages up to date. Perhaps that updated the nx-libs and started
> the problem ?
> For example, I can see nxagent was updated a while back:
>
> nxagent:amd64 (2:3.5.99.26-0,
> 2:3.5.99.26-0+git20210204.4362+18.04.release.1),
>
> I have removed and reinstalled x2goserver with no effect.
>
> And I've just confirmed suspending the session manually produces the
> same problem.
>
> I'm at a loss how to debug this. Only the nuclear option of reinstalling
> Ubunutu comes to mind.
>
> Norm Green
>
>
> On 6/29/2021 11:18 PM, Ulrich Sibiller wrote:
> > 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
>