cups fails to print on OfficeJet G55 via hpoj (usb)

Patrick <Nowhere> patrick at
Wed Nov 24 08:18:46 PST 2004


Hello!

I am running a gentoo with a 2.6.8 kernel. My printer is a HP OfficeJet G55
I installed the following packages:

libusb 0.1.7-r1
cups 1.1.20-r5
hpoj 0.91-r3
foomatic 3.0.2
hpijs 1.5
ghostscript -7.07.1-r7

Everything compiled well (once I found out that hpoj only compiles if libusb 
is downgraded from 0.1.8 to 0.1.7-r1)
I installed the usb printer following the hpoj documentation with ptal-init 
setup and ptal-init start.
I can print directly by using:

 ptal-connect HP-OfficeJet_G55 -print (.......pipe textfile or 
whatever.......)

I created a foomatic ppd file using the following command:

foomatic-configure -s cups -p HP-OfficeJet_G55 -c 
ptal:/mlc:usb:OfficeJet_G55 -n MyPrintQueue -d hpijs

When I connect to the webinterface I can see the printer, I can configure it 
to take A4 instead of Letter (Should prove that it reads the ppd, shouldn't 
it?)
BUT I can't print a testpage.

Strangely the Job just dies. If I go to the Show Completed Jobs I can see 
that they were "aborted".

Here' my error_log from cups:
(ps i took the basic auth string out)

d [24/Nov/2004:18:05:03 +0000] ReadClient() 7, used=0
D [24/Nov/2004:18:05:03 +0000] ReadClient() 7 GET 
/printers/HPOJ_Printer_Queue?op=print-test-page HTTP/1.1
d [24/Nov/2004:18:05:03 +0000] decode_auth(0xb7be9c38): Authorization string 
= "Basic xxxxreplacedxxxx"
d [24/Nov/2004:18:05:03 +0000] decode_auth() 7 username="root"
d [24/Nov/2004:18:05:03 +0000] IsAuthorized: con->uri = 
"/printers/HPOJ_Printer_Queue?op=print-test-page"
d [24/Nov/2004:18:05:03 +0000] FindBest: uri = 
"/printers/HPOJ_Printer_Queue?op=print-test-page"...
d [24/Nov/2004:18:05:03 +0000] FindBest: Location / Limit 7f
d [24/Nov/2004:18:05:03 +0000] FindBest: Location /admin Limit 7f
d [24/Nov/2004:18:05:03 +0000] FindBest: best = "/"
d [24/Nov/2004:18:05:03 +0000] IsAuthorized: auth = 0, satisfy=0...
d [24/Nov/2004:18:05:03 +0000] argv[0] = "HPOJ_Printer_Queue"
d [24/Nov/2004:18:05:03 +0000] envp[0] = "PATH=/bin:/usr/bin"
d [24/Nov/2004:18:05:03 +0000] envp[1] = "SERVER_SOFTWARE=CUPS/1.1"
d [24/Nov/2004:18:05:03 +0000] envp[2] = "GATEWAY_INTERFACE=CGI/1.1"
d [24/Nov/2004:18:05:03 +0000] envp[3] = "SERVER_PROTOCOL=HTTP/1.1"
d [24/Nov/2004:18:05:03 +0000] envp[4] = "REDIRECT_STATUS=1"
d [24/Nov/2004:18:05:03 +0000] envp[5] = "CUPS_SERVER=localhost"
d [24/Nov/2004:18:05:03 +0000] envp[6] = "IPP_PORT=631"
d [24/Nov/2004:18:05:03 +0000] envp[7] = "SERVER_NAME=gwynaheir"
d [24/Nov/2004:18:05:03 +0000] envp[8] = "SERVER_PORT=631"
d [24/Nov/2004:18:05:03 +0000] envp[9] = "REMOTE_ADDR=192.168.0.1"
d [24/Nov/2004:18:05:03 +0000] envp[10] = "REMOTE_HOST=gwynaheir"
d [24/Nov/2004:18:05:03 +0000] envp[11] = "REMOTE_USER=root"
d [24/Nov/2004:18:05:03 +0000] envp[12] = "LANG=en_US.UTF-8"
d [24/Nov/2004:18:05:03 +0000] envp[13] = ""
d [24/Nov/2004:18:05:03 +0000] envp[14] = "TMPDIR=/var/spool/cups/tmp"
d [24/Nov/2004:18:05:03 +0000] envp[15] = "CUPS_DATADIR=/usr/share/cups"
d [24/Nov/2004:18:05:03 +0000] envp[16] = "CUPS_SERVERROOT=/etc/cups"
d [24/Nov/2004:18:05:03 +0000] envp[17] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; 
U; Linux i686; en-US; rv:1.6) Gecko/20040509 Firefox/0.8"
d [24/Nov/2004:18:05:03 +0000] envp[18] = 
"SCRIPT_NAME=/printers/HPOJ_Printer_Queue"
d [24/Nov/2004:18:05:03 +0000] envp[19] = "REQUEST_METHOD=GET"
d [24/Nov/2004:18:05:03 +0000] envp[20] = "QUERY_STRING=op=print-test-page"
d [24/Nov/2004:18:05:03 +0000] AddCert: adding certificate for pid 10540
D [24/Nov/2004:18:05:03 +0000] CGI /usr/lib/cups/cgi-bin/printers.cgi 
started - PID = 10540
I [24/Nov/2004:18:05:03 +0000] Started "/usr/lib/cups/cgi-bin/printers.cgi" 
(pid=10540)
D [24/Nov/2004:18:05:03 +0000] SendCommand() 7 file=9
d [24/Nov/2004:18:05:03 +0000] SendCommand: Adding fd 9 to InputSet...
d [24/Nov/2004:18:05:03 +0000] SendCommand: Adding fd 7 to OutputSet...
d [24/Nov/2004:18:05:03 +0000] WriteClient() 7 Content-Type: 
text/html;charset=utf-8
d [24/Nov/2004:18:05:03 +0000] WriteClient() 7
d [24/Nov/2004:18:05:03 +0000] WriteClient() 7 1193 bytes
d [24/Nov/2004:18:05:03 +0000] AcceptClient(0x8088ec8) 0 NumClients = 2
D [24/Nov/2004:18:05:03 +0000] AcceptClient() 8 from localhost:631.
d [24/Nov/2004:18:05:03 +0000] AcceptClient: Adding fd 8 to InputSet...
d [24/Nov/2004:18:05:03 +0000] ReadClient() 8, used=0
D [24/Nov/2004:18:05:03 +0000] ReadClient() 8 POST 
/printers/HPOJ_Printer_Queue HTTP/1.1
d [24/Nov/2004:18:05:03 +0000] decode_auth(0xb7bec868): Authorization string 
= ""
d [24/Nov/2004:18:05:03 +0000] decode_auth() 8 username=""
d [24/Nov/2004:18:05:03 +0000] IsAuthorized: con->uri = 
"/printers/HPOJ_Printer_Queue"
d [24/Nov/2004:18:05:03 +0000] FindBest: uri = 
"/printers/HPOJ_Printer_Queue"...
d [24/Nov/2004:18:05:03 +0000] FindBest: Location / Limit 7f
d [24/Nov/2004:18:05:03 +0000] FindBest: Location /admin Limit 7f
d [24/Nov/2004:18:05:03 +0000] FindBest: best = "/"
d [24/Nov/2004:18:05:03 +0000] IsAuthorized: auth = 0, satisfy=0...
d [24/Nov/2004:18:05:03 +0000] POST /printers/HPOJ_Printer_Queue
d [24/Nov/2004:18:05:03 +0000] CONTENT_TYPE = application/ipp
d [24/Nov/2004:18:05:03 +0000] ReadClient() 8 con->data_encoding = length, 
con->data_remaining = 14781, con->file = 0
d [24/Nov/2004:18:05:03 +0000] ReadClient() 8 REQUEST 
/var/spool/cups/00000000=10
d [24/Nov/2004:18:05:03 +0000] ReadClient() 8 writing 1703 bytes to 10
d [24/Nov/2004:18:05:03 +0000] ReadClient() 8, used=0
d [24/Nov/2004:18:05:03 +0000] ReadClient() 8 con->data_encoding = length, 
con->data_remaining = 12850, con->file = 10
d [24/Nov/2004:18:05:03 +0000] ReadClient() 8 writing 12850 bytes to 10
d [24/Nov/2004:18:05:03 +0000] ReadClient() 8 Closing data file 10, size = 
14553.
d [24/Nov/2004:18:05:03 +0000] ProcessIPPRequest(0xb7bec868[8]): 
operation_id = 0002
d [24/Nov/2004:18:05:03 +0000] print_job(0xb7bec868[8], 
ipp://localhost/printers/HPOJ_Printer_Queue)
D [24/Nov/2004:18:05:03 +0000] print_job: request file type is 
application/postscript.
d [24/Nov/2004:18:05:03 +0000] check_quotas(0xb7bec868[8], 
0x8093a50[HPOJ_Printer_Queue])
D [24/Nov/2004:18:05:03 +0000] check_quotas: requesting-user-name = 'root'
D [24/Nov/2004:18:05:03 +0000] print_job: requesting-user-name = 'root'
D [24/Nov/2004:18:05:03 +0000] Adding default job-sheets values 
"none,none"...
I [24/Nov/2004:18:05:03 +0000] Adding start banner page "none" to job 4.
d [24/Nov/2004:18:05:03 +0000] copy_banner(0xb7bec868[8], 0x8165838[4], 
none)
d [24/Nov/2004:18:05:03 +0000] add_file(con=0xb7bec868[8], job=4, 
filetype=application/postscript, compression=0)
I [24/Nov/2004:18:05:03 +0000] Adding end banner page "none" to job 4.
d [24/Nov/2004:18:05:03 +0000] copy_banner(0xb7bec868[8], 0x8165838[4], 
none)
I [24/Nov/2004:18:05:03 +0000] Job 4 queued on 'HPOJ_Printer_Queue' by 
'root'.
D [24/Nov/2004:18:05:03 +0000] Job 4 hold_until = 0
d [24/Nov/2004:18:05:03 +0000] SaveJob: Closing file 10...
D [24/Nov/2004:18:05:03 +0000] StartJob(4, 0x8093a50)
D [24/Nov/2004:18:05:03 +0000] StartJob() id = 4, file = 0/1
D [24/Nov/2004:18:05:03 +0000] job-sheets=none,none
D [24/Nov/2004:18:05:03 +0000] banner_page = 0
D [24/Nov/2004:18:05:03 +0000] StartJob: argv = 
"HPOJ_Printer_Queue","4","root","Test 
Page","1","","/var/spool/cups/d00004-001"
D [24/Nov/2004:18:05:03 +0000] StartJob: 
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [24/Nov/2004:18:05:03 +0000] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [24/Nov/2004:18:05:03 +0000] StartJob: envp[2]="USER=root"
D [24/Nov/2004:18:05:03 +0000] StartJob: envp[3]="CHARSET=utf-8"
D [24/Nov/2004:18:05:03 +0000] StartJob: envp[4]="LANG=en_US"
D [24/Nov/2004:18:05:03 +0000] StartJob: 
envp[5]="PPD=/etc/cups/ppd/HPOJ_Printer_Queue.ppd"
D [24/Nov/2004:18:05:03 +0000] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [24/Nov/2004:18:05:03 +0000] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [24/Nov/2004:18:05:03 +0000] StartJob: 
envp[8]="TMPDIR=/var/spool/cups/tmp"
D [24/Nov/2004:18:05:03 +0000] StartJob: 
envp[9]="CONTENT_TYPE=application/postscript"
D [24/Nov/2004:18:05:03 +0000] StartJob: 
envp[10]="DEVICE_URI=ptal:/mlc:usb:OfficeJet_G55"
D [24/Nov/2004:18:05:03 +0000] StartJob: 
envp[11]="PRINTER=HPOJ_Printer_Queue"
D [24/Nov/2004:18:05:03 +0000] StartJob: 
envp[12]="CUPS_DATADIR=/usr/share/cups"
D [24/Nov/2004:18:05:03 +0000] StartJob: 
envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [24/Nov/2004:18:05:03 +0000] StartJob: envp[14]="CUPS_SERVER=localhost"
D [24/Nov/2004:18:05:03 +0000] StartJob: envp[15]="IPP_PORT=631"
d [24/Nov/2004:18:05:03 +0000] StartJob: Allocating status buffer...
D [24/Nov/2004:18:05:03 +0000] StartJob: statusfds = [ 10 11 ]
D [24/Nov/2004:18:05:03 +0000] StartJob: filterfds[1] = [ 12 -1 ]
D [24/Nov/2004:18:05:03 +0000] StartJob: filter = 
"/usr/lib/cups/filter/pstops"
D [24/Nov/2004:18:05:03 +0000] StartJob: filterfds[0] = [ 13 14 ]
D [24/Nov/2004:18:05:03 +0000] start_process("/usr/lib/cups/filter/pstops", 
0xbffefde0, 0xbffef150, 12, 14, 11)
I [24/Nov/2004:18:05:03 +0000] Started filter /usr/lib/cups/filter/pstops 
(PID 10541) for job 4.
D [24/Nov/2004:18:05:03 +0000] StartJob: filter = 
"/usr/lib/cups/filter/foomatic-rip"
D [24/Nov/2004:18:05:03 +0000] StartJob: filterfds[1] = [ 12 15 ]
D [24/Nov/2004:18:05:03 +0000] 
start_process("/usr/lib/cups/filter/foomatic-rip", 0xbffefde0, 0xbffef150, 
13, 15, 11)
I [24/Nov/2004:18:05:03 +0000] Started filter 
/usr/lib/cups/filter/foomatic-rip (PID 10542) for job 4.
D [24/Nov/2004:18:05:03 +0000] StartJob: backend = 
"/usr/lib/cups/backend/ptal"
D [24/Nov/2004:18:05:03 +0000] StartJob: filterfds[0] = [ -1 13 ]
D [24/Nov/2004:18:05:03 +0000] start_process("/usr/lib/cups/backend/ptal", 
0xbffefde0, 0xbffef150, 12, 13, 11)
I [24/Nov/2004:18:05:03 +0000] Started backend /usr/lib/cups/backend/ptal 
(PID 10543) for job 4.
d [24/Nov/2004:18:05:03 +0000] StartJob: Adding fd 10 to InputSet...
d [24/Nov/2004:18:05:03 +0000] add_job_state_reasons(0xb7bec868[8], 4)
D [24/Nov/2004:18:05:03 +0000] ProcessIPPRequest: 8 status_code=0
d [24/Nov/2004:18:05:03 +0000] ProcessIPPRequest: Adding fd 8 to 
OutputSet...
D [24/Nov/2004:18:05:03 +0000] [Job 4] Page = 595x842; 10,36 to 585,833
D [24/Nov/2004:18:05:03 +0000] [Job 4] slowcollate=0, slowduplex=0, 
sloworder=0
D [24/Nov/2004:18:05:03 +0000] [Job 4] 0 %%BoundingBox: 0 0 612 792
D [24/Nov/2004:18:05:03 +0000] [Job 4] 0 %%Pages: 1
D [24/Nov/2004:18:05:03 +0000] [Job 4] 0 %%LanguageLevel: 1
D [24/Nov/2004:18:05:03 +0000] [Job 4] 0 %%DocumentData: Clean7Bit
D [24/Nov/2004:18:05:03 +0000] [Job 4] 0 %%DocumentSuppliedResources: 
procset testprint/1.0
D [24/Nov/2004:18:05:03 +0000] [Job 4] 0 %%DocumentNeededResources: font 
Helvetica Helvetica-Bold Times-Roman
D [24/Nov/2004:18:05:03 +0000] [Job 4] 0 %%Creator: Michael Sweet, Easy 
Software Products
D [24/Nov/2004:18:05:03 +0000] [Job 4] 0 %%CreationDate: May 11, 1999
D [24/Nov/2004:18:05:03 +0000] [Job 4] 0 %%Title: Test Page
D [24/Nov/2004:18:05:03 +0000] [Job 4] 0 %%EndComments
D [24/Nov/2004:18:05:03 +0000] [Job 4] 0 %%BeginProlog
D [24/Nov/2004:18:05:03 +0000] [Job 4] 0 %%BeginResource procset testprint 
1.1 0
D [24/Nov/2004:18:05:03 +0000] [Job 4] 0 %%EndResource
D [24/Nov/2004:18:05:03 +0000] [Job 4] 0 %%EndProlog
D [24/Nov/2004:18:05:03 +0000] [Job 4] 0 %%Page: 1 1
D [24/Nov/2004:18:05:03 +0000] [Job 4] 0 %%Page: 1 1
D [24/Nov/2004:18:05:03 +0000] [Job 4] pw = 575.6, pl = 797.0
D [24/Nov/2004:18:05:03 +0000] [Job 4] PageLeft = 9.7, PageRight = 585.3
D [24/Nov/2004:18:05:03 +0000] [Job 4] PageTop = 833.0, PageBottom = 36.0
D [24/Nov/2004:18:05:03 +0000] [Job 4] PageWidth = 595.0, PageLength = 842.0
d [24/Nov/2004:18:05:03 +0000] WriteClient: Removing fd 8 from OutputSet...
d [24/Nov/2004:18:05:03 +0000] PID 10540 exited with no errors.
d [24/Nov/2004:18:05:03 +0000] DeleteCert: removing certificate for pid 
10540
d [24/Nov/2004:18:05:03 +0000] ReadClient() 8, used=0
D [24/Nov/2004:18:05:03 +0000] CloseClient() 8
d [24/Nov/2004:18:05:03 +0000] CloseClient: Removing fd 8 from InputSet and 
OutputSet...
d [24/Nov/2004:18:05:03 +0000] WriteClient: Removing fd 7 from OutputSet...
d [24/Nov/2004:18:05:03 +0000] WriteClient: Removing fd 9 from InputSet...
d [24/Nov/2004:18:05:03 +0000] WriteClient: 7 Closing data file 9.
D [24/Nov/2004:18:05:04 +0000] [Job 4] ptal-connect: connecting to device 
"mlc:usb:OfficeJet_G55"...  successful.
D [24/Nov/2004:18:05:10 +0000] [Job 4] 0 %%EOF
D [24/Nov/2004:18:05:10 +0000] [Job 4] Saw EOF!
d [24/Nov/2004:18:05:10 +0000] PID 10541 exited with no errors.
D [24/Nov/2004:18:05:11 +0000] [Job 4] Error closing renderer
E [24/Nov/2004:18:05:11 +0000] PID 10542 stopped with status 3!
d [24/Nov/2004:18:05:11 +0000] PID 10543 exited with no errors.
D [24/Nov/2004:18:05:11 +0000] UpdateJob: job 4, file 0 is complete.
d [24/Nov/2004:18:05:11 +0000] UpdateJob: Removing fd 10 from InputSet...
D [24/Nov/2004:18:05:11 +0000] CancelJob: id = 4
D [24/Nov/2004:18:05:11 +0000] StopJob: id = 4, force = 0
D [24/Nov/2004:18:05:11 +0000] StopJob: printer state is 3
d [24/Nov/2004:18:05:11 +0000] StopJob: Freeing status buffer...
d [24/Nov/2004:18:05:11 +0000] SaveJob: Closing file 8...
d [24/Nov/2004:18:05:11 +0000] SaveJob: Closing file 8...
d [24/Nov/2004:18:06:02 +0000] ReadClient() 5, used=0
D [24/Nov/2004:18:06:02 +0000] CloseClient() 5
d [24/Nov/2004:18:06:02 +0000] CloseClient: Removing fd 5 from InputSet and 
OutputSet...

My foomatic-rip.log looks like this:

foomatic-rip version $Revision: 3.43.2.2 $ running...
called with arguments: '4', 'root', 'Test Page', '1', ''
Parsing PPD file ...
*cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
Added option ColorSpace
Added option Resolution
Added option PageSize
Added option PageRegion
Added option Model
Added option PrintoutMode
Added option ImageableArea
Added option PaperDimension
Added option Duplex
Added option Quality
Added option Font

Parameter Summary
-----------------

Spooler: cups
Printer: HPOJ_Printer_Queue
PPD file: /etc/cups/ppd/HPOJ_Printer_Queue.ppd
Printer model: HP OfficeJet G55 Foomatic/hpijs (recommended)
Options:
Job title: Test Page
File(s) to be printed:
<STDIN>


================================================

File: <STDIN>

================================================

Reading PostScript input ...
--> This document is DSC-conforming!

-----------
Found: %%BeginProlog
Found: %%EndProlog

-----------
Found: %%BeginSetup
Inserting PostScript code for CUPS' page accounting
Found: %%BeginFeature: *PrintoutMode Normal
   Option: PrintoutMode=Normal --> Setting option
Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal
   Option: PrintoutMode=Normal --> Setting option
Found: %%BeginFeature: *Quality FromPrintoutMode
   Option: Quality=FromPrintoutMode --> Setting option
Found: %% FoomaticRIPOptionSetting: Quality=@PrintoutMode
   Option: Quality=FromPrintoutMode --> Setting option
Found: %%BeginFeature: *PageSize A4
   Option: PageSize=A4 --> Setting option
Found: %% FoomaticRIPOptionSetting: PageSize=A4
   Option: PageSize=A4 --> Setting option
Found: %%BeginFeature: *Duplex None
   Option: Duplex=None --> Setting option
Found: %% FoomaticRIPOptionSetting: Duplex=None
   Option: Duplex=None --> Setting option
Found: %%EndSetup

-----------
New page:  1 1
Inserting option code into "PageSetup" section.
No page header or page header not DSC-conforming
Stopping search for page header options
Found:
     pageHeight sub   % Move down...
 --> Output goes directly to the renderer now.


Starting renderer
JCL: <job data>

renderer PID kid4=10558
renderer command: 
gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs 
 -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="DESKJET 
930" -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -dDuplex=false -r300 -sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=2 
 -dIjsUseOutputFD -sOutputFile=- -
foomatic-gswrapper: gs '-dBATCH' '-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' 
'-sDEVICE=ijs' '-sIjsServer=hpijs' '-sDeviceManufacturer=HEWLETT-PACKARD' 
'-sDeviceModel=DESKJET 930' '-dDEVICEWIDTHPOINTS=595' 
'-dDEVICEHEIGHTPOINTS=842' '-dDuplex=false' '-r300' 
'-sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=2' 
'-dIjsUseOutputFD' '-sOutputFile=| cat >&3' '/dev/fd/0' 3>&1 1>&2

Closing renderer
tee: write error
renderer return value: 139
renderer received signal: 139
Process dying with "The renderer may have dumped core.", exit stat: 3
The renderer may have dumped core.
tail process done writing data to STDOUT
KID4 finished
KID3 exited with status 3
Renderer exit stat: 3
Renderer process finished
Killing process 10557 (KID3)
Process dying with "Error closing renderer", exit stat: 3



Could somebody tell me what I did wrong? Maybe some helpful soul will have a 
clue... I'm trying fo r quite some time to get it running and the many posts 
are sometimes quite confusing on the subject.
Thank you.

Greetings,

Patrick






More information about the cups mailing list