File backend to replace existing pin printers

[Calvin Gordon]

> 1. did you remember "chmod +x"?
i did do a chmod 755 on that file

> 2. try making the second line (the line immediately after the "#!"):
>       exec >>/tmp/fw.errlog 2>&1
>     and see what if anything gets into that file
still nothing, no files being created at all, BUT

cleared out the ques, fixed one spelling error, and resubmitted a job.
cups log is showing a call for authentication of my user name, i can't think where that would come from (i edited my username, servername, and domainname for posting)

The job first requests user-name="lp"
then later: cupsdIsAuthorized: requesting-user-name="<MYUSERNAME>"

Because <MYUSERNAME> has an associated password, i suspect that the call for authentication for that user is failing.  Not sure why that call is being made.

/usr/lib/cups/filewriter is owned by root:root


D [19/Sep/2008:09:48:56 -0600] Print-Job ipp://localhost/printers/fileprinter
D [19/Sep/2008:09:48:56 -0600] add_job: setting context of job to UNKNOWN SL
D [19/Sep/2008:09:48:56 -0600] add_job: requesting-user-name="lp"
I [19/Sep/2008:09:48:56 -0600] [Job 45] Adding start banner page "none".
I [19/Sep/2008:09:48:56 -0600] Saving subscriptions.conf...
I [19/Sep/2008:09:48:56 -0600] [Job 45] Adding job file of type application/vnd.cups-raw.
I [19/Sep/2008:09:48:56 -0600] [Job 45] Adding end banner page "none".
I [19/Sep/2008:09:48:56 -0600] [Job 45] Queued on "fileprinter" by "lp".
D [19/Sep/2008:09:48:56 -0600] [Job 45] hold_until = 0
D [19/Sep/2008:09:48:56 -0600] [Job 45] Sending job to queue tagged as raw...
I [19/Sep/2008:09:48:56 -0600] Saving subscriptions.conf...
D [19/Sep/2008:09:48:56 -0600] [Job 45] job-sheets=none,none
D [19/Sep/2008:09:48:56 -0600] [Job 45] banner_page = 0
D [19/Sep/2008:09:48:56 -0600] [Job 45] argv[0]="fileprinter"
D [19/Sep/2008:09:48:56 -0600] [Job 45] argv[1]="45"
D [19/Sep/2008:09:48:56 -0600] [Job 45] argv[2]="lp"
D [19/Sep/2008:09:48:56 -0600] [Job 45] argv[3]="(stdin)"
D [19/Sep/2008:09:48:56 -0600] [Job 45] argv[4]="1"
D [19/Sep/2008:09:48:56 -0600] [Job 45] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:f2e55e86-7a98-3cd9-7475-686ab6fe636a"
D [19/Sep/2008:09:48:56 -0600] [Job 45] argv[6]="/var/spool/cups/d00045-001"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[9]="SERVER_ADMIN=root at myservername.mydomainname.com"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[10]="SOFTWARE=CUPS/1.3.7"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[12]="USER=root"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[13]="CUPS_SERVER=/var/run/cups/cups.sock"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[14]="CUPS_ENCRYPTION=IfRequested"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[15]="IPP_PORT=631"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[16]="CHARSET=utf-8"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[17]="LANG=en_US.UTF8"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[18]="PPD=/etc/cups/ppd/fileprinter.ppd"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[19]="RIP_MAX_CACHE=8m"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[20]="CONTENT_TYPE=application/vnd.cups-raw"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[21]="DEVICE_URI=filewrite:/home/profit-master/test.prn"
D [19/Sep/2008:09:48:56 -0600] [Job 45] envp[22]="PRINTER=fileprinter"
I [19/Sep/2008:09:48:56 -0600] [Job 45] Started backend /usr/lib/cups/backend/filewrite (PID 11258)
I [19/Sep/2008:09:48:56 -0600] Saving subscriptions.conf...
D [19/Sep/2008:09:48:56 -0600] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
E [19/Sep/2008:09:48:56 -0600] PID 11258 (/usr/lib/cups/backend/filewrite) stopped with status 22!
D [19/Sep/2008:09:48:56 -0600] [Job 45] /usr/lib/cups/backend/filewrite: No such file or directory
D [19/Sep/2008:09:48:56 -0600] [Job 45] File 0 is complete.
I [19/Sep/2008:09:48:56 -0600] [Job 45] Backend returned status 22 (unknown)
I [19/Sep/2008:09:48:56 -0600] Saving subscriptions.conf...
I [19/Sep/2008:09:48:56 -0600] Saving subscriptions.conf...
I [19/Sep/2008:09:48:56 -0600] Saving printers.conf...
I [19/Sep/2008:09:48:56 -0600] Saving subscriptions.conf...
D [19/Sep/2008:09:48:56 -0600] cupsdCloseClient: 9
D [19/Sep/2008:09:48:56 -0600] cupsdAcceptClient: skipping getpeercon()
D [19/Sep/2008:09:48:56 -0600] cupsdAcceptClient: 9 from localhost (Domain)
D [19/Sep/2008:09:48:56 -0600] cupsdAcceptClient: skipping getpeercon()
D [19/Sep/2008:09:48:56 -0600] cupsdAcceptClient: 12 from localhost (Domain)
D [19/Sep/2008:09:48:56 -0600] cupsdReadClient: 9 POST / HTTP/1.1
D [19/Sep/2008:09:48:56 -0600] cupsdAuthorize: No authentication data provided.
D [19/Sep/2008:09:48:56 -0600] Get-Notifications /
D [19/Sep/2008:09:48:56 -0600] cupsdIsAuthorized: requesting-user-name="<MYUSERNAME>"
D [19/Sep/2008:09:48:56 -0600] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [19/Sep/2008:09:48:56 -0600] cupsdReadClient: 12 POST / HTTP/1.1
D [19/Sep/2008:09:48:56 -0600] cupsdAuthorize: No authentication data provided.
D [19/Sep/2008:09:48:56 -0600] Get-Notifications /
D [19/Sep/2008:09:48:56 -0600] cupsdIsAuthorized: requesting-user-name="<MYUSERNAME>"
D [19/Sep/2008:09:48:56 -0600] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [19/Sep/2008:09:48:56 -0600] cupsdReadClient: 9 POST / HTTP/1.1
D [19/Sep/2008:09:48:56 -0600] cupsdAuthorize: No authentication data provided.
D [19/Sep/2008:09:48:56 -0600] Get-Job-Attributes ipp://localhost/jobs/45
D [19/Sep/2008:09:48:56 -0600] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [19/Sep/2008:09:48:56 -0600] cupsdReadClient: 12 POST / HTTP/1.1
D [19/Sep/2008:09:48:56 -0600] cupsdAuthorize: No authentication data provided.
D [19/Sep/2008:09:48:56 -0600] Get-Job-Attributes ipp://localhost/jobs/45
D [19/Sep/2008:09:48:56 -0600] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [19/Sep/2008:09:48:56 -0600] cupsdCloseClient: 9
D [19/Sep/2008:09:48:56 -0600] cupsdCloseClient: 12
D [19/Sep/2008:09:48:57 -0600] [Job 45] Unloading...
D [19/Sep/2008:09:49:40 -0600] cupsdAcceptClient: skipping getpeercon()
D [19/Sep/2008:09:49:40 -0600] cupsdAcceptClient: 9 from localhost (Domain)
D [19/Sep/2008:09:49:40 -0600] cupsdReadClient: 9 POST / HTTP/1.1
D [19/Sep/2008:09:49:40 -0600] cupsdAuthorize: No authentication data provided.
D [19/Sep/2008:09:49:40 -0600] CUPS-Get-Printers
D [19/Sep/2008:09:49:40 -0600] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [19/Sep/2008:09:49:40 -0600] cupsdReadClient: 9 POST / HTTP/1.1
D [19/Sep/2008:09:49:40 -0600] cupsdAuthorize: No authentication data provided.
D [19/Sep/2008:09:49:40 -0600] CUPS-Get-Classes
D [19/Sep/2008:09:49:40 -0600] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [19/Sep/2008:09:49:40 -0600] cupsdCloseClient: 9