[Pkg-cups-devel] cupsys: job is in "processing" state forever due to a filter exited with error code 1
Németh Márton
nm127 at freemail.hu
Thu Feb 21 18:21:13 UTC 2008
Package: cupsys
Version: 1.3.5-1+b1
Severity: important
I have a Canon PIXMA MP160 printer connected to the USB port. I am using
the printer with the filter "pstocanonij" written by Canon, and available
at http://software.canon-europe.com/software/0027403.asp . I tried to print
the PDF file http://www.kommentar.info.hu/a_lehetetlent_meg_kell_kiserteni.pdf
from Acrobat Reader 7.0.0 (03/21/2005).
The result was that nothing was printed, but the job was pending forever
(see the attached cups_job_processing.png) at the state column.
I set the "LogLevel debug" in the /etc/cups/cupsd.conf file, the result is included
here. Although the "pstocanonij" filter exited with error code 1, the print process
was not stopped, and no feedback was sent to the user that it was not possible to
print this document.
The other problem with this behavior is that no more printing job can complete after
this until this "processing" job is not removed. I thing this job is not "processing",
but it got a fatal error and should be removed from the printing queue in order other jobs
can complete.
D [21/Feb/2008:18:23:50 +0100] cupsdAcceptClient: 11 from localhost (Domain)
D [21/Feb/2008:18:23:50 +0100] cupsdReadClient: 11 POST / HTTP/1.1
D [21/Feb/2008:18:23:50 +0100] cupsdAuthorize: No authentication data provided.
D [21/Feb/2008:18:23:50 +0100] CUPS-Get-Printers
D [21/Feb/2008:18:23:50 +0100] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [21/Feb/2008:18:23:50 +0100] cupsdReadClient: 11 POST / HTTP/1.1
D [21/Feb/2008:18:23:50 +0100] cupsdAuthorize: No authentication data provided.
D [21/Feb/2008:18:23:50 +0100] CUPS-Get-Classes
D [21/Feb/2008:18:23:50 +0100] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [21/Feb/2008:18:23:50 +0100] cupsdReadClient: 11 POST / HTTP/1.1
D [21/Feb/2008:18:23:50 +0100] cupsdAuthorize: No authentication data provided.
D [21/Feb/2008:18:23:50 +0100] CUPS-Get-Default
D [21/Feb/2008:18:23:50 +0100] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [21/Feb/2008:18:23:50 +0100] cupsdCloseClient: 11
D [21/Feb/2008:18:23:50 +0100] cupsdAcceptClient: 11 from localhost (Domain)
D [21/Feb/2008:18:23:50 +0100] cupsdReadClient: 11 POST /printers/Canon_PIXMA HTTP/1.1
D [21/Feb/2008:18:23:50 +0100] cupsdAuthorize: No authentication data provided.
D [21/Feb/2008:18:23:50 +0100] Print-Job ipp://localhost/printers/Canon_PIXMA
D [21/Feb/2008:18:23:50 +0100] print_job: auto-typing file...
D [21/Feb/2008:18:23:50 +0100] add_job: requesting-user-name="nmarci"
I [21/Feb/2008:18:23:50 +0100] [Job 187] Adding start banner page "none".
D [21/Feb/2008:18:23:50 +0100] Discarding unused job-created event...
I [21/Feb/2008:18:23:50 +0100] [Job 187] Adding job file of type application/postscript.
I [21/Feb/2008:18:23:50 +0100] [Job 187] Adding end banner page "none".
I [21/Feb/2008:18:23:50 +0100] [Job 187] Queued on "Canon_PIXMA" by "nmarci".
D [21/Feb/2008:18:23:50 +0100] [Job 187] hold_until = 0
D [21/Feb/2008:18:23:50 +0100] Discarding unused printer-state-changed event...
D [21/Feb/2008:18:23:50 +0100] [Job 187] job-sheets=none,none
D [21/Feb/2008:18:23:50 +0100] [Job 187] banner_page = 0
D [21/Feb/2008:18:23:50 +0100] [Job 187] argv[0]="Canon_PIXMA"
D [21/Feb/2008:18:23:50 +0100] [Job 187] argv[1]="187"
D [21/Feb/2008:18:23:50 +0100] [Job 187] argv[2]="nmarci"
D [21/Feb/2008:18:23:50 +0100] [Job 187] argv[3]="Acro000kOnkp7"
D [21/Feb/2008:18:23:50 +0100] [Job 187] argv[4]="1"
D [21/Feb/2008:18:23:50 +0100] [Job 187] argv[5]="media=A4 finishings=3 number-up=1 job-uuid=urn:uuid:96d646f5-7e47-374d-4ce5-19bdfe5a97f6"
D [21/Feb/2008:18:23:50 +0100] [Job 187] argv[6]="/var/spool/cups/d00187-001"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[9]="SERVER_ADMIN=root at localhost.localdomain"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[10]="SOFTWARE=CUPS/1.3.5"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[12]="TZ=Europe/Budapest"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[13]="USER=root"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[16]="IPP_PORT=631"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[17]="CHARSET=utf-8"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[18]="LANG=hu_HU.UTF8"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[19]="PPD=/etc/cups/ppd/Canon_PIXMA.ppd"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[20]="RIP_MAX_CACHE=8m"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[21]="CONTENT_TYPE=application/postscript"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[22]="DEVICE_URI=cnij_usb:/dev/usb/lp0"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[23]="PRINTER=Canon_PIXMA"
D [21/Feb/2008:18:23:50 +0100] [Job 187] envp[24]="FINAL_CONTENT_TYPE=printer/Canon_PIXMA"
I [21/Feb/2008:18:23:50 +0100] [Job 187] Started filter /usr/lib/cups/filter/pstops (PID 4144)
I [21/Feb/2008:18:23:50 +0100] [Job 187] Started filter /usr/lib/cups/filter/pstocanonij (PID 4145)
I [21/Feb/2008:18:23:50 +0100] [Job 187] Started backend /usr/lib/cups/backend/cnij_usb (PID 4146)
D [21/Feb/2008:18:23:50 +0100] Discarding unused job-state event...
D [21/Feb/2008:18:23:50 +0100] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [21/Feb/2008:18:23:50 +0100] [Job 187] Page = 595x842; 10,14 to 586,833
D [21/Feb/2008:18:23:50 +0100] cupsdCloseClient: 11
D [21/Feb/2008:18:23:50 +0100] [Job 187] pstocanonij start.
D [21/Feb/2008:18:23:50 +0100] Discarding unused printer-state-changed event...
D [21/Feb/2008:18:23:50 +0100] [Job 187] slow_collate=0, slow_duplex=0, slow_order=0
D [21/Feb/2008:18:23:50 +0100] [Job 187] Before copy_comments - %!PS-Adobe-3.0
D [21/Feb/2008:18:23:50 +0100] [Job 187] %!PS-Adobe-3.0
D [21/Feb/2008:18:23:50 +0100] [Job 187] %%Title: (kommentar2007-6-kesz.qxd)
D [21/Feb/2008:18:23:50 +0100] [Job 187] %%Version: 1 4
D [21/Feb/2008:18:23:50 +0100] [Job 187] %%CreationDate: 18.23.49 2008-02-21
D [21/Feb/2008:18:23:50 +0100] [Job 187] %%DocumentData: Clean7Bit
D [21/Feb/2008:18:23:50 +0100] [Job 187] %%LanguageLevel: 2
D [21/Feb/2008:18:23:50 +0100] [Job 187] %%BoundingBox: 30 29 502 710
D [21/Feb/2008:18:23:50 +0100] [Job 187] %%HiResBoundingBox: 29.5937 29.1118 501.591 709.928
D [21/Feb/2008:18:23:50 +0100] [Job 187] %%Pages: 11
D [21/Feb/2008:18:23:50 +0100] [Job 187] %%DocumentProcessColors: (atend)
D [21/Feb/2008:18:23:50 +0100] [Job 187] %%DocumentSuppliedResources:
D [21/Feb/2008:18:23:50 +0100] [Job 187] %%+ procset (Adobe Acrobat - PDF operators) 1.2 0
D [21/Feb/2008:18:23:50 +0100] [Job 187] %%+ procset (Adobe Acrobat - type operators) 1.2 0
D [21/Feb/2008:18:23:50 +0100] [Job 187] %%EndComments
D [21/Feb/2008:18:23:50 +0100] [Job 187] Before copy_prolog - %%BeginDefaults
D [21/Feb/2008:18:23:50 +0100] [Job 187] Before copy_setup - %%BeginSetup
D [21/Feb/2008:18:23:50 +0100] [Job 187] (Canon) langage moniter[/usr/local/bin/lgmonmp160 --gui --cups] start!
D [21/Feb/2008:18:23:50 +0100] [Job 187] Before page loop - %%Page: 1 1
D [21/Feb/2008:18:23:50 +0100] [Job 187] Copying page 1...
D [21/Feb/2008:18:23:50 +0100] [Job 187] pagew = 576.0, pagel = 819.2
D [21/Feb/2008:18:23:50 +0100] [Job 187] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageLeft = 9.6, PageRight = 585.6
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageTop = 833.4, PageBottom = 14.2
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageWidth = 595.0, PageLength = 842.0
D [21/Feb/2008:18:23:50 +0100] [Job 187] Copying page 2...
D [21/Feb/2008:18:23:50 +0100] [Job 187] pagew = 576.0, pagel = 819.2
D [21/Feb/2008:18:23:50 +0100] [Job 187] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageLeft = 9.6, PageRight = 585.6
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageTop = 833.4, PageBottom = 14.2
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageWidth = 595.0, PageLength = 842.0
D [21/Feb/2008:18:23:50 +0100] [Job 187] Copying page 3...
D [21/Feb/2008:18:23:50 +0100] [Job 187] pagew = 576.0, pagel = 819.2
D [21/Feb/2008:18:23:50 +0100] [Job 187] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageLeft = 9.6, PageRight = 585.6
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageTop = 833.4, PageBottom = 14.2
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageWidth = 595.0, PageLength = 842.0
D [21/Feb/2008:18:23:50 +0100] [Job 187] Copying page 4...
D [21/Feb/2008:18:23:50 +0100] [Job 187] pagew = 576.0, pagel = 819.2
D [21/Feb/2008:18:23:50 +0100] [Job 187] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageLeft = 9.6, PageRight = 585.6
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageTop = 833.4, PageBottom = 14.2
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageWidth = 595.0, PageLength = 842.0
D [21/Feb/2008:18:23:50 +0100] [Job 187] Copying page 5...
D [21/Feb/2008:18:23:50 +0100] [Job 187] pagew = 576.0, pagel = 819.2
D [21/Feb/2008:18:23:50 +0100] [Job 187] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageLeft = 9.6, PageRight = 585.6
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageTop = 833.4, PageBottom = 14.2
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageWidth = 595.0, PageLength = 842.0
D [21/Feb/2008:18:23:50 +0100] [Job 187] Copying page 6...
D [21/Feb/2008:18:23:50 +0100] [Job 187] pagew = 576.0, pagel = 819.2
D [21/Feb/2008:18:23:50 +0100] [Job 187] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageLeft = 9.6, PageRight = 585.6
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageTop = 833.4, PageBottom = 14.2
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageWidth = 595.0, PageLength = 842.0
D [21/Feb/2008:18:23:50 +0100] [Job 187] Copying page 7...
D [21/Feb/2008:18:23:50 +0100] [Job 187] pagew = 576.0, pagel = 819.2
D [21/Feb/2008:18:23:50 +0100] [Job 187] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageLeft = 9.6, PageRight = 585.6
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageTop = 833.4, PageBottom = 14.2
D [21/Feb/2008:18:23:50 +0100] [Job 187] PageWidth = 595.0, PageLength = 842.0
D [21/Feb/2008:18:23:51 +0100] [Job 187] pstocanonij: /usr/bin/gs -r600 -g4958x7016 -q -dNOPROMPT -dSAFER -sDEVICE=ppmraw -sOutputFile=- -|
/usr/local/bin/cifmp160 --imageres 600 --papersize a4 --media plain --paperload asf --bbox 9,14,585,834
D [21/Feb/2008:18:23:52 +0100] [Job 187] CIF COMMAND ERROR :file access Error!
D [21/Feb/2008:18:23:52 +0100] [Job 187] not support this image format
D [21/Feb/2008:18:23:52 +0100] [Job 187] ESP Ghostscript 815.03: Unrecoverable error, exit code 1
E [21/Feb/2008:18:23:52 +0100] [Job 187] pstocanonij write error,32.
D [21/Feb/2008:18:23:52 +0100] Discarding unused printer-state-changed event...
E [21/Feb/2008:18:23:52 +0100] PID 4145 (/usr/lib/cups/filter/pstocanonij) stopped with status 1!
D [21/Feb/2008:18:23:52 +0100] [Job 187] Copying page 8...
D [21/Feb/2008:18:23:52 +0100] [Job 187] pagew = 576.0, pagel = 819.2
D [21/Feb/2008:18:23:52 +0100] [Job 187] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [21/Feb/2008:18:23:52 +0100] [Job 187] PageLeft = 9.6, PageRight = 585.6
D [21/Feb/2008:18:23:52 +0100] [Job 187] PageTop = 833.4, PageBottom = 14.2
D [21/Feb/2008:18:23:52 +0100] [Job 187] PageWidth = 595.0, PageLength = 842.0
D [21/Feb/2008:18:23:52 +0100] [Job 187] Copying page 9...
D [21/Feb/2008:18:23:52 +0100] [Job 187] pagew = 576.0, pagel = 819.2
D [21/Feb/2008:18:23:52 +0100] [Job 187] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [21/Feb/2008:18:23:52 +0100] [Job 187] PageLeft = 9.6, PageRight = 585.6
D [21/Feb/2008:18:23:52 +0100] [Job 187] PageTop = 833.4, PageBottom = 14.2
D [21/Feb/2008:18:23:52 +0100] [Job 187] PageWidth = 595.0, PageLength = 842.0
D [21/Feb/2008:18:23:52 +0100] [Job 187] Copying page 10...
D [21/Feb/2008:18:23:52 +0100] [Job 187] pagew = 576.0, pagel = 819.2
D [21/Feb/2008:18:23:52 +0100] [Job 187] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [21/Feb/2008:18:23:52 +0100] [Job 187] PageLeft = 9.6, PageRight = 585.6
D [21/Feb/2008:18:23:52 +0100] [Job 187] PageTop = 833.4, PageBottom = 14.2
D [21/Feb/2008:18:23:52 +0100] [Job 187] PageWidth = 595.0, PageLength = 842.0
D [21/Feb/2008:18:23:52 +0100] [Job 187] Copying page 11...
D [21/Feb/2008:18:23:52 +0100] [Job 187] pagew = 576.0, pagel = 819.2
D [21/Feb/2008:18:23:52 +0100] [Job 187] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [21/Feb/2008:18:23:52 +0100] [Job 187] PageLeft = 9.6, PageRight = 585.6
D [21/Feb/2008:18:23:52 +0100] [Job 187] PageTop = 833.4, PageBottom = 14.2
D [21/Feb/2008:18:23:52 +0100] [Job 187] PageWidth = 595.0, PageLength = 842.0
D [21/Feb/2008:18:23:52 +0100] [Job 187] Wrote 11 pages...
D [21/Feb/2008:18:23:52 +0100] PID 4144 (/usr/lib/cups/filter/pstops) exited with no errors.
My /etc/cups/printers.conf looks like:
<DefaultPrinter Canon_PIXMA>
Info Canon PIXMA MP160
DeviceURI cnij_usb:/dev/usb/lp0
State Idle
StateTime 1203614563
Accepting Yes
Shared Yes
JobSheets none none
QuotaPeriod 0
PageLimit 0
KLimit 0
OpPolicy default
ErrorPolicy stop-printer
</Printer>
-- System Information:
Debian Release: lenny/sid
APT prefers testing
APT policy: (500, 'testing')
Architecture: i386 (i686)
Kernel: Linux 2.6.23 (PREEMPT)
Locale: LANG=hu_HU, LC_CTYPE=hu_HU (charmap=ISO-8859-2)
Shell: /bin/sh linked to /bin/bash
Versions of packages cupsys depends on:
ii adduser 3.105 add and remove users and groups
ii cupsys-common 1.3.5-1 Common UNIX Printing System(tm) -
ii debconf [debconf-2.0] 1.5.19 Debian configuration management sy
ii gs-esp 8.15.3.dfsg.1-1 The Ghostscript PostScript interpr
ii libavahi-compat-libdn 0.6.22-2 Avahi Apple Bonjour compatibility
ii libc6 2.7-6 GNU C Library: Shared libraries
ii libcupsimage2 1.3.5-1+b1 Common UNIX Printing System(tm) -
ii libcupsys2 1.3.5-1+b1 Common UNIX Printing System(tm) -
ii libdbus-1-3 1.1.2-1 simple interprocess messaging syst
ii libgnutls13 2.0.4-1 the GNU TLS library - runtime libr
ii libkrb53 1.6.dfsg.3~beta1-2 MIT Kerberos runtime libraries
ii libldap-2.4-2 2.4.7-4 OpenLDAP libraries
ii libpam0g 0.99.7.1-5 Pluggable Authentication Modules l
ii libpaper1 1.1.23 library for handling paper charact
ii libslp1 1.2.1-7.1 OpenSLP libraries
ii lsb-base 3.1-24 Linux Standard Base 3.1 init scrip
ii perl-modules 5.8.8-12 Core Perl modules
ii procps 1:3.2.7-5 /proc file system utilities
ii ssl-cert 1.0.14 Simple debconf wrapper for openssl
ii xpdf-utils [poppler-u 3.02-1.3 Portable Document Format (PDF) sui
Versions of packages cupsys recommends:
pn avahi-utils <none> (no description available)
ii cupsys-client 1.3.5-1+b1 Common UNIX Printing System(tm) -
ii foomatic-filters 3.0.2-20061031-1.2 linuxprinting.org printer support
ii smbclient 3.0.28-2+b1 a LanManager-like simple client fo
-- debconf information:
cupsys/raw-print: true
cupsys/backend: ipp, lpd, parallel, scsi, serial, socket, usb, snmp, dnssd
-------------- next part --------------
A non-text attachment was scrubbed...
Name: cups_job_processing.png
Type: image/png
Size: 26577 bytes
Desc: not available
Url : http://lists.alioth.debian.org/pipermail/pkg-cups-devel/attachments/20080221/338a6fab/attachment-0002.png
-------------- next part --------------
A non-text attachment was scrubbed...
Name: cups_printer_processing.png
Type: image/png
Size: 30144 bytes
Desc: not available
Url : http://lists.alioth.debian.org/pipermail/pkg-cups-devel/attachments/20080221/338a6fab/attachment-0003.png
More information about the Pkg-cups-devel
mailing list