Attachment 1475812 Details for Bug 1614978 – cups daemon log

cups daemon log

kitchen.log (text/plain), 105.71 KB, created by Bill Gianopoulos on 2018-08-14 10:57:34 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Bill Gianopoulos

Created: 2018-08-14 10:57:34 UTC

Size: 105.71 KB

patch

obsolete

>-- Logs begin at Wed 2018-07-25 15:30:34 EDT, end at Tue 2018-08-14 06:52:18 EDT. --
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea3da80((null))
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: op=0(0x0000)
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: printer-icons="http://localhost/icons/Kitchen.png"
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: add_printer_state_reasons(0x56468eadeea0[7], 0x56468ea5b5c0[Kitchen])
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: printer-uri-supported="ipp://localhost/printers/Kitchen"
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: add_queued_job_count(0x56468eadeea0[7], 0x56468ea5b5c0[Kitchen])
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4b00, from=0x56468ea4a760, ra=(nil), group=0, quickcopy=0)
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4b00, from=0x56468ea4d680, ra=(nil), group=0, quickcopy=0)
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4b00, from=0x56468ea48480, ra=(nil), group=0, quickcopy=-2147483648)
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: [Client 7] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost.
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: [Client 7] Content-Length: 9267
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: [Client 7] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=18, read_cb=0x56468e4b1b00, write_cb=0x56468e4b1110, data=0x56468eadeea0)
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: [Client 7] con->http=0x56468eae0060
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9267, response=0x56468eae4b00(IPP_STATE_DATA), pipe_pid=0, file=-1
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: [Client 7] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: [Client 7] bytes=0, http_state=0, data_remaining=9267
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: [Client 7] Flushing write buffer.
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: [Client 7] New state is HTTP_STATE_WAITING
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=18, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eadeea0)
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: [Client 7] Waiting for request.
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:24 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] POST / HTTP/1.1
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Read: status=200, state=6
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: uri="/", limit=10...
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/log(10) Limit 7f
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/conf(11) Limit 7f
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin(6) Limit 7f
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /(1) Limit 7f
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: best=/
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] No authentication data provided.
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] 2.0 CUPS-Get-Printers 48
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest(0x56468eac8260[1]): operation_id=4002(CUPS-Get-Printers)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: operation-attributes-tag
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8'
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us'
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'printer-name,printer-type,printer-location,printer-info,printer-make-and-model,printer-state,printer-state-message,printer-state-reasons,printer-uri-supported,device-uri,printer-is-shared'
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: CUPS-Get-Printers
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: get_printers(0x56468eac8260[1], 0)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp(p=0x56468ea395f0, op=4002(CUPS-Get-Printers))
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp: Found wildcard match...
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea3da80((null))
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: op=0(0x0000)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: add_printer_state_reasons(0x56468eac8260[1], 0x56468ea5b5c0[Kitchen])
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: printer-uri-supported="ipp://localhost/printers/Kitchen"
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ad0, from=0x56468ea4a760, ra=0x56468eae5960, group=0, quickcopy=0)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ad0, from=0x56468ea4d680, ra=0x56468eae5960, group=0, quickcopy=0)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ad0, from=0x56468ea48480, ra=0x56468eae5960, group=0, quickcopy=-2147483648)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: add_printer_state_reasons(0x56468eac8260[1], 0x56468ea49ad0[Office])
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: printer-uri-supported="ipp://localhost/printers/Office"
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ad0, from=0x56468ea4cc10, ra=0x56468eae5960, group=0, quickcopy=0)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ad0, from=0x56468ea6b7a0, ra=0x56468eae5960, group=0, quickcopy=0)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ad0, from=0x56468ea48480, ra=0x56468eae5960, group=0, quickcopy=-2147483648)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Content-Length: 1040
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=11, read_cb=0x56468e4b1b00, write_cb=0x56468e4b1110, data=0x56468eac8260)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: Expiring subscriptions...
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] con->http=0x56468eacf150
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1040, response=0x56468eae3ad0(IPP_STATE_DATA), pipe_pid=0, file=-1
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] bytes=0, http_state=0, data_remaining=1040
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Flushing write buffer.
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] New state is HTTP_STATE_WAITING
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=11, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eac8260)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Waiting for request.
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] POST / HTTP/1.1
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Read: status=200, state=6
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: uri="/", limit=10...
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/log(10) Limit 7f
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/conf(11) Limit 7f
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin(6) Limit 7f
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /(1) Limit 7f
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: best=/
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] No authentication data provided.
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=129, request=0x56468eae3ad0(IPP_STATE_IDLE), file=-1
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Read: status=100, state=6
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=121, request=0x56468eae3ad0(IPP_STATE_ATTRIBUTE), file=-1
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Read: status=100, state=6
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=92, request=0x56468eae3ad0(IPP_STATE_ATTRIBUTE), file=-1
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Read: status=100, state=6
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=55, request=0x56468eae3ad0(IPP_STATE_ATTRIBUTE), file=-1
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Read: status=100, state=6
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=18, request=0x56468eae3ad0(IPP_STATE_ATTRIBUTE), file=-1
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Read: status=100, state=6
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1, request=0x56468eae3ad0(IPP_STATE_ATTRIBUTE), file=-1
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Read: status=100, state=6
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] 2.0 CUPS-Get-Classes 49
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest(0x56468eac8260[1]): operation_id=4005(CUPS-Get-Classes)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: operation-attributes-tag
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8'
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us'
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'printer-name,member-names'
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: CUPS-Get-Classes
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: get_printers(0x56468eac8260[1], 1)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp(p=0x56468ea395f0, op=4005(CUPS-Get-Classes))
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp: Found wildcard match...
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea3da80((null))
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: op=0(0x0000)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost.
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Content-Length: 75
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=11, read_cb=0x56468e4b1b00, write_cb=0x56468e4b1110, data=0x56468eac8260)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] con->http=0x56468eacf150
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x56468eae4b00(IPP_STATE_DATA), pipe_pid=0, file=-1
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] bytes=0, http_state=0, data_remaining=75
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Flushing write buffer.
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] New state is HTTP_STATE_WAITING
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=11, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eac8260)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Waiting for request.
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] POST / HTTP/1.1
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Read: status=200, state=6
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: uri="/", limit=10...
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/log(10) Limit 7f
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/conf(11) Limit 7f
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin(6) Limit 7f
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /(1) Limit 7f
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: best=/
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] No authentication data provided.
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] 2.0 CUPS-Get-Default 50
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest(0x56468eac8260[1]): operation_id=4001(CUPS-Get-Default)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: operation-attributes-tag
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8'
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us'
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: CUPS-Get-Default
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: get_default(0x56468eac8260[1])
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp(p=0x56468ea395f0, op=4001(CUPS-Get-Default))
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp: Found wildcard match...
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea3da80((null))
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: op=0(0x0000)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: printer-icons="http://localhost/icons/Kitchen.png"
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: add_printer_state_reasons(0x56468eac8260[1], 0x56468ea5b5c0[Kitchen])
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: printer-uri-supported="ipp://localhost/printers/Kitchen"
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: add_queued_job_count(0x56468eac8260[1], 0x56468ea5b5c0[Kitchen])
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ad0, from=0x56468ea4a760, ra=(nil), group=0, quickcopy=0)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ad0, from=0x56468ea4d680, ra=(nil), group=0, quickcopy=0)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ad0, from=0x56468ea48480, ra=(nil), group=0, quickcopy=-2147483648)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost.
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Content-Length: 9267
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=11, read_cb=0x56468e4b1b00, write_cb=0x56468e4b1110, data=0x56468eac8260)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] con->http=0x56468eacf150
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9267, response=0x56468eae3ad0(IPP_STATE_DATA), pipe_pid=0, file=-1
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] bytes=0, http_state=0, data_remaining=9267
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Flushing write buffer.
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] New state is HTTP_STATE_WAITING
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=11, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eac8260)
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: [Client 1] Waiting for request.
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:25 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:26 rawhide.wg9s.com cupsd[2819]: Expiring subscriptions...
>Aug 14 06:51:26 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:26 rawhide.wg9s.com cupsd[2819]: select_timeout(0): 12 seconds to write dirty config/state files
>Aug 14 06:51:38 rawhide.wg9s.com cupsd[2819]: Saving job.cache...
>Aug 14 06:51:38 rawhide.wg9s.com cupsd[2819]: cupsdSaveJob(job=0x56468eac1e20(16)): job->attrs=0x56468eaa38c0
>Aug 14 06:51:38 rawhide.wg9s.com cupsd[2819]: Saving subscriptions.conf...
>Aug 14 06:51:38 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
>Aug 14 06:51:38 rawhide.wg9s.com cupsd[2819]: Expiring subscriptions...
>Aug 14 06:51:38 rawhide.wg9s.com cupsd[2819]: cupsdCheckJobs: 1 active jobs, sleeping=0, ac-power=-1, reload=0, curtime=1534243898
>Aug 14 06:51:38 rawhide.wg9s.com cupsd[2819]: cupsdCheckJobs: Job 16 - dest="Kitchen", printer=0x56468ea5b5c0, state=5, cancel_time=1534254682, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0
>Aug 14 06:51:38 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:38 rawhide.wg9s.com cupsd[2819]: select_timeout(0): 284 seconds to timeout a client connection
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: Unable to locate printer \"EPSON050E62.local\".
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdMarkDirty(---J-)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Not busy"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: Set job-printer-state-message to "Unable to locate printer \"EPSON050E62.local\".", current level=INFO
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddEvent(event=job-progress, dest=0x56468ea5b5c0(Kitchen), job=0x56468eac1e20(16), text="%s", ...)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: Discarding unused job-progress event...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddEvent(event=printer-state-changed, dest=0x56468ea5b5c0(Kitchen), job=(nil)(0), text="Printer "%s" state changed.", ...)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsd_send_notification(sub=0x56468eaa2360(37), event=0x56468eacdba0(printer-state-changed))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: sub->pipe=17
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsd_send_notification(sub=0x56468eacdf60(43), event=0x56468eae79b0(printer-state-changed))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdMarkDirty(----S)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: Expiring subscriptions...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Notifier] state=3
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Notifier] PrinterStateChanged
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] POST / HTTP/1.1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Read: status=200, state=6
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: uri="/", limit=10...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/log(10) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/conf(11) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin(6) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /(1) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: best=/
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] No authentication data provided.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] 2.0 Get-Notifications 34
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest(0x56468eadeea0[7]): operation_id=001c(Get-Notifications)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: operation-attributes-tag
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: requesting-user-name nameWithoutLanguage 'wag'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: notify-subscription-ids integer '37'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: printer-uri uri '/printers/'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: job-uri uri '/jobs/'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: notify-sequence-numbers integer '10'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: Get-Notifications /printers/
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: get_notifications(con=0x56468eadeea0[7])
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp(p=0x56468ea395f0, op=1c(Get-Notifications))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp: Found exact match...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea3ade0((null))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: owner="wag"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=2
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: op=1c(Get-Notifications)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: requesting-user-name="wag"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: Checking user membership...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eaede30, from=0x56468eae4b00, ra=(nil), group=7, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Content-Length: 553
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=18, read_cb=0x56468e4b1b00, write_cb=0x56468e4b1110, data=0x56468eadeea0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] con->http=0x56468eae0060
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=553, response=0x56468eaede30(IPP_STATE_DATA), pipe_pid=0, file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] bytes=0, http_state=0, data_remaining=553
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Flushing write buffer.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] New state is HTTP_STATE_WAITING
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=18, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eadeea0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Waiting for request.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] POST / HTTP/1.1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Read: status=200, state=6
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: uri="/", limit=10...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/log(10) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/conf(11) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin(6) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /(1) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: best=/
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] No authentication data provided.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] 2.0 CUPS-Get-Printers 35
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest(0x56468eadeea0[7]): operation_id=4002(CUPS-Get-Printers)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: operation-attributes-tag
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'auth-info-required,device-uri,job-sheets-default,marker-change-time,marker-colors,marker-high-levels,marker-levels,marker-low-levels,marker-message,marker-names,marker-types,printer-commands,printer-defaults,printer-info,printer-is-accepting-jobs,printer-is-shared,printer-is-temporary,printer-location,printer-make-and-model,printer-mandatory-job-attributes,printer-name,printer-state,printer-state-change-time,printer-state-reasons,printer-type,printer-uri-supported'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: requesting-user-name nameWithoutLanguage 'wag'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: CUPS-Get-Printers
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: get_printers(0x56468eadeea0[7], 0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp(p=0x56468ea395f0, op=4002(CUPS-Get-Printers))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp: Found wildcard match...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea3da80((null))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: op=0(0x0000)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: add_printer_state_reasons(0x56468eadeea0[7], 0x56468ea5b5c0[Kitchen])
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: printer-uri-supported="ipp://localhost/printers/Kitchen"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4f10, from=0x56468ea4a760, ra=0x56468eae4480, group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4f10, from=0x56468ea4d680, ra=0x56468eae4480, group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4f10, from=0x56468ea48480, ra=0x56468eae4480, group=0, quickcopy=-2147483648)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: add_printer_state_reasons(0x56468eadeea0[7], 0x56468ea49ad0[Office])
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: printer-uri-supported="ipp://localhost/printers/Office"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4f10, from=0x56468ea4cc10, ra=0x56468eae4480, group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4f10, from=0x56468ea6b7a0, ra=0x56468eae4480, group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4f10, from=0x56468ea48480, ra=0x56468eae4480, group=0, quickcopy=-2147483648)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Content-Length: 2573
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=18, read_cb=0x56468e4b1b00, write_cb=0x56468e4b1110, data=0x56468eadeea0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] con->http=0x56468eae0060
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2573, response=0x56468eae4f10(IPP_STATE_DATA), pipe_pid=0, file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] bytes=0, http_state=0, data_remaining=2573
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Flushing write buffer.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] New state is HTTP_STATE_WAITING
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=18, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eadeea0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Waiting for request.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] POST / HTTP/1.1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Read: status=200, state=6
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: uri="/", limit=10...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/log(10) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/conf(11) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin(6) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /(1) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: best=/
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] No authentication data provided.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] 2.0 CUPS-Get-Default 36
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest(0x56468eadeea0[7]): operation_id=4001(CUPS-Get-Default)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: operation-attributes-tag
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: CUPS-Get-Default
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: get_default(0x56468eadeea0[7])
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp(p=0x56468ea395f0, op=4001(CUPS-Get-Default))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp: Found wildcard match...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea3da80((null))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: op=0(0x0000)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: printer-icons="http://localhost/icons/Kitchen.png"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: add_printer_state_reasons(0x56468eadeea0[7], 0x56468ea5b5c0[Kitchen])
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: printer-uri-supported="ipp://localhost/printers/Kitchen"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: add_queued_job_count(0x56468eadeea0[7], 0x56468ea5b5c0[Kitchen])
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4560, from=0x56468ea4a760, ra=(nil), group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4560, from=0x56468ea4d680, ra=(nil), group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4560, from=0x56468ea48480, ra=(nil), group=0, quickcopy=-2147483648)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Content-Length: 9293
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=18, read_cb=0x56468e4b1b00, write_cb=0x56468e4b1110, data=0x56468eadeea0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] con->http=0x56468eae0060
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9293, response=0x56468eae4560(IPP_STATE_DATA), pipe_pid=0, file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] bytes=0, http_state=0, data_remaining=9293
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Flushing write buffer.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] New state is HTTP_STATE_WAITING
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=18, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eadeea0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Waiting for request.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAcceptClient(lis=0x56468ea38d40(3)) Clients=6
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] Server address is "/var/run/cups/cups.sock".
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] Accepted from localhost (Domain)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=19, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eae50c0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] Waiting for request.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] POST / HTTP/1.1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] Read: status=200, state=6
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: uri="/", limit=10...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/log(10) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/conf(11) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin(6) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /(1) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: best=/
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] No authentication data provided.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] 2.0 Get-Notifications 51
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest(0x56468eae50c0[29]): operation_id=001c(Get-Notifications)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: operation-attributes-tag
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: printer-uri uri '/'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: requesting-user-name nameWithoutLanguage 'wag'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: notify-subscription-ids integer '43'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: notify-sequence-numbers integer '8'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: Get-Notifications /
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: get_notifications(con=0x56468eae50c0[29])
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp(p=0x56468ea395f0, op=1c(Get-Notifications))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp: Found exact match...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea3ade0((null))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: owner="wag"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=2
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: op=1c(Get-Notifications)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: requesting-user-name="wag"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: Checking user membership...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae44c0, from=0x56468eaedfe0, ra=(nil), group=7, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] Returning IPP successful-ok for Get-Notifications (/) from localhost.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] Content-Length: 553
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=19, read_cb=0x56468e4b1b00, write_cb=0x56468e4b1110, data=0x56468eae50c0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] con->http=0x56468eaf3120
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=553, response=0x56468eae44c0(IPP_STATE_DATA), pipe_pid=0, file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] bytes=0, http_state=0, data_remaining=553
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] Flushing write buffer.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] New state is HTTP_STATE_WAITING
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=19, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eae50c0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] Waiting for request.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] POST /printers/Kitchen HTTP/1.1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] Read: status=200, state=6
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: uri="/printers/Kitchen", limit=10...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/log(10) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/conf(11) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin(6) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /(1) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: best=/
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] con->uri="/printers/Kitchen", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] No authentication data provided.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/printers/Kitchen", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] 2.0 Get-Printer-Attributes 52
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest(0x56468eae80e0[11]): operation_id=000b(Get-Printer-Attributes)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: operation-attributes-tag
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: printer-uri uri 'ipp://localhost/printers/Kitchen'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'member-uris,printer-uri-supported'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: Get-Printer-Attributes ipp://localhost/printers/Kitchen
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: get_printer_attrs(0x56468eae80e0[11], ipp://localhost/printers/Kitchen)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp(p=0x56468ea395f0, op=b(Get-Printer-Attributes))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp: Found wildcard match...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/printers/Kitchen", con->best=0x56468ea3da80((null))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: op=0(0x0000)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: printer-uri-supported="ipp://localhost/printers/Kitchen"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4f10, from=0x56468ea4a760, ra=0x56468eae37f0, group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4f10, from=0x56468ea4d680, ra=0x56468eae37f0, group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4f10, from=0x56468ea48480, ra=0x56468eae37f0, group=0, quickcopy=-2147483648)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Kitchen) from localhost.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] Content-Length: 134
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=20, read_cb=0x56468e4b1b00, write_cb=0x56468e4b1110, data=0x56468eae80e0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] con->http=0x56468eaea790
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=134, response=0x56468eae4f10(IPP_STATE_DATA), pipe_pid=0, file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] bytes=0, http_state=0, data_remaining=134
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] Flushing write buffer.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] New state is HTTP_STATE_WAITING
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=20, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eae80e0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] Waiting for request.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 4] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 4] POST / HTTP/1.1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 4] Read: status=200, state=6
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: uri="/", limit=10...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/log(10) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/conf(11) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin(6) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /(1) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: best=/
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 4] con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 4] No authentication data provided.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 4] 2.0 Get-Printer-Attributes 53
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest(0x56468ead2360[4]): operation_id=000b(Get-Printer-Attributes)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: operation-attributes-tag
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: printer-uri uri 'ipp://localhost/printers/Kitchen'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: Get-Printer-Attributes ipp://localhost/printers/Kitchen
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: get_printer_attrs(0x56468ead2360[4], ipp://localhost/printers/Kitchen)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp(p=0x56468ea395f0, op=b(Get-Printer-Attributes))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp: Found wildcard match...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea3da80((null))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: op=0(0x0000)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: printer-icons="http://localhost/icons/Kitchen.png"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: add_printer_state_reasons(0x56468ead2360[4], 0x56468ea5b5c0[Kitchen])
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: printer-uri-supported="ipp://localhost/printers/Kitchen"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: add_queued_job_count(0x56468ead2360[4], 0x56468ea5b5c0[Kitchen])
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae44c0, from=0x56468ea4a760, ra=(nil), group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae44c0, from=0x56468ea4d680, ra=(nil), group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae44c0, from=0x56468ea48480, ra=(nil), group=0, quickcopy=-2147483648)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 4] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Kitchen) from localhost.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 4] Content-Length: 9293
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 4] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=12, read_cb=0x56468e4b1b00, write_cb=0x56468e4b1110, data=0x56468ead2360)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 4] con->http=0x56468ead8230
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 4] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9293, response=0x56468eae44c0(IPP_STATE_DATA), pipe_pid=0, file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 4] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 4] bytes=0, http_state=0, data_remaining=9293
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 4] Flushing write buffer.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 4] New state is HTTP_STATE_WAITING
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=12, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468ead2360)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 4] Waiting for request.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAcceptClient(lis=0x56468ea38d40(3)) Clients=7
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] Server address is "/var/run/cups/cups.sock".
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] Accepted from localhost (Domain)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=22, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eae6280)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] Waiting for request.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] GET /printers/Kitchen.ppd HTTP/1.1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] Read: status=200, state=3
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: uri="/printers/Kitchen", limit=2...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/log(10) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/conf(11) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin(6) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /(1) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: best=/
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] con->uri="/printers/Kitchen.ppd", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] No authentication data provided.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/printers/Kitchen.ppd", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] Processing GET /printers/Kitchen.ppd
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] get_file: filestats=0x7ffdff86b360, filename=0x7ffdff86b3f0, len=1024, returning "/etc/cups/ppd/Kitchen.ppd".
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] filename="/etc/cups/ppd/Kitchen.ppd", type=application/vnd.cups-ppd
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] is_cgi: filename="/etc/cups/ppd/Kitchen.ppd", filestats=0x7ffdff86b360, type=application/vnd.cups-ppd, returning 0.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] check_if_modified: filestats=0x7ffdff86b360(97135, 1534190191)) If-Modified-Since="Tue, 14 Aug 2018 10:51:22 GMT"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] cupsdSendError code=304, auth_type=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] cupsdSendHeader: code=304, type="(null)", auth_type=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 30] Closing connection.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=22)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=-1)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] POST /printers/Kitchen HTTP/1.1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] Read: status=200, state=6
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: uri="/printers/Kitchen", limit=10...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/log(10) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/conf(11) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin(6) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /(1) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: best=/
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] con->uri="/printers/Kitchen", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] No authentication data provided.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/printers/Kitchen", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] 2.0 Get-Printer-Attributes 54
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest(0x56468eae80e0[11]): operation_id=000b(Get-Printer-Attributes)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: operation-attributes-tag
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: printer-uri uri 'ipp://localhost/printers/Kitchen'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'member-uris,printer-uri-supported'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: Get-Printer-Attributes ipp://localhost/printers/Kitchen
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: get_printer_attrs(0x56468eae80e0[11], ipp://localhost/printers/Kitchen)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp(p=0x56468ea395f0, op=b(Get-Printer-Attributes))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp: Found wildcard match...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/printers/Kitchen", con->best=0x56468ea3da80((null))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: op=0(0x0000)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: printer-uri-supported="ipp://localhost/printers/Kitchen"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae5020, from=0x56468ea4a760, ra=0x56468eae39a0, group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae5020, from=0x56468ea4d680, ra=0x56468eae39a0, group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae5020, from=0x56468ea48480, ra=0x56468eae39a0, group=0, quickcopy=-2147483648)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Kitchen) from localhost.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] Content-Length: 134
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=20, read_cb=0x56468e4b1b00, write_cb=0x56468e4b1110, data=0x56468eae80e0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] con->http=0x56468eaea790
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=134, response=0x56468eae5020(IPP_STATE_DATA), pipe_pid=0, file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] bytes=0, http_state=0, data_remaining=134
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] Flushing write buffer.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] New state is HTTP_STATE_WAITING
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=20, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eae80e0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 11] Waiting for request.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 29] Closing connection.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=19)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=-1)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAcceptClient(lis=0x56468ea38d40(3)) Clients=6
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] Server address is "/var/run/cups/cups.sock".
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] Accepted from localhost (Domain)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=19, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eae50c0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] Waiting for request.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] GET /printers/Kitchen.ppd HTTP/1.1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] Read: status=200, state=3
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: uri="/printers/Kitchen", limit=2...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/log(10) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/conf(11) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin(6) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /(1) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: best=/
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] con->uri="/printers/Kitchen.ppd", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] No authentication data provided.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/printers/Kitchen.ppd", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] Processing GET /printers/Kitchen.ppd
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] get_file: filestats=0x7ffdff86b360, filename=0x7ffdff86b3f0, len=1024, returning "/etc/cups/ppd/Kitchen.ppd".
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] filename="/etc/cups/ppd/Kitchen.ppd", type=application/vnd.cups-ppd
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] is_cgi: filename="/etc/cups/ppd/Kitchen.ppd", filestats=0x7ffdff86b360, type=application/vnd.cups-ppd, returning 0.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] check_if_modified: filestats=0x7ffdff86b360(97135, 1534190191)) If-Modified-Since="Tue, 14 Aug 2018 10:51:22 GMT"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] cupsdSendError code=304, auth_type=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] cupsdSendHeader: code=304, type="(null)", auth_type=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 31] Closing connection.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=19)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=-1)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] POST / HTTP/1.1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Read: status=200, state=6
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: uri="/", limit=10...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/log(10) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/conf(11) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin(6) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /(1) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: best=/
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] No authentication data provided.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] 2.0 CUPS-Get-Default 37
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest(0x56468eadeea0[7]): operation_id=4001(CUPS-Get-Default)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: operation-attributes-tag
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: CUPS-Get-Default
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: get_default(0x56468eadeea0[7])
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp(p=0x56468ea395f0, op=4001(CUPS-Get-Default))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp: Found wildcard match...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea3da80((null))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: op=0(0x0000)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: printer-icons="http://localhost/icons/Kitchen.png"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: add_printer_state_reasons(0x56468eadeea0[7], 0x56468ea5b5c0[Kitchen])
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: printer-uri-supported="ipp://localhost/printers/Kitchen"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: add_queued_job_count(0x56468eadeea0[7], 0x56468ea5b5c0[Kitchen])
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4fe0, from=0x56468ea4a760, ra=(nil), group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4fe0, from=0x56468ea4d680, ra=(nil), group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae4fe0, from=0x56468ea48480, ra=(nil), group=0, quickcopy=-2147483648)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Content-Length: 9293
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=18, read_cb=0x56468e4b1b00, write_cb=0x56468e4b1110, data=0x56468eadeea0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] con->http=0x56468eae0060
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9293, response=0x56468eae4fe0(IPP_STATE_DATA), pipe_pid=0, file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] bytes=0, http_state=0, data_remaining=9293
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Flushing write buffer.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] New state is HTTP_STATE_WAITING
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=18, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eadeea0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 7] Waiting for request.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] POST / HTTP/1.1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Read: status=200, state=6
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: uri="/", limit=10...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/log(10) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/conf(11) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin(6) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /(1) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: best=/
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] No authentication data provided.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] 2.0 CUPS-Get-Printers 55
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest(0x56468eac8260[1]): operation_id=4002(CUPS-Get-Printers)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: operation-attributes-tag
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'printer-name,printer-type,printer-location,printer-info,printer-make-and-model,printer-state,printer-state-message,printer-state-reasons,printer-uri-supported,device-uri,printer-is-shared'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: CUPS-Get-Printers
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: get_printers(0x56468eac8260[1], 0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp(p=0x56468ea395f0, op=4002(CUPS-Get-Printers))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp: Found wildcard match...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea3da80((null))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: op=0(0x0000)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: add_printer_state_reasons(0x56468eac8260[1], 0x56468ea5b5c0[Kitchen])
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: printer-uri-supported="ipp://localhost/printers/Kitchen"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ff0, from=0x56468ea4a760, ra=0x56468eae5a30, group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ff0, from=0x56468ea4d680, ra=0x56468eae5a30, group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ff0, from=0x56468ea48480, ra=0x56468eae5a30, group=0, quickcopy=-2147483648)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: add_printer_state_reasons(0x56468eac8260[1], 0x56468ea49ad0[Office])
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: printer-uri-supported="ipp://localhost/printers/Office"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ff0, from=0x56468ea4cc10, ra=0x56468eae5a30, group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ff0, from=0x56468ea6b7a0, ra=0x56468eae5a30, group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ff0, from=0x56468ea48480, ra=0x56468eae5a30, group=0, quickcopy=-2147483648)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Content-Length: 1066
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=11, read_cb=0x56468e4b1b00, write_cb=0x56468e4b1110, data=0x56468eac8260)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] con->http=0x56468eacf150
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1066, response=0x56468eae3ff0(IPP_STATE_DATA), pipe_pid=0, file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] bytes=0, http_state=0, data_remaining=1066
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Flushing write buffer.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] New state is HTTP_STATE_WAITING
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=11, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eac8260)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Waiting for request.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] POST / HTTP/1.1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Read: status=200, state=6
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: uri="/", limit=10...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/log(10) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/conf(11) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin(6) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /(1) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: best=/
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] No authentication data provided.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] 2.0 CUPS-Get-Classes 56
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest(0x56468eac8260[1]): operation_id=4005(CUPS-Get-Classes)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: operation-attributes-tag
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'printer-name,member-names'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: CUPS-Get-Classes
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: get_printers(0x56468eac8260[1], 1)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp(p=0x56468ea395f0, op=4005(CUPS-Get-Classes))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp: Found wildcard match...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea3da80((null))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: op=0(0x0000)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Content-Length: 75
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=11, read_cb=0x56468e4b1b00, write_cb=0x56468e4b1110, data=0x56468eac8260)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] con->http=0x56468eacf150
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x56468eae4fe0(IPP_STATE_DATA), pipe_pid=0, file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] bytes=0, http_state=0, data_remaining=75
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Flushing write buffer.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] New state is HTTP_STATE_WAITING
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=11, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eac8260)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Waiting for request.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] POST / HTTP/1.1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Read: status=200, state=6
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: uri="/", limit=10...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/log(10) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/conf(11) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin(6) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /(1) Limit 7f
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: best=/
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] No authentication data provided.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] 2.0 CUPS-Get-Default 57
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest(0x56468eac8260[1]): operation_id=4001(CUPS-Get-Default)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: operation-attributes-tag
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us'
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: CUPS-Get-Default
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: get_default(0x56468eac8260[1])
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp(p=0x56468ea395f0, op=4001(CUPS-Get-Default))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp: Found wildcard match...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea3da80((null))
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: op=0(0x0000)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: printer-icons="http://localhost/icons/Kitchen.png"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: add_printer_state_reasons(0x56468eac8260[1], 0x56468ea5b5c0[Kitchen])
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: printer-uri-supported="ipp://localhost/printers/Kitchen"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: add_queued_job_count(0x56468eac8260[1], 0x56468ea5b5c0[Kitchen])
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ff0, from=0x56468ea4a760, ra=(nil), group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ff0, from=0x56468ea4d680, ra=(nil), group=0, quickcopy=0)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: copy_attrs(to=0x56468eae3ff0, from=0x56468ea48480, ra=(nil), group=0, quickcopy=-2147483648)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Content-Length: 9293
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=11, read_cb=0x56468e4b1b00, write_cb=0x56468e4b1110, data=0x56468eac8260)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] con->http=0x56468eacf150
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9293, response=0x56468eae3ff0(IPP_STATE_DATA), pipe_pid=0, file=-1
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] bytes=0, http_state=0, data_remaining=9293
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Flushing write buffer.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] New state is HTTP_STATE_WAITING
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=11, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eac8260)
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: [Client 1] Waiting for request.
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:47 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:48 rawhide.wg9s.com cupsd[2819]: Expiring subscriptions...
>Aug 14 06:51:48 rawhide.wg9s.com cupsd[2819]: cupsdCheckJobs: 1 active jobs, sleeping=0, ac-power=-1, reload=0, curtime=1534243908
>Aug 14 06:51:48 rawhide.wg9s.com cupsd[2819]: cupsdCheckJobs: Job 16 - dest="Kitchen", printer=0x56468ea5b5c0, state=5, cancel_time=1534254682, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0
>Aug 14 06:51:48 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:48 rawhide.wg9s.com cupsd[2819]: select_timeout(0): 30 seconds to write dirty config/state files
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdAcceptClient(lis=0x56468ea38d40(3)) Clients=6
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] Server address is "/var/run/cups/cups.sock".
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] Accepted from localhost (Domain)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=19, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eae50c0)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] Waiting for request.
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: Expiring subscriptions...
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: Report: clients=7
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: Report: jobs=6
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: Report: jobs-active=1
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: Report: printers=2
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: Report: stringpool-string-count=8284
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: Report: stringpool-alloc-bytes=13216
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: Report: stringpool-total-bytes=148544
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] POST / HTTP/1.1
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] Read: status=200, state=6
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: uri="/", limit=10...
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/log(10) Limit 7f
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin/conf(11) Limit 7f
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /admin(6) Limit 7f
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: Location /(1) Limit 7f
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdFindBest: best=/
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] No authentication data provided.
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea35bc0(/)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] 2.0 Cancel-Subscription 58
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest(0x56468eae50c0[32]): operation_id=001b(Cancel-Subscription)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: operation-attributes-tag
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8'
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us'
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: printer-uri uri '/'
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: requesting-user-name nameWithoutLanguage 'wag'
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdProcessIPPRequest: notify-subscription-id integer '43'
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: Cancel-Subscription /
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cancel_subscription(con=0x56468eae50c0[32], sub_id=43)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp(p=0x56468ea395f0, op=1b(Cancel-Subscription))
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdFindPolicyOp: Found exact match...
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: con->uri="/", con->best=0x56468ea3acb0((null))
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: owner="wag"
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=2
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: op=1b(Cancel-Subscription)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: requesting-user-name="wag"
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdIsAuthorized: Checking user membership...
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdMarkDirty(----S)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] Returning IPP successful-ok for Cancel-Subscription (/) from localhost.
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] Content-Length: 75
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=19, read_cb=0x56468e4b1b00, write_cb=0x56468e4b1110, data=0x56468eae50c0)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] con->http=0x56468eaf3120
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x56468eae4480(IPP_STATE_DATA), pipe_pid=0, file=-1
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] bytes=0, http_state=0, data_remaining=75
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: REQUEST localhost - - "POST / HTTP/1.1" 200 151 Cancel-Subscription successful-ok
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] Flushing write buffer.
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] New state is HTTP_STATE_WAITING
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdAddSelect(fd=19, read_cb=0x56468e4b1b00, write_cb=(nil), data=0x56468eae50c0)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] Waiting for request.
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 32] Closing connection.
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=19)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=-1)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 6] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 6] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 6] Closing connection.
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=13)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=-1)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 11] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 11] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 11] Closing connection.
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=20)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=-1)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 4] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 4] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 4] Closing connection.
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=12)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=-1)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 1] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 1] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: [Client 1] Closing connection.
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=11)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=-1)
>Aug 14 06:51:52 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:53 rawhide.wg9s.com cupsd[2819]: [Client 12] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
>Aug 14 06:51:53 rawhide.wg9s.com cupsd[2819]: [Client 12] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
>Aug 14 06:51:53 rawhide.wg9s.com cupsd[2819]: [Client 12] Closing connection.
>Aug 14 06:51:53 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>Aug 14 06:51:53 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=21)
>Aug 14 06:51:53 rawhide.wg9s.com cupsd[2819]: cupsdRemoveSelect(fd=-1)
>Aug 14 06:51:53 rawhide.wg9s.com cupsd[2819]: Expiring subscriptions...
>Aug 14 06:51:53 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:54 rawhide.wg9s.com cupsd[2819]: Expiring subscriptions...
>Aug 14 06:51:54 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:51:54 rawhide.wg9s.com cupsd[2819]: select_timeout(0): 24 seconds to write dirty config/state files
>Aug 14 06:52:02 rawhide.wg9s.com cupsd[2819]: Unable to locate printer \"EPSON050E62.local\".
>Aug 14 06:52:02 rawhide.wg9s.com cupsd[2819]: Expiring subscriptions...
>Aug 14 06:52:02 rawhide.wg9s.com cupsd[2819]: cupsdCheckJobs: 1 active jobs, sleeping=0, ac-power=-1, reload=0, curtime=1534243922
>Aug 14 06:52:02 rawhide.wg9s.com cupsd[2819]: cupsdCheckJobs: Job 16 - dest="Kitchen", printer=0x56468ea5b5c0, state=5, cancel_time=1534254682, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0
>Aug 14 06:52:02 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:52:03 rawhide.wg9s.com cupsd[2819]: Expiring subscriptions...
>Aug 14 06:52:03 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:52:03 rawhide.wg9s.com cupsd[2819]: select_timeout(0): 15 seconds to write dirty config/state files
>Aug 14 06:52:18 rawhide.wg9s.com cupsd[2819]: Saving job.cache...
>Aug 14 06:52:18 rawhide.wg9s.com cupsd[2819]: cupsdSaveJob(job=0x56468eac1e20(16)): job->attrs=0x56468eaa38c0
>Aug 14 06:52:18 rawhide.wg9s.com cupsd[2819]: Saving subscriptions.conf...
>Aug 14 06:52:18 rawhide.wg9s.com cupsd[2819]: cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
>Aug 14 06:52:18 rawhide.wg9s.com cupsd[2819]: Expiring subscriptions...
>Aug 14 06:52:18 rawhide.wg9s.com cupsd[2819]: cupsdCheckJobs: 1 active jobs, sleeping=0, ac-power=-1, reload=0, curtime=1534243938
>Aug 14 06:52:18 rawhide.wg9s.com cupsd[2819]: cupsdCheckJobs: Job 16 - dest="Kitchen", printer=0x56468ea5b5c0, state=5, cancel_time=1534254682, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0
>Aug 14 06:52:18 rawhide.wg9s.com cupsd[2819]: select_timeout: JobHistoryUpdate=0
>Aug 14 06:52:18 rawhide.wg9s.com cupsd[2819]: select_timeout(0): 270 seconds to timeout a client connection

Actions: View

Attachments on bug 1614978: 1475665 | 1475666 | 1475667 | 1475670 | 1475812 | 1475813 | 1476421 | 1476422 | 1476423