No printing. foomatic-rip stops with status 3
 

I have spent days trying to get my printer to work. Finally I uninstalled Cups, Foomatic, GS, and the hpijs packages. I reinstalled them as follows, in order, with no errors:

cups-1.1.23
espgs-8.15.1
foomatic-db-20051121
foomatic-filters-3.0-20051121
foomatic-db-engine-3.0-20051121
foomatic-db-hpijs-1.3.1-20031121
hpijs-2.1.4

I “killall -HUP cups”'d then installed a HP Deskjet printer with the appropriate hpijs/Foomatic driver via the “localhost:361” interface. I then printed a test page. The printer was on. The cables were connected. There was paper in the printer, the printer is fully functional. Nothing happened. I checked and the CUPS manager said that the job was aborted. When I tried to reprint, I got an error: client-error-not-possible.

Looking at the error logs, foomatic-rip always stops with a status 3, whatever that means.

Here is the /var/log/cups/error_log entry:

<pre>
I [21/Nov/2005:17:31:01 -0500] Allowing up to 100 client connections per host.
I [21/Nov/2005:17:31:01 -0500] Full reload is required.
I [21/Nov/2005:17:31:01 -0500] LoadPPDs: Read "/etc/cups/ppds.dat", 1249 PPDs...
I [21/Nov/2005:17:31:01 -0500] LoadPPDs: Wrote "/etc/cups/ppds.dat", 1671 PPDs...
I [21/Nov/2005:17:31:01 -0500] Full reload complete..
I [21/Nov/2005:17:31:13 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=27322)
I [21/Nov/2005:17:31:16 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=27323)
I [21/Nov/2005:17:31:30 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=27325)
I [21/Nov/2005:17:31:37 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=27326)
I [21/Nov/2005:17:31:40 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=27327)
I [21/Nov/2005:17:32:06 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=27328)
I [21/Nov/2005:17:32:06 -0500] Setting hpdj device-uri to "usb:/dev/usblp0" (was "file:/dev/null".)
I [21/Nov/2005:17:32:06 -0500] Setting hpdj printer-is-accepting-jobs to 1 (was 0.)
I [21/Nov/2005:17:32:06 -0500] Setting hpdj printer-state to 3 (was 5.)
I [21/Nov/2005:17:32:06 -0500] Saving printers.conf...
I [21/Nov/2005:17:32:06 -0500] New printer 'hpdj' added by ''.
I [21/Nov/2005:17:32:08 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=27329)
I [21/Nov/2005:17:32:11 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=27330)
I [21/Nov/2005:17:32:11 -0500] Adding start banner page "none" to job 33.
I [21/Nov/2005:17:32:11 -0500] Adding end banner page "none" to job 33.
I [21/Nov/2005:17:32:11 -0500] Job 33 queued on 'hpdj' by ''.
I [21/Nov/2005:17:32:11 -0500] Started filter /usr/lib/cups/filter/pstops (PID 27331) for job 33.
I [21/Nov/2005:17:32:11 -0500] Started filter /usr/lib/cups/filter/foomatic-rip (PID 27332) for job 33.
I [21/Nov/2005:17:32:11 -0500] Started backend /usr/lib/cups/backend/usb (PID 27333) for job 33.
E [21/Nov/2005:17:32:12 -0500] PID 27332 stopped with status 3!
I [21/Nov/2005:17:32:12 -0500] Hint: Try setting the LogLevel to "debug" to find out more.
I [21/Nov/2005:17:33:49 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=27345)
I [21/Nov/2005:17:33:52 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=27346)
I [21/Nov/2005:17:33:54 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=27347)
I [21/Nov/2005:17:33:57 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=27348)
I [21/Nov/2005:17:34:00 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=27349)
I [21/Nov/2005:17:34:03 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=27350)
I [21/Nov/2005:17:34:06 -0500] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=27351)
E [21/Nov/2005:17:34:07 -0500] restart_job: job #33 cannot be restarted - no files!
</pre>


I have an acer aspire laptop with Mandrake 10 and a 2.6.14.2 kernel. Everything else works on the computer. USB printer support is compiled into the kernel. I have a USB flash drive that I can read/write to. I also tried printing with a HP 750xi. Same results. When I connect the printer this is the output from dmesg | tail:

<pre>
usb 3-2: new full speed USB device using ohci_hcd and address 6
drivers/usb/class/usblp.c: usblp0: USB Bidirectional printer dev 6 if 0 alt 0 proto 2 vid 0x03F0 pid 0x7304
</pre?
Someone please help!!!

debug error_log
 

I changed the loglevel to debug. After readin, I decided to renstall GhostScript. I installed espgs 7.07 from SourceForge.net. I got the exact same results. See below. Hopefully someone'll be able to see what I am doing wrong, and tell me. Thanks!

D [22/Nov/2005:18:23:42 -0500] [Job 37] PPD file: /etc/cups/ppd/hpdj.ppd
D [22/Nov/2005:18:23:42 -0500] [Job 37] Printer model: HP DeskJet 3520 Foomatic/hpijs (recommended)
D [22/Nov/2005:18:23:42 -0500] [Job 37] Job title: Test Page
D [22/Nov/2005:18:23:42 -0500] [Job 37] File(s) to be printed:
D [22/Nov/2005:18:23:42 -0500] [Job 37] <STDIN>
D [22/Nov/2005:18:23:42 -0500] [Job 37]
D [22/Nov/2005:18:23:42 -0500] [Job 37] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [22/Nov/2005:18:23:42 -0500] [Job 37]
D [22/Nov/2005:18:23:42 -0500] [Job 37] ================================================
D [22/Nov/2005:18:23:42 -0500] [Job 37]
D [22/Nov/2005:18:23:42 -0500] [Job 37] File: <STDIN>
D [22/Nov/2005:18:23:42 -0500] [Job 37]
D [22/Nov/2005:18:23:42 -0500] [Job 37] ================================================
D [22/Nov/2005:18:23:42 -0500] [Job 37]
D [22/Nov/2005:18:23:42 -0500] [Job 37] Reading PostScript input ...
D [22/Nov/2005:18:23:42 -0500] [Job 37] --> This document is DSC-conforming!
D [22/Nov/2005:18:23:42 -0500] [Job 37]
D [22/Nov/2005:18:23:42 -0500] [Job 37] -----------
D [22/Nov/2005:18:23:42 -0500] [Job 37] Found: %%BeginProlog
D [22/Nov/2005:18:23:42 -0500] [Job 37] Found: %%EndProlog
D [22/Nov/2005:18:23:42 -0500] [Job 37]
D [22/Nov/2005:18:23:42 -0500] [Job 37] -----------
D [22/Nov/2005:18:23:42 -0500] [Job 37] Found: %%BeginSetup
D [22/Nov/2005:18:23:42 -0500] [Job 37] Found: %%BeginFeature: *PrintoutMode Normal
D [22/Nov/2005:18:23:42 -0500] [Job 37] Option: PrintoutMode=Normal --> Setting option
D [22/Nov/2005:18:23:42 -0500] [Job 37] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal
D [22/Nov/2005:18:23:42 -0500] [Job 37] Option: PrintoutMode=Normal --> Setting option
D [22/Nov/2005:18:23:42 -0500] [Job 37] Found: %%BeginFeature: *Quality FromPrintoutMode
D [22/Nov/2005:18:23:42 -0500] [Job 37] Option: Quality=FromPrintoutMode --> Setting option
D [22/Nov/2005:18:23:42 -0500] [Job 37] Found: %% FoomaticRIPOptionSetting: Quality=@PrintoutMode
D [22/Nov/2005:18:23:42 -0500] [Job 37] Option: Quality=FromPrintoutMode --> Setting option
D [22/Nov/2005:18:23:42 -0500] [Job 37] Found: %%BeginFeature: *PageSize Letter
D [22/Nov/2005:18:23:42 -0500] [Job 37] Option: PageSize=Letter --> Setting option
D [22/Nov/2005:18:23:42 -0500] [Job 37] Found: %% FoomaticRIPOptionSetting: PageSize=Letter
D [22/Nov/2005:18:23:42 -0500] [Job 37] Option: PageSize=Letter --> Setting option
D [22/Nov/2005:18:23:42 -0500] [Job 37] Found: %%EndSetup
D [22/Nov/2005:18:23:42 -0500] [Job 37] Inserting PostScript code for CUPS' page accounting
D [22/Nov/2005:18:23:42 -0500] [Job 37]
D [22/Nov/2005:18:23:42 -0500] [Job 37] -----------
D [22/Nov/2005:18:23:42 -0500] [Job 37] New page: 1 1
D [22/Nov/2005:18:23:42 -0500] [Job 37] Inserting option code into "PageSetup" section.
D [22/Nov/2005:18:23:42 -0500] [Job 37] No page header or page header not DSC-conforming
D [22/Nov/2005:18:23:42 -0500] [Job 37] Stopping search for page header options
D [22/Nov/2005:18:23:42 -0500] [Job 37] Found:
D [22/Nov/2005:18:23:42 -0500] [Job 37] pageHeight sub % Move down...
D [22/Nov/2005:18:23:42 -0500] [Job 37] --> Output goes directly to the renderer now.
D [22/Nov/2005:18:23:42 -0500] [Job 37]
D [22/Nov/2005:18:23:42 -0500] [Job 37]
D [22/Nov/2005:18:23:42 -0500] [Job 37] Starting renderer
D [22/Nov/2005:18:23:42 -0500] [Job 37] JCL: <job data>
D [22/Nov/2005:18:23:42 -0500] [Job 37]
D [22/Nov/2005:18:23:42 -0500] [Job 37] renderer PID kid4=13498
D [22/Nov/2005:18:23:42 -0500] [Job 37] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="deskjet 3320" -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -r300 -sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=1 -dIjsUseOutputFD -sOutputFile=- -
D [22/Nov/2005:18:23:42 -0500] [Job 37]
D [22/Nov/2005:18:23:42 -0500] [Job 37] Closing renderer
D [22/Nov/2005:18:23:42 -0500] [Job 37] foomatic-gswrapper: gs '-dBATCH' '-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=ijs' '-sIjsServer=hpijs' '-sDeviceManufacturer=HEWLETT-PACKARD' '-sDeviceModel=deskjet 3320' '-dDEVICEWIDTHPOINTS=612' '-dDEVICEHEIGHTPOINTS=792' '-r300' '-sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=1' '-dIjsUseOutputFD' '-sOutputFile=/dev/fd/3' '/dev/fd/0' 3>&1 1>&2
D [22/Nov/2005:18:23:42 -0500] [Job 37] Error: /invalidfont in findfont
D [22/Nov/2005:18:23:42 -0500] [Job 37] Operand stack:
D [22/Nov/2005:18:23:42 -0500] [Job 37] bigFont Helvetica-Bold Font Helvetica-Bold 383285 Helvetica-Bold --nostringval-- Helvetica-Bold NimbusSanL-Bold (NimbusSanL-Bold) NimbusSanL-Bold (NimbusSanL-Bold) NimbusSanL-Bold
D [22/Nov/2005:18:23:42 -0500] [Job 37] Execution stack:
D [22/Nov/2005:18:23:42 -0500] [Job 37] %interp_exit .runexec2 --nostringval-- --nostringval-- --nostringval-- 2 %stopped_push --nostringval-- --nostringval-- --nostringval-- false 1 %stopped_push 1 3 %oparray_pop 1 3 %oparray_pop 1 3 %oparray_pop .runexec2 --nostringval-- --nostringval-- --nostringval-- 2 %stopped_push --nostringval-- 2 3 %oparray_pop 3 3 %oparray_pop --nostringval-- --nostringval-- --nostringval-- --nostringval-- --nostringval-- false 1 %stopped_push 6 4 %oparray_pop --nostringval-- --nostringval-- --nostringval-- 5 -1 1 --nostringval-- %for_neg_int_continue --nostringval-- --nostringval--
D [22/Nov/2005:18:23:42 -0500] [Job 37] Dictionary stack:
D [22/Nov/2005:18:23:42 -0500] [Job 37] --dict:1059/1417(ro)(G)-- --dict:1/20(G)-- --dict:91/200(L)-- --dict:17/17(ro)(G)-- --dict:1059/1417(ro)(G)--
D [22/Nov/2005:18:23:42 -0500] [Job 37] Current allocation mode is local
D [22/Nov/2005:18:23:42 -0500] [Job 37] Last OS error: 2
D [22/Nov/2005:18:23:42 -0500] [Job 37] ESP Ghostscript 7.07.1: Unrecoverable error, exit code 1
D [22/Nov/2005:18:23:42 -0500] [Job 37] renderer return value: 1
D [22/Nov/2005:18:23:42 -0500] [Job 37] renderer received signal: 1
D [22/Nov/2005:18:23:42 -0500] [Job 37] Process dying with "Possible error on renderer command line or PostScript error. Check options.", exit stat: 3
D [22/Nov/2005:18:23:42 -0500] [Job 37] error: Illegal seek (29)
D [22/Nov/2005:18:23:42 -0500] [Job 37] Possible error on renderer command line or PostScript error. Check options.
D [22/Nov/2005:18:23:42 -0500] [Job 37] KID3 exited with status 3
D [22/Nov/2005:18:23:42 -0500] [Job 37] Renderer exit stat: 3
D [22/Nov/2005:18:23:42 -0500] [Job 37] Renderer process finished
D [22/Nov/2005:18:23:42 -0500] [Job 37] Killing process 13497 (KID3)
D [22/Nov/2005:18:23:42 -0500] [Job 37] Process dying with "Error closing renderer", exit stat: 3
D [22/Nov/2005:18:23:42 -0500] [Job 37] error: Bad file descriptor (9)
D [22/Nov/2005:18:23:42 -0500] [Job 37] Error closing renderer
D [22/Nov/2005:18:23:42 -0500] [Job 37] tail process done writing data to STDOUT
D [22/Nov/2005:18:23:42 -0500] [Job 37] KID4 finished
E [22/Nov/2005:18:23:42 -0500] PID 13495 stopped with status 3!
D [22/Nov/2005:18:23:44 -0500] UpdateJob: job 37, file 0 is complete.
D [22/Nov/2005:18:23:44 -0500] CancelJob: id = 37
D [22/Nov/2005:18:23:44 -0500] StopJob: id = 37, force = 0
D [22/Nov/2005:18:23:44 -0500] StopJob: printer state is 3