-
Bug
-
Resolution: Won't Do
-
Undefined
-
None
-
rhel-8.6.0
-
None
-
No
-
None
-
rhel-stacks-web-servers
-
ssg_core_services
-
None
-
False
-
False
-
-
None
-
None
-
None
-
None
-
x86_64
-
None
What were you trying to do that didn't work?
Cannot print with Windows Printer Server and Kerberos authentication.
What is the impact of this issue to you?
Cannot print documents
Please provide the package NVR for which the bug is seen:
$ rpm -qa | grep samba-krb5-printing samba-krb5-printing-4.15.5-5.el8.x86_64 $ rpm -qa | grep samba-client samba-client-4.15.5-5.el8.x86_64 samba-client-libs-4.15.5-5.el8.x86_64 $ rpm -qa | grep krb5-workstation krb5-workstation-1.18.2-14.el8.x86_64
How reproducible is this bug?:
Steps to reproduce
- lpadmin -p "example_printer" -v smb://win_print_srv.ad.example.com/Example -m x2UNIV.ppd -o auth-info-required=negotiate -E
- lp -d example_printer file
Expected results
Print the job
Actual results
$ ls -la /usr/lib/cups/backend/ total 656 drwxr-xr-x. 2 root root   273 Jan 23  2024 . drwxr-xr-x. 9 root root   109 Apr 18  2023 .. -rwxr-xr-x. 1 root root  16608 Jul 26  2021 beh -rwxr-xr-x. 1 root root  12136 Jul 26  2021 cups-brf -rwx------. 1 root root  25032 Jan 19  2022 dnssd lrwxrwxrwx. 1 root root   20 Jul 26  2021 driverless -> ../driver/driverless -rwx------. 1 root root  24992 Jan 19  2022 failover -rwx------. 1 root root 233600 Aug 15  2018 gutenprint52+usb lrwxrwxrwx. 1 root root    3 Jan 19  2022 http -> ipp lrwxrwxrwx. 1 root root    3 Jan 19  2022 https -> ipp -rwxr-xr-x. 1 root root  16640 Jul 26  2021 implicitclass -rwx------. 1 root root  86848 Jan 19  2022 ipp lrwxrwxrwx. 1 root root    3 Jan 19  2022 ipps -> ipp -rwx------. 1 root root  49840 Jan 19  2022 lpd -rwxr-xr-x. 1 root root  1073 Jan 19  2022 ncp -rwxr-xr-x. 1 root root  25008 Jul 26  2021 parallel -rwx------. 1 root root  20320 Jul 26  2021 serial lrwxrwxrwx  1 root root   35 Jan 23  2024 smb -> /usr/libexec/samba/cups_backend_smb -rwxr-xr-x. 1 root root  33424 Jan 19  2022 snmp -rwxr-xr-x. 1 root root  41576 Jan 19  2022 socket -rwxr-xr-x. 1 root root  41576 Jan 19  2022 usb
Â
$ smbclient -L win_print_srv.ad.example.com --use-kerberos=required -k WARNING: The option -k|--kerberos is deprecated!   Sharename    Type    Comment   ---------    ----    ------- ... SMB1 disabled -- no workgroup available
$ ls -l /usr/libexec/samba/ total 48 lrwxrwxrwx  1 root root   34 Jan 23  2024 cups_backend_smb -> /etc/alternatives/cups_backend_smb -rwxr-xr-x. 1 root root 25288 Mar 18  2022 samba-bgqd -rwx------  1 root root 16488 Mar 18  2022 smbspool_krb5_wrapper
$ journalctl -u cups.service -f ... Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Server address is "/var/run/cups/cups.sock". Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Accepted from localhost (Domain) Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Waiting for request. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: Expiring subscriptions... Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] POST / HTTP/1.1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Read: status=200, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] No authentication data provided. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] 1.1 Get-Printer-Attributes 1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: Get-Printer-Attributes ipp://localhost:631/printers/Follow-you1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/Follow-you1) from localhost Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Content-Length: 905 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] con->http=0x559143e9ba50 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=905, response=0x559143e95b40(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] bytes=0, http_state=0, data_remaining=905 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Flushing write buffer. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] New state is HTTP_STATE_WAITING Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Waiting for request. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] POST / HTTP/1.1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Read: status=200, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] No authentication data provided. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] 1.1 CUPS-Get-Printers 2 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: CUPS-Get-Printers Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Content-Length: 2097 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] con->http=0x559143e9ba50 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2097, response=0x559143e5b390(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] bytes=0, http_state=0, data_remaining=2097 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Flushing write buffer. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] New state is HTTP_STATE_WAITING Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Waiting for request. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] POST / HTTP/1.1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Read: status=200, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] No authentication data provided. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] 1.1 CUPS-Get-Default 3 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: CUPS-Get-Default Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: CUPS-Get-Default client-error-not-found: No default printer. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Content-Length: 113 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] con->http=0x559143e9ba50 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x559143e79960(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] bytes=0, http_state=0, data_remaining=113 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Flushing write buffer. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] New state is HTTP_STATE_WAITING Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Waiting for request. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] POST /printers/Follow-you1 HTTP/1.1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Read: status=200, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] No authentication data provided. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] 2.0 Get-Printer-Attributes 4 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: Get-Printer-Attributes ipp://localhost/printers/Follow-you1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Follow-you1) from localhost Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Content-Length: 7221 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] con->http=0x559143e9ba50 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7221, response=0x559143e5b390(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Writing IPP response, ipp_state=IPP_STATE_ATTRIBUTE, old wused=0, new wused=0 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] bytes=1, http_state=7, data_remaining=7221 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] con->http=0x559143e9ba50 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7221, response=0x559143e5b390(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] bytes=0, http_state=0, data_remaining=7221 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Flushing write buffer. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] New state is HTTP_STATE_WAITING Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Waiting for request. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] POST /printers/Follow-you1 HTTP/1.1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Read: status=200, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] No authentication data provided. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] 2.0 Create-Job 5 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: Create-Job ipp://localhost/printers/Follow-you1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Returning HTTP Unauthorized for Create-Job (ipp://localhost/printers/Follow-you1) from localhost Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: REQUEST localhost - - "POST /printers/Follow-you1 HTTP/1.1" 401 342 Create-Job successful-ok Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] cupsdSendHeader: code=401, type="text/html", auth_type=2 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] WWW-Authenticate: Basic realm=\"CUPS\" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 362] Closing connection. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 363] Server address is "/var/run/cups/cups.sock". Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 363] Accepted from localhost (Domain) Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 363] Waiting for request. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 364] Server address is "/var/run/cups/cups.sock". Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 364] Accepted from localhost (Domain) Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 364] Waiting for request. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 363] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 363] Closing connection. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Server address is "/var/run/cups/cups.sock". Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Accepted from localhost (Domain) Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Waiting for request. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 364] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 364] Closing connection. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] POST /printers/Follow-you1 HTTP/1.1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=200, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Authorized as username using PeerCred. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] 2.0 Create-Job 5 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: Create-Job ipp://localhost/printers/Follow-you1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdMarkDirty(---J-) Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Job 45] Applying default options... Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Job 45] Adding start banner page "none". Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdMarkDirty(----S) Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Job 45] Queued on "Follow-you1" by "username". Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Returning IPP successful-ok for Create-Job (ipp://localhost/printers/Follow-you1) from localhost Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Content-Length: 200 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] con->http=0x559143e9ba50 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=200, response=0x559143e70c20(IPP_STATE_IDLE), pipe_pid=0, file=-1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] bytes=0, http_state=0, data_remaining=200 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: REQUEST localhost - username "POST /printers/Follow-you1 HTTP/1.1" 200 342 Create-Job successful-ok Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Flushing write buffer. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] New state is HTTP_STATE_WAITING Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Waiting for request. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Notifier] state=3 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Notifier] JobCreated Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] POST /printers/Follow-you1 HTTP/1.1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=200, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Authorized as username using PeerCred. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] 1.1 Send-Document 6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 357] POST / HTTP/1.1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=200, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 357] No authentication data provided. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 357] 1.1 Get-Notifications 708 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: Get-Notifications /printers/ Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdIsAuthorized: requesting-user-name="username" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 357] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 357] Content-Length: 661 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 357] con->http=0x559143eb7a20 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=661, response=0x559143e7ae80(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 357] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 357] bytes=0, http_state=0, data_remaining=661 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 357] Flushing write buffer. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 357] New state is HTTP_STATE_WAITING Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 357] Waiting for request. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] Server address is "[v1.::1]". Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] Accepted from localhost:49208 (IPv6) Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] Waiting for request. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] POST / HTTP/1.1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] Read: status=200, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] No authentication data provided. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] 1.1 Get-Job-Attributes 709 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: Get-Job-Attributes ipp://localhost/jobs/45 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/45) from localhost Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] Content-Length: 114 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Read: status=100, state=6 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: Send-Document ipp://localhost:631/printers/Follow-you1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdIsAuthorized: username="username" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Job 45] Auto-typing file... Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Job 45] Request file type is image/png. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdMarkDirty(---J-) Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Job 45] File of type image/png queued by "username". Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Job 45] Adding end banner page "none". Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdMarkDirty(---J-) Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/Follow-you1) from localhost Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Content-Length: 170 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] con->http=0x559143ecfc80 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=114, response=0x559143e718d0(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] bytes=0, http_state=0, data_remaining=114 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] Flushing write buffer. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] New state is HTTP_STATE_WAITING Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] Waiting for request. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] con->http=0x559143e9ba50 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=170, response=0x559143dec4f0(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] bytes=0, http_state=0, data_remaining=170 Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: REQUEST localhost - username "POST /printers/Follow-you1 HTTP/1.1" 200 183030 Send-Document successful-ok Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Flushing write buffer. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] New state is HTTP_STATE_WAITING Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Waiting for request. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 366] Closing connection. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: [Client 365] Closing connection. Jan 03 11:32:22 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" Jan 03 11:32:23 COMPUTERNAME cupsd[2088097]: Expiring subscriptions... Jan 03 11:32:31 COMPUTERNAME cupsd[2088097]: Saving job.cache... Jan 03 11:32:31 COMPUTERNAME cupsd[2088097]: Saving subscriptions.conf... Jan 03 11:32:31 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files" Jan 03 11:32:31 COMPUTERNAME cupsd[2088097]: Expiring subscriptions... Jan 03 11:32:42 COMPUTERNAME cupsd[2088097]: Expiring subscriptions... Jan 03 11:32:53 COMPUTERNAME cupsd[2088097]: Expiring subscriptions... Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Job 44] Try to authenticate as username@LOCAL.DOMAIN Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Job 44] Try to connect using Kerberos ... Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: Expiring subscriptions... Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: Report: clients=1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: Report: jobs=7 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: Report: jobs-active=2 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: Report: printers=2 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: Report: stringpool-string-count=3710 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: Report: stringpool-alloc-bytes=15096 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: Report: stringpool-total-bytes=79328 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Job 44] Session setup failed: NT_STATUS_INVALID_PARAMETER Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: Discarding unused job-progress event... Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdMarkDirty(----S) Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Notifier] state=3 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Notifier] PrinterStateChanged Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] POST / HTTP/1.1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=200, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] No authentication data provided. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] 1.1 Get-Notifications 710 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: Get-Notifications /printers/ Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdIsAuthorized: requesting-user-name="username" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Content-Length: 546 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] con->http=0x559143eb7a20 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=546, response=0x559143e67f50(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] bytes=0, http_state=0, data_remaining=546 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Flushing write buffer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] New state is HTTP_STATE_WAITING Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Waiting for request. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] POST / HTTP/1.1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=200, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] No authentication data provided. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] 1.1 CUPS-Get-Printers 711 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: CUPS-Get-Printers Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Content-Length: 2105 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] con->http=0x559143eb7a20 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2105, response=0x559143e5f110(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] bytes=0, http_state=0, data_remaining=2105 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Flushing write buffer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] New state is HTTP_STATE_WAITING Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Waiting for request. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] POST / HTTP/1.1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=200, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] No authentication data provided. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] 1.1 CUPS-Get-Default 712 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: CUPS-Get-Default Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: CUPS-Get-Default client-error-not-found: No default printer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Content-Length: 113 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] con->http=0x559143eb7a20 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x559143e67f50(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] bytes=0, http_state=0, data_remaining=113 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Flushing write buffer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] New state is HTTP_STATE_WAITING Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Waiting for request. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] POST / HTTP/1.1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=200, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] No authentication data provided. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] 1.1 CUPS-Get-Default 713 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: CUPS-Get-Default Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: CUPS-Get-Default client-error-not-found: No default printer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Content-Length: 113 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] con->http=0x559143eb7a20 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x559143e5f110(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] bytes=0, http_state=0, data_remaining=113 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Flushing write buffer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] New state is HTTP_STATE_WAITING Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Waiting for request. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Job 44] Session setup failed: NT_STATUS_NOT_SUPPORTED Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: Discarding unused job-progress event... Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdMarkDirty(----S) Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Notifier] state=3 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Notifier] PrinterStateChanged Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] POST / HTTP/1.1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=200, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] No authentication data provided. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] 1.1 Get-Notifications 714 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: Get-Notifications /printers/ Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdIsAuthorized: requesting-user-name="username" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Content-Length: 546 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] con->http=0x559143eb7a20 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=546, response=0x559143e5f110(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] bytes=0, http_state=0, data_remaining=546 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Flushing write buffer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] New state is HTTP_STATE_WAITING Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Waiting for request. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] POST / HTTP/1.1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=200, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] No authentication data provided. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] 1.1 CUPS-Get-Printers 715 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: CUPS-Get-Printers Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Content-Length: 2105 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] con->http=0x559143eb7a20 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2105, response=0x559143eaacf0(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] bytes=0, http_state=0, data_remaining=2105 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Flushing write buffer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] New state is HTTP_STATE_WAITING Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Waiting for request. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] POST / HTTP/1.1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=200, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] No authentication data provided. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] 1.1 CUPS-Get-Default 716 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: CUPS-Get-Default Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: CUPS-Get-Default client-error-not-found: No default printer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Content-Length: 113 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] con->http=0x559143eb7a20 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x559143e5f110(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] bytes=0, http_state=0, data_remaining=113 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Flushing write buffer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] New state is HTTP_STATE_WAITING Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Waiting for request. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] POST / HTTP/1.1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=200, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] No authentication data provided. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] 1.1 CUPS-Get-Default 717 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: CUPS-Get-Default Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: CUPS-Get-Default client-error-not-found: No default printer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Content-Length: 113 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] con->http=0x559143eb7a20 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x559143eaacf0(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] bytes=0, http_state=0, data_remaining=113 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Flushing write buffer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] New state is HTTP_STATE_WAITING Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Waiting for request. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Job 44] Session setup failed: NT_STATUS_NOT_SUPPORTED Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Job 44] get_exit_code(nt_status=NT_STATUS_NOT_SUPPORTED [c00000bb]) Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Job 44] Unable to connect to CIFS host: NT_STATUS_NOT_SUPPORTED. Will retry in 60 seconds... Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: Discarding unused job-progress event... Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdMarkDirty(----S) Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Notifier] state=3 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Notifier] PrinterStateChanged Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] POST / HTTP/1.1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=200, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] No authentication data provided. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] 1.1 Get-Notifications 718 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: Get-Notifications /printers/ Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdIsAuthorized: requesting-user-name="username" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Content-Length: 546 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] con->http=0x559143eb7a20 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=546, response=0x559143eaaca0(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] bytes=0, http_state=0, data_remaining=546 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Flushing write buffer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] New state is HTTP_STATE_WAITING Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Waiting for request. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] POST / HTTP/1.1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=200, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] No authentication data provided. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] 1.1 CUPS-Get-Printers 719 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: CUPS-Get-Printers Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Content-Length: 2105 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] con->http=0x559143eb7a20 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2105, response=0x559143e9abc0(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] bytes=0, http_state=0, data_remaining=2105 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Flushing write buffer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] New state is HTTP_STATE_WAITING Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Waiting for request. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] POST / HTTP/1.1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=200, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] No authentication data provided. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=100, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] 1.1 CUPS-Get-Default 720 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: CUPS-Get-Default Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: CUPS-Get-Default client-error-not-found: No default printer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Content-Length: 113 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] con->http=0x559143eb7a20 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x559143eaaca0(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] bytes=0, http_state=0, data_remaining=113 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Flushing write buffer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] New state is HTTP_STATE_WAITING Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Waiting for request. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] POST / HTTP/1.1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Read: status=200, state=6 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] No authentication data provided. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] 1.1 CUPS-Get-Default 721 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: CUPS-Get-Default Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: CUPS-Get-Default client-error-not-found: No default printer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Content-Length: 113 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] con->http=0x559143eb7a20 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x559143e9abc0(IPP_STATE_DATA), pipe_pid=0, file=-1 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] bytes=0, http_state=0, data_remaining=113 Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Flushing write buffer. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] New state is HTTP_STATE_WAITING Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: [Client 357] Waiting for request. Jan 03 11:33:00 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" Jan 03 11:33:01 COMPUTERNAME cupsd[2088097]: Expiring subscriptions... Jan 03 11:33:12 COMPUTERNAME cupsd[2088097]: Expiring subscriptions... Jan 03 11:33:23 COMPUTERNAME cupsd[2088097]: Expiring subscriptions... Jan 03 11:33:31 COMPUTERNAME cupsd[2088097]: Saving subscriptions.conf... Jan 03 11:33:31 COMPUTERNAME cupsd[2088097]: cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files" Jan 03 11:33:31 COMPUTERNAME cupsd[2088097]: Expiring subscriptions... Jan 03 11:33:42 COMPUTERNAME cupsd[2088097]: Expiring subscriptions...