[NBLUG/talk] Printing

[iansuth at sbcglobal.net]

On Wednesday 02 February 2005 00:38, Eric Eisenhart wrote:
> On Tue, Feb 01, 2005 at 02:49:19PM +0000, iansuth at sbcglobal.net wrote:
> > Clues please, because I don't have any.
>
> Look in /var/log/cups/.  Especially the error_log.  Sometimes CUPS will
> actually put words that make sense in the error log.  (other times what
> it's saying is incomprehensible, but still a good starting point)
>
> Find a big document (say, a 100 page PDF or something) and try to print it
> (lpr bigdoc.pdf).  Then immediately run "lpq" and check the print queue.
> Does the document go into the print queue?  Does it ever leave the print
> queue?  (if it doesn't go in, that tells you where to look.  If it never
> leaves the queue, CUPS knows it isn't printing.  If it leaves the queue,
> CUPS probably believes that the print job is working.)
Here is the end of error_log:

I [01/Feb/2005:10:35:22 -0800] Printer 'HPdeskjet' modified by 'root'.
I [01/Feb/2005:10:35:26 -0800] Started 
"/usr/lib/cups/cgi-bin/admin.cgi" (pid=7136)
I [01/Feb/2005:10:35:30 -0800] Started 
"/usr/lib/cups/cgi-bin/classes.cgi" (pid=7147)
I [01/Feb/2005:10:35:33 -0800] Started 
"/usr/lib/cups/cgi-bin/admin.cgi" (pid=7148)
I [01/Feb/2005:10:35:38 -0800] Started 
"/usr/lib/cups/cgi-bin/printers.cgi" (pid=7151)
I [01/Feb/2005:10:35:46 -0800] Started 
"/usr/lib/cups/cgi-bin/admin.cgi" (pid=7154)
I [01/Feb/2005:10:35:47 -0800] Saving printers.conf...
I [01/Feb/2005:10:35:47 -0800] Saving classes.conf...
I [01/Feb/2005:10:35:47 -0800] Default destination set to 'HPdeskjet' by 
'root'.
I [01/Feb/2005:10:36:28 -0800] Adding start banner page "none" to job 45.
I [01/Feb/2005:10:36:28 -0800] Adding end banner page "none" to job 45.
I [01/Feb/2005:10:36:28 -0800] Job 45 queued on 'HPdeskjet' by 'root'.
I [01/Feb/2005:10:36:28 -0800] Started filter /usr/lib/cups/filter/pstops (PID 
7156) for job 45.
I [01/Feb/2005:10:36:28 -0800] Started 
filter /usr/lib/cups/filter/foomatic-rip (PID 7157) for job 45.
I [01/Feb/2005:10:36:28 -0800] Started backend /usr/lib/cups/backend/parallel 
(PID 7158) for job 45.
E [01/Feb/2005:10:36:30 -0800] PID 7157 stopped with status 3!
I [01/Feb/2005:10:36:30 -0800] Hint: Try setting the LogLevel to "debug" to 
find out more.

This from lpr, lpq:

ian at debian:~$ lpr /usr/share/ImageMagick-6.0.6/Magick.pdf
ian at debian:~$ lpq
HPdeskjet is ready and printing
Rank    Owner   Job     File(s)                         Total Size
active  ian     46      Magick.pdf                      815104 bytes
ian at debian:~$ lpr /usr/share/cups/doc-root/fr/overview.pdf
ian at debian:~$ lpq
HPdeskjet is ready
no entries
ian at debian:~$ lpr /usr/share/cups/doc-root/ja/translation.pdf
ian at debian:~$ lpq
HPdeskjet is ready
no entries

Then error_log:

I [01/Feb/2005:11:13:41 -0800] Adding start banner page "none" to job 46.
I [01/Feb/2005:11:13:41 -0800] Adding end banner page "none" to job 46.
I [01/Feb/2005:11:13:41 -0800] Job 46 queued on 'HPdeskjet' by 'ian'.
I [01/Feb/2005:11:13:41 -0800] Started filter /usr/lib/cups/filter/pdftops 
(PID 7265) for job 46.
I [01/Feb/2005:11:13:41 -0800] Started filter /usr/lib/cups/filter/pstops (PID 
7266) for job 46.
I [01/Feb/2005:11:13:41 -0800] Started 
filter /usr/lib/cups/filter/foomatic-rip (PID 7267) for job 46.
I [01/Feb/2005:11:13:41 -0800] Started backend /usr/lib/cups/backend/parallel 
(PID 7268) for job 46.
E [01/Feb/2005:11:13:41 -0800] [Job 46] pdftops-options: 
-cfg /etc/cups/pdftops.conf
E [01/Feb/2005:11:13:53 -0800] PID 7267 stopped with status 3!
I [01/Feb/2005:11:13:53 -0800] Hint: Try setting the LogLevel to "debug" to 
find out more.
I [01/Feb/2005:11:14:55 -0800] Adding start banner page "none" to job 47.
I [01/Feb/2005:11:14:55 -0800] Adding end banner page "none" to job 47.
I [01/Feb/2005:11:14:55 -0800] Job 47 queued on 'HPdeskjet' by 'ian'.
I [01/Feb/2005:11:14:55 -0800] Started filter /usr/lib/cups/filter/pdftops 
(PID 7281) for job 47.
I [01/Feb/2005:11:14:55 -0800] Started filter /usr/lib/cups/filter/pstops (PID 
7282) for job 47.
I [01/Feb/2005:11:14:55 -0800] Started 
filter /usr/lib/cups/filter/foomatic-rip (PID 7283) for job 47.
I [01/Feb/2005:11:14:55 -0800] Started backend /usr/lib/cups/backend/parallel 
(PID 7284) for job 47.
E [01/Feb/2005:11:14:55 -0800] [Job 47] pdftops-options: 
-cfg /etc/cups/pdftops.conf
E [01/Feb/2005:11:14:57 -0800] PID 7283 stopped with status 3!
I [01/Feb/2005:11:14:57 -0800] Hint: Try setting the LogLevel to "debug" to 
find out more.
I [01/Feb/2005:11:15:45 -0800] Adding start banner page "none" to job 48.
I [01/Feb/2005:11:15:45 -0800] Adding end banner page "none" to job 48.
I [01/Feb/2005:11:15:45 -0800] Job 48 queued on 'HPdeskjet' by 'ian'.
I [01/Feb/2005:11:15:45 -0800] Started filter /usr/lib/cups/filter/pdftops 
(PID 7293) for job 48.
I [01/Feb/2005:11:15:45 -0800] Started filter /usr/lib/cups/filter/pstops (PID 
7294) for job 48.
I [01/Feb/2005:11:15:46 -0800] Started 
filter /usr/lib/cups/filter/foomatic-rip (PID 7295) for job 48.
I [01/Feb/2005:11:15:46 -0800] Started backend /usr/lib/cups/backend/parallel 
(PID 7296) for job 48.
E [01/Feb/2005:11:15:46 -0800] [Job 48] pdftops-options: 
-cfg /etc/cups/pdftops.conf
E [01/Feb/2005:11:15:47 -0800] PID 7295 stopped with status 3!
I [01/Feb/2005:11:15:47 -0800] Hint: Try setting the LogLevel to "debug" to 
find out more.


Thanks, Ian