[cups] Can't print to a local HP LaserJet

[Ken Wright]

On 3/11/20 3:37 AM, Jostein Berntsen wrote:
> Can you try to print again and post some of the last lines from
> /var/log/cups/error_log and /var/log/cups/access_log?
>
Here's the access log:

localhost - - [11/Mar/2020:06:25:05 +0000] "POST / HTTP/1.1" 200 349
Create-Printer-Subscriptions successful-ok
localhost - - [11/Mar/2020:06:25:05 +0000] "POST / HTTP/1.1" 200 176
Create-Printer-Subscriptions client-error-not-possible
localhost - - [11/Mar/2020:06:25:06 +0000] "POST / HTTP/1.1" 200 285
CUPS-Create-Local-Printer successful-ok
localhost - - [11/Mar/2020:06:25:07 +0000] "POST /admin/ HTTP/1.1" 401
201 CUPS-Add-Modify-Printer successful-ok
localhost - root [11/Mar/2020:06:25:07 +0000] "POST /admin/ HTTP/1.1"
200 201 CUPS-Add-Modify-Printer client-error-bad-request
localhost - root [11/Mar/2020:06:25:07 +0000] "POST /admin/ HTTP/1.1"
200 177 CUPS-Delete-Printer successful-ok
localhost - - [11/Mar/2020:17:16:49 +0000] "POST
/printers/HP_LaserJet_M14-M17 HTTP/1.1" 200 346 Create-Job successful-ok
localhost - - [11/Mar/2020:17:16:49 +0000] "POST
/printers/HP_LaserJet_M14-M17 HTTP/1.1" 200 825 Send-Document successful-ok

and here are the last several lines from the error log:

D [11/Mar/2020:17:19:28 +0000] [Client 6] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [11/Mar/2020:17:19:28 +0000] [Client 6] bytes=0, http_state=0,
data_remaining=130
D [11/Mar/2020:17:19:28 +0000] [Client 6] Flushing write buffer.
D [11/Mar/2020:17:19:28 +0000] [Client 6] New state is HTTP_STATE_WAITING
D [11/Mar/2020:17:19:28 +0000] [Client 6] Waiting for request.
D [11/Mar/2020:17:19:28 +0000] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [11/Mar/2020:17:19:28 +0000] [Client 2] POST / HTTP/1.1
D [11/Mar/2020:17:19:28 +0000] cupsdSetBusyState: newbusy="Active
clients", busy="Not busy"
D [11/Mar/2020:17:19:28 +0000] [Client 2] Read: status=200, state=6
D [11/Mar/2020:17:19:28 +0000] [Client 2] No authentication data provided.
D [11/Mar/2020:17:19:28 +0000] [Client 2] 2.0 CUPS-Get-Printers 11797
D [11/Mar/2020:17:19:28 +0000] CUPS-Get-Printers
D [11/Mar/2020:17:19:28 +0000] [Client 2] Returning IPP successful-ok
for CUPS-Get-Printers (no URI) from localhost.
D [11/Mar/2020:17:19:28 +0000] [Client 2] Content-Length: 2431
D [11/Mar/2020:17:19:28 +0000] [Client 2] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0
D [11/Mar/2020:17:19:28 +0000] [Client 2] con->http=0x5596c28948b0
D [11/Mar/2020:17:19:28 +0000] [Client 2] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=2431, response=0x5596c2891fb0(IPP_STATE_DATA),
pipe_pid=0, file=-1
D [11/Mar/2020:17:19:28 +0000] [Client 2] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [11/Mar/2020:17:19:28 +0000] [Client 2] bytes=0, http_state=0,
data_remaining=2431
D [11/Mar/2020:17:19:28 +0000] [Client 2] Flushing write buffer.
D [11/Mar/2020:17:19:28 +0000] [Client 2] New state is HTTP_STATE_WAITING
D [11/Mar/2020:17:19:28 +0000] [Client 2] Waiting for request.
D [11/Mar/2020:17:19:28 +0000] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [11/Mar/2020:17:19:28 +0000] [Client 2] POST / HTTP/1.1
D [11/Mar/2020:17:19:28 +0000] cupsdSetBusyState: newbusy="Active
clients", busy="Not busy"
D [11/Mar/2020:17:19:28 +0000] [Client 2] Read: status=200, state=6
D [11/Mar/2020:17:19:28 +0000] [Client 2] No authentication data provided.
D [11/Mar/2020:17:19:28 +0000] [Client 2] 2.0 CUPS-Get-Default 11798
D [11/Mar/2020:17:19:28 +0000] CUPS-Get-Default
D [11/Mar/2020:17:19:28 +0000] [Client 2] Returning IPP successful-ok
for CUPS-Get-Default (no URI) from localhost.
D [11/Mar/2020:17:19:28 +0000] [Client 2] Content-Length: 9831
D [11/Mar/2020:17:19:28 +0000] [Client 2] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0
D [11/Mar/2020:17:19:28 +0000] [Client 2] con->http=0x5596c28948b0
D [11/Mar/2020:17:19:28 +0000] [Client 2] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=9831, response=0x5596c2893820(IPP_STATE_DATA),
pipe_pid=0, file=-1
D [11/Mar/2020:17:19:28 +0000] [Client 2] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [11/Mar/2020:17:19:28 +0000] [Client 2] bytes=0, http_state=0,
data_remaining=9831
D [11/Mar/2020:17:19:28 +0000] [Client 2] Flushing write buffer.
D [11/Mar/2020:17:19:28 +0000] [Client 2] New state is HTTP_STATE_WAITING
D [11/Mar/2020:17:19:28 +0000] [Client 2] Waiting for request.
D [11/Mar/2020:17:19:28 +0000] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"

Hope that helps!

Ken