Printing issues with HP printers (especially HP LaserJet 1320)

cfr reescf at gmail.com
Sat Jun 23 08:00:23 PDT 2012 

Forum thread on Arch Linux forums: https://bbs.archlinux.org/viewtopic.php?pid=1118786#p1118786

I've been trying to print to a couple of different HP printers over USB and experiencing difficulties. The one I need to use most often is an HP LaserJet 1320. Recently, updates to cups appear to have made matters worse although I consider it extremely likely that I'm missing something obvious.

I have tried several different printer drivers including a customised plxmono driver recommended on the openprinting site, the hplip hpijs driver, a gutenprint driver and the main hplip postscript driver for the printer. The web interface makes it difficult to pick the right driver because I see many identically named options in the list but I think I've chosen correctly. (For example, there are 5 or 6 identical driver options for the "recommended" hplip postscript driver but hplip also appears to install 5 or 6 identical drivers with different filenames so it doesn't appear to actually matter which you pick.)

The plxmono driver I downloaded from openprinting I could not get to work at all. The hplip postscript printer sometimes works but is very slow for graphics and, since the update, not very quick for text either. (When I say "slow", I mean it might take 40 minutes to print the CUPS test page if it prints it at all.) The hplip hpijs driver works best but the quality is rather poor and the printout misses certain characters (usually dashes of various kinds). The gutenprint driver hasn't worked at all since the recent updates (I'm now using CUPS 1.5.3). It never worked that well - it never wanted to print the last page of anything.

All printers have been deleted and reinstalled through the web interface following the updates to CUPS, hplip etc.

Generally speaking, the computer communicates with the printer - the data light on the printer flashes - but either nothing prints, garbage prints or an incomplete document is printed. Typically, CUPS believes that the print job has completed although sometimes it reports an error. The only way to clear the problem is to power the printer off and on and resend the job or part of the job to the printer.

Here's the output from a recent attempt to print a 24 page document using the hplip postscript driver. The document is a PDF produced from pdflatex. The first 16 pages print fine. Then the printer looks for more data but as far as CUPS is concerned, the job is done with.

Initial part of log (to page 16ish):

D [19/Jun/2012:15:11:22 +0100] PID 23636 (/usr/lib/cups/filter/pstopdf) exited with no errors.
D [19/Jun/2012:15:11:22 +0100] PID 23637 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [19/Jun/2012:15:11:22 +0100] [Job 103] Using image rendering resolution 600 dpi
D [19/Jun/2012:15:11:22 +0100] [Job 103] Started filter gs (PID 23673)
D [19/Jun/2012:15:11:22 +0100] [Job 103] Started filter pstops (PID 23674)
D [19/Jun/2012:15:11:24 +0100] [Job 103] Page = 595x842; 12,12 to 583,829
D [19/Jun/2012:15:11:24 +0100] [Job 103] slow_collate=0, slow_duplex=0, slow_order=0
D [19/Jun/2012:15:11:24 +0100] [Job 103] Before copy_comments - %!PS-Adobe-3.0
D [19/Jun/2012:15:11:24 +0100] [Job 103] %!PS-Adobe-3.0
D [19/Jun/2012:15:11:24 +0100] [Job 103] %%BoundingBox: 0 0 612 792
D [19/Jun/2012:15:11:24 +0100] [Job 103] %%Creator: GPL Ghostscript 905 (ps2write)
D [19/Jun/2012:15:11:24 +0100] [Job 103] %%LanguageLevel: 2
D [19/Jun/2012:15:11:24 +0100] [Job 103] %%CreationDate: D:20120619151122+01'00'
D [19/Jun/2012:15:11:24 +0100] [Job 103] %%Pages: 24
D [19/Jun/2012:15:11:24 +0100] [Job 103] %%EndComments
D [19/Jun/2012:15:11:24 +0100] [Job 103] Before copy_prolog - %%BeginProlog
D [19/Jun/2012:15:11:24 +0100] [Job 103] STATE: +connecting-to-device
D [19/Jun/2012:15:11:24 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:24 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:24 +0100] [Job 103] STATE: -connecting-to-device
D [19/Jun/2012:15:11:24 +0100] [Job 103] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other
D [19/Jun/2012:15:11:24 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
D [19/Jun/2012:15:11:25 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [19/Jun/2012:15:11:25 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [19/Jun/2012:15:11:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:25 +0100] cupsdAuthorize: No authentication data provided.
D [19/Jun/2012:15:11:25 +0100] cupsdReadClient: 16 1.1 Get-Notifications 1
D [19/Jun/2012:15:11:25 +0100] Get-Notifications /
D [19/Jun/2012:15:11:25 +0100] cupsdIsAuthorized: requesting-user-name="<username>"
D [19/Jun/2012:15:11:25 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [19/Jun/2012:15:11:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [19/Jun/2012:15:11:25 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [19/Jun/2012:15:11:25 +0100] cupsdCloseClient: 16
D [19/Jun/2012:15:11:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:25 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [19/Jun/2012:15:11:25 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [19/Jun/2012:15:11:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:25 +0100] cupsdAuthorize: No authentication data provided.
D [19/Jun/2012:15:11:25 +0100] cupsdReadClient: 16 1.1 Get-Notifications 1
D [19/Jun/2012:15:11:25 +0100] Get-Notifications /
D [19/Jun/2012:15:11:25 +0100] cupsdIsAuthorized: requesting-user-name="<username>"
D [19/Jun/2012:15:11:25 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [19/Jun/2012:15:11:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [19/Jun/2012:15:11:25 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [19/Jun/2012:15:11:25 +0100] cupsdCloseClient: 16
D [19/Jun/2012:15:11:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:39 +0100] [Job 103] Before copy_setup - %%Page: 1 1
D [19/Jun/2012:15:11:39 +0100] [Job 103] Before page loop - %%Page: 1 1
D [19/Jun/2012:15:11:39 +0100] [Job 103] Copying page 1...
D [19/Jun/2012:15:11:39 +0100] [Job 103] PAGE: 1 1
D [19/Jun/2012:15:11:39 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:39 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:39 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:11:39 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:11:39 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:11:39 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:11:39 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:11:39 +0100] [Job 103] Copying page 2...
D [19/Jun/2012:15:11:39 +0100] [Job 103] PAGE: 2 1
D [19/Jun/2012:15:11:39 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:39 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:39 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:11:39 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:11:39 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:11:39 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:11:39 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:11:40 +0100] [Job 103] Copying page 3...
D [19/Jun/2012:15:11:40 +0100] [Job 103] PAGE: 3 1
D [19/Jun/2012:15:11:40 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:40 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:40 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:11:40 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:11:40 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:11:40 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:11:40 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:11:40 +0100] [Job 103] Copying page 4...
D [19/Jun/2012:15:11:40 +0100] [Job 103] PAGE: 4 1
D [19/Jun/2012:15:11:40 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:40 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:40 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:11:40 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:11:40 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:11:40 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:11:40 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:11:40 +0100] [Job 103] Copying page 5...
D [19/Jun/2012:15:11:40 +0100] [Job 103] PAGE: 5 1
D [19/Jun/2012:15:11:40 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:40 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:40 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:11:40 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:11:40 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:11:40 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:11:40 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:11:40 +0100] [Job 103] Copying page 6...
D [19/Jun/2012:15:11:40 +0100] [Job 103] PAGE: 6 1
D [19/Jun/2012:15:11:40 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:40 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:40 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:11:40 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:11:40 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:11:40 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:11:40 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:11:40 +0100] [Job 103] Copying page 7...
D [19/Jun/2012:15:11:40 +0100] [Job 103] PAGE: 7 1
D [19/Jun/2012:15:11:40 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:40 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:40 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:11:40 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:11:40 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:11:40 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:11:40 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] Copying page 8...
D [19/Jun/2012:15:11:41 +0100] [Job 103] PAGE: 8 1
D [19/Jun/2012:15:11:41 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:41 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:41 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:11:41 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] Copying page 9...
D [19/Jun/2012:15:11:41 +0100] [Job 103] PAGE: 9 1
D [19/Jun/2012:15:11:41 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:41 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:41 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:11:41 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] Copying page 10...
D [19/Jun/2012:15:11:41 +0100] [Job 103] PAGE: 10 1
D [19/Jun/2012:15:11:41 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:41 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:41 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:11:41 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] Copying page 11...
D [19/Jun/2012:15:11:41 +0100] [Job 103] PAGE: 11 1
D [19/Jun/2012:15:11:41 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:41 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:41 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:11:41 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] Copying page 12...
D [19/Jun/2012:15:11:41 +0100] [Job 103] PAGE: 12 1
D [19/Jun/2012:15:11:41 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:41 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:41 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:11:41 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] Copying page 13...
D [19/Jun/2012:15:11:41 +0100] [Job 103] PAGE: 13 1
D [19/Jun/2012:15:11:41 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:41 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:41 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:11:41 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] Copying page 14...
D [19/Jun/2012:15:11:41 +0100] [Job 103] PAGE: 14 1
D [19/Jun/2012:15:11:41 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:41 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:41 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:11:41 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:11:41 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:11:43 +0100] [Job 103] Copying page 15...
D [19/Jun/2012:15:11:43 +0100] [Job 103] PAGE: 15 1
D [19/Jun/2012:15:11:43 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:43 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:43 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:11:43 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:11:43 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:11:43 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:11:43 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:11:50 +0100] [Job 103] Copying page 16...
D [19/Jun/2012:15:11:50 +0100] [Job 103] PAGE: 16 1
D [19/Jun/2012:15:11:50 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:50 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:50 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:11:50 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:11:50 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:11:50 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:11:50 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0

I'm printing duplex and something seems to go wrong around page 18:

D [19/Jun/2012:15:11:50 +0100] [Job 103] Copying page 17...
D [19/Jun/2012:15:11:50 +0100] [Job 103] PAGE: 17 1
D [19/Jun/2012:15:11:50 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:11:50 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:11:50 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:11:50 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:11:50 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:11:50 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:11:50 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
I [19/Jun/2012:15:11:51 +0100] Saving job.cache...
I [19/Jun/2012:15:11:51 +0100] Saving subscriptions.conf...
D [19/Jun/2012:15:11:51 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:12:04 +0100] [Job 103] Copying page 18...
D [19/Jun/2012:15:12:04 +0100] [Job 103] PAGE: 18 1
D [19/Jun/2012:15:12:04 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:12:04 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs"
D [19/Jun/2012:15:12:04 +0100] Report: clients=1
D [19/Jun/2012:15:12:04 +0100] Report: jobs=32
D [19/Jun/2012:15:12:04 +0100] Report: jobs-active=1
D [19/Jun/2012:15:12:04 +0100] Report: printers=3
D [19/Jun/2012:15:12:04 +0100] Report: printers-implicit=0
D [19/Jun/2012:15:12:04 +0100] Report: stringpool-string-count=6815
D [19/Jun/2012:15:12:04 +0100] Report: stringpool-alloc-bytes=17088
D [19/Jun/2012:15:12:04 +0100] Report: stringpool-total-bytes=132368
D [19/Jun/2012:15:12:04 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:12:04 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:12:04 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:12:04 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:12:04 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:12:04 +0100] [Job 103] Copying page 19...
D [19/Jun/2012:15:12:04 +0100] [Job 103] PAGE: 19 1
D [19/Jun/2012:15:12:04 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:12:04 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:12:04 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:12:04 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:12:04 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:12:04 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:12:04 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:12:04 +0100] [Job 103] PID 23673 (gs) exited with no errors.
D [19/Jun/2012:15:12:24 +0100] [Job 103] Copying page 20...
D [19/Jun/2012:15:12:24 +0100] [Job 103] PAGE: 20 1
D [19/Jun/2012:15:12:24 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:12:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:12:24 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:12:24 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:12:24 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:12:24 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:12:24 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
I [19/Jun/2012:15:12:35 +0100] Saving subscriptions.conf...
D [19/Jun/2012:15:12:35 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:12:43 +0100] [Job 103] Copying page 21...
D [19/Jun/2012:15:12:43 +0100] [Job 103] PAGE: 21 1
D [19/Jun/2012:15:12:43 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:12:43 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs"
D [19/Jun/2012:15:12:43 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:12:43 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:12:43 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:12:43 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:12:43 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:12:43 +0100] [Job 103] Copying page 22...
D [19/Jun/2012:15:12:43 +0100] [Job 103] PAGE: 22 1
D [19/Jun/2012:15:12:43 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:12:43 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:12:43 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:12:43 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:12:43 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:12:43 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:12:43 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:13:03 +0100] [Job 103] Copying page 23...
D [19/Jun/2012:15:13:03 +0100] [Job 103] PAGE: 23 1
D [19/Jun/2012:15:13:03 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:13:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:13:03 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:13:03 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:13:03 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:13:03 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:13:03 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:13:04 +0100] Report: clients=1
D [19/Jun/2012:15:13:04 +0100] Report: jobs=32
D [19/Jun/2012:15:13:04 +0100] Report: jobs-active=1
D [19/Jun/2012:15:13:04 +0100] Report: printers=3
D [19/Jun/2012:15:13:04 +0100] Report: printers-implicit=0
D [19/Jun/2012:15:13:04 +0100] Report: stringpool-string-count=6945
D [19/Jun/2012:15:13:04 +0100] Report: stringpool-alloc-bytes=17248
D [19/Jun/2012:15:13:04 +0100] Report: stringpool-total-bytes=134968
D [19/Jun/2012:15:13:10 +0100] [Job 103] Copying page 24...
D [19/Jun/2012:15:13:10 +0100] [Job 103] PAGE: 24 1
D [19/Jun/2012:15:13:10 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:13:10 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:13:10 +0100] [Job 103] pagew = 571.0, pagel = 817.4
D [19/Jun/2012:15:13:10 +0100] [Job 103] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [19/Jun/2012:15:13:10 +0100] [Job 103] PageLeft = 12.0, PageRight = 583.0
D [19/Jun/2012:15:13:10 +0100] [Job 103] PageTop = 829.4, PageBottom = 12.0
D [19/Jun/2012:15:13:10 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:13:10 +0100] [Job 103] Wrote 24 pages...
D [19/Jun/2012:15:13:10 +0100] [Job 103] PID 23674 (pstops) exited with no errors.
D [19/Jun/2012:15:13:10 +0100] PID 23638 (/usr/lib/cups/filter/pdftops) exited with no errors.
I [19/Jun/2012:15:13:14 +0100] Saving subscriptions.conf...
D [19/Jun/2012:15:13:14 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:14:14 +0100] PID 23639 (/usr/lib/cups/monitor/tbcp) exited with no errors.
I [19/Jun/2012:15:14:22 +0100] [Job 103] ready to print
D [19/Jun/2012:15:14:22 +0100] [Job 103] Set job-printer-state-message to "ready to print", current level=INFO
D [19/Jun/2012:15:14:22 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:14:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs"
D [19/Jun/2012:15:14:22 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:14:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:14:22 +0100] Report: clients=1
D [19/Jun/2012:15:14:22 +0100] Report: jobs=32
D [19/Jun/2012:15:14:22 +0100] Report: jobs-active=1
D [19/Jun/2012:15:14:22 +0100] Report: printers=3
D [19/Jun/2012:15:14:22 +0100] Report: printers-implicit=0
D [19/Jun/2012:15:14:22 +0100] Report: stringpool-string-count=7016
D [19/Jun/2012:15:13:10 +0100] [Job 103] PageWidth = 595.0, PageLength = 842.0
D [19/Jun/2012:15:13:10 +0100] [Job 103] Wrote 24 pages...
D [19/Jun/2012:15:13:10 +0100] [Job 103] PID 23674 (pstops) exited with no errors.
D [19/Jun/2012:15:13:10 +0100] PID 23638 (/usr/lib/cups/filter/pdftops) exited with no errors.
I [19/Jun/2012:15:13:14 +0100] Saving subscriptions.conf...
D [19/Jun/2012:15:13:14 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files"
D [19/Jun/2012:15:14:14 +0100] PID 23639 (/usr/lib/cups/monitor/tbcp) exited with no errors.
I [19/Jun/2012:15:14:22 +0100] [Job 103] ready to print
D [19/Jun/2012:15:14:22 +0100] [Job 103] Set job-printer-state-message to "ready to print", current level=INFO
D [19/Jun/2012:15:14:22 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:14:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs"
D [19/Jun/2012:15:14:22 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:14:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty
 files"
D [19/Jun/2012:15:14:22 +0100] Report: clients=1
D [19/Jun/2012:15:14:22 +0100] Report: jobs=32
D [19/Jun/2012:15:14:22 +0100] Report: jobs-active=1
D [19/Jun/2012:15:14:22 +0100] Report: printers=3
D [19/Jun/2012:15:14:22 +0100] Report: printers-implicit=0
D [19/Jun/2012:15:14:22 +0100] Report: stringpool-string-count=7016
D [19/Jun/2012:15:14:22 +0100] Report: stringpool-alloc-bytes=17280
D [19/Jun/2012:15:14:22 +0100] Report: stringpool-total-bytes=136392
D [19/Jun/2012:15:14:22 +0100] PID 23640 (/usr/lib/cups/backend/hp) exited with no errors.
D [19/Jun/2012:15:14:22 +0100] cupsdMarkDirty(-----S)
D [19/Jun/2012:15:14:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
I [19/Jun/2012:15:14:22 +0100] [Job 103] Job completed.

Instead of a page 17, I get an error printed on the next page and then nothing - the printer waits forever for the next bit. (At least, that's what it looks like - perhaps the flashing light means something else but usually green flashing means processing/receiving data as far as I can tell.) Here's the error:

ERROR:
undefined
OFFENDING COMMAND:
m
STACK:
--nostringval--
2082

I've generated a lot of output and I've tried a lot of different things. I'm happy to post whatever information might be useful as I really need to get this working.

Pulling default configs from the printer doesn't work either:

D [18/Jun/2012:18:23:51 +0100] [Job 96] Unable to auto-configure PostScript Printer - no bidirectional I/O available!
D [18/Jun/2012:18:23:51 +0100] PID 599 (/usr/lib/cups/filter/commandtops) stopped with status 1.
D [18/Jun/2012:18:23:51 +0100] [Job 96] prnt/backend/hp.c 839: ERROR: null print job total=0

Any suggestions for getting all characters to print with the hpijs driver would also be very welcome. Although the quality is poorer, it is OK for some purposes but the missing dashes etc. is not good. Again, this is pdf from pdflatex. All fonts are embedded and the characters appear correctly in the pdfs.

More information about the cups mailing list