Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

ErrorPolicy abort-job does not abort job and stops printer #1145

Open
CvH opened this issue Jan 22, 2025 · 4 comments
Open

ErrorPolicy abort-job does not abort job and stops printer #1145

CvH opened this issue Jan 22, 2025 · 4 comments

Comments

@CvH
Copy link

CvH commented Jan 22, 2025 •
edited
Loading

Describe the bug
Jobs get stuck at the queue and printer get stopped. Setting to abort-job won't abort the job and or won't start the printer afterwards.

To Reproduce
Steps to reproduce the behavior version 1:

  • print a not supported paper format (A3 if just a A4 printer or heavy paper if just normal paper is at the tray)
  • job gets not aborted
  • printer get stopped at cups
  • manually delete the job that the printer (otherwise start does not work)
  • manually start the printer at cups that it works again

Steps to reproduce the behavior version 2:

  • print something the printer does not like/understands and errors
  • job get aborted
  • printer get stopped at cups
  • manually start the printer at cups that it works again

Expected behavior

  • job gets printed anyway or the job gets properly aborted that the printer does not stop

System Information:

  • Ubuntu 20.04-24.04
  • Printing via Windows Firefox, Chrome, Adobe
  • CUPS 2.4.7

Additional context
It might be related to some firmware bug at the generations of Kyocera P3155dn. The problem does not appear at HP, Epson and other printers we have connected to cups.
All other printers seem to happily ignore if they get a job with an unsupported media type (A3, heavy paper etc) or not properly understanding format.

We carry stupid workarounds like restarting printers via script to avoid restarting printers at cups 10 times a day.
@michaelrsweet
Copy link
Member

Please attach the CUPS error_log file (with debug logging enabled - run cupsctl --debug-logging) that shows a job stopping the queue. I suspect the IPP backend is returning an explicit "stop printer" status vs. "an error happened" which is preventing the default error policy from being used.

@CvH
Copy link
Author

CvH commented Jan 28, 2025

Hopefully I copied the correct parts (crowded enviorment) - if you need anything else just ask.

D [28/Jan/2025:06:30:12 +0100] [Client 83] Server address is "10.24.96.187".
D [28/Jan/2025:06:30:12 +0100] [Client 83] Accepted from 10.24.96.101:59347 (IPv4)
D [28/Jan/2025:06:30:12 +0100] [Client 83] Waiting for request.
D [28/Jan/2025:06:30:12 +0100] [Client 83] Connection now encrypted.
D [28/Jan/2025:06:30:12 +0100] [Client 83] POST /printers/kyocera-p3155d HTTP/1.1
D [28/Jan/2025:06:30:12 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [28/Jan/2025:06:30:12 +0100] [Client 83] Read: status=200, state=6
D [28/Jan/2025:06:30:12 +0100] [Client 83] No authentication data provided.
D [28/Jan/2025:06:30:12 +0100] [Client 83] 2.0 Get-Printer-Attributes 1
D [28/Jan/2025:06:30:12 +0100] Get-Printer-Attributes ipps://10.24.96.187:631/printers/kyocera-p3155d
D [28/Jan/2025:06:30:12 +0100] [Client 83] Returning IPP successful-ok for Get-Printer-Attributes (ipps://10.24.96.187:631/printers/kyocera-p3155d) from 10.24.96.101.
D [28/Jan/2025:06:30:12 +0100] [Client 83] Content-Length: 753
D [28/Jan/2025:06:30:12 +0100] [Client 83] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Jan/2025:06:30:12 +0100] [Client 83] con->http=0x65281027c7d0
D [28/Jan/2025:06:30:12 +0100] [Client 83] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=753, response=0x6528102d5f10(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Jan/2025:06:30:12 +0100] [Client 83] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Jan/2025:06:30:12 +0100] [Client 83] bytes=0, http_state=0, data_remaining=753
D [28/Jan/2025:06:30:12 +0100] [Client 83] Flushing write buffer.
D [28/Jan/2025:06:30:12 +0100] [Client 83] New state is HTTP_STATE_WAITING
D [28/Jan/2025:06:30:12 +0100] [Client 83] Waiting for request.
D [28/Jan/2025:06:30:12 +0100] [Client 83] Closing because Keep-Alive is disabled.
D [28/Jan/2025:06:30:12 +0100] [Client 83] Closing connection.
D [28/Jan/2025:06:30:12 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2025:06:30:12 +0100] [Client 83] Waiting for socket close.
D [28/Jan/2025:06:30:12 +0100] [Client 83] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Jan/2025:06:30:12 +0100] [Client 83] Closing connection.
D [28/Jan/2025:06:30:12 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2025:06:30:12 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2025:06:30:12 +0100] [Client 84] Server address is "10.24.96.187".
D [28/Jan/2025:06:30:12 +0100] [Client 84] Accepted from 10.24.96.101:59348 (IPv4)
D [28/Jan/2025:06:30:12 +0100] [Client 84] Waiting for request.
D [28/Jan/2025:06:30:13 +0100] [Client 84] Connection now encrypted.
D [28/Jan/2025:06:30:13 +0100] [Client 84] POST /printers/kyocera-p3155d HTTP/1.1
D [28/Jan/2025:06:30:13 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [28/Jan/2025:06:30:13 +0100] [Client 84] Read: status=200, state=6
D [28/Jan/2025:06:30:13 +0100] [Client 84] No authentication data provided.
D [28/Jan/2025:06:30:13 +0100] [Client 84] 2.0 Get-Printer-Attributes 2
D [28/Jan/2025:06:30:13 +0100] Get-Printer-Attributes ipps://10.24.96.187:631/printers/kyocera-p3155d
D [28/Jan/2025:06:30:13 +0100] [Client 84] Returning IPP successful-ok for Get-Printer-Attributes (ipps://10.24.96.187:631/printers/kyocera-p3155d) from 10.24.96.101.
D [28/Jan/2025:06:30:13 +0100] [Client 84] Content-Length: 753
D [28/Jan/2025:06:30:13 +0100] [Client 84] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Jan/2025:06:30:13 +0100] [Client 84] con->http=0x65281027c7d0
D [28/Jan/2025:06:30:13 +0100] [Client 84] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=753, response=0x6528102d5a40(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Jan/2025:06:30:13 +0100] [Client 84] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Jan/2025:06:30:13 +0100] [Client 84] bytes=0, http_state=0, data_remaining=753
D [28/Jan/2025:06:30:13 +0100] [Client 84] Flushing write buffer.
D [28/Jan/2025:06:30:13 +0100] [Client 84] New state is HTTP_STATE_WAITING
D [28/Jan/2025:06:30:13 +0100] [Client 84] Waiting for request.
D [28/Jan/2025:06:30:13 +0100] [Client 84] Closing because Keep-Alive is disabled.
D [28/Jan/2025:06:30:13 +0100] [Client 84] Closing connection.
D [28/Jan/2025:06:30:13 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2025:06:30:13 +0100] [Client 84] Waiting for socket close.
D [28/Jan/2025:06:30:13 +0100] [Client 84] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Jan/2025:06:30:13 +0100] [Client 84] Closing connection.
D [28/Jan/2025:06:30:13 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [28/Jan/2025:06:30:13 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [28/Jan/2025:06:30:13 +0100] [Client 85] Server address is "10.24.96.187".
D [28/Jan/2025:06:30:13 +0100] [Client 85] Accepted from 10.24.96.101:59349 (IPv4)
D [28/Jan/2025:06:30:13 +0100] [Client 85] Waiting for request.
D [28/Jan/2025:06:30:13 +0100] [Client 85] Connection now encrypted.
D [28/Jan/2025:06:30:13 +0100] [Client 85] POST /printers/kyocera-p3155d HTTP/1.1
D [28/Jan/2025:06:30:13 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [28/Jan/2025:06:30:13 +0100] [Client 85] Read: status=200, state=6
D [28/Jan/2025:06:30:13 +0100] [Client 85] No authentication data provided.
D [28/Jan/2025:06:30:13 +0100] [Client 85] 2.0 Get-Printer-Attributes 3
D [28/Jan/2025:06:30:13 +0100] Get-Printer-Attributes ipps://10.24.96.187:631/printers/kyocera-p3155d
D [28/Jan/2025:06:30:13 +0100] [Client 85] Returning IPP successful-ok for Get-Printer-Attributes (ipps://10.24.96.187:631/printers/kyocera-p3155d) from 10.24.96.101.
D [28/Jan/2025:06:30:13 +0100] [Client 85] Content-Length: 9963
D [28/Jan/2025:06:30:13 +0100] [Client 85] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [28/Jan/2025:06:30:13 +0100] [Client 85] con->http=0x65281027c7d0
D [28/Jan/2025:06:30:13 +0100] [Client 85] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9963, response=0x65281035b4a0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [28/Jan/2025:06:30:13 +0100] [Client 85] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [28/Jan/2025:06:30:13 +0100] [Client 85] bytes=0, http_state=0, data_remaining=9963
D [28/Jan/2025:06:30:13 +0100] [Client 85] Flushing write buffer.
D [28/Jan/2025:06:30:13 +0100] [Client 85] New state is HTTP_STATE_WAITING
D [28/Jan/2025:06:30:13 +0100] [Client 85] Waiting for request.
D [28/Jan/2025:06:30:13 +0100] [Client 85] Closing because Keep-Alive is disabled.
D [28/Jan/2025:06:30:13 +0100] [Client 85] Closing connection.
D [28/Jan/2025:06:30:13 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [28/Jan/2025:06:30:13 +0100] [Client 85] Waiting for socket close.
D [28/Jan/2025:06:30:13 +0100] [Client 85] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [28/Jan/2025:06:30:13 +0100] [Client 85] Closing connection.

root@printserver:~# apt list --installed | grep cups

cups-browsed/noble-updates,noble-security,now 2.0.0-0ubuntu10.2 amd64 [installed,automatic]
cups-client/noble-updates,noble-security,now 2.4.7-1.2ubuntu7.3 amd64 [installed,automatic]
cups-common/noble-updates,noble-security,now 2.4.7-1.2ubuntu7.3 all [installed,automatic]
cups-core-drivers/noble-updates,noble-security,now 2.4.7-1.2ubuntu7.3 amd64 [installed,automatic]
cups-daemon/noble-updates,noble-security,now 2.4.7-1.2ubuntu7.3 amd64 [installed,automatic]
cups-filters-core-drivers/noble,now 2.0.0-0ubuntu4 amd64 [installed,automatic]
cups-filters/noble,now 2.0.0-0ubuntu4 amd64 [installed,automatic]
cups-ipp-utils/noble-updates,noble-security,now 2.4.7-1.2ubuntu7.3 amd64 [installed,automatic]
cups-ppdc/noble-updates,noble-security,now 2.4.7-1.2ubuntu7.3 amd64 [installed,automatic]
cups-server-common/noble-updates,noble-security,now 2.4.7-1.2ubuntu7.3 all [installed,automatic]
cups/noble-updates,noble-security,now 2.4.7-1.2ubuntu7.3 amd64 [installed]
libcups2t64/noble-updates,noble-security,now 2.4.7-1.2ubuntu7.3 amd64 [installed,automatic]
libcupsfilters2-common/noble-updates,noble-security,now 2.0.0-0ubuntu7.1 all [installed,automatic]
libcupsfilters2t64/noble-updates,noble-security,now 2.0.0-0ubuntu7.1 amd64 [installed,automatic]
printer-driver-cups-pdf/noble,now 3.0.1-14build2 amd64 [installed]

@zdohnal
Copy link
Member

zdohnal commented Jan 28, 2025

@CvH unfortunately your snippet does not show logs for your error. Please try to reproduce the issue, put logs into a file with suffix .txt and attach it to the issue.

@CvH
Copy link
Author

CvH commented Jan 30, 2025

sorry for the messy log, there are a lot printers

Steps I done as quick as possible

  • enable debug at console
  • I print from Win PC 10.24.96.101 to the printer "kyocera-p3155d"
  • Printer gets stuck
  • delete the job and restart the printer
  • disable debug at console

error_log.log

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@michaelrsweet @CvH @zdohnal