HP M402dn printer. Mageia 7 with cups-2.2.11
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to
print the file which is completely rediculous.
Looking in /var/log/cups there are a huge number of lines
D [23/Aug/2019:21:54:55 -0700] [Job 212] Wrote 8192 bytes of print data...
D [23/Aug/2019:21:54:55 -0700] [Job 212] Read 8192 bytes of print data...
but the time between one and next is about 1 second which is rediculous
Here are the lines from the error.log (without the numerous lines above)
It really seems that gs is broken.
I have placed blank lines in the places where the above sets of lines occured.
D [23/Aug/2019:21:34:39 -0700] [Job 212] Applying default options.......
D [23/Aug/2019:21:34:40 -0700] [Job 212] Running command line for pstops:pstops 212 unruh schuld-lease.pdf 1 \'finishings=3 job-uuid=urn:uuid:e2b98acc-67d9-303d-702e-612bf0217336 print-quality=3 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1566621279 time-at-processing=1566621279 document-name-supplied=schuld-lease.pdf cupsPrintQuality=Draft\'
D [23/Aug/2019:21:34:40 -0700] [Job 212] Using image rendering resolution1200 dpi
D [23/Aug/2019:21:34:40 -0700] [Job 212] Running command line for gs: gs -q-dNOPAUSE -dBATCH -dSAFER -dNOMEDIAATTRS -sDEVICE=ps2write -dShowAcroForm -sOUTPUTFILE=%stdout -dLanguageLevel=3 -r1200 -dCompressFonts=false -dNoT3CCITT -dNOINTERPOLATE -c \'save pop\' -f /var/spool/cups/tmp/01db35d63d5f0
D [23/Aug/2019:21:34:40 -0700] [Job 212] Started filter gs (PID 7615)
D [23/Aug/2019:21:34:40 -0700] [Job 212] Started filter pstops (PID 7616)
D [23/Aug/2019:21:34:41 -0700] [Job 212] Page = 612x792; 12,12 to 600,780slow_order=0
D [23/Aug/2019:21:34:41 -0700] [Job 212] slow_collate=0, slow_duplex=0,
D [23/Aug/2019:21:34:41 -0700] [Job 212] Before copy_comments -%!PS-Adobe-3.0
D [23/Aug/2019:21:34:41 -0700] [Job 212] %!PS-Adobe-3.0792.00
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%BoundingBox: 0 0 612 792
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%HiResBoundingBox: 0 0 612.00
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%Creator: GPL Ghostscript 927(ps2write)
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%LanguageLevel: 2D:20190823213440-07\'00\'
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%CreationDate:
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%Pages: 5bboxl = 792
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%EndComments
D [23/Aug/2019:21:34:41 -0700] [Job 212] Before copy_prolog - %%BeginProlog
D [23/Aug/2019:21:34:43 -0700] [Job 212] Before copy_setup - %%Page: 1 1
D [23/Aug/2019:21:34:43 -0700] [Job 212] Before page loop - %%Page: 1 1
D [23/Aug/2019:21:34:43 -0700] [Job 212] Copying page 1...
D [23/Aug/2019:21:34:43 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:34:43 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612,
D [23/Aug/2019:21:34:43 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0792.0
D [23/Aug/2019:21:34:43 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0
D [23/Aug/2019:21:34:43 -0700] [Job 212] PageWidth = 612.0, PageLength =
D [23/Aug/2019:21:38:55 -0700] [Job 212] Copying page 2...bboxl = 792
D [23/Aug/2019:21:38:55 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:38:55 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612,
D [23/Aug/2019:21:38:55 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0792.0
D [23/Aug/2019:21:38:55 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0
D [23/Aug/2019:21:38:55 -0700] [Job 212] PageWidth = 612.0, PageLength =
D [23/Aug/2019:21:43:59 -0700] [Job 212] Copying page 3...bboxl = 792
D [23/Aug/2019:21:43:59 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:43:59 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612,
D [23/Aug/2019:21:43:59 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0792.0
D [23/Aug/2019:21:43:59 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0
D [23/Aug/2019:21:43:59 -0700] [Job 212] PageWidth = 612.0, PageLength =
D [23/Aug/2019:21:48:29 -0700] [Job 212] Copying page 4...bboxl = 792
D [23/Aug/2019:21:48:29 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:48:29 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612,
D [23/Aug/2019:21:48:29 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0792.0
D [23/Aug/2019:21:48:29 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0
D [23/Aug/2019:21:48:29 -0700] [Job 212] PageWidth = 612.0, PageLength =
D [23/Aug/2019:21:53:30 -0700] [Job 212] Copying page 5...bboxl = 792
D [23/Aug/2019:21:53:30 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:53:30 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612,
D [23/Aug/2019:21:53:30 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0792.0
D [23/Aug/2019:21:53:30 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0
D [23/Aug/2019:21:53:30 -0700] [Job 212] PageWidth = 612.0, PageLength =
D [23/Aug/2019:21:54:43 -0700] [Job 212] PID 7615 (gs) exited with noerrors.
D [23/Aug/2019:21:54:49 -0700] [Job 212] Wrote 5 pages...errors.
D [23/Aug/2019:21:54:49 -0700] [Job 212] PID 7616 (pstops) exited with no
D [23/Aug/2019:21:54:49 -0700] [Job 212] PID 7603(/usr/lib/cups/filter/pdftops) exited with no errors.
D [23/Aug/2019:21:54:54 -0700] [Job 212] HP PS filter ENDS
D [23/Aug/2019:21:54:54 -0700] [Job 212] PID 7604(/usr/lib/cups/filter/hpps) exited with no errors.
D [23/Aug/2019:21:54:59 -0700] [Job 212] Sent 14820622 bytes...(/usr/lib/cups/backend/usb) exited with no errors.
D [23/Aug/2019:21:54:59 -0700] [Job 212] Waiting for read thread to exit...
D [23/Aug/2019:21:54:59 -0700] [Job 212] PID 7605
D [23/Aug/2019:21:54:59 -0700] [Job 212] time-at-completed=1566622499
I [23/Aug/2019:21:54:59 -0700] [Job 212] Job completed.
D [23/Aug/2019:21:55:00 -0700] [Job 212] Unloading...
HP M402dn printer. Mageia 7 with cups-2.2.11
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to
print the file which is completely rediculous.
Looking in /var/log/cups there are a huge number of lines
D [23/Aug/2019:21:54:55 -0700] [Job 212] Wrote 8192 bytes of print data...
D [23/Aug/2019:21:54:55 -0700] [Job 212] Read 8192 bytes of print data...
but the time between one and next is about 1 second which is rediculous
Here are the lines from the error.log (without the numerous lines above)
It really seems that gs is broken.
I have placed blank lines in the places where the above sets of lines occured.
William Unruh wrote:
HP M402dn printer. Mageia 7 with cups-2.2.11
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to
print the file which is completely rediculous. Looking in
/var/log/cups there are a huge number of lines
D [23/Aug/2019:21:54:55 -0700] [Job 212] Wrote 8192 bytes of print
data...
D [23/Aug/2019:21:54:55 -0700] [Job 212] Read 8192 bytes of print data...
but the time between one and next is about 1 second which is rediculous
Here are the lines from the error.log (without the numerous lines above)
It really seems that gs is broken.
I have placed blank lines in the places where the above sets of lines
occured.
It says here, there is a possibility a printer sends back way too much "back-channel data" during printing.
On 24/08/2019 07.40, William Unruh wrote:
HP M402dn printer. Mageia 7 with cups-2.2.11
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to
print the file which is completely rediculous.
What command are you using to print it?
On Sat, 24 Aug 2019 08:13:13 +0200, "Carlos E.R."
<robin_listas@es.invalid> wrote:
On 24/08/2019 07.40, William Unruh wrote:
HP M402dn printer. Mageia 7 with cups-2.2.11
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to
print the file which is completely rediculous.
If it's an inkjet printer
set to fine it could be normal.
My laser printer would print 5 pages in about 2 seconds.
What command are you using to print it?
Probably pring file.pdf.
HP M402dn printer. Mageia 7 with cups-2.2.11
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to
print the file which is completely rediculous.
Looking in /var/log/cups there are a huge number of lines
D [23/Aug/2019:21:54:55 -0700] [Job 212] Wrote 8192 bytes of print data...
D [23/Aug/2019:21:54:55 -0700] [Job 212] Read 8192 bytes of print data...
but the time between one and next is about 1 second which is rediculous
Here are the lines from the error.log (without the numerous lines above)
It really seems that gs is broken.
I have placed blank lines in the places where the above sets of lines occured.
D [23/Aug/2019:21:34:39 -0700] [Job 212] Applying default options...application/pdf.
D [23/Aug/2019:21:34:39 -0700] [Job 212] Adding default print-quality=3
I [23/Aug/2019:21:34:39 -0700] [Job 212] Adding start banner page "none".
I [23/Aug/2019:21:34:39 -0700] [Job 212] Queued on "hpd" by "unruh".
D [23/Aug/2019:21:34:39 -0700] [Job 212] Auto-typing file...
D [23/Aug/2019:21:34:39 -0700] [Job 212] Request file type is
I [23/Aug/2019:21:34:39 -0700] [Job 212] File of type application/pdf queuedby "unruh".
I [23/Aug/2019:21:34:39 -0700] [Job 212] Adding end banner page "none".application/vnd.cups-pdf, cost 66)
D [23/Aug/2019:21:34:39 -0700] [Job 212] time-at-processing=1566621279
D [23/Aug/2019:21:34:39 -0700] [Job 212] 3 filters for job:
D [23/Aug/2019:21:34:39 -0700] [Job 212] pdftopdf (application/pdf to
D [23/Aug/2019:21:34:39 -0700] [Job 212] pdftops (application/vnd.cups-pdfto application/vnd.cups-postscript, cost 100)
D [23/Aug/2019:21:34:39 -0700] [Job 212] hpps(application/vnd.cups-postscript to printer/hpd, cost 0)
D [23/Aug/2019:21:34:39 -0700] [Job 212] job-sheets=none,nonenumber-up=1 job-uuid=urn:uuid:e2b98acc-67d9-303d-702e-612bf0217336 print-quality=3 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1566621279 time-at-processing=1566621279 document-name-supplied=schuld-lease.pdf cupsPrintQuality=Draft"
D [23/Aug/2019:21:34:39 -0700] [Job 212] argv[0]="hpd"
D [23/Aug/2019:21:34:39 -0700] [Job 212] argv[1]="212"
D [23/Aug/2019:21:34:39 -0700] [Job 212] argv[2]="unruh"
D [23/Aug/2019:21:34:39 -0700] [Job 212] argv[3]="schuld-lease.pdf"
D [23/Aug/2019:21:34:39 -0700] [Job 212] argv[4]="1"
D [23/Aug/2019:21:34:39 -0700] [Job 212] argv[5]="finishings=3 fitplot
D [23/Aug/2019:21:34:39 -0700] [Job 212]argv[6]="/var/spool/cups/d00212-001"
D [23/Aug/2019:21:34:39 -0700] [Job 212]envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [23/Aug/2019:21:34:39 -0700] [Job 212]envp[1]="CUPS_DATADIR=/usr/share/cups"
D [23/Aug/2019:21:34:39 -0700] [Job 212]envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
D [23/Aug/2019:21:34:39 -0700] [Job 212]envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [23/Aug/2019:21:34:39 -0700] [Job 212]envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [23/Aug/2019:21:34:39 -0700] [Job 212]envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[6]="CUPS_SERVERROOT=/etc/cups" D [23/Aug/2019:21:34:39 -0700] [Job 212]envp[7]="CUPS_STATEDIR=/var/run/cups"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[8]="HOME=/var/spool/cups/tmp"envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [23/Aug/2019:21:34:39 -0700] [Job 212]
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[10]="SERVER_ADMIN=root@tunnel" D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[11]="SOFTWARE=CUPS/2.2.11"envp[12]="TMPDIR=/var/spool/cups/tmp"
D [23/Aug/2019:21:34:39 -0700] [Job 212]
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[13]="USER=root"envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[14]="CUPS_MAX_MESSAGE=2047"
D [23/Aug/2019:21:34:39 -0700] [Job 212]
D [23/Aug/2019:21:34:39 -0700] [Job 212]envp[16]="CUPS_ENCRYPTION=IfRequested"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[17]="IPP_PORT=631"envp[20]="PPD=/etc/cups/ppd/hpd.ppd"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[18]="CHARSET=utf-8"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[19]="LANG=en_CA.UTF-8"
D [23/Aug/2019:21:34:39 -0700] [Job 212]
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[21]="RIP_MAX_CACHE=128m"envp[22]="CONTENT_TYPE=application/pdf"
D [23/Aug/2019:21:34:39 -0700] [Job 212]
D [23/Aug/2019:21:34:39 -0700] [Job 212]envp[23]="DEVICE_URI=usb://HP/LaserJet%20M402dw?serial=PHBV703311"
D [23/Aug/2019:21:34:39 -0700] [Job 212]envp[24]="PRINTER_INFO=Hewlett-Packard HP LaserJet M402dw duplex"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[25]="PRINTER_LOCATION=tunnel"envp[27]="PRINTER_STATE_REASONS=none"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[26]="PRINTER=hpd"
D [23/Aug/2019:21:34:39 -0700] [Job 212]
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[28]="CUPS_FILETYPE=document"envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
D [23/Aug/2019:21:34:39 -0700] [Job 212]
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[30]="AUTH_I****"/usr/lib/cups/filter/pdftopdf (PID 7602)
I [23/Aug/2019:21:34:39 -0700] [Job 212] Started filter
I [23/Aug/2019:21:34:39 -0700] [Job 212] Started filter/usr/lib/cups/filter/pdftops (PID 7603)
I [23/Aug/2019:21:34:39 -0700] [Job 212] Started filter/usr/lib/cups/filter/hpps (PID 7604)
I [23/Aug/2019:21:34:39 -0700] [Job 212] Started backend/usr/lib/cups/backend/usb (PID 7605)
D [23/Aug/2019:21:34:39 -0700] [Job 212] HP PS filter starting : hpdschuld-lease.pdf
D [23/Aug/2019:21:34:39 -0700] [Job 212] hppsfilter: argv[0] = hpd
D [23/Aug/2019:21:34:39 -0700] [Job 212] hppsfilter: argv[1] = 212
D [23/Aug/2019:21:34:39 -0700] [Job 212] hppsfilter: argv[2] = unruh
D [23/Aug/2019:21:34:39 -0700] [Job 212] hppsfilter: argv[3] =
D [23/Aug/2019:21:34:39 -0700] [Job 212] hppsfilter: argv[4] = 1fitplot number-up=1 job-uuid=urn:uuid:e2b98acc-67d9-303d-702e-612bf0217336 print-quality=3 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1566621279 time-at-processing=1566621279 document-name-supplied=schuld-lease.pdf cupsPrintQuality=Draft
D [23/Aug/2019:21:34:39 -0700] [Job 212] hppsfilter: argv[5] = finishings=3
D [23/Aug/2019:21:34:39 -0700] [Job 212] HP PS filter func = WriteHeader: WRITING PJL HEADER INFO
D [23/Aug/2019:21:34:39 -0700] [Job 212] HP PS filter func = GetPPDValues :PPD NAME /etc/cups/ppd/hpd.ppd
D [23/Aug/2019:21:34:39 -0700] [Job 212] HP PS filter func =WriteJobAccounting : WRITING JOB ACCOUNTING INFO
D [23/Aug/2019:21:34:39 -0700] [Job 212] HP PS filter func = WriteBornOnDate: WRITING BORN-ON-DATE
D [23/Aug/2019:21:34:39 -0700] [Job 212] HP PS filter func = WriteECONOMODE: WRITNING ECONOMODE
D [23/Aug/2019:21:34:39 -0700] [Job 212] HP PS filter func = WriteECONOMODE2: WRITING ECONOMODE
D [23/Aug/2019:21:34:39 -0700] [Job 212] Loading USB quirks from\"/usr/share/cups/usb\".
D [23/Aug/2019:21:34:39 -0700] [Job 212] Loaded 167 quirks.usb://HP/LaserJet%20M402dw?serial=PHBV703311
D [23/Aug/2019:21:34:39 -0700] [Job 212] Printing on printer with URI:
D [23/Aug/2019:21:34:39 -0700] [Job 212] pdftops - copying to temp printfile \"/var/spool/cups/tmp/01db35d63d5f0\"
D [23/Aug/2019:21:34:39 -0700] [Job 212] libusb_get_device_list=4"Sending data to printer.", current level=INFO
D [23/Aug/2019:21:34:39 -0700] [Job 212] STATE: +connecting-to-device
D [23/Aug/2019:21:34:39 -0700] [Job 212] STATE: -connecting-to-device
D [23/Aug/2019:21:34:39 -0700] [Job 212] Device protocol: 2
D [23/Aug/2019:21:34:39 -0700] [Job 212] Sending data to printer.
D [23/Aug/2019:21:34:39 -0700] [Job 212] Set job-printer-state-message to
D [23/Aug/2019:21:34:39 -0700] [Job 212] pdftopdf: Last filter determined bythe PPD: hpps; FINAL_CONTENT_TYPE: application/vnd.cups-postscript => pdftopdf will log pages in page_log.
D [23/Aug/2019:21:34:39 -0700] [Job 212] Read 1 bytes of back-channeldata...
D [23/Aug/2019:21:34:39 -0700] [Job 212] PDF interactive form and annotationflattening done via QPDF
D [23/Aug/2019:21:34:39 -0700] [Job 212] PAGE: 1 1data...
D [23/Aug/2019:21:34:39 -0700] [Job 212] PAGE: 2 1
D [23/Aug/2019:21:34:39 -0700] [Job 212] PAGE: 3 1
D [23/Aug/2019:21:34:39 -0700] [Job 212] PAGE: 4 1
D [23/Aug/2019:21:34:39 -0700] [Job 212] PAGE: 5 1
D [23/Aug/2019:21:34:39 -0700] [Job 212] Read 106 bytes of back-channel
D [23/Aug/2019:21:34:40 -0700] [Job 212] PID 7602(/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [23/Aug/2019:21:34:40 -0700] [Job 212] Printer make and model: HP HPLaserJet M403dn
D [23/Aug/2019:21:34:40 -0700] [Job 212] Running command line for pstops:pstops 212 unruh schuld-lease.pdf 1 \'finishings=3 job-uuid=urn:uuid:e2b98acc-67d9-303d-702e-612bf0217336 print-quality=3 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1566621279 time-at-processing=1566621279 document-name-supplied=schuld-lease.pdf cupsPrintQuality=Draft\'
D [23/Aug/2019:21:34:40 -0700] [Job 212] Using image rendering resolution1200 dpi
D [23/Aug/2019:21:34:40 -0700] [Job 212] Running command line for gs: gs -q-dNOPAUSE -dBATCH -dSAFER -dNOMEDIAATTRS -sDEVICE=ps2write -dShowAcroForm -sOUTPUTFILE=%stdout -dLanguageLevel=3 -r1200 -dCompressFonts=false -dNoT3CCITT -dNOINTERPOLATE -c \'save pop\' -f /var/spool/cups/tmp/01db35d63d5f0
D [23/Aug/2019:21:34:40 -0700] [Job 212] Started filter gs (PID 7615)slow_order=0
D [23/Aug/2019:21:34:40 -0700] [Job 212] Started filter pstops (PID 7616)
D [23/Aug/2019:21:34:41 -0700] [Job 212] Page = 612x792; 12,12 to 600,780
D [23/Aug/2019:21:34:41 -0700] [Job 212] slow_collate=0, slow_duplex=0,
D [23/Aug/2019:21:34:41 -0700] [Job 212] Before copy_comments -%!PS-Adobe-3.0
D [23/Aug/2019:21:34:41 -0700] [Job 212] %!PS-Adobe-3.0792.00
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%BoundingBox: 0 0 612 792
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%HiResBoundingBox: 0 0 612.00
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%Creator: GPL Ghostscript 927(ps2write)
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%LanguageLevel: 2D:20190823213440-07\'00\'
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%CreationDate:
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%Pages: 5bboxl = 792
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%EndComments
D [23/Aug/2019:21:34:41 -0700] [Job 212] Before copy_prolog - %%BeginProlog
D [23/Aug/2019:21:34:43 -0700] [Job 212] Before copy_setup - %%Page: 1 1
D [23/Aug/2019:21:34:43 -0700] [Job 212] Before page loop - %%Page: 1 1
D [23/Aug/2019:21:34:43 -0700] [Job 212] Copying page 1...
D [23/Aug/2019:21:34:43 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:34:43 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612,
D [23/Aug/2019:21:34:43 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0792.0
D [23/Aug/2019:21:34:43 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0
D [23/Aug/2019:21:34:43 -0700] [Job 212] PageWidth = 612.0, PageLength =
D [23/Aug/2019:21:38:55 -0700] [Job 212] Copying page 2...bboxl = 792
D [23/Aug/2019:21:38:55 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:38:55 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612,
D [23/Aug/2019:21:38:55 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0792.0
D [23/Aug/2019:21:38:55 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0
D [23/Aug/2019:21:38:55 -0700] [Job 212] PageWidth = 612.0, PageLength =
D [23/Aug/2019:21:43:59 -0700] [Job 212] Copying page 3...bboxl = 792
D [23/Aug/2019:21:43:59 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:43:59 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612,
D [23/Aug/2019:21:43:59 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0792.0
D [23/Aug/2019:21:43:59 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0
D [23/Aug/2019:21:43:59 -0700] [Job 212] PageWidth = 612.0, PageLength =
D [23/Aug/2019:21:48:29 -0700] [Job 212] Copying page 4...bboxl = 792
D [23/Aug/2019:21:48:29 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:48:29 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612,
D [23/Aug/2019:21:48:29 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0792.0
D [23/Aug/2019:21:48:29 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0
D [23/Aug/2019:21:48:29 -0700] [Job 212] PageWidth = 612.0, PageLength =
D [23/Aug/2019:21:53:30 -0700] [Job 212] Copying page 5...bboxl = 792
D [23/Aug/2019:21:53:30 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:53:30 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612,
D [23/Aug/2019:21:53:30 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0792.0
D [23/Aug/2019:21:53:30 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0
D [23/Aug/2019:21:53:30 -0700] [Job 212] PageWidth = 612.0, PageLength =
D [23/Aug/2019:21:54:43 -0700] [Job 212] PID 7615 (gs) exited with noerrors.
D [23/Aug/2019:21:54:49 -0700] [Job 212] Wrote 5 pages...errors.
D [23/Aug/2019:21:54:49 -0700] [Job 212] PID 7616 (pstops) exited with no
D [23/Aug/2019:21:54:49 -0700] [Job 212] PID 7603(/usr/lib/cups/filter/pdftops) exited with no errors.
D [23/Aug/2019:21:54:54 -0700] [Job 212] HP PS filter ENDS(/usr/lib/cups/filter/hpps) exited with no errors.
D [23/Aug/2019:21:54:54 -0700] [Job 212] PID 7604
D [23/Aug/2019:21:54:59 -0700] [Job 212] Sent 14820622 bytes...(/usr/lib/cups/backend/usb) exited with no errors.
D [23/Aug/2019:21:54:59 -0700] [Job 212] Waiting for read thread to exit...
D [23/Aug/2019:21:54:59 -0700] [Job 212] PID 7605
D [23/Aug/2019:21:54:59 -0700] [Job 212] time-at-completed=1566622499
I [23/Aug/2019:21:54:59 -0700] [Job 212] Job completed.
D [23/Aug/2019:21:55:00 -0700] [Job 212] Unloading...
On 24/08/2019 07.40, William Unruh wrote:
HP M402dn printer. Mageia 7 with cups-2.2.11
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to
print the file which is completely rediculous.
What command are you using to print it?
Looking in /var/log/cups there are a huge number of lines
D [23/Aug/2019:21:54:55 -0700] [Job 212] Wrote 8192 bytes of print data... >> D [23/Aug/2019:21:54:55 -0700] [Job 212] Read 8192 bytes of print data...
but the time between one and next is about 1 second which is rediculous
Here are the lines from the error.log (without the numerous lines above)
It really seems that gs is broken.
I have placed blank lines in the places where the above sets of lines
occured.
Look at the time stamps.
...
D [23/Aug/2019:21:34:39 -0700] [Job 212] Applying default options...
D [23/Aug/2019:21:34:40 -0700] [Job 212] Running command line for pstops: pstops 212 unruh schuld-lease.pdf 1 \'finishings=3 job-uuid=urn:uuid:e2b98acc-67d9-303d-702e-612bf0217336 print-quality=3 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1566621279 time-at-processing=1566621279 document-name-supplied=schuld-lease.pdf cupsPrintQuality=Draft\'
It confuses me a bit that it uses pstops on a pdf file.
D [23/Aug/2019:21:34:40 -0700] [Job 212] Using image rendering resolution 1200 dpi
D [23/Aug/2019:21:34:40 -0700] [Job 212] Running command line for gs: gs -q -dNOPAUSE -dBATCH -dSAFER -dNOMEDIAATTRS -sDEVICE=ps2write -dShowAcroForm -sOUTPUTFILE=%stdout -dLanguageLevel=3 -r1200 -dCompressFonts=false -dNoT3CCITT -dNOINTERPOLATE -c \'save pop\' -f /var/spool/cups/tmp/01db35d63d5f0
D [23/Aug/2019:21:34:40 -0700] [Job 212] Started filter gs (PID 7615)
D [23/Aug/2019:21:34:40 -0700] [Job 212] Started filter pstops (PID 7616)
pstops filter here
D [23/Aug/2019:21:34:41 -0700] [Job 212] Page = 612x792; 12,12 to 600,780
D [23/Aug/2019:21:34:41 -0700] [Job 212] slow_collate=0, slow_duplex=0, slow_order=0
D [23/Aug/2019:21:34:41 -0700] [Job 212] Before copy_comments - %!PS-Adobe-3.0
D [23/Aug/2019:21:34:41 -0700] [Job 212] %!PS-Adobe-3.0
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%BoundingBox: 0 0 612 792
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%HiResBoundingBox: 0 0 612.00 792.00
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%Creator: GPL Ghostscript 927 (ps2write)
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%LanguageLevel: 2
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%CreationDate: D:20190823213440-07\'00\'
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%Pages: 5
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%EndComments
D [23/Aug/2019:21:34:41 -0700] [Job 212] Before copy_prolog - %%BeginProlog >>
D [23/Aug/2019:21:34:43 -0700] [Job 212] Before copy_setup - %%Page: 1 1
D [23/Aug/2019:21:34:43 -0700] [Job 212] Before page loop - %%Page: 1 1
D [23/Aug/2019:21:34:43 -0700] [Job 212] Copying page 1...
D [23/Aug/2019:21:34:43 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:34:43 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [23/Aug/2019:21:34:43 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0 >> D [23/Aug/2019:21:34:43 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0 >> D [23/Aug/2019:21:34:43 -0700] [Job 212] PageWidth = 612.0, PageLength = 792.0
Four minutes here. Page 1.
D [23/Aug/2019:21:38:55 -0700] [Job 212] Copying page 2...
D [23/Aug/2019:21:38:55 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:38:55 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [23/Aug/2019:21:38:55 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0 >> D [23/Aug/2019:21:38:55 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0 >> D [23/Aug/2019:21:38:55 -0700] [Job 212] PageWidth = 612.0, PageLength = 792.0
Another four minutes here. Page 2.
D [23/Aug/2019:21:43:59 -0700] [Job 212] Copying page 3...
D [23/Aug/2019:21:43:59 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:43:59 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [23/Aug/2019:21:43:59 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0 >> D [23/Aug/2019:21:43:59 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0 >> D [23/Aug/2019:21:43:59 -0700] [Job 212] PageWidth = 612.0, PageLength = 792.0
Another four minutes here. Page 3
D [23/Aug/2019:21:48:29 -0700] [Job 212] Copying page 4...
D [23/Aug/2019:21:48:29 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:48:29 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [23/Aug/2019:21:48:29 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0 >> D [23/Aug/2019:21:48:29 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0 >> D [23/Aug/2019:21:48:29 -0700] [Job 212] PageWidth = 612.0, PageLength = 792.0
Another four minutes here. Page 4
D [23/Aug/2019:21:53:30 -0700] [Job 212] Copying page 5...
D [23/Aug/2019:21:53:30 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:53:30 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [23/Aug/2019:21:53:30 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0 >> D [23/Aug/2019:21:53:30 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0 >> D [23/Aug/2019:21:53:30 -0700] [Job 212] PageWidth = 612.0, PageLength = 792.0
No delay on page 5.
D [23/Aug/2019:21:54:43 -0700] [Job 212] PID 7615 (gs) exited with no errors.
D [23/Aug/2019:21:54:49 -0700] [Job 212] Wrote 5 pages...
D [23/Aug/2019:21:54:49 -0700] [Job 212] PID 7616 (pstops) exited with no errors.
D [23/Aug/2019:21:54:49 -0700] [Job 212] PID 7603 (/usr/lib/cups/filter/pdftops) exited with no errors.
D [23/Aug/2019:21:54:54 -0700] [Job 212] HP PS filter ENDS
It says it has been using pstops and pdftops, no errors.
D [23/Aug/2019:21:54:54 -0700] [Job 212] PID 7604 (/usr/lib/cups/filter/hpps) exited with no errors.
D [23/Aug/2019:21:54:59 -0700] [Job 212] Sent 14820622 bytes...
D [23/Aug/2019:21:54:59 -0700] [Job 212] Waiting for read thread to exit... >> D [23/Aug/2019:21:54:59 -0700] [Job 212] PID 7605 (/usr/lib/cups/backend/usb) exited with no errors.
D [23/Aug/2019:21:54:59 -0700] [Job 212] time-at-completed=1566622499
I [23/Aug/2019:21:54:59 -0700] [Job 212] Job completed.
D [23/Aug/2019:21:55:00 -0700] [Job 212] Unloading...
So I would run the pstops and pdftops programs externally and find out
the time it takes.
Possibly the pages have to be rendered and they are not that simple.
On Sat, 24 Aug 2019 08:13:13 +0200, "Carlos E.R."
<robin_listas@es.invalid> wrote:
On 24/08/2019 07.40, William Unruh wrote:
HP M402dn printer. Mageia 7 with cups-2.2.11
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to
print the file which is completely rediculous.
If it's an inkjet printer set to fine it could be normal.
My laser printer would print 5 pages in about 2 seconds.
What command are you using to print it?
Probably pring file.pdf.
On 24/08/2019 10.45, Lucifer wrote:
On Sat, 24 Aug 2019 08:13:13 +0200, "Carlos E.R."
<robin_listas@es.invalid> wrote:
My laser printer would print 5 pages in about 2 seconds.
It is 20 minutes before the printer sees a single byte.
What command are you using to print it?
Probably pring file.pdf.
:-D
William Unruh wrote:in it:
HP M402dn printer. Mageia 7 with cups-2.2.11
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to
print the file which is completely rediculous.
Looking in /var/log/cups there are a huge number of lines
D [23/Aug/2019:21:54:55 -0700] [Job 212] Wrote 8192 bytes of print data... >> D [23/Aug/2019:21:54:55 -0700] [Job 212] Read 8192 bytes of print data...
but the time between one and next is about 1 second which is rediculous
Here are the lines from the error.log (without the numerous lines above)
It really seems that gs is broken.
I have placed blank lines in the places where the above sets of lines
occured.
It says here, there is a possibility a printer sends back way too much "back-channel data" during printing.
https://github.com/apple/cups/issues/5221
"Many printers are "chatty" - depending on the driver in use,
it may not be necessary to have back channel data at all.
And a lot of Canon MP printers are already flagged as unidirectional.
Try adding a file called "/usr/share/cups/usb/canon" with the following
[0x04a9 is the USB vendor code for Canon, so this blocks all of Canon]
0x04a9 unidir
If that restores proper performance, run "lsusb -v" to get the vendor
and product IDs for this printer and we'll add them to the base USB
"quirks" file.
"
If you had a sufficient log-level, maybe you can see the back=channel
stuff getting logged to. Or perhaps, if there is no back-channel
activity to be seen, the printer might already be in the quirks
as being too chatty.
Paul
On 2019-08-24, William Unruh <unruh@invalid.ca> wrote:
HP M402dn printer. Mageia 7 with cups-2.2.11
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to
print the file which is completely rediculous.
Looking in /var/log/cups there are a huge number of lines
D [23/Aug/2019:21:54:55 -0700] [Job 212] Wrote 8192 bytes of print data... >> D [23/Aug/2019:21:54:55 -0700] [Job 212] Read 8192 bytes of print data...
but the time between one and next is about 1 second which is rediculous
Here are the lines from the error.log (without the numerous lines above)
It really seems that gs is broken.
I have placed blank lines in the places where the above sets of lines
occured.
Open the PDF with gv and see how long the pages take to render
on-screen. it could be that that PDF is using some PS feature that gs
does not handle well. I had one PDF that took several minutes to
render due to the way that gradients were done.
: WRITING PJL HEADER INFO
D [23/Aug/2019:21:34:39 -0700] [Job 212] Applying default options...
D [23/Aug/2019:21:34:39 -0700] [Job 212] Adding default print-quality=3
I [23/Aug/2019:21:34:39 -0700] [Job 212] Adding start banner page "none".
I [23/Aug/2019:21:34:39 -0700] [Job 212] Queued on "hpd" by "unruh".
D [23/Aug/2019:21:34:39 -0700] [Job 212] Auto-typing file...
D [23/Aug/2019:21:34:39 -0700] [Job 212] Request file type is application/pdf.
I [23/Aug/2019:21:34:39 -0700] [Job 212] File of type application/pdf queued by "unruh".
I [23/Aug/2019:21:34:39 -0700] [Job 212] Adding end banner page "none".
D [23/Aug/2019:21:34:39 -0700] [Job 212] time-at-processing=1566621279
D [23/Aug/2019:21:34:39 -0700] [Job 212] 3 filters for job:
D [23/Aug/2019:21:34:39 -0700] [Job 212] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [23/Aug/2019:21:34:39 -0700] [Job 212] pdftops (application/vnd.cups-pdf to application/vnd.cups-postscript, cost 100)
D [23/Aug/2019:21:34:39 -0700] [Job 212] hpps (application/vnd.cups-postscript to printer/hpd, cost 0)
D [23/Aug/2019:21:34:39 -0700] [Job 212] job-sheets=none,none
D [23/Aug/2019:21:34:39 -0700] [Job 212] argv[0]="hpd"
D [23/Aug/2019:21:34:39 -0700] [Job 212] argv[1]="212"
D [23/Aug/2019:21:34:39 -0700] [Job 212] argv[2]="unruh"
D [23/Aug/2019:21:34:39 -0700] [Job 212] argv[3]="schuld-lease.pdf"
D [23/Aug/2019:21:34:39 -0700] [Job 212] argv[4]="1"
D [23/Aug/2019:21:34:39 -0700] [Job 212] argv[5]="finishings=3 fitplot number-up=1 job-uuid=urn:uuid:e2b98acc-67d9-303d-702e-612bf0217336 print-quality=3 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1566621279 time-at-processing=1566621279 document-name-supplied=schuld-lease.pdf cupsPrintQuality=Draft"
D [23/Aug/2019:21:34:39 -0700] [Job 212] argv[6]="/var/spool/cups/d00212-001"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[8]="HOME=/var/spool/cups/tmp" >> D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[10]="SERVER_ADMIN=root@tunnel"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[11]="SOFTWARE=CUPS/2.2.11"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[13]="USER=root"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[14]="CUPS_MAX_MESSAGE=2047"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[17]="IPP_PORT=631"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[18]="CHARSET=utf-8"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[19]="LANG=en_CA.UTF-8"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[20]="PPD=/etc/cups/ppd/hpd.ppd"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[21]="RIP_MAX_CACHE=128m"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[22]="CONTENT_TYPE=application/pdf"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[23]="DEVICE_URI=usb://HP/LaserJet%20M402dw?serial=PHBV703311"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[24]="PRINTER_INFO=Hewlett-Packard HP LaserJet M402dw duplex"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[25]="PRINTER_LOCATION=tunnel" >> D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[26]="PRINTER=hpd"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[27]="PRINTER_STATE_REASONS=none"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[28]="CUPS_FILETYPE=document" >> D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
D [23/Aug/2019:21:34:39 -0700] [Job 212] envp[30]="AUTH_I****"
I [23/Aug/2019:21:34:39 -0700] [Job 212] Started filter /usr/lib/cups/filter/pdftopdf (PID 7602)
I [23/Aug/2019:21:34:39 -0700] [Job 212] Started filter /usr/lib/cups/filter/pdftops (PID 7603)
I [23/Aug/2019:21:34:39 -0700] [Job 212] Started filter /usr/lib/cups/filter/hpps (PID 7604)
I [23/Aug/2019:21:34:39 -0700] [Job 212] Started backend /usr/lib/cups/backend/usb (PID 7605)
D [23/Aug/2019:21:34:39 -0700] [Job 212] HP PS filter starting : hpd
D [23/Aug/2019:21:34:39 -0700] [Job 212] hppsfilter: argv[0] = hpd
D [23/Aug/2019:21:34:39 -0700] [Job 212] hppsfilter: argv[1] = 212
D [23/Aug/2019:21:34:39 -0700] [Job 212] hppsfilter: argv[2] = unruh
D [23/Aug/2019:21:34:39 -0700] [Job 212] hppsfilter: argv[3] = schuld-lease.pdf
D [23/Aug/2019:21:34:39 -0700] [Job 212] hppsfilter: argv[4] = 1
D [23/Aug/2019:21:34:39 -0700] [Job 212] hppsfilter: argv[5] = finishings=3 fitplot number-up=1 job-uuid=urn:uuid:e2b98acc-67d9-303d-702e-612bf0217336 print-quality=3 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1566621279 time-at-processing=1566621279 document-name-supplied=schuld-lease.pdf cupsPrintQuality=Draft
D [23/Aug/2019:21:34:39 -0700] [Job 212] HP PS filter func = WriteHeader
: WRITNING ECONOMODED [23/Aug/2019:21:34:39 -0700] [Job 212] HP PS filter func = GetPPDValues : PPD NAME /etc/cups/ppd/hpd.ppd
D [23/Aug/2019:21:34:39 -0700] [Job 212] HP PS filter func = WriteJobAccounting : WRITING JOB ACCOUNTING INFO
D [23/Aug/2019:21:34:39 -0700] [Job 212] HP PS filter func = WriteBornOnDate : WRITING BORN-ON-DATE
D [23/Aug/2019:21:34:39 -0700] [Job 212] HP PS filter func = WriteECONOMODE
D [23/Aug/2019:21:34:39 -0700] [Job 212] HP PS filter func = WriteECONOMODE2 : WRITING ECONOMODE
D [23/Aug/2019:21:34:39 -0700] [Job 212] Loading USB quirks from \"/usr/share/cups/usb\".
D [23/Aug/2019:21:34:39 -0700] [Job 212] Loaded 167 quirks.
D [23/Aug/2019:21:34:39 -0700] [Job 212] Printing on printer with URI: usb://HP/LaserJet%20M402dw?serial=PHBV703311
D [23/Aug/2019:21:34:39 -0700] [Job 212] pdftops - copying to temp print file \"/var/spool/cups/tmp/01db35d63d5f0\"
D [23/Aug/2019:21:34:39 -0700] [Job 212] libusb_get_device_list=4
D [23/Aug/2019:21:34:39 -0700] [Job 212] STATE: +connecting-to-device
D [23/Aug/2019:21:34:39 -0700] [Job 212] STATE: -connecting-to-device
D [23/Aug/2019:21:34:39 -0700] [Job 212] Device protocol: 2
D [23/Aug/2019:21:34:39 -0700] [Job 212] Sending data to printer.
D [23/Aug/2019:21:34:39 -0700] [Job 212] Set job-printer-state-message to "Sending data to printer.", current level=INFO
D [23/Aug/2019:21:34:39 -0700] [Job 212] pdftopdf: Last filter determined by the PPD: hpps; FINAL_CONTENT_TYPE: application/vnd.cups-postscript => pdftopdf will log pages in page_log.
D [23/Aug/2019:21:34:39 -0700] [Job 212] Read 1 bytes of back-channel data...
D [23/Aug/2019:21:34:39 -0700] [Job 212] PDF interactive form and annotation flattening done via QPDF
D [23/Aug/2019:21:34:39 -0700] [Job 212] PAGE: 1 1
D [23/Aug/2019:21:34:39 -0700] [Job 212] PAGE: 2 1
D [23/Aug/2019:21:34:39 -0700] [Job 212] PAGE: 3 1
D [23/Aug/2019:21:34:39 -0700] [Job 212] PAGE: 4 1
D [23/Aug/2019:21:34:39 -0700] [Job 212] PAGE: 5 1
D [23/Aug/2019:21:34:39 -0700] [Job 212] Read 106 bytes of back-channel data...
D [23/Aug/2019:21:34:40 -0700] [Job 212] PID 7602 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [23/Aug/2019:21:34:40 -0700] [Job 212] Printer make and model: HP HP LaserJet M403dn
D [23/Aug/2019:21:34:40 -0700] [Job 212] Running command line for pstops: pstops 212 unruh schuld-lease.pdf 1 \'finishings=3 job-uuid=urn:uuid:e2b98acc-67d9-303d-702e-612bf0217336 print-quality=3 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1566621279 time-at-processing=1566621279 document-name-supplied=schuld-lease.pdf cupsPrintQuality=Draft\'
D [23/Aug/2019:21:34:40 -0700] [Job 212] Using image rendering resolution 1200 dpi
D [23/Aug/2019:21:34:40 -0700] [Job 212] Running command line for gs: gs -q -dNOPAUSE -dBATCH -dSAFER -dNOMEDIAATTRS -sDEVICE=ps2write -dShowAcroForm -sOUTPUTFILE=%stdout -dLanguageLevel=3 -r1200 -dCompressFonts=false -dNoT3CCITT -dNOINTERPOLATE -c \'save pop\' -f /var/spool/cups/tmp/01db35d63d5f0
D [23/Aug/2019:21:34:40 -0700] [Job 212] Started filter gs (PID 7615)
D [23/Aug/2019:21:34:40 -0700] [Job 212] Started filter pstops (PID 7616)
D [23/Aug/2019:21:34:41 -0700] [Job 212] Page = 612x792; 12,12 to 600,780
D [23/Aug/2019:21:34:41 -0700] [Job 212] slow_collate=0, slow_duplex=0, slow_order=0
D [23/Aug/2019:21:34:41 -0700] [Job 212] Before copy_comments - %!PS-Adobe-3.0
D [23/Aug/2019:21:34:41 -0700] [Job 212] %!PS-Adobe-3.0
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%BoundingBox: 0 0 612 792
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%HiResBoundingBox: 0 0 612.00 792.00
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%Creator: GPL Ghostscript 927 (ps2write)
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%LanguageLevel: 2
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%CreationDate: D:20190823213440-07\'00\'
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%Pages: 5
D [23/Aug/2019:21:34:41 -0700] [Job 212] %%EndComments
D [23/Aug/2019:21:34:41 -0700] [Job 212] Before copy_prolog - %%BeginProlog >>
D [23/Aug/2019:21:34:43 -0700] [Job 212] Before copy_setup - %%Page: 1 1
D [23/Aug/2019:21:34:43 -0700] [Job 212] Before page loop - %%Page: 1 1
D [23/Aug/2019:21:34:43 -0700] [Job 212] Copying page 1...
D [23/Aug/2019:21:34:43 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:34:43 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [23/Aug/2019:21:34:43 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0 >> D [23/Aug/2019:21:34:43 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0 >> D [23/Aug/2019:21:34:43 -0700] [Job 212] PageWidth = 612.0, PageLength = 792.0
D [23/Aug/2019:21:38:55 -0700] [Job 212] Copying page 2...
D [23/Aug/2019:21:38:55 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:38:55 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [23/Aug/2019:21:38:55 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0 >> D [23/Aug/2019:21:38:55 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0 >> D [23/Aug/2019:21:38:55 -0700] [Job 212] PageWidth = 612.0, PageLength = 792.0
D [23/Aug/2019:21:43:59 -0700] [Job 212] Copying page 3...
D [23/Aug/2019:21:43:59 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:43:59 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [23/Aug/2019:21:43:59 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0 >> D [23/Aug/2019:21:43:59 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0 >> D [23/Aug/2019:21:43:59 -0700] [Job 212] PageWidth = 612.0, PageLength = 792.0
D [23/Aug/2019:21:48:29 -0700] [Job 212] Copying page 4...
D [23/Aug/2019:21:48:29 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:48:29 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [23/Aug/2019:21:48:29 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0 >> D [23/Aug/2019:21:48:29 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0 >> D [23/Aug/2019:21:48:29 -0700] [Job 212] PageWidth = 612.0, PageLength = 792.0
D [23/Aug/2019:21:53:30 -0700] [Job 212] Copying page 5...
D [23/Aug/2019:21:53:30 -0700] [Job 212] pagew = 588.0, pagel = 768.0
D [23/Aug/2019:21:53:30 -0700] [Job 212] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [23/Aug/2019:21:53:30 -0700] [Job 212] PageLeft = 12.0, PageRight = 600.0 >> D [23/Aug/2019:21:53:30 -0700] [Job 212] PageTop = 780.0, PageBottom = 12.0 >> D [23/Aug/2019:21:53:30 -0700] [Job 212] PageWidth = 612.0, PageLength = 792.0
D [23/Aug/2019:21:54:43 -0700] [Job 212] PID 7615 (gs) exited with no errors.
D [23/Aug/2019:21:54:49 -0700] [Job 212] Wrote 5 pages...
D [23/Aug/2019:21:54:49 -0700] [Job 212] PID 7616 (pstops) exited with no errors.
D [23/Aug/2019:21:54:49 -0700] [Job 212] PID 7603 (/usr/lib/cups/filter/pdftops) exited with no errors.
D [23/Aug/2019:21:54:54 -0700] [Job 212] HP PS filter ENDS
D [23/Aug/2019:21:54:54 -0700] [Job 212] PID 7604 (/usr/lib/cups/filter/hpps) exited with no errors.
D [23/Aug/2019:21:54:59 -0700] [Job 212] Sent 14820622 bytes...
D [23/Aug/2019:21:54:59 -0700] [Job 212] Waiting for read thread to exit... >> D [23/Aug/2019:21:54:59 -0700] [Job 212] PID 7605 (/usr/lib/cups/backend/usb) exited with no errors.
D [23/Aug/2019:21:54:59 -0700] [Job 212] time-at-completed=1566622499
I [23/Aug/2019:21:54:59 -0700] [Job 212] Job completed.
D [23/Aug/2019:21:55:00 -0700] [Job 212] Unloading...
On 2019-08-24, Jasen Betts <jasen@xnet.co.nz> wrote:
On 2019-08-24, William Unruh <unruh@invalid.ca> wrote:
HP M402dn printer. Mageia 7 with cups-2.2.11Open the PDF with gv and see how long the pages take to render
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to
print the file which is completely rediculous.
Looking in /var/log/cups there are a huge number of lines
D [23/Aug/2019:21:54:55 -0700] [Job 212] Wrote 8192 bytes of print data... >>> D [23/Aug/2019:21:54:55 -0700] [Job 212] Read 8192 bytes of print data... >>> but the time between one and next is about 1 second which is rediculous
Here are the lines from the error.log (without the numerous lines above) >>> It really seems that gs is broken.
I have placed blank lines in the places where the above sets of lines
occured.
on-screen. it could be that that PDF is using some PS feature that gs
does not handle well. I had one PDF that took several minutes to
render due to the way that gradients were done.
I have done that. I grabbed the gs command from the ps auxww listing,
and ran it to convert pdf to ps. It took about 2 sec to generate the ps
file. (but why in the world was it running for the full 20 min in the printing chain?)
D [23/Aug/2019:21:34:40 -0700] [Job 212] Printer make and model: HP HPLaserJet M403dn
D [23/Aug/2019:21:34:40 -0700] [Job 212] Running command line for gs: gs -q-sDEVICE=ps2write ...
William Unruh wrote:
On 2019-08-24, Jasen Betts <jasen@xnet.co.nz> wrote:
On 2019-08-24, William Unruh <unruh@invalid.ca> wrote:
HP M402dn printer. Mageia 7 with cups-2.2.11Open the PDF with gv and see how long the pages take to render
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to >>>> print the file which is completely rediculous.
Looking in /var/log/cups there are a huge number of lines
D [23/Aug/2019:21:54:55 -0700] [Job 212] Wrote 8192 bytes of print data...
D [23/Aug/2019:21:54:55 -0700] [Job 212] Read 8192 bytes of print data... >>>> but the time between one and next is about 1 second which is rediculous >>>>
Here are the lines from the error.log (without the numerous lines above) >>>> It really seems that gs is broken.
I have placed blank lines in the places where the above sets of lines
occured.
on-screen. it could be that that PDF is using some PS feature that gs
does not handle well. I had one PDF that took several minutes to
render due to the way that gradients were done.
I have done that. I grabbed the gs command from the ps auxww listing,
and ran it to convert pdf to ps. It took about 2 sec to generate the ps
file. (but why in the world was it running for the full 20 min in the
printing chain?)
The 8K reads and writes, could be commands situated in "pipes"
and using piping.
Paul
On Sat, 24 Aug 2019 05:40:49 +0000, William Unruh wrote:
D [23/Aug/2019:21:34:40 -0700] [Job 212] Printer make and model: HP HP LaserJet M403dn
D [23/Aug/2019:21:34:40 -0700] [Job 212] Running command line for gs: gs -q -sDEVICE=ps2write ...
The device is far from optimal.
This printer requires the HPIJS ghostscript device *and* it requires
an HP binary plug-in:
https://developers.hp.com/hp-linux-imaging-and-printing/binary_plugin.html
IOW, using ps2write is like using the vesa graphic driver with an nvidiacard.
This is another example of why the Linux user should not trust these do-it-all-for-you frameworks like cups. Before acquiring a printer
do the research and find out exactly what is required and then implement
your own print queue.
except the m402 or 403 is NOT in that list.
On 2019-08-24, Carlos E.R. <robin_listas@es.invalid> wrote:
On 24/08/2019 10.45, Lucifer wrote:
On Sat, 24 Aug 2019 08:13:13 +0200, "Carlos E.R."
<robin_listas@es.invalid> wrote:
My laser printer would print 5 pages in about 2 seconds.
It is 20 minutes before the printer sees a single byte.
Nope. It starts to see bytes immediately but for some reason that bytes
are being transfered really really really really slowly. ( about 10-20K/sec) Those blank lines represent many man lines like I showed
On 2019-08-24, Lucifer <LuciferMorningstar@bigpond.com> wrote:
On Sat, 24 Aug 2019 08:13:13 +0200, "Carlos E.R."
<robin_listas@es.invalid> wrote:
On 24/08/2019 07.40, William Unruh wrote:
HP M402dn printer. Mageia 7 with cups-2.2.11
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to >>>> print the file which is completely rediculous.
If it's an inkjet printer set to fine it could be normal.
My laser printer would print 5 pages in about 2 seconds.
Nope. Laserjet pro M402dn
What command are you using to print it?
Probably pring file.pdf.
lpr file.pdf
On Sat, 24 Aug 2019 05:40:49 +0000, William Unruh wrote:
D [23/Aug/2019:21:34:40 -0700] [Job 212] Printer make and model: HP HP LaserJet M403dn
D [23/Aug/2019:21:34:40 -0700] [Job 212] Running command line for gs: gs -q -sDEVICE=ps2write ...
The device is far from optimal.
This printer requires the HPIJS ghostscript device *and* it requires
an HP binary plug-in:
https://developers.hp.com/hp-linux-imaging-and-printing/binary_plugin.html
IOW, using ps2write is like using the vesa graphic driver with an nvidiacard.
This is another example of why the Linux user should not trust these do-it-all-for-you frameworks like cups. Before acquiring a printer
do the research and find out exactly what is required and then implement
your own print queue.
On 2019-08-24, Paul <nospam@needed.invalid> wrote:
William Unruh wrote:
On 2019-08-24, Jasen Betts <jasen@xnet.co.nz> wrote:The 8K reads and writes, could be commands situated in "pipes"
On 2019-08-24, William Unruh <unruh@invalid.ca> wrote:I have done that. I grabbed the gs command from the ps auxww listing,
HP M402dn printer. Mageia 7 with cups-2.2.11Open the PDF with gv and see how long the pages take to render
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to >>>>> print the file which is completely rediculous.
Looking in /var/log/cups there are a huge number of lines
D [23/Aug/2019:21:54:55 -0700] [Job 212] Wrote 8192 bytes of print data...
D [23/Aug/2019:21:54:55 -0700] [Job 212] Read 8192 bytes of print data...
but the time between one and next is about 1 second which is rediculous >>>>>
Here are the lines from the error.log (without the numerous lines above) >>>>> It really seems that gs is broken.
I have placed blank lines in the places where the above sets of lines >>>>> occured.
on-screen. it could be that that PDF is using some PS feature that gs >>>> does not handle well. I had one PDF that took several minutes to
render due to the way that gradients were done.
and ran it to convert pdf to ps. It took about 2 sec to generate the ps
file. (but why in the world was it running for the full 20 min in the
printing chain?)
and using piping.
But pipe from what to what?
And could the pipe stop the left program when the pipe is full to wait
for the pipe to empty?
Another weirdnessunruh file.pdf 1 finishings=3 job-uuid=urn:uuid:3acb9d04-c86b-3cd0-4ac1-3c890e3a8b03 print-quality=3 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1566664080 time-at-processing=1566664080 document-name-supplied=file.pdf cupsPrintQuality=Draft
In my list of ps auxww programs are a few that look like
lp 17801 0.0 0.0 29100 7828 ? S 09:27 0:00 hpd 214
Now there is no program called hpd. However, hpd IS the name of the
printer.
OK, this is bizzare. I just ran lpr as root, and the pages came out in seconds. So, clearly there is something problem somewhere with
permissions and something "timing out" but on a sub second timeframe.
There are still the same number of Read Write lines. (3600 of them
altogether (and if each takes 1/3 sec, that is 20 min.)
So for some reason that pipe is really really slow if not run by root.
Paul
William Unruh wrote:
On 2019-08-24, Paul <nospam@needed.invalid> wrote:
William Unruh wrote:
On 2019-08-24, Jasen Betts <jasen@xnet.co.nz> wrote:The 8K reads and writes, could be commands situated in "pipes"
On 2019-08-24, William Unruh <unruh@invalid.ca> wrote:I have done that. I grabbed the gs command from the ps auxww listing,
HP M402dn printer. Mageia 7 with cups-2.2.11Open the PDF with gv and see how long the pages take to render
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to >>>>>> print the file which is completely rediculous.
Looking in /var/log/cups there are a huge number of lines
D [23/Aug/2019:21:54:55 -0700] [Job 212] Wrote 8192 bytes of print data...
D [23/Aug/2019:21:54:55 -0700] [Job 212] Read 8192 bytes of print data...
but the time between one and next is about 1 second which is rediculous >>>>>>
Here are the lines from the error.log (without the numerous lines above)
It really seems that gs is broken.
I have placed blank lines in the places where the above sets of lines >>>>>> occured.
on-screen. it could be that that PDF is using some PS feature that gs >>>>> does not handle well. I had one PDF that took several minutes to
render due to the way that gradients were done.
and ran it to convert pdf to ps. It took about 2 sec to generate the ps >>>> file. (but why in the world was it running for the full 20 min in the
printing chain?)
and using piping.
But pipe from what to what?
And could the pipe stop the left program when the pipe is full to wait
for the pipe to empty?
Another weirdness
In my list of ps auxww programs are a few that look like
lp 17801 0.0 0.0 29100 7828 ? S 09:27 0:00 hpd 214 unruh file.pdf 1 finishings=3 job-uuid=urn:uuid:3acb9d04-c86b-3cd0-4ac1-3c890e3a8b03 print-quality=3 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1566664080 time-at-processing=1566664080 document-name-supplied=file.pdf cupsPrintQuality=Draft
Now there is no program called hpd. However, hpd IS the name of the
printer.
OK, this is bizzare. I just ran lpr as root, and the pages came out in
seconds. So, clearly there is something problem somewhere with
permissions and something "timing out" but on a sub second timeframe.
There are still the same number of Read Write lines. (3600 of them
altogether (and if each takes 1/3 sec, that is 20 min.)
So for some reason that pipe is really really slow if not run by root.
Paul
Standard Print languages
HP PCL 5
HP PCL 6
HP postscript level 3 emulation
direct PDF (v 1.7) printing <=== does LPR do this for a PDF ?
URF
PCLM
PWG
That might be why the LPR is faster.
That's a lot of optional print paths, in a sense.
Paul
On 2019-08-24, Paul <nospam@needed.invalid> wrote:
William Unruh wrote:
On 2019-08-24, Paul <nospam@needed.invalid> wrote:
William Unruh wrote:
On 2019-08-24, Jasen Betts <jasen@xnet.co.nz> wrote:The 8K reads and writes, could be commands situated in "pipes"
On 2019-08-24, William Unruh <unruh@invalid.ca> wrote:I have done that. I grabbed the gs command from the ps auxww listing, >>>>> and ran it to convert pdf to ps. It took about 2 sec to generate the ps >>>>> file. (but why in the world was it running for the full 20 min in the >>>>> printing chain?)
HP M402dn printer. Mageia 7 with cups-2.2.11Open the PDF with gv and see how long the pages take to render
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to
print the file which is completely rediculous.
Looking in /var/log/cups there are a huge number of lines
D [23/Aug/2019:21:54:55 -0700] [Job 212] Wrote 8192 bytes of print data...
D [23/Aug/2019:21:54:55 -0700] [Job 212] Read 8192 bytes of print data...
but the time between one and next is about 1 second which is rediculous
Here are the lines from the error.log (without the numerous lines above)
It really seems that gs is broken.
I have placed blank lines in the places where the above sets of lines >>>>>>> occured.
on-screen. it could be that that PDF is using some PS feature that gs >>>>>> does not handle well. I had one PDF that took several minutes to
render due to the way that gradients were done.
and using piping.
But pipe from what to what?
And could the pipe stop the left program when the pipe is full to wait
for the pipe to empty?
Another weirdness
In my list of ps auxww programs are a few that look like
lp 17801 0.0 0.0 29100 7828 ? S 09:27 0:00 hpd 214 unruh file.pdf 1 finishings=3 job-uuid=urn:uuid:3acb9d04-c86b-3cd0-4ac1-3c890e3a8b03 print-quality=3 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1566664080 time-at-processing=1566664080 document-name-supplied=file.pdf cupsPrintQuality=Draft
Now there is no program called hpd. However, hpd IS the name of the
printer.
OK, this is bizzare. I just ran lpr as root, and the pages came out in
seconds. So, clearly there is something problem somewhere with
permissions and something "timing out" but on a sub second timeframe.
There are still the same number of Read Write lines. (3600 of them
altogether (and if each takes 1/3 sec, that is 20 min.)
So for some reason that pipe is really really slow if not run by root.
Paul
http://www.hp.com/sbso/hpinfo/newsroom/HPSMB2015/HPLaserJetProM402dnDatasheet.p df
Standard Print languages
HP PCL 5
HP PCL 6
HP postscript level 3 emulation
direct PDF (v 1.7) printing <=== does LPR do this for a PDF ? >> URF
PCLM
PWG
Thanks for this link. It is the first time I have found what languages
it supports! Note that both hp-setup and system-printer-config ppd files
just seem to support ps, not pdf as the output.
That might be why the LPR is faster.
On my system, lpr is emulated by cups, so it is a standard path through
cups.
On 24/08/2019 21.31, William Unruh wrote:
On 2019-08-24, Paul <nospam@needed.invalid> wrote:
William Unruh wrote:
On 2019-08-24, Paul <nospam@needed.invalid> wrote:
William Unruh wrote:
On 2019-08-24, Jasen Betts <jasen@xnet.co.nz> wrote:The 8K reads and writes, could be commands situated in "pipes"
On 2019-08-24, William Unruh <unruh@invalid.ca> wrote:I have done that. I grabbed the gs command from the ps auxww listing, >>>>>> and ran it to convert pdf to ps. It took about 2 sec to generate the ps >>>>>> file. (but why in the world was it running for the full 20 min in the >>>>>> printing chain?)
HP M402dn printer. Mageia 7 with cups-2.2.11Open the PDF with gv and see how long the pages take to render
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to
print the file which is completely rediculous.
Looking in /var/log/cups there are a huge number of lines
D [23/Aug/2019:21:54:55 -0700] [Job 212] Wrote 8192 bytes of print data...
D [23/Aug/2019:21:54:55 -0700] [Job 212] Read 8192 bytes of print data...
but the time between one and next is about 1 second which is rediculous
Here are the lines from the error.log (without the numerous lines above)
It really seems that gs is broken.
I have placed blank lines in the places where the above sets of lines >>>>>>>> occured.
on-screen. it could be that that PDF is using some PS feature that gs >>>>>>> does not handle well. I had one PDF that took several minutes to >>>>>>> render due to the way that gradients were done.
and using piping.
But pipe from what to what?
And could the pipe stop the left program when the pipe is full to wait >>>> for the pipe to empty?
Another weirdness
In my list of ps auxww programs are a few that look like
lp 17801 0.0 0.0 29100 7828 ? S 09:27 0:00 hpd 214 unruh file.pdf 1 finishings=3 job-uuid=urn:uuid:3acb9d04-c86b-3cd0-4ac1-3c890e3a8b03 print-quality=3 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1566664080 time-at-processing=1566664080 document-name-supplied=file.pdf cupsPrintQuality=Draft
Now there is no program called hpd. However, hpd IS the name of the
printer.
OK, this is bizzare. I just ran lpr as root, and the pages came out in >>>> seconds. So, clearly there is something problem somewhere with
permissions and something "timing out" but on a sub second timeframe. >>>>
There are still the same number of Read Write lines. (3600 of them
altogether (and if each takes 1/3 sec, that is 20 min.)
So for some reason that pipe is really really slow if not run by root. >>>>
Paul
http://www.hp.com/sbso/hpinfo/newsroom/HPSMB2015/HPLaserJetProM402dnDatasheet.p df
Standard Print languages
HP PCL 5
HP PCL 6
HP postscript level 3 emulation
direct PDF (v 1.7) printing <=== does LPR do this for a PDF ? >>> URF
PCLM
PWG
Thanks for this link. It is the first time I have found what languages
it supports! Note that both hp-setup and system-printer-config ppd files
just seem to support ps, not pdf as the output.
Since I don't remember what version, probably version 2, PDF is the
default language for CUPS, not PS.
For my HP printer, in Cups I have defined several printers, and each one
uses a different driver for the same actual printer: postscript, hpijs,
and pcl3. In your case, I would create another one using PDF language.
That might be why the LPR is faster.
On my system, lpr is emulated by cups, so it is a standard path through
cups.
Yes.
On 24/08/2019 10.45, Lucifer wrote:
On Sat, 24 Aug 2019 08:13:13 +0200, "Carlos E.R."
<robin_listas@es.invalid> wrote:
On 24/08/2019 07.40, William Unruh wrote:
HP M402dn printer. Mageia 7 with cups-2.2.11
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to >>>> print the file which is completely rediculous.
If it's an inkjet printer
It isn't
set to fine it could be normal.
It isn't.
My laser printer would print 5 pages in about 2 seconds.
It is 20 minutes before the printer sees a single byte.
On 2019-08-24, Carlos E.R. <robin_listas@es.invalid> wrote:
On 24/08/2019 21.31, William Unruh wrote:
For my HP printer, in Cups I have defined several printers, and each one
uses a different driver for the same actual printer: postscript, hpijs,
and pcl3. In your case, I would create another one using PDF language.
Yes, that would be good, except I do not know how to tell cups to use
the various different filters/drivers. Where is that stored? It is not
in /etc/cups/printers.conf, nor in /etc/cups/ppd/hpd.ppd that I can see.
On Sat, 24 Aug 2019 11:23:05 +0200, "Carlos E.R."
<robin_listas@es.invalid> wrote:
On 24/08/2019 10.45, Lucifer wrote:
On Sat, 24 Aug 2019 08:13:13 +0200, "Carlos E.R."
<robin_listas@es.invalid> wrote:
On 24/08/2019 07.40, William Unruh wrote:
HP M402dn printer. Mageia 7 with cups-2.2.11
I print a large 5 page pdf file (10MB pdf file). It takes 20 minutes to >>>>> print the file which is completely rediculous.
If it's an inkjet printer
It isn't
set to fine it could be normal.
It isn't.
My laser printer would print 5 pages in about 2 seconds.
It is 20 minutes before the printer sees a single byte.
It sounds like the computer is having to process the file before
it can be sent to the printer.
Once it starts printing is the printing quick?
I have an inkjet printer connected to a desktop computer.
When I print from the laptop there is a short delay while
the laptop processes the file.
On 2019-08-24, Carlos E.R. <robin_listas@es.invalid> wrote:
On 24/08/2019 21.31, William Unruh wrote:
On 2019-08-24, Paul <nospam@needed.invalid> wrote:
William Unruh wrote:
On 2019-08-24, Paul <nospam@needed.invalid> wrote:
William Unruh wrote:
On 2019-08-24, Jasen Betts <jasen@xnet.co.nz> wrote:The 8K reads and writes, could be commands situated in "pipes"
On 2019-08-24, William Unruh <unruh@invalid.ca> wrote:I have done that. I grabbed the gs command from the ps auxww
HP M402dn printer. Mageia 7 with cups-2.2.11Open the PDF with gv and see how long the pages take to render >>>>>>>> on-screen. it could be that that PDF is using some PS feature >>>>>>>> that gs does not handle well. I had one PDF that took several >>>>>>>> minutes to render due to the way that gradients were done.
I print a large 5 page pdf file (10MB pdf file). It takes 20 >>>>>>>>> minutes to print the file which is completely rediculous.
Looking in /var/log/cups there are a huge number of lines D
[23/Aug/2019:21:54:55 -0700] [Job 212] Wrote 8192 bytes of print >>>>>>>>> data...
D [23/Aug/2019:21:54:55 -0700] [Job 212] Read 8192 bytes of
print data... but the time between one and next is about 1
second which is rediculous
Here are the lines from the error.log (without the numerous
lines above) It really seems that gs is broken.
I have placed blank lines in the places where the above sets of >>>>>>>>> lines occured.
listing, and ran it to convert pdf to ps. It took about 2 sec to >>>>>>> generate the ps file. (but why in the world was it running for the >>>>>>> full 20 min in the printing chain?)
and using piping.
But pipe from what to what?
And could the pipe stop the left program when the pipe is full to
wait for the pipe to empty?
Another weirdness In my list of ps auxww programs are a few that
look like lp 17801 0.0 0.0 29100 7828 ? S 09:27 >>>>> 0:00 hpd 214 unruh file.pdf 1 finishings=3
job-uuid=urn:uuid:3acb9d04-c86b-3cd0-4ac1-3c890e3a8b03
print-quality=3 job-originating-host-name=localhost
date-time-at-creation= date-time-at-processing=
time-at-creation=1566664080 time-at-processing=1566664080
document-name-supplied=file.pdf cupsPrintQuality=Draft
Now there is no program called hpd. However, hpd IS the name of the
printer.
OK, this is bizzare. I just ran lpr as root, and the pages came out
in seconds. So, clearly there is something problem somewhere with
permissions and something "timing out" but on a sub second
timeframe.
There are still the same number of Read Write lines. (3600 of them
altogether (and if each takes 1/3 sec, that is 20 min.)
So for some reason that pipe is really really slow if not run by
root.
Paul
http://www.hp.com/sbso/hpinfo/newsroom/HPSMB2015/ HPLaserJetProM402dnDatasheet.pdf
Standard Print languages
HP PCL 5 HP PCL 6 HP postscript level 3 emulation direct PDF
(v 1.7) printing <=== does LPR do this for a PDF ?
URF PCLM PWG
Thanks for this link. It is the first time I have found what languages
it supports! Note that both hp-setup and system-printer-config ppd
files just seem to support ps, not pdf as the output.
Since I don't remember what version, probably version 2, PDF is the
default language for CUPS, not PS.
For my HP printer, in Cups I have defined several printers, and each
one uses a different driver for the same actual printer: postscript,
hpijs, and pcl3. In your case, I would create another one using PDF
language.
Yes, that would be good, except I do not know how to tell cups to use
the various different filters/drivers. Where is that stored? It is not
in /etc/cups/printers.conf, nor in /etc/cups/ppd/hpd.ppd that I can see.
On Sun, 25 Aug 2019 00:30:51 +0000, William Unruh wrote:
On 2019-08-24, Carlos E.R. <robin_listas@es.invalid> wrote:
For my HP printer, in Cups I have defined several printers, and each
one uses a different driver for the same actual printer: postscript,
hpijs, and pcl3. In your case, I would create another one using PDF
language.
Yes, that would be good, except I do not know how to tell cups to use
the various different filters/drivers. Where is that stored? It is not
in /etc/cups/printers.conf, nor in /etc/cups/ppd/hpd.ppd that I can see.
Getting a printer set up has always been a pita for me. In gnome
desktop, I launch mcc, hardware, configure printing and scanning
routines, and then hope it will find the printer(s) (which may take a few minutes), and go from there. I think hplip has to be available, and
there is a plugin that is invariably pulled in from hp for any hp printer.
On 24/08/2019 18.56, F Russell wrote:
On Sat, 24 Aug 2019 05:40:49 +0000, William Unruh wrote:
D [23/Aug/2019:21:34:40 -0700] [Job 212] Printer make and model: HP HP LaserJet M403dn
D [23/Aug/2019:21:34:40 -0700] [Job 212] Running command line for gs: gs -q -sDEVICE=ps2write ...
The device is far from optimal.
This printer requires the HPIJS ghostscript device *and* it requires
an HP binary plug-in:
https://developers.hp.com/hp-linux-imaging-and-printing/binary_plugin.html
Well, if present, that would explain the talking.
IOW, using ps2write is like using the vesa graphic driver with an nvidia card.
This is another example of why the Linux user should not trust these
do-it-all-for-you frameworks like cups. Before acquiring a printer
do the research and find out exactly what is required and then implement
your own print queue.
Ignore that. This will be handled transparently by a a distribution with proper cups and hplip support. The instructions to install hp-plugin
might vary per distribution.
This is the important paragraph:
+++..........
Most Linux distributions include HPLIP with their software, but most do
not include the plug-in. Therefore, it is a safe practice to run a
utility called "hp-setup", which, will install the printer into the CUPS spooler, download, and install the plug-in at the appropriate time.
To install the plug-in using the GUI you can follow these procedures: ..........++-
| Sysop: | Keyop |
|---|---|
| Location: | Huddersfield, West Yorkshire, UK |
| Users: | 293 |
| Nodes: | 16 (2 / 14) |
| Uptime: | 225:47:39 |
| Calls: | 6,624 |
| Calls today: | 6 |
| Files: | 12,171 |
| Messages: | 5,318,613 |