Bug#769058: cups: "/usr/lib/cups/filter/foomatic-rip failed" after last CUPS update
El 14/11/14 14:27, Brian Potkin escribió:
Hello again, Tom. Still using your Delcop CL3005W we see! Thank you for
the report.
On Mon 10 Nov 2014 at 21:37:00 -0430, Tom Maneiro wrote:
After the last CUPS packages update (1.7.5-7) on all of my Jessie boxes at
home, I've been unable to print to my Konica-Minolta magicolor 1600W, which is
plugged to a old box running Wheezy. My last successful print jobs were under
1.7.5-5, a week ago. Due to resource constraints on the print server box, using
the remote print queue isn't an option (the print driver -foo2zjs- is very
resource-hungry and the print server only has 128MB RAM, hence the queues must
be local for the clients so they can crunch the print data before sending it to
the server, and ultimately to the printer). Printing still works from Windows
hosts, so there is nothing to blame in the server
After raising the debug level on CUPS, and taking a look at error_log, I've
noticed something... odd:
[Debug2 log snipped]
So... the filter (foomatic-rip) successfully exits, yet it fails!? This makes
no sense.
Please would you set up a print queue with
lpadmin -p test -v file:/home/tomman/test -E -m<the 1600W foo2zjs PPD>
Done. I had to enable FileDevice on cups-files.conf, though, as it comes
disabled by default.
Then
lp -d test /etc/debian_version
followed by
chmod 666 /home/tomman/test
if you want to switch away from the root account.
We want the information from
ls -l /etc/debian_version
tomman@tomman-lp:~$ ls -l /etc/debian_version
-rw-r--r-- 1 root root 11 may 5 2013 /etc/debian_version
ls -l /home/tomman/test
tomman@tomman-lp:~$ ls -l /home/tomman/test
-rw-rw-rw- 1 root root 1126 nov 17 18:59 /home/tomman/test
file /home/tomman/test
tomman@tomman-lp:~$ file /home/tomman/test
/home/tomman/test: HP Printer Job Language data
(actually, it's the raw LAVAFLOW stream, encapsulated into PJL)
How does the error_log compare with the one we have?
Filtered error log for successful print job is attached to this message.
Regards,
Brian.
And here is the PPD listing on the print server (the old box running
Wheezy):
tomman@saki:~$ ls -l /etc/cups/ppd
total 52
-rw-r--r-- 1 root root 25044 jun 12 2013 CL3005W.ppd
-rw-r--r-- 1 root root 20956 feb 26 2013 PDF.ppd
I [17/Nov/2014:18:59:18 -04-30] [Job 55] Adding start banner page "none".
I [17/Nov/2014:18:59:19 -04-30] [Job 55] Queued on "test" by "tomman".
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Auto-typing file...
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Request file type is text/plain.
I [17/Nov/2014:18:59:19 -04-30] [Job 55] File of type text/plain queued by "tomman".
I [17/Nov/2014:18:59:19 -04-30] [Job 55] Adding end banner page "none".
d [17/Nov/2014:18:59:19 -04-30] cupsdCheckJobs: Job 55 - dest="test", printer=(nil), state=3, cancel_time=0, hold_until=1416267258, kill_time=0, pending_cost=0, pending_timeout=0
D [17/Nov/2014:18:59:19 -04-30] [Job 55] time-at-processing=1416266959
D [17/Nov/2014:18:59:19 -04-30] [Job 55] 3 filters for job:
D [17/Nov/2014:18:59:19 -04-30] [Job 55] texttopdf (text/plain to application/pdf, cost 32)
D [17/Nov/2014:18:59:19 -04-30] [Job 55] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [17/Nov/2014:18:59:19 -04-30] [Job 55] foomatic-rip (application/vnd.cups-pdf to printer/test, cost 0)
D [17/Nov/2014:18:59:19 -04-30] [Job 55] job-sheets=none,none
D [17/Nov/2014:18:59:19 -04-30] [Job 55] argv[0]="test"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] argv[1]="55"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] argv[2]="tomman"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] argv[3]="debian_version"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] argv[4]="1"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:a9ad657b-f2c7-3231-73d6-8c62bd073b94 job-originating-host-name=localhost time-at-creation=1416266958 time-at-processing=1416266959"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] argv[6]="/var/spool/cups/d00055-001"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[8]="HOME=/var/spool/cups/tmp"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[10]="SERVER_ADMIN=root@tomman-lp"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[11]="SOFTWARE=CUPS/1.7.5"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[13]="USER=root"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[14]="CUPS_MAX_MESSAGE=2047"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[17]="IPP_PORT=631"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[18]="CHARSET=utf-8"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[19]="LANG=es_VE.UTF-8"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[20]="PPD=/etc/cups/ppd/test.ppd"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[21]="RIP_MAX_CACHE=128m"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[22]="CONTENT_TYPE=text/plain"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[23]="DEVICE_URI=file:/home/tomman/test"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[24]="PRINTER_INFO=test"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[25]="PRINTER_LOCATION="
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[26]="PRINTER=test"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[27]="PRINTER_STATE_REASONS=none"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[28]="CUPS_FILETYPE=document"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-pdf"
D [17/Nov/2014:18:59:19 -04-30] [Job 55] envp[30]="AUTH_I****"
I [17/Nov/2014:18:59:19 -04-30] [Job 55] Started filter /usr/lib/cups/filter/texttopdf (PID 4118)
I [17/Nov/2014:18:59:19 -04-30] [Job 55] Started filter /usr/lib/cups/filter/pdftopdf (PID 4119)
I [17/Nov/2014:18:59:19 -04-30] [Job 55] Started filter /usr/lib/cups/filter/foomatic-rip (PID 4120)
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Page = 612x792; 11,11 to 601,781
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Calling FindDeviceById(cups-test)
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Found device /org/freedesktop/ColorManager/devices/cups_test
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors)
D [17/Nov/2014:18:59:19 -04-30] [Job 55] 'CM Color Calibration' Mode in SPOOLER-LESS: Off
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Getting input from file
D [17/Nov/2014:18:59:19 -04-30] [Job 55] foomatic-rip version 1.0.61 running...
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Parsing PPD file ...
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Added option ColorSpace
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Added option PageSize
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Added option Quality
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Added option PrinterType
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Added option ColorMode
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Added option Resolution
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Added option ImageableArea
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Added option PaperDimension
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Added option InputSlot
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Added option MediaType
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Added option Duplex
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Added option Copies
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Added option halftone
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Added option ICM
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Added option NupOrient
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Added option NupPages
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Added option Font
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Parameter Summary
D [17/Nov/2014:18:59:19 -04-30] [Job 55] -----------------
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Spooler: cups
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Printer: test
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Shell: /bin/bash
D [17/Nov/2014:18:59:19 -04-30] [Job 55] PPD file: /etc/cups/ppd/test.ppd
D [17/Nov/2014:18:59:19 -04-30] [Job 55] ATTR file:
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Printer model: KONICA MINOLTA magicolor 1600W Foomatic/foo2lava (recommended)
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Job title: debian_version
D [17/Nov/2014:18:59:19 -04-30] [Job 55] File(s) to be printed:
D [17/Nov/2014:18:59:19 -04-30] [Job 55] <STDIN>
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Printing system options:
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Pondering option 'finishings=3'
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Unknown option finishings=3.
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Pondering option 'number-up=1'
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Unknown option number-up=1.
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Pondering option 'job-uuid=urn:uuid:a9ad657b-f2c7-3231-73d6-8c62bd073b94'
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Unknown option job-uuid=urn:uuid:a9ad657b-f2c7-3231-73d6-8c62bd073b94.
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Pondering option 'job-originating-host-name=localhost'
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Unknown option job-originating-host-name=localhost.
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Pondering option 'time-at-creation=1416266958'
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Unknown option time-at-creation=1416266958.
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Pondering option 'time-at-processing=1416266959'
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Unknown option time-at-processing=1416266959.
D [17/Nov/2014:18:59:19 -04-30] [Job 55] CM Color Calibration Mode in CUPS: Off
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Options from the PPD file:
D [17/Nov/2014:18:59:19 -04-30] [Job 55] ================================================
D [17/Nov/2014:18:59:19 -04-30] [Job 55] File: <STDIN>
D [17/Nov/2014:18:59:19 -04-30] [Job 55] ================================================
D [17/Nov/2014:18:59:19 -04-30] [Job 55] PID 4118 (/usr/lib/cups/filter/texttopdf) exited with no errors.
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Filetype: PDF
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Neither PDF renderer command line nor Ghostscript-based renderer command line found
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Driver does not understand PDF input, converting to PostScript
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Storing temporary files in /var/spool/cups/tmp
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Starting process "pdf-to-ps" (generation 1)
D [17/Nov/2014:18:59:19 -04-30] [Job 55] PID 4119 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Printer make and model: KONICA MINOLTA mc1600W
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Switching to Poppler's pdftops instead of Ghostscript for Brother, Minolta, and Konica Minolta to work around bugs in the printer's PS interpreters
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Running command line for pstops: pstops 55 tomman debian_version 1 ' finishings=3 job-uuid=urn:uuid:a9ad657b-f2c7-3231-73d6-8c62bd073b94 job-originating-host-name=localhost time-at-creation=1416266958 time-at-processing=1416266959'
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Using image rendering resolution 600 dpi
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Running command line for pdftops: pdftops -level3 -origpagesizes -nocenter -r 600 /var/spool/cups/tmp/foomatic-pPkZTr -
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Started filter pdftops (PID 4122)
D [17/Nov/2014:18:59:19 -04-30] [Job 55] Started filter pstops (PID 4123)
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Page = 612x792; 11,11 to 601,781
D [17/Nov/2014:18:59:20 -04-30] [Job 55] slow_collate=0, slow_duplex=0, slow_order=0
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Before copy_comments - %!PS-Adobe-3.0
D [17/Nov/2014:18:59:20 -04-30] [Job 55] %!PS-Adobe-3.0
D [17/Nov/2014:18:59:20 -04-30] [Job 55] %Produced by poppler pdftops version: 0.26.5 (http://poppler.freedesktop.org)
D [17/Nov/2014:18:59:20 -04-30] [Job 55] %%Creator: texttopdf/1.0.61
D [17/Nov/2014:18:59:20 -04-30] [Job 55] %%LanguageLevel: 3
D [17/Nov/2014:18:59:20 -04-30] [Job 55] %%DocumentSuppliedResources: (atend)
D [17/Nov/2014:18:59:20 -04-30] [Job 55] %%DocumentMedia: Letter 612 792 0 () ()
D [17/Nov/2014:18:59:20 -04-30] [Job 55] %%BoundingBox: 0 0 612 792
D [17/Nov/2014:18:59:20 -04-30] [Job 55] %%Pages: 1
D [17/Nov/2014:18:59:20 -04-30] [Job 55] %%EndComments
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Before copy_prolog - %%BeginProlog
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Filetype: PostScript
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Reading PostScript input ...
D [17/Nov/2014:18:59:20 -04-30] [Job 55] --> This document is DSC-conforming!
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found %RBINumCopies: 1
D [17/Nov/2014:18:59:20 -04-30] [Job 55] -----------
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %%BeginProlog
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Inserting option code into "Prolog" section.
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Before copy_setup - %%BeginSetup
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %%EndProlog
D [17/Nov/2014:18:59:20 -04-30] [Job 55] -----------
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %%BeginSetup
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %%BeginFeature: *Quality normal
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: Quality=normal
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %% FoomaticRIPOptionSetting: Quality=normal
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: Quality=normal
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %%BeginFeature: *halftone default
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: halftone=default
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %% FoomaticRIPOptionSetting: halftone=default
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: halftone=default
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %%BeginFeature: *ColorMode Monochrome
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: ColorMode=Monochrome
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %% FoomaticRIPOptionSetting: ColorMode=Monochrome
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: ColorMode=Monochrome
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %%BeginFeature: *Resolution 1200x600dpi
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: Resolution=1200x600dpi
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %% FoomaticRIPOptionSetting: Resolution=1200x600dpi
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: Resolution=1200x600dpi
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %%BeginFeature: *PageSize Letter
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: PageSize=Letter
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %% FoomaticRIPOptionSetting: PageSize=Letter
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: PageSize=Letter
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %%BeginFeature: *InputSlot Auto
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: InputSlot=Auto
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %% FoomaticRIPOptionSetting: InputSlot=Auto
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: InputSlot=Auto
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %%BeginFeature: *MediaType plain
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: MediaType=plain
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %% FoomaticRIPOptionSetting: MediaType=plain
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: MediaType=plain
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %%BeginFeature: *NupOrient port
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: NupOrient=port
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %% FoomaticRIPOptionSetting: NupOrient=port
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: NupOrient=port
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %%BeginFeature: *NupPages 1up
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: NupPages=1up
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %% FoomaticRIPOptionSetting: NupPages=1up
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: NupPages=1up
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %%BeginFeature: *Duplex None
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: Duplex=None
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %% FoomaticRIPOptionSetting: Duplex=None
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: Duplex=None
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %%BeginFeature: *Copies 1
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: Copies=1
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %% FoomaticRIPOptionSetting: Copies=1
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: Copies=1
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %%BeginFeature: *ICM km1600-default
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: ICM=km1600-default
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %% FoomaticRIPOptionSetting: ICM=km1600-default
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Option: ICM=km1600-default
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Setting option
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Before page loop - %%Page: 1 1
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Copying page 1...
D [17/Nov/2014:18:59:20 -04-30] [Job 55] pagew = 589.3, pagel = 769.3
D [17/Nov/2014:18:59:20 -04-30] [Job 55] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [17/Nov/2014:18:59:20 -04-30] [Job 55] PageLeft = 11.3, PageRight = 600.7
D [17/Nov/2014:18:59:20 -04-30] [Job 55] PageTop = 780.7, PageBottom = 11.3
D [17/Nov/2014:18:59:20 -04-30] [Job 55] PageWidth = 612.0, PageLength = 792.0
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Wrote 1 pages...
D [17/Nov/2014:18:59:20 -04-30] [Job 55] PID 4123 (pstops) exited with no errors.
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %%EndSetup
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Inserting PostScript code for CUPS' page accounting
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Inserting option code into "Setup" section.
D [17/Nov/2014:18:59:20 -04-30] [Job 55] -----------
D [17/Nov/2014:18:59:20 -04-30] [Job 55] New page: %%Page: 1 1
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Found: %%BeginPageSetup
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Inserting option code into "PageSetup" section.
D [17/Nov/2014:18:59:20 -04-30] [Job 55] PID 4122 (pdftops) exited with no errors.
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Flushing FIFO.
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Starting renderer with command: "foo2lava-wrapper -z2 -r1200x600 -p2 -m0 -s255 -d1 -n1 -Gkm-1600-rgb-392-bpp1.icm "
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Starting process "kid3" (generation 1)
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Starting process "kid4" (generation 2)
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Starting process "renderer" (generation 2)
D [17/Nov/2014:18:59:20 -04-30] [Job 55] JCL: %-12345X@PJL
D [17/Nov/2014:18:59:20 -04-30] [Job 55] <job data>
D [17/Nov/2014:18:59:20 -04-30] [Job 55] Closing renderer
D [17/Nov/2014:18:59:21 -04-30] [Job 55] PAGE: 1 1
D [17/Nov/2014:18:59:21 -04-30] [Job 55] renderer exited with status 0
D [17/Nov/2014:18:59:21 -04-30] [Job 55] kid4 exited with status 0
D [17/Nov/2014:18:59:21 -04-30] [Job 55] kid3 finished
D [17/Nov/2014:18:59:21 -04-30] [Job 55] kid3 exited with status 0
D [17/Nov/2014:18:59:21 -04-30] [Job 55] pdf-to-ps exited with status 0
D [17/Nov/2014:18:59:21 -04-30] [Job 55] Closing foomatic-rip.
D [17/Nov/2014:18:59:21 -04-30] [Job 55] PID 4120 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [17/Nov/2014:18:59:21 -04-30] [Job 55] time-at-completed=1416266961
I [17/Nov/2014:18:59:21 -04-30] [Job 55] Job completed.
D [17/Nov/2014:19:00:30 -04-30] [Job 55] Unloading...
Reply to: