[Pkg-cups-devel] Bug#639156: Cupsd stops running

[Daniel Tryba]

Since upgrading to squeeze (1.4.4-7+squeeze1), cupsd just stop running after 
some random time (from hours to weeks). Client sends a printjob, it will not 
be printed and cups stops:

See attachment, for last printjob. It "finishes" 13:39:58 but is never 
printed. 14:18 cups was restarted manually.

It seems the lpd backend might be suspect, printer model/URI:

MakeModel HP LaserJet 4250 Postscript (recommended)
DeviceURI lpd://192.168.1.5/queue

How to debug this problem

-- 

POCOS B.V. - Croy 9c - 5653 LC Eindhoven
Telefoon: 040 293 8661 - Fax: 040 293 8658
http://www.pocos.nl/   - http://www.sipo.nl/
K.v.K. Eindhoven 17097024
-------------- next part --------------
D [30/Jan/2012:13:39:58 +0100] cupsdAcceptClient: 13 from localhost:631 (IPv6)
D [30/Jan/2012:13:39:58 +0100] cupsdReadClient: 13 POST /printers/ HTTP/1.1
D [30/Jan/2012:13:39:58 +0100] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [30/Jan/2012:13:39:58 +0100] cupsdAuthorize: No authentication data provided.
D [30/Jan/2012:13:39:58 +0100] cupsdReadClient: 13 1.1 Print-Job 1
D [30/Jan/2012:13:39:58 +0100] Print-Job /printers/HP4250Blauw
D [30/Jan/2012:13:39:58 +0100] [Job ???] Auto-typing file...
I [30/Jan/2012:13:39:58 +0100] [Job ???] Request file type is application/pdf.
D [30/Jan/2012:13:39:58 +0100] cupsdMarkDirty(----J-)
D [30/Jan/2012:13:39:58 +0100] add_job: requesting-user-name="user"
D [30/Jan/2012:13:39:58 +0100] Adding default job-sheets values "none,none"...
I [30/Jan/2012:13:39:58 +0100] [Job 62275] Adding start banner page "none".
D [30/Jan/2012:13:39:58 +0100] Discarding unused job-created event...
D [30/Jan/2012:13:39:58 +0100] cupsdMarkDirty(----J-)
I [30/Jan/2012:13:39:58 +0100] [Job 62275] Adding end banner page "none".
I [30/Jan/2012:13:39:58 +0100] [Job 62275] File of type application/pdf queued by "user".
D [30/Jan/2012:13:39:58 +0100] [Job 62275] hold_until=0
I [30/Jan/2012:13:39:58 +0100] [Job 62275] Queued on "HP4250Blauw" by "user".
D [30/Jan/2012:13:39:58 +0100] cupsdMarkDirty(----J-)
D [30/Jan/2012:13:39:58 +0100] Discarding unused printer-state-changed event...
D [30/Jan/2012:13:39:58 +0100] [Job 62275] job-sheets=none,none
D [30/Jan/2012:13:39:58 +0100] [Job 62275] argv[0]="HP4250Blauw"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] argv[1]="62275"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] argv[2]="user"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] argv[3]="factuur 753741-13:39:58-0001"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] argv[4]="1"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] argv[5]="document-name=factuur\ 753741 media=Tray_2 job-uuid=urn:uuid:35ae0066-68f1-3ef4-5e86-654bc1bf1bd9 job-originating-host-name=localhost"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] argv[6]="/var/spool/cups/d62275-001"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[8]="HOME=/var/spool/cups/tmp"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[10]="SERVER_ADMIN=root at fw
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[11]="SOFTWARE=CUPS/1.4.4"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[13]="TZ=Europe/Amsterdam"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[14]="USER=root"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[17]="IPP_PORT=631"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[18]="CHARSET=utf-8"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[19]="LANG=en_US.UTF-8"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[20]="PPD=/etc/cups/ppd/HP4250Blauw.ppd"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[21]="RIP_MAX_CACHE=auto"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[22]="CONTENT_TYPE=application/pdf"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[23]="DEVICE_URI=lpd://192.168.1.5/queue"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[24]="PRINTER_INFO=Tray_2"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[25]="PRINTER_LOCATION="
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[26]="PRINTER=HP4250Blauw"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[27]="CUPS_FILETYPE=document"
D [30/Jan/2012:13:39:58 +0100] [Job 62275] envp[28]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
I [30/Jan/2012:13:39:58 +0100] [Job 62275] Started filter /usr/lib/cups/filter/pdftopdf (PID 14336)
I [30/Jan/2012:13:39:58 +0100] [Job 62275] Started filter /usr/lib/cups/filter/cpdftocps (PID 14337)
I [30/Jan/2012:13:39:58 +0100] [Job 62275] Started backend /usr/lib/cups/backend/lpd (PID 14338)
D [30/Jan/2012:13:39:58 +0100] Discarding unused job-state-changed event...
D [30/Jan/2012:13:39:58 +0100] Returning IPP successful-ok for Print-Job (/printers/HP4250Blauw) from localhost
D [30/Jan/2012:13:39:58 +0100] cupsdReadClient: 13 Closing on EOF
D [30/Jan/2012:13:39:58 +0100] cupsdCloseClient: 13
D [30/Jan/2012:13:39:58 +0100] cupsdSetBusyState: Printing jobs and dirty files
D [30/Jan/2012:13:39:58 +0100] cupsdWriteClient: 1630132800 Closing on unknown HTTP state 0
D [30/Jan/2012:13:39:58 +0100] cupsdCloseClient: 1566344760
I [30/Jan/2012:14:18:45 +0100] Listening to ::1:631 (IPv6)
I [30/Jan/2012:14:18:45 +0100] Listening to 127.0.0.1:631 (IPv4)
I [30/Jan/2012:14:18:45 +0100] Listening to 192.168.1.2:631 (IPv4)
I [30/Jan/2012:14:18:45 +0100] Listening to /var/run/cups/cups.sock (Domain)