Printing issues with HP printers (especially HP LaserJet 1320)

Helge Blischke h.blischke at acm.org
Sat Jun 23 14:15:24 PDT 2012


cfr wrote:

> 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.

I see you are on a fairly recent Linux system and a CUPS configuration that 
implements the PDF centric workflow, and your CUPS is set up to use 
Ghostscript (version 9.05, the most recent) to convert PDF to PostScript 
using Ghostscript's ps2write device.
That device by default makes heavy use of PostScript filters (decompression 
filters and SubfileDecode) which tends to screw up many PS interpreters 
implemented in printers (especially the use of PostScript's SubfileDecode  
filter is picky to my own experience).

As a first possible workaround I'd suggest to
(1) install (or compile from the sources) the pdftops utility either from 
the Xpdf suite (www.foolabs.com/xpdf) or the poppler based one.
(2) recompile CUPS from the sources but do not the default install, but make 
sure that the configure step recognizes the existence of the pdftops 
utility.
(3) replace the installed pdftops filter by your recompiled one.

Be aware that you need to repeat step 3 ech time you install a CUPS update 
from your Linux distributor (steps 1 and 2 are necessary only when updating 
to CUPS 1.6.x somewhere in the future).

Feel free to ask me off the list if you need special advice.

Helge





More information about the cups mailing list