The problem with *cupsFilter

Mon Nov 2 23:18:02 PST 2009

Hello!

I created my own filter. In the PPD wrote:

*cupsFilter:    "application/vnd.cups-raw 0 /usr/local/bin/sap.pl"
No other such lines in the file.

When I print in /var/log/cups/error_log says:

I [02/Nov/2009:16:57:50 +0300] [Job 98] Adding start banner page "none".
D [02/Nov/2009:16:57:50 +0300] Discarding unused job-created event...
I [02/Nov/2009:16:57:50 +0300] [Job 98] Adding job file of type application/vnd.cups-raw.
I [02/Nov/2009:16:57:50 +0300] [Job 98] Adding end banner page "none".
I [02/Nov/2009:16:57:50 +0300] [Job 98] Queued on "printer" by "avv".
D [02/Nov/2009:16:57:50 +0300] [Job 98] hold_until = 0
D [02/Nov/2009:16:57:50 +0300] Discarding unused printer-state-changed event...
D [02/Nov/2009:16:57:50 +0300] [Job 98] job-sheets=none,none
D [02/Nov/2009:16:57:50 +0300] [Job 98] banner_page = 0
D [02/Nov/2009:16:57:50 +0300] [Job 98] argv[0]="printer"
D [02/Nov/2009:16:57:50 +0300] [Job 98] argv[1]="98"
D [02/Nov/2009:16:57:50 +0300] [Job 98] argv[2]="avv"
D [02/Nov/2009:16:57:50 +0300] [Job 98] argv[3]="test.pjl"
D [02/Nov/2009:16:57:50 +0300] [Job 98] argv[4]="1"
D [02/Nov/2009:16:57:50 +0300] [Job 98] argv[5]="media=A4 sides=one-sided finishings=3 number-up=1 job-uuid=urn:uuid:96b852b1-21cb-346b-5b56-fbc3b6cb7ec8"
D [02/Nov/2009:16:57:50 +0300] [Job 98] argv[6]="/var/spool/cups/d00098-001"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[2]="CUPS_DOCROOT=/usr/share/doc/cups-1.3.7"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[9]="SERVER_ADMIN=root at localhost.localdomain"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[10]="SOFTWARE=CUPS/1.3.7"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[12]="USER=root"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[13]="CUPS_SERVER=/var/run/cups/cups.sock"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[14]="CUPS_ENCRYPTION=IfRequested"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[15]="IPP_PORT=631"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[16]="CHARSET=utf-8"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[17]="LANG=ru_RU.UTF8"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[18]="PPD=/etc/cups/ppd/printer.ppd"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[19]="RIP_MAX_CACHE=8m"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[20]="CONTENT_TYPE=application/vnd.cups-raw"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[21]="DEVICE_URI=lpd://192.168.91.205/ps"
D [02/Nov/2009:16:57:50 +0300] [Job 98] envp[22]="PRINTER=printer"
I [02/Nov/2009:16:57:50 +0300] [Job 98] Started backend /usr/lib/cups/backend/lpd (PID 7519)
D [02/Nov/2009:16:57:50 +0300] Discarding unused job-state-changed event...
D [02/Nov/2009:16:57:50 +0300] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [02/Nov/2009:16:57:50 +0300] cupsdCloseClient: 11
D [02/Nov/2009:16:57:50 +0300] Discarding unused printer-state-changed event...
D [02/Nov/2009:16:57:50 +0300] Discarding unused printer-state-changed event...
D [02/Nov/2009:16:57:50 +0300] Discarding unused printer-state-changed event...
D [02/Nov/2009:16:57:50 +0300] Discarding unused printer-state-changed event...
D [02/Nov/2009:16:57:50 +0300] [Job 98] Connected to 192.168.91.205:515 (IPv4) (local port 1023)...
D [02/Nov/2009:16:57:50 +0300] [Job 98] lpd_command 02 ps
D [02/Nov/2009:16:57:50 +0300] [Job 98] Sending command string (4 bytes)...
D [02/Nov/2009:16:57:50 +0300] [Job 98] Reading command status...
D [02/Nov/2009:16:57:50 +0300] [Job 98] lpd_command returning 0
D [02/Nov/2009:16:57:50 +0300] [Job 98] Control file is:
D [02/Nov/2009:16:57:50 +0300] [Job 98] Hlocalhost.localdomain
D [02/Nov/2009:16:57:50 +0300] [Job 98] Pavv
D [02/Nov/2009:16:57:50 +0300] [Job 98] Jtest_pjl
D [02/Nov/2009:16:57:50 +0300] [Job 98] ldfA519localhost.local
D [02/Nov/2009:16:57:50 +0300] [Job 98] UdfA519localhost.local
D [02/Nov/2009:16:57:50 +0300] [Job 98] Ntest_pjl
D [02/Nov/2009:16:57:50 +0300] [Job 98] lpd_command 02 94 cfA519localhost.local
D [02/Nov/2009:16:57:50 +0300] [Job 98] Sending command string (26 bytes)...
D [02/Nov/2009:16:57:50 +0300] [Job 98] Reading command status...
D [02/Nov/2009:16:57:50 +0300] [Job 98] lpd_command returning 0
D [02/Nov/2009:16:57:50 +0300] Discarding unused printer-state-changed event...
D [02/Nov/2009:16:57:50 +0300] Discarding unused printer-state-changed event...
D [02/Nov/2009:16:57:50 +0300] [Job 98] lpd_command 03 3641 dfA519localhost.local
D [02/Nov/2009:16:57:50 +0300] [Job 98] Sending command string (28 bytes)...
D [02/Nov/2009:16:57:50 +0300] [Job 98] Reading command status...
D [02/Nov/2009:16:57:50 +0300] [Job 98] lpd_command returning 0
D [02/Nov/2009:16:57:50 +0300] Discarding unused printer-state-changed event...
D [02/Nov/2009:16:57:50 +0300] Discarding unused printer-state-changed event...
D [02/Nov/2009:16:57:50 +0300] Discarding unused printer-state-changed event...
D [02/Nov/2009:16:57:50 +0300] Discarding unused job-progress event...
D [02/Nov/2009:16:57:50 +0300] PID 7519 (/usr/lib/cups/backend/lpd) exited with no errors.
D [02/Nov/2009:16:57:50 +0300] [Job 98] File 0 is complete.
I [02/Nov/2009:16:57:50 +0300] [Job 98] Completed successfully.

Why does the system determines the file type correctly but when it does not run my filter?