D [21/Jun/2007:17:33:13 +0100] cupsdReadClient: 9 GET /printers/HP710C?op=print-test-page HTTP/1.1 D [21/Jun/2007:17:33:13 +0100] cupsdReadClient: 9 Browser asked for language "en.utf-8"... D [21/Jun/2007:17:33:13 +0100] cupsdAuthorize: No authentication data provided. D [21/Jun/2007:17:33:13 +0100] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 27249 I [21/Jun/2007:17:33:13 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=27249) D [21/Jun/2007:17:33:13 +0100] cupsdSendCommand: 9 file=11 D [21/Jun/2007:17:33:13 +0100] cupsdAcceptClient: 13 from localhost (Domain) D [21/Jun/2007:17:33:13 +0100] cupsdReadClient: 13 POST /printers/HP710C HTTP/1.1 D [21/Jun/2007:17:33:13 +0100] cupsdAuthorize: No authentication data provided. D [21/Jun/2007:17:33:13 +0100] Print-Job ipp://localhost:631/printers/HP710C D [21/Jun/2007:17:33:13 +0100] print_job: request file type is application/postscript. D [21/Jun/2007:17:33:13 +0100] add_job: requesting-user-name="guest" D [21/Jun/2007:17:33:13 +0100] Adding default job-sheets values "none,none"... I [21/Jun/2007:17:33:13 +0100] Adding start banner page "none" to job 230. D [21/Jun/2007:17:33:13 +0100] Discarding unused job-created event... I [21/Jun/2007:17:33:13 +0100] Adding end banner page "none" to job 230. I [21/Jun/2007:17:33:13 +0100] Job 230 queued on "HP710C" by "guest". D [21/Jun/2007:17:33:13 +0100] Job 230 hold_until = 0 D [21/Jun/2007:17:33:13 +0100] Discarding unused printer-state-changed event... D [21/Jun/2007:17:33:13 +0100] job-sheets=none,none D [21/Jun/2007:17:33:13 +0100] banner_page = 0 D [21/Jun/2007:17:33:13 +0100] [Job 230] argv[0]="HP710C" D [21/Jun/2007:17:33:13 +0100] [Job 230] argv[1]="230" D [21/Jun/2007:17:33:13 +0100] [Job 230] argv[2]="guest" D [21/Jun/2007:17:33:13 +0100] [Job 230] argv[3]="Test Page" D [21/Jun/2007:17:33:13 +0100] [Job 230] argv[4]="1" D [21/Jun/2007:17:33:13 +0100] [Job 230] argv[5]="job-uuid=urn:uuid:94bf6e6b-e6d6-3250-748c-25a62eb22e29" D [21/Jun/2007:17:33:13 +0100] [Job 230] argv[6]="/var/spool/cups/d00230-001" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[1]="CUPS_DATADIR=/usr/share/cups" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[6]="CUPS_SERVERROOT=/etc/cups" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[7]="CUPS_STATEDIR=/var/run/cups" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[9]="SERVER_ADMIN=root@baron" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[10]="SOFTWARE=CUPS/1.2.7" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[11]="TMPDIR=/var/spool/cups/tmp" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[12]="TZ=Europe/London" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[13]="USER=root" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[15]="CUPS_ENCRYPTION=IfRequested" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[16]="IPP_PORT=631" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[17]="CHARSET=utf-8" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[18]="LANG=en" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[19]="PPD=/etc/cups/ppd/HP710C.ppd" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[20]="RIP_MAX_CACHE=8m" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[21]="CONTENT_TYPE=application/postscript" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[22]="DEVICE_URI=parallel:/dev/lp0" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[23]="PRINTER=HP710C" D [21/Jun/2007:17:33:13 +0100] [Job 230] envp[24]="FINAL_CONTENT_TYPE=printer/HP710C" I [21/Jun/2007:17:33:13 +0100] Started filter /usr/lib/cups/filter/pstops (PID 27250) for job 230. I [21/Jun/2007:17:33:13 +0100] Started filter /usr/lib/cups/filter/foomatic-rip (PID 27251) for job 230. I [21/Jun/2007:17:33:13 +0100] Started backend /usr/lib/cups/backend/parallel (PID 27252) for job 230. D [21/Jun/2007:17:33:13 +0100] Discarding unused job-state event... D [21/Jun/2007:17:33:13 +0100] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [21/Jun/2007:17:33:13 +0100] Discarding unused printer-state-changed event... D [21/Jun/2007:17:33:13 +0100] PID 27249 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [21/Jun/2007:17:33:13 +0100] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl") D [21/Jun/2007:17:33:13 +0100] [CGI] locale="en"... D [21/Jun/2007:17:33:13 +0100] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting at file position 0... D [21/Jun/2007:17:33:13 +0100] [CGI] "{title}" at 205... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting "{refresh_page?" at 374, result=1... D [21/Jun/2007:17:33:13 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting at file position 374... D [21/Jun/2007:17:33:13 +0100] [CGI] "{refresh_page}" at 424... D [21/Jun/2007:17:33:13 +0100] [CGI] Returning at file position 427 on character ':'... D [21/Jun/2007:17:33:13 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting at file position 427... D [21/Jun/2007:17:33:13 +0100] [CGI] Returning at file position 428 on character '}'... D [21/Jun/2007:17:33:13 +0100] [CGI] Finished "{refresh_page?", out=0xb7df23a0... D [21/Jun/2007:17:33:13 +0100] [CGI] "{title}" at 671... D [21/Jun/2007:17:33:13 +0100] [CGI] "{title}" at 952... D [21/Jun/2007:17:33:13 +0100] cupsdCloseClient: 13 D [21/Jun/2007:17:33:13 +0100] [CGI] Starting "{SECTION=admin" at 1411, result=0... D [21/Jun/2007:17:33:13 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting at file position 1411... D [21/Jun/2007:17:33:13 +0100] [CGI] Returning at file position 1412 on character ':'... D [21/Jun/2007:17:33:13 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting at file position 1412... D [21/Jun/2007:17:33:13 +0100] [CGI] Returning at file position 1415 on character '}'... D [21/Jun/2007:17:33:13 +0100] [CGI] Finished "{SECTION=admin", out=0xb7df23a0... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting "{SECTION=classes" at 1678, result=0... D [21/Jun/2007:17:33:13 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting at file position 1678... D [21/Jun/2007:17:33:13 +0100] [CGI] Returning at file position 1679 on character ':'... D [21/Jun/2007:17:33:13 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting at file position 1679... D [21/Jun/2007:17:33:13 +0100] [CGI] Returning at file position 1682 on character '}'... D [21/Jun/2007:17:33:13 +0100] [CGI] Finished "{SECTION=classes", out=0xb7df23a0... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting "{SECTION=help" at 1938, result=0... D [21/Jun/2007:17:33:13 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting at file position 1938... D [21/Jun/2007:17:33:13 +0100] [CGI] Returning at file position 1939 on character ':'... D [21/Jun/2007:17:33:13 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting at file position 1939... D [21/Jun/2007:17:33:13 +0100] [CGI] Returning at file position 1942 on character '}'... D [21/Jun/2007:17:33:13 +0100] [CGI] Finished "{SECTION=help", out=0xb7df23a0... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting "{SECTION=jobs" at 2206, result=0... D [21/Jun/2007:17:33:13 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting at file position 2206... D [21/Jun/2007:17:33:13 +0100] [CGI] Returning at file position 2207 on character ':'... D [21/Jun/2007:17:33:13 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting at file position 2207... D [21/Jun/2007:17:33:13 +0100] [CGI] Returning at file position 2210 on character '}'... D [21/Jun/2007:17:33:13 +0100] [CGI] Finished "{SECTION=jobs", out=0xb7df23a0... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting "{SECTION=printers" at 2464, result=1... D [21/Jun/2007:17:33:13 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting at file position 2464... D [21/Jun/2007:17:33:13 +0100] [CGI] Returning at file position 2465 on character ':'... D [21/Jun/2007:17:33:13 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting at file position 2465... D [21/Jun/2007:17:33:13 +0100] [CGI] Returning at file position 2468 on character '}'... D [21/Jun/2007:17:33:13 +0100] [CGI] Finished "{SECTION=printers", out=0xb7df23a0... D [21/Jun/2007:17:33:13 +0100] [CGI] Returning at file position 2830 on EOF... D [21/Jun/2007:17:33:13 +0100] [CGI] cgiCopyTemplateLang(tmpl="test-page.tmpl") D [21/Jun/2007:17:33:13 +0100] [CGI] locale="en"... D [21/Jun/2007:17:33:13 +0100] [CGI] Template file is "/usr/share/cups/templates/test-page.tmpl"... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting at file position 0... D [21/Jun/2007:17:33:13 +0100] [CGI] "{SECTION}" at 48... D [21/Jun/2007:17:33:13 +0100] [CGI] "{printer_name}" at 63... D [21/Jun/2007:17:33:13 +0100] [CGI] "{printer_name}" at 80... D [21/Jun/2007:17:33:13 +0100] [CGI] "{job_id}" at 89... D [21/Jun/2007:17:33:13 +0100] [CGI] Returning at file position 99 on EOF... D [21/Jun/2007:17:33:13 +0100] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl") D [21/Jun/2007:17:33:13 +0100] [CGI] locale="en"... D [21/Jun/2007:17:33:13 +0100] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"... D [21/Jun/2007:17:33:13 +0100] [CGI] Starting at file position 0... D [21/Jun/2007:17:33:13 +0100] [CGI] Returning at file position 614 on EOF... D [21/Jun/2007:17:33:13 +0100] [Job 230] perl: warning: Setting locale failed. D [21/Jun/2007:17:33:13 +0100] [Job 230] perl: warning: Please check that your locale settings: D [21/Jun/2007:17:33:13 +0100] [Job 230] LANGUAGE = (unset), D [21/Jun/2007:17:33:13 +0100] [Job 230] LC_ALL = (unset), D [21/Jun/2007:17:33:13 +0100] [Job 230] LANG = "en" D [21/Jun/2007:17:33:13 +0100] [Job 230] are supported and installed on your system. D [21/Jun/2007:17:33:13 +0100] [Job 230] perl: warning: Falling back to the standard locale ("C"). D [21/Jun/2007:17:33:13 +0100] [Job 230] Page = 595x842; 0,0 to 595,842 D [21/Jun/2007:17:33:13 +0100] [Job 230] slow_collate=0, slow_duplex=0, slow_order=0 D [21/Jun/2007:17:33:13 +0100] [Job 230] Before copy_comments - %!PS-Adobe-3.0 D [21/Jun/2007:17:33:13 +0100] [Job 230] %!PS-Adobe-3.0 D [21/Jun/2007:17:33:13 +0100] [Job 230] %%BoundingBox: 0 0 612 792 D [21/Jun/2007:17:33:13 +0100] [Job 230] %%Pages: 1 D [21/Jun/2007:17:33:13 +0100] [Job 230] %%LanguageLevel: 1 D [21/Jun/2007:17:33:13 +0100] [Job 230] %%DocumentData: Clean7Bit D [21/Jun/2007:17:33:13 +0100] [Job 230] %%DocumentSuppliedResources: procset testprint/1.2 D [21/Jun/2007:17:33:13 +0100] [Job 230] %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman D [21/Jun/2007:17:33:13 +0100] [Job 230] %%Creator: Michael Sweet, Easy Software Products D [21/Jun/2007:17:33:13 +0100] [Job 230] %%CreationDate: D:20060114093000+0500 D [21/Jun/2007:17:33:13 +0100] [Job 230] %%Title: Test Page D [21/Jun/2007:17:33:13 +0100] [Job 230] %%EndComments D [21/Jun/2007:17:33:13 +0100] [Job 230] Before copy_prolog - %%BeginProlog D [21/Jun/2007:17:33:13 +0100] [Job 230] Before copy_setup - %%Page: 1 1 D [21/Jun/2007:17:33:13 +0100] [Job 230] Before page loop - %%Page: 1 1 D [21/Jun/2007:17:33:13 +0100] [Job 230] Copying page 1... D [21/Jun/2007:17:33:13 +0100] [Job 230] pagew = 595.0, pagel = 842.0 D [21/Jun/2007:17:33:13 +0100] [Job 230] bboxw = 595, bboxl = 842 D [21/Jun/2007:17:33:13 +0100] [Job 230] PageLeft = 0.0, PageRight = 595.0 D [21/Jun/2007:17:33:13 +0100] [Job 230] PageTop = 842.0, PageBottom = 0.0 D [21/Jun/2007:17:33:13 +0100] [Job 230] PageWidth = 595.0, PageLength = 842.0 D [21/Jun/2007:17:33:13 +0100] [Job 230] Wrote 1 pages... D [21/Jun/2007:17:33:13 +0100] PID 27250 (/usr/lib/cups/filter/pstops) exited with no errors. D [21/Jun/2007:17:33:13 +0100] [Job 230] backendRunLoop(print_fd=0, device_fd=4, use_bc=0) D [21/Jun/2007:17:33:13 +0100] Discarding unused printer-state-changed event... D [21/Jun/2007:17:33:14 +0100] [Job 230] foomatic-rip version $Revision$ running... D [21/Jun/2007:17:33:14 +0100] [Job 230] Parsing PPD file ... D [21/Jun/2007:17:33:14 +0100] [Job 230] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip" D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option ColorSpace D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option Resolution D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option PageSize D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option PageRegion D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option ImageableArea D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option PaperDimension D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option ColorMode D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option EconoFast D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option Dither D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option Bidirectional D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option Blackness D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option TopMargin D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option BottomMargin D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option LeftMargin D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option RightMargin D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option XOffset D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option YOffset D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option Model D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option pnmFormat D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option GammaFile D [21/Jun/2007:17:33:14 +0100] [Job 230] Added option Font D [21/Jun/2007:17:33:14 +0100] [Job 230] D [21/Jun/2007:17:33:14 +0100] [Job 230] Parameter Summary D [21/Jun/2007:17:33:14 +0100] [Job 230] ----------------- D [21/Jun/2007:17:33:14 +0100] [Job 230] D [21/Jun/2007:17:33:14 +0100] [Job 230] Spooler: cups D [21/Jun/2007:17:33:14 +0100] [Job 230] Printer: HP710C D [21/Jun/2007:17:33:14 +0100] [Job 230] Shell: /bin/sh D [21/Jun/2007:17:33:14 +0100] [Job 230] PPD file: /etc/cups/ppd/HP710C.ppd D [21/Jun/2007:17:33:14 +0100] [Job 230] ATTR file: D [21/Jun/2007:17:33:14 +0100] [Job 230] Printer model: HP DeskJet 710C Foomatic/pnm2ppa (recommended) D [21/Jun/2007:17:33:14 +0100] [Job 230] Job title: Test Page D [21/Jun/2007:17:33:14 +0100] [Job 230] File(s) to be printed: D [21/Jun/2007:17:33:14 +0100] [Job 230] D [21/Jun/2007:17:33:14 +0100] [Job 230] D [21/Jun/2007:17:33:14 +0100] [Job 230] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts D [21/Jun/2007:17:33:14 +0100] [Job 230] Pondering option 'job-uuid=urn:uuid:94bf6e6b-e6d6-3250-748c-25a62eb22e29' D [21/Jun/2007:17:33:14 +0100] [Job 230] Unknown option job-uuid=urn:uuid:94bf6e6b-e6d6-3250-748c-25a62eb22e29. D [21/Jun/2007:17:33:14 +0100] [Job 230] D [21/Jun/2007:17:33:14 +0100] [Job 230] ================================================ D [21/Jun/2007:17:33:14 +0100] [Job 230] D [21/Jun/2007:17:33:14 +0100] [Job 230] File: D [21/Jun/2007:17:33:14 +0100] [Job 230] D [21/Jun/2007:17:33:14 +0100] [Job 230] ================================================ D [21/Jun/2007:17:33:14 +0100] [Job 230] D [21/Jun/2007:17:33:14 +0100] [Job 230] Reading PostScript input ... D [21/Jun/2007:17:33:14 +0100] [Job 230] --> This document is DSC-conforming! D [21/Jun/2007:17:33:14 +0100] [Job 230] D [21/Jun/2007:17:33:14 +0100] [Job 230] ----------- D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%BeginProlog D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%EndProlog D [21/Jun/2007:17:33:14 +0100] [Job 230] D [21/Jun/2007:17:33:14 +0100] [Job 230] ----------- D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%BeginSetup D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%BeginFeature: *PageSize A4 D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: PageSize=A4 --> Option will be set by PostScript interpreter D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%BeginFeature: *pnmFormat PixMap D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: pnmFormat=PixMap --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %% FoomaticRIPOptionSetting: pnmFormat=PixMap D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: pnmFormat=PixMap --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%BeginFeature: *ColorMode CMYK D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: ColorMode=CMYK --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %% FoomaticRIPOptionSetting: ColorMode=CMYK D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: ColorMode=CMYK --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%BeginFeature: *EconoFast Off D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: EconoFast=Off --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %% FoomaticRIPOptionSetting: EconoFast=Off D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: EconoFast=Off --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%BeginFeature: *Dither FloydSteinberg D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: Dither=FloydSteinberg --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %% FoomaticRIPOptionSetting: Dither=FloydSteinberg D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: Dither=FloydSteinberg --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%BeginFeature: *GammaFile Default D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: GammaFile=Default --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %% FoomaticRIPOptionSetting: GammaFile=Default D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: GammaFile=Default --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%BeginFeature: *Bidirectional Default D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: Bidirectional=Default --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %% FoomaticRIPOptionSetting: Bidirectional=Default D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: Bidirectional=Default --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%BeginFeature: *Blackness 2 D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: Blackness=2 --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %% FoomaticRIPOptionSetting: Blackness=2 D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: Blackness=2 --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%BeginFeature: *TopMargin 10 D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: TopMargin=10 --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %% FoomaticRIPOptionSetting: TopMargin=10 D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: TopMargin=10 --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%BeginFeature: *BottomMargin 150 D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: BottomMargin=150 --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %% FoomaticRIPOptionSetting: BottomMargin=150 D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: BottomMargin=150 --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%BeginFeature: *LeftMargin 10 D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: LeftMargin=10 --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %% FoomaticRIPOptionSetting: LeftMargin=10 D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: LeftMargin=10 --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%BeginFeature: *RightMargin 10 D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: RightMargin=10 --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %% FoomaticRIPOptionSetting: RightMargin=10 D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: RightMargin=10 --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%BeginFeature: *XOffset 160 D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: XOffset=160 --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %% FoomaticRIPOptionSetting: XOffset=160 D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: XOffset=160 --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%BeginFeature: *YOffset 50 D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: YOffset=50 --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %% FoomaticRIPOptionSetting: YOffset=50 D [21/Jun/2007:17:33:14 +0100] [Job 230] Option: YOffset=50 --> Setting option D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%EndSetup D [21/Jun/2007:17:33:14 +0100] [Job 230] Inserting PostScript code for CUPS' page accounting D [21/Jun/2007:17:33:14 +0100] [Job 230] D [21/Jun/2007:17:33:14 +0100] [Job 230] ----------- D [21/Jun/2007:17:33:14 +0100] [Job 230] New page: 1 1 D [21/Jun/2007:17:33:14 +0100] [Job 230] Inserting option code into "PageSetup" section. D [21/Jun/2007:17:33:14 +0100] [Job 230] D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%BeginPageSetup D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: %%EndPageSetup D [21/Jun/2007:17:33:14 +0100] [Job 230] End of page header D [21/Jun/2007:17:33:14 +0100] [Job 230] Stopping search for page header options D [21/Jun/2007:17:33:14 +0100] [Job 230] Found: D [21/Jun/2007:17:33:14 +0100] [Job 230] wheelSize -0.4 mul 0 rlineto % Left side... D [21/Jun/2007:17:33:14 +0100] [Job 230] --> Output goes directly to the renderer now. D [21/Jun/2007:17:33:14 +0100] [Job 230] D [21/Jun/2007:17:33:14 +0100] [Job 230] D [21/Jun/2007:17:33:14 +0100] [Job 230] Starting renderer D [21/Jun/2007:17:33:14 +0100] [Job 230] JCL: D [21/Jun/2007:17:33:14 +0100] [Job 230] D [21/Jun/2007:17:33:14 +0100] [Job 230] renderer PID kid4=27257 D [21/Jun/2007:17:33:14 +0100] [Job 230] renderer command: gs -q -dNOPAUSE -dPARANOIDSAFER -dBATCH -r600 -sDEVICE=ppmraw -sOutputFile=- - | pnm2ppa -v 710 -B 2 -t 10 -b 150 -l 10 -r 10 -x 160 -y 50 -i - -o - D [21/Jun/2007:17:33:14 +0100] [Job 230] D [21/Jun/2007:17:33:14 +0100] [Job 230] Closing renderer D [21/Jun/2007:17:33:14 +0100] [Job 230] sh: pnm2ppa: command not found D [21/Jun/2007:17:33:14 +0100] [Job 230] perl: warning: Setting locale failed. D [21/Jun/2007:17:33:14 +0100] [Job 230] perl: warning: Please check that your locale settings: D [21/Jun/2007:17:33:14 +0100] [Job 230] LANGUAGE = (unset), D [21/Jun/2007:17:33:14 +0100] [Job 230] LC_ALL = (unset), D [21/Jun/2007:17:33:14 +0100] [Job 230] LANG = "en" D [21/Jun/2007:17:33:14 +0100] [Job 230] are supported and installed on your system. D [21/Jun/2007:17:33:14 +0100] [Job 230] perl: warning: Falling back to the standard locale ("C"). D [21/Jun/2007:17:33:14 +0100] [Job 230] foomatic-gswrapper: gs '-sstdout=%stderr' '-dNOPAUSE' '-dPARANOIDSAFER' '-dBATCH' '-r600' '-sDEVICE=ppmraw' '-sOutputFile=%stdout' '-' D [21/Jun/2007:17:33:14 +0100] [Job 230] ESP Ghostscript 815.03 (2006-08-25) D [21/Jun/2007:17:33:14 +0100] [Job 230] Copyright (C) 2004 artofcode LLC, Benicia, CA. All rights reserved. D [21/Jun/2007:17:33:14 +0100] [Job 230] This software comes with NO WARRANTY: see the file PUBLIC for details. D [21/Jun/2007:17:33:14 +0100] cupsdReadClient: 8 POST / HTTP/1.1 D [21/Jun/2007:17:33:14 +0100] cupsdAuthorize: No authentication data provided. D [21/Jun/2007:17:33:14 +0100] CUPS-Get-Printers D [21/Jun/2007:17:33:14 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [21/Jun/2007:17:33:14 +0100] cupsdReadClient: 8 POST / HTTP/1.1 D [21/Jun/2007:17:33:14 +0100] cupsdAuthorize: No authentication data provided. D [21/Jun/2007:17:33:14 +0100] Get-Printer-Attributes ipp://localhost/printers/HP640C D [21/Jun/2007:17:33:14 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [21/Jun/2007:17:33:14 +0100] cupsdReadClient: 8 POST / HTTP/1.1 D [21/Jun/2007:17:33:14 +0100] cupsdAuthorize: No authentication data provided. D [21/Jun/2007:17:33:14 +0100] Get-Printer-Attributes ipp://localhost/printers/HP710C D [21/Jun/2007:17:33:14 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [21/Jun/2007:17:33:14 +0100] [Job 230] Warning: the map file cidfmap was not found. D [21/Jun/2007:17:33:15 +0100] [Job 230] Loading NimbusSanL-Bold font from /var/lib/defoma/gs.d/dirs/fonts/n019004l.pfb... 2412432 1107126 1978980 666104 1 done. D [21/Jun/2007:17:33:15 +0100] [Job 230] Loading NimbusSanL-Regu font from /var/lib/defoma/gs.d/dirs/fonts/n019003l.pfb... 2549672 1240376 1978980 666868 1 done. D [21/Jun/2007:17:33:15 +0100] [Job 230] Loading NimbusRomNo9L-Regu font from /var/lib/defoma/gs.d/dirs/fonts/n021003l.pfb... 2727104 1412966 1978980 671813 1 done. D [21/Jun/2007:17:33:15 +0100] [Job 230] Loading NimbusSanL-ReguCondItal font from /var/lib/defoma/gs.d/dirs/fonts/n019063l.pfb... 2844248 1536672 1999076 684118 1 done. D [21/Jun/2007:17:33:15 +0100] cupsdReadClient: 9 GET /printers/HP710C HTTP/1.1 D [21/Jun/2007:17:33:15 +0100] cupsdReadClient: 9 Browser asked for language "en.utf-8"... D [21/Jun/2007:17:33:15 +0100] cupsdAuthorize: No authentication data provided. D [21/Jun/2007:17:33:15 +0100] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 27266 I [21/Jun/2007:17:33:15 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=27266) D [21/Jun/2007:17:33:15 +0100] cupsdSendCommand: 9 file=11 D [21/Jun/2007:17:33:15 +0100] cupsdAcceptClient: 13 from localhost (Domain) D [21/Jun/2007:17:33:15 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [21/Jun/2007:17:33:15 +0100] cupsdAuthorize: No authentication data provided. D [21/Jun/2007:17:33:15 +0100] CUPS-Get-Default D [21/Jun/2007:17:33:15 +0100] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [21/Jun/2007:17:33:15 +0100] [CGI] show_printer(http=0x8070df8, printer="HP710C") D [21/Jun/2007:17:33:15 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [21/Jun/2007:17:33:15 +0100] cupsdAuthorize: No authentication data provided. D [21/Jun/2007:17:33:15 +0100] Get-Printer-Attributes ipp://localhost/printers/HP710C D [21/Jun/2007:17:33:15 +0100] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [21/Jun/2007:17:33:15 +0100] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl") D [21/Jun/2007:17:33:15 +0100] [CGI] locale="en"... D [21/Jun/2007:17:33:15 +0100] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 0... D [21/Jun/2007:17:33:15 +0100] [CGI] "{title}" at 205... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting "{refresh_page?" at 374, result=1... D [21/Jun/2007:17:33:15 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 374... D [21/Jun/2007:17:33:15 +0100] [CGI] "{refresh_page}" at 424... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 427 on character ':'... D [21/Jun/2007:17:33:15 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 427... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 428 on character '}'... D [21/Jun/2007:17:33:15 +0100] [CGI] Finished "{refresh_page?", out=0xb7eb23a0... D [21/Jun/2007:17:33:15 +0100] [CGI] "{title}" at 671... D [21/Jun/2007:17:33:15 +0100] [CGI] "{title}" at 952... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting "{SECTION=admin" at 1411, result=0... D [21/Jun/2007:17:33:15 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 1411... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 1412 on character ':'... D [21/Jun/2007:17:33:15 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 1412... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 1415 on character '}'... D [21/Jun/2007:17:33:15 +0100] [CGI] Finished "{SECTION=admin", out=0xb7eb23a0... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting "{SECTION=classes" at 1678, result=0... D [21/Jun/2007:17:33:15 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 1678... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 1679 on character ':'... D [21/Jun/2007:17:33:15 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 1679... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 1682 on character '}'... D [21/Jun/2007:17:33:15 +0100] [CGI] Finished "{SECTION=classes", out=0xb7eb23a0... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting "{SECTION=help" at 1938, result=0... D [21/Jun/2007:17:33:15 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 1938... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 1939 on character ':'... D [21/Jun/2007:17:33:15 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 1939... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 1942 on character '}'... D [21/Jun/2007:17:33:15 +0100] [CGI] Finished "{SECTION=help", out=0xb7eb23a0... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting "{SECTION=jobs" at 2206, result=0... D [21/Jun/2007:17:33:15 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 2206... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 2207 on character ':'... D [21/Jun/2007:17:33:15 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 2207... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 2210 on character '}'... D [21/Jun/2007:17:33:15 +0100] [CGI] Finished "{SECTION=jobs", out=0xb7eb23a0... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting "{SECTION=printers" at 2464, result=1... D [21/Jun/2007:17:33:15 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 2464... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 2465 on character ':'... D [21/Jun/2007:17:33:15 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 2465... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 2468 on character '}'... D [21/Jun/2007:17:33:15 +0100] [CGI] Finished "{SECTION=printers", out=0xb7eb23a0... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 2830 on EOF... D [21/Jun/2007:17:33:15 +0100] [CGI] cgiCopyTemplateLang(tmpl="printers.tmpl") D [21/Jun/2007:17:33:15 +0100] [CGI] locale="en"... D [21/Jun/2007:17:33:15 +0100] [CGI] Template file is "/usr/share/cups/templates/printers.tmpl"... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 0... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting "{printer_type?" at 14, result=1... D [21/Jun/2007:17:33:15 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 14... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 15 on character ':'... D [21/Jun/2007:17:33:15 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 15... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 16 on character '}'... D [21/Jun/2007:17:33:15 +0100] [CGI] Finished "{printer_type?", out=0xb7eb23a0... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting "{#printer_name=0" at 33, result=0... D [21/Jun/2007:17:33:15 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 33... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 34 on character ':'... D [21/Jun/2007:17:33:15 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 34... D [21/Jun/2007:17:33:15 +0100] [CGI] Looping on "printer_name" at 50, count=1... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 50... D [21/Jun/2007:17:33:15 +0100] [CGI] "{printer_uri_supported}" at 101... D [21/Jun/2007:17:33:15 +0100] [CGI] "{printer_name}" at 117... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting "{default_name=HP710C" at 150, result=0... D [21/Jun/2007:17:33:15 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 150... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 169 on character ':'... D [21/Jun/2007:17:33:15 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 169... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 170 on character '}'... D [21/Jun/2007:17:33:15 +0100] [CGI] Finished "{default_name=HP710C", out=0xb7eb23a0... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting "{?printer_state_message=" at 196, result=0... D [21/Jun/2007:17:33:15 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 196... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 197 on character ':'... D [21/Jun/2007:17:33:15 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 197... D [21/Jun/2007:17:33:15 +0100] [CGI] "{printer_state_message}" at 243... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 252 on character '}'... D [21/Jun/2007:17:33:15 +0100] [CGI] Finished "{?printer_state_message=", out=0xb7eb23a0... D [21/Jun/2007:17:33:15 +0100] [CGI] "{printer_name}" at 349... D [21/Jun/2007:17:33:15 +0100] [CGI] "{printer_uri_supported}" at 406... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting "{printer_state=3" at 452, result=0... D [21/Jun/2007:17:33:15 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 452... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 457 on character ':'... D [21/Jun/2007:17:33:15 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 457... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting "{printer_state=4" at 474, result=1... D [21/Jun/2007:17:33:15 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 474... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 485 on character ':'... D [21/Jun/2007:17:33:15 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 485... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 493 on character '}'... D [21/Jun/2007:17:33:15 +0100] [CGI] Finished "{printer_state=4", out=0xb7eb23a0... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 494 on character '}'... D [21/Jun/2007:17:33:15 +0100] [CGI] Finished "{printer_state=3", out=0xb7eb23a0... D [21/Jun/2007:17:33:15 +0100] [CGI] "{printer_info}" at 584... D [21/Jun/2007:17:33:15 +0100] [CGI] "{printer_location}" at 624... D [21/Jun/2007:17:33:15 +0100] [CGI] "{printer_make_and_model}" at 676... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting "{printer_state=3" at 720, result=0... D [21/Jun/2007:17:33:15 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 720... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 725 on character ':'... D [21/Jun/2007:17:33:15 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 725... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting "{printer_state=4" at 742, result=1... D [21/Jun/2007:17:33:15 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 742... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 753 on character ':'... D [21/Jun/2007:17:33:15 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 753... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 761 on character '}'... D [21/Jun/2007:17:33:15 +0100] [CGI] Finished "{printer_state=4", out=0xb7eb23a0... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 762 on character '}'... D [21/Jun/2007:17:33:15 +0100] [CGI] Finished "{printer_state=3", out=0xb7eb23a0... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting "{printer_is_accepting_jobs=0" at 793, result=0... D [21/Jun/2007:17:33:15 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 793... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 808 on character ':'... D [21/Jun/2007:17:33:15 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 808... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 823 on character '}'... D [21/Jun/2007:17:33:15 +0100] [CGI] Finished "{printer_is_accepting_jobs=0", out=0xb7eb23a0... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting "{printer_is_shared=0" at 846, result=0... D [21/Jun/2007:17:33:15 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 846... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 850 on character ':'... D [21/Jun/2007:17:33:15 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 850... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 851 on character '}'... D [21/Jun/2007:17:33:15 +0100] [CGI] Finished "{printer_is_shared=0", out=0xb7eb23a0... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting "{?device_uri=" at 877, result=0... D [21/Jun/2007:17:33:15 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 877... D [21/Jun/2007:17:33:15 +0100] [CGI] Returning at file position 878 on character ':'... D [21/Jun/2007:17:33:15 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:15 +0100] [CGI] Starting at file position 878... D [21/Jun/2007:17:33:15 +0100] [CGI] "{device_uri}" at 913... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 914 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{?device_uri=", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_uri_supported}" at 952... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{?cupscommand=1" at 1078, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1078... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_uri_supported}" at 1110... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_uri_supported}" at 1258... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1383 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1383... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1384 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{?cupscommand=1", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{printer_state=5" at 1402, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1402... D [21/Jun/2007:17:33:16 +0100] [CGI] "{admin_uri}" at 1423... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_name}" at 1473... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1561 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [21/Jun/2007:17:33:16 +0100] cupsdAuthorize: No authentication data provided. D [21/Jun/2007:17:33:16 +0100] Get-Jobs ipp://localhost:631/printers/HP710C D [21/Jun/2007:17:33:16 +0100] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1561... D [21/Jun/2007:17:33:16 +0100] [CGI] "{admin_uri}" at 1582... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_name}" at 1631... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1717 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{printer_state=5", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{printer_is_accepting_jobs=0" at 1747, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1747... D [21/Jun/2007:17:33:16 +0100] [CGI] "{admin_uri}" at 1768... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_name}" at 1816... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1900 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1900... D [21/Jun/2007:17:33:16 +0100] [CGI] "{admin_uri}" at 1921... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_name}" at 1969... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 2053 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{printer_is_accepting_jobs=0", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_uri_supported}" at 2086... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_name}" at 2132... D [21/Jun/2007:17:33:16 +0100] [CGI] "{admin_uri}" at 2235... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_name}" at 2282... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{printer_is_shared=0" at 2394, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 2394... D [21/Jun/2007:17:33:16 +0100] [CGI] "{admin_uri}" at 2415... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_name}" at 2463... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 2568 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 2568... D [21/Jun/2007:17:33:16 +0100] [CGI] "{admin_uri}" at 2589... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_name}" at 2637... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 2746 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{printer_is_shared=0", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] "{admin_uri}" at 2767... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_name}" at 2818... D [21/Jun/2007:17:33:16 +0100] [CGI] "{admin_uri}" at 2927... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_name}" at 2983... D [21/Jun/2007:17:33:16 +0100] [CGI] "{admin_uri}" at 3102... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_name}" at 3153... D [21/Jun/2007:17:33:16 +0100] [CGI] "{admin_uri}" at 3262... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_name}" at 3313... D [21/Jun/2007:17:33:16 +0100] [CGI] "{admin_uri}" at 3422... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_name}" at 3476... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 3598 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished looping on "printer_name"... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 3599 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{#printer_name=0", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 3600 on EOF... D [21/Jun/2007:17:33:16 +0100] [CGI] cgiCopyTemplateLang(tmpl="printer-jobs-header.tmpl") D [21/Jun/2007:17:33:16 +0100] [CGI] locale="en"... D [21/Jun/2007:17:33:16 +0100] [CGI] Template file is "/usr/share/cups/templates/printer-jobs-header.tmpl"... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 0... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 28 on EOF... D [21/Jun/2007:17:33:16 +0100] [CGI] cgiCopyTemplateLang(tmpl="search.tmpl") D [21/Jun/2007:17:33:16 +0100] [CGI] locale="en"... D [21/Jun/2007:17:33:16 +0100] [CGI] Template file is "/usr/share/cups/templates/search.tmpl"... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 0... D [21/Jun/2007:17:33:16 +0100] [CGI] "{SECTION}" at 24... D [21/Jun/2007:17:33:16 +0100] [CGI] "{?SEARCH_DEST}" at 39... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{WHICH_JOBS?" at 67, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] PID 27266 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 67... D [21/Jun/2007:17:33:16 +0100] [CGI] "{WHICH_JOBS}" at 125... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 128 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 128... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 129 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{WHICH_JOBS?", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{ORDER?" at 137, result=1... D [21/Jun/2007:17:33:16 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 137... D [21/Jun/2007:17:33:16 +0100] [CGI] "{ORDER}" at 185... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 188 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 188... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 189 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{ORDER?", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{SEARCH_DEST?" at 235, result=1... D [21/Jun/2007:17:33:16 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 235... D [21/Jun/2007:17:33:16 +0100] [CGI] "{SEARCH_DEST}" at 248... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 249 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 249... D [21/Jun/2007:17:33:16 +0100] cupsdCloseClient: 13 D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{SECTION=classes" at 266, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 266... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 274 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 274... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{SECTION=jobs" at 288, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 288... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 293 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 293... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 302 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{SECTION=jobs", out=(nil)... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 303 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{SECTION=classes", out=(nil)... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 304 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{SEARCH_DEST?", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] "{?QUERY}" at 357... D [21/Jun/2007:17:33:16 +0100] [CGI] "{SECTION}" at 455... D [21/Jun/2007:17:33:16 +0100] [CGI] "{?SEARCH_DEST}" at 470... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{WHICH_JOBS?" at 482, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 482... D [21/Jun/2007:17:33:16 +0100] [CGI] "{WHICH_JOBS}" at 506... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{ORDER?" at 513, result=1... D [21/Jun/2007:17:33:16 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 513... D [21/Jun/2007:17:33:16 +0100] [CGI] "{ORDER}" at 531... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 532 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 532... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 533 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{ORDER?", out=(nil)... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 534 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 534... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{ORDER?" at 541, result=1... D [21/Jun/2007:17:33:16 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 541... D [21/Jun/2007:17:33:16 +0100] [CGI] "{ORDER}" at 555... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 556 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 556... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 557 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{ORDER?", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 558 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{WHICH_JOBS?", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 641 on EOF... D [21/Jun/2007:17:33:16 +0100] [CGI] cgiCopyTemplateLang(tmpl="jobs-header.tmpl") D [21/Jun/2007:17:33:16 +0100] [CGI] locale="en"... D [21/Jun/2007:17:33:16 +0100] [CGI] Template file is "/usr/share/cups/templates/jobs-header.tmpl"... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{?which_jobs=" at 17, result=1... D [21/Jun/2007:17:33:16 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 17... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{?printer_name=" at 42, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 42... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 48 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 48... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_uri_supported}" at 71... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 72 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{?printer_name=", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{?printer_name=" at 211, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 211... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 217 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 217... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_uri_supported}" at 240... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 241 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{?printer_name=", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 333 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 333... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{which_jobs=all" at 349, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 349... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{?printer_name=" at 374, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 374... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 380 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 380... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_uri_supported}" at 403... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 404 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{?printer_name=", out=(nil)... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{?printer_name=" at 543, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 543... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 549 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 549... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_uri_supported}" at 572... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 573 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{?printer_name=", out=(nil)... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 656 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 656... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{?printer_name=" at 681, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 681... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 687 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 687... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_uri_supported}" at 710... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 711 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{?printer_name=", out=(nil)... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{?printer_name=" at 823, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 823... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 829 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 829... D [21/Jun/2007:17:33:16 +0100] [CGI] "{printer_uri_supported}" at 852... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 853 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{?printer_name=", out=(nil)... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 945 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{which_jobs=all", out=(nil)... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 946 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{?which_jobs=", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{total=0" at 983, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 983... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 991 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 991... D [21/Jun/2007:17:33:16 +0100] [CGI] "{#job_id}" at 1008... D [21/Jun/2007:17:33:16 +0100] [CGI] "{total}" at 1019... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{?which_jobs=" at 1034, result=1... D [21/Jun/2007:17:33:16 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1034... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1041 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1041... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{which_jobs=all" at 1057, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1057... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1058 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1058... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1068 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{which_jobs=all", out=(nil)... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1069 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{?which_jobs=", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{total=1" at 1082, result=1... D [21/Jun/2007:17:33:16 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1082... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1083 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1083... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1085 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{total=1", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1086 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{total=0", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1092 on EOF... D [21/Jun/2007:17:33:16 +0100] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl") D [21/Jun/2007:17:33:16 +0100] [CGI] locale="en"... D [21/Jun/2007:17:33:16 +0100] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl"... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{PREVURL?" at 87, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 87... D [21/Jun/2007:17:33:16 +0100] [CGI] "{PREVURL}" at 105... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 191 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 191... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 198 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{PREVURL?", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{ORDER=dec" at 247, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 247... D [21/Jun/2007:17:33:16 +0100] [CGI] "{THISURL}" at 265... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 367 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 367... D [21/Jun/2007:17:33:16 +0100] [CGI] "{THISURL}" at 385... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 489 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{ORDER=dec", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{NEXTURL?" at 535, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 535... D [21/Jun/2007:17:33:16 +0100] [CGI] "{NEXTURL}" at 553... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 631 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 631... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 638 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{NEXTURL?", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 659 on EOF... D [21/Jun/2007:17:33:16 +0100] [CGI] cgiCopyTemplateLang(tmpl="jobs.tmpl") D [21/Jun/2007:17:33:16 +0100] [CGI] locale="en"... D [21/Jun/2007:17:33:16 +0100] [CGI] Template file is "/usr/share/cups/templates/jobs.tmpl"... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{#job_id=0" at 11, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 11... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 12 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 12... D [21/Jun/2007:17:33:16 +0100] [CGI] Looping on "job_id" at 277, count=1... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 277... D [21/Jun/2007:17:33:16 +0100] [CGI] "{job_printer_uri}" at 339... D [21/Jun/2007:17:33:16 +0100] [CGI] "{job_printer_name}" at 359... D [21/Jun/2007:17:33:16 +0100] [CGI] "{job_id}" at 372... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{?job_name=" at 400, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 400... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 408 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 408... D [21/Jun/2007:17:33:16 +0100] [CGI] "{job_name}" at 418... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 419 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{?job_name=", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] "{job_originating_user_name}" at 462... D [21/Jun/2007:17:33:16 +0100] [CGI] "{job_k_octets}" at 492... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{job_media_sheets_completed=0" at 539, result=1... D [21/Jun/2007:17:33:16 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 539... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 547 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 547... D [21/Jun/2007:17:33:16 +0100] [CGI] "{?job_media_sheets_completed}" at 576... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 577 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{job_media_sheets_completed=0", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{job_state=3" at 600, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 600... D [21/Jun/2007:17:33:16 +0100] [CGI] "{time_at_creation}" at 635... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 636 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 636... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{job_state=4" at 649, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 649... D [21/Jun/2007:17:33:16 +0100] [CGI] "{time_at_creation}" at 681... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 682 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 682... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{job_state=5" at 696, result=1... D [21/Jun/2007:17:33:16 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 696... D [21/Jun/2007:17:33:16 +0100] [CGI] "{time_at_processing}" at 736... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 737 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 737... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{job_state=6" at 750, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 750... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 758 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 758... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{job_state=7" at 772, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 772... D [21/Jun/2007:17:33:16 +0100] [CGI] "{time_at_completed}" at 806... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 807 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 807... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{job_state=8" at 820, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 820... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 828 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 828... D [21/Jun/2007:17:33:16 +0100] [CGI] "{time_at_completed}" at 863... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 864 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{job_state=8", out=(nil)... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 865 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{job_state=7", out=(nil)... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 866 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{job_state=6", out=(nil)... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 867 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{job_state=5", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 868 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{job_state=4", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 869 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{job_state=3", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{job_preserved>0" at 903, result=1... D [21/Jun/2007:17:33:16 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 903... D [21/Jun/2007:17:33:16 +0100] [CGI] "{job_id}" at 954... D [21/Jun/2007:17:33:16 +0100] [CGI] "{job_printer_uri}" at 992... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1075 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1075... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1076 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{job_preserved>0", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{job_state=4" at 1090, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1090... D [21/Jun/2007:17:33:16 +0100] [CGI] "{job_id}" at 1141... D [21/Jun/2007:17:33:16 +0100] [CGI] "{job_printer_uri}" at 1179... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1262 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1262... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1263 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{job_state=4", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{job_state=3" at 1277, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1277... D [21/Jun/2007:17:33:16 +0100] [CGI] "{job_id}" at 1325... D [21/Jun/2007:17:33:16 +0100] [CGI] "{job_printer_uri}" at 1363... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1440 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1440... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1441 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{job_state=3", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{job_state<7" at 1455, result=1... D [21/Jun/2007:17:33:16 +0100] [CGI] Output first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1455... D [21/Jun/2007:17:33:16 +0100] [CGI] "{job_id}" at 1505... D [21/Jun/2007:17:33:16 +0100] [CGI] "{job_printer_uri}" at 1543... D [21/Jun/2007:17:33:16 +0100] [CGI] "{job_id}" at 1671... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1747 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 1747... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1748 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{job_state<7", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1768 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished looping on "job_id"... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1779 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{#job_id=0", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 1780 on EOF... D [21/Jun/2007:17:33:16 +0100] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl") D [21/Jun/2007:17:33:16 +0100] [CGI] locale="en"... D [21/Jun/2007:17:33:16 +0100] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl"... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{PREVURL?" at 87, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 87... D [21/Jun/2007:17:33:16 +0100] [CGI] "{PREVURL}" at 105... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 191 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 191... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 198 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{PREVURL?", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{ORDER=dec" at 247, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 247... D [21/Jun/2007:17:33:16 +0100] [CGI] "{THISURL}" at 265... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 367 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 367... D [21/Jun/2007:17:33:16 +0100] [CGI] "{THISURL}" at 385... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 489 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{ORDER=dec", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting "{NEXTURL?" at 535, result=0... D [21/Jun/2007:17:33:16 +0100] [CGI] Skip first part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 535... D [21/Jun/2007:17:33:16 +0100] [CGI] "{NEXTURL}" at 553... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 631 on character ':'... D [21/Jun/2007:17:33:16 +0100] [CGI] Output second part... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 631... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 638 on character '}'... D [21/Jun/2007:17:33:16 +0100] [CGI] Finished "{NEXTURL?", out=0xb7eb23a0... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 659 on EOF... D [21/Jun/2007:17:33:16 +0100] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl") D [21/Jun/2007:17:33:16 +0100] [CGI] locale="en"... D [21/Jun/2007:17:33:16 +0100] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"... D [21/Jun/2007:17:33:16 +0100] [CGI] Starting at file position 0... D [21/Jun/2007:17:33:16 +0100] [CGI] Returning at file position 614 on EOF... D [21/Jun/2007:17:33:16 +0100] cupsdReadClient: 9 GET /images/printer-processing.gif HTTP/1.1 D [21/Jun/2007:17:33:16 +0100] cupsdReadClient: 9 Browser asked for language "en.utf-8"... D [21/Jun/2007:17:33:16 +0100] cupsdAuthorize: No authentication data provided. D [21/Jun/2007:17:33:16 +0100] write_file: 9 file=11 E [21/Jun/2007:17:33:17 +0100] [Job 230] /ioerror in --.outputpage-- D [21/Jun/2007:17:33:17 +0100] [Job 230] Operand stack: D [21/Jun/2007:17:33:17 +0100] [Job 230] 1 true D [21/Jun/2007:17:33:17 +0100] [Job 230] Execution stack: D [21/Jun/2007:17:33:17 +0100] Discarding unused printer-state-changed event... D [21/Jun/2007:17:33:17 +0100] [Job 230] %interp_exit .runexec2 --nostringval-- --nostringval-- --nostringval-- 2 %stopped_push --nostringval-- --nostringval-- --nostringval-- false 1 %stopped_push 1 3 %oparray_pop 1 3 %oparray_pop 1 3 %oparray_pop 1 3 %oparray_pop .runexec2 --nostringval-- --nostringval-- --nostringval-- 2 %stopped_push --nostringval-- 0 3 %oparray_pop --nostringval-- --nostringval-- D [21/Jun/2007:17:33:17 +0100] [Job 230] Dictionary stack: D [21/Jun/2007:17:33:17 +0100] [Job 230] --dict:1127/1686(ro)(G)-- --dict:0/20(G)-- --dict:128/200(L)-- D [21/Jun/2007:17:33:17 +0100] [Job 230] Current allocation mode is local D [21/Jun/2007:17:33:17 +0100] [Job 230] Last OS error: 32 D [21/Jun/2007:17:33:17 +0100] [Job 230] ESP Ghostscript 815.03: Unrecoverable error, exit code 1 D [21/Jun/2007:17:33:17 +0100] [Job 230] renderer return value: 127 D [21/Jun/2007:17:33:17 +0100] [Job 230] renderer received signal: 127 D [21/Jun/2007:17:33:17 +0100] [Job 230] Process dying with "The renderer command line returned an unrecognized error code 127.", exit stat: 1 D [21/Jun/2007:17:33:17 +0100] [Job 230] error: Illegal seek (29) D [21/Jun/2007:17:33:17 +0100] [Job 230] The renderer command line returned an unrecognized error code 127. D [21/Jun/2007:17:33:17 +0100] [Job 230] tail process done writing data to STDOUT D [21/Jun/2007:17:33:17 +0100] [Job 230] KID3 exited with status 1 D [21/Jun/2007:17:33:17 +0100] [Job 230] Renderer exit stat: 1 D [21/Jun/2007:17:33:17 +0100] [Job 230] KID4 finished D [21/Jun/2007:17:33:17 +0100] [Job 230] Renderer process finished D [21/Jun/2007:17:33:17 +0100] [Job 230] Killing process 27256 (KID3) D [21/Jun/2007:17:33:17 +0100] [Job 230] Process dying with "Error closing renderer", exit stat: 1 D [21/Jun/2007:17:33:17 +0100] [Job 230] error: Bad file descriptor (9) D [21/Jun/2007:17:33:17 +0100] [Job 230] Error closing renderer E [21/Jun/2007:17:33:17 +0100] PID 27251 (/usr/lib/cups/filter/foomatic-rip) stopped with status 1! D [21/Jun/2007:17:33:17 +0100] PID 27252 (/usr/lib/cups/backend/parallel) exited with no errors. D [21/Jun/2007:17:33:17 +0100] [Job 230] File 0 is complete. D [21/Jun/2007:17:33:17 +0100] Discarding unused printer-state-changed event... D [21/Jun/2007:17:33:17 +0100] Discarding unused job-stopped event... D [21/Jun/2007:17:33:18 +0100] Unloading job 230... D [21/Jun/2007:17:33:19 +0100] cupsdReadClient: 8 POST / HTTP/1.1 D [21/Jun/2007:17:33:19 +0100] cupsdAuthorize: No authentication data provided. D [21/Jun/2007:17:33:19 +0100] CUPS-Get-Printers D [21/Jun/2007:17:33:19 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [21/Jun/2007:17:33:19 +0100] cupsdReadClient: 8 POST / HTTP/1.1 D [21/Jun/2007:17:33:19 +0100] cupsdAuthorize: No authentication data provided. D [21/Jun/2007:17:33:19 +0100] Get-Printer-Attributes ipp://localhost/printers/HP640C D [21/Jun/2007:17:33:19 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [21/Jun/2007:17:33:19 +0100] cupsdReadClient: 8 POST / HTTP/1.1 D [21/Jun/2007:17:33:19 +0100] cupsdAuthorize: No authentication data provided. D [21/Jun/2007:17:33:19 +0100] Get-Printer-Attributes ipp://localhost/printers/HP710C D [21/Jun/2007:17:33:19 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)