Web lists-archives.com

Re: cups "Filter failed" | filter rastertopdf stops with status 1 | local printing works; remote printing not




On Sun 27 Jan 2019 at 20:49:36 +0100, toogley@xxxxxxxxxxx wrote:

> Hey!
> 
> i have a laptop and one remote server/computer. The remote server is bananian, i
> hope that doesn't matter for this case.

It shouldn't.
 
> I have  configured cups with hplip to print things on the remote server. That
> means, i can go to https://remote-server/printers/printername in my laptop's
> webbrowser and print a testpage there. However, when i visit
> https://localhost:631 (on the laptop), add the printer of the remote-server
> manually there (url is ipp://172.16.2.4:631/printers/HP_Officejet_6600) and
> print e.g. the testpage, the job fails with "Filter failed".

The server is 172.16.2.4. The client is 172.16.2.7. But the logs also
show a 172.16.2.3; what is it?

[...]

> lpstat -t on the remote server
> ------------------------------
> 
> # lpstat -t
> scheduler is running
> system default destination: HP_Officejet_6600
> device for HP_Officejet_6600: hp:/usb/Officejet_6600?serial=CN48C9R1BQ05RN
> HP_Officejet_6600 accepting requests since Sun 27 Jan 2019 06:22:03 PM CET
> printer HP_Officejet_6600 is idle.  enabled since Sun 27 Jan 2019 06:22:03 PM
> CET
> HP_Officejet_6600-11    unknown         311296   Sun 27 Jan 2019 05:36:33 PM CET

Ok.

> lpstat -t on the laptop (contains also a local usb config)
> ---------------------
> 
> system default destination: Officejet-6600
> device for Officejet-6600: hp:/usb/Officejet_6600?serial=CN48C9R1BQ05RN

This is a device URI on the remote server, so it is not local. Jobs
sent to it aren't going anywhere.

> device for remote_printer: ipp://172.16.2.4:631/printers/HP_Officejet_6600
> Officejet-6600 accepting requests since Sat 19 Jan 2019 06:14:19 PM CET
> remote_printer accepting requests since Sun 27 Jan 2019 05:36:29 PM CET
> printer Officejet-6600 is idle.  enabled since Sat 19 Jan 2019 06:14:19 PM CET
> printer remote_printer now printing remote_printer-32.  enabled since Sun 27 Jan
> 2019 05:36:29 PM CET
> remote_printer-32       toogley           1024   Sun 27 Jan 2019 05:36:29 PM CET
> 
> 
> 
> /var/log/cups/error_log (LogLevel warn)
> --------------------------------------

The beginning of the log (which shows the file received) is missing.

> E [27/Jan/2019:20:38:14 +0100] [Job 13] Job stopped due to filter errors; please consult the error_log file for details.
> D [27/Jan/2019:20:38:14 +0100] [Job 13] The following messages were recorded from 08:38:12 PM to 08:38:14 PM
> D [27/Jan/2019:20:38:14 +0100] [Job 13] Adding start banner page "none".
> D [27/Jan/2019:20:38:14 +0100] [Job 13] Queued on "HP_Officejet_6600" by "anonymous".
> D [27/Jan/2019:20:38:14 +0100] [Job 13] File of type application/vnd.cups-raster queued by "anonymous".

A test file would be expected to be type application/vnd.cups-pdf-banner.

> D [27/Jan/2019:20:38:14 +0100] [Job 13] Adding end banner page "none".
> D [27/Jan/2019:20:38:14 +0100] [Job 13] time-at-processing=1548617893
> D [27/Jan/2019:20:38:14 +0100] [Job 13] 4 filters for job:
> D [27/Jan/2019:20:38:14 +0100] [Job 13] rastertopwg (application/vnd.cups-raster to image/pwg-raster, cost 100)
> D [27/Jan/2019:20:38:14 +0100] [Job 13] rastertopdf (image/pwg-raster to application/pdf, cost 32)

The file received is a raster file. I wonder why.

[...]

> D [27/Jan/2019:20:38:14 +0100] [Job 13] envp[21]="PPD=/etc/cups/ppd/HP_Officejet_6600.ppd"

There shouldn't be a PPD on the client.

> D [27/Jan/2019:20:38:14 +0100] [Job 13] envp[22]="RIP_MAX_CACHE=128m"
> D [27/Jan/2019:20:38:14 +0100] [Job 13] envp[23]="CONTENT_TYPE=application/vnd.cups-raster"
> D [27/Jan/2019:20:38:14 +0100] [Job 13] envp[24]="DEVICE_URI=hp:/usb/Officejet_6600?serial=CN48C9R1BQ05RN"

The file is being sent somewhere it cannot be delivered to, but this is
not the reason for the failed filter.

> D [27/Jan/2019:20:38:14 +0100] [Job 13] envp[25]="PRINTER_INFO=remote printer - HP Officejet 6600"
> D [27/Jan/2019:20:38:14 +0100] [Job 13] envp[26]="PRINTER_LOCATION="
> D [27/Jan/2019:20:38:14 +0100] [Job 13] envp[27]="PRINTER=HP_Officejet_6600"
> D [27/Jan/2019:20:38:14 +0100] [Job 13] envp[28]="PRINTER_STATE_REASONS=marker-supply-low-warning"
> D [27/Jan/2019:20:38:14 +0100] [Job 13] envp[29]="CUPS_FILETYPE=document"
> D [27/Jan/2019:20:38:14 +0100] [Job 13] envp[30]="FINAL_CONTENT_TYPE=application/vnd.cups-pdf"
> D [27/Jan/2019:20:38:14 +0100] [Job 13] envp[31]="AUTH_I****"
> D [27/Jan/2019:20:38:14 +0100] [Job 13] Started filter /usr/lib/cups/filter/rastertopwg (PID 2561)
> D [27/Jan/2019:20:38:14 +0100] [Job 13] Started filter /usr/lib/cups/filter/rastertopdf (PID 2562)
> D [27/Jan/2019:20:38:14 +0100] [Job 13] Started filter /usr/lib/cups/filter/pdftopdf (PID 2563)
> D [27/Jan/2019:20:38:14 +0100] [Job 13] Started filter /usr/lib/cups/filter/foomatic-rip (PID 2564)
> D [27/Jan/2019:20:38:14 +0100] [Job 13] Started backend /usr/lib/cups/backend/hp (PID 2565)
> D [27/Jan/2019:20:38:14 +0100] [Job 13] PID 2561 (/usr/lib/cups/filter/rastertopwg) exited with no errors.
> D [27/Jan/2019:20:38:14 +0100] [Job 13] PID 2562 (/usr/lib/cups/filter/rastertopdf) stopped with status 1.

rastertopdf does not like what it given (and the system shouldn't have
been given a raster file in the first place).

-- 
Brian.