[Date Prev][Date Next] [Thread Prev][Thread Next] [Date Index] [Thread Index]

Bug#719946: cups: CUPS 1.6 client sends wrong mimetype to 1.5 server, can't print



Package: cups
Version: 1.6.3-1
Severity: important

My printer setup is currently this:

- Server: old Pentium box running Wheezy (CUPS 1.5), and a Delcop CL3005W
(rebadged Konica-Minolta magicolor 1600W) USB printer (driver: foo2lava)
- Clients: a mix of Wheezy, Jessie (CUPS 1.6) and Windows XP/7 laptops. On the
Windows clients, the native Konica-Minolta drivers were used. On the Debian
clients, a local queue was created due to performance issues with the foo2lava
driver on the old Pentium box (expected, as the driver is quite resource-
intensive), so the print data is crunched on the clients, then sent as a raw
print job to the server (which does nothing but to dump it directly to the
printer in its native LAVAFLOW format)

The problem with my Jessie/CUPS 1.6 clients is that they can't print to the
Wheezy/CUPS 1.5 printserver. After enabling debug logging on everything
(including the printserver, a Wheezy client which can print, and a Jessie
client that CAN'T print), I've noticed that while the Wheezy (and Windows)
clients send the print job data as "application/octet-stream" (so the server
does nothing but to directly pipe the data into the printer), while the exact
same data (LAVAFLOW encapsulted into PJL) is sent by the Jessie clients as
"application/vnd.cups-pdf" (the server assumes that it's receiving a PDF... and
obviously fails to rasterize something that it isn't a REAL PDF), which leads
to a aborted print job and a failed foomatic-rip process.

Relevant log entries from the Wheezy print server:

- This Wheezy client can print:
D [16/Aug/2013:22:30:06 -04-30] Create-Job
ipp://saki.tomman.net.ve:631/printers/CL3005W
D [16/Aug/2013:22:30:06 -04-30] cupsdMarkDirty(----J-)
D [16/Aug/2013:22:30:06 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients"
D [16/Aug/2013:22:30:06 -04-30] add_job: requesting-user-name="tomman"
D [16/Aug/2013:22:30:06 -04-30] Adding default job-sheets values "none,none"...
I [16/Aug/2013:22:30:06 -04-30] [Job 169] Adding start banner page "none".
D [16/Aug/2013:22:30:06 -04-30] Discarding unused job-created event...
I [16/Aug/2013:22:30:06 -04-30] [Job 169] Queued on "CL3005W" by "tomman".
D [16/Aug/2013:22:30:06 -04-30] Returning IPP successful-ok for Create-Job
(ipp://saki.tomman.net.ve:631/printers/CL3005W) from 192.168.0.101
D [16/Aug/2013:22:30:06 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [16/Aug/2013:22:30:06 -04-30] cupsdReadClient: 16 POST /printers/CL3005W
HTTP/1.1
D [16/Aug/2013:22:30:06 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [16/Aug/2013:22:30:06 -04-30] cupsdAuthorize: No authentication data
provided.
D [16/Aug/2013:22:30:06 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [16/Aug/2013:22:30:06 -04-30] cupsdReadClient: 15 POST /printers/CL3005W
HTTP/1.1
D [16/Aug/2013:22:30:06 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [16/Aug/2013:22:30:06 -04-30] cupsdAuthorize: No authentication data
provided.
D [16/Aug/2013:22:30:06 -04-30] cupsdReadClient: 15 2.0 Send-Document 1
D [16/Aug/2013:22:30:06 -04-30] Send-Document
ipp://saki.tomman.net.ve:631/printers/CL3005W
D [16/Aug/2013:22:30:06 -04-30] cupsdIsAuthorized: requesting-user-
name="tomman"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Auto-typing file...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Request file type is application
/octet-stream.
D [16/Aug/2013:22:30:06 -04-30] cupsdMarkDirty(----J-)
D [16/Aug/2013:22:30:06 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
I [16/Aug/2013:22:30:06 -04-30] [Job 169] File of type application/octet-stream
queued by "tomman".
I [16/Aug/2013:22:30:06 -04-30] [Job 169] Adding end banner page "none".
D [16/Aug/2013:22:30:06 -04-30] cupsdMarkDirty(----J-)
D [16/Aug/2013:22:30:06 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [16/Aug/2013:22:30:06 -04-30] cupsdMarkDirty(----J-)
D [16/Aug/2013:22:30:06 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [16/Aug/2013:22:30:06 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [16/Aug/2013:22:30:06 -04-30] Discarding unused printer-state-changed
event...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] job-sheets=none,none
D [16/Aug/2013:22:30:06 -04-30] [Job 169] argv[0]="CL3005W"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] argv[1]="169"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] argv[2]="tomman"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] argv[3]="Historial - PayPal"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] argv[4]="1"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] argv[5]="job-uuid=urn:uuid:0510e2dd-
4a0d-3fda-558c-ea1251b91f2e job-originating-host-name=192.168.0.101 time-at-
creation=1376708406 time-at-processing=1376708406"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] argv[6]="/var/spool/cups/d00169-001"
D [16/Aug/2013:22:30:06 -04-30] [Job 169]
envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [16/Aug/2013:22:30:06 -04-30] [Job 169]
envp[1]="CUPS_DATADIR=/usr/share/cups"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[2]="CUPS_DOCROOT=/usr/share/cups
/doc-root"
D [16/Aug/2013:22:30:06 -04-30] [Job 169]
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [16/Aug/2013:22:30:06 -04-30] [Job 169]
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [16/Aug/2013:22:30:06 -04-30] [Job 169]
envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[8]="HOME=/var/spool/cups/tmp"
D [16/Aug/2013:22:30:06 -04-30] [Job 169]
envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[10]="SERVER_ADMIN=root@saki"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[11]="SOFTWARE=CUPS/1.5.3"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[13]="TZ=America/Caracas"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[14]="USER=root"
D [16/Aug/2013:22:30:06 -04-30] [Job 169]
envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [16/Aug/2013:22:30:06 -04-30] [Job 169]
envp[16]="CUPS_ENCRYPTION=IfRequested"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[17]="IPP_PORT=631"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[18]="CHARSET=utf-8"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[19]="LANG=es_VE.UTF-8"
D [16/Aug/2013:22:30:06 -04-30] [Job 169]
envp[20]="PPD=/etc/cups/ppd/CL3005W.ppd"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[21]="RIP_MAX_CACHE=128m"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[22]="CONTENT_TYPE=application
/octet-stream"
D [16/Aug/2013:22:30:06 -04-30] [Job 169]
envp[23]="DEVICE_URI=usb://KONICA%20MINOLTA/mc1600W?serial=06796"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[24]="PRINTER_INFO=DELCOP
CL3005W"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[25]="PRINTER_LOCATION=TSDX Base
ETG"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[26]="PRINTER=CL3005W"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[27]="PRINTER_STATE_REASONS=none"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[28]="CUPS_FILETYPE=document"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] envp[29]="AUTH_I****"
I [16/Aug/2013:22:30:06 -04-30] [Job 169] Started backend
/usr/lib/cups/backend/usb (PID 3890)
D [16/Aug/2013:22:30:06 -04-30] Discarding unused job-state-changed event...
D [16/Aug/2013:22:30:06 -04-30] Returning IPP successful-ok for Send-Document
(ipp://saki.tomman.net.ve:631/printers/CL3005W) from 192.168.0.101
D [16/Aug/2013:22:30:06 -04-30] cupsdReadClient: 16 2.0 Get-Jobs 1
D [16/Aug/2013:22:30:06 -04-30] Get-Jobs
ipp://saki.tomman.net.ve:631/printers/CL3005W
D [16/Aug/2013:22:30:06 -04-30] Returning IPP successful-ok for Get-Jobs
(ipp://saki.tomman.net.ve:631/printers/CL3005W) from 192.168.0.101
D [16/Aug/2013:22:30:06 -04-30] cupsdSetBusyState: newbusy="Active clients,
printing jobs, and dirty files", busy="Active clients and dirty files"
D [16/Aug/2013:22:30:06 -04-30] cupsdReadClient: 15 WAITING Closing on EOF
D [16/Aug/2013:22:30:06 -04-30] cupsdCloseClient: 15
D [16/Aug/2013:22:30:06 -04-30] cupsdSetBusyState: newbusy="Active clients,
printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty
files"
D [16/Aug/2013:22:30:06 -04-30] cupsdAcceptClient: 15 from 192.168.0.101:631
(IPv4)
D [16/Aug/2013:22:30:06 -04-30] cupsdReadClient: 15 POST /printers/CL3005W
HTTP/1.1
D [16/Aug/2013:22:30:06 -04-30] cupsdSetBusyState: newbusy="Active clients,
printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty
files"
D [16/Aug/2013:22:30:06 -04-30] cupsdAuthorize: No authentication data
provided.
D [16/Aug/2013:22:30:06 -04-30] cupsdSetBusyState: newbusy="Active clients,
printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty
files"
D [16/Aug/2013:22:30:06 -04-30] cupsdReadClient: 16 WAITING Closing on EOF
D [16/Aug/2013:22:30:06 -04-30] cupsdCloseClient: 16
D [16/Aug/2013:22:30:06 -04-30] cupsdSetBusyState: newbusy="Active clients,
printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty
files"
D [16/Aug/2013:22:30:06 -04-30] cupsdReadClient: 15 2.0 Get-Job-Attributes 1
D [16/Aug/2013:22:30:06 -04-30] Get-Job-Attributes
ipp://saki.tomman.net.ve:631/printers/CL3005W
D [16/Aug/2013:22:30:06 -04-30] Returning IPP successful-ok for Get-Job-
Attributes (ipp://saki.tomman.net.ve:631/printers/CL3005W) from 192.168.0.101
D [16/Aug/2013:22:30:06 -04-30] cupsdSetBusyState: newbusy="Printing jobs and
dirty files", busy="Active clients, printing jobs, and dirty files"
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Printing on printer with URI:
usb://KONICA%20MINOLTA/mc1600W?serial=06796
D [16/Aug/2013:22:30:06 -04-30] [Job 169] libusb_get_device_list=5
D [16/Aug/2013:22:30:06 -04-30] [Job 169] STATE: +connecting-to-device
D [16/Aug/2013:22:30:06 -04-30] Discarding unused printer-state-changed
event...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] STATE: -connecting-to-device
D [16/Aug/2013:22:30:06 -04-30] Discarding unused printer-state-changed
event...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Device protocol: 2
I [16/Aug/2013:22:30:06 -04-30] [Job 169] Enviando datos a la impresora.
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Set job-printer-state-message to
"Enviando datos a la impresora.", current level=INFO
D [16/Aug/2013:22:30:06 -04-30] Discarding unused job-progress event...
D [16/Aug/2013:22:30:06 -04-30] Discarding unused printer-state-changed
event...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] PAGE: 1 1
D [16/Aug/2013:22:30:06 -04-30] Discarding unused job-progress event...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Read 8192 bytes of print data...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Wrote 8192 bytes of print data...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Sending print file, 8192 bytes...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Read 8192 bytes of print data...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Wrote 8192 bytes of print data...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Sending print file, 16384 bytes...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Read 8192 bytes of print data...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Wrote 8192 bytes of print data...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Sending print file, 24576 bytes...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Read 8192 bytes of print data...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Wrote 8192 bytes of print data...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Sending print file, 32768 bytes...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Read 8192 bytes of print data...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Wrote 8192 bytes of print data...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Sending print file, 40960 bytes...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Read 8192 bytes of print data...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Wrote 8192 bytes of print data...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Sending print file, 49152 bytes...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Read 511 bytes of print data...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Wrote 511 bytes of print data...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Sending print file, 49663 bytes...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Sent 49663 bytes...
D [16/Aug/2013:22:30:06 -04-30] [Job 169] Waiting for read thread to exit...


- On the other side, this Jessie client can't print at all on the same server
D [16/Aug/2013:22:38:33 -04-30] Create-Job
ipp://saki.tomman.net.ve:631/printers/CL3005W
D [16/Aug/2013:22:38:33 -04-30] cupsdMarkDirty(----J-)
D [16/Aug/2013:22:38:33 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients"
D [16/Aug/2013:22:38:33 -04-30] add_job: requesting-user-name="tomman"
D [16/Aug/2013:22:38:33 -04-30] Adding default job-sheets values "none,none"...
I [16/Aug/2013:22:38:33 -04-30] [Job 171] Adding start banner page "none".
D [16/Aug/2013:22:38:33 -04-30] Discarding unused job-created event...
I [16/Aug/2013:22:38:33 -04-30] [Job 171] Queued on "CL3005W" by "tomman".
D [16/Aug/2013:22:38:33 -04-30] Returning IPP successful-ok for Create-Job
(ipp://saki.tomman.net.ve:631/printers/CL3005W) from 192.168.0.102
D [16/Aug/2013:22:38:33 -04-30] cupsdReadClient: 16 2.0 Get-Jobs 5
D [16/Aug/2013:22:38:33 -04-30] Get-Jobs
ipp://saki.tomman.net.ve:631/printers/CL3005W
D [16/Aug/2013:22:38:33 -04-30] Returning IPP successful-ok for Get-Jobs
(ipp://saki.tomman.net.ve:631/printers/CL3005W) from 192.168.0.102
D [16/Aug/2013:22:38:33 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [16/Aug/2013:22:38:33 -04-30] cupsdReadClient: 15 POST /printers/CL3005W
HTTP/1.1
D [16/Aug/2013:22:38:33 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [16/Aug/2013:22:38:33 -04-30] cupsdAuthorize: No authentication data
provided.
D [16/Aug/2013:22:38:33 -04-30] cupsdReadClient: 15 2.0 Send-Document 6
D [16/Aug/2013:22:38:33 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [16/Aug/2013:22:38:33 -04-30] cupsdReadClient: 16 WAITING Closing on EOF
D [16/Aug/2013:22:38:33 -04-30] cupsdCloseClient: 16
D [16/Aug/2013:22:38:33 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [16/Aug/2013:22:38:33 -04-30] Send-Document
ipp://saki.tomman.net.ve:631/printers/CL3005W
D [16/Aug/2013:22:38:33 -04-30] cupsdIsAuthorized: requesting-user-
name="tomman"
D [16/Aug/2013:22:38:33 -04-30] cupsdMarkDirty(----J-)
D [16/Aug/2013:22:38:33 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
I [16/Aug/2013:22:38:33 -04-30] [Job 171] File of type application/vnd.cups-pdf
queued by "tomman".
I [16/Aug/2013:22:38:33 -04-30] [Job 171] Adding end banner page "none".
D [16/Aug/2013:22:38:33 -04-30] cupsdMarkDirty(----J-)
D [16/Aug/2013:22:38:33 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [16/Aug/2013:22:38:33 -04-30] cupsdMarkDirty(----J-)
D [16/Aug/2013:22:38:33 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [16/Aug/2013:22:38:33 -04-30] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [16/Aug/2013:22:38:33 -04-30] Discarding unused printer-state-changed
event...
D [16/Aug/2013:22:38:33 -04-30] [Job 171] job-sheets=none,none
D [16/Aug/2013:22:38:33 -04-30] [Job 171] argv[0]="CL3005W"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] argv[1]="171"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] argv[2]="tomman"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] argv[3]="Historial - PayPal"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] argv[4]="1"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] argv[5]="ColorMode=ICM Duplex=None
halftone=default ICM=km1600-default InputSlot=Auto job-originating-host-
name=192.168.0.102 job-uuid=urn:uuid:12b31394-66df-3d2c-59e5-01e5dd79517c
MediaType=plain number-up=1 NupOrient=port NupPages=1up PageSize=Letter
Quality=draft Resolution=600x600dpi time-at-creation=1376708913 time-at-
processing=1376708913"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] argv[6]="/var/spool/cups/d00171-001"
D [16/Aug/2013:22:38:33 -04-30] [Job 171]
envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [16/Aug/2013:22:38:33 -04-30] [Job 171]
envp[1]="CUPS_DATADIR=/usr/share/cups"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[2]="CUPS_DOCROOT=/usr/share/cups
/doc-root"
D [16/Aug/2013:22:38:33 -04-30] [Job 171]
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [16/Aug/2013:22:38:33 -04-30] [Job 171]
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [16/Aug/2013:22:38:33 -04-30] [Job 171]
envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[8]="HOME=/var/spool/cups/tmp"
D [16/Aug/2013:22:38:33 -04-30] [Job 171]
envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[10]="SERVER_ADMIN=root@saki"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[11]="SOFTWARE=CUPS/1.5.3"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[13]="TZ=America/Caracas"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[14]="USER=root"
D [16/Aug/2013:22:38:33 -04-30] [Job 171]
envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [16/Aug/2013:22:38:33 -04-30] [Job 171]
envp[16]="CUPS_ENCRYPTION=IfRequested"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[17]="IPP_PORT=631"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[18]="CHARSET=utf-8"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[19]="LANG=es_VE.UTF-8"
D [16/Aug/2013:22:38:33 -04-30] [Job 171]
envp[20]="PPD=/etc/cups/ppd/CL3005W.ppd"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[21]="RIP_MAX_CACHE=128m"
D [16/Aug/2013:22:38:33 -04-30] [Job 171]
envp[22]="CONTENT_TYPE=application/vnd.cups-pdf"
D [16/Aug/2013:22:38:33 -04-30] [Job 171]
envp[23]="DEVICE_URI=usb://KONICA%20MINOLTA/mc1600W?serial=06796"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[24]="PRINTER_INFO=DELCOP
CL3005W"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[25]="PRINTER_LOCATION=TSDX Base
ETG"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[26]="PRINTER=CL3005W"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[27]="PRINTER_STATE_REASONS=none"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[28]="CUPS_FILETYPE=document"
D [16/Aug/2013:22:38:33 -04-30] [Job 171]
envp[29]="FINAL_CONTENT_TYPE=printer/CL3005W"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] envp[30]="AUTH_I****"
I [16/Aug/2013:22:38:33 -04-30] [Job 171] Started filter /usr/lib/cups/filter
/foomatic-rip (PID 3923)
I [16/Aug/2013:22:38:33 -04-30] [Job 171] Started backend
/usr/lib/cups/backend/usb (PID 3924)
D [16/Aug/2013:22:38:33 -04-30] Discarding unused job-state-changed event...
D [16/Aug/2013:22:38:33 -04-30] Returning IPP successful-ok for Send-Document
(ipp://saki.tomman.net.ve:631/printers/CL3005W) from 192.168.0.102
D [16/Aug/2013:22:38:33 -04-30] cupsdSetBusyState: newbusy="Printing jobs and
dirty files", busy="Active clients and dirty files"
D [16/Aug/2013:22:38:33 -04-30] cupsdReadClient: 15 WAITING Closing on EOF
D [16/Aug/2013:22:38:33 -04-30] cupsdCloseClient: 15
D [16/Aug/2013:22:38:33 -04-30] cupsdSetBusyState: newbusy="Printing jobs and
dirty files", busy="Printing jobs and dirty files"
D [16/Aug/2013:22:38:33 -04-30] cupsdAcceptClient: 15 from 192.168.0.102:631
(IPv4)
D [16/Aug/2013:22:38:33 -04-30] cupsdReadClient: 15 POST /printers/CL3005W
HTTP/1.1
D [16/Aug/2013:22:38:33 -04-30] cupsdSetBusyState: newbusy="Active clients,
printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [16/Aug/2013:22:38:33 -04-30] cupsdAuthorize: No authentication data
provided.
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Getting input from file
/var/spool/cups/d00171-001
D [16/Aug/2013:22:38:33 -04-30] cupsdReadClient: 15 2.0 Get-Job-Attributes 7
D [16/Aug/2013:22:38:33 -04-30] Get-Job-Attributes
ipp://saki.tomman.net.ve:631/printers/CL3005W
D [16/Aug/2013:22:38:33 -04-30] Returning IPP successful-ok for Get-Job-
Attributes (ipp://saki.tomman.net.ve:631/printers/CL3005W) from 192.168.0.102
D [16/Aug/2013:22:38:33 -04-30] [Job 171] foomatic-rip version 4.0.17.256
running...
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Parsing PPD file ...
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Added option ColorSpace
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Added option PageSize
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Added option Quality
D [16/Aug/2013:22:38:33 -04-30] cupsdSetBusyState: newbusy="Printing jobs and
dirty files", busy="Active clients, printing jobs, and dirty files"
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Added option PrinterType
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Added option ColorMode
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Added option Resolution
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Printing on printer with URI:
usb://KONICA%20MINOLTA/mc1600W?serial=06796
D [16/Aug/2013:22:38:33 -04-30] [Job 171] libusb_get_device_list=5
D [16/Aug/2013:22:38:33 -04-30] [Job 171] STATE: +connecting-to-device
D [16/Aug/2013:22:38:33 -04-30] Discarding unused printer-state-changed
event...
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Added option ImageableArea
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Added option PaperDimension
D [16/Aug/2013:22:38:33 -04-30] [Job 171] STATE: -connecting-to-device
D [16/Aug/2013:22:38:33 -04-30] Discarding unused printer-state-changed
event...
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Added option InputSlot
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Added option MediaType
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Device protocol: 2
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Added option Duplex
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Added option Copies
I [16/Aug/2013:22:38:33 -04-30] [Job 171] Enviando datos a la impresora.
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Set job-printer-state-message to
"Enviando datos a la impresora.", current level=INFO
D [16/Aug/2013:22:38:33 -04-30] Discarding unused job-progress event...
D [16/Aug/2013:22:38:33 -04-30] Discarding unused printer-state-changed
event...
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Added option halftone
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Added option ICM
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Added option NupOrient
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Added option NupPages
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Added option Font
D [16/Aug/2013:22:38:33 -04-30] [Job 171]
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Parameter Summary
D [16/Aug/2013:22:38:33 -04-30] [Job 171] -----------------
D [16/Aug/2013:22:38:33 -04-30] [Job 171]
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Spooler: cups
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Printer: CL3005W
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Shell: /bin/bash
D [16/Aug/2013:22:38:33 -04-30] [Job 171] PPD file: /etc/cups/ppd/CL3005W.ppd
D [16/Aug/2013:22:38:33 -04-30] [Job 171] ATTR file:
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Printer model: KONICA MINOLTA
magicolor 1600W Foomatic/foo2lava (recommended)
D [16/Aug/2013:22:38:33 -04-30] [Job 171] Job title: Historial - PayPal
D [16/Aug/2013:22:38:33 -04-30] [Job 171] File(s) to be printed:
D [16/Aug/2013:22:38:33 -04-30] [Job 171] /var/spool/cups/d00171-001
D [16/Aug/2013:22:38:33 -04-30] [Job 171]
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Ghostscript extra search path
('GS_LIB'): /usr/share/cups/fonts
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Printing system options:
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Pondering option 'job-originating-
host-name=192.168.0.102'
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Unknown option job-originating-host-
name=192.168.0.102.
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Pondering option 'job-
uuid=urn:uuid:12b31394-66df-3d2c-59e5-01e5dd79517c'
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Unknown option job-
uuid=urn:uuid:12b31394-66df-3d2c-59e5-01e5dd79517c.
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Pondering option 'number-up=1'
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Unknown option number-up=1.
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Pondering option 'time-at-
creation=1376708913'
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Unknown option time-at-
creation=1376708913.
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Pondering option 'time-at-
processing=1376708913'
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Unknown option time-at-
processing=1376708913.
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Options from the PPD file:
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Pondering option 'ColorMode=ICM'
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Pondering option 'Duplex=None'
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Pondering option 'halftone=default'
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Pondering option 'ICM=km1600-default'
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Pondering option 'InputSlot=Auto'
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Pondering option 'MediaType=plain'
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Pondering option 'NupOrient=port'
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Pondering option 'NupPages=1up'
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Pondering option 'PageSize=Letter'
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Pondering option 'Quality=draft'
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Pondering option
'Resolution=600x600dpi'
D [16/Aug/2013:22:38:34 -04-30] [Job 171]
D [16/Aug/2013:22:38:34 -04-30] [Job 171]
================================================
D [16/Aug/2013:22:38:34 -04-30] [Job 171]
D [16/Aug/2013:22:38:34 -04-30] [Job 171] File: /var/spool/cups/d00171-001
D [16/Aug/2013:22:38:34 -04-30] [Job 171]
D [16/Aug/2013:22:38:34 -04-30] [Job 171]
================================================
D [16/Aug/2013:22:38:34 -04-30] [Job 171]
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Cannot process
"/var/spool/cups/d00171-001": Unknown filetype.
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Process is dying with "Could not
print file /var/spool/cups/d00171-001
D [16/Aug/2013:22:38:34 -04-30] [Job 171] ", exit stat 2
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Cleaning up...
D [16/Aug/2013:22:38:34 -04-30] PID 3923 (/usr/lib/cups/filter/foomatic-rip)
stopped with status 2.
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Sent 0 bytes...
D [16/Aug/2013:22:38:34 -04-30] [Job 171] Waiting for read thread to exit...


Sadly this means that my Jessie hosts are useless for printing. Even the
quintessential CUPS test page fails on these!



-- System Information:
Debian Release: jessie/sid
  APT prefers testing
  APT policy: (990, 'testing'), (1, 'experimental')
Architecture: amd64 (x86_64)
Foreign Architectures: i386

Kernel: Linux 3.10-2-amd64 (SMP w/4 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.6.3-1
ii  cups-common            1.6.3-1
ii  cups-daemon            1.6.3-1
ii  cups-filters           1.0.34-3+b1
ii  cups-ppdc              1.6.3-1
ii  cups-server-common     1.6.3-1
ii  debconf [debconf-2.0]  1.5.50
ii  ghostscript            9.05~dfsg-8
ii  libavahi-client3       0.6.31-2
ii  libavahi-common3       0.6.31-2
ii  libc-bin               2.17-92
ii  libc6                  2.17-92
ii  libcups2               1.6.3-1
ii  libcupscgi1            1.6.3-1
ii  libcupsimage2          1.6.3-1
ii  libcupsmime1           1.6.3-1
ii  libcupsppdc1           1.6.3-1
ii  libgcc1                1:4.8.1-2
ii  libstdc++6             4.8.1-2
ii  libusb-1.0-0           2:1.0.16-3
ii  lsb-base               4.1+Debian12
ii  poppler-utils          0.18.4-6
ii  procps                 1:3.3.4-2

Versions of packages cups recommends:
ii  avahi-daemon               0.6.31-2
ii  colord                     1.0.2-1
ii  foomatic-filters           4.0.17-1
ii  ghostscript-cups           9.05~dfsg-8
ii  printer-driver-gutenprint  5.2.9-1

Versions of packages cups suggests:
ii  cups-bsd                                   1.6.3-1
pn  cups-pdf                                   <none>
ii  foomatic-db-compressed-ppds [foomatic-db]  20130609-1
ii  hplip                                      3.13.4-1+b1
ii  printer-driver-hpcups                      3.13.4-1+b1
ii  smbclient                                  2:3.6.17-1
ii  udev                                       175-7.2

-- debconf information:
  cupsys/raw-print: true
  cupsys/backend: lpd, socket, usb, snmp, dnssd


Reply to: