[EN] "Filter failed"

[lanig]

Hi all,
I have a  LBP113/913 Canon printer which was working perfectly and then stopped with the last big updates.
The jobs fails with "Filter failed" message. I have reinstalled the Canon drivers but it didn't help.

Code: [Select]

tail -n 50 /var/log/cups/
access_log       access_log.4.gz  cups-pdf_log     error_log.3.gz   error_log.7.gz
access_log.1     access_log.5.gz  error_log        error_log.4.gz   
access_log.2.gz  access_log.6.gz  error_log.1      error_log.5.gz   
access_log.3.gz  access_log.7.gz  error_log.2.gz   error_log.6.gz   
root@logodenn:~# tail -n 50 /var/log/cups/error_log
D [20/Mar/2024:22:27:43 +0100] [CGI] cgiSetVariable: PRINTER_NAME=\"Canon_LBP113_913_UFRII_LT\"
D [20/Mar/2024:22:27:43 +0100] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/Canon_LBP113_913_UFRII_LT\"
D [20/Mar/2024:22:27:43 +0100] [CGI] cgiSetVariable: THISURL=\"/printers/Canon_LBP113_913_UFRII_LT\"
D [20/Mar/2024:22:27:43 +0100] [CGI] cgiSetVariable: SEARCH_DEST=\"Canon_LBP113_913_UFRII_LT\"
D [20/Mar/2024:22:27:43 +0100] [Client 84] CGI data ready to be sent.
D [20/Mar/2024:22:27:43 +0100] [Client 84] con->http=0x55b93b052a90
D [20/Mar/2024:22:27:43 +0100] [Client 84] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=67555, file=21
D [20/Mar/2024:22:27:43 +0100] [Client 84] Waiting for CGI data.
D [20/Mar/2024:22:27:43 +0100] [Client 84] con->http=0x55b93b052a90
D [20/Mar/2024:22:27:43 +0100] [Client 84] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=67555, file=21
D [20/Mar/2024:22:27:43 +0100] [Client 84] Waiting for CGI data.
D [20/Mar/2024:22:27:43 +0100] [Client 84] CGI data ready to be sent.
D [20/Mar/2024:22:27:43 +0100] [Client 84] con->http=0x55b93b052a90
D [20/Mar/2024:22:27:43 +0100] [Client 84] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=67555, file=21
D [20/Mar/2024:22:27:43 +0100] [Client 84] Waiting for CGI data.
D [20/Mar/2024:22:27:43 +0100] [Client 84] con->http=0x55b93b052a90
D [20/Mar/2024:22:27:43 +0100] [Client 84] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=67555, file=21
D [20/Mar/2024:22:27:43 +0100] [Client 84] Waiting for CGI data.
D [20/Mar/2024:22:27:43 +0100] [Client 85] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [20/Mar/2024:22:27:43 +0100] [Client 85] Closing connection.
D [20/Mar/2024:22:27:43 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [20/Mar/2024:22:27:43 +0100] [Client 84] CGI data ready to be sent.
D [20/Mar/2024:22:27:43 +0100] [Client 84] con->http=0x55b93b052a90
D [20/Mar/2024:22:27:43 +0100] [Client 84] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=67555, file=21
D [20/Mar/2024:22:27:43 +0100] [Client 84] Waiting for CGI data.
D [20/Mar/2024:22:27:43 +0100] [Client 84] con->http=0x55b93b052a90
D [20/Mar/2024:22:27:43 +0100] [Client 84] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=67555, file=21
D [20/Mar/2024:22:27:43 +0100] [Client 84] Waiting for CGI data.
D [20/Mar/2024:22:27:43 +0100] [Client 84] CGI data ready to be sent.
D [20/Mar/2024:22:27:43 +0100] [Client 84] con->http=0x55b93b052a90
D [20/Mar/2024:22:27:43 +0100] [Client 84] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=67555, file=21
D [20/Mar/2024:22:27:43 +0100] [Client 84] Waiting for CGI data.
D [20/Mar/2024:22:27:43 +0100] [Client 84] Sending 0-length chunk.
D [20/Mar/2024:22:27:43 +0100] [Client 84] Flushing write buffer.
D [20/Mar/2024:22:27:43 +0100] [Client 84] New state is HTTP_STATE_WAITING
D [20/Mar/2024:22:27:43 +0100] [Client 84] Waiting for request.
D [20/Mar/2024:22:27:43 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [20/Mar/2024:22:27:43 +0100] PID 67555 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [20/Mar/2024:22:27:44 +0100] Expiring subscriptions...
I [20/Mar/2024:22:27:51 +0100] Saving printers.conf...
I [20/Mar/2024:22:27:51 +0100] Generating printcap /run/cups/printcap...
I [20/Mar/2024:22:27:51 +0100] Saving job.cache...
I [20/Mar/2024:22:27:51 +0100] Saving subscriptions.conf...
D [20/Mar/2024:22:27:51 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [20/Mar/2024:22:27:51 +0100] Expiring subscriptions...
D [20/Mar/2024:22:27:54 +0100] [Client 84] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [20/Mar/2024:22:27:54 +0100] [Client 84] Closing connection.
D [20/Mar/2024:22:27:54 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [20/Mar/2024:22:27:54 +0100] Expiring subscriptions...
D [20/Mar/2024:22:27:55 +0100] Expiring subscriptions...
Thanks for your help

[eriefisher]

You've reinstalled the driver but have you tried to reinstall the printer?

Code: [Select]

HTTP_STATE_WAITING Closing for error 32 (Broken pipe)

[lanig]

Yes, suppressed and recreated the printer.

[eriefisher]

During the recent T64 transformation I noticed several cups packages were going to be be remove. I was able to avoid it but did you? Are you missing any cups packages?

I just installed a new Cannon wireless printer the other day and it went without issue and prints fine although it shows as driverless.

[dibl]

The jobs fails with "Filter failed" message. I have reinstalled the Canon drivers but it didn't help.

That is the message I get on my HP LaserJet when the proprietary firmware plugin is not installed. Does your Canon need a firmware plugin?

[lanig]

Thanks for the answers.
The Canon installation script does an "apt update" and installs what is necessary. I suppose that all the cups packages are installed. Same answer for the firmware.

I have tried to change the driver to LPB 112 but same result.
If I try to clean the printer I got the message

Code: [Select]

Unsupported document-format "application/vnd.cups-command".

Code: [Select]

journalctl -b | grep cups
[...]
mars 21 13:26:18 logodenn snapd[1032]: services.go:1103: RemoveSnapServices - disabling snap.cups.cupsd.service
mars 21 13:26:18 logodenn snapd[1032]: services.go:1103: RemoveSnapServices - disabling snap.cups.cups-browsed.service
mars 21 13:26:18 logodenn systemd[1]: run-snapd-ns-cups.mnt.mount: Deactivated successfully.
mars 21 13:26:18 logodenn systemd[1]: snap-cups-1024.mount: Deactivated successfully.
mars 21 13:27:35 logodenn systemd[1]: Stopping cups-browsed.service - Make remote CUPS printers available locally...
mars 21 13:27:35 logodenn systemd[1]: cups-browsed.service: Deactivated successfully.
mars 21 13:27:35 logodenn systemd[1]: Stopped cups-browsed.service - Make remote CUPS printers available locally.
mars 21 13:27:35 logodenn systemd[1]: Stopping cups.service - CUPS Scheduler...
mars 21 13:27:35 logodenn systemd[1]: cups.service: Deactivated successfully.
mars 21 13:27:35 logodenn systemd[1]: Stopped cups.service - CUPS Scheduler.
mars 21 13:27:35 logodenn systemd[1]: cups.service: Consumed 42.870s CPU time, 275.0M memory peak, 288.0K memory swap peak.
mars 21 13:27:35 logodenn systemd[1]: cups.path: Deactivated successfully.
mars 21 13:27:35 logodenn systemd[1]: Stopped cups.path - CUPS Scheduler.
mars 21 13:27:35 logodenn systemd[1]: Stopping cups.path - CUPS Scheduler...
mars 21 13:27:35 logodenn systemd[1]: Started cups.path - CUPS Scheduler.
mars 21 13:27:35 logodenn systemd[1]: cups.socket: Deactivated successfully.
mars 21 13:27:35 logodenn systemd[1]: Closed cups.socket - CUPS Scheduler.
mars 21 13:27:35 logodenn systemd[1]: Stopping cups.socket - CUPS Scheduler...
mars 21 13:27:35 logodenn systemd[1]: Listening on cups.socket - CUPS Scheduler.
mars 21 13:27:35 logodenn systemd[1]: Starting cups.service - CUPS Scheduler...
mars 21 13:27:35 logodenn systemd[1]: Started cups.service - CUPS Scheduler.
mars 21 13:27:35 logodenn systemd[1]: Started cups-browsed.service - Make remote CUPS printers available locally.
mars 21 13:27:55 logodenn kernel: audit: type=1400 audit(1711024075.964:464): apparmor="DENIED" operation="capable" class="cap" profile="/usr/sbin/cupsd" pid=130098 comm="usb" capability=12  capname="net_admin"
mars 21 13:27:55 logodenn kernel: audit: type=1400 audit(1711024075.988:465): apparmor="DENIED" operation="open" class="file" profile="/usr/lib/cups/backend/cups-pdf" name="/etc/cups/" pid=130117 comm="cups-pdf" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
mars 21 13:28:22 logodenn kernel: audit: type=1400 audit(1711024102.154:466): apparmor="DENIED" operation="open" class="file" profile="/usr/lib/cups/backend/cups-pdf" name="/etc/cups/" pid=130174 comm="cups-pdf" requested_mask="r" denied_mask="r" fsuid=0 ouid=0


Could it be an app armor problem ?

[eriefisher]

Do you have libcups-filters installed? Should be a t64 version now.

[lanig]

I think so :

Code: [Select]

dpkg -l  |grep cups |grep filter
ii  cups-filters                                             1.28.17-3.1+b1                            amd64        OpenPrinting CUPS Filters - Main Package
ii  cups-filters-core-drivers                                1.28.17-3.1+b1                            amd64        OpenPrinting CUPS Filters - Driverless printing
ii  libcupsfilters1t64:amd64                                 1.28.17-3.1+b1                            amd64        OpenPrinting CUPS Filters - Shared library


[eriefisher]

I'm not sure what to add at this point. Possibly a firewall issue but you say the printer is detected automatically so this may not be the case.

[hendrikL]

If it works as a driverless printer, then there is no need to install extra printing software.
What you can try is to download our latest, in this case kde, testimage, and try to print a testfile. If it's working, then something is broken or miss configured on your system.

[lanig]

OK, thanks for the advice.
Is there a way to have a newer version of Siduction than the installation media from 09-Sep-2023  ?

[edlin]

https://testbuilds.siduction.org/

edlin

[lanig]

I have the same problem with the livecd
-if I choose IPPeverywhere printing the test page gives me "Job cancelled at printer"
-if I choose LPB133 driverless ; "could not get the pdd file".