Uploaded image for project: 'Guacamole'
  1. Guacamole
  2. GUACAMOLE-1326

Guacamole v1.30 cannot establish RDP sessions after upgrading from v1.2.0

    XMLWordPrintableJSON

Details

    • Bug
    • Status: Closed
    • Major
    • Resolution: Invalid
    • 1.3.0
    • None
    • guacamole-client, guacd
    • None
    • Debian 10 Buster
      guacd v1.3.0
      freerdp2-dev v2.0.0~git20190204.1.2693389a+dfsg1-1+deb10u2

    Description

      I've been worikig with Guacamole v1.2.0 without any issues using RDP in my Debian 10 Buster until now. I have upgraded to version v1.3.0 building guacamole-server and guacamole-client source code directly from Repo and I cannot connect to my servers using RDP anymore. This is the error I get:

      guacd daemon is running:

      root@guacamole2:/opt/tomcat/logs# systemctl status guacd
      ● guacd.service - LSB: Guacamole proxy daemon
      Loaded: loaded (/etc/init.d/guacd; generated)
      Active: active (running) since Mon 2021-04-12 11:18:11 -03; 1h 19min ago
      Docs: man:systemd-sysv-generator(8)
      Process: 18457 ExecStart=/etc/init.d/guacd start (code=exited, status=0/SUCCESS)
      Tasks: 1 (limit: 2277)
      Memory: 10.4M
      CGroup: /system.slice/guacd.service
      └─18461 /usr/local/sbin/guacd -p /var/run/guacd.pid

      Apr 12 11:18:11 guacamole2 systemd[1]: Starting LSB: Guacamole proxy daemon...
      Apr 12 11:18:11 guacamole2 guacd[18459]: Guacamole proxy daemon (guacd) version 1.3.0 started
      Apr 12 11:18:11 guacamole2 guacd[18457]: Starting guacd: guacd[18459]: INFO: Guacamole proxy daemon (guacd) version 1.3.0 s
      Apr 12 11:18:11 guacamole2 guacd[18457]: SUCCESS
      Apr 12 11:18:11 guacamole2 systemd[1]: Started LSB: Guacamole proxy daemon.
      Apr 12 11:18:11 guacamole2 guacd[18461]: Listening on host ::1, port 4822
      lines 1-16/16 (END)

      And is listening at localhost:4822 :

      root@guacamole2:/etc/guacamole# cat guacamole.properties
      # Hostname and Guacamole server port
      guacd-hostname: localhost
      guacd-port: 4822

      root@guacamole2:/etc/guacamole# telnet localhost 4822
      Trying ::1...
      Connected to localhost.
      Escape character is '^]'.

      There seems to be no problems in /var/log/syslog:

      Apr 12 11:18:03 guacamole2 systemd[1]: Starting Apache Tomcat Web Application Container...
      Apr 12 11:18:03 guacamole2 startup.sh[18412]: Tomcat started.
      Apr 12 11:18:03 guacamole2 systemd[1]: Started Apache Tomcat Web Application Container.
      Apr 12 11:18:11 guacamole2 systemd[1]: Starting LSB: Guacamole proxy daemon...
      Apr 12 11:18:11 guacamole2 guacd[18459]: Guacamole proxy daemon (guacd) version 1.3.0 started
      Apr 12 11:18:11 guacamole2 guacd[18457]: Starting guacd: guacd[18459]: INFO:#011Guacamole proxy daemon (guacd) version 1.3.0 started
      Apr 12 11:18:11 guacamole2 guacd[18457]: SUCCESS
      Apr 12 11:18:11 guacamole2 systemd[1]: Started LSB: Guacamole proxy daemon.
      Apr 12 11:18:11 guacamole2 guacd[18461]: Listening on host ::1, port 4822

      This is what I get in /opt/tomcat/logs/localhost_access_log.*:

      127.0.0.1 - - [12/Apr/2021:12:51:00 -0300] "POST /guacamole/api/tokens HTTP/1.1" 200 187
      0:0:0:0:0:0:0:1 - - [12/Apr/2021:12:51:00 -0300] "GET /guacamole/app/element/templates/blank.html HTTP/1.1" 200 173
      127.0.0.1 - - [12/Apr/2021:12:51:01 -0300] "GET /guacamole/websocket-tunnel?token=F01049CFF44F51D6480CBDA56F795AA4130BB19383CBE430A0DDAA9789EFFD12&GUAC_DATA_SOURCE=mysql&GUAC_ID=4&GUAC_TYPE=c&GUAC_WIDTH=1360&GUAC_HEIGHT=625&GUAC_DPI=96&GUAC_TIMEZONE=America%2FBuenos_Aires&GUAC_AUDIO=audio%2FL8&GUAC_AUDIO=audio%2FL16&GUAC_IMAGE=image%2Fjpeg&GUAC_IMAGE=image%2Fpng&GUAC_IMAGE=image%2Fwebp HTTP/1.1" 101 -
      0:0:0:0:0:0:0:1 - - [12/Apr/2021:12:51:01 -0300] "POST /guacamole/tunnel?connect HTTP/1.1" 500 676
      127.0.0.1 - - [12/Apr/2021:12:51:01 -0300] "POST /guacamole/api/tokens HTTP/1.1" 200 187

      This in /opt/tomcat/logs/localhost.*:

      12-Apr-2021 08:16:03.006 INFO [main] org.apache.catalina.core.ApplicationContext.log SessionListener: contextDestroyed()
      12-Apr-2021 08:16:03.006 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: contextDestroyed()
      12-Apr-2021 08:33:37.912 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: contextInitialized()
      12-Apr-2021 08:33:37.913 INFO [main] org.apache.catalina.core.ApplicationContext.log SessionListener: contextInitialized()
      12-Apr-2021 08:33:37.914 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: attributeAdded('StockTicker', 'async.Stockticker@6c758c74')
      12-Apr-2021 08:34:25.924 INFO [main] org.apache.catalina.core.ApplicationContext.log SessionListener: contextDestroyed()
      12-Apr-2021 08:34:25.924 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: contextDestroyed()
      12-Apr-2021 08:34:44.032 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: contextInitialized()
      12-Apr-2021 08:34:44.033 INFO [main] org.apache.catalina.core.ApplicationContext.log SessionListener: contextInitialized()
      12-Apr-2021 08:34:44.035 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: attributeAdded('StockTicker', 'async.Stockticker@55a4a0ab')
      12-Apr-2021 08:38:01.000 INFO [main] org.apache.catalina.core.ApplicationContext.log SessionListener: contextDestroyed()
      12-Apr-2021 08:38:01.000 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: contextDestroyed()
      12-Apr-2021 08:47:10.578 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: contextInitialized()
      12-Apr-2021 08:47:10.578 INFO [main] org.apache.catalina.core.ApplicationContext.log SessionListener: contextInitialized()
      12-Apr-2021 08:47:10.581 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: attributeAdded('StockTicker', 'async.Stockticker@1f94e1')
      12-Apr-2021 09:06:14.160 INFO [main] org.apache.catalina.core.ApplicationContext.log SessionListener: contextDestroyed()
      12-Apr-2021 09:06:14.160 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: contextDestroyed()
      12-Apr-2021 09:26:49.382 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: contextInitialized()
      12-Apr-2021 09:26:49.384 INFO [main] org.apache.catalina.core.ApplicationContext.log SessionListener: contextInitialized()
      12-Apr-2021 09:26:49.385 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: attributeAdded('StockTicker', 'async.Stockticker@7194f5b')
      12-Apr-2021 09:47:59.700 INFO [main] org.apache.catalina.core.ApplicationContext.log SessionListener: contextDestroyed()
      12-Apr-2021 09:47:59.700 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: contextDestroyed()
      12-Apr-2021 09:48:28.334 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: contextInitialized()
      12-Apr-2021 09:48:28.335 INFO [main] org.apache.catalina.core.ApplicationContext.log SessionListener: contextInitialized()
      12-Apr-2021 09:48:28.336 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: attributeAdded('StockTicker', 'async.Stockticker@4af32714')
      12-Apr-2021 10:36:59.848 INFO [main] org.apache.catalina.core.ApplicationContext.log SessionListener: contextDestroyed()
      12-Apr-2021 10:36:59.848 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: contextDestroyed()
      12-Apr-2021 10:37:21.985 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: contextInitialized()
      12-Apr-2021 10:37:21.985 INFO [main] org.apache.catalina.core.ApplicationContext.log SessionListener: contextInitialized()
      12-Apr-2021 10:37:21.987 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: attributeAdded('StockTicker', 'async.Stockticker@417c9b17')
      12-Apr-2021 10:42:38.044 INFO [main] org.apache.catalina.core.ApplicationContext.log SessionListener: contextDestroyed()
      12-Apr-2021 10:42:38.044 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: contextDestroyed()
      12-Apr-2021 10:43:09.830 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: contextInitialized()
      12-Apr-2021 10:43:09.830 INFO [main] org.apache.catalina.core.ApplicationContext.log SessionListener: contextInitialized()
      12-Apr-2021 10:43:09.832 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: attributeAdded('StockTicker', 'async.Stockticker@1f94e1')
      12-Apr-2021 11:16:59.485 INFO [main] org.apache.catalina.core.ApplicationContext.log SessionListener: contextDestroyed()
      12-Apr-2021 11:16:59.485 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: contextDestroyed()
      12-Apr-2021 11:18:11.313 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: contextInitialized()
      12-Apr-2021 11:18:11.314 INFO [main] org.apache.catalina.core.ApplicationContext.log SessionListener: contextInitialized()
      12-Apr-2021 11:18:11.316 INFO [main] org.apache.catalina.core.ApplicationContext.log ContextListener: attributeAdded('StockTicker', 'async.Stockticker@31e5d408')

      And this in /opt/tomcat/logs/catalina.out:

      12:56:56.442 [http-nio-8080-exec-8] ERROR o.a.g.w.GuacamoleWebSocketTunnelEndpoint - Creation of WebSocket tunnel to guacd failed: java.net.ConnectException: Connection refused (Connection refused)
      12:56:56.467 [http-nio-8080-exec-5] ERROR o.a.g.s.GuacamoleHTTPTunnelServlet - HTTP tunnel request failed: java.net.ConnectException: Connection refused (Connection refused)

      How you can see, this last log shows that guacamole-client cannot establish a connection with guacd.

      I also tried to run guacamole-client and server downloading them from [https://guacamole.apache.org/releases/1.3.0/|https://guacamole.apache.org/releases/1.3.0/,] however I had this other issue. 

      Could you please help me with this?

       

      Attachments

        1. image-2021-04-12-12-36-25-174.png
          26 kB
          Tomás Esteban Losada

        Activity

          People

            Unassigned Unassigned
            Blackula Tomás Esteban Losada
            Votes:
            0 Vote for this issue
            Watchers:
            3 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved: