You are viewing a plain text version of this content. The canonical link for it is here.
Posted to user@guacamole.apache.org by Regev Batchen <ba...@gmail.com> on 2021/09/09 06:16:34 UTC

Docker guacamole+guacd 1.2.0 gets stuck once a day randomly

Hello, I'm using guacamole on docker compose 1.25.0 with guacd and
guacamole 1.2.0 on ubuntu 18.04.3 LTS (Bionic Beaver).
Everything worked fine for more than 6 month and now it has started to get
stuck and won't work until I restart both containers..

docker-compose:
        guacamole:
                image: guacamole/guacamole:1.2.0
                depends_on:
                      - guacd
                      - postgres_gqm
                volumes:
                      -
/root/ansible/api/start.sh:/opt/guacamole/bin/start.sh
                      -
/root/ansible/api/program.jar:/opt/guacamole/program.jar
                environment:
                      GUACD_HOSTNAME: guacd
                      POSTGRES_DATABASE:  guacd_db
                      POSTGRES_HOSTNAME: postgres_gqm
                      POSTGRES_PASSWORD: password
                      POSTGRES_USER: guacd_user
                      GUACD_LOG_LEVEL: debug
                links:
                     - guacd
                ports:
                     - 8080/tcp
                restart: always
                logging:
                      driver: "json-file"
                      options:
                            max-size: "50m"
        guacd:
                image: guacamole/guacd:1.2.0
                restart: always
                volumes:
                        - ./drive:/drive:rw
                        - ./record:/record:rw
                environment:
                  GUACD_LOG_LEVEL: debug
                logging:
                    driver: "json-file"
                    options:
                          max-size: "50m"


containers logs:

guacamole -
guacamole_1       | 05:31:36.344 [http-nio-8080-exec-3] INFO
 o.a.g.r.auth.AuthenticationService - User "guacadmin" successfully
authenticated from [172.23.0.1, 172.23.0.19].
guacamole_1       | 05:31:40.852 [http-nio-8080-exec-8] INFO
 o.a.g.r.auth.AuthenticationService - User "guacadmin" successfully
authenticated from [172.23.0.1, 172.23.0.19].
guacamole_1       | 05:31:45.960 [http-nio-8080-exec-11] INFO
 o.a.g.r.auth.AuthenticationService - User "guacadmin" successfully
authenticated from [172.23.0.1, 172.23.0.19].
guacamole_1       | 05:31:51.639 [http-nio-8080-exec-12] INFO
 o.a.g.tunnel.TunnelRequestService - User "guacadmin" disconnected from
connection "22354". Duration: 1195913 milliseconds
guacamole_1       | 05:31:51.641 [Thread-26] INFO
 o.a.g.tunnel.TunnelRequestService - User "" disconnected from connection
"UqrJNFZyrUOvaC1vkfz2DP_Qm9-hJhTEB9yzAc9dpk-d". Duration: 1144359
milliseconds
guacamole_1       | 05:31:53.033 [http-nio-8080-exec-11] INFO
 o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
guacamole_1       | 05:32:00.223 [http-nio-8080-exec-16] INFO
 o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
guacamole_1       | 05:32:14.894 [http-nio-8080-exec-21] INFO
 o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
guacamole_1       | 05:33:15.593 [http-nio-8080-exec-23] ERROR
o.a.g.w.GuacamoleWebSocketTunnelEndpoint - Creation of WebSocket tunnel to
guacd failed: Requested tunnel destination does not exist.
guacamole_1       | 05:33:15.643 [http-nio-8080-exec-23] WARN
 o.a.g.s.GuacamoleHTTPTunnelServlet - HTTP tunnel request rejected:
Requested tunnel destination does not exist.
guacamole_1       | 05:33:16.603 [http-nio-8080-exec-23] ERROR
o.a.g.w.GuacamoleWebSocketTunnelEndpoint - Creation of WebSocket tunnel to
guacd failed: Requested tunnel destination does not exist.
guacamole_1       | 05:33:16.657 [http-nio-8080-exec-23] WARN
 o.a.g.s.GuacamoleHTTPTunnelServlet - HTTP tunnel request rejected:
Requested tunnel destination does not exist.
guacamole_1       | 05:34:35.958 [http-nio-8080-exec-24] ERROR
o.a.g.w.GuacamoleWebSocketTunnelEndpoint - Creation of WebSocket tunnel to
guacd failed: Requested tunnel destination does not exist.
guacamole_1       | 05:34:36.009 [http-nio-8080-exec-24] WARN
 o.a.g.s.GuacamoleHTTPTunnelServlet - HTTP tunnel request rejected:
Requested tunnel destination does not exist.
guacamole_1       | 05:40:53.700 [http-nio-8080-exec-28] INFO
 o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
guacamole_1       | 05:42:01.393 [http-nio-8080-exec-35] INFO
 o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
guacamole_1       | 05:42:06.440 [http-nio-8080-exec-39] INFO
 o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
guacamole_1       | 05:42:07.738 [http-nio-8080-exec-43] INFO
 o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
guacamole_1       | 05:42:08.654 [http-nio-8080-exec-47] INFO
 o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
guacamole_1       | 05:42:08.846 [http-nio-8080-exec-51] INFO
 o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
guacamole_1       | 05:42:09.022 [http-nio-8080-exec-55] INFO
 o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
guacamole_1       | 05:43:00.382 [http-nio-8080-exec-61] INFO
 o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
guacamole_1       | 05:44:07.327 [http-nio-8080-exec-66] INFO
 o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
guacamole_1       | 05:44:12.253 [http-nio-8080-exec-71] INFO
 o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
guacamole_1       | 05:44:55.139 [http-nio-8080-exec-78] INFO
 o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".


guacd-
guacd_1           | guacd[385]: DEBUG:  Client is using protocol version
"VERSION_1_1_0"
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_alloc: Creating
directory "shared-Raven" if necessary.
guacd_1           | guacd[385]: INFO:   Loading keymap "base"
guacd_1           | guacd[385]: INFO:   Loading keymap "en-us-qwerty"
guacd_1           | guacd[385]: DEBUG:  Support for CLIPRDR (clipboard
redirection) registered. Awaiting channel connection.
guacd_1           | guacd[385]: DEBUG:  Support for static channel "rdpdr"
loaded.
guacd_1           | guacd[385]: DEBUG:  Support for static channel "rdpsnd"
loaded.
guacd_1           | guacd[385]: DEBUG:  Local framebuffer format
 PIXEL_FORMAT_BGRX32
guacd_1           | guacd[385]: DEBUG:  Remote framebuffer format
PIXEL_FORMAT_RGB16
guacd_1           | guacd[385]: DEBUG:  CLIPRDR (clipboard redirection)
channel connected.
guacd_1           | guacd[385]: DEBUG:  SVC "rdpdr" connected.
guacd_1           | guacd[385]: DEBUG:  SVC "rdpsnd" connected.
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: path="/",
access=0x80000000, file_attributes=0x0, create_disposition=0x1,
create_options=0x0
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Normalized path
"/" to "\".
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Translated path
"\" to "shared-Raven/".
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: native open:
real_path="shared-Raven/", flags=0x0
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Opened "\" as
file_id=0
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: path="/Download",
access=0x80000000, file_attributes=0x0, create_disposition=0x1,
create_options=0x0
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Normalized path
"/Download" to "\Download".
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Translated path
"\Download" to "shared-Raven/Download".
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: native open:
real_path="shared-Raven/Download", flags=0x0
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Opened
"\Download" as file_id=1
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_close: Closed
"\Download" (file_id=1)
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_close: Closed "\"
(file_id=0)
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: INFO:   Connected to RDPDR 1.13 as client
0x0003
guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
type=0x0001, length=44
guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
type=0x0002, length=8
guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
type=0x0003, length=8
guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
type=0x0004, length=8
guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
type=0x0005, length=8
guacd_1           | guacd[385]: DEBUG:  Sending capabilities...
guacd_1           | guacd[385]: DEBUG:  Capabilities sent.
guacd_1           | guacd[385]: DEBUG:  Client ID confirmed
guacd_1           | guacd[354]: DEBUG:
 ERRINFO_DISCONNECTED_BY_OTHER_CONNECTION (0x00000005):Another user
connected to the server, forcing the disconnection of the current
connection.
guacd_1           | guacd[354]: INFO:   RDP server closed connection:
Disconnected by other connection.
guacd_1           | guacd[354]: DEBUG:  Disconnect reason code: 0x5.
guacd_1           | guacd[354]: DEBUG:  Unloading device 0 (G)
guacd_1           | guacd[354]: DEBUG:  SVC "rdpdr" disconnected.
guacd_1           | guacd[354]: DEBUG:  SVC "rdpsnd" disconnected.
guacd_1           | guacd[354]: INFO:   Internal RDP client disconnected
guacd_1           | guacd[385]: INFO:   Connected to RDPDR 1.13 as client
0x0002
guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
type=0x0001, length=44
guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
type=0x0002, length=8
guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
type=0x0003, length=8
guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
type=0x0004, length=8
guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
type=0x0005, length=8
guacd_1           | guacd[385]: DEBUG:  Sending capabilities...
guacd_1           | guacd[385]: DEBUG:  Capabilities sent.
guacd_1           | guacd[385]: DEBUG:  Client ID confirmed
guacd_1           | guacd[385]: INFO:   RDPDR user logged on
guacd_1           | guacd[385]: DEBUG:  Registered device 0 (G)
guacd_1           | guacd[385]: DEBUG:  All supported devices sent.
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: path="",
access=0x80, file_attributes=0x0, create_disposition=0x1, create_options=0x1
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Normalized path
"\" to "\".
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Translated path
"\" to "shared-Raven/".
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: native open:
real_path="shared-Raven/", flags=0x0
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Opened "\" as
file_id=1
guacd_1           | guacd[385]: DEBUG:  guac_rdpdr_fs_process_create:
[file_id=1] desired_access=0x80, file_attributes=0x0,
create_disposition=0x1, create_options=0x1, path=""
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: path="\Download",
access=0x80000000, file_attributes=0x0, create_disposition=0x3,
create_options=0x1
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Normalized path
"\Download" to "\Download".
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Translated path
"\Download" to "shared-Raven/Download".
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: native open:
real_path="shared-Raven/Download", flags=0x0
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Opened
"\Download" as file_id=0
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_close: Closed
"\Download" (file_id=0)
guacd_1           | guacd[385]: DEBUG:  Device 0 (G) connected successfully
guacd_1           | guacd[385]: DEBUG:
 guac_rdpdr_fs_process_query_standard_info: [file_id=1]
guacd_1           | guacd[385]: DEBUG:
 guac_rdpdr_fs_process_query_basic_info: [file_id=1]
guacd_1           | guacd[385]: DEBUG:  guac_rdpdr_fs_process_close:
[file_id=1]
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_close: Closed "\"
(file_id=1)
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: path="",
access=0x80, file_attributes=0x0, create_disposition=0x1, create_options=0x1
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Normalized path
"\" to "\".
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Translated path
"\" to "shared-Raven/".
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: native open:
real_path="shared-Raven/", flags=0x0
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Opened "\" as
file_id=0
guacd_1           | guacd[385]: DEBUG:  guac_rdpdr_fs_process_create:
[file_id=0] desired_access=0x80, file_attributes=0x0,
create_disposition=0x1, create_options=0x1, path=""
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: path="\Download",
access=0x80000000, file_attributes=0x0, create_disposition=0x3,
create_options=0x1
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Normalized path
"\Download" to "\Download".
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Translated path
"\Download" to "shared-Raven/Download".
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: native open:
real_path="shared-Raven/Download", flags=0x0
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Opened
"\Download" as file_id=1
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_close: Closed
"\Download" (file_id=1)
guacd_1           | guacd[385]: DEBUG:
 guac_rdpdr_fs_process_query_standard_info: [file_id=0]
guacd_1           | guacd[385]: DEBUG:
 guac_rdpdr_fs_process_query_basic_info: [file_id=0]
guacd_1           | guacd[385]: DEBUG:
 guac_rdpdr_fs_process_query_standard_info: [file_id=0]
guacd_1           | guacd[385]: DEBUG:  guac_rdpdr_fs_process_close:
[file_id=0]
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_close: Closed "\"
(file_id=0)
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: path="",
access=0x80, file_attributes=0x0, create_disposition=0x1, create_options=0x1
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Normalized path
"\" to "\".
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Translated path
"\" to "shared-Raven/".
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: native open:
real_path="shared-Raven/", flags=0x0
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Opened "\" as
file_id=1
guacd_1           | guacd[385]: DEBUG:  guac_rdpdr_fs_process_create:
[file_id=1] desired_access=0x80, file_attributes=0x0,
create_disposition=0x1, create_options=0x1, path=""
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: path="\Download",
access=0x80000000, file_attributes=0x0, create_disposition=0x3,
create_options=0x1
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Normalized path
"\Download" to "\Download".
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Translated path
"\Download" to "shared-Raven/Download".
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: native open:
real_path="shared-Raven/Download", flags=0x0
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Opened
"\Download" as file_id=0
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_close: Closed
"\Download" (file_id=0)
guacd_1           | guacd[385]: DEBUG:
 guac_rdpdr_fs_process_query_standard_info: [file_id=1]
guacd_1           | guacd[385]: DEBUG:
 guac_rdpdr_fs_process_query_basic_info: [file_id=1]
guacd_1           | guacd[385]: DEBUG:
 guac_rdpdr_fs_process_query_standard_info: [file_id=1]
guacd_1           | guacd[385]: DEBUG:  guac_rdpdr_fs_process_close:
[file_id=1]
guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_close: Closed "\"
(file_id=1)
guacd_1           | guacd[354]: ERROR:  User is not responding.
guacd_1           | guacd[354]: INFO:   User
"@82723fb6-0aa9-4e9f-857d-92925d11ce91" disconnected (0 users remain)
guacd_1           | guacd[354]: INFO:   Last user of connection
"$d98f91b1-232c-4b1e-8357-38c83e1ed0a1" disconnected
guacd_1           | guacd[354]: DEBUG:  Requesting termination of client...
guacd_1           | guacd[354]: DEBUG:  Client terminated successfully.
guacd_1           | guacd[6]: INFO:     Connection
"$d98f91b1-232c-4b1e-8357-38c83e1ed0a1" removed.
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[6]: INFO:     Joining existing connection
"$014f34c8-f450-45c0-91a6-1e5dea763e26"
guacd_1           | guacd[385]: DEBUG:  Processing instruction: size
guacd_1           | guacd[385]: DEBUG:  Processing instruction: audio
guacd_1           | guacd[385]: DEBUG:  Processing instruction: video
guacd_1           | guacd[385]: DEBUG:  Processing instruction: image
guacd_1           | guacd[385]: DEBUG:  Processing instruction: timezone
guacd_1           | guacd[385]: DEBUG:  Parameter "console" omitted. Using
default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "console-audio" omitted.
Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "ignore-cert" omitted.
Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "disable-auth" omitted.
Using default value of 0.
guacd_1           | guacd[385]: INFO:   No security mode specified.
Defaulting to security mode negotiation with server.
guacd_1           | guacd[385]: DEBUG:  Parameter "hostname" omitted. Using
default value of "".
guacd_1           | guacd[385]: DEBUG:  Parameter "port" omitted. Using
default value of 3389.
guacd_1           | guacd[385]: DEBUG:  User resolution is 1360x664 at 96
DPI
guacd_1           | guacd[385]: DEBUG:  Parameter "dpi" omitted. Using
default value of 96.
guacd_1           | guacd[385]: DEBUG:  Using resolution of 1360x664 at 96
DPI
guacd_1           | guacd[385]: DEBUG:  Parameter "read-only" omitted.
Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "client-name" omitted.
Using default value of "Guacamole RDP".
guacd_1           | guacd[385]: DEBUG:  Parameter "enable-wallpaper"
omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "enable-theming" omitted.
Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "enable-font-smoothing"
omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "enable-full-window-drag"
omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter
"enable-desktop-composition" omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "enable-menu-animations"
omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "disable-bitmap-caching"
omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter
"disable-offscreen-caching" omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "disable-glyph-caching"
omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "color-depth" omitted.
Using default value of 16.
guacd_1           | guacd[385]: DEBUG:  Parameter "disable-audio" omitted.
Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "enable-printing"
omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "printer-name" omitted.
Using default value of "Guacamole Printer".
guacd_1           | guacd[385]: DEBUG:  Parameter "enable-drive" omitted.
Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "drive-name" omitted.
Using default value of "Guacamole Filesystem".
guacd_1           | guacd[385]: DEBUG:  Parameter "drive-path" omitted.
Using default value of "".
guacd_1           | guacd[385]: DEBUG:  Parameter "create-drive-path"
omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "disable-download"
omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "disable-upload" omitted.
Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "timezone" omitted. Using
default value of "Asia/Jerusalem".
guacd_1           | guacd[385]: DEBUG:  Parameter "enable-sftp" omitted.
Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "sftp-hostname" omitted.
Using default value of "".
guacd_1           | guacd[385]: DEBUG:  Parameter "sftp-port" omitted.
Using default value of "22".
guacd_1           | guacd[385]: DEBUG:  Parameter "sftp-username" omitted.
Using default value of "".
guacd_1           | guacd[385]: DEBUG:  Parameter "sftp-password" omitted.
Using default value of "".
guacd_1           | guacd[385]: DEBUG:  Parameter "sftp-passphrase"
omitted. Using default value of "".
guacd_1           | guacd[385]: DEBUG:  Parameter "sftp-root-directory"
omitted. Using default value of "/".
guacd_1           | guacd[385]: DEBUG:  Parameter
"sftp-server-alive-interval" omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "sftp-disable-download"
omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "sftp-disable-upload"
omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "recording-name" omitted.
Using default value of "recording".
guacd_1           | guacd[385]: DEBUG:  Parameter
"recording-exclude-output" omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "recording-exclude-mouse"
omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "recording-include-keys"
omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "create-recording-path"
omitted. Using default value of 0.
guacd_1           | guacd[385]: INFO:   Resize method: none
guacd_1           | guacd[385]: DEBUG:  Parameter "enable-audio-input"
omitted. Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "gateway-port" omitted.
Using default value of 443.
guacd_1           | guacd[385]: DEBUG:  Parameter "disable-copy" omitted.
Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "disable-paste" omitted.
Using default value of 0.
guacd_1           | guacd[385]: DEBUG:  Parameter "wol-send-packet"
omitted. Using default value of 0.
guacd_1           | guacd[385]: INFO:   User
"@f58acd16-f14c-4239-9a15-2926c0d26d8e" joined connection
"$014f34c8-f450-45c0-91a6-1e5dea763e26" (2 users now present)
guacd_1           | guacd[385]: DEBUG:  Client is using protocol version
"VERSION_1_1_0"
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: DEBUG:  Sending keysym 0x10005d3 as Unicode
guacd_1           | guacd[385]: DEBUG:  Sending keysym 0x10005d5 as Unicode
guacd_1           | guacd[385]: DEBUG:  Sending keysym 0x10005d2 as Unicode
guacd_1           | guacd[385]: DEBUG:  Sending keysym 0x10005dd as Unicode
guacd_1           | guacd[385]: DEBUG:  Sending keysym 0x10005dc as Unicode
guacd_1           | guacd[385]: DEBUG:  Sending keysym 0x10005df as Unicode
guacd_1           | guacd[385]: DEBUG:  Sending keysym 0x10005da as Unicode
guacd_1           | guacd[385]: DEBUG:  Sending keysym 0x10005da as Unicode
guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
channels at 44100 Hz
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
channels at 44100 Hz
guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
channels at 44100 Hz
guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
channels at 44100 Hz
guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
channels at 44100 Hz
guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
channels at 44100 Hz
guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
channels at 44100 Hz
guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
channels at 44100 Hz
guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
channels at 44100 Hz
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
channels at 44100 Hz
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
channels at 44100 Hz
guacd_1           | guacd[385]: DEBUG:  Broadcasting clipboard to all
connected users.
guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
clipboard data.
guacd_1           | guacd[385]: DEBUG:  Sent 5 bytes of clipboard data on
stream 0.
guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
clipboard data.
guacd_1           | guacd[385]: DEBUG:  Sent 5 bytes of clipboard data on
stream 0.
guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
guacd_1           | guacd[385]: DEBUG:  Broadcast of clipboard complete.
guacd_1           | guacd[385]: DEBUG:  Broadcasting clipboard to all
connected users.
guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
clipboard data.
guacd_1           | guacd[385]: DEBUG:  Sent 142 bytes of clipboard data on
stream 0.
guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
clipboard data.
guacd_1           | guacd[385]: DEBUG:  Sent 142 bytes of clipboard data on
stream 0.
guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
guacd_1           | guacd[385]: DEBUG:  Broadcast of clipboard complete.
guacd_1           | guacd[385]: DEBUG:  Broadcasting clipboard to all
connected users.
guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
clipboard data.
guacd_1           | guacd[385]: DEBUG:  Sent 5 bytes of clipboard data on
stream 0.
guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
clipboard data.
guacd_1           | guacd[385]: DEBUG:  Sent 5 bytes of clipboard data on
stream 0.
guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
guacd_1           | guacd[385]: DEBUG:  Broadcast of clipboard complete.
guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
channels at 44100 Hz
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
channels at 44100 Hz
guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
channels at 44100 Hz
guacd_1           | guacd[385]: DEBUG:  Broadcasting clipboard to all
connected users.
guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
clipboard data.
guacd_1           | guacd[385]: DEBUG:  Sent 4 bytes of clipboard data on
stream 0.
guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
clipboard data.
guacd_1           | guacd[385]: DEBUG:  Sent 4 bytes of clipboard data on
stream 0.
guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
guacd_1           | guacd[385]: DEBUG:  Broadcast of clipboard complete.
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: DEBUG:  Broadcasting clipboard to all
connected users.
guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
clipboard data.
guacd_1           | guacd[385]: DEBUG:  Sent 2 bytes of clipboard data on
stream 0.
guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
clipboard data.
guacd_1           | guacd[385]: DEBUG:  Sent 2 bytes of clipboard data on
stream 0.
guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
guacd_1           | guacd[385]: DEBUG:  Broadcast of clipboard complete.
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: DEBUG:  Broadcasting clipboard to all
connected users.
guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
clipboard data.
guacd_1           | guacd[385]: DEBUG:  Sent 2 bytes of clipboard data on
stream 0.
guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
clipboard data.
guacd_1           | guacd[385]: DEBUG:  Sent 2 bytes of clipboard data on
stream 0.
guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
guacd_1           | guacd[385]: DEBUG:  Broadcast of clipboard complete.
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: DEBUG:  Broadcasting clipboard to all
connected users.
guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
clipboard data.
guacd_1           | guacd[385]: DEBUG:  Sent 13 bytes of clipboard data on
stream 0.
guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
clipboard data.
guacd_1           | guacd[385]: DEBUG:  Sent 13 bytes of clipboard data on
stream 0.
guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
guacd_1           | guacd[385]: DEBUG:  Broadcast of clipboard complete.
guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
availability of clipboard data to RDP server.
guacd_1           | guacd[385]: INFO:   User
"@65fabdc8-e59d-4af1-965a-be3888e9309f" disconnected (1 users remain)
guacd_1           | guacd[385]: ERROR:  User is not responding.
guacd_1           | guacd[385]: INFO:   User
"@f58acd16-f14c-4239-9a15-2926c0d26d8e" disconnected (0 users remain)
guacd_1           | guacd[385]: INFO:   Last user of connection
"$014f34c8-f450-45c0-91a6-1e5dea763e26" disconnected
guacd_1           | guacd[385]: DEBUG:  Requesting termination of client...
guacd_1           | guacd[385]: DEBUG:  Unloading device 0 (G)
guacd_1           | guacd[385]: DEBUG:  SVC "rdpdr" disconnected.
guacd_1           | guacd[385]: DEBUG:  SVC "rdpsnd" disconnected.
guacd_1           | guacd[385]: INFO:   Internal RDP client disconnected
guacd_1           | guacd[385]: DEBUG:  Client terminated successfully.
guacd_1           | guacd[6]: INFO:     Connection
"$014f34c8-f450-45c0-91a6-1e5dea763e26" removed.


Containers get stuck meaning no new data is writing, container looks
"running" from docker state or docker ps.
user sees blank page and requests guacamole using url /gqm/api gets 502
error.

What should i do ?
my server is very big no memory issue or cpu or disk no other errors on
another containers (i have 10-12) only GQM gets stuck and i don't know what
to do.
Please help?
I'm planning on upgrading to 1.3 as this is my only option left + maybe add
a script twice a day to restart only these containers.

Re: Docker guacamole+guacd 1.2.0 gets stuck once a day randomly

Posted by Regev Batchen <ba...@gmail.com>.
I have to update that the container stuck has not happened again after i
upgraded ubuntu 18 kernel from 4.5 to latest 5 kernel and apt-get upgrade
all packages on machine.
So if anyone is running into it this was the solution.
Thanks.

בתאריך יום ג׳, 12 באוק׳ 2021 ב-18:40 מאת Nick Couchman <vn...@apache.org>:

> On Tue, Oct 12, 2021 at 5:48 AM Regev Batchen <ba...@gmail.com> wrote:
>
>> Hey Again, i'm still not able to handle this error that happens once a
>> day, all containers are up but api request to gqm gets 502
>> I did get the full postgres query when this usually happened:
>>
>> postgres_gqm_1    | 2021-10-12T06:55:19.947186314Z 2021-10-12
>> 06:55:19.938 UTC [35] LOG:  could not send data to client: Connection reset
>> by peer
>>
>
> I don't think your issues are specifically related to Guacamole - I think
> there is something else driving network connectivity challenges between
> your containers, which is very odd since networking on a local system
> doesn't usually have much in the way of limits or instability. But, that's
> really what it looks like, here - between this error, where the peer is
> resetting the connection, and the previous gaucd one you posted about the
> error reading select, it really seems like a network problem.
>
>
>> postgres_gqm_1    | 2021-10-12T06:55:19.947212467Z 2021-10-12
>> 06:55:19.938 UTC [35] STATEMENT:  SELECT
>> postgres_gqm_1    | 2021-10-12T06:55:19.947215854Z
>>  guacamole_connection.connection_id,
>> postgres_gqm_1    | 2021-10-12T06:55:19.947218656Z
>>  guacamole_connection.connection_name,
>> postgres_gqm_1    | 2021-10-12T06:55:19.947221509Z
>>  parent_id,
>> postgres_gqm_1    | 2021-10-12T06:55:19.947224165Z
>>  protocol,
>> postgres_gqm_1    | 2021-10-12T06:55:19.947226788Z
>>  max_connections,
>> postgres_gqm_1    | 2021-10-12T06:55:19.947229387Z
>>  max_connections_per_user,
>> postgres_gqm_1    | 2021-10-12T06:55:19.947232302Z
>>  proxy_hostname,
>> postgres_gqm_1    | 2021-10-12T06:55:19.947234855Z
>>  proxy_port,
>> postgres_gqm_1    | 2021-10-12T06:55:19.947237399Z
>>  proxy_encryption_method,
>> postgres_gqm_1    | 2021-10-12T06:55:19.947239993Z
>>  connection_weight,
>> postgres_gqm_1    | 2021-10-12T06:55:19.947242599Z
>>  failover_only,
>> postgres_gqm_1    | 2021-10-12T06:55:19.947245130Z
>>  MAX(start_date) AS last_active
>> postgres_gqm_1    | 2021-10-12T06:55:19.947247726Z              FROM
>> guacamole_connection
>> postgres_gqm_1    | 2021-10-12T06:55:19.947250330Z              LEFT JOIN
>> guacamole_connection_history ON guacamole_connection_history.connection_id
>> = guacamole_connection.connection_id
>> postgres_gqm_1    | 2021-10-12T06:55:19.947253528Z              WHERE
>> guacamole_connection.connection_id IN
>> postgres_gqm_1    | 2021-10-12T06:55:19.947256208Z                   (
>> postgres_gqm_1    | 2021-10-12T06:55:19.947258731Z
>>  $1::integer
>> postgres_gqm_1    | 2021-10-12T06:55:19.947261284Z                   ,
>> postgres_gqm_1    | 2021-10-12T06:55:19.947263751Z
>>  $2::integer
>> postgres_gqm_1    | 2021-10-12T06:55:21.230273860Z                   ,
>> postgres_gqm_1    | 2021-10-12T06:55:21.230276158Z
>>  $25400::integer
>> postgres_gqm_1    | 2021-10-12T06:55:21.230278327Z                   )
>> postgres_gqm_1    | 2021-10-12T06:55:21.230280533Z              GROUP BY
>> guacamole_connection.connection_id
>>
>> Is this helping in any way? My next step  is gonna be upgrading the
>> kernel and libs on the host and if that doesn't work to move from docker to
>> kubernetes :(
>>
>>
> I mean, you can try those things, but I would spend time figuring out why
> network traffic is getting dropped to the point of causing these issues. Do
> you see elevated error rates on any of the interfaces in your environment?
> Any messages - not necessarily Guacamole-related - that point to traffic
> getting dropped/stopped/blocked/lost?
>
> > ERRINFO_DISCONNECTED_BY_OTHER_CONNECTION (0x00000005):Another user
> connected to the server, forcing the disconnection of the current
> connection.
>
> This is definitely not Guacamole-related, and is exactly what the error
> message describes - some other user connected to the server and forced the
> session to log off. This is a RDP server message, and Guacamole is just
> forwarding the error information through.
>
> -Nick
>
>>

Re: Docker guacamole+guacd 1.2.0 gets stuck once a day randomly

Posted by Nick Couchman <vn...@apache.org>.
On Tue, Oct 12, 2021 at 5:48 AM Regev Batchen <ba...@gmail.com> wrote:

> Hey Again, i'm still not able to handle this error that happens once a
> day, all containers are up but api request to gqm gets 502
> I did get the full postgres query when this usually happened:
>
> postgres_gqm_1    | 2021-10-12T06:55:19.947186314Z 2021-10-12 06:55:19.938
> UTC [35] LOG:  could not send data to client: Connection reset by peer
>

I don't think your issues are specifically related to Guacamole - I think
there is something else driving network connectivity challenges between
your containers, which is very odd since networking on a local system
doesn't usually have much in the way of limits or instability. But, that's
really what it looks like, here - between this error, where the peer is
resetting the connection, and the previous gaucd one you posted about the
error reading select, it really seems like a network problem.


> postgres_gqm_1    | 2021-10-12T06:55:19.947212467Z 2021-10-12 06:55:19.938
> UTC [35] STATEMENT:  SELECT
> postgres_gqm_1    | 2021-10-12T06:55:19.947215854Z
>  guacamole_connection.connection_id,
> postgres_gqm_1    | 2021-10-12T06:55:19.947218656Z
>  guacamole_connection.connection_name,
> postgres_gqm_1    | 2021-10-12T06:55:19.947221509Z
>  parent_id,
> postgres_gqm_1    | 2021-10-12T06:55:19.947224165Z
>  protocol,
> postgres_gqm_1    | 2021-10-12T06:55:19.947226788Z
>  max_connections,
> postgres_gqm_1    | 2021-10-12T06:55:19.947229387Z
>  max_connections_per_user,
> postgres_gqm_1    | 2021-10-12T06:55:19.947232302Z
>  proxy_hostname,
> postgres_gqm_1    | 2021-10-12T06:55:19.947234855Z
>  proxy_port,
> postgres_gqm_1    | 2021-10-12T06:55:19.947237399Z
>  proxy_encryption_method,
> postgres_gqm_1    | 2021-10-12T06:55:19.947239993Z
>  connection_weight,
> postgres_gqm_1    | 2021-10-12T06:55:19.947242599Z
>  failover_only,
> postgres_gqm_1    | 2021-10-12T06:55:19.947245130Z
>  MAX(start_date) AS last_active
> postgres_gqm_1    | 2021-10-12T06:55:19.947247726Z              FROM
> guacamole_connection
> postgres_gqm_1    | 2021-10-12T06:55:19.947250330Z              LEFT JOIN
> guacamole_connection_history ON guacamole_connection_history.connection_id
> = guacamole_connection.connection_id
> postgres_gqm_1    | 2021-10-12T06:55:19.947253528Z              WHERE
> guacamole_connection.connection_id IN
> postgres_gqm_1    | 2021-10-12T06:55:19.947256208Z                   (
> postgres_gqm_1    | 2021-10-12T06:55:19.947258731Z
>  $1::integer
> postgres_gqm_1    | 2021-10-12T06:55:19.947261284Z                   ,
> postgres_gqm_1    | 2021-10-12T06:55:19.947263751Z
>  $2::integer
> postgres_gqm_1    | 2021-10-12T06:55:21.230273860Z                   ,
> postgres_gqm_1    | 2021-10-12T06:55:21.230276158Z
>  $25400::integer
> postgres_gqm_1    | 2021-10-12T06:55:21.230278327Z                   )
> postgres_gqm_1    | 2021-10-12T06:55:21.230280533Z              GROUP BY
> guacamole_connection.connection_id
>
> Is this helping in any way? My next step  is gonna be upgrading the kernel
> and libs on the host and if that doesn't work to move from docker to
> kubernetes :(
>
>
I mean, you can try those things, but I would spend time figuring out why
network traffic is getting dropped to the point of causing these issues. Do
you see elevated error rates on any of the interfaces in your environment?
Any messages - not necessarily Guacamole-related - that point to traffic
getting dropped/stopped/blocked/lost?

> ERRINFO_DISCONNECTED_BY_OTHER_CONNECTION (0x00000005):Another user
connected to the server, forcing the disconnection of the current
connection.

This is definitely not Guacamole-related, and is exactly what the error
message describes - some other user connected to the server and forced the
session to log off. This is a RDP server message, and Guacamole is just
forwarding the error information through.

-Nick

>

Re: Docker guacamole+guacd 1.2.0 gets stuck once a day randomly

Posted by Regev Batchen <ba...@gmail.com>.
And also this error on guacd :
ERRINFO_DISCONNECTED_BY_OTHER_CONNECTION (0x00000005):Another user
connected to the server, forcing the disconnection of the current
connection.

‫בתאריך יום ג׳, 12 באוק׳ 2021 ב-12:47 מאת ‪Regev Batchen‬‏ <‪
batchenr@gmail.com‬‏>:‬

> Hey Again, i'm still not able to handle this error that happens once a
> day, all containers are up but api request to gqm gets 502
> I did get the full postgres query when this usually happened:
>
> postgres_gqm_1    | 2021-10-12T06:55:19.947186314Z 2021-10-12 06:55:19.938
> UTC [35] LOG:  could not send data to client: Connection reset by peer
> postgres_gqm_1    | 2021-10-12T06:55:19.947212467Z 2021-10-12 06:55:19.938
> UTC [35] STATEMENT:  SELECT
> postgres_gqm_1    | 2021-10-12T06:55:19.947215854Z
>  guacamole_connection.connection_id,
> postgres_gqm_1    | 2021-10-12T06:55:19.947218656Z
>  guacamole_connection.connection_name,
> postgres_gqm_1    | 2021-10-12T06:55:19.947221509Z
>  parent_id,
> postgres_gqm_1    | 2021-10-12T06:55:19.947224165Z
>  protocol,
> postgres_gqm_1    | 2021-10-12T06:55:19.947226788Z
>  max_connections,
> postgres_gqm_1    | 2021-10-12T06:55:19.947229387Z
>  max_connections_per_user,
> postgres_gqm_1    | 2021-10-12T06:55:19.947232302Z
>  proxy_hostname,
> postgres_gqm_1    | 2021-10-12T06:55:19.947234855Z
>  proxy_port,
> postgres_gqm_1    | 2021-10-12T06:55:19.947237399Z
>  proxy_encryption_method,
> postgres_gqm_1    | 2021-10-12T06:55:19.947239993Z
>  connection_weight,
> postgres_gqm_1    | 2021-10-12T06:55:19.947242599Z
>  failover_only,
> postgres_gqm_1    | 2021-10-12T06:55:19.947245130Z
>  MAX(start_date) AS last_active
> postgres_gqm_1    | 2021-10-12T06:55:19.947247726Z              FROM
> guacamole_connection
> postgres_gqm_1    | 2021-10-12T06:55:19.947250330Z              LEFT JOIN
> guacamole_connection_history ON guacamole_connection_history.connection_id
> = guacamole_connection.connection_id
> postgres_gqm_1    | 2021-10-12T06:55:19.947253528Z              WHERE
> guacamole_connection.connection_id IN
> postgres_gqm_1    | 2021-10-12T06:55:19.947256208Z                   (
> postgres_gqm_1    | 2021-10-12T06:55:19.947258731Z
>  $1::integer
> postgres_gqm_1    | 2021-10-12T06:55:19.947261284Z                   ,
> postgres_gqm_1    | 2021-10-12T06:55:19.947263751Z
>  $2::integer
> postgres_gqm_1    | 2021-10-12T06:55:21.230273860Z                   ,
> postgres_gqm_1    | 2021-10-12T06:55:21.230276158Z
>  $25400::integer
> postgres_gqm_1    | 2021-10-12T06:55:21.230278327Z                   )
> postgres_gqm_1    | 2021-10-12T06:55:21.230280533Z              GROUP BY
> guacamole_connection.connection_id
>
> Is this helping in any way? My next step  is gonna be upgrading the kernel
> and libs on the host and if that doesn't work to move from docker to
> kubernetes :(
>
> ‫בתאריך יום א׳, 26 בספט׳ 2021 ב-10:45 מאת ‪Regev Batchen‬‏ <‪
> batchenr@gmail.com‬‏>:‬
>
>> Hey,
>>
>> I dont have the full psql container query because i tried to do tail of
>> 20000 lines and it still shows
>>
>> postgres_gqm_1    | 2021-09-13T14:59:26.208973521Z                   ,
>> postgres_gqm_1    | 2021-09-13T14:59:26.208975652Z
>>  $22740::integer
>> postgres_gqm_1    | 2021-09-13T14:59:26.208977856Z                   ,
>> postgres_gqm_1    | 2021-09-13T14:59:26.208980045Z
>>  $22741::integer
>>
>> docker ps shows containers are healthy even when service is stuck
>>
>> Is there a way to add guacamole debug logs?
>> In the guacd container I have : GUACD_LOG_LEVEL: debug
>> but for guacamole ?
>>
>> It looks like psql did a restart after the big quarry and guacd did too
>> but something is not working correctly until i restart the guacd and
>> guacamole and then it works for a day or two,
>> what else can I check?
>> I am going to create a bash script to restart containers if service is
>> stuck because I really don't know what else to do :(
>>
>>
>>
>>
>> ‫בתאריך יום ד׳, 22 בספט׳ 2021 ב-22:40 מאת ‪Mike Jumper‬‏ <‪
>> mike.jumper@glyptodon.com‬‏>:‬
>>
>>> On Wed, Sep 22, 2021 at 5:45 AM Regev Batchen <ba...@gmail.com>
>>> wrote:
>>>
>>>> Hello, the postgres error happened again.
>>>> I dont understand why postgrece started to get stuck after i updated to
>>>> guacamole 1.3 , befor i had an error that it was only get stuck with no sql
>>>> error.
>>>> i use psql 12.3 and here are some logs, PLEASE let me know what else i
>>>> can check and debug? i have 10 more containers on this docker-compose and
>>>> only guacamole gets stuck.
>>>>
>>>> It just stop receiving api request and no logs are written to the guac
>>>> containers.
>>>> what causing this SELECT error?
>>>>
>>>
>>> Do you have the full message from the PostgreSQL container?
>>>
>>> The log message you see from guacd (Error reading "select") is not
>>> actually related to the database (which is only used by the webapp, not
>>> guacd). This is guacd letting you know that a connection to guacd was
>>> established and then closed during the Guacamole protocol handshake, the
>>> first message of which happens to be called "select". This is the result of
>>> a healthcheck ping within Docker and is not an error.
>>>
>>> - Mike
>>>
>>>

Re: Docker guacamole+guacd 1.2.0 gets stuck once a day randomly

Posted by Regev Batchen <ba...@gmail.com>.
Hey Again, i'm still not able to handle this error that happens once a day,
all containers are up but api request to gqm gets 502
I did get the full postgres query when this usually happened:

postgres_gqm_1    | 2021-10-12T06:55:19.947186314Z 2021-10-12 06:55:19.938
UTC [35] LOG:  could not send data to client: Connection reset by peer
postgres_gqm_1    | 2021-10-12T06:55:19.947212467Z 2021-10-12 06:55:19.938
UTC [35] STATEMENT:  SELECT
postgres_gqm_1    | 2021-10-12T06:55:19.947215854Z
 guacamole_connection.connection_id,
postgres_gqm_1    | 2021-10-12T06:55:19.947218656Z
 guacamole_connection.connection_name,
postgres_gqm_1    | 2021-10-12T06:55:19.947221509Z
 parent_id,
postgres_gqm_1    | 2021-10-12T06:55:19.947224165Z
 protocol,
postgres_gqm_1    | 2021-10-12T06:55:19.947226788Z
 max_connections,
postgres_gqm_1    | 2021-10-12T06:55:19.947229387Z
 max_connections_per_user,
postgres_gqm_1    | 2021-10-12T06:55:19.947232302Z
 proxy_hostname,
postgres_gqm_1    | 2021-10-12T06:55:19.947234855Z
 proxy_port,
postgres_gqm_1    | 2021-10-12T06:55:19.947237399Z
 proxy_encryption_method,
postgres_gqm_1    | 2021-10-12T06:55:19.947239993Z
 connection_weight,
postgres_gqm_1    | 2021-10-12T06:55:19.947242599Z
 failover_only,
postgres_gqm_1    | 2021-10-12T06:55:19.947245130Z
 MAX(start_date) AS last_active
postgres_gqm_1    | 2021-10-12T06:55:19.947247726Z              FROM
guacamole_connection
postgres_gqm_1    | 2021-10-12T06:55:19.947250330Z              LEFT JOIN
guacamole_connection_history ON guacamole_connection_history.connection_id
= guacamole_connection.connection_id
postgres_gqm_1    | 2021-10-12T06:55:19.947253528Z              WHERE
guacamole_connection.connection_id IN
postgres_gqm_1    | 2021-10-12T06:55:19.947256208Z                   (
postgres_gqm_1    | 2021-10-12T06:55:19.947258731Z
 $1::integer
postgres_gqm_1    | 2021-10-12T06:55:19.947261284Z                   ,
postgres_gqm_1    | 2021-10-12T06:55:19.947263751Z
 $2::integer
postgres_gqm_1    | 2021-10-12T06:55:21.230273860Z                   ,
postgres_gqm_1    | 2021-10-12T06:55:21.230276158Z
 $25400::integer
postgres_gqm_1    | 2021-10-12T06:55:21.230278327Z                   )
postgres_gqm_1    | 2021-10-12T06:55:21.230280533Z              GROUP BY
guacamole_connection.connection_id

Is this helping in any way? My next step  is gonna be upgrading the kernel
and libs on the host and if that doesn't work to move from docker to
kubernetes :(

‫בתאריך יום א׳, 26 בספט׳ 2021 ב-10:45 מאת ‪Regev Batchen‬‏ <‪
batchenr@gmail.com‬‏>:‬

> Hey,
>
> I dont have the full psql container query because i tried to do tail of
> 20000 lines and it still shows
>
> postgres_gqm_1    | 2021-09-13T14:59:26.208973521Z                   ,
> postgres_gqm_1    | 2021-09-13T14:59:26.208975652Z
>  $22740::integer
> postgres_gqm_1    | 2021-09-13T14:59:26.208977856Z                   ,
> postgres_gqm_1    | 2021-09-13T14:59:26.208980045Z
>  $22741::integer
>
> docker ps shows containers are healthy even when service is stuck
>
> Is there a way to add guacamole debug logs?
> In the guacd container I have : GUACD_LOG_LEVEL: debug
> but for guacamole ?
>
> It looks like psql did a restart after the big quarry and guacd did too
> but something is not working correctly until i restart the guacd and
> guacamole and then it works for a day or two,
> what else can I check?
> I am going to create a bash script to restart containers if service is
> stuck because I really don't know what else to do :(
>
>
>
>
> ‫בתאריך יום ד׳, 22 בספט׳ 2021 ב-22:40 מאת ‪Mike Jumper‬‏ <‪
> mike.jumper@glyptodon.com‬‏>:‬
>
>> On Wed, Sep 22, 2021 at 5:45 AM Regev Batchen <ba...@gmail.com> wrote:
>>
>>> Hello, the postgres error happened again.
>>> I dont understand why postgrece started to get stuck after i updated to
>>> guacamole 1.3 , befor i had an error that it was only get stuck with no sql
>>> error.
>>> i use psql 12.3 and here are some logs, PLEASE let me know what else i
>>> can check and debug? i have 10 more containers on this docker-compose and
>>> only guacamole gets stuck.
>>>
>>> It just stop receiving api request and no logs are written to the guac
>>> containers.
>>> what causing this SELECT error?
>>>
>>
>> Do you have the full message from the PostgreSQL container?
>>
>> The log message you see from guacd (Error reading "select") is not
>> actually related to the database (which is only used by the webapp, not
>> guacd). This is guacd letting you know that a connection to guacd was
>> established and then closed during the Guacamole protocol handshake, the
>> first message of which happens to be called "select". This is the result of
>> a healthcheck ping within Docker and is not an error.
>>
>> - Mike
>>
>>

Re: Docker guacamole+guacd 1.2.0 gets stuck once a day randomly

Posted by Regev Batchen <ba...@gmail.com>.
Hey,

I dont have the full psql container query because i tried to do tail of
20000 lines and it still shows

postgres_gqm_1    | 2021-09-13T14:59:26.208973521Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208975652Z
 $22740::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208977856Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208980045Z
 $22741::integer

docker ps shows containers are healthy even when service is stuck

Is there a way to add guacamole debug logs?
In the guacd container I have : GUACD_LOG_LEVEL: debug
but for guacamole ?

It looks like psql did a restart after the big quarry and guacd did too but
something is not working correctly until i restart the guacd and guacamole
and then it works for a day or two,
what else can I check?
I am going to create a bash script to restart containers if service is
stuck because I really don't know what else to do :(




‫בתאריך יום ד׳, 22 בספט׳ 2021 ב-22:40 מאת ‪Mike Jumper‬‏ <‪
mike.jumper@glyptodon.com‬‏>:‬

> On Wed, Sep 22, 2021 at 5:45 AM Regev Batchen <ba...@gmail.com> wrote:
>
>> Hello, the postgres error happened again.
>> I dont understand why postgrece started to get stuck after i updated to
>> guacamole 1.3 , befor i had an error that it was only get stuck with no sql
>> error.
>> i use psql 12.3 and here are some logs, PLEASE let me know what else i
>> can check and debug? i have 10 more containers on this docker-compose and
>> only guacamole gets stuck.
>>
>> It just stop receiving api request and no logs are written to the guac
>> containers.
>> what causing this SELECT error?
>>
>
> Do you have the full message from the PostgreSQL container?
>
> The log message you see from guacd (Error reading "select") is not
> actually related to the database (which is only used by the webapp, not
> guacd). This is guacd letting you know that a connection to guacd was
> established and then closed during the Guacamole protocol handshake, the
> first message of which happens to be called "select". This is the result of
> a healthcheck ping within Docker and is not an error.
>
> - Mike
>
>

Re: Docker guacamole+guacd 1.2.0 gets stuck once a day randomly

Posted by Mike Jumper <mi...@glyptodon.com>.
On Wed, Sep 22, 2021 at 5:45 AM Regev Batchen <ba...@gmail.com> wrote:

> Hello, the postgres error happened again.
> I dont understand why postgrece started to get stuck after i updated to
> guacamole 1.3 , befor i had an error that it was only get stuck with no sql
> error.
> i use psql 12.3 and here are some logs, PLEASE let me know what else i can
> check and debug? i have 10 more containers on this docker-compose and only
> guacamole gets stuck.
>
> It just stop receiving api request and no logs are written to the guac
> containers.
> what causing this SELECT error?
>

Do you have the full message from the PostgreSQL container?

The log message you see from guacd (Error reading "select") is not actually
related to the database (which is only used by the webapp, not guacd). This
is guacd letting you know that a connection to guacd was established and
then closed during the Guacamole protocol handshake, the first message of
which happens to be called "select". This is the result of a healthcheck
ping within Docker and is not an error.

- Mike

Re: Docker guacamole+guacd 1.2.0 gets stuck once a day randomly

Posted by Regev Batchen <ba...@gmail.com>.
Hello, the postgres error happened again.
I dont understand why postgrece started to get stuck after i updated to
guacamole 1.3 , befor i had an error that it was only get stuck with no sql
error.
i use psql 12.3 and here are some logs, PLEASE let me know what else i can
check and debug? i have 10 more containers on this docker-compose and only
guacamole gets stuck.

It just stop receiving api request and no logs are written to the guac
containers.
what causing this SELECT error?

guacamole container:
ly authenticated from [172.18.0.1, 172.18.0.19].
guacamole_1       | 2021-09-22T12:04:07.430728042Z 12:04:07.430
[http-nio-8080-exec-6] INFO  o.a.g.r.auth.AuthenticationService - User
"guacadmin" successfully authenticated from [172.18.0.1, 172.18.0.19].
guacamole_1       | 2021-09-22T12:04:11.566017923Z 12:04:11.565
[http-nio-8080-exec-9] INFO  o.a.g.tunnel.TunnelRequestService - User
"guacadmin" disconnected from connection "23220". Duration: 5674714
milliseconds
guacamole_1       | 2021-09-22T12:04:11.566735871Z Exception in thread
"Thread-552" java.lang.IllegalStateException: Message will not be sent
because the WebSocket session has been closed
guacamole_1       | 2021-09-22T12:04:11.566843330Z      at
org.apache.tomcat.websocket.WsRemoteEndpointImplBase.writeMessagePart(WsRemoteEndpointImplBase.java:438)
guacamole_1       | 2021-09-22T12:04:11.566857797Z      at
org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendMessageBlock(WsRemoteEndpointImplBase.java:311)
guacamole_1       | 2021-09-22T12:04:11.566883431Z      at
org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendMessageBlock(WsRemoteEndpointImplBase.java:251)
guacamole_1       | 2021-09-22T12:04:11.566886392Z      at
org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendString(WsRemoteEndpointImplBase.java:192)
guacamole_1       | 2021-09-22T12:04:11.566889001Z      at
org.apache.tomcat.websocket.WsRemoteEndpointBasic.sendText(WsRemoteEndpointBasic.java:37)
guacamole_1       | 2021-09-22T12:04:11.566891477Z      at
org.apache.guacamole.websocket.GuacamoleWebSocketTunnelEndpoint.sendInstruction(GuacamoleWebSocketTunnelEndpoint.java:152)
guacamole_1       | 2021-09-22T12:04:11.566894058Z      at
org.apache.guacamole.websocket.GuacamoleWebSocketTunnelEndpoint.access$200(GuacamoleWebSocketTunnelEndpoint.java:53)
guacamole_1       | 2021-09-22T12:04:11.566896437Z      at
org.apache.guacamole.websocket.GuacamoleWebSocketTunnelEndpoint$2.run(GuacamoleWebSocketTunnelEndpoint.java:253)
guacamole_1       | 2021-09-22T12:04:18.125077252Z 12:04:18.124
[http-nio-8080-exec-12] INFO  o.a.g.r.auth.AuthenticationService - User
"guacadmin" successfully authenticated from [172.18.0.1, 172.18.0.19].
guacamole_1       | 2021-09-22T12:04:28.250858537Z 12:04:28.250
[http-nio-8080-exec-8] INFO  o.a.g.r.auth.AuthenticationService - User
"guacadmin" successfully authenticated from [172.18.0.1, 172.18.0.19].
guacamole_1       | 2021-09-22T12:04:31.674122035Z 12:04:31.673
[http-nio-8080-exec-13] INFO  o.a.g.r.auth.AuthenticationService - User
"guacadmin" successfully authenticated from [172.18.0.1, 172.18.0.19].
guacamole_1       | 2021-09-22T12:04:38.268114338Z 12:04:38.266
[http-nio-8080-exec-5] INFO  o.a.g.r.auth.AuthenticationService - User
"guacadmin" successfully authenticated from [172.18.0.1, 172.18.0.19].
guacamole_1       | 2021-09-22T12:04:45.967076967Z 12:04:45.966
[http-nio-8080-exec-1] INFO  o.a.g.r.auth.AuthenticationService - User
"guacadmin" successfully authenticated from [172.18.0.1, 172.18.0.19].
guacamole_1       | 2021-09-22T12:04:48.738061916Z 12:04:48.736
[http-nio-8080-exec-14] INFO  o.a.g.r.auth.AuthenticationService - User
"guacadmin" successfully authenticated from [172.18.0.1, 172.18.0.19].
guacamole_1       | 2021-09-22T12:04:59.333466877Z 12:04:59.333
[http-nio-8080-exec-1] INFO  o.a.g.r.auth.AuthenticationService - User
"guacadmin" successfully authenticated from [172.18.0.1, 172.18.0.19].
guacamole_1       | 2021-09-22T12:04:59.915805852Z 12:04:59.915
[http-nio-8080-exec-6] INFO  o.a.g.r.auth.AuthenticationService - User
"guacadmin" successful

GUACD container:
guacd_1           | 2021-09-22T10:29:36.852260079Z guacd[39256]: DEBUG:
Client is using protocol version "VERSION_1_3_0"
guacd_1           | 2021-09-22T10:29:36.852342982Z guacd[39256]: DEBUG:
guac_rdp_fs_alloc: Creating directory "shared-WO" if necessary.
guacd_1           | 2021-09-22T10:29:36.852387457Z guacd[39256]: ERROR:
Unable to create directory "shared-WO": Permission denied
guacd_1           | 2021-09-22T10:29:36.854361876Z guacd[39256]: INFO:
 Loading keymap "base"
guacd_1           | 2021-09-22T10:29:36.854374290Z guacd[39256]: INFO:
 Loading keymap "en-us-qwerty"
guacd_1           | 2021-09-22T10:29:36.854773801Z guacd[39256]: DEBUG:
freerdp_connect:freerdp_set_last_error_ex resetting error state
guacd_1           | 2021-09-22T10:29:36.854841779Z guacd[39256]: DEBUG:
Support for CLIPRDR (clipboard redirection) registered. Awaiting channel
connection.
guacd_1           | 2021-09-22T10:29:36.856498816Z guacd[39256]: DEBUG:
Support for static channel "rdpdr" loaded.
guacd_1           | 2021-09-22T10:29:36.856509724Z guacd[39256]: DEBUG:
Support for static channel "rdpsnd" loaded.
guacd_1           | 2021-09-22T10:29:36.856512579Z guacd[39256]: DEBUG:
Local framebuffer format  PIXEL_FORMAT_BGRX32
guacd_1           | 2021-09-22T10:29:36.856515256Z guacd[39256]: DEBUG:
Remote framebuffer format PIXEL_FORMAT_RGB16
guacd_1           | 2021-09-22T10:29:36.946528508Z guacd[39256]: DEBUG:
guac_rdp_fs_open: path="/", access=0x80000000, file_attributes=0x0,
create_disposition=0x1, create_options=0x0
guacd_1           | 2021-09-22T10:29:36.946559468Z guacd[39256]: DEBUG:
guac_rdp_fs_open: Normalized path "/" to "\".
guacd_1           | 2021-09-22T10:29:36.946563443Z guacd[39256]: DEBUG:
guac_rdp_fs_open: Translated path "\" to "shared-WOR/".
guacd_1           | 2021-09-22T10:29:36.946566333Z guacd[39256]: DEBUG:
guac_rdp_fs_open: native open: real_path="shared-WOR/", flags=0x0
guacd_1           | 2021-09-22T10:29:36.946568942Z guacd[39256]: DEBUG:
guac_rdp_fs_open: open() failed: No such file or directory
guacd_1           | 2021-09-22T10:29:36.946571523Z guacd[39256]: INFO:
 Unable to read file "/"
guacd_1           | 2021-09-22T10:29:37.188702211Z guacd[39256]: DEBUG:
primitives autodetect, using optimized
guacd_1           | 2021-09-22T10:29:37.203643529Z guacd[39256]: DEBUG:
freerdp_tcp_is_hostname_resolvable:freerdp_set_last_error_ex resetting
error state
guacd_1           | 2021-09-22T10:29:37.203678306Z guacd[39256]: DEBUG:
freerdp_tcp_connect:freerdp_set_last_error_ex resetting error state
guacd_1           | 2021-09-22T10:29:37.217794485Z guacd[39256]: DEBUG:
VERSION ={
guacd_1           | 2021-09-22T10:29:37.217825497Z guacd[39256]: DEBUG:
    ProductMajorVersion: 6
guacd_1           | 2021-09-22T10:29:37.217828652Z guacd[39256]: DEBUG:
    ProductMinorVersion: 1
guacd_1           | 2021-09-22T10:29:37.217831827Z guacd[39256]: DEBUG:
    ProductBuild: 7601
guacd_1           | 2021-09-22T10:29:37.217834258Z guacd[39256]: DEBUG:
    Reserved: 0x000000
guacd_1           | 2021-09-22T10:29:37.217836592Z guacd[39256]: DEBUG:
    NTLMRevisionCurrent: 0x0F
guacd_1           | 2021-09-22T10:29:37.318338229Z guacd[39256]: DEBUG:
negotiateFlags "0xE28A8235"
guacd_1           | 2021-09-22T10:29:37.318364261Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_56 (0),
guacd_1           | 2021-09-22T10:29:37.318368002Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_KEY_EXCH (1),
guacd_1           | 2021-09-22T10:29:37.318370688Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_128 (2),
guacd_1           | 2021-09-22T10:29:37.318412855Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_VERSION (6),
guacd_1           | 2021-09-22T10:29:37.318437004Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_TARGET_INFO (8),
guacd_1           | 2021-09-22T10:29:37.318440677Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_EXTENDED_SESSION_SECURITY (12),
guacd_1           | 2021-09-22T10:29:37.318443349Z guacd[39256]: DEBUG:
    NTLMSSP_TARGET_TYPE_SERVER (14),
guacd_1           | 2021-09-22T10:29:37.318478417Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_ALWAYS_SIGN (16),
guacd_1           | 2021-09-22T10:29:37.318566326Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_NTLM (22),
guacd_1           | 2021-09-22T10:29:37.318676209Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_SEAL (26),
guacd_1           | 2021-09-22T10:29:37.318720125Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_SIGN (27),
guacd_1           | 2021-09-22T10:29:37.318791002Z guacd[39256]: DEBUG:
    NTLMSSP_REQUEST_TARGET (29),
guacd_1           | 2021-09-22T10:29:37.318872054Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_UNICODE (31),
guacd_1           | 2021-09-22T10:29:37.318877311Z guacd[39256]: DEBUG:
VERSION ={
guacd_1           | 2021-09-22T10:29:37.318879846Z guacd[39256]: DEBUG:
    ProductMajorVersion: 10
guacd_1           | 2021-09-22T10:29:37.318882178Z guacd[39256]: DEBUG:
    ProductMinorVersion: 0
guacd_1           | 2021-09-22T10:29:37.318884539Z guacd[39256]: DEBUG:
    ProductBuild: 17763
guacd_1           | 2021-09-22T10:29:37.318890980Z guacd[39256]: DEBUG:
    Reserved: 0x000000
guacd_1           | 2021-09-22T10:29:37.318932230Z guacd[39256]: DEBUG:
    NTLMRevisionCurrent: 0x0F
guacd_1           | 2021-09-22T10:29:37.319117916Z guacd[39256]: DEBUG:
AV_PAIRs =
guacd_1           | 2021-09-22T10:29:37.319198577Z guacd[39256]: DEBUG:
    MsvAvNbDomainName AvId: 2 AvLen: 380482120
guacd_1           | 2021-09-22T10:29:37.319259501Z guacd[39256]: DEBUG:
0000 43 00 36 00 32 00 41 00 31 00 36 00 33 00 32 00 C.6.2.A.1.6.3.2.
guacd_1           | 2021-09-22T10:29:37.319324096Z guacd[39256]: DEBUG:
0016 30 00 35 00 38 00 33 00 37 00 37 00             0.5.8.3.7.7.
guacd_1           | 2021-09-22T10:29:37.319377151Z guacd[39256]: DEBUG:
[length=28]
guacd_1           | 2021-09-22T10:29:37.319445252Z guacd[39256]: DEBUG:
    MsvAvNbComputerName AvId: 1 AvLen: 380482120
guacd_1           | 2021-09-22T10:29:37.319512506Z guacd[39256]: DEBUG:
0000 43 00 36 00 32 00 41 00 31 00 36 00 33 00 32 00 C.6.2.A.1.6.3.2.
guacd_1           | 2021-09-22T10:29:37.319640736Z guacd[39256]: DEBUG:
0016 30 00 35 00 38 00 33 00 37 00 37 00             0.5.8.3.7.7.
guacd_1           | 2021-09-22T10:29:37.319644805Z guacd[39256]: DEBUG:
[length=28]
guacd_1           | 2021-09-22T10:29:37.319647148Z guacd[39256]: DEBUG:
    MsvAvDnsDomainName AvId: 4 AvLen: 380482120
guacd_1           | 2021-09-22T10:29:37.319649485Z guacd[39256]: DEBUG:
0000 43 00 36 00 32 00 41 00 31 00 36 00 33 00 32 00 C.6.2.A.1.6.3.2.
guacd_1           | 2021-09-22T10:29:37.319770006Z guacd[39256]: DEBUG:
0016 30 00 35 00 38 00 33 00 37 00 37 00             0.5.8.3.7.7.
guacd_1           | 2021-09-22T10:29:37.319775050Z guacd[39256]: DEBUG:
[length=28]
guacd_1           | 2021-09-22T10:29:37.319777617Z guacd[39256]: DEBUG:
    MsvAvDnsComputerName AvId: 3 AvLen: 380482120
guacd_1           | 2021-09-22T10:29:37.319780329Z guacd[39256]: DEBUG:
0000 43 00 36 00 32 00 41 00 31 00 36 00 33 00 32 00 C.6.2.A.1.6.3.2.
guacd_1           | 2021-09-22T10:29:37.319782664Z guacd[39256]: DEBUG:
0016 30 00 35 00 38 00 33 00 37 00 37 00             0.5.8.3.7.7.
guacd_1           | 2021-09-22T10:29:37.319785110Z guacd[39256]: DEBUG:
[length=28]
guacd_1           | 2021-09-22T10:29:37.319798298Z guacd[39256]: DEBUG:
    MsvAvTimestamp AvId: 7 AvLen: 380482120
guacd_1           | 2021-09-22T10:29:37.319963212Z guacd[39256]: DEBUG:
0000 aa 1c 24 d4 11 b0 d7 01                         ..$.....
guacd_1           | 2021-09-22T10:29:37.319972919Z guacd[39256]: DEBUG:
[length=8]
guacd_1           | 2021-09-22T10:29:37.320376293Z guacd[39256]: DEBUG:
negotiateFlags "0xE288A235"
guacd_1           | 2021-09-22T10:29:37.320388678Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_56 (0),
guacd_1           | 2021-09-22T10:29:37.320392007Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_KEY_EXCH (1),
guacd_1           | 2021-09-22T10:29:37.320394664Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_128 (2),
guacd_1           | 2021-09-22T10:29:37.320398803Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_VERSION (6),
guacd_1           | 2021-09-22T10:29:37.320439684Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_TARGET_INFO (8),
guacd_1           | 2021-09-22T10:29:37.320457474Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_EXTENDED_SESSION_SECURITY (12),
guacd_1           | 2021-09-22T10:29:37.320510617Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_ALWAYS_SIGN (16),
guacd_1           | 2021-09-22T10:29:37.320518504Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_WORKSTATION_SUPPLIED (18),
guacd_1           | 2021-09-22T10:29:37.320521002Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_NTLM (22),
guacd_1           | 2021-09-22T10:29:37.320523460Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_SEAL (26),
guacd_1           | 2021-09-22T10:29:37.320526179Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_SIGN (27),
guacd_1           | 2021-09-22T10:29:37.320528551Z guacd[39256]: DEBUG:
    NTLMSSP_REQUEST_TARGET (29),
guacd_1           | 2021-09-22T10:29:37.320530882Z guacd[39256]: DEBUG:
    NTLMSSP_NEGOTIATE_UNICODE (31),
guacd_1           | 2021-09-22T10:29:37.320544029Z guacd[39256]: DEBUG:
VERSION ={
guacd_1           | 2021-09-22T10:29:37.320559250Z guacd[39256]: DEBUG:
    ProductMajorVersion: 6
guacd_1           | 2021-09-22T10:29:37.320562192Z guacd[39256]: DEBUG:
    ProductMinorVersion: 1
guacd_1           | 2021-09-22T10:29:37.320564529Z guacd[39256]: DEBUG:
    ProductBuild: 7601
guacd_1           | 2021-09-22T10:29:37.320566871Z guacd[39256]: DEBUG:
    Reserved: 0x000000
guacd_1           | 2021-09-22T10:29:37.320592122Z guacd[39256]: DEBUG:
    NTLMRevisionCurrent: 0x0F
guacd_1           | 2021-09-22T10:29:37.320600883Z guacd[39256]: DEBUG:
AV_PAIRs =
guacd_1           | 2021-09-22T10:29:37.320603866Z guacd[39256]: DEBUG:
    MsvAvNbDomainName AvId: 2 AvLen: 380482120
guacd_1           | 2021-09-22T10:29:37.320648364Z guacd[39256]: DEBUG:
0000 43 00 36 00 32 00 41 00 31 00 36 00 33 00 32 00 C.6.2.A.1.6.3.2.
guacd_1           | 2021-09-22T10:29:37.320656502Z guacd[39256]: DEBUG:
0016 30 00 35 00 38 00 33 00 37 00 37 00             0.5.8.3.7.7.
guacd_1           | 2021-09-22T10:29:37.320659276Z guacd[39256]: DEBUG:
[length=28]
guacd_1           | 2021-09-22T10:29:37.320661685Z guacd[39256]: DEBUG:
    MsvAvNbComputerName AvId: 1 AvLen: 380482120
guacd_1           | 2021-09-22T10:29:37.320680802Z guacd[39256]: DEBUG:
0000 43 00 36 00 32 00 41 00 31 00 36 00 33 00 32 00 C.6.2.A.1.6.3.2.
guacd_1           | 2021-09-22T10:29:37.320689653Z guacd[39256]: DEBUG:
0016 30 00 35 00 38 00 33 00 37 00 37 00             0.5.8.3.7.7.
guacd_1           | 2021-09-22T10:29:37.320736722Z guacd[39256]: DEBUG:
[length=28]
guacd_1           | 2021-09-22T10:29:37.320743835Z guacd[39256]: DEBUG:
    MsvAvDnsDomainName AvId: 4 AvLen: 380482120
guacd_1           | 2021-09-22T10:29:37.320746300Z guacd[39256]: DEBUG:
0000 43 00 36 00 32 00 41 00 31 00 36 00 33 00 32 00 C.6.2.A.1.6.3.2.
guacd_1           | 2021-09-22T10:29:37.320748870Z guacd[39256]: DEBUG:
0016 30 00 35 00 38 00 33 00 37 00 37 00             0.5.8.3.7.7.
guacd_1           | 2021-09-22T10:29:37.320780099Z guacd[39256]: DEBUG:
[length=28]
guacd_1           | 2021-09-22T10:29:37.320822345Z guacd[39256]: DEBUG:
    MsvAvDnsComputerName AvId: 3 AvLen: 380482120
guacd_1           | 2021-09-22T10:29:37.320826563Z guacd[39256]: DEBUG:
0000 43 00 36 00 32 00 41 00 31 00 36 00 33 00 32 00 C.6.2.A.1.6.3.2.
guacd_1           | 2021-09-22T10:29:37.320866960Z guacd[39256]: DEBUG:
0016 30 00 35 00 38 00 33 00 37 00 37 00             0.5.8.3.7.7.
guacd_1           | 2021-09-22T10:29:37.320870440Z guacd[39256]: DEBUG:
[length=28]
guacd_1           | 2021-09-22T10:29:37.320927779Z guacd[39256]: DEBUG:
    MsvAvTimestamp AvId: 7 AvLen: 380482120
guacd_1           | 2021-09-22T10:29:37.320931477Z guacd[39256]: DEBUG:
0000 aa 1c 24 d4 11 b0 d7 01                         ..$.....
guacd_1           | 2021-09-22T10:29:37.320938007Z guacd[39256]: DEBUG:
[length=8]
guacd_1           | 2021-09-22T10:29:37.320969672Z guacd[39256]: DEBUG:
    MsvAvFlags AvId: 6 AvLen: 380482120
guacd_1           | 2021-09-22T10:29:37.320972977Z guacd[39256]: DEBUG:
0000 02 00 00 00                                     ....
guacd_1           | 2021-09-22T10:29:37.320992093Z guacd[39256]: DEBUG:
[length=4]
guacd_1           | 2021-09-22T10:29:37.321003321Z guacd[39256]: DEBUG:
    MsvChannelBindings AvId: 10 AvLen: 380482120
guacd_1           | 2021-09-22T10:29:37.321038499Z guacd[39256]: DEBUG:
0000 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
guacd_1           | 2021-09-22T10:29:37.321052115Z guacd[39256]: DEBUG:
[length=16]
guacd_1           | 2021-09-22T10:29:37.321065578Z guacd[39256]: DEBUG:
    MsvAvTargetName AvId: 9 AvLen: 380482120
guacd_1           | 2021-09-22T10:29:37.321068313Z guacd[39256]: DEBUG:
0000 54 00 45 00 52 00 4d 00 53 00 52 00 56 00 2f 00 T.E.R.M.S.R.V./.
guacd_1           | 2021-09-22T10:29:37.321089417Z guacd[39256]: DEBUG:
0016 31 00 30 00 2e 00 33 00 30 00 2e 00 36 00 32 00 1.0...3.0...6.2.
guacd_1           | 2021-09-22T10:29:37.321097300Z guacd[39256]: DEBUG:
0032 2e 00 33 00 32 00                               ..3.2.
guacd_1           | 2021-09-22T10:29:37.321099996Z guacd[39256]: DEBUG:
[length=38]
guacd_1           | 2021-09-22T10:29:38.323790993Z guacd[39256]: DEBUG:
Receiving
guacd_1           | 2021-09-22T10:29:38.323816088Z guacd[39256]: DEBUG:
ShareCapabilitySet (length 4):
guacd_1           | 2021-09-22T10:29:38.323819764Z guacd[39256]: DEBUG:
    nodeId: 0x03EA
guacd_1           | 2021-09-22T10:29:38.323822666Z guacd[39256]: DEBUG:
    pad2Octets: 0x0000
guacd_1           | 2021-09-22T10:29:38.323825197Z guacd[39256]: DEBUG:
Receiving
guacd_1           | 2021-09-22T10:29:38.324655895Z guacd[39256]: DEBUG:
GeneralCapabilitySet (length 20):
guacd_1           | 2021-09-22T10:29:38.324671425Z guacd[39256]: DEBUG:
    osMajorType: 0x0001
guacd_1           | 2021-09-22T10:29:38.324674544Z guacd[39256]: DEBUG:
    osMinorType: 0x0003
guacd_1           | 2021-09-22T10:29:38.324677111Z guacd[39256]: DEBUG:
    protocolVersion: 0x0200
guacd_1           | 2021-09-22T10:29:38.324679577Z guacd[39256]: DEBUG:
    pad2OctetsA: 0x0000
guacd_1           | 2021-09-22T10:29:38.324682057Z guacd[39256]: DEBUG:
    generalCompressionTypes: 0x0000
guacd_1           | 2021-09-22T10:29:38.324684709Z guacd[39256]: DEBUG:
    extraFlags: 0x041D
guacd_1           | 2021-09-22T10:29:38.324688299Z guacd[39256]: DEBUG:
    updateCapabilityFlag: 0x0000
guacd_1           | 2021-09-22T10:29:38.324690648Z guacd[39256]: DEBUG:
    remoteUnshareFlag: 0x0000
guacd_1           | 2021-09-22T10:29:38.324692950Z guacd[39256]: DEBUG:
    generalCompressionLevel: 0x0000
guacd_1           | 2021-09-22T10:29:38.324695327Z guacd[39256]: DEBUG:
    refreshRectSupport: 0x01
guacd_1           | 2021-09-22T10:29:38.324697872Z guacd[39256]: DEBUG:
    suppressOutputSupport: 0x01
guacd_1           | 2021-09-22T10:29:38.324700145Z guacd[39256]: DEBUG:
Receiving
guacd_1           | 2021-09-22T10:29:38.324702438Z guacd[39256]: DEBUG:
VirtualChannelCapabilitySet (length 8):
guacd_1           | 2021-09-22T10:29:38.324704896Z guacd[39256]: DEBUG:
    flags: 0x00000002
guacd_1           | 2021-09-22T10:29:38.324707330Z guacd[39256]: DEBUG:
    VCChunkSize: 0x00000640
guacd_1           | 2021-09-22T10:29:38.324709687Z guacd[39256]: DEBUG:
Receiving
guacd_1           | 2021-09-22T10:29:38.324712082Z guacd[39256]: DEBUG:
ColorCacheCapabilitySet (length 4):
guacd_1           | 2021-09-22T10:29:38.324714389Z guacd[39256]: DEBUG:
    colorTableCacheSize: 0x0006
guacd_1           | 2021-09-22T10:29:38.324716739Z guacd[39256]: DEBUG:
    pad2Octets: 0x0000
guacd_1           | 2021-09-22T10:29:38.324719011Z guacd[39256]: DEBUG:
Receiving
guacd_1           | 2021-09-22T10:29:38.324732171Z guacd[39256]: DEBUG:
PointerCapabilitySet (length 6):
guacd_1           | 2021-09-22T10:29:38.324734747Z guacd[39256]: DEBUG:
    colorPointerFlag: 0x0001
guacd_1           | 2021-09-22T10:29:38.324737126Z guacd[39256]: DEBUG:
    colorPointerCacheSize: 0x0019
guacd_1           | 2021-09-22T10:29:38.324739464Z guacd[39256]: DEBUG:
    pointerCacheSize: 0x0019
guacd_1           | 2021-09-22T10:29:38.324741844Z guacd[39256]: DEBUG:
Receiving
guacd_1           | 2021-09-22T10:29:38.324744044Z guacd[39256]: DEBUG:
LargePointerCapabilitySet (length 2):
guacd_1           | 2021-09-22T10:29:38.324746299Z guacd[39256]: DEBUG:
    largePointerSupportFlags: 0x0001
guacd_1           | 2021-09-22T10:29:38.324748981Z guacd[39256]: DEBUG:
Receiving
guacd_1           | 2021-09-22T10:29:38.324751200Z guacd[39256]: DEBUG:
FontCapabilitySet (length 4):
guacd_1           | 2021-09-22T10:29:38.324753662Z guacd[39256]: DEBUG:
    fontSupportFlags: 0x0001
guacd_1           | 2021-09-22T10:29:38.324756136Z guacd[39256]: DEBUG:
    pad2Octets: 0x0000
guacd_1           | 2021-09-22T10:29:38.324758496Z guacd[39256]: DEBUG:
Receiving
guacd_1           | 2021-09-22T10:29:38.324760698Z guacd[39256]: DEBUG:
BitmapCapabilitySet (length 24):
guacd_1           | 2021-09-22T10:29:38.324762972Z guacd[39256]: DEBUG:
    preferredBitsPerPixel: 0x0010
guacd_1           | 2021-09-22T10:29:38.324765366Z guacd[39256]: DEBUG:
    receive1BitPerPixel: 0x0001
guacd_1           | 2021-09-22T10:29:38.324767615Z guacd[39256]: DEBUG:
    receive4BitsPerPixel: 0x0001
guacd_1           | 2021-09-22T10:29:38.324769860Z guacd[39256]: DEBUG:
    receive8BitsPerPixel: 0x0001
guacd_1           | 2021-09-22T10:29:38.324772341Z guacd[39256]: DEBUG:
    desktopWidth: 0x073C
guacd_1           | 2021-09-22T10:29:38.324774806Z guacd[39256]: DEBUG:
    desktopHeight: 0x03B5
guacd_1           | 2021-09-22T10:29:38.324777155Z guacd[39256]: DEBUG:
    pad2Octets: 0x0000
guacd_1           | 2021-09-22T10:29:38.324779497Z guacd[39256]: DEBUG:
    desktopResizeFlag: 0x0001
guacd_1           | 2021-09-22T10:29:38.324781727Z guacd[39256]: DEBUG:
    bitmapCompressionFlag: 0x0001
guacd_1           | 2021-09-22T10:29:38.324784070Z guacd[39256]: DEBUG:
    highColorFlags: 0x00
guacd_1           | 2021-09-22T10:29:38.324787045Z guacd[39256]: DEBUG:
    drawingFlags: 0x1E
guacd_1           | 2021-09-22T10:29:38.324789316Z guacd[39256]: DEBUG:
    multipleRectangleSupport: 0x0001
guacd_1           | 2021-09-22T10:29:38.324791801Z guacd[39256]: DEBUG:
    pad2OctetsB: 0x0000
guacd_1           | 2021-09-22T10:29:38.324794326Z guacd[39256]: DEBUG:
Receiving
guacd_1           | 2021-09-22T10:29:38.324796705Z guacd[39256]: DEBUG:
BitmapCodecsCapabilitySet (length 92):
guacd_1           | 2021-09-22T10:29:38.324799398Z guacd[39256]: DEBUG:
    bitmapCodecCount: 4
guacd_1           | 2021-09-22T10:29:38.324801650Z guacd[39256]: DEBUG:
    codecGuid: 0x
guacd_1           | 2021-09-22T10:29:38.324803946Z guacd[39256]: DEBUG:
CA8D1BB9000F154F589FAE2D1A87E2D6
guacd_1           | 2021-09-22T10:29:38.324806215Z guacd[39256]: DEBUG:
 (CODEC_GUID_NSCODEC)
guacd_1           | 2021-09-22T10:29:38.324808451Z guacd[39256]: DEBUG:
    codecId: 0
guacd_1           | 2021-09-22T10:29:38.324814877Z guacd[39256]: DEBUG:
    codecPropertiesLength: 3
guacd_1           | 2021-09-22T10:29:38.324817303Z guacd[39256]: DEBUG:
    codecGuid: 0x
guacd_1           | 2021-09-22T10:29:38.324819664Z guacd[39256]: DEBUG:
76772F12BD724463AFB3B73C9C6F7886
guacd_1           | 2021-09-22T10:29:38.324822082Z guacd[39256]: DEBUG:
 (CODEC_GUID_REMOTEFX)
guacd_1           | 2021-09-22T10:29:38.324824345Z guacd[39256]: DEBUG:
    codecId: 0
guacd_1           | 2021-09-22T10:29:38.324826563Z guacd[39256]: DEBUG:
    codecPropertiesLength: 4
guacd_1           | 2021-09-22T10:29:38.324828807Z guacd[39256]: DEBUG:
    codecGuid: 0x
guacd_1           | 2021-09-22T10:29:38.324831039Z guacd[39256]: DEBUG:
9C4351A6353542AE910CCDFCE5760B58
guacd_1           | 2021-09-22T10:29:38.324833470Z guacd[39256]: DEBUG:
 (CODEC_GUID_IGNORE)
guacd_1           | 2021-09-22T10:29:38.324835849Z guacd[39256]: DEBUG:
    codecId: 0
guacd_1           | 2021-09-22T10:29:38.324838186Z guacd[39256]: DEBUG:
    codecPropertiesLength: 4
guacd_1           | 2021-09-22T10:29:38.324840708Z guacd[39256]: DEBUG:
    codecGuid: 0x
guacd_1           | 2021-09-22T10:29:38.324842969Z guacd[39256]: DEBUG:
2744CCD49D8A4E74803C0ECBEEA19C54
guacd_1           | 2021-09-22T10:29:38.324845262Z guacd[39256]: DEBUG:
 (CODEC_GUID_IMAGE_REMOTEFX)
guacd_1           | 2021-09-22T10:29:38.324847501Z guacd[39256]: DEBUG:
    codecId: 0
guacd_1           | 2021-09-22T10:29:38.324849738Z guacd[39256]: DEBUG:
    codecPropertiesLength: 4
guacd_1           | 2021-09-22T10:29:38.324852297Z guacd[39256]: DEBUG:
Receiving
guacd_1           | 2021-09-22T10:29:38.324854612Z guacd[39256]: DEBUG:
OrderCapabilitySet (length 84):
guacd_1           | 2021-09-22T10:29:38.324856970Z guacd[39256]: DEBUG:
    pad4OctetsA: 0x000F4240
guacd_1           | 2021-09-22T10:29:38.324859376Z guacd[39256]: DEBUG:
    desktopSaveXGranularity: 0x0001
guacd_1           | 2021-09-22T10:29:38.324861732Z guacd[39256]: DEBUG:
    desktopSaveYGranularity: 0x0014
guacd_1           | 2021-09-22T10:29:38.324864024Z guacd[39256]: DEBUG:
    pad2OctetsA: 0x0000
guacd_1           | 2021-09-22T10:29:38.324866281Z guacd[39256]: DEBUG:
    maximumOrderLevel: 0x0001
guacd_1           | 2021-09-22T10:29:38.324868566Z guacd[39256]: DEBUG:
    numberFonts: 0x0000
guacd_1           | 2021-09-22T10:29:38.324871095Z guacd[39256]: DEBUG:
    orderFlags: 0x00AA
guacd_1           | 2021-09-22T10:29:38.324873474Z guacd[39256]: DEBUG:
    orderSupport:
guacd_1           | 2021-09-22T10:29:38.324875846Z guacd[39256]: DEBUG:
            DSTBLT: 1
guacd_1           | 2021-09-22T10:29:38.324878257Z guacd[39256]: DEBUG:
            PATBLT: 1
guacd_1           | 2021-09-22T10:29:38.324880666Z guacd[39256]: DEBUG:
            SCRBLT: 1
guacd_1           | 2021-09-22T10:29:38.324883024Z guacd[39256]: DEBUG:
            MEMBLT: 1
guacd_1           | 2021-09-22T10:29:38.324885249Z guacd[39256]: DEBUG:
            MEM3BLT: 1
guacd_1           | 2021-09-22T10:29:38.324887520Z guacd[39256]: DEBUG:
            ATEXTOUT: 0
guacd_1           | 2021-09-22T10:29:38.324890008Z guacd[39256]: DEBUG:
            AEXTTEXTOUT: 0
guacd_1           | 2021-09-22T10:29:38.324894969Z guacd[39256]: DEBUG:
            DRAWNINEGRID: 0
guacd_1           | 2021-09-22T10:29:38.324897457Z guacd[39256]: DEBUG:
            LINETO: 1
guacd_1           | 2021-09-22T10:29:38.324899754Z guacd[39256]: DEBUG:
            MULTI_DRAWNINEGRID: 0
guacd_1           | 2021-09-22T10:29:38.324902040Z guacd[39256]: DEBUG:
            OPAQUE_RECT: 0
guacd_1           | 2021-09-22T10:29:38.324907488Z guacd[39256]: DEBUG:
            SAVEBITMAP: 1
guacd_1           | 2021-09-22T10:29:38.324909914Z guacd[39256]: DEBUG:
            WTEXTOUT: 0
guacd_1           | 2021-09-22T10:29:38.324912385Z guacd[39256]: DEBUG:
            MEMBLT_V2: 0
guacd_1           | 2021-09-22T10:29:38.324914870Z guacd[39256]: DEBUG:
            MEM3BLT_V2: 0
guacd_1           | 2021-09-22T10:29:38.324917250Z guacd[39256]: DEBUG:
            MULTIDSTBLT: 1
guacd_1           | 2021-09-22T10:29:38.324919648Z guacd[39256]: DEBUG:
            MULTIPATBLT: 1
guacd_1           | 2021-09-22T10:29:38.324922012Z guacd[39256]: DEBUG:
            MULTISCRBLT: 1
guacd_1           | 2021-09-22T10:29:38.324924240Z guacd[39256]: DEBUG:
            MULTIOPAQUERECT: 1
guacd_1           | 2021-09-22T10:29:38.324926601Z guacd[39256]: DEBUG:
            FAST_INDEX: 1
guacd_1           | 2021-09-22T10:29:38.324929599Z guacd[39256]: DEBUG:
            POLYGON_SC: 1
guacd_1           | 2021-09-22T10:29:38.324931928Z guacd[39256]: DEBUG:
            POLYGON_CB: 1
guacd_1           | 2021-09-22T10:29:38.324941651Z guacd[39256]: DEBUG:
            POLYLINE: 1
guacd_1           | 2021-09-22T10:29:38.324944366Z guacd[39256]: DEBUG:
            UNUSED23: 0
guacd_1           | 2021-09-22T10:29:38.324956905Z guacd[39256]: DEBUG:
            FAST_GLYPH: 1
guacd_1           | 2021-09-22T10:29:38.324994626Z guacd[39256]: DEBUG:
            ELLIPSE_SC: 1
guacd_1           | 2021-09-22T10:29:38.325003983Z guacd[39256]: DEBUG:
            ELLIPSE_CB: 1
guacd_1           | 2021-09-22T10:29:38.325006755Z guacd[39256]: DEBUG:
            GLYPH_INDEX: 1
guacd_1           | 2021-09-22T10:29:38.325010053Z guacd[39256]: DEBUG:
            GLYPH_WEXTTEXTOUT: 0
guacd_1           | 2021-09-22T10:29:38.325013013Z guacd[39256]: DEBUG:
            GLYPH_WLONGTEXTOUT: 0
guacd_1           | 2021-09-22T10:29:38.325032775Z guacd[39256]: DEBUG:
            GLYPH_WLONGEXTTEXTOUT: 0
guacd_1           | 2021-09-22T10:29:38.325044174Z guacd[39256]: DEBUG:
            UNUSED31: 0
guacd_1           | 2021-09-22T10:29:38.325074919Z guacd[39256]: DEBUG:
    textFlags: 0x06A1
guacd_1           | 2021-09-22T10:29:38.325078444Z guacd[39256]: DEBUG:
    orderSupportExFlags: 0x0006
guacd_1           | 2021-09-22T10:29:38.325089816Z guacd[39256]: DEBUG:
    pad4OctetsB: 0x000F4240
guacd_1           | 2021-09-22T10:29:38.325095942Z guacd[39256]: DEBUG:
    desktopSaveSize: 0x000F4240
guacd_1           | 2021-09-22T10:29:38.325129430Z guacd[39256]: DEBUG:
    pad2OctetsC: 0x0001
guacd_1           | 2021-09-22T10:29:38.325133757Z guacd[39256]: DEBUG:
    pad2OctetsD: 0x0000
guacd_1           | 2021-09-22T10:29:38.325144555Z guacd[39256]: DEBUG:
    textANSICodePage: 0x0000
guacd_1           | 2021-09-22T10:29:38.325167221Z guacd[39256]: DEBUG:
    pad2OctetsE: 0x0000
guacd_1           | 2021-09-22T10:29:38.325171671Z guacd[39256]: DEBUG:
Receiving
guacd_1           | 2021-09-22T10:29:38.325182661Z guacd[39256]: DEBUG:
BitmapCacheHostSupportCapabilitySet (length 4):
guacd_1           | 2021-09-22T10:29:38.325195474Z guacd[39256]: DEBUG:
    cacheVersion: 0x01
guacd_1           | 2021-09-22T10:29:38.325239755Z guacd[39256]: DEBUG:
    pad1: 0x00
guacd_1           | 2021-09-22T10:29:38.325243655Z guacd[39256]: DEBUG:
    pad2: 0x0000
guacd_1           | 2021-09-22T10:29:38.325245937Z guacd[39256]: DEBUG:
Receiving
guacd_1           | 2021-09-22T10:29:38.325248269Z guacd[39256]: DEBUG:
InputCapabilitySet (length 84)
guacd_1           | 2021-09-22T10:29:38.325255190Z guacd[39256]: DEBUG:
    inputFlags: 0x0375
guacd_1           | 2021-09-22T10:29:38.325268149Z guacd[39256]: DEBUG:
    pad2OctetsA: 0x0000
guacd_1           | 2021-09-22T10:29:38.325289001Z guacd[39256]: DEBUG:
    keyboardLayout: 0x00000000
guacd_1           | 2021-09-22T10:29:38.325291891Z guacd[39256]: DEBUG:
    keyboardType: 0x00000000
guacd_1           | 2021-09-22T10:29:38.325303202Z guacd[39256]: DEBUG:
    keyboardSubType: 0x00000000
guacd_1           | 2021-09-22T10:29:38.325333040Z guacd[39256]: DEBUG:
    keyboardFunctionKey: 0x00000000
guacd_1           | 2021-09-22T10:29:38.325338027Z guacd[39256]: DEBUG:
Receiving
guacd_1           | 2021-09-22T10:29:38.325344709Z guacd[39256]: DEBUG:
RemoteProgramsCapabilitySet (length 4):
guacd_1           | 2021-09-22T10:29:38.325368464Z guacd[39256]: DEBUG:
    railSupportLevel: 0x000000FF
guacd_1           | 2021-09-22T10:29:38.325371438Z guacd[39256]: DEBUG:
Receiving
guacd_1           | 2021-09-22T10:29:38.325377279Z guacd[39256]: DEBUG:
WindowListCapabilitySet (length 7):
guacd_1           | 2021-09-22T10:29:38.325383392Z guacd[39256]: DEBUG:
    wndSupportLevel: 0x00000002
guacd_1           | 2021-09-22T10:29:38.325421137Z guacd[39256]: DEBUG:
    numIconCaches: 0x03
guacd_1           | 2021-09-22T10:29:38.325424568Z guacd[39256]: DEBUG:
    numIconCacheEntries: 0x000C
guacd_1           | 2021-09-22T10:29:38.325448127Z guacd[39256]: DEBUG:
Receiving
guacd_1           | 2021-09-22T10:29:38.325451367Z guacd[39256]: DEBUG:
MultifragmentUpdateCapabilitySet (length 4):
guacd_1           | 2021-09-22T10:29:38.325462786Z guacd[39256]: DEBUG:
    maxRequestSize: 0x000094A7
guacd_1           | 2021-09-22T10:29:38.325487083Z guacd[39256]: DEBUG:
Receiving
guacd_1           | 2021-09-22T10:29:38.325490039Z guacd[39256]: DEBUG:
SurfaceCommandsCapabilitySet (length 8):
guacd_1           | 2021-09-22T10:29:38.325502015Z guacd[39256]: DEBUG:
    cmdFlags: 0x00000052
guacd_1           | 2021-09-22T10:29:38.325521500Z guacd[39256]: DEBUG:
    reserved: 0x00000000
guacd_1           | 2021-09-22T10:29:38.325524359Z guacd[39256]: DEBUG:
Receiving
guacd_1           | 2021-09-22T10:29:38.325546880Z guacd[39256]: DEBUG:
FrameAcknowledgeCapabilitySet (length 4):
guacd_1           | 2021-09-22T10:29:38.325551347Z guacd[39256]: DEBUG:
    frameAcknowledge: 0x00000000
guacd_1           | 2021-09-22T10:29:38.325562806Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.325570402Z guacd[39256]: DEBUG:
GeneralCapabilitySet (length 20):
guacd_1           | 2021-09-22T10:29:38.325600061Z guacd[39256]: DEBUG:
    osMajorType: 0x0000
guacd_1           | 2021-09-22T10:29:38.325606253Z guacd[39256]: DEBUG:
    osMinorType: 0x0000
guacd_1           | 2021-09-22T10:29:38.325671830Z guacd[39256]: DEBUG:
    protocolVersion: 0x0200
guacd_1           | 2021-09-22T10:29:38.325675430Z guacd[39256]: DEBUG:
    pad2OctetsA: 0x0000
guacd_1           | 2021-09-22T10:29:38.325677739Z guacd[39256]: DEBUG:
    generalCompressionTypes: 0x0000
guacd_1           | 2021-09-22T10:29:38.325680141Z guacd[39256]: DEBUG:
    extraFlags: 0x0415
guacd_1           | 2021-09-22T10:29:38.325682402Z guacd[39256]: DEBUG:
    updateCapabilityFlag: 0x0000
guacd_1           | 2021-09-22T10:29:38.325684701Z guacd[39256]: DEBUG:
    remoteUnshareFlag: 0x0000
guacd_1           | 2021-09-22T10:29:38.325692246Z guacd[39256]: DEBUG:
    generalCompressionLevel: 0x0000
guacd_1           | 2021-09-22T10:29:38.325708908Z guacd[39256]: DEBUG:
    refreshRectSupport: 0x01
guacd_1           | 2021-09-22T10:29:38.325711723Z guacd[39256]: DEBUG:
    suppressOutputSupport: 0x01
guacd_1           | 2021-09-22T10:29:38.325744910Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.325749475Z guacd[39256]: DEBUG:
BitmapCapabilitySet (length 24):
guacd_1           | 2021-09-22T10:29:38.325756042Z guacd[39256]: DEBUG:
    preferredBitsPerPixel: 0x0010
guacd_1           | 2021-09-22T10:29:38.325776844Z guacd[39256]: DEBUG:
    receive1BitPerPixel: 0x0001
guacd_1           | 2021-09-22T10:29:38.325779794Z guacd[39256]: DEBUG:
    receive4BitsPerPixel: 0x0001
guacd_1           | 2021-09-22T10:29:38.325796626Z guacd[39256]: DEBUG:
    receive8BitsPerPixel: 0x0001
guacd_1           | 2021-09-22T10:29:38.325799963Z guacd[39256]: DEBUG:
    desktopWidth: 0x073C
guacd_1           | 2021-09-22T10:29:38.325811036Z guacd[39256]: DEBUG:
    desktopHeight: 0x03B5
guacd_1           | 2021-09-22T10:29:38.325833831Z guacd[39256]: DEBUG:
    pad2Octets: 0x0000
guacd_1           | 2021-09-22T10:29:38.325836793Z guacd[39256]: DEBUG:
    desktopResizeFlag: 0x0001
guacd_1           | 2021-09-22T10:29:38.325847888Z guacd[39256]: DEBUG:
    bitmapCompressionFlag: 0x0001
guacd_1           | 2021-09-22T10:29:38.325857609Z guacd[39256]: DEBUG:
    highColorFlags: 0x00
guacd_1           | 2021-09-22T10:29:38.325867868Z guacd[39256]: DEBUG:
    drawingFlags: 0x0E
guacd_1           | 2021-09-22T10:29:38.325892681Z guacd[39256]: DEBUG:
    multipleRectangleSupport: 0x0001
guacd_1           | 2021-09-22T10:29:38.325895591Z guacd[39256]: DEBUG:
    pad2OctetsB: 0x0000
guacd_1           | 2021-09-22T10:29:38.325906745Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.325912981Z guacd[39256]: DEBUG:
OrderCapabilitySet (length 84):
guacd_1           | 2021-09-22T10:29:38.325949628Z guacd[39256]: DEBUG:
    pad4OctetsA: 0x00000000
guacd_1           | 2021-09-22T10:29:38.325953322Z guacd[39256]: DEBUG:
    desktopSaveXGranularity: 0x0001
guacd_1           | 2021-09-22T10:29:38.325973602Z guacd[39256]: DEBUG:
    desktopSaveYGranularity: 0x0014
guacd_1           | 2021-09-22T10:29:38.325976374Z guacd[39256]: DEBUG:
    pad2OctetsA: 0x0000
guacd_1           | 2021-09-22T10:29:38.325989282Z guacd[39256]: DEBUG:
    maximumOrderLevel: 0x0001
guacd_1           | 2021-09-22T10:29:38.326043904Z guacd[39256]: DEBUG:
    numberFonts: 0x0000
guacd_1           | 2021-09-22T10:29:38.326049435Z guacd[39256]: DEBUG:
    orderFlags: 0x00AA
guacd_1           | 2021-09-22T10:29:38.326060396Z guacd[39256]: DEBUG:
    orderSupport:
guacd_1           | 2021-09-22T10:29:38.326079940Z guacd[39256]: DEBUG:
            DSTBLT: 1
guacd_1           | 2021-09-22T10:29:38.326115994Z guacd[39256]: DEBUG:
            PATBLT: 0
guacd_1           | 2021-09-22T10:29:38.326119803Z guacd[39256]: DEBUG:
            SCRBLT: 1
guacd_1           | 2021-09-22T10:29:38.326122209Z guacd[39256]: DEBUG:
            MEMBLT: 1
guacd_1           | 2021-09-22T10:29:38.326138931Z guacd[39256]: DEBUG:
            MEM3BLT: 0
guacd_1           | 2021-09-22T10:29:38.326141772Z guacd[39256]: DEBUG:
            ATEXTOUT: 0
guacd_1           | 2021-09-22T10:29:38.326170547Z guacd[39256]: DEBUG:
            AEXTTEXTOUT: 0
guacd_1           | 2021-09-22T10:29:38.326174155Z guacd[39256]: DEBUG:
            DRAWNINEGRID: 0
guacd_1           | 2021-09-22T10:29:38.326184688Z guacd[39256]: DEBUG:
            LINETO: 0
guacd_1           | 2021-09-22T10:29:38.326210698Z guacd[39256]: DEBUG:
            MULTI_DRAWNINEGRID: 0
guacd_1           | 2021-09-22T10:29:38.326214080Z guacd[39256]: DEBUG:
            OPAQUE_RECT: 0
guacd_1           | 2021-09-22T10:29:38.326216400Z guacd[39256]: DEBUG:
            SAVEBITMAP: 0
guacd_1           | 2021-09-22T10:29:38.326242331Z guacd[39256]: DEBUG:
            WTEXTOUT: 0
guacd_1           | 2021-09-22T10:29:38.326245796Z guacd[39256]: DEBUG:
            MEMBLT_V2: 0
guacd_1           | 2021-09-22T10:29:38.326254954Z guacd[39256]: DEBUG:
            MEM3BLT_V2: 0
guacd_1           | 2021-09-22T10:29:38.326257444Z guacd[39256]: DEBUG:
            MULTIDSTBLT: 0
guacd_1           | 2021-09-22T10:29:38.326293323Z guacd[39256]: DEBUG:
            MULTIPATBLT: 0
guacd_1           | 2021-09-22T10:29:38.326296903Z guacd[39256]: DEBUG:
            MULTISCRBLT: 0
guacd_1           | 2021-09-22T10:29:38.326305308Z guacd[39256]: DEBUG:
            MULTIOPAQUERECT: 0
guacd_1           | 2021-09-22T10:29:38.326324456Z guacd[39256]: DEBUG:
            FAST_INDEX: 1
guacd_1           | 2021-09-22T10:29:38.326347440Z guacd[39256]: DEBUG:
            POLYGON_SC: 0
guacd_1           | 2021-09-22T10:29:38.326350463Z guacd[39256]: DEBUG:
            POLYGON_CB: 0
guacd_1           | 2021-09-22T10:29:38.326358917Z guacd[39256]: DEBUG:
            POLYLINE: 0
guacd_1           | 2021-09-22T10:29:38.326377523Z guacd[39256]: DEBUG:
            UNUSED23: 0
guacd_1           | 2021-09-22T10:29:38.326393609Z guacd[39256]: DEBUG:
            FAST_GLYPH: 1
guacd_1           | 2021-09-22T10:29:38.326410390Z guacd[39256]: DEBUG:
            ELLIPSE_SC: 0
guacd_1           | 2021-09-22T10:29:38.326413192Z guacd[39256]: DEBUG:
            ELLIPSE_CB: 0
guacd_1           | 2021-09-22T10:29:38.326443201Z guacd[39256]: DEBUG:
            GLYPH_INDEX: 1
guacd_1           | 2021-09-22T10:29:38.326446535Z guacd[39256]: DEBUG:
            GLYPH_WEXTTEXTOUT: 0
guacd_1           | 2021-09-22T10:29:38.326458430Z guacd[39256]: DEBUG:
            GLYPH_WLONGTEXTOUT: 0
guacd_1           | 2021-09-22T10:29:38.326470443Z guacd[39256]: DEBUG:
            GLYPH_WLONGEXTTEXTOUT: 0
guacd_1           | 2021-09-22T10:29:38.326482711Z guacd[39256]: DEBUG:
            UNUSED31: 0
guacd_1           | 2021-09-22T10:29:38.326505030Z guacd[39256]: DEBUG:
    textFlags: 0x0000
guacd_1           | 2021-09-22T10:29:38.326507899Z guacd[39256]: DEBUG:
    orderSupportExFlags: 0x0004
guacd_1           | 2021-09-22T10:29:38.326516580Z guacd[39256]: DEBUG:
    pad4OctetsB: 0x00000000
guacd_1           | 2021-09-22T10:29:38.326537900Z guacd[39256]: DEBUG:
    desktopSaveSize: 0x00038400
guacd_1           | 2021-09-22T10:29:38.326550054Z guacd[39256]: DEBUG:
    pad2OctetsC: 0x0000
guacd_1           | 2021-09-22T10:29:38.326568092Z guacd[39256]: DEBUG:
    pad2OctetsD: 0x0000
guacd_1           | 2021-09-22T10:29:38.326577607Z guacd[39256]: DEBUG:
    textANSICodePage: 0xFDE9
guacd_1           | 2021-09-22T10:29:38.326593246Z guacd[39256]: DEBUG:
    pad2OctetsE: 0x0000
guacd_1           | 2021-09-22T10:29:38.326599763Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.326625340Z guacd[39256]: DEBUG:
BitmapCacheV2CapabilitySet (length 36):
guacd_1           | 2021-09-22T10:29:38.326636110Z guacd[39256]: DEBUG:
    cacheFlags: 0x0003
guacd_1           | 2021-09-22T10:29:38.326642135Z guacd[39256]: DEBUG:
    pad2: 0x00
guacd_1           | 2021-09-22T10:29:38.326670428Z guacd[39256]: DEBUG:
    numCellCaches: 0x05
guacd_1           | 2021-09-22T10:29:38.326673803Z guacd[39256]: DEBUG:
    bitmapCache0CellInfo: numEntries: 600 persistent: 0
guacd_1           | 2021-09-22T10:29:38.326691563Z guacd[39256]: DEBUG:
    bitmapCache1CellInfo: numEntries: 600 persistent: 0
guacd_1           | 2021-09-22T10:29:38.326698278Z guacd[39256]: DEBUG:
    bitmapCache2CellInfo: numEntries: 2048 persistent: 0
guacd_1           | 2021-09-22T10:29:38.326729659Z guacd[39256]: DEBUG:
    bitmapCache3CellInfo: numEntries: 4096 persistent: 0
guacd_1           | 2021-09-22T10:29:38.326739431Z guacd[39256]: DEBUG:
    bitmapCache4CellInfo: numEntries: 2048 persistent: 0
guacd_1           | 2021-09-22T10:29:38.326747939Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.326774502Z guacd[39256]: DEBUG:
PointerCapabilitySet (length 6):
guacd_1           | 2021-09-22T10:29:38.326777803Z guacd[39256]: DEBUG:
    colorPointerFlag: 0x0001
guacd_1           | 2021-09-22T10:29:38.326800268Z guacd[39256]: DEBUG:
    colorPointerCacheSize: 0x0014
guacd_1           | 2021-09-22T10:29:38.326803385Z guacd[39256]: DEBUG:
    pointerCacheSize: 0x0014
guacd_1           | 2021-09-22T10:29:38.326811484Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.326817262Z guacd[39256]: DEBUG:
InputCapabilitySet (length 84)
guacd_1           | 2021-09-22T10:29:38.326850258Z guacd[39256]: DEBUG:
    inputFlags: 0x013D
guacd_1           | 2021-09-22T10:29:38.326853611Z guacd[39256]: DEBUG:
    pad2OctetsA: 0x0000
guacd_1           | 2021-09-22T10:29:38.326884340Z guacd[39256]: DEBUG:
    keyboardLayout: 0x00000409
guacd_1           | 2021-09-22T10:29:38.326887730Z guacd[39256]: DEBUG:
    keyboardType: 0x00000004
guacd_1           | 2021-09-22T10:29:38.326896671Z guacd[39256]: DEBUG:
    keyboardSubType: 0x00000000
guacd_1           | 2021-09-22T10:29:38.326916707Z guacd[39256]: DEBUG:
    keyboardFunctionKey: 0x0000000C
guacd_1           | 2021-09-22T10:29:38.326922795Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.326933113Z guacd[39256]: DEBUG:
BrushCapabilitySet (length 4):
guacd_1           | 2021-09-22T10:29:38.326965751Z guacd[39256]: DEBUG:
    brushSupportLevel: 0x00000002
guacd_1           | 2021-09-22T10:29:38.326969690Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.326972223Z guacd[39256]: DEBUG:
GlyphCacheCapabilitySet (length 48):
guacd_1           | 2021-09-22T10:29:38.326983562Z guacd[39256]: DEBUG:
    glyphCache0: Entries: 254 MaximumCellSize: 4
guacd_1           | 2021-09-22T10:29:38.326999529Z guacd[39256]: DEBUG:
    glyphCache1: Entries: 254 MaximumCellSize: 4
guacd_1           | 2021-09-22T10:29:38.327002614Z guacd[39256]: DEBUG:
    glyphCache2: Entries: 254 MaximumCellSize: 8
guacd_1           | 2021-09-22T10:29:38.327038197Z guacd[39256]: DEBUG:
    glyphCache3: Entries: 254 MaximumCellSize: 8
guacd_1           | 2021-09-22T10:29:38.327041699Z guacd[39256]: DEBUG:
    glyphCache4: Entries: 254 MaximumCellSize: 16
guacd_1           | 2021-09-22T10:29:38.327050197Z guacd[39256]: DEBUG:
    glyphCache5: Entries: 254 MaximumCellSize: 32
guacd_1           | 2021-09-22T10:29:38.327069745Z guacd[39256]: DEBUG:
    glyphCache6: Entries: 254 MaximumCellSize: 64
guacd_1           | 2021-09-22T10:29:38.327072838Z guacd[39256]: DEBUG:
    glyphCache7: Entries: 254 MaximumCellSize: 128
guacd_1           | 2021-09-22T10:29:38.327084430Z guacd[39256]: DEBUG:
    glyphCache8: Entries: 254 MaximumCellSize: 256
guacd_1           | 2021-09-22T10:29:38.327098007Z guacd[39256]: DEBUG:
    glyphCache9: Entries: 64 MaximumCellSize: 256
guacd_1           | 2021-09-22T10:29:38.327131838Z guacd[39256]: DEBUG:
    fragCache: Entries: 256 MaximumCellSize: 256
guacd_1           | 2021-09-22T10:29:38.327135728Z guacd[39256]: DEBUG:
    glyphSupportLevel: 0x0002
guacd_1           | 2021-09-22T10:29:38.327138110Z guacd[39256]: DEBUG:
    pad2Octets: 0x0000
guacd_1           | 2021-09-22T10:29:38.327145777Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.327167288Z guacd[39256]: DEBUG:
VirtualChannelCapabilitySet (length 8):
guacd_1           | 2021-09-22T10:29:38.327170500Z guacd[39256]: DEBUG:
    flags: 0x00000000
guacd_1           | 2021-09-22T10:29:38.327177088Z guacd[39256]: DEBUG:
    VCChunkSize: 0x00000640
guacd_1           | 2021-09-22T10:29:38.327187862Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.327197678Z guacd[39256]: DEBUG:
SoundCapabilitySet (length 4):
guacd_1           | 2021-09-22T10:29:38.327210101Z guacd[39256]: DEBUG:
    soundFlags: 0x0001
guacd_1           | 2021-09-22T10:29:38.327236539Z guacd[39256]: DEBUG:
    pad2OctetsA: 0x0000
guacd_1           | 2021-09-22T10:29:38.327241076Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.327248802Z guacd[39256]: DEBUG:
ShareCapabilitySet (length 4):
guacd_1           | 2021-09-22T10:29:38.327254752Z guacd[39256]: DEBUG:
    nodeId: 0x0000
guacd_1           | 2021-09-22T10:29:38.327290274Z guacd[39256]: DEBUG:
    pad2Octets: 0x0000
guacd_1           | 2021-09-22T10:29:38.327293714Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.327301385Z guacd[39256]: DEBUG:
FontCapabilitySet (length 4):
guacd_1           | 2021-09-22T10:29:38.327317488Z guacd[39256]: DEBUG:
    fontSupportFlags: 0x0001
guacd_1           | 2021-09-22T10:29:38.327326251Z guacd[39256]: DEBUG:
    pad2Octets: 0x0000
guacd_1           | 2021-09-22T10:29:38.327339045Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.327363143Z guacd[39256]: DEBUG:
ControlCapabilitySet (length 8):
guacd_1           | 2021-09-22T10:29:38.327366162Z guacd[39256]: DEBUG:
    controlFlags: 0x0000
guacd_1           | 2021-09-22T10:29:38.327375916Z guacd[39256]: DEBUG:
    remoteDetachFlag: 0x0000
guacd_1           | 2021-09-22T10:29:38.327400530Z guacd[39256]: DEBUG:
    controlInterest: 0x0002
guacd_1           | 2021-09-22T10:29:38.327404968Z guacd[39256]: DEBUG:
    detachInterest: 0x0002
guacd_1           | 2021-09-22T10:29:38.327414080Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.327416869Z guacd[39256]: DEBUG:
ColorCacheCapabilitySet (length 4):
guacd_1           | 2021-09-22T10:29:38.327449309Z guacd[39256]: DEBUG:
    colorTableCacheSize: 0x0006
guacd_1           | 2021-09-22T10:29:38.327452634Z guacd[39256]: DEBUG:
    pad2Octets: 0x0000
guacd_1           | 2021-09-22T10:29:38.327460846Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.327477839Z guacd[39256]: DEBUG:
WindowActivationCapabilitySet (length 8):
guacd_1           | 2021-09-22T10:29:38.327480802Z guacd[39256]: DEBUG:
    helpKeyFlag: 0x0000
guacd_1           | 2021-09-22T10:29:38.327513984Z guacd[39256]: DEBUG:
    helpKeyIndexFlag: 0x0000
guacd_1           | 2021-09-22T10:29:38.327518563Z guacd[39256]: DEBUG:
    helpExtendedKeyFlag: 0x0000
guacd_1           | 2021-09-22T10:29:38.327525991Z guacd[39256]: DEBUG:
    windowManagerKeyFlag: 0x0000
guacd_1           | 2021-09-22T10:29:38.327532985Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.327568293Z guacd[39256]: DEBUG:
OffscreenBitmapCacheCapabilitySet (length 8):
guacd_1           | 2021-09-22T10:29:38.327573048Z guacd[39256]: DEBUG:
    offscreenSupportLevel: 0x00000001
guacd_1           | 2021-09-22T10:29:38.327579899Z guacd[39256]: DEBUG:
    offscreenCacheSize: 0x1E00
guacd_1           | 2021-09-22T10:29:38.327606188Z guacd[39256]: DEBUG:
    offscreenCacheEntries: 0x07D0
guacd_1           | 2021-09-22T10:29:38.327609501Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.327618176Z guacd[39256]: DEBUG:
LargePointerCapabilitySet (length 2):
guacd_1           | 2021-09-22T10:29:38.327635197Z guacd[39256]: DEBUG:
    largePointerSupportFlags: 0x0001
guacd_1           | 2021-09-22T10:29:38.327638067Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.327665963Z guacd[39256]: DEBUG:
MultifragmentUpdateCapabilitySet (length 4):
guacd_1           | 2021-09-22T10:29:38.327669313Z guacd[39256]: DEBUG:
    maxRequestSize: 0x0000FFFF
guacd_1           | 2021-09-22T10:29:38.327678826Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.327694524Z guacd[39256]: DEBUG:
SurfaceCommandsCapabilitySet (length 8):
guacd_1           | 2021-09-22T10:29:38.327697399Z guacd[39256]: DEBUG:
    cmdFlags: 0x00000052
guacd_1           | 2021-09-22T10:29:38.327728058Z guacd[39256]: DEBUG:
    reserved: 0x00000000
guacd_1           | 2021-09-22T10:29:38.327736091Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.327742899Z guacd[39256]: DEBUG:
BitmapCodecsCapabilitySet (length 1):
guacd_1           | 2021-09-22T10:29:38.327753234Z guacd[39256]: DEBUG:
    bitmapCodecCount: 0
guacd_1           | 2021-09-22T10:29:38.327781876Z guacd[39256]: DEBUG:
Sending
guacd_1           | 2021-09-22T10:29:38.327785205Z guacd[39256]: DEBUG:
FrameAcknowledgeCapabilitySet (length 4):
guacd_1           | 2021-09-22T10:29:38.327793281Z guacd[39256]: DEBUG:
    frameAcknowledge: 0x00000002
guacd_1           | 2021-09-22T10:29:38.428786881Z guacd[39256]: DEBUG:
CLIPRDR (clipboard redirection) channel connected.
guacd_1           | 2021-09-22T10:29:38.428800881Z guacd[39256]: DEBUG: SVC
"rdpdr" connected.
guacd_1           | 2021-09-22T10:29:38.428803986Z guacd[39256]: DEBUG: SVC
"rdpsnd" connected.
guacd_1           | 2021-09-22T10:29:38.451855796Z guacd[39256]: INFO:
 Connected to RDPDR 1.13 as client 0x0003
guacd_1           | 2021-09-22T10:29:38.452432350Z guacd[39256]: DEBUG:
Ignoring server capability set type=0x0001, length=44
guacd_1           | 2021-09-22T10:29:38.452439068Z guacd[39256]: DEBUG:
Ignoring server capability set type=0x0002, length=8
guacd_1           | 2021-09-22T10:29:38.452441783Z guacd[39256]: DEBUG:
Ignoring server capability set type=0x0003, length=8
guacd_1           | 2021-09-22T10:29:38.452444383Z guacd[39256]: DEBUG:
Ignoring server capability set type=0x0004, length=8
guacd_1           | 2021-09-22T10:29:38.452448189Z guacd[39256]: DEBUG:
Ignoring server capability set type=0x0005, length=8
guacd_1           | 2021-09-22T10:29:38.452450821Z guacd[39256]: DEBUG:
Sending capabilities...
guacd_1           | 2021-09-22T10:29:38.452462536Z guacd[39256]: DEBUG:
Capabilities sent.
guacd_1           | 2021-09-22T10:29:38.452496089Z guacd[39256]: DEBUG:
Client ID confirmed
guacd_1           | 2021-09-22T10:29:38.502144449Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:29:38.529903330Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:29:38.675113209Z guacd[39256]: DEBUG:
rdp_set_error_info:freerdp_set_last_error_ex resetting error state
guacd_1           | 2021-09-22T10:29:38.967617774Z guacd[39256]: DEBUG:
generalFlags (0x0000003E) {
guacd_1           | 2021-09-22T10:29:38.967643557Z guacd[39256]: DEBUG:
    CB_USE_LONG_FORMAT_NAMES
guacd_1           | 2021-09-22T10:29:38.967646797Z guacd[39256]: DEBUG:
    CB_STREAM_FILECLIP_ENABLED
guacd_1           | 2021-09-22T10:29:38.967649366Z guacd[39256]: DEBUG:
    CB_FILECLIP_NO_FILE_PATHS
guacd_1           | 2021-09-22T10:29:38.967651773Z guacd[39256]: DEBUG:
    CB_CAN_LOCK_CLIPDATA
guacd_1           | 2021-09-22T10:29:38.967654241Z guacd[39256]: DEBUG: }
guacd_1           | 2021-09-22T10:29:38.976045553Z guacd[39256]: INFO:
 Connected to RDPDR 1.13 as client 0x0002
guacd_1           | 2021-09-22T10:29:38.976854732Z guacd[39256]: DEBUG:
Ignoring server capability set type=0x0001, length=44
guacd_1           | 2021-09-22T10:29:38.976873897Z guacd[39256]: DEBUG:
Ignoring server capability set type=0x0002, length=8
guacd_1           | 2021-09-22T10:29:38.976887893Z guacd[39256]: DEBUG:
Ignoring server capability set type=0x0003, length=8
guacd_1           | 2021-09-22T10:29:38.976890423Z guacd[39256]: DEBUG:
Ignoring server capability set type=0x0004, length=8
guacd_1           | 2021-09-22T10:29:38.976892757Z guacd[39256]: DEBUG:
Ignoring server capability set type=0x0005, length=8
guacd_1           | 2021-09-22T10:29:38.976895162Z guacd[39256]: DEBUG:
Sending capabilities...
guacd_1           | 2021-09-22T10:29:38.976897590Z guacd[39256]: DEBUG:
Capabilities sent.
guacd_1           | 2021-09-22T10:29:38.976899970Z guacd[39256]: DEBUG:
Client ID confirmed
guacd_1           | 2021-09-22T10:29:38.977335325Z guacd[39256]: INFO:
 RDPDR user logged on
guacd_1           | 2021-09-22T10:29:38.977344660Z guacd[39256]: DEBUG:
Registered device 0 (G)
guacd_1           | 2021-09-22T10:29:38.977347453Z guacd[39256]: DEBUG: All
supported devices sent.
guacd_1           | 2021-09-22T10:29:38.977825655Z guacd[39256]: DEBUG:
Device 0 (G) connected successfully
guacd_1           | 2021-09-22T10:29:38.978229310Z guacd[39256]: DEBUG:
guac_rdp_fs_open: path="", access=0x80, file_attributes=0x0,
create_disposition=0x1, create_options=0x1
guacd_1           | 2021-09-22T10:29:38.978235397Z guacd[39256]: DEBUG:
guac_rdp_fs_open: Normalized path "\" to "\".
guacd_1           | 2021-09-22T10:29:38.978238231Z guacd[39256]: DEBUG:
guac_rdp_fs_open: Translated path "\" to "shared-WOR/".
guacd_1           | 2021-09-22T10:29:38.978246244Z guacd[39256]: DEBUG:
guac_rdp_fs_open: native open: real_path="shared-WO/R", flags=0x0
guacd_1           | 2021-09-22T10:29:38.978265282Z guacd[39256]: DEBUG:
guac_rdp_fs_open: open() failed: No such file or directory
guacd_1           | 2021-09-22T10:29:38.978276717Z guacd[39256]: DEBUG:
guac_rdpdr_fs_process_create: [file_id=-2] desired_access=0x80,
file_attributes=0x0, create_disposition=0x1, create_options=0x1, path=""
guacd_1           | 2021-09-22T10:29:38.978294541Z guacd[39256]: ERROR:
File open refused (-2): ""
guacd_1           | 2021-09-22T10:29:39.008063974Z guacd[39256]: DEBUG:
guac_rdp_fs_open: path="", access=0x80, file_attributes=0x0,
create_disposition=0x1, create_options=0x1
guacd_1           | 2021-09-22T10:29:39.008091710Z guacd[39256]: DEBUG:
guac_rdp_fs_open: Normalized path "\" to "\".
guacd_1           | 2021-09-22T10:29:39.008095422Z guacd[39256]: DEBUG:
guac_rdp_fs_open: Translated path "\" to "shared-WOR/".
guacd_1           | 2021-09-22T10:29:39.008098347Z guacd[39256]: DEBUG:
guac_rdp_fs_open: native open: real_path="shared-WOR/", flags=0x0
guacd_1           | 2021-09-22T10:29:39.008101172Z guacd[39256]: DEBUG:
guac_rdp_fs_open: open() failed: No such file or directory
guacd_1           | 2021-09-22T10:29:39.008103952Z guacd[39256]: DEBUG:
guac_rdpdr_fs_process_create: [file_id=-2] desired_access=0x80,
file_attributes=0x0, create_disposition=0x1, create_options=0x1, path=""
guacd_1           | 2021-09-22T10:29:39.008106699Z guacd[39256]: ERROR:
File open refused (-2): ""
guacd_1           | 2021-09-22T10:29:39.457823661Z guacd[39256]: DEBUG:
guac_rdp_fs_open: path="", access=0x80, file_attributes=0x0,
create_disposition=0x1, create_options=0x1
guacd_1           | 2021-09-22T10:29:39.457856742Z guacd[39256]: DEBUG:
guac_rdp_fs_open: Normalized path "\" to "\".
guacd_1           | 2021-09-22T10:29:39.457860383Z guacd[39256]: DEBUG:
guac_rdp_fs_open: Translated path "\" to "shared-WO/".
guacd_1           | 2021-09-22T10:29:39.457877700Z guacd[39256]: DEBUG:
guac_rdp_fs_open: native open: real_path="shared-WO/", flags=0x0
guacd_1           | 2021-09-22T10:29:39.457880903Z guacd[39256]: DEBUG:
guac_rdp_fs_open: open() failed: No such file or directory
guacd_1           | 2021-09-22T10:29:39.457883485Z guacd[39256]: DEBUG:
guac_rdpdr_fs_process_create: [file_id=-2] desired_access=0x80,
file_attributes=0x0, create_disposition=0x1, create_options=0x1, path=""
guacd_1           | 2021-09-22T10:29:39.457886299Z guacd[39256]: ERROR:
File open refused (-2): ""
guacd_1           | 2021-09-22T10:31:53.110637233Z guacd[39256]: INFO:
 Accepted format: 16-bit PCM with 2 channels at 44100 Hz
guacd_1           | 2021-09-22T10:32:34.710417587Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:32:45.034384157Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:33:19.957253264Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:33:26.423872910Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T10:33:26.423902804Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T10:35:36.534366120Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:36:20.010500739Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:37:05.104955706Z guacd[39256]: DEBUG:
Sending keysym 0x10005d0 as Unicode
guacd_1           | 2021-09-22T10:37:05.237745733Z guacd[39256]: DEBUG:
Sending keysym 0x10005e7 as Unicode
guacd_1           | 2021-09-22T10:37:05.454687712Z guacd[39256]: DEBUG:
Sending keysym 0x10005d3 as Unicode
guacd_1           | 2021-09-22T10:37:05.531716797Z guacd[39256]: DEBUG:
Sending keysym 0x10005d0 as Unicode
guacd_1           | 2021-09-22T10:38:26.544055049Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T10:38:26.544087455Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T10:38:32.127630377Z guacd[39256]: INFO:
 Accepted format: 16-bit PCM with 2 channels at 44100 Hz
guacd_1           | 2021-09-22T10:39:01.193412007Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:39:08.291024267Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:39:15.079663898Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:40:10.999473038Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:42:42.047219585Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:42:48.750400072Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:43:26.646384853Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T10:43:26.646421797Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T10:45:09.698797171Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:45:16.148024990Z guacd[39256]: INFO:
 Accepted format: 16-bit PCM with 2 channels at 44100 Hz
guacd_1           | 2021-09-22T10:45:16.246257395Z guacd[39256]: INFO:
 Accepted format: 16-bit PCM with 2 channels at 44100 Hz
guacd_1           | 2021-09-22T10:45:24.121249803Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:45:44.995914873Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:46:16.008714238Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:46:53.730330596Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:47:18.607905540Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:48:26.887084102Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T10:48:26.887111851Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T10:52:34.354179078Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:52:37.993769080Z guacd[39256]: INFO:
 Accepted format: 16-bit PCM with 2 channels at 44100 Hz
guacd_1           | 2021-09-22T10:52:45.520756016Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:53:16.546301500Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:53:27.027189389Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T10:53:27.027218384Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T10:53:40.462312886Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:55:20.848796413Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:58:27.134203910Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T10:58:27.134235513Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T10:58:48.925189222Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T10:59:05.897377003Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:00:17.234314012Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:03:27.237580278Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T11:03:27.237603229Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T11:05:45.939704924Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:05:57.280301244Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:06:13.674903140Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:07:10.674577204Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:08:27.339963874Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T11:08:27.339998200Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T11:13:08.483115118Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:13:15.072346765Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:13:27.437853178Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T11:13:27.437888835Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T11:13:30.108474952Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:15:45.918105713Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:16:03.196841327Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:17:34.801922616Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:18:27.543844067Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T11:18:27.543866312Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T11:19:29.291044906Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:23:27.646168010Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T11:23:27.646201514Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T11:28:27.749646379Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T11:28:27.749680890Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T11:33:28.042567876Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T11:33:28.042590875Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T11:36:58.083493846Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:38:28.171652550Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T11:38:28.171748056Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T11:39:30.525582261Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:43:28.274630066Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T11:43:28.274658443Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T11:48:28.375257453Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T11:48:28.375295754Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T11:50:54.761880727Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:52:23.834327162Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:52:56.923476023Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:53:28.493188256Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T11:53:28.493223438Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T11:55:02.859005968Z guacd[39256]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-22T11:58:28.596025668Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T11:58:28.596052169Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T12:03:28.707121094Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T12:03:28.707154676Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T12:04:11.565709361Z guacd[39256]: INFO:
 User "@e9d6181d-afaa-41a3-bd5b-b235660c207d" disconnected (0 users remain)
guacd_1           | 2021-09-22T12:04:11.565745392Z guacd[39256]: INFO:
 Last user of connection "$3f4207c9-9f78-43d3-9ee1-10ec7277a71f"
disconnected
guacd_1           | 2021-09-22T12:04:11.565797414Z guacd[39256]: DEBUG:
Requesting termination of client...
guacd_1           | 2021-09-22T12:04:11.749533871Z guacd[39256]: DEBUG:
Unloading device 0 (G)
guacd_1           | 2021-09-22T12:04:11.749568472Z guacd[39256]: DEBUG: SVC
"rdpdr" disconnected.
guacd_1           | 2021-09-22T12:04:11.749635651Z guacd[39256]: DEBUG: SVC
"rdpsnd" disconnected.
guacd_1           | 2021-09-22T12:04:11.755037254Z guacd[39256]: INFO:
 Internal RDP client disconnected
guacd_1           | 2021-09-22T12:04:11.756097657Z guacd[39256]: DEBUG:
Client terminated successfully.
guacd_1           | 2021-09-22T12:04:11.768713372Z guacd[7]: INFO:
 Connection "$3f4207c9-9f78-43d3-9ee1-10ec7277a71f" removed.
guacd_1           | 2021-09-22T12:08:28.813985092Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T12:08:28.814039406Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T12:13:28.918905305Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T12:13:28.918935323Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T12:18:29.035480453Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T12:18:29.035505354Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T12:23:29.142931862Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T12:23:29.142952538Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-22T12:28:29.249367824Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-22T12:28:29.249401081Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction


psql container:
postgres_gqm_1    | 2021-09-13T14:59:26.208824252Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208826369Z
 $22710::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208836387Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208839572Z
 $22711::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208841990Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208844278Z
 $22712::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208846546Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208848750Z
 $22713::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208851129Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208853437Z
 $22714::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208855778Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208857931Z
 $22715::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208860125Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208862251Z
 $22716::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208864425Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208866626Z
 $22717::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208869001Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208871282Z
 $22718::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208873616Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208875869Z
 $22719::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208878074Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208880215Z
 $22720::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208882389Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208884574Z
 $22721::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208886921Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208889188Z
 $22722::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208891517Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208893787Z
 $22723::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208895967Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208898099Z
 $22724::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208900287Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208902499Z
 $22725::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208904861Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208907216Z
 $22726::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208909534Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208911788Z
 $22727::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208916856Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208919001Z
 $22728::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208921188Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208923397Z
 $22729::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208925745Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208928037Z
 $22730::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208930370Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208932667Z
 $22731::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208934864Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208937008Z
 $22732::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208939254Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208941505Z
 $22733::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208943832Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208946145Z
 $22734::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208948457Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208950754Z
 $22735::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208955514Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208957654Z
 $22736::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208959843Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208962030Z
 $22737::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208964372Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208966687Z
 $22738::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208969021Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208971301Z
 $22739::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208973521Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208975652Z
 $22740::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208977856Z                   ,
postgres_gqm_1    | 2021-09-13T14:59:26.208980045Z
 $22741::integer
postgres_gqm_1    | 2021-09-13T14:59:26.208982374Z                   )
postgres_gqm_1    | 2021-09-13T14:59:26.208984696Z              GROUP BY
guacamole_connection.connection_id
postgres_gqm_1    | 2021-09-13T15:06:00.739401772Z 2021-09-13 15:06:00.738
UTC [1] LOG:  received smart shutdown request
postgres_gqm_1    | 2021-09-13T15:06:00.743053885Z 2021-09-13 15:06:00.742
UTC [1] LOG:  background worker "logical replication launcher" (PID 32)
exited with exit code 1
postgres_gqm_1    | 2021-09-13T15:06:06.500687412Z 2021-09-13 15:06:06.500
UTC [27] LOG:  shutting down
postgres_gqm_1    | 2021-09-13T15:06:06.569765693Z 2021-09-13 15:06:06.569
UTC [1] LOG:  database system is shut down
postgres_gqm_1    | 2021-09-13T15:06:08.236309993Z
postgres_gqm_1    | 2021-09-13T15:06:08.236343497Z PostgreSQL Database
directory appears to contain a database; Skipping initialization
postgres_gqm_1    | 2021-09-13T15:06:08.236351318Z
postgres_gqm_1    | 2021-09-13T15:06:08.291434455Z 2021-09-13 15:06:08.291
UTC [1] LOG:  starting PostgreSQL 12.3 (Debian 12.3-1.pgdg100+1) on
x86_64-pc-linux-gnu, compiled by gcc (Debian 8.3.0-6) 8.3.0, 64-bit
postgres_gqm_1    | 2021-09-13T15:06:08.291535278Z 2021-09-13 15:06:08.291
UTC [1] LOG:  listening on IPv4 address "0.0.0.0", port 5432
postgres_gqm_1    | 2021-09-13T15:06:08.291551608Z 2021-09-13 15:06:08.291
UTC [1] LOG:  listening on IPv6 address "::", port 5432
postgres_gqm_1    | 2021-09-13T15:06:08.302468493Z 2021-09-13 15:06:08.302
UTC [1] LOG:  listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432"
postgres_gqm_1    | 2021-09-13T15:06:08.331319455Z 2021-09-13 15:06:08.331
UTC [29] LOG:  database system was shut down at 2021-09-13 15:06:06 UTC
postgres_gqm_1    | 2021-09-13T15:06:08.365236332Z 2021-09-13 15:06:08.365
UTC [1] LOG:  database system is ready to accept connections
postgres_gqm_1    | 2021-09-20T00:00:33.235204952Z 2021-09-20 00:00:33.235
UTC [74459] LOG:  using stale statistics instead of current ones because
stats collector is not responding
postgres_gqm_1    | 2021-09-20T00:02:33.328346241Z 2021-09-20 00:02:33.328
UTC [74467] LOG:  using stale statistics instead of current ones because
stats collector is not responding
postgres_gqm_1    | 2021-09-20T00:07:33.588279209Z 2021-09-20 00:07:33.588
UTC [74475] LOG:  using stale statistics instead of current ones because
stats collector is not responding

‫בתאריך יום ב׳, 13 בספט׳ 2021 ב-23:13 מאת ‪Nick Couchman‬‏ <‪
vnick@apache.org‬‏>:‬

> On Mon, Sep 13, 2021 at 2:51 PM Regev Batchen <ba...@gmail.com> wrote:
>
>> I did it when it got stuck , works fine for now but i have this random
>> guacamole stuck for 2 month so i feel like im gonna see this again this
>> week, i really hope not thats why i upgraded everything..
>>
>>  You think this current error was a 1 time thing?
>>
>>
> Not sure, but I don't think it's anything specific to Guacamole.
>
> -Nick
>
>>

Re: Docker guacamole+guacd 1.2.0 gets stuck once a day randomly

Posted by Nick Couchman <vn...@apache.org>.
On Mon, Sep 13, 2021 at 2:51 PM Regev Batchen <ba...@gmail.com> wrote:

> I did it when it got stuck , works fine for now but i have this random
> guacamole stuck for 2 month so i feel like im gonna see this again this
> week, i really hope not thats why i upgraded everything..
>
>  You think this current error was a 1 time thing?
>
>
Not sure, but I don't think it's anything specific to Guacamole.

-Nick

>

Re: Docker guacamole+guacd 1.2.0 gets stuck once a day randomly

Posted by Regev Batchen <ba...@gmail.com>.
I did it when it got stuck , works fine for now but i have this random
guacamole stuck for 2 month so i feel like im gonna see this again this
week, i really hope not thats why i upgraded everything..

 You think this current error was a 1 time thing?

I will post here again if it happens again ..
🥺

בתאריך יום ב׳, 13 בספט׳ 2021 ב-21:47 מאת Nick Couchman <vn...@apache.org>:

> On Mon, Sep 13, 2021 at 2:40 PM Regev Batchen <ba...@gmail.com> wrote:
>
>>
>> Hey thank you for your answer!
>> I have upgraded it to 1.3.0 and it got stuck again with db error
>>
>>
>> guacd[7]: DEBUG:     Error reading "select": End of stream reached while
>> reading instruction
>>
>> And db shows
>>
>> 3T14:59:26.208975652Z                   $22740::integer
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208977856Z
>>    ,
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208980045Z
>>       $22741::integer
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208982374Z
>>    )
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208984696Z
>> GROUP BY guacamole_connection.connection_id
>>
>>
>> Looks like some quary from guacamole got it stuck this time not like
>> other times before the upgrade ( have upgraded docker and compose and
>> guacamole)
>>
>> I will see this days if this happened again i really think the last issue
>> i had with a quary making psql stuck is not a docker issue..
>>
>> My last resort now is to make a script that automatically restart
>> guacamole containers when they get stuck or db gqm
>>
>> Thank you and if you have any ides about the last gqm db error i will
>> look into whatever u suggest!
>>
>>
> Have you tried restarting the DB container?
>
> -Nick
>
>>

Re: Docker guacamole+guacd 1.2.0 gets stuck once a day randomly

Posted by Nick Couchman <vn...@apache.org>.
On Mon, Sep 13, 2021 at 2:40 PM Regev Batchen <ba...@gmail.com> wrote:

>
> Hey thank you for your answer!
> I have upgraded it to 1.3.0 and it got stuck again with db error
>
>
> guacd[7]: DEBUG:     Error reading "select": End of stream reached while
> reading instruction
>
> And db shows
>
> 3T14:59:26.208975652Z                   $22740::integer
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208977856Z
>    ,
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208980045Z
>       $22741::integer
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208982374Z
>    )
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208984696Z
> GROUP BY guacamole_connection.connection_id
>
>
> Looks like some quary from guacamole got it stuck this time not like other
> times before the upgrade ( have upgraded docker and compose and guacamole)
>
> I will see this days if this happened again i really think the last issue
> i had with a quary making psql stuck is not a docker issue..
>
> My last resort now is to make a script that automatically restart
> guacamole containers when they get stuck or db gqm
>
> Thank you and if you have any ides about the last gqm db error i will look
> into whatever u suggest!
>
>
Have you tried restarting the DB container?

-Nick

>

Re: Docker guacamole+guacd 1.2.0 gets stuck once a day randomly

Posted by Regev Batchen <ba...@gmail.com>.
Hey thank you for your answer!
I have upgraded it to 1.3.0 and it got stuck again with db error


guacd[7]: DEBUG:     Error reading "select": End of stream reached while
reading instruction

And db shows

3T14:59:26.208975652Z                   $22740::integer
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208977856Z
 ,
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208980045Z
    $22741::integer
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208982374Z
 )
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208984696Z
GROUP BY guacamole_connection.connection_id


Looks like some quary from guacamole got it stuck this time not like other
times before the upgrade ( have upgraded docker and compose and guacamole)

I will see this days if this happened again i really think the last issue i
had with a quary making psql stuck is not a docker issue..

My last resort now is to make a script that automatically restart guacamole
containers when they get stuck or db gqm

Thank you and if you have any ides about the last gqm db error i will look
into whatever u suggest!


בתאריך יום ב׳, 13 בספט׳ 2021 ב-19:20 מאת Nick Couchman <vn...@apache.org>:

> On Mon, Sep 13, 2021 at 11:13 AM Regev Batchen <ba...@gmail.com> wrote:
>
>> Hello team,
>> can somone please help me ?
>>
>> I upgraded docker to version 20.10.8 and docker-compose  version 1.29.2
>> and also guacd + guacamole version to latest version 1.3.0
>>
>> Out of all my containers only guacamole gets stuck
>> this is what i see on kern.log:
>>
>> Sep 13 14:28:44 ansible kernel: [1548303.041290] br-4260ec4ed3f1: port
>> 19(veth842fd2a) entered disabled state
>> Sep 13 14:28:44 ansible kernel: [1548303.041444] veth8c74c8b: renamed
>> from eth0
>> Sep 13 14:28:44 ansible kernel: [1548303.103365] br-4260ec4ed3f1: port
>> 19(veth842fd2a) entered disabled state
>> Sep 13 14:28:44 ansible kernel: [1548303.114983] device veth842fd2a left
>> promiscuous mode
>> Sep 13 14:28:44 ansible kernel: [1548303.114987] br-4260ec4ed3f1: port
>> 19(veth842fd2a) entered disabled state
>> Sep 13 14:28:45 ansible kernel: [1548303.703639] br-4260ec4ed3f1: port
>> 11(veth6370b5e) entered disabled state
>> Sep 13 14:28:45 ansible kernel: [1548303.703767] veth3a75fd3: renamed
>> from eth0
>> Sep 13 14:28:45 ansible kernel: [1548303.769807] br-4260ec4ed3f1: port
>> 11(veth6370b5e) entered disabled state
>> Sep 13 14:28:45 ansible kernel: [1548303.778901] device veth6370b5e left
>> promiscuous mode
>> Sep 13 14:28:45 ansible kernel: [1548303.778906] br-4260ec4ed3f1: port
>> 11(veth6370b5e) entered disabled state
>> Sep 13 14:28:45 ansible kernel: [1548304.023824] br-4260ec4ed3f1: port
>> 11(veth0e30632) entered blocking state
>> Sep 13 14:28:45 ansible kernel: [1548304.023826] br-4260ec4ed3f1: port
>> 11(veth0e30632) entered disabled state
>> Sep 13 14:28:45 ansible kernel: [1548304.023993] device veth0e30632
>> entered promiscuous mode
>> Sep 13 14:28:45 ansible kernel: [1548304.024117] IPv6:
>> ADDRCONF(NETDEV_UP): veth0e30632: link is not ready
>> Sep 13 14:28:45 ansible kernel: [1548304.024125] br-4260ec4ed3f1: port
>> 11(veth0e30632) entered blocking state
>> Sep 13 14:28:45 ansible kernel: [1548304.024127] br-4260ec4ed3f1: port
>> 11(veth0e30632) entered forwarding state
>> Sep 13 14:28:45 ansible kernel: [1548304.066552] br-4260ec4ed3f1: port
>> 11(veth0e30632) entered disabled state
>> Sep 13 14:28:46 ansible kernel: [1548305.059156] eth0: renamed from
>> veth0b98f17
>> Sep 13 14:28:46 ansible kernel: [1548305.091017] IPv6:
>> ADDRCONF(NETDEV_CHANGE): veth0e30632: link becomes ready
>>
>> guacd :
>>
>> guacd_1           | 2021-09-13T14:15:42.167400545Z guacd[15338]: DEBUG:
>> guac_rdpdr_fs_process_create: [file_id=-2] desired_access=0x80,
>> file_attributes=0x0, create_disposition=0x1, create_options=0x1, path=""
>> guacd_1           | 2021-09-13T14:15:42.167403278Z guacd[15338]: ERROR:
>> File open refused (-2): ""
>> guacd_1           | 2021-09-13T14:17:02.948295495Z guacd[15338]: DEBUG:
>> Clipboard data received. Reporting availability of clipboard data to RDP
>> server.
>> guacd_1           | 2021-09-13T14:17:14.857231812Z guacd[15338]: DEBUG:
>> Clipboard data received. Reporting availability of clipboard data to RDP
>> server.
>> guacd_1           | 2021-09-13T14:18:16.590645926Z guacd[15338]: INFO:
>>  Accepted format: 16-bit PCM with 2 channels at 44100 Hz
>> guacd_1           | 2021-09-13T14:18:51.820263319Z guacd[7]: DEBUG:
>> Guacamole connection closed during handshake
>> guacd_1           | 2021-09-13T14:18:51.820418741Z guacd[7]: DEBUG:
>> Error reading "select": End of stream reached while reading instruction
>> guacd_1           | 2021-09-13T14:21:51.700790573Z guacd[14814]: DEBUG:
>> Clipboard data received. Reporting availability of clipboard data to RDP
>> server.
>> guacd_1           | 2021-09-13T14:21:52.644317299Z guacd[14814]: INFO:
>>  User "@4e696ad7-a66f-4415-a84f-aa599d6239ee" disconnected (0 users remain)
>> guacd_1           | 2021-09-13T14:21:52.644346809Z guacd[14814]: INFO:
>>  Last user of connection "$ac241f0d-90de-487e-9bf5-d1fcab6463ed"
>> disconnected
>> guacd_1           | 2021-09-13T14:21:52.644350539Z guacd[14814]: DEBUG:
>> Requesting termination of client...
>> guacd_1           | 2021-09-13T14:21:52.712513048Z guacd[14814]: DEBUG:
>> Unloading device 0 (G)
>> guacd_1           | 2021-09-13T14:21:52.712527764Z guacd[14814]: DEBUG:
>> SVC "rdpdr" disconnected.
>> guacd_1           | 2021-09-13T14:21:52.712531208Z guacd[14814]: DEBUG:
>> SVC "rdpsnd" disconnected.
>> guacd_1           | 2021-09-13T14:21:52.713832474Z guacd[14814]: INFO:
>>  Internal RDP client disconnected
>> guacd_1           | 2021-09-13T14:21:52.714336876Z guacd[14814]: DEBUG:
>> Client terminated successfully.
>> guacd_1           | 2021-09-13T14:21:52.719287612Z guacd[7]: INFO:
>>  Connection "$ac241f0d-90de-487e-9bf5-d1fcab6463ed" removed.
>> guacd_1           | 2021-09-13T14:22:02.951448033Z guacd[15338]: DEBUG:
>> Clipboard data received. Reporting availability of clipboard data to RDP
>> server.
>> guacd_1           | 2021-09-13T14:23:52.041486801Z guacd[7]: DEBUG:
>> Guacamole connection closed during handshake
>> guacd_1           | 2021-09-13T14:23:52.041508185Z guacd[7]: DEBUG:
>> Error reading "select": End of stream reached while reading instruction
>> guacd_1           | 2021-09-13T14:24:33.529359679Z guacd[15338]: DEBUG:
>> Clipboard data received. Reporting availability of clipboard data to RDP
>> server.
>> guacd_1           | 2021-09-13T14:24:37.095385182Z guacd[15338]: DEBUG:
>> Clipboard data received. Reporting availability of clipboard data to RDP
>> server.
>> guacd_1           | 2021-09-13T14:28:15.375586356Z guacd[15338]: DEBUG:
>> Clipboard data received. Reporting availability of clipboard data to RDP
>> server.
>> guacd_1           | 2021-09-13T14:28:52.159743146Z guacd[7]: DEBUG:
>> Guacamole connection closed during handshake
>> guacd_1           | 2021-09-13T14:28:52.159773981Z guacd[7]: DEBUG:
>> Error reading "select": End of stream reached while reading instruction
>> guacd_1           | 2021-09-13T14:32:19.198653389Z guacd[15338]: DEBUG:
>> BIO_read returned a system error 104: Connection reset by peer
>> guacd_1           | 2021-09-13T14:32:19.198676263Z guacd[15338]: DEBUG:
>> transport_read_layer:freerdp_set_last_error_ex
>> ERRCONNECT_CONNECT_TRANSPORT_FAILED [0x0002000D]
>> guacd_1           | 2021-09-13T14:32:19.198747713Z guacd[15338]: ERROR:
>> Connection closed.
>> guacd_1           | 2021-09-13T14:32:19.207063823Z guacd[15338]: DEBUG:
>> Unloading device 0 (G)
>> guacd_1           | 2021-09-13T14:32:19.207082713Z guacd[15338]: DEBUG:
>> SVC "rdpdr" disconnected.
>> guacd_1           | 2021-09-13T14:32:19.207086339Z guacd[15338]: DEBUG:
>> SVC "rdpsnd" disconnected.
>> guacd_1           | 2021-09-13T14:32:19.211464966Z guacd[15338]: INFO:
>>  Internal RDP client disconnected
>> guacd_1           | 2021-09-13T14:32:19.212441975Z guacd[15338]: INFO:
>>  User "@b62dbd8f-0d04-4170-9f59-bab9436a4410" disconnected (0 users remain)
>> guacd_1           | 2021-09-13T14:32:19.212453322Z guacd[15338]: INFO:
>>  Last user of connection "$cf755c62-ebb4-4866-bdd8-9b3c868ce3f7"
>> disconnected
>> guacd_1           | 2021-09-13T14:32:19.212658496Z guacd[15338]: DEBUG:
>> Requesting termination of client...
>> guacd_1           | 2021-09-13T14:32:19.213289155Z guacd[15338]: DEBUG:
>> Client terminated successfully.
>> guacd_1           | 2021-09-13T14:32:19.223127887Z guacd[7]: INFO:
>>  Connection "$cf755c62-ebb4-4866-bdd8-9b3c868ce3f7" removed.
>> guacd_1           | 2021-09-13T14:33:52.257022053Z guacd[7]: DEBUG:
>> Guacamole connection closed during handshake
>> guacd_1           | 2021-09-13T14:33:52.257054063Z guacd[7]: DEBUG:
>> Error reading "select": End of stream reached while reading instruction
>> guacd_1           | 2021-09-13T14:38:52.349598898Z guacd[7]: DEBUG:
>> Guacamole connection closed during handshake
>> guacd_1           | 2021-09-13T14:38:52.349631320Z guacd[7]: DEBUG:
>> Error reading "select": End of stream reached while reading instruction
>> guacd_1           | 2021-09-13T14:43:52.457177071Z guacd[7]: DEBUG:
>> Guacamole connection closed during handshake
>> guacd_1           | 2021-09-13T14:43:52.457214339Z guacd[7]: DEBUG:
>> Error reading "select": End of stream reached while reading instruction
>> guacd_1           | 2021-09-13T14:48:52.569283904Z guacd[7]: DEBUG:
>> Guacamole connection closed during handshake
>> guacd_1           | 2021-09-13T14:48:52.569329287Z guacd[7]: DEBUG:
>> Error reading "select": End of stream reached while reading instruction
>> guacd_1           | 2021-09-13T14:53:52.686851881Z guacd[7]: DEBUG:
>> Guacamole connection closed during handshake
>> guacd_1           | 2021-09-13T14:53:52.686895047Z guacd[7]: DEBUG:
>> Error reading "select": End of stream reached while reading instruction
>>
>>
>> guacmole :
>>
>> guacamole_1       | 2021-09-13T14:18:44.269349795Z 14:18:44.269
>> [http-nio-8080-exec-6] INFO  o.a.g.r.auth.AuthenticationService - User
>> "guacadmin" successfully authenticated from [172.18.0.1, 172.18.0.19].
>> guacamole_1       | 2021-09-13T14:21:52.644905551Z 14:21:52.644
>> [http-nio-8080-exec-14] INFO  o.a.g.tunnel.TunnelRequestService - User
>> "guacadmin" disconnected from connection "22743". Duration: 5446031
>> milliseconds
>> guacamole_1       | 2021-09-13T14:32:19.212341284Z 14:32:19.212
>> [http-nio-8080-exec-16] INFO  o.a.g.tunnel.TunnelRequestService - User
>> "guacadmin" disconnected from connection "22672". Duration: 1031145
>> milliseconds
>> guacamole_1       | 2021-09-13T14:32:19.214220909Z Exception in thread
>> "Thread-63" java.lang.IllegalStateException: Message will not be sent
>> because the WebSocket session has been closed
>> guacamole_1       | 2021-09-13T14:32:19.214297548Z      at
>> org.apache.tomcat.websocket.WsRemoteEndpointImplBase.writeMessagePart(WsRemoteEndpointImplBase.java:438)
>> guacamole_1       | 2021-09-13T14:32:19.214302309Z      at
>> org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendMessageBlock(WsRemoteEndpointImplBase.java:311)
>> guacamole_1       | 2021-09-13T14:32:19.214305247Z      at
>> org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendMessageBlock(WsRemoteEndpointImplBase.java:251)
>> guacamole_1       | 2021-09-13T14:32:19.214308114Z      at
>> org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendString(WsRemoteEndpointImplBase.java:192)
>> guacamole_1       | 2021-09-13T14:32:19.214310977Z      at
>> org.apache.tomcat.websocket.WsRemoteEndpointBasic.sendText(WsRemoteEndpointBasic.java:37)
>> guacamole_1       | 2021-09-13T14:32:19.214338369Z      at
>> org.apache.guacamole.websocket.GuacamoleWebSocketTunnelEndpoint.sendInstruction(GuacamoleWebSocketTunnelEndpoint.java:152)
>> guacamole_1       | 2021-09-13T14:32:19.214341240Z      at
>> org.apache.guacamole.websocket.GuacamoleWebSocketTunnelEndpoint.access$200(GuacamoleWebSocketTunnelEndpoint.java:53)
>> guacamole_1       | 2021-09-13T14:32:19.214343913Z      at
>> org.apache.guacamole.websocket.GuacamoleWebSocketTunnelEndpoint$2.run(GuacamoleWebSocketTunnelEndpoint.java:253)
>>
>> guacamole db:
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208925745Z
>>    ,
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208928037Z
>>       $22730::integer
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208930370Z
>>    ,
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208932667Z
>>       $22731::integer
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208934864Z
>>    ,
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208937008Z
>>       $22732::integer
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208939254Z
>>    ,
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208941505Z
>>       $22733::integer
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208943832Z
>>    ,
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208946145Z
>>       $22734::integer
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208948457Z
>>    ,
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208950754Z
>>       $22735::integer
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208955514Z
>>    ,
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208957654Z
>>       $22736::integer
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208959843Z
>>    ,
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208962030Z
>>       $22737::integer
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208964372Z
>>    ,
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208966687Z
>>       $22738::integer
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208969021Z
>>    ,
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208971301Z
>>       $22739::integer
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208973521Z
>>    ,
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208975652Z
>>       $22740::integer
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208977856Z
>>    ,
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208980045Z
>>       $22741::integer
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208982374Z
>>    )
>> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208984696Z
>> GROUP BY guacamole_connection.connection_id
>>
>>
>> couldn't get the query in the first row as it SUPER long!
>> What is going on? How can I solve this?
>>
>> why the guacamole service gets stuck? PLEASE HELP
>>
>>
> I don't really have any good ideas, except that I don't think it is
> specifically a Guacamole issue. I think it's some sort of issue with the
> Guacamole container(s) that is interrupting things, or perhaps with the DB
> container, but nothing about Guacamole itself is causing this problem. I'd
> suggest that you re-deploy the containers - you can even upgrade 1.2.0 ->
> 1.3.0 while you're at it - and see if clean containers take care of the
> issue. Obviously you'll want to make sure you save your database - either
> don't refresh that Postgres container, or make sure the data is in a
> persistent location.
>
> You should not have to restart your containers a couple of times a day or
> anything like that. But, this seems to me a Docker or DB issue, nothing
> with Guacamole.
>
> -Nick
>
>>

Re: Docker guacamole+guacd 1.2.0 gets stuck once a day randomly

Posted by Nick Couchman <vn...@apache.org>.
On Mon, Sep 13, 2021 at 11:13 AM Regev Batchen <ba...@gmail.com> wrote:

> Hello team,
> can somone please help me ?
>
> I upgraded docker to version 20.10.8 and docker-compose  version 1.29.2
> and also guacd + guacamole version to latest version 1.3.0
>
> Out of all my containers only guacamole gets stuck
> this is what i see on kern.log:
>
> Sep 13 14:28:44 ansible kernel: [1548303.041290] br-4260ec4ed3f1: port
> 19(veth842fd2a) entered disabled state
> Sep 13 14:28:44 ansible kernel: [1548303.041444] veth8c74c8b: renamed from
> eth0
> Sep 13 14:28:44 ansible kernel: [1548303.103365] br-4260ec4ed3f1: port
> 19(veth842fd2a) entered disabled state
> Sep 13 14:28:44 ansible kernel: [1548303.114983] device veth842fd2a left
> promiscuous mode
> Sep 13 14:28:44 ansible kernel: [1548303.114987] br-4260ec4ed3f1: port
> 19(veth842fd2a) entered disabled state
> Sep 13 14:28:45 ansible kernel: [1548303.703639] br-4260ec4ed3f1: port
> 11(veth6370b5e) entered disabled state
> Sep 13 14:28:45 ansible kernel: [1548303.703767] veth3a75fd3: renamed from
> eth0
> Sep 13 14:28:45 ansible kernel: [1548303.769807] br-4260ec4ed3f1: port
> 11(veth6370b5e) entered disabled state
> Sep 13 14:28:45 ansible kernel: [1548303.778901] device veth6370b5e left
> promiscuous mode
> Sep 13 14:28:45 ansible kernel: [1548303.778906] br-4260ec4ed3f1: port
> 11(veth6370b5e) entered disabled state
> Sep 13 14:28:45 ansible kernel: [1548304.023824] br-4260ec4ed3f1: port
> 11(veth0e30632) entered blocking state
> Sep 13 14:28:45 ansible kernel: [1548304.023826] br-4260ec4ed3f1: port
> 11(veth0e30632) entered disabled state
> Sep 13 14:28:45 ansible kernel: [1548304.023993] device veth0e30632
> entered promiscuous mode
> Sep 13 14:28:45 ansible kernel: [1548304.024117] IPv6:
> ADDRCONF(NETDEV_UP): veth0e30632: link is not ready
> Sep 13 14:28:45 ansible kernel: [1548304.024125] br-4260ec4ed3f1: port
> 11(veth0e30632) entered blocking state
> Sep 13 14:28:45 ansible kernel: [1548304.024127] br-4260ec4ed3f1: port
> 11(veth0e30632) entered forwarding state
> Sep 13 14:28:45 ansible kernel: [1548304.066552] br-4260ec4ed3f1: port
> 11(veth0e30632) entered disabled state
> Sep 13 14:28:46 ansible kernel: [1548305.059156] eth0: renamed from
> veth0b98f17
> Sep 13 14:28:46 ansible kernel: [1548305.091017] IPv6:
> ADDRCONF(NETDEV_CHANGE): veth0e30632: link becomes ready
>
> guacd :
>
> guacd_1           | 2021-09-13T14:15:42.167400545Z guacd[15338]: DEBUG:
> guac_rdpdr_fs_process_create: [file_id=-2] desired_access=0x80,
> file_attributes=0x0, create_disposition=0x1, create_options=0x1, path=""
> guacd_1           | 2021-09-13T14:15:42.167403278Z guacd[15338]: ERROR:
> File open refused (-2): ""
> guacd_1           | 2021-09-13T14:17:02.948295495Z guacd[15338]: DEBUG:
> Clipboard data received. Reporting availability of clipboard data to RDP
> server.
> guacd_1           | 2021-09-13T14:17:14.857231812Z guacd[15338]: DEBUG:
> Clipboard data received. Reporting availability of clipboard data to RDP
> server.
> guacd_1           | 2021-09-13T14:18:16.590645926Z guacd[15338]: INFO:
>  Accepted format: 16-bit PCM with 2 channels at 44100 Hz
> guacd_1           | 2021-09-13T14:18:51.820263319Z guacd[7]: DEBUG:
> Guacamole connection closed during handshake
> guacd_1           | 2021-09-13T14:18:51.820418741Z guacd[7]: DEBUG:
> Error reading "select": End of stream reached while reading instruction
> guacd_1           | 2021-09-13T14:21:51.700790573Z guacd[14814]: DEBUG:
> Clipboard data received. Reporting availability of clipboard data to RDP
> server.
> guacd_1           | 2021-09-13T14:21:52.644317299Z guacd[14814]: INFO:
>  User "@4e696ad7-a66f-4415-a84f-aa599d6239ee" disconnected (0 users remain)
> guacd_1           | 2021-09-13T14:21:52.644346809Z guacd[14814]: INFO:
>  Last user of connection "$ac241f0d-90de-487e-9bf5-d1fcab6463ed"
> disconnected
> guacd_1           | 2021-09-13T14:21:52.644350539Z guacd[14814]: DEBUG:
> Requesting termination of client...
> guacd_1           | 2021-09-13T14:21:52.712513048Z guacd[14814]: DEBUG:
> Unloading device 0 (G)
> guacd_1           | 2021-09-13T14:21:52.712527764Z guacd[14814]: DEBUG:
> SVC "rdpdr" disconnected.
> guacd_1           | 2021-09-13T14:21:52.712531208Z guacd[14814]: DEBUG:
> SVC "rdpsnd" disconnected.
> guacd_1           | 2021-09-13T14:21:52.713832474Z guacd[14814]: INFO:
>  Internal RDP client disconnected
> guacd_1           | 2021-09-13T14:21:52.714336876Z guacd[14814]: DEBUG:
> Client terminated successfully.
> guacd_1           | 2021-09-13T14:21:52.719287612Z guacd[7]: INFO:
>  Connection "$ac241f0d-90de-487e-9bf5-d1fcab6463ed" removed.
> guacd_1           | 2021-09-13T14:22:02.951448033Z guacd[15338]: DEBUG:
> Clipboard data received. Reporting availability of clipboard data to RDP
> server.
> guacd_1           | 2021-09-13T14:23:52.041486801Z guacd[7]: DEBUG:
> Guacamole connection closed during handshake
> guacd_1           | 2021-09-13T14:23:52.041508185Z guacd[7]: DEBUG:
> Error reading "select": End of stream reached while reading instruction
> guacd_1           | 2021-09-13T14:24:33.529359679Z guacd[15338]: DEBUG:
> Clipboard data received. Reporting availability of clipboard data to RDP
> server.
> guacd_1           | 2021-09-13T14:24:37.095385182Z guacd[15338]: DEBUG:
> Clipboard data received. Reporting availability of clipboard data to RDP
> server.
> guacd_1           | 2021-09-13T14:28:15.375586356Z guacd[15338]: DEBUG:
> Clipboard data received. Reporting availability of clipboard data to RDP
> server.
> guacd_1           | 2021-09-13T14:28:52.159743146Z guacd[7]: DEBUG:
> Guacamole connection closed during handshake
> guacd_1           | 2021-09-13T14:28:52.159773981Z guacd[7]: DEBUG:
> Error reading "select": End of stream reached while reading instruction
> guacd_1           | 2021-09-13T14:32:19.198653389Z guacd[15338]: DEBUG:
> BIO_read returned a system error 104: Connection reset by peer
> guacd_1           | 2021-09-13T14:32:19.198676263Z guacd[15338]: DEBUG:
> transport_read_layer:freerdp_set_last_error_ex
> ERRCONNECT_CONNECT_TRANSPORT_FAILED [0x0002000D]
> guacd_1           | 2021-09-13T14:32:19.198747713Z guacd[15338]: ERROR:
> Connection closed.
> guacd_1           | 2021-09-13T14:32:19.207063823Z guacd[15338]: DEBUG:
> Unloading device 0 (G)
> guacd_1           | 2021-09-13T14:32:19.207082713Z guacd[15338]: DEBUG:
> SVC "rdpdr" disconnected.
> guacd_1           | 2021-09-13T14:32:19.207086339Z guacd[15338]: DEBUG:
> SVC "rdpsnd" disconnected.
> guacd_1           | 2021-09-13T14:32:19.211464966Z guacd[15338]: INFO:
>  Internal RDP client disconnected
> guacd_1           | 2021-09-13T14:32:19.212441975Z guacd[15338]: INFO:
>  User "@b62dbd8f-0d04-4170-9f59-bab9436a4410" disconnected (0 users remain)
> guacd_1           | 2021-09-13T14:32:19.212453322Z guacd[15338]: INFO:
>  Last user of connection "$cf755c62-ebb4-4866-bdd8-9b3c868ce3f7"
> disconnected
> guacd_1           | 2021-09-13T14:32:19.212658496Z guacd[15338]: DEBUG:
> Requesting termination of client...
> guacd_1           | 2021-09-13T14:32:19.213289155Z guacd[15338]: DEBUG:
> Client terminated successfully.
> guacd_1           | 2021-09-13T14:32:19.223127887Z guacd[7]: INFO:
>  Connection "$cf755c62-ebb4-4866-bdd8-9b3c868ce3f7" removed.
> guacd_1           | 2021-09-13T14:33:52.257022053Z guacd[7]: DEBUG:
> Guacamole connection closed during handshake
> guacd_1           | 2021-09-13T14:33:52.257054063Z guacd[7]: DEBUG:
> Error reading "select": End of stream reached while reading instruction
> guacd_1           | 2021-09-13T14:38:52.349598898Z guacd[7]: DEBUG:
> Guacamole connection closed during handshake
> guacd_1           | 2021-09-13T14:38:52.349631320Z guacd[7]: DEBUG:
> Error reading "select": End of stream reached while reading instruction
> guacd_1           | 2021-09-13T14:43:52.457177071Z guacd[7]: DEBUG:
> Guacamole connection closed during handshake
> guacd_1           | 2021-09-13T14:43:52.457214339Z guacd[7]: DEBUG:
> Error reading "select": End of stream reached while reading instruction
> guacd_1           | 2021-09-13T14:48:52.569283904Z guacd[7]: DEBUG:
> Guacamole connection closed during handshake
> guacd_1           | 2021-09-13T14:48:52.569329287Z guacd[7]: DEBUG:
> Error reading "select": End of stream reached while reading instruction
> guacd_1           | 2021-09-13T14:53:52.686851881Z guacd[7]: DEBUG:
> Guacamole connection closed during handshake
> guacd_1           | 2021-09-13T14:53:52.686895047Z guacd[7]: DEBUG:
> Error reading "select": End of stream reached while reading instruction
>
>
> guacmole :
>
> guacamole_1       | 2021-09-13T14:18:44.269349795Z 14:18:44.269
> [http-nio-8080-exec-6] INFO  o.a.g.r.auth.AuthenticationService - User
> "guacadmin" successfully authenticated from [172.18.0.1, 172.18.0.19].
> guacamole_1       | 2021-09-13T14:21:52.644905551Z 14:21:52.644
> [http-nio-8080-exec-14] INFO  o.a.g.tunnel.TunnelRequestService - User
> "guacadmin" disconnected from connection "22743". Duration: 5446031
> milliseconds
> guacamole_1       | 2021-09-13T14:32:19.212341284Z 14:32:19.212
> [http-nio-8080-exec-16] INFO  o.a.g.tunnel.TunnelRequestService - User
> "guacadmin" disconnected from connection "22672". Duration: 1031145
> milliseconds
> guacamole_1       | 2021-09-13T14:32:19.214220909Z Exception in thread
> "Thread-63" java.lang.IllegalStateException: Message will not be sent
> because the WebSocket session has been closed
> guacamole_1       | 2021-09-13T14:32:19.214297548Z      at
> org.apache.tomcat.websocket.WsRemoteEndpointImplBase.writeMessagePart(WsRemoteEndpointImplBase.java:438)
> guacamole_1       | 2021-09-13T14:32:19.214302309Z      at
> org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendMessageBlock(WsRemoteEndpointImplBase.java:311)
> guacamole_1       | 2021-09-13T14:32:19.214305247Z      at
> org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendMessageBlock(WsRemoteEndpointImplBase.java:251)
> guacamole_1       | 2021-09-13T14:32:19.214308114Z      at
> org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendString(WsRemoteEndpointImplBase.java:192)
> guacamole_1       | 2021-09-13T14:32:19.214310977Z      at
> org.apache.tomcat.websocket.WsRemoteEndpointBasic.sendText(WsRemoteEndpointBasic.java:37)
> guacamole_1       | 2021-09-13T14:32:19.214338369Z      at
> org.apache.guacamole.websocket.GuacamoleWebSocketTunnelEndpoint.sendInstruction(GuacamoleWebSocketTunnelEndpoint.java:152)
> guacamole_1       | 2021-09-13T14:32:19.214341240Z      at
> org.apache.guacamole.websocket.GuacamoleWebSocketTunnelEndpoint.access$200(GuacamoleWebSocketTunnelEndpoint.java:53)
> guacamole_1       | 2021-09-13T14:32:19.214343913Z      at
> org.apache.guacamole.websocket.GuacamoleWebSocketTunnelEndpoint$2.run(GuacamoleWebSocketTunnelEndpoint.java:253)
>
> guacamole db:
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208925745Z
>    ,
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208928037Z
>       $22730::integer
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208930370Z
>    ,
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208932667Z
>       $22731::integer
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208934864Z
>    ,
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208937008Z
>       $22732::integer
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208939254Z
>    ,
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208941505Z
>       $22733::integer
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208943832Z
>    ,
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208946145Z
>       $22734::integer
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208948457Z
>    ,
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208950754Z
>       $22735::integer
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208955514Z
>    ,
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208957654Z
>       $22736::integer
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208959843Z
>    ,
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208962030Z
>       $22737::integer
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208964372Z
>    ,
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208966687Z
>       $22738::integer
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208969021Z
>    ,
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208971301Z
>       $22739::integer
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208973521Z
>    ,
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208975652Z
>       $22740::integer
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208977856Z
>    ,
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208980045Z
>       $22741::integer
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208982374Z
>    )
> ^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208984696Z
> GROUP BY guacamole_connection.connection_id
>
>
> couldn't get the query in the first row as it SUPER long!
> What is going on? How can I solve this?
>
> why the guacamole service gets stuck? PLEASE HELP
>
>
I don't really have any good ideas, except that I don't think it is
specifically a Guacamole issue. I think it's some sort of issue with the
Guacamole container(s) that is interrupting things, or perhaps with the DB
container, but nothing about Guacamole itself is causing this problem. I'd
suggest that you re-deploy the containers - you can even upgrade 1.2.0 ->
1.3.0 while you're at it - and see if clean containers take care of the
issue. Obviously you'll want to make sure you save your database - either
don't refresh that Postgres container, or make sure the data is in a
persistent location.

You should not have to restart your containers a couple of times a day or
anything like that. But, this seems to me a Docker or DB issue, nothing
with Guacamole.

-Nick

>

Re: Docker guacamole+guacd 1.2.0 gets stuck once a day randomly

Posted by Regev Batchen <ba...@gmail.com>.
Hello team,
can somone please help me ?

I upgraded docker to version 20.10.8 and docker-compose  version 1.29.2 and
also guacd + guacamole version to latest version 1.3.0

Out of all my containers only guacamole gets stuck
this is what i see on kern.log:

Sep 13 14:28:44 ansible kernel: [1548303.041290] br-4260ec4ed3f1: port
19(veth842fd2a) entered disabled state
Sep 13 14:28:44 ansible kernel: [1548303.041444] veth8c74c8b: renamed from
eth0
Sep 13 14:28:44 ansible kernel: [1548303.103365] br-4260ec4ed3f1: port
19(veth842fd2a) entered disabled state
Sep 13 14:28:44 ansible kernel: [1548303.114983] device veth842fd2a left
promiscuous mode
Sep 13 14:28:44 ansible kernel: [1548303.114987] br-4260ec4ed3f1: port
19(veth842fd2a) entered disabled state
Sep 13 14:28:45 ansible kernel: [1548303.703639] br-4260ec4ed3f1: port
11(veth6370b5e) entered disabled state
Sep 13 14:28:45 ansible kernel: [1548303.703767] veth3a75fd3: renamed from
eth0
Sep 13 14:28:45 ansible kernel: [1548303.769807] br-4260ec4ed3f1: port
11(veth6370b5e) entered disabled state
Sep 13 14:28:45 ansible kernel: [1548303.778901] device veth6370b5e left
promiscuous mode
Sep 13 14:28:45 ansible kernel: [1548303.778906] br-4260ec4ed3f1: port
11(veth6370b5e) entered disabled state
Sep 13 14:28:45 ansible kernel: [1548304.023824] br-4260ec4ed3f1: port
11(veth0e30632) entered blocking state
Sep 13 14:28:45 ansible kernel: [1548304.023826] br-4260ec4ed3f1: port
11(veth0e30632) entered disabled state
Sep 13 14:28:45 ansible kernel: [1548304.023993] device veth0e30632 entered
promiscuous mode
Sep 13 14:28:45 ansible kernel: [1548304.024117] IPv6: ADDRCONF(NETDEV_UP):
veth0e30632: link is not ready
Sep 13 14:28:45 ansible kernel: [1548304.024125] br-4260ec4ed3f1: port
11(veth0e30632) entered blocking state
Sep 13 14:28:45 ansible kernel: [1548304.024127] br-4260ec4ed3f1: port
11(veth0e30632) entered forwarding state
Sep 13 14:28:45 ansible kernel: [1548304.066552] br-4260ec4ed3f1: port
11(veth0e30632) entered disabled state
Sep 13 14:28:46 ansible kernel: [1548305.059156] eth0: renamed from
veth0b98f17
Sep 13 14:28:46 ansible kernel: [1548305.091017] IPv6:
ADDRCONF(NETDEV_CHANGE): veth0e30632: link becomes ready

guacd :

guacd_1           | 2021-09-13T14:15:42.167400545Z guacd[15338]: DEBUG:
guac_rdpdr_fs_process_create: [file_id=-2] desired_access=0x80,
file_attributes=0x0, create_disposition=0x1, create_options=0x1, path=""
guacd_1           | 2021-09-13T14:15:42.167403278Z guacd[15338]: ERROR:
File open refused (-2): ""
guacd_1           | 2021-09-13T14:17:02.948295495Z guacd[15338]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-13T14:17:14.857231812Z guacd[15338]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-13T14:18:16.590645926Z guacd[15338]: INFO:
 Accepted format: 16-bit PCM with 2 channels at 44100 Hz
guacd_1           | 2021-09-13T14:18:51.820263319Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-13T14:18:51.820418741Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-13T14:21:51.700790573Z guacd[14814]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-13T14:21:52.644317299Z guacd[14814]: INFO:
 User "@4e696ad7-a66f-4415-a84f-aa599d6239ee" disconnected (0 users remain)
guacd_1           | 2021-09-13T14:21:52.644346809Z guacd[14814]: INFO:
 Last user of connection "$ac241f0d-90de-487e-9bf5-d1fcab6463ed"
disconnected
guacd_1           | 2021-09-13T14:21:52.644350539Z guacd[14814]: DEBUG:
Requesting termination of client...
guacd_1           | 2021-09-13T14:21:52.712513048Z guacd[14814]: DEBUG:
Unloading device 0 (G)
guacd_1           | 2021-09-13T14:21:52.712527764Z guacd[14814]: DEBUG: SVC
"rdpdr" disconnected.
guacd_1           | 2021-09-13T14:21:52.712531208Z guacd[14814]: DEBUG: SVC
"rdpsnd" disconnected.
guacd_1           | 2021-09-13T14:21:52.713832474Z guacd[14814]: INFO:
 Internal RDP client disconnected
guacd_1           | 2021-09-13T14:21:52.714336876Z guacd[14814]: DEBUG:
Client terminated successfully.
guacd_1           | 2021-09-13T14:21:52.719287612Z guacd[7]: INFO:
 Connection "$ac241f0d-90de-487e-9bf5-d1fcab6463ed" removed.
guacd_1           | 2021-09-13T14:22:02.951448033Z guacd[15338]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-13T14:23:52.041486801Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-13T14:23:52.041508185Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-13T14:24:33.529359679Z guacd[15338]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-13T14:24:37.095385182Z guacd[15338]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-13T14:28:15.375586356Z guacd[15338]: DEBUG:
Clipboard data received. Reporting availability of clipboard data to RDP
server.
guacd_1           | 2021-09-13T14:28:52.159743146Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-13T14:28:52.159773981Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-13T14:32:19.198653389Z guacd[15338]: DEBUG:
BIO_read returned a system error 104: Connection reset by peer
guacd_1           | 2021-09-13T14:32:19.198676263Z guacd[15338]: DEBUG:
transport_read_layer:freerdp_set_last_error_ex
ERRCONNECT_CONNECT_TRANSPORT_FAILED [0x0002000D]
guacd_1           | 2021-09-13T14:32:19.198747713Z guacd[15338]: ERROR:
Connection closed.
guacd_1           | 2021-09-13T14:32:19.207063823Z guacd[15338]: DEBUG:
Unloading device 0 (G)
guacd_1           | 2021-09-13T14:32:19.207082713Z guacd[15338]: DEBUG: SVC
"rdpdr" disconnected.
guacd_1           | 2021-09-13T14:32:19.207086339Z guacd[15338]: DEBUG: SVC
"rdpsnd" disconnected.
guacd_1           | 2021-09-13T14:32:19.211464966Z guacd[15338]: INFO:
 Internal RDP client disconnected
guacd_1           | 2021-09-13T14:32:19.212441975Z guacd[15338]: INFO:
 User "@b62dbd8f-0d04-4170-9f59-bab9436a4410" disconnected (0 users remain)
guacd_1           | 2021-09-13T14:32:19.212453322Z guacd[15338]: INFO:
 Last user of connection "$cf755c62-ebb4-4866-bdd8-9b3c868ce3f7"
disconnected
guacd_1           | 2021-09-13T14:32:19.212658496Z guacd[15338]: DEBUG:
Requesting termination of client...
guacd_1           | 2021-09-13T14:32:19.213289155Z guacd[15338]: DEBUG:
Client terminated successfully.
guacd_1           | 2021-09-13T14:32:19.223127887Z guacd[7]: INFO:
 Connection "$cf755c62-ebb4-4866-bdd8-9b3c868ce3f7" removed.
guacd_1           | 2021-09-13T14:33:52.257022053Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-13T14:33:52.257054063Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-13T14:38:52.349598898Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-13T14:38:52.349631320Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-13T14:43:52.457177071Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-13T14:43:52.457214339Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-13T14:48:52.569283904Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-13T14:48:52.569329287Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction
guacd_1           | 2021-09-13T14:53:52.686851881Z guacd[7]: DEBUG:
Guacamole connection closed during handshake
guacd_1           | 2021-09-13T14:53:52.686895047Z guacd[7]: DEBUG:
Error reading "select": End of stream reached while reading instruction


guacmole :

guacamole_1       | 2021-09-13T14:18:44.269349795Z 14:18:44.269
[http-nio-8080-exec-6] INFO  o.a.g.r.auth.AuthenticationService - User
"guacadmin" successfully authenticated from [172.18.0.1, 172.18.0.19].
guacamole_1       | 2021-09-13T14:21:52.644905551Z 14:21:52.644
[http-nio-8080-exec-14] INFO  o.a.g.tunnel.TunnelRequestService - User
"guacadmin" disconnected from connection "22743". Duration: 5446031
milliseconds
guacamole_1       | 2021-09-13T14:32:19.212341284Z 14:32:19.212
[http-nio-8080-exec-16] INFO  o.a.g.tunnel.TunnelRequestService - User
"guacadmin" disconnected from connection "22672". Duration: 1031145
milliseconds
guacamole_1       | 2021-09-13T14:32:19.214220909Z Exception in thread
"Thread-63" java.lang.IllegalStateException: Message will not be sent
because the WebSocket session has been closed
guacamole_1       | 2021-09-13T14:32:19.214297548Z      at
org.apache.tomcat.websocket.WsRemoteEndpointImplBase.writeMessagePart(WsRemoteEndpointImplBase.java:438)
guacamole_1       | 2021-09-13T14:32:19.214302309Z      at
org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendMessageBlock(WsRemoteEndpointImplBase.java:311)
guacamole_1       | 2021-09-13T14:32:19.214305247Z      at
org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendMessageBlock(WsRemoteEndpointImplBase.java:251)
guacamole_1       | 2021-09-13T14:32:19.214308114Z      at
org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendString(WsRemoteEndpointImplBase.java:192)
guacamole_1       | 2021-09-13T14:32:19.214310977Z      at
org.apache.tomcat.websocket.WsRemoteEndpointBasic.sendText(WsRemoteEndpointBasic.java:37)
guacamole_1       | 2021-09-13T14:32:19.214338369Z      at
org.apache.guacamole.websocket.GuacamoleWebSocketTunnelEndpoint.sendInstruction(GuacamoleWebSocketTunnelEndpoint.java:152)
guacamole_1       | 2021-09-13T14:32:19.214341240Z      at
org.apache.guacamole.websocket.GuacamoleWebSocketTunnelEndpoint.access$200(GuacamoleWebSocketTunnelEndpoint.java:53)
guacamole_1       | 2021-09-13T14:32:19.214343913Z      at
org.apache.guacamole.websocket.GuacamoleWebSocketTunnelEndpoint$2.run(GuacamoleWebSocketTunnelEndpoint.java:253)

guacamole db:
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208925745Z
 ,
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208928037Z
    $22730::integer
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208930370Z
 ,
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208932667Z
    $22731::integer
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208934864Z
 ,
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208937008Z
    $22732::integer
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208939254Z
 ,
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208941505Z
    $22733::integer
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208943832Z
 ,
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208946145Z
    $22734::integer
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208948457Z
 ,
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208950754Z
    $22735::integer
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208955514Z
 ,
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208957654Z
    $22736::integer
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208959843Z
 ,
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208962030Z
    $22737::integer
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208964372Z
 ,
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208966687Z
    $22738::integer
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208969021Z
 ,
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208971301Z
    $22739::integer
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208973521Z
 ,
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208975652Z
    $22740::integer
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208977856Z
 ,
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208980045Z
    $22741::integer
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208982374Z
 )
^[[36mpostgres_gqm_1    |^[[0m 2021-09-13T14:59:26.208984696Z
GROUP BY guacamole_connection.connection_id


couldn't get the query in the first row as it SUPER long!
What is going on? How can I solve this?

why the guacamole service gets stuck? PLEASE HELP

‫בתאריך יום ה׳, 9 בספט׳ 2021 ב-9:16 מאת ‪Regev Batchen‬‏ <‪
batchenr@gmail.com‬‏>:‬

> Hello, I'm using guacamole on docker compose 1.25.0 with guacd and
> guacamole 1.2.0 on ubuntu 18.04.3 LTS (Bionic Beaver).
> Everything worked fine for more than 6 month and now it has started to get
> stuck and won't work until I restart both containers..
>
> docker-compose:
>         guacamole:
>                 image: guacamole/guacamole:1.2.0
>                 depends_on:
>                       - guacd
>                       - postgres_gqm
>                 volumes:
>                       -
> /root/ansible/api/start.sh:/opt/guacamole/bin/start.sh
>                       -
> /root/ansible/api/program.jar:/opt/guacamole/program.jar
>                 environment:
>                       GUACD_HOSTNAME: guacd
>                       POSTGRES_DATABASE:  guacd_db
>                       POSTGRES_HOSTNAME: postgres_gqm
>                       POSTGRES_PASSWORD: password
>                       POSTGRES_USER: guacd_user
>                       GUACD_LOG_LEVEL: debug
>                 links:
>                      - guacd
>                 ports:
>                      - 8080/tcp
>                 restart: always
>                 logging:
>                       driver: "json-file"
>                       options:
>                             max-size: "50m"
>         guacd:
>                 image: guacamole/guacd:1.2.0
>                 restart: always
>                 volumes:
>                         - ./drive:/drive:rw
>                         - ./record:/record:rw
>                 environment:
>                   GUACD_LOG_LEVEL: debug
>                 logging:
>                     driver: "json-file"
>                     options:
>                           max-size: "50m"
>
>
> containers logs:
>
> guacamole -
> guacamole_1       | 05:31:36.344 [http-nio-8080-exec-3] INFO
>  o.a.g.r.auth.AuthenticationService - User "guacadmin" successfully
> authenticated from [172.23.0.1, 172.23.0.19].
> guacamole_1       | 05:31:40.852 [http-nio-8080-exec-8] INFO
>  o.a.g.r.auth.AuthenticationService - User "guacadmin" successfully
> authenticated from [172.23.0.1, 172.23.0.19].
> guacamole_1       | 05:31:45.960 [http-nio-8080-exec-11] INFO
>  o.a.g.r.auth.AuthenticationService - User "guacadmin" successfully
> authenticated from [172.23.0.1, 172.23.0.19].
> guacamole_1       | 05:31:51.639 [http-nio-8080-exec-12] INFO
>  o.a.g.tunnel.TunnelRequestService - User "guacadmin" disconnected from
> connection "22354". Duration: 1195913 milliseconds
> guacamole_1       | 05:31:51.641 [Thread-26] INFO
>  o.a.g.tunnel.TunnelRequestService - User "" disconnected from connection
> "UqrJNFZyrUOvaC1vkfz2DP_Qm9-hJhTEB9yzAc9dpk-d". Duration: 1144359
> milliseconds
> guacamole_1       | 05:31:53.033 [http-nio-8080-exec-11] INFO
>  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
> guacamole_1       | 05:32:00.223 [http-nio-8080-exec-16] INFO
>  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
> guacamole_1       | 05:32:14.894 [http-nio-8080-exec-21] INFO
>  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
> guacamole_1       | 05:33:15.593 [http-nio-8080-exec-23] ERROR
> o.a.g.w.GuacamoleWebSocketTunnelEndpoint - Creation of WebSocket tunnel to
> guacd failed: Requested tunnel destination does not exist.
> guacamole_1       | 05:33:15.643 [http-nio-8080-exec-23] WARN
>  o.a.g.s.GuacamoleHTTPTunnelServlet - HTTP tunnel request rejected:
> Requested tunnel destination does not exist.
> guacamole_1       | 05:33:16.603 [http-nio-8080-exec-23] ERROR
> o.a.g.w.GuacamoleWebSocketTunnelEndpoint - Creation of WebSocket tunnel to
> guacd failed: Requested tunnel destination does not exist.
> guacamole_1       | 05:33:16.657 [http-nio-8080-exec-23] WARN
>  o.a.g.s.GuacamoleHTTPTunnelServlet - HTTP tunnel request rejected:
> Requested tunnel destination does not exist.
> guacamole_1       | 05:34:35.958 [http-nio-8080-exec-24] ERROR
> o.a.g.w.GuacamoleWebSocketTunnelEndpoint - Creation of WebSocket tunnel to
> guacd failed: Requested tunnel destination does not exist.
> guacamole_1       | 05:34:36.009 [http-nio-8080-exec-24] WARN
>  o.a.g.s.GuacamoleHTTPTunnelServlet - HTTP tunnel request rejected:
> Requested tunnel destination does not exist.
> guacamole_1       | 05:40:53.700 [http-nio-8080-exec-28] INFO
>  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
> guacamole_1       | 05:42:01.393 [http-nio-8080-exec-35] INFO
>  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
> guacamole_1       | 05:42:06.440 [http-nio-8080-exec-39] INFO
>  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
> guacamole_1       | 05:42:07.738 [http-nio-8080-exec-43] INFO
>  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
> guacamole_1       | 05:42:08.654 [http-nio-8080-exec-47] INFO
>  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
> guacamole_1       | 05:42:08.846 [http-nio-8080-exec-51] INFO
>  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
> guacamole_1       | 05:42:09.022 [http-nio-8080-exec-55] INFO
>  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
> guacamole_1       | 05:43:00.382 [http-nio-8080-exec-61] INFO
>  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
> guacamole_1       | 05:44:07.327 [http-nio-8080-exec-66] INFO
>  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
> guacamole_1       | 05:44:12.253 [http-nio-8080-exec-71] INFO
>  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
> guacamole_1       | 05:44:55.139 [http-nio-8080-exec-78] INFO
>  o.a.g.environment.LocalEnvironment - GUACAMOLE_HOME is "/root/.guacamole".
>
>
> guacd-
> guacd_1           | guacd[385]: DEBUG:  Client is using protocol version
> "VERSION_1_1_0"
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_alloc: Creating
> directory "shared-Raven" if necessary.
> guacd_1           | guacd[385]: INFO:   Loading keymap "base"
> guacd_1           | guacd[385]: INFO:   Loading keymap "en-us-qwerty"
> guacd_1           | guacd[385]: DEBUG:  Support for CLIPRDR (clipboard
> redirection) registered. Awaiting channel connection.
> guacd_1           | guacd[385]: DEBUG:  Support for static channel "rdpdr"
> loaded.
> guacd_1           | guacd[385]: DEBUG:  Support for static channel
> "rdpsnd" loaded.
> guacd_1           | guacd[385]: DEBUG:  Local framebuffer format
>  PIXEL_FORMAT_BGRX32
> guacd_1           | guacd[385]: DEBUG:  Remote framebuffer format
> PIXEL_FORMAT_RGB16
> guacd_1           | guacd[385]: DEBUG:  CLIPRDR (clipboard redirection)
> channel connected.
> guacd_1           | guacd[385]: DEBUG:  SVC "rdpdr" connected.
> guacd_1           | guacd[385]: DEBUG:  SVC "rdpsnd" connected.
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: path="/",
> access=0x80000000, file_attributes=0x0, create_disposition=0x1,
> create_options=0x0
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Normalized path
> "/" to "\".
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Translated path
> "\" to "shared-Raven/".
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: native open:
> real_path="shared-Raven/", flags=0x0
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Opened "\" as
> file_id=0
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open:
> path="/Download", access=0x80000000, file_attributes=0x0,
> create_disposition=0x1, create_options=0x0
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Normalized path
> "/Download" to "\Download".
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Translated path
> "\Download" to "shared-Raven/Download".
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: native open:
> real_path="shared-Raven/Download", flags=0x0
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Opened
> "\Download" as file_id=1
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_close: Closed
> "\Download" (file_id=1)
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_close: Closed "\"
> (file_id=0)
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: INFO:   Connected to RDPDR 1.13 as client
> 0x0003
> guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
> type=0x0001, length=44
> guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
> type=0x0002, length=8
> guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
> type=0x0003, length=8
> guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
> type=0x0004, length=8
> guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
> type=0x0005, length=8
> guacd_1           | guacd[385]: DEBUG:  Sending capabilities...
> guacd_1           | guacd[385]: DEBUG:  Capabilities sent.
> guacd_1           | guacd[385]: DEBUG:  Client ID confirmed
> guacd_1           | guacd[354]: DEBUG:
>  ERRINFO_DISCONNECTED_BY_OTHER_CONNECTION (0x00000005):Another user
> connected to the server, forcing the disconnection of the current
> connection.
> guacd_1           | guacd[354]: INFO:   RDP server closed connection:
> Disconnected by other connection.
> guacd_1           | guacd[354]: DEBUG:  Disconnect reason code: 0x5.
> guacd_1           | guacd[354]: DEBUG:  Unloading device 0 (G)
> guacd_1           | guacd[354]: DEBUG:  SVC "rdpdr" disconnected.
> guacd_1           | guacd[354]: DEBUG:  SVC "rdpsnd" disconnected.
> guacd_1           | guacd[354]: INFO:   Internal RDP client disconnected
> guacd_1           | guacd[385]: INFO:   Connected to RDPDR 1.13 as client
> 0x0002
> guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
> type=0x0001, length=44
> guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
> type=0x0002, length=8
> guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
> type=0x0003, length=8
> guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
> type=0x0004, length=8
> guacd_1           | guacd[385]: DEBUG:  Ignoring server capability set
> type=0x0005, length=8
> guacd_1           | guacd[385]: DEBUG:  Sending capabilities...
> guacd_1           | guacd[385]: DEBUG:  Capabilities sent.
> guacd_1           | guacd[385]: DEBUG:  Client ID confirmed
> guacd_1           | guacd[385]: INFO:   RDPDR user logged on
> guacd_1           | guacd[385]: DEBUG:  Registered device 0 (G)
> guacd_1           | guacd[385]: DEBUG:  All supported devices sent.
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: path="",
> access=0x80, file_attributes=0x0, create_disposition=0x1, create_options=0x1
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Normalized path
> "\" to "\".
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Translated path
> "\" to "shared-Raven/".
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: native open:
> real_path="shared-Raven/", flags=0x0
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Opened "\" as
> file_id=1
> guacd_1           | guacd[385]: DEBUG:  guac_rdpdr_fs_process_create:
> [file_id=1] desired_access=0x80, file_attributes=0x0,
> create_disposition=0x1, create_options=0x1, path=""
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open:
> path="\Download", access=0x80000000, file_attributes=0x0,
> create_disposition=0x3, create_options=0x1
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Normalized path
> "\Download" to "\Download".
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Translated path
> "\Download" to "shared-Raven/Download".
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: native open:
> real_path="shared-Raven/Download", flags=0x0
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Opened
> "\Download" as file_id=0
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_close: Closed
> "\Download" (file_id=0)
> guacd_1           | guacd[385]: DEBUG:  Device 0 (G) connected successfully
> guacd_1           | guacd[385]: DEBUG:
>  guac_rdpdr_fs_process_query_standard_info: [file_id=1]
> guacd_1           | guacd[385]: DEBUG:
>  guac_rdpdr_fs_process_query_basic_info: [file_id=1]
> guacd_1           | guacd[385]: DEBUG:  guac_rdpdr_fs_process_close:
> [file_id=1]
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_close: Closed "\"
> (file_id=1)
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: path="",
> access=0x80, file_attributes=0x0, create_disposition=0x1, create_options=0x1
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Normalized path
> "\" to "\".
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Translated path
> "\" to "shared-Raven/".
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: native open:
> real_path="shared-Raven/", flags=0x0
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Opened "\" as
> file_id=0
> guacd_1           | guacd[385]: DEBUG:  guac_rdpdr_fs_process_create:
> [file_id=0] desired_access=0x80, file_attributes=0x0,
> create_disposition=0x1, create_options=0x1, path=""
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open:
> path="\Download", access=0x80000000, file_attributes=0x0,
> create_disposition=0x3, create_options=0x1
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Normalized path
> "\Download" to "\Download".
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Translated path
> "\Download" to "shared-Raven/Download".
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: native open:
> real_path="shared-Raven/Download", flags=0x0
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Opened
> "\Download" as file_id=1
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_close: Closed
> "\Download" (file_id=1)
> guacd_1           | guacd[385]: DEBUG:
>  guac_rdpdr_fs_process_query_standard_info: [file_id=0]
> guacd_1           | guacd[385]: DEBUG:
>  guac_rdpdr_fs_process_query_basic_info: [file_id=0]
> guacd_1           | guacd[385]: DEBUG:
>  guac_rdpdr_fs_process_query_standard_info: [file_id=0]
> guacd_1           | guacd[385]: DEBUG:  guac_rdpdr_fs_process_close:
> [file_id=0]
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_close: Closed "\"
> (file_id=0)
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: path="",
> access=0x80, file_attributes=0x0, create_disposition=0x1, create_options=0x1
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Normalized path
> "\" to "\".
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Translated path
> "\" to "shared-Raven/".
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: native open:
> real_path="shared-Raven/", flags=0x0
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Opened "\" as
> file_id=1
> guacd_1           | guacd[385]: DEBUG:  guac_rdpdr_fs_process_create:
> [file_id=1] desired_access=0x80, file_attributes=0x0,
> create_disposition=0x1, create_options=0x1, path=""
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open:
> path="\Download", access=0x80000000, file_attributes=0x0,
> create_disposition=0x3, create_options=0x1
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Normalized path
> "\Download" to "\Download".
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Translated path
> "\Download" to "shared-Raven/Download".
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: native open:
> real_path="shared-Raven/Download", flags=0x0
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_open: Opened
> "\Download" as file_id=0
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_close: Closed
> "\Download" (file_id=0)
> guacd_1           | guacd[385]: DEBUG:
>  guac_rdpdr_fs_process_query_standard_info: [file_id=1]
> guacd_1           | guacd[385]: DEBUG:
>  guac_rdpdr_fs_process_query_basic_info: [file_id=1]
> guacd_1           | guacd[385]: DEBUG:
>  guac_rdpdr_fs_process_query_standard_info: [file_id=1]
> guacd_1           | guacd[385]: DEBUG:  guac_rdpdr_fs_process_close:
> [file_id=1]
> guacd_1           | guacd[385]: DEBUG:  guac_rdp_fs_close: Closed "\"
> (file_id=1)
> guacd_1           | guacd[354]: ERROR:  User is not responding.
> guacd_1           | guacd[354]: INFO:   User
> "@82723fb6-0aa9-4e9f-857d-92925d11ce91" disconnected (0 users remain)
> guacd_1           | guacd[354]: INFO:   Last user of connection
> "$d98f91b1-232c-4b1e-8357-38c83e1ed0a1" disconnected
> guacd_1           | guacd[354]: DEBUG:  Requesting termination of client...
> guacd_1           | guacd[354]: DEBUG:  Client terminated successfully.
> guacd_1           | guacd[6]: INFO:     Connection
> "$d98f91b1-232c-4b1e-8357-38c83e1ed0a1" removed.
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[6]: INFO:     Joining existing connection
> "$014f34c8-f450-45c0-91a6-1e5dea763e26"
> guacd_1           | guacd[385]: DEBUG:  Processing instruction: size
> guacd_1           | guacd[385]: DEBUG:  Processing instruction: audio
> guacd_1           | guacd[385]: DEBUG:  Processing instruction: video
> guacd_1           | guacd[385]: DEBUG:  Processing instruction: image
> guacd_1           | guacd[385]: DEBUG:  Processing instruction: timezone
> guacd_1           | guacd[385]: DEBUG:  Parameter "console" omitted. Using
> default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "console-audio" omitted.
> Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "ignore-cert" omitted.
> Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "disable-auth" omitted.
> Using default value of 0.
> guacd_1           | guacd[385]: INFO:   No security mode specified.
> Defaulting to security mode negotiation with server.
> guacd_1           | guacd[385]: DEBUG:  Parameter "hostname" omitted.
> Using default value of "".
> guacd_1           | guacd[385]: DEBUG:  Parameter "port" omitted. Using
> default value of 3389.
> guacd_1           | guacd[385]: DEBUG:  User resolution is 1360x664 at 96
> DPI
> guacd_1           | guacd[385]: DEBUG:  Parameter "dpi" omitted. Using
> default value of 96.
> guacd_1           | guacd[385]: DEBUG:  Using resolution of 1360x664 at 96
> DPI
> guacd_1           | guacd[385]: DEBUG:  Parameter "read-only" omitted.
> Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "client-name" omitted.
> Using default value of "Guacamole RDP".
> guacd_1           | guacd[385]: DEBUG:  Parameter "enable-wallpaper"
> omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "enable-theming"
> omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "enable-font-smoothing"
> omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter
> "enable-full-window-drag" omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter
> "enable-desktop-composition" omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "enable-menu-animations"
> omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "disable-bitmap-caching"
> omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter
> "disable-offscreen-caching" omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "disable-glyph-caching"
> omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "color-depth" omitted.
> Using default value of 16.
> guacd_1           | guacd[385]: DEBUG:  Parameter "disable-audio" omitted.
> Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "enable-printing"
> omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "printer-name" omitted.
> Using default value of "Guacamole Printer".
> guacd_1           | guacd[385]: DEBUG:  Parameter "enable-drive" omitted.
> Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "drive-name" omitted.
> Using default value of "Guacamole Filesystem".
> guacd_1           | guacd[385]: DEBUG:  Parameter "drive-path" omitted.
> Using default value of "".
> guacd_1           | guacd[385]: DEBUG:  Parameter "create-drive-path"
> omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "disable-download"
> omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "disable-upload"
> omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "timezone" omitted.
> Using default value of "Asia/Jerusalem".
> guacd_1           | guacd[385]: DEBUG:  Parameter "enable-sftp" omitted.
> Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "sftp-hostname" omitted.
> Using default value of "".
> guacd_1           | guacd[385]: DEBUG:  Parameter "sftp-port" omitted.
> Using default value of "22".
> guacd_1           | guacd[385]: DEBUG:  Parameter "sftp-username" omitted.
> Using default value of "".
> guacd_1           | guacd[385]: DEBUG:  Parameter "sftp-password" omitted.
> Using default value of "".
> guacd_1           | guacd[385]: DEBUG:  Parameter "sftp-passphrase"
> omitted. Using default value of "".
> guacd_1           | guacd[385]: DEBUG:  Parameter "sftp-root-directory"
> omitted. Using default value of "/".
> guacd_1           | guacd[385]: DEBUG:  Parameter
> "sftp-server-alive-interval" omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "sftp-disable-download"
> omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "sftp-disable-upload"
> omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "recording-name"
> omitted. Using default value of "recording".
> guacd_1           | guacd[385]: DEBUG:  Parameter
> "recording-exclude-output" omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter
> "recording-exclude-mouse" omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "recording-include-keys"
> omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "create-recording-path"
> omitted. Using default value of 0.
> guacd_1           | guacd[385]: INFO:   Resize method: none
> guacd_1           | guacd[385]: DEBUG:  Parameter "enable-audio-input"
> omitted. Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "gateway-port" omitted.
> Using default value of 443.
> guacd_1           | guacd[385]: DEBUG:  Parameter "disable-copy" omitted.
> Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "disable-paste" omitted.
> Using default value of 0.
> guacd_1           | guacd[385]: DEBUG:  Parameter "wol-send-packet"
> omitted. Using default value of 0.
> guacd_1           | guacd[385]: INFO:   User
> "@f58acd16-f14c-4239-9a15-2926c0d26d8e" joined connection
> "$014f34c8-f450-45c0-91a6-1e5dea763e26" (2 users now present)
> guacd_1           | guacd[385]: DEBUG:  Client is using protocol version
> "VERSION_1_1_0"
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: DEBUG:  Sending keysym 0x10005d3 as Unicode
> guacd_1           | guacd[385]: DEBUG:  Sending keysym 0x10005d5 as Unicode
> guacd_1           | guacd[385]: DEBUG:  Sending keysym 0x10005d2 as Unicode
> guacd_1           | guacd[385]: DEBUG:  Sending keysym 0x10005dd as Unicode
> guacd_1           | guacd[385]: DEBUG:  Sending keysym 0x10005dc as Unicode
> guacd_1           | guacd[385]: DEBUG:  Sending keysym 0x10005df as Unicode
> guacd_1           | guacd[385]: DEBUG:  Sending keysym 0x10005da as Unicode
> guacd_1           | guacd[385]: DEBUG:  Sending keysym 0x10005da as Unicode
> guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
> channels at 44100 Hz
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
> channels at 44100 Hz
> guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
> channels at 44100 Hz
> guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
> channels at 44100 Hz
> guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
> channels at 44100 Hz
> guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
> channels at 44100 Hz
> guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
> channels at 44100 Hz
> guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
> channels at 44100 Hz
> guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
> channels at 44100 Hz
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
> channels at 44100 Hz
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
> channels at 44100 Hz
> guacd_1           | guacd[385]: DEBUG:  Broadcasting clipboard to all
> connected users.
> guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
> clipboard data.
> guacd_1           | guacd[385]: DEBUG:  Sent 5 bytes of clipboard data on
> stream 0.
> guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
> guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
> clipboard data.
> guacd_1           | guacd[385]: DEBUG:  Sent 5 bytes of clipboard data on
> stream 0.
> guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
> guacd_1           | guacd[385]: DEBUG:  Broadcast of clipboard complete.
> guacd_1           | guacd[385]: DEBUG:  Broadcasting clipboard to all
> connected users.
> guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
> clipboard data.
> guacd_1           | guacd[385]: DEBUG:  Sent 142 bytes of clipboard data
> on stream 0.
> guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
> guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
> clipboard data.
> guacd_1           | guacd[385]: DEBUG:  Sent 142 bytes of clipboard data
> on stream 0.
> guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
> guacd_1           | guacd[385]: DEBUG:  Broadcast of clipboard complete.
> guacd_1           | guacd[385]: DEBUG:  Broadcasting clipboard to all
> connected users.
> guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
> clipboard data.
> guacd_1           | guacd[385]: DEBUG:  Sent 5 bytes of clipboard data on
> stream 0.
> guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
> guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
> clipboard data.
> guacd_1           | guacd[385]: DEBUG:  Sent 5 bytes of clipboard data on
> stream 0.
> guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
> guacd_1           | guacd[385]: DEBUG:  Broadcast of clipboard complete.
> guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
> channels at 44100 Hz
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
> channels at 44100 Hz
> guacd_1           | guacd[385]: INFO:   Accepted format: 16-bit PCM with 2
> channels at 44100 Hz
> guacd_1           | guacd[385]: DEBUG:  Broadcasting clipboard to all
> connected users.
> guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
> clipboard data.
> guacd_1           | guacd[385]: DEBUG:  Sent 4 bytes of clipboard data on
> stream 0.
> guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
> guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
> clipboard data.
> guacd_1           | guacd[385]: DEBUG:  Sent 4 bytes of clipboard data on
> stream 0.
> guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
> guacd_1           | guacd[385]: DEBUG:  Broadcast of clipboard complete.
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: DEBUG:  Broadcasting clipboard to all
> connected users.
> guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
> clipboard data.
> guacd_1           | guacd[385]: DEBUG:  Sent 2 bytes of clipboard data on
> stream 0.
> guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
> guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
> clipboard data.
> guacd_1           | guacd[385]: DEBUG:  Sent 2 bytes of clipboard data on
> stream 0.
> guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
> guacd_1           | guacd[385]: DEBUG:  Broadcast of clipboard complete.
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: DEBUG:  Broadcasting clipboard to all
> connected users.
> guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
> clipboard data.
> guacd_1           | guacd[385]: DEBUG:  Sent 2 bytes of clipboard data on
> stream 0.
> guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
> guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
> clipboard data.
> guacd_1           | guacd[385]: DEBUG:  Sent 2 bytes of clipboard data on
> stream 0.
> guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
> guacd_1           | guacd[385]: DEBUG:  Broadcast of clipboard complete.
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: DEBUG:  Broadcasting clipboard to all
> connected users.
> guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
> clipboard data.
> guacd_1           | guacd[385]: DEBUG:  Sent 13 bytes of clipboard data on
> stream 0.
> guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
> guacd_1           | guacd[385]: DEBUG:  Created stream 0 for text/plain
> clipboard data.
> guacd_1           | guacd[385]: DEBUG:  Sent 13 bytes of clipboard data on
> stream 0.
> guacd_1           | guacd[385]: DEBUG:  Clipboard stream 0 complete.
> guacd_1           | guacd[385]: DEBUG:  Broadcast of clipboard complete.
> guacd_1           | guacd[385]: DEBUG:  Clipboard data received. Reporting
> availability of clipboard data to RDP server.
> guacd_1           | guacd[385]: INFO:   User
> "@65fabdc8-e59d-4af1-965a-be3888e9309f" disconnected (1 users remain)
> guacd_1           | guacd[385]: ERROR:  User is not responding.
> guacd_1           | guacd[385]: INFO:   User
> "@f58acd16-f14c-4239-9a15-2926c0d26d8e" disconnected (0 users remain)
> guacd_1           | guacd[385]: INFO:   Last user of connection
> "$014f34c8-f450-45c0-91a6-1e5dea763e26" disconnected
> guacd_1           | guacd[385]: DEBUG:  Requesting termination of client...
> guacd_1           | guacd[385]: DEBUG:  Unloading device 0 (G)
> guacd_1           | guacd[385]: DEBUG:  SVC "rdpdr" disconnected.
> guacd_1           | guacd[385]: DEBUG:  SVC "rdpsnd" disconnected.
> guacd_1           | guacd[385]: INFO:   Internal RDP client disconnected
> guacd_1           | guacd[385]: DEBUG:  Client terminated successfully.
> guacd_1           | guacd[6]: INFO:     Connection
> "$014f34c8-f450-45c0-91a6-1e5dea763e26" removed.
>
>
> Containers get stuck meaning no new data is writing, container looks
> "running" from docker state or docker ps.
> user sees blank page and requests guacamole using url /gqm/api gets 502
> error.
>
> What should i do ?
> my server is very big no memory issue or cpu or disk no other errors on
> another containers (i have 10-12) only GQM gets stuck and i don't know what
> to do.
> Please help?
> I'm planning on upgrading to 1.3 as this is my only option left + maybe
> add a script twice a day to restart only these containers.
>