For about one week, I can't anymore print: root@leopard:/root(184)# genlop -t cups | tail Wed Oct 2 04:21:20 2013 >>> net-print/cups-1.6.4 merge time: 57 seconds. Fri Nov 8 17:40:39 2013 >>> net-print/cups-1.7.0 merge time: 58 seconds. Thu Jan 2 04:28:22 2014 >>> net-print/cups-1.7.0-r1 merge time: 53 seconds. I want to print to a Canon iR C2620 PS printer. I try to access the printer via ipp: root@leopard:/root(187)# grep -u2 C2620 /etc/cups/printers.conf Info CANON irc2620 without Novell Location Erste Etage MakeModel Canon iR C2620 PS DeviceURI ipp://10.101.18.57/ipp State Idle -- Info CANON irc2620 without Novell with IPP 1.1 Location Erste Etage MakeModel Canon iR C2620 PS DeviceURI ipp://10.101.18.57/ipp?version=1.1 State Idle -- Info Etagendrucker 2.OG Location 2.OG MakeModel Canon iR C2620 PS DeviceURI ipp://10.101.18.58/ipp At the web interface of cups I see only: processing since Fr 10 Jan 2014 17:15:53 CET "Preparing to print." But the job is never finished. If I kill the job I see the same with the next print job. 'systemctl status cups' does not show any problem: oot@leopard:/root(189)# systemctl status cups cups.service - CUPS Printing Service Loaded: loaded (/usr/lib64/systemd/system/cups.service; enabled) Active: active (running) since Fri 2014-01-10 16:12:19 CET; 1h 9min ago Main PID: 8908 (cupsd) CGroup: /system.slice/cups.service ├─ 8908 /usr/sbin/cupsd -f ├─ 8911 /usr/libexec/cups/notifier/dbus dbus:// ├─10537 Canon-direct_irc2620c2cs 432 rose Comparing C++ Compilers' Parallel-Programming Performance 1 RepeatJob=None Destination=Printer Finishing=None UserID=None GSmooth=Default Duplex=DuplexNoTumble HalfTone=Default number-up-layout=lrtb PageSize=A4 In... ├─10538 ipp://10.101.18.57/ipp 432 rose Comparing C++ Compilers' Parallel-Programming Performance 1 RepeatJob=None Destination=Printer Finishing=None UserID=None GSmooth=Default Duplex=DuplexNoTumble HalfTone=Default number-up-layout=lrtb PageSize=A4 Inte... ├─10539 pdftops 432 rose Comparing C++ Compilers Parallel-Programming Performance 1 RepeatJob=None Destination=Printer Finishing=None UserID=None GSmooth=Default Duplex=DuplexNoTumble HalfTone=Default number-up-layout=lrtb PageSize=A4 Interleave=None CNR... ├─10540 pdftops -level2 -origpagesizes -nocenter -r 600 /var/spool/cups/tmp/foomatic-N6YGQd - ├─10541 pdftops 432 rose Comparing C++ Compilers Parallel-Programming Performance 1 RepeatJob=None Destination=Printer Finishing=None UserID=None GSmooth=Default Duplex=DuplexNoTumble HalfTone=Default PageSize=A4 Interleave=None CNRGBPBP=Default InputSlo... ├─10544 Canon-direct_irc2620c2cs 432 rose Comparing C++ Compilers' Parallel-Programming Performance 1 RepeatJob=None Destination=Printer Finishing=None UserID=None GSmooth=Default Duplex=DuplexNoTumble HalfTone=Default number-up-layout=lrtb PageSize=A4 In... ├─10545 Canon-direct_irc2620c2cs 432 rose Comparing C++ Compilers' Parallel-Programming Performance 1 RepeatJob=None Destination=Printer Finishing=None UserID=None GSmooth=Default Duplex=DuplexNoTumble HalfTone=Default number-up-layout=lrtb PageSize=A4 In... ├─10546 /bin/bash -c /bin/cat - | sicgsfilter -MPS -NP -A1 -urose -V"Comparing C++ Compilers Parallel-Programming Performance" -n1 ├─10547 /bin/cat - └─10548 sicgsfilter -MPS -NP -A1 -urose -VComparing C++ Compilers Parallel-Programming Performance -n1 Jan 10 17:10:51 leopard systemd[1]: Started CUPS Printing Service. Jan 10 17:12:12 leopard systemd[1]: Started CUPS Printing Service. Jan 10 17:13:13 leopard systemd[1]: Started CUPS Printing Service. Jan 10 17:13:46 leopard systemd[1]: Started CUPS Printing Service. Jan 10 17:14:53 leopard systemd[1]: Started CUPS Printing Service. Jan 10 17:16:13 leopard systemd[1]: Started CUPS Printing Service. Jan 10 17:17:34 leopard systemd[1]: Started CUPS Printing Service. Jan 10 17:18:55 leopard systemd[1]: Started CUPS Printing Service. Jan 10 17:20:15 leopard systemd[1]: Started CUPS Printing Service. Jan 10 17:21:36 leopard systemd[1]: Started CUPS Printing Service. Restart of cups does not change anything. root@leopard:/root(198)# ll /usr/bin/sicgsfilter lrwxrwxrwx 1 root root 24 Aug 29 2012 /usr/bin/sicgsfilter -> /opt/cel/bin/sicgsfilter* root@leopard:/root(199)# ll /opt/cel/bin/sicgsfilter -rwxr-xr-x 1 root root 123520 Nov 30 2012 /opt/cel/bin/sicgsfilter* sicgsfilter is not installed with portage, probably it is belonging to cque-de-2.0.3 package, which is the Canon Linux Printer Package. And I cant reinstall it, because it needs libpng12.so.0
If I try to install the newer cque-de-2.0-6 the installation fails due to missing libpng12.so.0 as well.
media-libs/libpng:1.2 is in the tree and provides /usr/lib64/libpng12.so.0, try reinstalling the driver with that!
Is there any way to check if sicgsfilter is working? I tried: cat /var/spool/cups/d00271-001 | sicgsfilter -MPS -NP -A1 -urose -V"Comparing C++ Compilers Parallel-Programming Performance" -n Which copied more or less the pdf file to standard out without any obvious error. I changed the LogLevel to debug. If I analyse /var/log/cups/error_log I do not detect any obvious error: ... D [10/Jan/2014:17:48:05 +0100] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs" D [10/Jan/2014:17:48:05 +0100] [Client 30] No authentication data provided. D [10/Jan/2014:17:48:05 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs" D [10/Jan/2014:17:48:06 +0100] [Job 432] Wrote 7 pages... D [10/Jan/2014:17:48:06 +0100] [Job 432] PID 11205 (pdftops) exited with no errors. D [10/Jan/2014:17:48:06 +0100] [Job 432] PID 11206 (pstops) exited with no errors. D [10/Jan/2014:17:48:06 +0100] [Job 432] Flushing FIFO. D [10/Jan/2014:17:48:06 +0100] [Job 432] Read 16384 bytes... D [10/Jan/2014:17:48:06 +0100] [Job 432] Read 16384 bytes... ... D [10/Jan/2014:17:48:16 +0100] [Job 432] Read 16384 bytes... D [10/Jan/2014:17:48:16 +0100] [Client 20] GET /printers/Canon-direct_irc2620c2cs HTTP/1.1 D [10/Jan/2014:17:48:16 +0100] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs" D [10/Jan/2014:17:48:16 +0100] [Client 20] Authorized as root using Basic ... D [10/Jan/2014:17:48:16 +0100] [CGI] cgiSetVariable: THISURL="/printers/Canon-direct_irc2620c2cs" D [10/Jan/2014:17:48:16 +0100] [CGI] cgiSetVariable: SEARCH_DEST="Canon-direct_irc2620c2cs" D [10/Jan/2014:17:48:16 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=11227, file=29 D [10/Jan/2014:17:48:16 +0100] [Client 20] Waiting for CGI data. ... I [10/Jan/2014:17:48:34 +0100] [Job 432] Started filter /usr/libexec/cups/filter/pdftopdf (PID 11230) I [10/Jan/2014:17:48:34 +0100] [Job 432] Started filter /usr/libexec/cups/filter/foomatic-rip (PID 11231) I [10/Jan/2014:17:48:34 +0100] [Job 432] Started backend /usr/libexec/cups/backend/ipp (PID 11232) D [10/Jan/2014:17:48:34 +0100] cupsdMarkDirty(----S) D [10/Jan/2014:17:48:34 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files" D [10/Jan/2014:17:48:34 +0100] [Notifier] state=3 ... D [10/Jan/2014:17:48:34 +0100] [Client 17] Waiting for request. D [10/Jan/2014:17:48:34 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [10/Jan/2014:17:48:34 +0100] [Job 432] ATTR: marker-colors=#000000,#00FFFF,#FF00FF,#FFFF00,none D [10/Jan/2014:17:48:34 +0100] cupsdMarkDirty(P----) D [10/Jan/2014:17:48:34 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" ... D [10/Jan/2014:17:49:09 +0100] [Job 432] Read 16384 bytes... D [10/Jan/2014:17:49:09 +0100] [Job 432] (monitor) Get-Jobs: successful-ok (successful-ok) D [10/Jan/2014:17:49:09 +0100] [Job 432] (monitor) job-state=pending D [10/Jan/2014:17:49:09 +0100] [Job 432] Read 16384 bytes...
(In reply to Andreas K. Hüttel from comment #2) > media-libs/libpng:1.2 is in the tree and provides /usr/lib64/libpng12.so.0, > try reinstalling the driver with that! Thanks Andreas, after 'I tried emerge -v1 =media-libs/libpng-1.2*' setup was running, but nothing was updated. Even after '/opt/cel/bin/setup -d; ./setup' nothing was updated. In /var/log/CQue2.0_UpdateLog I find: ... Das Installationsverzeichnis '/opt/cel' existiert und ist korrekt. Anzahl der gefundenen CQue-Drucker: 1. CQUEINST.CONF = /usr/src_leopard/cque-de-2.0-6/cque-de-2.0-6/CQUEINST.CONF. Weil Informationen fählen, hört setup auf.
This bug is really nasty, I get under almost continously messages about the running print process". In /var/spool/cups/tmp files are generated continously: root@leopard:/usr/local/portage/media-gfx(63)# ll -t /var/spool/cups/tmp/ | head total 50236 -rw------- 1 lp lp 70482 Jan 13 15:22 foomatic-4M2hwJ -rw------- 1 lp lp 70482 Jan 13 15:21 foomatic-NjT1zQ -rw------- 1 lp lp 70482 Jan 13 15:21 foomatic-7MGHvV -rw------- 1 lp lp 70482 Jan 13 15:21 foomatic-s61TOC -rw------- 1 lp lp 70482 Jan 13 15:21 foomatic-GAmR7G -rw------- 1 lp lp 70482 Jan 13 15:20 foomatic-J2gzqI -rw------- 1 lp lp 70482 Jan 13 15:20 foomatic-qsPpbO -rw------- 1 lp lp 70482 Jan 13 15:20 foomatic-vyMfwR -rw------- 1 lp lp 70482 Jan 13 15:20 foomatic-tsDm4S root@leopard:/usr/local/portage/media-gfx(64)# ll -t /var/spool/cups/tmp/ | wc 666 5987 35934 Wath can I do besides deleting the printer from my cups configuration?
I switched back to LogLevel warn. In /var/log/cups/error_log I find now: I [13/Jan/2014:12:22:26 +0100] Full reload complete. D [13/Jan/2014:12:22:26 +0100] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)") I [13/Jan/2014:12:22:26 +0100] Cleaning out old files in "/var/spool/cups/tmp". D [13/Jan/2014:12:22:38 +0100] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp") I [13/Jan/2014:12:22:38 +0100] Cleaning out old files in "/var/cache/cups". D [13/Jan/2014:12:22:38 +0100] systemd_checkin: Matched existing listener /run/cups/cups.sock with fd 3... D [13/Jan/2014:12:22:38 +0100] systemd_checkin: Adding new listener [v1.::] with fd 4... D [13/Jan/2014:12:22:38 +0100] Calling FindDeviceById(cups-Canon-direct_irc2620c2cs) D [13/Jan/2014:12:22:38 +0100] FindDeviceById failed: org.freedesktop.ColorManager.NotFound:device id 'cups-Canon-direct_irc2620c2cs' does not exist D [13/Jan/2014:12:22:38 +0100] Using profile ID "Canon-direct_irc2620c2cs-Gray..". D [13/Jan/2014:12:22:38 +0100] Calling CreateProfile(Canon-direct_irc2620c2cs-Gray..,temp) D [13/Jan/2014:12:22:38 +0100] Created profile "/org/freedesktop/ColorManager/profiles/Canon_direct_irc2620c2cs_Gray__". D [13/Jan/2014:12:22:38 +0100] Using profile ID "Canon-direct_irc2620c2cs-CMYK..". D [13/Jan/2014:12:22:38 +0100] Calling CreateProfile(Canon-direct_irc2620c2cs-CMYK..,temp) D [13/Jan/2014:12:22:38 +0100] Created profile "/org/freedesktop/ColorManager/profiles/Canon_direct_irc2620c2cs_CMYK__". I [13/Jan/2014:12:22:38 +0100] Registering ICC color profiles for "Canon-direct_irc2620c2cs". D [13/Jan/2014:12:22:38 +0100] Calling CreateDevice(cups-Canon-direct_irc2620c2cs,temp) D [13/Jan/2014:12:22:38 +0100] Created device "/org/freedesktop/ColorManager/devices/cups_Canon_direct_irc2620c2cs". D [13/Jan/2014:12:22:38 +0100] Calling /org/freedesktop/ColorManager/devices/cups_Canon_direct_irc2620c2cs:AddProfile(/org/freedesktop/ColorManager/profiles/Canon_direct_irc2620c2cs_Gray__) [soft] D [13/Jan/2014:12:22:38 +0100] Calling /org/freedesktop/ColorManager/devices/cups_Canon_direct_irc2620c2cs:AddProfile(/org/freedesktop/ColorManager/profiles/Canon_direct_irc2620c2cs_CMYK__) [soft] D [13/Jan/2014:12:22:38 +0100] Calling FindDeviceById(cups-Canon-direct_irc2620c2csIPP1.1) D [13/Jan/2014:12:22:38 +0100] FindDeviceById failed: org.freedesktop.ColorManager.NotFound:device id 'cups-Canon-direct_irc2620c2csIPP1.1' does not exist D [13/Jan/2014:12:22:38 +0100] Using profile ID "Canon-direct_irc2620c2csIPP1.1-Gray..". D [13/Jan/2014:12:22:38 +0100] Calling CreateProfile(Canon-direct_irc2620c2csIPP1.1-Gray..,temp) D [13/Jan/2014:12:22:38 +0100] Created profile "/org/freedesktop/ColorManager/profiles/Canon_direct_irc2620c2csIPP1_1_Gray__". D [13/Jan/2014:12:22:38 +0100] Using profile ID "Canon-direct_irc2620c2csIPP1.1-CMYK..". D [13/Jan/2014:12:22:38 +0100] Calling CreateProfile(Canon-direct_irc2620c2csIPP1.1-CMYK..,temp) D [13/Jan/2014:12:22:38 +0100] Created profile "/org/freedesktop/ColorManager/profiles/Canon_direct_irc2620c2csIPP1_1_CMYK__". I [13/Jan/2014:12:22:38 +0100] Registering ICC color profiles for "Canon-direct_irc2620c2csIPP1.1". D [13/Jan/2014:12:22:38 +0100] Calling CreateDevice(cups-Canon-direct_irc2620c2csIPP1.1,temp) D [13/Jan/2014:12:22:38 +0100] Created device "/org/freedesktop/ColorManager/devices/cups_Canon_direct_irc2620c2csIPP1_1". D [13/Jan/2014:12:22:38 +0100] Calling /org/freedesktop/ColorManager/devices/cups_Canon_direct_irc2620c2csIPP1_1:AddProfile(/org/freedesktop/ColorManager/profiles/Canon_direct_irc2620c2csIPP1_1_Gray__) [soft] D [13/Jan/2014:12:22:38 +0100] Calling /org/freedesktop/ColorManager/devices/cups_Canon_direct_irc2620c2csIPP1_1:AddProfile(/org/freedesktop/ColorManager/profiles/Canon_direct_irc2620c2csIPP1_1_CMYK__) [soft] D [13/Jan/2014:12:22:38 +0100] Calling FindDeviceById(cups-ICR2620IIOG) D [13/Jan/2014:12:22:38 +0100] FindDeviceById failed: org.freedesktop.ColorManager.NotFound:device id 'cups-ICR2620IIOG' does not exist D [13/Jan/2014:12:22:38 +0100] Using profile ID "ICR2620IIOG-Gray..". D [13/Jan/2014:12:22:38 +0100] Calling CreateProfile(ICR2620IIOG-Gray..,temp) D [13/Jan/2014:12:22:38 +0100] Created profile "/org/freedesktop/ColorManager/profiles/ICR2620IIOG_Gray__". D [13/Jan/2014:12:22:38 +0100] Using profile ID "ICR2620IIOG-CMYK..". D [13/Jan/2014:12:22:38 +0100] Calling CreateProfile(ICR2620IIOG-CMYK..,temp) D [13/Jan/2014:12:22:38 +0100] Created profile "/org/freedesktop/ColorManager/profiles/ICR2620IIOG_CMYK__". I [13/Jan/2014:12:22:38 +0100] Registering ICC color profiles for "ICR2620IIOG". D [13/Jan/2014:12:22:38 +0100] Calling CreateDevice(cups-ICR2620IIOG,temp) D [13/Jan/2014:12:22:38 +0100] Created device "/org/freedesktop/ColorManager/devices/cups_ICR2620IIOG". D [13/Jan/2014:12:22:38 +0100] Calling /org/freedesktop/ColorManager/devices/cups_ICR2620IIOG:AddProfile(/org/freedesktop/ColorManager/profiles/ICR2620IIOG_Gray__) [soft] D [13/Jan/2014:12:22:38 +0100] Calling /org/freedesktop/ColorManager/devices/cups_ICR2620IIOG:AddProfile(/org/freedesktop/ColorManager/profiles/ICR2620IIOG_CMYK__) [soft] E [13/Jan/2014:12:22:38 +0100] Unable to bind socket for address [v1.::1]:631 - Address already in use. I [13/Jan/2014:12:22:38 +0100] Listening to 127.0.0.1:631 on fd 12... I [13/Jan/2014:12:22:38 +0100] Listening to /run/cups/cups.sock:631 on fd 3... I [13/Jan/2014:12:22:38 +0100] Listening to [v1.::]:631 on fd 4... I [13/Jan/2014:12:22:38 +0100] Resuming new connection processing... D [13/Jan/2014:12:22:38 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [13/Jan/2014:12:22:38 +0100] Discarding unused server-started event... D [13/Jan/2014:12:22:39 +0100] Report: clients=0 D [13/Jan/2014:12:22:39 +0100] Report: jobs=499 D [13/Jan/2014:12:22:39 +0100] Report: jobs-active=12 D [13/Jan/2014:12:22:39 +0100] Report: printers=3 D [13/Jan/2014:12:22:39 +0100] Report: stringpool-string-count=26847 D [13/Jan/2014:12:22:39 +0100] Report: stringpool-alloc-bytes=13632 D [13/Jan/2014:12:22:39 +0100] Report: stringpool-total-bytes=480016 I [13/Jan/2014:12:23:14 +0100] Scheduler shutting down normally. D [13/Jan/2014:12:23:14 +0100] Discarding unused server-stopped event... I [13/Jan/2014:12:23:14 +0100] Saving job.cache... D [13/Jan/2014:12:23:14 +0100] [Job 432] Unloading... D [13/Jan/2014:12:23:14 +0100] [Job 433] Unloading... D [13/Jan/2014:12:23:14 +0100] [Job 434] Unloading... D [13/Jan/2014:12:23:14 +0100] [Job 435] Unloading... D [13/Jan/2014:12:23:14 +0100] [Job 436] Unloading... D [13/Jan/2014:12:23:14 +0100] [Job 437] Unloading... D [13/Jan/2014:12:23:14 +0100] [Job 438] Unloading... D [13/Jan/2014:12:23:14 +0100] [Job 439] Unloading... D [13/Jan/2014:12:23:14 +0100] [Job 440] Unloading... D [13/Jan/2014:12:23:14 +0100] [Job 441] Unloading... D [13/Jan/2014:12:23:14 +0100] [Job 442] Unloading... D [13/Jan/2014:12:23:14 +0100] [Job 443] Unloading... D [13/Jan/2014:12:23:14 +0100] cupsdStopSelect() E [13/Jan/2014:12:23:14 +0100] Unable to bind socket for address [v1.::1]:631 - Address already in use. E [13/Jan/2014:16:48:43 +0100] Unable to bind socket for address [v1.::1]:631 - Address already in use. Is this failing binding to socket the result or the reason of problem?
*** This bug has been marked as a duplicate of bug 494582 ***
