X2Go Bug report logs - #1279
Idle connection crashes and is unable to resume

Full log

🔗 View this message in rfc822 format

X-Loop: owner@bugs.x2go.org
Subject: Bug#1279: Idle connection crashes and is unable to resume
Reply-To: Michael Schneider <wand3r3r@gmail.com>, 1279@bugs.x2go.org
Resent-From: Michael Schneider <wand3r3r@gmail.com>
Resent-To: x2go-dev@lists.x2go.org
Resent-CC: X2Go Developers <x2go-dev@lists.x2go.org>
X-Loop: owner@bugs.x2go.org
Resent-Date: Wed, 28 Mar 2018 13:15:01 +0000
Resent-Message-ID: <handler.1279.B.152224276214449@bugs.x2go.org>
Resent-Sender: owner@bugs.x2go.org
X-X2Go-PR-Message: report 1279
X-X2Go-PR-Package: x2goserver
X-X2Go-PR-Keywords: 
Received: via spool by submit@bugs.x2go.org id=B.152224276214449
          (code B); Wed, 28 Mar 2018 13:15:01 +0000
Received: (at submit) by bugs.x2go.org; 28 Mar 2018 13:12:42 +0000
X-Spam-Checker-Version: SpamAssassin 3.4.1 (2015-04-28) on
	ymir.das-netzwerkteam.de
X-Spam-Level: 
X-Spam-Status: No, score=-2.7 required=3.0 tests=BAYES_00,DKIM_SIGNED,
	DKIM_VALID,DKIM_VALID_AU,FREEMAIL_FROM,HTML_MESSAGE,RCVD_IN_DNSWL_LOW,
	WEIRD_PORT autolearn=ham autolearn_force=no version=3.4.1
Received: from localhost (localhost [127.0.0.1])
	by ymir.das-netzwerkteam.de (Postfix) with ESMTP id 10E555DAE9
	for <submit@bugs.x2go.org>; Wed, 28 Mar 2018 15:12:39 +0200 (CEST)
X-Virus-Scanned: Debian amavisd-new at ymir.das-netzwerkteam.de
Received: from ymir.das-netzwerkteam.de ([127.0.0.1])
	by localhost (ymir.das-netzwerkteam.de [127.0.0.1]) (amavisd-new, port 10024)
	with ESMTP id bH2X1NwBfokl for <submit@bugs.x2go.org>;
	Wed, 28 Mar 2018 15:12:33 +0200 (CEST)
Received: from mail-io0-x22e.google.com (mail-io0-x22e.google.com [IPv6:2607:f8b0:4001:c06::22e])
	by ymir.das-netzwerkteam.de (Postfix) with ESMTPS id 11DA65DACC
	for <submit@bugs.x2go.org>; Wed, 28 Mar 2018 15:12:33 +0200 (CEST)
Received: by mail-io0-x22e.google.com with SMTP id v13so3481691iob.6
        for <submit@bugs.x2go.org>; Wed, 28 Mar 2018 06:12:33 -0700 (PDT)
DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed;
        d=gmail.com; s=20161025;
        h=mime-version:from:date:message-id:subject:to;
        bh=B8xxRNJL4MK0r3lt6Fdoq4kffXAAw4hOtH1UJGhlJjI=;
        b=TmPZGT2DYWZQjTzetU6zYJh2iKMvzy6zTifUPsgjs83yyLRySGip6UUYJtwhAWgyNQ
         +WLtlaiCek/nv+wybhpYVEuBJFUBHGHkTclN4bdLIkOiklqijcQyq/wGNaGiSnOEMYpy
         JUpoBBsK+YGmTDzrClMsGXvLKdUIWQwA7Ja0IDj3dNzOA/8UxZ501njW8pN27Kfxmohe
         pDFbI3QY6+hmmvF2n3LXXG9/mpICqekkjpbbVD5CtRBBopQN0bGdcr3NCaKnH/a34wy+
         3DHi7oSFaaT3cGi7NgyYXvOpMBnDNJ7oAlUAvrKVRAQm2vcvUV8RCRyJtoTRtFTWzDQ4
         2ezw==
X-Google-DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed;
        d=1e100.net; s=20161025;
        h=x-gm-message-state:mime-version:from:date:message-id:subject:to;
        bh=B8xxRNJL4MK0r3lt6Fdoq4kffXAAw4hOtH1UJGhlJjI=;
        b=FmAILgL/35pS+lbU5OLipTpzng+voppvbJH3LkpNCtn0x4NbcJxtHRM7FOYNPYaFOG
         6c/DdBPAuN1WWnO9oav0kZCBSD+T1pZN2BuAycpuH2XEp48obqtFdjPos+pglXldMYUu
         pb3yQbMlvFCvBOVWpRjxn4Zui6koD5e/3hmtgl0RlNbIC2+waVbBeGf1ob6qSz95GT0F
         bkyphbnu6LEV//okxawPterzKu4MG4jBcs6tx4VjnNIgTywSj8s53o6N6uy+In316/BS
         c9lnxVVfD/Pi3Z/JTu+dRrosdINvt4xFyiivEGhohglvSVzs8sybdIWUoDdS0B3tofDv
         hvgw==
X-Gm-Message-State: AElRT7HmjHL+Sac5Aiz4icmlYKBginkqruX39y7CZj1i0RhuezO9DmzT
	CMZuk3ScuLMtqUTmQytB/keilcZbZzs365dCFN9R7w==
X-Google-Smtp-Source: AIpwx49rowcdAxT/x2pXoMRHf4L9mMUuVcvM0rcu6OCBhmQX3ZpO5YbnR/f0dyiSjQktrRcjDOih0u4LlnHjJftLJpU=
X-Received: by 10.107.165.206 with SMTP id o197mr25409877ioe.27.1522242750760;
 Wed, 28 Mar 2018 06:12:30 -0700 (PDT)
MIME-Version: 1.0
Received: by 10.107.10.219 with HTTP; Wed, 28 Mar 2018 06:12:10 -0700 (PDT)
From: Michael Schneider <wand3r3r@gmail.com>
Date: Wed, 28 Mar 2018 09:12:10 -0400
Message-ID: <CAPS5NrBJGGrSjAnZZDx6nOVtQJFprvttvm1NOcjEWFmOdWSrbg@mail.gmail.com>
To: submit@bugs.x2go.org
Content-Type: multipart/alternative; boundary="001a114150746bcc2e056878c232"

[Message part 1 (text/plain, inline)]

Package: x2goserver

Version: 4.1.0.0


I recently upgraded x2goserver for Ubuntu 16.04.1.  The output of
x2goversion is below


x2golxdebindings: 1.0.2.4

x2gomatebindings: 0.0.1.5

x2goserver: 4.1.0.0

x2goserver-common: 4.1.0.0

x2goserver-extensions: 4.1.0.0

x2goserver-fmbindings: 4.1.0.0

x2goserver-printing: 4.1.0.0

x2goserver-x2goagent: 3.5.99.16

x2goserver-xsession: 4.1.0.0

After doing so now Idle sessions crash and are unable to resume.  The
`nxagent` process for the user begins eating up 100% CPU.  When I log in I
get a pulse audio error.

If I suspend the session (close the client without logging out) then reopen
it right away (with just a few minutes) I am able to successfully resume
the session.  However, if I leave the connection active but idle for
sometimes 15 minutes sometimes a few hours, the connection terminates.  The
client displays an error about the connection being terminated.  When I try
to log back in.  The client pops up an error about the remote proxy and
won't let me connect.

To reconnect I have to log in with putty and manually terminate the session
using:

$ x2goterminatesession <session_id>

Below are excerpts from syslog and session.log

*Syslog*

Mar 27 14:23:39 myhost systemd[1]: Started Session 5372 of user <user>.

Mar 27 14:23:39 myhost console-kit-daemon[2848]: (process:14605):
GLib-CRITICAL **: g_slice_set_config: assertion 'sys_page_size == 0' failed

Mar 27 14:23:39 myhost console-kit-daemon[2848]: missing action

Mar 27 14:23:44 myhost /usr/bin/x2gostartagent: successfully started X2Go
Agent session with ID <user>-53-1522175022_stDMATE_dp32

Mar 27 14:23:58 myhost /usr/bin/x2gosetkeyboard: Setting X keyboard
according to /home/<user>/.x2go/C-<user>-53-1522175022_stDMATE_dp32/keyboard

Mar 27 14:23:58 myhost /usr/bin/x2goruncommand: launching session with
Xsession-x2go mechanism, using STARTUP="mate-session"/x2

Mar 27 14:23:58 myhost /usr/bin/x2goruncommand: dbus wrapper available as
/usr/bin/dbus-run-session

Mar 27 14:23:59 myhost /usr/bin/x2gomountdirs[15174]: successfully
mounted <client_user>@127.0.0.1:33302/cygdrive/C/Users/<client_user_id>/DOWNLO~1
to /tmp/.x2go-<user>/media/disk/_cygdrive_C_Users_<client_user_id>_DOWNLO1
<M.Schneider.ctr@127.0.0.1:33302/cygdrive/C/Users/c10639/DOWNLO~1%20to%20/tmp/.x2go-schnemi/media/disk/_cygdrive_C_Users_c10639_DOWNLO1>

Mar 27 14:24:00 myhost pulseaudio[15611]: [pulseaudio] core-util.c: Failed
to open configuration file
'/home/<user>/.x2go/C-<user>-53-1522175022_stDMATE_dp32/.pulse-client.conf':
No such file or directory

Mar 27 14:24:01 myhost /usr/bin/x2gomountdirs[15174]: successfully
mounted <client_user>@127.0.0.1:33302/cygdrive/C/Users/<client_user_id>/X2GO~1/S-1249~1/spool
to /tmp/.x2go-<user>/spool/C-<user>-53-1522175022_stDMATE_dp32
<M.Schneider.ctr@127.0.0.1:33302/cygdrive/C/Users/c10639/X2GO~1/S-1249~1/spool%20to%20/tmp/.x2go-schnemi/spool/C-schnemi-53-1522175022_stDMATE_dp32>

…

…

…

Mar 27 14:25:41 myhost /usr/sbin/x2gocleansessions[40872]:
<user>-53-1522175140_stDMATE_dp32: state file for this session does not
exist: /tmp/.x2go-<user>/C-<user>-53-1522175140_stDMATE_dp32/state (this
can be ignored during session startups)

Mar 27 14:25:41 myhost /usr/sbin/x2gocleansessions[5711]:
<user>-53-1522175140_stDMATE_dp32: state file for this session does not
exist: /tmp/.x2go-<user>/C-<user>-53-1522175140_stDMATE_dp32/state (this
can be ignored during session startups)

Mar 27 14:25:41 myhost /usr/sbin/x2gocleansessions[60151]:
<user>-53-1522175140_stDMATE_dp32: state file for this session does not
exist: /tmp/.x2go-<user>/C-<user>-53-1522175140_stDMATE_dp32/state (this
can be ignored during session startups)

Mar 27 14:25:42 myhost /usr/bin/x2gostartagent: successfully started X2Go
Agent session with ID <user>-53-1522175140_stDMATE_dp32

Mar 27 14:25:46 myhost /usr/bin/x2gosetkeyboard: Setting X keyboard
according to /home/<user>/.x2go/C-<user>-53-1522175140_stDMATE_dp32/keyboard

Mar 27 14:25:46 myhost /usr/bin/x2goruncommand: launching session with
Xsession-x2go mechanism, using STARTUP="mate-session"

Mar 27 14:25:46 myhost /usr/bin/x2goruncommand: dbus wrapper available as
/usr/bin/dbus-run-session

Mar 27 14:25:47 myhost /usr/bin/x2gomountdirs[18935]: successfully
mounted <client_user>@127.0.0.1:50448/cygdrive/C/Users/<client_user_id>/DOWNLO~1
to /tmp/.x2go-<user>/media/disk/_cygdrive_C_Users_<client_user_id>_DOWNLO1
<M.Schneider.ctr@127.0.0.1:50448/cygdrive/C/Users/c10639/DOWNLO~1%20to%20/tmp/.x2go-schnemi/media/disk/_cygdrive_C_Users_c10639_DOWNLO1>

Mar 27 14:25:49 myhost /usr/bin/x2gomountdirs[18935]: successfully
mounted <client_user>@127.0.0.1:50448/cygdrive/C/Users/<client_user_id>/X2GO~1/S-3ED8~1/spool
to /tmp/.x2go-<user>/spool/C-<user>-53-1522175140_stDMATE_dp32
<M.Schneider.ctr@127.0.0.1:50448/cygdrive/C/Users/c10639/X2GO~1/S-3ED8~1/spool%20to%20/tmp/.x2go-schnemi/spool/C-schnemi-53-1522175140_stDMATE_dp32>

...

...

...

Mar 27 14:38:47 myhost /usr/bin/x2goumount-session[58516]: successfully
unmounted
"/tmp/.x2go-<user>/media/disk/_cygdrive_C_Users_<client_user_id>_DOWNLO1"

Mar 27 14:38:48 myhost /usr/bin/x2goumount-session[58516]: successfully
unmounted "/tmp/.x2go-<user>/spool/C-<user>-53-1522175140_stDMATE_dp32"

Mar 27 14:38:48 myhost /usr/bin/x2gosuspend-session: session with ID
<user>-53-1522175140_stDMATE_dp32 has been suspended successfully

Mar 27 14:38:48 myhost systemd[1]: Started Session c313 of user <user>.

Mar 27 14:39:00 myhost systemd[1]: Started Session 5377 of user <user>.

Mar 27 14:39:00 myhost console-kit-daemon[2848]: (process:58917):
GLib-CRITICAL **: g_slice_set_config: assertion 'sys_page_size == 0' failed

Mar 27 14:39:00 myhost console-kit-daemon[2848]: missing action

Mar 27 14:39:01 myhost CRON[58968]: (root) CMD (  [ -x
/usr/lib/php5/maxlifetime ] && [ -x /usr/lib/php5/sessionclean ] && [ -d
/var/lib/php5 ] && /usr/lib/php5/sessionclean /var/lib/php5
$(/usr/lib/php5/maxlifetime))

Mar 27 14:39:03 myhost /usr/bin/x2goresume-session: client XXX.XXX.XXX.33
has successfully resumed session with ID <user>-53-1522175140_stDMATE_dp32

Mar 27 14:39:09 myhost /usr/bin/x2gomountdirs[59285]: successfully mounted
<client_user>@127.0.0.1:50448/cygdrive/C/Users/<client_user_id>/DOWNLO~1 to
/tmp/.x2go-<user>/media/disk/_cygdrive_C_Users_<client_user_id>_DOWNLO1

Mar 27 14:39:11 myhost /usr/bin/x2gomountdirs[59285]: successfully mounted
<client_user>@127.0.0.1:50448/cygdrive/C/Users/<client_user_id>/X2GO~1/S-3ED8~1/spool
to /tmp/.x2go-<user>/spool/C-<user>-53-1522175140_stDMATE_dp32

Mar 27 14:39:12 myhost /usr/bin/x2gosetkeyboard: Setting X keyboard
according to /home/<user>/.x2go/C-<user>-53-1522175140_stDMATE_dp32/keyboard

Mar 27 14:39:12 myhost org.mate.panel.applet.MintMenuAppletFactory[19106]:
** (mintMenu.py:19622): WARNING **: Failed to get pixmap 10486396,0,0

Mar 27 14:39:27 myhost /usr/bin/x2goumount-session[59790]: successfully
unmounted
"/tmp/.x2go-<user>/media/disk/_cygdrive_C_Users_<client_user_id>_DOWNLO1"

Mar 27 14:39:28 myhost /usr/bin/x2goumount-session[59790]: successfully
unmounted "/tmp/.x2go-<user>/spool/C-<user>-53-1522175140_stDMATE_dp32"

Mar 27 14:39:28 myhost /usr/bin/x2gosuspend-session: session with ID
<user>-53-1522175140_stDMATE_dp32 has been suspended successfully

Mar 27 14:39:28 myhost systemd[1]: Started Session c316 of user <user>.

Mar 27 14:39:29 myhost systemd[1]: Started Session c317 of user <user>.

Mar 27 14:39:36 myhost systemd[1]: Started Session 5379 of user <user>.

Mar 27 14:39:36 myhost console-kit-daemon[2848]: (process:60121):
GLib-CRITICAL **: g_slice_set_config: assertion 'sys_page_size == 0' failed

Mar 27 14:39:36 myhost console-kit-daemon[2848]: missing action

Mar 27 14:39:40 myhost /usr/bin/x2goresume-session: client XXX.XXX.XXX.33
has successfully resumed session with ID <user>-53-1522175140_stDMATE_dp32

Mar 27 14:39:47 myhost /usr/bin/x2gosetkeyboard: Setting X keyboard
according to /home/<user>/.x2go/C-<user>-53-1522175140_stDMATE_dp32/keyboard

Mar 27 14:39:49 myhost /usr/bin/x2gomountdirs[60531]: successfully mounted
<client_user>@127.0.0.1:50448/cygdrive/C/Users/<client_user_id>/DOWNLO~1 to
/tmp/.x2go-<user>/media/disk/_cygdrive_C_Users_<client_user_id>_DOWNLO1

Mar 27 14:39:52 myhost /usr/bin/x2gomountdirs[60531]: successfully mounted
<client_user>@127.0.0.1:50448/cygdrive/C/Users/<client_user_id>/X2GO~1/S-3ED8~1/spool
to /tmp/.x2go-<user>/spool/C-<user>-53-1522175140_stDMATE_dp32


…

…

…

Mar 28 08:25:40 myhost /usr/bin/x2goresume-session: client XXX.XXX.XXX.33
has successfully resumed session with ID <user>-53-1522175140_stDMATE_dp32

Mar 28 08:25:42 myhost systemd[1]: Started Session c325 of user <user>.

Mar 28 08:25:42 myhost console-kit-daemon[2848]: console-kit-daemon[2848]:
GLib-CRITICAL: Source ID 25295 was not found when attempting to remove it

Mar 28 08:25:42 myhost console-kit-daemon[2848]: GLib-CRITICAL: Source ID
25295 was not found when attempting to remove it

Mar 28 08:25:42 myhost systemd[1]: Started Session c326 of user <user>.

Mar 28 08:25:42 myhost console-kit-daemon[2848]: (process:10753):
GLib-CRITICAL **: g_slice_set_config: assertion 'sys_page_size == 0' failed

Mar 28 08:25:42 myhost console-kit-daemon[2848]: missing action

Mar 28 08:25:42 myhost console-kit-daemon[2848]: console-kit-daemon[2848]:
GLib-CRITICAL: Source ID 25284 was not found when attempting to remove it

Mar 28 08:25:42 myhost console-kit-daemon[2848]: GLib-CRITICAL: Source ID
25284 was not found when attempting to remove it

Mar 28 08:25:42 myhost console-kit-daemon[2848]: console-kit-daemon[2848]:
GLib-CRITICAL: Source ID 25303 was not found when attempting to remove it

Mar 28 08:25:42 myhost console-kit-daemon[2848]: GLib-CRITICAL: Source ID
25303 was not found when attempting to remove it

Mar 28 08:25:42 myhost console-kit-daemon[2848]: console-kit-daemon[2848]:
GLib-CRITICAL: Source ID 25313 was not found when attempting to remove it

Mar 28 08:25:42 myhost console-kit-daemon[2848]: GLib-CRITICAL: Source ID
25313 was not found when attempting to remove it

Mar 28 08:25:42 myhost systemd[1]: Started Session c327 of user <user>.

Mar 28 08:25:42 myhost console-kit-daemon[2848]: console-kit-daemon[2848]:
GLib-CRITICAL: Source ID 25340 was not found when attempting to remove it

Mar 28 08:25:42 myhost console-kit-daemon[2848]: GLib-CRITICAL: Source ID
25340 was not found when attempting to remove it

Mar 28 08:25:42 myhost console-kit-daemon[2848]: console-kit-daemon[2848]:
GLib-CRITICAL: Source ID 25329 was not found when attempting to remove it

Mar 28 08:25:42 myhost console-kit-daemon[2848]: GLib-CRITICAL: Source ID
25329 was not found when attempting to remove it

Mar 28 08:25:43 myhost console-kit-daemon[2848]: console-kit-daemon[2848]:
GLib-CRITICAL: Source ID 25353 was not found when attempting to remove it

Mar 28 08:25:43 myhost console-kit-daemon[2848]: GLib-CRITICAL: Source ID
25353 was not found when attempting to remove it

Mar 28 08:26:08 myhost systemd[1]: Started Session 5553 of user <user>.

Mar 28 08:26:08 myhost console-kit-daemon[2848]: (process:11374):
GLib-CRITICAL **: g_slice_set_config: assertion 'sys_page_size == 0' failed

Mar 28 08:26:08 myhost console-kit-daemon[2848]: missing action

Mar 28 08:26:12 myhost /usr/bin/x2gosetkeyboard:
/home/<user>/.x2go/C-<user>-53-1522175140_stDMATE_dp32/keyboard did not
appear within 30s after agent startup

*.x2go/C-<user>-53-1522175140_stDMATE_dp32/session.log*

Session: Session resumed at 'Tue Mar 27 14:39:47 2018'.

Info: Screen [0] resized to geometry [1280x1024] fullscreen [0].

Info: Screen [0] resized to geometry [1280x1024] fullscreen [0].

Info: Screen [0] resized to geometry [1280x1024] fullscreen [0].

Info: Screen [0] resized to geometry [1280x1024] fullscreen [0].

Error: Failure reading from the peer proxy.

Loop: PANIC! No shutdown of proxy link performed by remote proxy.

Error: Connection with remote peer broken.

Error: Please check the state of your network and retry.

Session: Display failure detected at 'Tue Mar 27 18:25:50 2018'.

Session: Suspending session at 'Tue Mar 27 18:25:50 2018'.

Session: Session suspended at 'Tue Mar 27 18:25:50 2018'.

[Message part 2 (text/html, inline)]

Send a report that this bug log contains spam.