[GUACAMOLE-958] Race in guacd client termination

• Type: Bug
• Status: Resolved
• Priority: Minor
• Resolution: Fixed
• Affects Version/s: 1.1.0
• Fix Version/s: 1.2.0
• Component/s: guacd
• Assignee: Unassigned
• Reporter: Jonas Zeiger
• Created: 12/Feb/20 14:01
• Updated: 14/Feb/20 13:41
• Resolved: 14/Feb/20 13:41

Description

I observed delays when trying to shutdown guacd cleanly:

2020-02-12 12:20:52.886 [some-app] [0c7bfb7f-c009-4685-8b83-a144aa0bb4f6] [140049387599616]: guacd[174]: DEBUG:	Client terminated successfully.
2020-02-12 12:20:52.887 [some-app] [0c7bfb7f-c009-4685-8b83-a144aa0bb4f6] [140049387599616]: guacd[166]: ERROR:	Error handling message from VNC server.
2020-02-12 12:20:52.887 [some-app] [0c7bfb7f-c009-4685-8b83-a144aa0bb4f6] [140049387599616]: guacd[166]: INFO:	User "@e2dac0b0-5e01-4b3e-8898-14d2d351abee" disconnected (0 users remain)
2020-02-12 12:20:52.887 [some-app] [0c7bfb7f-c009-4685-8b83-a144aa0bb4f6] [140049387599616]: guacd[166]: INFO:	Last user of connection "$902a4fea-c198-409f-a249-9a5dbaa4bdec" disconnected
2020-02-12 12:20:52.887 [some-app] [0c7bfb7f-c009-4685-8b83-a144aa0bb4f6] [140049387599616]: guacd[166]: DEBUG:	Requesting termination of client...
2020-02-12 12:20:52.887 [some-app] [0c7bfb7f-c009-4685-8b83-a144aa0bb4f6] [140049387599616]: guacd[166]: INFO:	Internal VNC client disconnected
2020-02-12 12:20:57.887 [some-app] [0c7bfb7f-c009-4685-8b83-a144aa0bb4f6] [140049387599616]: guacd[166]: WARNING:	Client did not terminate in a timely manner. Forcibly terminating client and any child processes.

I suspect the client actually terminated properly (see log message "Internal VNC client disconnected").