[cups] printer-state-message="Filter failed" ? is it cups, ghostscript, or other?
PGNet Dev
pgnet.dev at gmail.com
Thu Apr 4 22:06:58 PDT 2019
I've got cups,
cups-2.3b6-lp150.237.1.x86_64
cups-client-2.3b6-lp150.237.1.x86_64
cups-config-2.3b6-lp150.237.1.x86_64
cups-devel-2.3b6-lp150.237.1.x86_64
cups-filters-1.20.4-lp150.86.1.x86_64
cups-pk-helper-0.2.6-lp150.1.6.x86_64
cups-pk-helper-lang-0.2.6-lp150.1.6.noarch
libcups2-2.3b6-lp150.237.1.x86_64
libcupscgi1-2.2.7-lp150.2.6.1.x86_64
libcupsimage2-2.3b6-lp150.237.1.x86_64
libcupsmime1-2.2.7-lp150.2.6.1.x86_64
libcupsppdc1-2.2.7-lp150.2.6.1.x86_64
liboyranos0-cups-0.9.6-lp150.60.1.x86_64
python2-pycups-1.9.73-lp150.1.7.x86_64
python3-cupshelpers-1.5.7-lp150.5.1.noarch
python3-pycups-1.9.73-lp150.1.7.x86_64
rpm -q --whatprovides /usr/lib/cups/filter/foomatic*
cups-filters-1.20.4-lp150.86.1.x86_64
installed & running,
ps ax | grep cups
2008 ? S<s 0:00 /usr/sbin/cupsd -l
5409 ? S< 0:00 /usr/lib/cups/notifier/dbus dbus://
on
lsb_release -rd
Description: openSUSE Leap 15.0
Release: 15.0
It's worked for ages.
In just the last couple of days, I can no longer print. Error
printer-state-message="Filter failed"
seems (?) to be the culprit. E.g., on print,
tail -f /var/log/cups/error_log
localhost - - [04/Apr/2019:21:56:34 -0700] "POST /printers/LJ4050N HTTP/1.1" 200 108156 Print-Job successful-ok
==> error_log <==
E [04/Apr/2019:21:56:34 -0700] [Job 742] Job stopped due to filter errors; please consult the /var/log/cups/error_log file for details.
D [04/Apr/2019:21:56:34 -0700] [Job 742] The following messages were recorded from 21:56:34 to 21:56:34
D [04/Apr/2019:21:56:34 -0700] [Job 742] Applying default options...
D [04/Apr/2019:21:56:34 -0700] [Job 742] Adding start banner page "none".
D [04/Apr/2019:21:56:34 -0700] [Job 742] Adding end banner page "none".
D [04/Apr/2019:21:56:34 -0700] [Job 742] File of type application/pdf queued by "user003".
D [04/Apr/2019:21:56:34 -0700] [Job 742] hold_until=0
D [04/Apr/2019:21:56:34 -0700] [Job 742] Queued on "LJ4050N" by "user003".
D [04/Apr/2019:21:56:34 -0700] [Job 742] time-at-processing=1554440194
D [04/Apr/2019:21:56:34 -0700] [Job 742] 2 filters for job:
D [04/Apr/2019:21:56:34 -0700] [Job 742] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [04/Apr/2019:21:56:34 -0700] [Job 742] foomatic-rip (application/vnd.cups-pdf to printer/LJ4050N, cost 0)
D [04/Apr/2019:21:56:34 -0700] [Job 742] job-sheets=none,none
D [04/Apr/2019:21:56:34 -0700] [Job 742] argv[0]="LJ4050N"
D [04/Apr/2019:21:56:34 -0700] [Job 742] argv[1]="742"
D [04/Apr/2019:21:56:34 -0700] [Job 742] argv[2]="user003"
D [04/Apr/2019:21:56:34 -0700] [Job 742] argv[3]="CUPS/Troubleshooting - ArchWiki"
D [04/Apr/2019:21:56:34 -0700] [Job 742] argv[4]="1"
D [04/Apr/2019:21:56:34 -0700] [Job 742] argv[5]="TonerDensity=3 Duplex=None REt=Medium noCollate number-up=1 PageSize=Letter InputSlot=Default LowToner=Continue PrintoutMode=Normal Manualfeed=Off Economode=FromPrintoutMode PrinterResolution=FromPrintoutMode job-uuid=urn:uuid:0082678b-bf7e-3fda-7883-e05b6575d2be job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1554440194 time-at-processing=1554440194"
D [04/Apr/2019:21:56:34 -0700] [Job 742] argv[6]="/var/spool/cups/d00742-001"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[2]="CUPS_DOCROOT=/usr/share/cups/webcontent"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[7]="CUPS_STATEDIR=/run/cups"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[8]="HOME=/var/spool/cups/tmp"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[10]="SERVER_ADMIN=root at desk003.example.com"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[11]="SOFTWARE=CUPS/2.3b6"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[13]="USER=root"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[14]="CUPS_MAX_MESSAGE=2047"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[15]="CUPS_SERVER=/run/cups/cups.sock"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[17]="IPP_PORT=631"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[18]="CHARSET=utf-8"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[19]="LANG=en_US.UTF-8"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[20]="PPD=/etc/cups/ppd/LJ4050N.ppd"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[21]="RIP_MAX_CACHE=128m"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[22]="CONTENT_TYPE=application/pdf"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[23]="DEVICE_URI=socket://10.1.1.15:9100"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[24]="PRINTER_INFO=LJ4050N"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[25]="PRINTER_LOCATION=LJ4050N"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[26]="PRINTER=LJ4050N"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[27]="PRINTER_STATE_REASONS=none"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[28]="CUPS_FILETYPE=document"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-pdf"
D [04/Apr/2019:21:56:34 -0700] [Job 742] envp[30]="AUTH_I****"
D [04/Apr/2019:21:56:34 -0700] [Job 742] Started filter /usr/lib/cups/filter/pdftopdf (PID 28229)
D [04/Apr/2019:21:56:34 -0700] [Job 742] Started filter /usr/lib/cups/filter/foomatic-rip (PID 28230)
D [04/Apr/2019:21:56:34 -0700] [Job 742] Started backend /usr/lib/cups/backend/socket (PID 28231)
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: +connecting-to-device
D [04/Apr/2019:21:56:34 -0700] [Job 742] Looking up \"10.1.1.15\"...
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -connecting-to-device
D [04/Apr/2019:21:56:34 -0700] [Job 742] 10.1.1.15=10.1.1.15
D [04/Apr/2019:21:56:34 -0700] [Job 742] pdftopdf: Last filter determined by the PPD: foomatic-rip; FINAL_CONTENT_TYPE: application/vnd.cups-pdf => pdftopdf will log pages in page_log.
D [04/Apr/2019:21:56:34 -0700] [Job 742] PAGE: 1 1
D [04/Apr/2019:21:56:34 -0700] [Job 742] hrDeviceDesc=\"HP LaserJet 4050 Series \"
D [04/Apr/2019:21:56:34 -0700] [Job 742] Calling FindDeviceById(cups-LJ4050N)
D [04/Apr/2019:21:56:34 -0700] [Job 742] Found device /org/freedesktop/ColorManager/devices/cups_LJ4050N
D [04/Apr/2019:21:56:34 -0700] [Job 742] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors)
D [04/Apr/2019:21:56:34 -0700] [Job 742] \'CM Color Calibration\' Mode in SPOOLER-LESS: Off
D [04/Apr/2019:21:56:34 -0700] [Job 742] Getting input from file
D [04/Apr/2019:21:56:34 -0700] [Job 742] foomatic-rip version 1.20.4 running...
D [04/Apr/2019:21:56:34 -0700] [Job 742] Parsing PPD file ...
D [04/Apr/2019:21:56:34 -0700] [Job 742] Added option ColorSpace
D [04/Apr/2019:21:56:34 -0700] [Job 742] Added option Resolution
D [04/Apr/2019:21:56:34 -0700] [Job 742] Added option PageSize
D [04/Apr/2019:21:56:34 -0700] [Job 742] Added option PrintoutMode
D [04/Apr/2019:21:56:34 -0700] [Job 742] Added option ImageableArea
D [04/Apr/2019:21:56:34 -0700] [Job 742] Added option PaperDimension
D [04/Apr/2019:21:56:34 -0700] [Job 742] Added option InputSlot
D [04/Apr/2019:21:56:34 -0700] [Job 742] Added option Manualfeed
D [04/Apr/2019:21:56:34 -0700] [Job 742] Added option Duplex
D [04/Apr/2019:21:56:34 -0700] [Job 742] Added option Copies
D [04/Apr/2019:21:56:34 -0700] [Job 742] Added option REt
D [04/Apr/2019:21:56:34 -0700] [Job 742] Added option TonerDensity
D [04/Apr/2019:21:56:34 -0700] [Job 742] Added option LowToner
D [04/Apr/2019:21:56:34 -0700] [Job 742] Added option Economode
D [04/Apr/2019:21:56:34 -0700] [Job 742] Added option ColorModel
D [04/Apr/2019:21:56:34 -0700] [Job 742] Added option PrinterResolution
D [04/Apr/2019:21:56:34 -0700] [Job 742] Added option Font
D [04/Apr/2019:21:56:34 -0700] [Job 742] Parameter Summary
D [04/Apr/2019:21:56:34 -0700] [Job 742] -----------------
D [04/Apr/2019:21:56:34 -0700] [Job 742] Spooler: cups
D [04/Apr/2019:21:56:34 -0700] [Job 742] Printer: LJ4050N
D [04/Apr/2019:21:56:34 -0700] [Job 742] Shell: /bin/bash
D [04/Apr/2019:21:56:34 -0700] [Job 742] PPD file: /etc/cups/ppd/LJ4050N.ppd
D [04/Apr/2019:21:56:34 -0700] [Job 742] ATTR file:
D [04/Apr/2019:21:56:34 -0700] [Job 742] Printer model: HP LaserJet 4050 Foomatic/pxlmono
D [04/Apr/2019:21:56:34 -0700] [Job 742] Job title: CUPS/Troubleshooting - ArchWiki
D [04/Apr/2019:21:56:34 -0700] [Job 742] File(s) to be printed:
D [04/Apr/2019:21:56:34 -0700] [Job 742] <STDIN>
D [04/Apr/2019:21:56:34 -0700] [Job 742] Ghostscript extra search path (\'GS_LIB\'): /usr/share/cups/fonts
D [04/Apr/2019:21:56:34 -0700] [Job 742] Printing system options:
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'noCollate\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] Unknown boolean option \"noCollate\".
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'number-up=1\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] Unknown option number-up=1.
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'job-uuid=urn:uuid:0082678b-bf7e-3fda-7883-e05b6575d2be\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] Unknown option job-uuid=urn:uuid:0082678b-bf7e-3fda-7883-e05b6575d2be.
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'job-originating-host-name=localhost\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] Unknown option job-originating-host-name=localhost.
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'date-time-at-creation=\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] Unknown option date-time-at-creation=.
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'date-time-at-processing=\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] Unknown option date-time-at-processing=.
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'time-at-creation=1554440194\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] Unknown option time-at-creation=1554440194.
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'time-at-processing=1554440194\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] Unknown option time-at-processing=1554440194.
D [04/Apr/2019:21:56:34 -0700] [Job 742] CM Color Calibration Mode in CUPS: Off
D [04/Apr/2019:21:56:34 -0700] [Job 742] Options from the PPD file:
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'TonerDensity=3\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'Duplex=None\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'REt=Medium\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'PageSize=Letter\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'InputSlot=Default\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'LowToner=Continue\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'PrintoutMode=Normal\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'Manualfeed=Off\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'Economode=FromPrintoutMode\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] Pondering option \'PrinterResolution=FromPrintoutMode\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] ================================================
D [04/Apr/2019:21:56:34 -0700] [Job 742] File: <STDIN>
D [04/Apr/2019:21:56:34 -0700] [Job 742] ================================================
D [04/Apr/2019:21:56:34 -0700] [Job 742] Filetype: PDF
D [04/Apr/2019:21:56:34 -0700] [Job 742] Storing temporary files in /var/spool/cups/tmp
D [04/Apr/2019:21:56:34 -0700] [Job 742] PID 28229 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [04/Apr/2019:21:56:34 -0700] [Job 742] ATTR: marker-colors=none
D [04/Apr/2019:21:56:34 -0700] [Job 742] ATTR: marker-names=\'\"Toner Cartridge HP C4127X\"\'
D [04/Apr/2019:21:56:34 -0700] [Job 742] ATTR: marker-types=toner
D [04/Apr/2019:21:56:34 -0700] [Job 742] ATTR: marker-levels=13
D [04/Apr/2019:21:56:34 -0700] [Job 742] new_supply_state=0, change_state=ffff
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -developer-low-report
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -developer-empty-warning
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -marker-supply-low-report
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -marker-supply-empty-warning
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -opc-near-eol-report
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -opc-life-over-warning
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -toner-low-report
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -toner-empty-warning
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -waste-receptacle-almost-full-report
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -waste-receptacle-full-warning
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -cleaner-life-almost-over-report
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -cleaner-life-over-warning
D [04/Apr/2019:21:56:34 -0700] [Job 742] new_state=0, change_state=ffff
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -media-empty-warning
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -door-open-report
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -media-jam-warning
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -input-tray-missing-warning
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -output-tray-missing-warning
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -marker-supply-missing-warning
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -output-area-almost-full-report
D [04/Apr/2019:21:56:34 -0700] [Job 742] STATE: -output-area-full-warning
D [04/Apr/2019:21:56:34 -0700] [Job 742] backendWaitLoop(snmp_fd=5, addr=0x5602ef15e028, side_cb=0x5602ed6dc5b0)
D [04/Apr/2019:21:56:34 -0700] [Job 742] GPL Ghostscript 9.27: Unrecoverable error, exit code 1
D [04/Apr/2019:21:56:34 -0700] [Job 742] Process is dying with \"Unable to determine number of pages, page count: -1
D [04/Apr/2019:21:56:34 -0700] [Job 742] \", exit stat 3
D [04/Apr/2019:21:56:34 -0700] [Job 742] Cleaning up...
D [04/Apr/2019:21:56:34 -0700] [Job 742] PID 28230 (/usr/lib/cups/filter/foomatic-rip) stopped with status 3.
D [04/Apr/2019:21:56:34 -0700] [Job 742] Hint: Try setting the LogLevel to "debug" to find out more.
D [04/Apr/2019:21:56:34 -0700] [Job 742] PID 28231 (/usr/lib/cups/backend/socket) exited with no errors.
D [04/Apr/2019:21:56:34 -0700] [Job 742] End of messages
D [04/Apr/2019:21:56:34 -0700] [Job 742] printer-state=3(idle)
D [04/Apr/2019:21:56:34 -0700] [Job 742] printer-state-message="Filter failed"
D [04/Apr/2019:21:56:34 -0700] [Job 742] printer-state-reasons=none
*Is* 'printer-state-message="Filter failed"` the problem here?
Or ghostscript? other?
More information about the cups
mailing list