You are viewing a plain text version of this content. The canonical link for it is here.
Posted to user@guacamole.apache.org by Andy Pattrick <an...@horiba.com> on 2016/09/22 10:19:56 UTC

Server connection error

Hi All,



I've got guacamole 0.9.9 set up inside docker on Ubuntu Server 14.04 LTS, using MySQL and LDAP authentication. On the whole it seems to work very well indeed but I have one user who is helping me test it who is having problems and I am stumped so I am wondering if anyone on here can help me?



The symptoms are that he can log in just fine but when he tries to open a connection he receives a 'Connection Error' - 'An internal error has occurred within the Guacamole server and the connection has been terminated. If the problem persists.....blah blah blah'. Looking in the logs (with "docker log guacamole-container"), all I can see is that his connection is made and then terminated a few milliseconds later.



 09:10:41.485 [http-nio-8080-exec-10] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" connected to connection "1".

 09:10:41.490 [http-nio-8080-exec-7] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" disconnected from connection "1". Duration: 5 milliseconds

It doesn't matter which connection he tries he always gets the same error. Other users can open these connections just fine. He has reproduced this in Chrome 53.0.2785.116 m and IE11 and gets the same behaviour in both. I am using the same browser versions and they are working fine for me.  I suspected localisation issues because he is on a Japanese OS but I have since tested on a Japanese VM and it's working fine from there.



Is there other logging I can look at to diagnose what is happening on the server? Any ideas what might be going on?



Many thanks, Andy.



RE: Server connection error

Posted by Andy Pattrick <an...@horiba.com>.
Aha! I found that it was our Kaspersky installation that was blocking the traffic. Its heuristics obviously thought that the our guacamole web site traffic was suspicious. I added the URL to the trusted list and now it's working fine.

Thanks for your support anyway....hopefully this information may come in useful to others if they experience this problem.

Cheers Andy.

________________________________________
From: Andy Pattrick [andy.pattrick@horiba.com]
Sent: 07 October 2016 15:36
To: user@guacamole.incubator.apache.org
Subject: RE: Server connection error

Hi Mike,

There is nothing showing up in the chrome dev tools console.

There are no other messages in the guacamole log preceeding these:

13:31:34.846 [http-nio-8080-exec-7] INFO  o.g.g.n.b.r.a.AuthenticationService - User "satoshi.koyama" successfully authenticated from 149.240.170.46.
13:31:45.996 [http-nio-8080-exec-7] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" connected to connection "1".
13:31:46.010 [http-nio-8080-exec-3] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" disconnected from connection "1". Duration: 14 milliseconds
13:32:02.482 [http-nio-8080-exec-3] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" connected to connection "1".
13:32:02.492 [http-nio-8080-exec-10] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" disconnected from connection "1". Duration: 10 milliseconds
13:32:25.639 [http-nio-8080-exec-6] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" connected to connection "1".
13:32:25.660 [http-nio-8080-exec-3] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" disconnected from connection "1". Duration: 21 milliseconds
13:32:46.356 [http-nio-8080-exec-1] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" connected to connection "1".
13:32:46.377 [http-nio-8080-exec-6] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" disconnected from connection "1". Duration: 21 milliseconds
14:21:42.747 [http-nio-8080-exec-2] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" connected to connection "1".
14:21:42.776 [http-nio-8080-exec-1] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" disconnected from connection "1". Duration: 29 milliseconds
14:22:38.442 [http-nio-8080-exec-10] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" connected to connection "1".
14:22:38.466 [http-nio-8080-exec-2] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" disconnected from connection "1". Duration: 23 milliseconds

The guacd log just contains:

connected to 10.240.181.21:3389
guacd[241]: INFO:       Protocol "rdp" selected
guacd[241]: INFO:       Connection ID is "$42525e5b-b309-4b05-b824-2ad6f425325d"
guacd[241]: INFO:       No security mode specified. Defaulting to RDP.
guacd[241]: INFO:       Loading keymap "base"
guacd[241]: INFO:       Loading keymap "en-us-qwerty"
guacd[241]: INFO:       Starting client
guacd[241]: INFO:       Client disconnected
connected to 10.240.181.21:3389
guacd[245]: INFO:       Protocol "rdp" selected
guacd[245]: INFO:       Connection ID is "$5cdb2785-89b5-4a05-a48f-90f85c8c287c"
guacd[245]: INFO:       No security mode specified. Defaulting to RDP.
guacd[245]: INFO:       Loading keymap "base"
guacd[245]: INFO:       Loading keymap "en-us-qwerty"
guacd[245]: INFO:       Starting client
guacd[245]: INFO:       Client disconnected
connected to 10.240.181.21:3389
guacd[249]: INFO:       Protocol "rdp" selected
guacd[249]: INFO:       Connection ID is "$e381f83f-a34c-401f-9d2d-b8b4a58ec928"
guacd[249]: INFO:       No security mode specified. Defaulting to RDP.
guacd[249]: INFO:       Loading keymap "base"
guacd[249]: INFO:       Loading keymap "en-us-qwerty"
guacd[249]: INFO:       Starting client
guacd[249]: INFO:       Client disconnected


I've now had this on two machines with identical symptoms, one Windows 7 Japanese, one Windows 10 English. It doesn't matter which browser they use, the result is the same. It's like the underlying sockets is getting closed immediately by the server or something. Where can I go from here? Can I enable additional logging with an env variable (I'm in docker) ? It's annoying because I really want to roll this out because it does exactly what we want but the boss is one of the people having issues and now we've seen it on two machines he wants to see it run himself. If it was only one machine we could rule it out as a machine issue but we can't do that now.

I'd appreciate any further guidance you can give.

Cheers Andy.

________________________________________
From: Mike Jumper [mike.jumper@guac-dev.org]
Sent: 27 September 2016 01:57
To: user@guacamole.incubator.apache.org
Subject: Re: Server connection error

On Thu, Sep 22, 2016 at 3:19 AM, Andy Pattrick <an...@horiba.com> wrote:
> Hi All,
>
> I've got guacamole 0.9.9 set up inside docker on Ubuntu Server 14.04 LTS,
> using MySQL and LDAP authentication. On the whole it seems to work very well
> indeed but I have one user who is helping me test it who is having problems
> and I am stumped so I am wondering if anyone on here can help me?
>
> The symptoms are that he can log in just fine but when he tries to open a
> connection he receives a 'Connection Error' - 'An internal error has
> occurred within the Guacamole server and the connection has been terminated.
> If the problem persists.....blah blah blah'. Looking in the logs (with
> "docker log guacamole-container"), all I can see is that his connection is
> made and then terminated a few milliseconds later.
>
>  09:10:41.485 [http-nio-8080-exec-10] INFO
> o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" connected to
> connection "1".
>
>  09:10:41.490 [http-nio-8080-exec-7] INFO
> o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" disconnected
> from connection "1". Duration: 5 milliseconds
>

5 ms is awfully short. Normally I would suspect proxy/network issues
between the Guacamole server and the browser, but that would manifest
itself as disconnects after 15 seconds (the network timeout used by
Guacamole internally).

Are there any other errors in the logs prior to the messages you've
already listed?

> It doesn't matter which connection he tries he always gets the same error.
> Other users can open these connections just fine. He has reproduced this in
> Chrome 53.0.2785.116 m and IE11 and gets the same behaviour in both. I am
> using the same browser versions and they are working fine for me.  I
> suspected localisation issues because he is on a Japanese OS but I have
> since tested on a Japanese VM and it's working fine from there.
>

Localisation issues shouldn't have any impact on the connections themselves.

> Is there other logging I can look at to diagnose what is happening on the
> server? Any ideas what might be going on?
>

If only that particular user is encountering issues, the first thing
I'd check is their browser logs, such as those displayed in the
JavaScript console of most browsers' dev tools.

It's also worth checking the guacd logs, to see if anything sticks out.

- Mike

RE: Server connection error

Posted by Andy Pattrick <an...@horiba.com>.
Hi Mike, 

There is nothing showing up in the chrome dev tools console. 

There are no other messages in the guacamole log preceeding these:

13:31:34.846 [http-nio-8080-exec-7] INFO  o.g.g.n.b.r.a.AuthenticationService - User "satoshi.koyama" successfully authenticated from 149.240.170.46.
13:31:45.996 [http-nio-8080-exec-7] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" connected to connection "1".
13:31:46.010 [http-nio-8080-exec-3] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" disconnected from connection "1". Duration: 14 milliseconds
13:32:02.482 [http-nio-8080-exec-3] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" connected to connection "1".
13:32:02.492 [http-nio-8080-exec-10] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" disconnected from connection "1". Duration: 10 milliseconds
13:32:25.639 [http-nio-8080-exec-6] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" connected to connection "1".
13:32:25.660 [http-nio-8080-exec-3] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" disconnected from connection "1". Duration: 21 milliseconds
13:32:46.356 [http-nio-8080-exec-1] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" connected to connection "1".
13:32:46.377 [http-nio-8080-exec-6] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" disconnected from connection "1". Duration: 21 milliseconds
14:21:42.747 [http-nio-8080-exec-2] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" connected to connection "1".
14:21:42.776 [http-nio-8080-exec-1] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" disconnected from connection "1". Duration: 29 milliseconds
14:22:38.442 [http-nio-8080-exec-10] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" connected to connection "1".
14:22:38.466 [http-nio-8080-exec-2] INFO  o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" disconnected from connection "1". Duration: 23 milliseconds

The guacd log just contains:

connected to 10.240.181.21:3389
guacd[241]: INFO:       Protocol "rdp" selected
guacd[241]: INFO:       Connection ID is "$42525e5b-b309-4b05-b824-2ad6f425325d"
guacd[241]: INFO:       No security mode specified. Defaulting to RDP.
guacd[241]: INFO:       Loading keymap "base"
guacd[241]: INFO:       Loading keymap "en-us-qwerty"
guacd[241]: INFO:       Starting client
guacd[241]: INFO:       Client disconnected
connected to 10.240.181.21:3389
guacd[245]: INFO:       Protocol "rdp" selected
guacd[245]: INFO:       Connection ID is "$5cdb2785-89b5-4a05-a48f-90f85c8c287c"
guacd[245]: INFO:       No security mode specified. Defaulting to RDP.
guacd[245]: INFO:       Loading keymap "base"
guacd[245]: INFO:       Loading keymap "en-us-qwerty"
guacd[245]: INFO:       Starting client
guacd[245]: INFO:       Client disconnected
connected to 10.240.181.21:3389
guacd[249]: INFO:       Protocol "rdp" selected
guacd[249]: INFO:       Connection ID is "$e381f83f-a34c-401f-9d2d-b8b4a58ec928"
guacd[249]: INFO:       No security mode specified. Defaulting to RDP.
guacd[249]: INFO:       Loading keymap "base"
guacd[249]: INFO:       Loading keymap "en-us-qwerty"
guacd[249]: INFO:       Starting client
guacd[249]: INFO:       Client disconnected


I've now had this on two machines with identical symptoms, one Windows 7 Japanese, one Windows 10 English. It doesn't matter which browser they use, the result is the same. It's like the underlying sockets is getting closed immediately by the server or something. Where can I go from here? Can I enable additional logging with an env variable (I'm in docker) ? It's annoying because I really want to roll this out because it does exactly what we want but the boss is one of the people having issues and now we've seen it on two machines he wants to see it run himself. If it was only one machine we could rule it out as a machine issue but we can't do that now.

I'd appreciate any further guidance you can give. 

Cheers Andy.

________________________________________
From: Mike Jumper [mike.jumper@guac-dev.org]
Sent: 27 September 2016 01:57
To: user@guacamole.incubator.apache.org
Subject: Re: Server connection error

On Thu, Sep 22, 2016 at 3:19 AM, Andy Pattrick <an...@horiba.com> wrote:
> Hi All,
>
> I've got guacamole 0.9.9 set up inside docker on Ubuntu Server 14.04 LTS,
> using MySQL and LDAP authentication. On the whole it seems to work very well
> indeed but I have one user who is helping me test it who is having problems
> and I am stumped so I am wondering if anyone on here can help me?
>
> The symptoms are that he can log in just fine but when he tries to open a
> connection he receives a 'Connection Error' - 'An internal error has
> occurred within the Guacamole server and the connection has been terminated.
> If the problem persists.....blah blah blah'. Looking in the logs (with
> "docker log guacamole-container"), all I can see is that his connection is
> made and then terminated a few milliseconds later.
>
>  09:10:41.485 [http-nio-8080-exec-10] INFO
> o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" connected to
> connection "1".
>
>  09:10:41.490 [http-nio-8080-exec-7] INFO
> o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" disconnected
> from connection "1". Duration: 5 milliseconds
>

5 ms is awfully short. Normally I would suspect proxy/network issues
between the Guacamole server and the browser, but that would manifest
itself as disconnects after 15 seconds (the network timeout used by
Guacamole internally).

Are there any other errors in the logs prior to the messages you've
already listed?

> It doesn't matter which connection he tries he always gets the same error.
> Other users can open these connections just fine. He has reproduced this in
> Chrome 53.0.2785.116 m and IE11 and gets the same behaviour in both. I am
> using the same browser versions and they are working fine for me.  I
> suspected localisation issues because he is on a Japanese OS but I have
> since tested on a Japanese VM and it's working fine from there.
>

Localisation issues shouldn't have any impact on the connections themselves.

> Is there other logging I can look at to diagnose what is happening on the
> server? Any ideas what might be going on?
>

If only that particular user is encountering issues, the first thing
I'd check is their browser logs, such as those displayed in the
JavaScript console of most browsers' dev tools.

It's also worth checking the guacd logs, to see if anything sticks out.

- Mike

Re: Server connection error

Posted by Mike Jumper <mi...@guac-dev.org>.
On Thu, Sep 22, 2016 at 3:19 AM, Andy Pattrick <an...@horiba.com> wrote:
> Hi All,
>
> I've got guacamole 0.9.9 set up inside docker on Ubuntu Server 14.04 LTS,
> using MySQL and LDAP authentication. On the whole it seems to work very well
> indeed but I have one user who is helping me test it who is having problems
> and I am stumped so I am wondering if anyone on here can help me?
>
> The symptoms are that he can log in just fine but when he tries to open a
> connection he receives a 'Connection Error' - 'An internal error has
> occurred within the Guacamole server and the connection has been terminated.
> If the problem persists.....blah blah blah'. Looking in the logs (with
> "docker log guacamole-container"), all I can see is that his connection is
> made and then terminated a few milliseconds later.
>
>  09:10:41.485 [http-nio-8080-exec-10] INFO
> o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" connected to
> connection "1".
>
>  09:10:41.490 [http-nio-8080-exec-7] INFO
> o.g.g.net.basic.TunnelRequestService - User "satoshi.koyama" disconnected
> from connection "1". Duration: 5 milliseconds
>

5 ms is awfully short. Normally I would suspect proxy/network issues
between the Guacamole server and the browser, but that would manifest
itself as disconnects after 15 seconds (the network timeout used by
Guacamole internally).

Are there any other errors in the logs prior to the messages you've
already listed?

> It doesn't matter which connection he tries he always gets the same error.
> Other users can open these connections just fine. He has reproduced this in
> Chrome 53.0.2785.116 m and IE11 and gets the same behaviour in both. I am
> using the same browser versions and they are working fine for me.  I
> suspected localisation issues because he is on a Japanese OS but I have
> since tested on a Japanese VM and it's working fine from there.
>

Localisation issues shouldn't have any impact on the connections themselves.

> Is there other logging I can look at to diagnose what is happening on the
> server? Any ideas what might be going on?
>

If only that particular user is encountering issues, the first thing
I'd check is their browser logs, such as those displayed in the
JavaScript console of most browsers' dev tools.

It's also worth checking the guacd logs, to see if anything sticks out.

- Mike