Bug#489045: cups: infinite loop, 100%CPU use, while trying to print with HPJetDirect

[Patricio Rojo-3]

Package: cups
Version: 1.3.7-7
Severity: grave
Justification: renders package unusable

Hi,

   Nothing happens when trying to print, even the test-page, to a HP
4200 network printer through HPDirect.  If I run 'top', I see a process
called 'socket' owned by user 'lp' that is using 100% of computer use.

   I first saw this problem several months ago when I was using 'etch'
on my amd64 machine, but I did not reported a bug because my laptop
(running i386 'sid') could print without problems in the same printer.  
So, I just thought it was an incompatibility of the etch version of cups
with the printer.

   However, I'm now runnning 'lenny' on my amd64 machine (and thus I
have the same version of cups in both computers) and I still have the
same problem.

   Since no one else is reporting this problem, I guess is something
very specific to amd64.

   I could not find errors messages anywhere to help with
the debugging... so please let me know how else can I help.

    Thank you very much,

                      Patricio



-- System Information:
Debian Release: lenny/sid
  APT prefers testing
  APT policy: (500, 'testing'), (500, 'stable'), (50, 'unstable')
Architecture: amd64 (x86_64)

Kernel: Linux 2.6.24-1-amd64 (SMP w/2 CPU cores)
Locale: LANG=en_US, LC_CTYPE=en_US (charmap=ISO-8859-1)
Shell: /bin/sh linked to /bin/bash

Versions of packages cups depends on:
ii  adduser                  3.108           add and remove users and groups
ii  cups-common              1.3.7-7         Common UNIX Printing System(tm) -
ii  debconf [debconf-2.0]    1.5.22          Debian configuration management sy
pi  ghostscript              8.62.dfsg.1-2.1 The GPL Ghostscript PostScript/PDF
ii  libavahi-compat-libdnssd 0.6.22-3        Avahi Apple Bonjour compatibility
ii  libc6                    2.7-10          GNU C Library: Shared libraries
ii  libcups2                 1.3.7-7         Common UNIX Printing System(tm) -
pi  libcupsimage2            1.3.7-7         Common UNIX Printing System(tm) -
ii  libdbus-1-3              1.2.1-2         simple interprocess messaging syst
ii  libgnutls26              2.4.0-2         the GNU TLS library - runtime libr
ii  libkrb53                 1.6.dfsg.3-2    MIT Kerberos runtime libraries
ii  libldap-2.4-2            2.4.9-1         OpenLDAP libraries
ii  libpam0g                 0.99.7.1-6      Pluggable Authentication Modules l
ii  libpaper1                1.1.23          library for handling paper charact
ii  libslp1                  1.2.1-7.3       OpenSLP libraries
ii  lsb-base                 3.2-12          Linux Standard Base 3.2 init scrip
ii  perl-modules             5.10.0-11       Core Perl modules
ii  procps                   1:3.2.7-8       /proc file system utilities
ii  ssl-cert                 1.0.20          simple debconf wrapper for OpenSSL
ii  xpdf-utils [poppler-util 3.02-1.3        Portable Document Format (PDF) sui

Versions of packages cups recommends:
ii  avahi-utils           0.6.22-3           Avahi browsing, publishing and dis
ii  cups-client           1.3.7-7            Common UNIX Printing System(tm) -
ii  foomatic-filters      3.0.2-20080211-3.1 OpenPrinting printer support - fil
ii  smbclient             2:3.0.30-3         a LanManager-like simple client fo

-- debconf information:
  cupsys/raw-print: true
  cupsys/backend: ipp, lpd, parallel, scsi, serial, socket, usb, snmp, dnssd




--
To UNSUBSCRIBE, email to debian-printing-request@...
with a subject of "unsubscribe". Trouble? Contact listmaster@...

[Martin Pitt-3]

severity 489045 normal
tag 489045 moreinfo
thanks

Patricio Rojo [2008-07-02 20:03 -0400]:
>    Nothing happens when trying to print, even the test-page, to a HP
> 4200 network printer through HPDirect.  If I run 'top', I see a process
> called 'socket' owned by user 'lp' that is using 100% of computer use.

Since this does not happen for everyone, just on this particular
model, I'm downgrading the severity.

Can you please attach your /var/log/cups/error_log right after this
happens? Also, by using top, can you find out the process ID of that
runaway socket process, and do


  strace -f -o /tmp/strace.out -p PID

  (set PID to the process ID of the socket process)

for some seconds? Please attach /tmp/strace.out here, too.

Thanks,

Martin

--
Martin Pitt                        | http://www.piware.de
Ubuntu Developer (www.ubuntu.com)  | Debian Developer  (www.debian.org)




--
To UNSUBSCRIBE, email to debian-printing-request@...
with a subject of "unsubscribe". Trouble? Contact listmaster@...

[Debian Bug Tracking System]

Processing commands for control@...:

> severity 489045 normal
Bug#489045: cups: infinite loop, 100%CPU use, while trying to print with HPJetDirect
Severity set to `normal' from `grave'

> tag 489045 moreinfo
Bug#489045: cups: infinite loop, 100%CPU use, while trying to print with HPJetDirect
There were no tags set.
Tags added: moreinfo

> thanks
Stopping processing here.

Please contact me if you need assistance.

Debian bug tracking system administrator
(administrator, Debian Bugs database)



--
To UNSUBSCRIBE, email to debian-printing-request@...
with a subject of "unsubscribe". Trouble? Contact listmaster@...

[Patricio Rojo-3]

Attaching requested info, thanks!

=================
error_log:  (the first two entries appeared when adding the printer
through the web interface, even though the printer appeared to be
successfully added with no error messages in the web interface.  The
last entry appeared only after I 'kill -9' the runaway process)
------------
E [03/Jul/2008:17:35:19 -0400] CUPS-Add-Modify-Printer: Unauthorized
E [03/Jul/2008:17:35:28 -0400] CUPS-Add-Modify-Printer: Unauthorized

E [03/Jul/2008:17:39:09 -0400] PID 19284 (/usr/lib/cups/backend/socket)
crashed on signal 9!


===================
strace.out  (I ran it for 10 seconds and I got a file with more than
800000 lines that seem to repeat the following forever)
-------------------

19284 select(6, [4 5], [5], NULL, NULL) = 1 (in [5])
19284 read(5, "", 1024)                 = 0
19284 select(6, [4 5], [5], NULL, NULL) = 1 (in [5])
19284 read(5, "", 1024)                 = 0
19284 select(6, [4 5], [5], NULL, NULL) = 1 (in [5])
19284 read(5, "", 1024)                 = 0
19284 select(6, [4 5], [5], NULL, NULL) = 1 (in [5])
19284 read(5, "", 1024)                 = 0
19284 select(6, [4 5], [5], NULL, NULL) = 1 (in [5])
19284 read(5, "", 1024)                 = 0
19284 select(6, [4 5], [5], NULL, NULL) = 1 (in [5])
19284 read(5, "", 1024)                 = 0
19284 select(6, [4 5], [5], NULL, NULL) = 1 (in [5])
19284 read(5, "", 1024)                 = 0
19284 select(6, [4 5], [5], NULL, NULL) = 1 (in [5])
19284 read(5, "", 1024)                 = 0
19284 select(6, [4 5], [5], NULL, NULL) = 1 (in [5])
19284 read(5, "", 1024)                 = 0
19284 select(6, [4 5], [5], NULL, NULL) = 1 (in [5])
19284 read(5, "", 1024)                 = 0
19284 select(6, [4 5], [5], NULL, NULL) = 1 (in [5])
19284 read(5, "", 1024)                 = 0
19284 select(6, [4 5], [5], NULL, NULL) = 1 (in [5])
19284 read(5, "", 1024)                 = 0
19284 select(6, [4 5], [5], NULL, NULL) = 1 (in [5])
19284 read(5, "", 1024)                 = 0
19284 select(6, [4 5], [5], NULL, NULL) = 1 (in [5])
19284 read(5, "", 1024)                 = 0
19284 select(6, [4 5], [5], NULL, NULL) = 1 (in [5])
19284 read(5, "", 1024)                 = 0
19284 select(6, [4 5], [5], NULL, NULL) = 1 (in [5])
19284 read(5, "", 1024)                 = 0
19284 select(6, [4 5], [5], NULL, NULL) = 1 (in [5])
19284 read(5, "", 1024)                 = 0






--
To UNSUBSCRIBE, email to debian-printing-request@...
with a subject of "unsubscribe". Trouble? Contact listmaster@...