--- Begin Message ---
- To: Debian Bug Tracking System <submit@bugs.debian.org>
- Subject: cups: "/usr/lib/cups/filter/foomatic-rip failed" after last CUPS update
- From: Tom Maneiro <tomman@tsdx.net.ve>
- Date: Mon, 10 Nov 2014 21:37:00 -0430
- Message-id: <20141111020700.22463.36402.reportbug@tomman-lp-c2.tomman.net.ve>
Package: cups
Version: 1.7.5-7
Severity: important
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:
D [10/Nov/2014:21:04:23 -04-30] [Job 155] IPP/2.0 Create-Job #4
D [10/Nov/2014:21:04:23 -04-30] [Job 155] ---- operation-attributes-tag ----
D [10/Nov/2014:21:04:23 -04-30] [Job 155] attributes-charset charset utf-8
D [10/Nov/2014:21:04:23 -04-30] [Job 155] attributes-natural-language
naturalLanguage es-ve
D [10/Nov/2014:21:04:23 -04-30] [Job 155] printer-uri uri
ipp://saki.tomman.net.ve:631/printers/CL3005W
D [10/Nov/2014:21:04:23 -04-30] [Job 155] requesting-user-name
nameWithoutLanguage root
D [10/Nov/2014:21:04:23 -04-30] [Job 155] job-name nameWithoutLanguage Test
Page
D [10/Nov/2014:21:04:23 -04-30] [Job 155] ---- job-attributes-tag ----
D [10/Nov/2014:21:04:23 -04-30] [Job 155] job-originating-host-name
nameWithoutLanguage localhost
D [10/Nov/2014:21:04:23 -04-30] [Job 155] job-uuid uri
urn:uuid:47ecf607-9d3d-3091-7581-ca1a88cbd249
D [10/Nov/2014:21:04:23 -04-30] [Job 155] ---- end-of-attributes-tag ----
D [10/Nov/2014:21:04:23 -04-30] [Job 155] Create-Job: successful-ok
(successful-ok)
D [10/Nov/2014:21:04:23 -04-30] [Job 155] Print job accepted - job ID 329.
D [10/Nov/2014:21:04:23 -04-30] [Job 155] Sending file 1 using chunking...
D [10/Nov/2014:21:04:23 -04-30] [Job 155] kid4 exited with status 0
D [10/Nov/2014:21:04:23 -04-30] [Job 155] kid3 finished
D [10/Nov/2014:21:04:23 -04-30] [Job 155] kid3 exited with status 0
D [10/Nov/2014:21:04:23 -04-30] [Job 155] pdf-to-ps exited with status 0
D [10/Nov/2014:21:04:23 -04-30] [Job 155] Closing foomatic-rip.
d [10/Nov/2014:21:04:23 -04-30] cupsdFinishProcess(pid=27918,
name=0x7fffe8b037f0, namelen=1024, job_id=0x7fffe8b037ec(155)) =
"/usr/lib/cups/filter/foomatic-rip"
D [10/Nov/2014:21:04:23 -04-30] [Job 155] PID 27918 (/usr/lib/cups/filter
/foomatic-rip) exited with no errors.
I [10/Nov/2014:21:04:23 -04-30] [Job 155] /usr/lib/cups/filter/foomatic-rip
failed
D [10/Nov/2014:21:04:23 -04-30] [Job 155] update_reasons(attr=1(none),
s="(null)")
d [10/Nov/2014:21:04:23 -04-30] [Job 155] op=' ', new_reasons=0,
state_reasons=0
D [10/Nov/2014:21:04:23 -04-30] [Job 155] Get-Printer-Attributes: successful-ok
(successful-ok)
D [10/Nov/2014:21:04:23 -04-30] [Job 155] (monitor) Get-Job-Attributes:
successful-ok (successful-ok)
D [10/Nov/2014:21:04:23 -04-30] [Job 155] (monitor) job-state=pending-held
So... the filter (foomatic-rip) successfully exits, yet it fails!? This makes
no sense.
-- System Information:
Debian Release: jessie/sid
APT prefers testing
APT policy: (990, 'testing'), (500, 'testing-updates')
Architecture: amd64 (x86_64)
Foreign Architectures: i386
Kernel: Linux 3.16-3-amd64 (SMP w/2 CPU cores)
Locale: LANG=es_VE.UTF-8, LC_CTYPE=es_VE.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash
Versions of packages cups depends on:
ii cups-client 1.7.5-7
ii cups-common 1.7.5-7
ii cups-core-drivers 1.7.5-7
ii cups-daemon 1.7.5-7
it cups-filters 1.0.61-2
ii cups-ppdc 1.7.5-7
ii cups-server-common 1.7.5-7
ii debconf [debconf-2.0] 1.5.53
ii ghostscript 9.06~dfsg-1.1+b1
ii libavahi-client3 0.6.31-4+b1
ii libavahi-common3 0.6.31-4+b1
ii libc-bin 2.19-12
ii libc6 2.19-12
ii libcups2 1.7.5-7
ii libcupscgi1 1.7.5-7
ii libcupsimage2 1.7.5-7
ii libcupsmime1 1.7.5-7
ii libcupsppdc1 1.7.5-7
ii libgcc1 1:4.9.1-19
ii libstdc++6 4.9.1-19
ii libusb-1.0-0 2:1.0.19-1
ii lsb-base 4.1+Debian13+nmu1
ii poppler-utils 0.26.5-2
ii procps 2:3.3.9-8
Versions of packages cups recommends:
it avahi-daemon 0.6.31-4+b1
ii colord 1.2.1-1+b1
it cups-filters [ghostscript-cups] 1.0.61-2
ii printer-driver-gutenprint 5.2.10-3
Versions of packages cups suggests:
ii cups-bsd 1.7.5-7
pn cups-pdf <none>
ii foomatic-db-compressed-ppds [foomatic-db] 20141016-1
it hplip 3.14.6-1+b2
ii printer-driver-hpcups 3.14.6-1+b2
ii smbclient 2:4.1.13+dfsg-2
ii udev 215-5+b1
-- Configuration Files:
/etc/cups/cupsd.conf.default 9ffef7ab095f3f35dcd108ba52de6295 [Errno 2] No existe el fichero o el directorio: u'/etc/cups/cupsd.conf.default 9ffef7ab095f3f35dcd108ba52de6295'
/etc/default/cups 2b436fbb1a32b82b6aba45a76a1d7e40 [Errno 2] No existe el fichero o el directorio: u'/etc/default/cups 2b436fbb1a32b82b6aba45a76a1d7e40'
-- debconf information:
cupsys/raw-print: true
cupsys/backend: lpd, socket, usb, snmp, dnssd
d [10/Nov/2014:21:35:50 -04-30] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0, curtime=1415671550
d [10/Nov/2014:21:35:50 -04-30] cupsdCheckJobs: Job 155 - dest="CL3005Wi", printer=0x7f1a544f08f0, state=5, cancel_time=1415680463, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0
d [10/Nov/2014:21:35:56 -04-30] [CGI] Starting "{SECTION=printers" at 1563, result=1...
d [10/Nov/2014:21:35:56 -04-30] [CGI] Starting at file position 1563...
d [10/Nov/2014:21:35:56 -04-30] [CGI] Returning at file position 1564 on character ':'...
d [10/Nov/2014:21:35:56 -04-30] [CGI] Starting at file position 1564...
d [10/Nov/2014:21:35:56 -04-30] [CGI] Returning at file position 1567 on character '}'...
d [10/Nov/2014:21:36:00 -04-30] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0, curtime=1415671560
d [10/Nov/2014:21:36:00 -04-30] cupsdCheckJobs: Job 155 - dest="CL3005Wi", printer=0x7f1a544f08f0, state=5, cancel_time=1415680463, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0
D [10/Nov/2014:21:36:06 -04-30] [Job 155] time-at-completed=1415671566
d [10/Nov/2014:21:36:06 -04-30] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0, curtime=1415671566
d [10/Nov/2014:21:36:06 -04-30] cupsdCheckJobs: Job 155 - dest="CL3005Wi", printer=0x7f1a544f08f0, state=7, cancel_time=1415680463, hold_until=0, kill_time=1415671866, pending_cost=0, pending_timeout=0
d [10/Nov/2014:21:36:06 -04-30] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0, curtime=1415671566
d [10/Nov/2014:21:36:06 -04-30] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0, curtime=1415671566
d [10/Nov/2014:21:36:06 -04-30] [CGI] Starting "{SECTION=printers" at 1563, result=1...
d [10/Nov/2014:21:36:06 -04-30] [CGI] Starting at file position 1563...
d [10/Nov/2014:21:36:06 -04-30] [CGI] Returning at file position 1564 on character ':'...
d [10/Nov/2014:21:36:06 -04-30] [CGI] Starting at file position 1564...
d [10/Nov/2014:21:36:06 -04-30] [CGI] Returning at file position 1567 on character '}'...
d [10/Nov/2014:21:36:13 -04-30] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0, curtime=1415671573
I [10/Nov/2014:21:36:22 -04-30] [Job 156] Adding start banner page "none".
d [10/Nov/2014:21:36:22 -04-30] copy_banner(con=0x7f1a545dbab0[16], job=0x7f1a545c8ed0[156], name="none")
d [10/Nov/2014:21:36:22 -04-30] cupsdAddEvent(event=job-created, dest=0x7f1a544f08f0(CL3005Wi), job=0x7f1a545c8ed0(156), text="Job created.", ...)
d [10/Nov/2014:21:36:22 -04-30] add_file(con=0x7f1a545dbab0[16], job=156, filetype=application/pdf, compression=0)
I [10/Nov/2014:21:36:22 -04-30] [Job 156] Adding end banner page "none".
d [10/Nov/2014:21:36:22 -04-30] copy_banner(con=(nil)[-1], job=0x7f1a545c8ed0[156], name="none")
I [10/Nov/2014:21:36:22 -04-30] [Job 156] File of type application/pdf queued by "tomman".
D [10/Nov/2014:21:36:22 -04-30] [Job 156] hold_until=0
I [10/Nov/2014:21:36:22 -04-30] [Job 156] Queued on "CL3005Wi" by "tomman".
d [10/Nov/2014:21:36:22 -04-30] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0, curtime=1415671582
d [10/Nov/2014:21:36:22 -04-30] cupsdCheckJobs: Job 156 - dest="CL3005Wi", printer=(nil), state=3, cancel_time=0, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0
d [10/Nov/2014:21:36:22 -04-30] start_job(job=0x7f1a545c8ed0(156), printer=0x7f1a544f08f0(CL3005Wi))
d [10/Nov/2014:21:36:22 -04-30] cupsdSetJobState(job=0x7f1a545c8ed0(156), state=3, newstate=5, action=0, message="(null)")
D [10/Nov/2014:21:36:22 -04-30] [Job 156] time-at-processing=1415671582
d [10/Nov/2014:21:36:22 -04-30] cupsdCreateProfile(job_id=156) = NULL
d [10/Nov/2014:21:36:22 -04-30] cupsdContinueJob(job=0x7f1a545c8ed0(156)): current_file=0, num_files=1
D [10/Nov/2014:21:36:22 -04-30] [Job 156] 2 filters for job:
D [10/Nov/2014:21:36:22 -04-30] [Job 156] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [10/Nov/2014:21:36:22 -04-30] [Job 156] foomatic-rip (application/vnd.cups-pdf to printer/CL3005Wi, cost 0)
D [10/Nov/2014:21:36:22 -04-30] [Job 156] job-sheets=none,none
D [10/Nov/2014:21:36:22 -04-30] [Job 156] argv[0]="CL3005Wi"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] argv[1]="156"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] argv[2]="tomman"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] argv[3]="Documento no guardado 1"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] argv[4]="1"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] argv[5]="NupOrient=port ColorMode=ICM number-up=1 halftone=as PageSize=Letter Duplex=None Resolution=600x600dpi InputSlot=Auto NupPages=1up Quality=draft MediaType=plain ICM=km2530_0 job-uuid=urn:uuid:29214ad9-370a-36b6-5c50-6b0a6f56fbb8 job-originating-host-name=localhost time-at-creation=1415671582 time-at-processing=1415671582"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] argv[6]="/var/spool/cups/d00156-001"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[8]="HOME=/var/spool/cups/tmp"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[10]="SERVER_ADMIN=root@tomman-lp-c2"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[11]="SOFTWARE=CUPS/1.7.5"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[13]="USER=root"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[14]="CUPS_MAX_MESSAGE=2047"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[17]="IPP_PORT=631"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[18]="CHARSET=utf-8"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[19]="LANG=es_VE.UTF-8"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[20]="PPD=/etc/cups/ppd/CL3005Wi.ppd"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[21]="RIP_MAX_CACHE=128m"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[22]="CONTENT_TYPE=application/pdf"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[23]="DEVICE_URI=ipp://saki.tomman.net.ve:631/printers/CL3005W"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[24]="PRINTER_INFO=DELCOP CL3005W (local mirror)"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[25]="PRINTER_LOCATION=saki"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[26]="PRINTER=CL3005Wi"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[27]="PRINTER_STATE_REASONS=none"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[28]="CUPS_FILETYPE=document"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-pdf"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] envp[30]="AUTH_I****"
d [10/Nov/2014:21:36:22 -04-30] cupsdStartProcess(command="/usr/lib/cups/filter/pdftopdf", argv=0x7f1a545bd0f0, envp=0x7fffe8af34b0, infd=-1, outfd=25, errfd=19, backfd=20, sidefd=22, root=0, profile=(nil), job=0x7f1a545c8ed0(156), pid=0x7f1a545c8fdc) = 7809
I [10/Nov/2014:21:36:22 -04-30] [Job 156] Started filter /usr/lib/cups/filter/pdftopdf (PID 7809)
d [10/Nov/2014:21:36:22 -04-30] cupsdStartProcess(command="/usr/lib/cups/filter/foomatic-rip", argv=0x7f1a545bd0f0, envp=0x7fffe8af34b0, infd=24, outfd=27, errfd=19, backfd=20, sidefd=22, root=0, profile=(nil), job=0x7f1a545c8ed0(156), pid=0x7f1a545c8fe0) = 7810
I [10/Nov/2014:21:36:22 -04-30] [Job 156] Started filter /usr/lib/cups/filter/foomatic-rip (PID 7810)
d [10/Nov/2014:21:36:22 -04-30] cupsdStartProcess(command="/usr/lib/cups/backend/ipp", argv=0x7f1a545bd0f0, envp=0x7fffe8af34b0, infd=26, outfd=-1, errfd=19, backfd=21, sidefd=23, root=0, profile=(nil), job=0x7f1a545c8ed0(156), pid=0x7f1a545c9030) = 7811
I [10/Nov/2014:21:36:22 -04-30] [Job 156] Started backend /usr/lib/cups/backend/ipp (PID 7811)
d [10/Nov/2014:21:36:22 -04-30] cupsdAddEvent(event=job-state-changed, dest=0x7f1a544f08f0(CL3005Wi), job=0x7f1a545c8ed0(156), text="Job #%d started.", ...)
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Sending stdin for job...
D [10/Nov/2014:21:36:22 -04-30] [Job 156] update_reasons(attr=0(), s="+connecting-to-device")
d [10/Nov/2014:21:36:22 -04-30] [Job 156] op='+', new_reasons=1, state_reasons=1
D [10/Nov/2014:21:36:22 -04-30] [Job 156] STATE: +connecting-to-device
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Looking up "saki.tomman.net.ve"...
d [10/Nov/2014:21:36:22 -04-30] cupsdFinishProcess(pid=7809, name=0x7fffe8b037f0, namelen=1024, job_id=0x7fffe8b037ec(156)) = "/usr/lib/cups/filter/pdftopdf"
D [10/Nov/2014:21:36:22 -04-30] [Job 156] PID 7809 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Calling FindDeviceById(cups-CL3005Wi)
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found device /org/freedesktop/ColorManager/devices/cups_CL3005Wi
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors)
D [10/Nov/2014:21:36:22 -04-30] [Job 156] 'CM Color Calibration' Mode in SPOOLER-LESS: Off
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Getting input from file
D [10/Nov/2014:21:36:22 -04-30] [Job 156] foomatic-rip version 1.0.61 running...
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Parsing PPD file ...
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Added option ColorSpace
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Added option PageSize
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Added option Quality
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Added option PrinterType
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Added option ColorMode
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Added option Resolution
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Added option ImageableArea
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Added option PaperDimension
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Added option InputSlot
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Added option MediaType
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Added option Duplex
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Added option Copies
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Added option halftone
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Added option ICM
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Added option NupOrient
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Added option NupPages
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Added option Font
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Parameter Summary
D [10/Nov/2014:21:36:22 -04-30] [Job 156] -----------------
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Spooler: cups
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Printer: CL3005Wi
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Shell: /bin/bash
D [10/Nov/2014:21:36:22 -04-30] [Job 156] PPD file: /etc/cups/ppd/CL3005Wi.ppd
D [10/Nov/2014:21:36:22 -04-30] [Job 156] ATTR file:
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Printer model: KONICA MINOLTA magicolor 1600W Foomatic/foo2lava (recommended)
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Job title: Documento no guardado 1
D [10/Nov/2014:21:36:22 -04-30] [Job 156] File(s) to be printed:
D [10/Nov/2014:21:36:22 -04-30] [Job 156] <STDIN>
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Printing system options:
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Pondering option 'number-up=1'
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Unknown option number-up=1.
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Pondering option 'job-uuid=urn:uuid:29214ad9-370a-36b6-5c50-6b0a6f56fbb8'
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Unknown option job-uuid=urn:uuid:29214ad9-370a-36b6-5c50-6b0a6f56fbb8.
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Pondering option 'job-originating-host-name=localhost'
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Unknown option job-originating-host-name=localhost.
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Pondering option 'time-at-creation=1415671582'
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Unknown option time-at-creation=1415671582.
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Pondering option 'time-at-processing=1415671582'
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Unknown option time-at-processing=1415671582.
D [10/Nov/2014:21:36:22 -04-30] [Job 156] CM Color Calibration Mode in CUPS: Off
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Options from the PPD file:
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Pondering option 'NupOrient=port'
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Pondering option 'ColorMode=ICM'
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Pondering option 'halftone=as'
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Pondering option 'PageSize=Letter'
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Pondering option 'Duplex=None'
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Pondering option 'Resolution=600x600dpi'
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Pondering option 'InputSlot=Auto'
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Pondering option 'NupPages=1up'
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Pondering option 'Quality=draft'
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Pondering option 'MediaType=plain'
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Pondering option 'ICM=km2530_0'
D [10/Nov/2014:21:36:22 -04-30] [Job 156] ================================================
D [10/Nov/2014:21:36:22 -04-30] [Job 156] File: <STDIN>
D [10/Nov/2014:21:36:22 -04-30] [Job 156] ================================================
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Filetype: PDF
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Neither PDF renderer command line nor Ghostscript-based renderer command line found
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Driver does not understand PDF input, converting to PostScript
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Storing temporary files in /var/spool/cups/tmp
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Starting process "pdf-to-ps" (generation 1)
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Printer make and model: KONICA MINOLTA mc1600W
D [10/Nov/2014:21:36:22 -04-30] [Job 156] 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 [10/Nov/2014:21:36:22 -04-30] [Job 156] Running command line for pstops: pstops 156 tomman 'Documento no guardado 1' 1 ' NupOrient=port ColorMode=ICM halftone=as PageSize=Letter Duplex=None Resolution=600x600dpi InputSlot=Auto NupPages=1up Quality=draft MediaType=plain ICM=km2530_0 job-uuid=urn:uuid:29214ad9-370a-36b6-5c50-6b0a6f56fbb8 job-originating-host-name=localhost time-at-creation=1415671582 time-at-processing=1415671582'
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Using image rendering resolution 600 dpi
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Running command line for pdftops: pdftops -level3 -origpagesizes -nocenter -r 600 /var/spool/cups/tmp/foomatic-w35yfe -
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Started filter pdftops (PID 7813)
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Started filter pstops (PID 7814)
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Page = 612x792; 11,11 to 601,781
D [10/Nov/2014:21:36:22 -04-30] [Job 156] slow_collate=0, slow_duplex=0, slow_order=0
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Before copy_comments - %!PS-Adobe-3.0
D [10/Nov/2014:21:36:22 -04-30] [Job 156] %!PS-Adobe-3.0
D [10/Nov/2014:21:36:22 -04-30] [Job 156] %Produced by poppler pdftops version: 0.26.5 (http://poppler.freedesktop.org)
D [10/Nov/2014:21:36:22 -04-30] [Job 156] %%Creator: cairo 1.14.0 (http://cairographics.org)
D [10/Nov/2014:21:36:22 -04-30] [Job 156] %%LanguageLevel: 3
D [10/Nov/2014:21:36:22 -04-30] [Job 156] %%DocumentSuppliedResources: (atend)
D [10/Nov/2014:21:36:22 -04-30] [Job 156] %%DocumentMedia: Letter 612 792 0 () ()
D [10/Nov/2014:21:36:22 -04-30] [Job 156] %%BoundingBox: 0 0 612 792
D [10/Nov/2014:21:36:22 -04-30] [Job 156] %%Pages: 1
D [10/Nov/2014:21:36:22 -04-30] [Job 156] %%EndComments
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Before copy_prolog - %%BeginProlog
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Filetype: PostScript
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Reading PostScript input ...
D [10/Nov/2014:21:36:22 -04-30] [Job 156] --> This document is DSC-conforming!
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found %RBINumCopies: 1
D [10/Nov/2014:21:36:22 -04-30] [Job 156] -----------
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %%BeginProlog
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Inserting option code into "Prolog" section.
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Before copy_setup - %%BeginSetup
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %%EndProlog
D [10/Nov/2014:21:36:22 -04-30] [Job 156] -----------
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %%BeginSetup
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %%BeginFeature: *Quality draft
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: Quality=draft
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %% FoomaticRIPOptionSetting: Quality=draft
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: Quality=draft
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %%BeginFeature: *halftone as
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: halftone=as
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %% FoomaticRIPOptionSetting: halftone=as
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: halftone=as
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %%BeginFeature: *ColorMode ICM
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: ColorMode=ICM
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %% FoomaticRIPOptionSetting: ColorMode=ICM
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: ColorMode=ICM
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %%BeginFeature: *Resolution 600x600dpi
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: Resolution=600x600dpi
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %% FoomaticRIPOptionSetting: Resolution=600x600dpi
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: Resolution=600x600dpi
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %%BeginFeature: *PageSize Letter
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: PageSize=Letter
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %% FoomaticRIPOptionSetting: PageSize=Letter
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: PageSize=Letter
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %%BeginFeature: *InputSlot Auto
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: InputSlot=Auto
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %% FoomaticRIPOptionSetting: InputSlot=Auto
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: InputSlot=Auto
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %%BeginFeature: *MediaType plain
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: MediaType=plain
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %% FoomaticRIPOptionSetting: MediaType=plain
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: MediaType=plain
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %%BeginFeature: *NupOrient port
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: NupOrient=port
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %% FoomaticRIPOptionSetting: NupOrient=port
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: NupOrient=port
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %%BeginFeature: *NupPages 1up
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: NupPages=1up
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %% FoomaticRIPOptionSetting: NupPages=1up
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: NupPages=1up
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %%BeginFeature: *Duplex None
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: Duplex=None
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %% FoomaticRIPOptionSetting: Duplex=None
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: Duplex=None
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %%BeginFeature: *Copies 1
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: Copies=1
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %% FoomaticRIPOptionSetting: Copies=1
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: Copies=1
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %%BeginFeature: *ICM km2530_0
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: ICM=km2530_0
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %% FoomaticRIPOptionSetting: ICM=km2530_0
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Option: ICM=km2530_0
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Setting option
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Before page loop - %%Page: 1 1
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Copying page 1...
D [10/Nov/2014:21:36:22 -04-30] [Job 156] pagew = 589.3, pagel = 769.3
D [10/Nov/2014:21:36:22 -04-30] [Job 156] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [10/Nov/2014:21:36:22 -04-30] [Job 156] PageLeft = 11.3, PageRight = 600.7
D [10/Nov/2014:21:36:22 -04-30] [Job 156] PageTop = 780.7, PageBottom = 11.3
D [10/Nov/2014:21:36:22 -04-30] [Job 156] PageWidth = 612.0, PageLength = 792.0
D [10/Nov/2014:21:36:22 -04-30] [Job 156] PID 7813 (pdftops) exited with no errors.
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %%EndSetup
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Inserting PostScript code for CUPS' page accounting
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Inserting option code into "Setup" section.
D [10/Nov/2014:21:36:22 -04-30] [Job 156] -----------
D [10/Nov/2014:21:36:22 -04-30] [Job 156] New page: %%Page: 1 1
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Found: %%BeginPageSetup
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Inserting option code into "PageSetup" section.
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Wrote 1 pages...
D [10/Nov/2014:21:36:22 -04-30] [Job 156] PID 7814 (pstops) exited with no errors.
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Flushing FIFO.
D [10/Nov/2014:21:36:22 -04-30] Get-Job-Attributes ipp://localhost/jobs/156
d [10/Nov/2014:21:36:22 -04-30] get_job_attrs(0x7f1a545dbab0[16], ipp://localhost/jobs/156)
D [10/Nov/2014:21:36:22 -04-30] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/156) from localhost
D [10/Nov/2014:21:36:22 -04-30] Get-Job-Attributes ipp://localhost/jobs/156
d [10/Nov/2014:21:36:22 -04-30] get_job_attrs(0x7f1a545e4210[20], ipp://localhost/jobs/156)
D [10/Nov/2014:21:36:22 -04-30] [Client 20] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/156) from localhost
D [10/Nov/2014:21:36:22 -04-30] Get-Job-Attributes ipp://localhost/jobs/156
d [10/Nov/2014:21:36:22 -04-30] get_job_attrs(0x7f1a545dfe60[19], ipp://localhost/jobs/156)
D [10/Nov/2014:21:36:22 -04-30] [Client 19] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/156) from localhost
D [10/Nov/2014:21:36:22 -04-30] Get-Job-Attributes ipp://localhost/jobs/156
d [10/Nov/2014:21:36:22 -04-30] get_job_attrs(0x7f1a545dfe60[19], ipp://localhost/jobs/156)
D [10/Nov/2014:21:36:22 -04-30] [Client 19] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/156) from localhost
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Starting renderer with command: "foo2lava-wrapper -qas -z2 -t -c -C10 -r600x600 -p2 -m0 -s255 -d1 -n1 -Gkm2530_0.icm "
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Starting process "kid3" (generation 1)
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Closing renderer
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Starting process "kid4" (generation 2)
D [10/Nov/2014:21:36:22 -04-30] [Job 156] Starting process "renderer" (generation 2)
D [10/Nov/2014:21:36:22 -04-30] [Job 156] JCL: %-12345X@PJL
D [10/Nov/2014:21:36:22 -04-30] [Job 156] <job data>
d [10/Nov/2014:21:36:23 -04-30] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0, curtime=1415671583
d [10/Nov/2014:21:36:23 -04-30] cupsdCheckJobs: Job 156 - dest="CL3005Wi", printer=0x7f1a544f08f0, state=5, cancel_time=1415682382, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0
D [10/Nov/2014:21:36:23 -04-30] [Job 156] PAGE: 1 1
d [10/Nov/2014:21:36:23 -04-30] cupsdAddEvent(event=job-progress, dest=0x7f1a544f08f0(CL3005Wi), job=0x7f1a545c8ed0(156), text="Printed %d page(s).", ...)
D [10/Nov/2014:21:36:23 -04-30] [Job 156] renderer exited with status 0
D [10/Nov/2014:21:36:23 -04-30] [Job 156] kid4 exited with status 0
D [10/Nov/2014:21:36:23 -04-30] [Job 156] kid3 finished
D [10/Nov/2014:21:36:23 -04-30] [Job 156] kid3 exited with status 0
D [10/Nov/2014:21:36:23 -04-30] [Job 156] pdf-to-ps exited with status 0
D [10/Nov/2014:21:36:23 -04-30] [Job 156] Closing foomatic-rip.
d [10/Nov/2014:21:36:23 -04-30] cupsdFinishProcess(pid=7810, name=0x7fffe8b037f0, namelen=1024, job_id=0x7fffe8b037ec(156)) = "/usr/lib/cups/filter/foomatic-rip"
D [10/Nov/2014:21:36:23 -04-30] [Job 156] PID 7810 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
d [10/Nov/2014:21:36:24 -04-30] [Job 156] hrDeviceDesc="Unknown"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] prtGeneralCurrentLocalization type is 0, expected 2!
D [10/Nov/2014:21:36:26 -04-30] [Job 156] backendWaitLoop(snmp_fd=7, addr=0x7f8a5b3ff3f8, side_cb=0x7f8a5b169be0)
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Connecting to saki.tomman.net.ve:631
I [10/Nov/2014:21:36:26 -04-30] [Job 156] Conectando a la impresora.
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Set job-printer-state-message to "Conectando a la impresora.", current level=INFO
d [10/Nov/2014:21:36:26 -04-30] cupsdAddEvent(event=job-progress, dest=0x7f1a544f08f0(CL3005Wi), job=0x7f1a545c8ed0(156), text="%s", ...)
D [10/Nov/2014:21:36:26 -04-30] [Job 156] update_reasons(attr=0(), s="-cups-certificate-error")
d [10/Nov/2014:21:36:26 -04-30] [Job 156] op='-', new_reasons=1, state_reasons=2
D [10/Nov/2014:21:36:26 -04-30] [Job 156] update_reasons(attr=0(), s="-connecting-to-device")
d [10/Nov/2014:21:36:26 -04-30] [Job 156] op='-', new_reasons=1, state_reasons=2
D [10/Nov/2014:21:36:26 -04-30] [Job 156] STATE: -connecting-to-device
I [10/Nov/2014:21:36:26 -04-30] [Job 156] Conectado a la impresora.
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Set job-printer-state-message to "Conectado a la impresora.", current level=INFO
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Connected to 192.168.0.254:631...
d [10/Nov/2014:21:36:26 -04-30] cupsdAddEvent(event=job-progress, dest=0x7f1a544f08f0(CL3005Wi), job=0x7f1a545c8ed0(156), text="%s", ...)
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Getting supported attributes...
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Get-Printer-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Automatically using "gzip" compression.
D [10/Nov/2014:21:36:26 -04-30] [Job 156] copies-supported=1-9999
D [10/Nov/2014:21:36:26 -04-30] [Job 156] document-format-supported (32 values)
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [0] = "application/octet-stream"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [1] = "application/pdf"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [2] = "application/postscript"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [3] = "application/vnd.adobe-reader-postscript"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [4] = "application/vnd.cups-command"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [5] = "application/vnd.cups-pdf"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [6] = "application/vnd.cups-pdf-banner"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [7] = "application/vnd.cups-postscript"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [8] = "application/vnd.cups-raw"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [9] = "application/x-cshell"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [10] = "application/x-csource"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [11] = "application/x-perl"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [12] = "application/x-shell"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [13] = "image/gif"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [14] = "image/jpeg"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [15] = "image/png"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [16] = "image/tiff"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [17] = "image/urf"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [18] = "image/x-bitmap"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [19] = "image/x-photocd"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [20] = "image/x-portable-anymap"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [21] = "image/x-portable-bitmap"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [22] = "image/x-portable-graymap"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [23] = "image/x-portable-pixmap"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [24] = "image/x-sgi-rgb"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [25] = "image/x-sun-raster"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [26] = "image/x-xbitmap"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [27] = "image/x-xpixmap"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [28] = "image/x-xwindowdump"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [29] = "text/css"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [30] = "text/html"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [31] = "text/plain"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] media-col-supported (7 values)
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [0] = "media-bottom-margin"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [1] = "media-left-margin"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [2] = "media-right-margin"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [3] = "media-size"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [4] = "media-source"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [5] = "media-top-margin"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [6] = "media-type"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] operations-supported (47 values)
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [0] = Print-Job
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [1] = Validate-Job
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [2] = Create-Job
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [3] = Send-Document
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [4] = Cancel-Job
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [5] = Get-Job-Attributes
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [6] = Get-Jobs
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [7] = Get-Printer-Attributes
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [8] = Hold-Job
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [9] = Release-Job
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [10] = Restart-Job
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [11] = Pause-Printer
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [12] = Resume-Printer
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [13] = Purge-Jobs
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [14] = Set-Printer-Attributes
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [15] = Set-Job-Attributes
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [16] = Get-Printer-Supported-Values
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [17] = Create-Printer-Subscriptions
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [18] = Create-Job-Subscriptions
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [19] = Get-Subscription-Attributes
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [20] = Get-Subscriptions
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [21] = Renew-Subscription
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [22] = Cancel-Subscription
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [23] = Get-Notifications
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [24] = Enable-Printer
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [25] = Disable-Printer
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [26] = Hold-New-Jobs
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [27] = Release-Held-New-Jobs
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [28] = Cancel-Jobs
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [29] = Cancel-My-Jobs
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [30] = Close-Job
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [31] = CUPS-Get-Default
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [32] = CUPS-Get-Printers
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [33] = CUPS-Add-Modify-Printer
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [34] = CUPS-Delete-Printer
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [35] = CUPS-Get-Classes
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [36] = CUPS-Add-Modify-Class
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [37] = CUPS-Delete-Class
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [38] = CUPS-Accept-Jobs
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [39] = CUPS-Reject-Jobs
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [40] = CUPS-Set-Default
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [41] = CUPS-Get-Devices
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [42] = CUPS-Get-PPDs
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [43] = CUPS-Move-Job
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [44] = CUPS-Authenticate-Job
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [45] = CUPS-Get-PPD
D [10/Nov/2014:21:36:26 -04-30] [Job 156] [46] = CUPS-Get-Document
I [10/Nov/2014:21:36:26 -04-30] [Job 156] Sending data to printer.
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Set job-printer-state-message to "Sending data to printer.", current level=INFO
D [10/Nov/2014:21:36:26 -04-30] [Job 156] update_reasons(attr=1(none), s="(null)")
d [10/Nov/2014:21:36:26 -04-30] [Job 156] op=' ', new_reasons=0, state_reasons=1
D [10/Nov/2014:21:36:26 -04-30] [Job 156] STATE: -none
D [10/Nov/2014:21:36:26 -04-30] [Job 156] final_content_type="application/vnd.cups-pdf", document_format="application/vnd.cups-pdf"
d [10/Nov/2014:21:36:26 -04-30] cupsdAddEvent(event=job-progress, dest=0x7f1a544f08f0(CL3005Wi), job=0x7f1a545c8ed0(156), text="%s", ...)
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Validate-Job IPP/2.0
D [10/Nov/2014:21:36:26 -04-30] [Job 156] printer-uri="ipp://saki.tomman.net.ve:631/printers/CL3005W"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] requesting-user-name="tomman"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] job-name="Documento no guardado 1"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] document-format="application/vnd.cups-pdf"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Adding all operation/job attributes.
D [10/Nov/2014:21:36:26 -04-30] [Job 156] IPP/2.0 Validate-Job #2
D [10/Nov/2014:21:36:26 -04-30] [Job 156] ---- operation-attributes-tag ----
D [10/Nov/2014:21:36:26 -04-30] [Job 156] attributes-charset charset utf-8
D [10/Nov/2014:21:36:26 -04-30] [Job 156] attributes-natural-language naturalLanguage es-ve
D [10/Nov/2014:21:36:26 -04-30] [Job 156] printer-uri uri ipp://saki.tomman.net.ve:631/printers/CL3005W
D [10/Nov/2014:21:36:26 -04-30] [Job 156] requesting-user-name nameWithoutLanguage tomman
D [10/Nov/2014:21:36:26 -04-30] [Job 156] job-name nameWithoutLanguage Documento no guardado 1
D [10/Nov/2014:21:36:26 -04-30] [Job 156] document-format mimeMediaType application/vnd.cups-pdf
D [10/Nov/2014:21:36:26 -04-30] [Job 156] ---- job-attributes-tag ----
D [10/Nov/2014:21:36:26 -04-30] [Job 156] ColorMode nameWithoutLanguage ICM
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Duplex nameWithoutLanguage None
D [10/Nov/2014:21:36:26 -04-30] [Job 156] halftone nameWithoutLanguage as
D [10/Nov/2014:21:36:26 -04-30] [Job 156] ICM nameWithoutLanguage km2530_0
D [10/Nov/2014:21:36:26 -04-30] [Job 156] InputSlot nameWithoutLanguage Auto
D [10/Nov/2014:21:36:26 -04-30] [Job 156] job-originating-host-name nameWithoutLanguage localhost
D [10/Nov/2014:21:36:26 -04-30] [Job 156] job-uuid uri urn:uuid:29214ad9-370a-36b6-5c50-6b0a6f56fbb8
D [10/Nov/2014:21:36:26 -04-30] [Job 156] MediaType nameWithoutLanguage plain
D [10/Nov/2014:21:36:26 -04-30] [Job 156] number-up integer 1
D [10/Nov/2014:21:36:26 -04-30] [Job 156] NupOrient nameWithoutLanguage port
D [10/Nov/2014:21:36:26 -04-30] [Job 156] NupPages nameWithoutLanguage 1up
D [10/Nov/2014:21:36:26 -04-30] [Job 156] PageSize nameWithoutLanguage Letter
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Quality nameWithoutLanguage draft
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Resolution nameWithoutLanguage 600x600dpi
D [10/Nov/2014:21:36:26 -04-30] [Job 156] ---- end-of-attributes-tag ----
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Validate-Job: successful-ok (successful-ok)
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Create-Job IPP/2.0
D [10/Nov/2014:21:36:26 -04-30] [Job 156] printer-uri="ipp://saki.tomman.net.ve:631/printers/CL3005W"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] requesting-user-name="tomman"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] job-name="Documento no guardado 1"
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Adding all operation/job attributes.
D [10/Nov/2014:21:36:26 -04-30] [Job 156] IPP/2.0 Create-Job #4
D [10/Nov/2014:21:36:26 -04-30] [Job 156] ---- operation-attributes-tag ----
D [10/Nov/2014:21:36:26 -04-30] [Job 156] attributes-charset charset utf-8
D [10/Nov/2014:21:36:26 -04-30] [Job 156] attributes-natural-language naturalLanguage es-ve
D [10/Nov/2014:21:36:26 -04-30] [Job 156] printer-uri uri ipp://saki.tomman.net.ve:631/printers/CL3005W
D [10/Nov/2014:21:36:26 -04-30] [Job 156] requesting-user-name nameWithoutLanguage tomman
D [10/Nov/2014:21:36:26 -04-30] [Job 156] job-name nameWithoutLanguage Documento no guardado 1
D [10/Nov/2014:21:36:26 -04-30] [Job 156] ---- job-attributes-tag ----
D [10/Nov/2014:21:36:26 -04-30] [Job 156] ColorMode nameWithoutLanguage ICM
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Duplex nameWithoutLanguage None
D [10/Nov/2014:21:36:26 -04-30] [Job 156] halftone nameWithoutLanguage as
D [10/Nov/2014:21:36:26 -04-30] [Job 156] ICM nameWithoutLanguage km2530_0
D [10/Nov/2014:21:36:26 -04-30] [Job 156] InputSlot nameWithoutLanguage Auto
D [10/Nov/2014:21:36:26 -04-30] [Job 156] job-originating-host-name nameWithoutLanguage localhost
D [10/Nov/2014:21:36:26 -04-30] [Job 156] job-uuid uri urn:uuid:29214ad9-370a-36b6-5c50-6b0a6f56fbb8
D [10/Nov/2014:21:36:26 -04-30] [Job 156] MediaType nameWithoutLanguage plain
D [10/Nov/2014:21:36:26 -04-30] [Job 156] number-up integer 1
D [10/Nov/2014:21:36:26 -04-30] [Job 156] NupOrient nameWithoutLanguage port
D [10/Nov/2014:21:36:26 -04-30] [Job 156] NupPages nameWithoutLanguage 1up
D [10/Nov/2014:21:36:26 -04-30] [Job 156] PageSize nameWithoutLanguage Letter
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Quality nameWithoutLanguage draft
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Resolution nameWithoutLanguage 600x600dpi
D [10/Nov/2014:21:36:26 -04-30] [Job 156] ---- end-of-attributes-tag ----
I [10/Nov/2014:21:36:26 -04-30] [Job 156] Sending data to printer.
D [10/Nov/2014:21:36:26 -04-30] [Job 156] update_reasons(attr=1(none), s="(null)")
d [10/Nov/2014:21:36:26 -04-30] [Job 156] op=' ', new_reasons=0, state_reasons=0
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Get-Printer-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Create-Job: successful-ok (successful-ok)
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Print job accepted - job ID 337.
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Sending file 1 using chunking...
D [10/Nov/2014:21:36:26 -04-30] [Job 156] (monitor) Get-Jobs: successful-ok (successful-ok)
D [10/Nov/2014:21:36:26 -04-30] [Job 156] (monitor) job-state=pending-held
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Send-Document: successful-ok (successful-ok)
I [10/Nov/2014:21:36:26 -04-30] [Job 156] Esperando a que finalice el trabajo.
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Set job-printer-state-message to "Esperando a que finalice el trabajo.", current level=INFO
d [10/Nov/2014:21:36:26 -04-30] cupsdAddEvent(event=job-progress, dest=0x7f1a544f08f0(CL3005Wi), job=0x7f1a545c8ed0(156), text="%s", ...)
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Set job-printer-state-message to "Esperando a que finalice el trabajo.", current level=INFO
D [10/Nov/2014:21:36:26 -04-30] [Job 156] update_reasons(attr=1(none), s="(null)")
d [10/Nov/2014:21:36:26 -04-30] [Job 156] op=' ', new_reasons=0, state_reasons=0
d [10/Nov/2014:21:36:26 -04-30] cupsdAddEvent(event=job-progress, dest=0x7f1a544f08f0(CL3005Wi), job=0x7f1a545c8ed0(156), text="%s", ...)
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Get-Printer-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:26 -04-30] [Job 156] Get-Job-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:26 -04-30] [Job 156] update_reasons(attr=0(), s="+cups-remote-processing")
d [10/Nov/2014:21:36:26 -04-30] [Job 156] op='+', new_reasons=1, state_reasons=0
D [10/Nov/2014:21:36:26 -04-30] [Job 156] STATE: +cups-remote-processing
D [10/Nov/2014:21:36:26 -04-30] [Job 156] PAGE: total 0
d [10/Nov/2014:21:36:26 -04-30] cupsdAddEvent(event=job-progress, dest=0x7f1a544f08f0(CL3005Wi), job=0x7f1a545c8ed0(156), text="Printed %d page(s).", ...)
D [10/Nov/2014:21:36:26 -04-30] Get-Job-Attributes ipp://localhost/jobs/156
d [10/Nov/2014:21:36:26 -04-30] get_job_attrs(0x7f1a545dbab0[16], ipp://localhost/jobs/156)
D [10/Nov/2014:21:36:26 -04-30] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/156) from localhost
D [10/Nov/2014:21:36:26 -04-30] Get-Job-Attributes ipp://localhost/jobs/156
d [10/Nov/2014:21:36:26 -04-30] get_job_attrs(0x7f1a545dbab0[16], ipp://localhost/jobs/156)
D [10/Nov/2014:21:36:26 -04-30] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/156) from localhost
D [10/Nov/2014:21:36:26 -04-30] Get-Job-Attributes ipp://localhost/jobs/156
d [10/Nov/2014:21:36:26 -04-30] get_job_attrs(0x7f1a545dbab0[16], ipp://localhost/jobs/156)
D [10/Nov/2014:21:36:26 -04-30] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/156) from localhost
D [10/Nov/2014:21:36:26 -04-30] Get-Job-Attributes ipp://localhost/jobs/156
d [10/Nov/2014:21:36:26 -04-30] get_job_attrs(0x7f1a545dbab0[16], ipp://localhost/jobs/156)
D [10/Nov/2014:21:36:26 -04-30] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/156) from localhost
D [10/Nov/2014:21:36:26 -04-30] Get-Job-Attributes ipp://localhost/jobs/156
d [10/Nov/2014:21:36:26 -04-30] get_job_attrs(0x7f1a545dbab0[16], ipp://localhost/jobs/156)
D [10/Nov/2014:21:36:26 -04-30] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/156) from localhost
D [10/Nov/2014:21:36:26 -04-30] Get-Job-Attributes ipp://localhost/jobs/156
d [10/Nov/2014:21:36:26 -04-30] get_job_attrs(0x7f1a545dbab0[16], ipp://localhost/jobs/156)
D [10/Nov/2014:21:36:26 -04-30] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/156) from localhost
D [10/Nov/2014:21:36:26 -04-30] Get-Job-Attributes ipp://localhost/jobs/156
d [10/Nov/2014:21:36:26 -04-30] get_job_attrs(0x7f1a545dbab0[16], ipp://localhost/jobs/156)
D [10/Nov/2014:21:36:26 -04-30] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/156) from localhost
I [10/Nov/2014:21:36:27 -04-30] [Job 156] /usr/lib/cups/filter/foomatic-rip failed
D [10/Nov/2014:21:36:27 -04-30] [Job 156] Set job-printer-state-message to "/usr/lib/cups/filter/foomatic-rip failed", current level=INFO
D [10/Nov/2014:21:36:27 -04-30] [Job 156] update_reasons(attr=1(none), s="(null)")
d [10/Nov/2014:21:36:27 -04-30] [Job 156] op=' ', new_reasons=0, state_reasons=1
D [10/Nov/2014:21:36:27 -04-30] [Job 156] Get-Printer-Attributes: successful-ok (successful-ok)
d [10/Nov/2014:21:36:27 -04-30] cupsdAddEvent(event=job-progress, dest=0x7f1a544f08f0(CL3005Wi), job=0x7f1a545c8ed0(156), text="%s", ...)
D [10/Nov/2014:21:36:27 -04-30] [Job 156] (monitor) Get-Job-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:27 -04-30] [Job 156] (monitor) job-state=processing
I [10/Nov/2014:21:36:27 -04-30] [Job 156] /usr/lib/cups/filter/foomatic-rip failed
D [10/Nov/2014:21:36:27 -04-30] [Job 156] update_reasons(attr=1(none), s="(null)")
d [10/Nov/2014:21:36:27 -04-30] [Job 156] op=' ', new_reasons=0, state_reasons=1
D [10/Nov/2014:21:36:27 -04-30] [Job 156] Get-Printer-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:27 -04-30] [Job 156] Get-Job-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:27 -04-30] [Job 156] update_reasons(attr=0(), s="+cups-remote-processing")
d [10/Nov/2014:21:36:27 -04-30] [Job 156] op='+', new_reasons=1, state_reasons=1
D [10/Nov/2014:21:36:27 -04-30] [Job 156] PAGE: total 0
d [10/Nov/2014:21:36:27 -04-30] cupsdAddEvent(event=job-progress, dest=0x7f1a544f08f0(CL3005Wi), job=0x7f1a545c8ed0(156), text="Printed %d page(s).", ...)
D [10/Nov/2014:21:36:27 -04-30] Get-Job-Attributes ipp://localhost/jobs/156
d [10/Nov/2014:21:36:27 -04-30] get_job_attrs(0x7f1a545dbab0[16], ipp://localhost/jobs/156)
D [10/Nov/2014:21:36:27 -04-30] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/156) from localhost
D [10/Nov/2014:21:36:27 -04-30] Get-Job-Attributes ipp://localhost/jobs/156
d [10/Nov/2014:21:36:27 -04-30] get_job_attrs(0x7f1a545dbab0[16], ipp://localhost/jobs/156)
D [10/Nov/2014:21:36:27 -04-30] [Client 16] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/156) from localhost
I [10/Nov/2014:21:36:28 -04-30] [Job 156] /usr/lib/cups/filter/foomatic-rip failed
D [10/Nov/2014:21:36:28 -04-30] [Job 156] update_reasons(attr=1(none), s="(null)")
d [10/Nov/2014:21:36:28 -04-30] [Job 156] op=' ', new_reasons=0, state_reasons=1
D [10/Nov/2014:21:36:28 -04-30] [Job 156] Get-Printer-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:28 -04-30] [Job 156] (monitor) Get-Job-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:28 -04-30] [Job 156] (monitor) job-state=processing
I [10/Nov/2014:21:36:28 -04-30] [Job 156] /usr/lib/cups/filter/foomatic-rip failed
D [10/Nov/2014:21:36:28 -04-30] [Job 156] update_reasons(attr=1(none), s="(null)")
d [10/Nov/2014:21:36:28 -04-30] [Job 156] op=' ', new_reasons=0, state_reasons=1
D [10/Nov/2014:21:36:28 -04-30] [Job 156] Get-Printer-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:28 -04-30] [Job 156] Get-Job-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:28 -04-30] [Job 156] update_reasons(attr=0(), s="+cups-remote-processing")
d [10/Nov/2014:21:36:28 -04-30] [Job 156] op='+', new_reasons=1, state_reasons=1
D [10/Nov/2014:21:36:28 -04-30] [Job 156] PAGE: total 0
d [10/Nov/2014:21:36:28 -04-30] cupsdAddEvent(event=job-progress, dest=0x7f1a544f08f0(CL3005Wi), job=0x7f1a545c8ed0(156), text="Printed %d page(s).", ...)
I [10/Nov/2014:21:36:30 -04-30] [Job 156] /usr/lib/cups/filter/foomatic-rip failed
D [10/Nov/2014:21:36:30 -04-30] [Job 156] update_reasons(attr=1(none), s="(null)")
d [10/Nov/2014:21:36:30 -04-30] [Job 156] op=' ', new_reasons=0, state_reasons=1
D [10/Nov/2014:21:36:30 -04-30] [Job 156] Get-Printer-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:30 -04-30] [Job 156] (monitor) Get-Job-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:30 -04-30] [Job 156] (monitor) job-state=processing
I [10/Nov/2014:21:36:30 -04-30] [Job 156] /usr/lib/cups/filter/foomatic-rip failed
D [10/Nov/2014:21:36:30 -04-30] [Job 156] update_reasons(attr=1(none), s="(null)")
d [10/Nov/2014:21:36:30 -04-30] [Job 156] op=' ', new_reasons=0, state_reasons=1
D [10/Nov/2014:21:36:30 -04-30] [Job 156] Get-Printer-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:30 -04-30] [Job 156] Get-Job-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:30 -04-30] [Job 156] update_reasons(attr=0(), s="+cups-remote-processing")
d [10/Nov/2014:21:36:30 -04-30] [Job 156] op='+', new_reasons=1, state_reasons=1
D [10/Nov/2014:21:36:30 -04-30] [Job 156] PAGE: total 0
d [10/Nov/2014:21:36:30 -04-30] cupsdAddEvent(event=job-progress, dest=0x7f1a544f08f0(CL3005Wi), job=0x7f1a545c8ed0(156), text="Printed %d page(s).", ...)
I [10/Nov/2014:21:36:33 -04-30] [Job 156] /usr/lib/cups/filter/foomatic-rip failed
D [10/Nov/2014:21:36:33 -04-30] [Job 156] update_reasons(attr=1(none), s="(null)")
d [10/Nov/2014:21:36:33 -04-30] [Job 156] op=' ', new_reasons=0, state_reasons=1
d [10/Nov/2014:21:36:33 -04-30] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0, curtime=1415671593
d [10/Nov/2014:21:36:33 -04-30] cupsdCheckJobs: Job 156 - dest="CL3005Wi", printer=0x7f1a544f08f0, state=5, cancel_time=1415682386, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0
D [10/Nov/2014:21:36:33 -04-30] [Job 156] Get-Printer-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:33 -04-30] [Job 156] (monitor) Get-Job-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:33 -04-30] [Job 156] (monitor) job-state=processing
I [10/Nov/2014:21:36:33 -04-30] [Job 156] /usr/lib/cups/filter/foomatic-rip failed
D [10/Nov/2014:21:36:33 -04-30] [Job 156] update_reasons(attr=1(none), s="(null)")
d [10/Nov/2014:21:36:33 -04-30] [Job 156] op=' ', new_reasons=0, state_reasons=1
D [10/Nov/2014:21:36:33 -04-30] [Job 156] Get-Printer-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:33 -04-30] [Job 156] Get-Job-Attributes: successful-ok (successful-ok)
D [10/Nov/2014:21:36:33 -04-30] [Job 156] update_reasons(attr=0(), s="+cups-remote-processing")
d [10/Nov/2014:21:36:33 -04-30] [Job 156] op='+', new_reasons=1, state_reasons=1
D [10/Nov/2014:21:36:33 -04-30] [Job 156] PAGE: total 0
d [10/Nov/2014:21:36:33 -04-30] cupsdAddEvent(event=job-progress, dest=0x7f1a544f08f0(CL3005Wi), job=0x7f1a545c8ed0(156), text="Printed %d page(s).", ...)
--- End Message ---