[SOLVED] Printing test page fails

AtaSmrk007 · 2026-09-02 18:38:47

I am trying to make use of the printer (Canon TS5150) on my system.

Using AUR I installed a vendor driver and using KDE settings added a printer. Everything seemed to work and I tried to print a test page. However nothing happened and queue was reporting pdftopdf exiting with status 1.

Content of my cups error for one job:

W [02/Sep/2026:10:33:58 +0200] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name is not activatable
W [02/Sep/2026:10:33:58 +0200] CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name is not activatable
W [02/Sep/2026:10:33:58 +0200] CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name is not activatable
E [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterChain: pdftopdf (PID 4512) stopped with status 1
E [02/Sep/2026:10:34:02 +0200] [Job 7] universal filter failed.
E [02/Sep/2026:10:34:02 +0200] [Job 7] Job stopped due to filter errors; please consult the /var/log/cups/error_log file for details.
D [02/Sep/2026:10:34:02 +0200] [Job 7] The following messages were recorded from 10:34:01 AM to 10:34:02 AM
D [02/Sep/2026:10:34:02 +0200] [Job 7] Applying default options...
D [02/Sep/2026:10:34:02 +0200] [Job 7] Adding start banner page "none".
D [02/Sep/2026:10:34:02 +0200] [Job 7] Adding end banner page "none".
D [02/Sep/2026:10:34:02 +0200] [Job 7] File of type application/vnd.cups-pdf-banner queued by "anze".
D [02/Sep/2026:10:34:02 +0200] [Job 7] hold_until=0
D [02/Sep/2026:10:34:02 +0200] [Job 7] Queued on "TS5100_series" by "anze".
D [02/Sep/2026:10:34:02 +0200] [Job 7] time-at-processing=1788338041
D [02/Sep/2026:10:34:02 +0200] [Job 7] 4 filters for job:
D [02/Sep/2026:10:34:02 +0200] [Job 7] - (application/vnd.cups-pdf-banner to application/vnd.universal-input, cost 0)
D [02/Sep/2026:10:34:02 +0200] [Job 7] universal (application/vnd.universal-input to application/vnd.cups-raster, cost 0)
D [02/Sep/2026:10:34:02 +0200] [Job 7] - (application/vnd.cups-raster to printer/TS5100_series/image/pwg-raster, cost 0)
D [02/Sep/2026:10:34:02 +0200] [Job 7] - (printer/TS5100_series/image/pwg-raster to printer/TS5100_series, cost 0)
D [02/Sep/2026:10:34:02 +0200] [Job 7] job-sheets=none,none
D [02/Sep/2026:10:34:02 +0200] [Job 7] argv[0]="TS5100_series"
D [02/Sep/2026:10:34:02 +0200] [Job 7] argv[1]="7"
D [02/Sep/2026:10:34:02 +0200] [Job 7] argv[2]="anze"
D [02/Sep/2026:10:34:02 +0200] [Job 7] argv[3]="Test Page"
D [02/Sep/2026:10:34:02 +0200] [Job 7] argv[4]="1"
D [02/Sep/2026:10:34:02 +0200] [Job 7] argv[5]="job-uuid=urn:uuid:5e3c4691-7844-3204-69a9-85b2da63a069 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1788338041 time-at-processing=1788338041"
D [02/Sep/2026:10:34:02 +0200] [Job 7] argv[6]="/var/spool/cups/d00007-001"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[3]="CUPS_REQUESTROOT=/var/spool/cups"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[4]="CUPS_SERVERBIN=/usr/lib/cups"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[5]="CUPS_SERVERROOT=/etc/cups"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[6]="CUPS_STATEDIR=/run/cups"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[7]="HOME=/var/spool/cups/tmp"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[9]="SERVER_ADMIN=root@GamingStation"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[10]="SOFTWARE=CUPS/2.4.19"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[12]="USER=root"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[13]="CUPS_MAX_MESSAGE=2047"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[14]="CUPS_SERVER=/run/cups/cups.sock"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[16]="IPP_PORT=631"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[17]="CHARSET=utf-8"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[18]="LANG=en_US.UTF-8"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[19]="PPD=/etc/cups/ppd/TS5100_series.ppd"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[20]="CONTENT_TYPE=application/vnd.cups-pdf-banner"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[21]="DEVICE_URI=socket://192.168.50.110"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[22]="PRINTER_INFO=Canon TS5100 series 3.000"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[23]="PRINTER_LOCATION="
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[24]="PRINTER=TS5100_series"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[25]="PRINTER_STATE_REASONS=none"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[26]="CUPS_FILETYPE=document"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[27]="FINAL_CONTENT_TYPE=image/pwg-raster"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[28]="AUTH_I****"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[29]="AUTH_U****"
D [02/Sep/2026:10:34:02 +0200] [Job 7] envp[30]="AUTH_P****"
D [02/Sep/2026:10:34:02 +0200] [Job 7] Started filter /usr/lib/cups/filter/universal (PID 4508)
D [02/Sep/2026:10:34:02 +0200] [Job 7] Started backend /usr/lib/cups/backend/socket (PID 4509)
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: +connecting-to-device
D [02/Sep/2026:10:34:02 +0200] [Job 7] Looking up \"192.168.50.110\"...
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -connecting-to-device
D [02/Sep/2026:10:34:02 +0200] [Job 7] 192.168.50.110=192.168.50.110
D [02/Sep/2026:10:34:02 +0200] [Job 7] ppdFilterLoadPPD: Color profile qualifier determined from job and PPD data \'..600dpi\'
D [02/Sep/2026:10:34:02 +0200] [Job 7] ppdFilterLoadPPD: No ICC profiles specified in PPD
D [02/Sep/2026:10:34:02 +0200] [Job 7] ppdFilterLoadPPD: Searching for profile \"600dpi/-\"...
D [02/Sep/2026:10:34:02 +0200] [Job 7] ppdFilterLoadPPD: Last filter determined by the PPD: -; Final data MIME type: image/pwg-raster => PDF filter will log pages in page_log.
D [02/Sep/2026:10:34:02 +0200] [Job 7] ppdFilterUniversal: \"*cupsFilter(2): ...\" lines in the PPD file:
D [02/Sep/2026:10:34:02 +0200] [Job 7] ppdFilterUniversal:    application/vnd.cups-raw application/octet-stream 0 -
D [02/Sep/2026:10:34:02 +0200] [Job 7] ppdFilterUniversal:    application/vnd.cups-raster image/pwg-raster 0 -
D [02/Sep/2026:10:34:02 +0200] [Job 7] ppdFilterUniversal:       --> Selecting this line
D [02/Sep/2026:10:34:02 +0200] [Job 7] ppdFilterUniversal:    Cost value is down to zero, stopping reading further lines
D [02/Sep/2026:10:34:02 +0200] [Job 7] ppdFilterUniversal: Converting from application/vnd.cups-pdf-banner to application/vnd.cups-raster, final output will be image/pwg-raster
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterUniversal: Converting from application/vnd.cups-pdf-banner to application/vnd.cups-raster
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterUniversal: Final output format for job: image/pwg-raster
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterUniversal: Adding bannertopdf to chain
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterUniversal: Adding pdftopdf to chain
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterUniversal: Adding ghostscript to chain
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterChain: Running filter: bannertopdf
D [02/Sep/2026:10:34:02 +0200] [Job 7] Set job-printer-state-message to "cfFilterChain: Running filter: bannertopdf", current level=INFO
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterChain: Running filter: pdftopdf
D [02/Sep/2026:10:34:02 +0200] [Job 7] Set job-printer-state-message to "cfFilterChain: Running filter: pdftopdf", current level=INFO
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterChain: Running filter: ghostscript
D [02/Sep/2026:10:34:02 +0200] [Job 7] Set job-printer-state-message to "cfFilterChain: Running filter: ghostscript", current level=INFO
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterChain: bannertopdf (PID 4511) started.
D [02/Sep/2026:10:34:02 +0200] [Job 7] Set job-printer-state-message to "cfFilterChain: bannertopdf (PID 4511) started.", current level=INFO
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterBannerToPDF: Copying input to temp file \"/var/spool/cups/tmp/0119f6aa2b0c7\"
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterChain: pdftopdf (PID 4512) started.
D [02/Sep/2026:10:34:02 +0200] [Job 7] Set job-printer-state-message to "cfFilterChain: pdftopdf (PID 4512) started.", current level=INFO
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterChain: ghostscript (PID 4513) started.
D [02/Sep/2026:10:34:02 +0200] [Job 7] Set job-printer-state-message to "cfFilterChain: ghostscript (PID 4513) started.", current level=INFO
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterGhostscript: Output format: CUPS Raster
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterGhostscript: Copying input to temp file \"/var/spool/cups/tmp/011a16aa2b366\"
D [02/Sep/2026:10:34:02 +0200] [Job 7] hrDeviceDesc=\"Canon TS5100 series 3.000\"
D [02/Sep/2026:10:34:02 +0200] [Job 7] Opening content stream 1/1...
D [02/Sep/2026:10:34:02 +0200] [Job 7] Opened stream 1
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterChain: bannertopdf completed with status 0.
D [02/Sep/2026:10:34:02 +0200] [Job 7] prtMarkerColorantValue.1.1 = \"black\"
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterChain: pdftopdf completed with status 1.
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterGhostscript: Input is empty, outputting empty file.
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterChain: ghostscript completed with status 0.
D [02/Sep/2026:10:34:02 +0200] [Job 7] prtMarkerColorantValue.1.2 = \"color\"
D [02/Sep/2026:10:34:02 +0200] [Job 7] ATTR: marker-colors=#000000,none
D [02/Sep/2026:10:34:02 +0200] [Job 7] ATTR: marker-names=\'\"Canon Black Ink Tank\"\',\'\"Canon Color Ink Tank\"\'
D [02/Sep/2026:10:34:02 +0200] [Job 7] ATTR: marker-types=ink-cartridge,ink-cartridge
D [02/Sep/2026:10:34:02 +0200] [Job 7] ATTR: marker-levels=-1,-1
D [02/Sep/2026:10:34:02 +0200] [Job 7] new_supply_state=0, change_state=ffff
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -developer-low-report
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -developer-empty-warning
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -marker-supply-low-report
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -marker-supply-empty-warning
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -opc-near-eol-report
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -opc-life-over-warning
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -toner-low-report
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -toner-empty-warning
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -waste-receptacle-almost-full-report
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -waste-receptacle-full-warning
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -cleaner-life-almost-over-report
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -cleaner-life-over-warning
D [02/Sep/2026:10:34:02 +0200] [Job 7] new_state=0, change_state=ffff
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -media-empty-warning
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -door-open-report
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -media-jam-warning
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -input-tray-missing-warning
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -output-tray-missing-warning
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -marker-supply-missing-warning
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -output-area-almost-full-report
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -output-area-full-warning
D [02/Sep/2026:10:34:02 +0200] [Job 7] backendWaitLoop(snmp_fd=5, addr=0x557bf1367c18, side_cb=0x557bb24df820)
D [02/Sep/2026:10:34:02 +0200] [Job 7] Connecting to 192.168.50.110:9100
D [02/Sep/2026:10:34:02 +0200] [Job 7] Connecting to printer.
D [02/Sep/2026:10:34:02 +0200] [Job 7] Set job-printer-state-message to "Connecting to printer.", current level=INFO
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: -connecting-to-device
D [02/Sep/2026:10:34:02 +0200] [Job 7] Connected to printer.
D [02/Sep/2026:10:34:02 +0200] [Job 7] Set job-printer-state-message to "Connected to printer.", current level=INFO
D [02/Sep/2026:10:34:02 +0200] [Job 7] Connected to 192.168.50.110:9100...
D [02/Sep/2026:10:34:02 +0200] [Job 7] backendRunLoop(print_fd=0, device_fd=6, snmp_fd=5, addr=0x557bf1367c18, use_bc=1, side_cb=0x557bb24df820)
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterChain: bannertopdf (PID 4511) exited with no errors.
D [02/Sep/2026:10:34:02 +0200] [Job 7] Set job-printer-state-message to "cfFilterChain: bannertopdf (PID 4511) exited with no errors.", current level=INFO
D [02/Sep/2026:10:34:02 +0200] [Job 7] Set job-printer-state-message to "cfFilterChain: pdftopdf (PID 4512) stopped with status 1", current level=ERROR
D [02/Sep/2026:10:34:02 +0200] [Job 7] cfFilterChain: ghostscript (PID 4513) exited with no errors.
D [02/Sep/2026:10:34:02 +0200] [Job 7] STATE: +cups-waiting-for-job-completed
D [02/Sep/2026:10:34:02 +0200] [Job 7] Waiting for printer to finish.
D [02/Sep/2026:10:34:02 +0200] [Job 7] prtMarkerSuppliesLevel.1.1 = -2
D [02/Sep/2026:10:34:02 +0200] [Job 7] prtMarkerSuppliesLevel.1.2 = -2
D [02/Sep/2026:10:34:02 +0200] [Job 7] ATTR: marker-levels=-1,-1
D [02/Sep/2026:10:34:02 +0200] [Job 7] new_supply_state=0, change_state=0
D [02/Sep/2026:10:34:02 +0200] [Job 7] new_state=0, change_state=0
D [02/Sep/2026:10:34:02 +0200] [Job 7] PID 4508 (/usr/lib/cups/filter/universal) stopped with status 1.
D [02/Sep/2026:10:34:02 +0200] [Job 7] Hint: Try setting the LogLevel to "debug" to find out more.
D [02/Sep/2026:10:34:02 +0200] [Job 7] PID 4509 (/usr/lib/cups/backend/socket) exited with no errors.
D [02/Sep/2026:10:34:02 +0200] [Job 7] End of messages
D [02/Sep/2026:10:34:02 +0200] [Job 7] printer-state=3(idle)
D [02/Sep/2026:10:34:02 +0200] [Job 7] printer-state-message="Waiting for printer to finish."
D [02/Sep/2026:10:34:02 +0200] [Job 7] printer-state-reasons=none

Communication with the printer works, I can trigger printing of printer's self test page. I also tried using "Generic IPP Everywhere Printer" driver, but have the same problem.

My google foo is failing me and I am unable to find any information about a problem like this.

Last edited by AtaSmrk007 (2026-09-04 07:14:52)

seth · 2026-09-02 20:53:20

For IPPe:

nmap 192.168.50.110

Random guess, do you have https://archlinux.org/packages/core/x86_64/which/ installed (a whole bunch of printer drivers silently depend on it to find gs)

AtaSmrk007 · 2026-09-03 14:22:00

I did not have which installed, but it made no difference.

One difference is that for some reason there are a lot more entries in the error log now.

D [03/Sep/2026:16:20:01 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [03/Sep/2026:16:20:01 +0200] [Client 12] Server address is "/run/cups/cups.sock".
D [03/Sep/2026:16:20:01 +0200] [Client 12] Accepted from localhost (Domain)
D [03/Sep/2026:16:20:01 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:01 +0200] Expiring subscriptions...
D [03/Sep/2026:16:20:01 +0200] [Job 12] Unloading...
D [03/Sep/2026:16:20:01 +0200] cupsdAddCert: Adding certificate for PID 0
D [03/Sep/2026:16:20:01 +0200] Report: clients=2
D [03/Sep/2026:16:20:01 +0200] Report: jobs=2
D [03/Sep/2026:16:20:01 +0200] Report: jobs-active=0
D [03/Sep/2026:16:20:01 +0200] Report: printers=1
D [03/Sep/2026:16:20:01 +0200] Report: stringpool-string-count=1690
D [03/Sep/2026:16:20:01 +0200] Report: stringpool-alloc-bytes=9976
D [03/Sep/2026:16:20:01 +0200] Report: stringpool-total-bytes=33240
D [03/Sep/2026:16:20:01 +0200] [Client 12] POST /printers/TS5100_series HTTP/1.1
D [03/Sep/2026:16:20:01 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [03/Sep/2026:16:20:01 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:01 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:01 +0200] [Client 12] 2.0 Get-Printer-Attributes 20
D [03/Sep/2026:16:20:01 +0200] Get-Printer-Attributes ipp://localhost/printers/TS5100_series
D [03/Sep/2026:16:20:01 +0200] [Client 12] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:01 +0200] [Client 12] Content-Length: 140
D [03/Sep/2026:16:20:01 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:01 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:01 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=140, response=0x55f5a67d9370(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:01 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:01 +0200] [Client 12] bytes=0, http_state=0, data_remaining=140
D [03/Sep/2026:16:20:01 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:01 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:01 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:01 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [03/Sep/2026:16:20:01 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [03/Sep/2026:16:20:01 +0200] [Client 13] Server address is "/run/cups/cups.sock".
D [03/Sep/2026:16:20:01 +0200] [Client 13] Accepted from localhost (Domain)
D [03/Sep/2026:16:20:01 +0200] [Client 13] Waiting for request.
D [03/Sep/2026:16:20:01 +0200] [Client 13] GET /printers/TS5100_series.ppd HTTP/1.1
D [03/Sep/2026:16:20:01 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [03/Sep/2026:16:20:01 +0200] [Client 13] Read: status=200, state=3
D [03/Sep/2026:16:20:01 +0200] [Client 13] No authentication data provided.
D [03/Sep/2026:16:20:01 +0200] [Client 13] Processing GET /printers/TS5100_series.ppd
D [03/Sep/2026:16:20:01 +0200] [Client 13] filename="/etc/cups/ppd/TS5100_series.ppd", type=application/vnd.cups-ppd
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdSendHeader: code=200, type="application/vnd.cups-ppd", auth_type=0
D [03/Sep/2026:16:20:01 +0200] [Client 13] Sending file.
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] con->http=0x55f5a67ebb80
D [03/Sep/2026:16:20:01 +0200] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=91349, response=(nil)(), pipe_pid=0, file=21
D [03/Sep/2026:16:20:01 +0200] [Client 13] Flushing write buffer.
D [03/Sep/2026:16:20:01 +0200] [Client 13] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:01 +0200] [Client 13] Waiting for request.
D [03/Sep/2026:16:20:01 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [03/Sep/2026:16:20:01 +0200] [Client 13] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [03/Sep/2026:16:20:01 +0200] [Client 13] Closing connection.
D [03/Sep/2026:16:20:01 +0200] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [03/Sep/2026:16:20:02 +0200] Expiring subscriptions...
D [03/Sep/2026:16:20:04 +0200] [Client 12] POST /printers/TS5100_series HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [03/Sep/2026:16:20:04 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 12] 2.0 Print-Job 21
D [03/Sep/2026:16:20:04 +0200] Print-Job ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Job ???] Auto-typing file...
I [03/Sep/2026:16:20:04 +0200] [Job ???] Request file type is application/vnd.cups-pdf-banner.
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [03/Sep/2026:16:20:04 +0200] [Job 13] Applying default options...
D [03/Sep/2026:16:20:04 +0200] [Job 13] Adding default print-color-mode=monochrome
D [03/Sep/2026:16:20:04 +0200] add_job: requesting-user-name="anze"
D [03/Sep/2026:16:20:04 +0200] Adding default job-sheets values "none,none"...
I [03/Sep/2026:16:20:04 +0200] [Job 13] Adding start banner page "none".
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [03/Sep/2026:16:20:04 +0200] [Job 13] Adding end banner page "none".
I [03/Sep/2026:16:20:04 +0200] [Job 13] File of type application/vnd.cups-pdf-banner queued by "anze".
D [03/Sep/2026:16:20:04 +0200] [Job 13] hold_until=0
I [03/Sep/2026:16:20:04 +0200] [Job 13] Queued on "TS5100_series" by "anze".
D [03/Sep/2026:16:20:04 +0200] [Job 13] time-at-processing=1788445204
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] 3 filters for job:
D [03/Sep/2026:16:20:04 +0200] [Job 13] - (application/vnd.cups-pdf-banner to application/vnd.universal-input, cost 0)
D [03/Sep/2026:16:20:04 +0200] [Job 13] universal (application/vnd.universal-input to application/vnd.cups-raster, cost 0)
D [03/Sep/2026:16:20:04 +0200] [Job 13] rastertocanonij (application/vnd.cups-raster to printer/TS5100_series, cost 0)
D [03/Sep/2026:16:20:04 +0200] [Job 13] job-sheets=none,none
D [03/Sep/2026:16:20:04 +0200] [Job 13] argv[0]="TS5100_series"
D [03/Sep/2026:16:20:04 +0200] [Job 13] argv[1]="13"
D [03/Sep/2026:16:20:04 +0200] [Job 13] argv[2]="anze"
D [03/Sep/2026:16:20:04 +0200] [Job 13] argv[3]="Test Page"
D [03/Sep/2026:16:20:04 +0200] [Job 13] argv[4]="1"
D [03/Sep/2026:16:20:04 +0200] [Job 13] argv[5]="job-uuid=urn:uuid:577792be-fcb8-3019-49fb-489cd8f75511 print-color-mode=monochrome job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1788445204 time-at-processing=1788445204"
D [03/Sep/2026:16:20:04 +0200] [Job 13] argv[6]="/var/spool/cups/d00013-001"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[3]="CUPS_REQUESTROOT=/var/spool/cups"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[4]="CUPS_SERVERBIN=/usr/lib/cups"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[5]="CUPS_SERVERROOT=/etc/cups"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[6]="CUPS_STATEDIR=/run/cups"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[7]="HOME=/var/spool/cups/tmp"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[9]="SERVER_ADMIN=root@GamingStation"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[10]="SOFTWARE=CUPS/2.4.19"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[12]="USER=root"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[13]="CUPS_MAX_MESSAGE=2047"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[14]="CUPS_SERVER=/run/cups/cups.sock"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[16]="IPP_PORT=631"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[17]="CHARSET=utf-8"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[18]="LANG=en_US.UTF-8"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[19]="PPD=/etc/cups/ppd/TS5100_series.ppd"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[20]="CONTENT_TYPE=application/vnd.cups-pdf-banner"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[21]="DEVICE_URI=socket://192.168.50.110"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[22]="PRINTER_INFO=Canon TS5100 series 3.000"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[23]="PRINTER_LOCATION="
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[24]="PRINTER=TS5100_series"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[25]="PRINTER_STATE_REASONS=none"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[26]="CUPS_FILETYPE=document"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[27]="FINAL_CONTENT_TYPE=application/vnd.cups-raster"
D [03/Sep/2026:16:20:04 +0200] [Job 13] envp[28]="AUTH_I****"
I [03/Sep/2026:16:20:04 +0200] [Job 13] Started filter /usr/lib/cups/filter/universal (PID 7069)
I [03/Sep/2026:16:20:04 +0200] [Job 13] Started filter /usr/lib/cups/filter/rastertocanonij (PID 7070)
I [03/Sep/2026:16:20:04 +0200] [Job 13] Started backend /usr/lib/cups/backend/socket (PID 7071)
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] Returning IPP successful-ok for Print-Job (no URI) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 12] Content-Length: 192
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] Expiring subscriptions...
D [03/Sep/2026:16:20:04 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=192, response=0x55f5a67d15d0(IPP_STATE_IDLE), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] bytes=0, http_state=0, data_remaining=192
D [03/Sep/2026:16:20:04 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] JobCreated
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] PrinterStateChanged
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] JobState
D [03/Sep/2026:16:20:04 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 11] 2.0 Get-Jobs 22
D [03/Sep/2026:16:20:04 +0200] Get-Jobs ipp://anze@localhost:631/printers/
D [03/Sep/2026:16:20:04 +0200] [Client 11] Returning IPP successful-ok for Get-Jobs (ipp://anze@localhost:631/printers/) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 11] Content-Length: 479
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 14] Server address is "/run/cups/cups.sock".
D [03/Sep/2026:16:20:04 +0200] [Client 14] Accepted from localhost (Domain)
D [03/Sep/2026:16:20:04 +0200] [Client 14] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] [Client 12] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 12] 2.0 Get-Printer-Attributes 22
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 12] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 12] Content-Length: 717
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=479, response=0x55f5a6807f60(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] bytes=0, http_state=0, data_remaining=479
D [03/Sep/2026:16:20:04 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 14] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 14] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 14] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 14] 2.0 Get-Printer-Attributes 3
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 14] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 14] Content-Length: 280
D [03/Sep/2026:16:20:04 +0200] [Client 14] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=717, response=0x55f5a680e8b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] bytes=0, http_state=0, data_remaining=717
D [03/Sep/2026:16:20:04 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 11] 2.0 Get-Printer-Attributes 23
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 11] Content-Length: 717
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 14] con->http=0x55f5a67ecd50
D [03/Sep/2026:16:20:04 +0200] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=280, response=0x55f5a67d15d0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 14] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 14] bytes=0, http_state=0, data_remaining=280
D [03/Sep/2026:16:20:04 +0200] [Client 14] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 14] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 14] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=717, response=0x55f5a680baf0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] bytes=0, http_state=0, data_remaining=717
D [03/Sep/2026:16:20:04 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: +connecting-to-device
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] Looking up \"192.168.50.110\"...
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -connecting-to-device
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] 192.168.50.110=192.168.50.110
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] PrinterStateChanged
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] PrinterStateChanged
D [03/Sep/2026:16:20:04 +0200] [Client 12] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 12] 2.0 Get-Printer-Attributes 23
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 12] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 12] Content-Length: 717
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=717, response=0x55f5a681feb0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] bytes=0, http_state=0, data_remaining=717
D [03/Sep/2026:16:20:04 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 11] 2.0 Get-Printer-Attributes 24
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 11] Content-Length: 717
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 12] 2.0 Get-Printer-Attributes 24
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 12] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 12] Content-Length: 717
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=717, response=0x55f5a681fb70(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] bytes=0, http_state=0, data_remaining=717
D [03/Sep/2026:16:20:04 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=717, response=0x55f5a67f83a0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] bytes=0, http_state=0, data_remaining=717
D [03/Sep/2026:16:20:04 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 11] 2.0 Get-Printer-Attributes 25
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 11] Content-Length: 717
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=717, response=0x55f5a67f8730(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] bytes=0, http_state=0, data_remaining=717
D [03/Sep/2026:16:20:04 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] ppdFilterLoadPPD: Color profile qualifier determined from job and PPD data \'rgb.plain.600dpi\'
D [03/Sep/2026:16:20:04 +0200] [Job 13] ppdFilterLoadPPD: No ICC profiles specified in PPD
D [03/Sep/2026:16:20:04 +0200] [Job 13] ppdFilterLoadPPD: Searching for profile \"600dpi/plain\"...
D [03/Sep/2026:16:20:04 +0200] [Job 13] ppdFilterLoadPPD: Last filter determined by the PPD: rastertocanonij; Final data MIME type: application/vnd.cups-raster => PDF filter will not log pages in page_log.
D [03/Sep/2026:16:20:04 +0200] [Job 13] ppdFilterUniversal: \"*cupsFilter(2): ...\" lines in the PPD file:
D [03/Sep/2026:16:20:04 +0200] [Job 13] ppdFilterUniversal:    application/vnd.cups-raw application/octet-stream 0 -
D [03/Sep/2026:16:20:04 +0200] [Job 13] ppdFilterUniversal:    application/vnd.cups-raster 0 rastertocanonij
D [03/Sep/2026:16:20:04 +0200] [Job 13] ppdFilterUniversal: PPD uses \"*cupsFilter: ...\" lines, so we always convert to format given by FINAL_CONTENT_TYPE
D [03/Sep/2026:16:20:04 +0200] [Job 13] ppdFilterUniversal: Converting from application/vnd.cups-pdf-banner to application/vnd.cups-raster
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterUniversal: Converting from application/vnd.cups-pdf-banner to application/vnd.cups-raster
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterUniversal: Final output format for job: application/vnd.cups-raster
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterUniversal: Adding bannertopdf to chain
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterUniversal: Adding pdftopdf to chain
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterUniversal: Adding ghostscript to chain
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterChain: Running filter: bannertopdf
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] Set job-printer-state-message to "cfFilterChain: Running filter: bannertopdf", current level=INFO
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterChain: Running filter: pdftopdf
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] Set job-printer-state-message to "cfFilterChain: Running filter: pdftopdf", current level=INFO
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterChain: Running filter: ghostscript
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] Set job-printer-state-message to "cfFilterChain: Running filter: ghostscript", current level=INFO
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] JobProgress
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] PrinterStateChanged
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterChain: bannertopdf (PID 7075) started.
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] Set job-printer-state-message to "cfFilterChain: bannertopdf (PID 7075) started.", current level=INFO
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 12] 2.0 Get-Printer-Attributes 25
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 12] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 12] Content-Length: 763
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 11] 2.0 Get-Printer-Attributes 26
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 11] Content-Length: 763
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] JobProgress
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] PrinterStateChanged
D [03/Sep/2026:16:20:04 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=763, response=0x55f5a67f9b80(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] bytes=0, http_state=0, data_remaining=763
D [03/Sep/2026:16:20:04 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=763, response=0x55f5a67f9c50(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] bytes=0, http_state=0, data_remaining=763
D [03/Sep/2026:16:20:04 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterBannerToPDF: Copying input to temp file \"/var/spool/cups/tmp/01ba36aa23f19\"
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterChain: pdftopdf (PID 7076) started.
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] Set job-printer-state-message to "cfFilterChain: pdftopdf (PID 7076) started.", current level=INFO
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 12] 2.0 Get-Printer-Attributes 26
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 12] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 12] Content-Length: 760
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 11] 2.0 Get-Printer-Attributes 27
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 11] Content-Length: 760
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterChain: ghostscript (PID 7077) started.
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] Set job-printer-state-message to "cfFilterChain: ghostscript (PID 7077) started.", current level=INFO
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterGhostscript: Output format: CUPS Raster
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterGhostscript: Copying input to temp file \"/var/spool/cups/tmp/01ba56aa24381\"
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] JobProgress
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] PrinterStateChanged
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] JobProgress
D [03/Sep/2026:16:20:04 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=760, response=0x55f5a67fa5a0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] bytes=0, http_state=0, data_remaining=760
D [03/Sep/2026:16:20:04 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=760, response=0x55f5a67fa670(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] bytes=0, http_state=0, data_remaining=760
D [03/Sep/2026:16:20:04 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] PrinterStateChanged
D [03/Sep/2026:16:20:04 +0200] [Job 13] Opening content stream 1/1...
D [03/Sep/2026:16:20:04 +0200] [Job 13] Opened stream 1
D [03/Sep/2026:16:20:04 +0200] [Client 12] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 12] 2.0 Get-Printer-Attributes 27
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 12] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 12] Content-Length: 763
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=763, response=0x55f5a67faac0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] bytes=0, http_state=0, data_remaining=763
D [03/Sep/2026:16:20:04 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 11] 2.0 Get-Printer-Attributes 28
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 11] Content-Length: 763
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterChain: bannertopdf completed with status 0.
D [03/Sep/2026:16:20:04 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=763, response=0x55f5a67fa670(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] bytes=0, http_state=0, data_remaining=763
D [03/Sep/2026:16:20:04 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 12] 2.0 Get-Printer-Attributes 28
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 12] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 12] Content-Length: 763
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=763, response=0x55f5a67faac0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] bytes=0, http_state=0, data_remaining=763
D [03/Sep/2026:16:20:04 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 11] 2.0 Get-Printer-Attributes 29
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 11] Content-Length: 763
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=763, response=0x55f5a67fa670(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] bytes=0, http_state=0, data_remaining=763
D [03/Sep/2026:16:20:04 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] /usr/bin/tocnpwg: unrecognized option \'--uuid\'
D [03/Sep/2026:16:20:04 +0200] [Job 13] /usr/bin/tocnpwg: unrecognized option \'--jobid\'
D [03/Sep/2026:16:20:04 +0200] [Job 13] /usr/bin/tocnpwg: unrecognized option \'--media\'
D [03/Sep/2026:16:20:04 +0200] [Job 13] /usr/bin/tocnpwg: unrecognized option \'--papersize\'
D [03/Sep/2026:16:20:04 +0200] [Job 13] /usr/bin/tocnpwg: unrecognized option \'--filterpath\'
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterChain: pdftopdf completed with status 1.
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterGhostscript: Input is empty, outputting empty file.
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterChain: ghostscript completed with status 0.
D [03/Sep/2026:16:20:04 +0200] [Job 13] ATTR: marker-colors=#000000,none
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(P----)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] ATTR: marker-names=\'\"Canon Black Ink Tank\"\',\'\"Canon Color Ink Tank\"\'
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(P----)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] ATTR: marker-types=ink-cartridge,ink-cartridge
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(P----)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] ATTR: marker-levels=-1,-1
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(P----)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] new_supply_state=0, change_state=ffff
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -developer-low-report
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -developer-empty-warning
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -marker-supply-low-report
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -marker-supply-empty-warning
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -opc-near-eol-report
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -opc-life-over-warning
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -toner-low-report
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -toner-empty-warning
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -waste-receptacle-almost-full-report
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -waste-receptacle-full-warning
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -cleaner-life-almost-over-report
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -cleaner-life-over-warning
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] PrinterStateChanged
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Client 12] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 12] 2.0 Get-Printer-Attributes 29
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 12] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 12] Content-Length: 763
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=763, response=0x55f5a67faf00(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] bytes=0, http_state=0, data_remaining=763
D [03/Sep/2026:16:20:04 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 11] 2.0 Get-Printer-Attributes 30
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 11] Content-Length: 763
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=763, response=0x55f5a67fa470(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] bytes=0, http_state=0, data_remaining=763
D [03/Sep/2026:16:20:04 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] new_state=0, change_state=ffff
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -media-empty-warning
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -door-open-report
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -media-jam-warning
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -input-tray-missing-warning
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -output-tray-missing-warning
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -marker-supply-missing-warning
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -output-area-almost-full-report
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -output-area-full-warning
D [03/Sep/2026:16:20:04 +0200] [Job 13] backendWaitLoop(snmp_fd=5, addr=0x5602318db898, side_cb=0x56022e6f5820)
D [03/Sep/2026:16:20:04 +0200] [Job 13] Connecting to 192.168.50.110:9100
D [03/Sep/2026:16:20:04 +0200] [Job 13] Connecting to printer.
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] Set job-printer-state-message to "Connecting to printer.", current level=INFO
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] JobProgress
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] PrinterStateChanged
D [03/Sep/2026:16:20:04 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 11] 2.0 Get-Printer-Attributes 31
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 11] Content-Length: 739
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=739, response=0x55f5a67fc6d0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] bytes=0, http_state=0, data_remaining=739
D [03/Sep/2026:16:20:04 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 12] 2.0 Get-Printer-Attributes 30
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 12] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 12] Content-Length: 739
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=739, response=0x55f5a67fc360(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] bytes=0, http_state=0, data_remaining=739
D [03/Sep/2026:16:20:04 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] STATE: -connecting-to-device
D [03/Sep/2026:16:20:04 +0200] [Job 13] Connected to printer.
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] Set job-printer-state-message to "Connected to printer.", current level=INFO
D [03/Sep/2026:16:20:04 +0200] [Job 13] Connected to 192.168.50.110:9100...
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] backendRunLoop(print_fd=0, device_fd=6, snmp_fd=5, addr=0x5602318db898, use_bc=1, side_cb=0x56022e6f5820)
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] JobProgress
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] PrinterStateChanged
D [03/Sep/2026:16:20:04 +0200] [Client 12] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 12] 2.0 Get-Printer-Attributes 31
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 12] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 12] Content-Length: 738
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 11] 2.0 Get-Printer-Attributes 32
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 11] Content-Length: 738
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=738, response=0x55f5a67fd3d0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] bytes=0, http_state=0, data_remaining=738
D [03/Sep/2026:16:20:04 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=738, response=0x55f5a67fd4a0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] bytes=0, http_state=0, data_remaining=738
D [03/Sep/2026:16:20:04 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] Read 503 bytes of print data...
D [03/Sep/2026:16:20:04 +0200] [Job 13] ATTR: marker-levels=-1,-1
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(P----)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] new_supply_state=0, change_state=0
D [03/Sep/2026:16:20:04 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Notifier] PrinterStateChanged
D [03/Sep/2026:16:20:04 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:04 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 11] 2.0 Get-Printer-Attributes 33
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 11] Content-Length: 738
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] POST / HTTP/1.1
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:04 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:04 +0200] [Client 12] 2.0 Get-Printer-Attributes 32
D [03/Sep/2026:16:20:04 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:04 +0200] [Client 12] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:04 +0200] [Client 12] Content-Length: 738
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:04 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=738, response=0x55f5a67fcf00(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 11] bytes=0, http_state=0, data_remaining=738
D [03/Sep/2026:16:20:04 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:04 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=738, response=0x55f5a67fdaf0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:04 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:04 +0200] [Client 12] bytes=0, http_state=0, data_remaining=738
D [03/Sep/2026:16:20:04 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:04 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:04 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:04 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:04 +0200] [Job 13] new_state=0, change_state=0
D [03/Sep/2026:16:20:04 +0200] [Job 13] Wrote 503 bytes of print data...
D [03/Sep/2026:16:20:05 +0200] [Job 13] cfFilterChain: bannertopdf (PID 7075) exited with no errors.
D [03/Sep/2026:16:20:05 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Job 13] Set job-printer-state-message to "cfFilterChain: bannertopdf (PID 7075) exited with no errors.", current level=INFO
E [03/Sep/2026:16:20:05 +0200] [Job 13] cfFilterChain: pdftopdf (PID 7076) stopped with status 1
D [03/Sep/2026:16:20:05 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Job 13] Set job-printer-state-message to "cfFilterChain: pdftopdf (PID 7076) stopped with status 1", current level=ERROR
D [03/Sep/2026:16:20:05 +0200] [Job 13] cfFilterChain: ghostscript (PID 7077) exited with no errors.
D [03/Sep/2026:16:20:05 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] Expiring subscriptions...
E [03/Sep/2026:16:20:05 +0200] [Job 13] universal filter failed.
D [03/Sep/2026:16:20:05 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:05 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:05 +0200] [Notifier] JobProgress
D [03/Sep/2026:16:20:05 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:05 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:05 +0200] [Notifier] PrinterStateChanged
D [03/Sep/2026:16:20:05 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:05 +0200] [Notifier] JobProgress
D [03/Sep/2026:16:20:05 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:05 +0200] [Notifier] PrinterStateChanged
D [03/Sep/2026:16:20:05 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:05 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:05 +0200] [Client 11] 2.0 Get-Printer-Attributes 34
D [03/Sep/2026:16:20:05 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:05 +0200] [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:05 +0200] [Client 11] Content-Length: 741
D [03/Sep/2026:16:20:05 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:05 +0200] [Client 12] POST / HTTP/1.1
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:05 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:05 +0200] [Client 12] 2.0 Get-Printer-Attributes 33
D [03/Sep/2026:16:20:05 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:05 +0200] [Client 12] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:05 +0200] [Client 12] Content-Length: 741
D [03/Sep/2026:16:20:05 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:05 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:05 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=741, response=0x55f5a67ff460(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:05 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:05 +0200] [Client 11] bytes=0, http_state=0, data_remaining=741
D [03/Sep/2026:16:20:05 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:05 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:05 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:05 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=741, response=0x55f5a67ff4f0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:05 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:05 +0200] [Client 12] bytes=0, http_state=0, data_remaining=741
D [03/Sep/2026:16:20:05 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:05 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:05 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Job 13] PID 7069 (/usr/lib/cups/filter/universal) stopped with status 1.
D [03/Sep/2026:16:20:05 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:05 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:05 +0200] [Client 11] 2.0 Get-Printer-Attributes 35
D [03/Sep/2026:16:20:05 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:05 +0200] [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:05 +0200] [Client 11] Content-Length: 741
D [03/Sep/2026:16:20:05 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:05 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:05 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=741, response=0x55f5a67ff7c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:05 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:05 +0200] [Client 11] bytes=0, http_state=0, data_remaining=741
D [03/Sep/2026:16:20:05 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:05 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:05 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Client 12] POST / HTTP/1.1
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:05 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:05 +0200] [Client 12] 2.0 Get-Printer-Attributes 34
D [03/Sep/2026:16:20:05 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:05 +0200] [Client 12] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:05 +0200] [Client 12] Content-Length: 741
D [03/Sep/2026:16:20:05 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:05 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:05 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=741, response=0x55f5a67ff4f0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:05 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:05 +0200] [Client 12] bytes=0, http_state=0, data_remaining=741
D [03/Sep/2026:16:20:05 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:05 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:05 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Job 13] getEndJobCommand Successed
D [03/Sep/2026:16:20:05 +0200] [Job 13] Read 263 bytes of print data...
D [03/Sep/2026:16:20:05 +0200] [Job 13] Wrote 263 bytes of print data...
D [03/Sep/2026:16:20:05 +0200] [Job 13] STATE: +cups-waiting-for-job-completed
D [03/Sep/2026:16:20:05 +0200] cupsdMarkDirty(P----)
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Job 13] Waiting for printer to finish.
D [03/Sep/2026:16:20:05 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Job 13] PID 7070 (/usr/lib/cups/filter/rastertocanonij) exited with no errors.
D [03/Sep/2026:16:20:05 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:05 +0200] [Notifier] JobProgress
D [03/Sep/2026:16:20:05 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:05 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:05 +0200] [Notifier] PrinterStateChanged
D [03/Sep/2026:16:20:05 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:05 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:05 +0200] [Client 11] 2.0 Get-Printer-Attributes 36
D [03/Sep/2026:16:20:05 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:05 +0200] [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:05 +0200] [Client 11] Content-Length: 747
D [03/Sep/2026:16:20:05 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:05 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:05 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=747, response=0x55f5a67ffd80(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:05 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:05 +0200] [Client 11] bytes=0, http_state=0, data_remaining=747
D [03/Sep/2026:16:20:05 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:05 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:05 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Client 12] POST / HTTP/1.1
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:05 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:05 +0200] [Client 12] 2.0 Get-Printer-Attributes 35
D [03/Sep/2026:16:20:05 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:05 +0200] [Client 12] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:05 +0200] [Client 12] Content-Length: 747
D [03/Sep/2026:16:20:05 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:05 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:05 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=747, response=0x55f5a67fee10(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:05 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:05 +0200] [Client 12] bytes=0, http_state=0, data_remaining=747
D [03/Sep/2026:16:20:05 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:05 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:05 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Job 13] ATTR: marker-levels=-1,-1
D [03/Sep/2026:16:20:05 +0200] cupsdMarkDirty(P----)
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Job 13] new_supply_state=0, change_state=0
D [03/Sep/2026:16:20:05 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:05 +0200] [Notifier] PrinterStateChanged
D [03/Sep/2026:16:20:05 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:05 +0200] [Client 12] POST / HTTP/1.1
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:05 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:05 +0200] [Client 12] 2.0 Get-Printer-Attributes 36
D [03/Sep/2026:16:20:05 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:05 +0200] [Client 12] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:05 +0200] [Client 12] Content-Length: 747
D [03/Sep/2026:16:20:05 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:05 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:05 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=747, response=0x55f5a6800510(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:05 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:05 +0200] [Client 12] bytes=0, http_state=0, data_remaining=747
D [03/Sep/2026:16:20:05 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:05 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:05 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:05 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:05 +0200] [Client 11] 2.0 Get-Printer-Attributes 37
D [03/Sep/2026:16:20:05 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:05 +0200] [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:05 +0200] [Client 11] Content-Length: 747
D [03/Sep/2026:16:20:05 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:05 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:05 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=747, response=0x55f5a68001a0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:05 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:05 +0200] [Client 11] bytes=0, http_state=0, data_remaining=747
D [03/Sep/2026:16:20:05 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:05 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:05 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Job 13] new_state=0, change_state=0
D [03/Sep/2026:16:20:05 +0200] [Job 13] PID 7071 (/usr/lib/cups/backend/socket) exited with no errors.
D [03/Sep/2026:16:20:05 +0200] cupsdMarkDirty(P----)
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
E [03/Sep/2026:16:20:05 +0200] [Job 13] Job stopped due to filter errors; please consult the /var/log/cups/error_log file for details.
D [03/Sep/2026:16:20:05 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] cupsdMarkDirty(---J-)
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Job 13] The following messages were recorded from 04:20:04 PM to 04:20:05 PM
D [03/Sep/2026:16:20:05 +0200] [Job 13] hrDeviceDesc=\"Canon TS5100 series 3.000\"
D [03/Sep/2026:16:20:05 +0200] [Job 13] prtMarkerColorantValue.1.1 = \"black\"
D [03/Sep/2026:16:20:05 +0200] [Job 13] prtMarkerColorantValue.1.2 = \"color\"
D [03/Sep/2026:16:20:05 +0200] [Job 13] prtMarkerSuppliesLevel.1.1 = -2
D [03/Sep/2026:16:20:05 +0200] [Job 13] prtMarkerSuppliesLevel.1.2 = -2
D [03/Sep/2026:16:20:05 +0200] [Job 13] prtMarkerSuppliesLevel.1.1 = -2
D [03/Sep/2026:16:20:05 +0200] [Job 13] prtMarkerSuppliesLevel.1.2 = -2
D [03/Sep/2026:16:20:05 +0200] [Job 13] End of messages
D [03/Sep/2026:16:20:05 +0200] [Job 13] printer-state=3(idle)
D [03/Sep/2026:16:20:05 +0200] [Job 13] printer-state-message="Waiting for printer to finish."
D [03/Sep/2026:16:20:05 +0200] [Job 13] printer-state-reasons=none
D [03/Sep/2026:16:20:05 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:05 +0200] [Notifier] JobState
D [03/Sep/2026:16:20:05 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:05 +0200] [Notifier] state=3
D [03/Sep/2026:16:20:05 +0200] [Notifier] PrinterStateChanged
D [03/Sep/2026:16:20:05 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:05 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:05 +0200] [Client 11] 2.0 Get-Jobs 38
D [03/Sep/2026:16:20:05 +0200] Get-Jobs ipp://anze@localhost:631/printers/
D [03/Sep/2026:16:20:05 +0200] [Client 11] Returning IPP successful-ok for Get-Jobs (ipp://anze@localhost:631/printers/) from localhost.
D [03/Sep/2026:16:20:05 +0200] [Client 11] Content-Length: 597
D [03/Sep/2026:16:20:05 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:05 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:05 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=597, response=0x55f5a67faac0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:05 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:05 +0200] [Client 11] bytes=0, http_state=0, data_remaining=597
D [03/Sep/2026:16:20:05 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:05 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:05 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Client 12] POST / HTTP/1.1
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [03/Sep/2026:16:20:05 +0200] [Client 12] Read: status=200, state=6
D [03/Sep/2026:16:20:05 +0200] [Client 12] No authentication data provided.
D [03/Sep/2026:16:20:05 +0200] [Client 12] 2.0 Get-Printer-Attributes 37
D [03/Sep/2026:16:20:05 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:05 +0200] [Client 12] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:05 +0200] [Client 12] Content-Length: 747
D [03/Sep/2026:16:20:05 +0200] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:05 +0200] [Client 12] con->http=0x55f5a6811480
D [03/Sep/2026:16:20:05 +0200] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=747, response=0x55f5a67faa40(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:05 +0200] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:05 +0200] [Client 12] bytes=0, http_state=0, data_remaining=747
D [03/Sep/2026:16:20:05 +0200] [Client 12] Flushing write buffer.
D [03/Sep/2026:16:20:05 +0200] [Client 12] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:05 +0200] [Client 12] Waiting for request.
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [03/Sep/2026:16:20:05 +0200] [Client 11] POST / HTTP/1.1
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [03/Sep/2026:16:20:05 +0200] [Client 11] Read: status=200, state=6
D [03/Sep/2026:16:20:05 +0200] [Client 11] No authentication data provided.
D [03/Sep/2026:16:20:05 +0200] [Client 11] 2.0 Get-Printer-Attributes 39
D [03/Sep/2026:16:20:05 +0200] Get-Printer-Attributes ipp://anze@localhost:631/printers/TS5100_series
D [03/Sep/2026:16:20:05 +0200] [Client 11] Returning IPP successful-ok for Get-Printer-Attributes (ipp://anze@localhost:631/printers/TS5100_series) from localhost.
D [03/Sep/2026:16:20:05 +0200] [Client 11] Content-Length: 747
D [03/Sep/2026:16:20:05 +0200] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:05 +0200] [Client 11] con->http=0x55f5a67e0530
D [03/Sep/2026:16:20:05 +0200] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=747, response=0x55f5a67faac0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:05 +0200] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:05 +0200] [Client 11] bytes=0, http_state=0, data_remaining=747
D [03/Sep/2026:16:20:05 +0200] [Client 11] Flushing write buffer.
D [03/Sep/2026:16:20:05 +0200] [Client 11] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:05 +0200] [Client 11] Waiting for request.
D [03/Sep/2026:16:20:05 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [03/Sep/2026:16:20:06 +0200] Expiring subscriptions...
D [03/Sep/2026:16:20:06 +0200] [Job 13] Unloading...
D [03/Sep/2026:16:20:08 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [03/Sep/2026:16:20:08 +0200] [Client 15] Server address is "/run/cups/cups.sock".
D [03/Sep/2026:16:20:08 +0200] [Client 15] Accepted from localhost (Domain)
D [03/Sep/2026:16:20:08 +0200] [Client 15] Waiting for request.
D [03/Sep/2026:16:20:08 +0200] Expiring subscriptions...
D [03/Sep/2026:16:20:08 +0200] [Client 15] POST / HTTP/1.1
D [03/Sep/2026:16:20:08 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [03/Sep/2026:16:20:08 +0200] [Client 15] Read: status=200, state=6
D [03/Sep/2026:16:20:08 +0200] [Client 15] No authentication data provided.
D [03/Sep/2026:16:20:08 +0200] [Client 15] 2.0 CUPS-Get-Printers 1
D [03/Sep/2026:16:20:08 +0200] CUPS-Get-Printers
D [03/Sep/2026:16:20:08 +0200] [Client 15] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [03/Sep/2026:16:20:08 +0200] [Client 15] Content-Length: 747
D [03/Sep/2026:16:20:08 +0200] [Client 15] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:08 +0200] [Client 15] con->http=0x55f5a6820470
D [03/Sep/2026:16:20:08 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=747, response=0x55f5a67fdf40(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:08 +0200] [Client 15] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:08 +0200] [Client 15] bytes=0, http_state=0, data_remaining=747
D [03/Sep/2026:16:20:08 +0200] [Client 15] Flushing write buffer.
D [03/Sep/2026:16:20:08 +0200] [Client 15] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:08 +0200] [Client 15] Waiting for request.
D [03/Sep/2026:16:20:08 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [03/Sep/2026:16:20:08 +0200] [Client 15] POST / HTTP/1.1
D [03/Sep/2026:16:20:08 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [03/Sep/2026:16:20:08 +0200] [Client 15] Read: status=200, state=6
D [03/Sep/2026:16:20:08 +0200] [Client 15] No authentication data provided.
D [03/Sep/2026:16:20:08 +0200] [Client 15] 2.0 Get-Jobs 2
D [03/Sep/2026:16:20:08 +0200] Get-Jobs ipp://anze@localhost:631/printers/
D [03/Sep/2026:16:20:08 +0200] [Job 13] Loading attributes...
D [03/Sep/2026:16:20:08 +0200] [Client 15] Returning IPP successful-ok for Get-Jobs (ipp://anze@localhost:631/printers/) from localhost.
D [03/Sep/2026:16:20:08 +0200] [Client 15] Content-Length: 597
D [03/Sep/2026:16:20:08 +0200] [Client 15] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:08 +0200] [Client 15] con->http=0x55f5a6820470
D [03/Sep/2026:16:20:08 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=597, response=0x55f5a67d9370(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:08 +0200] [Client 15] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:08 +0200] [Client 15] bytes=0, http_state=0, data_remaining=597
D [03/Sep/2026:16:20:08 +0200] [Client 15] Flushing write buffer.
D [03/Sep/2026:16:20:08 +0200] [Client 15] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:08 +0200] [Client 15] Waiting for request.
D [03/Sep/2026:16:20:08 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [03/Sep/2026:16:20:08 +0200] [Client 15] POST / HTTP/1.1
D [03/Sep/2026:16:20:08 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [03/Sep/2026:16:20:08 +0200] [Client 15] Read: status=200, state=6
D [03/Sep/2026:16:20:08 +0200] [Client 15] No authentication data provided.
D [03/Sep/2026:16:20:08 +0200] [Client 15] 2.0 Create-Printer-Subscriptions 3
D [03/Sep/2026:16:20:08 +0200] Create-Printer-Subscriptions ipp://localhost/
D [03/Sep/2026:16:20:08 +0200] create_subscriptions(con=0x55f5a6800a70(15), uri="ipp://localhost/")
D [03/Sep/2026:16:20:08 +0200] recipient="dbus://"
D [03/Sep/2026:16:20:08 +0200] notify-lease-duration=3600
D [03/Sep/2026:16:20:08 +0200] notify-time-interval=0
D [03/Sep/2026:16:20:08 +0200] cupsdAddSubscription(mask=1ffb8f, dest=(nil)(), job=(nil)(0), uri="dbus://")
D [03/Sep/2026:16:20:08 +0200] Added subscription #46 for server.
D [03/Sep/2026:16:20:08 +0200] cupsdMarkDirty(----S)
D [03/Sep/2026:16:20:08 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [03/Sep/2026:16:20:08 +0200] [Client 15] Returning IPP successful-ok for Create-Printer-Subscriptions (ipp://localhost/) from localhost.
D [03/Sep/2026:16:20:08 +0200] [Client 15] Content-Length: 583
D [03/Sep/2026:16:20:08 +0200] [Client 15] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [03/Sep/2026:16:20:08 +0200] [Client 15] con->http=0x55f5a6820470
D [03/Sep/2026:16:20:08 +0200] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=583, response=0x55f5a67fdf40(IPP_STATE_DATA), pipe_pid=0, file=-1
D [03/Sep/2026:16:20:08 +0200] [Client 15] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [03/Sep/2026:16:20:08 +0200] [Client 15] bytes=0, http_state=0, data_remaining=583
D [03/Sep/2026:16:20:08 +0200] [Client 15] Flushing write buffer.
D [03/Sep/2026:16:20:08 +0200] [Client 15] New state is HTTP_STATE_WAITING
D [03/Sep/2026:16:20:08 +0200] [Client 15] Waiting for request.
D [03/Sep/2026:16:20:08 +0200] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [03/Sep/2026:16:20:09 +0200] Expiring subscriptions...

seth · 2026-09-03 14:44:32

A lot of the output is from the cups http daemon ( http://localhost:631 ) - did you try to print the testpage from there?
You care about the "[Job 13]" lines which has

D [03/Sep/2026:16:20:04 +0200] [Job 13] /usr/bin/tocnpwg: unrecognized option \'--uuid\'
D [03/Sep/2026:16:20:04 +0200] [Job 13] /usr/bin/tocnpwg: unrecognized option \'--jobid\'
D [03/Sep/2026:16:20:04 +0200] [Job 13] /usr/bin/tocnpwg: unrecognized option \'--media\'
D [03/Sep/2026:16:20:04 +0200] [Job 13] /usr/bin/tocnpwg: unrecognized option \'--papersize\'
D [03/Sep/2026:16:20:04 +0200] [Job 13] /usr/bin/tocnpwg: unrecognized option \'--filterpath\'
D [03/Sep/2026:16:20:04 +0200] [Job 13] cfFilterChain: pdftopdf completed with status 1.

I suspect

pacman -Qo tocnpwg

is from your AUR printer driver?

seth wrote:

For IPPe:
nmap 192.168.50.110

If you can use IPPe you can side-step the problems w/ badly maintained drivers.

https://www.canon.de/printers/pixma-ts5 … fications/ says AirPrint (which is IPPe, just cooler name) is supported but on some printers you've to activate that.
You'll also need https://archlinux.org/packages/extra/x86_64/cups-pdf/

rossboulet · 2026-09-03 20:56:39

I had the same issue on at least two machines, one of which had been printing with IPP all along. I did a "systemctl status cups" and saw a crash that seemed to implicate libcupsfilters. Sure enough libcupsfilters had recently been upgraded on the systems in question. I downgraded libcupsfilters from 2.2.1-2 to 2.1.1-4 and printing works again. I was failing on the test page in pdftopdf and attempting to print a text file failed in texttopdf.

Edited: corrected 2.2.1-4 --> 2.2.1-2

Last edited by rossboulet (2026-09-04 06:56:44)

seth · 2026-09-03 21:25:54

Oh, thanks for the heads up.
They completely rewrote that filter…
https://github.com/OpenPrinting/libcups … 5463504959

Arch already applies a downstream patch, https://gitlab.archlinux.org/archlinux/ … 1766c769d1 but there're obviously bigger problems.

@AtaSmrk007 see https://wiki.archlinux.org/title/Arch_Linux_Archive on how to downgrade packages.

AtaSmrk007 · 2026-09-04 07:13:33

Thank you very much for your help. Downgrading the libcupsfilters package fixed the problem.

rossboulet · 2026-09-04 21:07:00

Well, I wouldn't really call it solved. Your immediate problem is solved, but there is still and outstanding issue with the current version of libcupsfilters. I will open a new topic to make sure this gets addressed.

lomov_vl · 2026-09-05 03:33:17

I'm experiencing the same problem here with HP LaserJet MFP 125nrw; downgrading libcupsfilters to 2.1.1-4 helped.

resurtm · Yesterday 11:07:12

Confirmed issue and temporary fix with Brother DCP-J1200W + IPP Everywhere. Stable Arch, AUR not used.

Last edited by resurtm (Yesterday 11:09:18)

Arch Linux

#1 2026-09-02 18:38:47

[SOLVED] Printing test page fails

#2 2026-09-02 20:53:20

Re: [SOLVED] Printing test page fails

#3 2026-09-03 14:22:00

Re: [SOLVED] Printing test page fails

#4 2026-09-03 14:44:32

Re: [SOLVED] Printing test page fails

#5 2026-09-03 20:56:39

Re: [SOLVED] Printing test page fails

#6 2026-09-03 21:25:54

Re: [SOLVED] Printing test page fails

#7 2026-09-04 07:13:33

Re: [SOLVED] Printing test page fails

#8 2026-09-04 21:07:00

Re: [SOLVED] Printing test page fails

#9 2026-09-05 03:33:17

Re: [SOLVED] Printing test page fails

#10 Yesterday 11:07:12

Re: [SOLVED] Printing test page fails

Board footer