[cups.bugs] [HIGH] STR #3201: cups 1.3.10 refuses to accept files from clients with older (1.3.6) cups

Mon May 18 08:48:10 PDT 2009

DO NOT REPLY TO THIS MESSAGE.  INSTEAD, POST ANY RESPONSES TO THE LINK BELOW.

[STR New]

Cups 1.3.10 (on Mandriva 2009.1)  refuses to accept print files from older
versions of cups. 
I have a machine which is my print server ( and has been for many years)
which suddenly ceased accepting print files from the other clients when I
upgraded it. 
In /var/log/cups/error_log, was a line
D [16/May/2009:15:15:38 -0700] Print-Job client-error-bad-request: Bad
job-sheets value "none,none"!
and the cups would refuse to accept the print job from the client.
The job had the JobSheets  none,none entry in it, which the older versions
of cups set up from the server information. 
This meant that none of the older clients could print to the 1.3.10 cups
server. ( they were running 1.3.6 on Mandriva 2008.1)

If I upgraded the clients to 1.3.10 version of cups, suddenly printing
worked again with no change on the server (in this case the clients were
sending a job control with JobSheets  none  none ). 

Since I have no control over a number of the clients which use my server,
upgrading them all is simply not in the cards. Making cups incompatible
with older versions of itself as you appear to have done is extremely bad.

Full error report from error_log on the server.

------------------------------------------
D [16/May/2009:15:15:38 -0700] cupsdAcceptClient: 10 from
142.103.234.23:631 (IPv4)
D [16/May/2009:15:15:38 -0700] cupsdReadClient: 10 POST /printers/hp311d
HTTP/1.1
D [16/May/2009:15:15:38 -0700] cupsdAuthorize: No authentication data
provided.
D [16/May/2009:15:15:38 -0700] Get-Printer-Attributes
ipp://monopole.physics.ubc.ca:631/printers/hp311d
D [16/May/2009:15:15:38 -0700] cupsdProcessIPPRequest: 10 status_code=0
(successful-ok)
D [16/May/2009:15:15:38 -0700] cupsdReadClient: 10 POST /printers/hp311d
HTTP/1.1
D [16/May/2009:15:15:38 -0700] cupsdAuthorize: No authentication data
provided.
D [16/May/2009:15:15:38 -0700] Print-Job
ipp://monopole.physics.ubc.ca:631/printers/hp311d
D [16/May/2009:15:15:38 -0700] Print-Job client-error-bad-request: Bad
job-sheets value "none,none"!
D [16/May/2009:15:15:38 -0700] cupsdProcessIPPRequest: 10 status_code=400
(client-error-bad-request)
D [16/May/2009:15:15:38 -0700] cupsdCloseClient: 10

-----------------------------------------------------------

>From access_log on the server (monopole.physics.ubc.ca)

----------------------------------------------------
142.103.234.23 - - [16/May/2009:15:15:38 -0700] "POST /printers/hp311d
HTTP/1.1" 200 311 Get-Printer-Attributes successful-ok
142.103.234.23 - - [16/May/2009:15:15:38 -0700] "POST /printers/hp311d
HTTP/1.1" 200 481 Print-Job client-error-bad-request
142.103.234.23 - - [16/May/2009:15:15:38 -0700] "POST /printers/hp311d
HTTP/1.1" 200 241 Get-Printer-Attributes successful-ok
--------------------------------------------------------

And from the error_log on the client machine( 142.103.234.23)

---------------------------------------------------------------
D [16/May/2009:15:15:37 -0700] cupsdAcceptClient: 9 from localhost
(Domain)
D [16/May/2009:15:15:37 -0700] cupsdReadClient: 9 POST /printers/hp311d
HTTP/1.1
D [16/May/2009:15:15:37 -0700] cupsdAuthorize: No authentication data
provided.
D [16/May/2009:15:15:37 -0700] Print-Job ipp://localhost/printers/hp311d
D [16/May/2009:15:15:37 -0700] print_job: auto-typing file...
D [16/May/2009:15:15:37 -0700] add_job: requesting-user-name="root"
D [16/May/2009:15:15:37 -0700] Discarding unused job-created event...
I [16/May/2009:15:15:37 -0700] [Job 228] Adding job file of type
text/plain.
I [16/May/2009:15:15:37 -0700] [Job 228] Queued on "hp311d" by "root".
D [16/May/2009:15:15:37 -0700] [Job 228] hold_until = 0
D [16/May/2009:15:15:37 -0700] [Job 228] Sending job to queue tagged as
raw...
D [16/May/2009:15:15:37 -0700] [Job 228] No job-sheets attribute.
D [16/May/2009:15:15:37 -0700] [Job 228] ... but someone added one without
setting job_sheets!
D [16/May/2009:15:15:37 -0700] [Job 228] banner_page = 0
D [16/May/2009:15:15:37 -0700] [Job 228] argv[0]="hp311d"
D [16/May/2009:15:15:37 -0700] [Job 228] argv[1]="228"
D [16/May/2009:15:15:37 -0700] [Job 228] argv[2]="root"
D [16/May/2009:15:15:37 -0700] [Job 228] argv[3]="STDIN"
D [16/May/2009:15:15:37 -0700] [Job 228] argv[4]="1"
D [16/May/2009:15:15:37 -0700] [Job 228]
argv[5]="sides=two-sided-long-edge job-hold-until=no-hold job-priority=50
number-up=1 job-uuid=urn:uuid:6a22affe-4a9c-3916-5383-63acc8de4fab
job-sheets=none,none lease-duration=300"
D [16/May/2009:15:15:37 -0700] [Job 228]
argv[6]="/var/spool/cups/d00228-001"
D [16/May/2009:15:15:37 -0700] [Job 228]
envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [16/May/2009:15:15:37 -0700] [Job 228]
envp[1]="CUPS_DATADIR=/usr/share/cups"
D [16/May/2009:15:15:37 -0700] [Job 228]
envp[2]="CUPS_DOCROOT=/usr/share/cups/doc"
D [16/May/2009:15:15:37 -0700] [Job 228]
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [16/May/2009:15:15:37 -0700] [Job 228]
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [16/May/2009:15:15:37 -0700] [Job 228]
envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [16/May/2009:15:15:37 -0700] [Job 228]
envp[6]="CUPS_SERVERROOT=/etc/cups"
D [16/May/2009:15:15:37 -0700] [Job 228]
envp[7]="CUPS_STATEDIR=/var/run/cups"
D [16/May/2009:15:15:37 -0700] [Job 228]
envp[8]="NLSPATH=/usr/share/locale/%l/%N"D [16/May/2009:15:15:37 -0700]
[Job 228]
envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [16/May/2009:15:15:37 -0700] [Job 228] envp[10]="SERVER_ADMIN=root at info"
D [16/May/2009:15:15:37 -0700] [Job 228] envp[11]="SOFTWARE=CUPS/1.3.6"
D [16/May/2009:15:15:37 -0700] [Job 228]
envp[12]="TMPDIR=/var/spool/cups/tmp"
D [16/May/2009:15:15:37 -0700] [Job 228] envp[13]="USER=root"
D [16/May/2009:15:15:37 -0700] [Job 228]
envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [16/May/2009:15:15:37 -0700] [Job 228]
envp[15]="CUPS_ENCRYPTION=IfRequested"
D [16/May/2009:15:15:37 -0700] [Job 228] envp[16]="IPP_PORT=631"
D [16/May/2009:15:15:37 -0700] [Job 228] envp[17]="CHARSET=utf-8"
D [16/May/2009:15:15:37 -0700] [Job 228] envp[18]="LANG=en_CA.UTF8"
D [16/May/2009:15:15:37 -0700] [Job 228]
envp[19]="PPD=/etc/cups/ppd/hp311d.ppd"
D [16/May/2009:15:15:37 -0700] [Job 228] envp[20]="RIP_MAX_CACHE=8m"
D [16/May/2009:15:15:37 -0700] [Job 228]
envp[21]="CONTENT_TYPE=text/plain"
D [16/May/2009:15:15:37 -0700] [Job 228]
envp[22]="DEVICE_URI=ipp://monopole.physics.ubc.ca:631/printers/hp311d"
D [16/May/2009:15:15:37 -0700] [Job 228] envp[23]="PRINTER=hp311d"
I [16/May/2009:15:15:37 -0700] [Job 228] Started backend
/usr/lib/cups/backend/ipp (PID 7024)
D [16/May/2009:15:15:37 -0700] Discarding unused job-state-changed
event...
D [16/May/2009:15:15:37 -0700] cupsdProcessIPPRequest: 9 status_code=0
(successful-ok)
D [16/May/2009:15:15:37 -0700] cupsdCloseClient: 9
D [16/May/2009:15:15:37 -0700] [Job 228] 1 files to send in job...
D [16/May/2009:15:15:37 -0700] Discarding unused printer-state-changed
event...
D [16/May/2009:15:15:37 -0700] Discarding unused printer-state-changed
event...
D [16/May/2009:15:15:38 -0700] Discarding unused printer-state-changed
event...
D [16/May/2009:15:15:38 -0700] [Job 228] Connected to 142.103.234.29:631
(IPv4)...
D [16/May/2009:15:15:38 -0700] [Job 228] Getting supported attributes...
D [16/May/2009:15:15:38 -0700] Discarding unused printer-state-changed
event...
D [16/May/2009:15:15:38 -0700] [Job 228] document-format-supported (27
values)
D [16/May/2009:15:15:38 -0700] [0] = "application/octet-stream"
D [16/May/2009:15:15:38 -0700] [1] = "application/pdf"
D [16/May/2009:15:15:38 -0700] [2] = "application/postscript"
D [16/May/2009:15:15:38 -0700] [3] = "application/vnd.cups-postscript"
D [16/May/2009:15:15:38 -0700] [4] = "application/vnd.cups-raw"
D [16/May/2009:15:15:38 -0700] [5] = "application/vnd.hp-hpgl"
D [16/May/2009:15:15:38 -0700] [6] = "application/x-cshell"
D [16/May/2009:15:15:38 -0700] [7] = "application/x-csource"
D [16/May/2009:15:15:38 -0700] [8] = "application/x-perl"
D [16/May/2009:15:15:38 -0700] [9] = "application/x-shell"
D [16/May/2009:15:15:38 -0700] [10] = "image/gif"
D [16/May/2009:15:15:38 -0700] [11] = "image/jpeg"
D [16/May/2009:15:15:38 -0700] [12] = "image/png"
D [16/May/2009:15:15:38 -0700] [13] = "image/tiff"
D [16/May/2009:15:15:38 -0700] [14] = "image/x-bitmap"
D [16/May/2009:15:15:38 -0700] [15] = "image/x-photocd"
D [16/May/2009:15:15:38 -0700] [16] = "image/x-portable-anymap"
D [16/May/2009:15:15:38 -0700] [17] = "image/x-portable-bitmap"
D [16/May/2009:15:15:38 -0700] [18] = "image/x-portable-graymap"
D [16/May/2009:15:15:38 -0700] [19] = "image/x-portable-pixmap"
D [16/May/2009:15:15:38 -0700] [20] = "image/x-sgi-rgb"
D [16/May/2009:15:15:38 -0700] [21] = "image/x-sun-raster"
D [16/May/2009:15:15:38 -0700] [22] = "image/x-xbitmap"
D [16/May/2009:15:15:38 -0700] [23] = "image/x-xpixmap"
D [16/May/2009:15:15:38 -0700] [24] = "text/html"
D [16/May/2009:15:15:38 -0700] [25] = "text/plain"
D [16/May/2009:15:15:38 -0700] [26] = ""
D [16/May/2009:15:15:38 -0700] [Job 228] printer-uri =
"ipp://monopole.physics.ubc.ca:631/printers/hp311d"
D [16/May/2009:15:15:38 -0700] [Job 228] requesting-user-name = "root"
D [16/May/2009:15:15:38 -0700] [Job 228] job-name = "STDIN"
D [16/May/2009:15:15:38 -0700] Discarding unused printer-state-changed
event...
D [16/May/2009:15:15:38 -0700] Discarding unused printer-state-changed
event...
D [16/May/2009:15:15:38 -0700] Discarding unused printer-state-changed
event...
D [16/May/2009:15:15:38 -0700] Discarding unused printer-state-changed
event...
E [16/May/2009:15:15:38 -0700] PID 7024 (/usr/lib/cups/backend/ipp)
stopped with status 1!
D [16/May/2009:15:15:38 -0700] [Job 228] File 0 is complete.
I [16/May/2009:15:15:38 -0700] [Job 228] Backend returned status 1
(failed)
D [16/May/2009:15:15:38 -0700] Discarding unused job-stopped event...
D [16/May/2009:15:15:39 -0700] [Job 228] Unloading...

Link: http://www.cups.org/str.php?L3201
Version: 1.3.10