Perl: Warning: Setting locale failed

Anonymous anonymous at easysw.com
Wed Aug 18 21:12:26 PDT 2004


Hi, please help!  Thanks very much for looking at this.  Harry Heim  forharryh at access4less.net

I have installed CUPS and have spent 2 weeks trying to get it to print (nothing at all job stuck in the print queue). Received this message when trying to do a test page from CUPS Web interface:

perl: warning: Setting locale failed.
D [18/Aug/2004:16:04:18 -0700] [Job 1] perl: warning: Please check that your locale settings:
D [18/Aug/2004:16:04:18 -0700] [Job 1] LC_ALL = (unset),
D [18/Aug/2004:16:04:18 -0700] [Job 1] LANG = "en"

I have placed en_US.ISO_8859-1 in every startup (.login, .cshrc, .xinitrc, et al)script I can find for LANG; LC_ALL.  The "env" command shows all is assigned to the variables OK.  Why does Perl not see my locale variables?  How to fix?

Also, get a bunch of:
SendError() 8 code=304 (Not Modified)

Also a new one:
Unable to open /usr/local/etc/cups/passwd.md5 - No such file or directory
E [18/Aug/2004:16:06:38 -0700] cancel_job: "remroot" not authorized to delete job id 1 owned by ""!
D [18/Aug/2004:16:06:38 -0700] Sending error: client-error-forbidden
D [18/Aug/2004:16:06:38 -0700] ProcessIPPRequest: 6 status_code=401

I am running FreeBSD Unix 4.10 - without modifications to the kernel.  The printer is a HP DeskJet 520 that works fine with Windows.  I got the PPD from Linuxprinting.org along with Foomatic.  Installed CUPS from the Unix port that came with the OS.  Installed the printer via the Web interface.  Also installed Gimp and HPIJS from its Unix ports collection.  CUPS seems to run OK but will not print?

Here is the stripped log file:

ServerName BSDUnix.Workgroup
AccessLog /var/log/cups/access_log
DefaultCharset en_US.ISO_8859-1
DefaultLanguage en_US.ISO8859-1
ErrorLog /var/log/cups/error_log
LogLevel debug
PageLog /var/log/cups/page_log
Printcap /etc/printcap
PrintcapFormat BSD
<Location />
Order Deny,Allow
Deny From All
Allow From 127.0.0.1
Allow From 10.7.80.*
</Location>
<Location /admin>
AuthType Basic
AuthClass System
Order Deny,Allow
Deny From All
Allow From 127.0.0.1
</Location>

Here is the error file:
I [18/Aug/2004:16:02:37 -0700] Listening to 0:631
D [18/Aug/2004:16:02:37 -0700] AddLocation: added location '/'
D [18/Aug/2004:16:02:37 -0700] DenyIP: / deny 00000000/00000000
D [18/Aug/2004:16:02:37 -0700] AllowIP: / allow 7f000001/ffffffff
D [18/Aug/2004:16:02:37 -0700] AllowIP: / allow 0a075000/ffffff00
D [18/Aug/2004:16:02:37 -0700] AddLocation: added location '/admin'
D [18/Aug/2004:16:02:37 -0700] DenyIP: /admin deny 00000000/00000000
D [18/Aug/2004:16:02:37 -0700] AllowIP: /admin allow 7f000001/ffffffff
I [18/Aug/2004:16:02:37 -0700] Loaded configuration file "/usr/local/etc/cups/cupsd.conf"
I [18/Aug/2004:16:02:37 -0700] Configured for up to 100 clients.
I [18/Aug/2004:16:02:37 -0700] Allowing up to 100 client connections per host.
I [18/Aug/2004:16:02:37 -0700] Full reload is required.
D [18/Aug/2004:16:02:38 -0700] LoadAllPrinters: Loading printer HP-DJ520...
D [18/Aug/2004:16:02:38 -0700] LoadDevices: Added device "http"...
D [18/Aug/2004:16:02:38 -0700] LoadDevices: Added device "ipp"...
D [18/Aug/2004:16:02:38 -0700] LoadDevices: Added device "lpd"...
D [18/Aug/2004:16:02:42 -0700] LoadDevices: Added device "serial:/dev/ttyd0?baud=115200"...
D [18/Aug/2004:16:02:42 -0700] LoadDevices: Added device "socket"...
D [18/Aug/2004:16:02:42 -0700] LoadDevices: Added device "usb:/dev/ulpt0"...
D [18/Aug/2004:16:02:42 -0700] LoadDevices: Added device "usb:/dev/unlpt0"...
I [18/Aug/2004:16:02:42 -0700] LoadPPDs: Read "/usr/local/etc/cups/ppds.dat", 3389 PPDs...
I [18/Aug/2004:16:02:43 -0700] LoadPPDs: No new or changed PPDs...
D [18/Aug/2004:16:02:43 -0700] LoadAllJobs: Scanning /var/spool/cups...
I [18/Aug/2004:16:02:43 -0700] Full reload complete.
D [18/Aug/2004:16:02:43 -0700] StartListening: NumListeners=1
D [18/Aug/2004:16:02:43 -0700] StartListening: address=00000000 port=631
D [18/Aug/2004:16:02:43 -0700] ResumeListening: setting input bits...
D [18/Aug/2004:16:03:09 -0700] AcceptClient() 5 from BSDUnix.Workgroup:631.
D [18/Aug/2004:16:03:09 -0700] AcceptClient() 6 from BSDUnix.Workgroup:631.
D [18/Aug/2004:16:03:09 -0700] ReadClient() 6 POST / HTTP/1.1
D [18/Aug/2004:16:03:09 -0700] ProcessIPPRequest: 6 status_code=1
D [18/Aug/2004:16:03:09 -0700] CloseClient() 6
D [18/Aug/2004:16:03:09 -0700] AcceptClient() 6 from BSDUnix.Workgroup:631.
D [18/Aug/2004:16:03:09 -0700] ReadClient() 6 POST / HTTP/1.1
D [18/Aug/2004:16:03:09 -0700] ProcessIPPRequest: 6 status_code=1
D [18/Aug/2004:16:03:09 -0700] CloseClient() 6
D [18/Aug/2004:16:03:09 -0700] AcceptClient() 6 from BSDUnix.Workgroup:631.
D [18/Aug/2004:16:03:09 -0700] ReadClient() 6 POST / HTTP/1.1
D [18/Aug/2004:16:03:09 -0700] ProcessIPPRequest: 6 status_code=0
D [18/Aug/2004:16:03:09 -0700] ReadClient() 5 POST / HTTP/1.1
D [18/Aug/2004:16:03:09 -0700] ProcessIPPRequest: 5 status_code=0
D [18/Aug/2004:16:03:09 -0700] ReadClient() 5 POST / HTTP/1.1
D [18/Aug/2004:16:03:09 -0700] ProcessIPPRequest: 5 status_code=0
D [18/Aug/2004:16:03:09 -0700] CloseClient() 5
D [18/Aug/2004:16:03:09 -0700] CloseClient() 6
D [18/Aug/2004:16:04:03 -0700] AcceptClient() 5 from localhost:631.
D [18/Aug/2004:16:04:03 -0700] ReadClient() 5 GET /printers/HP-DJ520 HTTP/1.1
D [18/Aug/2004:16:04:03 -0700] CGI /usr/local/libexec/cups/cgi-bin/printers.cgi started - PID = 293
I [18/Aug/2004:16:04:03 -0700] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=293)
D [18/Aug/2004:16:04:03 -0700] SendCommand() 5 file=8
D [18/Aug/2004:16:04:03 -0700] AcceptClient() 6 from localhost:631.
D [18/Aug/2004:16:04:03 -0700] ReadClient() 6 POST / HTTP/1.1
D [18/Aug/2004:16:04:03 -0700] ProcessIPPRequest: 6 status_code=0
D [18/Aug/2004:16:04:03 -0700] ReadClient() 6 POST / HTTP/1.1
D [18/Aug/2004:16:04:03 -0700] ProcessIPPRequest: 6 status_code=1
D [18/Aug/2004:16:04:03 -0700] ReadClient() 6 POST / HTTP/1.1
D [18/Aug/2004:16:04:03 -0700] ProcessIPPRequest: 6 status_code=1
D [18/Aug/2004:16:04:03 -0700] CloseClient() 6
D [18/Aug/2004:16:04:04 -0700] AcceptClient() 6 from localhost:631.
D [18/Aug/2004:16:04:04 -0700] ReadClient() 6 GET /cups.css HTTP/1.1
D [18/Aug/2004:16:04:04 -0700] SendError() 6 code=304 (Not Modified)
D [18/Aug/2004:16:04:04 -0700] CloseClient() 6
D [18/Aug/2004:16:04:04 -0700] ReadClient() 5 GET /images/navbar.gif HTTP/1.1
D [18/Aug/2004:16:04:04 -0700] SendError() 5 code=304 (Not Modified)
D [18/Aug/2004:16:04:04 -0700] AcceptClient() 6 from localhost:631.
D [18/Aug/2004:16:04:04 -0700] AcceptClient() 8 from localhost:631.
D [18/Aug/2004:16:04:04 -0700] ReadClient() 6 GET /images/right.gif HTTP/1.1
D [18/Aug/2004:16:04:04 -0700] SendError() 6 code=304 (Not Modified)
D [18/Aug/2004:16:04:04 -0700] CloseClient() 5
D [18/Aug/2004:16:04:04 -0700] ReadClient() 8 GET /images/left.gif HTTP/1.1
D [18/Aug/2004:16:04:04 -0700] SendError() 8 code=304 (Not Modified)
D [18/Aug/2004:16:04:04 -0700] CloseClient() 6
D [18/Aug/2004:16:04:04 -0700] CloseClient() 8
D [18/Aug/2004:16:04:05 -0700] AcceptClient() 5 from localhost:631.
D [18/Aug/2004:16:04:05 -0700] AcceptClient() 6 from localhost:631.
D [18/Aug/2004:16:04:05 -0700] ReadClient() 5 GET /images/stop-printer.gif HTTP/1.1
D [18/Aug/2004:16:04:05 -0700] SendError() 5 code=304 (Not Modified)
D [18/Aug/2004:16:04:05 -0700] ReadClient() 6 GET /images/printer-idle.gif HTTP/1.1
D [18/Aug/2004:16:04:05 -0700] SendError() 6 code=304 (Not Modified)
D [18/Aug/2004:16:04:05 -0700] AcceptClient() 8 from localhost:631.
D [18/Aug/2004:16:04:05 -0700] ReadClient() 8 GET /images/print-test-page.gif HTTP/1.1
D [18/Aug/2004:16:04:05 -0700] SendError() 8 code=304 (Not Modified)
D [18/Aug/2004:16:04:05 -0700] CloseClient() 5
D [18/Aug/2004:16:04:05 -0700] CloseClient() 6
D [18/Aug/2004:16:04:05 -0700] CloseClient() 8
D [18/Aug/2004:16:04:05 -0700] AcceptClient() 5 from localhost:631.
D [18/Aug/2004:16:04:05 -0700] AcceptClient() 6 from localhost:631.
D [18/Aug/2004:16:04:05 -0700] ReadClient() 6 GET /images/modify-printer.gif HTTP/1.1
D [18/Aug/2004:16:04:05 -0700] SendError() 6 code=304 (Not Modified)
D [18/Aug/2004:16:04:05 -0700] ReadClient() 5 GET /images/config-printer.gif HTTP/1.1
D [18/Aug/2004:16:04:05 -0700] SendError() 5 code=304 (Not Modified)
D [18/Aug/2004:16:04:05 -0700] AcceptClient() 8 from localhost:631.
D [18/Aug/2004:16:04:05 -0700] CloseClient() 6
D [18/Aug/2004:16:04:05 -0700] ReadClient() 8 GET /images/reject-jobs.gif HTTP/1.1
D [18/Aug/2004:16:04:05 -0700] SendError() 8 code=304 (Not Modified)
D [18/Aug/2004:16:04:05 -0700] CloseClient() 5
D [18/Aug/2004:16:04:05 -0700] AcceptClient() 5 from localhost:631.
D [18/Aug/2004:16:04:05 -0700] ReadClient() 5 GET /images/delete-printer.gif HTTP/1.1
D [18/Aug/2004:16:04:05 -0700] SendError() 5 code=304 (Not Modified)
D [18/Aug/2004:16:04:05 -0700] CloseClient() 8
D [18/Aug/2004:16:04:05 -0700] CloseClient() 5
D [18/Aug/2004:16:04:05 -0700] AcceptClient() 5 from localhost:631.
D [18/Aug/2004:16:04:05 -0700] ReadClient() 5 GET /images/add-printer.gif HTTP/1.1
D [18/Aug/2004:16:04:05 -0700] SendError() 5 code=304 (Not Modified)
D [18/Aug/2004:16:04:05 -0700] AcceptClient() 6 from localhost:631.
D [18/Aug/2004:16:04:05 -0700] ReadClient() 6 GET /images/show-completed.gif HTTP/1.1
D [18/Aug/2004:16:04:05 -0700] SendError() 6 code=304 (Not Modified)
D [18/Aug/2004:16:04:05 -0700] CloseClient() 5
D [18/Aug/2004:16:04:05 -0700] CloseClient() 6
D [18/Aug/2004:16:04:05 -0700] AcceptClient() 5 from localhost:631.
D [18/Aug/2004:16:04:05 -0700] ReadClient() 5 GET /favicon.ico HTTP/1.1
D [18/Aug/2004:16:04:05 -0700] SendError() 5 code=404 (Not Found)
D [18/Aug/2004:16:04:05 -0700] CloseClient() 5
D [18/Aug/2004:16:04:10 -0700] AcceptClient() 5 from localhost:631.
D [18/Aug/2004:16:04:10 -0700] ReadClient() 5 GET /jobs HTTP/1.1
D [18/Aug/2004:16:04:10 -0700] CGI /usr/local/libexec/cups/cgi-bin/jobs.cgi started - PID = 297
I [18/Aug/2004:16:04:10 -0700] Started "/usr/local/libexec/cups/cgi-bin/jobs.cgi" (pid=297)
D [18/Aug/2004:16:04:10 -0700] SendCommand() 5 file=8
D [18/Aug/2004:16:04:10 -0700] AcceptClient() 6 from localhost:631.
D [18/Aug/2004:16:04:10 -0700] ReadClient() 6 POST / HTTP/1.1
D [18/Aug/2004:16:04:10 -0700] ProcessIPPRequest: 6 status_code=1
D [18/Aug/2004:16:04:10 -0700] CloseClient() 6
D [18/Aug/2004:16:04:12 -0700] AcceptClient() 6 from localhost:631.
D [18/Aug/2004:16:04:12 -0700] ReadClient() 6 GET /jobs?which_jobs=completed HTTP/1.1
D [18/Aug/2004:16:04:12 -0700] CGI /usr/local/libexec/cups/cgi-bin/jobs.cgi started - PID = 298
I [18/Aug/2004:16:04:12 -0700] Started "/usr/local/libexec/cups/cgi-bin/jobs.cgi" (pid=298)
D [18/Aug/2004:16:04:12 -0700] SendCommand() 6 file=9
D [18/Aug/2004:16:04:12 -0700] AcceptClient() 8 from localhost:631.
D [18/Aug/2004:16:04:12 -0700] ReadClient() 8 POST / HTTP/1.1
D [18/Aug/2004:16:04:12 -0700] ProcessIPPRequest: 8 status_code=1
D [18/Aug/2004:16:04:12 -0700] CloseClient() 8
D [18/Aug/2004:16:04:13 -0700] AcceptClient() 8 from localhost:631.
D [18/Aug/2004:16:04:13 -0700] ReadClient() 8 GET /images/show-active.gif HTTP/1.1
D [18/Aug/2004:16:04:13 -0700] SendError() 8 code=304 (Not Modified)
D [18/Aug/2004:16:04:13 -0700] CloseClient() 8
D [18/Aug/2004:16:04:14 -0700] ReadClient() 6 GET /printers HTTP/1.1
D [18/Aug/2004:16:04:14 -0700] CGI /usr/local/libexec/cups/cgi-bin/printers.cgi started - PID = 299
I [18/Aug/2004:16:04:14 -0700] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=299)
D [18/Aug/2004:16:04:14 -0700] SendCommand() 6 file=9
D [18/Aug/2004:16:04:14 -0700] AcceptClient() 8 from localhost:631.
D [18/Aug/2004:16:04:14 -0700] ReadClient() 8 POST / HTTP/1.1
D [18/Aug/2004:16:04:14 -0700] ProcessIPPRequest: 8 status_code=0
D [18/Aug/2004:16:04:14 -0700] ReadClient() 8 POST / HTTP/1.1
D [18/Aug/2004:16:04:14 -0700] ProcessIPPRequest: 8 status_code=1
D [18/Aug/2004:16:04:14 -0700] CloseClient() 8
D [18/Aug/2004:16:04:16 -0700] ReadClient() 5 GET /printers/HP-DJ520?op=print-test-page HTTP/1.1
D [18/Aug/2004:16:04:16 -0700] CGI /usr/local/libexec/cups/cgi-bin/printers.cgi started - PID = 300
I [18/Aug/2004:16:04:16 -0700] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=300)
D [18/Aug/2004:16:04:16 -0700] SendCommand() 5 file=9
D [18/Aug/2004:16:04:16 -0700] AcceptClient() 8 from localhost:631.
D [18/Aug/2004:16:04:16 -0700] ReadClient() 8 POST /printers/HP-DJ520 HTTP/1.1
D [18/Aug/2004:16:04:16 -0700] print_job: request file type is application/postscript.
D [18/Aug/2004:16:04:16 -0700] check_quotas: requesting-user-name = ''
D [18/Aug/2004:16:04:16 -0700] print_job: requesting-user-name = ''
D [18/Aug/2004:16:04:16 -0700] Adding default job-sheets values "none,none"...
I [18/Aug/2004:16:04:16 -0700] Adding start banner page "none" to job 1.
I [18/Aug/2004:16:04:16 -0700] Adding end banner page "none" to job 1.
I [18/Aug/2004:16:04:16 -0700] Job 1 queued on 'HP-DJ520' by ''.
D [18/Aug/2004:16:04:16 -0700] Job 1 hold_until = 0
D [18/Aug/2004:16:04:16 -0700] StartJob(1, 0x81a3000)
D [18/Aug/2004:16:04:16 -0700] StartJob() id = 1, file = 0/1
D [18/Aug/2004:16:04:16 -0700] job-sheets=none,none
D [18/Aug/2004:16:04:16 -0700] banner_page = 0
D [18/Aug/2004:16:04:16 -0700] StartJob: argv = "HP-DJ520","1","","Test Page","1","","/var/spool/cups/d00001-001"
D [18/Aug/2004:16:04:16 -0700] StartJob: envp[0]="PATH=/usr/local/libexec/cups/filter:/bin:/usr/bin"
D [18/Aug/2004:16:04:16 -0700] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [18/Aug/2004:16:04:16 -0700] StartJob: envp[2]="USER=root"
D [18/Aug/2004:16:04:16 -0700] StartJob: envp[3]="CHARSET=iso-8859-15"
D [18/Aug/2004:16:04:16 -0700] StartJob: envp[4]="LANG=en"
D [18/Aug/2004:16:04:16 -0700] StartJob: envp[5]="PPD=/usr/local/etc/cups/ppd/HP-DJ520.ppd"
D [18/Aug/2004:16:04:16 -0700] StartJob: envp[6]="CUPS_SERVERROOT=/usr/local/etc/cups"
D [18/Aug/2004:16:04:16 -0700] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [18/Aug/2004:16:04:16 -0700] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [18/Aug/2004:16:04:16 -0700] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [18/Aug/2004:16:04:16 -0700] StartJob: envp[10]="DEVICE_URI=parallel:/dev/lpt0"
D [18/Aug/2004:16:04:16 -0700] StartJob: envp[11]="PRINTER=HP-DJ520"
D [18/Aug/2004:16:04:16 -0700] StartJob: envp[12]="CUPS_DATADIR=/usr/local/share/cups"
D [18/Aug/2004:16:04:16 -0700] StartJob: envp[13]="CUPS_FONTPATH=/usr/local/share/cups/fonts"
D [18/Aug/2004:16:04:16 -0700] StartJob: envp[14]="CUPS_SERVER=localhost"
D [18/Aug/2004:16:04:16 -0700] StartJob: envp[15]="IPP_PORT=631"
D [18/Aug/2004:16:04:16 -0700] StartJob: statusfds = [ 10 11 ]
D [18/Aug/2004:16:04:16 -0700] StartJob: filterfds[1] = [ 12 -1 ]
D [18/Aug/2004:16:04:16 -0700] StartJob: filter = "/usr/local/libexec/cups/filter/pstops"
D [18/Aug/2004:16:04:16 -0700] StartJob: filterfds[0] = [ 13 14 ]
D [18/Aug/2004:16:04:16 -0700] start_process("/usr/local/libexec/cups/filter/pstops", 0xbfbf02e0, 0xbfbef650, 12, 14, 11)
I [18/Aug/2004:16:04:17 -0700] Started filter /usr/local/libexec/cups/filter/pstops (PID 301) for job 1.
D [18/Aug/2004:16:04:17 -0700] StartJob: filter = "/usr/local/libexec/cups/filter/foomatic-rip"
D [18/Aug/2004:16:04:17 -0700] StartJob: filterfds[1] = [ 12 15 ]
D [18/Aug/2004:16:04:17 -0700] start_process("/usr/local/libexec/cups/filter/foomatic-rip", 0xbfbf02e0, 0xbfbef650, 13, 15, 11)
I [18/Aug/2004:16:04:17 -0700] Started filter /usr/local/libexec/cups/filter/foomatic-rip (PID 302) for job 1.
D [18/Aug/2004:16:04:17 -0700] StartJob: backend = "/usr/local/libexec/cups/backend/parallel"
D [18/Aug/2004:16:04:17 -0700] StartJob: filterfds[0] = [ -1 13 ]
D [18/Aug/2004:16:04:17 -0700] start_process("/usr/local/libexec/cups/backend/parallel", 0xbfbf02e0, 0xbfbef650, 12, 13, 11)
I [18/Aug/2004:16:04:17 -0700] Started backend /usr/local/libexec/cups/backend/parallel (PID 303) for job 1.
D [18/Aug/2004:16:04:17 -0700] ProcessIPPRequest: 8 status_code=0
D [18/Aug/2004:16:04:17 -0700] CloseClient() 8
D [18/Aug/2004:16:04:17 -0700] [Job 1] perl: warning: Setting locale failed.
D [18/Aug/2004:16:04:17 -0700] [Job 1] perl: warning: Please check that your locale settings:
D [18/Aug/2004:16:04:17 -0700] [Job 1] LC_ALL = (unset),
D [18/Aug/2004:16:04:17 -0700] [Job 1] LANG = "en"
D [18/Aug/2004:16:04:17 -0700] [Job 1] are supported and installed on your system.
D [18/Aug/2004:16:04:17 -0700] [Job 1] perl: warning: Falling back to the standard locale ("C").
D [18/Aug/2004:16:04:17 -0700] [Job 1] Page = 612x792; 0,0 to 612,792
D [18/Aug/2004:16:04:17 -0700] [Job 1] slowcollate=0, slowduplex=0, sloworder=0
D [18/Aug/2004:16:04:17 -0700] [Job 1] 0 %%BoundingBox: 0 0 612 792
D [18/Aug/2004:16:04:17 -0700] [Job 1] 0 %%Pages: 1
D [18/Aug/2004:16:04:17 -0700] [Job 1] 0 %%LanguageLevel: 1
D [18/Aug/2004:16:04:17 -0700] [Job 1] 0 %%DocumentData: Clean7Bit
D [18/Aug/2004:16:04:17 -0700] [Job 1] 0 %%DocumentSuppliedResources: procset testprint/1.0
D [18/Aug/2004:16:04:17 -0700] [Job 1] 0 %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman
D [18/Aug/2004:16:04:17 -0700] [Job 1] 0 %%Creator: Michael Sweet, Easy Software Products
D [18/Aug/2004:16:04:17 -0700] [Job 1] 0 %%CreationDate: May 11, 1999
D [18/Aug/2004:16:04:17 -0700] [Job 1] 0 %%Title: Test Page
D [18/Aug/2004:16:04:17 -0700] [Job 1] 0 %%EndComments
D [18/Aug/2004:16:04:17 -0700] [Job 1] 0 %%BeginProlog
D [18/Aug/2004:16:04:17 -0700] [Job 1] 0 %%BeginResource procset testprint 1.1 0
D [18/Aug/2004:16:04:17 -0700] [Job 1] 0 %%EndResource
D [18/Aug/2004:16:04:17 -0700] [Job 1] 0 %%EndProlog
D [18/Aug/2004:16:04:17 -0700] [Job 1] 0 %%Page: 1 1
D [18/Aug/2004:16:04:17 -0700] [Job 1] 0 %%Page: 1 1
D [18/Aug/2004:16:04:17 -0700] [Job 1] pw = 612.0, pl = 792.0
D [18/Aug/2004:16:04:17 -0700] [Job 1] PageLeft = 0.0, PageRight = 612.0
D [18/Aug/2004:16:04:17 -0700] [Job 1] PageTop = 792.0, PageBottom = 0.0
D [18/Aug/2004:16:04:17 -0700] [Job 1] PageWidth = 612.0, PageLength = 792.0
D [18/Aug/2004:16:04:18 -0700] [Job 1] foomatic-rip version $Revision: 3.43.2.5 $ running...
D [18/Aug/2004:16:04:18 -0700] [Job 1] Parsing PPD file ...
D [18/Aug/2004:16:04:18 -0700] [Job 1] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option ColorSpace
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option PageSize
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option PageRegion
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option ImageableArea
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option PaperDimension
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option InputSlot
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option Manual
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option MediaType
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option Resolution
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option Quality
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option ColorMode
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option IntensityRendering
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option RasterGraphicsQuality
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option Model
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option DitherPPI
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option DryTime
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option MemLimit
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option BlackLevels
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option CompressionMethod
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option ConfigureEveryPage
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option LeadingEdge
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option OnlyCRD
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option Passes
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option PCL3GUI
D [18/Aug/2004:16:04:18 -0700] [Job 1] Added option Font
D [18/Aug/2004:16:04:18 -0700] [Job 1]
D [18/Aug/2004:16:04:18 -0700] [Job 1] Parameter Summary
D [18/Aug/2004:16:04:18 -0700] [Job 1] -----------------
D [18/Aug/2004:16:04:18 -0700] [Job 1]
D [18/Aug/2004:16:04:18 -0700] [Job 1] Spooler: cups
D [18/Aug/2004:16:04:18 -0700] [Job 1] Printer: HP-DJ520
D [18/Aug/2004:16:04:18 -0700] [Job 1] PPD file: /usr/local/etc/cups/ppd/HP-DJ520.ppd
D [18/Aug/2004:16:04:18 -0700] [Job 1] Printer model: HP DeskJet 520 Foomatic/pcl3 (recommended)
D [18/Aug/2004:16:04:18 -0700] [Job 1] Job title: Test Page
D [18/Aug/2004:16:04:18 -0700] [Job 1] File(s) to be printed:
D [18/Aug/2004:16:04:18 -0700] [Job 1] <STDIN>
D [18/Aug/2004:16:04:18 -0700] [Job 1]
D [18/Aug/2004:16:04:18 -0700] [Job 1]
D [18/Aug/2004:16:04:18 -0700] [Job 1] ================================================
D [18/Aug/2004:16:04:18 -0700] [Job 1]
D [18/Aug/2004:16:04:18 -0700] [Job 1] File: <STDIN>
D [18/Aug/2004:16:04:18 -0700] [Job 1]
D [18/Aug/2004:16:04:18 -0700] [Job 1] ================================================
D [18/Aug/2004:16:04:18 -0700] [Job 1]
D [18/Aug/2004:16:04:18 -0700] [Job 1] Reading PostScript input ...
D [18/Aug/2004:16:04:18 -0700] [Job 1] 0 %%EOF
D [18/Aug/2004:16:04:18 -0700] [Job 1] Saw EOF!
D [18/Aug/2004:16:04:18 -0700] [Job 1] --> This document is DSC-conforming!
D [18/Aug/2004:16:04:18 -0700] [Job 1]
D [18/Aug/2004:16:04:18 -0700] [Job 1] -----------
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginProlog
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%EndProlog
D [18/Aug/2004:16:04:18 -0700] [Job 1]
D [18/Aug/2004:16:04:18 -0700] [Job 1] -----------
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginSetup
D [18/Aug/2004:16:04:18 -0700] [Job 1] Inserting PostScript code for CUPS' page accounting
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginFeature: *Resolution 300x300dpi
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: Resolution=300x300dpi --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %% FoomaticRIPOptionSetting: Resolution=300x300dpi
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: Resolution=300x300dpi --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginFeature: *PageRegion Letter
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: PageRegion=Letter --> Option will be set by PostScript interpreter
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginFeature: *Quality Default
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: Quality=Default --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %% FoomaticRIPOptionSetting: Quality=Default
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: Quality=Default --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginFeature: *MediaType Plain
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: MediaType=Plain --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %% FoomaticRIPOptionSetting: MediaType=Plain
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: MediaType=Plain --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginFeature: *CompressionMethod Default
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: CompressionMethod=Default --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %% FoomaticRIPOptionSetting: CompressionMethod=Default
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: CompressionMethod=Default --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginFeature: *InputSlot Tray
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: InputSlot=Tray --> Option will be set by PostScript interpreter
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginFeature: *Manual False
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: Manual=False --> Correcting numerical/string option to Manual=0 (Command line argument)
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginFeature: *LeadingEdge Default
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: LeadingEdge=Default --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %% FoomaticRIPOptionSetting: LeadingEdge=Default
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: LeadingEdge=Default --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginFeature: *BlackLevels Default
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: BlackLevels=Default --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %% FoomaticRIPOptionSetting: BlackLevels=Default
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: BlackLevels=Default --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginFeature: *DryTime 0
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: DryTime=0 --> Correcting numerical/string option to DryTime=20 (Command line argument)
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginFeature: *IntensityRendering Halftones
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: IntensityRendering=Halftones --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %% FoomaticRIPOptionSetting: IntensityRendering=Halftones
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: IntensityRendering=Halftones --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginFeature: *RasterGraphicsQuality Default
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: RasterGraphicsQuality=Default --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %% FoomaticRIPOptionSetting: RasterGraphicsQuality=Default
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: RasterGraphicsQuality=Default --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginFeature: *Passes Default
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: Passes=Default --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %% FoomaticRIPOptionSetting: Passes=Default
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: Passes=Default --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginFeature: *OnlyCRD False
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: OnlyCRD=False --> Correcting numerical/string option to OnlyCRD=0 (Command line argument)
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginFeature: *PCL3GUI False
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: PCL3GUI=False --> Correcting numerical/string option to PCL3GUI=0 (Command line argument)
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginFeature: *DitherPPI 60
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: DitherPPI=60 --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %% FoomaticRIPOptionSetting: DitherPPI=60
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: DitherPPI=60 --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%BeginFeature: *MemLimit 8388608
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: MemLimit=8388608 --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %% FoomaticRIPOptionSetting: MemLimit=8388608
D [18/Aug/2004:16:04:18 -0700] [Job 1] Option: MemLimit=8388608 --> Setting option
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found: %%EndSetup
D [18/Aug/2004:16:04:18 -0700] [Job 1]
D [18/Aug/2004:16:04:18 -0700] [Job 1] -----------
D [18/Aug/2004:16:04:18 -0700] [Job 1] New page:  1 1
D [18/Aug/2004:16:04:18 -0700] [Job 1] Inserting option code into "PageSetup" section.
D [18/Aug/2004:16:04:18 -0700] [Job 1] No page header or page header not DSC-conforming
D [18/Aug/2004:16:04:18 -0700] [Job 1] Stopping search for page header options
D [18/Aug/2004:16:04:18 -0700] [Job 1] Found:
D [18/Aug/2004:16:04:18 -0700] [Job 1] pageHeight sub			% Move down...
D [18/Aug/2004:16:04:18 -0700] [Job 1] --> Output goes directly to the renderer now.
D [18/Aug/2004:16:04:18 -0700] [Job 1]
D [18/Aug/2004:16:04:18 -0700] [Job 1]
D [18/Aug/2004:16:04:18 -0700] [Job 1] Starting renderer
D [18/Aug/2004:16:04:18 -0700] [Job 1] renderer PID kid4=305
D [18/Aug/2004:16:04:18 -0700] [Job 1] JCL: <job data>
D [18/Aug/2004:16:04:18 -0700] [Job 1]
D [18/Aug/2004:16:04:18 -0700] [Job 1] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=pcl3 -r300x300 -sSubdevice=hpdj520 -sColorModel=Gray -sMedium=0 -dDryTime=20 -sIntensityRendering=halftones -dRasterGraphicsQuality=0 -dDITHERPPI=60 -dMaxBitmap=8388608 -sOutputFile=- -
D [18/Aug/2004:16:04:18 -0700] [Job 1] perl: warning: Setting locale failed.
D [18/Aug/2004:16:04:18 -0700] [Job 1] perl: warning: Please check that your locale settings:
D [18/Aug/2004:16:04:18 -0700] [Job 1] LC_ALL = (unset),
D [18/Aug/2004:16:04:18 -0700] [Job 1] LANG = "en"
D [18/Aug/2004:16:04:18 -0700] [Job 1] are supported and installed on your system.
D [18/Aug/2004:16:04:18 -0700] [Job 1] perl: warning: Falling back to the standard locale ("C").
D [18/Aug/2004:16:04:18 -0700] [Job 1] foomatic-gswrapper: gs '-dBATCH' '-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=pcl3' '-r300x300' '-sSubdevice=hpdj520' '-sColorModel=Gray' '-sMedium=0' '-dDryTime=20' '-sIntensityRendering=halftones' '-dRasterGraphicsQuality=0' '-dDITHERPPI=60' '-dMaxBitmap=8388608' '-sOutputFile=/dev/fd/3' '/dev/fd/0' 3>&1 1>&2
D [18/Aug/2004:16:04:19 -0700] [Job 1]
D [18/Aug/2004:16:04:19 -0700] [Job 1] Closing renderer
D [18/Aug/2004:16:04:23 -0700] ReadClient() 6 GET /printers/HP-DJ520 HTTP/1.1
D [18/Aug/2004:16:04:23 -0700] CGI /usr/local/libexec/cups/cgi-bin/printers.cgi started - PID = 308
I [18/Aug/2004:16:04:23 -0700] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=308)
D [18/Aug/2004:16:04:23 -0700] SendCommand() 6 file=9
D [18/Aug/2004:16:04:23 -0700] AcceptClient() 8 from localhost:631.
D [18/Aug/2004:16:04:23 -0700] ReadClient() 8 POST / HTTP/1.1
D [18/Aug/2004:16:04:23 -0700] ProcessIPPRequest: 8 status_code=0
D [18/Aug/2004:16:04:23 -0700] ReadClient() 8 POST / HTTP/1.1
D [18/Aug/2004:16:04:23 -0700] ProcessIPPRequest: 8 status_code=1
D [18/Aug/2004:16:04:23 -0700] ReadClient() 8 POST / HTTP/1.1
D [18/Aug/2004:16:04:23 -0700] ProcessIPPRequest: 8 status_code=1
D [18/Aug/2004:16:04:23 -0700] CloseClient() 8
D [18/Aug/2004:16:04:23 -0700] AcceptClient() 8 from localhost:631.
D [18/Aug/2004:16:04:23 -0700] ReadClient() 8 GET /images/printer-processing.gif HTTP/1.1
D [18/Aug/2004:16:04:23 -0700] SendError() 8 code=304 (Not Modified)
D [18/Aug/2004:16:04:23 -0700] ReadClient() 5 GET /images/hold-job.gif HTTP/1.1
D [18/Aug/2004:16:04:23 -0700] SendError() 5 code=304 (Not Modified)
D [18/Aug/2004:16:04:23 -0700] AcceptClient() 9 from localhost:631.
D [18/Aug/2004:16:04:23 -0700] ReadClient() 9 GET /images/cancel-job.gif HTTP/1.1
D [18/Aug/2004:16:04:23 -0700] SendError() 9 code=304 (Not Modified)
D [18/Aug/2004:16:04:23 -0700] CloseClient() 5
D [18/Aug/2004:16:04:23 -0700] CloseClient() 8
D [18/Aug/2004:16:04:23 -0700] CloseClient() 9
D [18/Aug/2004:16:05:24 -0700] CloseClient() 6
D [18/Aug/2004:16:06:22 -0700] AcceptClient() 5 from BSDUnix.Workgroup:631.
D [18/Aug/2004:16:06:22 -0700] AcceptClient() 6 from BSDUnix.Workgroup:631.
D [18/Aug/2004:16:06:22 -0700] ReadClient() 6 POST / HTTP/1.1
D [18/Aug/2004:16:06:22 -0700] ProcessIPPRequest: 6 status_code=1
D [18/Aug/2004:16:06:22 -0700] CloseClient() 6
D [18/Aug/2004:16:06:22 -0700] AcceptClient() 6 from BSDUnix.Workgroup:631.
D [18/Aug/2004:16:06:22 -0700] ReadClient() 6 POST / HTTP/1.1
D [18/Aug/2004:16:06:22 -0700] ProcessIPPRequest: 6 status_code=1
D [18/Aug/2004:16:06:22 -0700] CloseClient() 6
D [18/Aug/2004:16:06:22 -0700] AcceptClient() 6 from BSDUnix.Workgroup:631.
D [18/Aug/2004:16:06:22 -0700] ReadClient() 6 POST / HTTP/1.1
D [18/Aug/2004:16:06:22 -0700] ProcessIPPRequest: 6 status_code=0
D [18/Aug/2004:16:06:22 -0700] ReadClient() 5 POST / HTTP/1.1
D [18/Aug/2004:16:06:22 -0700] ProcessIPPRequest: 5 status_code=0
D [18/Aug/2004:16:06:22 -0700] ReadClient() 5 POST / HTTP/1.1
D [18/Aug/2004:16:06:22 -0700] ProcessIPPRequest: 5 status_code=0
D [18/Aug/2004:16:06:22 -0700] CloseClient() 5
D [18/Aug/2004:16:06:22 -0700] CloseClient() 6
D [18/Aug/2004:16:06:38 -0700] AcceptClient() 5 from BSDUnix.Workgroup:631.
D [18/Aug/2004:16:06:38 -0700] ReadClient() 5 POST / HTTP/1.1
D [18/Aug/2004:16:06:38 -0700] ProcessIPPRequest: 5 status_code=1
D [18/Aug/2004:16:06:38 -0700] AcceptClient() 6 from BSDUnix.Workgroup:631.
D [18/Aug/2004:16:06:38 -0700] CloseClient() 5
D [18/Aug/2004:16:06:38 -0700] ReadClient() 6 POST / HTTP/1.1
D [18/Aug/2004:16:06:38 -0700] ProcessIPPRequest: 6 status_code=1
D [18/Aug/2004:16:06:38 -0700] AcceptClient() 5 from BSDUnix.Workgroup:631.
D [18/Aug/2004:16:06:38 -0700] CloseClient() 6
D [18/Aug/2004:16:06:38 -0700] ReadClient() 5 POST / HTTP/1.1
D [18/Aug/2004:16:06:38 -0700] ProcessIPPRequest: 5 status_code=0
D [18/Aug/2004:16:06:38 -0700] AcceptClient() 6 from BSDUnix.Workgroup:631.
D [18/Aug/2004:16:06:38 -0700] ReadClient() 6 POST /jobs/ HTTP/1.1
E [18/Aug/2004:16:06:38 -0700] Unable to open /usr/local/etc/cups/passwd.md5 - No such file or directory
E [18/Aug/2004:16:06:38 -0700] cancel_job: "remroot" not authorized to delete job id 1 owned by ""!
D [18/Aug/2004:16:06:38 -0700] Sending error: client-error-forbidden
D [18/Aug/2004:16:06:38 -0700] ProcessIPPRequest: 6 status_code=401
D [18/Aug/2004:16:06:38 -0700] CloseClient() 6
D [18/Aug/2004:16:06:38 -0700] CloseClient() 5










More information about the cups mailing list