D [02/Mar/2022:10:19:14 +0100] [Client 13] Flushing write buffer. D [02/Mar/2022:10:19:14 +0100] [Client 13] New state is HTTP_STATE_WAITING D [02/Mar/2022:10:19:14 +0100] [Client 13] Waiting for request. D [02/Mar/2022:10:19:14 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [02/Mar/2022:10:19:14 +0100] [Client 13] Closing on EOF. D [02/Mar/2022:10:19:14 +0100] [Client 13] Closing connection. D [02/Mar/2022:10:19:14 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [02/Mar/2022:10:19:24 +0100] [Client 14] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [02/Mar/2022:10:19:24 +0100] [Client 14] Closing connection. D [02/Mar/2022:10:19:24 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [02/Mar/2022:10:23:22 +0100] [Client 16] Server address is "/run/cups/cups.sock". D [02/Mar/2022:10:23:22 +0100] [Client 16] Accepted from localhost (Domain) D [02/Mar/2022:10:23:22 +0100] [Client 16] Waiting for request. D [02/Mar/2022:10:23:22 +0100] cupsdAddCert: Adding certificate for PID 0 D [02/Mar/2022:10:23:22 +0100] Report: clients=1 D [02/Mar/2022:10:23:22 +0100] Report: jobs=6 D [02/Mar/2022:10:23:22 +0100] Report: jobs-active=0 D [02/Mar/2022:10:23:22 +0100] Report: printers=5 D [02/Mar/2022:10:23:22 +0100] Report: stringpool-string-count=2258 D [02/Mar/2022:10:23:22 +0100] Report: stringpool-alloc-bytes=12784 D [02/Mar/2022:10:23:22 +0100] Report: stringpool-total-bytes=45904 D [02/Mar/2022:10:23:22 +0100] [Client 16] POST / HTTP/1.1 D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Mar/2022:10:23:22 +0100] [Client 16] Read: status=200, state=6 D [02/Mar/2022:10:23:22 +0100] [Client 16] No authentication data provided. D [02/Mar/2022:10:23:22 +0100] [Client 16] 2.0 Get-Printer-Attributes 1 D [02/Mar/2022:10:23:22 +0100] Get-Printer-Attributes ipp://localhost:631/printers/HomePrinterIPPS D [02/Mar/2022:10:23:22 +0100] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/HomePrinterIPPS) from localhost. D [02/Mar/2022:10:23:22 +0100] [Client 16] Content-Length: 1026 D [02/Mar/2022:10:23:22 +0100] [Client 16] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Mar/2022:10:23:22 +0100] [Client 16] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:22 +0100] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1026, response=0x55bc39e87a50(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Mar/2022:10:23:22 +0100] [Client 16] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Mar/2022:10:23:22 +0100] [Client 16] bytes=0, http_state=0, data_remaining=1026 D [02/Mar/2022:10:23:22 +0100] [Client 16] Flushing write buffer. D [02/Mar/2022:10:23:22 +0100] [Client 16] New state is HTTP_STATE_WAITING D [02/Mar/2022:10:23:22 +0100] [Client 16] Waiting for request. D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [02/Mar/2022:10:23:22 +0100] [Client 16] POST / HTTP/1.1 D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [02/Mar/2022:10:23:22 +0100] [Client 16] Read: status=200, state=6 D [02/Mar/2022:10:23:22 +0100] [Client 16] No authentication data provided. D [02/Mar/2022:10:23:22 +0100] [Client 16] 2.0 Get-Printer-Attributes 2 D [02/Mar/2022:10:23:22 +0100] Get-Printer-Attributes ipp://localhost:631/printers/HomePrinterIPPS D [02/Mar/2022:10:23:22 +0100] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/HomePrinterIPPS) from localhost. D [02/Mar/2022:10:23:22 +0100] [Client 16] Content-Length: 1026 D [02/Mar/2022:10:23:22 +0100] [Client 16] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Mar/2022:10:23:22 +0100] [Client 16] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:22 +0100] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1026, response=0x55bc39e84810(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Mar/2022:10:23:22 +0100] [Client 16] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Mar/2022:10:23:22 +0100] [Client 16] bytes=0, http_state=0, data_remaining=1026 D [02/Mar/2022:10:23:22 +0100] [Client 16] Flushing write buffer. D [02/Mar/2022:10:23:22 +0100] [Client 16] New state is HTTP_STATE_WAITING D [02/Mar/2022:10:23:22 +0100] [Client 16] Waiting for request. D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [02/Mar/2022:10:23:22 +0100] [Client 16] POST /printers/HomePrinterIPPS HTTP/1.1 D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [02/Mar/2022:10:23:22 +0100] [Client 16] Read: status=200, state=6 D [02/Mar/2022:10:23:22 +0100] [Client 16] No authentication data provided. D [02/Mar/2022:10:23:22 +0100] [Client 16] 2.0 Get-Printer-Attributes 3 D [02/Mar/2022:10:23:22 +0100] Get-Printer-Attributes ipp://localhost/printers/HomePrinterIPPS D [02/Mar/2022:10:23:22 +0100] [Client 16] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HomePrinterIPPS) from localhost. D [02/Mar/2022:10:23:22 +0100] [Client 16] Content-Length: 7570 D [02/Mar/2022:10:23:22 +0100] [Client 16] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Mar/2022:10:23:22 +0100] [Client 16] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:22 +0100] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7570, response=0x55bc39d5da40(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Mar/2022:10:23:22 +0100] [Client 16] Writing IPP response, ipp_state=IPP_STATE_ATTRIBUTE, old wused=0, new wused=0 D [02/Mar/2022:10:23:22 +0100] [Client 16] bytes=1, http_state=7, data_remaining=7570 D [02/Mar/2022:10:23:22 +0100] [Client 16] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:22 +0100] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7570, response=0x55bc39d5da40(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Mar/2022:10:23:22 +0100] [Client 16] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Mar/2022:10:23:22 +0100] [Client 16] bytes=0, http_state=0, data_remaining=7570 D [02/Mar/2022:10:23:22 +0100] [Client 16] Flushing write buffer. D [02/Mar/2022:10:23:22 +0100] [Client 16] New state is HTTP_STATE_WAITING D [02/Mar/2022:10:23:22 +0100] [Client 16] Waiting for request. D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [02/Mar/2022:10:23:22 +0100] [Client 16] POST /printers/HomePrinterIPPS HTTP/1.1 D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [02/Mar/2022:10:23:22 +0100] [Client 16] Read: status=200, state=6 D [02/Mar/2022:10:23:22 +0100] [Client 16] No authentication data provided. D [02/Mar/2022:10:23:22 +0100] [Client 16] 2.0 Create-Job 4 D [02/Mar/2022:10:23:22 +0100] Create-Job ipp://localhost/printers/HomePrinterIPPS D [02/Mar/2022:10:23:22 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients" D [02/Mar/2022:10:23:22 +0100] [Job 16] Applying default options... D [02/Mar/2022:10:23:22 +0100] add_job: requesting-user-name="arvid" I [02/Mar/2022:10:23:22 +0100] [Job 16] Adding start banner page "none". D [02/Mar/2022:10:23:22 +0100] Discarding unused job-created event... I [02/Mar/2022:10:23:22 +0100] [Job 16] Queued on "HomePrinterIPPS" by "arvid". D [02/Mar/2022:10:23:22 +0100] [Client 16] Returning IPP successful-ok for Create-Job (ipp://localhost/printers/HomePrinterIPPS) from localhost. D [02/Mar/2022:10:23:22 +0100] [Client 16] Content-Length: 200 D [02/Mar/2022:10:23:22 +0100] [Client 16] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Mar/2022:10:23:22 +0100] [Client 16] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:22 +0100] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=200, response=0x55bc39e878f0(IPP_STATE_IDLE), pipe_pid=0, file=-1 D [02/Mar/2022:10:23:22 +0100] [Client 16] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Mar/2022:10:23:22 +0100] [Client 16] bytes=0, http_state=0, data_remaining=200 D [02/Mar/2022:10:23:22 +0100] [Client 16] Flushing write buffer. D [02/Mar/2022:10:23:22 +0100] [Client 16] New state is HTTP_STATE_WAITING D [02/Mar/2022:10:23:22 +0100] [Client 16] Waiting for request. D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [02/Mar/2022:10:23:22 +0100] [Client 16] POST /printers/HomePrinterIPPS HTTP/1.1 D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [02/Mar/2022:10:23:22 +0100] [Client 16] Read: status=200, state=6 D [02/Mar/2022:10:23:22 +0100] [Client 16] No authentication data provided. D [02/Mar/2022:10:23:22 +0100] [Client 16] 2.0 Send-Document 5 D [02/Mar/2022:10:23:22 +0100] [Client 16] Read: status=100, state=6 D [02/Mar/2022:10:23:22 +0100] [Client 16] Read: status=100, state=6 D [02/Mar/2022:10:23:22 +0100] [Client 16] Read: status=100, state=6 D [02/Mar/2022:10:23:22 +0100] [Client 16] Read: status=100, state=6 D [02/Mar/2022:10:23:22 +0100] [Client 16] Read: status=100, state=6 D [02/Mar/2022:10:23:22 +0100] [Client 16] Read: status=100, state=6 D [02/Mar/2022:10:23:22 +0100] [Client 16] Read: status=100, state=6 D [02/Mar/2022:10:23:22 +0100] Send-Document ipp://localhost:631/printers/HomePrinterIPPS D [02/Mar/2022:10:23:22 +0100] cupsdIsAuthorized: requesting-user-name="arvid" D [02/Mar/2022:10:23:22 +0100] [Job 16] Auto-typing file... D [02/Mar/2022:10:23:22 +0100] [Job 16] Request file type is application/pdf. D [02/Mar/2022:10:23:22 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [02/Mar/2022:10:23:22 +0100] [Job 16] File of type application/pdf queued by "arvid". I [02/Mar/2022:10:23:22 +0100] [Job 16] Adding end banner page "none". D [02/Mar/2022:10:23:22 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Mar/2022:10:23:22 +0100] [Job 16] time-at-processing=1646213002 D [02/Mar/2022:10:23:22 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Mar/2022:10:23:22 +0100] Discarding unused printer-state-changed event... D [02/Mar/2022:10:23:22 +0100] [Job 16] Sending job to queue tagged as raw... D [02/Mar/2022:10:23:22 +0100] [Job 16] job-sheets=none,none D [02/Mar/2022:10:23:22 +0100] [Job 16] argv[0]="HomePrinterIPPS" D [02/Mar/2022:10:23:22 +0100] [Job 16] argv[1]="16" D [02/Mar/2022:10:23:22 +0100] [Job 16] argv[2]="arvid" D [02/Mar/2022:10:23:22 +0100] [Job 16] argv[3]="dummy.pdf" D [02/Mar/2022:10:23:22 +0100] [Job 16] argv[4]="1" D [02/Mar/2022:10:23:22 +0100] [Job 16] argv[5]="finishings=3 number-up=1 page-ranges=1 print-color-mode=monochrome job-uuid=urn:uuid:2ce0b39e-4f88-312c-439e-82a57cf85a21 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1646213002 time-at-processing=1646213002 document-name-supplied=dummy.pdf" D [02/Mar/2022:10:23:22 +0100] [Job 16] argv[6]="/var/spool/cups/d00016-001" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[1]="CUPS_DATADIR=/usr/share/cups" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[3]="CUPS_REQUESTROOT=/var/spool/cups" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[4]="CUPS_SERVERBIN=/usr/lib/cups" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[5]="CUPS_SERVERROOT=/etc/cups" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[6]="CUPS_STATEDIR=/run/cups" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[7]="HOME=/var/spool/cups/tmp" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[9]="SERVER_ADMIN=root@athena" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[10]="SOFTWARE=CUPS/2.4.1" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[11]="TMPDIR=/var/spool/cups/tmp" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[12]="USER=root" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[13]="CUPS_MAX_MESSAGE=2047" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[14]="CUPS_SERVER=/run/cups/cups.sock" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[15]="CUPS_ENCRYPTION=IfRequested" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[16]="IPP_PORT=631" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[17]="CHARSET=utf-8" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[18]="LANG=en_GB.UTF-8" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[19]="PPD=/etc/cups/ppd/HomePrinterIPPS.ppd" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[20]="CONTENT_TYPE=application/pdf" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[21]="DEVICE_URI=ipps://HP%20Color%20LaserJet%20MFP%20M281fdw%20(Home)._ipps._tcp.local/" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[22]="PRINTER_INFO=HP ColorLaserJet MFP M278-M281" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[23]="PRINTER_LOCATION=" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[24]="PRINTER=HomePrinterIPPS" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[25]="PRINTER_STATE_REASONS=none" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[26]="CUPS_FILETYPE=document" D [02/Mar/2022:10:23:22 +0100] [Job 16] envp[27]="AUTH_I****" I [02/Mar/2022:10:23:22 +0100] [Job 16] Started backend /usr/lib/cups/backend/ipps (PID 51867) D [02/Mar/2022:10:23:22 +0100] Discarding unused job-state-changed event... D [02/Mar/2022:10:23:22 +0100] [Client 16] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/HomePrinterIPPS) from localhost. D [02/Mar/2022:10:23:22 +0100] [Client 16] Content-Length: 170 D [02/Mar/2022:10:23:22 +0100] [Client 16] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Mar/2022:10:23:22 +0100] [Client 16] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:22 +0100] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=170, response=0x55bc39e84d30(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Mar/2022:10:23:22 +0100] [Client 16] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Mar/2022:10:23:22 +0100] [Client 16] bytes=0, http_state=0, data_remaining=170 D [02/Mar/2022:10:23:22 +0100] [Client 16] Flushing write buffer. D [02/Mar/2022:10:23:22 +0100] [Client 16] New state is HTTP_STATE_WAITING D [02/Mar/2022:10:23:22 +0100] [Client 16] Waiting for request. D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files" D [02/Mar/2022:10:23:22 +0100] [Client 16] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [02/Mar/2022:10:23:22 +0100] [Client 16] Closing connection. D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:22 +0100] [Job 16] Resolving \"HP Color LaserJet MFP M281fdw (Home)._ipps._tcp.local\"... D [02/Mar/2022:10:23:22 +0100] [Job 16] STATE: +connecting-to-device D [02/Mar/2022:10:23:22 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:22 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:22 +0100] [Job 16] Resolving \"HP Color LaserJet MFP M281fdw (Home)\", regtype=\"_ipps._tcp\", domain=\"local.\"... D [02/Mar/2022:10:23:22 +0100] Discarding unused printer-state-changed event... D [02/Mar/2022:10:23:23 +0100] [Job 16] Resolved as \"ipps://Printer.local:631/ipp/print\"... D [02/Mar/2022:10:23:23 +0100] [Job 16] STATE: -connecting-to-device,offline-report D [02/Mar/2022:10:23:23 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files" D [02/Mar/2022:10:23:23 +0100] [Job 16] 1 files to send in job... D [02/Mar/2022:10:23:23 +0100] [Job 16] STATE: +connecting-to-device D [02/Mar/2022:10:23:23 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:23 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:23 +0100] [Job 16] Looking up \"Printer.local\"... D [02/Mar/2022:10:23:23 +0100] Discarding unused printer-state-changed event... D [02/Mar/2022:10:23:23 +0100] [Job 16] STATE: -connecting-to-device D [02/Mar/2022:10:23:23 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files" D [02/Mar/2022:10:23:23 +0100] [Job 16] Printer.local=[v1.fe80::9e7b:efff:fe81:a44a+wlan0] D [02/Mar/2022:10:23:23 +0100] [Job 16] Printer.local=192.168.2.12 D [02/Mar/2022:10:23:23 +0100] Discarding unused printer-state-changed event... D [02/Mar/2022:10:23:23 +0100] [Job 16] Connecting to Printer.local:631 D [02/Mar/2022:10:23:23 +0100] [Job 16] Connecting to printer. D [02/Mar/2022:10:23:23 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:23 +0100] [Job 16] Set job-printer-state-message to "Connecting to printer.", current level=INFO D [02/Mar/2022:10:23:23 +0100] Discarding unused job-progress event... D [02/Mar/2022:10:23:23 +0100] Discarding unused printer-state-changed event... D [02/Mar/2022:10:23:23 +0100] [Job 16] update_reasons(attr=0(), s=\"-cups-certificate-error\") D [02/Mar/2022:10:23:23 +0100] [Job 16] Connection is encrypted. D [02/Mar/2022:10:23:23 +0100] [Job 16] Credentials are OK/trusted ((null)) D [02/Mar/2022:10:23:23 +0100] [Job 16] Printer credentials: NPI81A44A (issued by HP) / Tue, 01 Oct 2030 00:00:00 GMT / RSA-SHA256 / 4745EF8EFAB045CB6E281602341CD1A8 D [02/Mar/2022:10:23:23 +0100] [Job 16] Stored credentials: NPI81A44A (issued by HP) / Tue, 01 Oct 2030 00:00:00 GMT / RSA-SHA256 / 4745EF8EFAB045CB6E281602341CD1A8 D [02/Mar/2022:10:23:23 +0100] [Job 16] update_reasons(attr=0(), s=\"-cups-pki-invalid,cups-pki-changed,cups-pki-expired,cups-pki-unknown\") D [02/Mar/2022:10:23:23 +0100] [Job 16] update_reasons(attr=0(), s=\"-connecting-to-device\") D [02/Mar/2022:10:23:23 +0100] [Job 16] Connected to printer. D [02/Mar/2022:10:23:23 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:23 +0100] [Job 16] Set job-printer-state-message to "Connected to printer.", current level=INFO D [02/Mar/2022:10:23:23 +0100] Discarding unused job-progress event... D [02/Mar/2022:10:23:23 +0100] Discarding unused printer-state-changed event... D [02/Mar/2022:10:23:23 +0100] [Job 16] Connected to [v1.fe80::9e7b:efff:fe81:a44a+wlan0]:631... D [02/Mar/2022:10:23:23 +0100] [Job 16] Getting supported attributes... D [02/Mar/2022:10:23:24 +0100] [Job 16] Get-Printer-Attributes: successful-ok (successful-ok) D [02/Mar/2022:10:23:24 +0100] [Job 16] copies-supported=1-999 D [02/Mar/2022:10:23:24 +0100] [Job 16] document-format-supported (8 values) D [02/Mar/2022:10:23:24 +0100] [Job 16] [0] = \"image/urf\" D [02/Mar/2022:10:23:24 +0100] [Job 16] [1] = \"application/PCLm\" D [02/Mar/2022:10:23:24 +0100] [Job 16] [2] = \"application/octet-stream\" D [02/Mar/2022:10:23:24 +0100] [Job 16] [3] = \"application/pdf\" D [02/Mar/2022:10:23:24 +0100] [Job 16] [4] = \"application/postscript\" D [02/Mar/2022:10:23:24 +0100] [Job 16] [5] = \"application/vnd.hp-PCL\" D [02/Mar/2022:10:23:24 +0100] [Job 16] [6] = \"application/vnd.hp-PCLXL\" D [02/Mar/2022:10:23:24 +0100] [Job 16] [7] = \"image/jpeg\" D [02/Mar/2022:10:23:24 +0100] [Job 16] media-col-supported (9 values) D [02/Mar/2022:10:23:24 +0100] [Job 16] [0] = \"media-size\" D [02/Mar/2022:10:23:24 +0100] [Job 16] [1] = \"media-top-margin\" D [02/Mar/2022:10:23:24 +0100] [Job 16] [2] = \"media-left-margin\" D [02/Mar/2022:10:23:24 +0100] [Job 16] [3] = \"media-right-margin\" D [02/Mar/2022:10:23:24 +0100] [Job 16] [4] = \"media-bottom-margin\" D [02/Mar/2022:10:23:24 +0100] [Job 16] [5] = \"media-type\" D [02/Mar/2022:10:23:24 +0100] [Job 16] [6] = \"media-source\" D [02/Mar/2022:10:23:24 +0100] [Job 16] [7] = \"media-source-properties\" D [02/Mar/2022:10:23:24 +0100] [Job 16] [8] = \"duplex-supported\" D [02/Mar/2022:10:23:24 +0100] [Job 16] operations-supported (12 values) D [02/Mar/2022:10:23:24 +0100] [Job 16] [0] = Print-Job D [02/Mar/2022:10:23:24 +0100] [Job 16] [1] = Print-URI D [02/Mar/2022:10:23:24 +0100] [Job 16] [2] = Validate-Job D [02/Mar/2022:10:23:24 +0100] [Job 16] [3] = Create-Job D [02/Mar/2022:10:23:24 +0100] [Job 16] [4] = Send-Document D [02/Mar/2022:10:23:24 +0100] [Job 16] [5] = Send-URI D [02/Mar/2022:10:23:24 +0100] [Job 16] [6] = Close-Job D [02/Mar/2022:10:23:24 +0100] [Job 16] [7] = Cancel-Job D [02/Mar/2022:10:23:24 +0100] [Job 16] [8] = Get-Job-Attributes D [02/Mar/2022:10:23:24 +0100] [Job 16] [9] = Get-Jobs D [02/Mar/2022:10:23:24 +0100] [Job 16] [10] = Get-Printer-Attributes D [02/Mar/2022:10:23:24 +0100] [Job 16] [11] = Identify-Printer D [02/Mar/2022:10:23:24 +0100] [Job 16] Set job-printer-state-message to "Connected to printer.", current level=INFO D [02/Mar/2022:10:23:24 +0100] [Job 16] update_reasons(attr=1(wifi-not-configured-report), s=\"(null)\") D [02/Mar/2022:10:23:24 +0100] [Job 16] STATE: +wifi-not-configured-report D [02/Mar/2022:10:23:24 +0100] cupsdMarkDirty(P----) D [02/Mar/2022:10:23:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:24 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:24 +0100] [Job 16] STATE: -none D [02/Mar/2022:10:23:24 +0100] [Job 16] ATTR: marker-colors=\'\"#00FFFF\"\',\'\"#FF00FF\"\',\'\"#FFFF00\"\',\'\"#000000\"\' D [02/Mar/2022:10:23:24 +0100] cupsdMarkDirty(P----) D [02/Mar/2022:10:23:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:24 +0100] [Job 16] ATTR: marker-high-levels=100,100,100,100 D [02/Mar/2022:10:23:24 +0100] cupsdMarkDirty(P----) D [02/Mar/2022:10:23:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:24 +0100] [Job 16] ATTR: marker-levels=23,31,28,27 D [02/Mar/2022:10:23:24 +0100] cupsdMarkDirty(P----) D [02/Mar/2022:10:23:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:24 +0100] [Job 16] ATTR: marker-low-levels=10,10,10,10 D [02/Mar/2022:10:23:24 +0100] cupsdMarkDirty(P----) D [02/Mar/2022:10:23:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:24 +0100] [Job 16] ATTR: marker-names=\'\"Cyan Cartridge HP CF541A\"\',\'\"Magenta Cartridge HP CF543A\"\',\'\"Yellow Cartridge HP CF542A\"\',\'\"Black Cartridge HP CF540A\"\' D [02/Mar/2022:10:23:24 +0100] cupsdMarkDirty(P----) D [02/Mar/2022:10:23:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:24 +0100] [Job 16] ATTR: marker-types=\'\"toner\"\',\'\"toner\"\',\'\"toner\"\',\'\"toner\"\' D [02/Mar/2022:10:23:24 +0100] cupsdMarkDirty(P----) D [02/Mar/2022:10:23:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:24 +0100] [Job 16] final_content_type=\"application/pdf\", document_format=\"application/pdf\" D [02/Mar/2022:10:23:24 +0100] [Job 16] retryable=0 D [02/Mar/2022:10:23:24 +0100] [Job 16] Validate-Job IPP/2.0 D [02/Mar/2022:10:23:24 +0100] [Job 16] printer-uri=\"ipps://Printer.local:631/ipp/print\" D [02/Mar/2022:10:23:24 +0100] [Job 16] requesting-user-name=\"arvid\" D [02/Mar/2022:10:23:24 +0100] [Job 16] job-name=\"dummy.pdf\" D [02/Mar/2022:10:23:24 +0100] [Job 16] document-format=\"application/pdf\" D [02/Mar/2022:10:23:24 +0100] [Job 16] Adding all operation/job attributes. D [02/Mar/2022:10:23:24 +0100] [Job 16] IPP/2.0 Validate-Job #2 D [02/Mar/2022:10:23:24 +0100] [Job 16] ---- operation-attributes-tag ---- D [02/Mar/2022:10:23:24 +0100] [Job 16] attributes-charset charset utf-8 D [02/Mar/2022:10:23:24 +0100] [Job 16] attributes-natural-language naturalLanguage en-gb D [02/Mar/2022:10:23:24 +0100] [Job 16] printer-uri uri ipps://Printer.local:631/ipp/print D [02/Mar/2022:10:23:24 +0100] [Job 16] requesting-user-name nameWithoutLanguage arvid D [02/Mar/2022:10:23:24 +0100] [Job 16] job-name nameWithoutLanguage dummy.pdf D [02/Mar/2022:10:23:24 +0100] [Job 16] document-format mimeMediaType application/pdf D [02/Mar/2022:10:23:24 +0100] [Job 16] ---- job-attributes-tag ---- D [02/Mar/2022:10:23:24 +0100] [Job 16] document-name-supplied nameWithoutLanguage dummy.pdf D [02/Mar/2022:10:23:24 +0100] [Job 16] finishings enum none D [02/Mar/2022:10:23:24 +0100] [Job 16] job-originating-host-name nameWithoutLanguage localhost D [02/Mar/2022:10:23:24 +0100] [Job 16] job-uuid uri urn:uuid:2ce0b39e-4f88-312c-439e-82a57cf85a21 D [02/Mar/2022:10:23:24 +0100] [Job 16] number-up integer 1 D [02/Mar/2022:10:23:24 +0100] [Job 16] page-ranges rangeOfInteger 1-1 D [02/Mar/2022:10:23:24 +0100] [Job 16] print-color-mode keyword monochrome D [02/Mar/2022:10:23:24 +0100] [Job 16] ---- end-of-attributes-tag ---- D [02/Mar/2022:10:23:24 +0100] Discarding unused job-progress event... D [02/Mar/2022:10:23:24 +0100] Discarding unused printer-state-changed event... D [02/Mar/2022:10:23:25 +0100] [Job 16] IPP/2.0 Get-Printer-Attributes #3 D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- operation-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-charset charset utf-8 D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-natural-language naturalLanguage en-gb D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-uri uri ipps://Printer.local:631/ipp/print D [02/Mar/2022:10:23:25 +0100] [Job 16] requesting-user-name nameWithoutLanguage arvid D [02/Mar/2022:10:23:25 +0100] [Job 16] requested-attributes 1setOf keyword compression-supported,copies-supported,cups-version,document-format-supported,job-password-encryption-supported,marker-colors,marker-high-levels,marker-levels,marker-low-levels,marker-message,marker-names,marker-types,media-col-supported,multiple-document-handling-supported,operations-supported,print-color-mode-supported,printer-alert,printer-alert-description,printer-is-accepting-jobs,printer-mandatory-job-attributes,printer-state,printer-state-message,printer-state-reasons D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- end-of-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] Validate-Job: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes) D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- operation-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-charset charset utf-8 D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-natural-language naturalLanguage en D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- unsupported-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] number-up unsupported unsupported D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- end-of-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] Create-Job IPP/2.0 D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-uri=\"ipps://Printer.local:631/ipp/print\" D [02/Mar/2022:10:23:25 +0100] [Job 16] requesting-user-name=\"arvid\" D [02/Mar/2022:10:23:25 +0100] [Job 16] job-name=\"dummy.pdf\" D [02/Mar/2022:10:23:25 +0100] [Job 16] Adding all operation/job attributes. D [02/Mar/2022:10:23:25 +0100] [Job 16] IPP/2.0 Create-Job #4 D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- operation-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-charset charset utf-8 D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-natural-language naturalLanguage en-gb D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-uri uri ipps://Printer.local:631/ipp/print D [02/Mar/2022:10:23:25 +0100] [Job 16] requesting-user-name nameWithoutLanguage arvid D [02/Mar/2022:10:23:25 +0100] [Job 16] job-name nameWithoutLanguage dummy.pdf D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- job-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] document-name-supplied nameWithoutLanguage dummy.pdf D [02/Mar/2022:10:23:25 +0100] [Job 16] finishings enum none D [02/Mar/2022:10:23:25 +0100] [Job 16] job-originating-host-name nameWithoutLanguage localhost D [02/Mar/2022:10:23:25 +0100] [Job 16] job-uuid uri urn:uuid:2ce0b39e-4f88-312c-439e-82a57cf85a21 D [02/Mar/2022:10:23:25 +0100] [Job 16] number-up integer 1 D [02/Mar/2022:10:23:25 +0100] [Job 16] page-ranges rangeOfInteger 1-1 D [02/Mar/2022:10:23:25 +0100] [Job 16] print-color-mode keyword monochrome D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- end-of-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] Create-Job: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes) D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- operation-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-charset charset utf-8 D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-natural-language naturalLanguage en D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- unsupported-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] number-up unsupported unsupported D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- job-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] job-uri uri ipps://Printer.local/ipp/print/0018 D [02/Mar/2022:10:23:25 +0100] [Job 16] job-id integer 18 D [02/Mar/2022:10:23:25 +0100] [Job 16] job-state enum pending D [02/Mar/2022:10:23:25 +0100] [Job 16] job-state-reasons keyword none D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- end-of-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] Print job accepted - job ID 18. D [02/Mar/2022:10:23:25 +0100] [Job 16] Sending file 1 using chunking... D [02/Mar/2022:10:23:25 +0100] [Job 16] IPP/2.0 Send-Document #5 D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- operation-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-charset charset utf-8 D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-natural-language naturalLanguage en-gb D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-uri uri ipps://Printer.local:631/ipp/print D [02/Mar/2022:10:23:25 +0100] [Job 16] job-id integer 18 D [02/Mar/2022:10:23:25 +0100] [Job 16] requesting-user-name nameWithoutLanguage arvid D [02/Mar/2022:10:23:25 +0100] [Job 16] last-document boolean true D [02/Mar/2022:10:23:25 +0100] [Job 16] document-format mimeMediaType application/pdf D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- end-of-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] update_reasons(attr=1(wifi-not-configured-report), s=\"(null)\") D [02/Mar/2022:10:23:25 +0100] [Job 16] Get-Printer-Attributes: successful-ok (successful-ok) D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- operation-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-charset charset utf-8 D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-natural-language naturalLanguage en D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- printer-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-state enum idle D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-state-reasons keyword wifi-not-configured-report D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-state-message textWithoutLanguage D [02/Mar/2022:10:23:25 +0100] [Job 16] operations-supported 1setOf enum Print-Job,Print-URI,Validate-Job,Create-Job,Send-Document,Send-URI,Close-Job,Cancel-Job,Get-Job-Attributes,Get-Jobs,Get-Printer-Attributes,Identify-Printer D [02/Mar/2022:10:23:25 +0100] [Job 16] document-format-supported 1setOf mimeMediaType image/urf,application/PCLm,application/octet-stream,application/pdf,application/postscript,application/vnd.hp-PCL,application/vnd.hp-PCLXL,image/jpeg D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-is-accepting-jobs boolean true D [02/Mar/2022:10:23:25 +0100] [Job 16] compression-supported keyword none D [02/Mar/2022:10:23:25 +0100] [Job 16] media-col-supported 1setOf keyword media-size,media-top-margin,media-left-margin,media-right-margin,media-bottom-margin,media-type,media-source,media-source-properties,duplex-supported D [02/Mar/2022:10:23:25 +0100] [Job 16] marker-names 1setOf nameWithoutLanguage Cyan Cartridge HP CF541A,Magenta Cartridge HP CF543A,Yellow Cartridge HP CF542A,Black Cartridge HP CF540A D [02/Mar/2022:10:23:25 +0100] [Job 16] marker-colors 1setOf nameWithoutLanguage #00FFFF,#FF00FF,#FFFF00,#000000 D [02/Mar/2022:10:23:25 +0100] [Job 16] marker-types 1setOf keyword toner,toner,toner,toner D [02/Mar/2022:10:23:25 +0100] [Job 16] marker-low-levels 1setOf integer 10,10,10,10 D [02/Mar/2022:10:23:25 +0100] [Job 16] marker-high-levels 1setOf integer 100,100,100,100 D [02/Mar/2022:10:23:25 +0100] [Job 16] marker-levels 1setOf integer 23,31,28,27 D [02/Mar/2022:10:23:25 +0100] [Job 16] copies-supported rangeOfInteger 1-999 D [02/Mar/2022:10:23:25 +0100] [Job 16] multiple-document-handling-supported 1setOf keyword single-document,separate-documents-uncollated-copies,separate-documents-collated-copies,single-document-new-sheet D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-alert octetString code=unknown D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-alert-description textWithoutLanguage D [02/Mar/2022:10:23:25 +0100] [Job 16] print-color-mode-supported 1setOf keyword auto,auto-monochrome,monochrome,color D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- end-of-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] Send-Document: successful-ok (successful-ok) D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- operation-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-charset charset utf-8 D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-natural-language naturalLanguage en D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- job-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] job-uri uri ipps://Printer.local/ipp/print/0018 D [02/Mar/2022:10:23:25 +0100] [Job 16] job-id integer 18 D [02/Mar/2022:10:23:25 +0100] [Job 16] job-state enum pending D [02/Mar/2022:10:23:25 +0100] [Job 16] job-state-reasons keyword none D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- end-of-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] PAGE: 1 1 D [02/Mar/2022:10:23:25 +0100] Discarding unused job-progress event... D [02/Mar/2022:10:23:25 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:25 +0100] [Job 16] STATE: +cups-waiting-for-job-completed D [02/Mar/2022:10:23:25 +0100] cupsdMarkDirty(P----) D [02/Mar/2022:10:23:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:25 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:25 +0100] [Job 16] Waiting for job to complete. D [02/Mar/2022:10:23:25 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:25 +0100] [Job 16] Set job-printer-state-message to "Waiting for job to complete.", current level=INFO D [02/Mar/2022:10:23:25 +0100] Discarding unused job-progress event... D [02/Mar/2022:10:23:25 +0100] Discarding unused printer-state-changed event... D [02/Mar/2022:10:23:25 +0100] [Job 16] IPP/2.0 Get-Printer-Attributes #7 D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- operation-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-charset charset utf-8 D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-natural-language naturalLanguage en-gb D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-uri uri ipps://Printer.local:631/ipp/print D [02/Mar/2022:10:23:25 +0100] [Job 16] requesting-user-name nameWithoutLanguage arvid D [02/Mar/2022:10:23:25 +0100] [Job 16] requested-attributes 1setOf keyword compression-supported,copies-supported,cups-version,document-format-supported,job-password-encryption-supported,marker-colors,marker-high-levels,marker-levels,marker-low-levels,marker-message,marker-names,marker-types,media-col-supported,multiple-document-handling-supported,operations-supported,print-color-mode-supported,printer-alert,printer-alert-description,printer-is-accepting-jobs,printer-mandatory-job-attributes,printer-state,printer-state-message,printer-state-reasons D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- end-of-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] (monitor) Get-Job-Attributes: successful-ok (successful-ok) D [02/Mar/2022:10:23:25 +0100] [Job 16] (monitor) job-state = processing D [02/Mar/2022:10:23:25 +0100] [Job 16] (monitor) job-state = processing D [02/Mar/2022:10:23:25 +0100] [Job 16] Set job-printer-state-message to "Waiting for job to complete.", current level=INFO D [02/Mar/2022:10:23:25 +0100] [Job 16] update_reasons(attr=2(wifi-not-configured-report,...), s=\"(null)\") D [02/Mar/2022:10:23:25 +0100] [Job 16] Get-Printer-Attributes: successful-ok (successful-ok) D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- operation-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-charset charset utf-8 D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-natural-language naturalLanguage en D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- printer-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-state enum processing D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-state-reasons 1setOf keyword wifi-not-configured-report,spool-area-full-report D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-state-message 1setOf textWithoutLanguage , D [02/Mar/2022:10:23:25 +0100] [Job 16] operations-supported 1setOf enum Print-Job,Print-URI,Validate-Job,Create-Job,Send-Document,Send-URI,Close-Job,Cancel-Job,Get-Job-Attributes,Get-Jobs,Get-Printer-Attributes,Identify-Printer D [02/Mar/2022:10:23:25 +0100] [Job 16] document-format-supported 1setOf mimeMediaType image/urf,application/PCLm,application/octet-stream,application/pdf,application/postscript,application/vnd.hp-PCL,application/vnd.hp-PCLXL,image/jpeg D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-is-accepting-jobs boolean true D [02/Mar/2022:10:23:25 +0100] Discarding unused job-progress event... D [02/Mar/2022:10:23:25 +0100] Discarding unused printer-state-changed event... D [02/Mar/2022:10:23:25 +0100] [Job 16] compression-supported keyword none D [02/Mar/2022:10:23:25 +0100] [Job 16] media-col-supported 1setOf keyword media-size,media-top-margin,media-left-margin,media-right-margin,media-bottom-margin,media-type,media-source,media-source-properties,duplex-supported D [02/Mar/2022:10:23:25 +0100] [Job 16] marker-names 1setOf nameWithoutLanguage Cyan Cartridge HP CF541A,Magenta Cartridge HP CF543A,Yellow Cartridge HP CF542A,Black Cartridge HP CF540A D [02/Mar/2022:10:23:25 +0100] [Job 16] marker-colors 1setOf nameWithoutLanguage #00FFFF,#FF00FF,#FFFF00,#000000 D [02/Mar/2022:10:23:25 +0100] [Job 16] marker-types 1setOf keyword toner,toner,toner,toner D [02/Mar/2022:10:23:25 +0100] [Job 16] marker-low-levels 1setOf integer 10,10,10,10 D [02/Mar/2022:10:23:25 +0100] [Job 16] marker-high-levels 1setOf integer 100,100,100,100 D [02/Mar/2022:10:23:25 +0100] [Job 16] marker-levels 1setOf integer 23,31,28,27 D [02/Mar/2022:10:23:25 +0100] [Job 16] copies-supported rangeOfInteger 1-999 D [02/Mar/2022:10:23:25 +0100] [Job 16] multiple-document-handling-supported 1setOf keyword single-document,separate-documents-uncollated-copies,separate-documents-collated-copies,single-document-new-sheet D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-alert 1setOf octetString code=unknown,code=printerReadyToPrint D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-alert-description 1setOf textWithoutLanguage , D [02/Mar/2022:10:23:25 +0100] [Job 16] print-color-mode-supported 1setOf keyword auto,auto-monochrome,monochrome,color D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- end-of-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] IPP/2.0 Get-Job-Attributes #8 D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- operation-attributes-tag ---- D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-charset charset utf-8 D [02/Mar/2022:10:23:25 +0100] [Job 16] attributes-natural-language naturalLanguage en-gb D [02/Mar/2022:10:23:25 +0100] [Job 16] printer-uri uri ipps://Printer.local:631/ipp/print D [02/Mar/2022:10:23:25 +0100] [Job 16] job-id integer 18 D [02/Mar/2022:10:23:25 +0100] [Job 16] requesting-user-name nameWithoutLanguage arvid D [02/Mar/2022:10:23:25 +0100] [Job 16] requested-attributes 1setOf keyword job-id,job-impressions-completed,job-media-sheets-completed,job-name,job-originating-user-name,job-state,job-state-reasons D [02/Mar/2022:10:23:25 +0100] [Job 16] ---- end-of-attributes-tag ---- D [02/Mar/2022:10:23:26 +0100] [Job 16] IPP/2.0 Get-Printer-Attributes #9 D [02/Mar/2022:10:23:26 +0100] [Job 16] ---- operation-attributes-tag ---- D [02/Mar/2022:10:23:26 +0100] [Job 16] attributes-charset charset utf-8 D [02/Mar/2022:10:23:26 +0100] [Job 16] attributes-natural-language naturalLanguage en-gb D [02/Mar/2022:10:23:26 +0100] [Job 16] printer-uri uri ipps://Printer.local:631/ipp/print D [02/Mar/2022:10:23:26 +0100] [Job 16] requesting-user-name nameWithoutLanguage arvid D [02/Mar/2022:10:23:26 +0100] [Job 16] requested-attributes 1setOf keyword compression-supported,copies-supported,cups-version,document-format-supported,job-password-encryption-supported,marker-colors,marker-high-levels,marker-levels,marker-low-levels,marker-message,marker-names,marker-types,media-col-supported,multiple-document-handling-supported,operations-supported,print-color-mode-supported,printer-alert,printer-alert-description,printer-is-accepting-jobs,printer-mandatory-job-attributes,printer-state,printer-state-message,printer-state-reasons D [02/Mar/2022:10:23:26 +0100] [Job 16] ---- end-of-attributes-tag ---- D [02/Mar/2022:10:23:26 +0100] [Job 16] Get-Job-Attributes: successful-ok (successful-ok) D [02/Mar/2022:10:23:26 +0100] [Job 16] ---- operation-attributes-tag ---- D [02/Mar/2022:10:23:26 +0100] [Job 16] attributes-charset charset utf-8 D [02/Mar/2022:10:23:26 +0100] [Job 16] attributes-natural-language naturalLanguage en D [02/Mar/2022:10:23:26 +0100] [Job 16] ---- job-attributes-tag ---- D [02/Mar/2022:10:23:26 +0100] [Job 16] job-id integer 18 D [02/Mar/2022:10:23:26 +0100] [Job 16] job-name nameWithLanguage DEFAULT_PRINTER-0018[en-gb] D [02/Mar/2022:10:23:26 +0100] [Job 16] job-originating-user-name nameWithLanguage arvid[en-gb] D [02/Mar/2022:10:23:26 +0100] [Job 16] job-state enum completed D [02/Mar/2022:10:23:26 +0100] [Job 16] job-state-reasons keyword job-completed-successfully D [02/Mar/2022:10:23:26 +0100] [Job 16] job-media-sheets-completed integer 0 D [02/Mar/2022:10:23:26 +0100] [Job 16] job-impressions-completed integer 0 D [02/Mar/2022:10:23:26 +0100] [Job 16] ---- end-of-attributes-tag ---- D [02/Mar/2022:10:23:26 +0100] [Job 16] PAGE: total 0 D [02/Mar/2022:10:23:26 +0100] Discarding unused job-progress event... D [02/Mar/2022:10:23:26 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:26 +0100] [Job 16] IPP/2.0 Get-Printer-Attributes #10 D [02/Mar/2022:10:23:26 +0100] [Job 16] ---- operation-attributes-tag ---- D [02/Mar/2022:10:23:26 +0100] [Job 16] attributes-charset charset utf-8 D [02/Mar/2022:10:23:26 +0100] [Job 16] attributes-natural-language naturalLanguage en-gb D [02/Mar/2022:10:23:26 +0100] [Job 16] printer-uri uri ipps://Printer.local:631/ipp/print D [02/Mar/2022:10:23:26 +0100] [Job 16] requesting-user-name nameWithoutLanguage arvid D [02/Mar/2022:10:23:26 +0100] [Job 16] requested-attributes 1setOf keyword compression-supported,copies-supported,cups-version,document-format-supported,job-password-encryption-supported,marker-colors,marker-high-levels,marker-levels,marker-low-levels,marker-message,marker-names,marker-types,media-col-supported,multiple-document-handling-supported,operations-supported,print-color-mode-supported,printer-alert,printer-alert-description,printer-is-accepting-jobs,printer-mandatory-job-attributes,printer-state,printer-state-message,printer-state-reasons D [02/Mar/2022:10:23:26 +0100] [Job 16] ---- end-of-attributes-tag ---- D [02/Mar/2022:10:23:26 +0100] [Job 16] update_reasons(attr=1(wifi-not-configured-report), s=\"(null)\") D [02/Mar/2022:10:23:26 +0100] [Job 16] Get-Printer-Attributes: successful-ok (successful-ok) D [02/Mar/2022:10:23:26 +0100] [Job 16] ---- operation-attributes-tag ---- D [02/Mar/2022:10:23:26 +0100] [Job 16] attributes-charset charset utf-8 D [02/Mar/2022:10:23:26 +0100] [Job 16] attributes-natural-language naturalLanguage en D [02/Mar/2022:10:23:26 +0100] [Job 16] ---- printer-attributes-tag ---- D [02/Mar/2022:10:23:26 +0100] [Job 16] printer-state enum idle D [02/Mar/2022:10:23:26 +0100] [Job 16] printer-state-reasons keyword wifi-not-configured-report D [02/Mar/2022:10:23:26 +0100] [Job 16] printer-state-message textWithoutLanguage D [02/Mar/2022:10:23:26 +0100] [Job 16] operations-supported 1setOf enum Print-Job,Print-URI,Validate-Job,Create-Job,Send-Document,Send-URI,Close-Job,Cancel-Job,Get-Job-Attributes,Get-Jobs,Get-Printer-Attributes,Identify-Printer D [02/Mar/2022:10:23:26 +0100] [Job 16] document-format-supported 1setOf mimeMediaType image/urf,application/PCLm,application/octet-stream,application/pdf,application/postscript,application/vnd.hp-PCL,application/vnd.hp-PCLXL,image/jpeg D [02/Mar/2022:10:23:26 +0100] [Job 16] printer-is-accepting-jobs boolean true D [02/Mar/2022:10:23:26 +0100] [Job 16] compression-supported keyword none D [02/Mar/2022:10:23:26 +0100] [Job 16] media-col-supported 1setOf keyword media-size,media-top-margin,media-left-margin,media-right-margin,media-bottom-margin,media-type,media-source,media-source-properties,duplex-supported D [02/Mar/2022:10:23:26 +0100] [Job 16] marker-names 1setOf nameWithoutLanguage Cyan Cartridge HP CF541A,Magenta Cartridge HP CF543A,Yellow Cartridge HP CF542A,Black Cartridge HP CF540A D [02/Mar/2022:10:23:26 +0100] [Job 16] marker-colors 1setOf nameWithoutLanguage #00FFFF,#FF00FF,#FFFF00,#000000 D [02/Mar/2022:10:23:26 +0100] [Job 16] marker-types 1setOf keyword toner,toner,toner,toner D [02/Mar/2022:10:23:26 +0100] [Job 16] marker-low-levels 1setOf integer 10,10,10,10 D [02/Mar/2022:10:23:26 +0100] [Job 16] marker-high-levels 1setOf integer 100,100,100,100 D [02/Mar/2022:10:23:26 +0100] [Job 16] marker-levels 1setOf integer 23,31,28,27 D [02/Mar/2022:10:23:26 +0100] [Job 16] copies-supported rangeOfInteger 1-999 D [02/Mar/2022:10:23:26 +0100] [Job 16] multiple-document-handling-supported 1setOf keyword single-document,separate-documents-uncollated-copies,separate-documents-collated-copies,single-document-new-sheet D [02/Mar/2022:10:23:26 +0100] [Job 16] printer-alert octetString code=unknown D [02/Mar/2022:10:23:26 +0100] [Job 16] printer-alert-description textWithoutLanguage D [02/Mar/2022:10:23:26 +0100] [Job 16] print-color-mode-supported 1setOf keyword auto,auto-monochrome,monochrome,color D [02/Mar/2022:10:23:26 +0100] [Job 16] ---- end-of-attributes-tag ---- D [02/Mar/2022:10:23:27 +0100] [Job 16] update_reasons(attr=1(wifi-not-configured-report), s=\"(null)\") D [02/Mar/2022:10:23:27 +0100] [Job 16] Get-Printer-Attributes: successful-ok (successful-ok) D [02/Mar/2022:10:23:27 +0100] [Job 16] ---- operation-attributes-tag ---- D [02/Mar/2022:10:23:27 +0100] [Job 16] attributes-charset charset utf-8 D [02/Mar/2022:10:23:27 +0100] [Job 16] attributes-natural-language naturalLanguage en D [02/Mar/2022:10:23:27 +0100] [Job 16] ---- printer-attributes-tag ---- D [02/Mar/2022:10:23:27 +0100] [Job 16] printer-state enum idle D [02/Mar/2022:10:23:27 +0100] [Job 16] printer-state-reasons keyword wifi-not-configured-report D [02/Mar/2022:10:23:27 +0100] [Job 16] printer-state-message textWithoutLanguage D [02/Mar/2022:10:23:27 +0100] [Job 16] operations-supported 1setOf enum Print-Job,Print-URI,Validate-Job,Create-Job,Send-Document,Send-URI,Close-Job,Cancel-Job,Get-Job-Attributes,Get-Jobs,Get-Printer-Attributes,Identify-Printer D [02/Mar/2022:10:23:27 +0100] [Job 16] document-format-supported 1setOf mimeMediaType image/urf,application/PCLm,application/octet-stream,application/pdf,application/postscript,application/vnd.hp-PCL,application/vnd.hp-PCLXL,image/jpeg D [02/Mar/2022:10:23:27 +0100] [Job 16] printer-is-accepting-jobs boolean true D [02/Mar/2022:10:23:27 +0100] [Job 16] compression-supported keyword none D [02/Mar/2022:10:23:27 +0100] [Job 16] media-col-supported 1setOf keyword media-size,media-top-margin,media-left-margin,media-right-margin,media-bottom-margin,media-type,media-source,media-source-properties,duplex-supported D [02/Mar/2022:10:23:27 +0100] [Job 16] marker-names 1setOf nameWithoutLanguage Cyan Cartridge HP CF541A,Magenta Cartridge HP CF543A,Yellow Cartridge HP CF542A,Black Cartridge HP CF540A D [02/Mar/2022:10:23:27 +0100] [Job 16] marker-colors 1setOf nameWithoutLanguage #00FFFF,#FF00FF,#FFFF00,#000000 D [02/Mar/2022:10:23:27 +0100] [Job 16] marker-types 1setOf keyword toner,toner,toner,toner D [02/Mar/2022:10:23:27 +0100] [Job 16] marker-low-levels 1setOf integer 10,10,10,10 D [02/Mar/2022:10:23:27 +0100] [Job 16] marker-high-levels 1setOf integer 100,100,100,100 D [02/Mar/2022:10:23:27 +0100] [Job 16] marker-levels 1setOf integer 23,31,28,27 D [02/Mar/2022:10:23:27 +0100] [Job 16] copies-supported rangeOfInteger 1-999 D [02/Mar/2022:10:23:27 +0100] [Job 16] multiple-document-handling-supported 1setOf keyword single-document,separate-documents-uncollated-copies,separate-documents-collated-copies,single-document-new-sheet D [02/Mar/2022:10:23:27 +0100] [Job 16] printer-alert octetString code=unknown D [02/Mar/2022:10:23:27 +0100] [Job 16] printer-alert-description textWithoutLanguage D [02/Mar/2022:10:23:27 +0100] [Job 16] print-color-mode-supported 1setOf keyword auto,auto-monochrome,monochrome,color D [02/Mar/2022:10:23:27 +0100] [Job 16] ---- end-of-attributes-tag ---- D [02/Mar/2022:10:23:27 +0100] [Job 16] ATTR: auth-info-required=none D [02/Mar/2022:10:23:27 +0100] cupsdMarkDirty(P----) D [02/Mar/2022:10:23:27 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:27 +0100] load_ppd: Loading /etc/cups/ppd/HomePrinterIPPS.ppd... D [02/Mar/2022:10:23:27 +0100] cupsdRegisterPrinter(p=0x55bc39e37eb0(HomePrinterIPPS)) D [02/Mar/2022:10:23:27 +0100] cupsdMarkDirty(P----) D [02/Mar/2022:10:23:27 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:27 +0100] [Job 16] PID 51867 (/usr/lib/cups/backend/ipps) exited with no errors. D [02/Mar/2022:10:23:27 +0100] cupsdMarkDirty(P----) D [02/Mar/2022:10:23:27 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:27 +0100] [Job 16] time-at-completed=1646213007 D [02/Mar/2022:10:23:27 +0100] Discarding unused job-completed event... I [02/Mar/2022:10:23:27 +0100] [Job 16] Job completed. D [02/Mar/2022:10:23:27 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:27 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:27 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:27 +0100] Discarding unused printer-state-changed event... D [02/Mar/2022:10:23:27 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:27 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:27 +0100] cupsdMarkDirty(---J-) D [02/Mar/2022:10:23:27 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:28 +0100] [Job 16] Unloading... D [02/Mar/2022:10:23:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files" D [02/Mar/2022:10:23:38 +0100] [Client 17] Server address is "127.0.0.1". D [02/Mar/2022:10:23:38 +0100] [Client 17] Accepted from localhost:58926 (IPv4) D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for request. D [02/Mar/2022:10:23:38 +0100] [Client 17] GET /printers/HomePrinterIPPS HTTP/1.1 D [02/Mar/2022:10:23:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Mar/2022:10:23:38 +0100] [Client 17] Read: status=200, state=3 D [02/Mar/2022:10:23:38 +0100] [Client 17] Authorized as "arvid" using Basic. D [02/Mar/2022:10:23:38 +0100] [Client 17] Processing GET /printers/HomePrinterIPPS D [02/Mar/2022:10:23:38 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[3] = "CUPS_REQUESTROOT=/var/spool/cups" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[4] = "CUPS_SERVERBIN=/usr/lib/cups" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[5] = "CUPS_SERVERROOT=/etc/cups" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[6] = "CUPS_STATEDIR=/run/cups" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[7] = "HOME=/var/spool/cups/tmp" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[8] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[9] = "SERVER_ADMIN=root@athena" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[10] = "SOFTWARE=CUPS/2.4.1" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[11] = "TMPDIR=/var/spool/cups/tmp" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[12] = "USER=root" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[13] = "CUPS_MAX_MESSAGE=2047" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[14] = "CUPS_SERVER=/run/cups/cups.sock" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[16] = "IPP_PORT=631" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[17] = "AUTH_TYPE=Basic" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[18] = "LANG=en_GB.UTF8" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[19] = "REDIRECT_STATUS=1" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[21] = "SERVER_NAME=localhost" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[22] = "SERVER_PORT=631" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[23] = "REMOTE_ADDR=127.0.0.1" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[24] = "REMOTE_HOST=localhost" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[25] = "SCRIPT_NAME=/printers/HomePrinterIPPS" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc/printers/HomePrinterIPPS" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[27] = "PATH_INFO=/HomePrinterIPPS" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[28] = "REMOTE_USER=arvid" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=c32af640fe1c02a1a1aa9d8227581886" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:97.0) Gecko/20100101 Firefox/97.0" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[32] = "HTTP_REFERER=http://localhost:631/printers/" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[33] = "REQUEST_METHOD=GET" D [02/Mar/2022:10:23:38 +0100] [CGI] envp[34] = "QUERY_STRING=" D [02/Mar/2022:10:23:38 +0100] cupsdAddCert: Adding certificate for PID 51874 D [02/Mar/2022:10:23:38 +0100] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 51874) I [02/Mar/2022:10:23:38 +0100] [Client 17] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=51874, file=15) D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [CGI] org.cups.sid cookie is \"c32af640fe1c02a1a1aa9d8227581886\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: SECTION=\"printers\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: PRINTER_NAME=\"HomePrinterIPPS\" D [02/Mar/2022:10:23:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Mar/2022:10:23:38 +0100] [Client 18] Server address is "/run/cups/cups.sock". D [02/Mar/2022:10:23:38 +0100] [Client 18] Accepted from localhost (Domain) D [02/Mar/2022:10:23:38 +0100] [Client 18] Waiting for request. D [02/Mar/2022:10:23:38 +0100] [Client 18] POST / HTTP/1.1 D [02/Mar/2022:10:23:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Mar/2022:10:23:38 +0100] [Client 18] Read: status=200, state=6 D [02/Mar/2022:10:23:38 +0100] [Client 18] No authentication data provided. D [02/Mar/2022:10:23:38 +0100] [Client 18] 2.0 CUPS-Get-Default 1 D [02/Mar/2022:10:23:38 +0100] CUPS-Get-Default D [02/Mar/2022:10:23:38 +0100] CUPS-Get-Default client-error-not-found: No default printer. D [02/Mar/2022:10:23:38 +0100] [Client 18] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost. D [02/Mar/2022:10:23:38 +0100] [Client 18] Content-Length: 113 D [02/Mar/2022:10:23:38 +0100] [Client 18] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Mar/2022:10:23:38 +0100] [Client 18] con->http=0x55bc39e8c490 D [02/Mar/2022:10:23:38 +0100] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x55bc39e74cc0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Mar/2022:10:23:38 +0100] [Client 18] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Mar/2022:10:23:38 +0100] [Client 18] bytes=0, http_state=0, data_remaining=113 D [02/Mar/2022:10:23:38 +0100] [Client 18] Flushing write buffer. D [02/Mar/2022:10:23:38 +0100] [Client 18] New state is HTTP_STATE_WAITING D [02/Mar/2022:10:23:38 +0100] [Client 18] Waiting for request. D [02/Mar/2022:10:23:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Mar/2022:10:23:38 +0100] [CGI] show_printer(http=0x55fba1f665c0, printer=\"HomePrinterIPPS\") D [02/Mar/2022:10:23:38 +0100] [Client 18] POST / HTTP/1.1 D [02/Mar/2022:10:23:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Mar/2022:10:23:38 +0100] [Client 18] Read: status=200, state=6 D [02/Mar/2022:10:23:38 +0100] [Client 18] No authentication data provided. D [02/Mar/2022:10:23:38 +0100] [Client 18] 2.0 Get-Printer-Attributes 2 D [02/Mar/2022:10:23:38 +0100] Get-Printer-Attributes ipp://localhost/printers/HomePrinterIPPS D [02/Mar/2022:10:23:38 +0100] [Client 18] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HomePrinterIPPS) from localhost. D [02/Mar/2022:10:23:38 +0100] [Client 18] Content-Length: 516 D [02/Mar/2022:10:23:38 +0100] [Client 18] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Mar/2022:10:23:38 +0100] [Client 18] con->http=0x55bc39e8c490 D [02/Mar/2022:10:23:38 +0100] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=516, response=0x55bc39e88bc0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Mar/2022:10:23:38 +0100] [Client 18] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Mar/2022:10:23:38 +0100] [Client 18] bytes=0, http_state=0, data_remaining=516 D [02/Mar/2022:10:23:38 +0100] [Client 18] Flushing write buffer. D [02/Mar/2022:10:23:38 +0100] [Client 18] New state is HTTP_STATE_WAITING D [02/Mar/2022:10:23:38 +0100] [Client 18] Waiting for request. D [02/Mar/2022:10:23:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: REMOTE_USER=\"arvid\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: REMOTE_USER=\"arvid\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetArray: printer_is_shared[0]=\"0\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetArray: printer_state[0]=\"3\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/HomePrinterIPPS\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetArray: printer_name[0]=\"HomePrinterIPPS\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetArray: printer_location[0]=\"\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetArray: printer_info[0]=\"HP ColorLaserJet MFP M278-M281\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetArray: device_uri[0]=\"ipps://HP%20Color%20LaserJet%20MFP%20M281fdw%20(Home)._ipps._tcp.local/\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetArray: printer_make_and_model[0]=\"Local Raw Printer\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"0\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: TITLE=\"HomePrinterIPPS\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: REMOTE_USER=\"arvid\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.4.1\" D [02/Mar/2022:10:23:38 +0100] [Client 17] CGI data ready to be sent. D [02/Mar/2022:10:23:38 +0100] [Client 17] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=51874, file=15 D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [Client 17] Script header: Content-Type: text/html;charset=utf-8 D [02/Mar/2022:10:23:38 +0100] [Client 17] Script header: D [02/Mar/2022:10:23:38 +0100] [Client 17] Sending status 200 for CGI. D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [02/Mar/2022:10:23:38 +0100] [Client 17] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=51874, file=15 D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [Client 17] CGI data ready to be sent. D [02/Mar/2022:10:23:38 +0100] [CGI] Regular expression \".*Clean.*\" D [02/Mar/2022:10:23:38 +0100] [Client 17] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=51874, file=15 D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [CGI] Regular expression \".*PrintSelfTestPage.*\" D [02/Mar/2022:10:23:38 +0100] [Client 17] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=51874, file=15 D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [Client 17] CGI data ready to be sent. D [02/Mar/2022:10:23:38 +0100] [Client 17] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=51874, file=15 D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [Client 17] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=51874, file=15 D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [Client 18] POST / HTTP/1.1 D [02/Mar/2022:10:23:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Mar/2022:10:23:38 +0100] [Client 18] Read: status=200, state=6 D [02/Mar/2022:10:23:38 +0100] [Client 18] No authentication data provided. D [02/Mar/2022:10:23:38 +0100] [Client 18] 2.0 Get-Jobs 3 D [02/Mar/2022:10:23:38 +0100] Get-Jobs ipp://localhost:631/printers/HomePrinterIPPS D [02/Mar/2022:10:23:38 +0100] [Client 18] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HomePrinterIPPS) from localhost. D [02/Mar/2022:10:23:38 +0100] [Client 18] Content-Length: 75 D [02/Mar/2022:10:23:38 +0100] [Client 18] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Mar/2022:10:23:38 +0100] [Client 18] con->http=0x55bc39e8c490 D [02/Mar/2022:10:23:38 +0100] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55bc39e481b0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Mar/2022:10:23:38 +0100] [Client 18] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Mar/2022:10:23:38 +0100] [Client 18] bytes=0, http_state=0, data_remaining=75 D [02/Mar/2022:10:23:38 +0100] [Client 18] Flushing write buffer. D [02/Mar/2022:10:23:38 +0100] [Client 18] New state is HTTP_STATE_WAITING D [02/Mar/2022:10:23:38 +0100] [Client 18] Waiting for request. D [02/Mar/2022:10:23:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiClearVariables called. D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: SECTION=\"printers\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: TOTAL=\"0\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: PRINTER_NAME=\"HomePrinterIPPS\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/HomePrinterIPPS\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: THISURL=\"/printers/HomePrinterIPPS\" D [02/Mar/2022:10:23:38 +0100] [CGI] cgiSetVariable: SEARCH_DEST=\"HomePrinterIPPS\" D [02/Mar/2022:10:23:38 +0100] [Client 17] CGI data ready to be sent. D [02/Mar/2022:10:23:38 +0100] [Client 17] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=51874, file=15 D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [Client 17] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=51874, file=15 D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [Client 17] CGI data ready to be sent. D [02/Mar/2022:10:23:38 +0100] [Client 17] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=51874, file=15 D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [Client 17] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=51874, file=15 D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [Client 17] CGI data ready to be sent. D [02/Mar/2022:10:23:38 +0100] [Client 17] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=51874, file=15 D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [Client 17] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=51874, file=15 D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [Client 17] CGI data ready to be sent. D [02/Mar/2022:10:23:38 +0100] [Client 17] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=51874, file=15 D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [Client 17] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=51874, file=15 D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [Client 17] CGI data ready to be sent. D [02/Mar/2022:10:23:38 +0100] [Client 18] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [02/Mar/2022:10:23:38 +0100] [Client 18] Closing connection. D [02/Mar/2022:10:23:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Mar/2022:10:23:38 +0100] [Client 17] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=51874, file=15 D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [Client 17] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=51874, file=15 D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [Client 17] CGI data ready to be sent. D [02/Mar/2022:10:23:38 +0100] [Client 17] con->http=0x55bc39e7e770 D [02/Mar/2022:10:23:38 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=51874, file=15 D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for CGI data. D [02/Mar/2022:10:23:38 +0100] [Client 17] Sending 0-length chunk. D [02/Mar/2022:10:23:38 +0100] [Client 17] Flushing write buffer. D [02/Mar/2022:10:23:38 +0100] [Client 17] New state is HTTP_STATE_WAITING D [02/Mar/2022:10:23:38 +0100] [Client 17] Waiting for request. D [02/Mar/2022:10:23:38 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [02/Mar/2022:10:23:38 +0100] PID 51874 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.