View previous topic :: View next topic |
Author |
Message |
edward_scott Apprentice
Joined: 17 Jan 2006 Posts: 163
|
Posted: Sun Nov 22, 2020 10:28 pm Post subject: [SOLVED] filter failed printing |
|
|
I am running into a filter error when printing with CUPS. This is a new setup, I have not been able to print to this printer yet. (& I don't have other printers to try printing to.)
The symptoms of my problem are very similar to what is reported in https://forums.gentoo.org/viewtopic-t-1073878-start-0.html but that thread doesn't really solve the problem.
I think I've narrowed it down to the following part of /var/log/cups/error_log:
Code: | E [22/Nov/2020:14:05:48 -0800] [Job 54] Unable to open raster stream - : Broken pipe
D [22/Nov/2020:14:05:48 -0800] cupsdMarkDirty(----S)
D [22/Nov/2020:14:05:48 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/Nov/2020:14:05:48 -0800] cupsdMarkDirty(----S)
D [22/Nov/2020:14:05:48 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/Nov/2020:14:05:48 -0800] [Job 54] Error: /ioerror in --showpage--
D [22/Nov/2020:14:05:48 -0800] [Job 54] Operand stack:
D [22/Nov/2020:14:05:48 -0800] [Job 54] true (/var/spool/cups/tmp/gs_Yvubas) --nostringval-- 1 true
D [22/Nov/2020:14:05:48 -0800] [Job 54] Execution stack:
D [22/Nov/2020:14:05:48 -0800] [Job 54] %interp_exit .runexec2 --nostringval-- showpage --nostringval-- 2 %stopped_push --nostringval-- showpage showpage false 1 %stopped_push 1990 2 3 %oparray_pop 1989 2 3 %oparray_pop 1977 2 3 %oparray_pop showpage 1978 4 3 %oparray_pop showpage showpage 2 1 1 showpage %for_pos_int_continue 1981 4 7 %oparray_pop showpage showpage 1840 3 9 %oparray_pop showpage showpage
D [22/Nov/2020:14:05:48 -0800] [Job 54] Dictionary stack:
D [22/Nov/2020:14:05:48 -0800] [Job 54] --dict:741/1123(ro)(G)-- --dict:1/20(G)-- --dict:80/200(L)-- --dict:80/200(L)-- --dict:133/256(ro)(G)-- --dict:318/325(ro)(G)-- --dict:33/64(L)-- --dict:6/9(L)-- --dict:6/20(L)--
D [22/Nov/2020:14:05:48 -0800] [Job 54] Current allocation mode is local
D [22/Nov/2020:14:05:48 -0800] [Job 54] Last OS error: Broken pipe
D [22/Nov/2020:14:05:48 -0800] [Job 54] GPL Ghostscript 9.53.3: Unrecoverable error, exit code 1 |
I am a little stumped now on next steps. Reaching out for suggestions.
Some technical details -
Longer excerpt from log: https://pastebin.com/zf2LRrre
Epson XP-620 driver epson-escpr 1.4.1 epson-inkjet-printer-escpr-1.4.1-1lsb3.2.x86_64.rpm from https://www.openprinting.org/printer/Epson/Epson-XP-620_Series
net-print/cups-2.3.3-r1::gentoo was built with the following:
USE="X acl dbus pam ssl systemd threads usb zeroconf -debug -kerberos -lprng-compat (-selinux) -static-libs -xinetd" ABI_X86="32 (64) (-x32)"
app-text/ghostscript-gpl-9.53.3-r1::gentoo was built with the following:
USE="X cups dbus gtk unicode -static-libs" ABI_X86="(64)" L10N="-de (-ja) -ko -zh-CN -zh-TW"
Last edited by edward_scott on Sat Apr 17, 2021 4:42 pm; edited 1 time in total |
|
Back to top |
|
|
dmpogo Advocate
Joined: 02 Sep 2004 Posts: 3267 Location: Canada
|
Posted: Sun Nov 22, 2020 10:49 pm Post subject: |
|
|
Was there anything before the line
E [22/Nov/2020:14:05:48 -0800] [Job 54] Unable to open raster stream - : Broken pipe
in error_log ? |
|
Back to top |
|
|
edward_scott Apprentice
Joined: 17 Jan 2006 Posts: 163
|
Posted: Sun Nov 22, 2020 11:19 pm Post subject: |
|
|
Yes. Here is the Ghostscript command through the "Unable to open raster stream" message. More available in the paste bin.
Code: | D [22/Nov/2020:14:05:48 -0800] [Job 54] Ghostscript command line: gs -dQUIET -dSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -dNOMEDIAATTRS -dShowAcroForm -sstdout=%stderr -sOutputFile=%stdout -sDEVICE=cups -r360x360 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=841 -dcupsBitsPerColor=8 -dcupsColorOrder=0 -dcupsColorSpace=1 -dcupsCompression=1 -scupsPageSizeName=A4 -I/usr/share/cups/fonts -c \'<</.HWMargins[8.400000 8.400000 8.400024 8.399963] /Margins[0 0]>>setpagedevice\' -f -_
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[0]=\"CUPS_CACHEDIR=/var/cache/cups\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[1]=\"CUPS_DATADIR=/usr/share/cups\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[2]=\"CUPS_DOCROOT=/usr/share/cups/html\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[3]=\"CUPS_FONTPATH=/usr/share/cups/fonts\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[4]=\"CUPS_REQUESTROOT=/var/spool/cups\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[5]=\"CUPS_SERVERBIN=/usr/libexec/cups\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[6]=\"CUPS_SERVERROOT=/etc/cups\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[7]=\"CUPS_STATEDIR=/run/cups\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[8]=\"HOME=/var/spool/cups/tmp\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[9]=\"PATH=/usr/libexec/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[10]=\"SERVER_ADMIN=root@jukebox\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[11]=\"SOFTWARE=CUPS/2.3.3\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[12]=\"TMPDIR=/var/spool/cups/tmp\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[13]=\"USER=root\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[14]=\"CUPS_MAX_MESSAGE=2047\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[15]=\"CUPS_SERVER=/run/cups/cups.sock\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[16]=\"CUPS_ENCRYPTION=IfRequested\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[17]=\"IPP_PORT=631\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[18]=\"CHARSET=utf-8\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[19]=\"LANG=en_US.UTF-8\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[20]=\"PPD=/etc/cups/ppd/EPSON_XP-620_Series.ppd\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[21]=\"RIP_MAX_CACHE=128m\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[22]=\"CONTENT_TYPE=application/pdf\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[23]=\"DEVICE_URI=lpd://192.168.1.201:515/PASSTHRU\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[24]=\"PRINTER_INFO=EPSON XP-620 Series\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[25]=\"PRINTER_LOCATION=\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[26]=\"PRINTER=EPSON_XP-620_Series\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[27]=\"PRINTER_STATE_REASONS=none\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[28]=\"CUPS_FILETYPE=document\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[29]=\"FINAL_CONTENT_TYPE=application/vnd.cups-raster\"
D [22/Nov/2020:14:05:48 -0800] [Job 54] envp[30]=\"AUTH_INFO_REQUIRED=none\"
D [22/Nov/2020:14:05:48 -0800] [Client 8] POST / HTTP/1.1
D [22/Nov/2020:14:05:48 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [22/Nov/2020:14:05:48 -0800] [Client 8] Read: status=200, state=6
D [22/Nov/2020:14:05:48 -0800] [Client 8] No authentication data provided.
D [22/Nov/2020:14:05:48 -0800] [Client 8] 2.0 Get-Notifications 402
D [22/Nov/2020:14:05:48 -0800] Get-Notifications /printers/
D [22/Nov/2020:14:05:48 -0800] Get-Notifications client-error-not-found: Subscription #1586 does not exist.
D [22/Nov/2020:14:05:48 -0800] [Client 8] Returning IPP client-error-not-found for Get-Notifications (/printers/) from localhost.
D [22/Nov/2020:14:05:48 -0800] [Client 8] Content-Length: 128
D [22/Nov/2020:14:05:48 -0800] [Client 8] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [22/Nov/2020:14:05:48 -0800] [Client 8] con->http=0x55f0dd713120
D [22/Nov/2020:14:05:48 -0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=128, response=0x55f0dd734770(IPP_STATE_DATA), pipe_pid=0, file=-1
D [22/Nov/2020:14:05:48 -0800] [Client 8] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [22/Nov/2020:14:05:48 -0800] [Client 8] bytes=0, http_state=0, data_remaining=128
D [22/Nov/2020:14:05:48 -0800] [Client 8] Flushing write buffer.
D [22/Nov/2020:14:05:48 -0800] [Client 8] New state is HTTP_STATE_WAITING
D [22/Nov/2020:14:05:48 -0800] [Client 8] Waiting for request.
D [22/Nov/2020:14:05:48 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [22/Nov/2020:14:05:48 -0800] [Client 8] POST / HTTP/1.1
D [22/Nov/2020:14:05:48 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [22/Nov/2020:14:05:48 -0800] [Client 8] Read: status=200, state=6
D [22/Nov/2020:14:05:48 -0800] [Client 8] No authentication data provided.
D [22/Nov/2020:14:05:48 -0800] [Client 8] 2.0 Get-Notifications 403
D [22/Nov/2020:14:05:48 -0800] Get-Notifications /printers/
D [22/Nov/2020:14:05:48 -0800] Get-Notifications client-error-not-found: Subscription #1586 does not exist.
D [22/Nov/2020:14:05:48 -0800] [Client 8] Returning IPP client-error-not-found for Get-Notifications (/printers/) from localhost.
D [22/Nov/2020:14:05:48 -0800] [Client 8] Content-Length: 128
D [22/Nov/2020:14:05:48 -0800] [Client 8] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [22/Nov/2020:14:05:48 -0800] [Client 8] con->http=0x55f0dd713120
D [22/Nov/2020:14:05:48 -0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=128, response=0x55f0dd734560(IPP_STATE_DATA), pipe_pid=0, file=-1
D [22/Nov/2020:14:05:48 -0800] [Client 8] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [22/Nov/2020:14:05:48 -0800] [Client 8] bytes=0, http_state=0, data_remaining=128
D [22/Nov/2020:14:05:48 -0800] [Client 8] Flushing write buffer.
D [22/Nov/2020:14:05:48 -0800] [Client 8] New state is HTTP_STATE_WAITING
D [22/Nov/2020:14:05:48 -0800] [Client 8] Waiting for request.
D [22/Nov/2020:14:05:48 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [22/Nov/2020:14:05:48 -0800] [Job 54] Start rendering...
D [22/Nov/2020:14:05:48 -0800] [Job 54] Processing page 1...
D [22/Nov/2020:14:05:48 -0800] cupsdMarkDirty(----S)
D [22/Nov/2020:14:05:48 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/Nov/2020:14:05:48 -0800] cupsdMarkDirty(----S)
D [22/Nov/2020:14:05:48 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [22/Nov/2020:14:05:48 -0800] [Notifier] state=3
D [22/Nov/2020:14:05:48 -0800] [Notifier] JobProgress
D [22/Nov/2020:14:05:48 -0800] [Notifier] state=3
D [22/Nov/2020:14:05:48 -0800] [Notifier] PrinterStateChanged
D [22/Nov/2020:14:05:48 -0800] [Client 8] POST / HTTP/1.1
D [22/Nov/2020:14:05:48 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [22/Nov/2020:14:05:48 -0800] [Client 8] Read: status=200, state=6
D [22/Nov/2020:14:05:48 -0800] [Client 8] No authentication data provided.
D [22/Nov/2020:14:05:48 -0800] [Client 8] 2.0 Get-Notifications 404
D [22/Nov/2020:14:05:48 -0800] Get-Notifications /printers/
D [22/Nov/2020:14:05:48 -0800] Get-Notifications client-error-not-found: Subscription #1586 does not exist.
D [22/Nov/2020:14:05:48 -0800] [Client 8] Returning IPP client-error-not-found for Get-Notifications (/printers/) from localhost.
D [22/Nov/2020:14:05:48 -0800] [Client 8] Content-Length: 128
D [22/Nov/2020:14:05:48 -0800] [Client 8] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [22/Nov/2020:14:05:48 -0800] [Client 8] con->http=0x55f0dd713120
D [22/Nov/2020:14:05:48 -0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=128, response=0x55f0dd7406f0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [22/Nov/2020:14:05:48 -0800] [Client 8] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [22/Nov/2020:14:05:48 -0800] [Client 8] bytes=0, http_state=0, data_remaining=128
D [22/Nov/2020:14:05:48 -0800] [Client 8] Flushing write buffer.
D [22/Nov/2020:14:05:48 -0800] [Client 8] New state is HTTP_STATE_WAITING
D [22/Nov/2020:14:05:48 -0800] [Client 8] Waiting for request.
D [22/Nov/2020:14:05:48 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [22/Nov/2020:14:05:48 -0800] [Client 8] POST / HTTP/1.1
D [22/Nov/2020:14:05:48 -0800] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [22/Nov/2020:14:05:48 -0800] [Client 8] Read: status=200, state=6
D [22/Nov/2020:14:05:48 -0800] [Client 8] No authentication data provided.
D [22/Nov/2020:14:05:48 -0800] [Client 8] 2.0 Get-Notifications 405
D [22/Nov/2020:14:05:48 -0800] Get-Notifications /printers/
D [22/Nov/2020:14:05:48 -0800] Get-Notifications client-error-not-found: Subscription #1586 does not exist.
D [22/Nov/2020:14:05:48 -0800] [Client 8] Returning IPP client-error-not-found for Get-Notifications (/printers/) from localhost.
D [22/Nov/2020:14:05:48 -0800] [Client 8] Content-Length: 128
D [22/Nov/2020:14:05:48 -0800] [Client 8] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [22/Nov/2020:14:05:48 -0800] [Client 8] con->http=0x55f0dd713120
D [22/Nov/2020:14:05:48 -0800] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=128, response=0x55f0dd7404e0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [22/Nov/2020:14:05:48 -0800] [Client 8] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [22/Nov/2020:14:05:48 -0800] [Client 8] bytes=0, http_state=0, data_remaining=128
D [22/Nov/2020:14:05:48 -0800] [Client 8] Flushing write buffer.
D [22/Nov/2020:14:05:48 -0800] [Client 8] New state is HTTP_STATE_WAITING
D [22/Nov/2020:14:05:48 -0800] [Client 8] Waiting for request.
D [22/Nov/2020:14:05:48 -0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
E [22/Nov/2020:14:05:48 -0800] [Job 54] Unable to open raster stream - : Broken pipe |
|
|
Back to top |
|
|
edward_scott Apprentice
Joined: 17 Jan 2006 Posts: 163
|
Posted: Sat Apr 17, 2021 4:42 pm Post subject: |
|
|
Revisiting this after several months I was eventually able to get this working.
I don't know if the problems I was running into earlier today were exactly the same as reported earlier. The content of /var/log/cups/error_log is a little different. So as to what problem I was having before, and what (if?) I did to resolve it, I cannot be sure. But I do want to document a few details in case it helps others having issues. I'll mark this thread resolved, even though perhaps not every question was answered.
One thing I noticed in /var/log/cups/error_log today was:
Code: | E [17/Apr/2021:08:51:11 -0700] EPSON_XP-620_Series: File \"/usr/libexec/cups/filter/epson-escpr-wrapper\" not available: No such file or directory |
This led me to discover a package net-print/epson-inkjet-printer-escpr. Once I emerged that, that particular error went away.
After resolving that, CUPS would try to print and tell me: Unable to locate printer "EPSON0E275E.local". Nothing noticeable in /var/log/cups/error_log this time.
I'm not entirely sure what the cause of this is but I worked around it by adding an entry to my hosts file mapping EPSON0E275E.local to my printer's IP. |
|
Back to top |
|
|
|
|
You cannot post new topics in this forum You cannot reply to topics in this forum You cannot edit your posts in this forum You cannot delete your posts in this forum You cannot vote in polls in this forum
|
|