All print jobs on a HP laserjet P1102w are "stopped"

Edward C. Jones edcjones at comcast.net
Wed May 16 05:08:47 PDT 2012 

I use up-to-date Debian testing (wheezy) with the amd64 architecture.  I
have a HP LaserJet Professional P1102w printer.  I have tried many (probably
too many) things to try to get this printer to work.  When I boot to my
Windows 7 disk, the printer works.

When I submit a print job by "lpr text.txt", it is not printed.  The
screen at "http://localhost:631/jobs/" shows:

ID                                      Name            User            Size
HP_LaserJet_Professional_P1102w-44  	Unknown  	Withheld  	1k

Pages           State
Unknown  	stopped "/usr/lib/cups/filter/texttopdf failed"

What is the problem?

In /etc/cups/cupsd.conf, I set "LogLevel debug".  Here is the relevant part
of the error_log file after submitting a print job:

D [16/May/2012:07:44:06 -0400] cupsdAcceptClient: 16 from localhost (Domain)
D [16/May/2012:07:44:06 -0400] Report: clients=1
D [16/May/2012:07:44:06 -0400] Report: jobs=14
D [16/May/2012:07:44:06 -0400] Report: jobs-active=0
D [16/May/2012:07:44:06 -0400] Report: printers=3
D [16/May/2012:07:44:06 -0400] Report: printers-implicit=0
D [16/May/2012:07:44:06 -0400] Report: stringpool-string-count=5836
D [16/May/2012:07:44:06 -0400] Report: stringpool-alloc-bytes=9632
D [16/May/2012:07:44:06 -0400] Report: stringpool-total-bytes=109320
D [16/May/2012:07:44:06 -0400] cupsdReadClient: 16 POST / HTTP/1.1
D [16/May/2012:07:44:06 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/May/2012:07:44:06 -0400] cupsdAuthorize: No authentication data provided.
D [16/May/2012:07:44:06 -0400] cupsdReadClient: 16 1.1 CUPS-Get-Default 1
D [16/May/2012:07:44:06 -0400] CUPS-Get-Default
D [16/May/2012:07:44:06 -0400] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [16/May/2012:07:44:06 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/May/2012:07:44:06 -0400] cupsdReadClient: 16 POST /printers/HP_LaserJet_Professional_P1102w HTTP/1.1
D [16/May/2012:07:44:06 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/May/2012:07:44:06 -0400] cupsdAuthorize: No authentication data provided.
D [16/May/2012:07:44:06 -0400] cupsdReadClient: 16 1.1 Create-Job 1
D [16/May/2012:07:44:06 -0400] Create-Job ipp://localhost:631/printers/HP_LaserJet_Professional_P1102w
D [16/May/2012:07:44:06 -0400] cupsdMarkDirty(----J-)
D [16/May/2012:07:44:06 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [16/May/2012:07:44:06 -0400] add_job: requesting-user-name="edcjones"
I [16/May/2012:07:44:06 -0400] [Job 47] Adding start banner page "none".
D [16/May/2012:07:44:06 -0400] Discarding unused job-created event...
I [16/May/2012:07:44:06 -0400] [Job 47] Queued on "HP_LaserJet_Professional_P1102w" by "edcjones".
D [16/May/2012:07:44:06 -0400] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/HP_LaserJet_Professional_P1102w) from localhost
D [16/May/2012:07:44:06 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [16/May/2012:07:44:06 -0400] cupsdReadClient: 16 POST /printers/HP_LaserJet_Professional_P1102w HTTP/1.1
D [16/May/2012:07:44:06 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [16/May/2012:07:44:06 -0400] cupsdAuthorize: No authentication data provided.
D [16/May/2012:07:44:06 -0400] cupsdReadClient: 16 1.1 Send-Document 1
D [16/May/2012:07:44:06 -0400] Send-Document ipp://localhost:631/printers/HP_LaserJet_Professional_P1102w
D [16/May/2012:07:44:06 -0400] cupsdIsAuthorized: requesting-user-name="edcjones"
D [16/May/2012:07:44:06 -0400] [Job 47] Auto-typing file...
D [16/May/2012:07:44:06 -0400] [Job 47] Request file type is text/plain.
D [16/May/2012:07:44:06 -0400] cupsdMarkDirty(----J-)
D [16/May/2012:07:44:06 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [16/May/2012:07:44:06 -0400] [Job 47] File of type text/plain queued by "edcjones".
I [16/May/2012:07:44:06 -0400] [Job 47] Adding end banner page "none".
D [16/May/2012:07:44:06 -0400] cupsdMarkDirty(----J-)
D [16/May/2012:07:44:06 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [16/May/2012:07:44:06 -0400] cupsdMarkDirty(----J-)
D [16/May/2012:07:44:06 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [16/May/2012:07:44:06 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [16/May/2012:07:44:06 -0400] Discarding unused printer-state-changed event...
D [16/May/2012:07:44:06 -0400] [Job 47] job-sheets=none,none
D [16/May/2012:07:44:06 -0400] [Job 47] argv[0]="HP_LaserJet_Professional_P1102w"
D [16/May/2012:07:44:06 -0400] [Job 47] argv[1]="47"
D [16/May/2012:07:44:06 -0400] [Job 47] argv[2]="edcjones"
D [16/May/2012:07:44:06 -0400] [Job 47] argv[3]="text.txt"
D [16/May/2012:07:44:06 -0400] [Job 47] argv[4]="1"
D [16/May/2012:07:44:06 -0400] [Job 47] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:550eac6d-be88-322a-6c8f-fcb882ff9713
           job-originating-host-name=localhost time-at-creation=1337168646 time-at-processing=1337168646 AP_D_InputSlot="
D [16/May/2012:07:44:06 -0400] [Job 47] argv[6]="/var/spool/cups/d00047-001"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[8]="HOME=/var/spool/cups/tmp"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[10]="SERVER_ADMIN=root at c-76-111-67-198"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[11]="SOFTWARE=CUPS/1.5.2"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[13]="TZ=America/New_York"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[14]="USER=root"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[17]="IPP_PORT=631"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[18]="CHARSET=utf-8"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[19]="LANG=en_US.UTF-8"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[20]="PPD=/etc/cups/ppd/HP_LaserJet_Professional_P1102w.ppd"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[21]="RIP_MAX_CACHE=128m"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[22]="CONTENT_TYPE=text/plain"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[23]="DEVICE_URI=hp:/usb/HP_LaserJet_Professional_P1102w?serial=000000000Q91M347PR1a"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[24]="PRINTER_INFO=HP LaserJet Professional P1102w"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[25]="PRINTER_LOCATION="
D [16/May/2012:07:44:06 -0400] [Job 47] envp[26]="PRINTER=HP_LaserJet_Professional_P1102w"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[27]="PRINTER_STATE_REASONS=none"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[28]="CUPS_FILETYPE=document"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[29]="FINAL_CONTENT_TYPE=printer/HP_LaserJet_Professional_P1102w"
D [16/May/2012:07:44:06 -0400] [Job 47] envp[30]="AUTH_I****"
I [16/May/2012:07:44:06 -0400] [Job 47] Started filter /usr/lib/cups/filter/texttopdf (PID 13896)
I [16/May/2012:07:44:06 -0400] [Job 47] Started filter /usr/lib/cups/filter/pdftopdf (PID 13897)
I [16/May/2012:07:44:06 -0400] [Job 47] Started filter /usr/lib/cups/filter/foomatic-rip (PID 13898)
I [16/May/2012:07:44:06 -0400] [Job 47] Started backend /usr/lib/cups/backend/hp (PID 13899)
D [16/May/2012:07:44:06 -0400] Discarding unused job-state-changed event...
D [16/May/2012:07:44:06 -0400] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/HP_LaserJet_Professional_P1102w) from localhost
D [16/May/2012:07:44:06 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files"
D [16/May/2012:07:44:06 -0400] [Job 47] Getting input from file
D [16/May/2012:07:44:06 -0400] [Job 47] foomatic-rip version 4.0.15.251 running...
D [16/May/2012:07:44:06 -0400] [Job 47] Parsing PPD file ...
D [16/May/2012:07:44:06 -0400] [Job 47] Added option ColorSpace
D [16/May/2012:07:44:06 -0400] [Job 47] Added option Resolution
D [16/May/2012:07:44:06 -0400] [Job 47] Added option PageSize
D [16/May/2012:07:44:06 -0400] [Job 47] Added option Model
D [16/May/2012:07:44:06 -0400] [Job 47] Added option PrintoutMode
D [16/May/2012:07:44:06 -0400] [Job 47] Added option MediaType
D [16/May/2012:07:44:06 -0400] [Job 47] Added option InputSlot
D [16/May/2012:07:44:06 -0400] [Job 47] Added option Quality
D [16/May/2012:07:44:06 -0400] [Job 47] Added option ImageableArea
D [16/May/2012:07:44:06 -0400] [Job 47] Added option PaperDimension
D [16/May/2012:07:44:06 -0400] [Job 47] Added option Font
D [16/May/2012:07:44:06 -0400] [Job 47]
D [16/May/2012:07:44:06 -0400] [Job 47] Parameter Summary
D [16/May/2012:07:44:06 -0400] [Job 47] -----------------
D [16/May/2012:07:44:06 -0400] [Job 47]
D [16/May/2012:07:44:06 -0400] [Job 47] Spooler: cups
D [16/May/2012:07:44:06 -0400] [Job 47] Printer: HP_LaserJet_Professional_P1102w
D [16/May/2012:07:44:06 -0400] [Job 47] Shell: /bin/bash
D [16/May/2012:07:44:06 -0400] [Job 47] PPD file: /etc/cups/ppd/HP_LaserJet_Professional_P1102w.ppd
D [16/May/2012:07:44:06 -0400] [Job 47] ATTR file:
D [16/May/2012:07:44:06 -0400] [Job 47] Printer model: HP LaserJet Professional p1102w hpijs, 3.12.2, requires proprietary plugin
D [16/May/2012:07:44:06 -0400] [Job 47] Page = 612x792; 18,14 to 594,778
D [16/May/2012:07:44:06 -0400] [Job 47] Job title: text.txt
D [16/May/2012:07:44:06 -0400] [Job 47] File(s) to be printed:
D [16/May/2012:07:44:06 -0400] [Job 47] <STDIN>
D [16/May/2012:07:44:06 -0400] [Job 47]
D [16/May/2012:07:44:06 -0400] [Job 47] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [16/May/2012:07:44:06 -0400] [Job 47] Printing system options:
D [16/May/2012:07:44:06 -0400] [Job 47] Pondering option 'finishings=3'
D [16/May/2012:07:44:06 -0400] [Job 47] Unknown option finishings=3.
D [16/May/2012:07:44:06 -0400] [Job 47] Pondering option 'number-up=1'
D [16/May/2012:07:44:06 -0400] [Job 47] Unknown option number-up=1.
D [16/May/2012:07:44:06 -0400] [Job 47] Pondering option 'job-uuid=urn:uuid:550eac6d-be88-322a-6c8f-fcb882ff9713'
D [16/May/2012:07:44:06 -0400] [Job 47] Unknown option job-uuid=urn:uuid:550eac6d-be88-322a-6c8f-fcb882ff9713.
D [16/May/2012:07:44:06 -0400] [Job 47] Pondering option 'job-originating-host-name=localhost'
D [16/May/2012:07:44:06 -0400] [Job 47] Unknown option job-originating-host-name=localhost.
D [16/May/2012:07:44:06 -0400] [Job 47] Pondering option 'time-at-creation=1337168646'
D [16/May/2012:07:44:06 -0400] [Job 47] Unknown option time-at-creation=1337168646.
D [16/May/2012:07:44:06 -0400] [Job 47] Pondering option 'time-at-processing=1337168646'
D [16/May/2012:07:44:06 -0400] cupsdReadClient: 16 WAITING Closing on EOF
D [16/May/2012:07:44:06 -0400] cupsdCloseClient: 16
D [16/May/2012:07:44:06 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [16/May/2012:07:44:06 -0400] [Job 47] Unknown option time-at-processing=1337168646.
D [16/May/2012:07:44:06 -0400] [Job 47] Pondering option 'AP_D_InputSlot'
D [16/May/2012:07:44:06 -0400] [Job 47] Unknown boolean option "AP_D_InputSlot".
D [16/May/2012:07:44:06 -0400] [Job 47] Options from the PPD file:
D [16/May/2012:07:44:06 -0400] [Job 47]
D [16/May/2012:07:44:06 -0400] [Job 47] ================================================
D [16/May/2012:07:44:06 -0400] [Job 47]
D [16/May/2012:07:44:06 -0400] [Job 47] File: <STDIN>
D [16/May/2012:07:44:06 -0400] [Job 47]
D [16/May/2012:07:44:06 -0400] [Job 47] ================================================
D [16/May/2012:07:44:06 -0400] [Job 47]
D [16/May/2012:07:44:06 -0400] [Job 47] HP_LaserJet_Professional_P1102w: fcmatch.c:850: IA__FcFontSort: Assertion `result != ((void *)0)' failed.
E [16/May/2012:07:44:06 -0400] PID 13896 (/usr/lib/cups/filter/texttopdf) crashed on signal 6.
E [16/May/2012:07:44:06 -0400] [Job 47] PDF file is damaged - attempting to reconstruct xref table...
D [16/May/2012:07:44:06 -0400] Discarding unused job-progress event...
D [16/May/2012:07:44:06 -0400] Discarding unused printer-state-changed event...
E [16/May/2012:07:44:06 -0400] [Job 47] Couldn't find trailer dictionary
E [16/May/2012:07:44:06 -0400] [Job 47] Couldn't read xref table
D [16/May/2012:07:44:06 -0400] Discarding unused job-progress event...
D [16/May/2012:07:44:06 -0400] Discarding unused printer-state-changed event...
D [16/May/2012:07:44:06 -0400] PID 13897 (/usr/lib/cups/filter/pdftopdf) stopped with status 1.
D [16/May/2012:07:44:06 -0400] [Job 47] Cannot process "<STDIN>": Unknown filetype.
D [16/May/2012:07:44:06 -0400] [Job 47] Process is dying with "Could not print file <STDIN>
D [16/May/2012:07:44:06 -0400] [Job 47] ", exit stat 2
D [16/May/2012:07:44:06 -0400] [Job 47] Cleaning up...
D [16/May/2012:07:44:06 -0400] PID 13898 (/usr/lib/cups/filter/foomatic-rip) stopped with status 2.
D [16/May/2012:07:44:06 -0400] [Job 47] prnt/backend/hp.c 839: ERROR: null print job total=0
D [16/May/2012:07:44:06 -0400] PID 13899 (/usr/lib/cups/backend/hp) exited with no errors.
D [16/May/2012:07:44:06 -0400] Discarding unused job-state-changed event...
E [16/May/2012:07:44:06 -0400] [Job 47] Job stopped due to filter errors; please consult the error_log file for details.
D [16/May/2012:07:44:06 -0400] cupsdMarkDirty(----J-)
D [16/May/2012:07:44:06 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [16/May/2012:07:44:06 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [16/May/2012:07:44:06 -0400] Discarding unused printer-state-changed event...
D [16/May/2012:07:44:07 -0400] [Job 47] Unloading...
I [16/May/2012:07:44:37 -0400] Saving job.cache...
D [16/May/2012:07:44:37 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Printing jobs and dirty files"

More information about the cups mailing list