Re: stalled guacd processes

[Philippe MARASSE]

Hello,

Again, with usage of ghostscript, lots of stalled guacd processes :

root@guacd-dc:~# ps auxf | grep guacd




Regards.


Le 10/03/2022 à 17:00, Philippe MARASSE a écrit :

root      975526  0.0  0.0   6196   728 pts/0    S+   09:15 0:00          \_ grep guacd guacd     239034  0.0  0.4 1305732 8428 ?        Ssl  mars03 12:27 /opt/guacd-1.4.0/sbin/guacd -f *guacd     773326  0.0  0.4 1318236 9736 ?        Sl   mars16 0:09  \_ /opt/guacd-1.4.0/sbin/guacd -f** **guacd     776152  0.0  0.4 1318236 9964 ?        Sl mars16   0:15  \_ /opt/guacd-1.4.0/sbin/guacd -f** **guacd     778194  0.0  0.4 1324056 9964 ?        Sl mars16   0:08  \_ /opt/guacd-1.4.0/sbin/guacd -f** **guacd     786275  0.0  0.4 1348344 9588 ?        Sl mars16   0:14  \_ /opt/guacd-1.4.0/sbin/guacd -f** **guacd     788503  0.0  0.1  47920  3836 ?        S mars16   0:08  |   \_ gs -q -dNOPAUSE -dBATCH -dSAFER -dPARANOIDSAFER -sDEVICE=pdfwrite -sOutputFile=- -c .setpdfwrite -sstdout=/dev/null -f -** **guacd     828165  0.0  0.4 1423284 9956 ?        Sl mars17   0:10  \_ /opt/guacd-1.4.0/sbin/guacd -f** **guacd     830466  0.0  0.2  46292  4152 ?        S mars17   0:05  |   \_ gs -q -dNOPAUSE -dBATCH -dSAFER -dPARANOIDSAFER -sDEVICE=pdfwrite -sOutputFile=- -c .setpdfwrite -sstdout=/dev/null -f -** **guacd     830678  0.0  0.4 1423316 9804 ?        Sl mars17   0:04  \_ /opt/guacd-1.4.0/sbin/guacd -f** **guacd     830695  0.0  0.1  46292  3992 ?        S mars17   0:05  |   \_ gs -q -dNOPAUSE -dBATCH -dSAFER -dPARANOIDSAFER -sDEVICE=pdfwrite -sOutputFile=- -c .setpdfwrite -sstdout=/dev/null -f -** **guacd     860958  0.0  0.5 1357748 10256 ?       Sl mars18   0:07  \_ /opt/guacd-1.4.0/sbin/guacd -f** **guacd     862243  0.0  3.8 1423284 76780 ?       Sl mars18   0:14  \_ /opt/guacd-1.4.0/sbin/guacd -f** **guacd     863245  0.0  0.7 1480688 14932 ?       Sl mars18   0:00  \_ /opt/guacd-1.4.0/sbin/guacd -f** **guacd     863329  0.0  4.6 1423284 92264 ?       Sl mars18   0:11  \_ /opt/guacd-1.4.0/sbin/guacd -f** **guacd     866151  0.0  1.8 1322788 37152 ?       Sl mars18   0:01  \_ /opt/guacd-1.4.0/sbin/guacd -f** **guacd     871034  0.0  4.5 1334216 89992 ?       Sl mars18   0:06  \_ /opt/guacd-1.4.0/sbin/guacd -f* guacd     973738  0.1  4.0 1355708 81364 ?       Sl   08:09 0:06  \_ /opt/guacd-1.4.0/sbin/guacd -f guacd     974720  0.6  5.5 1355652 110296 ?      Sl   08:46 0:10  \_ /opt/guacd-1.4.0/sbin/guacd -f I'm seeking directions to narrow the issue. Some usage of ghostscript worked fine, other hangs.

Hello,



# ps auxf | grep guacd


All of them looks like :

# grep 460026 /var/log/daemon.log

Mar  8 14:08:22 guacd-dc guacd[460026]: Resize method: none

Mar  8 14:08:22 guacd-dc guacd[460026]: Loading keymap "base"

Mar  8 14:08:22 guacd-dc guacd[460026]: Loading keymap "fr-fr-azerty"

Mar  8 14:08:30 guacd-dc guacd[460026]: RDPDR user logged on

Mar  8 14:19:37 guacd-dc guacd[460026]: Print job created

Mar  8 14:19:37 guacd-dc guacd[460026]: Print job closed

Mar  8 14:19:49 guacd-dc guacd[460026]: Print job created


Print jobs are never closed at end, I think a normal job looks like :

Print job created
INFO:#011Print job created
INFO:#011Created PDF filter process PID=460369
Created PDF filter process PID=460369
Print job closed
INFO:#011Print job closed

And the stalled processes last log line is :

INFO:#011Created PDF filter process PID=460375



INFO:#011Created PDF filter process PID=460369
Created PDF filter process PID=460369

When it works, and once stalled :

Created PDF filter process PID=460375
INFO:#011Created PDF filter process PID=460375



Regards.
--
Philippe MARASSE

Responsable pôle Infrastructures - DSIO
Centre Hospitalier Henri Laborit
CS 10587 - 370 avenue Jacques Cœur
86021 Poitiers Cedex
Tel : 05.49.44.57.19

It occurs more frequently since I've upgraded to guacd 1.4.0, I see stalled process used a few days ago : root      548250  0.0  0.0   6196   716 pts/0    S+   16:48 0:00          \_ grep guacd guacd     239034  0.0  0.6 1231968 13364 ?       Ssl  mars03 6:07 /opt/guacd-1.4.0/sbin/guacd -f *guacd     450284  0.0  1.1 1323840 22476 ?       Sl mars08   0:11  \_ /opt/guacd-1.4.0/sbin/guacd -f** **guacd     459214  0.0  1.1 1319924 23760 ?       Sl mars08   0:01  \_ /opt/guacd-1.4.0/sbin/guacd -f** **guacd     460026  0.0  4.5 1330532 90820 ?       Sl mars08   0:03  \_ /opt/guacd-1.4.0/sbin/guacd -f** **guacd     462567  0.0  5.4 1357880 109208 ?      Sl mars08   0:04  \_ /opt/guacd-1.4.0/sbin/guacd -f** **guacd     488863  0.0  5.0 1332036 101260 ?      Sl mars09   0:18  \_ /opt/guacd-1.4.0/sbin/guacd -f* guacd     534541  0.1  5.7 1348296 114428 ?      Sl   08:14 0:34  \_ /opt/guacd-1.4.0/sbin/guacd -f guacd     544071  0.1  6.4 1423284 128796 ?      Sl   14:11 0:09  \_ /opt/guacd-1.4.0/sbin/guacd -f guacd     545739  0.2  5.9 1423680 118572 ?      Sl   15:15 0:15  \_ /opt/guacd-1.4.0/sbin/guacd -f Mar  8 14:08:22 guacd-dc guacd[460026]: No security mode specified. Defaulting to security mode negotiation with server. Mar  8 14:08:22 guacd-dc guacd[460026]: guacd[460026]: INFO:#011No security mode specified. Defaulting to security mode negotiation with server. Mar  8 14:08:22 guacd-dc guacd[460026]: guacd[460026]: INFO:#011Resize method: none Mar  8 14:08:22 guacd-dc guacd[460026]: guacd[460026]: INFO:#011No clipboard line-ending normalization specified. Defaulting to preserving the format of all line endings. Mar  8 14:08:22 guacd-dc guacd[460026]: guacd[460026]: INFO:#011User "@6c8b6aee-7840-4c64-b4a0-a72c40501d86" joined connection "$afe8dfaf-77fc-4c6b-8271-680b3a06ce1a" (1 users now present) Mar  8 14:08:22 guacd-dc guacd[460026]: No clipboard line-ending normalization specified. Defaulting to preserving the format of all line endings. Mar  8 14:08:22 guacd-dc guacd[460026]: User "@6c8b6aee-7840-4c64-b4a0-a72c40501d86" joined connection "$afe8dfaf-77fc-4c6b-8271-680b3a06ce1a" (1 users now present) Mar  8 14:08:22 guacd-dc guacd[460026]: Recording of session will be saved to "/xxx.sr". Mar  8 14:08:22 guacd-dc guacd[460026]: guacd[460026]: INFO:#011Recording of session will be saved to "/xxx.sr". Mar  8 14:08:22 guacd-dc guacd[460026]: guacd[460026]: INFO:#011Loading keymap "base" Mar  8 14:08:22 guacd-dc guacd[460026]: guacd[460026]: INFO:#011Loading keymap "fr-fr-azerty" Mar  8 14:08:30 guacd-dc guacd[460026]: Connected to RDPDR 1.12 as client 0x0038 Mar  8 14:08:30 guacd-dc guacd[460026]: guacd[460026]: INFO:#011Connected to RDPDR 1.12 as client 0x0038 Mar  8 14:08:30 guacd-dc guacd[460026]: guacd[460026]: INFO:#011RDPDR user logged on Mar  8 14:19:37 guacd-dc guacd[460026]: guacd[460026]: INFO:#011Print job created Mar  8 14:19:37 guacd-dc guacd[460026]: guacd[460026]: INFO:#011Created PDF filter process PID=460369 Mar  8 14:19:37 guacd-dc guacd[460026]: Created PDF filter process PID=460369 Mar  8 14:19:37 guacd-dc guacd[460026]: guacd[460026]: INFO:#011Print job closed Mar  8 14:19:49 guacd-dc guacd[460026]: guacd[460026]: INFO:#011Print job created Mar  8 14:19:49 guacd-dc guacd[460026]: Created PDF filter process PID=460375 Mar  8 14:19:49 guacd-dc guacd[460026]: guacd[460026]: INFO:#011Created PDF filter process PID=460375 Maybe an issue with ghostscript ? It looks like two log lines are inverted : As a workaround, I can kill processes individually, it does not seem to affect guacd behavior but it's a bit strange.

--
Philippe MARASSE

Responsable pôle Infrastructures
Direction de l'Informatique, Support à la Communication et à l'Organisation 
(DISCO)
Centre Hospitalier Henri Laborit
CS 10587 - 370 avenue Jacques Cœur
86021 Poitiers Cedex
Tel : 05.49.44.57.19

smime.p7s
Description: Signature cryptographique S/MIME