I [26/Aug/2006:07:35:08 -0400] Listening to 127.0.0.1:631 (IPv4) I [26/Aug/2006:07:35:08 -0400] Listening to /var/run/cups/cups.sock (Domain) I [26/Aug/2006:07:35:08 -0400] Loaded configuration file "/etc/cups/cupsd.conf" I [26/Aug/2006:07:35:08 -0400] Cleaning out old temporary files in "/var/spool/cups/tmp"... I [26/Aug/2006:07:35:08 -0400] Configured for up to 100 clients. I [26/Aug/2006:07:35:08 -0400] Allowing up to 100 client connections per host. I [26/Aug/2006:07:35:08 -0400] Using policy "default" as the default! I [26/Aug/2006:07:35:08 -0400] Full reload is required. I [26/Aug/2006:07:35:09 -0400] Loaded MIME database from '/etc/cups': 34 types, 39 filters... D [26/Aug/2006:07:35:09 -0400] Loading printer MyPrinter... D [26/Aug/2006:07:35:09 -0400] Scanning /var/spool/cups for jobs... D [26/Aug/2006:07:35:09 -0400] Loading attributes for job 236... D [26/Aug/2006:07:35:09 -0400] Unloading job 236... D [26/Aug/2006:07:35:09 -0400] Loading attributes for job 242... D [26/Aug/2006:07:35:09 -0400] Unloading job 242... I [26/Aug/2006:07:35:09 -0400] Loading NextJobId from job cache file "/var/cache/cups/job.cache"... I [26/Aug/2006:07:35:09 -0400] Full reload complete. I [26/Aug/2006:07:35:09 -0400] Listening to 127.0.0.1:631 on fd 0... I [26/Aug/2006:07:35:09 -0400] Listening to /var/run/cups/cups.sock on fd 1... D [26/Aug/2006:19:32:42 -0400] cupsdAcceptClient: 5 from localhost:631 (IPv4) D [26/Aug/2006:19:32:42 -0400] cupsdReadClient: 5 POST /printers/ HTTP/1.1 D [26/Aug/2006:19:32:42 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:42 -0400] CUPS-Get-Printers D [26/Aug/2006:19:32:42 -0400] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) D [26/Aug/2006:19:32:42 -0400] cupsdAcceptClient: 6 from localhost:631 (IPv4) D [26/Aug/2006:19:32:42 -0400] cupsdCloseClient: 5 D [26/Aug/2006:19:32:42 -0400] cupsdReadClient: 6 POST /printers/ HTTP/1.1 D [26/Aug/2006:19:32:42 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:42 -0400] CUPS-Get-Printers D [26/Aug/2006:19:32:42 -0400] cupsdProcessIPPRequest: 6 status_code=0 (successful-ok) D [26/Aug/2006:19:32:42 -0400] cupsdAcceptClient: 5 from localhost:631 (IPv4) D [26/Aug/2006:19:32:42 -0400] cupsdCloseClient: 6 D [26/Aug/2006:19:32:42 -0400] cupsdReadClient: 5 POST /classes/ HTTP/1.1 D [26/Aug/2006:19:32:42 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:42 -0400] CUPS-Get-Classes D [26/Aug/2006:19:32:42 -0400] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) D [26/Aug/2006:19:32:42 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:32:42 -0400] cupsdCloseClient: 5 D [26/Aug/2006:19:32:42 -0400] cupsdReadClient: 7 POST /printers/ HTTP/1.1 D [26/Aug/2006:19:32:42 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:42 -0400] CUPS-Get-Default D [26/Aug/2006:19:32:42 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:32:42 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:32:42 -0400] cupsdAcceptClient: 5 from localhost:631 (IPv4) D [26/Aug/2006:19:32:42 -0400] cupsdReadClient: 5 POST /printers/ HTTP/1.1 D [26/Aug/2006:19:32:42 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:42 -0400] Get-Printer-Attributes ipp://localhost:631/printers/MyPrinter D [26/Aug/2006:19:32:42 -0400] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) D [26/Aug/2006:19:32:42 -0400] cupsdCloseClient: 5 D [26/Aug/2006:19:32:42 -0400] cupsdAcceptClient: 5 from localhost:631 (IPv4) D [26/Aug/2006:19:32:42 -0400] cupsdReadClient: 5 POST / HTTP/1.1 D [26/Aug/2006:19:32:42 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:42 -0400] Get-Printer-Attributes ipp://localhost/printers/MyPrinter D [26/Aug/2006:19:32:42 -0400] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) D [26/Aug/2006:19:32:42 -0400] cupsdReadClient: 5 GET /printers/MyPrinter.ppd HTTP/1.1 D [26/Aug/2006:19:32:42 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:42 -0400] write_file: 5 file=7 D [26/Aug/2006:19:32:42 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:32:42 -0400] cupsdReadClient: 7 POST / HTTP/1.1 D [26/Aug/2006:19:32:42 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:42 -0400] Get-Jobs ipp://localhost:631/printers/MyPrinter D [26/Aug/2006:19:32:42 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:32:43 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:32:46 -0400] cupsdReadClient: 5 POST / HTTP/1.1 D [26/Aug/2006:19:32:46 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:46 -0400] Get-Printer-Attributes ipp://localhost/printers/MyPrinter D [26/Aug/2006:19:32:46 -0400] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) D [26/Aug/2006:19:32:46 -0400] cupsdReadClient: 5 GET /printers/MyPrinter.ppd HTTP/1.1 D [26/Aug/2006:19:32:46 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:46 -0400] write_file: 5 file=7 D [26/Aug/2006:19:32:46 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:32:47 -0400] cupsdReadClient: 7 POST /printers/MyPrinter HTTP/1.1 D [26/Aug/2006:19:32:47 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:47 -0400] Print-Job ipp://localhost/printers/MyPrinter D [26/Aug/2006:19:32:47 -0400] print_job: auto-typing file... D [26/Aug/2006:19:32:47 -0400] print_job: request file type is application/postscript. D [26/Aug/2006:19:32:47 -0400] add_job: requesting-user-name="root" D [26/Aug/2006:19:32:47 -0400] Adding default job-sheets values "none,none"... I [26/Aug/2006:19:32:47 -0400] Adding start banner page "none" to job 243. D [26/Aug/2006:19:32:47 -0400] Discarding unused job-created event... I [26/Aug/2006:19:32:47 -0400] Adding end banner page "none" to job 243. I [26/Aug/2006:19:32:47 -0400] Job 243 queued on "MyPrinter" by "root". D [26/Aug/2006:19:32:47 -0400] Job 243 hold_until = 0 D [26/Aug/2006:19:32:47 -0400] Discarding unused printer-state-changed event... D [26/Aug/2006:19:32:47 -0400] job-sheets=none,none D [26/Aug/2006:19:32:47 -0400] banner_page = 0 D [26/Aug/2006:19:32:47 -0400] [Job 243] argv[0]="MyPrinter" D [26/Aug/2006:19:32:47 -0400] [Job 243] argv[1]="243" D [26/Aug/2006:19:32:47 -0400] [Job 243] argv[2]="root" D [26/Aug/2006:19:32:47 -0400] [Job 243] argv[3]="KDE Print Test" D [26/Aug/2006:19:32:47 -0400] [Job 243] argv[4]="1" D [26/Aug/2006:19:32:47 -0400] [Job 243] argv[5]="multiple-document-handling=separate-documents-uncollated-copies orientation-requested=3 job-uuid=urn:uuid:bbf11bf6-796e-3646-5a0b-0e7bdaa3a2e3" D [26/Aug/2006:19:32:47 -0400] [Job 243] argv[6]="/var/spool/cups/d00243-001" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[1]="CUPS_DATADIR=/usr/share/cups" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[6]="CUPS_SERVERROOT=/etc/cups" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[7]="CUPS_STATEDIR=/var/run/cups" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[9]="SERVER_ADMIN=root@localhost" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[10]="SOFTWARE=CUPS/1.2.2" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[11]="TMPDIR=/var/spool/cups/tmp" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[12]="TZ=America/New_York" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[13]="USER=root" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[15]="CUPS_ENCRYPTION=IfRequested" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[16]="IPP_PORT=631" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[17]="CHARSET=utf-8" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[18]="LANG=en_US" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[19]="PPD=/etc/cups/ppd/MyPrinter.ppd" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[20]="RIP_MAX_CACHE=8m" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[21]="CONTENT_TYPE=application/postscript" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[22]="DEVICE_URI=parallel:/dev/lp0" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[23]="PRINTER=MyPrinter" D [26/Aug/2006:19:32:47 -0400] [Job 243] envp[24]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript" I [26/Aug/2006:19:32:47 -0400] Started filter /usr/lib/cups/filter/pstops (PID 2997) for job 243. I [26/Aug/2006:19:32:47 -0400] Started filter /usr/lib/cups/filter/foomatic-rip (PID 2998) for job 243. I [26/Aug/2006:19:32:47 -0400] Started backend /usr/lib/cups/backend/parallel (PID 2999) for job 243. D [26/Aug/2006:19:32:47 -0400] Discarding unused job-state event... D [26/Aug/2006:19:32:47 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:32:47 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:32:47 -0400] [Job 243] perl: warning: Setting locale failed. D [26/Aug/2006:19:32:47 -0400] [Job 243] perl: warning: Please check that your locale settings: D [26/Aug/2006:19:32:47 -0400] [Job 243] LANGUAGE = (unset), D [26/Aug/2006:19:32:47 -0400] [Job 243] LC_ALL = (unset), D [26/Aug/2006:19:32:47 -0400] [Job 243] LANG = "en_US" D [26/Aug/2006:19:32:47 -0400] [Job 243] are supported and installed on your system. D [26/Aug/2006:19:32:47 -0400] [Job 243] perl: warning: Falling back to the standard locale ("C"). D [26/Aug/2006:19:32:47 -0400] [Job 243] Page = 612x792; 0,0 to 612,792 D [26/Aug/2006:19:32:47 -0400] [Job 243] slow_collate=0, slow_duplex=0, slow_order=0 D [26/Aug/2006:19:32:47 -0400] [Job 243] Before copy_comments - %!PS-Adobe-3.0 D [26/Aug/2006:19:32:47 -0400] [Job 243] %!PS-Adobe-3.0 D [26/Aug/2006:19:32:47 -0400] [Job 243] %%BoundingBox: 0 0 612 792 D [26/Aug/2006:19:32:47 -0400] [Job 243] %%Pages: 1 D [26/Aug/2006:19:32:47 -0400] [Job 243] %%LanguageLevel: 1 D [26/Aug/2006:19:32:47 -0400] [Job 243] %%DocumentData: Clean7Bit D [26/Aug/2006:19:32:47 -0400] [Job 243] %%DocumentSuppliedResources: procset testprint/1.0 D [26/Aug/2006:19:32:47 -0400] [Job 243] %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman D [26/Aug/2006:19:32:47 -0400] [Job 243] %%Creator: Michael Sweet, Easy Software Products D [26/Aug/2006:19:32:47 -0400] [Job 243] %%CreationDate: May 11, 1999 D [26/Aug/2006:19:32:47 -0400] [Job 243] %%Title: Test Page D [26/Aug/2006:19:32:47 -0400] [Job 243] %%EndComments D [26/Aug/2006:19:32:47 -0400] [Job 243] Before copy_prolog - %%BeginProlog D [26/Aug/2006:19:32:47 -0400] [Job 243] Before copy_setup - %%Page: 1 1 D [26/Aug/2006:19:32:47 -0400] [Job 243] Before page loop - %%Page: 1 1 D [26/Aug/2006:19:32:47 -0400] [Job 243] Copying page 1... D [26/Aug/2006:19:32:47 -0400] [Job 243] pagew = 612.0, pagel = 792.0 D [26/Aug/2006:19:32:47 -0400] [Job 243] bboxw = 612, bboxl = 792 D [26/Aug/2006:19:32:47 -0400] PID 2997 (/usr/lib/cups/filter/pstops) exited with no errors. D [26/Aug/2006:19:32:47 -0400] [Job 243] PageLeft = 0.0, PageRight = 612.0 D [26/Aug/2006:19:32:47 -0400] [Job 243] PageTop = 792.0, PageBottom = 0.0 D [26/Aug/2006:19:32:47 -0400] [Job 243] PageWidth = 612.0, PageLength = 792.0 D [26/Aug/2006:19:32:47 -0400] [Job 243] Wrote 1 pages... D [26/Aug/2006:19:32:47 -0400] [Job 243] backendRunLoop(print_fd=0, device_fd=4, use_bc=1) D [26/Aug/2006:19:32:47 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:32:47 -0400] cupsdReadClient: 7 POST /printers/ HTTP/1.1 D [26/Aug/2006:19:32:47 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:47 -0400] CUPS-Get-Printers D [26/Aug/2006:19:32:47 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:32:47 -0400] cupsdAcceptClient: 8 from localhost:631 (IPv4) D [26/Aug/2006:19:32:47 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:32:47 -0400] cupsdReadClient: 8 POST /classes/ HTTP/1.1 D [26/Aug/2006:19:32:47 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:47 -0400] CUPS-Get-Classes D [26/Aug/2006:19:32:47 -0400] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [26/Aug/2006:19:32:47 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:32:47 -0400] cupsdCloseClient: 8 D [26/Aug/2006:19:32:47 -0400] cupsdReadClient: 7 POST /printers/ HTTP/1.1 D [26/Aug/2006:19:32:47 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:47 -0400] CUPS-Get-Default D [26/Aug/2006:19:32:47 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:32:47 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:32:47 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:32:47 -0400] cupsdReadClient: 7 POST /printers/ HTTP/1.1 D [26/Aug/2006:19:32:47 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:47 -0400] Get-Printer-Attributes ipp://localhost:631/printers/MyPrinter D [26/Aug/2006:19:32:47 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:32:47 -0400] cupsdReadClient: 5 POST / HTTP/1.1 D [26/Aug/2006:19:32:47 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:47 -0400] Get-Printer-Attributes ipp://localhost/printers/MyPrinter D [26/Aug/2006:19:32:47 -0400] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) D [26/Aug/2006:19:32:47 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:32:47 -0400] cupsdReadClient: 5 GET /printers/MyPrinter.ppd HTTP/1.1 D [26/Aug/2006:19:32:47 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:47 -0400] write_file: 5 file=7 D [26/Aug/2006:19:32:48 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:32:48 -0400] cupsdReadClient: 7 POST / HTTP/1.1 D [26/Aug/2006:19:32:48 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:48 -0400] Get-Jobs ipp://localhost:631/printers/MyPrinter D [26/Aug/2006:19:32:48 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:32:48 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:32:48 -0400] [Job 243] foomatic-rip version $Revision: 3.43.2.15 $ running... D [26/Aug/2006:19:32:48 -0400] [Job 243] Parsing PPD file ... D [26/Aug/2006:19:32:48 -0400] [Job 243] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip" D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option ColorSpace D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option Resolution D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option PageSize D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option PageRegion D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option ImageableArea D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option PaperDimension D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option EconoFast D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option ColorMode D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option Dither D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option Bidirectional D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option Blackness D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option BottomMargin D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option LeftMargin D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option RightMargin D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option TopMargin D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option XOffset D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option YOffset D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option Model D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option GammaFile D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option pnmFormat D [26/Aug/2006:19:32:48 -0400] [Job 243] Added option Font D [26/Aug/2006:19:32:48 -0400] [Job 243] D [26/Aug/2006:19:32:48 -0400] [Job 243] Parameter Summary D [26/Aug/2006:19:32:48 -0400] [Job 243] ----------------- D [26/Aug/2006:19:32:48 -0400] [Job 243] D [26/Aug/2006:19:32:48 -0400] [Job 243] Spooler: cups D [26/Aug/2006:19:32:48 -0400] [Job 243] Printer: MyPrinter D [26/Aug/2006:19:32:48 -0400] [Job 243] PPD file: /etc/cups/ppd/MyPrinter.ppd D [26/Aug/2006:19:32:48 -0400] [Job 243] Printer model: HP DeskJet 712C Foomatic/pnm2ppa (recommended) D [26/Aug/2006:19:32:48 -0400] [Job 243] Job title: KDE Print Test D [26/Aug/2006:19:32:48 -0400] [Job 243] File(s) to be printed: D [26/Aug/2006:19:32:48 -0400] [Job 243] D [26/Aug/2006:19:32:48 -0400] [Job 243] D [26/Aug/2006:19:32:48 -0400] [Job 243] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts D [26/Aug/2006:19:32:48 -0400] [Job 243] Pondering option 'multiple-document-handling=separate-documents-uncollated-copies' D [26/Aug/2006:19:32:48 -0400] [Job 243] Unknown option multiple-document-handling=separate-documents-uncollated-copies. D [26/Aug/2006:19:32:48 -0400] [Job 243] Pondering option 'orientation-requested=3' D [26/Aug/2006:19:32:48 -0400] [Job 243] Unknown option orientation-requested=3. D [26/Aug/2006:19:32:48 -0400] [Job 243] Pondering option 'job-uuid=urn:uuid:bbf11bf6-796e-3646-5a0b-0e7bdaa3a2e3' D [26/Aug/2006:19:32:48 -0400] [Job 243] Unknown option job-uuid=urn:uuid:bbf11bf6-796e-3646-5a0b-0e7bdaa3a2e3. D [26/Aug/2006:19:32:48 -0400] [Job 243] D [26/Aug/2006:19:32:48 -0400] [Job 243] ================================================ D [26/Aug/2006:19:32:48 -0400] [Job 243] D [26/Aug/2006:19:32:48 -0400] [Job 243] File: D [26/Aug/2006:19:32:48 -0400] [Job 243] D [26/Aug/2006:19:32:48 -0400] [Job 243] ================================================ D [26/Aug/2006:19:32:48 -0400] [Job 243] D [26/Aug/2006:19:32:48 -0400] [Job 243] Reading PostScript input ... D [26/Aug/2006:19:32:48 -0400] [Job 243] --> This document is DSC-conforming! D [26/Aug/2006:19:32:48 -0400] [Job 243] D [26/Aug/2006:19:32:48 -0400] [Job 243] ----------- D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%BeginProlog D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%EndProlog D [26/Aug/2006:19:32:48 -0400] [Job 243] D [26/Aug/2006:19:32:48 -0400] [Job 243] ----------- D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%BeginSetup D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%BeginFeature: *PageSize Letter D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: PageSize=Letter --> Option will be set by PostScript interpreter D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%BeginFeature: *pnmFormat PixMap D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: pnmFormat=PixMap --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %% FoomaticRIPOptionSetting: pnmFormat=PixMap D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: pnmFormat=PixMap --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%BeginFeature: *ColorMode CMYK D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: ColorMode=CMYK --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %% FoomaticRIPOptionSetting: ColorMode=CMYK D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: ColorMode=CMYK --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%BeginFeature: *EconoFast Off D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: EconoFast=Off --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %% FoomaticRIPOptionSetting: EconoFast=Off D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: EconoFast=Off --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%BeginFeature: *Dither FloydSteinberg D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: Dither=FloydSteinberg --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %% FoomaticRIPOptionSetting: Dither=FloydSteinberg D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: Dither=FloydSteinberg --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%BeginFeature: *GammaFile Default D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: GammaFile=Default --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %% FoomaticRIPOptionSetting: GammaFile=Default D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: GammaFile=Default --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%BeginFeature: *Bidirectional Default D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: Bidirectional=Default --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %% FoomaticRIPOptionSetting: Bidirectional=Default D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: Bidirectional=Default --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%BeginFeature: *Blackness 2 D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: Blackness=2 --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %% FoomaticRIPOptionSetting: Blackness=2 D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: Blackness=2 --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%BeginFeature: *TopMargin 10 D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: TopMargin=10 --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %% FoomaticRIPOptionSetting: TopMargin=10 D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: TopMargin=10 --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%BeginFeature: *BottomMargin 150 D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: BottomMargin=150 --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %% FoomaticRIPOptionSetting: BottomMargin=150 D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: BottomMargin=150 --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%BeginFeature: *LeftMargin 10 D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: LeftMargin=10 --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %% FoomaticRIPOptionSetting: LeftMargin=10 D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: LeftMargin=10 --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%BeginFeature: *RightMargin 10 D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: RightMargin=10 --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %% FoomaticRIPOptionSetting: RightMargin=10 D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: RightMargin=10 --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%BeginFeature: *XOffset 160 D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: XOffset=160 --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %% FoomaticRIPOptionSetting: XOffset=160 D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: XOffset=160 --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%BeginFeature: *YOffset 50 D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: YOffset=50 --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %% FoomaticRIPOptionSetting: YOffset=50 D [26/Aug/2006:19:32:48 -0400] [Job 243] Option: YOffset=50 --> Setting option D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%EndSetup D [26/Aug/2006:19:32:48 -0400] [Job 243] Inserting PostScript code for CUPS' page accounting D [26/Aug/2006:19:32:48 -0400] [Job 243] D [26/Aug/2006:19:32:48 -0400] [Job 243] ----------- D [26/Aug/2006:19:32:48 -0400] [Job 243] New page: 1 1 D [26/Aug/2006:19:32:48 -0400] [Job 243] Inserting option code into "PageSetup" section. D [26/Aug/2006:19:32:48 -0400] [Job 243] D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%BeginPageSetup D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: %%EndPageSetup D [26/Aug/2006:19:32:48 -0400] [Job 243] End of page header D [26/Aug/2006:19:32:48 -0400] [Job 243] Stopping search for page header options D [26/Aug/2006:19:32:48 -0400] [Job 243] Found: D [26/Aug/2006:19:32:48 -0400] [Job 243] (x) show % "x" D [26/Aug/2006:19:32:48 -0400] [Job 243] --> Output goes directly to the renderer now. D [26/Aug/2006:19:32:48 -0400] [Job 243] D [26/Aug/2006:19:32:48 -0400] [Job 243] D [26/Aug/2006:19:32:48 -0400] [Job 243] Starting renderer D [26/Aug/2006:19:32:48 -0400] [Job 243] D [26/Aug/2006:19:32:48 -0400] [Job 243] Closing renderer D [26/Aug/2006:19:32:48 -0400] [Job 243] JCL: D [26/Aug/2006:19:32:48 -0400] [Job 243] D [26/Aug/2006:19:32:48 -0400] [Job 243] renderer PID kid4=3003 D [26/Aug/2006:19:32:48 -0400] [Job 243] renderer command: gs -q -dNOPAUSE -dPARANOIDSAFER -dBATCH -r600 -sDEVICE=ppmraw -sOutputFile=- - | pnm2ppa -v 712 -B 2 -t 10 -b 150 -l 10 -r 10 -x 160 -y 50 -i - -o - D [26/Aug/2006:19:32:48 -0400] [Job 243] perl: warning: Setting locale failed. D [26/Aug/2006:19:32:48 -0400] [Job 243] perl: warning: Please check that your locale settings: D [26/Aug/2006:19:32:48 -0400] [Job 243] LANGUAGE = (unset), D [26/Aug/2006:19:32:48 -0400] [Job 243] LC_ALL = (unset), D [26/Aug/2006:19:32:48 -0400] [Job 243] LANG = "en_US" D [26/Aug/2006:19:32:48 -0400] [Job 243] are supported and installed on your system. D [26/Aug/2006:19:32:48 -0400] [Job 243] perl: warning: Falling back to the standard locale ("C"). D [26/Aug/2006:19:32:48 -0400] [Job 243] foomatic-gswrapper: gs '-dNOPAUSE' '-dPARANOIDSAFER' '-dBATCH' '-r600' '-sDEVICE=ppmraw' '-sOutputFile=/dev/fd/3' '/dev/fd/0' 3>&1 1>&2 D [26/Aug/2006:19:32:49 -0400] [Job 243] ESP Ghostscript 815.02 (2006-04-19) D [26/Aug/2006:19:32:49 -0400] [Job 243] Copyright (C) 2004 artofcode LLC, Benicia, CA. All rights reserved. D [26/Aug/2006:19:32:49 -0400] [Job 243] This software comes with NO WARRANTY: see the file PUBLIC for details. D [26/Aug/2006:19:32:50 -0400] [Job 243] Loading NimbusSanL-Bold font from /var/lib/defoma/gs.d/dirs/fonts/n019004l.pfb... 2949456 1520021 1719744 426920 1 done. D [26/Aug/2006:19:32:50 -0400] [Job 243] Loading NimbusSanL-Regu font from /var/lib/defoma/gs.d/dirs/fonts/n019003l.pfb... 2982736 1619147 1719744 427780 1 done. D [26/Aug/2006:19:32:50 -0400] [Job 243] Loading NimbusRomNo9L-Regu font from /var/lib/defoma/gs.d/dirs/fonts/n021003l.pfb... 3084456 1781472 1719744 429200 1 done. D [26/Aug/2006:19:32:50 -0400] [Job 243] Loading NimbusSanL-BoldItal font from /var/lib/defoma/gs.d/dirs/fonts/n019024l.pfb... 3218656 1909963 1739928 447904 1 done. D [26/Aug/2006:19:32:50 -0400] [Job 243] Read 4084 bytes of print data... D [26/Aug/2006:19:32:50 -0400] [Job 243] Wrote 4084 bytes of print data... D [26/Aug/2006:19:32:50 -0400] [Job 243] Read 7486 bytes of print data... D [26/Aug/2006:19:32:50 -0400] [Job 243] Wrote 7486 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Read 591 bytes of print data... D [26/Aug/2006:19:32:51 -0400] [Job 243] Wrote 591 bytes of print data... D [26/Aug/2006:19:32:52 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:52 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:52 -0400] [Job 243] Read 116 bytes of print data... D [26/Aug/2006:19:32:52 -0400] [Job 243] Wrote 116 bytes of print data... D [26/Aug/2006:19:32:52 -0400] [Job 243] Read 1460 bytes of print data... D [26/Aug/2006:19:32:52 -0400] [Job 243] Wrote 1460 bytes of print data... D [26/Aug/2006:19:32:52 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:52 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:52 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:52 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:52 -0400] [Job 243] Read 6573 bytes of print data... D [26/Aug/2006:19:32:52 -0400] [Job 243] Wrote 6573 bytes of print data... D [26/Aug/2006:19:32:52 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:52 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:52 -0400] [Job 243] Read 2966 bytes of print data... D [26/Aug/2006:19:32:53 -0400] [Job 243] Wrote 2966 bytes of print data... D [26/Aug/2006:19:32:53 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:53 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:32:53 -0400] cupsdReadClient: 7 POST /printers/ HTTP/1.1 D [26/Aug/2006:19:32:53 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:53 -0400] CUPS-Get-Printers D [26/Aug/2006:19:32:53 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:32:53 -0400] cupsdAcceptClient: 8 from localhost:631 (IPv4) D [26/Aug/2006:19:32:53 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:32:53 -0400] cupsdReadClient: 8 POST /classes/ HTTP/1.1 D [26/Aug/2006:19:32:53 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:53 -0400] CUPS-Get-Classes D [26/Aug/2006:19:32:53 -0400] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [26/Aug/2006:19:32:53 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:32:53 -0400] cupsdCloseClient: 8 D [26/Aug/2006:19:32:53 -0400] cupsdReadClient: 7 POST /printers/ HTTP/1.1 D [26/Aug/2006:19:32:53 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:53 -0400] CUPS-Get-Default D [26/Aug/2006:19:32:53 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:32:53 -0400] cupsdAcceptClient: 8 from localhost:631 (IPv4) D [26/Aug/2006:19:32:53 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:32:53 -0400] cupsdReadClient: 8 POST /printers/ HTTP/1.1 D [26/Aug/2006:19:32:53 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:53 -0400] Get-Printer-Attributes ipp://localhost:631/printers/MyPrinter D [26/Aug/2006:19:32:53 -0400] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [26/Aug/2006:19:32:53 -0400] cupsdReadClient: 5 POST / HTTP/1.1 D [26/Aug/2006:19:32:53 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:53 -0400] Get-Printer-Attributes ipp://localhost/printers/MyPrinter D [26/Aug/2006:19:32:53 -0400] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) D [26/Aug/2006:19:32:53 -0400] cupsdCloseClient: 8 D [26/Aug/2006:19:32:53 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:53 -0400] [Job 243] Read 8081 bytes of print data... D [26/Aug/2006:19:32:53 -0400] [Job 243] Wrote 8081 bytes of print data... D [26/Aug/2006:19:32:53 -0400] cupsdReadClient: 5 GET /printers/MyPrinter.ppd HTTP/1.1 D [26/Aug/2006:19:32:53 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:53 -0400] write_file: 5 file=7 D [26/Aug/2006:19:32:53 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:32:53 -0400] cupsdReadClient: 7 POST / HTTP/1.1 D [26/Aug/2006:19:32:53 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:53 -0400] Get-Jobs ipp://localhost:631/printers/MyPrinter D [26/Aug/2006:19:32:53 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:32:53 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:32:54 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Read 1335 bytes of print data... D [26/Aug/2006:19:32:54 -0400] [Job 243] Wrote 1335 bytes of print data... D [26/Aug/2006:19:32:55 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:55 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:55 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:55 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:55 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:55 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:55 -0400] [Job 243] Read 4154 bytes of print data... D [26/Aug/2006:19:32:55 -0400] [Job 243] Wrote 4154 bytes of print data... D [26/Aug/2006:19:32:55 -0400] [Job 243] Read 6749 bytes of print data... D [26/Aug/2006:19:32:56 -0400] [Job 243] Wrote 6749 bytes of print data... D [26/Aug/2006:19:32:56 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:56 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:56 -0400] [Job 243] Read 787 bytes of print data... D [26/Aug/2006:19:32:56 -0400] [Job 243] Wrote 787 bytes of print data... D [26/Aug/2006:19:32:56 -0400] [Job 243] Read 2776 bytes of print data... D [26/Aug/2006:19:32:56 -0400] [Job 243] Wrote 2776 bytes of print data... D [26/Aug/2006:19:32:56 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:56 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:56 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:56 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:56 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:56 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:56 -0400] [Job 243] Read 6078 bytes of print data... D [26/Aug/2006:19:32:56 -0400] [Job 243] Wrote 6078 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] KID3 exited with status 0 D [26/Aug/2006:19:32:57 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] tail process done writing data to STDOUT D [26/Aug/2006:19:32:57 -0400] [Job 243] KID4 exited with status 0 D [26/Aug/2006:19:32:57 -0400] [Job 243] Renderer exit stat: 0 D [26/Aug/2006:19:32:57 -0400] [Job 243] KID4 finished D [26/Aug/2006:19:32:57 -0400] [Job 243] KID3 finished D [26/Aug/2006:19:32:57 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Renderer process finished D [26/Aug/2006:19:32:57 -0400] [Job 243] D [26/Aug/2006:19:32:57 -0400] [Job 243] Closing foomatic-rip. D [26/Aug/2006:19:32:57 -0400] PID 2998 (/usr/lib/cups/filter/foomatic-rip) exited with no errors. D [26/Aug/2006:19:32:57 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Read 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Wrote 8192 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Read 4986 bytes of print data... D [26/Aug/2006:19:32:57 -0400] [Job 243] Wrote 4986 bytes of print data... D [26/Aug/2006:19:32:57 -0400] PID 2999 (/usr/lib/cups/backend/parallel) exited with no errors. D [26/Aug/2006:19:32:57 -0400] [Job 243] File 0 is complete. D [26/Aug/2006:19:32:57 -0400] Discarding unused job-completed event... D [26/Aug/2006:19:32:57 -0400] Discarding unused printer-state-changed event... D [26/Aug/2006:19:32:58 -0400] Unloading job 243... D [26/Aug/2006:19:32:58 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:32:58 -0400] cupsdReadClient: 7 POST /printers/ HTTP/1.1 D [26/Aug/2006:19:32:58 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:58 -0400] CUPS-Get-Printers D [26/Aug/2006:19:32:58 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:32:58 -0400] cupsdAcceptClient: 8 from localhost:631 (IPv4) D [26/Aug/2006:19:32:58 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:32:58 -0400] cupsdReadClient: 8 POST /classes/ HTTP/1.1 D [26/Aug/2006:19:32:58 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:58 -0400] CUPS-Get-Classes D [26/Aug/2006:19:32:58 -0400] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [26/Aug/2006:19:32:58 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:32:58 -0400] cupsdCloseClient: 8 D [26/Aug/2006:19:32:58 -0400] cupsdReadClient: 7 POST /printers/ HTTP/1.1 D [26/Aug/2006:19:32:58 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:58 -0400] CUPS-Get-Default D [26/Aug/2006:19:32:58 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:32:58 -0400] cupsdAcceptClient: 8 from localhost:631 (IPv4) D [26/Aug/2006:19:32:58 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:32:58 -0400] cupsdReadClient: 8 POST /printers/ HTTP/1.1 D [26/Aug/2006:19:32:58 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:58 -0400] Get-Printer-Attributes ipp://localhost:631/printers/MyPrinter D [26/Aug/2006:19:32:58 -0400] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [26/Aug/2006:19:32:58 -0400] cupsdReadClient: 5 POST / HTTP/1.1 D [26/Aug/2006:19:32:58 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:58 -0400] Get-Printer-Attributes ipp://localhost/printers/MyPrinter D [26/Aug/2006:19:32:58 -0400] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) D [26/Aug/2006:19:32:58 -0400] cupsdCloseClient: 8 D [26/Aug/2006:19:32:58 -0400] cupsdReadClient: 5 GET /printers/MyPrinter.ppd HTTP/1.1 D [26/Aug/2006:19:32:58 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:58 -0400] write_file: 5 file=7 D [26/Aug/2006:19:32:58 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:32:58 -0400] cupsdReadClient: 7 POST / HTTP/1.1 D [26/Aug/2006:19:32:58 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:32:58 -0400] Get-Jobs ipp://localhost:631/printers/MyPrinter D [26/Aug/2006:19:32:58 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:32:58 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:33:01 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:33:01 -0400] cupsdReadClient: 7 POST / HTTP/1.1 D [26/Aug/2006:19:33:01 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:33:01 -0400] Get-Jobs ipp://localhost:631/printers/MyPrinter D [26/Aug/2006:19:33:01 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:33:01 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:33:02 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:33:02 -0400] cupsdReadClient: 7 POST / HTTP/1.1 D [26/Aug/2006:19:33:02 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:33:02 -0400] Get-Jobs ipp://localhost:631/printers/MyPrinter D [26/Aug/2006:19:33:02 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:33:02 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:33:02 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:33:02 -0400] cupsdReadClient: 7 POST / HTTP/1.1 D [26/Aug/2006:19:33:02 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:33:02 -0400] Get-Jobs ipp://localhost:631/printers/MyPrinter D [26/Aug/2006:19:33:02 -0400] Loading attributes for job 236... D [26/Aug/2006:19:33:02 -0400] Loading attributes for job 242... D [26/Aug/2006:19:33:02 -0400] Loading attributes for job 243... D [26/Aug/2006:19:33:02 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:33:02 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:33:03 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:33:03 -0400] cupsdReadClient: 7 POST /printers/ HTTP/1.1 D [26/Aug/2006:19:33:03 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:33:03 -0400] CUPS-Get-Printers D [26/Aug/2006:19:33:03 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:33:03 -0400] cupsdAcceptClient: 8 from localhost:631 (IPv4) D [26/Aug/2006:19:33:03 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:33:03 -0400] cupsdReadClient: 8 POST /classes/ HTTP/1.1 D [26/Aug/2006:19:33:03 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:33:03 -0400] CUPS-Get-Classes D [26/Aug/2006:19:33:03 -0400] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [26/Aug/2006:19:33:03 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:33:03 -0400] cupsdCloseClient: 8 D [26/Aug/2006:19:33:03 -0400] cupsdReadClient: 7 POST /printers/ HTTP/1.1 D [26/Aug/2006:19:33:03 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:33:03 -0400] CUPS-Get-Default D [26/Aug/2006:19:33:03 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:33:03 -0400] cupsdAcceptClient: 8 from localhost:631 (IPv4) D [26/Aug/2006:19:33:03 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:33:03 -0400] cupsdReadClient: 8 POST /printers/ HTTP/1.1 D [26/Aug/2006:19:33:03 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:33:03 -0400] Get-Printer-Attributes ipp://localhost:631/printers/MyPrinter D [26/Aug/2006:19:33:03 -0400] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [26/Aug/2006:19:33:03 -0400] cupsdReadClient: 5 POST / HTTP/1.1 D [26/Aug/2006:19:33:03 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:33:03 -0400] Get-Printer-Attributes ipp://localhost/printers/MyPrinter D [26/Aug/2006:19:33:03 -0400] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) D [26/Aug/2006:19:33:03 -0400] cupsdCloseClient: 8 D [26/Aug/2006:19:33:03 -0400] cupsdReadClient: 5 GET /printers/MyPrinter.ppd HTTP/1.1 D [26/Aug/2006:19:33:03 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:33:03 -0400] write_file: 5 file=7 D [26/Aug/2006:19:33:03 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:33:03 -0400] cupsdReadClient: 7 POST / HTTP/1.1 D [26/Aug/2006:19:33:03 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:33:03 -0400] Get-Jobs ipp://localhost:631/printers/MyPrinter D [26/Aug/2006:19:33:03 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:33:03 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:33:04 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [26/Aug/2006:19:33:04 -0400] cupsdReadClient: 7 POST / HTTP/1.1 D [26/Aug/2006:19:33:04 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:33:04 -0400] Get-Jobs ipp://localhost:631/printers/MyPrinter D [26/Aug/2006:19:33:04 -0400] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [26/Aug/2006:19:33:04 -0400] cupsdCloseClient: 7 D [26/Aug/2006:19:33:05 -0400] cupsdCloseClient: 5 D [26/Aug/2006:19:33:22 -0400] cupsdAcceptClient: 5 from localhost (Domain) D [26/Aug/2006:19:33:22 -0400] cupsdReadClient: 5 POST / HTTP/1.1 D [26/Aug/2006:19:33:22 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:33:22 -0400] CUPS-Get-Printers D [26/Aug/2006:19:33:22 -0400] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) D [26/Aug/2006:19:33:22 -0400] cupsdReadClient: 5 POST / HTTP/1.1 D [26/Aug/2006:19:33:22 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:33:22 -0400] CUPS-Get-Classes D [26/Aug/2006:19:33:22 -0400] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) D [26/Aug/2006:19:33:22 -0400] cupsdReadClient: 5 POST / HTTP/1.1 D [26/Aug/2006:19:33:22 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:33:22 -0400] CUPS-Get-Default D [26/Aug/2006:19:33:22 -0400] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) D [26/Aug/2006:19:33:22 -0400] cupsdReadClient: 5 POST / HTTP/1.1 D [26/Aug/2006:19:33:22 -0400] cupsdAuthorize: No authentication data provided. D [26/Aug/2006:19:33:22 -0400] CUPS-Get-Printers D [26/Aug/2006:19:33:22 -0400] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) D [26/Aug/2006:19:33:22 -0400] cupsdCloseClient: 5