Cups error logs
Hello,
I am troubleshooting some printer issues in a college environment and have some questions about some errors I am seeing in the CUPS error log.
We have a lab of iMac with several printers of varying types.
Some of our printers have very intermittent printing issues wherein the print job will start and then stop at some point during print and will not restart.
On our printers connected to our network via ethernet we see "Communication Error" on the print queue on the Mac when the happens.
On a printer that is only connected via USB to on iMac, the Mac appears to think the job is complete when this happens, as does the printer.
The two issues described above may or may not be related. I am not sure.
I am attempting to replicate the issue and capture exact CUPS error logs when it occurs. Today I have not been able to recreate the error. Prints have been successful. However, I have some questions about some errors I am seeing in our CUPS logs in general. I don't know what they mean and am having trouble finding a resource that will assist me in learning the meaning. Some error messages I saw are below. My concerns are in red.
Error message for Client 21. This was part of a successful print:
D [28/Feb/2018:08:21:56 -0500] [Client 21] 2.0 Create-Job 111
D [28/Feb/2018:08:21:56 -0500] Create-Job ipp://localhost:631/printers/SAWGRASS_SG800
D [28/Feb/2018:08:21:56 -0500] cupsdMarkDirty(---J-)
D [28/Feb/2018:08:21:56 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [28/Feb/2018:08:21:56 -0500] add_job: requesting-user-name="h224users"
I [28/Feb/2018:08:21:56 -0500] [Job 110] Adding start banner page "none".
D [28/Feb/2018:08:21:56 -0500] Discarding unused job-created event...
I [28/Feb/2018:08:21:56 -0500] [Job 110] Queued on "SAWGRASS_SG800" by "h224users".
D [28/Feb/2018:08:21:56 -0500] [Client 21] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/SAWGRASS_SG800) from localhost
D [28/Feb/2018:08:21:56 -0500] [Client 21] Content-Length: 201
D [28/Feb/2018:08:21:56 -0500] [Client 21] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Feb/2018:08:21:56 -0500] [Client 21] con->http=0x7fc33d82f400
D [28/Feb/2018:08:21:56 -0500] [Client 21] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=201, response=0x7fc33cd130e0(IPP_STATE_IDLE), pipe_pid=0, file=-1
D [28/Feb/2018:08:21:56 -0500] [Client 21] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Feb/2018:08:21:56 -0500] [Client 21] bytes=0, http_state=0, data_remaining=201
D [28/Feb/2018:08:21:56 -0500] [Client 21] Flushing write buffer.
D [28/Feb/2018:08:21:56 -0500] [Client 21] New state is HTTP_STATE_WAITING
D [28/Feb/2018:08:21:56 -0500] [Client 21] Waiting for request.
D [28/Feb/2018:08:21:56 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Feb/2018:08:21:56 -0500] [Client 21] POST /printers/SAWGRASS_SG800 HTTP/1.1
D [28/Feb/2018:08:21:56 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
(What does "Dirty Files" mean?)
D [28/Feb/2018:08:21:56 -0500] [Client 21] Read: status=200
D [28/Feb/2018:08:21:56 -0500] [Client 21] No authentication data provided.
D [28/Feb/2018:08:21:56 -0500] [Client 21] 2.0 Send-Document 112
D [28/Feb/2018:08:21:56 -0500] [Client 21] Read: status=100
D [28/Feb/2018:08:21:56 -0500] [Client 21] Read: status=100
D [28/Feb/2018:08:21:56 -0500] [Client 21] Read: status=100
D [28/Feb/2018:08:21:56 -0500] [Client 21] Read: status=100
D [28/Feb/2018:08:21:56 -0500] [Client 21] Read: status=100
Error message for Client 17:
D [28/Feb/2018:08:21:56 -0500] [Client 17] Server address is "/private/var/run/cupsd".
D [28/Feb/2018:08:21:56 -0500] [Client 17] Accepted from localhost (Domain printtool[1097])
D [28/Feb/2018:08:21:56 -0500] [Client 17] Waiting for request.
D [28/Feb/2018:08:21:56 -0500] [Client 17] POST / HTTP/1.1
D [28/Feb/2018:08:21:56 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Feb/2018:08:21:56 -0500] [Client 17] Read: status=200
D [28/Feb/2018:08:21:56 -0500] [Client 17] No authentication data provided.
D [28/Feb/2018:08:21:56 -0500] [Client 17] 2.0 CUPS-Get-Default 202858
D [28/Feb/2018:08:21:56 -0500] CUPS-Get-Default
D [28/Feb/2018:08:21:56 -0500] CUPS-Get-Default client-error-not-found: No default printer.
D [28/Feb/2018:08:21:56 -0500] [Client 17] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [28/Feb/2018:08:21:56 -0500] [Client 17] Content-Length: 113
D [28/Feb/2018:08:21:56 -0500] [Client 17] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Feb/2018:08:21:56 -0500] [Client 17] con->http=0x7fc33d82ae00
D [28/Feb/2018:08:21:56 -0500] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x7fc33cd14c10(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Feb/2018:08:21:56 -0500] [Client 17] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Feb/2018:08:21:56 -0500] [Client 17] bytes=0, http_state=0, data_remaining=113
D [28/Feb/2018:08:21:56 -0500] [Client 17] Flushing write buffer.
D [28/Feb/2018:08:21:56 -0500] [Client 17] New state is HTTP_STATE_WAITING
D [28/Feb/2018:08:21:56 -0500] [Client 17] Waiting for request.
D [28/Feb/2018:08:21:56 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Feb/2018:08:21:56 -0500] [Client 17] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Feb/2018:08:21:56 -0500] [Client 17] Closing connection.
ERROR 32 and "Broken Pipe"?
I'd appreciate any assistance in understanding what these items in red mean.
Thank you!
