Gentoo Forums
Gentoo Forums
Gentoo Forums
Quick Search: in
cupsd not able to execute installed filters
View unanswered posts
View posts from last 24 hours

 
Reply to topic    Gentoo Forums Forum Index Other Things Gentoo
View previous topic :: View next topic  
Author Message
Chris4321
n00b
n00b


Joined: 05 Oct 2006
Posts: 6
Location: United States (that narrows it down, doesn't it?)

PostPosted: Thu Oct 05, 2006 6:17 pm    Post subject: cupsd not able to execute installed filters Reply with quote

I've just tried to do printing with gentoo, and am a little baffled by what I see.

  • I have an old hp deskjet 842C.
  • As of right now, everything is install as recommended at gentoo usb printing
  • I can print the test page from the web interface, but not the same page from the command line.
  • I can also print files generated by Gimp, when using their DeskJet 842c printer setting.
  • Looking at the error log, I find that some of the filters are puking, saying that they can't be executed, but permissions are fine on the filters.
  • This happens with different drivers, only the puking filter changes.
  • I'm thinking that as a result, it tries to print an empty document.
  • I also get an odd error afterward about not finding the installed ppd files.


I can't seem to find anything in documentation or by googling (perhaps I can't conjure the right magic keywords...), except a few people having possibly related
problems in gentoo. I'm not sure if this is a gentoo problem, but I'll start here anyway!
Any help would be appreciated.

Here's what I have:
Code:

D [05/Oct/2006:12:44:57 -0400] AcceptClient: 5 from localhost:631.
D [05/Oct/2006:12:44:57 -0400] ReadClient: 5 POST / HTTP/1.1
D [05/Oct/2006:12:44:57 -0400] ProcessIPPRequest: 5 status_code=1
D [05/Oct/2006:12:44:57 -0400] ReadClient: 5 POST / HTTP/1.1
D [05/Oct/2006:12:44:57 -0400] ProcessIPPRequest: 5 status_code=1
D [05/Oct/2006:12:44:57 -0400] ReadClient: 5 POST / HTTP/1.1
D [05/Oct/2006:12:44:57 -0400] ProcessIPPRequest: 5 status_code=0
D [05/Oct/2006:12:44:57 -0400] AcceptClient: 9 from localhost:631.
D [05/Oct/2006:12:44:57 -0400] CloseClient: 5
D [05/Oct/2006:12:44:57 -0400] ReadClient: 9 POST /printers/DeskJet_840C HTTP/1.1
D [05/Oct/2006:12:44:57 -0400] print_job: auto-typing file...
D [05/Oct/2006:12:44:57 -0400] print_job: request file type is application/postscript.
D [05/Oct/2006:12:44:57 -0400] check_quotas: requesting-user-name = 'chris'
D [05/Oct/2006:12:44:57 -0400] print_job: requesting-user-name = 'chris'
I [05/Oct/2006:12:44:57 -0400] Adding start banner page "none" to job 40.
I [05/Oct/2006:12:44:57 -0400] Adding end banner page "none" to job 40.
I [05/Oct/2006:12:44:57 -0400] Job 40 queued on 'DeskJet_840C' by 'chris'.
D [05/Oct/2006:12:44:57 -0400] Job 40 hold_until = 0
D [05/Oct/2006:12:44:57 -0400] StartJob(40, 0x8096150)
D [05/Oct/2006:12:44:57 -0400] StartJob() id = 40, file = 0/1
D [05/Oct/2006:12:44:57 -0400] job-sheets=none,none
D [05/Oct/2006:12:44:57 -0400] banner_page = 0
D [05/Oct/2006:12:44:57 -0400] StartJob: argv = "DeskJet_840C","40","chris","printfile.ps","1","PrintoutMode=Draft.Gray","/var/spool/cups/d00040-001"
D [05/Oct/2006:12:44:57 -0400] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [05/Oct/2006:12:44:57 -0400] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [05/Oct/2006:12:44:57 -0400] StartJob: envp[2]="USER=root"
D [05/Oct/2006:12:44:57 -0400] StartJob: envp[3]="CHARSET=utf-8"
D [05/Oct/2006:12:44:57 -0400] StartJob: envp[4]="LANG=en_US"
D [05/Oct/2006:12:44:57 -0400] StartJob: envp[5]="PPD=/etc/cups/ppd/DeskJet_840C.ppd"
D [05/Oct/2006:12:44:57 -0400] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [05/Oct/2006:12:44:57 -0400] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [05/Oct/2006:12:44:57 -0400] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [05/Oct/2006:12:44:57 -0400] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [05/Oct/2006:12:44:57 -0400] StartJob: envp[10]="DEVICE_URI=usb://HP/DeskJet%20840C?serial=CN04L1N1TFLB"
D [05/Oct/2006:12:44:57 -0400] StartJob: envp[11]="PRINTER=DeskJet_840C"
D [05/Oct/2006:12:44:57 -0400] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [05/Oct/2006:12:44:57 -0400] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [05/Oct/2006:12:44:57 -0400] StartJob: envp[14]="CUPS_SERVER=localhost"
D [05/Oct/2006:12:44:57 -0400] StartJob: envp[15]="IPP_PORT=631"
D [05/Oct/2006:12:44:57 -0400] StartJob: statusfds = [ 5 10 ]
D [05/Oct/2006:12:44:57 -0400] StartJob: filterfds[1] = [ 11 -1 ]
D [05/Oct/2006:12:44:57 -0400] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [05/Oct/2006:12:44:57 -0400] StartJob: filterfds[0] = [ 12 13 ]
D [05/Oct/2006:12:44:57 -0400] start_process("/usr/lib/cups/filter/pstops", 0xbf8c3b00, 0xbf8c2e70, 11, 13, 10)
I [05/Oct/2006:12:44:57 -0400] Started filter /usr/lib/cups/filter/pstops (PID 21710) for job 40.
D [05/Oct/2006:12:44:57 -0400] StartJob: filter = "/usr/lib/cups/filter/pstoraster"
D [05/Oct/2006:12:44:57 -0400] StartJob: filterfds[1] = [ 11 14 ]
D [05/Oct/2006:12:44:57 -0400] start_process("/usr/lib/cups/filter/pstoraster", 0xbf8c3b00, 0xbf8c2e70, 12, 14, 10)
I [05/Oct/2006:12:44:57 -0400] Started filter /usr/lib/cups/filter/pstoraster (PID 21711) for job 40.
D [05/Oct/2006:12:44:57 -0400] StartJob: filter = "/usr/lib/cups/filter/rastertoprinter"
D [05/Oct/2006:12:44:57 -0400] StartJob: filterfds[0] = [ 12 13 ]
D [05/Oct/2006:12:44:57 -0400] start_process("/usr/lib/cups/filter/rastertoprinter", 0xbf8c3b00, 0xbf8c2e70, 11, 13, 10)
I [05/Oct/2006:12:44:57 -0400] Started filter /usr/lib/cups/filter/rastertoprinter (PID 21712) for job 40.
D [05/Oct/2006:12:44:57 -0400] StartJob: backend = "/usr/lib/cups/backend/usb"
D [05/Oct/2006:12:44:57 -0400] StartJob: filterfds[1] = [ -1 11 ]
D [05/Oct/2006:12:44:57 -0400] start_process("/usr/lib/cups/backend/usb", 0xbf8c3b00, 0xbf8c2e70, 12, 11, 10)
I [05/Oct/2006:12:44:57 -0400] Started backend /usr/lib/cups/backend/usb (PID 21713) for job 40.
D [05/Oct/2006:12:44:57 -0400] ProcessIPPRequest: 9 status_code=0
E [05/Oct/2006:12:44:57 -0400] PID 21711 stopped with status 126!
D [05/Oct/2006:12:44:57 -0400] [Job 40] slowcollate=0, slowduplex=0, sloworder=0
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%BoundingBox: (atend)
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%Creator: OpenOffice.org 2.0
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%For: chris
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%CreationDate: Wed Oct  4 18:47:04 2006
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%Title: current
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%LanguageLevel: 3
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%DocumentData: Clean7Bit
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%Pages: (atend)
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%PageOrder: Ascend
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%EndComments
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%BeginProlog
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%BeginResource: procset PSPrint-Prolog 1.0 0
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%EndResource
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%EndProlog
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%BeginSetup
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%BeginResource: font NimbusRomNo9L-Regu
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%Title: NimbusRomNo9L-Regu
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%CreationDate: Tue Dec 31 16:49:50 2002
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%Creator: frob
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%DocumentSuppliedResources: font NimbusRomNo9L-Regu
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%EndComments

D [05/Oct/2006:12:44:57 -0400] [Job 40] /usr/lib/cups/filter/pstoraster: /usr/lib/cups/filter/pstoraster: cannot execute binary file

D [05/Oct/2006:12:44:57 -0400] CloseClient: 9

E [05/Oct/2006:12:44:57 -0400] [Job 40] Fatal error: Unable to load PPD file "/etc/cups/ppd/DeskJet_840C.ppd"!

D [05/Oct/2006:12:44:57 -0400] [Job 40] Printer using device file "/dev/usb/lp0"...
E [05/Oct/2006:12:44:57 -0400] PID 21712 stopped with status 1!
D [05/Oct/2006:12:44:57 -0400] [Job 40] LPGETSTATUS returned a port status of 18...
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%BeginResource: font NimbusRomNo9L-ReguItal
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%Title: NimbusRomNo9L-ReguItal
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%CreationDate: Tue Dec 31 16:48:49 2002
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%Creator: frob
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%DocumentSuppliedResources: font NimbusRomNo9L-ReguItal
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%EndComments
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%BeginResource: font OpenSymbolHGSet2
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%Creator: SunTypeTools-TT 1.0 gelf
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%EndResource
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%DocumentSuppliedResources: font NimbusRomNo9L-Regu
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%+ font NimbusRomNo9L-ReguItal
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%+ font OpenSymbolHGSet2
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%EndSetup
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%Page: 1 1
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%Page: 1 1
D [05/Oct/2006:12:44:57 -0400] [Job 40] pw = 576.0, pl = 720.0
D [05/Oct/2006:12:44:57 -0400] [Job 40] PageLeft = 18.0, PageRight = 594.0
D [05/Oct/2006:12:44:57 -0400] [Job 40] PageTop = 756.0, PageBottom = 36.0
D [05/Oct/2006:12:44:57 -0400] [Job 40] PageWidth = 612.0, PageLength = 792.0
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%PageBoundingBox: 18 36 594 756
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%BeginPageSetup
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%BeginFeature: *PageSize Letter
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%EndFeature
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%BeginFeature: *InputSlot Tray
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%EndFeature
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%EndPageSetup
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%PageTrailer
D [05/Oct/2006:12:44:57 -0400] [Job 40] 0 %%Trailer

D [05/Oct/2006:12:44:57 -0400] [Job 40] Saw Trailer!
D [05/Oct/2006:12:44:57 -0400] [Job 40] Saw EOF!

D [05/Oct/2006:12:44:57 -0400] UpdateJob: job 40, file 0 is complete.
D [05/Oct/2006:12:44:57 -0400] CancelJob: id = 40
D [05/Oct/2006:12:44:57 -0400] StopJob: id = 40, force = 0
D [05/Oct/2006:12:44:57 -0400] StopJob: printer state is 3

Back to top
View user's profile Send private message
wynn
Advocate
Advocate


Joined: 01 Apr 2005
Posts: 2421
Location: UK

PostPosted: Thu Oct 05, 2006 6:28 pm    Post subject: Reply with quote

Seeing
Code:
Started filter /usr/lib/cups/filter/pstoraster (PID 21711) for job 40.
...
PID 21711 stopped with status 126!
...
/usr/lib/cups/filter/pstoraster: /usr/lib/cups/filter/pstoraster: cannot execute binary file
looks as if ghostscript (which is called by pstoraster) is failing. Have you got ghostscript-esp installed? This is the one it wants.

Code:
Fatal error: Unable to load PPD file "/etc/cups/ppd/DeskJet_840C.ppd"!
is probably the next level up trying to make sense of the status code.
_________________
The avatar is jorma, a "duck" from "Elephants Dream": the film and all the production materials have been made available under a Creative Commons Attribution 2.5 License, see orange.blender.org for details.
Back to top
View user's profile Send private message
Chris4321
n00b
n00b


Joined: 05 Oct 2006
Posts: 6
Location: United States (that narrows it down, doesn't it?)

PostPosted: Thu Oct 05, 2006 10:13 pm    Post subject: Reply with quote

wynn wrote:
Seeing
Code:
Started filter /usr/lib/cups/filter/pstoraster (PID 21711) for job 40.
...
PID 21711 stopped with status 126!
...
/usr/lib/cups/filter/pstoraster: /usr/lib/cups/filter/pstoraster: cannot execute binary file
looks as if ghostscript (which is called by pstoraster) is failing. Have you got ghostscript-esp installed? This is the one it wants.


Unfortunately, it is installed, otherwise this would be easier.

Quote:

Code:
Fatal error: Unable to load PPD file "/etc/cups/ppd/DeskJet_840C.ppd"!
is probably the next level up trying to make sense of the status code.

I'm thinking the same thing, so I'm not too worried about it at this point.
What gets me is that I can print the /usr/share/cups/data/testprint.ps from the web page, but not the command line. Does the web page call something that isn't called automatically from the command line? If it could always print this postscript file, but not stuff generated from OpenOffice, I would think it had something to do with the files themselves, but this can't be the case.

Just in case that scroll bar is rusty, and for the sake of completeness, here is that testprint going through with no problems.
Code:

D [05/Oct/2006:17:20:43 -0400] StartJob() id = 41, file = 0/1
D [05/Oct/2006:17:20:43 -0400] job-sheets=none,none
D [05/Oct/2006:17:20:43 -0400] banner_page = 0
D [05/Oct/2006:17:20:43 -0400] StartJob: argv = "DeskJet_840C","41","","Test Page","1","","/var/spool/cups/d00041-001"
D [05/Oct/2006:17:20:43 -0400] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [05/Oct/2006:17:20:43 -0400] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [05/Oct/2006:17:20:43 -0400] StartJob: envp[2]="USER=root"
D [05/Oct/2006:17:20:43 -0400] StartJob: envp[3]="CHARSET=utf-8"
D [05/Oct/2006:17:20:43 -0400] StartJob: envp[4]="LANG=en"
D [05/Oct/2006:17:20:43 -0400] StartJob: envp[5]="PPD=/etc/cups/ppd/DeskJet_840C.ppd"
D [05/Oct/2006:17:20:43 -0400] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [05/Oct/2006:17:20:43 -0400] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [05/Oct/2006:17:20:43 -0400] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [05/Oct/2006:17:20:43 -0400] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [05/Oct/2006:17:20:43 -0400] StartJob: envp[10]="DEVICE_URI=usb://HP/DeskJet%20840C?serial=CN04L1N1TFLB"
D [05/Oct/2006:17:20:43 -0400] StartJob: envp[11]="PRINTER=DeskJet_840C"
D [05/Oct/2006:17:20:43 -0400] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [05/Oct/2006:17:20:43 -0400] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [05/Oct/2006:17:20:43 -0400] StartJob: envp[14]="CUPS_SERVER=localhost"
D [05/Oct/2006:17:20:43 -0400] StartJob: envp[15]="IPP_PORT=631"
D [05/Oct/2006:17:20:43 -0400] StartJob: statusfds = [ 11 12 ]
D [05/Oct/2006:17:20:43 -0400] StartJob: filterfds[1] = [ 13 -1 ]
D [05/Oct/2006:17:20:43 -0400] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [05/Oct/2006:17:20:43 -0400] StartJob: filterfds[0] = [ 14 15 ]
D [05/Oct/2006:17:20:43 -0400] start_process("/usr/lib/cups/filter/pstops", 0xbfbfae30, 0xbfbfa1a0, 13, 15, 12)
I [05/Oct/2006:17:20:43 -0400] Started filter /usr/lib/cups/filter/pstops (PID 8481) for job 41.
D [05/Oct/2006:17:20:43 -0400] StartJob: filter = "/usr/lib/cups/filter/pstoraster"
D [05/Oct/2006:17:20:43 -0400] StartJob: filterfds[1] = [ 13 16 ]
D [05/Oct/2006:17:20:43 -0400] start_process("/usr/lib/cups/filter/pstoraster", 0xbfbfae30, 0xbfbfa1a0, 14, 16, 12)
I [05/Oct/2006:17:20:43 -0400] Started filter /usr/lib/cups/filter/pstoraster (PID 8482) for job 41.
D [05/Oct/2006:17:20:43 -0400] StartJob: filter = "/usr/lib/cups/filter/rastertoprinter"
D [05/Oct/2006:17:20:43 -0400] StartJob: filterfds[0] = [ 14 15 ]
D [05/Oct/2006:17:20:43 -0400] start_process("/usr/lib/cups/filter/rastertoprinter", 0xbfbfae30, 0xbfbfa1a0, 13, 15, 12)
I [05/Oct/2006:17:20:43 -0400] Started filter /usr/lib/cups/filter/rastertoprinter (PID 8483) for job 41.
D [05/Oct/2006:17:20:43 -0400] StartJob: backend = "/usr/lib/cups/backend/usb"
D [05/Oct/2006:17:20:43 -0400] StartJob: filterfds[1] = [ -1 13 ]
D [05/Oct/2006:17:20:43 -0400] start_process("/usr/lib/cups/backend/usb", 0xbfbfae30, 0xbfbfa1a0, 14, 13, 12)
I [05/Oct/2006:17:20:43 -0400] Started backend /usr/lib/cups/backend/usb (PID 8484) for job 41.
D [05/Oct/2006:17:20:43 -0400] ProcessIPPRequest: 9 status_code=0
D [05/Oct/2006:17:20:43 -0400] [Job 41] Running /usr/bin/gs -dQUIET -dDEBUG -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOMEDIAATTRS -sDEVICE=cups -sstdout=%stderr -sOUTPUTFILE=%stdout -c -
D [05/Oct/2006:17:20:43 -0400] CloseClient: 9
D [05/Oct/2006:17:20:43 -0400] [Job 41] Printer using device file "/dev/usb/lp0"...
D [05/Oct/2006:17:20:43 -0400] [Job 41] LPGETSTATUS returned a port status of 18...
D [05/Oct/2006:17:20:44 -0400] [Job 41] Page = 612x792; 18,33 to 594,792
D [05/Oct/2006:17:20:44 -0400] [Job 41] slowcollate=0, slowduplex=0, sloworder=0
D [05/Oct/2006:17:20:44 -0400] [Job 41] 0 %%BoundingBox: 0 0 612 792
D [05/Oct/2006:17:20:44 -0400] [Job 41] 0 %%Pages: 1
D [05/Oct/2006:17:20:44 -0400] [Job 41] 0 %%LanguageLevel: 1
D [05/Oct/2006:17:20:44 -0400] [Job 41] 0 %%DocumentData: Clean7Bit
D [05/Oct/2006:17:20:44 -0400] [Job 41] 0 %%DocumentSuppliedResources: procset testprint/1.1
D [05/Oct/2006:17:20:44 -0400] [Job 41] 0 %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman
D [05/Oct/2006:17:20:44 -0400] [Job 41] 0 %%Creator: Michael Sweet, Easy Software Products
D [05/Oct/2006:17:20:44 -0400] [Job 41] 0 %%CreationDate: May 11, 1999
D [05/Oct/2006:17:20:44 -0400] [Job 41] 0 %%Title: Test Page
D [05/Oct/2006:17:20:44 -0400] [Job 41] 0 %%EndComments
D [05/Oct/2006:17:20:44 -0400] [Job 41] 0 %%BeginProlog
D [05/Oct/2006:17:20:44 -0400] [Job 41] 0 %%BeginResource procset testprint 1.1 0
D [05/Oct/2006:17:20:44 -0400] [Job 41] 0 %%EndResource
D [05/Oct/2006:17:20:44 -0400] [Job 41] 0 %%EndProlog
D [05/Oct/2006:17:20:44 -0400] [Job 41] 0 %%Page: 1 1
D [05/Oct/2006:17:20:44 -0400] [Job 41] 0 %%Page: 1 1
D [05/Oct/2006:17:20:44 -0400] [Job 41] pw = 576.0, pl = 759.0
D [05/Oct/2006:17:20:44 -0400] [Job 41] PageLeft = 18.0, PageRight = 594.0
D [05/Oct/2006:17:20:44 -0400] [Job 41] PageTop = 792.0, PageBottom = 33.0
D [05/Oct/2006:17:20:44 -0400] [Job 41] PageWidth = 612.0, PageLength = 792.0
D [05/Oct/2006:17:20:44 -0400] [Job 41] 0 %%EOF
D [05/Oct/2006:17:20:44 -0400] [Job 41] Saw EOF!
D [05/Oct/2006:17:20:44 -0400] [Job 41] START 0 1397176 113535 1300844 11688 true 510 3 <0>
D [05/Oct/2006:17:20:44 -0400] [Job 41] END PROCS 3 1417272 130705 1300844 13056 true 630 3 <0>
D [05/Oct/2006:17:20:44 -0400] [Job 41] gs_std_e.ps 21 1437368 140658 1300844 14432 true 639 3 <0>
D [05/Oct/2006:17:20:44 -0400] [Job 41] gs_il1_e.ps 29 1457464 146710 1300844 14432 true 640 3 <0>
D [05/Oct/2006:17:20:44 -0400] [Job 41] END FONTDIR/ENCS 29 1457464 146862 1300844 14432 true 642 3 <0>
D [05/Oct/2006:17:20:44 -0400] [Job 41] END DEVS 133 1461168 167383 1300844 14432 true 646 3 <0>
D [05/Oct/2006:17:20:44 -0400] [Job 41] END STATD 138 1481264 178507 1300844 16044 true 651 3 <0>
D [05/Oct/2006:17:20:44 -0400] [Job 41] END GS_FONTS 146 1521456 216238 1300844 16044 true 699 3 <0>
D [05/Oct/2006:17:20:44 -0400] [Job 41] END BASIC COLOR 151 1521456 224097 1300844 16044 true 714 3 <0>
D [05/Oct/2006:17:20:44 -0400] [Job 41] END LEVEL 1 COLOR 153 1541552 229212 1300844 16044 true 715 3 <0>
D [05/Oct/2006:17:20:44 -0400] [Job 41] END IMAGE 158 1541552 232960 1300844 16044 true 720 3 <0>
D [05/Oct/2006:17:20:44 -0400] [Job 41] gs_btokn.ps 166 1541552 237088 1300844 16044 true 723 3 <0>
D [05/Oct/2006:17:20:44 -0400] [Job 41] gs_dps1.ps 173 1541552 238846 1300844 16044 true 725 3 <0>
D [05/Oct/2006:17:20:44 -0400] [Job 41] gs_dps2.ps 174 1541552 241304 1300844 16044 true 726 3 <0>
D [05/Oct/2006:17:20:44 -0400] [Job 41] gs_type1.ps 184 1541552 246246 1300844 16044 true 733 3 <0>
D [05/Oct/2006:17:20:44 -0400] [Job 41] gs_lev2.ps 227 1561648 266297 1396944 112540 true 739 3 <0>


It goes on processing for quite a few pages. I must be blind, because I have to be missing something really obvious.
Back to top
View user's profile Send private message
wynn
Advocate
Advocate


Joined: 01 Apr 2005
Posts: 2421
Location: UK

PostPosted: Fri Oct 06, 2006 10:24 am    Post subject: Reply with quote

Quote:
Unfortunately, it is installed, otherwise this would be easier.
Yes, indeed! but the "quick fix" is worth a try nevertheless :)

Ok, let's look at
Code:
/usr/lib/cups/filter/pstoraster
If it works from the CUPS web page but not the command line have you got privileges on the web page you don't have on the command line? For instance, being logged in as administrator on the web page but being an ordinary user on the command line?

You appear to be running cups-1.1.23-rX and (borrowing from Ubuntu 6.06) pstoraster contains
Code:
echo INFO: Starting ESP Ghostscript 8.15.... 1>&2
echo DEBUG: Running $bindir/gs-esp $gsopts -sOUTPUTFILE="%stdout" -c"$profile" "$ifile" 1>&2

# Now run Ghostscript...
exec $bindir/gs-esp $gsopts -sOUTPUTFILE="%stdout" -c"$profile" "$ifile"
The binary file it can't run must therefore be gs — I don't know why the INFO and the DEBUG messages haven't appeared in error_log.

The permissions on /usr/bin/gs (a merry dance on Ubuntu: /usr/bin/gs -> /etc/alternatives/gs -> /usr/bin/gs-esp) are "-rwxr-xr-x 1 root root", perhaps you can check /usr/bin/gs? Though I can't think that they could be anything else. You haven't got anything like selinux running or ext2 attributes which might have an effect?
_________________
The avatar is jorma, a "duck" from "Elephants Dream": the film and all the production materials have been made available under a Creative Commons Attribution 2.5 License, see orange.blender.org for details.
Back to top
View user's profile Send private message
Chris4321
n00b
n00b


Joined: 05 Oct 2006
Posts: 6
Location: United States (that narrows it down, doesn't it?)

PostPosted: Sat Oct 07, 2006 2:16 am    Post subject: Reply with quote

wynn wrote:

If it works from the CUPS web page but not the command line have you got privileges on the web page you don't have on the command line? For instance, being logged in as administrator on the web page but being an ordinary user on the command line?

I noticed that I posted a log with two different users now, but it doesn't matter. The user is, in fact, root from the web interface, but I have also run it as root from the command line, so it isn't as if I am fighting permissions.
Quote:

The permissions on /usr/bin/gs (a merry dance on Ubuntu: /usr/bin/gs -> /etc/alternatives/gs -> /usr/bin/gs-esp) are "-rwxr-xr-x 1 root root", perhaps you can check /usr/bin/gs? Though I can't think that they could be anything else. You haven't got anything like selinux running or ext2 attributes which might have an effect?

No, there isn't anything funky set up. I haven't had much time to look at it today, but I need to try to do what cups is doing by hand, if I can. I tried an off the hip shot at calling gs with the same parameters as cups does, and it dies. I doubt that I am feeding it what it wants though. I'll have to take a better look at it this weekend.

I appreciate the ideas!

I think this is what they call a learning cups experience :)
Back to top
View user's profile Send private message
wynn
Advocate
Advocate


Joined: 01 Apr 2005
Posts: 2421
Location: UK

PostPosted: Sat Oct 07, 2006 10:30 am    Post subject: Reply with quote

Quote:
I tried an off the hip shot at calling gs with the same parameters as cups does, and it dies. I doubt that I am feeding it what it wants though.
That's an excellent idea. You might start by commenting out the "exec $bindir/gs-esp $gsopts..." (the Gentoo version may not be identical, especially gs-esp) and then the
Code:
echo DEBUG: Running $bindir/gs-esp $gsopts -sOUTPUTFILE="%stdout" -c"$profile" "$ifile" 1>&2
in /var/log/cups/error_log will give you all you need to run it separately.

Just to check, you could also add
Code:
echo INFO: $(id) 1>&2
after the first "echo INFO" which will give you the user running this script as well.
_________________
The avatar is jorma, a "duck" from "Elephants Dream": the film and all the production materials have been made available under a Creative Commons Attribution 2.5 License, see orange.blender.org for details.
Back to top
View user's profile Send private message
Chris4321
n00b
n00b


Joined: 05 Oct 2006
Posts: 6
Location: United States (that narrows it down, doesn't it?)

PostPosted: Sun Oct 08, 2006 4:45 am    Post subject: Reply with quote

Quote:

Just to check, you could also add
Code:
echo INFO: $(id) 1>&2
after the first "echo INFO" which will give you the user running this script as well.

It seems to be running as 'lp' when it works.
Code:
[Job 80] Running as uid=4(lp) gid=7(lp) groups=7(lp)

I have run everything by hand as a regular user, and it works?!! :?
Code:
chris@localhost ~/temp $ PPD=/etc/cups/ppd/printer.ppd /usr/lib/cups/filter/pstops 1 2 3 1 1 ../printfile.ps >test-step1.ps
chris@localhost ~/temp $ PPD=/etc/cups/ppd/printer.ppd cat test-step1.ps|/usr/lib/cups/filter/pstoraster >test-step2.raster
chris@localhost ~/temp $ PPD=/etc/cups/ppd/printer.ppd /usr/lib/cups/filter/rastertoprinter 1 2 3 1 1 test-step3.raster >test-step3.printer
chris@localhost ~/temp $ lp test-step4.printer
request id is printer-72 (1 file(s))

When cups runs as me, I still get the odd: /usr/lib/cups/filter/pstoraster: cannot execute binary file
What the heck! I have to take a look again at cups' configuration tommorow. I must have missed something huge. There isn't any way that it is running into file system permission trouble. I just ran it as myself.
Back to top
View user's profile Send private message
Chris4321
n00b
n00b


Joined: 05 Oct 2006
Posts: 6
Location: United States (that narrows it down, doesn't it?)

PostPosted: Tue Nov 07, 2006 5:56 am    Post subject: cupsd not able to execute installed filters [SOLVED] Reply with quote

After taking a break and some further poking, I noticed errno 29. That meant (as far as I know) something was trying to do a seek on the pipe between filters. I had some free time this weekend, so I was about to sit down to take a look at it. For kicks I got the ~x86 version of cups (1.2.5), and surprise, it worked!

That saved me some time. Cool.

Don't know what was changed to fix it, and don't really care! I am happy that I can print again.
Back to top
View user's profile Send private message
Display posts from previous:   
Reply to topic    Gentoo Forums Forum Index Other Things Gentoo All times are GMT
Page 1 of 1

 
Jump to:  
You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum
You cannot vote in polls in this forum