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)
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@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@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)
x2go-user mailing list x2go-user@lists.x2go.org https://lists.x2go.org/listinfo/x2go-user
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@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@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)
x2go-user mailing list x2go-user@lists.x2go.org https://lists.x2go.org/listinfo/x2go-user
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@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@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@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)
x2go-user mailing list x2go-user@lists.x2go.org https://lists.x2go.org/listinfo/x2go-user
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@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@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@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)
x2go-user mailing list x2go-user@lists.x2go.org https://lists.x2go.org/listinfo/x2go-user
On Wed, Jun 30, 2021 at 12:13 AM Norm Green <norm.green@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?
When I attempt to resume there are errors in the log. For one thing, the "options" file is now size 0.
If the options file gets corrupted this probably explains your problems. AFAIR the options file is updated on reconnect in /usr/bin/x2goresume-session, so adding some debug output there might help.
Uli
-
Norm Green -
Ulrich Sibiller