On Tue, Aug 25, 2020 at 11:06 AM rramalho <[email protected]> wrote:
> Hi all, > > I'm having a strange thing happening to my guacamole installation, since > I've upgraded to guacamole 1.1.0. > Clients to RDP servers disconnect after a few seconds. I've set guacd to > debug and got this. I'm running Guacamole on Ubuntu Server 20.04.1. > > Aug 25 15:25:19 molerat guacd[3090]: Internal RDP client disconnected > Aug 25 15:25:19 molerat guacd[3090]: User > "@493c7148-b53e-49f1-85cd-ac3e3ff0b7b8" disconnected (0 users remain) > Aug 25 15:25:19 molerat guacd[3090]: Last user of connection > "$9e67fe50-37d9-4677-be10-ca83885fb515" disconnected > Aug 25 15:25:19 molerat guacd[3090]: Requesting termination of client... > Aug 25 15:25:19 molerat guacd[3090]: Client terminated successfully. > Aug 25 15:25:19 molerat guacd[3076]: Connection > "$9e67fe50-37d9-4677-be10-ca83885fb515" removed. > Aug 25 15:36:30 molerat guacd[12627]: Stopping guacd: SUCCESS > Aug 25 15:36:30 molerat systemd[1]: guacd.service: Succeeded. > Aug 25 15:37:40 molerat guacd[14185]: Guacamole proxy daemon (guacd) > version > 1.1.0 started > Aug 25 15:37:40 molerat guacd[14183]: Starting guacd: > Aug 25 15:37:40 molerat guacd[14185]: guacd[14185]: INFO:#011Guacamole > proxy > daemon (guacd) version 1.1.0 started > Aug 25 15:37:40 molerat guacd[14185]: guacd[14185]: DEBUG:#011Unable to > bind > socket to host ::1, port 4822: Address family not supported by protocol > Aug 25 15:37:40 molerat guacd[14185]: guacd[14185]: DEBUG:#011Successfully > bound socket to host 127.0.0.1, port 4822 > Aug 25 15:37:40 molerat guacd[14185]: Unable to bind socket to host ::1, > port 4822: Address family not supported by protocol > Aug 25 15:37:40 molerat guacd[14185]: guacd[14185]: DEBUG:#011Exiting and > passing control to PID 14186 > Aug 25 15:37:40 molerat guacd[14185]: Successfully bound socket to host > 127.0.0.1, port 4822 > Aug 25 15:37:40 molerat guacd[14183]: SUCCESS > Aug 25 15:37:40 molerat guacd[14185]: Exiting and passing control to PID > 14186 > Aug 25 15:37:40 molerat guacd[14186]: guacd[14186]: DEBUG:#011Exiting and > passing control to PID 14187 > Aug 25 15:37:40 molerat guacd[14186]: Exiting and passing control to PID > 14187 > Aug 25 15:37:40 molerat guacd[14187]: Listening on host 127.0.0.1, port > 4822 > Aug 25 15:39:16 molerat guacd[14187]: Creating new client for protocol > "rdp" > Aug 25 15:39:16 molerat guacd[14187]: Connection ID is > "$f4711187-83f6-4684-b5ce-a08b79a38390" > Aug 25 15:39:16 molerat guacd[14194]: "HOME" environment variable was unset > and has been automatically set to "/root" > Aug 25 15:39:16 molerat guacd[14194]: Processing instruction: size > Aug 25 15:39:16 molerat guacd[14194]: Processing instruction: audio > Aug 25 15:39:16 molerat guacd[14194]: Processing instruction: video > Aug 25 15:39:16 molerat guacd[14194]: Processing instruction: image > Aug 25 15:39:16 molerat guacd[14194]: Processing instruction: timezone > Aug 25 15:39:16 molerat guacd[14194]: Parameter "console" omitted. Using > default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "console-audio" omitted. > Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "disable-auth" omitted. > Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: No security mode specified. > Defaulting > to security mode negotiation with server. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "port" omitted. Using > default value of 3389. > Aug 25 15:39:16 molerat guacd[14194]: User resolution is 1582x828 at 96 DPI > Aug 25 15:39:16 molerat guacd[14194]: Parameter "dpi" omitted. Using > default > value of 96. > Aug 25 15:39:16 molerat guacd[14194]: Using resolution of 1580x828 at 96 > DPI > Aug 25 15:39:16 molerat guacd[14194]: Parameter "read-only" omitted. Using > default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "client-name" omitted. > Using > default value of "Guacamole RDP". > Aug 25 15:39:16 molerat guacd[14194]: Parameter "enable-wallpaper" omitted. > Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "enable-theming" omitted. > Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "enable-font-smoothing" > omitted. Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "enable-full-window-drag" > omitted. Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter > "enable-desktop-composition" > omitted. Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "enable-menu-animations" > omitted. Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "disable-bitmap-caching" > omitted. Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "disable-offscreen-caching" > omitted. Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "disable-glyph-caching" > omitted. Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "color-depth" omitted. > Using > default value of 16. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "enable-printing" omitted. > Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "printer-name" omitted. > Using default value of "Guacamole Printer". > Aug 25 15:39:16 molerat guacd[14194]: Parameter "enable-drive" omitted. > Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "drive-name" omitted. Using > default value of "Guacamole Filesystem". > Aug 25 15:39:16 molerat guacd[14194]: Parameter "drive-path" omitted. Using > default value of "". > Aug 25 15:39:16 molerat guacd[14194]: Parameter "create-drive-path" > omitted. > Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "timezone" omitted. Using > default value of "Europe/Lisbon". > Aug 25 15:39:16 molerat guacd[14194]: Parameter "enable-sftp" omitted. > Using > default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "sftp-passphrase" omitted. > Using default value of "". > Aug 25 15:39:16 molerat guacd[14194]: Parameter > "sftp-server-alive-interval" > omitted. Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "recording-name" omitted. > Using default value of "recording". > Aug 25 15:39:16 molerat guacd[14194]: Parameter "recording-exclude-output" > omitted. Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "recording-exclude-mouse" > omitted. Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "recording-include-keys" > omitted. Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "create-recording-path" > omitted. Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Resize method: none > Aug 25 15:39:16 molerat guacd[14194]: Parameter "enable-audio-input" > omitted. Using default value of 0. > Aug 25 15:39:16 molerat guacd[14194]: Parameter "gateway-port" omitted. > Using default value of 443. > Aug 25 15:39:16 molerat guacd[14194]: User > "@f78e84cb-0ea8-4caa-afcb-40433d29e103" joined connection > "$f4711187-83f6-4684-b5ce-a08b79a38390" (1 users now present) > Aug 25 15:39:16 molerat guacd[14194]: Client is using protocol version > "VERSION_1_1_0" > Aug 25 15:39:16 molerat guacd[14194]: Loading keymap "base" > Aug 25 15:39:16 molerat guacd[14194]: Loading keymap "en-us-qwerty" > Aug 25 15:39:16 molerat guacd[14194]: > freerdp_connect:freerdp_set_last_error_ex resetting error state > Aug 25 15:39:16 molerat guacd[14194]: Support for CLIPRDR (clipboard > redirection) registered. Awaiting channel connection. > Aug 25 15:39:16 molerat guacd[14194]: Local framebuffer format > PIXEL_FORMAT_BGRX32 > Aug 25 15:39:16 molerat guacd[14194]: Remote framebuffer format > PIXEL_FORMAT_RGB16 > Aug 25 15:39:17 molerat guacd[14194]: primitives autodetect, using > optimized > Aug 25 15:39:17 molerat guacd[14194]: > freerdp_tcp_is_hostname_resolvable:freerdp_set_last_error_ex resetting > error > state > Aug 25 15:39:17 molerat guacd[14194]: > freerdp_tcp_connect:freerdp_set_last_error_ex resetting error state > Aug 25 15:39:17 molerat guacd[14194]: CLIPRDR (clipboard redirection) > channel connected. > Aug 25 15:39:18 molerat guacd[14194]: Clipboard data received. Reporting > availability of clipboard data to RDP server. > Aug 25 15:39:18 molerat guacd[14194]: [cliprdr_packet_format_list_new] > called with invalid type 00000000 > Aug 25 15:39:18 molerat guacd[14194]: Clipboard data received. Reporting > availability of clipboard data to RDP server. > Aug 25 15:39:18 molerat guacd[14194]: [cliprdr_packet_format_list_new] > called with invalid type 00000000 > Aug 25 15:39:24 molerat guacd[14194]: > rdp_set_error_info:freerdp_set_last_error_ex resetting error state > Aug 25 15:39:24 molerat guacd[14194]: invalid bitmap bpp 0 > Aug 25 15:39:24 molerat guacd[14194]: SECONDARY ORDER [0x00] Cache Bitmap > failed > Aug 25 15:39:24 molerat guacd[14194]: SECONDARY_ORDER [0x00] Cache Bitmap: > read 18446744073709551096bytes too much > Aug 25 15:39:24 molerat guacd[14194]: order flags 03 failed > Aug 25 15:39:24 molerat guacd[14194]: Fastpath update Orders [0] failed, > status 0 > Aug 25 15:39:24 molerat guacd[14194]: fastpath_recv_update() - -1 > Aug 25 15:39:24 molerat guacd[14194]: fastpath_recv_update_data() fail > Aug 25 15:39:24 molerat guacd[14194]: transport_check_fds: > transport->ReceiveCallback() - -3 > Aug 25 15:39:24 molerat guacd[14194]: freerdp_check_fds() failed - 0 > Aug 25 15:39:24 molerat guacd[14194]: Connection closed. > Aug 25 15:39:24 molerat guacd[14194]: Internal RDP client disconnected > Aug 25 15:39:24 molerat guacd[14194]: User > "@f78e84cb-0ea8-4caa-afcb-40433d29e103" disconnected (0 users remain) > Aug 25 15:39:24 molerat guacd[14194]: Last user of connection > "$f4711187-83f6-4684-b5ce-a08b79a38390" disconnected > Aug 25 15:39:24 molerat guacd[14194]: Requesting termination of client... > Aug 25 15:39:24 molerat guacd[14194]: Client terminated successfully. > Aug 25 15:39:24 molerat guacd[14187]: Connection > "$f4711187-83f6-4684-b5ce-a08b79a38390" removed. > > I really don't know what's happening. > > This happens with Windows Server 2008 R2 or Windows 7. With Windows 10, it > connects and works. > > I believe there are some known issues with certain versions of the FreeRDP libraries, specifically in Ubuntu, that cause this. I can't locate a JIRA issue for it at the moment, but it seems like it has been brought to either the mailing list or the JIRA tracker before, and relates to a specific update to FreeRDP that has caused issues. -Nick
