Strange behavior "permission denied" until "service cups restart"

Anonymous anonymous at easysw.com
Mon Apr 17 07:21:34 PDT 2006


So I'm having a very bizarre problem- Printing in FC5 on a Brother MFC210C gives me "Permission Denied" until I su and issue "service cups restart"  I figured it was some perm issue, so I checked:
/var/spool/cups
/var/spool/lpd/*
/etc/cups
/dev/usb/lp0
/usr/lib/cups
/usr/share/cups
On top of this I checked the UID of the deamon and all CUPS related processes.  None of these change perms/UID after I restart the daemon yet it somehow magically works! WTF?
-attached is a quote from the error_log

---------------------------------------------------------

D [16/Apr/2006:22:40:57 -0400] StartJob: argv = "brother","40","anelson","HW_williamsport.odt","1","PageSize=BrA4","/var/spool/cups/d00040-001"
D [16/Apr/2006:22:40:57 -0400] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [16/Apr/2006:22:40:57 -0400] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [16/Apr/2006:22:40:57 -0400] StartJob: envp[2]="USER=root"
D [16/Apr/2006:22:40:57 -0400] StartJob: envp[3]="CHARSET=utf-8"
D [16/Apr/2006:22:40:57 -0400] StartJob: envp[4]="LANG=en_US"
D [16/Apr/2006:22:40:57 -0400] StartJob: envp[5]="PPD=/etc/cups/ppd/brother.ppd"
D [16/Apr/2006:22:40:57 -0400] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [16/Apr/2006:22:40:57 -0400] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [16/Apr/2006:22:40:57 -0400] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [16/Apr/2006:22:40:57 -0400] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [16/Apr/2006:22:40:57 -0400] StartJob: envp[10]="DEVICE_URI=usb:/dev/usb/lp0"
D [16/Apr/2006:22:40:57 -0400] StartJob: envp[11]="PRINTER=brother"
D [16/Apr/2006:22:40:57 -0400] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [16/Apr/2006:22:40:57 -0400] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [16/Apr/2006:22:40:57 -0400] StartJob: envp[14]="CUPS_SERVER=localhost"
D [16/Apr/2006:22:40:57 -0400] StartJob: envp[15]="IPP_PORT=631"
d [16/Apr/2006:22:40:57 -0400] StartJob: Allocating status buffer...
D [16/Apr/2006:22:40:57 -0400] StartJob: statusfds = [ 12 13 ]
D [16/Apr/2006:22:40:57 -0400] StartJob: filterfds[1] = [ 14 -1 ]
D [16/Apr/2006:22:40:57 -0400] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [16/Apr/2006:22:40:57 -0400] StartJob: filterfds[0] = [ 15 16 ]
D [16/Apr/2006:22:40:57 -0400] start_process("/usr/lib/cups/filter/pstops", 0xbf84aebc, 0xbf84ad2c, 14, 16, 13)
I [16/Apr/2006:22:40:57 -0400] Started filter /usr/lib/cups/filter/pstops (PID 3482) for job 40.
D [16/Apr/2006:22:40:57 -0400] StartJob: filter = "/usr/lib/cups/filter/brlpdwrapperMFC210C"
D [16/Apr/2006:22:40:57 -0400] StartJob: filterfds[1] = [ 14 17 ]
D [16/Apr/2006:22:40:57 -0400] start_process("/usr/lib/cups/filter/brlpdwrapperMFC210C", 0xbf84aebc, 0xbf84ad2c, 15, 17, 13)
I [16/Apr/2006:22:40:57 -0400] Started filter /usr/lib/cups/filter/brlpdwrapperMFC210C (PID 3483) for job 40.
D [16/Apr/2006:22:40:57 -0400] StartJob: backend = "/usr/lib/cups/backend/usb"
D [16/Apr/2006:22:40:57 -0400] StartJob: filterfds[0] = [ -1 15 ]
D [16/Apr/2006:22:40:57 -0400] start_process("/usr/lib/cups/backend/usb", 0xbf84aebc, 0xbf84ad2c, 14, 15, 13)
I [16/Apr/2006:22:40:57 -0400] Started backend /usr/lib/cups/backend/usb (PID 3484) for job 40.
d [16/Apr/2006:22:40:57 -0400] StartJob: Adding fd 12 to InputSet...
d [16/Apr/2006:22:40:57 -0400] add_job_state_reasons(0xb7c35870[11], 40)
D [16/Apr/2006:22:40:57 -0400] ProcessIPPRequest: 11 status_code=0
d [16/Apr/2006:22:40:57 -0400] ProcessIPPRequest: Adding fd 11 to OutputSet...
d [16/Apr/2006:22:40:57 -0400] WriteClient: Removing fd 11 from OutputSet...
D [16/Apr/2006:22:40:57 -0400] [Job 40] /usr/lib/cups/filter/brlpdwrapperMFC210C: Permission denied
E [16/Apr/2006:22:40:57 -0400] PID 3483 stopped with status 22!
D [16/Apr/2006:22:40:57 -0400] [Job 40] LPGETSTATUS returned a port status of 18...
d [16/Apr/2006:22:40:57 -0400] PID 3484 exited with no errors.





More information about the cups mailing list