D [20/Jun/2007:18:45:56 -0400] cupsdReadClient: 7 POST / HTTP/1.1
D [20/Jun/2007:18:45:56 -0400] cupsdAuthorize: No authentication data provided.
D [20/Jun/2007:18:45:56 -0400] Get-Jobs ipp://localhost:631/printers/HPDESKJET3550
D [20/Jun/2007:18:45:56 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok)
D [20/Jun/2007:18:45:56 -0400] cupsdCloseClient: 7
D [20/Jun/2007:18:45:57 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4)
D [20/Jun/2007:18:45:57 -0400] cupsdReadClient: 7 GET /printers/HPDESKJET3550?op=print-test-page HTTP/1.1
D [20/Jun/2007:18:45:57 -0400] cupsdReadClient: 7 Browser asked for language "en.utf-8"...
D [20/Jun/2007:18:45:57 -0400] cupsdAuthorize: No authentication data provided.
D [20/Jun/2007:18:45:57 -0400] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 27669
I [20/Jun/2007:18:45:57 -0400] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=27669)
D [20/Jun/2007:18:45:57 -0400] cupsdSendCommand: 7 file=8
D [20/Jun/2007:18:45:57 -0400] cupsdAcceptClient: 9 from localhost (Domain)
D [20/Jun/2007:18:45:57 -0400] cupsdReadClient: 9 POST /printers/HPDESKJET3550 HTTP/1.1
D [20/Jun/2007:18:45:57 -0400] cupsdAuthorize: No authentication data provided.
D [20/Jun/2007:18:45:57 -0400] Print-Job ipp://localhost:631/printers/HPDESKJET3550
D [20/Jun/2007:18:45:57 -0400] print_job: request file type is application/postscript.
D [20/Jun/2007:18:45:57 -0400] add_job: requesting-user-name="guest"
D [20/Jun/2007:18:45:57 -0400] Adding default job-sheets values "none,none"...
I [20/Jun/2007:18:45:57 -0400] Adding start banner page "none" to job 46.
D [20/Jun/2007:18:45:57 -0400] Discarding unused job-created event...
I [20/Jun/2007:18:45:57 -0400] Adding end banner page "none" to job 46.
I [20/Jun/2007:18:45:57 -0400] Job 46 queued on "HPDESKJET3550" by "guest".
D [20/Jun/2007:18:45:57 -0400] Job 46 hold_until = 0
D [20/Jun/2007:18:45:57 -0400] Discarding unused printer-state-changed event...
D [20/Jun/2007:18:45:57 -0400] job-sheets=none,none
D [20/Jun/2007:18:45:57 -0400] banner_page = 0
D [20/Jun/2007:18:45:57 -0400] [Job 46] argv[0]="HPDESKJET3550"
D [20/Jun/2007:18:45:57 -0400] [Job 46] argv[1]="46"
D [20/Jun/2007:18:45:57 -0400] [Job 46] argv[2]="guest"
D [20/Jun/2007:18:45:57 -0400] [Job 46] argv[3]="Test Page"
D [20/Jun/2007:18:45:57 -0400] [Job 46] argv[4]="1"
D [20/Jun/2007:18:45:57 -0400] [Job 46] argv[5]="job-uuid=urn:uuid:3d945725-f1ad-3e06-4e6a-f6a85cfa0abc"
D [20/Jun/2007:18:45:57 -0400] [Job 46] argv[6]="/var/spool/cups/d00046-001"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[9]="SERVER_ADMIN=root@jupiter"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[10]="SOFTWARE=CUPS/1.2.4"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[12]="TZ=America/New_York"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[13]="USER=root"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[16]="IPP_PORT=631"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[17]="CHARSET=utf-8"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[18]="LANG=en"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[19]="PPD=/etc/cups/ppd/HPDESKJET3550.ppd"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[20]="RIP_MAX_CACHE=8m"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[21]="CONTENT_TYPE=application/postscript"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[22]="DEVICE_URI=usb://hp/deskjet%203500?serial=CN35O3F2K376"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[23]="PRINTER=HPDESKJET3550"
D [20/Jun/2007:18:45:57 -0400] [Job 46] envp[24]="FINAL_CONTENT_TYPE=printer/HPDESKJET3550"
I [20/Jun/2007:18:45:57 -0400] Started filter /usr/lib/cups/filter/pstops (PID 27670) for job 46.
I [20/Jun/2007:18:45:57 -0400] Started filter /usr/lib/cups/filter/foomatic-rip (PID 27671) for job 46.
I [20/Jun/2007:18:45:57 -0400] Started backend /usr/lib/cups/backend/usb (PID 27672) for job 46.
D [20/Jun/2007:18:45:57 -0400] Discarding unused job-state event...
D [20/Jun/2007:18:45:57 -0400] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [20/Jun/2007:18:45:57 -0400] [Job 46] Page = 612x792; 18,36 to 594,783
D [20/Jun/2007:18:45:57 -0400] [Job 46] slow_collate=0, slow_duplex=0, slow_order=0
D [20/Jun/2007:18:45:57 -0400] [Job 46] Before copy_comments - %!PS-Adobe-3.0
D [20/Jun/2007:18:45:57 -0400] [Job 46] %!PS-Adobe-3.0
D [20/Jun/2007:18:45:57 -0400] [Job 46] %%BoundingBox: 0 0 612 792
D [20/Jun/2007:18:45:57 -0400] [Job 46] %%Pages: 1
D [20/Jun/2007:18:45:57 -0400] [Job 46] %%LanguageLevel: 1
D [20/Jun/2007:18:45:57 -0400] [Job 46] %%DocumentData: Clean7Bit
D [20/Jun/2007:18:45:57 -0400] [Job 46] %%DocumentSuppliedResources: procset testprint/1.2
D [20/Jun/2007:18:45:57 -0400] [Job 46] %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman
D [20/Jun/2007:18:45:57 -0400] [Job 46] %%Creator: Michael Sweet, Easy Software Products
D [20/Jun/2007:18:45:57 -0400] [Job 46] %%CreationDate: D:20060114093000+0500
D [20/Jun/2007:18:45:57 -0400] [Job 46] %%Title: Test Page
D [20/Jun/2007:18:45:57 -0400] [Job 46] %%EndComments
D [20/Jun/2007:18:45:57 -0400] [Job 46] Before copy_prolog - %%BeginProlog
D [20/Jun/2007:18:45:57 -0400] [Job 46] Before copy_setup - %%Page: 1 1
D [20/Jun/2007:18:45:57 -0400] [Job 46] Before page loop - %%Page: 1 1
D [20/Jun/2007:18:45:57 -0400] [Job 46] Copying page 1...
D [20/Jun/2007:18:45:57 -0400] [Job 46] pagew = 576.0, pagel = 747.0
D [20/Jun/2007:18:45:57 -0400] [Job 46] bboxw = 612, bboxl = 792
D [20/Jun/2007:18:45:57 -0400] [Job 46] PageLeft = 18.0, PageRight = 594.0
D [20/Jun/2007:18:45:57 -0400] [Job 46] PageTop = 783.0, PageBottom = 36.0
D [20/Jun/2007:18:45:57 -0400] [Job 46] PageWidth = 612.0, PageLength = 792.0
D [20/Jun/2007:18:45:57 -0400] [Job 46] Wrote 1 pages...
D [20/Jun/2007:18:45:57 -0400] PID 27670 (/usr/lib/cups/filter/pstops) exited with no errors.
D [20/Jun/2007:18:45:57 -0400] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [20/Jun/2007:18:45:57 -0400] [CGI] locale="en"...
D [20/Jun/2007:18:45:57 -0400] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting at file position 0...
D [20/Jun/2007:18:45:57 -0400] [CGI] "{title}" at 205...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting "{refresh_page?" at 374, result=1...
D [20/Jun/2007:18:45:57 -0400] [CGI] Output first part...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting at file position 374...
D [20/Jun/2007:18:45:57 -0400] [CGI] "{refresh_page}" at 424...
D [20/Jun/2007:18:45:57 -0400] [CGI] Returning at file position 427 on character ':'...
D [20/Jun/2007:18:45:57 -0400] [CGI] Skip second part...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting at file position 427...
D [20/Jun/2007:18:45:57 -0400] [CGI] Returning at file position 428 on character '}'...
D [20/Jun/2007:18:45:57 -0400] [CGI] Finished "{refresh_page?", out=0xb7e774c0...
D [20/Jun/2007:18:45:57 -0400] [CGI] "{title}" at 671...
D [20/Jun/2007:18:45:57 -0400] [CGI] "{title}" at 952...
D [20/Jun/2007:18:45:57 -0400] PID 27669 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [20/Jun/2007:18:45:57 -0400] [Job 46] perl: warning: Setting locale failed.
D [20/Jun/2007:18:45:57 -0400] [Job 46] perl: warning: Please check that your locale settings:
D [20/Jun/2007:18:45:57 -0400] [Job 46] LANGUAGE = (unset),
D [20/Jun/2007:18:45:57 -0400] [Job 46] LC_ALL = (unset),
D [20/Jun/2007:18:45:57 -0400] [Job 46] LANG = "en"
D [20/Jun/2007:18:45:57 -0400] [Job 46] are supported and installed on your system.
D [20/Jun/2007:18:45:57 -0400] [Job 46] perl: warning: Falling back to the standard locale ("C").
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting "{SECTION=admin" at 1411, result=0...
D [20/Jun/2007:18:45:57 -0400] [CGI] Skip first part...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting at file position 1411...
D [20/Jun/2007:18:45:57 -0400] [CGI] Returning at file position 1412 on character ':'...
D [20/Jun/2007:18:45:57 -0400] [CGI] Output second part...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting at file position 1412...
D [20/Jun/2007:18:45:57 -0400] [CGI] Returning at file position 1415 on character '}'...
D [20/Jun/2007:18:45:57 -0400] [CGI] Finished "{SECTION=admin", out=0xb7e774c0...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting "{SECTION=classes" at 1678, result=0...
D [20/Jun/2007:18:45:57 -0400] [CGI] Skip first part...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting at file position 1678...
D [20/Jun/2007:18:45:57 -0400] [CGI] Returning at file position 1679 on character ':'...
D [20/Jun/2007:18:45:57 -0400] [CGI] Output second part...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting at file position 1679...
D [20/Jun/2007:18:45:57 -0400] [CGI] Returning at file position 1682 on character '}'...
D [20/Jun/2007:18:45:57 -0400] [CGI] Finished "{SECTION=classes", out=0xb7e774c0...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting "{SECTION=help" at 1938, result=0...
D [20/Jun/2007:18:45:57 -0400] [CGI] Skip first part...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting at file position 1938...
D [20/Jun/2007:18:45:57 -0400] [CGI] Returning at file position 1939 on character ':'...
D [20/Jun/2007:18:45:57 -0400] [CGI] Output second part...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting at file position 1939...
D [20/Jun/2007:18:45:57 -0400] cupsdCloseClient: 9
D [20/Jun/2007:18:45:57 -0400] Discarding unused printer-state-changed event...
D [20/Jun/2007:18:45:57 -0400] [CGI] Returning at file position 1942 on character '}'...
D [20/Jun/2007:18:45:57 -0400] [CGI] Finished "{SECTION=help", out=0xb7e774c0...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting "{SECTION=jobs" at 2206, result=0...
D [20/Jun/2007:18:45:57 -0400] [CGI] Skip first part...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting at file position 2206...
D [20/Jun/2007:18:45:57 -0400] [CGI] Returning at file position 2207 on character ':'...
D [20/Jun/2007:18:45:57 -0400] [CGI] Output second part...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting at file position 2207...
D [20/Jun/2007:18:45:57 -0400] [CGI] Returning at file position 2210 on character '}'...
D [20/Jun/2007:18:45:57 -0400] [CGI] Finished "{SECTION=jobs", out=0xb7e774c0...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting "{SECTION=printers" at 2464, result=1...
D [20/Jun/2007:18:45:57 -0400] [CGI] Output first part...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting at file position 2464...
D [20/Jun/2007:18:45:57 -0400] [CGI] Returning at file position 2465 on character ':'...
D [20/Jun/2007:18:45:57 -0400] [CGI] Skip second part...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting at file position 2465...
D [20/Jun/2007:18:45:57 -0400] [CGI] Returning at file position 2468 on character '}'...
D [20/Jun/2007:18:45:57 -0400] [CGI] Finished "{SECTION=printers", out=0xb7e774c0...
D [20/Jun/2007:18:45:57 -0400] [CGI] Returning at file position 2830 on EOF...
D [20/Jun/2007:18:45:57 -0400] [CGI] cgiCopyTemplateLang(tmpl="test-page.tmpl")
D [20/Jun/2007:18:45:57 -0400] [CGI] locale="en"...
D [20/Jun/2007:18:45:57 -0400] [CGI] Template file is "/usr/share/cups/templates/test-page.tmpl"...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting at file position 0...
D [20/Jun/2007:18:45:57 -0400] [CGI] "{SECTION}" at 48...
D [20/Jun/2007:18:45:57 -0400] [CGI] "{printer_name}" at 63...
D [20/Jun/2007:18:45:57 -0400] [CGI] "{printer_name}" at 80...
D [20/Jun/2007:18:45:57 -0400] [CGI] "{job_id}" at 89...
D [20/Jun/2007:18:45:57 -0400] [CGI] Returning at file position 99 on EOF...
D [20/Jun/2007:18:45:57 -0400] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [20/Jun/2007:18:45:57 -0400] [CGI] locale="en"...
D [20/Jun/2007:18:45:57 -0400] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"...
D [20/Jun/2007:18:45:57 -0400] [CGI] Starting at file position 0...
D [20/Jun/2007:18:45:57 -0400] [CGI] Returning at file position 614 on EOF...
D [20/Jun/2007:18:45:57 -0400] [Job 46] foomatic-rip version $Revision$ running...
D [20/Jun/2007:18:45:57 -0400] [Job 46] Parsing PPD file ...
D [20/Jun/2007:18:45:57 -0400] [Job 46] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
D [20/Jun/2007:18:45:57 -0400] [Job 46] Added option ColorSpace
D [20/Jun/2007:18:45:57 -0400] [Job 46] Added option Resolution
D [20/Jun/2007:18:45:57 -0400] [Job 46] Added option PageSize
D [20/Jun/2007:18:45:57 -0400] [Job 46] Added option PageRegion
D [20/Jun/2007:18:45:57 -0400] [Job 46] Added option Model
D [20/Jun/2007:18:45:57 -0400] [Job 46] Added option PrintoutMode
D [20/Jun/2007:18:45:57 -0400] [Job 46] Added option ImageableArea
D [20/Jun/2007:18:45:57 -0400] [Job 46] Added option PaperDimension
D [20/Jun/2007:18:45:57 -0400] [Job 46] Added option Quality
D [20/Jun/2007:18:45:57 -0400] [Job 46] Added option Font
D [20/Jun/2007:18:45:57 -0400] [Job 46] 
D [20/Jun/2007:18:45:57 -0400] [Job 46] Parameter Summary
D [20/Jun/2007:18:45:57 -0400] [Job 46] -----------------
D [20/Jun/2007:18:45:57 -0400] [Job 46] 
D [20/Jun/2007:18:45:57 -0400] [Job 46] Spooler: cups
D [20/Jun/2007:18:45:57 -0400] [Job 46] Printer: HPDESKJET3550
D [20/Jun/2007:18:45:57 -0400] [Job 46] Shell: /bin/bash
D [20/Jun/2007:18:45:57 -0400] [Job 46] PPD file: /etc/cups/ppd/HPDESKJET3550.ppd
D [20/Jun/2007:18:45:57 -0400] [Job 46] ATTR file: 
D [20/Jun/2007:18:45:57 -0400] [Job 46] Printer model: HP DeskJet 3550 Foomatic/hpijs (recommended)
D [20/Jun/2007:18:45:57 -0400] [Job 46] Job title: Test Page
D [20/Jun/2007:18:45:57 -0400] [Job 46] File(s) to be printed: 
D [20/Jun/2007:18:45:57 -0400] [Job 46] <STDIN>
D [20/Jun/2007:18:45:57 -0400] [Job 46] 
D [20/Jun/2007:18:45:57 -0400] [Job 46] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [20/Jun/2007:18:45:57 -0400] [Job 46] Pondering option 'job-uuid=urn:uuid:3d945725-f1ad-3e06-4e6a-f6a85cfa0abc'
D [20/Jun/2007:18:45:57 -0400] [Job 46] Unknown option job-uuid=urn:uuid:3d945725-f1ad-3e06-4e6a-f6a85cfa0abc.
D [20/Jun/2007:18:45:57 -0400] [Job 46] 
D [20/Jun/2007:18:45:57 -0400] [Job 46] ================================================
D [20/Jun/2007:18:45:57 -0400] [Job 46] 
D [20/Jun/2007:18:45:57 -0400] [Job 46] File: <STDIN>
D [20/Jun/2007:18:45:57 -0400] [Job 46] 
D [20/Jun/2007:18:45:57 -0400] [Job 46] ================================================
D [20/Jun/2007:18:45:57 -0400] [Job 46] 
D [20/Jun/2007:18:45:57 -0400] [Job 46] Reading PostScript input ...
D [20/Jun/2007:18:45:57 -0400] [Job 46] --> This document is DSC-conforming!
D [20/Jun/2007:18:45:57 -0400] [Job 46] 
D [20/Jun/2007:18:45:57 -0400] [Job 46] -----------
D [20/Jun/2007:18:45:57 -0400] [Job 46] Found: %%BeginProlog
D [20/Jun/2007:18:45:57 -0400] [Job 46] Found: %%EndProlog
D [20/Jun/2007:18:45:57 -0400] [Job 46] 
D [20/Jun/2007:18:45:57 -0400] [Job 46] -----------
D [20/Jun/2007:18:45:57 -0400] [Job 46] Found: %%BeginSetup
D [20/Jun/2007:18:45:57 -0400] [Job 46] Found: %%BeginFeature: *PrintoutMode Draft.Gray
D [20/Jun/2007:18:45:57 -0400] [Job 46] Option: PrintoutMode=Draft.Gray --> Setting option
D [20/Jun/2007:18:45:57 -0400] [Job 46] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Draft.Gray
D [20/Jun/2007:18:45:57 -0400] [Job 46] Option: PrintoutMode=Draft.Gray --> Setting option
D [20/Jun/2007:18:45:57 -0400] [Job 46] Found: %%BeginFeature: *Quality FromPrintoutMode
D [20/Jun/2007:18:45:57 -0400] [Job 46] Option: Quality=FromPrintoutMode --> Setting option
D [20/Jun/2007:18:45:57 -0400] [Job 46] Found: %% FoomaticRIPOptionSetting: Quality=@PrintoutMode
D [20/Jun/2007:18:45:57 -0400] [Job 46] Option: Quality=FromPrintoutMode --> Setting option
D [20/Jun/2007:18:45:57 -0400] [Job 46] Found: %%BeginFeature: *PageSize Letter
D [20/Jun/2007:18:45:57 -0400] [Job 46] Option: PageSize=Letter --> Setting option
D [20/Jun/2007:18:45:57 -0400] [Job 46] Found: %% FoomaticRIPOptionSetting: PageSize=Letter
D [20/Jun/2007:18:45:57 -0400] [Job 46] Option: PageSize=Letter --> Setting option
D [20/Jun/2007:18:45:57 -0400] [Job 46] Found: %%EndSetup
D [20/Jun/2007:18:45:57 -0400] [Job 46] Inserting PostScript code for CUPS' page accounting
D [20/Jun/2007:18:45:57 -0400] [Job 46] 
D [20/Jun/2007:18:45:57 -0400] [Job 46] -----------
D [20/Jun/2007:18:45:57 -0400] [Job 46] New page:  1 1
D [20/Jun/2007:18:45:57 -0400] [Job 46] Inserting option code into "PageSetup" section.
D [20/Jun/2007:18:45:57 -0400] [Job 46] 
D [20/Jun/2007:18:45:57 -0400] [Job 46] Found: %%BeginPageSetup
D [20/Jun/2007:18:45:57 -0400] [Job 46] Found: %%EndPageSetup
D [20/Jun/2007:18:45:57 -0400] [Job 46] End of page header
D [20/Jun/2007:18:45:57 -0400] [Job 46] Stopping search for page header options
D [20/Jun/2007:18:45:57 -0400] [Job 46] Found:
D [20/Jun/2007:18:45:57 -0400] [Job 46] wheelSize -0.4 mul 0 rlineto	% Left side...
D [20/Jun/2007:18:45:57 -0400] [Job 46] --> Output goes directly to the renderer now.
D [20/Jun/2007:18:45:57 -0400] [Job 46] 
D [20/Jun/2007:18:45:57 -0400] [Job 46] 
D [20/Jun/2007:18:45:57 -0400] [Job 46] Starting renderer
D [20/Jun/2007:18:45:57 -0400] [Job 46] 
D [20/Jun/2007:18:45:57 -0400] [Job 46] Closing renderer
D [20/Jun/2007:18:45:57 -0400] [Job 46] renderer PID kid4=27680
D [20/Jun/2007:18:45:57 -0400] [Job 46] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="deskjet 3320" -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -r300 -sIjsParams=Quality:Quality=1,Quality:ColorMode=0,Quality:MediaType=0,Quality:PenSet=0 -dIjsUseOutputFD -sOutputFile=- -
D [20/Jun/2007:18:45:57 -0400] [Job 46] JCL: <job data> 
D [20/Jun/2007:18:45:57 -0400] [Job 46] 
D [20/Jun/2007:18:45:57 -0400] [Job 46] perl: warning: Setting locale failed.
D [20/Jun/2007:18:45:57 -0400] [Job 46] perl: warning: Please check that your locale settings:
D [20/Jun/2007:18:45:57 -0400] [Job 46] LANGUAGE = (unset),
D [20/Jun/2007:18:45:57 -0400] [Job 46] LC_ALL = (unset),
D [20/Jun/2007:18:45:57 -0400] [Job 46] LANG = "en"
D [20/Jun/2007:18:45:57 -0400] [Job 46] are supported and installed on your system.
D [20/Jun/2007:18:45:57 -0400] [Job 46] perl: warning: Falling back to the standard locale ("C").
D [20/Jun/2007:18:45:58 -0400] [Job 46] foomatic-gswrapper: gs '-sstdout=%stderr' '-dBATCH' '-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=ijs' '-sIjsServer=hpijs' '-sDeviceManufacturer=HEWLETT-PACKARD' '-sDeviceModel=deskjet 3320' '-dDEVICEWIDTHPOINTS=612' '-dDEVICEHEIGHTPOINTS=792' '-r300' '-sIjsParams=Quality:Quality=1,Quality:ColorMode=0,Quality:MediaType=0,Quality:PenSet=0' '-dIjsUseOutputFD' '-sOutputFile=%stdout' '-'
D [20/Jun/2007:18:45:59 -0400] cupsdReadClient: 7 GET /printers/HPDESKJET3550 HTTP/1.1
D [20/Jun/2007:18:45:59 -0400] cupsdReadClient: 7 Browser asked for language "en.utf-8"...
D [20/Jun/2007:18:45:59 -0400] cupsdAuthorize: No authentication data provided.
D [20/Jun/2007:18:45:59 -0400] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 27687
I [20/Jun/2007:18:45:59 -0400] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=27687)
D [20/Jun/2007:18:45:59 -0400] cupsdSendCommand: 7 file=8
D [20/Jun/2007:18:46:00 -0400] cupsdAcceptClient: 9 from localhost (Domain)
D [20/Jun/2007:18:46:00 -0400] cupsdReadClient: 9 POST / HTTP/1.1
D [20/Jun/2007:18:46:00 -0400] cupsdAuthorize: No authentication data provided.
D [20/Jun/2007:18:46:00 -0400] CUPS-Get-Default
D [20/Jun/2007:18:46:00 -0400] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [20/Jun/2007:18:46:00 -0400] [CGI] show_printer(http=0x8070fe8, printer="HPDESKJET3550")
D [20/Jun/2007:18:46:00 -0400] cupsdReadClient: 9 POST / HTTP/1.1
D [20/Jun/2007:18:46:00 -0400] cupsdAuthorize: No authentication data provided.
D [20/Jun/2007:18:46:00 -0400] Get-Printer-Attributes ipp://localhost/printers/HPDESKJET3550
D [20/Jun/2007:18:46:00 -0400] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [20/Jun/2007:18:46:00 -0400] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [20/Jun/2007:18:46:00 -0400] [CGI] locale="en"...
D [20/Jun/2007:18:46:00 -0400] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 0...
D [20/Jun/2007:18:46:00 -0400] [CGI] "{title}" at 205...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting "{refresh_page?" at 374, result=1...
D [20/Jun/2007:18:46:00 -0400] [CGI] Output first part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 374...
D [20/Jun/2007:18:46:00 -0400] [CGI] "{refresh_page}" at 424...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 427 on character ':'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Skip second part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 427...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 428 on character '}'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Finished "{refresh_page?", out=0xb7de24c0...
D [20/Jun/2007:18:46:00 -0400] [CGI] "{title}" at 671...
D [20/Jun/2007:18:46:00 -0400] [CGI] "{title}" at 952...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting "{SECTION=admin" at 1411, result=0...
D [20/Jun/2007:18:46:00 -0400] [CGI] Skip first part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 1411...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 1412 on character ':'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Output second part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 1412...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 1415 on character '}'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Finished "{SECTION=admin", out=0xb7de24c0...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting "{SECTION=classes" at 1678, result=0...
D [20/Jun/2007:18:46:00 -0400] [CGI] Skip first part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 1678...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 1679 on character ':'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Output second part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 1679...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 1682 on character '}'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Finished "{SECTION=classes", out=0xb7de24c0...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting "{SECTION=help" at 1938, result=0...
D [20/Jun/2007:18:46:00 -0400] [CGI] Skip first part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 1938...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 1939 on character ':'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Output second part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 1939...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 1942 on character '}'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Finished "{SECTION=help", out=0xb7de24c0...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting "{SECTION=jobs" at 2206, result=0...
D [20/Jun/2007:18:46:00 -0400] [CGI] Skip first part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 2206...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 2207 on character ':'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Output second part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 2207...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 2210 on character '}'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Finished "{SECTION=jobs", out=0xb7de24c0...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting "{SECTION=printers" at 2464, result=1...
D [20/Jun/2007:18:46:00 -0400] [CGI] Output first part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 2464...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 2465 on character ':'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Skip second part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 2465...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 2468 on character '}'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Finished "{SECTION=printers", out=0xb7de24c0...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 2830 on EOF...
D [20/Jun/2007:18:46:00 -0400] [CGI] cgiCopyTemplateLang(tmpl="printers.tmpl")
D [20/Jun/2007:18:46:00 -0400] [CGI] locale="en"...
D [20/Jun/2007:18:46:00 -0400] [CGI] Template file is "/usr/share/cups/templates/printers.tmpl"...
D [20/Jun/2007:18:46:00 -0400] cupsdReadClient: 9 POST / HTTP/1.1
D [20/Jun/2007:18:46:00 -0400] cupsdAuthorize: No authentication data provided.
D [20/Jun/2007:18:46:00 -0400] Get-Jobs ipp://localhost:631/printers/HPDESKJET3550
D [20/Jun/2007:18:46:00 -0400] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 0...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting "{printer_type?" at 14, result=1...
D [20/Jun/2007:18:46:00 -0400] [CGI] Output first part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 14...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 15 on character ':'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Skip second part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 15...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 16 on character '}'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Finished "{printer_type?", out=0xb7de24c0...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting "{#printer_name=0" at 33, result=0...
D [20/Jun/2007:18:46:00 -0400] [CGI] Skip first part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 33...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 34 on character ':'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Output second part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 34...
D [20/Jun/2007:18:46:00 -0400] [CGI] Looping on "printer_name" at 50, count=1...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 50...
D [20/Jun/2007:18:46:00 -0400] [CGI] "{printer_uri_supported}" at 101...
D [20/Jun/2007:18:46:00 -0400] [CGI] "{printer_name}" at 117...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting "{default_name=HPDESKJET3550" at 150, result=1...
D [20/Jun/2007:18:46:00 -0400] [CGI] Output first part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 150...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 169 on character ':'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Skip second part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 169...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 170 on character '}'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Finished "{default_name=HPDESKJET3550", out=0xb7de24c0...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting "{?printer_state_message=" at 196, result=0...
D [20/Jun/2007:18:46:00 -0400] [CGI] Skip first part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 196...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 197 on character ':'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Output second part...
D [20/Jun/2007:18:46:00 -0400] [CGI] Starting at file position 197...
D [20/Jun/2007:18:46:00 -0400] [CGI] "{printer_state_message}" at 243...
D [20/Jun/2007:18:46:00 -0400] [CGI] Returning at file position 252 on character '}'...
D [20/Jun/2007:18:46:00 -0400] [CGI] Finished "{?printer_state_message=", out=0xb7de24c0...
D [20/Jun/2007:18:46:00 -0400] [CGI] "{printer_name}" at 349...