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.

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

CUPS proudcing "filter error" after printing #5389

Closed
lm808 opened this issue Aug 31, 2018 · 1 comment
Closed

CUPS proudcing "filter error" after printing #5389

lm808 opened this issue Aug 31, 2018 · 1 comment

Comments

@lm808
Copy link

lm808 commented Aug 31, 2018

The issues started about a couple days ago.
After printing all pages, CUPS will stop the job and throw a 'Filter Failed' on the WebUI.
Below I attach the contents of /var/log/cups/error_log, after printing a Test Page from the WebUI.

Operating system is Arch Linux with XFCE4. Other users have experienced the same error recently:
https://bbs.archlinux.org/viewtopic.php?id=239996
Apparently it could be fixed by downgrading ghostscript from 9.23-3 to 9.23-2, but I personally have not tried this so I cannot confirm.


/var/log/cups/error_log

D [31/Aug/2018:17:02:40 +0100] [Job 17] printer-state=3(idle)
D [31/Aug/2018:17:02:40 +0100] [Job 17] printer-state-message="Data file sent successfully."
D [31/Aug/2018:17:02:40 +0100] [Job 17] printer-state-reasons=none
**E [31/Aug/2018:17:35:13 +0100] [Job 18] Job stopped due to filter errors; please consult the error_log file for details.**
D [31/Aug/2018:17:35:13 +0100] [Job 18] The following messages were recorded from 17:34:57 to 17:35:13
D [31/Aug/2018:17:35:13 +0100] [Job 18] Read 1693 bytes of print data...
D [31/Aug/2018:17:35:13 +0100] [Job 18] Wrote 1693 bytes of print data...
D [31/Aug/2018:17:35:13 +0100] [Job 18] Read 8192 bytes of print data...
D [31/Aug/2018:17:35:13 +0100] [Job 18] Wrote 8192 bytes of print data...
D [31/Aug/2018:17:35:13 +0100] [Job 18] Read 1803 bytes of print data...
D [31/Aug/2018:17:35:13 +0100] [Job 18] Wrote 1803 bytes of print data...
...
...
...
D [31/Aug/2018:17:35:13 +0100] [Job 18] Read 8047 bytes of print data...
D [31/Aug/2018:17:35:13 +0100] [Job 18] Processing page 2...
D [31/Aug/2018:17:35:13 +0100] [Job 18] Set job-printer-state-message to "Processing page 2...", current level=INFO
D [31/Aug/2018:17:35:13 +0100] [Job 18] Wrote 8047 bytes of print data...
**D [31/Aug/2018:17:35:13 +0100] [Job 18] Error: /invalidfileaccess in --run--**
D [31/Aug/2018:17:35:13 +0100] [Job 18] Operand stack:
D [31/Aug/2018:17:35:13 +0100] [Job 18] true   (/var/spool/cups/tmp/gs_kkaCRj)
D [31/Aug/2018:17:35:13 +0100] [Job 18] Execution stack:
D [31/Aug/2018:17:35:13 +0100] [Job 18] %interp_exit   .runexec2   --nostringval--   --nostringval--   --nostringval--   2   %stopped_push   --nostringval--   --nostringval--   --nostringval--   false   1   %stopped_push   2015   2   3   %oparray_pop   2014   2   3   %oparray_pop   1998   2   3   %oparray_pop
D [31/Aug/2018:17:35:13 +0100] [Job 18] Dictionary stack:
D [31/Aug/2018:17:35:13 +0100] [Job 18] --dict:999/1684(ro)(G)--   --dict:1/20(G)--   --dict:78/200(L)--
D [31/Aug/2018:17:35:13 +0100] [Job 18] Current allocation mode is local
**D [31/Aug/2018:17:35:13 +0100] [Job 18] GPL Ghostscript 9.23: Unrecoverable error, exit code 1**
D [31/Aug/2018:17:35:13 +0100] [Job 18] Rendering completed
D [31/Aug/2018:17:35:13 +0100] [Job 18] Set job-printer-state-message to "Rendering completed", current level=INFO
D [31/Aug/2018:17:35:13 +0100] [Job 18] PID 4657 (/usr/lib/cups/filter/gstoraster) stopped with status 1.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Hint: Try setting the LogLevel to "debug" to find out more.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Read 11 bytes of print data...
D [31/Aug/2018:17:35:13 +0100] [Job 18] Wrote 11 bytes of print data...
D [31/Aug/2018:17:35:13 +0100] [Job 18] PID 4658 (/usr/lib/cups/filter/hpcups) exited with no errors.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Connecting to ** ip_addr_redacted **:515 for printer PASSTHRU
D [31/Aug/2018:17:35:13 +0100] [Job 18] Connecting to printer.
D [31/Aug/2018:17:35:13 +0100] [Job 18] STATE: -connecting-to-device
D [31/Aug/2018:17:35:13 +0100] [Job 18] Connected to printer.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Connected to ** ip_addr_redacted **:515 (local port 1023)...
D [31/Aug/2018:17:35:13 +0100] [Job 18] lpd_command 02 PASSTHRU
D [31/Aug/2018:17:35:13 +0100] [Job 18] Sending command string (10 bytes)...
D [31/Aug/2018:17:35:13 +0100] [Job 18] Reading command status...
D [31/Aug/2018:17:35:13 +0100] [Job 18] lpd_command returning 0
D [31/Aug/2018:17:35:13 +0100] [Job 18] Control file is:
D [31/Aug/2018:17:35:13 +0100] [Job 18] H**user info redacted**.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Panonymous
D [31/Aug/2018:17:35:13 +0100] [Job 18] JTest Page
D [31/Aug/2018:17:35:13 +0100] [Job 18] ldfA659**user info redacted**.
D [31/Aug/2018:17:35:13 +0100] [Job 18] UdfA659**user info redacted**.
D [31/Aug/2018:17:35:13 +0100] [Job 18] NTest Page
D [31/Aug/2018:17:35:13 +0100] [Job 18] lpd_command 02 101 cfA659**user info redacted**.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Sending command string (27 bytes)...
D [31/Aug/2018:17:35:13 +0100] [Job 18] Reading command status...
D [31/Aug/2018:17:35:13 +0100] [Job 18] lpd_command returning 0
D [31/Aug/2018:17:35:13 +0100] [Job 18] Sending control file (101 bytes)
D [31/Aug/2018:17:35:13 +0100] [Job 18] Control file sent successfully.
D [31/Aug/2018:17:35:13 +0100] [Job 18] lpd_command 03 375535 dfA659**user info redacted**.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Sending command string (30 bytes)...
D [31/Aug/2018:17:35:13 +0100] [Job 18] Reading command status...
D [31/Aug/2018:17:35:13 +0100] [Job 18] lpd_command returning 0
D [31/Aug/2018:17:35:13 +0100] [Job 18] Sending data file (375535 bytes)
D [31/Aug/2018:17:35:13 +0100] [Job 18] Spooling job, 0% complete.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Spooling job, 9% complete.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Spooling job, 17% complete.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Spooling job, 26% complete.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Spooling job, 35% complete.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Spooling job, 44% complete.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Spooling job, 52% complete.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Spooling job, 61% complete.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Spooling job, 70% complete.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Spooling job, 79% complete.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Spooling job, 87% complete.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Spooling job, 96% complete.
D [31/Aug/2018:17:35:13 +0100] [Job 18] Data file sent successfully.
D [31/Aug/2018:17:35:13 +0100] [Job 18] STATE: +cups-waiting-for-job-completed
D [31/Aug/2018:17:35:13 +0100] [Job 18] PID 4659 (/usr/lib/cups/backend/lpd) exited with no errors.
D [31/Aug/2018:17:35:13 +0100] [Job 18] End of messages
D [31/Aug/2018:17:35:13 +0100] [Job 18] printer-state=3(idle)
D [31/Aug/2018:17:35:13 +0100] [Job 18] printer-state-message="Data file sent successfully."
D [31/Aug/2018:17:35:13 +0100] [Job 18] printer-state-reasons=none
@michaelrsweet
Copy link
Collaborator

Ghostscript bug, not CUPS

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants