D [02/Mar/2022:17:09:28 +0100] [Client 4] Sending 0-length chunk.
D [02/Mar/2022:17:09:28 +0100] [Client 4] Flushing write buffer.
D [02/Mar/2022:17:09:28 +0100] [Client 4] New state is HTTP_STATE_WAITING
D [02/Mar/2022:17:09:28 +0100] [Client 4] Waiting for request.
D [02/Mar/2022:17:09:28 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [02/Mar/2022:17:09:29 +0100] Saving printers.conf...
D [02/Mar/2022:17:09:29 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [02/Mar/2022:17:09:39 +0100] [Client 4] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Mar/2022:17:09:39 +0100] [Client 4] Closing connection.
D [02/Mar/2022:17:09:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [02/Mar/2022:17:10:13 +0100] [Client 11] Server address is "/run/cups/cups.sock".
D [02/Mar/2022:17:10:13 +0100] [Client 11] Accepted from localhost (Domain)
D [02/Mar/2022:17:10:13 +0100] [Client 11] Waiting for request.
D [02/Mar/2022:17:10:13 +0100] Report: clients=1
D [02/Mar/2022:17:10:13 +0100] Report: jobs=7
D [02/Mar/2022:17:10:13 +0100] Report: jobs-active=0
D [02/Mar/2022:17:10:13 +0100] Report: printers=5
D [02/Mar/2022:17:10:13 +0100] Report: stringpool-string-count=2276
D [02/Mar/2022:17:10:13 +0100] Report: stringpool-alloc-bytes=12744
D [02/Mar/2022:17:10:13 +0100] Report: stringpool-total-bytes=46208
D [02/Mar/2022:17:10:13 +0100] [Client 11] POST / HTTP/1.1
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [02/Mar/2022:17:10:13 +0100] [Client 11] Read: status=200, state=6
D [02/Mar/2022:17:10:13 +0100] [Client 11] No authentication data provided.
D [02/Mar/2022:17:10:13 +0100] [Client 11] 2.0 Get-Printer-Attributes 1
D [02/Mar/2022:17:10:13 +0100] Get-Printer-Attributes ipp://localhost:631/printers/testqueuelogged
D [02/Mar/2022:17:10:13 +0100] Get-Printer-Attributes client-error-not-found: The printer or class does not exist.
D [02/Mar/2022:17:10:13 +0100] [Client 11] Returning IPP client-error-not-found for Get-Printer-Attributes (ipp://localhost:631/printers/testqueuelogged) from localhost.
D [02/Mar/2022:17:10:13 +0100] [Client 11] Content-Length: 130
D [02/Mar/2022:17:10:13 +0100] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [02/Mar/2022:17:10:13 +0100] [Client 11] con->http=0x55db8ae75a30
D [02/Mar/2022:17:10:13 +0100] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=130, response=0x55db8ae643d0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [02/Mar/2022:17:10:13 +0100] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [02/Mar/2022:17:10:13 +0100] [Client 11] bytes=0, http_state=0, data_remaining=130
D [02/Mar/2022:17:10:13 +0100] [Client 11] Flushing write buffer.
D [02/Mar/2022:17:10:13 +0100] [Client 11] New state is HTTP_STATE_WAITING
D [02/Mar/2022:17:10:13 +0100] [Client 11] Waiting for request.
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [02/Mar/2022:17:10:13 +0100] [Client 12] Server address is "/run/cups/cups.sock".
D [02/Mar/2022:17:10:13 +0100] [Client 12] Accepted from localhost (Domain)
D [02/Mar/2022:17:10:13 +0100] [Client 12] Waiting for request.
D [02/Mar/2022:17:10:13 +0100] [Client 12] POST /printers/testqueuelogged HTTP/1.1
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [02/Mar/2022:17:10:13 +0100] [Client 12] Read: status=200, state=6
D [02/Mar/2022:17:10:13 +0100] [Client 12] No authentication data provided.
D [02/Mar/2022:17:10:13 +0100] [Client 12] 2.0 Get-Printer-Attributes 3
D [02/Mar/2022:17:10:13 +0100] Get-Printer-Attributes ipp://localhost/printers/testqueuelogged
D [02/Mar/2022:17:10:13 +0100] Get-Printer-Attributes client-error-not-found: The printer or class does not exist.
D [02/Mar/2022:17:10:13 +0100] [Client 12] Returning IPP client-error-not-found for Get-Printer-Attributes (ipp://localhost/printers/testqueuelogged) from localhost.
D [02/Mar/2022:17:10:13 +0100] [Client 12] Content-Length: 130
D [02/Mar/2022:17:10:13 +0100] [Client 12] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [02/Mar/2022:17:10:13 +0100] [Client 12] con->http=0x55db8aef0f20
D [02/Mar/2022:17:10:13 +0100] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=130, response=0x55db8ae6beb0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [02/Mar/2022:17:10:13 +0100] [Client 12] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [02/Mar/2022:17:10:13 +0100] [Client 12] bytes=0, http_state=0, data_remaining=130
D [02/Mar/2022:17:10:13 +0100] [Client 12] Flushing write buffer.
D [02/Mar/2022:17:10:13 +0100] [Client 12] New state is HTTP_STATE_WAITING
D [02/Mar/2022:17:10:13 +0100] [Client 12] Waiting for request.
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [02/Mar/2022:17:10:13 +0100] [Client 11] POST /admin/ HTTP/1.1
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [02/Mar/2022:17:10:13 +0100] [Client 11] Read: status=200, state=6
D [02/Mar/2022:17:10:13 +0100] [Client 11] No authentication data provided.
D [02/Mar/2022:17:10:13 +0100] [Client 11] 2.0 CUPS-Add-Modify-Printer 2
D [02/Mar/2022:17:10:13 +0100] CUPS-Add-Modify-Printer ipp://localhost:631/printers/testqueuelogged
D [02/Mar/2022:17:10:13 +0100] cupsdIsAuthorized: username=""
D [02/Mar/2022:17:10:13 +0100] [Client 11] Returning HTTP Unauthorized for CUPS-Add-Modify-Printer (ipp://localhost:631/printers/testqueuelogged) from localhost
D [02/Mar/2022:17:10:13 +0100] [Client 11] cupsdSendHeader: code=401, type="text/html", auth_type=0
D [02/Mar/2022:17:10:13 +0100] [Client 11] WWW-Authenticate: Basic realm=\"CUPS\", PeerCred, Local trc=\"y\"
D [02/Mar/2022:17:10:13 +0100] [Client 11] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Mar/2022:17:10:13 +0100] [Client 11] Closing connection.
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [02/Mar/2022:17:10:13 +0100] [Client 13] Server address is "/run/cups/cups.sock".
D [02/Mar/2022:17:10:13 +0100] [Client 13] Accepted from localhost (Domain)
D [02/Mar/2022:17:10:13 +0100] [Client 13] Waiting for request.
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [02/Mar/2022:17:10:13 +0100] [Client 14] Server address is "/run/cups/cups.sock".
D [02/Mar/2022:17:10:13 +0100] [Client 14] Accepted from localhost (Domain)
D [02/Mar/2022:17:10:13 +0100] [Client 14] Waiting for request.
D [02/Mar/2022:17:10:13 +0100] [Client 13] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Mar/2022:17:10:13 +0100] [Client 13] Closing connection.
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [02/Mar/2022:17:10:13 +0100] [Client 15] Server address is "/run/cups/cups.sock".
D [02/Mar/2022:17:10:13 +0100] [Client 15] Accepted from localhost (Domain)
D [02/Mar/2022:17:10:13 +0100] [Client 15] Waiting for request.
D [02/Mar/2022:17:10:13 +0100] [Client 14] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Mar/2022:17:10:13 +0100] [Client 14] Closing connection.
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [02/Mar/2022:17:10:13 +0100] [Client 15] POST /admin/ HTTP/1.1
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [02/Mar/2022:17:10:13 +0100] [Client 15] Read: status=200, state=6
D [02/Mar/2022:17:10:13 +0100] [Client 15] Authorized as root using PeerCred.
D [02/Mar/2022:17:10:13 +0100] [Client 15] 2.0 CUPS-Add-Modify-Printer 2
D [02/Mar/2022:17:10:13 +0100] CUPS-Add-Modify-Printer ipp://localhost:631/printers/testqueuelogged
D [02/Mar/2022:17:10:13 +0100] cupsdIsAuthorized: username="root"
D [02/Mar/2022:17:10:13 +0100] testqueuelogged device-uri: OK
I [02/Mar/2022:17:10:13 +0100] Setting testqueuelogged device-uri to "ipps://HP%20Color%20LaserJet%20MFP%20M281fdw%20(81A44A)._ipps._tcp.local/" (was "file:///dev/null".)
I [02/Mar/2022:17:10:13 +0100] Setting testqueuelogged printer-is-accepting-jobs to 1 (was 0.)
D [02/Mar/2022:17:10:13 +0100] Discarding unused printer-state-changed event...
I [02/Mar/2022:17:10:13 +0100] Setting testqueuelogged printer-state to 3 (was 5.)
D [02/Mar/2022:17:10:13 +0100] Discarding unused printer-state-changed event...
D [02/Mar/2022:17:10:13 +0100] cupsdMarkDirty(P----)
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [02/Mar/2022:17:10:13 +0100] Calling FindDeviceById(cups-testqueuelogged)
D [02/Mar/2022:17:10:13 +0100] testqueuelogged: Generating PPD file from "ipps://HP%20Color%20LaserJet%20MFP%20M281fdw%20(81A44A)._ipps._tcp.local/"...
E [02/Mar/2022:17:10:13 +0100] testqueuelogged: Unable to connect to HP Color LaserJet MFP M281fdw (81A44A)._ipps._tcp.local:631: Name or service not known
D [02/Mar/2022:17:10:13 +0100] FindDeviceById failed: org.freedesktop.ColorManager.NotFound:device id \'cups-testqueuelogged\' does not exist
D [02/Mar/2022:17:10:13 +0100] cupsdMarkDirty(P----)
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [02/Mar/2022:17:10:13 +0100] cupsdMarkDirty(P----)
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [02/Mar/2022:17:10:13 +0100] load_ppd: Loading /etc/cups/ppd/testqueuelogged.ppd...
D [02/Mar/2022:17:10:13 +0100] cupsdRegisterPrinter(p=0x55db8aef4570(testqueuelogged))
D [02/Mar/2022:17:10:13 +0100] cupsdMarkDirty(--p--)
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [02/Mar/2022:17:10:13 +0100] Discarding unused printer-added event...
I [02/Mar/2022:17:10:13 +0100] New printer "testqueuelogged" added by "root".
D [02/Mar/2022:17:10:13 +0100] [Client 15] Returning IPP successful-ok for CUPS-Add-Modify-Printer (ipp://localhost:631/printers/testqueuelogged) from localhost.
D [02/Mar/2022:17:10:13 +0100] [Client 15] Content-Length: 75
D [02/Mar/2022:17:10:13 +0100] [Client 15] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [02/Mar/2022:17:10:13 +0100] [Client 15] con->http=0x55db8ae75a30
D [02/Mar/2022:17:10:13 +0100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x55db8ae6c1c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [02/Mar/2022:17:10:13 +0100] [Client 15] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [02/Mar/2022:17:10:13 +0100] [Client 15] bytes=0, http_state=0, data_remaining=75
D [02/Mar/2022:17:10:13 +0100] [Client 15] Flushing write buffer.
D [02/Mar/2022:17:10:13 +0100] [Client 15] New state is HTTP_STATE_WAITING
D [02/Mar/2022:17:10:13 +0100] [Client 15] Waiting for request.
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [02/Mar/2022:17:10:13 +0100] [Client 15] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Mar/2022:17:10:13 +0100] [Client 15] Closing connection.
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [02/Mar/2022:17:10:13 +0100] [Client 12] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Mar/2022:17:10:13 +0100] [Client 12] Closing connection.
D [02/Mar/2022:17:10:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"