Arch Linux

raphaelabb

Member

From: France

Registered: 2021-02-03

Posts: 31

[SOLVED] No printing after hplip upgrade

Hello,
My HP Laserjet p1102w printer stopped working after hplip package upgrade (hplip 1:3.20.11-2 -> 1:3.21.2-1). The light on the printer blinks as if receiving data but won't print.

I downgraded the package, added it to the IgnorePkg section in pacman.conf and printing works fine again.

My system is up to date.

Here are log entries from /var/log/cups/error_log for a one-page-printing test with the latest hplip package:

D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:58 +0100] [Client 600] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:58 +0100] [Client 600] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:58 +0100] [Client 600] Waiting for request.
I [27/Feb/2021:09:13:58 +0100] Expiring subscriptions...
D [27/Feb/2021:09:13:58 +0100] [Job 121] Unloading...
D [27/Feb/2021:09:13:58 +0100] cupsdAddCert: Adding certificate for PID 0
D [27/Feb/2021:09:13:58 +0100] Report: clients=3
D [27/Feb/2021:09:13:58 +0100] Report: jobs=1
D [27/Feb/2021:09:13:58 +0100] Report: jobs-active=1
D [27/Feb/2021:09:13:58 +0100] Report: printers=1
D [27/Feb/2021:09:13:58 +0100] Report: stringpool-string-count=7192
D [27/Feb/2021:09:13:58 +0100] Report: stringpool-alloc-bytes=11488
D [27/Feb/2021:09:13:58 +0100] Report: stringpool-total-bytes=167656
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:13:58 +0100] [Client 601] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:58 +0100] [Client 601] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:58 +0100] [Client 601] Waiting for request.
D [27/Feb/2021:09:13:58 +0100] [Client 600] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:13:58 +0100] [Client 600] Closing connection.
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:13:58 +0100] [Client 601] POST / HTTP/1.1
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=200, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] No authentication data provided.
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 601] 2.0 CUPS-Get-Printers 1
D [27/Feb/2021:09:13:58 +0100] CUPS-Get-Printers
D [27/Feb/2021:09:13:58 +0100] [Client 601] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [27/Feb/2021:09:13:58 +0100] [Client 601] Content-Length: 1015
D [27/Feb/2021:09:13:58 +0100] [Client 601] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:13:58 +0100] [Client 601] con->http=0x55ef90f82830
D [27/Feb/2021:09:13:58 +0100] [Client 601] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1015, response=0x55ef90f7a870(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:13:58 +0100] [Client 601] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:13:58 +0100] [Client 601] bytes=0, http_state=0, data_remaining=1015
D [27/Feb/2021:09:13:58 +0100] [Client 601] Flushing write buffer.
D [27/Feb/2021:09:13:58 +0100] [Client 601] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:13:58 +0100] [Client 601] Waiting for request.
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:13:58 +0100] [Client 601] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:13:58 +0100] [Client 601] Closing connection.
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:58 +0100] [Client 602] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:58 +0100] [Client 602] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:58 +0100] [Client 602] Waiting for request.
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:13:58 +0100] [Client 603] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:58 +0100] [Client 603] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:58 +0100] [Client 603] Waiting for request.
D [27/Feb/2021:09:13:58 +0100] [Client 602] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:13:58 +0100] [Client 602] Closing connection.
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:13:58 +0100] [Client 603] POST / HTTP/1.1
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=200, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] No authentication data provided.
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] Read: status=100, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 603] 2.0 CUPS-Get-Printers 1
D [27/Feb/2021:09:13:58 +0100] CUPS-Get-Printers
D [27/Feb/2021:09:13:58 +0100] [Client 603] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [27/Feb/2021:09:13:58 +0100] [Client 603] Content-Length: 1015
D [27/Feb/2021:09:13:58 +0100] [Client 603] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:13:58 +0100] [Client 603] con->http=0x55ef90f82830
D [27/Feb/2021:09:13:58 +0100] [Client 603] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1015, response=0x55ef9100fb10(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:13:58 +0100] [Client 603] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:13:58 +0100] [Client 603] bytes=0, http_state=0, data_remaining=1015
D [27/Feb/2021:09:13:58 +0100] [Client 603] Flushing write buffer.
D [27/Feb/2021:09:13:58 +0100] [Client 603] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:13:58 +0100] [Client 603] Waiting for request.
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:13:58 +0100] [Client 603] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:13:58 +0100] [Client 603] Closing connection.
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:58 +0100] [Client 604] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:58 +0100] [Client 604] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:58 +0100] [Client 604] Waiting for request.
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:13:58 +0100] [Client 605] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:58 +0100] [Client 605] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:58 +0100] [Client 605] Waiting for request.
D [27/Feb/2021:09:13:58 +0100] [Client 604] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:13:58 +0100] [Client 604] Closing connection.
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:13:58 +0100] [Client 605] POST / HTTP/1.1
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:58 +0100] [Client 605] Read: status=200, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 605] No authentication data provided.
D [27/Feb/2021:09:13:58 +0100] [Client 605] 2.0 CUPS-Get-Printers 1
D [27/Feb/2021:09:13:58 +0100] CUPS-Get-Printers
D [27/Feb/2021:09:13:58 +0100] [Client 605] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [27/Feb/2021:09:13:58 +0100] [Client 605] Content-Length: 1015
D [27/Feb/2021:09:13:58 +0100] [Client 605] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:13:58 +0100] [Client 605] con->http=0x55ef90f82830
D [27/Feb/2021:09:13:58 +0100] [Client 605] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1015, response=0x55ef90f59a30(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:13:58 +0100] [Client 605] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:13:58 +0100] [Client 605] bytes=0, http_state=0, data_remaining=1015
D [27/Feb/2021:09:13:58 +0100] [Client 605] Flushing write buffer.
D [27/Feb/2021:09:13:58 +0100] [Client 605] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:13:58 +0100] [Client 605] Waiting for request.
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:13:58 +0100] [Client 605] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:13:58 +0100] [Client 605] Closing connection.
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:58 +0100] [Client 606] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:58 +0100] [Client 606] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:58 +0100] [Client 606] Waiting for request.
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:13:58 +0100] [Client 607] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:58 +0100] [Client 607] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:58 +0100] [Client 607] Waiting for request.
D [27/Feb/2021:09:13:58 +0100] [Client 606] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:13:58 +0100] [Client 606] Closing connection.
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:13:58 +0100] [Client 607] POST / HTTP/1.1
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:58 +0100] [Client 607] Read: status=200, state=6
D [27/Feb/2021:09:13:58 +0100] [Client 607] No authentication data provided.
D [27/Feb/2021:09:13:58 +0100] [Client 607] 2.0 CUPS-Get-Printers 1
D [27/Feb/2021:09:13:58 +0100] CUPS-Get-Printers
D [27/Feb/2021:09:13:58 +0100] [Client 607] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [27/Feb/2021:09:13:58 +0100] [Client 607] Content-Length: 1015
D [27/Feb/2021:09:13:58 +0100] [Client 607] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:13:58 +0100] [Client 607] con->http=0x55ef90f82830
D [27/Feb/2021:09:13:58 +0100] [Client 607] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1015, response=0x55ef90f7a870(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:13:58 +0100] [Client 607] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:13:58 +0100] [Client 607] bytes=0, http_state=0, data_remaining=1015
D [27/Feb/2021:09:13:58 +0100] [Client 607] Flushing write buffer.
D [27/Feb/2021:09:13:58 +0100] [Client 607] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:13:58 +0100] [Client 607] Waiting for request.
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:13:58 +0100] [Client 607] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:13:58 +0100] [Client 607] Closing connection.
D [27/Feb/2021:09:13:58 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:59 +0100] [Client 608] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:59 +0100] [Client 608] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:59 +0100] [Client 608] Waiting for request.
I [27/Feb/2021:09:13:59 +0100] Expiring subscriptions...
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:13:59 +0100] [Client 609] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:59 +0100] [Client 609] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:59 +0100] [Client 609] Waiting for request.
D [27/Feb/2021:09:13:59 +0100] [Client 608] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:13:59 +0100] [Client 608] Closing connection.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:13:59 +0100] [Client 609] POST / HTTP/1.1
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=200, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] No authentication data provided.
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 609] 2.0 CUPS-Get-Printers 1
D [27/Feb/2021:09:13:59 +0100] CUPS-Get-Printers
D [27/Feb/2021:09:13:59 +0100] [Client 609] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [27/Feb/2021:09:13:59 +0100] [Client 609] Content-Length: 1015
D [27/Feb/2021:09:13:59 +0100] [Client 609] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:13:59 +0100] [Client 609] con->http=0x55ef90f82830
D [27/Feb/2021:09:13:59 +0100] [Client 609] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1015, response=0x55ef9100fb10(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:13:59 +0100] [Client 609] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:13:59 +0100] [Client 609] bytes=0, http_state=0, data_remaining=1015
D [27/Feb/2021:09:13:59 +0100] [Client 609] Flushing write buffer.
D [27/Feb/2021:09:13:59 +0100] [Client 609] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:13:59 +0100] [Client 609] Waiting for request.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:13:59 +0100] [Client 609] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:13:59 +0100] [Client 609] Closing connection.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:59 +0100] [Client 610] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:59 +0100] [Client 610] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:59 +0100] [Client 610] Waiting for request.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:13:59 +0100] [Client 611] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:59 +0100] [Client 611] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:59 +0100] [Client 611] Waiting for request.
D [27/Feb/2021:09:13:59 +0100] [Client 610] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:13:59 +0100] [Client 610] Closing connection.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:13:59 +0100] [Client 611] POST / HTTP/1.1
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=200, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] No authentication data provided.
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 611] 2.0 CUPS-Get-Printers 1
D [27/Feb/2021:09:13:59 +0100] CUPS-Get-Printers
D [27/Feb/2021:09:13:59 +0100] [Client 611] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [27/Feb/2021:09:13:59 +0100] [Client 611] Content-Length: 1015
D [27/Feb/2021:09:13:59 +0100] [Client 611] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:13:59 +0100] [Client 611] con->http=0x55ef90f82830
D [27/Feb/2021:09:13:59 +0100] [Client 611] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1015, response=0x55ef90f59a30(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:13:59 +0100] [Client 611] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:13:59 +0100] [Client 611] bytes=0, http_state=0, data_remaining=1015
D [27/Feb/2021:09:13:59 +0100] [Client 611] Flushing write buffer.
D [27/Feb/2021:09:13:59 +0100] [Client 611] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:13:59 +0100] [Client 611] Waiting for request.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:13:59 +0100] [Client 611] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:13:59 +0100] [Client 611] Closing connection.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:59 +0100] [Client 612] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:59 +0100] [Client 612] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:59 +0100] [Client 612] Waiting for request.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:13:59 +0100] [Client 613] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:59 +0100] [Client 613] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:59 +0100] [Client 613] Waiting for request.
D [27/Feb/2021:09:13:59 +0100] [Client 612] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:13:59 +0100] [Client 612] Closing connection.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:13:59 +0100] [Client 613] POST / HTTP/1.1
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=200, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] No authentication data provided.
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] Read: status=100, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 613] 2.0 CUPS-Get-Printers 1
D [27/Feb/2021:09:13:59 +0100] CUPS-Get-Printers
D [27/Feb/2021:09:13:59 +0100] [Client 613] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [27/Feb/2021:09:13:59 +0100] [Client 613] Content-Length: 1015
D [27/Feb/2021:09:13:59 +0100] [Client 613] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:13:59 +0100] [Client 613] con->http=0x55ef90f82830
D [27/Feb/2021:09:13:59 +0100] [Client 613] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1015, response=0x55ef90f7a870(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:13:59 +0100] [Client 613] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:13:59 +0100] [Client 613] bytes=0, http_state=0, data_remaining=1015
D [27/Feb/2021:09:13:59 +0100] [Client 613] Flushing write buffer.
D [27/Feb/2021:09:13:59 +0100] [Client 613] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:13:59 +0100] [Client 613] Waiting for request.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:13:59 +0100] [Client 613] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:13:59 +0100] [Client 613] Closing connection.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:59 +0100] [Client 614] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:59 +0100] [Client 614] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:59 +0100] [Client 614] Waiting for request.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:13:59 +0100] [Client 615] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:59 +0100] [Client 615] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:59 +0100] [Client 615] Waiting for request.
D [27/Feb/2021:09:13:59 +0100] [Client 614] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:13:59 +0100] [Client 614] Closing connection.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:13:59 +0100] [Client 615] POST / HTTP/1.1
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:59 +0100] [Client 615] Read: status=200, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 615] No authentication data provided.
D [27/Feb/2021:09:13:59 +0100] [Client 615] 2.0 CUPS-Get-Printers 1
D [27/Feb/2021:09:13:59 +0100] CUPS-Get-Printers
D [27/Feb/2021:09:13:59 +0100] [Client 615] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [27/Feb/2021:09:13:59 +0100] [Client 615] Content-Length: 1015
D [27/Feb/2021:09:13:59 +0100] [Client 615] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:13:59 +0100] [Client 615] con->http=0x55ef90f82830
D [27/Feb/2021:09:13:59 +0100] [Client 615] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1015, response=0x55ef9100fb10(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:13:59 +0100] [Client 615] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:13:59 +0100] [Client 615] bytes=0, http_state=0, data_remaining=1015
D [27/Feb/2021:09:13:59 +0100] [Client 615] Flushing write buffer.
D [27/Feb/2021:09:13:59 +0100] [Client 615] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:13:59 +0100] [Client 615] Waiting for request.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:13:59 +0100] [Client 615] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:13:59 +0100] [Client 615] Closing connection.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:59 +0100] [Client 616] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:59 +0100] [Client 616] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:59 +0100] [Client 616] Waiting for request.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:13:59 +0100] [Client 617] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:13:59 +0100] [Client 617] Accepted from localhost (Domain)
D [27/Feb/2021:09:13:59 +0100] [Client 617] Waiting for request.
D [27/Feb/2021:09:13:59 +0100] [Client 616] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:13:59 +0100] [Client 616] Closing connection.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:13:59 +0100] [Client 617] POST / HTTP/1.1
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:13:59 +0100] [Client 617] Read: status=200, state=6
D [27/Feb/2021:09:13:59 +0100] [Client 617] No authentication data provided.
D [27/Feb/2021:09:13:59 +0100] [Client 617] 2.0 CUPS-Get-Printers 1
D [27/Feb/2021:09:13:59 +0100] CUPS-Get-Printers
D [27/Feb/2021:09:13:59 +0100] [Client 617] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [27/Feb/2021:09:13:59 +0100] [Client 617] Content-Length: 1015
D [27/Feb/2021:09:13:59 +0100] [Client 617] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:13:59 +0100] [Client 617] con->http=0x55ef90f82830
D [27/Feb/2021:09:13:59 +0100] [Client 617] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1015, response=0x55ef90f59a30(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:13:59 +0100] [Client 617] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:13:59 +0100] [Client 617] bytes=0, http_state=0, data_remaining=1015
D [27/Feb/2021:09:13:59 +0100] [Client 617] Flushing write buffer.
D [27/Feb/2021:09:13:59 +0100] [Client 617] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:13:59 +0100] [Client 617] Waiting for request.
D [27/Feb/2021:09:13:59 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:00 +0100] [Client 617] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:00 +0100] [Client 617] Closing connection.
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:00 +0100] [Client 618] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:00 +0100] [Client 618] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:00 +0100] [Client 618] Waiting for request.
I [27/Feb/2021:09:14:00 +0100] Expiring subscriptions...
D [27/Feb/2021:09:14:00 +0100] [Client 618] GET /printers/HP_LaserJet_Professional_P_1102w.ppd HTTP/1.1
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:14:00 +0100] [Client 618] Read: status=200, state=3
D [27/Feb/2021:09:14:00 +0100] [Client 618] No authentication data provided.
D [27/Feb/2021:09:14:00 +0100] [Client 618] Processing GET /printers/HP_LaserJet_Professional_P_1102w.ppd
D [27/Feb/2021:09:14:00 +0100] [Client 618] filename="/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd", type=application/vnd.cups-ppd
D [27/Feb/2021:09:14:00 +0100] [Client 618] cupsdSendHeader: code=200, type="application/vnd.cups-ppd", auth_type=0
D [27/Feb/2021:09:14:00 +0100] [Client 618] Sending file.
D [27/Feb/2021:09:14:00 +0100] [Client 618] con->http=0x55ef90f7f190
D [27/Feb/2021:09:14:00 +0100] [Client 618] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=11018, response=(nil)(), pipe_pid=0, file=13
D [27/Feb/2021:09:14:00 +0100] [Client 618] con->http=0x55ef90f7f190
D [27/Feb/2021:09:14:00 +0100] [Client 618] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=11018, response=(nil)(), pipe_pid=0, file=13
D [27/Feb/2021:09:14:00 +0100] [Client 618] con->http=0x55ef90f7f190
D [27/Feb/2021:09:14:00 +0100] [Client 618] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=11018, response=(nil)(), pipe_pid=0, file=13
D [27/Feb/2021:09:14:00 +0100] [Client 618] con->http=0x55ef90f7f190
D [27/Feb/2021:09:14:00 +0100] [Client 618] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=11018, response=(nil)(), pipe_pid=0, file=13
D [27/Feb/2021:09:14:00 +0100] [Client 618] con->http=0x55ef90f7f190
D [27/Feb/2021:09:14:00 +0100] [Client 618] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=11018, response=(nil)(), pipe_pid=0, file=13
D [27/Feb/2021:09:14:00 +0100] [Client 618] con->http=0x55ef90f7f190
D [27/Feb/2021:09:14:00 +0100] [Client 618] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=11018, response=(nil)(), pipe_pid=0, file=13
D [27/Feb/2021:09:14:00 +0100] [Client 618] Flushing write buffer.
D [27/Feb/2021:09:14:00 +0100] [Client 618] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:00 +0100] [Client 618] Waiting for request.
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:00 +0100] [Client 618] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:00 +0100] [Client 618] Closing connection.
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:00 +0100] [Client 619] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:00 +0100] [Client 619] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:00 +0100] [Client 619] Waiting for request.
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:14:00 +0100] [Client 620] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:00 +0100] [Client 620] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:00 +0100] [Client 620] Waiting for request.
D [27/Feb/2021:09:14:00 +0100] [Client 619] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:00 +0100] [Client 619] Closing connection.
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:00 +0100] [Client 620] POST / HTTP/1.1
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:00 +0100] [Client 620] Read: status=200, state=6
D [27/Feb/2021:09:14:00 +0100] [Client 620] No authentication data provided.
D [27/Feb/2021:09:14:00 +0100] [Client 620] 2.0 CUPS-Get-Printers 1
D [27/Feb/2021:09:14:00 +0100] CUPS-Get-Printers
D [27/Feb/2021:09:14:00 +0100] [Client 620] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [27/Feb/2021:09:14:00 +0100] [Client 620] Content-Length: 1015
D [27/Feb/2021:09:14:00 +0100] [Client 620] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:00 +0100] [Client 620] con->http=0x55ef90f82830
D [27/Feb/2021:09:14:00 +0100] [Client 620] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1015, response=0x55ef90f7a870(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:00 +0100] [Client 620] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:00 +0100] [Client 620] bytes=0, http_state=0, data_remaining=1015
D [27/Feb/2021:09:14:00 +0100] [Client 620] Flushing write buffer.
D [27/Feb/2021:09:14:00 +0100] [Client 620] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:00 +0100] [Client 620] Waiting for request.
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:00 +0100] [Client 620] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:00 +0100] [Client 620] Closing connection.
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:00 +0100] [Client 621] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:00 +0100] [Client 621] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:00 +0100] [Client 621] Waiting for request.
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:14:00 +0100] [Client 622] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:00 +0100] [Client 622] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:00 +0100] [Client 622] Waiting for request.
D [27/Feb/2021:09:14:00 +0100] [Client 621] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:00 +0100] [Client 621] Closing connection.
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:00 +0100] [Client 622] POST / HTTP/1.1
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:00 +0100] [Client 622] Read: status=200, state=6
D [27/Feb/2021:09:14:00 +0100] [Client 622] No authentication data provided.
D [27/Feb/2021:09:14:00 +0100] [Client 622] 2.0 CUPS-Get-Printers 1
D [27/Feb/2021:09:14:00 +0100] CUPS-Get-Printers
D [27/Feb/2021:09:14:00 +0100] [Client 622] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [27/Feb/2021:09:14:00 +0100] [Client 622] Content-Length: 1015
D [27/Feb/2021:09:14:00 +0100] [Client 622] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:00 +0100] [Client 622] con->http=0x55ef90f82830
D [27/Feb/2021:09:14:00 +0100] [Client 622] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1015, response=0x55ef9100fb10(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:00 +0100] [Client 622] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:00 +0100] [Client 622] bytes=0, http_state=0, data_remaining=1015
D [27/Feb/2021:09:14:00 +0100] [Client 622] Flushing write buffer.
D [27/Feb/2021:09:14:00 +0100] [Client 622] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:00 +0100] [Client 622] Waiting for request.
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:00 +0100] [Client 622] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:00 +0100] [Client 622] Closing connection.
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:00 +0100] [Client 623] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:00 +0100] [Client 623] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:00 +0100] [Client 623] Waiting for request.
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:14:00 +0100] [Client 624] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:00 +0100] [Client 624] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:00 +0100] [Client 624] Waiting for request.
D [27/Feb/2021:09:14:00 +0100] [Client 623] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:00 +0100] [Client 623] Closing connection.
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:00 +0100] [Client 624] POST / HTTP/1.1
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:00 +0100] [Client 624] Read: status=200, state=6
D [27/Feb/2021:09:14:00 +0100] [Client 624] No authentication data provided.
D [27/Feb/2021:09:14:00 +0100] [Client 624] 2.0 CUPS-Get-Printers 1
D [27/Feb/2021:09:14:00 +0100] CUPS-Get-Printers
D [27/Feb/2021:09:14:00 +0100] [Client 624] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [27/Feb/2021:09:14:00 +0100] [Client 624] Content-Length: 1015
D [27/Feb/2021:09:14:00 +0100] [Client 624] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:00 +0100] [Client 624] con->http=0x55ef90f82830
D [27/Feb/2021:09:14:00 +0100] [Client 624] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1015, response=0x55ef90f59a30(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:00 +0100] [Client 624] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:00 +0100] [Client 624] bytes=0, http_state=0, data_remaining=1015
D [27/Feb/2021:09:14:00 +0100] [Client 624] Flushing write buffer.
D [27/Feb/2021:09:14:00 +0100] [Client 624] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:00 +0100] [Client 624] Waiting for request.
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:00 +0100] [Client 624] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:00 +0100] [Client 624] Closing connection.
D [27/Feb/2021:09:14:00 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:01 +0100] [Client 625] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:01 +0100] [Client 625] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:01 +0100] [Client 625] Waiting for request.
I [27/Feb/2021:09:14:01 +0100] Expiring subscriptions...
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:14:01 +0100] [Client 626] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:01 +0100] [Client 626] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:01 +0100] [Client 626] Waiting for request.
D [27/Feb/2021:09:14:01 +0100] [Client 625] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:01 +0100] [Client 625] Closing connection.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:01 +0100] [Client 626] POST / HTTP/1.1
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=200, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] No authentication data provided.
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] Read: status=100, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 626] 2.0 CUPS-Get-Printers 1
D [27/Feb/2021:09:14:01 +0100] CUPS-Get-Printers
D [27/Feb/2021:09:14:01 +0100] [Client 626] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [27/Feb/2021:09:14:01 +0100] [Client 626] Content-Length: 1015
D [27/Feb/2021:09:14:01 +0100] [Client 626] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:01 +0100] [Client 626] con->http=0x55ef90f82830
D [27/Feb/2021:09:14:01 +0100] [Client 626] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1015, response=0x55ef90f7a870(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:01 +0100] [Client 626] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:01 +0100] [Client 626] bytes=0, http_state=0, data_remaining=1015
D [27/Feb/2021:09:14:01 +0100] [Client 626] Flushing write buffer.
D [27/Feb/2021:09:14:01 +0100] [Client 626] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:01 +0100] [Client 626] Waiting for request.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:01 +0100] [Client 626] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:01 +0100] [Client 626] Closing connection.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:01 +0100] [Client 627] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:01 +0100] [Client 627] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:01 +0100] [Client 627] Waiting for request.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:14:01 +0100] [Client 628] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:01 +0100] [Client 628] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:01 +0100] [Client 628] Waiting for request.
D [27/Feb/2021:09:14:01 +0100] [Client 627] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:01 +0100] [Client 627] Closing connection.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:01 +0100] [Client 628] POST / HTTP/1.1
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:01 +0100] [Client 628] Read: status=200, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 628] No authentication data provided.
D [27/Feb/2021:09:14:01 +0100] [Client 628] 2.0 CUPS-Get-Printers 1
D [27/Feb/2021:09:14:01 +0100] CUPS-Get-Printers
D [27/Feb/2021:09:14:01 +0100] [Client 628] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [27/Feb/2021:09:14:01 +0100] [Client 628] Content-Length: 1015
D [27/Feb/2021:09:14:01 +0100] [Client 628] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:01 +0100] [Client 628] con->http=0x55ef90f82830
D [27/Feb/2021:09:14:01 +0100] [Client 628] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1015, response=0x55ef9100fb10(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:01 +0100] [Client 628] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:01 +0100] [Client 628] bytes=0, http_state=0, data_remaining=1015
D [27/Feb/2021:09:14:01 +0100] [Client 628] Flushing write buffer.
D [27/Feb/2021:09:14:01 +0100] [Client 628] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:01 +0100] [Client 628] Waiting for request.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:01 +0100] [Client 628] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:01 +0100] [Client 628] Closing connection.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:01 +0100] [Client 629] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:01 +0100] [Client 629] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:01 +0100] [Client 629] Waiting for request.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:14:01 +0100] [Client 630] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:01 +0100] [Client 630] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:01 +0100] [Client 630] Waiting for request.
D [27/Feb/2021:09:14:01 +0100] [Client 629] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:01 +0100] [Client 629] Closing connection.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:01 +0100] [Client 630] POST / HTTP/1.1
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:01 +0100] [Client 630] Read: status=200, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 630] No authentication data provided.
D [27/Feb/2021:09:14:01 +0100] [Client 630] 2.0 CUPS-Get-Printers 1
D [27/Feb/2021:09:14:01 +0100] CUPS-Get-Printers
D [27/Feb/2021:09:14:01 +0100] [Client 630] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [27/Feb/2021:09:14:01 +0100] [Client 630] Content-Length: 1015
D [27/Feb/2021:09:14:01 +0100] [Client 630] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:01 +0100] [Client 630] con->http=0x55ef90f82830
D [27/Feb/2021:09:14:01 +0100] [Client 630] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1015, response=0x55ef90f59a30(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:01 +0100] [Client 630] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:01 +0100] [Client 630] bytes=0, http_state=0, data_remaining=1015
D [27/Feb/2021:09:14:01 +0100] [Client 630] Flushing write buffer.
D [27/Feb/2021:09:14:01 +0100] [Client 630] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:01 +0100] [Client 630] Waiting for request.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:01 +0100] [Client 630] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:01 +0100] [Client 630] Closing connection.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:01 +0100] [Client 631] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:01 +0100] [Client 631] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:01 +0100] [Client 631] Waiting for request.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:14:01 +0100] [Client 632] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:01 +0100] [Client 632] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:01 +0100] [Client 632] Waiting for request.
D [27/Feb/2021:09:14:01 +0100] [Client 631] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:01 +0100] [Client 631] Closing connection.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:01 +0100] [Client 632] POST / HTTP/1.1
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:01 +0100] [Client 632] Read: status=200, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 632] No authentication data provided.
D [27/Feb/2021:09:14:01 +0100] [Client 632] 2.0 CUPS-Get-Printers 1
D [27/Feb/2021:09:14:01 +0100] CUPS-Get-Printers
D [27/Feb/2021:09:14:01 +0100] [Client 632] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [27/Feb/2021:09:14:01 +0100] [Client 632] Content-Length: 1015
D [27/Feb/2021:09:14:01 +0100] [Client 632] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:01 +0100] [Client 632] con->http=0x55ef90f82830
D [27/Feb/2021:09:14:01 +0100] [Client 632] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1015, response=0x55ef90f7a870(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:01 +0100] [Client 632] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:01 +0100] [Client 632] bytes=0, http_state=0, data_remaining=1015
D [27/Feb/2021:09:14:01 +0100] [Client 632] Flushing write buffer.
D [27/Feb/2021:09:14:01 +0100] [Client 632] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:01 +0100] [Client 632] Waiting for request.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:01 +0100] [Client 632] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:01 +0100] [Client 632] Closing connection.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:01 +0100] [Client 633] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:01 +0100] [Client 633] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:01 +0100] [Client 633] Waiting for request.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:14:01 +0100] [Client 634] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:01 +0100] [Client 634] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:01 +0100] [Client 634] Waiting for request.
D [27/Feb/2021:09:14:01 +0100] [Client 633] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:01 +0100] [Client 633] Closing connection.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:01 +0100] [Client 634] POST / HTTP/1.1
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:01 +0100] [Client 634] Read: status=200, state=6
D [27/Feb/2021:09:14:01 +0100] [Client 634] No authentication data provided.
D [27/Feb/2021:09:14:01 +0100] [Client 634] 2.0 CUPS-Get-Printers 1
D [27/Feb/2021:09:14:01 +0100] CUPS-Get-Printers
D [27/Feb/2021:09:14:01 +0100] [Client 634] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [27/Feb/2021:09:14:01 +0100] [Client 634] Content-Length: 1015
D [27/Feb/2021:09:14:01 +0100] [Client 634] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:01 +0100] [Client 634] con->http=0x55ef90f82830
D [27/Feb/2021:09:14:01 +0100] [Client 634] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1015, response=0x55ef9100fb10(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:01 +0100] [Client 634] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:01 +0100] [Client 634] bytes=0, http_state=0, data_remaining=1015
D [27/Feb/2021:09:14:01 +0100] [Client 634] Flushing write buffer.
D [27/Feb/2021:09:14:01 +0100] [Client 634] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:01 +0100] [Client 634] Waiting for request.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Feb/2021:09:14:01 +0100] [Client 634] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:01 +0100] [Client 634] Closing connection.
D [27/Feb/2021:09:14:01 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Feb/2021:09:14:02 +0100] [Client 635] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:02 +0100] [Client 635] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:02 +0100] [Client 635] Waiting for request.
I [27/Feb/2021:09:14:02 +0100] Expiring subscriptions...
D [27/Feb/2021:09:14:02 +0100] [Client 635] POST /printers/HP_LaserJet_Professional_P_1102w HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [27/Feb/2021:09:14:02 +0100] [Client 635] Read: status=200, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 635] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 635] Read: status=100, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 635] Read: status=100, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 635] Read: status=100, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 635] Read: status=100, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 635] Read: status=100, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 635] Read: status=100, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 635] Read: status=100, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 635] Read: status=100, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 635] Read: status=100, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 635] Read: status=100, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 635] Read: status=100, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 635] Read: status=100, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 635] Read: status=100, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 635] Read: status=100, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 635] Read: status=100, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 635] Read: status=100, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 635] 2.1 Print-Job 1
D [27/Feb/2021:09:14:02 +0100] Print-Job ipp://localhost:631/printers/HP_LaserJet_Professional_P_1102w
D [27/Feb/2021:09:14:02 +0100] [Job ???] Auto-typing file...
I [27/Feb/2021:09:14:02 +0100] [Job ???] Request file type is application/pdf.
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(---J-)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/Feb/2021:09:14:02 +0100] [Job 122] Applying default options...
D [27/Feb/2021:09:14:02 +0100] add_job: requesting-user-name="raphael"
I [27/Feb/2021:09:14:02 +0100] [Job 122] Adding start banner page "none".
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(----S)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(---J-)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [27/Feb/2021:09:14:02 +0100] [Job 122] Adding end banner page "none".
I [27/Feb/2021:09:14:02 +0100] [Job 122] File of type application/pdf queued by "raphael".
D [27/Feb/2021:09:14:02 +0100] [Job 122] hold_until=0
I [27/Feb/2021:09:14:02 +0100] [Job 122] Queued on "HP_LaserJet_Professional_P_1102w" by "raphael".
D [27/Feb/2021:09:14:02 +0100] [Job 122] time-at-processing=1614413642
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(---J-)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(----S)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Job 122] 3 filters for job:
D [27/Feb/2021:09:14:02 +0100] [Job 122] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [27/Feb/2021:09:14:02 +0100] [Job 122] gstoraster (application/vnd.cups-pdf to application/vnd.cups-raster, cost 99)
D [27/Feb/2021:09:14:02 +0100] [Job 122] hpcups (application/vnd.cups-raster to printer/HP_LaserJet_Professional_P_1102w, cost 0)
D [27/Feb/2021:09:14:02 +0100] [Job 122] job-sheets=none,none
D [27/Feb/2021:09:14:02 +0100] [Job 122] argv[0]="HP_LaserJet_Professional_P_1102w"
D [27/Feb/2021:09:14:02 +0100] [Job 122] argv[1]="122"
D [27/Feb/2021:09:14:02 +0100] [Job 122] argv[2]="raphael"
D [27/Feb/2021:09:14:02 +0100] [Job 122] argv[3]="Mousepad, tâche n°1"
D [27/Feb/2021:09:14:02 +0100] [Job 122] argv[4]="1"
D [27/Feb/2021:09:14:02 +0100] [Job 122] argv[5]="PageSize=A4 OutputMode=Normal MediaType=Plain number-up=1 InputSlot=Auto noCollate job-uuid=urn:uuid:cb4f7c51-253f-3047-5067-39407ff04f38 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1614413642 time-at-processing=1614413642"
D [27/Feb/2021:09:14:02 +0100] [Job 122] argv[6]="/var/spool/cups/d00122-001"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[7]="CUPS_STATEDIR=/run/cups"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[8]="HOME=/var/spool/cups/tmp"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[10]="SERVER_ADMIN=root@archlinux"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[11]="SOFTWARE=CUPS/2.3.3op2"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[13]="USER=root"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[14]="CUPS_MAX_MESSAGE=2047"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[15]="CUPS_SERVER=/run/cups/cups.sock"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[17]="IPP_PORT=631"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[18]="CHARSET=utf-8"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[19]="LANG=fr_FR.UTF-8"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[20]="PPD=/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[21]="RIP_MAX_CACHE=128m"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[22]="CONTENT_TYPE=application/pdf"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[23]="DEVICE_URI=hp:/usb/HP_LaserJet_Professional_P_1102w?serial=000000000W445V0LPR1a"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[24]="PRINTER_INFO=HP LaserJet Professional P 1102w"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[25]="PRINTER_LOCATION="
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[26]="PRINTER=HP_LaserJet_Professional_P_1102w"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[27]="PRINTER_STATE_REASONS=none"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[28]="CUPS_FILETYPE=document"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-raster"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[30]="AUTH_I****"
I [27/Feb/2021:09:14:02 +0100] [Job 122] Started filter /usr/lib/cups/filter/pdftopdf (PID 3681)
I [27/Feb/2021:09:14:02 +0100] [Job 122] Started filter /usr/lib/cups/filter/gstoraster (PID 3682)
I [27/Feb/2021:09:14:02 +0100] [Job 122] Started filter /usr/lib/cups/filter/hpcups (PID 3683)
I [27/Feb/2021:09:14:02 +0100] [Job 122] Started backend /usr/lib/cups/backend/hp (PID 3684)
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(----S)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 635] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/HP_LaserJet_Professional_P_1102w) from localhost.
D [27/Feb/2021:09:14:02 +0100] [Client 635] Content-Length: 193
D [27/Feb/2021:09:14:02 +0100] [Client 635] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:02 +0100] [Client 635] con->http=0x55ef90f7f190
D [27/Feb/2021:09:14:02 +0100] [Client 635] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=193, response=0x55ef90f7a870(IPP_STATE_IDLE), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:02 +0100] [Client 635] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:02 +0100] [Client 635] bytes=0, http_state=0, data_remaining=193
D [27/Feb/2021:09:14:02 +0100] [Client 635] Flushing write buffer.
D [27/Feb/2021:09:14:02 +0100] [Client 635] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:02 +0100] [Client 635] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 635] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:02 +0100] [Client 635] Closing connection.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Job 122] pdftopdf: Last filter determined by the PPD: hpcups; FINAL_CONTENT_TYPE: application/vnd.cups-raster => pdftopdf will not log pages in page_log.
D [27/Feb/2021:09:14:02 +0100] [Job 122] PDF interactive form and annotation flattening done via QPDF
D [27/Feb/2021:09:14:02 +0100] [Job 122] OUTFORMAT=\"<none>\", so output format will be CUPS/PWG Raster
D [27/Feb/2021:09:14:02 +0100] [Job 122] PID 3681 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [27/Feb/2021:09:14:02 +0100] [Job 122] Color Manager: Calibration Mode/Off
D [27/Feb/2021:09:14:02 +0100] [Job 122] Calling FindDeviceById(cups-HP_LaserJet_Professional_P_1102w)
D [27/Feb/2021:09:14:02 +0100] [Job 122] Found device /org/freedesktop/ColorManager/devices/cups_HP_LaserJet_Professional_P_1102w
D [27/Feb/2021:09:14:02 +0100] [Job 122] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors)
D [27/Feb/2021:09:14:02 +0100] [Job 122] Calling FindDeviceById(cups-HP_LaserJet_Professional_P_1102w)
D [27/Feb/2021:09:14:02 +0100] [Job 122] Found device /org/freedesktop/ColorManager/devices/cups_HP_LaserJet_Professional_P_1102w
D [27/Feb/2021:09:14:02 +0100] [Job 122] Calling GetProfileForQualifiers(Gray.Plain....)
D [27/Feb/2021:09:14:02 +0100] [Job 122] Failed to send: org.freedesktop.ColorManager.Device.NothingMatched:nothing matched expression \'Gray.Plain.,Gray.Plain.*,Gray.*.,Gray.*.*,*\'
D [27/Feb/2021:09:14:02 +0100] [Job 122] Failed to get profile filename for cups-HP_LaserJet_Professional_P_1102w
D [27/Feb/2021:09:14:02 +0100] [Job 122] Color Manager: no profiles specified in PPD
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(---J-)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(---J-)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Job 122] Set job-printer-state-message to "Color Manager: no profiles specified in PPD", current level=INFO
D [27/Feb/2021:09:14:02 +0100] [Job 122] Color Manager: ICC Profile: None
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(----S)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(----S)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Job 122] Ghostscript using Any-Part-of-Pixel method to fill paths.
D [27/Feb/2021:09:14:02 +0100] [Job 122] Ghostscript command line: gs -dQUIET -dSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -dNOMEDIAATTRS -dShowAcroForm -sstdout=%stderr -sOutputFile=%stdout -sDEVICE=cups -sMediaType=Plain -sOutputType=0 -r600x600 -dMediaPosition=7 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=841 -dcupsBitsPerColor=1 -dcupsColorOrder=0 -dcupsColorSpace=3 -dcupsRowCount=1 -dcupsRowStep=2 -dcupsInteger0=9 -scupsPageSizeName=A4 -I/usr/share/cups/fonts -c \'<</.HWMargins[18.000000 15.500000 18.000000 15.500000] /Margins[0 0]>>setpagedevice\' -f -_
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[0]=\"CUPS_CACHEDIR=/var/cache/cups\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[1]=\"CUPS_DATADIR=/usr/share/cups\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[2]=\"CUPS_DOCROOT=/usr/share/cups/doc\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[3]=\"CUPS_FONTPATH=/usr/share/cups/fonts\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[4]=\"CUPS_REQUESTROOT=/var/spool/cups\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[5]=\"CUPS_SERVERBIN=/usr/lib/cups\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[6]=\"CUPS_SERVERROOT=/etc/cups\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[7]=\"CUPS_STATEDIR=/run/cups\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[8]=\"HOME=/var/spool/cups/tmp\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[9]=\"PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[10]=\"SERVER_ADMIN=root@archlinux\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[11]=\"SOFTWARE=CUPS/2.3.3op2\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[12]=\"TMPDIR=/var/spool/cups/tmp\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[13]=\"USER=root\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[14]=\"CUPS_MAX_MESSAGE=2047\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[15]=\"CUPS_SERVER=/run/cups/cups.sock\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[16]=\"CUPS_ENCRYPTION=IfRequested\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[17]=\"IPP_PORT=631\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[18]=\"CHARSET=utf-8\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[19]=\"LANG=fr_FR.UTF-8\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[20]=\"PPD=/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[21]=\"RIP_MAX_CACHE=128m\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[22]=\"CONTENT_TYPE=application/pdf\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[23]=\"DEVICE_URI=hp:/usb/HP_LaserJet_Professional_P_1102w?serial=000000000W445V0LPR1a\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[24]=\"PRINTER_INFO=HP LaserJet Professional P 1102w\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[25]=\"PRINTER_LOCATION=\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[26]=\"PRINTER=HP_LaserJet_Professional_P_1102w\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[27]=\"PRINTER_STATE_REASONS=none\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[28]=\"CUPS_FILETYPE=document\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[29]=\"FINAL_CONTENT_TYPE=application/vnd.cups-raster\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] envp[30]=\"AUTH_INFO_REQUIRED=none\"
D [27/Feb/2021:09:14:02 +0100] [Job 122] Start rendering...
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(---J-)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Job 122] Set job-printer-state-message to "Start rendering...", current level=INFO
D [27/Feb/2021:09:14:02 +0100] [Job 122] Processing page 1...
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(---J-)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Job 122] Set job-printer-state-message to "Processing page 1...", current level=INFO
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(----S)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(----S)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Job 122] STATE: +connecting-to-device
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(---J-)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(----S)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [27/Feb/2021:09:14:02 +0100] [Job 122] STATE: +hplip.plugin-error
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(P----)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(---J-)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [27/Feb/2021:09:14:02 +0100] [Job 122] prnt/hpcups/HPCupsFilter.cpp 489: m_Job initialization failed with error = 48
D [27/Feb/2021:09:14:02 +0100] [Job 122] corrupted double-linked list
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(----S)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [27/Feb/2021:09:14:02 +0100] [Job 122] STATE: -connecting-to-device
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(---J-)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(----S)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Job 122] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(P----)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(---J-)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(----S)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Job 122] PID 3683 (/usr/lib/cups/filter/hpcups) crashed on signal 6.
D [27/Feb/2021:09:14:02 +0100] [Job 122] Processing page 2...
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(----S)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(----S)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Job 122] Rendering completed
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(----S)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdMarkDirty(----S)
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Job 122] PID 3682 (/usr/lib/cups/filter/gstoraster) exited with no errors.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 636] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:02 +0100] [Client 636] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:02 +0100] [Client 636] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] [Client 636] POST / HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 636] Read: status=200, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 636] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 636] 2.0 Get-Notifications 334
D [27/Feb/2021:09:14:02 +0100] Get-Notifications /
D [27/Feb/2021:09:14:02 +0100] cupsdIsAuthorized: requesting-user-name="raphael"
D [27/Feb/2021:09:14:02 +0100] [Client 636] Returning IPP successful-ok for Get-Notifications (/) from localhost.
D [27/Feb/2021:09:14:02 +0100] [Client 636] Content-Length: 7963
D [27/Feb/2021:09:14:02 +0100] [Client 636] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:02 +0100] [Client 636] con->http=0x55ef90f91420
D [27/Feb/2021:09:14:02 +0100] [Client 636] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7963, response=0x55ef90f94cf0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:02 +0100] [Client 636] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:02 +0100] [Client 636] bytes=0, http_state=0, data_remaining=7963
D [27/Feb/2021:09:14:02 +0100] [Client 636] Flushing write buffer.
D [27/Feb/2021:09:14:02 +0100] [Client 636] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:02 +0100] [Client 636] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 636] POST / HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 636] Read: status=200, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 636] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 636] 2.0 Get-Job-Attributes 335
D [27/Feb/2021:09:14:02 +0100] Get-Job-Attributes ipp://localhost/jobs/122
D [27/Feb/2021:09:14:02 +0100] [Client 636] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/122) from localhost.
D [27/Feb/2021:09:14:02 +0100] [Client 636] Content-Length: 1119
D [27/Feb/2021:09:14:02 +0100] [Client 636] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:02 +0100] [Client 636] con->http=0x55ef90f91420
D [27/Feb/2021:09:14:02 +0100] [Client 636] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1119, response=0x55ef90f94ac0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:02 +0100] [Client 636] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:02 +0100] [Client 636] bytes=0, http_state=0, data_remaining=1119
D [27/Feb/2021:09:14:02 +0100] [Client 636] Flushing write buffer.
D [27/Feb/2021:09:14:02 +0100] [Client 636] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:02 +0100] [Client 636] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 637] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:02 +0100] [Client 637] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:02 +0100] [Client 637] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] [Client 637] POST / HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 637] Read: status=200, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 637] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 637] 2.0 Get-Printer-Attributes 336
D [27/Feb/2021:09:14:02 +0100] Get-Printer-Attributes 
D [27/Feb/2021:09:14:02 +0100] Get-Printer-Attributes client-error-not-found: The printer or class does not exist.
D [27/Feb/2021:09:14:02 +0100] [Client 637] Returning IPP client-error-not-found for Get-Printer-Attributes () from localhost.
D [27/Feb/2021:09:14:02 +0100] [Client 637] Content-Length: 130
D [27/Feb/2021:09:14:02 +0100] [Client 637] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:02 +0100] [Client 637] con->http=0x55ef90f96260
D [27/Feb/2021:09:14:02 +0100] [Client 637] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=130, response=0x55ef90f94c00(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:02 +0100] [Client 637] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:02 +0100] [Client 637] bytes=0, http_state=0, data_remaining=130
D [27/Feb/2021:09:14:02 +0100] [Client 637] Flushing write buffer.
D [27/Feb/2021:09:14:02 +0100] [Client 637] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:02 +0100] [Client 637] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 637] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:02 +0100] [Client 637] Closing connection.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 638] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:02 +0100] [Client 638] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:02 +0100] [Client 638] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] [Client 638] POST / HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 638] Read: status=200, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 638] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 638] 2.0 Get-Job-Attributes 337
D [27/Feb/2021:09:14:02 +0100] Get-Job-Attributes ipp://localhost/jobs/122
D [27/Feb/2021:09:14:02 +0100] [Client 638] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/122) from localhost.
D [27/Feb/2021:09:14:02 +0100] [Client 638] Content-Length: 196
D [27/Feb/2021:09:14:02 +0100] [Client 638] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:02 +0100] [Client 638] con->http=0x55ef90f96260
D [27/Feb/2021:09:14:02 +0100] [Client 638] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=196, response=0x55ef90f94c10(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:02 +0100] [Client 638] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:02 +0100] [Client 638] bytes=0, http_state=0, data_remaining=196
D [27/Feb/2021:09:14:02 +0100] [Client 638] Flushing write buffer.
D [27/Feb/2021:09:14:02 +0100] [Client 638] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:02 +0100] [Client 638] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 638] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:02 +0100] [Client 638] Closing connection.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 639] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:02 +0100] [Client 639] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:02 +0100] [Client 639] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] [Client 639] POST / HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 639] Read: status=200, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 639] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 639] 2.0 Get-Job-Attributes 338
D [27/Feb/2021:09:14:02 +0100] Get-Job-Attributes ipp://localhost/jobs/122
D [27/Feb/2021:09:14:02 +0100] [Client 639] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/122) from localhost.
D [27/Feb/2021:09:14:02 +0100] [Client 639] Content-Length: 101
D [27/Feb/2021:09:14:02 +0100] [Client 639] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:02 +0100] [Client 639] con->http=0x55ef90f96260
D [27/Feb/2021:09:14:02 +0100] [Client 639] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=101, response=0x55ef90f76a90(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:02 +0100] [Client 639] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:02 +0100] [Client 639] bytes=0, http_state=0, data_remaining=101
D [27/Feb/2021:09:14:02 +0100] [Client 639] Flushing write buffer.
D [27/Feb/2021:09:14:02 +0100] [Client 639] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:02 +0100] [Client 639] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 639] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:02 +0100] [Client 639] Closing connection.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 640] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:02 +0100] [Client 640] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:02 +0100] [Client 640] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] [Client 640] POST / HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 640] Read: status=200, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 640] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 640] 2.0 Get-Job-Attributes 339
D [27/Feb/2021:09:14:02 +0100] Get-Job-Attributes ipp://localhost/jobs/122
D [27/Feb/2021:09:14:02 +0100] [Client 640] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/122) from localhost.
D [27/Feb/2021:09:14:02 +0100] [Client 640] Content-Length: 101
D [27/Feb/2021:09:14:02 +0100] [Client 640] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:02 +0100] [Client 640] con->http=0x55ef90f96260
D [27/Feb/2021:09:14:02 +0100] [Client 640] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=101, response=0x55ef90f7a1b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:02 +0100] [Client 640] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:02 +0100] [Client 640] bytes=0, http_state=0, data_remaining=101
D [27/Feb/2021:09:14:02 +0100] [Client 640] Flushing write buffer.
D [27/Feb/2021:09:14:02 +0100] [Client 640] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:02 +0100] [Client 640] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 640] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:02 +0100] [Client 640] Closing connection.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 641] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:02 +0100] [Client 641] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:02 +0100] [Client 641] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] [Client 641] POST / HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 641] Read: status=200, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 641] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 641] 2.0 Get-Job-Attributes 340
D [27/Feb/2021:09:14:02 +0100] Get-Job-Attributes ipp://localhost/jobs/122
D [27/Feb/2021:09:14:02 +0100] [Client 641] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/122) from localhost.
D [27/Feb/2021:09:14:02 +0100] [Client 641] Content-Length: 101
D [27/Feb/2021:09:14:02 +0100] [Client 641] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:02 +0100] [Client 641] con->http=0x55ef90f95fb0
D [27/Feb/2021:09:14:02 +0100] [Client 641] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=101, response=0x55ef90f76b40(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:02 +0100] [Client 641] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:02 +0100] [Client 641] bytes=0, http_state=0, data_remaining=101
D [27/Feb/2021:09:14:02 +0100] [Client 641] Flushing write buffer.
D [27/Feb/2021:09:14:02 +0100] [Client 641] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:02 +0100] [Client 641] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 641] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:02 +0100] [Client 641] Closing connection.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 642] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:02 +0100] [Client 642] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:02 +0100] [Client 642] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] [Client 642] POST / HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 642] Read: status=200, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 642] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 642] 2.0 Get-Job-Attributes 341
D [27/Feb/2021:09:14:02 +0100] Get-Job-Attributes ipp://localhost/jobs/122
D [27/Feb/2021:09:14:02 +0100] [Client 642] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/122) from localhost.
D [27/Feb/2021:09:14:02 +0100] [Client 642] Content-Length: 101
D [27/Feb/2021:09:14:02 +0100] [Client 642] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:02 +0100] [Client 642] con->http=0x55ef90f95f80
D [27/Feb/2021:09:14:02 +0100] [Client 642] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=101, response=0x55ef90f76d20(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:02 +0100] [Client 642] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:02 +0100] [Client 642] bytes=0, http_state=0, data_remaining=101
D [27/Feb/2021:09:14:02 +0100] [Client 642] Flushing write buffer.
D [27/Feb/2021:09:14:02 +0100] [Client 642] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:02 +0100] [Client 642] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 642] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:02 +0100] [Client 642] Closing connection.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 588] POST /printers/HP_LaserJet_Professional_P_1102w HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 588] Read: status=200, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 588] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 588] 2.0 Get-Printer-Attributes 342
D [27/Feb/2021:09:14:02 +0100] Get-Printer-Attributes ipp://localhost/printers/HP_LaserJet_Professional_P_1102w
D [27/Feb/2021:09:14:02 +0100] [Client 588] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_LaserJet_Professional_P_1102w) from localhost.
D [27/Feb/2021:09:14:02 +0100] [Client 588] Content-Length: 159
D [27/Feb/2021:09:14:02 +0100] [Client 588] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:02 +0100] [Client 588] con->http=0x55ef90f7baf0
D [27/Feb/2021:09:14:02 +0100] [Client 588] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=159, response=0x55ef90f59730(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:02 +0100] [Client 588] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:02 +0100] [Client 588] bytes=0, http_state=0, data_remaining=159
D [27/Feb/2021:09:14:02 +0100] [Client 588] Flushing write buffer.
D [27/Feb/2021:09:14:02 +0100] [Client 588] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:02 +0100] [Client 588] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 643] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:02 +0100] [Client 643] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:02 +0100] [Client 643] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] [Client 643] POST / HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 643] Read: status=200, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 643] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 643] 2.0 Get-Job-Attributes 343
D [27/Feb/2021:09:14:02 +0100] Get-Job-Attributes ipp://localhost/jobs/122
D [27/Feb/2021:09:14:02 +0100] [Client 643] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/122) from localhost.
D [27/Feb/2021:09:14:02 +0100] [Client 643] Content-Length: 101
D [27/Feb/2021:09:14:02 +0100] [Client 643] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:02 +0100] [Client 643] con->http=0x55ef90f95ee0
D [27/Feb/2021:09:14:02 +0100] [Client 643] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=101, response=0x55ef90fa17a0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:02 +0100] [Client 643] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:02 +0100] [Client 643] bytes=0, http_state=0, data_remaining=101
D [27/Feb/2021:09:14:02 +0100] [Client 643] Flushing write buffer.
D [27/Feb/2021:09:14:02 +0100] [Client 643] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:02 +0100] [Client 643] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 644] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:02 +0100] [Client 644] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:02 +0100] [Client 644] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] [Client 643] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:02 +0100] [Client 643] Closing connection.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 644] GET /printers/HP_LaserJet_Professional_P_1102w.ppd HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 644] Read: status=200, state=3
D [27/Feb/2021:09:14:02 +0100] [Client 644] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 644] Processing GET /printers/HP_LaserJet_Professional_P_1102w.ppd
D [27/Feb/2021:09:14:02 +0100] [Client 644] filename="/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd", type=application/vnd.cups-ppd
D [27/Feb/2021:09:14:02 +0100] [Client 644] cupsdSendHeader: code=304, type="(null)", auth_type=0
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 645] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:02 +0100] [Client 645] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:02 +0100] [Client 645] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] [Client 644] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:02 +0100] [Client 644] Closing connection.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 645] POST / HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 645] Read: status=200, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 645] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 645] 2.0 Get-Job-Attributes 344
D [27/Feb/2021:09:14:02 +0100] Get-Job-Attributes ipp://localhost/jobs/122
D [27/Feb/2021:09:14:02 +0100] [Client 645] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/122) from localhost.
D [27/Feb/2021:09:14:02 +0100] [Client 645] Content-Length: 101
D [27/Feb/2021:09:14:02 +0100] [Client 645] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:02 +0100] [Client 645] con->http=0x55ef90f94ce0
D [27/Feb/2021:09:14:02 +0100] [Client 645] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=101, response=0x55ef90f94b70(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:02 +0100] [Client 645] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:02 +0100] [Client 645] bytes=0, http_state=0, data_remaining=101
D [27/Feb/2021:09:14:02 +0100] [Client 645] Flushing write buffer.
D [27/Feb/2021:09:14:02 +0100] [Client 645] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:02 +0100] [Client 645] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 588] POST /printers/HP_LaserJet_Professional_P_1102w HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 588] Read: status=200, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 588] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 588] 2.0 Get-Printer-Attributes 345
D [27/Feb/2021:09:14:02 +0100] Get-Printer-Attributes ipp://localhost/printers/HP_LaserJet_Professional_P_1102w
D [27/Feb/2021:09:14:02 +0100] [Client 588] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_LaserJet_Professional_P_1102w) from localhost.
D [27/Feb/2021:09:14:02 +0100] [Client 588] Content-Length: 159
D [27/Feb/2021:09:14:02 +0100] [Client 588] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:02 +0100] [Client 645] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:02 +0100] [Client 645] Closing connection.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 588] con->http=0x55ef90f7baf0
D [27/Feb/2021:09:14:02 +0100] [Client 588] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=159, response=0x55ef90f9f0b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:02 +0100] [Client 588] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:02 +0100] [Client 588] bytes=0, http_state=0, data_remaining=159
D [27/Feb/2021:09:14:02 +0100] [Client 588] Flushing write buffer.
D [27/Feb/2021:09:14:02 +0100] [Client 588] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:02 +0100] [Client 588] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 636] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:02 +0100] [Client 636] Closing connection.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 646] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:02 +0100] [Client 646] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:02 +0100] [Client 646] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] [Client 646] GET /printers/HP_LaserJet_Professional_P_1102w.ppd HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 646] Read: status=200, state=3
D [27/Feb/2021:09:14:02 +0100] [Client 646] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 646] Processing GET /printers/HP_LaserJet_Professional_P_1102w.ppd
D [27/Feb/2021:09:14:02 +0100] [Client 646] filename="/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd", type=application/vnd.cups-ppd
D [27/Feb/2021:09:14:02 +0100] [Client 646] cupsdSendHeader: code=304, type="(null)", auth_type=0
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 646] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:02 +0100] [Client 646] Closing connection.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 588] POST /printers/HP_LaserJet_Professional_P_1102w HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 588] Read: status=200, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 588] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 588] 2.0 Get-Printer-Attributes 346
D [27/Feb/2021:09:14:02 +0100] Get-Printer-Attributes ipp://localhost/printers/HP_LaserJet_Professional_P_1102w
D [27/Feb/2021:09:14:02 +0100] [Client 588] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_LaserJet_Professional_P_1102w) from localhost.
D [27/Feb/2021:09:14:02 +0100] [Client 588] Content-Length: 159
D [27/Feb/2021:09:14:02 +0100] [Client 588] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:02 +0100] [Client 588] con->http=0x55ef90f7baf0
D [27/Feb/2021:09:14:02 +0100] [Client 588] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=159, response=0x55ef90f7a3c0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:02 +0100] [Client 588] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:02 +0100] [Client 588] bytes=0, http_state=0, data_remaining=159
D [27/Feb/2021:09:14:02 +0100] [Client 588] Flushing write buffer.
D [27/Feb/2021:09:14:02 +0100] [Client 588] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:02 +0100] [Client 588] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 647] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:02 +0100] [Client 647] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:02 +0100] [Client 647] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] [Client 647] GET /printers/HP_LaserJet_Professional_P_1102w.ppd HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 647] Read: status=200, state=3
D [27/Feb/2021:09:14:02 +0100] [Client 647] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 647] Processing GET /printers/HP_LaserJet_Professional_P_1102w.ppd
D [27/Feb/2021:09:14:02 +0100] [Client 647] filename="/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd", type=application/vnd.cups-ppd
D [27/Feb/2021:09:14:02 +0100] [Client 647] cupsdSendHeader: code=304, type="(null)", auth_type=0
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 647] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:02 +0100] [Client 647] Closing connection.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 588] POST /printers/HP_LaserJet_Professional_P_1102w HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 588] Read: status=200, state=6
D [27/Feb/2021:09:14:02 +0100] [Client 588] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 588] 2.0 Get-Printer-Attributes 347
D [27/Feb/2021:09:14:02 +0100] Get-Printer-Attributes ipp://localhost/printers/HP_LaserJet_Professional_P_1102w
D [27/Feb/2021:09:14:02 +0100] [Client 588] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_LaserJet_Professional_P_1102w) from localhost.
D [27/Feb/2021:09:14:02 +0100] [Client 588] Content-Length: 159
D [27/Feb/2021:09:14:02 +0100] [Client 588] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [27/Feb/2021:09:14:02 +0100] [Client 588] con->http=0x55ef90f7baf0
D [27/Feb/2021:09:14:02 +0100] [Client 588] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=159, response=0x55ef90f9f0e0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [27/Feb/2021:09:14:02 +0100] [Client 588] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [27/Feb/2021:09:14:02 +0100] [Client 588] bytes=0, http_state=0, data_remaining=159
D [27/Feb/2021:09:14:02 +0100] [Client 588] Flushing write buffer.
D [27/Feb/2021:09:14:02 +0100] [Client 588] New state is HTTP_STATE_WAITING
D [27/Feb/2021:09:14:02 +0100] [Client 588] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 648] Server address is "/run/cups/cups.sock".
D [27/Feb/2021:09:14:02 +0100] [Client 648] Accepted from localhost (Domain)
D [27/Feb/2021:09:14:02 +0100] [Client 648] Waiting for request.
D [27/Feb/2021:09:14:02 +0100] [Client 648] GET /printers/HP_LaserJet_Professional_P_1102w.ppd HTTP/1.1
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 648] Read: status=200, state=3
D [27/Feb/2021:09:14:02 +0100] [Client 648] No authentication data provided.
D [27/Feb/2021:09:14:02 +0100] [Client 648] Processing GET /printers/HP_LaserJet_Professional_P_1102w.ppd
D [27/Feb/2021:09:14:02 +0100] [Client 648] filename="/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd", type=application/vnd.cups-ppd
D [27/Feb/2021:09:14:02 +0100] [Client 648] cupsdSendHeader: code=304, type="(null)", auth_type=0
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [27/Feb/2021:09:14:02 +0100] [Client 648] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [27/Feb/2021:09:14:02 +0100] [Client 648] Closing connection.
D [27/Feb/2021:09:14:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Feb/2021:09:14:03 +0100] [Job 122] prnt/backend/hp.c 376: read new pjl status: 10023
I [27/Feb/2021:09:14:03 +0100] Expiring subscriptions...
I [27/Feb/2021:09:14:04 +0100] Expiring subscriptions...
D [27/Feb/2021:09:14:05 +0100] [Job 122] prnt/backend/hp.c 376: read new pjl status: 10403
I [27/Feb/2021:09:14:05 +0100] Expiring subscriptions...
I [27/Feb/2021:09:14:06 +0100] Expiring subscriptions...

I didn't find any E(rror) line.
And I don't know what to do from here.
Is there something to fix? Or to report?
Any suggestion is welcome.
Merci.

Last edited by raphaelabb (2021-02-27 10:38:23)

progandy

Member

Registered: 2012-05-17

Posts: 5,190

Re: [SOLVED] No printing after hplip upgrade

Have you updated the hplip-plugin (AUR) package as well? According to hp, the closed source plugins are necessary for your printer. https://developers.hp.com/hp-linux-imag … lugin.html

---

| alias CUTF='LANG=en_XX.UTF-8@POSIX ' |

raphaelabb

Member

From: France

Registered: 2021-02-03

Posts: 31

Re: [SOLVED] No printing after hplip upgrade

That was it, I had not updated AUR hp-plugin. I just did and now it works.
Thank you, that's efficient answer.

seth

Member

Registered: 2012-09-03

Posts: 51,034

Re: [SOLVED] No printing after hplip upgrade

To answer your DM with a necrobump

I use hplip drivers and it needs hplip-plugin (which is AUR). Each time a new version of hplip comes out I have to wait for the update of hplip-plugin, otherwise I can't print.
And since I often need to print I tell pacman not to update hplip until the new hplip-plugin comes out.
Is there a better way than pinning in that case?

You could stall the entire update until hplip-plugin has caught up, but
1. this is typically a short-term delay
2. https://archlinux.org/packages/extra/x86_64/hplip/ is a strict leaf package, nothing depends on it.
So even iff the short-term isolated delay would break anything, it'd break hplip only and whether you can't print because hplip or hplip-plugin is dated effectively doesn't matter.
So it is plausibly "safe" to stall the hplip update because the impact is limited to itself.

---

How to upload text · How to boot w/o GUI · Disable Windows Fast-Start! · Fix your xinitrc

walkingstickfan

Member

From: USA

Registered: 2021-05-10

Posts: 104

Re: [SOLVED] No printing after hplip upgrade

I've always used the functionality within HPLIP to prompt for the proprietary driver upgrade. Perhaps that would be an option to the AUR package.

---

Arch Linux with Openbox & Tint2