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

Wed Jun 30 17:58:01 CEST 2021

Just tried SIGHUP and no, same problem as before.

On 6/30/2021 8:40 AM, Ulrich Sibiller wrote:
> 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