emerged hplip - cups works, hplip not

Message

valmar · Post by **valmar** » Thu Jun 09, 2005 6:14 am

Hello everyone. I am Valerio from Texas, I have some experience with linux but I am fairly new to gentoo.

Here is the problem, hope you can help:

I emerge hplip using the ebuild from gentoo bugzilla. Everithing went fine. If I install a printer using cups and the usb device created by hplip, I can see the printer in the HP Device Manager, check the ink level, and so on. However, every time I print something (a test page, for example), the printer does not come to life, and the job appears as "printing" in the Device Manager and "processing" in the CUPS interface, staying there for life. The strange this is that if I intall the printer through the cups interface using the common USB slot, as opposed to the one created by hplip, everyting is fine and I can print. Is there a reason while the printer is responding to commands sent with CUPS and no to commands sent through the HP GUI?

BTW I patched the kernel as described in the Gentoo wiki for the HPOJ service.

Thank you in advance for your help

Valerio

lavacano · Post by **lavacano** » Thu Jun 09, 2005 6:45 am

the new cups I emerged had a little problem;

try ln -s /var/spool /var/lib/spool
that made it all better hope that helps also
cat /var/log/cups/error_log

valmar · Post by **valmar** » Thu Jun 09, 2005 9:06 am

Things changed. Now all my jobs are automaticaly aborted and end up in the completed jobs list. If I try to restart them, I get client-error-not-possible

Here are the last lines of the log file:

[09/Jun/2005:02:57:56 -0500] Listening to 0:631
I [09/Jun/2005:02:57:56 -0500] Loaded configuration file "/etc/cups/cupsd.conf"
I [09/Jun/2005:02:57:56 -0500] Configured for up to 100 clients.
I [09/Jun/2005:02:57:56 -0500] Allowing up to 100 client connections per host.
I [09/Jun/2005:02:57:56 -0500] Full reload is required.
I [09/Jun/2005:02:57:57 -0500] LoadPPDs: Read "/etc/cups/ppds.dat", 440 PPDs...
I [09/Jun/2005:02:57:59 -0500] LoadPPDs: Wrote "/etc/cups/ppds.dat", 440 PPDs...
I [09/Jun/2005:02:57:59 -0500] Full reload complete.
E [09/Jun/2005:02:57:59 -0500] StartListening: Unable to find IP address for server name "gentoo" - Unknown host
I [09/Jun/2005:03:02:18 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5487)
I [09/Jun/2005:03:02:22 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5490)
I [09/Jun/2005:03:02:28 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5493)
I [09/Jun/2005:03:02:33 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5495)
I [09/Jun/2005:03:02:37 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5497)
I [09/Jun/2005:03:02:49 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5499)
I [09/Jun/2005:03:02:49 -0500] Setting a device-uri to "hp:/usb/Deskjet_3740?serial=CN45J3Y1D7040Q" (was "file:/dev/null".)
I [09/Jun/2005:03:02:49 -0500] Setting a printer-is-accepting-jobs to 1 (was 0.)
I [09/Jun/2005:03:02:49 -0500] Setting a printer-state to 3 (was 5.)
I [09/Jun/2005:03:02:49 -0500] Saving printers.conf...
I [09/Jun/2005:03:02:49 -0500] New printer 'a' added by 'root'.
I [09/Jun/2005:03:05:20 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5500)
I [09/Jun/2005:03:11:35 -0500] Adding start banner page "none" to job 1.
I [09/Jun/2005:03:11:35 -0500] Adding end banner page "none" to job 1.
I [09/Jun/2005:03:11:35 -0500] Job 1 queued on 'a' by 'root'.
I [09/Jun/2005:03:11:35 -0500] Started filter /usr/lib/cups/filter/pstops (PID 5639) for job 1.
I [09/Jun/2005:03:11:35 -0500] Started filter /usr/lib/cups/filter/foomatic-rip (PID 5640) for job 1.
I [09/Jun/2005:03:11:35 -0500] Started backend /usr/lib/cups/backend/hp (PID 5641) for job 1.
E [09/Jun/2005:03:11:35 -0500] PID 5640 stopped with status 22!
I [09/Jun/2005:03:11:35 -0500] Hint: Try setting the LogLevel to "debug" to find out more.
I [09/Jun/2005:03:11:54 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5644)
I [09/Jun/2005:03:11:59 -0500] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=5645)
I [09/Jun/2005:03:12:04 -0500] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=5647)
I [09/Jun/2005:03:12:08 -0500] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=5648)
E [09/Jun/2005:03:12:08 -0500] restart_job: job #1 cannot be restarted - no files!
I

Thank You for your precious help!

Valerio

lavacano · Post by **lavacano** » Thu Jun 09, 2005 6:50 pm

thats great! its good it actually changed the behavior. now try

mkdir /var/spool/cups
mkdir /var/spool/cups/tmp

they may have already been there also another problem is permissions:

chmod 755 /var/spool/cups /var/spool/cups/tmp

then delete your printer and reconfigure it. Hope that helps a little more.

valmar · Post by **valmar** » Fri Jun 10, 2005 2:57 am

No change in behaviour sorry. the files we already there. Here is the complete log:

I [09/Jun/2005:21:53:30 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid
=8420)
I [09/Jun/2005:21:53:42 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=84
22)
I [09/Jun/2005:21:53:45 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=84
23)
I [09/Jun/2005:21:53:45 -0500] Printer 'a' deleted by 'root'.
I [09/Jun/2005:21:53:45 -0500] Saving printers.conf...
I [09/Jun/2005:21:53:46 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid
=8424)
I [09/Jun/2005:21:53:52 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid
=8425)
I [09/Jun/2005:21:53:54 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=84
26)
I [09/Jun/2005:21:54:02 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=84
27)
I [09/Jun/2005:21:54:09 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=84
28)
I [09/Jun/2005:21:54:12 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=84
29)
I [09/Jun/2005:21:54:21 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=84
30)
I [09/Jun/2005:21:54:21 -0500] Setting a device-uri to "hp:/usb/Deskjet_3740?ser
ial=CN45J3Y1D7040Q" (was "file:/dev/null".)
I [09/Jun/2005:21:54:21 -0500] Setting a printer-is-accepting-jobs to 1 (was 0.)
I [09/Jun/2005:21:54:21 -0500] Setting a printer-state to 3 (was 5.)
I [09/Jun/2005:21:54:21 -0500] Saving printers.conf...
I [09/Jun/2005:21:54:21 -0500] New printer 'a' added by 'root'.
I [09/Jun/2005:21:54:23 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid
=8431)
I [09/Jun/2005:21:54:26 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=8432)
I [09/Jun/2005:21:54:27 -0500] Adding start banner page "none" to job 8.
I [09/Jun/2005:21:54:27 -0500] Adding end banner page "none" to job 8.
I [09/Jun/2005:21:54:27 -0500] Job 8 queued on 'a' by 'root'.
I [09/Jun/2005:21:54:27 -0500] Started filter /usr/lib/cups/filter/pstops (PID 8433) for job 8.
I [09/Jun/2005:21:54:27 -0500] Started filter /usr/lib/cups/filter/foomatic-rip(PID 8434) for job 8.
I [09/Jun/2005:21:54:27 -0500] Started backend /usr/lib/cups/backend/hp (PID 8435) for job 8.
E [09/Jun/2005:21:54:27 -0500] PID 8434 stopped with status 22!
I [09/Jun/2005:21:54:27 -0500] Hint: Try setting the LogLevel to "debug" to find out more.
I [09/Jun/2005:21:54:29 -0500] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=8437)
I [09/Jun/2005:21:54:32 -0500] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=8438)
I [09/Jun/2005:21:54:34 -0500] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=8439)
E [09/Jun/2005:21:54:35 -0500] restart_job: job #8 cannot be restarted - no files!

valmar · Post by **valmar** » Fri Jun 10, 2005 3:10 am

I turned the log system to debug. Here is the output (long)

I [09/Jun/2005:22:04:34 -0500] Scheduler shutting down normally.
I [09/Jun/2005:22:04:35 -0500] Listening to 0:631
D [09/Jun/2005:22:04:35 -0500] AddLocation: added location '/'
D [09/Jun/2005:22:04:35 -0500] DenyIP: / deny 00000000/00000000
D [09/Jun/2005:22:04:35 -0500] AllowIP: / allow 7f000001/ffffffff
D [09/Jun/2005:22:04:35 -0500] AddLocation: added location '/admin'
D [09/Jun/2005:22:04:35 -0500] DenyIP: /admin deny 00000000/00000000
D [09/Jun/2005:22:04:35 -0500] AllowIP: /admin allow 7f000001/ffffffff
D [09/Jun/2005:22:04:35 -0500] AllowIP: /admin allow c0a80000/ffff0000
I [09/Jun/2005:22:04:35 -0500] Loaded configuration file "/etc/cups/cupsd.conf"
I [09/Jun/2005:22:04:35 -0500] Configured for up to 100 clients.
I [09/Jun/2005:22:04:35 -0500] Allowing up to 100 client connections per host.
I [09/Jun/2005:22:04:35 -0500] Full reload is required.
D [09/Jun/2005:22:04:35 -0500] LoadAllPrinters: Loading printer a...
D [09/Jun/2005:22:04:35 -0500] LoadDevices: Added device "ipp"...
D [09/Jun/2005:22:04:35 -0500] LoadDevices: Added device "lpd"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "scsi"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS0?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS1?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS2?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS3?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS4?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS5?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS6?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS7?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS8?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS9?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS10?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS11?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS12?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS13?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS14?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS15?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS16?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS17?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS18?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS19?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS20?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS21?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS22?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS23?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS24?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS25?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS26?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS27?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS28?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS29?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS30?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS31?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS32?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS33?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS34?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS35?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS36?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS37?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS38?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS39?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS40?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS41?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS42?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS43?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS44?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS45?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS46?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "serial:/dev/ttyS47?baud=115200"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "socket"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "usb://3745?serial=CN45J3Y1D7040Q"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "usb:/dev/usb/lp1"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "usb:/dev/usb/lp2"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "usb:/dev/usb/lp3"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "usb:/dev/usb/lp4"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "usb:/dev/usb/lp5"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "usb:/dev/usb/lp6"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "usb:/dev/usb/lp7"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "usb:/dev/usb/lp8"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "usb:/dev/usb/lp9"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "usb:/dev/usb/lp10"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "usb:/dev/usb/lp11"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "usb:/dev/usb/lp12"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "usb:/dev/usb/lp13"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "usb:/dev/usb/lp14"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "usb:/dev/usb/lp15"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "http"...
D [09/Jun/2005:22:04:36 -0500] LoadDevices: Added device "hp:/usb/Deskjet_3740?serial=CN45J3Y1D7040Q"...
I [09/Jun/2005:22:04:36 -0500] LoadPPDs: Read "/etc/cups/ppds.dat", 440 PPDs...
I [09/Jun/2005:22:04:36 -0500] LoadPPDs: No new or changed PPDs...
D [09/Jun/2005:22:04:36 -0500] LoadAllJobs: Scanning /var/spool/cups...
D [09/Jun/2005:22:04:36 -0500] LoadAllJobs: Loading attributes for job 8...
I [09/Jun/2005:22:04:36 -0500] Full reload complete.
D [09/Jun/2005:22:04:36 -0500] StartListening: NumListeners=1
E [09/Jun/2005:22:04:36 -0500] StartListening: Unable to find IP address for server name "gentoo" - Unknown host
D [09/Jun/2005:22:04:36 -0500] StartListening: address=00000000 port=631
D [09/Jun/2005:22:04:36 -0500] ResumeListening: setting input bits...
D [09/Jun/2005:22:05:17 -0500] AcceptClient: 5 from localhost:631.
D [09/Jun/2005:22:05:17 -0500] ReadClient: 5 GET /printers HTTP/1.1
D [09/Jun/2005:22:05:17 -0500] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 8618
I [09/Jun/2005:22:05:17 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=8618)
D [09/Jun/2005:22:05:17 -0500] SendCommand: 5 file=7
D [09/Jun/2005:22:05:17 -0500] AcceptClient: 8 from localhost:631.
D [09/Jun/2005:22:05:17 -0500] ReadClient: 8 POST / HTTP/1.1
D [09/Jun/2005:22:05:17 -0500] ProcessIPPRequest: 8 status_code=1
D [09/Jun/2005:22:05:17 -0500] ReadClient: 8 POST / HTTP/1.1
D [09/Jun/2005:22:05:17 -0500] ProcessIPPRequest: 8 status_code=1
D [09/Jun/2005:22:05:18 -0500] CloseClient: 8
D [09/Jun/2005:22:05:18 -0500] ReadClient: 5 GET /favicon.ico HTTP/1.1
D [09/Jun/2005:22:05:18 -0500] SendError: 5 code=404 (Not Found)
D [09/Jun/2005:22:05:18 -0500] CloseClient: 5
D [09/Jun/2005:22:05:20 -0500] AcceptClient: 5 from localhost:631.
D [09/Jun/2005:22:05:20 -0500] ReadClient: 5 GET /admin/?op=delete-printer&printer_name=a HTTP/1.1
D [09/Jun/2005:22:05:20 -0500] CGI /usr/lib/cups/cgi-bin/admin.cgi started - PID = 8620
I [09/Jun/2005:22:05:20 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=8620)
D [09/Jun/2005:22:05:20 -0500] SendCommand: 5 file=8
D [09/Jun/2005:22:05:20 -0500] AcceptClient: 7 from localhost:631.
D [09/Jun/2005:22:05:20 -0500] CloseClient: 7
D [09/Jun/2005:22:05:20 -0500] ReadClient: 5 GET /favicon.ico HTTP/1.1
D [09/Jun/2005:22:05:20 -0500] SendError: 5 code=404 (Not Found)
D [09/Jun/2005:22:05:20 -0500] CloseClient: 5
D [09/Jun/2005:22:05:21 -0500] AcceptClient: 5 from localhost:631.
D [09/Jun/2005:22:05:21 -0500] ReadClient: 5 GET /admin/?op=delete-printer&printer_name=a&confirm=yes HTTP/1.1
D [09/Jun/2005:22:05:21 -0500] CGI /usr/lib/cups/cgi-bin/admin.cgi started - PID = 8621
I [09/Jun/2005:22:05:21 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=8621)
D [09/Jun/2005:22:05:21 -0500] SendCommand: 5 file=8
D [09/Jun/2005:22:05:21 -0500] AcceptClient: 7 from localhost:631.
D [09/Jun/2005:22:05:21 -0500] ReadClient: 7 POST /admin/ HTTP/1.1
D [09/Jun/2005:22:05:21 -0500] SendError: 7 code=401 (Unauthorized)
D [09/Jun/2005:22:05:21 -0500] CloseClient: 7
D [09/Jun/2005:22:05:21 -0500] AcceptClient: 7 from localhost:631.
D [09/Jun/2005:22:05:21 -0500] ReadClient: 7 POST /admin/ HTTP/1.1
D [09/Jun/2005:22:05:21 -0500] CancelJob: id = 8
I [09/Jun/2005:22:05:21 -0500] Printer 'a' deleted by 'root'.
I [09/Jun/2005:22:05:21 -0500] Saving printers.conf...
D [09/Jun/2005:22:05:21 -0500] ProcessIPPRequest: 7 status_code=0
D [09/Jun/2005:22:05:21 -0500] CloseClient: 7
D [09/Jun/2005:22:05:22 -0500] ReadClient: 5 GET /favicon.ico HTTP/1.1
D [09/Jun/2005:22:05:22 -0500] SendError: 5 code=404 (Not Found)
D [09/Jun/2005:22:05:22 -0500] CloseClient: 5
D [09/Jun/2005:22:05:23 -0500] AcceptClient: 5 from localhost:631.
D [09/Jun/2005:22:05:23 -0500] ReadClient: 5 GET /printers HTTP/1.1
D [09/Jun/2005:22:05:23 -0500] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 8622
I [09/Jun/2005:22:05:23 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=8622)
D [09/Jun/2005:22:05:23 -0500] SendCommand: 5 file=8
D [09/Jun/2005:22:05:23 -0500] AcceptClient: 7 from localhost:631.
D [09/Jun/2005:22:05:23 -0500] ReadClient: 7 POST / HTTP/1.1
D [09/Jun/2005:22:05:23 -0500] ProcessIPPRequest: 7 status_code=406
D [09/Jun/2005:22:05:24 -0500] ReadClient: 7 POST / HTTP/1.1
D [09/Jun/2005:22:05:24 -0500] ProcessIPPRequest: 7 status_code=1
D [09/Jun/2005:22:05:24 -0500] CloseClient: 7
D [09/Jun/2005:22:05:24 -0500] ReadClient: 5 GET /favicon.ico HTTP/1.1
D [09/Jun/2005:22:05:24 -0500] SendError: 5 code=404 (Not Found)
D [09/Jun/2005:22:05:24 -0500] CloseClient: 5
D [09/Jun/2005:22:05:26 -0500] AcceptClient: 5 from localhost:631.
D [09/Jun/2005:22:05:26 -0500] ReadClient: 5 GET /admin/?op=add-printer HTTP/1.1
D [09/Jun/2005:22:05:26 -0500] CGI /usr/lib/cups/cgi-bin/admin.cgi started - PID = 8623
I [09/Jun/2005:22:05:26 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=8623)
D [09/Jun/2005:22:05:26 -0500] SendCommand: 5 file=8
D [09/Jun/2005:22:05:26 -0500] AcceptClient: 7 from localhost:631.
D [09/Jun/2005:22:05:26 -0500] CloseClient: 7
D [09/Jun/2005:22:05:26 -0500] ReadClient: 5 GET /favicon.ico HTTP/1.1
D [09/Jun/2005:22:05:26 -0500] SendError: 5 code=404 (Not Found)
D [09/Jun/2005:22:05:26 -0500] CloseClient: 5
D [09/Jun/2005:22:05:33 -0500] AcceptClient: 5 from localhost:631.
D [09/Jun/2005:22:05:33 -0500] ReadClient: 5 POST /admin HTTP/1.1
D [09/Jun/2005:22:05:33 -0500] CGI /usr/lib/cups/cgi-bin/admin.cgi started - PID = 8624
I [09/Jun/2005:22:05:33 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=8624)
D [09/Jun/2005:22:05:33 -0500] SendCommand: 5 file=8
D [09/Jun/2005:22:05:33 -0500] AcceptClient: 7 from localhost:631.
D [09/Jun/2005:22:05:33 -0500] ReadClient: 7 POST / HTTP/1.1
D [09/Jun/2005:22:05:33 -0500] ProcessIPPRequest: 7 status_code=0
D [09/Jun/2005:22:05:33 -0500] CloseClient: 7
D [09/Jun/2005:22:05:33 -0500] ReadClient: 5 GET /favicon.ico HTTP/1.1
D [09/Jun/2005:22:05:33 -0500] SendError: 5 code=404 (Not Found)
D [09/Jun/2005:22:05:33 -0500] CloseClient: 5
D [09/Jun/2005:22:05:37 -0500] AcceptClient: 5 from localhost:631.
D [09/Jun/2005:22:05:37 -0500] ReadClient: 5 POST /admin HTTP/1.1
D [09/Jun/2005:22:05:37 -0500] CGI /usr/lib/cups/cgi-bin/admin.cgi started - PID = 8625
I [09/Jun/2005:22:05:37 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=8625)
D [09/Jun/2005:22:05:37 -0500] SendCommand: 5 file=8
D [09/Jun/2005:22:05:37 -0500] AcceptClient: 7 from localhost:631.
D [09/Jun/2005:22:05:37 -0500] ReadClient: 7 POST / HTTP/1.1
D [09/Jun/2005:22:05:37 -0500] ProcessIPPRequest: 7 status_code=0
D [09/Jun/2005:22:05:37 -0500] CloseClient: 7
D [09/Jun/2005:22:05:37 -0500] ReadClient: 5 GET /favicon.ico HTTP/1.1
D [09/Jun/2005:22:05:37 -0500] SendError: 5 code=404 (Not Found)
D [09/Jun/2005:22:05:37 -0500] CloseClient: 5
D [09/Jun/2005:22:05:39 -0500] AcceptClient: 5 from localhost:631.
D [09/Jun/2005:22:05:39 -0500] ReadClient: 5 POST /admin HTTP/1.1
D [09/Jun/2005:22:05:39 -0500] CGI /usr/lib/cups/cgi-bin/admin.cgi started - PID = 8626
I [09/Jun/2005:22:05:39 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=8626)
D [09/Jun/2005:22:05:39 -0500] SendCommand: 5 file=8
D [09/Jun/2005:22:05:39 -0500] AcceptClient: 7 from localhost:631.
D [09/Jun/2005:22:05:39 -0500] ReadClient: 7 POST / HTTP/1.1
D [09/Jun/2005:22:05:39 -0500] ProcessIPPRequest: 7 status_code=0
D [09/Jun/2005:22:05:39 -0500] CloseClient: 7
D [09/Jun/2005:22:05:40 -0500] ReadClient: 5 GET /favicon.ico HTTP/1.1
D [09/Jun/2005:22:05:40 -0500] SendError: 5 code=404 (Not Found)
D [09/Jun/2005:22:05:40 -0500] CloseClient: 5
D [09/Jun/2005:22:05:46 -0500] AcceptClient: 5 from localhost:631.
D [09/Jun/2005:22:05:46 -0500] ReadClient: 5 POST /admin HTTP/1.1
D [09/Jun/2005:22:05:46 -0500] CGI /usr/lib/cups/cgi-bin/admin.cgi started - PID = 8627
I [09/Jun/2005:22:05:46 -0500] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=8627)
D [09/Jun/2005:22:05:46 -0500] SendCommand: 5 file=8
D [09/Jun/2005:22:05:46 -0500] AcceptClient: 7 from localhost:631.
D [09/Jun/2005:22:05:46 -0500] ReadClient: 7 POST /admin/ HTTP/1.1
D [09/Jun/2005:22:05:46 -0500] SendError: 7 code=401 (Unauthorized)
D [09/Jun/2005:22:05:46 -0500] CloseClient: 7
D [09/Jun/2005:22:05:46 -0500] AcceptClient: 7 from localhost:631.
D [09/Jun/2005:22:05:46 -0500] ReadClient: 7 POST /admin/ HTTP/1.1
I [09/Jun/2005:22:05:46 -0500] Setting aa device-uri to "hp:/usb/Deskjet_3740?serial=CN45J3Y1D7040Q" (was "file:/dev/null".)
I [09/Jun/2005:22:05:46 -0500] Setting aa printer-is-accepting-jobs to 1 (was 0.)
I [09/Jun/2005:22:05:46 -0500] Setting aa printer-state to 3 (was 5.)
D [09/Jun/2005:22:05:46 -0500] add_printer: Copied PPD file successfully!
I [09/Jun/2005:22:05:46 -0500] Saving printers.conf...
I [09/Jun/2005:22:05:46 -0500] New printer 'aa' added by 'root'.
D [09/Jun/2005:22:05:46 -0500] ProcessIPPRequest: 7 status_code=0
D [09/Jun/2005:22:05:46 -0500] CloseClient: 7
D [09/Jun/2005:22:05:46 -0500] ReadClient: 5 GET /favicon.ico HTTP/1.1
D [09/Jun/2005:22:05:46 -0500] SendError: 5 code=404 (Not Found)
D [09/Jun/2005:22:05:46 -0500] CloseClient: 5
D [09/Jun/2005:22:05:48 -0500] AcceptClient: 5 from localhost:631.
D [09/Jun/2005:22:05:48 -0500] ReadClient: 5 GET /printers HTTP/1.1
D [09/Jun/2005:22:05:48 -0500] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 8628
I [09/Jun/2005:22:05:48 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=8628)
D [09/Jun/2005:22:05:48 -0500] SendCommand: 5 file=8
D [09/Jun/2005:22:05:48 -0500] AcceptClient: 7 from localhost:631.
D [09/Jun/2005:22:05:48 -0500] ReadClient: 7 POST / HTTP/1.1
D [09/Jun/2005:22:05:48 -0500] ProcessIPPRequest: 7 status_code=1
D [09/Jun/2005:22:05:49 -0500] ReadClient: 7 POST / HTTP/1.1
D [09/Jun/2005:22:05:49 -0500] ProcessIPPRequest: 7 status_code=1
D [09/Jun/2005:22:05:49 -0500] CloseClient: 7
D [09/Jun/2005:22:05:49 -0500] ReadClient: 5 GET /favicon.ico HTTP/1.1
D [09/Jun/2005:22:05:49 -0500] SendError: 5 code=404 (Not Found)
D [09/Jun/2005:22:05:49 -0500] CloseClient: 5
D [09/Jun/2005:22:05:51 -0500] AcceptClient: 5 from localhost:631.
D [09/Jun/2005:22:05:51 -0500] ReadClient: 5 GET /printers/aa?op=print-test-page HTTP/1.1
D [09/Jun/2005:22:05:51 -0500] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 8629
I [09/Jun/2005:22:05:51 -0500] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=8629)
D [09/Jun/2005:22:05:51 -0500] SendCommand: 5 file=8
D [09/Jun/2005:22:05:51 -0500] AcceptClient: 7 from localhost:631.
D [09/Jun/2005:22:05:51 -0500] ReadClient: 7 POST /printers/aa HTTP/1.1
D [09/Jun/2005:22:05:51 -0500] print_job: request file type is application/postscript.
D [09/Jun/2005:22:05:51 -0500] check_quotas: requesting-user-name = 'root'
D [09/Jun/2005:22:05:51 -0500] print_job: requesting-user-name = 'root'
D [09/Jun/2005:22:05:51 -0500] Adding default job-sheets values "none,none"...
I [09/Jun/2005:22:05:51 -0500] Adding start banner page "none" to job 9.
I [09/Jun/2005:22:05:51 -0500] Adding end banner page "none" to job 9.
I [09/Jun/2005:22:05:51 -0500] Job 9 queued on 'aa' by 'root'.
D [09/Jun/2005:22:05:51 -0500] Job 9 hold_until = 0
D [09/Jun/2005:22:05:51 -0500] StartJob(9, 0x8094628)
D [09/Jun/2005:22:05:51 -0500] StartJob() id = 9, file = 0/1
D [09/Jun/2005:22:05:51 -0500] job-sheets=none,none
D [09/Jun/2005:22:05:51 -0500] banner_page = 0
D [09/Jun/2005:22:05:51 -0500] StartJob: argv = "aa","9","root","Test Page","1","","/var/spool/cups/d00009-001"
D [09/Jun/2005:22:05:51 -0500] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [09/Jun/2005:22:05:51 -0500] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [09/Jun/2005:22:05:51 -0500] StartJob: envp[2]="USER=root"
D [09/Jun/2005:22:05:51 -0500] StartJob: envp[3]="CHARSET=utf-8"
D [09/Jun/2005:22:05:51 -0500] StartJob: envp[4]="LANG=en_US"
D [09/Jun/2005:22:05:51 -0500] StartJob: envp[5]="PPD=/etc/cups/ppd/aa.ppd"
D [09/Jun/2005:22:05:51 -0500] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [09/Jun/2005:22:05:51 -0500] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [09/Jun/2005:22:05:51 -0500] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [09/Jun/2005:22:05:51 -0500] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [09/Jun/2005:22:05:51 -0500] StartJob: envp[10]="DEVICE_URI=hp:/usb/Deskjet_3740?serial=CN45J3Y1D7040Q"
D [09/Jun/2005:22:05:51 -0500] StartJob: envp[11]="PRINTER=aa"
D [09/Jun/2005:22:05:51 -0500] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [09/Jun/2005:22:05:51 -0500] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [09/Jun/2005:22:05:51 -0500] StartJob: envp[14]="CUPS_SERVER=localhost"
D [09/Jun/2005:22:05:51 -0500] StartJob: envp[15]="IPP_PORT=631"
D [09/Jun/2005:22:05:51 -0500] StartJob: statusfds = [ 9 10 ]
D [09/Jun/2005:22:05:51 -0500] StartJob: filterfds[1] = [ 11 -1 ]
D [09/Jun/2005:22:05:51 -0500] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [09/Jun/2005:22:05:51 -0500] StartJob: filterfds[0] = [ 12 13 ]
D [09/Jun/2005:22:05:51 -0500] start_process("/usr/lib/cups/filter/pstops", 0xbffefc30, 0xbffeefa0, 11, 13, 10)
I [09/Jun/2005:22:05:51 -0500] Started filter /usr/lib/cups/filter/pstops (PID 8630) for job 9.
D [09/Jun/2005:22:05:51 -0500] StartJob: filter = "/usr/lib/cups/filter/foomatic-rip"
D [09/Jun/2005:22:05:51 -0500] StartJob: filterfds[1] = [ 11 14 ]
D [09/Jun/2005:22:05:51 -0500] start_process("/usr/lib/cups/filter/foomatic-rip", 0xbffefc30, 0xbffeefa0, 12, 14, 10)
I [09/Jun/2005:22:05:51 -0500] Started filter /usr/lib/cups/filter/foomatic-rip (PID 8631) for job 9.
D [09/Jun/2005:22:05:51 -0500] StartJob: backend = "/usr/lib/cups/backend/hp"
D [09/Jun/2005:22:05:51 -0500] StartJob: filterfds[0] = [ -1 12 ]
D [09/Jun/2005:22:05:51 -0500] start_process("/usr/lib/cups/backend/hp", 0xbffefc30, 0xbffeefa0, 11, 12, 10)
I [09/Jun/2005:22:05:51 -0500] Started backend /usr/lib/cups/backend/hp (PID 8632) for job 9.
D [09/Jun/2005:22:05:51 -0500] ProcessIPPRequest: 7 status_code=0
E [09/Jun/2005:22:05:51 -0500] PID 8631 stopped with status 22!
D [09/Jun/2005:22:05:51 -0500] [Job 9] /usr/lib/cups/filter/foomatic-rip: No such file or directory
D [09/Jun/2005:22:05:51 -0500] CloseClient: 7
D [09/Jun/2005:22:05:51 -0500] [Job 9] Page = 612x792; 18,36 to 594,783
D [09/Jun/2005:22:05:51 -0500] [Job 9] slowcollate=0, slowduplex=0, sloworder=0
D [09/Jun/2005:22:05:51 -0500] [Job 9] 0 %%BoundingBox: 0 0 612 792
D [09/Jun/2005:22:05:51 -0500] [Job 9] 0 %%Pages: 1
D [09/Jun/2005:22:05:51 -0500] [Job 9] 0 %%LanguageLevel: 1
D [09/Jun/2005:22:05:51 -0500] [Job 9] 0 %%DocumentData: Clean7Bit
D [09/Jun/2005:22:05:51 -0500] [Job 9] 0 %%DocumentSuppliedResources: procset testprint/1.1
D [09/Jun/2005:22:05:51 -0500] [Job 9] 0 %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman
D [09/Jun/2005:22:05:51 -0500] [Job 9] 0 %%Creator: Michael Sweet, Easy Software Products
D [09/Jun/2005:22:05:51 -0500] [Job 9] 0 %%CreationDate: May 11, 1999
D [09/Jun/2005:22:05:51 -0500] [Job 9] 0 %%Title: Test Page
D [09/Jun/2005:22:05:51 -0500] [Job 9] 0 %%EndComments
D [09/Jun/2005:22:05:51 -0500] [Job 9] 0 %%BeginProlog
D [09/Jun/2005:22:05:51 -0500] [Job 9] 0 %%BeginResource procset testprint 1.1 0
D [09/Jun/2005:22:05:51 -0500] [Job 9] 0 %%EndResource
D [09/Jun/2005:22:05:51 -0500] [Job 9] 0 %%EndProlog
D [09/Jun/2005:22:05:51 -0500] [Job 9] 0 %%Page: 1 1
D [09/Jun/2005:22:05:51 -0500] [Job 9] 0 %%Page: 1 1
D [09/Jun/2005:22:05:51 -0500] [Job 9] pw = 576.0, pl = 747.0
D [09/Jun/2005:22:05:51 -0500] [Job 9] PageLeft = 18.0, PageRight = 594.0
D [09/Jun/2005:22:05:51 -0500] [Job 9] PageTop = 783.0, PageBottom = 36.0
D [09/Jun/2005:22:05:51 -0500] [Job 9] PageWidth = 612.0, PageLength = 792.0
D [09/Jun/2005:22:05:51 -0500] [Job 9] 0 %%EOF
D [09/Jun/2005:22:05:51 -0500] [Job 9] Saw EOF!
D [09/Jun/2005:22:05:51 -0500] AcceptClient: 7 from localhost:631.
D [09/Jun/2005:22:05:51 -0500] ReadClient: 7 POST / HTTP/1.1
D [09/Jun/2005:22:05:51 -0500] ProcessIPPRequest: 7 status_code=1
D [09/Jun/2005:22:05:51 -0500] ReadClient: 7 POST / HTTP/1.1
D [09/Jun/2005:22:05:51 -0500] ProcessIPPRequest: 7 status_code=1
D [09/Jun/2005:22:05:52 -0500] ReadClient: 5 GET /favicon.ico HTTP/1.1
D [09/Jun/2005:22:05:52 -0500] SendError: 5 code=404 (Not Found)
D [09/Jun/2005:22:05:52 -0500] CloseClient: 5
D [09/Jun/2005:22:05:52 -0500] ReadClient: 7 POST / HTTP/1.1
D [09/Jun/2005:22:05:52 -0500] ProcessIPPRequest: 7 status_code=1
D [09/Jun/2005:22:05:52 -0500] UpdateJob: job 9, file 0 is complete.
D [09/Jun/2005:22:05:52 -0500] CancelJob: id = 9
D [09/Jun/2005:22:05:52 -0500] StopJob: id = 9, force = 0
D [09/Jun/2005:22:05:52 -0500] StopJob: printer state is 3
D [09/Jun/2005:22:05:52 -0500] ReadClient: 7 POST / HTTP/1.1
D [09/Jun/2005:22:05:52 -0500] ProcessIPPRequest: 7 status_code=1

valmar · Post by **valmar** » Fri Jun 10, 2005 3:34 am

I created the link to the foomatic-rip files. Now it still doesn't print but the job stays in processing queue

The log output:

Parameter Summary
D [09/Jun/2005:22:31:10 -0500] [Job 12] -----------------
D [09/Jun/2005:22:31:10 -0500] [Job 12]
D [09/Jun/2005:22:31:10 -0500] [Job 12] Spooler: cups
D [09/Jun/2005:22:31:10 -0500] [Job 12] Printer: a
D [09/Jun/2005:22:31:10 -0500] [Job 12] PPD file: /etc/cups/ppd/a.ppd
D [09/Jun/2005:22:31:10 -0500] [Job 12] Printer model: HP DeskJet 3740 Foomatic/hpijs (recommended)
D [09/Jun/2005:22:31:10 -0500] [Job 12] Job title: Test Page
D [09/Jun/2005:22:31:10 -0500] [Job 12] File(s) to be printed:
D [09/Jun/2005:22:31:10 -0500] [Job 12] <STDIN>
D [09/Jun/2005:22:31:10 -0500] [Job 12]
D [09/Jun/2005:22:31:10 -0500] [Job 12]
D [09/Jun/2005:22:31:10 -0500] [Job 12] ================================================
D [09/Jun/2005:22:31:10 -0500] [Job 12]
D [09/Jun/2005:22:31:10 -0500] [Job 12] File: <STDIN>
D [09/Jun/2005:22:31:10 -0500] [Job 12]
D [09/Jun/2005:22:31:10 -0500] [Job 12] ================================================
D [09/Jun/2005:22:31:10 -0500] [Job 12]
D [09/Jun/2005:22:31:10 -0500] [Job 12] Reading PostScript input ...
D [09/Jun/2005:22:31:10 -0500] [Job 12] --> This document is DSC-conforming!
D [09/Jun/2005:22:31:10 -0500] [Job 12]
D [09/Jun/2005:22:31:10 -0500] [Job 12] -----------
D [09/Jun/2005:22:31:10 -0500] [Job 12] Found: %%BeginProlog
D [09/Jun/2005:22:31:10 -0500] [Job 12] Found: %%EndProlog
D [09/Jun/2005:22:31:10 -0500] [Job 12]
D [09/Jun/2005:22:31:10 -0500] [Job 12] -----------
D [09/Jun/2005:22:31:10 -0500] [Job 12] Found: %%BeginSetup
D [09/Jun/2005:22:31:10 -0500] [Job 12] Inserting PostScript code for CUPS' page accounting
D [09/Jun/2005:22:31:10 -0500] [Job 12] Found: %%BeginFeature: *PrintoutMode Normal
D [09/Jun/2005:22:31:10 -0500] [Job 12] Option: PrintoutMode=Normal --> Setting option
D [09/Jun/2005:22:31:10 -0500] [Job 12] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal
D [09/Jun/2005:22:31:10 -0500] [Job 12] Option: PrintoutMode=Normal --> Setting option
D [09/Jun/2005:22:31:10 -0500] [Job 12] Found: %%BeginFeature: *Quality FromPrintoutMode
D [09/Jun/2005:22:31:10 -0500] [Job 12] Option: Quality=FromPrintoutMode --> Setting option
D [09/Jun/2005:22:31:10 -0500] [Job 12] Found: %% FoomaticRIPOptionSetting: Quality=@PrintoutMode
D [09/Jun/2005:22:31:10 -0500] [Job 12] Option: Quality=FromPrintoutMode --> Setting option
D [09/Jun/2005:22:31:10 -0500] [Job 12] Found: %%BeginFeature: *PageSize Letter
D [09/Jun/2005:22:31:10 -0500] [Job 12] Option: PageSize=Letter --> Setting option
D [09/Jun/2005:22:31:10 -0500] [Job 12] Found: %% FoomaticRIPOptionSetting: PageSize=Letter
D [09/Jun/2005:22:31:10 -0500] [Job 12] Option: PageSize=Letter --> Setting option
D [09/Jun/2005:22:31:10 -0500] [Job 12] Found: %%EndSetup
D [09/Jun/2005:22:31:10 -0500] [Job 12]
D [09/Jun/2005:22:31:10 -0500] [Job 12] -----------
D [09/Jun/2005:22:31:10 -0500] [Job 12] New page: 1 1
D [09/Jun/2005:22:31:10 -0500] [Job 12] Inserting option code into "PageSetup" section.
D [09/Jun/2005:22:31:10 -0500] [Job 12] No page header or page header not DSC-conforming
D [09/Jun/2005:22:31:10 -0500] [Job 12] Stopping search for page header options
D [09/Jun/2005:22:31:10 -0500] [Job 12] Found:
D [09/Jun/2005:22:31:10 -0500] [Job 12] pageHeight sub % Move down...
D [09/Jun/2005:22:31:10 -0500] [Job 12] --> Output goes directly to the renderer now.
D [09/Jun/2005:22:31:10 -0500] [Job 12]
D [09/Jun/2005:22:31:10 -0500] [Job 12]
D [09/Jun/2005:22:31:10 -0500] [Job 12] Starting renderer
D [09/Jun/2005:22:31:10 -0500] [Job 12] JCL: <job data>
D [09/Jun/2005:22:31:10 -0500] [Job 12]
D [09/Jun/2005:22:31:10 -0500] [Job 12] renderer PID kid4=9735
D [09/Jun/2005:22:31:10 -0500] [Job 12] 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 [09/Jun/2005:22:31:10 -0500] [Job 12]
D [09/Jun/2005:22:31:10 -0500] [Job 12] Closing renderer
D [09/Jun/2005:22:31:10 -0500] [Job 12] 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=| cat >&3' '/dev/fd/0' 3>&1 1>&2

valmar · Post by **valmar** » Fri Jun 10, 2005 9:49 am

the problem seems to be ghostscript. Actually when I type in directly the gs command line, the command just keeps executing without even finishing, and the machine just hangs there. Anyone has any idea?

Thank you

Valerio

ssweber · Post by **ssweber** » Sat Jun 11, 2005 3:04 am

Hey,

Valmar, what link did you create to the foomatic-rip?

::I created the link to the foomatic-rip files. Now it still doesn't print but the job stays in processing queue ::

Hopefully some guroo can tell what the problem is

P.S. I just upgrade to hplip in portage (0.9.3) from the old one in bugs database (0.9.2) Could that of caused the problem?

valmar · Post by **valmar** » Sat Jun 11, 2005 5:54 am

Sorry I am away from my home pc for 3-4 days. I'll get back to it ASAP

Thank you

Valerio

Druid_cz · Post by **Druid_cz** » Sun Jun 12, 2005 1:17 pm

I have the same problem with HP PSC 1315.
Previously hplip compiled from hplip.sf.net worked fine, but these from ebuild not

Druid_cz · Post by **Druid_cz** » Sun Jun 12, 2005 1:28 pm

Ok, I did

ln -s /usr/bin/foomatic-rip /usr/lib/cups/filter/foomatic-rip
chmod g+w /var/spool/cups /var/spool/cups/tmp

but now the job is "processing" forever...and hpijs is eating cpu time forever

Druid_cz · Post by **Druid_cz** » Sun Jun 12, 2005 1:32 pm

Should we post this bug to some maintainers?

RobB_NZ · Post by **RobB_NZ** » Mon Jun 13, 2005 7:13 am

Druid_cz, I had exactly the same situation as you: printing a job created a hpijs process that ate all CPU time and never actually printed anything.

Then, I emerged hpijs (1.7.1) without unmerging hplip (0.9.3) - there was a warning about hpijs being deprecated but it merged fine, and now my printer (PSC1350) prints and scans with no problems.

However, when I do "emerge sync" and "emerge -upD world", I get a Blocker error about hpijs blocking hplip. I don't actually mind this too much, when a new version of hplip is released I'll unmerge hpijs before updating hplip.

Maybe you could try this as well?

I'm off to log a bug in bugzilla.

RobB_NZ · Post by **RobB_NZ** » Mon Jun 13, 2005 10:55 am

My bug is 95953. Hope it helps someone else as well

ssweber · Post by **ssweber** » Tue Jun 14, 2005 7:24 pm

Ok, I downgraded to hplip 0.9.2, from and old ebuild in gentoo. The device is under the usb section of cups. And it works, So im happy!

heres the ebuild:

hplip-0.9.2.ebuild
# Copyright 1999-2005 Gentoo Foundation
# Distributed under the terms of the GNU General Public License v2
# $Header: $

inherit eutils

DESCRIPTION="HP Linux Imaging and Printing System. Includes net-print/hpijs, scanner drivers and service tools."
HOMEPAGE="http://hpinkjet.sourceforge.net/"
SRC_URI="mirror://sourceforge/hpinkjet/${P}.tar.gz"
LICENSE="GPL MIT BSD"
SLOT="0"
KEYWORDS="~x86"
#IUSE="cups foomaticdb network X"
IUSE="foomaticdb network X"

# !net-print/hpijs - as this package contains hpijs (but which version? :/)
DEPEND="
>=dev-lang/python-2.2.0
!net-print/hpijs
>=net-print/cups-1.1.18-r2
network? ( >=net-analyzer/net-snmp-5.0.9 )"

RDEPEND="
>=app-text/ghostscript-7.05
foomaticdb? ( >=net-print/foomatic-db-engine-3.0.2
>=net-print/foomatic-filters-3.0.2 )
X? ( >=dev-python/PyQt-3.11 )"

src_unpack() {
unpack ${A}

cd ${WORKDIR}
# fixes a the bug mentioned here:
# http://sourceforge.net/forum/forum.php? ... m_id=53438
# epatch ${FILESDIR}/hplip-0.9.1-qsizepolicy-patch
# -> fixed in 0.9.2
}

src_compile() {
# cups is build-time dependency no matter if you --disable-cups-install
# or --enable.. it (is it a bug to report upstream? :/)
# so we leave the default ./configure setting of --enable-cups-install
# (but is it the Gentoo-way :/)
#use cups || EXTRA_ECONF="${EXTRA_ECONF} --disable-cups-install"
use foomaticdb || EXTRA_ECONF="${EXTRA_ECONF} --disable-foomatic-install"
use network || EXTRA_ECONF="${EXTRA_ECONF} --disable-network-build"
echo EXTRA_CONF
econf || die "Error: econf failed!"

emake || die "Error: emake failed!"
}

src_install() {
# this should work now (since hplip-0.9.0.2)
make install DESTDIR=${D}

# install Gentoo-specific init script
exeinto /etc/init.d
newexe ${FILESDIR}/hplip.init.d hplip

# for scanner support:
insinto /etc/sane.d
echo "hpaio" > dll.conf
doins dll.conf

dodir /usr/lib/sane
dosym /usr/lib/libsane-hpaio.la /usr/lib/sane/libsane-hpaio.la
dosym /usr/lib/libsane-hpaio.so /usr/lib/sane/libsane-hpaio.so
dosym /usr/lib/libsane-hpaio.so.1 /usr/lib/sane/libsane-hpaio.so.1
dosym /usr/lib/libsane-hpaio.so.1.0.0 /usr/lib/sane/libsane-hpaio.so.1.0.0
}

hplip.init.d:

#!/sbin/runscript
# Copyright 1999-2005 Gentoo Foundation
# Distributed under the terms of the GNU General Public License, v2 or later
# $Header: $

depend() {
before cupsd
after hotplug
}

start() {
ebegin "Starting hpiod"
start-stop-daemon --start --quiet --exec /usr/sbin/hpiod
eend $?

ebegin "Starting hpssd"
start-stop-daemon --quiet --start --exec /usr/share/hplip/hpssd.py \
--pidfile /var/run/hpssd.pid >/dev/null 2>&1
eend $?
}

stop() {
ebegin "Stopping hpiod"
start-stop-daemon --stop --quiet -n hpiod
eend $?

ebegin "Stopping hpssd"
start-stop-daemon --stop --pidfile /var/run/hpssd.pid
RETVAL=$?
for PIDFILE in /var/run/*; do
case "$( basename $PIDFILE )" in
hpguid-*.pid)
read PID < $PIDFILE
kill $PID
rm $PIDFILE
esac
done
eend $RETVAL
}

Cheers!
-Sam (ill post this to the bug report)

emerged hplip - cups works, hplip not

emerged hplip - cups works, hplip not

I have the same problem

Working!