State: stopped "Filter failed" on a Xerox Phaser 6125N. how do you troubleshoot a printer in Linux?

classic Classic list List threaded Threaded
14 messages Options
Reply | Threaded
Open this post in threaded view
|

State: stopped "Filter failed" on a Xerox Phaser 6125N. how do you troubleshoot a printer in Linux?

Albretch Mueller-3
 I got a:

_OFL="hwinfo_$(date +%Y%m%d%H%M%S)_hwinfo_printer.log"

hwinfo --printer --log "${_OFL}" 2>&1

17: USB 00.0: 10900 Printer
  [Created at usb.122]
  Unique ID: L0iK.+dt1oXx0UKE
  Old Unique ID: vxkL.pGWWNSDBYN7
  Parent ID: 2UT6.DFkaVl_rzX0
  SysFS ID: /devices/pci0000:00/0000:00:12.2/usb1/1-3/1-3.2/1-3.2:1.0
  SysFS BusID: 1-3.2:1.0
  Hardware Class: printer
  HW Class List: printer, usb
  Model: "Xerox Phaser 6125N"
  Hotplug: USB
  Vendor: usb 0x0924 "Xerox"
  Device: usb 0x3d61 "Phaser 6125N"
  Revision: "1.00"
  Serial ID: "LAL056826"
  Driver: "usblp"
  Driver Modules: "usblp"
  Main Driver Module: "usblp"
  Speed: 480 Mbps
  Module Alias: "usb:v0924p3D61d0100dc00dsc00dp00ic07isc01ip02in00"
  Driver Info #0:
    Driver Status: usblp is active
    Driver Activation Cmd: "modprobe usblp"
    Driver DB0: 2, usblp
  Config Status: cfg=new, avail=yes, need=no, active=unknown
  Attached to: #13 (Hub)
~
 and after mostly following the steps outlined in:

 https://rickvanderzwet.nl/trac/personal/wiki/XeroxPhaser6125N

 https://wiki.archlinux.org/index.php/CUPS/Printer-specific_problems

 I can see the printer fine both while using cups going: localhost:631
and in libreoffice and okular, but the printer doesn't actually print.
The message that I see in cups is: State: stopped "Filter failed"
~
 A google search a less than 5 sinvoll hits which weren't really helpful.

 WOuld you suggest a good guideline about how to troubleshoot printers?

 lbrtchx

Reply | Threaded
Open this post in threaded view
|

Re: State: stopped "Filter failed" on a Xerox Phaser 6125N. how do you troubleshoot a printer in Linux?

Albretch Mueller-3
I see on the error logs after making sure the printer is ready to
receive print jobs (based on its own menu)

http://localhost:631/admin/log/error_log
...
D [06/Apr/2020:01:19:35 +0200] [Job 5] printer-state=3(idle)
D [06/Apr/2020:01:19:35 +0200] [Job 5] printer-state-message="Filter failed"
D [06/Apr/2020:01:19:35 +0200] [Job 5] printer-state-reasons=none
~
 lbrtchx

Reply | Threaded
Open this post in threaded view
|

Re: State: stopped "Filter failed" on a Xerox Phaser 6125N. how do you troubleshoot a printer in Linux?

Brian
On Mon 06 Apr 2020 at 01:28:51 +0200, Albretch Mueller wrote:

> I see on the error logs after making sure the printer is ready to
> receive print jobs (based on its own menu)
>
> http://localhost:631/admin/log/error_log
> ...
> D [06/Apr/2020:01:19:35 +0200] [Job 5] printer-state=3(idle)
> D [06/Apr/2020:01:19:35 +0200] [Job 5] printer-state-message="Filter failed"
> D [06/Apr/2020:01:19:35 +0200] [Job 5] printer-state-reasons=none

A complete error_log is needed. The Printing section of the wiki should
help you get one.

--
Brian.

Reply | Threaded
Open this post in threaded view
|

Re: State: stopped "Filter failed" on a Xerox Phaser 6125N. how do you troubleshoot a printer in Linux?

elvis
In reply to this post by Albretch Mueller-3
On 6/4/20 9:06 am, Albretch Mueller wrote:


Last time I had a 'filter failed' it was because a program in the filter
chain was not installed or was crashing.


The filter is just your output piped to a program to convert it to
another format etc. Like pdf something your printer can print like
postscript.

Or things might have changed but I hope this helps



>   I got a:
>
> _OFL="hwinfo_$(date +%Y%m%d%H%M%S)_hwinfo_printer.log"
>
> hwinfo --printer --log "${_OFL}" 2>&1
>
> 17: USB 00.0: 10900 Printer
>    [Created at usb.122]
>    Unique ID: L0iK.+dt1oXx0UKE
>    Old Unique ID: vxkL.pGWWNSDBYN7
>    Parent ID: 2UT6.DFkaVl_rzX0
>    SysFS ID: /devices/pci0000:00/0000:00:12.2/usb1/1-3/1-3.2/1-3.2:1.0
>    SysFS BusID: 1-3.2:1.0
>    Hardware Class: printer
>    HW Class List: printer, usb
>    Model: "Xerox Phaser 6125N"
>    Hotplug: USB
>    Vendor: usb 0x0924 "Xerox"
>    Device: usb 0x3d61 "Phaser 6125N"
>    Revision: "1.00"
>    Serial ID: "LAL056826"
>    Driver: "usblp"
>    Driver Modules: "usblp"
>    Main Driver Module: "usblp"
>    Speed: 480 Mbps
>    Module Alias: "usb:v0924p3D61d0100dc00dsc00dp00ic07isc01ip02in00"
>    Driver Info #0:
>      Driver Status: usblp is active
>      Driver Activation Cmd: "modprobe usblp"
>      Driver DB0: 2, usblp
>    Config Status: cfg=new, avail=yes, need=no, active=unknown
>    Attached to: #13 (Hub)
> ~
>   and after mostly following the steps outlined in:
>
>   https://rickvanderzwet.nl/trac/personal/wiki/XeroxPhaser6125N
>
>   https://wiki.archlinux.org/index.php/CUPS/Printer-specific_problems
>
>   I can see the printer fine both while using cups going: localhost:631
> and in libreoffice and okular, but the printer doesn't actually print.
> The message that I see in cups is: State: stopped "Filter failed"
> ~
>   A google search a less than 5 sinvoll hits which weren't really helpful.
>
>   WOuld you suggest a good guideline about how to troubleshoot printers?
>
>   lbrtchx
>
--
Start a download. Get a beer. Multitasking.

Reply | Threaded
Open this post in threaded view
|

Re: State: stopped "Filter failed" on a Xerox Phaser 6125N. how do you troubleshoot a printer in Linux?

G.W. Haywood-2
In reply to this post by Albretch Mueller-3
Hi there,

On Mon, 6 Apr 2020, Albretch Mueller wrote:

> WOuld you suggest a good guideline about how to troubleshoot printers?

When CUPS works, it just works.  When it doesn't, it's a nightmare.
Some of the defaults for CUPS (especially for its behaviour after
errors) are questionable at the best, and the documentation is all
over the place.  On the bright side, there's an entire mailing list
dedicated to it:

https://lists.cups.org/mailman/listinfo/cups

I recommend that you subscribe to the list.

--

73,
Ged.

Reply | Threaded
Open this post in threaded view
|

Re: State: stopped "Filter failed" on a Xerox Phaser 6125N. how do you troubleshoot a printer in Linux?

Albretch Mueller-3
In reply to this post by Brian
On 4/6/20, Brian <[hidden email]> wrote:
> A complete error_log is needed. The Printing section of the wiki should
> help you get one.

 following the documentation on in the case of a USB connected printer:

 https://wiki.ubuntu.com/DebuggingPrintingProblems

 * usb kernel modules loaded for the line printer (usblp):

$ date; lsmod | grep usb
Mo 6. Apr 15:41:20 CEST 2020
usbnet                 45056  2 rndis_host,cdc_ether
mii                    16384  1 usbnet
usblp                  20480  0
usbhid                 53248  0
hid                   122880  2 hid_generic,usbhid
usbcore               253952  9
rndis_host,usbnet,usbhid,ehci_hcd,cdc_ether,ohci_pci,usblp,ohci_hcd,ehci_pci
usb_common             16384  1 usbcore

 * messages in /var/log/syslog

# date; tail -f /var/log/syslog
Mo 6. Apr 15:44:27 CEST 2020
Apr  6 15:40:28 lbrtchx udev-configure-printer: Re-enabled printer
ipp://localhost/printers/Xerox_Phaser_6125N
Apr  6 15:42:44 lbrtchx kernel: [30620.642787] usb 1-1: USB
disconnect, device number 13
Apr  6 15:42:44 lbrtchx kernel: [30620.643243] usblp0: removed
Apr  6 15:42:44 lbrtchx udev-configure-printer: remove
/devices/pci0000:00/0000:00:12.2/usb1/1-1
Apr  6 15:42:44 lbrtchx udev-configure-printer: URI of detected
printer: usb://Xerox/Phaser%206125N?serial=LAL056826, normalized:
xerox phaser 6125n serial lal056826
Apr  6 15:42:44 lbrtchx udev-configure-printer: URI of print queue:
usb://Xerox/Phaser%206125N?serial=LAL056826, normalized: xerox phaser
6125n serial lal056826
Apr  6 15:42:44 lbrtchx udev-configure-printer: Queue
ipp://localhost/printers/Xerox_Phaser_6125N has matching device URI
Apr  6 15:42:44 lbrtchx udev-configure-printer: Disabled printer
ipp://localhost/printers/Xerox_Phaser_6125N as the corresponding
device was unplugged or turned off
Apr  6 15:42:44 lbrtchx systemd[1]: printer.target: Unit not needed
anymore. Stopping.
Apr  6 15:42:44 lbrtchx systemd[1]: Stopped target Printer.

 * after reconnecting the USB printer cable:

...

Apr  6 15:42:44 lbrtchx udev-configure-printer: Disabled printer
ipp://localhost/printers/Xerox_Phaser_6125N as the corresponding
device was unplugged or turned off
Apr  6 15:42:44 lbrtchx systemd[1]: printer.target: Unit not needed
anymore. Stopping.
Apr  6 15:42:44 lbrtchx systemd[1]: Stopped target Printer.
Apr  6 15:45:01 lbrtchx CRON[15218]: (root) CMD (command -v debian-sa1
> /dev/null && debian-sa1 1 1)
Apr  6 15:45:37 lbrtchx kernel: [30793.845579] usb 1-1: new high-speed
USB device number 14 using ehci-pci
Apr  6 15:45:37 lbrtchx kernel: [30793.995029] usb 1-1: New USB device
found, idVendor=0924, idProduct=3d61
Apr  6 15:45:37 lbrtchx kernel: [30793.995039] usb 1-1: New USB device
strings: Mfr=1, Product=2, SerialNumber=3
Apr  6 15:45:37 lbrtchx kernel: [30793.995044] usb 1-1: Product: Phaser 6125N
Apr  6 15:45:37 lbrtchx kernel: [30793.995049] usb 1-1: Manufacturer: Xerox
Apr  6 15:45:37 lbrtchx kernel: [30793.995053] usb 1-1: SerialNumber: LAL056826
Apr  6 15:45:37 lbrtchx kernel: [30793.998315] usblp 1-1:1.0: usblp0:
USB Bidirectional printer dev 14 if 0 alt 0 proto 2 vid 0x0924 pid
0x3D61
Apr  6 15:45:37 lbrtchx mtp-probe: checking bus 1, device 14:
"/sys/devices/pci0000:00/0000:00:12.2/usb1/1-1"
Apr  6 15:45:37 lbrtchx mtp-probe: bus: 1, device: 14 was not an MTP device
Apr  6 15:45:37 lbrtchx systemd[1]: Reached target Printer.
Apr  6 15:45:37 lbrtchx systemd[1]: Started Configure Plugged-In Printer.
Apr  6 15:45:37 lbrtchx udev-configure-printer: add usb-001-014
Apr  6 15:45:37 lbrtchx udev-configure-printer: device devpath is
/devices/pci0000:00/0000:00:12.2/usb1/1-1
Apr  6 15:45:37 lbrtchx udev-configure-printer: MFG:Xerox MDL:Phaser
6125N SERN:- serial:LAL056826
Apr  6 15:45:43 lbrtchx kernel: [30799.238348] usblp0: removed
Apr  6 15:45:43 lbrtchx kernel: [30799.245755] usblp 1-1:1.0: usblp0:
USB Bidirectional printer dev 14 if 0 alt 0 proto 2 vid 0x0924 pid
0x3D61
Apr  6 15:45:43 lbrtchx udev-configure-printer: URI contains USB serial number
Apr  6 15:45:43 lbrtchx udev-configure-printer: URI match:
usb://Xerox/Phaser%206125N?serial=LAL056826
Apr  6 15:45:43 lbrtchx udev-configure-printer: URI of detected
printer: usb://Xerox/Phaser%206125N?serial=LAL056826, normalized:
xerox phaser 6125n serial lal056826
Apr  6 15:45:43 lbrtchx udev-configure-printer: URI of print queue:
usb://Xerox/Phaser%206125N?serial=LAL056826, normalized: xerox phaser
6125n serial lal056826
Apr  6 15:45:43 lbrtchx udev-configure-printer: Queue
ipp://localhost/printers/Xerox_Phaser_6125N has matching device URI
Apr  6 15:45:43 lbrtchx udev-configure-printer: Re-enabled printer
ipp://localhost/printers/Xerox_Phaser_6125N


 * USB connected printer correctly detected by the USB subsystem?:

$ date; lsusb
Mo 6. Apr 15:48:13 CEST 2020
Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 004 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 001 Device 003: ID 413c:2105 Dell Computer Corp. Model L100 Keyboard
Bus 001 Device 002: ID 1a40:0101 Terminus Technology Inc. Hub
Bus 001 Device 014: ID 0924:3d61 Xerox
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 003 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
$

 * USB connected printer correctly detected by the USB subsystem and
are ownerships and permissions correctly set?:

$ date; ls -l /dev/usb/lp* /dev/bus/usb/*/*
Mo 6. Apr 15:57:05 CEST 2020
crw-rw-r-- 1 root root    189,   0 Apr  6 01:10 /dev/bus/usb/001/001
crw-rw-r-- 1 root root    189,   1 Apr  6 01:10 /dev/bus/usb/001/002
crw-rw-r-- 1 root plugdev 189,   2 Apr  6 01:10 /dev/bus/usb/001/003
crw-rw-r-- 1 root lp      189,  13 Apr  6 15:45 /dev/bus/usb/001/014
crw-rw-r-- 1 root root    189, 128 Apr  6 01:10 /dev/bus/usb/002/001
crw-rw-r-- 1 root root    189, 256 Apr  6 01:10 /dev/bus/usb/003/001
crw-rw-r-- 1 root root    189, 384 Apr  6 01:10 /dev/bus/usb/004/001
crw-rw---- 1 root lp      180,   0 Apr  6 15:45 /dev/usb/lp0


 * printer's device ID strings

# date; usb_printerid /dev/usb/lp0
Mo 6. Apr 15:58:13 CEST 2020
GET_DEVICE_ID string:
MFG:Xerox;CMD:HBPL;MDL:Phaser 6125N;DES:Xerox Phaser 6125 Color Laser
Printer, Letter/A4
Size;CLS:PRINTER;STS:AAAPAQD/AP8AAAAAAAIAA2QDZANkAzwDZAMAA2QDQBoAAAAAt4IAAAAAAAAAAAAAIQAAEAAAAAAQ
DA==;
#
~
 I don't think exactly where the problem may be/how to troubleshoot the problem?

 lbrtchx

Reply | Threaded
Open this post in threaded view
|

Re: State: stopped "Filter failed" on a Xerox Phaser 6125N. how do you troubleshoot a printer in Linux?

Brian
On Mon 06 Apr 2020 at 16:05:11 +0200, Albretch Mueller wrote:

> On 4/6/20, Brian <[hidden email]> wrote:
> > A complete error_log is needed. The Printing section of the wiki should
> > help you get one.
>
>  following the documentation on in the case of a USB connected printer:
>
>  https://wiki.ubuntu.com/DebuggingPrintingProblems

I meant the Debian wiki:

  https://wiki.debian.org/CUPSDebugging#errorlog

Compress the error_log before sending it here.

--
Brian.

Reply | Threaded
Open this post in threaded view
|

Re: State: stopped "Filter failed" on a Xerox Phaser 6125N. how do you troubleshoot a printer in Linux?

Klaus Singvogel-2
In reply to this post by Albretch Mueller-3
Albretch Mueller wrote:
> $ date; lsmod | grep usb
[...]
>  * messages in /var/log/syslog
[...]
>  * after reconnecting the USB printer cable:
[...]
>  * USB connected printer correctly detected by the USB subsystem and
> are ownerships and permissions correctly set?:
[...]
>  * printer's device ID strings
[...]

>  I don't think exactly where the problem may be/how to troubleshoot the problem?

For me it looks like your printer is correctly installed regarding
hardware connection, but no guaranty.

The more interesting question is: why does the Filter fail?

Maybe the filter, which is defined in the printer PPD file, has wrong
permissions or is missing?

Have a closer look at /var/log/cups/error_log

Maybe you've to enable Loglevel "info" or "debug" in /etc/cups/cupsd.conf
before, followed by a "systemctl restart cups":
        LogLevel info

Regards,
        Klaus.
--
Klaus Singvogel
GnuPG-Key-ID: 1024R/5068792D  1994-06-27

Reply | Threaded
Open this post in threaded view
|

Re: State: stopped "Filter failed" on a Xerox Phaser 6125N. how do you troubleshoot a printer in Linux?

Albretch Mueller-3
 Perhaps you will be able to see something I don't. If not let me know
how could do whatever you need with the debug levels or log file in a
better way

>Maybe you've to enable Loglevel "info" or "debug" in /etc/cups/cupsd.conf

# date; cat /etc/cups/cupsd.conf | grep LogLevel
Mo 6. Apr 22:37:44 CEST 2020
LogLevel debug
#

# cupsctl --debug-logging
# cupsctl LogLevel=debug2

# grep -v "not idle any more" /var/log/cups/error_log | less

E [06/Apr/2020:00:31:51 +0200] [Job 4] Job stopped due to filter
errors; please consult the error_log file for details.
D [06/Apr/2020:00:31:51 +0200] [Job 4] The following messages were
recorded from 00:31:51 to 00:31:51
D [06/Apr/2020:00:31:51 +0200] [Job 4] Adding start banner page "none".
D [06/Apr/2020:00:31:51 +0200] [Job 4] Queued on "Phaser-6125N" by "debuserme".
D [06/Apr/2020:00:31:51 +0200] [Job 4] Auto-typing file...
D [06/Apr/2020:00:31:51 +0200] [Job 4] Request file type is
application/postscript.
D [06/Apr/2020:00:31:51 +0200] [Job 4] File of type
application/postscript queued by "debuserme".
D [06/Apr/2020:00:31:51 +0200] [Job 4] Adding end banner page "none".
D [06/Apr/2020:00:31:51 +0200] [Job 4] time-at-processing=1586125911
D [06/Apr/2020:00:31:51 +0200] [Job 4] 2 filters for job:
D [06/Apr/2020:00:31:51 +0200] [Job 4] pstops (application/postscript
to application/vnd.cups-postscript, cost 66)
D [06/Apr/2020:00:31:51 +0200] [Job 4] /usr/lib/cups/filter/FXM_PF
(application/vnd.cups-postscript to printer/Phaser-6125N, cost 0)
D [06/Apr/2020:00:31:51 +0200] [Job 4] job-sheets=none,none
D [06/Apr/2020:00:31:51 +0200] [Job 4] argv[0]="Phaser-6125N"
D [06/Apr/2020:00:31:51 +0200] [Job 4] argv[1]="4"
D [06/Apr/2020:00:31:51 +0200] [Job 4] argv[2]="debuserme"
D [06/Apr/2020:00:31:51 +0200] [Job 4] argv[3]="2020-04-03_draft08.pdf"
D [06/Apr/2020:00:31:51 +0200] [Job 4] argv[4]="1"
D [06/Apr/2020:00:31:51 +0200] [Job 4] argv[5]="noCollate
Duplex=DuplexNoTumble finishings=3 fit-to-page media=A4 number-up=1
outputorder=normal page-bottom=12 page-left=11 page-right=12
page-top=11 portrait sides=one-sided
job-uuid=urn:uuid:5a8f06ec-1d43-3cfa-6138-560befd55620
job-originating-host-name=localhost date-time-at-creation=
date-time-at-processing= time-at-creation=1586125911
time-at-processing=1586125911 document-name-supplied=okularDB4703.ps
PageSize=A4"
D [06/Apr/2020:00:31:51 +0200] [Job 4] argv[6]="/var/spool/cups/d00004-001"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [06/Apr/2020:00:31:51 +0200] [Job 4]
envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [06/Apr/2020:00:31:51 +0200] [Job 4]
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [06/Apr/2020:00:31:51 +0200] [Job 4]
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[8]="HOME=/var/spool/cups/tmp"
D [06/Apr/2020:00:31:51 +0200] [Job 4]
envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[10]="SERVER_ADMIN=root@lbrtchx"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[11]="SOFTWARE=CUPS/2.2.1"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[13]="USER=root"



> Have a closer look at /var/log/cups/error_log

the last lines in the error_log are:

http://localhost:631/admin/log/error_log
...
D [06/Apr/2020:22:51:06 +0200] [Client 96] Server address is "[v1.::1]".
D [06/Apr/2020:22:51:06 +0200] [Client 96] Accepted from localhost:45526 (IPv6)
d [06/Apr/2020:22:51:06 +0200] cupsdAddSelect(fd=15,
read_cb=0x5559df967db0, write_cb=(nil), data=0x5559e175d430)
D [06/Apr/2020:22:51:06 +0200] [Client 96] Waiting for request.
I [06/Apr/2020:22:51:06 +0200] Expiring subscriptions...
d [06/Apr/2020:22:51:06 +0200] cupsdCheckJobs: 2 active jobs,
sleeping=0, ac-power=-1, reload=0, curtime=1586206266
d [06/Apr/2020:22:51:06 +0200] cupsdCheckJobs: Job 5 -
dest="Xerox_Phaser_6125N", printer=(nil), state=6, cancel_time=0,
hold_until=1586129075, kill_time=0, pending_cost=0, pending_timeout=0
d [06/Apr/2020:22:51:06 +0200] cupsdCheckJobs: Job 6 -
dest="Xerox_Phaser_6125N", printer=(nil), state=6, cancel_time=0,
hold_until=1586206257, kill_time=0, pending_cost=0, pending_timeout=0
d [06/Apr/2020:22:51:06 +0200] select_timeout: JobHistoryUpdate=0
d [06/Apr/2020:22:51:06 +0200] [Client 96] cupsdReadClient: error=0,
used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=0, request=(nil)(), file=-1
D [06/Apr/2020:22:51:06 +0200] [Client 96] GET /admin/log/error_log HTTP/1.1
D [06/Apr/2020:22:51:06 +0200] cupsdSetBusyState: newbusy="Active
clients", busy="Not busy"
D [06/Apr/2020:22:51:06 +0200] [Client 96] Read: status=200
d [06/Apr/2020:22:51:06 +0200] cupsdFindBest:
uri="/admin/log/error_log", limit=2...
d [06/Apr/2020:22:51:06 +0200] cupsdFindBest: Location /admin/log(10) Limit 7f
d [06/Apr/2020:22:51:06 +0200] cupsdFindBest: Location /admin/conf(11) Limit 7f
d [06/Apr/2020:22:51:06 +0200] cupsdFindBest: Location /admin(6) Limit 7f
d [06/Apr/2020:22:51:06 +0200] cupsdFindBest: Location /(1) Limit 7f
d [06/Apr/2020:22:51:06 +0200] cupsdFindBest: best=/admin/log
d [06/Apr/2020:22:51:06 +0200] [Client 96]
con->uri="/admin/log/error_log", con->best=0x5559e16d8a80(/admin/log)
D [06/Apr/2020:22:51:06 +0200] [Client 96] Authorized as "debuserme"
using Basic.
d [06/Apr/2020:22:51:06 +0200] cupsdIsAuthorized:
con->uri="/admin/log/error_log", con->best=0x5559e16d8a80(/admin/log)
d [06/Apr/2020:22:51:06 +0200] cupsdIsAuthorized:
level=CUPSD_AUTH_USER, type=Basic, satisfy=CUPSD_AUTH_SATISFY_ALL,
num_names=1
d [06/Apr/2020:22:51:06 +0200] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
D [06/Apr/2020:22:51:06 +0200] cupsdIsAuthorized: username="debuserme"
d [06/Apr/2020:22:51:06 +0200] cupsdIsAuthorized: Checking user membership...
d [06/Apr/2020:22:51:06 +0200] cupsdCheckGroup(username="debuserme",
user=0x7fafb092ed80, groupname="lpadmin")
D [06/Apr/2020:22:51:06 +0200] [Client 96] Processing GET /admin/log/error_log

Reply | Threaded
Open this post in threaded view
|

Re: State: stopped "Filter failed" on a Xerox Phaser 6125N. how do you troubleshoot a printer in Linux?

Klaus Singvogel-2
Albretch Mueller wrote:
>  Perhaps you will be able to see something I don't. If not let me know
> how could do whatever you need with the debug levels or log file in a
> better way
>
[...]
>
> # grep -v "not idle any more" /var/log/cups/error_log | less
>
> D [06/Apr/2020:00:31:51 +0200] [Job 4] 2 filters for job:
> D [06/Apr/2020:00:31:51 +0200] [Job 4] pstops (application/postscript
> to application/vnd.cups-postscript, cost 66)
> D [06/Apr/2020:00:31:51 +0200] [Job 4] /usr/lib/cups/filter/FXM_PF
> (application/vnd.cups-postscript to printer/Phaser-6125N, cost 0)

Two filters are executed:

1. pstops

Is CUPS internal and included in Debian package "cups-core-drivers" at my
side (running Debian buster).

2. /usr/lib/cups/filter/FXM_PF

Not known by me. Is the file present? Is it executable? Does it fail, if
you execute it?

I've the feeling this is the culprint, you want to work on.

Regards,
        Klaus.
--
Klaus Singvogel
GnuPG-Key-ID: 1024R/5068792D  1994-06-27

Reply | Threaded
Open this post in threaded view
|

Re: State: stopped "Filter failed" on a Xerox Phaser 6125N. how do you troubleshoot a printer in Linux?

Albretch Mueller-3
On 4/6/20, Klaus Singvogel <[hidden email]> wrote:
> Two filters are executed:
> 1. pstops
> Is CUPS internal and included in Debian package "cups-core-drivers" at my
> side (running Debian buster).

# date; time apt-get install cups-core-drivers
Di 7. Apr 06:58:26 CEST 2020
Paketlisten werden gelesen... Fertig
Abhängigkeitsbaum wird aufgebaut.
Statusinformationen werden eingelesen.... Fertig
cups-core-drivers ist schon die neueste Version (2.2.1-8+deb9u5).
cups-core-drivers wurde als manuell installiert festgelegt.
0 aktualisiert, 0 neu installiert, 0 zu entfernen und 13 nicht aktualisiert.

real    0m2,514s
user    0m2,380s
sys     0m0,104s
#


> 2. /usr/lib/cups/filter/FXM_PF

> Not known by me. Is the file present? Is it executable? Does it fail, if
> you execute it?

# ls -l /usr/lib/cups/filter/FXM_PF
-rwxr-xr-x 1 root root 17065 Feb 11  2012 /usr/lib/cups/filter/FXM_PF

# file /usr/lib/cups/filter/FXM_PF
/usr/lib/cups/filter/FXM_PF: ELF 32-bit LSB executable, Intel 80386,
version 1 (SYSV), dynamically linked, interpreter /lib/ld-linux.so.2,
for GNU/Linux 2.2.5, not stripped
#

> I've the feeling this is the culprint, you want to work on.

 I checked both and it doesn't seem to be the case, but I really can't tell.

 thank you,
 lbrtchx

Reply | Threaded
Open this post in threaded view
|

Re: State: stopped "Filter failed" on a Xerox Phaser 6125N. how do you troubleshoot a printer in Linux?

Klaus Singvogel-2
Albretch Mueller wrote:

> > 2. /usr/lib/cups/filter/FXM_PF
>
> > Not known by me. Is the file present? Is it executable? Does it fail, if
> > you execute it?
>
> # ls -l /usr/lib/cups/filter/FXM_PF
> -rwxr-xr-x 1 root root 17065 Feb 11  2012 /usr/lib/cups/filter/FXM_PF
>
> # file /usr/lib/cups/filter/FXM_PF
> /usr/lib/cups/filter/FXM_PF: ELF 32-bit LSB executable, Intel 80386,
> version 1 (SYSV), dynamically linked, interpreter /lib/ld-linux.so.2,
> for GNU/Linux 2.2.5, not stripped

It's 32bit and outdated.

Debian Buster usually installs 64bit Packages. Though 32bit can be
installed, but aren't by default.

To find out, if the filter works: can you execute it?

Regards,
        Klaus.
--
Klaus Singvogel
GnuPG-Key-ID: 1024R/5068792D  1994-06-27

Reply | Threaded
Open this post in threaded view
|

Re: State: stopped "Filter failed" on a Xerox Phaser 6125N. how do you troubleshoot a printer in Linux?

Brian
In reply to this post by Albretch Mueller-3
On Tue 07 Apr 2020 at 07:02:55 +0200, Albretch Mueller wrote:

> # ls -l /usr/lib/cups/filter/FXM_PF
> -rwxr-xr-x 1 root root 17065 Feb 11  2012 /usr/lib/cups/filter/FXM_PF
>
> # file /usr/lib/cups/filter/FXM_PF
> /usr/lib/cups/filter/FXM_PF: ELF 32-bit LSB executable, Intel 80386,
> version 1 (SYSV), dynamically linked, interpreter /lib/ld-linux.so.2,
> for GNU/Linux 2.2.5, not stripped

Please name the package you installed and give a link to where you
downloaded it from.

--
Brian.

Reply | Threaded
Open this post in threaded view
|

Re: State: stopped "Filter failed" on a Xerox Phaser 6125N. how do you troubleshoot a printer in Linux?

Brian
In reply to this post by Klaus Singvogel-2
On Mon 06 Apr 2020 at 23:26:03 +0200, Klaus Singvogel wrote:

> Albretch Mueller wrote:
> >  Perhaps you will be able to see something I don't. If not let me know
> > how could do whatever you need with the debug levels or log file in a
> > better way
> >
> [...]
> >
> > # grep -v "not idle any more" /var/log/cups/error_log | less
> >
> > D [06/Apr/2020:00:31:51 +0200] [Job 4] 2 filters for job:
> > D [06/Apr/2020:00:31:51 +0200] [Job 4] pstops (application/postscript
> > to application/vnd.cups-postscript, cost 66)
> > D [06/Apr/2020:00:31:51 +0200] [Job 4] /usr/lib/cups/filter/FXM_PF
> > (application/vnd.cups-postscript to printer/Phaser-6125N, cost 0)
>
> Two filters are executed:
>
> 1. pstops
>
> Is CUPS internal and included in Debian package "cups-core-drivers" at my
> side (running Debian buster).
>
> 2. /usr/lib/cups/filter/FXM_PF
>
> Not known by me. Is the file present? Is it executable? Does it fail, if
> you execute it?
>
> I've the feeling this is the culprint, you want to work on.

I don't experience a failure of either filter. Both work flawlessly
for me.

--
Brian.