Print jobs never get to printer

Printing and scanning should be possible on Ubuntu 22.04 without HPLIP.
Give the outputs of

  lsusb -v | grep -A 3 bInterfaceClass.*7
  systemctl status ipp-usb
  avahi-browse -rt _ipp._tcp
  avahi-browse -rt _uscan._tcp
  driverless

scott@all-in-one:~/sandbox$ lsusb -v | grep -A 3 bInterfaceClass.*7
Couldn't open device, some information will be missing
      bInterfaceClass 7 Printer
      bInterfaceSubClass 1 Printer
      bInterfaceProtocol 4
      iInterface 0
--
      bInterfaceClass 7 Printer
      bInterfaceSubClass 1 Printer
      bInterfaceProtocol 2 Bidirectional
      iInterface 0
--
      bInterfaceClass 7 Printer
      bInterfaceSubClass 1 Printer
      bInterfaceProtocol 4
      iInterface 0
Couldn't open device, some information will be missing
--
      bInterfaceClass 7 Printer
      bInterfaceSubClass 1 Printer
      bInterfaceProtocol 4
      iInterface 0
Couldn't open device, some information will be missing
Couldn't open device, some information will be missing
Couldn't open device, some information will be missing
Couldn't open device, some information will be missing
Couldn't open device, some information will be missing
Couldn't open device, some information will be missing
Couldn't open device, some information will be missing

scott@all-in-one:~/sandbox$ systemctl status ipp-usb
● ipp-usb.service - Daemon for IPP over USB printer support
     Loaded: loaded (/lib/systemd/system/ipp-usb.service; static)
     Active: active (running) since Wed 2023-06-14 17:29:43 PDT; 13h ago
       Docs: man:ipp-usb(8)
   Main PID: 796 (ipp-usb)
      Tasks: 14 (limit: 8701)
     Memory: 13.9M
        CPU: 1min 28.464s
     CGroup: /system.slice/ipp-usb.service
             └─796 /sbin/ipp-usb udev

Jun 14 17:29:43 all-in-one systemd[1]: Started Daemon for IPP over USB
printer support.

scott@all-in-one:~/sandbox$ avahi-browse -rt _ipp._tcp

scott@all-in-one:~/sandbox$ avahi-browse -rt _uscan._tcp

scott@all-in-one:~/sandbox$ driverless

The last 3 commands returned nothing to the command prompt.

I should mention something else.
I have Anaconda (python + numpy + scipy + etc.) installed.

In my first attempt to install HPLIB I used the automatic installer.
I used the "automatic" mode.
I couldn't get it to work.
I think the problem was the confusion between my two versions of python
/usr/bin/python
/home/scott/anaconda3/bin/python
I ended up doing several "conda installs", e.g. (from my "history"):
  633 conda install -c conda-forge dbus-python
  634 conda install -c conda-forge qt
  635 conda install -c anaconda reportlab
  636 conda install -c conda-forge notify2
  637 conda install -c anaconda pyqt
  638 conda install -c conda-forge dbus-python
  639 conda install -c anaconda pyqt
  640 conda install -c conda-forge dbus
  641 conda install -c conda-forge python-devtools
The "conda installs" will update just my anaconda version of python (I
assume).
As you can see, I installed some things more than once as the installer
complained about it.
I got tired of playing "whack-a-mole" and came to the conclusion that there
was some confusion about which version of python was being used.
I do have some concern about what state my machine is in after all this
mucking about.
I can still bring up spyder and use my anaconda python, so I apparently did
not break anaconda.

For my second attempt, I commented out the lines of my .bashrc which
get me anaconda python. I.e., I commented out stuff like:
 export PATH="/home/scott/anaconda3/bin:$PATH"
Then I installed HPLIB using the manual installer.
It was easier to follow the individual steps and see where the problems
were.
I followed the instructions at
https://developers.hp.com/hp-linux-imaging-and-printing/install/manual/distros/ubuntu
The install seemed to go OK other than dealing with dependencies.
E.g., it was complaining about "libcanberra-gtk3" and a google search had
me do the following:
sudo apt install libcanberra-gtk-module libcanberra-gtk3-module -y
This "second attempt" is where I am now.

On Thu, Jun 15, 2023 at 7:05 AM brian_p <
<email address hidden>> wrote:

> Your question #706974 on HPLIP changed:
> https://answers.launchpad.net/hplip/+question/706974
>
> Status: Open => Needs information
>
> brian_p requested more information:
> Printing and scanning should be possible on Ubuntu 22.04 without HPLIP.
> Give the outputs of
>
> lsusb -v | grep -A 3 bInterfaceClass.*7
> systemctl status ipp-usb
> avahi-browse -rt _ipp._tcp
> avahi-browse -rt _uscan._tcp
> driverless
>
> --
> To answer this request for more information, you can either reply to
> this email or enter your reply at the following page:
> https://answers.launchpad.net/hplip/+question/706974
>
> You received this question notification because you asked the question.
>

> bInterfaceClass 7 Printer
> bInterfaceSubClass 1 Printer
> bInterfaceProtocol 4
> iInterface 0

The 7/1/4 indicates a device capable of IPP-over-USB. The printer should
have been auto-setup for you (without using HPLIP). See

  https://wiki.debian.org/CUPSDriverlessPrinting

> ipp-usb.service - Daemon for IPP over USB printer support
> Loaded: loaded (/lib/systemd/system/ipp-usb.service; static)
> Active: active (running) since Wed 2023-06-14 17:29:43 PDT; 13h ago

The ipp-usb service is active. It knows the device is capable of IPP-over-USB.

> The last 3 commands returned nothing to the command prompt.

You have a problem with mDNS/DNS-SD. The printer should be advertising
itself and Ubuntu should detect it. That is why you did not get auto-setup.
Is port 5353 blocked?

> bInterfaceClass 7 Printer
> bInterfaceSubClass 1 Printer
> bInterfaceProtocol 4
> iInterface 0

The 7/1/4 indicates a device capable of IPP-over-USB. The printer should
have been auto-setup for you (without using HPLIP). See

  https://wiki.debian.org/CUPSDriverlessPrinting

> ipp-usb.service - Daemon for IPP over USB printer support
> Loaded: loaded (/lib/systemd/system/ipp-usb.service; static)
> Active: active (running) since Wed 2023-06-14 17:29:43 PDT; 13h ago

The ipp-usb service is active. It knows the device is capable of IPP-over-USB.

> The last 3 commands returned nothing to the command prompt.

You have a problem with mDNS/DNS-SD. The printer should be advertising
itself and Ubuntu should detect it. That is why you did not get auto-setup.
Is port 5353 blocked?

google tells me to execute the following command to see open ports, and here is what I see:

scott@all-in-one:~/sandbox$ sudo ss -tulpn | grep LISTEN
tcp LISTEN 0 4096 127.0.0.53%lo:53 0.0.0.0:* users:(("systemd-resolve",pid=540,fd=14))
tcp LISTEN 0 128 127.0.0.1:631 0.0.0.0:* users:(("cupsd",pid=33767,fd=8))
tcp LISTEN 0 128 [::1]:631 [::]:* users:(("cupsd",pid=33767,fd=7))
tcp LISTEN 0 4096 *:9090 *:* users:(("systemd",pid=1,fd=65))

I apologize for my ignorance here. My experience is as a S/W developer on linux/unix systems, not as a sys admin. I am learning on my home machine.

The printer advertising (look for mdns, Bonjour, AirPrint) can be
checked from the printer's web server:

  http://localhost:60000

Also check

  systemctl status avahi-daemon

Clicking on the link http://localhost:60000 brings up a window which says:

Unable to connect

Firefox can’t establish a connection to the server at localhost:60000.

    The site could be temporarily unavailable or too busy. Try again in a few moments.
    If you are unable to load any pages, check your computer’s network connection.
    If your computer or network is protected by a firewall or proxy, make sure that Firefox is permitted to access the web.

running the command systemctl status avahi-daemon:

 scott@all-in-one:~$ systemctl status avahi-daemon
● avahi-daemon.service - Avahi mDNS/DNS-SD Stack
     Loaded: loaded (/lib/systemd/system/avahi-daemon.service; enabled; vendor preset: enabled)
     Active: active (running) since Wed 2023-06-14 17:29:42 PDT; 1 day 15h ago
TriggeredBy: ● avahi-daemon.socket
   Main PID: 617 (avahi-daemon)
     Status: "avahi-daemon 0.8 starting up."
      Tasks: 2 (limit: 8701)
     Memory: 1.1M
        CPU: 339ms
     CGroup: /system.slice/avahi-daemon.service
             ├─617 "avahi-daemon: running [all-in-one.local]"
             └─668 "avahi-daemon: chroot helper"

Jun 14 17:29:42 all-in-one avahi-daemon[617]: Network interface enumeration completed.
Jun 14 17:29:42 all-in-one avahi-daemon[617]: Registering new address record for ::1 on lo.*.
Jun 14 17:29:42 all-in-one avahi-daemon[617]: Registering new address record for 127.0.0.1 on lo.IPv4.
Jun 14 17:29:43 all-in-one avahi-daemon[617]: Server startup complete. Host name is all-in-one.local. Local service cookie is 2208114016.
Jun 14 17:29:47 all-in-one avahi-daemon[617]: Joining mDNS multicast group on interface enp1s0.IPv6 with address fe80::4cb0:c4ce:65d7:4d63.
Jun 14 17:29:47 all-in-one avahi-daemon[617]: New relevant interface enp1s0.IPv6 for mDNS.
Jun 14 17:29:47 all-in-one avahi-daemon[617]: Registering new address record for fe80::4cb0:c4ce:65d7:4d63 on enp1s0.*.
Jun 14 17:29:50 all-in-one avahi-daemon[617]: Joining mDNS multicast group on interface enp1s0.IPv4 with address 192.168.1.40.
Jun 14 17:29:50 all-in-one avahi-daemon[617]: New relevant interface enp1s0.IPv4 for mDNS.
Jun 14 17:29:50 all-in-one avahi-daemon[617]: Registering new address record for 192.168.1.40 on enp1s0.IPv4.

I went to the website:
https://support.hp.com/us-en/product/hp-smart-tank-5100-all-in-one-printer-series/2100992728/how-to
From the "manuals" tab, I downloaded 3 manuals:
and searched them for each of the 3 keywords "mdns", "bojour", "airprint".
I could find nothing.
The 3 manuals are (typing by hand, so excuse typos):
    HP Printers - SMB Protocol Support for HP Printing Devices (white paper) 0.25 MB
    Setup and Reference Guide 11.54 MB
    User Guide 4.07 MB

what else can you suggest?

I am a novice at system admin. I do not know how to "open" or "close" ports. If that's all I need and if there is a simple command to do so, please tell me. My searching on the internet and trying things out is just getting me confused. I have tried to turn on the port, but it seems that I have not been successful. I have simple questions like: Is 53:53 equivalent to 5353 when looking at the output of some of these commands. Is there a simple overview of the relevant info that I could read?

A google search suggested looking at the file /var/log/cups/error_log.
Here is the end of the file (the last job, numbered "20" plus the last line of job "19"):

D [16/Jun/2023:09:13:58 -0700] [Job 19] printer-state-reasons=none
E [16/Jun/2023:20:20:33 -0700] [Job 20] Unable to open raster stream - : Broken pipe
E [16/Jun/2023:20:20:33 -0700] [Job 20] Job stopped due to filter errors; please consult the /var/log/cups/error_log file for details.
D [16/Jun/2023:20:20:33 -0700] [Job 20] The following messages were recorded from 08:20:33 PM to 08:20:33 PM
D [16/Jun/2023:20:20:33 -0700] [Job 20] Applying default options...
D [16/Jun/2023:20:20:33 -0700] [Job 20] Adding start banner page "none".
D [16/Jun/2023:20:20:33 -0700] [Job 20] Queued on "Smart_Tank_5100" by "scott".
D [16/Jun/2023:20:20:33 -0700] [Job 20] Auto-typing file...
D [16/Jun/2023:20:20:33 -0700] [Job 20] Request file type is application/pdf.
D [16/Jun/2023:20:20:33 -0700] [Job 20] File of type application/pdf queued by "scott".
D [16/Jun/2023:20:20:33 -0700] [Job 20] Adding end banner page "none".
D [16/Jun/2023:20:20:33 -0700] [Job 20] time-at-processing=1686972033
D [16/Jun/2023:20:20:33 -0700] [Job 20] 3 filters for job:
D [16/Jun/2023:20:20:33 -0700] [Job 20] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [16/Jun/2023:20:20:33 -0700] [Job 20] gstoraster (application/vnd.cups-pdf to application/vnd.cups-raster, cost 99)
D [16/Jun/2023:20:20:33 -0700] [Job 20] hpcups (application/vnd.cups-raster to printer/Smart_Tank_5100, cost 0)
D [16/Jun/2023:20:20:33 -0700] [Job 20] job-sheets=none,none
D [16/Jun/2023:20:20:33 -0700] [Job 20] argv[0]="Smart_Tank_5100"
D [16/Jun/2023:20:20:33 -0700] [Job 20] argv[1]="20"
D [16/Jun/2023:20:20:33 -0700] [Job 20] argv[2]="scott"
D [16/Jun/2023:20:20:33 -0700] [Job 20] argv[3]="junks.odt"
D [16/Jun/2023:20:20:33 -0700] [Job 20] argv[4]="1"
D [16/Jun/2023:20:20:33 -0700] [Job 20] argv[5]="InputSlot=Main PageSize=IndexCardLetter job-uuid=urn:uuid:1d4a1f59-442a-39f3-4ab6-812b4e11d5b6 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1686972033 time-at-processing=1686972033 document-name-supplied=vxdIeW"
D [16/Jun/2023:20:20:33 -0700] [Job 20] argv[6]="/var/spool/cups/d00020-001"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[3]="CUPS_REQUESTROOT=/var/spool/cups"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[4]="CUPS_SERVERBIN=/usr/lib/cups"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[5]="CUPS_SERVERROOT=/etc/cups"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[6]="CUPS_STATEDIR=/run/cups"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[7]="HOME=/var/spool/cups/tmp"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[9]="SERVER_ADMIN=root@all-in-one"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[10]="SOFTWARE=CUPS/2.4.1"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[12]="USER=root"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[13]="CUPS_MAX_MESSAGE=2047"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[14]="CUPS_SERVER=/run/cups/cups.sock"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[16]="IPP_PORT=631"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[17]="CHARSET=utf-8"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[18]="LANG=en_US.UTF-8"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[19]="PPD=/etc/cups/ppd/Smart_Tank_5100.ppd"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[20]="CONTENT_TYPE=application/pdf"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[21]="DEVICE_URI=hp:/usb/Smart_Tank_5100_series?serial=CN32L3C0W7"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[22]="PRINTER_INFO="
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[23]="PRINTER_LOCATION="
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[24]="PRINTER=Smart_Tank_5100"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[25]="PRINTER_STATE_REASONS=none"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[26]="CUPS_FILETYPE=document"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[27]="FINAL_CONTENT_TYPE=application/vnd.cups-raster"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[28]="AUTH_I****"
D [16/Jun/2023:20:20:33 -0700] [Job 20] Started filter /usr/lib/cups/filter/pdftopdf (PID 249913)
D [16/Jun/2023:20:20:33 -0700] [Job 20] Started filter /usr/lib/cups/filter/gstoraster (PID 249914)
D [16/Jun/2023:20:20:33 -0700] [Job 20] Started filter /usr/lib/cups/filter/hpcups (PID 249915)
D [16/Jun/2023:20:20:33 -0700] [Job 20] Started backend /usr/lib/cups/backend/hp (PID 249916)
D [16/Jun/2023:20:20:33 -0700] [Job 20] Smart_Tank_5100: error while loading shared libraries: libImageProcessor.so: cannot open shared object file: No such file or directory
D [16/Jun/2023:20:20:33 -0700] [Job 20] PID 249915 (/usr/lib/cups/filter/hpcups) stopped with status 127 (File too large)
D [16/Jun/2023:20:20:33 -0700] [Job 20] Hint: Try setting the LogLevel to "debug" to find out more.
D [16/Jun/2023:20:20:33 -0700] [Job 20] OUTFORMAT=\"<none>\", so output format will be CUPS/PWG Raster
D [16/Jun/2023:20:20:33 -0700] [Job 20] pdftopdf: Last filter determined by the PPD: hpcups; FINAL_CONTENT_TYPE: application/vnd.cups-raster => pdftopdf will not log pages in page_log.
D [16/Jun/2023:20:20:33 -0700] [Job 20] prnt/backend/hp.c 919: ERROR: null print job total=0
D [16/Jun/2023:20:20:33 -0700] [Job 20] PID 249916 (/usr/lib/cups/backend/hp) exited with no errors.
D [16/Jun/2023:20:20:33 -0700] [Job 20] PDF interactive form and annotation flattening done via QPDF
D [16/Jun/2023:20:20:33 -0700] [Job 20] pdftopdf: \"print-scaling\" IPP attribute: auto
D [16/Jun/2023:20:20:33 -0700] [Job 20] pdftopdf: Print scaling mode: Do not scale, center, crop if needed
D [16/Jun/2023:20:20:33 -0700] [Job 20] After Cropping: 612.000000 792.000000 612.000000 792.000000
D [16/Jun/2023:20:20:33 -0700] [Job 20] PID 249913 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [16/Jun/2023:20:20:33 -0700] [Job 20] Color Manager: Calibration Mode/Off
D [16/Jun/2023:20:20:33 -0700] [Job 20] Calling FindDeviceById(cups-Smart_Tank_5100)
D [16/Jun/2023:20:20:33 -0700] [Job 20] Found device /org/freedesktop/ColorManager/devices/cups_Smart_Tank_5100
D [16/Jun/2023:20:20:33 -0700] [Job 20] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors)
D [16/Jun/2023:20:20:33 -0700] [Job 20] Calling FindDeviceById(cups-Smart_Tank_5100)
D [16/Jun/2023:20:20:33 -0700] [Job 20] Found device /org/freedesktop/ColorManager/devices/cups_Smart_Tank_5100
D [16/Jun/2023:20:20:33 -0700] [Job 20] Calling GetProfileForQualifiers(RGB.Plain....)
D [16/Jun/2023:20:20:33 -0700] [Job 20] Found profile /org/freedesktop/ColorManager/profiles/Smart_Tank_5100_RGB__
D [16/Jun/2023:20:20:33 -0700] [Job 20] Calling org.freedesktop.ColorManager.Profile.Get(Filename)
D [16/Jun/2023:20:20:33 -0700] [Job 20] Failed to get profile filename for cups-Smart_Tank_5100
D [16/Jun/2023:20:20:33 -0700] [Job 20] Color Manager: ICC Profile:
D [16/Jun/2023:20:20:33 -0700] [Job 20] Ghostscript using Any-Part-of-Pixel method to fill paths.
D [16/Jun/2023:20:20:33 -0700] [Job 20] Ghostscript command line: gs -dQUIET -dSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -dNOMEDIAATTRS -dShowAcroForm -sstdout=%stderr -sOutputFile=%stdout -sDEVICE=cups -sMediaType=Plain -sOutputType=0 -r600x600 -dMediaPosition=1 -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -dcupsBitsPerColor=8 -dcupsColorOrder=0 -dcupsColorSpace=17 -dcupsInteger0=2 -dcupsInteger6=1084 -scupsPageSizeName=IndexCardLetter -I/usr/share/cups/fonts -c \'<</.HWMargins[8.400000 8.400000 8.400024 8.400024] /Margins[0 0]>>setpagedevice\' -f -_
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[0]=\"CUPS_CACHEDIR=/var/cache/cups\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[1]=\"CUPS_DATADIR=/usr/share/cups\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[2]=\"CUPS_DOCROOT=/usr/share/cups/doc-root\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[3]=\"CUPS_REQUESTROOT=/var/spool/cups\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[4]=\"CUPS_SERVERBIN=/usr/lib/cups\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[5]=\"CUPS_SERVERROOT=/etc/cups\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[6]=\"CUPS_STATEDIR=/run/cups\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[7]=\"HOME=/var/spool/cups/tmp\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[8]=\"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[9]=\"SERVER_ADMIN=root@all-in-one\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[10]=\"SOFTWARE=CUPS/2.4.1\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[11]=\"USER=root\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[12]=\"CUPS_MAX_MESSAGE=2047\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[13]=\"CUPS_SERVER=/run/cups/cups.sock\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[14]=\"CUPS_ENCRYPTION=IfRequested\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[15]=\"IPP_PORT=631\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[16]=\"CHARSET=utf-8\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[17]=\"LANG=en_US.UTF-8\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[18]=\"PPD=/etc/cups/ppd/Smart_Tank_5100.ppd\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[19]=\"CONTENT_TYPE=application/pdf\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[20]=\"DEVICE_URI=hp:/usb/Smart_Tank_5100_series?serial=CN32L3C0W7\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[21]=\"PRINTER_INFO=\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[22]=\"PRINTER_LOCATION=\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[23]=\"PRINTER=Smart_Tank_5100\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[24]=\"PRINTER_STATE_REASONS=none\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[25]=\"CUPS_FILETYPE=document\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[26]=\"FINAL_CONTENT_TYPE=application/vnd.cups-raster\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] envp[27]=\"AUTH_INFO_REQUIRED=none\"
D [16/Jun/2023:20:20:33 -0700] [Job 20] Start rendering...
D [16/Jun/2023:20:20:33 -0700] [Job 20] Processing page 1...
D [16/Jun/2023:20:20:33 -0700] [Job 20] Error: /ioerror in --showpage--
D [16/Jun/2023:20:20:33 -0700] [Job 20] Operand stack:
D [16/Jun/2023:20:20:33 -0700] [Job 20] (/tmp/gs_mOVlQK) --nostringval-- 1 true
D [16/Jun/2023:20:20:33 -0700] [Job 20] Execution stack:
D [16/Jun/2023:20:20:33 -0700] [Job 20] %interp_exit .runexec2 --nostringval-- showpage --nostringval-- 2 %stopped_push --nostringval-- showpage showpage false 1 %stopped_push 1990 1 3 %oparray_pop 1989 1 3 %oparray_pop 1977 1 3 %oparray_pop showpage 1978 3 3 %oparray_pop showpage showpage 2 1 1 showpage %for_pos_int_continue 1981 3 7 %oparray_pop showpage showpage 1840 2 9 %oparray_pop showpage showpage
D [16/Jun/2023:20:20:33 -0700] [Job 20] Dictionary stack:
D [16/Jun/2023:20:20:33 -0700] [Job 20] --dict:777/1123(ro)(G)-- --dict:1/20(G)-- --dict:81/200(L)-- --dict:81/200(L)-- --dict:134/256(ro)(G)-- --dict:325/325(ro)(G)-- --dict:35/64(L)-- --dict:6/9(L)-- --dict:6/20(L)--
D [16/Jun/2023:20:20:33 -0700] [Job 20] Current allocation mode is local
D [16/Jun/2023:20:20:33 -0700] [Job 20] Last OS error: Broken pipe
D [16/Jun/2023:20:20:33 -0700] [Job 20] GPL Ghostscript 9.55.0: Unrecoverable error, exit code 1
D [16/Jun/2023:20:20:33 -0700] [Job 20] Rendering completed
D [16/Jun/2023:20:20:33 -0700] [Job 20] PID 249914 (/usr/lib/cups/filter/gstoraster) stopped with status 1.
D [16/Jun/2023:20:20:33 -0700] [Job 20] Hint: Try setting the LogLevel to "debug" to find out more.
D [16/Jun/2023:20:20:33 -0700] [Job 20] End of messages
D [16/Jun/2023:20:20:33 -0700] [Job 20] printer-state=3(idle)
D [16/Jun/2023:20:20:33 -0700] [Job 20] printer-state-message="Rendering completed"
D [16/Jun/2023:20:20:33 -0700] [Job 20] printer-state-reasons=none

> Firefox can’t establish a connection to the server at localhost:60000.

ipp-usb knows the printer is there because the service is active. An active
service should mean the printer's web interface is accessible. I'm lost and
imagine

  http://127.0.0.1:60000

also fails.

What do you get for (as root)

  ipp-usb check ?

From the error_log:

> D libImageProcessor.so: cannot open shared object file: No such file or directory
> D [16/Jun/2023:20:20:33 -0700] [Job 20] PID 249915 (/usr/lib/cups/filter/hpcups) stopped > with status 127 (File too large)

Looks like the cause of HPLIP failing. I do not know how to deal with this

As you suggested,

  http://127.0.0.1:60000

also fails

ipp-usb check gives me:

scott@all-in-one:/var/log/cups$ sudo ipp-usb check
[sudo] password for scott:
IPP over USB devices:
 Num Device Vndr:Prod Model
   1. Bus 003 Device 004 03f0:5054 "HP Smart Tank 5100 series"

I have two general comments:

[1] I did some research on "driverless" etc. and now agree with your previous statement to the effect that I should not need HPLIP with Ubuntu 22.04 and my particular printer. If that is the case, then why should I care if HPLIP is failing.

[2] Recall earlier in discussion, the ambiguity between the 2 pythons I have installed. I think this hindered my installation of HPLIP.
In fact, perhaps HPLIB cannot find the shared object file because of my botched installation.
I do not know that my installation is botched, but I do have some trepidation.

I am quite happy going with the driverless route. I did not know that was an option until you mentioned it.
But, if (I say IF) I still want to go with HPLIP, is there a way of doing the equivalent of a "make clean", and re-installing it?

Thank you for your patience

FWIW, I reinstalled cups:

sudo apt install --reinstall cups

I rebooted and did the following:

scott@all-in-one:~$ sudo lsof -i -P -n | grep LISTEN
systemd 1 root 65u IPv6 26073 0t0 TCP *:9090 (LISTEN)
systemd-r 550 systemd-resolve 14u IPv4 19066 0t0 TCP 127.0.0.53:53 (LISTEN)
cupsd 742 root 7u IPv6 20967 0t0 TCP [::1]:631 (LISTEN)
cupsd 742 root 8u IPv4 20968 0t0 TCP 127.0.0.1:631 (LISTEN)

scott@all-in-one:~$ sudo ufw status
Status: active

To Action From
-- ------ ----
5353 ALLOW Anywhere
80/tcp ALLOW Anywhere
443 ALLOW Anywhere
5353 (v6) ALLOW Anywhere (v6)
80/tcp (v6) ALLOW Anywhere (v6)
443 (v6) ALLOW Anywhere (v6)

BTW, I found the following link which is educational to me:
http://localhost:631

I tried hp-testpage which does not work
file /var/log/cups/error_log show the same errors as above (#9)

"GPL Ghostscript 9.55.0: Unrecoverable error, exit code 1"

You might also try reinstalling ghostscript

sudo apt install --reinstall libgs9 libgs9-common

I did a:

sudo apt install --reinstall libgs9 libgs9-common

then rebooted.

It still does not work.
hp-testpage still sends nothing to the printer.

BTW, when I run hp-testpage, there are two radio buttons:
           * HPLIP test page (tests print driver)
           * Printer diagnostic page (does not test print driver)

The second option (".... (does not test print driver)") is grayed out, so I cannot click that option.
Should I be concerned?

FWIW, here's what happens when I run hp-testpage in debug mode:

scott@all-in-one:~$ hp-testpage -g

HP Linux Imaging and Printing System (ver. 3.23.5)
Testpage Print Utility ver. 6.0

Copyright (c) 2001-18 HP Development Company, LP
This software comes with ABSOLUTELY NO WARRANTY.
This is free software, and you are welcome to distribute it
under certain conditions. See COPYING file for more details.

hp-testpage[6341]: debug: getPrinterName(None, None, ('hp',), None)
hp-testpage[6341]: debug: Mode=1
hp-testpage[6341]: debug: Cache miss: smart_tank_5100_series
hp-testpage[6341]: debug: Reading file: /usr/share/hplip/data/models/models.dat
hp-testpage[6341]: debug: Searching for section [smart_tank_5100_series] in file /usr/share/hplip/data/models/models.dat
hp-testpage[6341]: debug: Found section [smart_tank_5100_series] in file /usr/share/hplip/data/models/models.dat
hp-testpage[6341]: debug: ['Smart_Tank_5100']
hp-testpage[6341]: debug: Using PyQt5
Warning: Ignoring XDG_SESSION_TYPE=wayland on Gnome. Use QT_QPA_PLATFORM=wayland to run on Wayland anyway.
hp-testpage(UI)[6341]: debug: Loading user settings...
hp-testpage(UI)[6341]: debug: FAB command: /usr/bin/hp-fab
hp-testpage(UI)[6341]: debug: Scan command: /usr/bin/xsane -V %SANE_URI%
hp-testpage(UI)[6341]: debug: Auto refresh: False
hp-testpage(UI)[6341]: debug: Auto refresh rate: 30
hp-testpage(UI)[6341]: debug: Auto refresh type: 1
hp-testpage(UI)[6341]: debug: Systray visible: 0
hp-testpage(UI)[6341]: debug: Systray messages: 0
hp-testpage(UI)[6341]: debug: Last used device URI: hp:/usb/Smart_Tank_5100_series?serial=CN32L3C0W7
hp-testpage(UI)[6341]: debug: Last used printer: Smart_Tank_5100
hp-testpage(UI)[6341]: debug: Working directory: .
hp-testpage(UI)[6341]: debug: Starting GUI loop...
hp-testpage(UI)[6341]: debug: Saving user settings...
hp-testpage(UI)[6341]: debug: {'kind': 2, 'type': 4, 'health': 0, 'level': 100, 'level-trigger': 0, 'agent-sku': ''}
hp-testpage(UI)[6341]: debug: {'kind': 2, 'type': 5, 'health': 0, 'level': 100, 'level-trigger': 0, 'agent-sku': ''}
hp-testpage(UI)[6341]: debug: {'kind': 2, 'type': 6, 'health': 0, 'level': 100, 'level-trigger': 0, 'agent-sku': ''}
hp-testpage(UI)[6341]: debug: {'kind': 2, 'type': 1, 'health': 0, 'level': 100, 'level-trigger': 0, 'agent-sku': ''}

Any other ideas?

I tried restarting cups:

sudo service cups restart

I tried re-installing hplip:

sudo apt remove hplip hplip-data

 udo apt install hplip hplip-gui (anwer “yes” to question)

This seemed to leave me worse off than before, so I (re-)installed HPLIP using the tar.gz file following instructions at
https://developers.hp.com/hp-linux-imaging-and-printing/install/manual/distros/ubuntu

hp-check gives me all "OKs", No errors or warnings.
But hp-testpage does not send anything to the printer.

Any comments?

I went to the website:

https://wiki.ubuntu.com/DebuggingPrintingProblems

and worked my way down the page.
When I got to the section:

https://wiki.ubuntu.com/DebuggingPrintingProblems#Getting_the_data_which_would_go_to_the_printer

I got an odd result:

The file /tmp/printout gets created, but it has a size of 0

Maybe this is still caused by the ghostscript crash (see comments #10 and #14)

Is there anything shown in the system logs about a crash of a related process?

I disabled "apparmor" and re-tried printing to the test file as per

https://wiki.ubuntu.com/DebuggingPrintingProblems#Getting_the_data_which_would_go_to_the_printer

I used journalctl to look at system logs and saw no error message about ghostscript.
I did see the error message:
                   SQLite error: database disk image is malformed (errno: Bad file descriptor)
After a dialog with chatgpt I did the following:

tracker3 daemon --kill
rm -r ~/.cache/tracker3/
rm -r ~/.local/share/tracker3/
tracker3 daemon --start

I re-tried printing, looking at the error logs, the SQLite error was gone, and now I found the error message:
         colord-sane[30612]: io/hpmud/musb.c 2101: Invalid usb_open: Permission denied
After a dialog with chatgpt, I did the following:

sudo usermod -aG scanner colord
<reboot>
sudo systemctl restart colord.service

I re-tried printing (notice the time stamp)

scott@all-in-one:~/sandbox$ date; lp -d test junk.txt
Thu Jun 22 04:35:23 PM PDT 2023

My error log file has only two lines which contain the text "error":
Jun 22 16:34:31 all-in-one fprintd[6994]: libusb: error [udev_hotplug_event] ignoring udev action change
Jun 22 16:40:51 all-in-one fprintd[8263]: libusb: error [udev_hotplug_event] ignoring udev action change

I have two comments about the "fprintd" error message:
[1] fprintd is apparently a "Fingerprint Authentication Daemon". Why do I need one of these? I have no hardware to support such a thing.
[2] The times of the "fprintd" errors are both BEFORE and AFTER the file of printing, which to me implies that the daemon is just doing its thing independently of my print request.

So why does my printing still not work?

To get this last error log, I executed the command:
journalctl --since "10 minutes ago" > journal8.txt

What follows is the contents of the file journal8.txt (recall the time stamp of the print: Thu Jun 22 04:35:23 PM PDT 2023):

Jun 22 16:33:19 all-in-one systemd[1]: Started Run anacron jobs.
Jun 22 16:33:19 all-in-one anacron[6986]: Anacron 2.3 started on 2023-06-22
Jun 22 16:33:19 all-in-one anacron[6986]: Normal exit (0 jobs run)
Jun 22 16:33:19 all-in-one systemd[1]: anacron.service: Deactivated successfully.
Jun 22 16:34:30 all-in-one dbus-daemon[623]: [system] Activating via systemd: service name='net.reactivated.Fprint' unit='fprintd.service' requested by ':1.89' (uid=1000 pid=1776 comm="/usr/bin/gnome-shell " label="unconfined")
Jun 22 16:34:30 all-in-one systemd[1]: Starting Fingerprint Authentication Daemon...
Jun 22 16:34:30 all-in-one dbus-daemon[623]: [system] Successfully activated service 'net.reactivated.Fprint'
Jun 22 16:34:30 all-in-one systemd[1]: Started Fingerprint Authentication Daemon.
Jun 22 16:34:30 all-in-one gnome-shell[1776]: JS ERROR: Failed to initialize fprintd service: Gio.IOErrorEnum: GDBus.Error:net.reactivated.Fprint.Error.NoSuchDevice: No devices available
                                              asyncCallback@resource:///org/gnome/gjs/modules/core/overrides/Gio.js:114:23
Jun 22 16:34:31 all-in-one kernel: usb 1-3.1: new full-speed USB device number 6 using xhci_hcd
Jun 22 16:34:31 all-in-one kernel: usb 1-3.1: New USB device found, idVendor=1fd2, idProduct=8102, bcdDevice= 1.00
Jun 22 16:34:31 all-in-one kernel: usb 1-3.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Jun 22 16:34:31 all-in-one kernel: usb 1-3.1: Product: LGDisplay Incell Touch
Jun 22 16:34:31 all-in-one kernel: usb 1-3.1: Manufacturer: Melfas
Jun 22 16:34:31 all-in-one kernel: input: Melfas LGDisplay Incell Touch as /devices/pci0000:00/0000:00:08.1/0000:04:00.3/usb1/1-3/1-3.1/1-3.1:1.0/0003:1FD2:8102.0008/input/input16
Jun 22 16:34:31 all-in-one kernel: hid-multitouch 0003:1FD2:8102.0008: input,hidraw3: USB HID v1.11 Device [Melfas LGDisplay Incell Touch] on usb-0000:04:00.3-3.1/input0
Jun 22 16:34:31 all-in-one kernel: hid-generic 0003:1FD2:8102.0009: hiddev1,hidraw4: USB HID v1.11 Device [Melfas LGDisplay Incell Touch] on usb-0000:04:00.3-3.1/input1
Jun 22 16:34:31 all-in-one mtp-probe[7015]: checking bus 1, device 6: "/sys/devices/pci0000:00/0000:00:08.1/0000:04:00.3/usb1/1-3/1-3.1"
Jun 22 16:34:31 all-in-one mtp-probe[7015]: bus: 1, device: 6 was not an MTP device
Jun 22 16:34:31 all-in-one fprintd[6994]: libusb: error [udev_hotplug_event] ignoring udev action change
Jun 22 16:34:31 all-in-one mtp-probe[7032]: checking bus 1, device 6: "/sys/devices/pci0000:00/0000:00:08.1/0000:04:00.3/usb1/1-3/1-3.1"
Jun 22 16:34:31 all-in-one mtp-probe[7032]: bus: 1, device: 6 was not an MTP device
Jun 22 16:34:36 all-in-one gdm-password][7012]: gkr-pam: unlocked login keyring
Jun 22 16:34:36 all-in-one dbus-daemon[1567]: [session uid=1000 pid=1567] Activating service name='org.freedesktop.FileManager1' requested by ':1.35' (uid=1000 pid=1776 comm="/usr/bin/gnome-shell " label="unconfined")
Jun 22 16:34:36 all-in-one NetworkManager[893]: <info> [1687476876.6446] agent-manager: agent[a5e06cb9b46f65c0,:1.89/org.gnome.Shell.NetworkAgent/1000]: agent registered
Jun 22 16:34:36 all-in-one <email address hidden>[1776]: unable to update icon for software-update-available
Jun 22 16:34:36 all-in-one <email address hidden>[1776]: unable to update icon for livepatch
Jun 22 16:34:36 all-in-one dbus-daemon[1567]: [session uid=1000 pid=1567] Successfully activated service 'org.freedesktop.FileManager1'
Jun 22 16:34:36 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 32 with keysym 32 (keycode b).
Jun 22 16:34:36 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 31 with keysym 31 (keycode a).
Jun 22 16:34:36 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 33 with keysym 33 (keycode c).
Jun 22 16:34:36 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 35 with keysym 35 (keycode e).
Jun 22 16:34:36 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 34 with keysym 34 (keycode d).
Jun 22 16:34:36 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 36 with keysym 36 (keycode f).
Jun 22 16:34:36 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 37 with keysym 37 (keycode 10).
Jun 22 16:34:36 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 39 with keysym 39 (keycode 12).
Jun 22 16:34:36 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 38 with keysym 38 (keycode 11).
Jun 22 16:34:36 all-in-one dbus-daemon[1567]: [session uid=1000 pid=1567] Activating service name='org.gnome.ArchiveManager1' requested by ':1.141' (uid=1000 pid=7043 comm="gjs /usr/share/gnome-shell/extensions/ding@rasters" label="unconfined")
Jun 22 16:34:37 all-in-one dbus-daemon[1567]: [session uid=1000 pid=1567] Successfully activated service 'org.gnome.ArchiveManager1'
Jun 22 16:34:37 all-in-one gnome-shell[1776]: DING: Detected async api for thumbnails
Jun 22 16:34:37 all-in-one gnome-shell[1776]: DING: GNOME nautilus 42.6
Jun 22 16:34:46 all-in-one nautilus[7035]: Could not delete '.meta.isrunning': No such file or directory
Jun 22 16:35:00 all-in-one systemd[1]: fprintd.service: Deactivated successfully.
Jun 22 16:36:34 all-in-one systemd[1542]: Starting Kite Updater...
Jun 22 16:37:22 all-in-one systemd[1542]: Reloading.
Jun 22 16:37:22 all-in-one systemd[1542]: Reloading.
Jun 22 16:37:22 all-in-one systemd[1542]: Reloading.
Jun 22 16:37:22 all-in-one systemd[1542]: Stopped kite-updater timer.
Jun 22 16:37:22 all-in-one systemd[1542]: Reloading.
Jun 22 16:37:23 all-in-one systemd[1542]: Reloading.
Jun 22 16:37:23 all-in-one systemd[1542]: Reloading.
Jun 22 16:37:23 all-in-one systemd[1542]: Reloading.
Jun 22 16:37:23 all-in-one systemd[1542]: Reloading.
Jun 22 16:37:23 all-in-one systemd[1542]: Reloading.
Jun 22 16:37:23 all-in-one dbus-daemon[1567]: [session uid=1000 pid=1567] Activating via systemd: service name='org.freedesktop.Tracker3.Miner.Extract' unit='tracker-extract-3.service' requested by ':1.9' (uid=1000 pid=1632 comm="/usr/libexec/tracker-miner-fs-3 " label="unconfined")
Jun 22 16:37:24 all-in-one systemd[1542]: Starting Tracker metadata extractor...
Jun 22 16:37:24 all-in-one systemd[1542]: Reloading.
Jun 22 16:37:24 all-in-one dbus-daemon[1567]: [session uid=1000 pid=1567] Successfully activated service 'org.freedesktop.Tracker3.Miner.Extract'
Jun 22 16:37:24 all-in-one systemd[1542]: Started Tracker metadata extractor.
Jun 22 16:37:24 all-in-one systemd[1542]: Started kite-updater timer.
Jun 22 16:37:24 all-in-one systemd[1542]: Reloading.
Jun 22 16:37:24 all-in-one systemd[1542]: Reloading.
Jun 22 16:37:24 all-in-one systemd[1542]: Reloading.
Jun 22 16:37:24 all-in-one systemd[1542]: Finished Kite Updater.
Jun 22 16:37:24 all-in-one systemd[1542]: kite-updater.service: Consumed 30.659s CPU time.
Jun 22 16:40:33 all-in-one update-notifier[4186]: gtk_widget_get_scale_factor: assertion 'GTK_IS_WIDGET (widget)' failed
Jun 22 16:40:33 all-in-one update-notifier[4186]: gtk_widget_get_scale_factor: assertion 'GTK_IS_WIDGET (widget)' failed
Jun 22 16:40:34 all-in-one kernel: usb 1-3.1: USB disconnect, device number 6
Jun 22 16:40:49 all-in-one dbus-daemon[623]: [system] Activating via systemd: service name='net.reactivated.Fprint' unit='fprintd.service' requested by ':1.89' (uid=1000 pid=1776 comm="/usr/bin/gnome-shell " label="unconfined")
Jun 22 16:40:49 all-in-one systemd[1]: Starting Fingerprint Authentication Daemon...
Jun 22 16:40:49 all-in-one dbus-daemon[623]: [system] Successfully activated service 'net.reactivated.Fprint'
Jun 22 16:40:49 all-in-one systemd[1]: Started Fingerprint Authentication Daemon.
Jun 22 16:40:49 all-in-one gnome-shell[1776]: JS ERROR: Failed to initialize fprintd service: Gio.IOErrorEnum: GDBus.Error:net.reactivated.Fprint.Error.NoSuchDevice: No devices available
                                              asyncCallback@resource:///org/gnome/gjs/modules/core/overrides/Gio.js:114:23
Jun 22 16:40:50 all-in-one kernel: usb 1-3.1: new full-speed USB device number 7 using xhci_hcd
Jun 22 16:40:51 all-in-one kernel: usb 1-3.1: New USB device found, idVendor=1fd2, idProduct=8102, bcdDevice= 1.00
Jun 22 16:40:51 all-in-one kernel: usb 1-3.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Jun 22 16:40:51 all-in-one kernel: usb 1-3.1: Product: LGDisplay Incell Touch
Jun 22 16:40:51 all-in-one kernel: usb 1-3.1: Manufacturer: Melfas
Jun 22 16:40:51 all-in-one kernel: input: Melfas LGDisplay Incell Touch as /devices/pci0000:00/0000:00:08.1/0000:04:00.3/usb1/1-3/1-3.1/1-3.1:1.0/0003:1FD2:8102.000A/input/input17
Jun 22 16:40:51 all-in-one kernel: hid-multitouch 0003:1FD2:8102.000A: input,hidraw3: USB HID v1.11 Device [Melfas LGDisplay Incell Touch] on usb-0000:04:00.3-3.1/input0
Jun 22 16:40:51 all-in-one kernel: hid-generic 0003:1FD2:8102.000B: hiddev1,hidraw4: USB HID v1.11 Device [Melfas LGDisplay Incell Touch] on usb-0000:04:00.3-3.1/input1
Jun 22 16:40:51 all-in-one mtp-probe[8276]: checking bus 1, device 7: "/sys/devices/pci0000:00/0000:00:08.1/0000:04:00.3/usb1/1-3/1-3.1"
Jun 22 16:40:51 all-in-one mtp-probe[8276]: bus: 1, device: 7 was not an MTP device
Jun 22 16:40:51 all-in-one fprintd[8263]: libusb: error [udev_hotplug_event] ignoring udev action change
Jun 22 16:40:51 all-in-one mtp-probe[8294]: checking bus 1, device 7: "/sys/devices/pci0000:00/0000:00:08.1/0000:04:00.3/usb1/1-3/1-3.1"
Jun 22 16:40:51 all-in-one mtp-probe[8294]: bus: 1, device: 7 was not an MTP device
Jun 22 16:40:55 all-in-one gdm-password][8272]: gkr-pam: unlocked login keyring
Jun 22 16:40:55 all-in-one dbus-daemon[1567]: [session uid=1000 pid=1567] Activating service name='org.freedesktop.FileManager1' requested by ':1.35' (uid=1000 pid=1776 comm="/usr/bin/gnome-shell " label="unconfined")
Jun 22 16:40:55 all-in-one NetworkManager[893]: <info> [1687477255.9974] agent-manager: agent[ffbdb4bcfe854575,:1.89/org.gnome.Shell.NetworkAgent/1000]: agent registered
Jun 22 16:40:56 all-in-one <email address hidden>[1776]: unable to update icon for software-update-available
Jun 22 16:40:56 all-in-one <email address hidden>[1776]: unable to update icon for livepatch
Jun 22 16:40:56 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 37 with keysym 37 (keycode 10).
Jun 22 16:40:56 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 38 with keysym 38 (keycode 11).
Jun 22 16:40:56 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 33 with keysym 33 (keycode c).
Jun 22 16:40:56 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 31 with keysym 31 (keycode a).
Jun 22 16:40:56 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 35 with keysym 35 (keycode e).
Jun 22 16:40:56 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 39 with keysym 39 (keycode 12).
Jun 22 16:40:56 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 36 with keysym 36 (keycode f).
Jun 22 16:40:56 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 34 with keysym 34 (keycode d).
Jun 22 16:40:56 all-in-one gnome-shell[1776]: Window manager warning: Overwriting existing binding of keysym 32 with keysym 32 (keycode b).
Jun 22 16:40:56 all-in-one dbus-daemon[1567]: [session uid=1000 pid=1567] Successfully activated service 'org.freedesktop.FileManager1'
Jun 22 16:40:56 all-in-one dbus-daemon[1567]: [session uid=1000 pid=1567] Activating service name='org.gnome.ArchiveManager1' requested by ':1.149' (uid=1000 pid=8304 comm="gjs /usr/share/gnome-shell/extensions/ding@rasters" label="unconfined")
Jun 22 16:40:56 all-in-one dbus-daemon[1567]: [session uid=1000 pid=1567] Successfully activated service 'org.gnome.ArchiveManager1'
Jun 22 16:40:56 all-in-one gnome-shell[1776]: DING: Detected async api for thumbnails
Jun 22 16:40:56 all-in-one gnome-shell[1776]: DING: GNOME nautilus 42.6
Jun 22 16:41:06 all-in-one nautilus[8297]: Could not delete '.meta.isrunning': No such file or directory
Jun 22 16:41:19 all-in-one systemd[1]: fprintd.service: Deactivated successfully.

I forgot to mention: One other change I made was to disable network sharing of the printer (it is connected directly by USB).
I did this using the Settings Application:
Settings --> Printers --> Additional Printer Settings
In the popup window:
Right click on "Smart_Tank_5100" which gives a list with some items checked.
I make sure that "Enabled" is the only item checked.
Which means that "Shared" is not checked

BTW, as per
https://wiki.ubuntu.com/DebuggingPrintingProblems#Getting_the_data_which_would_go_to_the_printer
the file /tmp/printout is still of size zero.

I checked out ghostscript (see comment 18).
Using 'gs', I can successfully read a pdf file and a postcript file.

I ran 'hp-check' and the only problem I see is the message:
warning: Printer is not HPLIP installed. Printers must use the hp: or hpfax: CUPS backend for HP-Devices.

I tried submitting jobs to each of the 2 queues that I have:
Queue # 1 is the one I really want, it's called "Smart_Tank_5100"
Queue #2 is called "test". This just prints to a file as per the website I used before (see comment 19), I.e., the following link:
https://wiki.ubuntu.com/DebuggingPrintingProblems#Getting_the_data_which_would_go_to_the_printer

Then I pointed my browser to:
http://localhost:631
and looked under the "jobs" tab.
There is a table with 3 rows and 7 columns.
Column 7 is just some "control" menu items, so I will omit that and type in a table with 3 rows and 6 columns.
I will use a colon (:) to delimit the columns of the table (just in case the variable width font messes things up):

ID : Name : User : Size : Pages : State
test-56 : Unknown : Withheld : 1k : Unknown : stopped "Filter failed"
Smart_Tank_5100-57 : Unknown : Withheld : 1k : Unknown : stopped "Filter failed"

Clearly the "filter failed" message is not what I want to see.
I'm thinking that I should follow the instructions at:
https://wiki.ubuntu.com/DebuggingPrintingProblems#Sending_a_file_to_the_printer_unfiltered

I will quote from this web page:
Do not send arbitrary files unfiltered to the printer now hoping that will solve any problems. Please use this mainly if you are asked for doing so in bug reports or after asking a support question.

So because the above advice gives me some trepidation, I await your advice on how to do this "Sending_a_file_to_the_printer_unfiltered". Or any other advice you might have.

FWIW, If I run the command
find /dev -name 'lp*'
I find nothing.

I'm telling you this, because I see that "method 2" at
https://wiki.ubuntu.com/DebuggingPrintingProblems#Sending_a_file_to_the_printer_unfiltered
assumes that one or more of these files exist.

ChatGPT tells me not to worry if I am using a USB-connected printer.

My understanding is that "method 3" makes no sense for me (my printer is not connected via a network).
This leaves only "method 1".

But for now, I will wait for your advice.

I guess, the key still is
stopped "Filter failed"

Unfortunately I do not have any idea how to find the root cause.

I am not giving up yet.
As per "method 1" of:
https://wiki.ubuntu.com/DebuggingPrintingProblems#Sending_a_file_to_the_printer_unfiltered
I tried the command:
 lp -d Smart_Tank_5100 -oraw junk.txt

The printer responded by lighting up a Red "X" (which is the "cancel" button).
This button stayed lit for over a minute and then went dark.
This is the most response I've seen from my printer..

For completeness, and in desperation, I am going to type in by hand (some of) what my printer spits out when I push the "I" button
(I assume I can ignore all the stuff about wireless, because I am using USB):

Printer Information Report
Model Name: HJP Smart Tank 5100 Series
Automatic Firmware Update: Off
Quiet Mode: Off
Bonjour Name: HP Smart Tank 5100 series [8EC9AE]
Estimated Ink Levels. Actual ink levelss may vary: K-unknown, CMY-Unknown
Serial Number: CN32L3C0W7
Firmware Version: LWP1CN2302GR
Printer PIN: 86151370

This printer is not connected to a network.
Wi-Fi has been turned off. Press the Wi-Fi* button to turn on Wi-Fi.
Once Wi-Fi is turned on, for printed instructions on how to connect the printer to a wireless network, press and hold the
Information* button for 3 seconds until all buttons are lit, and then press the Information button again.
To print additional network information, press and hold the Information button for 3 seconds until all buttons are lit, and then press the information and Resume* buttons at the same time.

With Wi-Fi Direct, you can connect your computer, smartphone, or tablet directly to a printer wirelessly -- without connecting to an
existing Wi-Fi network.
The Wi-Fi Direct name is "DIRECT-AE-HP Smart Tank 5100"
The Wi-Fi Direct password is "743338277356"
For instructions on how to connect to the printer using Wi=Fi Direct, press and hold the Information* button for 3 seconds until all
buttons are lit, and the press the Information and Cancel buttons at the same time

Additional Assistance
   * To print the Printer Status Report, press and hold the Information* button for 3 seconds until all buttons are lit, and then press the Resume* button.

<more stuff which I didn't bother to type>

FWIW, what follows is a Printer Status Report (with comments in angle brackets <>)
(I.e., press and hold the Information* button for 3 seconds until all buttons are lit, and then press the Resume* button):

Printer Status Report

Product Information
1. Model Name: HP Smart Tank 5100 series
2 Model Number: 5101
3. Serial Number: CN32L3C0W7
4. Product Number: 1F3Y0A
5. Service ID: 00000
6. Printer Zone (PX): 0
7. Firmware Version: LWP1CN2302GR
8. FW Patch Version: 0
9. Country/Region: 15 / 1
10. Duplexer: Not Installed
11. Quiet Mode: Off
12: Printer PIN: 86151370
13: DIIR: UD

Printer Usage Information
14. Total Pages Printed: 7
15. Borderless Pages Printed: 0

Connectivity Settings
16. Network Status: Ready
17. Active Connection Type: Wi-Fi
18. URL: https://192.168.223.1 < BTW: connection times out if I point my browser at this URL>
19. Hostname: HP8EC9AE
20. Admin Password: Not Set

Scan Settings
21. Front Panel Destinations List: 0

Scan Usage Information
22. Pages Scanned: 1

Ink Delivery System Information
23. Tank Black Cyan Magenta Yellow
24. Estimated Tank Level: < graphic indicating full tanks>
25. Print Head Information: Color Black
26. Ink Zone: 1 1
27. Status: 0 0
28. HP: 1 1

Additional Assistance
< info about contacting hp.com>

Wi-Fi Network Test
< some stuff about wi-fi which I assume I can ignore>

< color test pattern>

I have a print queue named "test" created using the command:
lpadmin -p test -E -v file:/tmp/printout -P /etc/cups/ppd/Smart_Tank_5100.ppd
(as per https://wiki.ubuntu.com/DebuggingPrintingProblems)
I then did:
cancel -a
And then submitted a tiny file (an ASCII file with one line of text) as follows:

scott@all-in-one:~/sandbox$ date; lp -d test junk.txt
Sun Jun 25 11:23:10 AM PDT 2023
request id is test-62 (1 file(s))
scott@all-in-one:~/sandbox$ ls -alt /tmp/printout
-rw------- 1 root root 0 Jun 25 11:23 /tmp/printout

Notes:
[1] The output is a file of size zero
[2] The time stamp is: 11:23
[3] /var/log/cups/error_log has some interesting messages. Things like:
D [25/Jun/2023:11:23:10 -0700] [Job 62] PID 343158 (/usr/lib/cups/filter/hpcups) stopped with status 127 (File too large)
D [25/Jun/2023:11:23:10 -0700] [Job 62] test: error while loading shared libraries: libImageProcessor.so: cannot open shared object file: No such file or directory
FWIW, I have the file: /usr/lib64/libImageProcessor.so, so somebody is looking in the wrong place (??)
But, I have no idea why I get the message "File too large"

What follows is the relevant portion of /var/log/cups/error_log (recall the relevant timestamp of 11:23):

D [25/Jun/2023:11:21:50 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [25/Jun/2023:11:21:50 -0700] [Client 20] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:21:50 -0700] [Client 20] Closing connection.
D [25/Jun/2023:11:21:50 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [25/Jun/2023:11:21:51 -0700] Expiring subscriptions...
D [25/Jun/2023:11:21:51 -0700] [Job 61] Unloading...
D [25/Jun/2023:11:22:05 -0700] [Client 19] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:22:05 -0700] [Client 19] Closing connection.
D [25/Jun/2023:11:22:05 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [25/Jun/2023:11:22:05 -0700] Expiring subscriptions...
I [25/Jun/2023:11:22:06 -0700] Expiring subscriptions...
I [25/Jun/2023:11:22:20 -0700] Saving job.cache...
I [25/Jun/2023:11:22:20 -0700] Saving subscriptions.conf...
D [25/Jun/2023:11:22:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [25/Jun/2023:11:22:20 -0700] Expiring subscriptions...
D [25/Jun/2023:11:22:20 -0700] Report: clients=3
D [25/Jun/2023:11:22:20 -0700] Report: jobs=61
D [25/Jun/2023:11:22:20 -0700] Report: jobs-active=2
D [25/Jun/2023:11:22:20 -0700] Report: printers=2
D [25/Jun/2023:11:22:20 -0700] Report: stringpool-string-count=6234
D [25/Jun/2023:11:22:20 -0700] Report: stringpool-alloc-bytes=12248
D [25/Jun/2023:11:22:20 -0700] Report: stringpool-total-bytes=123720
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [25/Jun/2023:11:23:00 -0700] [Client 22] Server address is "/run/cups/cups.sock".
D [25/Jun/2023:11:23:00 -0700] [Client 22] Accepted from localhost (Domain)
D [25/Jun/2023:11:23:00 -0700] [Client 22] Waiting for request.
I [25/Jun/2023:11:23:00 -0700] Expiring subscriptions...
D [25/Jun/2023:11:23:00 -0700] [Job 60] Unloading...
D [25/Jun/2023:11:23:00 -0700] [Client 22] POST /admin/ HTTP/1.1
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [25/Jun/2023:11:23:00 -0700] [Client 22] Read: status=200, state=6
D [25/Jun/2023:11:23:00 -0700] [Client 22] No authentication data provided.
D [25/Jun/2023:11:23:00 -0700] [Client 22] 2.0 Cancel-Jobs 1
D [25/Jun/2023:11:23:00 -0700] Cancel-Jobs ipp://localhost/printers/
D [25/Jun/2023:11:23:00 -0700] cupsdIsAuthorized: username=""
D [25/Jun/2023:11:23:00 -0700] [Client 22] Returning HTTP Unauthorized for Cancel-Jobs (ipp://localhost/printers/) from localhost
D [25/Jun/2023:11:23:00 -0700] [Client 22] cupsdSendHeader: code=401, type="text/html", auth_type=0
D [25/Jun/2023:11:23:00 -0700] [Client 22] WWW-Authenticate: Basic realm=\"CUPS\", PeerCred, Local trc=\"y\"
D [25/Jun/2023:11:23:00 -0700] [Client 22] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:23:00 -0700] [Client 22] Closing connection.
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [25/Jun/2023:11:23:00 -0700] [Client 23] Server address is "/run/cups/cups.sock".
D [25/Jun/2023:11:23:00 -0700] [Client 23] Accepted from localhost (Domain)
D [25/Jun/2023:11:23:00 -0700] [Client 23] Waiting for request.
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [25/Jun/2023:11:23:00 -0700] [Client 24] Server address is "/run/cups/cups.sock".
D [25/Jun/2023:11:23:00 -0700] [Client 24] Accepted from localhost (Domain)
D [25/Jun/2023:11:23:00 -0700] [Client 24] Waiting for request.
D [25/Jun/2023:11:23:00 -0700] [Client 23] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:23:00 -0700] [Client 23] Closing connection.
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [25/Jun/2023:11:23:00 -0700] [Client 25] Server address is "/run/cups/cups.sock".
D [25/Jun/2023:11:23:00 -0700] [Client 25] Accepted from localhost (Domain)
D [25/Jun/2023:11:23:00 -0700] [Client 25] Waiting for request.
D [25/Jun/2023:11:23:00 -0700] [Client 24] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:23:00 -0700] [Client 24] Closing connection.
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [25/Jun/2023:11:23:00 -0700] [Client 25] POST /admin/ HTTP/1.1
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [25/Jun/2023:11:23:00 -0700] [Client 25] Read: status=200, state=6
D [25/Jun/2023:11:23:00 -0700] [Client 25] Authorized as scott using PeerCred.
D [25/Jun/2023:11:23:00 -0700] [Client 25] 2.0 Cancel-Jobs 1
D [25/Jun/2023:11:23:00 -0700] Cancel-Jobs ipp://localhost/printers/
D [25/Jun/2023:11:23:00 -0700] cupsdIsAuthorized: username="scott"
D [25/Jun/2023:11:23:00 -0700] [Client 25] AppArmor profile is 'unconfined'.
D [25/Jun/2023:11:23:00 -0700] [Client 25] Not a snap - allowed.
D [25/Jun/2023:11:23:00 -0700] [Job 60] Loading attributes...
D [25/Jun/2023:11:23:00 -0700] [Job 60] time-at-completed=1687717380
D [25/Jun/2023:11:23:00 -0700] cupsdMarkDirty(----S)
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
I [25/Jun/2023:11:23:00 -0700] [Job 60] Job canceled by user.
I [25/Jun/2023:11:23:00 -0700] Expiring subscriptions...
D [25/Jun/2023:11:23:00 -0700] cupsdMarkDirty(---J-)
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] [Job 61] Loading attributes...
D [25/Jun/2023:11:23:00 -0700] [Job 61] time-at-completed=1687717380
D [25/Jun/2023:11:23:00 -0700] cupsdMarkDirty(----S)
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [25/Jun/2023:11:23:00 -0700] [Job 61] Job canceled by user.
I [25/Jun/2023:11:23:00 -0700] Expiring subscriptions...
D [25/Jun/2023:11:23:00 -0700] cupsdMarkDirty(---J-)
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [25/Jun/2023:11:23:00 -0700] All jobs were canceled by "scott".
D [25/Jun/2023:11:23:00 -0700] [Client 25] Returning IPP successful-ok for Cancel-Jobs (ipp://localhost/printers/) from localhost.
D [25/Jun/2023:11:23:00 -0700] [Client 25] Content-Length: 75
D [25/Jun/2023:11:23:00 -0700] [Client 25] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:00 -0700] [Client 25] con->http=0x55a8ecd83770
D [25/Jun/2023:11:23:00 -0700] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55a8ecd59a00(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:00 -0700] [Client 25] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:00 -0700] [Client 25] bytes=0, http_state=0, data_remaining=75
D [25/Jun/2023:11:23:00 -0700] [Client 25] Flushing write buffer.
D [25/Jun/2023:11:23:00 -0700] [Client 25] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:00 -0700] [Client 25] Waiting for request.
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:00 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:00 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:00 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:00 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:00 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:00 -0700] [Notifier] JobCompleted
D [25/Jun/2023:11:23:00 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:00 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:00 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:00 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:00 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:00 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:00 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:00 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:00 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:00 -0700] [Notifier] JobCompleted
D [25/Jun/2023:11:23:00 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:00 -0700] [Client 5] POST / HTTP/1.1
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 5] Read: status=200, state=6
D [25/Jun/2023:11:23:00 -0700] [Client 5] No authentication data provided.
D [25/Jun/2023:11:23:00 -0700] [Client 5] 2.0 Get-Notifications 169
D [25/Jun/2023:11:23:00 -0700] Get-Notifications /printers/
D [25/Jun/2023:11:23:00 -0700] cupsdIsAuthorized: requesting-user-name="scott"
D [25/Jun/2023:11:23:00 -0700] [Client 5] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost.
D [25/Jun/2023:11:23:00 -0700] [Client 5] Content-Length: 1159
D [25/Jun/2023:11:23:00 -0700] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:00 -0700] [Client 5] con->http=0x55a8ecd520c0
D [25/Jun/2023:11:23:00 -0700] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1159, response=0x55a8ecd27970(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:00 -0700] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:00 -0700] [Client 5] bytes=0, http_state=0, data_remaining=1159
D [25/Jun/2023:11:23:00 -0700] [Client 5] Flushing write buffer.
D [25/Jun/2023:11:23:00 -0700] [Client 5] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:00 -0700] [Client 5] Waiting for request.
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 26] Server address is "/run/cups/cups.sock".
D [25/Jun/2023:11:23:00 -0700] [Client 26] Accepted from localhost (Domain)
D [25/Jun/2023:11:23:00 -0700] [Client 26] Waiting for request.
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 27] Server address is "/run/cups/cups.sock".
D [25/Jun/2023:11:23:00 -0700] [Client 27] Accepted from localhost (Domain)
D [25/Jun/2023:11:23:00 -0700] [Client 27] Waiting for request.
D [25/Jun/2023:11:23:00 -0700] [Client 26] POST / HTTP/1.1
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 26] Read: status=200, state=6
D [25/Jun/2023:11:23:00 -0700] [Client 26] No authentication data provided.
D [25/Jun/2023:11:23:00 -0700] [Client 26] 2.0 Get-Job-Attributes 170
D [25/Jun/2023:11:23:00 -0700] Get-Job-Attributes ipp://localhost/jobs/60
D [25/Jun/2023:11:23:00 -0700] [Client 26] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/60) from localhost.
D [25/Jun/2023:11:23:00 -0700] [Client 26] Content-Length: 111
D [25/Jun/2023:11:23:00 -0700] [Client 26] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 28] Server address is "/run/cups/cups.sock".
D [25/Jun/2023:11:23:00 -0700] [Client 28] Accepted from localhost (Domain)
D [25/Jun/2023:11:23:00 -0700] [Client 28] Waiting for request.
D [25/Jun/2023:11:23:00 -0700] [Client 26] con->http=0x55a8ecd91b60
D [25/Jun/2023:11:23:00 -0700] [Client 26] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=111, response=0x55a8ecd96480(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:00 -0700] [Client 26] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:00 -0700] [Client 26] bytes=0, http_state=0, data_remaining=111
D [25/Jun/2023:11:23:00 -0700] [Client 26] Flushing write buffer.
D [25/Jun/2023:11:23:00 -0700] [Client 26] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:00 -0700] [Client 26] Waiting for request.
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 27] POST / HTTP/1.1
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 27] Read: status=200, state=6
D [25/Jun/2023:11:23:00 -0700] [Client 27] No authentication data provided.
D [25/Jun/2023:11:23:00 -0700] [Client 27] 2.0 Get-Job-Attributes 252
D [25/Jun/2023:11:23:00 -0700] Get-Job-Attributes ipp://localhost/jobs/60
D [25/Jun/2023:11:23:00 -0700] [Client 27] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/60) from localhost.
D [25/Jun/2023:11:23:00 -0700] [Client 27] Content-Length: 160
D [25/Jun/2023:11:23:00 -0700] [Client 27] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:00 -0700] [Client 25] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:23:00 -0700] [Client 25] Closing connection.
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 26] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:23:00 -0700] [Client 26] Closing connection.
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 27] con->http=0x55a8ecdb1a80
D [25/Jun/2023:11:23:00 -0700] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=160, response=0x55a8ecd5cee0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:00 -0700] [Client 27] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:00 -0700] [Client 27] bytes=0, http_state=0, data_remaining=160
D [25/Jun/2023:11:23:00 -0700] [Client 27] Flushing write buffer.
D [25/Jun/2023:11:23:00 -0700] [Client 27] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:00 -0700] [Client 27] Waiting for request.
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 28] POST / HTTP/1.1
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 28] Read: status=200, state=6
D [25/Jun/2023:11:23:00 -0700] [Client 28] No authentication data provided.
D [25/Jun/2023:11:23:00 -0700] [Client 28] 2.0 Get-Job-Attributes 253
D [25/Jun/2023:11:23:00 -0700] Get-Job-Attributes ipp://localhost/jobs/61
D [25/Jun/2023:11:23:00 -0700] [Client 28] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/61) from localhost.
D [25/Jun/2023:11:23:00 -0700] [Client 28] Content-Length: 160
D [25/Jun/2023:11:23:00 -0700] [Client 28] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:00 -0700] [Client 28] con->http=0x55a8ecdb51e0
D [25/Jun/2023:11:23:00 -0700] [Client 28] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=160, response=0x55a8ecd96480(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:00 -0700] [Client 28] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:00 -0700] [Client 28] bytes=0, http_state=0, data_remaining=160
D [25/Jun/2023:11:23:00 -0700] [Client 28] Flushing write buffer.
D [25/Jun/2023:11:23:00 -0700] [Client 28] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:00 -0700] [Client 28] Waiting for request.
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 28] POST / HTTP/1.1
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 28] Read: status=200, state=6
D [25/Jun/2023:11:23:00 -0700] [Client 28] No authentication data provided.
D [25/Jun/2023:11:23:00 -0700] [Client 28] 2.0 Get-Jobs 255
D [25/Jun/2023:11:23:00 -0700] Get-Jobs ipp://localhost/printers/test
D [25/Jun/2023:11:23:00 -0700] [Client 28] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/test) from localhost.
D [25/Jun/2023:11:23:00 -0700] [Client 28] Content-Length: 75
D [25/Jun/2023:11:23:00 -0700] [Client 28] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:00 -0700] [Client 27] POST / HTTP/1.1
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 27] Read: status=200, state=6
D [25/Jun/2023:11:23:00 -0700] [Client 27] No authentication data provided.
D [25/Jun/2023:11:23:00 -0700] [Client 27] 2.0 Get-Jobs 254
D [25/Jun/2023:11:23:00 -0700] Get-Jobs ipp://localhost/printers/test
D [25/Jun/2023:11:23:00 -0700] [Client 27] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/test) from localhost.
D [25/Jun/2023:11:23:00 -0700] [Client 27] Content-Length: 75
D [25/Jun/2023:11:23:00 -0700] [Client 27] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:00 -0700] [Client 28] con->http=0x55a8ecdb51e0
D [25/Jun/2023:11:23:00 -0700] [Client 28] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55a8ecd5cee0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:00 -0700] [Client 28] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:00 -0700] [Client 28] bytes=0, http_state=0, data_remaining=75
D [25/Jun/2023:11:23:00 -0700] [Client 28] Flushing write buffer.
D [25/Jun/2023:11:23:00 -0700] [Client 28] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:00 -0700] [Client 28] Waiting for request.
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 27] con->http=0x55a8ecdb1a80
D [25/Jun/2023:11:23:00 -0700] [Client 27] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55a8ecd97b70(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:00 -0700] [Client 27] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:00 -0700] [Client 27] bytes=0, http_state=0, data_remaining=75
D [25/Jun/2023:11:23:00 -0700] [Client 27] Flushing write buffer.
D [25/Jun/2023:11:23:00 -0700] [Client 27] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:00 -0700] [Client 27] Waiting for request.
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 29] Server address is "/run/cups/cups.sock".
D [25/Jun/2023:11:23:00 -0700] [Client 29] Accepted from localhost (Domain)
D [25/Jun/2023:11:23:00 -0700] [Client 29] Waiting for request.
D [25/Jun/2023:11:23:00 -0700] [Client 29] POST / HTTP/1.1
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 29] Read: status=200, state=6
D [25/Jun/2023:11:23:00 -0700] [Client 29] No authentication data provided.
D [25/Jun/2023:11:23:00 -0700] [Client 29] 2.0 Get-Job-Attributes 171
D [25/Jun/2023:11:23:00 -0700] Get-Job-Attributes ipp://localhost/jobs/61
D [25/Jun/2023:11:23:00 -0700] [Client 29] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/61) from localhost.
D [25/Jun/2023:11:23:00 -0700] [Client 29] Content-Length: 111
D [25/Jun/2023:11:23:00 -0700] [Client 29] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:00 -0700] [Client 29] con->http=0x55a8ecd909a0
D [25/Jun/2023:11:23:00 -0700] [Client 29] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=111, response=0x55a8ecd59a00(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:00 -0700] [Client 29] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:00 -0700] [Client 29] bytes=0, http_state=0, data_remaining=111
D [25/Jun/2023:11:23:00 -0700] [Client 29] Flushing write buffer.
D [25/Jun/2023:11:23:00 -0700] [Client 29] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:00 -0700] [Client 29] Waiting for request.
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 29] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:23:00 -0700] [Client 29] Closing connection.
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 5] POST / HTTP/1.1
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:00 -0700] [Client 5] Read: status=200, state=6
D [25/Jun/2023:11:23:00 -0700] [Client 5] No authentication data provided.
D [25/Jun/2023:11:23:00 -0700] [Client 5] 2.0 Get-Notifications 172
D [25/Jun/2023:11:23:00 -0700] Get-Notifications /printers/
D [25/Jun/2023:11:23:00 -0700] cupsdIsAuthorized: requesting-user-name="scott"
D [25/Jun/2023:11:23:00 -0700] [Client 5] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost.
D [25/Jun/2023:11:23:00 -0700] [Client 5] Content-Length: 127
D [25/Jun/2023:11:23:00 -0700] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:00 -0700] [Client 5] con->http=0x55a8ecd520c0
D [25/Jun/2023:11:23:00 -0700] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x55a8ecd964c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:00 -0700] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:00 -0700] [Client 5] bytes=0, http_state=0, data_remaining=127
D [25/Jun/2023:11:23:00 -0700] [Client 5] Flushing write buffer.
D [25/Jun/2023:11:23:00 -0700] [Client 5] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:00 -0700] [Client 5] Waiting for request.
D [25/Jun/2023:11:23:00 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:01 -0700] [Client 27] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:23:01 -0700] [Client 27] Closing connection.
D [25/Jun/2023:11:23:01 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [25/Jun/2023:11:23:01 -0700] Expiring subscriptions...
I [25/Jun/2023:11:23:02 -0700] Expiring subscriptions...
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 30] Server address is "/run/cups/cups.sock".
D [25/Jun/2023:11:23:10 -0700] [Client 30] Accepted from localhost (Domain)
D [25/Jun/2023:11:23:10 -0700] [Client 30] Waiting for request.
I [25/Jun/2023:11:23:10 -0700] Expiring subscriptions...
D [25/Jun/2023:11:23:10 -0700] [Client 30] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 30] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 30] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 30] 2.0 Get-Printer-Attributes 1
D [25/Jun/2023:11:23:10 -0700] Get-Printer-Attributes ipp://localhost:631/printers/test
D [25/Jun/2023:11:23:10 -0700] [Client 30] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/test) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 30] Content-Length: 1196
D [25/Jun/2023:11:23:10 -0700] [Client 30] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 30] con->http=0x55a8ecdb1a80
D [25/Jun/2023:11:23:10 -0700] [Client 30] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1196, response=0x55a8ecd98be0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 30] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 30] bytes=0, http_state=0, data_remaining=1196
D [25/Jun/2023:11:23:10 -0700] [Client 30] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 30] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 30] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 30] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 30] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 30] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 30] 2.0 Get-Printer-Attributes 2
D [25/Jun/2023:11:23:10 -0700] Get-Printer-Attributes ipp://localhost:631/printers/test
D [25/Jun/2023:11:23:10 -0700] [Client 30] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/test) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 30] Content-Length: 1196
D [25/Jun/2023:11:23:10 -0700] [Client 30] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 30] con->http=0x55a8ecdb1a80
D [25/Jun/2023:11:23:10 -0700] [Client 30] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1196, response=0x55a8ecd99120(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 30] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 30] bytes=0, http_state=0, data_remaining=1196
D [25/Jun/2023:11:23:10 -0700] [Client 30] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 30] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 30] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 30] POST /printers/test HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 30] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 30] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 30] 2.0 Get-Printer-Attributes 3
D [25/Jun/2023:11:23:10 -0700] Get-Printer-Attributes ipp://localhost/printers/test
D [25/Jun/2023:11:23:10 -0700] [Client 30] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/test) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 30] Content-Length: 17332
D [25/Jun/2023:11:23:10 -0700] [Client 30] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 30] con->http=0x55a8ecdb1a80
D [25/Jun/2023:11:23:10 -0700] [Client 30] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=17332, response=0x55a8ecd98be0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 30] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 30] bytes=0, http_state=0, data_remaining=17332
D [25/Jun/2023:11:23:10 -0700] [Client 30] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 30] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 30] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 30] POST /printers/test HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 30] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 30] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 30] 2.0 Create-Job 4
D [25/Jun/2023:11:23:10 -0700] Create-Job ipp://localhost/printers/test
D [25/Jun/2023:11:23:10 -0700] cupsdMarkDirty(---J-)
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Job 62] Applying default options...
D [25/Jun/2023:11:23:10 -0700] add_job: requesting-user-name="scott"
I [25/Jun/2023:11:23:10 -0700] [Job 62] Adding start banner page "none".
D [25/Jun/2023:11:23:10 -0700] cupsdMarkDirty(----S)
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [25/Jun/2023:11:23:10 -0700] [Job 62] Queued on "test" by "scott".
D [25/Jun/2023:11:23:10 -0700] [Client 30] Returning IPP successful-ok for Create-Job (ipp://localhost/printers/test) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 30] Content-Length: 200
D [25/Jun/2023:11:23:10 -0700] [Client 30] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 30] con->http=0x55a8ecdb1a80
D [25/Jun/2023:11:23:10 -0700] [Client 30] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=200, response=0x55a8ecd5cee0(IPP_STATE_IDLE), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 30] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 30] bytes=0, http_state=0, data_remaining=200
D [25/Jun/2023:11:23:10 -0700] [Client 30] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 30] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 30] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] JobCreated
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Client 30] POST /printers/test HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 30] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 30] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 30] 2.0 Send-Document 5
D [25/Jun/2023:11:23:10 -0700] [Client 5] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 5] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 5] 2.0 Get-Notifications 173
D [25/Jun/2023:11:23:10 -0700] Get-Notifications /printers/
D [25/Jun/2023:11:23:10 -0700] cupsdIsAuthorized: requesting-user-name="scott"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 5] Content-Length: 636
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] con->http=0x55a8ecd520c0
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=636, response=0x55a8ecd84ce0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] bytes=0, http_state=0, data_remaining=636
D [25/Jun/2023:11:23:10 -0700] [Client 5] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 5] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 5] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 31] Server address is "/run/cups/cups.sock".
D [25/Jun/2023:11:23:10 -0700] [Client 31] Accepted from localhost (Domain)
D [25/Jun/2023:11:23:10 -0700] [Client 31] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] [Client 30] Read: status=100, state=6
D [25/Jun/2023:11:23:10 -0700] Send-Document ipp://localhost:631/printers/test
D [25/Jun/2023:11:23:10 -0700] cupsdIsAuthorized: requesting-user-name="scott"
D [25/Jun/2023:11:23:10 -0700] [Job 62] Auto-typing file...
D [25/Jun/2023:11:23:10 -0700] [Job 62] Request file type is text/plain.
D [25/Jun/2023:11:23:10 -0700] cupsdMarkDirty(---J-)
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [25/Jun/2023:11:23:10 -0700] [Job 62] File of type text/plain queued by "scott".
I [25/Jun/2023:11:23:10 -0700] [Job 62] Adding end banner page "none".
D [25/Jun/2023:11:23:10 -0700] cupsdMarkDirty(---J-)
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Job 62] time-at-processing=1687717390
D [25/Jun/2023:11:23:10 -0700] cupsdMarkDirty(---J-)
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] cupsdMarkDirty(----S)
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Job 62] 4 filters for job:
D [25/Jun/2023:11:23:10 -0700] [Job 62] texttopdf (text/plain to application/pdf, cost 32)
D [25/Jun/2023:11:23:10 -0700] [Job 62] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [25/Jun/2023:11:23:10 -0700] [Job 62] gstoraster (application/vnd.cups-pdf to application/vnd.cups-raster, cost 99)
D [25/Jun/2023:11:23:10 -0700] [Job 62] hpcups (application/vnd.cups-raster to printer/test, cost 0)
D [25/Jun/2023:11:23:10 -0700] [Job 62] job-sheets=none,none
D [25/Jun/2023:11:23:10 -0700] [Job 62] print-color-mode=color, print-quality=normal
D [25/Jun/2023:11:23:10 -0700] [Job 62] Adding preset option ColorModel=RGB
D [25/Jun/2023:11:23:10 -0700] [Job 62] Adding preset option OutputMode=Normal
D [25/Jun/2023:11:23:10 -0700] [Job 62] argv[0]="test"
D [25/Jun/2023:11:23:10 -0700] [Job 62] argv[1]="62"
D [25/Jun/2023:11:23:10 -0700] [Job 62] argv[2]="scott"
D [25/Jun/2023:11:23:10 -0700] [Job 62] argv[3]="junk.txt"
D [25/Jun/2023:11:23:10 -0700] [Job 62] argv[4]="1"
D [25/Jun/2023:11:23:10 -0700] [Job 62] argv[5]="finishings=3 number-up=1 print-color-mode=color job-uuid=urn:uuid:f7447656-20da-3a59-7b31-97ea37ff60b8 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1687717390 time-at-processing=1687717390 document-name-supplied=junk.txt ColorModel=RGB OutputMode=Normal"
D [25/Jun/2023:11:23:10 -0700] [Job 62] argv[6]="/var/spool/cups/d00062-001"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[3]="CUPS_REQUESTROOT=/var/spool/cups"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[4]="CUPS_SERVERBIN=/usr/lib/cups"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[5]="CUPS_SERVERROOT=/etc/cups"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[6]="CUPS_STATEDIR=/run/cups"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[7]="HOME=/var/spool/cups/tmp"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[9]="SERVER_ADMIN=root@all-in-one"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[10]="SOFTWARE=CUPS/2.4.1"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[12]="USER=root"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[13]="CUPS_MAX_MESSAGE=2047"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[14]="CUPS_SERVER=/run/cups/cups.sock"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[16]="IPP_PORT=631"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[17]="CHARSET=utf-8"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[18]="LANG=en_US.UTF-8"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[19]="PPD=/etc/cups/ppd/test.ppd"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[20]="CONTENT_TYPE=text/plain"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[21]="DEVICE_URI=file:/tmp/printout"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[22]="PRINTER_INFO=test"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[23]="PRINTER_LOCATION="
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[24]="PRINTER=test"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[25]="PRINTER_STATE_REASONS=none"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[26]="CUPS_FILETYPE=document"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[27]="FINAL_CONTENT_TYPE=application/vnd.cups-raster"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[28]="AUTH_I****"
I [25/Jun/2023:11:23:10 -0700] [Job 62] Started filter /usr/lib/cups/filter/texttopdf (PID 343155)
I [25/Jun/2023:11:23:10 -0700] [Job 62] Started filter /usr/lib/cups/filter/pdftopdf (PID 343156)
I [25/Jun/2023:11:23:10 -0700] [Job 62] Started filter /usr/lib/cups/filter/gstoraster (PID 343157)
I [25/Jun/2023:11:23:10 -0700] [Job 62] Started filter /usr/lib/cups/filter/hpcups (PID 343158)
D [25/Jun/2023:11:23:10 -0700] cupsdMarkDirty(----S)
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 30] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/test) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 30] Content-Length: 170
D [25/Jun/2023:11:23:10 -0700] [Client 30] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 31] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 31] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 31] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 31] 2.0 Get-Job-Attributes 174
D [25/Jun/2023:11:23:10 -0700] Get-Job-Attributes ipp://localhost/jobs/62
D [25/Jun/2023:11:23:10 -0700] [Client 31] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/62) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 31] Content-Length: 111
D [25/Jun/2023:11:23:10 -0700] [Client 31] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 30] con->http=0x55a8ecdb1a80
D [25/Jun/2023:11:23:10 -0700] [Client 30] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=170, response=0x55a8ecd2bfe0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 30] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 30] bytes=0, http_state=0, data_remaining=170
D [25/Jun/2023:11:23:10 -0700] [Client 30] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 30] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 30] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 31] con->http=0x55a8ecd91b60
D [25/Jun/2023:11:23:10 -0700] [Client 31] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=111, response=0x55a8ecdbbb30(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 31] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 31] bytes=0, http_state=0, data_remaining=111
D [25/Jun/2023:11:23:10 -0700] [Client 31] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 31] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 31] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] PrinterStateChanged
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Client 28] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 28] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 28] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 28] 2.0 Get-Job-Attributes 256
D [25/Jun/2023:11:23:10 -0700] Get-Job-Attributes ipp://localhost/jobs/62
D [25/Jun/2023:11:23:10 -0700] [Client 28] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/62) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 28] Content-Length: 160
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 32] Server address is "/run/cups/cups.sock".
D [25/Jun/2023:11:23:10 -0700] [Client 32] Accepted from localhost (Domain)
D [25/Jun/2023:11:23:10 -0700] [Client 32] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] [Job 62] PID 343158 (/usr/lib/cups/filter/hpcups) stopped with status 127 (File too large)
D [25/Jun/2023:11:23:10 -0700] [Client 31] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:23:10 -0700] [Client 31] Closing connection.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] JobState
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Client 28] con->http=0x55a8ecdb51e0
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=160, response=0x55a8ecdbb970(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 28] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 28] bytes=0, http_state=0, data_remaining=160
D [25/Jun/2023:11:23:10 -0700] [Client 28] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 28] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 28] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 5] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 5] 2.0 Get-Notifications 175
D [25/Jun/2023:11:23:10 -0700] Get-Notifications /printers/
D [25/Jun/2023:11:23:10 -0700] cupsdIsAuthorized: requesting-user-name="scott"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 5] Content-Length: 1043
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Job 62] test: error while loading shared libraries: libImageProcessor.so: cannot open shared object file: No such file or directory
D [25/Jun/2023:11:23:10 -0700] [Client 32] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 32] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 32] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 32] 2.0 CUPS-Get-Printers 257
D [25/Jun/2023:11:23:10 -0700] CUPS-Get-Printers
D [25/Jun/2023:11:23:10 -0700] [Client 32] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 32] Content-Length: 2365
D [25/Jun/2023:11:23:10 -0700] [Client 32] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] con->http=0x55a8ecd520c0
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1043, response=0x55a8ecdbbb30(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] bytes=0, http_state=0, data_remaining=1043
D [25/Jun/2023:11:23:10 -0700] [Client 5] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 5] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 5] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 32] con->http=0x55a8ecdbcee0
D [25/Jun/2023:11:23:10 -0700] [Client 32] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2365, response=0x55a8ecd91090(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 32] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 32] bytes=0, http_state=0, data_remaining=2365
D [25/Jun/2023:11:23:10 -0700] [Client 32] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 32] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 32] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 33] Server address is "/run/cups/cups.sock".
D [25/Jun/2023:11:23:10 -0700] [Client 33] Accepted from localhost (Domain)
D [25/Jun/2023:11:23:10 -0700] [Client 33] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] [Client 33] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 33] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 33] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 33] 2.0 Get-Job-Attributes 176
D [25/Jun/2023:11:23:10 -0700] Get-Job-Attributes ipp://localhost/jobs/62
D [25/Jun/2023:11:23:10 -0700] [Client 33] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/62) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 33] Content-Length: 111
D [25/Jun/2023:11:23:10 -0700] [Client 33] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 33] con->http=0x55a8ecd91b60
D [25/Jun/2023:11:23:10 -0700] [Client 33] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=111, response=0x55a8ecd58950(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 33] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 33] bytes=0, http_state=0, data_remaining=111
D [25/Jun/2023:11:23:10 -0700] [Client 33] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 33] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 33] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 33] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:23:10 -0700] [Client 33] Closing connection.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 30] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:23:10 -0700] [Client 30] Closing connection.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Job 62] pdftopdf: Last filter determined by the PPD: hpcups; FINAL_CONTENT_TYPE: application/vnd.cups-raster => pdftopdf will not log pages in page_log.
D [25/Jun/2023:11:23:10 -0700] [Client 28] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 28] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 28] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 28] 2.0 Get-Jobs 258
D [25/Jun/2023:11:23:10 -0700] Get-Jobs ipp://localhost/printers/test
D [25/Jun/2023:11:23:10 -0700] [Client 28] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/test) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 28] Content-Length: 360
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 28] con->http=0x55a8ecdb51e0
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=360, response=0x55a8ecd2bfe0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 28] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 28] bytes=0, http_state=0, data_remaining=360
D [25/Jun/2023:11:23:10 -0700] [Client 28] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 28] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 28] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Job 62] OUTFORMAT=\"<none>\", so output format will be CUPS/PWG Raster
D [25/Jun/2023:11:23:10 -0700] [Job 62] Page = 612x792; 8,8 to 604,784
D [25/Jun/2023:11:23:10 -0700] [Client 5] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 5] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 5] 2.0 Get-Notifications 177
D [25/Jun/2023:11:23:10 -0700] Get-Notifications /printers/
D [25/Jun/2023:11:23:10 -0700] cupsdIsAuthorized: requesting-user-name="scott"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 5] Content-Length: 127
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] con->http=0x55a8ecd520c0
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x55a8ecdbbc60(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] bytes=0, http_state=0, data_remaining=127
D [25/Jun/2023:11:23:10 -0700] [Client 5] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 5] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 5] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Job 62] PID 343155 (/usr/lib/cups/filter/texttopdf) exited with no errors.
D [25/Jun/2023:11:23:10 -0700] [Job 62] PDF interactive form and annotation flattening done via QPDF
D [25/Jun/2023:11:23:10 -0700] [Job 62] pdftopdf: \"print-scaling\" IPP attribute: auto
D [25/Jun/2023:11:23:10 -0700] [Job 62] pdftopdf: Print scaling mode: Do not scale, center, crop if needed
D [25/Jun/2023:11:23:10 -0700] [Job 62] After Cropping: 612.000000 792.000000 612.000000 792.000000
D [25/Jun/2023:11:23:10 -0700] [Job 62] PID 343156 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [25/Jun/2023:11:23:10 -0700] [Job 62] Color Manager: Calibration Mode/Off
D [25/Jun/2023:11:23:10 -0700] [Job 62] Calling FindDeviceById(cups-test)
D [25/Jun/2023:11:23:10 -0700] [Client 28] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 28] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 28] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 28] 2.0 Get-Jobs 259
D [25/Jun/2023:11:23:10 -0700] Get-Jobs ipp://localhost/printers/Smart_Tank_5100
D [25/Jun/2023:11:23:10 -0700] [Client 28] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/Smart_Tank_5100) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 28] Content-Length: 75
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 28] con->http=0x55a8ecdb51e0
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55a8ecd2bfe0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 28] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 28] bytes=0, http_state=0, data_remaining=75
D [25/Jun/2023:11:23:10 -0700] [Client 28] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 28] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 28] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Job 62] Found device /org/freedesktop/ColorManager/devices/cups_test
D [25/Jun/2023:11:23:10 -0700] [Job 62] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors)
D [25/Jun/2023:11:23:10 -0700] [Client 32] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 32] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 32] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 32] 2.0 Get-Jobs 260
D [25/Jun/2023:11:23:10 -0700] Get-Jobs ipp://localhost/printers/test
D [25/Jun/2023:11:23:10 -0700] [Client 32] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/test) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 32] Content-Length: 360
D [25/Jun/2023:11:23:10 -0700] [Client 32] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 32] con->http=0x55a8ecdbcee0
D [25/Jun/2023:11:23:10 -0700] [Client 32] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=360, response=0x55a8ecdbbc60(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 32] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 32] bytes=0, http_state=0, data_remaining=360
D [25/Jun/2023:11:23:10 -0700] [Client 32] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 32] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 32] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Job 62] Calling FindDeviceById(cups-test)
D [25/Jun/2023:11:23:10 -0700] [Job 62] Found device /org/freedesktop/ColorManager/devices/cups_test
D [25/Jun/2023:11:23:10 -0700] [Job 62] Calling GetProfileForQualifiers(RGB.Plain....)
D [25/Jun/2023:11:23:10 -0700] [Job 62] Found profile /org/freedesktop/ColorManager/profiles/test_RGB__
D [25/Jun/2023:11:23:10 -0700] [Job 62] Calling org.freedesktop.ColorManager.Profile.Get(Filename)
D [25/Jun/2023:11:23:10 -0700] [Job 62] Failed to get profile filename for cups-test
D [25/Jun/2023:11:23:10 -0700] [Job 62] Color Manager: ICC Profile:
D [25/Jun/2023:11:23:10 -0700] [Job 62] Ghostscript using Any-Part-of-Pixel method to fill paths.
D [25/Jun/2023:11:23:10 -0700] [Job 62] Ghostscript command line: gs -dQUIET -dSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -dNOMEDIAATTRS -dShowAcroForm -sstdout=%stderr -sOutputFile=%stdout -sDEVICE=cups -sMediaType=Plain -sOutputType=0 -r600x600 -dMediaPosition=1 -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -dcupsBitsPerColor=8 -dcupsColorOrder=0 -dcupsColorSpace=17 -dcupsInteger0=2 -dcupsInteger6=1084 -scupsPageSizeName=Letter -I/usr/share/cups/fonts -c \'<</.HWMargins[8.400000 8.400000 8.400024 8.400024] /Margins[0 0]>>setpagedevice\' -f -_
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[0]=\"CUPS_CACHEDIR=/var/cache/cups\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[1]=\"CUPS_DATADIR=/usr/share/cups\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[2]=\"CUPS_DOCROOT=/usr/share/cups/doc-root\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[3]=\"CUPS_REQUESTROOT=/var/spool/cups\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[4]=\"CUPS_SERVERBIN=/usr/lib/cups\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[5]=\"CUPS_SERVERROOT=/etc/cups\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[6]=\"CUPS_STATEDIR=/run/cups\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[7]=\"HOME=/var/spool/cups/tmp\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[8]=\"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[9]=\"SERVER_ADMIN=root@all-in-one\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[10]=\"SOFTWARE=CUPS/2.4.1\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[11]=\"USER=root\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[12]=\"CUPS_MAX_MESSAGE=2047\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[13]=\"CUPS_SERVER=/run/cups/cups.sock\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[14]=\"CUPS_ENCRYPTION=IfRequested\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[15]=\"IPP_PORT=631\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[16]=\"CHARSET=utf-8\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[17]=\"LANG=en_US.UTF-8\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[18]=\"PPD=/etc/cups/ppd/test.ppd\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[19]=\"CONTENT_TYPE=text/plain\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[20]=\"DEVICE_URI=file:/tmp/printout\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[21]=\"PRINTER_INFO=test\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[22]=\"PRINTER_LOCATION=\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[23]=\"PRINTER=test\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[24]=\"PRINTER_STATE_REASONS=none\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[25]=\"CUPS_FILETYPE=document\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[26]=\"FINAL_CONTENT_TYPE=application/vnd.cups-raster\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] envp[27]=\"AUTH_INFO_REQUIRED=none\"
D [25/Jun/2023:11:23:10 -0700] [Job 62] Start rendering...
D [25/Jun/2023:11:23:10 -0700] [Job 62] Processing page 1...
D [25/Jun/2023:11:23:10 -0700] cupsdMarkDirty(----S)
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] cupsdMarkDirty(----S)
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] JobProgress
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] PrinterStateChanged
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Client 5] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 5] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 5] 2.0 Get-Notifications 178
D [25/Jun/2023:11:23:10 -0700] Get-Notifications /printers/
D [25/Jun/2023:11:23:10 -0700] cupsdIsAuthorized: requesting-user-name="scott"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 5] Content-Length: 522
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] con->http=0x55a8ecd520c0
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=522, response=0x55a8ecdb5140(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] bytes=0, http_state=0, data_remaining=522
D [25/Jun/2023:11:23:10 -0700] [Client 5] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 5] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 5] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 28] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 28] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 28] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 28] 2.0 CUPS-Get-Printers 261
D [25/Jun/2023:11:23:10 -0700] CUPS-Get-Printers
D [25/Jun/2023:11:23:10 -0700] [Client 28] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 28] Content-Length: 2365
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 5] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 5] 2.0 CUPS-Get-Printers 179
D [25/Jun/2023:11:23:10 -0700] CUPS-Get-Printers
D [25/Jun/2023:11:23:10 -0700] [Client 5] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 5] Content-Length: 2365
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 28] con->http=0x55a8ecdb51e0
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2365, response=0x55a8ecdb5190(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 28] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 28] bytes=0, http_state=0, data_remaining=2365
D [25/Jun/2023:11:23:10 -0700] [Client 28] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 28] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 28] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] con->http=0x55a8ecd520c0
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2365, response=0x55a8ecd91040(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] bytes=0, http_state=0, data_remaining=2365
D [25/Jun/2023:11:23:10 -0700] [Client 5] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 5] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 5] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
E [25/Jun/2023:11:23:10 -0700] [Job 62] Unable to open raster stream - : Broken pipe
D [25/Jun/2023:11:23:10 -0700] cupsdMarkDirty(----S)
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] cupsdMarkDirty(----S)
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Job 62] Error: /ioerror in --showpage--
D [25/Jun/2023:11:23:10 -0700] [Job 62] Operand stack:
D [25/Jun/2023:11:23:10 -0700] [Job 62] (/tmp/gs_dklWQq) --nostringval-- 1 true
D [25/Jun/2023:11:23:10 -0700] [Job 62] Execution stack:
D [25/Jun/2023:11:23:10 -0700] [Job 62] %interp_exit .runexec2 --nostringval-- showpage --nostringval-- 2 %stopped_push --nostringval-- showpage showpage false 1 %stopped_push 1990 1 3 %oparray_pop 1989 1 3 %oparray_pop 1977 1 3 %oparray_pop showpage 1978 3 3 %oparray_pop showpage showpage 2 1 1 showpage %for_pos_int_continue 1981 3 7 %oparray_pop showpage showpage 1840 2 9 %oparray_pop showpage showpage
D [25/Jun/2023:11:23:10 -0700] [Job 62] Dictionary stack:
D [25/Jun/2023:11:23:10 -0700] [Job 62] --dict:777/1123(ro)(G)-- --dict:1/20(G)-- --dict:81/200(L)-- --dict:81/200(L)-- --dict:134/256(ro)(G)-- --dict:325/325(ro)(G)-- --dict:35/64(L)-- --dict:6/9(L)-- --dict:6/20(L)--
D [25/Jun/2023:11:23:10 -0700] [Job 62] Current allocation mode is local
D [25/Jun/2023:11:23:10 -0700] [Job 62] Last OS error: Broken pipe
D [25/Jun/2023:11:23:10 -0700] [Job 62] GPL Ghostscript 9.55.0: Unrecoverable error, exit code 1
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] JobProgress
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] PrinterStateChanged
D [25/Jun/2023:11:23:10 -0700] [Client 32] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 32] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 32] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 32] 2.0 CUPS-Get-Printers 262
D [25/Jun/2023:11:23:10 -0700] CUPS-Get-Printers
D [25/Jun/2023:11:23:10 -0700] [Client 32] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 32] Content-Length: 2365
D [25/Jun/2023:11:23:10 -0700] [Client 32] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 32] con->http=0x55a8ecdbcee0
D [25/Jun/2023:11:23:10 -0700] [Client 32] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2365, response=0x55a8ecdc0ba0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 32] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 32] bytes=0, http_state=0, data_remaining=2365
D [25/Jun/2023:11:23:10 -0700] [Client 32] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 32] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 32] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 5] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 5] 2.0 Get-Notifications 180
D [25/Jun/2023:11:23:10 -0700] Get-Notifications /printers/
D [25/Jun/2023:11:23:10 -0700] cupsdIsAuthorized: requesting-user-name="scott"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 5] Content-Length: 522
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] con->http=0x55a8ecd520c0
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=522, response=0x55a8ecdc08c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] bytes=0, http_state=0, data_remaining=522
D [25/Jun/2023:11:23:10 -0700] [Client 5] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 5] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 5] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 28] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 28] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 28] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 28] 2.0 Get-Jobs 263
D [25/Jun/2023:11:23:10 -0700] Get-Jobs ipp://localhost/printers/Smart_Tank_5100
D [25/Jun/2023:11:23:10 -0700] [Client 28] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/Smart_Tank_5100) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 28] Content-Length: 75
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 28] con->http=0x55a8ecdb51e0
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55a8ecdc0ba0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 28] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 28] bytes=0, http_state=0, data_remaining=75
D [25/Jun/2023:11:23:10 -0700] [Client 28] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 28] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 28] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 5] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 5] 2.0 CUPS-Get-Printers 181
D [25/Jun/2023:11:23:10 -0700] CUPS-Get-Printers
D [25/Jun/2023:11:23:10 -0700] [Client 5] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 5] Content-Length: 2365
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] con->http=0x55a8ecd520c0
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2365, response=0x55a8ecdc08c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] bytes=0, http_state=0, data_remaining=2365
D [25/Jun/2023:11:23:10 -0700] [Client 5] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 5] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 5] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 28] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 28] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 28] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 28] 2.0 Get-Jobs 264
D [25/Jun/2023:11:23:10 -0700] Get-Jobs ipp://localhost/printers/test
D [25/Jun/2023:11:23:10 -0700] [Client 28] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/test) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 28] Content-Length: 360
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 28] con->http=0x55a8ecdb51e0
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=360, response=0x55a8ecdc0ba0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 28] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 28] bytes=0, http_state=0, data_remaining=360
D [25/Jun/2023:11:23:10 -0700] [Client 28] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 28] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 28] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Job 62] Rendering completed
D [25/Jun/2023:11:23:10 -0700] cupsdMarkDirty(----S)
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] cupsdMarkDirty(----S)
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] JobProgress
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] PrinterStateChanged
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Client 28] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 28] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 28] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 28] 2.0 CUPS-Get-Printers 265
D [25/Jun/2023:11:23:10 -0700] CUPS-Get-Printers
D [25/Jun/2023:11:23:10 -0700] [Client 28] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 28] Content-Length: 2365
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 28] con->http=0x55a8ecdb51e0
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2365, response=0x55a8ecdc6160(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 28] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 28] bytes=0, http_state=0, data_remaining=2365
D [25/Jun/2023:11:23:10 -0700] [Client 28] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 28] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 28] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Job 62] PID 343157 (/usr/lib/cups/filter/gstoraster) stopped with status 1.
D [25/Jun/2023:11:23:10 -0700] cupsdMarkDirty(----S)
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
E [25/Jun/2023:11:23:10 -0700] [Job 62] Job stopped due to filter errors; please consult the /var/log/cups/error_log file for details.
D [25/Jun/2023:11:23:10 -0700] cupsdMarkDirty(---J-)
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] cupsdMarkDirty(----S)
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] cupsdMarkDirty(---J-)
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Job 62] The following messages were recorded from 11:23:10 AM to 11:23:10 AM
D [25/Jun/2023:11:23:10 -0700] [Job 62] After mapping finishings ColorModel=RGB
D [25/Jun/2023:11:23:10 -0700] [Job 62] After mapping finishings OutputMode=Normal
D [25/Jun/2023:11:23:10 -0700] [Job 62] End of messages
D [25/Jun/2023:11:23:10 -0700] [Job 62] printer-state=3(idle)
D [25/Jun/2023:11:23:10 -0700] [Job 62] printer-state-message="Rendering completed"
D [25/Jun/2023:11:23:10 -0700] [Job 62] printer-state-reasons=none
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] JobState
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] PrinterStateChanged
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] [Notifier] state=3
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 34] Server address is "/run/cups/cups.sock".
D [25/Jun/2023:11:23:10 -0700] [Client 34] Accepted from localhost (Domain)
D [25/Jun/2023:11:23:10 -0700] [Client 34] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] [Client 34] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 34] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 34] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 34] 2.0 CUPS-Get-Printers 266
D [25/Jun/2023:11:23:10 -0700] CUPS-Get-Printers
D [25/Jun/2023:11:23:10 -0700] [Client 34] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 34] Content-Length: 2365
D [25/Jun/2023:11:23:10 -0700] [Client 34] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 34] con->http=0x55a8ecdcbaa0
D [25/Jun/2023:11:23:10 -0700] [Client 34] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2365, response=0x55a8ecd28800(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 34] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 34] bytes=0, http_state=0, data_remaining=2365
D [25/Jun/2023:11:23:10 -0700] [Client 34] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 34] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 34] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 5] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 5] 2.0 Get-Notifications 182
D [25/Jun/2023:11:23:10 -0700] Get-Notifications /printers/
D [25/Jun/2023:11:23:10 -0700] cupsdIsAuthorized: requesting-user-name="scott"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 5] Content-Length: 1509
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] con->http=0x55a8ecd520c0
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1509, response=0x55a8ecd7f0b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] bytes=0, http_state=0, data_remaining=1509
D [25/Jun/2023:11:23:10 -0700] [Client 5] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 5] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 5] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 5] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 5] 2.0 CUPS-Get-Printers 183
D [25/Jun/2023:11:23:10 -0700] CUPS-Get-Printers
D [25/Jun/2023:11:23:10 -0700] [Client 5] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 5] Content-Length: 2365
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] con->http=0x55a8ecd520c0
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2365, response=0x55a8ecd28800(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] bytes=0, http_state=0, data_remaining=2365
D [25/Jun/2023:11:23:10 -0700] [Client 5] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 5] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 5] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 28] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 28] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 28] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 28] 2.0 Get-Jobs 267
D [25/Jun/2023:11:23:10 -0700] Get-Jobs ipp://localhost/printers/Smart_Tank_5100
D [25/Jun/2023:11:23:10 -0700] [Client 28] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/Smart_Tank_5100) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 28] Content-Length: 75
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 28] con->http=0x55a8ecdb51e0
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55a8ecd7f0b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 28] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 28] bytes=0, http_state=0, data_remaining=75
D [25/Jun/2023:11:23:10 -0700] [Client 28] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 28] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 28] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 34] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 34] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 34] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 34] 2.0 Get-Jobs 268
D [25/Jun/2023:11:23:10 -0700] Get-Jobs ipp://localhost/printers/test
D [25/Jun/2023:11:23:10 -0700] [Client 34] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/test) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 34] Content-Length: 360
D [25/Jun/2023:11:23:10 -0700] [Client 34] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 34] con->http=0x55a8ecdcbaa0
D [25/Jun/2023:11:23:10 -0700] [Client 34] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=360, response=0x55a8ecd28800(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 34] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 34] bytes=0, http_state=0, data_remaining=360
D [25/Jun/2023:11:23:10 -0700] [Client 34] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 34] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 34] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 32] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 32] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 32] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 32] 2.0 Get-Jobs 269
D [25/Jun/2023:11:23:10 -0700] Get-Jobs ipp://localhost/printers/Smart_Tank_5100
D [25/Jun/2023:11:23:10 -0700] [Client 32] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/Smart_Tank_5100) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 32] Content-Length: 75
D [25/Jun/2023:11:23:10 -0700] [Client 32] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 32] con->http=0x55a8ecdbcee0
D [25/Jun/2023:11:23:10 -0700] [Client 32] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55a8ecd382e0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 32] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 32] bytes=0, http_state=0, data_remaining=75
D [25/Jun/2023:11:23:10 -0700] [Client 32] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 32] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 32] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 35] Server address is "/run/cups/cups.sock".
D [25/Jun/2023:11:23:10 -0700] [Client 35] Accepted from localhost (Domain)
D [25/Jun/2023:11:23:10 -0700] [Client 35] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] [Client 28] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 28] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 28] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 28] 2.0 Get-Jobs 271
D [25/Jun/2023:11:23:10 -0700] Get-Jobs ipp://localhost/printers/Smart_Tank_5100
D [25/Jun/2023:11:23:10 -0700] [Client 28] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/Smart_Tank_5100) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 28] Content-Length: 75
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 34] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 34] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 34] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 34] 2.0 Get-Jobs 272
D [25/Jun/2023:11:23:10 -0700] Get-Jobs ipp://localhost/printers/test
D [25/Jun/2023:11:23:10 -0700] [Client 34] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/test) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 34] Content-Length: 360
D [25/Jun/2023:11:23:10 -0700] [Client 34] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 35] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 35] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 35] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 35] 2.0 Get-Jobs 270
D [25/Jun/2023:11:23:10 -0700] Get-Jobs ipp://localhost/printers/test
D [25/Jun/2023:11:23:10 -0700] [Client 35] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/test) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 35] Content-Length: 360
D [25/Jun/2023:11:23:10 -0700] [Client 35] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 28] con->http=0x55a8ecdb51e0
D [25/Jun/2023:11:23:10 -0700] [Client 28] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55a8ecdd3d00(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 28] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 28] bytes=0, http_state=0, data_remaining=75
D [25/Jun/2023:11:23:10 -0700] [Client 28] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 28] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 28] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 34] con->http=0x55a8ecdcbaa0
D [25/Jun/2023:11:23:10 -0700] [Client 34] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=360, response=0x55a8ecdd4050(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 34] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 34] bytes=0, http_state=0, data_remaining=360
D [25/Jun/2023:11:23:10 -0700] [Client 34] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 34] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 34] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 35] con->http=0x55a8ecdd03c0
D [25/Jun/2023:11:23:10 -0700] [Client 35] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=360, response=0x55a8ecdd46b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 35] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 35] bytes=0, http_state=0, data_remaining=360
D [25/Jun/2023:11:23:10 -0700] [Client 35] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 35] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 35] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 36] Server address is "/run/cups/cups.sock".
D [25/Jun/2023:11:23:10 -0700] [Client 36] Accepted from localhost (Domain)
D [25/Jun/2023:11:23:10 -0700] [Client 36] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] [Client 36] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 36] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 36] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 36] 2.0 Get-Job-Attributes 184
D [25/Jun/2023:11:23:10 -0700] Get-Job-Attributes ipp://localhost/jobs/62
D [25/Jun/2023:11:23:10 -0700] [Client 36] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/62) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 36] Content-Length: 111
D [25/Jun/2023:11:23:10 -0700] [Client 36] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 36] con->http=0x55a8ecdd4ce0
D [25/Jun/2023:11:23:10 -0700] [Client 36] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=111, response=0x55a8ecdd84c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 36] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 36] bytes=0, http_state=0, data_remaining=111
D [25/Jun/2023:11:23:10 -0700] [Client 36] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 36] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 36] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 36] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:23:10 -0700] [Client 36] Closing connection.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 5] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 5] 2.0 Get-Notifications 185
D [25/Jun/2023:11:23:10 -0700] Get-Notifications /printers/
D [25/Jun/2023:11:23:10 -0700] cupsdIsAuthorized: requesting-user-name="scott"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 5] Content-Length: 127
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] con->http=0x55a8ecd520c0
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x55a8ecd287c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] bytes=0, http_state=0, data_remaining=127
D [25/Jun/2023:11:23:10 -0700] [Client 5] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 5] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 5] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 5] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 5] 2.0 Get-Notifications 186
D [25/Jun/2023:11:23:10 -0700] Get-Notifications /printers/
D [25/Jun/2023:11:23:10 -0700] cupsdIsAuthorized: requesting-user-name="scott"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 5] Content-Length: 127
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] con->http=0x55a8ecd520c0
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x55a8ecdd84c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] bytes=0, http_state=0, data_remaining=127
D [25/Jun/2023:11:23:10 -0700] [Client 5] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 5] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 5] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 5] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 5] 2.0 Get-Notifications 187
D [25/Jun/2023:11:23:10 -0700] Get-Notifications /printers/
D [25/Jun/2023:11:23:10 -0700] cupsdIsAuthorized: requesting-user-name="scott"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 5] Content-Length: 127
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] con->http=0x55a8ecd520c0
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x55a8ecd287c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] bytes=0, http_state=0, data_remaining=127
D [25/Jun/2023:11:23:10 -0700] [Client 5] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 5] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 5] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 5] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 5] 2.0 Get-Notifications 188
D [25/Jun/2023:11:23:10 -0700] Get-Notifications /printers/
D [25/Jun/2023:11:23:10 -0700] cupsdIsAuthorized: requesting-user-name="scott"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 5] Content-Length: 127
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] con->http=0x55a8ecd520c0
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x55a8ecdd84c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] bytes=0, http_state=0, data_remaining=127
D [25/Jun/2023:11:23:10 -0700] [Client 5] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 5] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 5] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] POST / HTTP/1.1
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Read: status=200, state=6
D [25/Jun/2023:11:23:10 -0700] [Client 5] No authentication data provided.
D [25/Jun/2023:11:23:10 -0700] [Client 5] 2.0 Get-Notifications 189
D [25/Jun/2023:11:23:10 -0700] Get-Notifications /printers/
D [25/Jun/2023:11:23:10 -0700] cupsdIsAuthorized: requesting-user-name="scott"
D [25/Jun/2023:11:23:10 -0700] [Client 5] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost.
D [25/Jun/2023:11:23:10 -0700] [Client 5] Content-Length: 127
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] con->http=0x55a8ecd520c0
D [25/Jun/2023:11:23:10 -0700] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x55a8ecd287c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:23:10 -0700] [Client 5] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:23:10 -0700] [Client 5] bytes=0, http_state=0, data_remaining=127
D [25/Jun/2023:11:23:10 -0700] [Client 5] Flushing write buffer.
D [25/Jun/2023:11:23:10 -0700] [Client 5] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:23:10 -0700] [Client 5] Waiting for request.
D [25/Jun/2023:11:23:10 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Jun/2023:11:23:11 -0700] [Client 35] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:23:11 -0700] [Client 35] Closing connection.
D [25/Jun/2023:11:23:11 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:11 -0700] [Client 34] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:23:11 -0700] [Client 34] Closing connection.
D [25/Jun/2023:11:23:11 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [25/Jun/2023:11:23:11 -0700] [Client 28] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:23:11 -0700] [Client 28] Closing connection.
D [25/Jun/2023:11:23:11 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [25/Jun/2023:11:23:11 -0700] Expiring subscriptions...
D [25/Jun/2023:11:23:11 -0700] [Job 62] Unloading...
I [25/Jun/2023:11:23:12 -0700] Expiring subscriptions...
D [25/Jun/2023:11:23:26 -0700] [Client 32] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:23:26 -0700] [Client 32] Closing connection.
D [25/Jun/2023:11:23:26 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [25/Jun/2023:11:23:26 -0700] Expiring subscriptions...
D [25/Jun/2023:11:23:26 -0700] Report: clients=3
D [25/Jun/2023:11:23:26 -0700] Report: jobs=62
D [25/Jun/2023:11:23:26 -0700] Report: jobs-active=1
D [25/Jun/2023:11:23:26 -0700] Report: printers=2
D [25/Jun/2023:11:23:26 -0700] Report: stringpool-string-count=9073
D [25/Jun/2023:11:23:26 -0700] Report: stringpool-alloc-bytes=12400
D [25/Jun/2023:11:23:26 -0700] Report: stringpool-total-bytes=180200
I [25/Jun/2023:11:23:27 -0700] Expiring subscriptions...
I [25/Jun/2023:11:23:31 -0700] Saving job.cache...
I [25/Jun/2023:11:23:31 -0700] Saving subscriptions.conf...
D [25/Jun/2023:11:23:31 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [25/Jun/2023:11:23:31 -0700] Expiring subscriptions...
D [25/Jun/2023:11:29:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [25/Jun/2023:11:29:31 -0700] [Client 37] Server address is "/run/cups/cups.sock".
D [25/Jun/2023:11:29:31 -0700] [Client 37] Accepted from localhost (Domain)
D [25/Jun/2023:11:29:31 -0700] [Client 37] Waiting for request.
I [25/Jun/2023:11:29:31 -0700] Expiring subscriptions...
D [25/Jun/2023:11:29:31 -0700] [Job 60] Unloading...
D [25/Jun/2023:11:29:31 -0700] [Job 61] Unloading...
D [25/Jun/2023:11:29:31 -0700] cupsdAddCert: Adding certificate for PID 0
D [25/Jun/2023:11:29:31 -0700] Report: clients=4
D [25/Jun/2023:11:29:31 -0700] Report: jobs=62
D [25/Jun/2023:11:29:31 -0700] Report: jobs-active=1
D [25/Jun/2023:11:29:31 -0700] Report: printers=2
D [25/Jun/2023:11:29:31 -0700] Report: stringpool-string-count=8967
D [25/Jun/2023:11:29:31 -0700] Report: stringpool-alloc-bytes=11232
D [25/Jun/2023:11:29:31 -0700] Report: stringpool-total-bytes=178008
D [25/Jun/2023:11:29:31 -0700] [Client 37] POST / HTTP/1.1
D [25/Jun/2023:11:29:31 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [25/Jun/2023:11:29:31 -0700] [Client 37] Read: status=200, state=6
D [25/Jun/2023:11:29:31 -0700] [Client 37] No authentication data provided.
D [25/Jun/2023:11:29:31 -0700] [Client 37] 2.0 Renew-Subscription 273
D [25/Jun/2023:11:29:31 -0700] Renew-Subscription /
D [25/Jun/2023:11:29:31 -0700] cupsdIsAuthorized: requesting-user-name="scott"
D [25/Jun/2023:11:29:31 -0700] cupsdMarkDirty(----S)
D [25/Jun/2023:11:29:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [25/Jun/2023:11:29:31 -0700] [Client 37] Returning IPP successful-ok for Renew-Subscription (/) from localhost.
D [25/Jun/2023:11:29:31 -0700] [Client 37] Content-Length: 106
D [25/Jun/2023:11:29:31 -0700] [Client 37] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [25/Jun/2023:11:29:31 -0700] [Client 37] con->http=0x55a8ecdb51e0
D [25/Jun/2023:11:29:31 -0700] [Client 37] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=106, response=0x55a8ecd575c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [25/Jun/2023:11:29:31 -0700] [Client 37] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [25/Jun/2023:11:29:31 -0700] [Client 37] bytes=0, http_state=0, data_remaining=106
D [25/Jun/2023:11:29:31 -0700] [Client 37] Flushing write buffer.
D [25/Jun/2023:11:29:31 -0700] [Client 37] New state is HTTP_STATE_WAITING
D [25/Jun/2023:11:29:31 -0700] [Client 37] Waiting for request.
D [25/Jun/2023:11:29:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [25/Jun/2023:11:29:32 -0700] Expiring subscriptions...
I [25/Jun/2023:11:29:46 -0700] Expiring subscriptions...
D [25/Jun/2023:11:29:46 -0700] Closing client 1 after 900 seconds of inactivity.
D [25/Jun/2023:11:29:46 -0700] [Client 1] Closing connection.
D [25/Jun/2023:11:29:46 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [25/Jun/2023:11:29:46 -0700] Closing client 2 after 900 seconds of inactivity.
D [25/Jun/2023:11:29:46 -0700] [Client 2] Closing connection.
D [25/Jun/2023:11:29:46 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [25/Jun/2023:11:29:47 -0700] [Client 37] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [25/Jun/2023:11:29:47 -0700] [Client 37] Closing connection.
D [25/Jun/2023:11:29:47 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [25/Jun/2023:11:29:47 -0700] Expiring subscriptions...
I [25/Jun/2023:11:29:48 -0700] Expiring subscriptions...
I [25/Jun/2023:11:30:02 -0700] Saving subscriptions.conf...
D [25/Jun/2023:11:30:02 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [25/Jun/2023:11:30:02 -0700] Expiring subscriptions...