Uploaded image for project: 'RHEL'
  1. RHEL
  2. RHEL-72678

Instal samba printer with kerberos authentication

Linking RHIVOS CVEs to...Migration: Automation ...SWIFT: POC ConversionSync from "Extern...XMLWordPrintable

    • Icon: Bug Bug
    • Resolution: Won't Do
    • Icon: Undefined Undefined
    • None
    • rhel-8.6.0
    • cups
    • None
    • No
    • None
    • rhel-stacks-web-servers
    • ssg_core_services
    • None
    • False
    • False
    • Hide

      None

      Show
      None
    • 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

      1. lpadmin -p "example_printer" -v smb://win_print_srv.ad.example.com/Example -m x2UNIV.ppd -o auth-info-required=negotiate -E
      2. 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...

              zdohnal@redhat.com Zdenek Dohnal
              quentin.laffitte Quentin LAFFITTE (Inactive)
              Zdenek Dohnal Zdenek Dohnal
              Petr Dancak Petr Dancak
              Votes:
              0 Vote for this issue
              Watchers:
              3 Start watching this issue

                Created:
                Updated:
                Resolved: