FS#51743 - [cups-filters] Every try to print canceled with "filter error" after 2016.11.01

Attached to Project: Arch Linux
Opened by Nicholas (Chawoosh) - Tuesday, 08 November 2016, 09:53 GMT
Last edited by Andreas Radke (AndyRTR) - Saturday, 18 February 2017, 19:27 GMT
Task Type Bug Report
Category Packages: Extra
Status Closed
Assigned To Andreas Radke (AndyRTR)
Architecture x86_64
Severity High
Priority Normal
Reported Version
Due in Version Undecided
Due Date Undecided
Percent Complete 100%
Votes 0
Private No

Details

Description:
System can not print at all. Problem appear after 2016.10.30, as I think, the last success printing was 2016.11.27.
Arch linux, updated every morning, GMT +7 timezone.

Additional info:
* package version(s)
* config and/or log files etc.


Steps to reproduce:
This task depends upon

Closed by  Andreas Radke (AndyRTR)
Saturday, 18 February 2017, 19:27 GMT
Reason for closing:  Won't fix
Additional comments about closing:  IJS is dead by upstream decision.
Comment by Nicholas (Chawoosh) - Tuesday, 08 November 2016, 11:21 GMT
Updates after 2016-10-27:

$lsupdate 2016-10-28
btrfs-progs (4.8.1-1 -> 4.8.2-1)
file (5.28-1 -> 5.29-1)
gtk-update-icon-cache (3.22.1+69+ga1115c0-1 -> 3.22.2+4+gc54f348-1)
harfbuzz (1.3.2-1 -> 1.3.3-1)
gtk3 (3.22.1+69+ga1115c0-1 -> 3.22.2+4+gc54f348-1)
harfbuzz-icu (1.3.2-1 -> 1.3.3-1)
libwbclient (4.5.0-2 -> 4.5.1-1)
tevent (1:0.9.29-2 -> 1:0.9.31-1)
smbclient (4.5.0-2 -> 4.5.1-1)
kdenetwork-kget (16.08.2-1 -> 16.08.2-2)
less (481-2 -> 487-1)
samba (4.5.0-2 -> 4.5.1-1)
valgrind (3.11.0-4 -> 3.12.0-1)
iperf3 (3.1.3-1)
$ lsupdate 2016-10-29
$ lsupdate 2016-10-30
$ lsupdate 2016-10-31
boost-libs (1.61.0-2 -> 1.62.0-2)
libxi (1.7.7-2 -> 1.7.8-1)
llvm-libs (3.8.1-1 -> 3.9.0-1)
mesa (12.0.3-3 -> 13.0.0rc2-2)
mesa-libgl (12.0.3-3 -> 13.0.0rc2-2)
shadow (4.2.1-3 -> 4.4-2)
avogadro (1.2.0-3 -> 1.2.0-4)
bluez (5.42-1 -> 5.43-1)
bluez-libs (5.42-1 -> 5.43-1)
ceph (10.2.2-2 -> 10.2.3-1)
cups-filters (1.11.4-1 -> 1.11.5-1)
python-packaging (16.7-1 -> 16.8-1)
python-setuptools (1:28.6.1-1 -> 1:28.7.1-1)
cython (0.25-1 -> 0.25.1-1)
sdl2 (2.0.5-1 -> 2.0.5-2)
x265 (2.1-1 -> 2.1-2)
ffmpeg (1:3.1.5-1 -> 1:3.2-2)
git (2.10.1-1 -> 2.10.2-1)
kdebindings-python-common (4.14.3-6 -> 4.14.3-7)
kdebindings-python2 (4.14.3-6 -> 4.14.3-7)
kig (16.08.2-1 -> 16.08.2-2)
lib32-llvm-libs (3.8.1-1 -> 3.9.0-1)
lib32-mesa (12.0.3-4 -> 13.0.0rc2-1)
lib32-mesa-libgl (12.0.3-4 -> 13.0.0rc2-1)
libakonadi-qt4 (1.13.0-10 -> 1.13.0-11)
libcmis (0.5.1-3 -> 0.5.1-4)
libindi (1.2.0-5 -> 1.3-1)
libixion (0.11.1-2 -> 0.11.1-3)
libkolabxml (1.1.6-1 -> 1.1.6-2)
liborcus (0.11.2-2 -> 0.11.2-3)
libwps (0.4.3-1 -> 0.4.4-1)
neon (0.30.1-2 -> 0.30.2-1)
libreoffice-still (5.1.5-3 -> 5.1.6-1)
mpg123 (1.23.6-1 -> 1.23.8-1)
python2-packaging (16.7-1 -> 16.8-1)
python2-setuptools (1:28.6.1-1 -> 1:28.7.1-1)
python2-urllib3 (1.18-1 -> 1.18.1-1)
source-highlight (3.1.8-9 -> 3.1.8-10)
vim-runtime (8.0.0046-2 -> 8.0.0046-4)
vim (8.0.0046-2 -> 8.0.0046-4)
wine (1.9.21-1 -> 1.9.22-1)
wireshark-cli (2.2.0-2 -> 2.2.1-1)
wireshark-common (2.2.0-2 -> 2.2.1-1)
wireshark-qt (2.2.0-2 -> 2.2.1-1)
skype (4.3.0.37-7 -> 4.3.0.37-8)
Comment by Nicholas (Chawoosh) - Tuesday, 08 November 2016, 11:23 GMT
Definition of  FS#51743  has one error date: last success printing was 2016.10.27.
Comment by Antonio Rojas (arojas) - Tuesday, 08 November 2016, 11:31 GMT
and what is your current version of cups-filters?
Comment by Nicholas (Chawoosh) - Tuesday, 08 November 2016, 12:49 GMT
Current version is 1.11.6-1
Comment by Nicholas (Chawoosh) - Tuesday, 08 November 2016, 12:50 GMT
On my home comp I have the same error message.
Comment by Doug Newgard (Scimmia) - Tuesday, 08 November 2016, 14:19 GMT
Did you restart cups after the update to 1.11.6?
Comment by Nicholas (Chawoosh) - Wednesday, 09 November 2016, 01:58 GMT
CUPS and computer was restarted many times.
Comment by Nicholas (Chawoosh) - Wednesday, 09 November 2016, 05:23 GMT
Here is screenshot of my printing, with dates.
Comment by Nicholas (Chawoosh) - Wednesday, 09 November 2016, 05:32 GMT
If I do not cancel print job, after long time of blinking by Ready led, HP LJ 3050 prints as simple printer "**** Unable to open the initial device, quitting."
Comment by Andreas Radke (AndyRTR) - Wednesday, 09 November 2016, 15:29 GMT
As an Arch user you should know the way how to deal such stuff - please provide log files.

Comment by Nicholas (Chawoosh) - Thursday, 10 November 2016, 03:35 GMT
Here is CUPS error log file.
   error_log (12.6 KiB)
Comment by Nicholas (Chawoosh) - Thursday, 10 November 2016, 03:46 GMT
Hmm... All is printing after installing gutenprint and foomatic-db-gutenprint-ppds. But I can't understand why all this isn't in a dependents.

Later: In a log file still are errors:
D [09/Nov/2016:11:48:32 +0700] [Job 109] sh: ijsgutenprint.5.2: command not found
D [09/Nov/2016:11:48:32 +0700] [Job 109] GPL Ghostscript 9.20: Can\'t start ijs server \"ijsgutenprint.5.2\"
D [09/Nov/2016:11:48:32 +0700] [Job 109] renderer exited with status 1

and printer stay in a printing state ("ready" is blinking) after page print.
Comment by Andreas Radke (AndyRTR) - Thursday, 10 November 2016, 15:58 GMT
It seems your gutenprint package isn't present on your system anymore. You should reinstall it or use a different driver.

Not a packaging bug.
Comment by Nicholas (Chawoosh) - Friday, 11 November 2016, 03:13 GMT
I don't know about different driver for HP LJ 3050.

And...

$ yaourt -Qi gutenprint
Name : gutenprint
Version : 5.2.11-4
Description : Top quality printer drivers for POSIX systems
Architecture : x86_64
URL : http://gimp-print.sourceforge.net/
Licenses : GPL
Groups : None
Provides : None
Depends On : cups cups-filters ijs
Optional Deps : gimp: adds gutenprint plugin to gimp [installed]
libusb: required for drivers that depend on gutenprint52usb backend [installed]
foomatic-db-gutenprint-ppds: prebuilt ppd files [installed]
Required By : None
Optional For : gimp
Conflicts With : None
Replaces : gimp-print
Installed Size : 27.90 MiB
Packager : Andreas Radke <andyrtr@archlinux.org>
Build Date : Thu 28 Jul 2016 02:06:32 AM +07
Install Date : Thu 10 Nov 2016 10:41:56 AM +07
Install Reason : Explicitly installed
Install Script : Yes
Validated By : Signature
Comment by Nicholas (Chawoosh) - Friday, 11 November 2016, 03:16 GMT
It's not error of gutenprint, sure. But cups don't print at all without it.
Comment by Andreas Radke (AndyRTR) - Friday, 11 November 2016, 14:13 GMT
Package gutenprint owns /usr/bin/ijsgutenprint.5.2 - cups seems not to be able to run it.

Can you confirm its presence and permissions? Try to remove+readd the driver to see some more verbose debug msg. Also turning log level to debug could help.
Comment by Nicholas (Chawoosh) - Sunday, 13 November 2016, 06:21 GMT
Yes, /usr/bin/ijsgutenprint.5.2 is on a place. I'll test again tomorrow printing.
I can't understand why all was OK before 2016.10.30 and all is need ijsgutenprint.5.2 after.
Comment by Nicholas (Chawoosh) - Monday, 14 November 2016, 02:44 GMT
All is OK, I did mistake about error log. Only one string appear in error log while printing picture:

E [14/Nov/2016:08:50:31 +0700] Missing value on line 1380 of /var/cache/cups/job.cache.
Comment by Andreas Radke (AndyRTR) - Wednesday, 07 December 2016, 21:26 GMT
All is ok? Does printing work now or is this your real issue? Then investigate the print job file what value is missing. This could be an error in the gutenprint driver then.
Comment by Nicholas (Chawoosh) - Thursday, 08 December 2016, 01:44 GMT
All is OK with HP LJ 3050, but on the home comp I have the same error with GDI Kiocera FS-1040. It using driver from Kiocera.
Comment by Kai Sauerwald (Landarzar) - Friday, 13 January 2017, 09:48 GMT
I have the same error.

cups.log says:

D [13/Jan/2017:10:41:14 +0100] [Job 1133] Traceback (most recent call last):
D [13/Jan/2017:10:41:14 +0100] [Job 1133] File \"/usr/lib/cups/filter/hpps\", line 203, in <module>
D [13/Jan/2017:10:41:14 +0100] [Job 1133] os.write(output_fd, to_bytes_utf8(\'@PJL SET JOBNAME=\"%s\"\\x0a
D [13/Jan/2017:10:41:14 +0100] [Job 1133] File \"/usr/share/hplip/base/sixext.py\", line 109, in to_bytes_
D [13/Jan/2017:10:41:14 +0100] [Job 1133] return s.encode(\"utf-8\")
D [13/Jan/2017:10:41:14 +0100] [Job 1133] UnicodeEncodeError: \'utf-8\' codec can\'t encode characters in

changing line 109 and line 113 in /usr/share/hplip/base/sixext.py to the following fixes the problem:
return s.encode("utf-8", errors="surrogateescape")
Comment by Andreas Radke (AndyRTR) - Friday, 13 January 2017, 16:17 GMT
Please report this error to hplip upstream.

I guess the other error is Kiocera driver related. Be aware the ijs gutenprint driver will be dropped with the new gutenprint release currently in testing.
Comment by Nicholas (Chawoosh) - Thursday, 26 January 2017, 07:12 GMT
Hi.

After update to gutenprint 2.740 error is returned:

D [26/Jan/2017:14:10:30 +0700] [Job 130] Set job-printer-state-message to "Sending data to printer.", current level=INFO
D [26/Jan/2017:14:10:30 +0700] [Job 130] File contains 1 pages
D [26/Jan/2017:14:10:30 +0700] [Job 130] Starting renderer with command: gs -q -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -sDEVICE=ijs -sIjsServer=ijsgutenpr
D [26/Jan/2017:14:10:30 +0700] [Job 130] Starting process \"kid3\" (generation 1)
D [26/Jan/2017:14:10:30 +0700] [Job 130] Starting process \"kid4\" (generation 2)
D [26/Jan/2017:14:10:30 +0700] [Job 130] Starting process \"renderer\" (generation 2)
D [26/Jan/2017:14:10:30 +0700] [Job 130] JCL: \033%-12345X@PJL
D [26/Jan/2017:14:10:30 +0700] [Job 130] <job data>
D [26/Jan/2017:14:10:30 +0700] [Job 130] sh: ijsgutenprint.5.2: command not found
D [26/Jan/2017:14:10:30 +0700] [Job 130] GPL Ghostscript 9.20: Can\'t start ijs server \"ijsgutenprint.5.2\"
D [26/Jan/2017:14:10:30 +0700] [Job 130] renderer exited with status 1
D [26/Jan/2017:14:10:30 +0700] [Job 130] Possible error on renderer command line or PostScript error. Check options.DEBUG: Read 50 bytes of print data...
D [26/Jan/2017:14:10:30 +0700] [Job 130] Kid3 exit status: 3
D [26/Jan/2017:14:10:30 +0700] [Job 130] Wrote 50 bytes of print data...
D [26/Jan/2017:14:10:30 +0700] [Job 130] Sent 50 bytes...
D [26/Jan/2017:14:10:30 +0700] [Job 130] Waiting for read thread to exit...
D [26/Jan/2017:14:10:30 +0700] [Job 130] PID 8828 (/usr/lib/cups/filter/foomatic-rip) stopped with status 9.
D [26/Jan/2017:14:10:30 +0700] [Job 130] Hint: Try setting the LogLevel to "debug" to find out more.
D [26/Jan/2017:14:10:30 +0700] [Job 130] PID 8829 (/usr/lib/cups/backend/usb) exited with no errors.
D [26/Jan/2017:14:10:30 +0700] [Job 130] End of messages
Comment by Nicholas (Chawoosh) - Thursday, 26 January 2017, 08:26 GMT
OK, gutenprint-5.2.12 don't contain ijsgutenprint.5.2. After downgrade to gutenprint-5.2.11-5 all is OK.
Comment by Andreas Radke (AndyRTR) - Friday, 27 January 2017, 09:18 GMT
ijs is dead. Use a different driver. From the announce:

* Changes from 5.2.11

1) The Ghostscript IJS driver and Foomatic data generator have been
removed. CUPS is ubiquitous on Linux/UNIX systems, and the
Ghostscript IJS driver and Foomatic data are not needed with
CUPS.

Loading...