Centos 5.6 and Cups 1.4.6

[Scott Hallenbeck]

> I removed cups from Centos 5.6 server leaving cups-libs-1.3.7-26, downloaded cups 2.4.6, compiled and installed it.
>
> I copied over the printer.conf and /ppd directory from a cups 1.3.7 server and brought it all up.
>
> All the printers (2400+) show online and ready.  I can send them 'test page' prints from the gui, and have printed a pdf file from the command line.  All appears fully functional and prints come out fine.
>
> Then I make the print server live to our applications, and that's when things go bad.
>
> This is what I see from cups error log in debug2
>
> d [14/Jun/2011:12:33:36 -0400] cupsdAcceptClient(lis=0x2b067fb4a110(5)) Clients=0
> D [14/Jun/2011:12:33:36 -0400] cupsdAcceptClient: 9 from 10.0.34.91:631 (IPv4)
> d [14/Jun/2011:12:33:36 -0400] cupsdAddSelect(fd=9, read_cb=0x2b0667d54fde, write_cb=(nil), data=0x2b06a4b89ed0)
> d [14/Jun/2011:12:33:36 -0400] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0
> d [14/Jun/2011:12:33:36 -0400] cupsdReadClient(con=0x2b06a4b89ed0(9)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1
> D [14/Jun/2011:12:33:36 -0400] cupsdReadClient: 9 POST /printers/tx1313-check HTTP/1.0
> D [14/Jun/2011:12:33:36 -0400] cupsdSetBusyState: Active clients
> d [14/Jun/2011:12:33:36 -0400] cupsdFindBest: uri = "/printers/tx1313-check"...
> d [14/Jun/2011:12:33:36 -0400] cupsdFindBest: Location /admin/conf Limit 7f
> d [14/Jun/2011:12:33:36 -0400] cupsdFindBest: Location /admin Limit 7f
> d [14/Jun/2011:12:33:36 -0400] cupsdFindBest: Location / Limit 7f
> d [14/Jun/2011:12:33:36 -0400] cupsdFindBest: best = /
> d [14/Jun/2011:12:33:36 -0400] cupsdAuthorize: con->uri="/printers/tx1313-check", con->best=0x2b067fb5dc30(/)
> d [14/Jun/2011:12:33:36 -0400] cupsdAuthorize: Authorization=""
> D [14/Jun/2011:12:33:36 -0400] cupsdAuthorize: No authentication data provided.
> d [14/Jun/2011:12:33:36 -0400] cupsdIsAuthorized: con->uri="/printers/tx1313-check", con->best=0x2b067fb5dc30(/)
> d [14/Jun/2011:12:33:36 -0400] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
> d [14/Jun/2011:12:33:36 -0400] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
> d [14/Jun/2011:12:33:36 -0400] cupsdReadClient(con=0x2b06a4b89ed0(9)) con->http.error=0 con->http.used=1946, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=21627, con->file=-1
> d [14/Jun/2011:12:33:36 -0400] cupsdReadClient(con=0x2b06a4b89ed0(9)) con->http.error=0 con->http.used=1912, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=21593, con->file=-1
> d [14/Jun/2011:12:33:36 -0400] cupsdReadClient(con=0x2b06a4b89ed0(9)) con->http.error=0 con->http.used=1878, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=21559, con->file=-1
> d [14/Jun/2011:12:33:36 -0400] cupsdReadClient(con=0x2b06a4b89ed0(9)) con->http.error=0 con->http.used=1804, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=21485, con->file=-1
> d [14/Jun/2011:12:33:36 -0400] cupsdReadClient(con=0x2b06a4b89ed0(9)) con->http.error=0 con->http.used=1744, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=21425, con->file=-1
> D [14/Jun/2011:12:33:36 -0400] cupsdReadClient: 9 1.1 Print-Job 1
> d [14/Jun/2011:12:33:36 -0400] cupsdReadClient(con=0x2b06a4b89ed0(9)) con->http.error=0 con->http.used=0, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=19681, con->file=11
> d [14/Jun/2011:12:33:36 -0400] cupsdProcessIPPRequest(0x2b06a4b89ed0[9]): operation_id = 0002
> E [14/Jun/2011:12:33:36 -0400] Unsupported character set "iso-8859-1"!
> d [14/Jun/2011:12:33:36 -0400] cupsdAddEvent(event=server-audit, dest=(nil)(), job=(nil)(0), text="%04X %s Unsupported attributes-charset value "%s"", ...)
> D [14/Jun/2011:12:33:36 -0400] Discarding unused server-audit event...
> D [14/Jun/2011:12:33:36 -0400] Print-Job client-error-bad-request: Unsupported character set "iso-8859-1"!
> E [14/Jun/2011:12:33:36 -0400] Returning IPP client-error-bad-request for Print-Job (http://atl-elmsprint.clacorp.com:631/printers/tx1313-check) from 10.0.34.91
> d [14/Jun/2011:12:33:36 -0400] cupsdProcessIPPRequest: ippLength(response)=135
> d [14/Jun/2011:12:33:36 -0400] cupsdAddSelect(fd=9, read_cb=0x2b0667d54fde, write_cb=0x2b0667d54403, data=0x2b06a4b89ed0)
> d [14/Jun/2011:12:33:36 -0400] cupsdWriteClient(con=0x2b06a4b89ed0(9)) response=0x2b06931d2350(0), file=-1 pipe_pid=0 state=7
> d [14/Jun/2011:12:33:36 -0400] cupsdWriteClient(con=0x2b06a4b89ed0(9)) response=0x2b06931d2350(2), file=-1 pipe_pid=0 state=7
> d [14/Jun/2011:12:33:36 -0400] cupsdWriteClient(con=0x2b06a4b89ed0(9)) response=0x2b06931d2350(2), file=-1 pipe_pid=0 state=7
> d [14/Jun/2011:12:33:36 -0400] cupsdWriteClient(con=0x2b06a4b89ed0(9)) response=0x2b06931d2350(2), file=-1 pipe_pid=0 state=7
> d [14/Jun/2011:12:33:36 -0400] cupsdAddSelect(fd=9, read_cb=0x2b0667d54fde, write_cb=(nil), data=0x2b06a4b89ed0)
> D [14/Jun/2011:12:33:36 -0400] cupsdWriteClient: 9 Closing because Keep-Alive disabled
> D [14/Jun/2011:12:33:36 -0400] cupsdCloseClient: 9
> D [14/Jun/2011:12:33:36 -0400] cupsdSetBusyState: Not busy
> d [14/Jun/2011:12:33:36 -0400] cupsdRemoveSelect(fd=9)
>
>
>
> What I am thinking is that the real issue here is: Print-Job client-error-bad-request: Unsupported character set "iso-8859-1"!
>
> Since it seems to print 'local' jobs just fine, I can't help but wonder if it's the print jobs coming in that are causing this.
>
> Any idea's on how I can fix this?
>
> Scott
>


My work on 1.4.6 has been vetoed for now and I am being pushed back to the 1.3.7 version.