LinuxQuestions.org

LinuxQuestions.org (/questions/)
-   Linux - Newbie (https://www.linuxquestions.org/questions/linux-newbie-8/)
-   -   CUPS issue; error with network printer "/usr/lib/cups/backend/hp failed" on client (https://www.linuxquestions.org/questions/linux-newbie-8/cups-issue%3B-error-with-network-printer-usr-lib-cups-backend-hp-failed-on-client-4175601187/)

floppy_stuttgart 03-06-2017 02:50 PM

CUPS issue; error with network printer "/usr/lib/cups/backend/hp failed" on client
 
my printer server raspberry PI (raspian "Wheezy") dont work = it dont print (anymore; probably after an update?). the /var/log/cups/error_log on the server is continuously filling and growing to several megabytes. I try to print from the client (a notebook) but I get the error "/usr/lib/cups/backend/hp failed"
"Resume printer" dont work.
Any advice is welcome.

.....
Quote:

D [06/Mar/2017:21:20:39 +0100] cupsdReadClient: 16 POST /printers/Deskjet_2540 HTTP/1.1
D [06/Mar/2017:21:20:39 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [06/Mar/2017:21:20:39 +0100] cupsdAuthorize: No authentication data provided.
D [06/Mar/2017:21:20:39 +0100] cupsdReadClient: 16 2.0 Get-Job-Attributes 1
D [06/Mar/2017:21:20:39 +0100] Get-Job-Attributes ipp://192.168.178.50:631/printers/Deskjet_2540
D [06/Mar/2017:21:20:39 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://192.168.178.50:631/printers/Deskjet_2540) from 192.168.178.37
D [06/Mar/2017:21:20:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [06/Mar/2017:21:20:39 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Mar/2017:21:20:39 +0100] cupsdCloseClient: 16
D [06/Mar/2017:21:20:39 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [06/Mar/2017:21:20:40 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [06/Mar/2017:21:20:40 +0100] cupsdCloseClient: 15
D [06/Mar/2017:21:20:40 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [06/Mar/2017:21:20:40 +0100] cupsdAcceptClient: 15 from 192.168.178.37:631 (IPv4)
D [06/Mar/2017:21:20:40 +0100] cupsdReadClient: 15 POST /printers/Deskjet_2540 HTTP/1.1
D [06/Mar/2017:21:20:40 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [06/Mar/2017:21:20:40 +0100] cupsdAuthorize: No authentication data provided.
D [06/Mar/2017:21:20:40 +0100] cupsdReadClient: 15 2.0 Get-Job-Attributes 1
D [06/Mar/2017:21:20:40 +0100] Get-Job-Attributes ipp://192.168.178.50:631/printers/Deskjet_2540
D [06/Mar/2017:21:20:40 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://192.168.178.50:631/printers/Deskjet_2540) from 192.168.178.37
D [06/Mar/2017:21:20:40 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [06/Mar/2017:21:20:43 +0100] cupsdAcceptClient: 16 from 192.168.178.37:631 (IPv4)
D [06/Mar/2017:21:20:43 +0100] cupsdReadClient: 16 POST /printers/Deskjet_2540 HTTP/1.1
D [06/Mar/2017:21:20:43 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [06/Mar/2017:21:20:43 +0100] cupsdAuthorize: No authentication data provided.
D [06/Mar/2017:21:20:43 +0100] cupsdReadClient: 16 2.0 Get-Printer-Attributes 1
D [06/Mar/2017:21:20:43 +0100] Get-Printer-Attributes ipp://192.168.178.50:631/printers/Deskjet_2540
D [06/Mar/2017:21:20:43 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://192.168.178.50:631/printers/Deskjet_2540) from 192.168.178.37
D [06/Mar/2017:21:20:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [06/Mar/2017:21:20:43 +0100] cupsdReadClient: 16 POST /printers/Deskjet_2540 HTTP/1.1
D [06/Mar/2017:21:20:43 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [06/Mar/2017:21:20:43 +0100] cupsdAuthorize: No authentication data provided.
D [06/Mar/2017:21:20:43 +0100] cupsdReadClient: 16 2.0 Get-Job-Attributes 1
D [06/Mar/2017:21:20:43 +0100] Get-Job-Attributes ipp://192.168.178.50:631/printers/Deskjet_2540
D [06/Mar/2017:21:20:43 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://192.168.178.50:631/printers/Deskjet_2540) from 192.168.178.37
D [06/Mar/2017:21:20:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [06/Mar/2017:21:20:43 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Mar/2017:21:20:43 +0100] cupsdCloseClient: 16
D [06/Mar/2017:21:20:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"

ferrari 03-06-2017 09:45 PM

If you can get to the CUPS web interface, then try resuming the printer concerned, or check/edit its status directly in /etc/cups/printers.conf. You can also re-enable a printer (assuming that is the problem) using the 'cupsenable' command.

Get status and printer name from
Code:

lpstat -p
then
Code:

cupsenable <printername>

floppy_stuttgart 03-07-2017 12:53 PM

Quote:

Originally Posted by ferrari (Post 5680019)
Get status and printer name from
Code:

lpstat -p
then
Code:

cupsenable <printername>

lpstat -p
printer Deskjet_2540 disabled since Di 07 Mär 2017 18:46:46 CET -
Processing page 1...
cupsenable Deskjet_2540 (no reaction)

in the client CUPS web interface, I see ("Resume printer" has no effect)
Deskjet_2540-817 Unknown Withheld 1k Unknown pending since
Mon Mar 6 19:43:03 2017
"/usr/lib/cups/backend/hp failed"

Deskjet_2540-818 Unknown Withheld 21k Unknown pending since
Mon Mar 6 20:09:11 2017

Deskjet_2540-819 Unknown Withheld 21k Unknown pending since
Mon Mar 6 21:37:24 2017

Deskjet_2540-820 Unknown Withheld 21k Unknown pending since
Tue Mar 7 19:45:40 2017

Deskjet_2540-821 Unknown Withheld 21k Unknown pending since
Tue Mar 7 19:50:48 2017

/etc/cups/printers.conf
# Printer configuration file for CUPS v1.5.3
# Written by cupsd
# DO NOT EDIT THIS FILE WHEN CUPSD IS RUNNING
<Printer Deskjet_2540>
UUID urn:uuid:9fdaaaca-a671-321b-6493-607072915f93
Info Deskjet_2540
Location waschkueche
MakeModel HP Deskjet 2540 Series, hpcups 3.15.4
DeviceURI hp:/usb/Deskjet_2540_series?serial=CN49Q4F4KH0604
State Stopped
StateMessage Processing page 1...
StateTime 1488912703
Reason paused
Type 36876
Accepting Yes
Shared Yes
JobSheets none none
QuotaPeriod 0
PageLimit 0
KLimit 0
OpPolicy default
ErrorPolicy stop-printer
</Printer>


Any idea is very welcome.

floppy_stuttgart 03-07-2017 02:05 PM

searching error messages in /var/log/cups/error_log show
I found few messages. Any explanation is welcome (I will further search).
Thanks.

Quote:

...
D [07/Mar/2017:06:25:08 +0100] [Job 819] Unloading...
D [07/Mar/2017:06:25:08 +0100] Calling DeleteDevice(cups-Deskjet_2540)
D [07/Mar/2017:06:25:08 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, '(s)', does not match expected type '(o)'
D [07/Mar/2017:06:25:08 +0100] cupsdDeregisterPrinter(p=0x7fc41180(Deskjet_2540), removeit=1)
I [07/Mar/2017:06:25:08 +0100] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 39 types, 56 filters...
D [07/Mar/2017:06:25:08 +0100] Loading printer Deskjet_2540...
D [07/Mar/2017:06:25:08 +0100] load_ppd: Loading /var/cache/cups/Deskjet_2540.data...
D [07/Mar/2017:06:25:08 +0100] Calling DeleteDevice(cups-Deskjet_2540)
D [07/Mar/2017:06:25:08 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, '(s)', does not match expected type '(o)'
D [07/Mar/2017:06:25:08 +0100] Using profile id of Deskjet_2540-Gray..
D [07/Mar/2017:06:25:08 +0100] Calling CreateProfile(Deskjet_2540-Gray..,temp)
W [07/Mar/2017:06:25:08 +0100] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Deskjet_2540-Gray..' already exists
D [07/Mar/2017:06:25:08 +0100] Using profile id of Deskjet_2540-RGB..
D [07/Mar/2017:06:25:08 +0100] Calling CreateProfile(Deskjet_2540-RGB..,temp)
W [07/Mar/2017:06:25:08 +0100] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'Deskjet_2540-RGB..' already exists
I [07/Mar/2017:06:25:08 +0100] Registering ICC color profiles for "Deskjet_2540"
D [07/Mar/2017:06:25:08 +0100] Calling CreateDevice(cups-Deskjet_2540,temp)
W [07/Mar/2017:06:25:08 +0100] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-Deskjet_2540' already exists
D [07/Mar/2017:06:25:08 +0100] cupsdRegisterPrinter(p=0x7fc55540(Deskjet_2540))
D [07/Mar/2017:06:25:08 +0100] Registering Avahi printer Deskjet_2540 with name "Deskjet_2540 @ druckerprinterserverraspberrypi.fritz.box" and type "_ipp._tcp,_cups,_universal"
D [07/Mar/2017:06:25:08 +0100] Adding TXT record for Deskjet_2540 @ druckerprinterserverraspberrypi.fritz.box (_ipp._tcp)
D [07/Mar/2017:06:25:08 +0100] Adding TXT record for Deskjet_2540 @ druckerprinterserverraspberrypi.fritz.box (_cups._sub._ipp._tcp)
D [07/Mar/2017:06:25:08 +0100] Adding TXT record for Deskjet_2540 @ druckerprinterserverraspberrypi.fritz.box (_universal._sub._ipp._tcp)
I [07/Mar/2017:06:25:08 +0100] Loading job cache file "/var/cache/cups/job.cache"...
D [07/Mar/2017:06:25:08 +0100] [Job 321] Loading from cache...
D [07/Mar/2017:06:25:08 +0100] [Job 322] Loading from cache...
...
D [07/Mar/2017:19:47:25 +0100] Get-Subscriptions ipp://localhost/
D [07/Mar/2017:19:47:25 +0100] Get-Subscriptions client-error-not-found: No subscriptions found.
D [07/Mar/2017:19:47:25 +0100] Returning IPP client-error-not-found for Get-Subscriptions (ipp://localhost/) from localhost
D [07/Mar/2017:19:47:25 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [07/Mar/2017:19:47:25 +0100] [CGI] cgiSetVariable: SERVER_NAME="192.168.178.50"
...
D [07/Mar/2017:19:48:13 +0100] Script header:
D [07/Mar/2017:19:48:13 +0100] PID 2828 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [07/Mar/2017:19:48:13 +0100] PID 2833 (/usr/lib/cups/backend/hp) stopped with status 127 (File too large)
D [07/Mar/2017:19:48:13 +0100] cupsdAcceptClient: 16 from 192.168.178.37:631 (IPv4)
D [07/Mar/2017:19:48:13 +0100] cupsdReadClient: 15 WAITING Closing on EOF
D [07/Mar/2017:19:48:13 +0100] cupsdCloseClient: 15
D [07/Mar/2017:19:48:13 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Mar/2017:19:48:13 +0100] [Job 817] hp:/usb/Deskjet_2540_series?serial=CN49Q4F4KH0604: symbol lookup error: hp:/usb/Deskjet_2540_series?serial=CN49Q4F4KH0604: undefined symbol: getHPLogLevel
D [07/Mar/2017:19:48:13 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Mar/2017:19:48:13 +0100] cupsdReadClient: 16 POST /printers/Deskjet_2540 HTTP/1.1
...
D [07/Mar/2017:19:48:22 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Mar/2017:19:48:25 +0100] PID 2831 (/usr/lib/cups/filter/gstoraster) stopped with status 13.
I [07/Mar/2017:19:48:25 +0100] [Job 817] Backend returned status 127 (unknown)
D [07/Mar/2017:19:48:25 +0100] Discarding unused job-state-changed event...
I [07/Mar/2017:19:48:25 +0100] [Job 817] Printer stopped due to backend errors; please consult the error_log file for details.
D [07/Mar/2017:19:48:25 +0100] cupsdMarkDirty(----J-)
D [07/Mar/2017:19:48:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Mar/2017:19:48:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Mar/2017:19:48:25 +0100] Discarding unused printer-stopped event...
...

D [07/Mar/2017:19:51:31 +0100] Get-Printer-Attributes ipp://192.168.178.50:631/printers/Deskjet_2540
D [07/Mar/2017:19:51:31 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://192.168.178.50:631/printers/Deskjet_2540) from 192.168.178.37
D [07/Mar/2017:19:51:31 +0100] PID 2839 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [07/Mar/2017:19:51:31 +0100] PID 2844 (/usr/lib/cups/backend/hp) stopped with status 127 (File too large)
D [07/Mar/2017:19:51:31 +0100] cupsdReadClient: 14 WAITING Closing on EOF
D [07/Mar/2017:19:51:31 +0100] cupsdCloseClient: 14
D [07/Mar/2017:19:51:31 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Mar/2017:19:51:31 +0100] [Job 817] hp:/usb/Deskjet_2540_series?serial=CN49Q4F4KH0604: symbol lookup error: hp:/usb/Deskjet_2540_series?serial=CN49Q4F4KH0604: undefined symbol: getHPLogLevel
D [07/Mar/2017:19:51:31 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Mar/2017:19:51:31 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Mar/2017:19:51:31 +0100] cupsdReadClient: 19 POST /printers/Deskjet_2540 HTTP/1.1
...

D [07/Mar/2017:19:51:42 +0100] Get-Job-Attributes ipp://192.168.178.50:631/printers/Deskjet_2540
D [07/Mar/2017:19:51:42 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://192.168.178.50:631/printers/Deskjet_2540) from 192.168.178.37
D [07/Mar/2017:19:51:42 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Mar/2017:19:51:42 +0100] cupsdReadClient: 22 WAITING Closing on EOF
D [07/Mar/2017:19:51:42 +0100] cupsdCloseClient: 22
D [07/Mar/2017:19:51:42 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Mar/2017:19:51:43 +0100] PID 2842 (/usr/lib/cups/filter/gstoraster) stopped with status 13.
I [07/Mar/2017:19:51:43 +0100] [Job 817] Backend returned status 127 (unknown)
D [07/Mar/2017:19:51:43 +0100] Discarding unused job-state-changed event...
I [07/Mar/2017:19:51:43 +0100] [Job 817] Printer stopped due to backend errors; please consult the error_log file for details.
D [07/Mar/2017:19:51:43 +0100] cupsdMarkDirty(----J-)
D [07/Mar/2017:19:51:43 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Mar/2017:19:51:43 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Mar/2017:19:51:43 +0100] Discarding unused printer-stopped event...
D [07/Mar/2017:19:51:43 +0100] cupsdMarkDirty(P-----)
D [07/Mar/2017:19:51:43 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Mar/2017:19:51:43 +0100] cupsdRegisterPrinter(p=0x7fc55540(Deskjet_2540))
...

I [01/Jan/1970:01:00:34 +0100] Configured for up to 100 clients.
I [01/Jan/1970:01:00:34 +0100] Allowing up to 100 client connections per host.
I [01/Jan/1970:01:00:34 +0100] Using policy "default" as the default.
I [01/Jan/1970:01:00:34 +0100] Full reload is required.
I [01/Jan/1970:01:00:34 +0100] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 39 types, 56 filters...
D [01/Jan/1970:01:00:34 +0100] Loading printer Deskjet_2540...
D [01/Jan/1970:01:00:34 +0100] load_ppd: Loading /var/cache/cups/Deskjet_2540.data...
D [01/Jan/1970:01:00:34 +0100] Calling DeleteDevice(cups-Deskjet_2540)
D [01/Jan/1970:01:00:34 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, '(s)', does not match expected type '(o)'
D [01/Jan/1970:01:00:34 +0100] Using profile id of Deskjet_2540-Gray..
D [01/Jan/1970:01:00:34 +0100] Calling CreateProfile(Deskjet_2540-Gray..,temp)
D [01/Jan/1970:01:00:34 +0100] created profile /org/freedesktop/ColorManager/profiles/Deskjet_2540_Gray__
D [01/Jan/1970:01:00:34 +0100] Using profile id of Deskjet_2540-RGB..
...
D [07/Mar/2017:20:13:24 +0100] Script header:
D [07/Mar/2017:20:13:24 +0100] PID 2362 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [07/Mar/2017:20:13:24 +0100] cupsdReadClient: 18 WAITING Closing on EOF
D [07/Mar/2017:20:13:24 +0100] cupsdCloseClient: 18
D [07/Mar/2017:20:13:24 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Mar/2017:20:13:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [07/Mar/2017:20:13:24 +0100] [Job 817] hp:/usb/Deskjet_2540_series?serial=CN49Q4F4KH0604: symbol lookup error: hp:/usb/Deskjet_2540_series?serial=CN49Q4F4KH0604: undefined symbol: getHPLogLevel
D [07/Mar/2017:20:13:24 +0100] PID 2367 (/usr/lib/cups/backend/hp) stopped with status 127 (File too large)
...

D [07/Mar/2017:20:13:36 +0100] SSL shutdown successful!
D [07/Mar/2017:20:13:36 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Mar/2017:20:13:36 +0100] cupsdReadClient: 18 WAITING Closing on EOF
D [07/Mar/2017:20:13:36 +0100] cupsdCloseClient: 18
D [07/Mar/2017:20:13:36 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Mar/2017:20:13:37 +0100] PID 2365 (/usr/lib/cups/filter/gstoraster) stopped with status 13.
I [07/Mar/2017:20:13:37 +0100] [Job 817] Backend returned status 127 (unknown)
D [07/Mar/2017:20:13:37 +0100] Discarding unused job-state-changed event...
I [07/Mar/2017:20:13:37 +0100] [Job 817] Printer stopped due to backend errors; please consult the error_log file for details.
D [07/Mar/2017:20:13:37 +0100] cupsdMarkDirty(----J-)
D [07/Mar/2017:20:13:37 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Mar/2017:20:13:37 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Mar/2017:20:13:37 +0100] Discarding unused printer-stopped event...
D [07/Mar/2017:20:13:37 +0100] cupsdMarkDirty(P-----)
D [07/Mar/2017:20:13:37 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [07/Mar/2017:20:13:37 +0100] cupsdRegisterPrinter(p=0x8146b180(Deskjet_2540))
...

ferrari 03-07-2017 06:27 PM

This printer status output is telling...
Code:

lpstat -p
printer Deskjet_2540 disabled since Di 07 Mär 2017 18:46:46 CET -
Processing page 1...

and reflected in printers.conf as well....
Code:

State Stopped
StateMessage Processing page 1...
StateTime 1488912703
Reason paused

Cancel any stale print jobs with
Code:

cancel -a
and remove the above entries from printers.conf (as root). Once done, restart CUPS. Wait a few seconds then confirm that the printer is ready/idle. For reference I get
Code:

lpstat -p
printer Epson is idle.  enabled since Tue 22 Nov 2016 21:36:30 NZDT
        File "/opt/epson-inkjet-printer-escpr/cups/lib/filter/epson-escpr-wrapper" not available: No such file or directory
printer HP is idle.  enabled since Mon 16 Jan 2017 14:33:00 NZDT

and printers.conf (for each printer) shows
Code:

State Idle
Try sending another print job.

floppy_stuttgart 03-09-2017 01:38 AM

Done.
Result->

I deleted from printers.conf
Quote:

State Stopped
StateMessage Processing page 1...
StateTime 1488912703
Reason paused
sudo service cups restart
[ ok ] Restarting Common Unix Printing System: cupsd.
lpstat -p
printer Deskjet_2540 is idle. enabled since Do 09 Mär 2017 08:29:45 CET
I sent a print; then a new job is seen
Deskjet_2540-826 Unknown Withheld 11k Unknown pending since
Thu Mar 9 08:32:41 2017
"/usr/lib/cups/backend/hp failed"
I "Resume printer"
then it shows (again)
lpstat -p
printer Deskjet_2540 disabled since Do 09 Mär 2017 08:36:11 CET -
Processing page 1...
and (again)
Deskjet_2540-826 Unknown Withheld 11k Unknown pending since
Thu Mar 9 08:32:41 2017
"/usr/lib/cups/backend/hp failed"

Any advice is welcome.

ferrari 03-09-2017 01:54 AM

You're going to have to be a bit more systematic to get more useful diagnostic information.. Get the printer status restored as explained already. Cancel any stale print jobs.

Increase CUPS logging verbosity
Code:

sudo cupsctl --debug-logging
then send a print job.

Examine the CUPS error_log. Hopefully this will throw some light on what is wrong.

Since you're using HPLIP, you might also try using 'hp-doctor' to see if it can pick up any problems.

floppy_stuttgart 03-09-2017 09:59 AM

1 Attachment(s)
here is the new log file.

ferrari 03-09-2017 04:46 PM

This is telling....
Code:

D [09/Mar/2017:16:47:42 +0100] PID 3104 (/usr/lib/cups/filter/gstoraster) stopped with status 13.
I [09/Mar/2017:16:47:42 +0100] [Job 828] Backend returned status 127 (unknown)
D [09/Mar/2017:16:47:42 +0100] Discarding unused job-state-changed event...
I [09/Mar/2017:16:47:42 +0100] [Job 828] Printer stopped due to backend errors; please consult the error_log file for details.

However, a little earlier than this, the pdftopdf filter stops and might be closer to the root cause perhaps...
Code:

D [09/Mar/2017:16:47:38 +0100] PID 3103 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
This might require a bug report to help resolve. Maybe there is a problem with particular documents?

Have you tried restoring the printer (back to idle status) and then trying to print a testpage?
Code:

lpr -P Deskjet_2540 /usr/share/cups/data/default-testpage.pdf
FWIW, this Fedora CUPS debugging guide has an excellent section on running the filter chain by hand...
https://fedoraproject.org/wiki/How_t...ilters_by_hand

Rickkkk 03-10-2017 08:56 AM

Hey floppy_stuttgart,

In my experience, these "job withheld" and "backend errors" with CUPS usually had something to do with the communication protocol chosen (i.e. ipp, http, JetDirect, socket ...). My HP printer, connected directly to a network switch via ethernet, works best with:

socket://<printer's IP address>:9100.

... You can check this via the CUPS interface on the client you are trying to print from.

Cheers - hope this helps.

floppy_stuttgart 03-10-2017 01:44 PM

1 Attachment(s)
to#9:
lpr -P Deskjet_2540 /usr/share/cups/data/default-testpage.pdf
is not working (see the log file).
I further search.

to#10:
I will have a look.

THANKS.

floppy_stuttgart 03-10-2017 02:09 PM

WONDER:
a) I de-installed the printer
b) I installed the printer again
c) I print.. it works
So, thanks to everybody here. I dont know why it works.. but it works.
THANKS.

ferrari 03-10-2017 02:37 PM

Thanks for the update.

Rickkkk 03-10-2017 02:38 PM

Quote:

Originally Posted by floppy_stuttgart (Post 5681724)
WONDER:
a) I de-installed the printer
b) I installed the printer again
c) I print.. it works
So, thanks to everybody here. I dont know why it works.. but it works.
THANKS.

Good news - Cheers !


All times are GMT -5. The time now is 12:54 PM.