Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 1482399 Details for
Bug 1627834
hpcups results in "Filter failed" for HP OfficeJet Pro 6970
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
Journal for CUPS while a job was printed that resulted in the described error
troubleshoot-logs.txt (text/plain), 221.49 KB, created by
Stefan Schwarzer
on 2018-09-11 16:11:53 UTC
(
hide
)
Description:
Journal for CUPS while a job was printed that resulted in the described error
Filename:
MIME Type:
Creator:
Stefan Schwarzer
Created:
2018-09-11 16:11:53 UTC
Size:
221.49 KB
patch
obsolete
>-- Logs begin at Wed 2017-11-08 07:55:56 CET, end at Tue 2018-09-11 13:31:24 CEST. -- >Sep 11 13:30:01 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:01 warpy cupsd[6306]: cupsdCheckJobs: 1 active jobs, sleeping=0, ac-power=-1, reload=0, curtime=1536665401 >Sep 11 13:30:01 warpy cupsd[6306]: cupsdCheckJobs: Job 697 - dest="HP-OfficeJet-Pro-6970", printer=(nil), state=6, cancel_time=0, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0 >Sep 11 13:30:01 warpy cupsd[6306]: Report: clients=0 >Sep 11 13:30:01 warpy cupsd[6306]: Report: jobs=16 >Sep 11 13:30:01 warpy cupsd[6306]: Report: jobs-active=1 >Sep 11 13:30:01 warpy cupsd[6306]: Report: printers=2 >Sep 11 13:30:01 warpy cupsd[6306]: Report: stringpool-string-count=10649 >Sep 11 13:30:01 warpy cupsd[6306]: Report: stringpool-alloc-bytes=15216 >Sep 11 13:30:01 warpy cupsd[6306]: Report: stringpool-total-bytes=229880 >Sep 11 13:30:01 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:01 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:01 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:01 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:01 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:01 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:02 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:02 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:02 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:03 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:03 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:03 warpy cupsd[6306]: select_timeout(0): 35603 seconds to update job history >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=0 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] Accepted from localhost (Domain) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAddSelect(fd=12, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3c05d0) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] Waiting for request. >Sep 11 13:30:15 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:15 warpy cupsd[6306]: cupsdDeleteCert: Removing certificate for PID 0. >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAddCert: Adding certificate for PID 0 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAddCert: NumSystemGroups=3 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdCheckJobs: 1 active jobs, sleeping=0, ac-power=-1, reload=0, curtime=1536665415 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdCheckJobs: Job 697 - dest="HP-OfficeJet-Pro-6970", printer=(nil), state=6, cancel_time=0, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] POST / HTTP/1.1 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Active clients" >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] Read: status=200, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: uri="/", limit=10... >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] No authentication data provided. >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] 1.1 CUPS-Get-Printers 11 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab3c05d0[1683]): operation_id=4002(CUPS-Get-Printers) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:15 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:15 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:15 warpy cupsd[6306]: 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' >Sep 11 13:30:15 warpy cupsd[6306]: cupsdProcessIPPRequest: requesting-user-name nameWithoutLanguage 'schwa' >Sep 11 13:30:15 warpy cupsd[6306]: CUPS-Get-Printers >Sep 11 13:30:15 warpy cupsd[6306]: get_printers(0x55faab3c05d0[1683], 0) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=4002(CUPS-Get-Printers)) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindPolicyOp: Found wildcard match... >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab294110((null)) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: op=0(0x0000) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:15 warpy cupsd[6306]: add_printer_state_reasons(0x55faab3c05d0[1683], 0x55faab2d43a0[HP-OfficeJet-Pro-6970]) >Sep 11 13:30:15 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970" >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab3b3ab0, from=0x55faab31a1b0, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab3b3ab0, from=0x55faab326610, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab3b3ab0, from=0x55faab2b1c10, ra=0x55faab3bd060, group=0, quickcopy=-2147483648) >Sep 11 13:30:15 warpy cupsd[6306]: add_printer_state_reasons(0x55faab3c05d0[1683], 0x55faab31e470[HP-OfficeJet-Pro-6970-hpijs]) >Sep 11 13:30:15 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970-hpijs" >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab3b3ab0, from=0x55faab35ec50, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab3b3ab0, from=0x55faab3a67e0, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab3b3ab0, from=0x55faab2b1c10, ra=0x55faab3bd060, group=0, quickcopy=-2147483648) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] Content-Length: 3077 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAddSelect(fd=12, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab3c05d0) >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] con->http=0x55faab3f5b70 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3077, response=0x55faab3b3ab0(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] bytes=0, http_state=0, data_remaining=3077 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] Flushing write buffer. >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] New state is HTTP_STATE_WAITING >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAddSelect(fd=12, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3c05d0) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] Waiting for request. >Sep 11 13:30:15 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients" >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] POST / HTTP/1.1 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] Read: status=200, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: uri="/", limit=10... >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] No authentication data provided. >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] 1.1 CUPS-Get-Printers 12 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab3c05d0[1683]): operation_id=4002(CUPS-Get-Printers) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:15 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:15 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:15 warpy cupsd[6306]: 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' >Sep 11 13:30:15 warpy cupsd[6306]: cupsdProcessIPPRequest: requesting-user-name nameWithoutLanguage 'schwa' >Sep 11 13:30:15 warpy cupsd[6306]: CUPS-Get-Printers >Sep 11 13:30:15 warpy cupsd[6306]: get_printers(0x55faab3c05d0[1683], 0) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=4002(CUPS-Get-Printers)) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindPolicyOp: Found wildcard match... >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab294110((null)) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: op=0(0x0000) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:15 warpy cupsd[6306]: add_printer_state_reasons(0x55faab3c05d0[1683], 0x55faab2d43a0[HP-OfficeJet-Pro-6970]) >Sep 11 13:30:15 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970" >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab3cc1f0, from=0x55faab31a1b0, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab3cc1f0, from=0x55faab326610, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab3cc1f0, from=0x55faab2b1c10, ra=0x55faab3bd060, group=0, quickcopy=-2147483648) >Sep 11 13:30:15 warpy cupsd[6306]: add_printer_state_reasons(0x55faab3c05d0[1683], 0x55faab31e470[HP-OfficeJet-Pro-6970-hpijs]) >Sep 11 13:30:15 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970-hpijs" >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab3cc1f0, from=0x55faab35ec50, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab3cc1f0, from=0x55faab3a67e0, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab3cc1f0, from=0x55faab2b1c10, ra=0x55faab3bd060, group=0, quickcopy=-2147483648) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] Content-Length: 3077 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAddSelect(fd=12, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab3c05d0) >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] con->http=0x55faab3f5b70 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3077, response=0x55faab3cc1f0(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] bytes=0, http_state=0, data_remaining=3077 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] Flushing write buffer. >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] New state is HTTP_STATE_WAITING >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAddSelect(fd=12, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3c05d0) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1683] Waiting for request. >Sep 11 13:30:15 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients" >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=1 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1684] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1684] Accepted from localhost (Domain) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAddSelect(fd=13, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ffb00) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1684] Waiting for request. >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=2 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Active clients" >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Accepted from localhost (Domain) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAddSelect(fd=14, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee5a0) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Waiting for request. >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1684] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1684] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1684] Closing connection. >Sep 11 13:30:15 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients" >Sep 11 13:30:15 warpy cupsd[6306]: cupsdRemoveSelect(fd=13) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] POST / HTTP/1.1 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=200, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: uri="/", limit=10... >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] No authentication data provided. >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=581, request=0x55faab3b3ab0(IPP_STATE_IDLE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=573, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=544, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=507, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=477, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=440, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=414, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=398, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=377, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=360, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=334, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=308, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=290, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=269, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=239, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=214, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=191, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=174, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=151, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=129, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=102, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=61, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=40, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=16, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Read: status=100, state=6 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] 1.1 CUPS-Get-Printers 1 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab3ee5a0[1685]): operation_id=4002(CUPS-Get-Printers) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:15 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:15 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:15 warpy cupsd[6306]: cupsdProcessIPPRequest: requesting-user-name nameWithoutLanguage 'schwa' >Sep 11 13:30:15 warpy cupsd[6306]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'printer-name,printer-uri-supported,member-uris,printer-location,printer-info,printer-state-message,printer-state-reasons,printer-state,queued-job-count,printer-is-accepting-jobs,job-sheets-supported,job-sheets-default,printer-type,auth-info-required,number-up-default,ipp-versions-supported,multiple-document-handling-supported,copies-supported,number-up-supported,device-uri' >Sep 11 13:30:15 warpy cupsd[6306]: CUPS-Get-Printers >Sep 11 13:30:15 warpy cupsd[6306]: get_printers(0x55faab3ee5a0[1685], 0) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=4002(CUPS-Get-Printers)) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindPolicyOp: Found wildcard match... >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab294110((null)) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: op=0(0x0000) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:15 warpy cupsd[6306]: add_printer_state_reasons(0x55faab3ee5a0[1685], 0x55faab2d43a0[HP-OfficeJet-Pro-6970]) >Sep 11 13:30:15 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970" >Sep 11 13:30:15 warpy cupsd[6306]: add_queued_job_count(0x55faab3ee5a0[1685], 0x55faab2d43a0[HP-OfficeJet-Pro-6970]) >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab4091b0, from=0x55faab31a1b0, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab4091b0, from=0x55faab326610, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab4091b0, from=0x55faab2b1c10, ra=0x55faab3bd060, group=0, quickcopy=-2147483648) >Sep 11 13:30:15 warpy cupsd[6306]: add_printer_state_reasons(0x55faab3ee5a0[1685], 0x55faab31e470[HP-OfficeJet-Pro-6970-hpijs]) >Sep 11 13:30:15 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970-hpijs" >Sep 11 13:30:15 warpy cupsd[6306]: add_queued_job_count(0x55faab3ee5a0[1685], 0x55faab31e470[HP-OfficeJet-Pro-6970-hpijs]) >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab4091b0, from=0x55faab35ec50, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab4091b0, from=0x55faab3a67e0, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:15 warpy cupsd[6306]: copy_attrs(to=0x55faab4091b0, from=0x55faab2b1c10, ra=0x55faab3bd060, group=0, quickcopy=-2147483648) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Content-Length: 1909 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAddSelect(fd=14, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab3ee5a0) >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] con->http=0x55faab327820 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1909, response=0x55faab4091b0(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] bytes=0, http_state=0, data_remaining=1909 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Flushing write buffer. >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] New state is HTTP_STATE_WAITING >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAddSelect(fd=14, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee5a0) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Waiting for request. >Sep 11 13:30:15 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients" >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=2 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] Accepted from localhost (Domain) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAddSelect(fd=13, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ffb00) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] Waiting for request. >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1685] Closing connection. >Sep 11 13:30:15 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients" >Sep 11 13:30:15 warpy cupsd[6306]: cupsdRemoveSelect(fd=14) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] GET /printers/HP-OfficeJet-Pro-6970.ppd HTTP/1.1 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] Read: status=200, state=3 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: uri="/printers/HP-OfficeJet-Pro-6970", limit=2... >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:15 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->uri="/printers/HP-OfficeJet-Pro-6970.ppd", con->best=0x55faab28f2d0(/) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] No authentication data provided. >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/printers/HP-OfficeJet-Pro-6970.ppd", con->best=0x55faab28f2d0(/) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] Processing GET /printers/HP-OfficeJet-Pro-6970.ppd >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] get_file: filestats=0x7ffc36e4c560, filename=0x7ffc36e4c5f0, len=1024, returning "/etc/cups/ppd/HP-OfficeJet-Pro-6970.ppd". >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] filename="/etc/cups/ppd/HP-OfficeJet-Pro-6970.ppd", type=application/vnd.cups-ppd >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] is_cgi: filename="/etc/cups/ppd/HP-OfficeJet-Pro-6970.ppd", filestats=0x7ffc36e4c560, type=application/vnd.cups-ppd, returning 0. >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] write_file: code=200, filename="/etc/cups/ppd/HP-OfficeJet-Pro-6970.ppd" (14), type="application/vnd.cups-ppd", filestats=0x7ffc36e4c560. >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdSendHeader: code=200, type="application/vnd.cups-ppd", auth_type=0 >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAddSelect(fd=13, read_cb=(nil), write_cb=0x55faa95a03a0, data=0x55faab3ffb00) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] Sending file. >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] con->http=0x55faab31a740 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44031, response=(nil)(), pipe_pid=0, file=14 >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] Flushing write buffer. >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] New state is HTTP_STATE_WAITING >Sep 11 13:30:15 warpy cupsd[6306]: cupsdAddSelect(fd=13, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ffb00) >Sep 11 13:30:15 warpy cupsd[6306]: [Client 1686] Waiting for request. >Sep 11 13:30:15 warpy cupsd[6306]: cupsdRemoveSelect(fd=14) >Sep 11 13:30:15 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients" >Sep 11 13:30:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1686] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1686] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1686] Closing connection. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Not busy" >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=13) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:16 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=1 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1687] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1687] Accepted from localhost (Domain) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=13, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ffb00) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1687] Waiting for request. >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=2 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Active clients" >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Accepted from localhost (Domain) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=14, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee5a0) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Waiting for request. >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1687] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1687] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1687] Closing connection. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients" >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=13) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] POST / HTTP/1.1 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=200, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: uri="/", limit=10... >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] No authentication data provided. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=581, request=0x55faab4091b0(IPP_STATE_IDLE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=573, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=544, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=507, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=477, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=440, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=414, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=398, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=377, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=360, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=334, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=308, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=290, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=269, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=239, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=214, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=191, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=174, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=151, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=129, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=102, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=61, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=40, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=16, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] 1.1 CUPS-Get-Printers 1 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab3ee5a0[1688]): operation_id=4002(CUPS-Get-Printers) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: requesting-user-name nameWithoutLanguage 'schwa' >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'printer-name,printer-uri-supported,member-uris,printer-location,printer-info,printer-state-message,printer-state-reasons,printer-state,queued-job-count,printer-is-accepting-jobs,job-sheets-supported,job-sheets-default,printer-type,auth-info-required,number-up-default,ipp-versions-supported,multiple-document-handling-supported,copies-supported,number-up-supported,device-uri' >Sep 11 13:30:16 warpy cupsd[6306]: CUPS-Get-Printers >Sep 11 13:30:16 warpy cupsd[6306]: get_printers(0x55faab3ee5a0[1688], 0) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=4002(CUPS-Get-Printers)) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindPolicyOp: Found wildcard match... >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab294110((null)) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: op=0(0x0000) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:16 warpy cupsd[6306]: add_printer_state_reasons(0x55faab3ee5a0[1688], 0x55faab2d43a0[HP-OfficeJet-Pro-6970]) >Sep 11 13:30:16 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970" >Sep 11 13:30:16 warpy cupsd[6306]: add_queued_job_count(0x55faab3ee5a0[1688], 0x55faab2d43a0[HP-OfficeJet-Pro-6970]) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab394bb0, from=0x55faab31a1b0, ra=0x55faab28f0a0, group=0, quickcopy=0) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab394bb0, from=0x55faab326610, ra=0x55faab28f0a0, group=0, quickcopy=0) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab394bb0, from=0x55faab2b1c10, ra=0x55faab28f0a0, group=0, quickcopy=-2147483648) >Sep 11 13:30:16 warpy cupsd[6306]: add_printer_state_reasons(0x55faab3ee5a0[1688], 0x55faab31e470[HP-OfficeJet-Pro-6970-hpijs]) >Sep 11 13:30:16 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970-hpijs" >Sep 11 13:30:16 warpy cupsd[6306]: add_queued_job_count(0x55faab3ee5a0[1688], 0x55faab31e470[HP-OfficeJet-Pro-6970-hpijs]) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab394bb0, from=0x55faab35ec50, ra=0x55faab28f0a0, group=0, quickcopy=0) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab394bb0, from=0x55faab3a67e0, ra=0x55faab28f0a0, group=0, quickcopy=0) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab394bb0, from=0x55faab2b1c10, ra=0x55faab28f0a0, group=0, quickcopy=-2147483648) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Content-Length: 1909 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=14, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab3ee5a0) >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] con->http=0x55faab327820 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1909, response=0x55faab394bb0(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] bytes=0, http_state=0, data_remaining=1909 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Flushing write buffer. >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] New state is HTTP_STATE_WAITING >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=14, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee5a0) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Waiting for request. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients" >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1688] Closing connection. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Not busy" >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=14) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=1 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1689] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1689] Accepted from localhost (Domain) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=13, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ffb00) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1689] Waiting for request. >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=2 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Active clients" >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] Accepted from localhost (Domain) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=14, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee5a0) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] Waiting for request. >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1689] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1689] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1689] Closing connection. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients" >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=13) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] POST / HTTP/1.1 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] Read: status=200, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: uri="/", limit=10... >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] No authentication data provided. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] 1.1 CUPS-Get-Printers 1 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab3ee5a0[1690]): operation_id=4002(CUPS-Get-Printers) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: requesting-user-name nameWithoutLanguage 'schwa' >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'printer-name,printer-uri-supported,member-uris,printer-location,printer-info,printer-state-message,printer-state-reasons,printer-state,queued-job-count,printer-is-accepting-jobs,job-sheets-supported,job-sheets-default,printer-type,auth-info-required,number-up-default,ipp-versions-supported,multiple-document-handling-supported,copies-supported,number-up-supported,device-uri' >Sep 11 13:30:16 warpy cupsd[6306]: CUPS-Get-Printers >Sep 11 13:30:16 warpy cupsd[6306]: get_printers(0x55faab3ee5a0[1690], 0) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=4002(CUPS-Get-Printers)) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindPolicyOp: Found wildcard match... >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab294110((null)) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: op=0(0x0000) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:16 warpy cupsd[6306]: add_printer_state_reasons(0x55faab3ee5a0[1690], 0x55faab2d43a0[HP-OfficeJet-Pro-6970]) >Sep 11 13:30:16 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970" >Sep 11 13:30:16 warpy cupsd[6306]: add_queued_job_count(0x55faab3ee5a0[1690], 0x55faab2d43a0[HP-OfficeJet-Pro-6970]) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab3776a0, from=0x55faab31a1b0, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab3776a0, from=0x55faab326610, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab3776a0, from=0x55faab2b1c10, ra=0x55faab3bd060, group=0, quickcopy=-2147483648) >Sep 11 13:30:16 warpy cupsd[6306]: add_printer_state_reasons(0x55faab3ee5a0[1690], 0x55faab31e470[HP-OfficeJet-Pro-6970-hpijs]) >Sep 11 13:30:16 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970-hpijs" >Sep 11 13:30:16 warpy cupsd[6306]: add_queued_job_count(0x55faab3ee5a0[1690], 0x55faab31e470[HP-OfficeJet-Pro-6970-hpijs]) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab3776a0, from=0x55faab35ec50, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab3776a0, from=0x55faab3a67e0, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab3776a0, from=0x55faab2b1c10, ra=0x55faab3bd060, group=0, quickcopy=-2147483648) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] Content-Length: 1909 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=14, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab3ee5a0) >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] con->http=0x55faab327820 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1909, response=0x55faab3776a0(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] bytes=0, http_state=0, data_remaining=1909 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] Flushing write buffer. >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] New state is HTTP_STATE_WAITING >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=14, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee5a0) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] Waiting for request. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients" >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1690] Closing connection. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Not busy" >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=14) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=1 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1691] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1691] Accepted from localhost (Domain) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=13, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ffb00) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1691] Waiting for request. >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=2 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Active clients" >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] Accepted from localhost (Domain) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=14, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee5a0) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] Waiting for request. >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1691] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1691] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1691] Closing connection. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients" >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=13) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] POST / HTTP/1.1 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] Read: status=200, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: uri="/", limit=10... >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] No authentication data provided. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] 1.1 CUPS-Get-Printers 1 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab3ee5a0[1692]): operation_id=4002(CUPS-Get-Printers) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: requesting-user-name nameWithoutLanguage 'schwa' >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'printer-name,printer-uri-supported,member-uris,printer-location,printer-info,printer-state-message,printer-state-reasons,printer-state,queued-job-count,printer-is-accepting-jobs,job-sheets-supported,job-sheets-default,printer-type,auth-info-required,number-up-default,ipp-versions-supported,multiple-document-handling-supported,copies-supported,number-up-supported,device-uri' >Sep 11 13:30:16 warpy cupsd[6306]: CUPS-Get-Printers >Sep 11 13:30:16 warpy cupsd[6306]: get_printers(0x55faab3ee5a0[1692], 0) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=4002(CUPS-Get-Printers)) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindPolicyOp: Found wildcard match... >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab294110((null)) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: op=0(0x0000) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:16 warpy cupsd[6306]: add_printer_state_reasons(0x55faab3ee5a0[1692], 0x55faab2d43a0[HP-OfficeJet-Pro-6970]) >Sep 11 13:30:16 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970" >Sep 11 13:30:16 warpy cupsd[6306]: add_queued_job_count(0x55faab3ee5a0[1692], 0x55faab2d43a0[HP-OfficeJet-Pro-6970]) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab3b3ab0, from=0x55faab31a1b0, ra=0x55faab28f0a0, group=0, quickcopy=0) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab3b3ab0, from=0x55faab326610, ra=0x55faab28f0a0, group=0, quickcopy=0) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab3b3ab0, from=0x55faab2b1c10, ra=0x55faab28f0a0, group=0, quickcopy=-2147483648) >Sep 11 13:30:16 warpy cupsd[6306]: add_printer_state_reasons(0x55faab3ee5a0[1692], 0x55faab31e470[HP-OfficeJet-Pro-6970-hpijs]) >Sep 11 13:30:16 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970-hpijs" >Sep 11 13:30:16 warpy cupsd[6306]: add_queued_job_count(0x55faab3ee5a0[1692], 0x55faab31e470[HP-OfficeJet-Pro-6970-hpijs]) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab3b3ab0, from=0x55faab35ec50, ra=0x55faab28f0a0, group=0, quickcopy=0) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab3b3ab0, from=0x55faab3a67e0, ra=0x55faab28f0a0, group=0, quickcopy=0) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab3b3ab0, from=0x55faab2b1c10, ra=0x55faab28f0a0, group=0, quickcopy=-2147483648) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] Content-Length: 1909 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=14, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab3ee5a0) >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] con->http=0x55faab327820 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1909, response=0x55faab3b3ab0(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] bytes=0, http_state=0, data_remaining=1909 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] Flushing write buffer. >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] New state is HTTP_STATE_WAITING >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=14, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee5a0) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] Waiting for request. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients" >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1692] Closing connection. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Not busy" >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=14) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=1 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1693] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1693] Accepted from localhost (Domain) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=13, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ffb00) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1693] Waiting for request. >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=2 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Active clients" >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Accepted from localhost (Domain) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=14, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee5a0) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Waiting for request. >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1693] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1693] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1693] Closing connection. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients" >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=13) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] POST / HTTP/1.1 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=200, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: uri="/", limit=10... >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] No authentication data provided. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=581, request=0x55faab3b3ab0(IPP_STATE_IDLE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=573, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=544, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=507, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=477, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=440, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=414, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=398, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=377, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=360, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=334, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=308, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=290, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=269, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=239, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=214, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=191, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=174, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=151, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=129, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=102, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=61, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=40, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=16, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1, request=0x55faab3b3ab0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] 1.1 CUPS-Get-Printers 1 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab3ee5a0[1694]): operation_id=4002(CUPS-Get-Printers) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: requesting-user-name nameWithoutLanguage 'schwa' >Sep 11 13:30:16 warpy cupsd[6306]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'printer-name,printer-uri-supported,member-uris,printer-location,printer-info,printer-state-message,printer-state-reasons,printer-state,queued-job-count,printer-is-accepting-jobs,job-sheets-supported,job-sheets-default,printer-type,auth-info-required,number-up-default,ipp-versions-supported,multiple-document-handling-supported,copies-supported,number-up-supported,device-uri' >Sep 11 13:30:16 warpy cupsd[6306]: CUPS-Get-Printers >Sep 11 13:30:16 warpy cupsd[6306]: get_printers(0x55faab3ee5a0[1694], 0) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=4002(CUPS-Get-Printers)) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindPolicyOp: Found wildcard match... >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab294110((null)) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: op=0(0x0000) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:16 warpy cupsd[6306]: add_printer_state_reasons(0x55faab3ee5a0[1694], 0x55faab2d43a0[HP-OfficeJet-Pro-6970]) >Sep 11 13:30:16 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970" >Sep 11 13:30:16 warpy cupsd[6306]: add_queued_job_count(0x55faab3ee5a0[1694], 0x55faab2d43a0[HP-OfficeJet-Pro-6970]) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab4091b0, from=0x55faab31a1b0, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab4091b0, from=0x55faab326610, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab4091b0, from=0x55faab2b1c10, ra=0x55faab3bd060, group=0, quickcopy=-2147483648) >Sep 11 13:30:16 warpy cupsd[6306]: add_printer_state_reasons(0x55faab3ee5a0[1694], 0x55faab31e470[HP-OfficeJet-Pro-6970-hpijs]) >Sep 11 13:30:16 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970-hpijs" >Sep 11 13:30:16 warpy cupsd[6306]: add_queued_job_count(0x55faab3ee5a0[1694], 0x55faab31e470[HP-OfficeJet-Pro-6970-hpijs]) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab4091b0, from=0x55faab35ec50, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab4091b0, from=0x55faab3a67e0, ra=0x55faab3bd060, group=0, quickcopy=0) >Sep 11 13:30:16 warpy cupsd[6306]: copy_attrs(to=0x55faab4091b0, from=0x55faab2b1c10, ra=0x55faab3bd060, group=0, quickcopy=-2147483648) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Content-Length: 1909 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=14, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab3ee5a0) >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] con->http=0x55faab327820 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1909, response=0x55faab4091b0(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] bytes=0, http_state=0, data_remaining=1909 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Flushing write buffer. >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] New state is HTTP_STATE_WAITING >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=14, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee5a0) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Waiting for request. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients" >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1694] Closing connection. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Not busy" >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=14) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=1 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1695] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1695] Accepted from localhost (Domain) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=13, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ffb00) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1695] Waiting for request. >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=2 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Active clients" >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Accepted from localhost (Domain) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdAddSelect(fd=14, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee5a0) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Waiting for request. >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1695] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1695] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1695] Closing connection. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients" >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=13) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] POST / HTTP/1.1 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy" >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=200, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: uri="/", limit=10... >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:16 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] No authentication data provided. >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:16 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=581, request=0x55faab4091b0(IPP_STATE_IDLE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=573, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=544, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=507, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=477, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=440, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=414, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=398, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=377, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=360, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=334, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=308, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=290, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=269, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=239, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=214, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=191, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=174, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=151, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=129, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=102, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=61, request=0x55faab4091b0(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:16 warpy cupsd[6306]: [Client 1696] Read: status=100, state=6 >Sep 11 13:30:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:17 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:18 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:19 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:20 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:21 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:22 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:22 warpy cupsd[6306]: [Job ???] Request file type is application/pdf. >Sep 11 13:30:22 warpy cupsd[6306]: [Job 698] Adding start banner page "none". >Sep 11 13:30:22 warpy cupsd[6306]: [Job 698] Adding end banner page "none". >Sep 11 13:30:22 warpy cupsd[6306]: [Job 698] File of type application/pdf queued by "schwa". >Sep 11 13:30:22 warpy cupsd[6306]: [Job 698] Queued on "HP-OfficeJet-Pro-6970" by "schwa". >Sep 11 13:30:22 warpy cupsd[6306]: [Job 698] Started filter /usr/lib/cups/filter/pdftopdf (PID 24137) >Sep 11 13:30:22 warpy cupsd[6306]: [Job 698] Started filter /usr/lib/cups/filter/gstoraster (PID 24138) >Sep 11 13:30:22 warpy cupsd[6306]: [Job 698] Started filter /usr/lib/cups/filter/hpcups (PID 24139) >Sep 11 13:30:22 warpy cupsd[6306]: [Job 698] Started backend /usr/lib/cups/backend/dnssd (PID 24140) >Sep 11 13:30:22 warpy cupsd[6306]: REQUEST localhost - - "POST /printers/HP-OfficeJet-Pro-6970 HTTP/1.1" 200 54600 Print-Job successful-ok >Sep 11 13:30:23 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:23 warpy cupsd[6306]: HP-OfficeJet-Pro-6970 schwa 698 [11/Sep/2018:13:30:23 +0200] 1 1 - localhost cupsfilter(8) - - >Sep 11 13:30:24 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:25 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:25 warpy cupsd[6306]: HP-OfficeJet-Pro-6970 schwa 698 [11/Sep/2018:13:30:25 +0200] 2 1 - localhost cupsfilter(8) - - >Sep 11 13:30:26 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:27 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:28 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:29 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:31 warpy systemd-journald[583]: Suppressed 6030 messages from cups.service >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] IPP/2.0 Get-Printer-Attributes #12 >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] ---- operation-attributes-tag ---- >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] attributes-charset charset utf-8 >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] attributes-natural-language naturalLanguage en-us >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] printer-uri uri ipp://192.168.19.27:631/ipp/print >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] requesting-user-name nameWithoutLanguage schwa >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] requested-attributes 1setOf keyword compression-supported,copies-supported,cups-version,document-format-supported,job-password-encryption-supported,marker-colors,marker-high-levels,marker-levels,marker-low-levels,marker-message,marker-names,marker-types,media-col-supported,multiple-document-handling-supported,operations-supported,print-color-mode-supported,printer-alert,printer-alert-description,printer-is-accepting-jobs,printer-mandatory-job-attributes,printer-state,printer-state-message,printer-state-reasons >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] ---- end-of-attributes-tag ---- >Sep 11 13:30:31 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:31 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] update_reasons(attr=1(spool-area-full-report), s=\"(null)\") >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] op=\' \', new_reasons=0, state_reasons=0 >Sep 11 13:30:31 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] Get-Printer-Attributes: successful-ok (successful-ok) >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] ---- operation-attributes-tag ---- >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] attributes-charset charset utf-8 >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] attributes-natural-language naturalLanguage en >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] ---- printer-attributes-tag ---- >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] printer-state enum processing >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] printer-state-reasons keyword spool-area-full-report >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] printer-state-message textWithoutLanguage >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] operations-supported 1setOf enum Print-Job,Validate-Job,Cancel-Job,Cancel-My-Jobs,Get-Job-Attributes,Get-Jobs,Get-Printer-Attributes,Create-Job,Send-Document,Set-Printer-Attributes,Print-URI,Send-URI,Close-Job,Identify-Printer >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] document-format-supported 1setOf mimeMediaType application/vnd.hp-PCL,image/jpeg,application/PCLm,image/urf,image/pwg-raster,application/octet-stream >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] printer-is-accepting-jobs boolean false >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] compression-supported 1setOf keyword none,deflate,gzip >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] printer-alert 1setOf octetString code=unknown;severity=other;group=other,code=unknown;severity=other;group=other,code=unknown;severity=other;group=other,code=unknown;severity=other;group=other >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] printer-alert-description 1setOf textWithoutLanguage genuineHP,genuineHP,genuineHP,genuineHP >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] media-col-supported 1setOf keyword media-type,media-size,media-top-margin,media-left-margin,media-right-margin,media-bottom-margin,media-source,media-size-name >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] marker-names 1setOf nameWithoutLanguage magenta ink,cyan ink,yellow ink,black ink >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] marker-colors 1setOf nameWithoutLanguage #FF00FF,#00FFFF,#FFFF00,#000000 >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] marker-types 1setOf keyword ink-cartridge,ink-cartridge,ink-cartridge,ink-cartridge >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] marker-low-levels 1setOf integer 1,1,1,1 >Sep 11 13:30:31 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] marker-high-levels 1setOf integer 100,100,100,100 >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] marker-levels 1setOf integer 90,90,90,80 >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] copies-supported rangeOfInteger 1-99 >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] print-color-mode-supported 1setOf keyword auto,auto-monochrome,monochrome,color,process-monochrome >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] multiple-document-handling-supported 1setOf keyword separate-documents-uncollated-copies,separate-documents-collated-copies >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] ---- end-of-attributes-tag ---- >Sep 11 13:30:31 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] (monitor) Get-Job-Attributes: successful-ok (successful-ok) >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] (monitor) job-state = processing >Sep 11 13:30:31 warpy cupsd[6306]: [Job 698] (monitor) job-state = processing >Sep 11 13:30:31 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:32 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:32 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:32 warpy cupsd[6306]: select_timeout(0): 21 seconds to write dirty config/state files >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] Send-Document: successful-ok (successful-ok) >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] ---- operation-attributes-tag ---- >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] attributes-charset charset utf-8 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] attributes-natural-language naturalLanguage en >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] ---- job-attributes-tag ---- >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] job-uri uri ipp://192.168.19.27/ipp/print/job-0017 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] job-id integer 17 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] job-state enum processing >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] job-state-reasons keyword none >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] job-state-message textWithoutLanguage >Sep 11 13:30:33 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] ---- end-of-attributes-tag ---- >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] STATE: +cups-waiting-for-job-completed >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetPrinterReasons(p=0x55faab2d43a0(HP-OfficeJet-Pro-6970),s="+cups-waiting-for-job-completed" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdMarkDirty(P----) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdMarkDirty(---J-) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] Waiting for job to complete. >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] IPP/2.0 Get-Printer-Attributes #14 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] ---- operation-attributes-tag ---- >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] attributes-charset charset utf-8 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] attributes-natural-language naturalLanguage en-us >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] printer-uri uri ipp://192.168.19.27:631/ipp/print >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] requesting-user-name nameWithoutLanguage schwa >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] requested-attributes 1setOf keyword compression-supported,copies-supported,cups-version,document-format-supported,job-password-encryption-supported,marker-colors,marker-high-levels,marker-levels,marker-low-levels,marker-message,marker-names,marker-types,media-col-supported,multiple-document-handling-supported,operations-supported,print-color-mode-supported,printer-alert,printer-alert-description,printer-is-accepting-jobs,printer-mandatory-job-attributes,printer-state,printer-state-message,printer-state-reasons >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] ---- end-of-attributes-tag ---- >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddEvent(event=job-progress, dest=0x55faab2d43a0(HP-OfficeJet-Pro-6970), job=0x55faab2db1a0(698), text="%s", ...) >Sep 11 13:30:33 warpy cupsd[6306]: cupsd_send_notification(sub=0x55faab2a0850(250), event=0x55faab379be0(job-progress)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsd_send_notification(sub=0x55faab2a0900(260), event=0x55faab408e60(job-progress)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdMarkDirty(----S) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddEvent(event=printer-state-changed, dest=0x55faab2d43a0(HP-OfficeJet-Pro-6970), job=(nil)(0), text="Printer "%s" state changed.", ...) >Sep 11 13:30:33 warpy cupsd[6306]: cupsd_send_notification(sub=0x55faab2a07a0(232), event=0x55faab3b3a80(printer-state-changed)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsd_send_notification(sub=0x55faab2a0850(250), event=0x55faab2ffaa0(printer-state-changed)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsd_send_notification(sub=0x55faab2a0900(260), event=0x55faab2ce1c0(printer-state-changed)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsd_send_notification(sub=0x55faab2d4270(261), event=0x55faab2db7f0(printer-state-changed)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdMarkDirty(----S) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] update_reasons(attr=1(spool-area-full-report), s=\"(null)\") >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] op=\' \', new_reasons=0, state_reasons=0 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] Get-Printer-Attributes: successful-ok (successful-ok) >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] ---- operation-attributes-tag ---- >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] attributes-charset charset utf-8 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] attributes-natural-language naturalLanguage en >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] ---- printer-attributes-tag ---- >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] printer-state enum processing >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] printer-state-reasons keyword spool-area-full-report >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] printer-state-message textWithoutLanguage >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] operations-supported 1setOf enum Print-Job,Validate-Job,Cancel-Job,Cancel-My-Jobs,Get-Job-Attributes,Get-Jobs,Get-Printer-Attributes,Create-Job,Send-Document,Set-Printer-Attributes,Print-URI,Send-URI,Close-Job,Identify-Printer >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] document-format-supported 1setOf mimeMediaType application/vnd.hp-PCL,image/jpeg,application/PCLm,image/urf,image/pwg-raster,application/octet-stream >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddEvent(event=job-progress, dest=0x55faab2d43a0(HP-OfficeJet-Pro-6970), job=0x55faab2db1a0(698), text="%s", ...) >Sep 11 13:30:33 warpy cupsd[6306]: cupsd_send_notification(sub=0x55faab2a0850(250), event=0x55faab3b86b0(job-progress)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsd_send_notification(sub=0x55faab2a0900(260), event=0x55faab311160(job-progress)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdMarkDirty(----S) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddEvent(event=printer-state-changed, dest=0x55faab2d43a0(HP-OfficeJet-Pro-6970), job=(nil)(0), text="Printer "%s" state changed.", ...) >Sep 11 13:30:33 warpy cupsd[6306]: cupsd_send_notification(sub=0x55faab2a07a0(232), event=0x55faab33a8e0(printer-state-changed)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsd_send_notification(sub=0x55faab2a0850(250), event=0x55faab369ae0(printer-state-changed)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsd_send_notification(sub=0x55faab2a0900(260), event=0x55faab401710(printer-state-changed)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsd_send_notification(sub=0x55faab2d4270(261), event=0x55faab3cb3c0(printer-state-changed)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdMarkDirty(----S) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] printer-is-accepting-jobs boolean false >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] compression-supported 1setOf keyword none,deflate,gzip >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] printer-alert 1setOf octetString code=unknown;severity=other;group=other,code=unknown;severity=other;group=other,code=unknown;severity=other;group=other,code=unknown;severity=other;group=other >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] printer-alert-description 1setOf textWithoutLanguage genuineHP,genuineHP,genuineHP,genuineHP >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] media-col-supported 1setOf keyword media-type,media-size,media-top-margin,media-left-margin,media-right-margin,media-bottom-margin,media-source,media-size-name >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] marker-names 1setOf nameWithoutLanguage magenta ink,cyan ink,yellow ink,black ink >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] marker-colors 1setOf nameWithoutLanguage #FF00FF,#00FFFF,#FFFF00,#000000 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] marker-types 1setOf keyword ink-cartridge,ink-cartridge,ink-cartridge,ink-cartridge >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] marker-low-levels 1setOf integer 1,1,1,1 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] marker-high-levels 1setOf integer 100,100,100,100 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] marker-levels 1setOf integer 90,90,90,80 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] copies-supported rangeOfInteger 1-99 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] print-color-mode-supported 1setOf keyword auto,auto-monochrome,monochrome,color,process-monochrome >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] multiple-document-handling-supported 1setOf keyword separate-documents-uncollated-copies,separate-documents-collated-copies >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] ---- end-of-attributes-tag ---- >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] IPP/2.0 Get-Job-Attributes #15 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] ---- operation-attributes-tag ---- >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] attributes-charset charset utf-8 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] attributes-natural-language naturalLanguage en-us >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] printer-uri uri ipp://192.168.19.27:631/ipp/print >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] job-id integer 17 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] requesting-user-name nameWithoutLanguage schwa >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] requested-attributes 1setOf keyword job-id,job-impressions-completed,job-media-sheets-completed,job-name,job-originating-user-name,job-state,job-state-reasons >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] ---- end-of-attributes-tag ---- >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] Get-Job-Attributes: successful-ok (successful-ok) >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] ---- operation-attributes-tag ---- >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] attributes-charset charset utf-8 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] attributes-natural-language naturalLanguage en >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] ---- job-attributes-tag ---- >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] job-id integer 17 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] job-name nameWithLanguage cupsfilter(8)[en-us] >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] job-originating-user-name nameWithLanguage schwa[en-us] >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] job-state enum processing >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] job-state-reasons keyword none >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] job-impressions-completed integer 0 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] job-media-sheets-completed integer 0 >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] job-mandatory-attributes no-value no-value >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] ---- end-of-attributes-tag ---- >Sep 11 13:30:33 warpy cupsd[6306]: [Job 698] PAGE: total 0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdMarkDirty(---J-) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdMarkDirty(---J-) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: HP-OfficeJet-Pro-6970 schwa 698 [11/Sep/2018:13:30:33 +0200] total 0 - localhost cupsfilter(8) - - >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddEvent(event=job-progress, dest=0x55faab2d43a0(HP-OfficeJet-Pro-6970), job=0x55faab2db1a0(698), text="Printed %d page(s).", ...) >Sep 11 13:30:33 warpy cupsd[6306]: cupsd_send_notification(sub=0x55faab2a0850(250), event=0x55faab381890(job-progress)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsd_send_notification(sub=0x55faab2a0900(260), event=0x55faab3f2760(job-progress)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdMarkDirty(----S) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=4 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] Accepted from localhost (Domain) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=15, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee560) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=5 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] Accepted from localhost (Domain) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=17, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab426f90) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] POST / HTTP/1.1 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] Read: status=200, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: uri="/", limit=10... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] No authentication data provided. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] 1.1 Get-Notifications 59 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab3ee560[1776]): operation_id=001c(Get-Notifications) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: printer-uri uri '/' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: requesting-user-name nameWithoutLanguage 'schwa' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: notify-subscription-ids integer '261' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: notify-sequence-numbers integer '25' >Sep 11 13:30:33 warpy cupsd[6306]: Get-Notifications / >Sep 11 13:30:33 warpy cupsd[6306]: get_notifications(con=0x55faab3ee560[1776]) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=1c(Get-Notifications)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp: Found exact match... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab291470((null)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: owner="schwa" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=2 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: op=1c(Get-Notifications) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: requesting-user-name="schwa" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: Checking user membership... >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab3947e0, from=0x55faab2df310, ra=(nil), group=7, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab3947e0, from=0x55faab3f3870, ra=(nil), group=7, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] Returning IPP successful-ok for Get-Notifications (/) from localhost >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] Content-Length: 1077 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=15, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab3ee560) >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] con->http=0x55faab420b70 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1077, response=0x55faab3947e0(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] bytes=0, http_state=0, data_remaining=1077 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] Flushing write buffer. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] New state is HTTP_STATE_WAITING >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=15, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee560) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] POST / HTTP/1.1 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] Read: status=200, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: uri="/", limit=10... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] No authentication data provided. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] 1.1 Get-Notifications 901 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab426f90[1777]): operation_id=001c(Get-Notifications) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: printer-uri uri '/' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: requesting-user-name nameWithoutLanguage 'schwa' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: notify-subscription-ids integer '260' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: notify-sequence-numbers integer '579' >Sep 11 13:30:33 warpy cupsd[6306]: Get-Notifications / >Sep 11 13:30:33 warpy cupsd[6306]: get_notifications(con=0x55faab426f90[1777]) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=1c(Get-Notifications)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp: Found exact match... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab291470((null)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: owner="schwa" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=2 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: op=1c(Get-Notifications) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: requesting-user-name="schwa" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: Checking user membership... >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab33e870, from=0x55faab3bdd50, ra=(nil), group=7, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab33e870, from=0x55faab406f40, ra=(nil), group=7, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab33e870, from=0x55faab3451d0, ra=(nil), group=7, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab33e870, from=0x55faab401660, ra=(nil), group=7, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab33e870, from=0x55faab2f4910, ra=(nil), group=7, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] Returning IPP successful-ok for Get-Notifications (/) from localhost >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] Content-Length: 2785 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=17, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab426f90) >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] con->http=0x55faab327820 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2785, response=0x55faab33e870(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] bytes=0, http_state=0, data_remaining=2785 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] Flushing write buffer. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] New state is HTTP_STATE_WAITING >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=17, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab426f90) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] POST /printers/HP-OfficeJet-Pro-6970 HTTP/1.1 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Read: status=200, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: uri="/printers/HP-OfficeJet-Pro-6970", limit=10... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] con->uri="/printers/HP-OfficeJet-Pro-6970", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] No authentication data provided. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/printers/HP-OfficeJet-Pro-6970", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=231, request=0x55faab33e870(IPP_STATE_IDLE), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Read: status=100, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=223, request=0x55faab33e870(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Read: status=100, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=194, request=0x55faab33e870(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Read: status=100, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=157, request=0x55faab33e870(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Read: status=100, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=95, request=0x55faab33e870(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Read: status=100, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=60, request=0x55faab33e870(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Read: status=100, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=44, request=0x55faab33e870(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Read: status=100, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=6 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] Accepted from localhost (Domain) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=18, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab378040) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=18, request=0x55faab33e870(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Read: status=100, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1776] Closing connection. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=15) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] POST / HTTP/1.1 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] Read: status=200, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: uri="/", limit=10... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] No authentication data provided. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] 1.1 Get-Job-Attributes 902 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab378040[1778]): operation_id=0009(Get-Job-Attributes) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: job-uri uri 'ipp://localhost/jobs/698' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'job-preserved,auth-info-required,time-at-creation,job-originating-user-name' >Sep 11 13:30:33 warpy cupsd[6306]: Get-Job-Attributes ipp://localhost/jobs/698 >Sep 11 13:30:33 warpy cupsd[6306]: get_job_attrs(0x55faab378040[1778], ipp://localhost/jobs/698) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=9(Get-Job-Attributes)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp: Found wildcard match... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab294110((null)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: owner="schwa" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: op=0(0x0000) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdCheckGroup(username="anonymous", user=(nil), groupname="OWNER") >Sep 11 13:30:33 warpy cupsd[6306]: cupsdCheckGroup(username="anonymous", user=(nil), groupname="sys") >Sep 11 13:30:33 warpy cupsd[6306]: cupsdCheckGroup(username="anonymous", user=(nil), groupname="root") >Sep 11 13:30:33 warpy cupsd[6306]: cupsdCheckGroup(username="anonymous", user=(nil), groupname="wheel") >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab347b30, from=0x55faab3b3ab0, ra=0x55faab3f1270, group=2, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/698) from localhost >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] Content-Length: 101 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=18, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab378040) >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] cupsdReadClient: error=0, used=0, state=HTTP_STATE_POST_RECV, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1, request=0x55faab33e870(IPP_STATE_ATTRIBUTE), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Read: status=100, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] 1.1 Get-Printer-Attributes 60 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab3f9fc0[1744]): operation_id=000b(Get-Printer-Attributes) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: printer-uri uri 'ipp://localhost/printers/HP-OfficeJet-Pro-6970' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'device-uri,member-uris,printer-uri-supported,printer-type' >Sep 11 13:30:33 warpy cupsd[6306]: Get-Printer-Attributes ipp://localhost/printers/HP-OfficeJet-Pro-6970 >Sep 11 13:30:33 warpy cupsd[6306]: get_printer_attrs(0x55faab3f9fc0[1744], ipp://localhost/printers/HP-OfficeJet-Pro-6970) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=b(Get-Printer-Attributes)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp: Found wildcard match... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/printers/HP-OfficeJet-Pro-6970", con->best=0x55faab294110((null)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: op=0(0x0000) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970" >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab39d310, from=0x55faab31a1b0, ra=0x55faab404890, group=0, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab39d310, from=0x55faab326610, ra=0x55faab404890, group=0, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab39d310, from=0x55faab2b1c10, ra=0x55faab404890, group=0, quickcopy=-2147483648) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP-OfficeJet-Pro-6970) from localhost >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Content-Length: 293 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=16, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab3f9fc0) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] con->http=0x55faab428fd0 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=101, response=0x55faab347b30(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] bytes=0, http_state=0, data_remaining=101 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] Flushing write buffer. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] New state is HTTP_STATE_WAITING >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=18, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab378040) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] con->http=0x55faab3e1f70 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=293, response=0x55faab39d310(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] bytes=0, http_state=0, data_remaining=293 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Flushing write buffer. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] New state is HTTP_STATE_WAITING >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=16, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3f9fc0) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=6 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] Accepted from localhost (Domain) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=15, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee560) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1778] Closing connection. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=18) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] GET /printers/HP-OfficeJet-Pro-6970.ppd HTTP/1.1 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] Read: status=200, state=3 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: uri="/printers/HP-OfficeJet-Pro-6970", limit=2... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] con->uri="/printers/HP-OfficeJet-Pro-6970.ppd", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] No authentication data provided. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/printers/HP-OfficeJet-Pro-6970.ppd", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] Processing GET /printers/HP-OfficeJet-Pro-6970.ppd >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] get_file: filestats=0x7ffc36e4c560, filename=0x7ffc36e4c5f0, len=1024, returning "/etc/cups/ppd/HP-OfficeJet-Pro-6970.ppd". >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] filename="/etc/cups/ppd/HP-OfficeJet-Pro-6970.ppd", type=application/vnd.cups-ppd >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] is_cgi: filename="/etc/cups/ppd/HP-OfficeJet-Pro-6970.ppd", filestats=0x7ffc36e4c560, type=application/vnd.cups-ppd, returning 0. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] check_if_modified: filestats=0x7ffc36e4c560(44031, 1536649911)) If-Modified-Since="Tue, 11 Sep 2018 11:30:23 GMT" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] cupsdSendError code=304, auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] cupsdSendHeader: code=304, type="(null)", auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1779] Closing connection. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=15) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=5 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] Accepted from localhost (Domain) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=15, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee560) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] POST / HTTP/1.1 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] Read: status=200, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: uri="/", limit=10... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] No authentication data provided. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] 1.1 Get-Job-Attributes 903 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab3ee560[1780]): operation_id=0009(Get-Job-Attributes) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: job-uri uri 'ipp://localhost/jobs/698' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'job-preserved,auth-info-required,time-at-creation,job-originating-user-name' >Sep 11 13:30:33 warpy cupsd[6306]: Get-Job-Attributes ipp://localhost/jobs/698 >Sep 11 13:30:33 warpy cupsd[6306]: get_job_attrs(0x55faab3ee560[1780], ipp://localhost/jobs/698) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=9(Get-Job-Attributes)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp: Found wildcard match... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab294110((null)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: owner="schwa" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: op=0(0x0000) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdCheckGroup(username="anonymous", user=(nil), groupname="OWNER") >Sep 11 13:30:33 warpy cupsd[6306]: cupsdCheckGroup(username="anonymous", user=(nil), groupname="sys") >Sep 11 13:30:33 warpy cupsd[6306]: cupsdCheckGroup(username="anonymous", user=(nil), groupname="root") >Sep 11 13:30:33 warpy cupsd[6306]: cupsdCheckGroup(username="anonymous", user=(nil), groupname="wheel") >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab395910, from=0x55faab3b3ab0, ra=0x55faab39b9d0, group=2, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/698) from localhost >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] Content-Length: 101 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=15, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab3ee560) >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] con->http=0x55faab420b70 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=101, response=0x55faab395910(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] bytes=0, http_state=0, data_remaining=101 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] Flushing write buffer. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] New state is HTTP_STATE_WAITING >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=15, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee560) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] POST /printers/HP-OfficeJet-Pro-6970 HTTP/1.1 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Read: status=200, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: uri="/printers/HP-OfficeJet-Pro-6970", limit=10... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] con->uri="/printers/HP-OfficeJet-Pro-6970", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] No authentication data provided. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/printers/HP-OfficeJet-Pro-6970", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] 1.1 Get-Printer-Attributes 61 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab3f9fc0[1744]): operation_id=000b(Get-Printer-Attributes) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: printer-uri uri 'ipp://localhost/printers/HP-OfficeJet-Pro-6970' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'device-uri,member-uris,printer-uri-supported,printer-type' >Sep 11 13:30:33 warpy cupsd[6306]: Get-Printer-Attributes ipp://localhost/printers/HP-OfficeJet-Pro-6970 >Sep 11 13:30:33 warpy cupsd[6306]: get_printer_attrs(0x55faab3f9fc0[1744], ipp://localhost/printers/HP-OfficeJet-Pro-6970) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=b(Get-Printer-Attributes)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp: Found wildcard match... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/printers/HP-OfficeJet-Pro-6970", con->best=0x55faab294110((null)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: op=0(0x0000) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970" >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab316bf0, from=0x55faab31a1b0, ra=0x55faab3f1270, group=0, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab316bf0, from=0x55faab326610, ra=0x55faab3f1270, group=0, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab316bf0, from=0x55faab2b1c10, ra=0x55faab3f1270, group=0, quickcopy=-2147483648) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP-OfficeJet-Pro-6970) from localhost >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Content-Length: 293 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=16, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab3f9fc0) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] POST /printers/HP-OfficeJet-Pro-6970 HTTP/1.1 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] Read: status=200, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: uri="/printers/HP-OfficeJet-Pro-6970", limit=10... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] con->uri="/printers/HP-OfficeJet-Pro-6970", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] No authentication data provided. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/printers/HP-OfficeJet-Pro-6970", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] 1.1 Get-Printer-Attributes 904 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab34de80[1753]): operation_id=000b(Get-Printer-Attributes) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: printer-uri uri 'ipp://localhost/printers/HP-OfficeJet-Pro-6970' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'device-uri,member-uris,printer-uri-supported,printer-type' >Sep 11 13:30:33 warpy cupsd[6306]: Get-Printer-Attributes ipp://localhost/printers/HP-OfficeJet-Pro-6970 >Sep 11 13:30:33 warpy cupsd[6306]: get_printer_attrs(0x55faab34de80[1753], ipp://localhost/printers/HP-OfficeJet-Pro-6970) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=b(Get-Printer-Attributes)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp: Found wildcard match... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/printers/HP-OfficeJet-Pro-6970", con->best=0x55faab294110((null)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: op=0(0x0000) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970" >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab4187f0, from=0x55faab31a1b0, ra=0x55faab404890, group=0, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab4187f0, from=0x55faab326610, ra=0x55faab404890, group=0, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab4187f0, from=0x55faab2b1c10, ra=0x55faab404890, group=0, quickcopy=-2147483648) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP-OfficeJet-Pro-6970) from localhost >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] Content-Length: 293 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=19, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab34de80) >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1780] Closing connection. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=15) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] con->http=0x55faab3e1f70 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=293, response=0x55faab316bf0(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] bytes=0, http_state=0, data_remaining=293 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Flushing write buffer. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] New state is HTTP_STATE_WAITING >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=16, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3f9fc0) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1744] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] con->http=0x55faab423d80 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=293, response=0x55faab4187f0(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] bytes=0, http_state=0, data_remaining=293 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] Flushing write buffer. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] New state is HTTP_STATE_WAITING >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=19, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab34de80) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=5 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] Accepted from localhost (Domain) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=15, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee560) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=6 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] Accepted from localhost (Domain) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=18, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab378040) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] POST / HTTP/1.1 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] Read: status=200, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: uri="/", limit=10... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] No authentication data provided. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] 1.1 Get-Job-Attributes 905 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab3ee560[1781]): operation_id=0009(Get-Job-Attributes) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: job-uri uri 'ipp://localhost/jobs/698' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'job-preserved,auth-info-required,time-at-creation,job-originating-user-name' >Sep 11 13:30:33 warpy cupsd[6306]: Get-Job-Attributes ipp://localhost/jobs/698 >Sep 11 13:30:33 warpy cupsd[6306]: get_job_attrs(0x55faab3ee560[1781], ipp://localhost/jobs/698) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=9(Get-Job-Attributes)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp: Found wildcard match... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab294110((null)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: owner="schwa" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: op=0(0x0000) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdCheckGroup(username="anonymous", user=(nil), groupname="OWNER") >Sep 11 13:30:33 warpy cupsd[6306]: cupsdCheckGroup(username="anonymous", user=(nil), groupname="sys") >Sep 11 13:30:33 warpy cupsd[6306]: cupsdCheckGroup(username="anonymous", user=(nil), groupname="root") >Sep 11 13:30:33 warpy cupsd[6306]: cupsdCheckGroup(username="anonymous", user=(nil), groupname="wheel") >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab35c5d0, from=0x55faab3b3ab0, ra=0x55faab39b9d0, group=2, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/698) from localhost >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] Content-Length: 101 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=15, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab3ee560) >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=7 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] Accepted from localhost (Domain) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=20, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3c2a30) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] con->http=0x55faab420b70 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=101, response=0x55faab35c5d0(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] bytes=0, http_state=0, data_remaining=101 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] Flushing write buffer. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] New state is HTTP_STATE_WAITING >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=15, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee560) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] GET /printers/HP-OfficeJet-Pro-6970.ppd HTTP/1.1 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] Read: status=200, state=3 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: uri="/printers/HP-OfficeJet-Pro-6970", limit=2... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] con->uri="/printers/HP-OfficeJet-Pro-6970.ppd", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] No authentication data provided. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/printers/HP-OfficeJet-Pro-6970.ppd", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] Processing GET /printers/HP-OfficeJet-Pro-6970.ppd >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] get_file: filestats=0x7ffc36e4c560, filename=0x7ffc36e4c5f0, len=1024, returning "/etc/cups/ppd/HP-OfficeJet-Pro-6970.ppd". >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] filename="/etc/cups/ppd/HP-OfficeJet-Pro-6970.ppd", type=application/vnd.cups-ppd >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] is_cgi: filename="/etc/cups/ppd/HP-OfficeJet-Pro-6970.ppd", filestats=0x7ffc36e4c560, type=application/vnd.cups-ppd, returning 0. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] check_if_modified: filestats=0x7ffc36e4c560(44031, 1536649911)) If-Modified-Since="Tue, 11 Sep 2018 11:30:23 GMT" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] cupsdSendError code=304, auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] cupsdSendHeader: code=304, type="(null)", auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1781] Closing connection. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=15) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] GET /printers/HP-OfficeJet-Pro-6970.ppd HTTP/1.1 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] Read: status=200, state=3 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: uri="/printers/HP-OfficeJet-Pro-6970", limit=2... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] con->uri="/printers/HP-OfficeJet-Pro-6970.ppd", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] No authentication data provided. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/printers/HP-OfficeJet-Pro-6970.ppd", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] Processing GET /printers/HP-OfficeJet-Pro-6970.ppd >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] get_file: filestats=0x7ffc36e4c560, filename=0x7ffc36e4c5f0, len=1024, returning "/etc/cups/ppd/HP-OfficeJet-Pro-6970.ppd". >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] filename="/etc/cups/ppd/HP-OfficeJet-Pro-6970.ppd", type=application/vnd.cups-ppd >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] is_cgi: filename="/etc/cups/ppd/HP-OfficeJet-Pro-6970.ppd", filestats=0x7ffc36e4c560, type=application/vnd.cups-ppd, returning 0. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] check_if_modified: filestats=0x7ffc36e4c560(44031, 1536649911)) If-Modified-Since="Tue, 11 Sep 2018 07:12:11 GMT" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] cupsdSendError code=304, auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] cupsdSendHeader: code=304, type="(null)", auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1782] Closing connection. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=18) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1777] Closing connection. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=17) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1783] Closing connection. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=20) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] POST /printers/HP-OfficeJet-Pro-6970 HTTP/1.1 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] Read: status=200, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: uri="/printers/HP-OfficeJet-Pro-6970", limit=10... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] con->uri="/printers/HP-OfficeJet-Pro-6970", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] No authentication data provided. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/printers/HP-OfficeJet-Pro-6970", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] 1.1 Get-Printer-Attributes 906 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab34de80[1753]): operation_id=000b(Get-Printer-Attributes) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: printer-uri uri 'ipp://localhost/printers/HP-OfficeJet-Pro-6970' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'device-uri,member-uris,printer-uri-supported,printer-type' >Sep 11 13:30:33 warpy cupsd[6306]: Get-Printer-Attributes ipp://localhost/printers/HP-OfficeJet-Pro-6970 >Sep 11 13:30:33 warpy cupsd[6306]: get_printer_attrs(0x55faab34de80[1753], ipp://localhost/printers/HP-OfficeJet-Pro-6970) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=b(Get-Printer-Attributes)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp: Found wildcard match... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/printers/HP-OfficeJet-Pro-6970", con->best=0x55faab294110((null)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: op=0(0x0000) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970" >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab300e70, from=0x55faab31a1b0, ra=0x55faab3f1270, group=0, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab300e70, from=0x55faab326610, ra=0x55faab3f1270, group=0, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab300e70, from=0x55faab2b1c10, ra=0x55faab3f1270, group=0, quickcopy=-2147483648) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP-OfficeJet-Pro-6970) from localhost >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] Content-Length: 293 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=19, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab34de80) >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] con->http=0x55faab423d80 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=293, response=0x55faab300e70(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] bytes=0, http_state=0, data_remaining=293 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] Flushing write buffer. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] New state is HTTP_STATE_WAITING >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=19, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab34de80) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1753] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAcceptClient(lis=0x55faab28f1b0(3)) Clients=4 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] Server address is "/var/run/cups/cups.sock". >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] Accepted from localhost (Domain) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=15, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ee560) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] GET /printers/HP-OfficeJet-Pro-6970.ppd HTTP/1.1 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] Read: status=200, state=3 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: uri="/printers/HP-OfficeJet-Pro-6970", limit=2... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] con->uri="/printers/HP-OfficeJet-Pro-6970.ppd", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] No authentication data provided. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/printers/HP-OfficeJet-Pro-6970.ppd", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] Processing GET /printers/HP-OfficeJet-Pro-6970.ppd >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] get_file: filestats=0x7ffc36e4c560, filename=0x7ffc36e4c5f0, len=1024, returning "/etc/cups/ppd/HP-OfficeJet-Pro-6970.ppd". >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] filename="/etc/cups/ppd/HP-OfficeJet-Pro-6970.ppd", type=application/vnd.cups-ppd >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] is_cgi: filename="/etc/cups/ppd/HP-OfficeJet-Pro-6970.ppd", filestats=0x7ffc36e4c560, type=application/vnd.cups-ppd, returning 0. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] check_if_modified: filestats=0x7ffc36e4c560(44031, 1536649911)) If-Modified-Since="Tue, 11 Sep 2018 07:12:11 GMT" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] cupsdSendError code=304, auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] cupsdSendHeader: code=304, type="(null)", auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1784] Closing connection. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=15) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdRemoveSelect(fd=-1) >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] POST / HTTP/1.1 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] Read: status=200, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: uri="/", limit=10... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] No authentication data provided. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] 1.1 CUPS-Get-Printers 62 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab3ffb00[1759]): operation_id=4002(CUPS-Get-Printers) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:33 warpy cupsd[6306]: 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' >Sep 11 13:30:33 warpy cupsd[6306]: CUPS-Get-Printers >Sep 11 13:30:33 warpy cupsd[6306]: get_printers(0x55faab3ffb00[1759], 0) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=4002(CUPS-Get-Printers)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp: Found wildcard match... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab294110((null)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: op=0(0x0000) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: add_printer_state_reasons(0x55faab3ffb00[1759], 0x55faab2d43a0[HP-OfficeJet-Pro-6970]) >Sep 11 13:30:33 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970" >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab416580, from=0x55faab31a1b0, ra=0x55faab404890, group=0, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab416580, from=0x55faab326610, ra=0x55faab404890, group=0, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab416580, from=0x55faab2b1c10, ra=0x55faab404890, group=0, quickcopy=-2147483648) >Sep 11 13:30:33 warpy cupsd[6306]: add_printer_state_reasons(0x55faab3ffb00[1759], 0x55faab31e470[HP-OfficeJet-Pro-6970-hpijs]) >Sep 11 13:30:33 warpy cupsd[6306]: printer-uri-supported="ipp://localhost/printers/HP-OfficeJet-Pro-6970-hpijs" >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab416580, from=0x55faab35ec50, ra=0x55faab404890, group=0, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab416580, from=0x55faab3a67e0, ra=0x55faab404890, group=0, quickcopy=0) >Sep 11 13:30:33 warpy cupsd[6306]: copy_attrs(to=0x55faab416580, from=0x55faab2b1c10, ra=0x55faab404890, group=0, quickcopy=-2147483648) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] Content-Length: 1076 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=13, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab3ffb00) >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] con->http=0x55faab31a740 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1076, response=0x55faab416580(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] bytes=0, http_state=0, data_remaining=1076 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] Flushing write buffer. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] New state is HTTP_STATE_WAITING >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=13, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ffb00) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] POST / HTTP/1.1 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] Read: status=200, state=6 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: uri="/", limit=10... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/log(10) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin/conf(11) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /admin(6) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: Location /(1) Limit 7f >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindBest: best=/ >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] No authentication data provided. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab28f2d0(/) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] 1.1 CUPS-Get-Classes 63 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest(0x55faab3ffb00[1759]): operation_id=4005(CUPS-Get-Classes) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: operation-attributes-tag >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-charset charset 'utf-8' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us' >Sep 11 13:30:33 warpy cupsd[6306]: cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'printer-name,member-names' >Sep 11 13:30:33 warpy cupsd[6306]: CUPS-Get-Classes >Sep 11 13:30:33 warpy cupsd[6306]: get_printers(0x55faab3ffb00[1759], 1) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp(p=0x55faab28fc20, op=4005(CUPS-Get-Classes)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdFindPolicyOp: Found wildcard match... >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: con->uri="/", con->best=0x55faab294110((null)) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: op=0(0x0000) >Sep 11 13:30:33 warpy cupsd[6306]: cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] Content-Length: 75 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=13, read_cb=0x55faa95a0d90, write_cb=0x55faa95a03a0, data=0x55faab3ffb00) >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] con->http=0x55faab31a740 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55faab3799e0(IPP_STATE_DATA), pipe_pid=0, file=-1 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] bytes=0, http_state=0, data_remaining=75 >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] Flushing write buffer. >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] New state is HTTP_STATE_WAITING >Sep 11 13:30:33 warpy cupsd[6306]: cupsdAddSelect(fd=13, read_cb=0x55faa95a0d90, write_cb=(nil), data=0x55faab3ffb00) >Sep 11 13:30:33 warpy cupsd[6306]: [Client 1759] Waiting for request. >Sep 11 13:30:33 warpy cupsd[6306]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" >Sep 11 13:30:33 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] IPP/2.0 Get-Printer-Attributes #16 >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] ---- operation-attributes-tag ---- >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] attributes-charset charset utf-8 >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] attributes-natural-language naturalLanguage en-us >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] printer-uri uri ipp://192.168.19.27:631/ipp/print >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] requesting-user-name nameWithoutLanguage schwa >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] requested-attributes 1setOf keyword compression-supported,copies-supported,cups-version,document-format-supported,job-password-encryption-supported,marker-colors,marker-high-levels,marker-levels,marker-low-levels,marker-message,marker-names,marker-types,media-col-supported,multiple-document-handling-supported,operations-supported,print-color-mode-supported,printer-alert,printer-alert-description,printer-is-accepting-jobs,printer-mandatory-job-attributes,printer-state,printer-state-message,printer-state-reasons >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] ---- end-of-attributes-tag ---- >Sep 11 13:30:34 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:34 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] update_reasons(attr=1(spool-area-full-report), s=\"(null)\") >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] op=\' \', new_reasons=0, state_reasons=0 >Sep 11 13:30:34 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] Get-Printer-Attributes: successful-ok (successful-ok) >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] ---- operation-attributes-tag ---- >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] attributes-charset charset utf-8 >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] attributes-natural-language naturalLanguage en >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] ---- printer-attributes-tag ---- >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] printer-state enum processing >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] printer-state-reasons keyword spool-area-full-report >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] printer-state-message textWithoutLanguage >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] operations-supported 1setOf enum Print-Job,Validate-Job,Cancel-Job,Cancel-My-Jobs,Get-Job-Attributes,Get-Jobs,Get-Printer-Attributes,Create-Job,Send-Document,Set-Printer-Attributes,Print-URI,Send-URI,Close-Job,Identify-Printer >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] document-format-supported 1setOf mimeMediaType application/vnd.hp-PCL,image/jpeg,application/PCLm,image/urf,image/pwg-raster,application/octet-stream >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] printer-is-accepting-jobs boolean false >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] compression-supported 1setOf keyword none,deflate,gzip >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] printer-alert 1setOf octetString code=unknown;severity=other;group=other,code=unknown;severity=other;group=other,code=unknown;severity=other;group=other,code=unknown;severity=other;group=other >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] printer-alert-description 1setOf textWithoutLanguage genuineHP,genuineHP,genuineHP,genuineHP >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] media-col-supported 1setOf keyword media-type,media-size,media-top-margin,media-left-margin,media-right-margin,media-bottom-margin,media-source,media-size-name >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] marker-names 1setOf nameWithoutLanguage magenta ink,cyan ink,yellow ink,black ink >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] marker-colors 1setOf nameWithoutLanguage #FF00FF,#00FFFF,#FFFF00,#000000 >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] marker-types 1setOf keyword ink-cartridge,ink-cartridge,ink-cartridge,ink-cartridge >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] marker-low-levels 1setOf integer 1,1,1,1 >Sep 11 13:30:34 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] marker-high-levels 1setOf integer 100,100,100,100 >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] marker-levels 1setOf integer 90,90,90,80 >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] copies-supported rangeOfInteger 1-99 >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] print-color-mode-supported 1setOf keyword auto,auto-monochrome,monochrome,color,process-monochrome >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] multiple-document-handling-supported 1setOf keyword separate-documents-uncollated-copies,separate-documents-collated-copies >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] ---- end-of-attributes-tag ---- >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] IPP/2.0 Get-Job-Attributes #17 >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] ---- operation-attributes-tag ---- >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] attributes-charset charset utf-8 >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] attributes-natural-language naturalLanguage en-us >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] printer-uri uri ipp://192.168.19.27:631/ipp/print >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] job-id integer 17 >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] requesting-user-name nameWithoutLanguage schwa >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] requested-attributes 1setOf keyword job-id,job-impressions-completed,job-media-sheets-completed,job-name,job-originating-user-name,job-state,job-state-reasons >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] ---- end-of-attributes-tag ---- >Sep 11 13:30:34 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] Get-Job-Attributes: successful-ok (successful-ok) >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] ---- operation-attributes-tag ---- >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] attributes-charset charset utf-8 >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] attributes-natural-language naturalLanguage en >Sep 11 13:30:34 warpy cupsd[6306]: [Job 698] ---- job-attributes-tag ---- >Sep 11 13:30:34 warpy cupsd[6306]: HP-OfficeJet-Pro-6970 schwa 698 [11/Sep/2018:13:30:34 +0200] total 0 - localhost cupsfilter(8) - - >Sep 11 13:30:35 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:35 warpy cupsd[6306]: HP-OfficeJet-Pro-6970 schwa 698 [11/Sep/2018:13:30:35 +0200] total 0 - localhost cupsfilter(8) - - >Sep 11 13:30:36 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:37 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:37 warpy cupsd[6306]: HP-OfficeJet-Pro-6970 schwa 698 [11/Sep/2018:13:30:37 +0200] total 1 - localhost cupsfilter(8) - - >Sep 11 13:30:38 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:40 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:40 warpy cupsd[6306]: HP-OfficeJet-Pro-6970 schwa 698 [11/Sep/2018:13:30:40 +0200] total 1 - localhost cupsfilter(8) - - >Sep 11 13:30:41 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:45 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:45 warpy cupsd[6306]: HP-OfficeJet-Pro-6970 schwa 698 [11/Sep/2018:13:30:45 +0200] total 2 - localhost cupsfilter(8) - - >Sep 11 13:30:46 warpy cupsd[6306]: HP-OfficeJet-Pro-6970 schwa 698 [11/Sep/2018:13:30:46 +0200] total 2 - localhost cupsfilter(8) - - >Sep 11 13:30:46 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:46 warpy cupsd[6306]: [Job 698] Job stopped due to filter errors; please consult the error_log file for details. >Sep 11 13:30:47 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:53 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:54 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:55 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:56 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:57 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:58 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:30:59 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:00 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:01 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:02 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:02 warpy systemd-journald[583]: Suppressed 2601 messages from cups.service >Sep 11 13:31:02 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:02 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:03 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:03 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:03 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:04 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:04 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:04 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:05 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:05 warpy cupsd[6306]: cupsdCheckJobs: 2 active jobs, sleeping=0, ac-power=-1, reload=0, curtime=1536665465 >Sep 11 13:31:05 warpy cupsd[6306]: cupsdCheckJobs: Job 697 - dest="HP-OfficeJet-Pro-6970", printer=(nil), state=6, cancel_time=0, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0 >Sep 11 13:31:05 warpy cupsd[6306]: cupsdCheckJobs: Job 698 - dest="HP-OfficeJet-Pro-6970", printer=(nil), state=6, cancel_time=0, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0 >Sep 11 13:31:05 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:05 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:06 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:06 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:06 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:07 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:07 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:07 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:08 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:08 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:08 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:09 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:09 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:09 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:10 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:10 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:10 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:11 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:11 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:11 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:12 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:12 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:12 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:13 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:13 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:13 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:14 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:14 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:14 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:15 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:15 warpy cupsd[6306]: cupsdCheckJobs: 2 active jobs, sleeping=0, ac-power=-1, reload=0, curtime=1536665475 >Sep 11 13:31:15 warpy cupsd[6306]: cupsdCheckJobs: Job 697 - dest="HP-OfficeJet-Pro-6970", printer=(nil), state=6, cancel_time=0, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0 >Sep 11 13:31:15 warpy cupsd[6306]: cupsdCheckJobs: Job 698 - dest="HP-OfficeJet-Pro-6970", printer=(nil), state=6, cancel_time=0, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0 >Sep 11 13:31:15 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:15 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:16 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:16 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:16 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:17 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:17 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:17 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:18 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:18 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:18 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:19 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:19 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:19 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:20 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:20 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:20 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:21 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:21 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:21 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:22 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:22 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:22 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:23 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:23 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:23 warpy cupsd[6306]: select_timeout(0): 1 seconds to write dirty config/state files >Sep 11 13:31:24 warpy cupsd[6306]: Expiring subscriptions... >Sep 11 13:31:24 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005 >Sep 11 13:31:24 warpy cupsd[6306]: select_timeout: JobHistoryUpdate=1536701005
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 1627834
:
1482398
| 1482399