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

Wed Jun 30 17:28:29 CEST 2021

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