Bug#719946: marked as done (cups: CUPS 1.6 client sends wrong mimetype to 1.5 server, can't print)

To: Didier Raboud <odyx@debian.org>
Subject: Bug#719946: marked as done (cups: CUPS 1.6 client sends wrong mimetype to 1.5 server, can't print)
From: owner@bugs.debian.org (Debian Bug Tracking System)
Date: Thu, 05 Dec 2013 20:09:13 +0000
Message-id: <[🔎] handler.719946.D719946.138627408927687.ackdone@bugs.debian.org>
References: <fcab5688da49ff2cf80b657e7b97c01b@raboud.com> <20130817035844.14258.23149.reportbug@himawari.tomman.net.ve>

Your message dated Thu, 05 Dec 2013 09:29:41 +0100
with message-id <fcab5688da49ff2cf80b657e7b97c01b@raboud.com>
and subject line Re: Bug#719946: cups: CUPS 1.6 client sends wrong mimetype to 1.5 server, can't print
has caused the Debian Bug report #719946,
regarding cups: CUPS 1.6 client sends wrong mimetype to 1.5 server, can't print
to be marked as done.

This means that you claim that the problem has been dealt with.
If this is not the case it is now your responsibility to reopen the
Bug report if necessary, and/or fix the problem forthwith.

(NB: If you are a system administrator and have no idea what this
message is talking about, this may indicate a serious mail system
misconfiguration somewhere. Please contact owner@bugs.debian.org
immediately.)


-- 
719946: http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=719946
Debian Bug Tracking System
Contact owner@bugs.debian.org with problems

--- Begin Message ---

To: Debian Bug Tracking System <submit@bugs.debian.org>
Subject: cups: CUPS 1.6 client sends wrong mimetype to 1.5 server, can't print
From: Tom Maneiro <tomman@tsdx.net.ve>
Date: Fri, 16 Aug 2013 23:28:44 -0430
Message-id: <20130817035844.14258.23149.reportbug@himawari.tomman.net.ve>

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

--- End Message ---

--- Begin Message ---

To: Tom Maneiro <tomman@tsdx.net.ve>, 719946-done@bugs.debian.org

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

From: Didier Raboud <odyx@debian.org>

Date: Thu, 05 Dec 2013 09:29:41 +0100

Message-id: <fcab5688da49ff2cf80b657e7b97c01b@raboud.com>

In-reply-to: <[🔎] 20131205020226.21206.19668.reportbug@himawari.tomman.net.ve>

References: <[🔎] 20131205020226.21206.19668.reportbug@himawari.tomman.net.ve>
Version: 1.6.4-2

Le 2013-12-05 03:02, Tom Maneiro a écrit :
Looks like that bugfix did the job. After the update, I can now printagainjust like it used to be in CUPS 1.5 - crunch the data in the host, sendit to
the server, which will pipe it to the printer as intended.
Great. That looks like your bug is solved then; hereby closing this bugas fixed in 1.6.4-2.
Cheers, OdyX
--- End Message ---

Reply to:

Prev by Date: Moved cups-filters packaging to git
Next by Date: Re: Released cups-filters 1.0.42, updated Debian package BZR
Previous by thread: Bug#719946: marked as done (cups: CUPS 1.6 client sends wrong mimetype to 1.5 server, can't print)
Next by thread: Moved cups-filters packaging to git
Index(es):
- Date
- Thread