[cups.general] Problem printing to cups-lpd

Michael Sweet msweet at apple.com
Thu Jan 12 12:37:44 PST 2012


Please file a bug here:

    http://www.cups.org/str.php

The cups-lpd code is using the wrong resource path when sending the print documents, and that is triggering an auth check in your policy.


On Jan 12, 2012, at 12:22 PM, trice2 at gmail.com wrote:

> I'm having issues getting cups-lpd to print anything. The queues are working great for IPP printing, but everything sent to the server as lpd is being aborted with the following errors. Any ideas on how to fix this?
> 
> Thanks,
> Tim
> 
> 
>> From /var/log/syslog:
> 
> Jan 12 15:05:30 prntsrv cups-lpd[2021]: Receive print job for ICS_HP1200N
> Jan 12 15:05:30 prntsrv cups-lpd[2021]: Print file - job ID = 661681
> Jan 12 15:05:30 prntsrv cups-lpd[2021]: Unable to send document - Unauthorized
> Jan 12 15:05:30 prntsrv cups-lpd[2021]: Closing connection
> 
>> From /var/log/cups/error_log
> 
> d [12/Jan/2012:15:05:30 -0500] cupsdAcceptClient(lis=0xb87da408(6)) Clients=0
> D [12/Jan/2012:15:05:30 -0500] cupsdAcceptClient: 9 from localhost:631 (IPv4)
> d [12/Jan/2012:15:05:30 -0500] cupsdAddSelect(fd=9, read_cb=0xb7799700, write_cb=(nil), data=0xb96478f8)
> d [12/Jan/2012:15:05:30 -0500] cupsdDeleteCert: Removing certificate for PID 0
> d [12/Jan/2012:15:05:30 -0500] cupsdAddCert: Adding certificate for PID 0
> d [12/Jan/2012:15:05:30 -0500] cupsdAddCert: NumSystemGroups=1
> d [12/Jan/2012:15:05:30 -0500] cupsdCheckJobs: 0 active jobs, sleeping=0, reload=0
> D [12/Jan/2012:15:05:30 -0500] Report: clients=1
> D [12/Jan/2012:15:05:30 -0500] Report: jobs=499
> D [12/Jan/2012:15:05:30 -0500] Report: jobs-active=0
> D [12/Jan/2012:15:05:30 -0500] Report: printers=248
> D [12/Jan/2012:15:05:30 -0500] Report: printers-implicit=0
> D [12/Jan/2012:15:05:30 -0500] Report: stringpool-string-count=324666
> D [12/Jan/2012:15:05:30 -0500] Report: stringpool-alloc-bytes=58608
> D [12/Jan/2012:15:05:30 -0500] Report: stringpool-total-bytes=6138808
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(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 [12/Jan/2012:15:05:30 -0500] cupsdReadClient: 9 POST / HTTP/1.1
> D [12/Jan/2012:15:05:30 -0500] cupsdSetBusyState: Active clients
> d [12/Jan/2012:15:05:30 -0500] cupsdFindBest: uri = "/"...
> d [12/Jan/2012:15:05:30 -0500] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
> d [12/Jan/2012:15:05:30 -0500] cupsdFindBest: Location /jobs Limit 7f
> d [12/Jan/2012:15:05:30 -0500] cupsdFindBest: Location / Limit 7f
> d [12/Jan/2012:15:05:30 -0500] cupsdFindBest: best = /
> d [12/Jan/2012:15:05:30 -0500] cupsdAuthorize: con->uri="/", con->best=0xb87da2c0(/)
> d [12/Jan/2012:15:05:30 -0500] cupsdAuthorize: Authorization=""
> D [12/Jan/2012:15:05:30 -0500] cupsdAuthorize: No authentication data provided.
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: con->uri="/", con->best=0xb87da2c0(/)
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=243, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=243, con->file=-1
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=214, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=214, con->file=-1
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=177, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=177, con->file=-1
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=125, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=125, con->file=-1
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=88, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=88, con->file=-1
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=58, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=58, con->file=-1
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=36, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=36, con->file=-1
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=19, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=19, con->file=-1
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=1, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=1, con->file=-1
> D [12/Jan/2012:15:05:30 -0500] cupsdReadClient: 9 1.1 Get-Printer-Attributes 1
> d [12/Jan/2012:15:05:30 -0500] cupsdProcessIPPRequest(0xb96478f8[9]): operation_id = 000b
> D [12/Jan/2012:15:05:30 -0500] Get-Printer-Attributes ipp://localhost/printers/ICS_HP1200N
> d [12/Jan/2012:15:05:30 -0500] get_printer_attrs(0xb96478f8[9], ipp://localhost/printers/ICS_HP1200N)
> d [12/Jan/2012:15:05:30 -0500] cupsdFindPolicyOp(p=0xb87d3e58, op=b(Get-Printer-Attributes))
> d [12/Jan/2012:15:05:30 -0500] cupsdFindPolicyOp: Found wildcard match...
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: con->uri="/", con->best=0xb87d52d0((null))
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: con->uri="/", con->best=0xb87d52d0((null))
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: op=0(unknown-0000)
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
> d [12/Jan/2012:15:05:30 -0500] copy_attrs(to=0xb96114d8, from=0xb9223088, ra=0xb964b950, group=0, quickcopy=0)
> d [12/Jan/2012:15:05:30 -0500] copy_attribute(0xb96114d8, 0xb9223108[printer-name,4,42])
> d [12/Jan/2012:15:05:30 -0500] copy_attribute(0xb96114d8, 0xb92c7010[printer-info,4,41])
> d [12/Jan/2012:15:05:30 -0500] copy_attrs(to=0xb96114d8, from=0xb92bc688, ra=0xb964b950, group=0, quickcopy=0)
> d [12/Jan/2012:15:05:30 -0500] copy_attrs(to=0xb96114d8, from=0xb87db600, ra=0xb964b950, group=0, quickcopy=-2147483648)
> D [12/Jan/2012:15:05:30 -0500] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/ICS_HP1200N) from localhost
> d [12/Jan/2012:15:05:30 -0500] cupsdProcessIPPRequest: ippLength(response)=199
> d [12/Jan/2012:15:05:30 -0500] cupsdAddSelect(fd=9, read_cb=0xb7799700, write_cb=0xb7796f00, data=0xb96478f8)
> d [12/Jan/2012:15:05:30 -0500] select_timeout(0): 301 seconds to timeout a client connection
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb96114d8(0), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb96114d8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb96114d8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb96114d8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb96114d8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb96114d8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb96114d8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb96114d8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdAddSelect(fd=9, read_cb=0xb7799700, write_cb=(nil), data=0xb96478f8)
> D [12/Jan/2012:15:05:30 -0500] cupsdSetBusyState: Not busy
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(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 [12/Jan/2012:15:05:30 -0500] cupsdReadClient: 9 POST /printers/ICS_HP1200N HTTP/1.1
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: con->uri="/", con->best=0xb87d52d0((null))
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: op=0(unknown-0000)
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
> d [12/Jan/2012:15:05:30 -0500] copy_attrs(to=0xb96114d8, from=0xb9223088, ra=0xb964b950, group=0, quickcopy=0)
> d [12/Jan/2012:15:05:30 -0500] copy_attribute(0xb96114d8, 0xb9223108[printer-name,4,42])
> d [12/Jan/2012:15:05:30 -0500] copy_attribute(0xb96114d8, 0xb92c7010[printer-info,4,41])
> d [12/Jan/2012:15:05:30 -0500] copy_attrs(to=0xb96114d8, from=0xb92bc688, ra=0xb964b950, group=0, quickcopy=0)
> d [12/Jan/2012:15:05:30 -0500] copy_attrs(to=0xb96114d8, from=0xb87db600, ra=0xb964b950, group=0, quickcopy=-2147483648)
> D [12/Jan/2012:15:05:30 -0500] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/ICS_HP1200N) from localhost
> d [12/Jan/2012:15:05:30 -0500] cupsdProcessIPPRequest: ippLength(response)=199
> d [12/Jan/2012:15:05:30 -0500] cupsdAddSelect(fd=9, read_cb=0xb7799700, write_cb=0xb7796f00, data=0xb96478f8)
> d [12/Jan/2012:15:05:30 -0500] select_timeout(0): 301 seconds to timeout a client connection
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb96114d8(0), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb96114d8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb96114d8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb96114d8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb96114d8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb96114d8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb96114d8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb96114d8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdAddSelect(fd=9, read_cb=0xb7799700, write_cb=(nil), data=0xb96478f8)
> D [12/Jan/2012:15:05:30 -0500] cupsdSetBusyState: Not busy
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(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 [12/Jan/2012:15:05:30 -0500] cupsdReadClient: 9 POST /printers/ICS_HP1200N HTTP/1.1
> D [12/Jan/2012:15:05:30 -0500] cupsdSetBusyState: Active clients
> d [12/Jan/2012:15:05:30 -0500] cupsdFindBest: uri = "/printers/ICS_HP1200N"...
> d [12/Jan/2012:15:05:30 -0500] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
> d [12/Jan/2012:15:05:30 -0500] cupsdFindBest: Location /jobs Limit 7f
> d [12/Jan/2012:15:05:30 -0500] cupsdFindBest: Location /admin Limit 7f
> d [12/Jan/2012:15:05:30 -0500] cupsdFindBest: Location / Limit 7f
> d [12/Jan/2012:15:05:30 -0500] cupsdFindBest: best = /
> d [12/Jan/2012:15:05:30 -0500] cupsdAuthorize: con->uri="/printers/ICS_HP1200N", con->best=0xb87da2c0(/)
> d [12/Jan/2012:15:05:30 -0500] cupsdAuthorize: Authorization=""
> D [12/Jan/2012:15:05:30 -0500] cupsdAuthorize: No authentication data provided.
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: con->uri="/printers/ICS_HP1200N", con->best=0xb87da2c0(/)
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=297, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=297, con->file=-1
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=268, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=268, con->file=-1
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=231, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=231, con->file=-1
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=179, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=179, con->file=-1
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=147, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=147, con->file=-1
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=125, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=125, con->file=-1
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=98, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=98, con->file=-1
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=54, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=54, con->file=-1
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(9)) con->http.error=0 con->http.used=1, con->http.state=6 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=1, con->file=-1
> D [12/Jan/2012:15:05:30 -0500] cupsdReadClient: 9 1.1 Create-Job 1
> d [12/Jan/2012:15:05:30 -0500] cupsdProcessIPPRequest(0xb96478f8[9]): operation_id = 0005
> D [12/Jan/2012:15:05:30 -0500] Create-Job ipp://localhost/printers/ICS_HP1200N
> d [12/Jan/2012:15:05:30 -0500] create_job(0xb96478f8[9], ipp://localhost/printers/ICS_HP1200N)
> d [12/Jan/2012:15:05:30 -0500] add_job(0xb96478f8[9], 0xb92bbd38(ICS_HP1200N), (nil)(none/none))
> d [12/Jan/2012:15:05:30 -0500] cupsdFindPolicyOp(p=0xb87d3e58, op=5(Create-Job))
> d [12/Jan/2012:15:05:30 -0500] cupsdFindPolicyOp: Found wildcard match...
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: con->uri="/printers/ICS_HP1200N", con->best=0xb87d52d0((null))
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: op=0(unknown-0000)
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
> d [12/Jan/2012:15:05:30 -0500] check_quotas(0xb96478f8[9], 0xb92bbd38[ICS_HP1200N])
> D [12/Jan/2012:15:05:30 -0500] cupsdMarkDirty(----J-)
> D [12/Jan/2012:15:05:30 -0500] cupsdSetBusyState: Active clients and dirty files
> D [12/Jan/2012:15:05:30 -0500] add_job: requesting-user-name="trice"
> D [12/Jan/2012:15:05:30 -0500] Adding default job-sheets values "none,none"...
> I [12/Jan/2012:15:05:30 -0500] [Job 661681] Adding start banner page "none".
> d [12/Jan/2012:15:05:30 -0500] copy_banner(con=0xb96478f8[9], job=0xb964bd40[661681], name="none")
> d [12/Jan/2012:15:05:30 -0500] add_job_state_reasons(0xb96478f8[9], 661681)
> d [12/Jan/2012:15:05:30 -0500] cupsdAddEvent(event=job-created, dest=0xb92bbd38(ICS_HP1200N), job=0xb964bd40(661681), text="Job created.", ...)
> D [12/Jan/2012:15:05:30 -0500] Discarding unused job-created event...
> I [12/Jan/2012:15:05:30 -0500] [Job 661681] Queued on "ICS_HP1200N" by "trice".
> D [12/Jan/2012:15:05:30 -0500] Returning IPP successful-ok for Create-Job (ipp://localhost/printers/ICS_HP1200N) from localhost
> d [12/Jan/2012:15:05:30 -0500] cupsdProcessIPPRequest: ippLength(response)=211
> d [12/Jan/2012:15:05:30 -0500] cupsdAddSelect(fd=9, read_cb=0xb7799700, write_cb=0xb7796f00, data=0xb96478f8)
> d [12/Jan/2012:15:05:30 -0500] select_timeout(0): 31 seconds to write dirty config/state files
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb964c6b8(0), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb964c6b8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb964c6b8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb964c6b8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb964c6b8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb964c6b8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdWriteClient(con=0xb96478f8(9)) response=0xb964c6b8(2), file=-1 pipe_pid=0 state=7
> d [12/Jan/2012:15:05:30 -0500] cupsdAddSelect(fd=9, read_cb=0xb7799700, write_cb=(nil), data=0xb96478f8)
> D [12/Jan/2012:15:05:30 -0500] cupsdSetBusyState: Dirty files
> d [12/Jan/2012:15:05:30 -0500] cupsdReadClient(con=0xb96478f8(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 [12/Jan/2012:15:05:30 -0500] cupsdReadClient: 9 POST /jobs/661681 HTTP/1.1
> D [12/Jan/2012:15:05:30 -0500] cupsdSetBusyState: Active clients and dirty files
> d [12/Jan/2012:15:05:30 -0500] cupsdFindBest: uri = "/jobs/661681"...
> d [12/Jan/2012:15:05:30 -0500] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0
> d [12/Jan/2012:15:05:30 -0500] cupsdFindBest: Location /jobs Limit 7f
> d [12/Jan/2012:15:05:30 -0500] cupsdFindBest: Location /admin Limit 7f
> d [12/Jan/2012:15:05:30 -0500] cupsdFindBest: Location / Limit 7f
> d [12/Jan/2012:15:05:30 -0500] cupsdFindBest: best = /jobs
> d [12/Jan/2012:15:05:30 -0500] cupsdAuthorize: con->uri="/jobs/661681", con->best=0xb87da728(/jobs)
> d [12/Jan/2012:15:05:30 -0500] cupsdAuthorize: Authorization=""
> D [12/Jan/2012:15:05:30 -0500] cupsdAuthorize: No authentication data provided.
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: con->uri="/jobs/661681", con->best=0xb87da728(/jobs)
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=Basic, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
> d [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
> D [12/Jan/2012:15:05:30 -0500] cupsdIsAuthorized: username=""
> d [12/Jan/2012:15:05:30 -0500] cupsdSendError(con=0xb96478f8(9), code=401, auth_type=0
> D [12/Jan/2012:15:05:30 -0500] cupsdSendHeader: 9 WWW-Authenticate: Basic realm="CUPS"
> D [12/Jan/2012:15:05:30 -0500] cupsdCloseClient: 9
> D [12/Jan/2012:15:05:30 -0500] cupsdSetBusyState: Dirty files
> d [12/Jan/2012:15:05:30 -0500] cupsdRemoveSelect(fd=9)
> d [12/Jan/2012:15:05:31 -0500] select_timeout(0): 30 seconds to write dirty config/state files
> I [12/Jan/2012:15:06:01 -0500] Saving job cache file "/var/cache/cups/job.cache"...
> d [12/Jan/2012:15:06:01 -0500] cupsdSaveJob(job=0xb964bd40(661681)): job->attrs=0xb96114d8
> D [12/Jan/2012:15:06:01 -0500] cupsdSetBusyState: Not busy
> d [12/Jan/2012:15:06:01 -0500] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0
> d [12/Jan/2012:15:06:01 -0500] select_timeout(0): 270 seconds to release held jobs
> 
> 
> _______________________________________________
> cups mailing list
> cups at easysw.com
> http://lists.easysw.com/mailman/listinfo/cups

_________________________________________________________
Michael Sweet, Senior Printing System Engineer, PWG Chair





More information about the cups mailing list