[Pkg-cups-devel] Bug#682426: cups: filter gs takes several minutes consuming 100 % of CPU

Sun Jul 22 16:38:44 UTC 2012

Package: cups
Version: 1.5.3-2
Severity: grave

Dear Debian folks,

printing takes longer than expected. Instead of several seconds to
process the job it takes several minutes until the printer starts to
print.

This seems to be related to Ghostscript.

        $ more /var/log/cups/error_log
        […]
        D [22/Jul/2012:18:13:33 +0200] [Job 1238] Options from the PPD file:
        D [22/Jul/2012:18:13:33 +0200] [Job 1238] Pondering option 'InputSlot=Default'
        D [22/Jul/2012:18:13:33 +0200] [Job 1238] Pondering option 'Quality=FromPrintoutMode'
        D [22/Jul/2012:18:13:33 +0200] [Job 1238] Pondering option 'PageSize=A4'
        D [22/Jul/2012:18:13:33 +0200] [Job 1238] Pondering option 'PrintoutMode=Draft'
        D [22/Jul/2012:18:13:33 +0200] [Job 1238] Pondering option 'Duplex=None'
        D [22/Jul/2012:18:13:33 +0200] [Job 1238] 
        D [22/Jul/2012:18:13:33 +0200] [Job 1238] ================================================
        D [22/Jul/2012:18:13:33 +0200] [Job 1238] 
        D [22/Jul/2012:18:13:33 +0200] [Job 1238] File: <STDIN>
        D [22/Jul/2012:18:13:33 +0200] [Job 1238] 
        D [22/Jul/2012:18:13:33 +0200] [Job 1238] ================================================
        D [22/Jul/2012:18:13:33 +0200] [Job 1238] 
        D [22/Jul/2012:18:13:33 +0200] PID 14271 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
        D [22/Jul/2012:18:13:33 +0200] [Job 1238] Using image rendering resolution 1200 dpi
        D [22/Jul/2012:18:13:33 +0200] [Job 1238] Started filter gs (PID 14275)
        D [22/Jul/2012:18:13:33 +0200] [Job 1238] Started filter pstops (PID 14276)
        D [22/Jul/2012:18:13:44 +0200] Report: clients=2
        D [22/Jul/2012:18:13:44 +0200] Report: jobs=499
        D [22/Jul/2012:18:13:44 +0200] Report: jobs-active=1
        D [22/Jul/2012:18:13:44 +0200] Report: printers=4
        D [22/Jul/2012:18:13:44 +0200] Report: printers-implicit=0
        D [22/Jul/2012:18:13:44 +0200] Report: stringpool-string-count=5919
        D [22/Jul/2012:18:13:44 +0200] Report: stringpool-alloc-bytes=13760
        D [22/Jul/2012:18:13:44 +0200] Report: stringpool-total-bytes=107840
        I [22/Jul/2012:18:13:53 +0200] Saving job.cache...
        […]

This does not finish and takes two to three minutes. CPU usage shown by `htop` is 100 %.

        $ ps aux | grep gs
        lp       14275 15.0 16.5 571444 557404 ?       t    18:13   0:39 gs -q -dNOPAUSE -dBATCH -dSAFER -sDEVICE=ps2write -sOUTPUTFILE=%stdout -dLanguageLevel=3 -r1200 -dCompressFonts=false -dNoT3CCITT -c save pop -f /var/spool/cups/tmp/037c0500da6c6

I am marking this bug as serious because printing several documents is
not possible anymore and renders this system unusable for this task.

This problem was also mentioned by myself in report #662780 [1] but
seems to be unrelated, since the reporter of #662780 says it is fixed
for him.

Thanks,

Paul

[1] http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=662780#34

-- System Information:
Debian Release: wheezy/sid
  APT prefers unstable
  APT policy: (500, 'unstable'), (500, 'testing'), (500, 'stable'), (1, 'experimental')
Architecture: i386 (i686)

Kernel: Linux 3.5.0-rc7+ (SMP w/2 CPU cores)
Locale: LANG=de_DE.UTF-8, LC_CTYPE=de_DE.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/bash

Versions of packages cups depends on:
ii  adduser                3.113+nmu3
ii  bc                     1.06.95-3
ii  cups-client            1.5.3-2
ii  cups-common            1.5.3-2
ii  cups-filters           1.0.18-2+b1
ii  cups-ppdc              1.5.3-2
ii  debconf [debconf-2.0]  1.5.45
ii  dpkg                   1.16.8
ii  ghostscript            9.05~dfsg-6
ii  libavahi-client3       0.6.31-1
ii  libavahi-common3       0.6.31-1
ii  libc6                  2.13-34
ii  libcups2               1.5.3-2
ii  libcupscgi1            1.5.3-2
ii  libcupsimage2          1.5.3-2
ii  libcupsmime1           1.5.3-2
ii  libcupsppdc1           1.5.3-2
ii  libdbus-1-3            1.6.2-2
ii  libgcc1                1:4.7.1-5
ii  libgnutls26            2.12.20-1
ii  libgssapi-krb5-2       1.10.1+dfsg-1
ii  libkrb5-3              1.10.1+dfsg-1
ii  libldap-2.4-2          2.4.31-1
ii  libpam0g               1.1.3-7.1
ii  libpaper1              1.1.24+nmu2
ii  libslp1                1.2.1-9
ii  libstdc++6             4.7.1-5
ii  libusb-1.0-0           2:1.0.12-2
ii  lsb-base               4.1+Debian7
ii  poppler-utils          0.18.4-3
ii  procps                 1:3.3.3-2
ii  ssl-cert               1.0.31

Versions of packages cups recommends:
ii  avahi-daemon               0.6.31-1
ii  colord                     0.1.21-1
ii  foomatic-filters           4.0.17-1
ii  ghostscript-cups           9.05~dfsg-6
ii  printer-driver-gutenprint  5.2.9-1

Versions of packages cups suggests:
ii  cups-bsd                                   1.5.3-2
ii  cups-pdf                                   2.6.1-6
pn  foomatic-db-compressed-ppds | foomatic-db  <none>
ii  hplip                                      3.12.6-3
ii  printer-driver-hpcups                      3.12.6-3
pn  smbclient                                  <none>
ii  udev                                       175-3.1
-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 198 bytes
Desc: This is a digitally signed message part
URL: <http://lists.alioth.debian.org/pipermail/pkg-cups-devel/attachments/20120722/4b4eb21e/attachment.pgp>