I [01/May/2008:17:20:21 +1000] Listening to 127.0.0.1:631 (IPv4)
I [01/May/2008:17:20:21 +1000] Listening to /var/run/cups/cups.sock (Domain)
I [01/May/2008:17:20:21 +1000] Loaded configuration file "/etc/cups/cupsd.conf"
I [01/May/2008:17:20:21 +1000] Using default TempDir of /var/spool/cups/tmp...
I [01/May/2008:17:20:21 +1000] Configured for up to 100 clients.
I [01/May/2008:17:20:21 +1000] Allowing up to 100 client connections per host.
I [01/May/2008:17:20:21 +1000] Using policy "default" as the default!
I [01/May/2008:17:20:21 +1000] Partial reload complete.
I [01/May/2008:17:20:21 +1000] Listening to 127.0.0.1:631 on fd 3...
I [01/May/2008:17:20:21 +1000] Listening to /var/run/cups/cups.sock on fd 5...
I [01/May/2008:17:20:21 +1000] Resuming new connection processing...
D [01/May/2008:17:20:21 +1000] cupsdAcceptClient: 8 from localhost (Domain)
D [01/May/2008:17:20:21 +1000] cupsdReadClient: 8 POST / HTTP/1.1
E [01/May/2008:17:20:21 +1000] cupsdAuthorize: Local authentication certificate not found!
D [01/May/2008:17:20:21 +1000] CUPS-Get-Classes
D [01/May/2008:17:20:21 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [01/May/2008:17:20:21 +1000] cupsdReadClient: 8 POST / HTTP/1.1
E [01/May/2008:17:20:21 +1000] cupsdAuthorize: Local authentication certificate not found!
D [01/May/2008:17:20:21 +1000] CUPS-Get-Printers
D [01/May/2008:17:20:21 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [01/May/2008:17:20:21 +1000] cupsdReadClient: 8 POST / HTTP/1.1
E [01/May/2008:17:20:21 +1000] cupsdAuthorize: Local authentication certificate not found!
D [01/May/2008:17:20:21 +1000] CUPS-Get-Classes
D [01/May/2008:17:20:21 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [01/May/2008:17:20:22 +1000] cupsdReadClient: 8 POST / HTTP/1.1
E [01/May/2008:17:20:22 +1000] cupsdAuthorize: Local authentication certificate not found!
D [01/May/2008:17:20:22 +1000] Get-Printer-Attributes ipp://localhost/printers/LexmarkE232
D [01/May/2008:17:20:22 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [01/May/2008:17:20:22 +1000] cupsdReadClient: 8 POST / HTTP/1.1
E [01/May/2008:17:20:22 +1000] cupsdAuthorize: Local authentication certificate not found!
D [01/May/2008:17:20:22 +1000] Get-Printer-Attributes ipp://localhost/printers/PDF
D [01/May/2008:17:20:22 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [01/May/2008:17:20:44 +1000] cupsdAcceptClient: 10 from localhost (Domain)
D [01/May/2008:17:20:44 +1000] cupsdReadClient: 10 POST / HTTP/1.1
D [01/May/2008:17:20:44 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:20:44 +1000] CUPS-Get-Default
D [01/May/2008:17:20:44 +1000] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [01/May/2008:17:20:44 +1000] cupsdAcceptClient: 11 from localhost (Domain)
D [01/May/2008:17:20:44 +1000] cupsdCloseClient: 10
D [01/May/2008:17:20:44 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:20:44 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:20:44 +1000] CUPS-Get-Printers
D [01/May/2008:17:20:44 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:20:44 +1000] cupsdCloseClient: 11
D [01/May/2008:17:20:47 +1000] cupsdAcceptClient: 10 from localhost (Domain)
D [01/May/2008:17:20:47 +1000] cupsdReadClient: 10 POST / HTTP/1.1
D [01/May/2008:17:20:47 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:20:47 +1000] CUPS-Get-Printers
D [01/May/2008:17:20:47 +1000] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [01/May/2008:17:20:47 +1000] cupsdCloseClient: 10
D [01/May/2008:17:20:50 +1000] cupsdAcceptClient: 10 from localhost (Domain)
D [01/May/2008:17:20:51 +1000] cupsdReadClient: 10 POST / HTTP/1.1
D [01/May/2008:17:20:51 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:20:51 +1000] CUPS-Get-Printers
D [01/May/2008:17:20:51 +1000] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [01/May/2008:17:20:51 +1000] cupsdCloseClient: 10
D [01/May/2008:17:21:31 +1000] cupsdAcceptClient: 10 from localhost (Domain)
D [01/May/2008:17:21:31 +1000] cupsdReadClient: 10 POST / HTTP/1.1
D [01/May/2008:17:21:31 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:21:31 +1000] CUPS-Get-Default
D [01/May/2008:17:21:31 +1000] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [01/May/2008:17:21:31 +1000] cupsdAcceptClient: 11 from localhost (Domain)
D [01/May/2008:17:21:31 +1000] cupsdCloseClient: 10
D [01/May/2008:17:21:31 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:21:31 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:21:31 +1000] CUPS-Get-Printers
D [01/May/2008:17:21:31 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:21:31 +1000] cupsdCloseClient: 11
D [01/May/2008:17:21:34 +1000] cupsdAcceptClient: 10 from localhost (Domain)
D [01/May/2008:17:21:34 +1000] cupsdReadClient: 10 POST / HTTP/1.1
D [01/May/2008:17:21:34 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:21:34 +1000] CUPS-Get-Printers
D [01/May/2008:17:21:34 +1000] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [01/May/2008:17:21:34 +1000] cupsdCloseClient: 10
D [01/May/2008:17:21:37 +1000] cupsdAcceptClient: 10 from localhost (Domain)
D [01/May/2008:17:21:37 +1000] cupsdReadClient: 10 POST / HTTP/1.1
D [01/May/2008:17:21:37 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:21:37 +1000] CUPS-Get-Printers
D [01/May/2008:17:21:37 +1000] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [01/May/2008:17:21:37 +1000] cupsdCloseClient: 10
D [01/May/2008:17:21:39 +1000] cupsdAcceptClient: 10 from localhost:631 (IPv4)
D [01/May/2008:17:21:39 +1000] cupsdReadClient: 10 GET /printers/LexmarkE232.ppd HTTP/1.1
D [01/May/2008:17:21:39 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:21:40 +1000] cupsdCloseClient: 10
D [01/May/2008:17:21:40 +1000] cupsdAcceptClient: 10 from localhost (Domain)
D [01/May/2008:17:21:40 +1000] cupsdReadClient: 10 POST / HTTP/1.1
D [01/May/2008:17:21:40 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:21:40 +1000] CUPS-Get-Printers
D [01/May/2008:17:21:40 +1000] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [01/May/2008:17:21:40 +1000] cupsdCloseClient: 10
D [01/May/2008:17:21:43 +1000] cupsdAcceptClient: 10 from localhost (Domain)
D [01/May/2008:17:21:43 +1000] cupsdReadClient: 10 POST /printers/LexmarkE232 HTTP/1.1
D [01/May/2008:17:21:43 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:21:43 +1000] Print-Job ipp://localhost:631/printers/LexmarkE232
D [01/May/2008:17:21:43 +1000] print_job: auto-typing file...
D [01/May/2008:17:21:43 +1000] add_job: requesting-user-name="john"
I [01/May/2008:17:21:43 +1000] [Job 95] Adding start banner page "none".
D [01/May/2008:17:21:43 +1000] Discarding unused job-created event...
I [01/May/2008:17:21:43 +1000] [Job 95] Adding job file of type application/postscript.
I [01/May/2008:17:21:43 +1000] [Job 95] Adding end banner page "none".
I [01/May/2008:17:21:43 +1000] [Job 95] Queued on "LexmarkE232" by "john".
D [01/May/2008:17:21:43 +1000] [Job 95] hold_until = 0
D [01/May/2008:17:21:43 +1000] Discarding unused printer-state-changed event...
D [01/May/2008:17:21:43 +1000] [Job 95] job-sheets=none,none
D [01/May/2008:17:21:43 +1000] [Job 95] banner_page = 0
D [01/May/2008:17:21:43 +1000] [Job 95] argv[0]="LexmarkE232"
D [01/May/2008:17:21:43 +1000] [Job 95] argv[1]="95"
D [01/May/2008:17:21:43 +1000] [Job 95] argv[2]="john"
D [01/May/2008:17:21:43 +1000] [Job 95] argv[3]="evince-print"
D [01/May/2008:17:21:43 +1000] [Job 95] argv[4]="1"
D [01/May/2008:17:21:43 +1000] [Job 95] argv[5]="PrintoutMode=Normal.Gray ColorMode=FromPrintoutMode PageSize=A4 PrinterResolution=FromPrintoutMode InputSlot=Default number-up=1 job-uuid=urn:uuid:bda48da4-1b95-3ae1-7197-d12df8bf93ea"
D [01/May/2008:17:21:43 +1000] [Job 95] argv[6]="/var/spool/cups/d00095-001"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[9]="SERVER_ADMIN=root@john-laptop"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[10]="SOFTWARE=CUPS/1.3.2"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[12]="TZ=Australia/Melbourne"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[13]="USER=root"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[16]="IPP_PORT=631"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[17]="CHARSET=utf-8"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[18]="LANG=en_AU"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[19]="PPD=/etc/cups/ppd/LexmarkE232.ppd"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[20]="RIP_MAX_CACHE=8m"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[21]="CONTENT_TYPE=application/postscript"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[22]="DEVICE_URI=parallel:/dev/lp0"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[23]="PRINTER=LexmarkE232"
D [01/May/2008:17:21:43 +1000] [Job 95] envp[24]="FINAL_CONTENT_TYPE=printer/LexmarkE232"
I [01/May/2008:17:21:43 +1000] [Job 95] Started filter /usr/lib/cups/filter/pstops (PID 17670)
I [01/May/2008:17:21:43 +1000] [Job 95] Started filter /usr/lib/cups/filter/foomatic-rip (PID 17671)
I [01/May/2008:17:21:43 +1000] [Job 95] Started backend /usr/lib/cups/backend/parallel (PID 17672)
D [01/May/2008:17:21:43 +1000] Discarding unused job-state event...
D [01/May/2008:17:21:43 +1000] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [01/May/2008:17:21:43 +1000] [Job 95] backendRunLoop(print_fd=0, device_fd=5, use_bc=0, side_cb=0x8049560)
D [01/May/2008:17:21:43 +1000] Discarding unused printer-state-changed event...
D [01/May/2008:17:21:43 +1000] [Job 95] perl: warning: Setting locale failed.
D [01/May/2008:17:21:43 +1000] [Job 95] perl: warning: Please check that your locale settings:
D [01/May/2008:17:21:43 +1000] [Job 95] LANGUAGE = (unset),
D [01/May/2008:17:21:43 +1000] [Job 95] LC_ALL = (unset),
D [01/May/2008:17:21:43 +1000] [Job 95] LANG = "en_AU"
D [01/May/2008:17:21:43 +1000] [Job 95] are supported and installed on your system.
D [01/May/2008:17:21:43 +1000] [Job 95] perl: warning: Falling back to the standard locale ("C").
D [01/May/2008:17:21:43 +1000] cupsdAcceptClient: 11 from localhost (Domain)
D [01/May/2008:17:21:43 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:21:43 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:21:43 +1000] Get-Jobs ipp://localhost/jobs/
D [01/May/2008:17:21:43 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:21:43 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:21:43 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:21:43 +1000] CUPS-Get-Printers
D [01/May/2008:17:21:43 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:21:43 +1000] cupsdCloseClient: 10
D [01/May/2008:17:21:43 +1000] cupsdCloseClient: 11
D [01/May/2008:17:21:44 +1000] [Job 95] Page = 595x842; 18,36 to 577,806
D [01/May/2008:17:21:44 +1000] [Job 95] slow_collate=0, slow_duplex=0, slow_order=0
D [01/May/2008:17:21:44 +1000] [Job 95] Before copy_comments - %!PS-Adobe-3.0
D [01/May/2008:17:21:44 +1000] [Job 95] %!PS-Adobe-3.0
D [01/May/2008:17:21:44 +1000] [Job 95] %%Creator: cairo 1.4.10 (http://cairographics.org)
D [01/May/2008:17:21:44 +1000] [Job 95] %%CreationDate: Thu May  1 17:21:43 2008
D [01/May/2008:17:21:44 +1000] [Job 95] %%Pages: 1
D [01/May/2008:17:21:44 +1000] [Job 95] %%BoundingBox: 0 0 596 842
D [01/May/2008:17:21:44 +1000] [Job 95] %%DocumentData: Clean7Bit
D [01/May/2008:17:21:44 +1000] [Job 95] %%LanguageLevel: 2
D [01/May/2008:17:21:44 +1000] [Job 95] %%EndComments
D [01/May/2008:17:21:44 +1000] [Job 95] Before copy_prolog - %%BeginProlog
D [01/May/2008:17:21:44 +1000] [Job 95] Before copy_setup - % _cairo_ps_surface_emit_font_subsets
D [01/May/2008:17:21:44 +1000] cupsdAcceptClient: 10 from localhost (Domain)
D [01/May/2008:17:21:44 +1000] cupsdReadClient: 10 POST / HTTP/1.1
D [01/May/2008:17:21:44 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:21:44 +1000] Get-Jobs ipp://localhost/jobs/
D [01/May/2008:17:21:44 +1000] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [01/May/2008:17:21:44 +1000] cupsdReadClient: 10 POST / HTTP/1.1
D [01/May/2008:17:21:44 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:21:44 +1000] CUPS-Get-Printers
D [01/May/2008:17:21:44 +1000] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [01/May/2008:17:21:45 +1000] cupsdCloseClient: 10
D [01/May/2008:17:21:46 +1000] [Job 95] foomatic-rip version $Revision$ running...
D [01/May/2008:17:21:46 +1000] [Job 95] Parsing PPD file ...
D [01/May/2008:17:21:46 +1000] [Job 95] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
D [01/May/2008:17:21:46 +1000] [Job 95] Added option ColorSpace
D [01/May/2008:17:21:46 +1000] [Job 95] Added option Resolution
D [01/May/2008:17:21:46 +1000] [Job 95] Added option PrintoutMode
D [01/May/2008:17:21:46 +1000] [Job 95] Added option PageSize
D [01/May/2008:17:21:46 +1000] [Job 95] Added option PageRegion
D [01/May/2008:17:21:46 +1000] [Job 95] Added option ImageableArea
D [01/May/2008:17:21:46 +1000] [Job 95] Added option PaperDimension
D [01/May/2008:17:21:46 +1000] [Job 95] Added option InputSlot
D [01/May/2008:17:21:46 +1000] [Job 95] Added option GSResolution
D [01/May/2008:17:21:46 +1000] [Job 95] Added option JCLResolution
D [01/May/2008:17:21:46 +1000] [Job 95] Added option ColorMode
D [01/May/2008:17:21:46 +1000] [Job 95] Added option PrinterResolution
D [01/May/2008:17:21:46 +1000] [Job 95] Added option Font
D [01/May/2008:17:21:46 +1000] [Job 95] 
D [01/May/2008:17:21:46 +1000] [Job 95] Parameter Summary
D [01/May/2008:17:21:46 +1000] [Job 95] -----------------
D [01/May/2008:17:21:46 +1000] [Job 95] 
D [01/May/2008:17:21:46 +1000] [Job 95] Spooler: cups
D [01/May/2008:17:21:46 +1000] [Job 95] Printer: LexmarkE232
D [01/May/2008:17:21:46 +1000] [Job 95] Shell: /bin/bash
D [01/May/2008:17:21:46 +1000] [Job 95] PPD file: /etc/cups/ppd/LexmarkE232.ppd
D [01/May/2008:17:21:46 +1000] [Job 95] ATTR file: 
D [01/May/2008:17:21:46 +1000] [Job 95] Printer model: Generic PCL 6/PCL XL Printer Foomatic/pxlmono (recommended)
D [01/May/2008:17:21:46 +1000] [Job 95] Job title: evince-print
D [01/May/2008:17:21:46 +1000] [Job 95] File(s) to be printed: 
D [01/May/2008:17:21:46 +1000] [Job 95] <STDIN>
D [01/May/2008:17:21:46 +1000] [Job 95] 
D [01/May/2008:17:21:46 +1000] [Job 95] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [01/May/2008:17:21:46 +1000] [Job 95] Pondering option 'PrintoutMode=Normal.Gray'
D [01/May/2008:17:21:46 +1000] [Job 95] Pondering option 'ColorMode=FromPrintoutMode'
D [01/May/2008:17:21:46 +1000] [Job 95] Pondering option 'PageSize=A4'
D [01/May/2008:17:21:46 +1000] [Job 95] Pondering option 'PrinterResolution=FromPrintoutMode'
D [01/May/2008:17:21:46 +1000] [Job 95] Pondering option 'InputSlot=Default'
D [01/May/2008:17:21:46 +1000] [Job 95] Pondering option 'number-up=1'
D [01/May/2008:17:21:46 +1000] [Job 95] Unknown option number-up=1.
D [01/May/2008:17:21:46 +1000] [Job 95] Pondering option 'job-uuid=urn:uuid:bda48da4-1b95-3ae1-7197-d12df8bf93ea'
D [01/May/2008:17:21:46 +1000] [Job 95] Unknown option job-uuid=urn:uuid:bda48da4-1b95-3ae1-7197-d12df8bf93ea.
D [01/May/2008:17:21:46 +1000] [Job 95] 
D [01/May/2008:17:21:46 +1000] [Job 95] ================================================
D [01/May/2008:17:21:46 +1000] [Job 95] 
D [01/May/2008:17:21:46 +1000] [Job 95] File: <STDIN>
D [01/May/2008:17:21:46 +1000] [Job 95] 
D [01/May/2008:17:21:46 +1000] [Job 95] ================================================
D [01/May/2008:17:21:46 +1000] [Job 95] 
D [01/May/2008:17:21:46 +1000] [Job 95] Reading PostScript input ...
D [01/May/2008:17:21:46 +1000] [Job 95] --> This document is DSC-conforming!
D [01/May/2008:17:21:46 +1000] [Job 95] 
D [01/May/2008:17:21:46 +1000] [Job 95] -----------
D [01/May/2008:17:21:46 +1000] [Job 95] Found: %%BeginProlog
D [01/May/2008:17:21:46 +1000] [Job 95] Found: %%EndProlog
D [01/May/2008:17:21:46 +1000] [Job 95] Before page loop - %%Page: 1 1
D [01/May/2008:17:21:46 +1000] [Job 95] Copying page 1...
D [01/May/2008:17:21:46 +1000] [Job 95] pagew = 559.0, pagel = 770.0
D [01/May/2008:17:21:46 +1000] [Job 95] bboxw = 595, bboxl = 842
D [01/May/2008:17:21:46 +1000] [Job 95] PageLeft = 18.0, PageRight = 577.0
D [01/May/2008:17:21:46 +1000] [Job 95] PageTop = 806.0, PageBottom = 36.0
D [01/May/2008:17:21:46 +1000] [Job 95] PageWidth = 595.0, PageLength = 842.0
D [01/May/2008:17:21:46 +1000] [Job 95] This job seems not to be DSC-conforming, DSC-comment for next section not found, stopping to parse the rest, passing it directly to the renderer.
D [01/May/2008:17:21:46 +1000] [Job 95] "Setup" section is missing, inserting it.
D [01/May/2008:17:21:46 +1000] [Job 95] Inserting PostScript code for CUPS' page accounting
D [01/May/2008:17:21:46 +1000] [Job 95] "PageSetup" section is missing, inserting it.
D [01/May/2008:17:21:46 +1000] [Job 95] Inserting option code into "PageSetup" section.
D [01/May/2008:17:21:46 +1000] [Job 95] Stopped parsing the PostScript data, sending rest directly to renderer.
D [01/May/2008:17:21:46 +1000] [Job 95] 
D [01/May/2008:17:21:46 +1000] [Job 95] Starting renderer
D [01/May/2008:17:21:46 +1000] [Job 95] JCL: %-12345X@PJL
D [01/May/2008:17:21:46 +1000] [Job 95] @PJL SET RESOLUTION=600
D [01/May/2008:17:21:46 +1000] [Job 95] <job data> 
D [01/May/2008:17:21:46 +1000] [Job 95] %-12345X@PJL RESET
D [01/May/2008:17:21:46 +1000] [Job 95] 
D [01/May/2008:17:21:46 +1000] [Job 95] renderer PID kid4=17680
D [01/May/2008:17:21:46 +1000] [Job 95] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dNOPAUSE -sDEVICE=pxlmono -r600x600 -sOutputFile=- - | perl -p -e 'if (! $did) { s/\xc0.\xf8\x26/\xc0\x01\xf8\x26/ && $did++; }'
D [01/May/2008:17:21:46 +1000] [Job 95] perl: warning: Setting locale failed.
D [01/May/2008:17:21:46 +1000] [Job 95] perl: warning: Please check that your locale settings:
D [01/May/2008:17:21:46 +1000] [Job 95] LANGUAGE = (unset),
D [01/May/2008:17:21:46 +1000] [Job 95] LC_ALL = (unset),
D [01/May/2008:17:21:46 +1000] [Job 95] LANG = "en_AU"
D [01/May/2008:17:21:46 +1000] [Job 95] are supported and installed on your system.
D [01/May/2008:17:21:46 +1000] [Job 95] perl: warning: Falling back to the standard locale ("C").
D [01/May/2008:17:21:46 +1000] [Job 95] perl: warning: Setting locale failed.
D [01/May/2008:17:21:46 +1000] [Job 95] perl: warning: Please check that your locale settings:
D [01/May/2008:17:21:46 +1000] [Job 95] LANGUAGE = (unset),
D [01/May/2008:17:21:46 +1000] [Job 95] LC_ALL = (unset),
D [01/May/2008:17:21:46 +1000] [Job 95] LANG = "en_AU"
D [01/May/2008:17:21:46 +1000] [Job 95] are supported and installed on your system.
D [01/May/2008:17:21:46 +1000] [Job 95] perl: warning: Falling back to the standard locale ("C").
D [01/May/2008:17:21:47 +1000] [Job 95] foomatic-gswrapper: gs '-sstdout=%stderr' '-dBATCH' '-dPARANOIDSAFER' '-dNOPAUSE' '-sDEVICE=pxlmono' '-r600x600' '-sOutputFile=%stdout' '-'
D [01/May/2008:17:21:47 +1000] [Job 95] GPL Ghostscript SVN PRE-RELEASE 8.61 (2007-08-02)
D [01/May/2008:17:21:47 +1000] [Job 95] Copyright (C) 2007 Artifex Software, Inc.  All rights reserved.
D [01/May/2008:17:21:47 +1000] [Job 95] This software comes with NO WARRANTY: see the file PUBLIC for details.
D [01/May/2008:17:21:47 +1000] [Job 95] Wrote 1 pages...
D [01/May/2008:17:21:47 +1000] PID 17670 (/usr/lib/cups/filter/pstops) exited with no errors.
D [01/May/2008:17:21:48 +1000] Discarding unused job-progress event...
D [01/May/2008:17:21:48 +1000] Discarding unused printer-state-changed event...
D [01/May/2008:17:21:48 +1000] cupsdAcceptClient: 11 from localhost (Domain)
D [01/May/2008:17:21:48 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:21:48 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:21:48 +1000] Get-Jobs ipp://localhost/jobs/
D [01/May/2008:17:21:48 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:21:48 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:21:48 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:21:48 +1000] CUPS-Get-Printers
D [01/May/2008:17:21:48 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:21:48 +1000] cupsdCloseClient: 11
D [01/May/2008:17:21:48 +1000] [Job 95] 
D [01/May/2008:17:21:48 +1000] [Job 95] Closing renderer
D [01/May/2008:17:21:48 +1000] [Job 95] Read 8192 bytes of print data...
D [01/May/2008:17:21:48 +1000] Discarding unused printer-state-changed event...
D [01/May/2008:17:21:48 +1000] Discarding unused printer-state-changed event...
D [01/May/2008:17:21:48 +1000] Discarding unused printer-state-changed event...
D [01/May/2008:17:21:48 +1000] [Job 95] Wrote 8192 bytes of print data...
D [01/May/2008:17:21:48 +1000] [Job 95] Read 8192 bytes of print data...
D [01/May/2008:17:21:48 +1000] Discarding unused job-progress event...
D [01/May/2008:17:21:48 +1000] [Job 95] KID3 exited with status 0
D [01/May/2008:17:21:48 +1000] [Job 95] Wrote 8192 bytes of print data...
D [01/May/2008:17:21:48 +1000] [Job 95] Read 8192 bytes of print data...
D [01/May/2008:17:21:48 +1000] [Job 95] Wrote 8192 bytes of print data...
D [01/May/2008:17:21:48 +1000] [Job 95] Read 8192 bytes of print data...
D [01/May/2008:17:21:49 +1000] [Job 95] Wrote 8192 bytes of print data...
D [01/May/2008:17:21:49 +1000] [Job 95] Read 8192 bytes of print data...
D [01/May/2008:17:21:49 +1000] [Job 95] Wrote 8192 bytes of print data...
D [01/May/2008:17:21:49 +1000] [Job 95] Read 8192 bytes of print data...
D [01/May/2008:17:21:49 +1000] [Job 95] Wrote 8192 bytes of print data...
D [01/May/2008:17:21:49 +1000] [Job 95] Read 8192 bytes of print data...
D [01/May/2008:17:21:49 +1000] [Job 95] Wrote 8192 bytes of print data...
D [01/May/2008:17:21:49 +1000] [Job 95] Read 8192 bytes of print data...
D [01/May/2008:17:21:49 +1000] cupsdAcceptClient: 11 from localhost (Domain)
D [01/May/2008:17:21:49 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:21:49 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:21:49 +1000] Get-Jobs ipp://localhost/jobs/
D [01/May/2008:17:21:49 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:21:49 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:21:49 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:21:49 +1000] CUPS-Get-Printers
D [01/May/2008:17:21:49 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:21:49 +1000] cupsdCloseClient: 11
D [01/May/2008:17:21:49 +1000] [Job 95] Wrote 8192 bytes of print data...
D [01/May/2008:17:21:49 +1000] [Job 95] Read 8192 bytes of print data...
D [01/May/2008:17:21:49 +1000] [Job 95] Wrote 8192 bytes of print data...
D [01/May/2008:17:21:49 +1000] [Job 95] tail process done writing data to STDOUT
D [01/May/2008:17:21:49 +1000] [Job 95] KID4 finished
D [01/May/2008:17:21:49 +1000] [Job 95] KID4 exited with status 0
D [01/May/2008:17:21:49 +1000] [Job 95] Renderer exit stat: 0
D [01/May/2008:17:21:49 +1000] [Job 95] KID3 finished
D [01/May/2008:17:21:49 +1000] [Job 95] Renderer process finished
D [01/May/2008:17:21:49 +1000] [Job 95] 
D [01/May/2008:17:21:49 +1000] [Job 95] Closing foomatic-rip.
D [01/May/2008:17:21:49 +1000] PID 17671 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [01/May/2008:17:21:49 +1000] [Job 95] Read 8192 bytes of print data...
D [01/May/2008:17:21:49 +1000] [Job 95] Wrote 8192 bytes of print data...
D [01/May/2008:17:21:49 +1000] [Job 95] Read 8192 bytes of print data...
D [01/May/2008:17:21:50 +1000] [Job 95] Wrote 8192 bytes of print data...
D [01/May/2008:17:21:50 +1000] [Job 95] Read 8192 bytes of print data...
D [01/May/2008:17:21:50 +1000] [Job 95] Wrote 8192 bytes of print data...
D [01/May/2008:17:21:50 +1000] [Job 95] Read 8192 bytes of print data...
D [01/May/2008:17:21:50 +1000] [Job 95] Wrote 8192 bytes of print data...
D [01/May/2008:17:21:50 +1000] [Job 95] Read 8192 bytes of print data...
D [01/May/2008:17:21:50 +1000] [Job 95] Wrote 8192 bytes of print data...
D [01/May/2008:17:21:50 +1000] [Job 95] Read 8192 bytes of print data...
D [01/May/2008:17:21:50 +1000] [Job 95] Wrote 8192 bytes of print data...
D [01/May/2008:17:21:50 +1000] [Job 95] Read 8192 bytes of print data...
D [01/May/2008:17:21:50 +1000] [Job 95] Wrote 8192 bytes of print data...
D [01/May/2008:17:21:50 +1000] [Job 95] Read 8192 bytes of print data...
D [01/May/2008:17:21:50 +1000] [Job 95] Wrote 8192 bytes of print data...
D [01/May/2008:17:21:50 +1000] [Job 95] Read 6716 bytes of print data...
D [01/May/2008:17:21:51 +1000] [Job 95] Wrote 6716 bytes of print data...
D [01/May/2008:17:21:53 +1000] PID 17672 (/usr/lib/cups/backend/parallel) exited with no errors.
D [01/May/2008:17:21:53 +1000] [Job 95] File 0 is complete.
I [01/May/2008:17:21:53 +1000] [Job 95] Completed successfully.
D [01/May/2008:17:21:53 +1000] Discarding unused printer-state-changed event...
D [01/May/2008:17:21:53 +1000] Discarding unused job-completed event...
D [01/May/2008:17:21:53 +1000] cupsdAcceptClient: 11 from localhost (Domain)
D [01/May/2008:17:21:53 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:21:53 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:21:53 +1000] Get-Jobs ipp://localhost/jobs/
D [01/May/2008:17:21:53 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:21:53 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:21:53 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:21:53 +1000] CUPS-Get-Printers
D [01/May/2008:17:21:53 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:21:53 +1000] cupsdCloseClient: 11
D [01/May/2008:17:21:54 +1000] [Job 95] Unloading...
D [01/May/2008:17:25:23 +1000] Closing client 8 after 300 seconds of inactivity...
D [01/May/2008:17:25:23 +1000] cupsdCloseClient: 8
D [01/May/2008:17:31:55 +1000] cupsdAcceptClient: 8 from localhost (Domain)
D [01/May/2008:17:31:55 +1000] cupsdReadClient: 8 POST / HTTP/1.1
D [01/May/2008:17:31:55 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:31:55 +1000] CUPS-Get-Printers
D [01/May/2008:17:31:55 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [01/May/2008:17:31:55 +1000] cupsdReadClient: 8 POST / HTTP/1.1
D [01/May/2008:17:31:55 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:31:55 +1000] CUPS-Get-Classes
D [01/May/2008:17:31:55 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [01/May/2008:17:31:55 +1000] cupsdReadClient: 8 POST / HTTP/1.1
D [01/May/2008:17:31:55 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:31:55 +1000] CUPS-Get-Default
D [01/May/2008:17:31:55 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [01/May/2008:17:31:55 +1000] cupsdCloseClient: 8
D [01/May/2008:17:31:55 +1000] cupsdAcceptClient: 8 from localhost (Domain)
D [01/May/2008:17:31:55 +1000] cupsdReadClient: 8 POST / HTTP/1.1
D [01/May/2008:17:31:55 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:31:55 +1000] Get-Printer-Attributes ipp://localhost/printers/LexmarkE232
D [01/May/2008:17:31:55 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [01/May/2008:17:31:55 +1000] cupsdReadClient: 8 GET /printers/LexmarkE232.ppd HTTP/1.1
D [01/May/2008:17:31:55 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:31:55 +1000] cupsdCloseClient: 8
D [01/May/2008:17:31:55 +1000] cupsdAcceptClient: 8 from localhost (Domain)
D [01/May/2008:17:31:55 +1000] cupsdReadClient: 8 POST / HTTP/1.1
D [01/May/2008:17:31:55 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:31:55 +1000] CUPS-Get-Printers
D [01/May/2008:17:31:55 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [01/May/2008:17:31:55 +1000] cupsdReadClient: 8 POST / HTTP/1.1
D [01/May/2008:17:31:55 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:31:55 +1000] CUPS-Get-Classes
D [01/May/2008:17:31:55 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [01/May/2008:17:31:55 +1000] cupsdReadClient: 8 POST / HTTP/1.1
D [01/May/2008:17:31:55 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:31:55 +1000] CUPS-Get-Default
D [01/May/2008:17:31:55 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [01/May/2008:17:31:55 +1000] cupsdCloseClient: 8
D [01/May/2008:17:32:04 +1000] cupsdAcceptClient: 8 from localhost (Domain)
D [01/May/2008:17:32:04 +1000] cupsdReadClient: 8 POST /printers/LexmarkE232 HTTP/1.1
D [01/May/2008:17:32:04 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:32:04 +1000] Print-Job ipp://localhost/printers/LexmarkE232
D [01/May/2008:17:32:04 +1000] print_job: auto-typing file...
D [01/May/2008:17:32:04 +1000] add_job: requesting-user-name="john"
D [01/May/2008:17:32:04 +1000] Adding default job-sheets values "none,none"...
I [01/May/2008:17:32:04 +1000] [Job 96] Adding start banner page "none".
D [01/May/2008:17:32:04 +1000] Discarding unused job-created event...
I [01/May/2008:17:32:04 +1000] [Job 96] Adding job file of type application/postscript.
I [01/May/2008:17:32:04 +1000] [Job 96] Adding end banner page "none".
I [01/May/2008:17:32:04 +1000] [Job 96] Queued on "LexmarkE232" by "john".
D [01/May/2008:17:32:04 +1000] [Job 96] hold_until = 0
D [01/May/2008:17:32:04 +1000] Discarding unused printer-state-changed event...
D [01/May/2008:17:32:04 +1000] [Job 96] job-sheets=none,none
D [01/May/2008:17:32:04 +1000] [Job 96] banner_page = 0
D [01/May/2008:17:32:04 +1000] [Job 96] argv[0]="LexmarkE232"
D [01/May/2008:17:32:04 +1000] [Job 96] argv[1]="96"
D [01/May/2008:17:32:04 +1000] [Job 96] argv[2]="john"
D [01/May/2008:17:32:04 +1000] [Job 96] argv[3]="Untitled1"
D [01/May/2008:17:32:04 +1000] [Job 96] argv[4]="1"
D [01/May/2008:17:32:04 +1000] [Job 96] argv[5]="PageSize=A4 InputSlot=Default job-uuid=urn:uuid:2e360561-cd0c-39e9-4d74-8ec1187cdeb1"
D [01/May/2008:17:32:04 +1000] [Job 96] argv[6]="/var/spool/cups/d00096-001"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[9]="SERVER_ADMIN=root@john-laptop"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[10]="SOFTWARE=CUPS/1.3.2"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[12]="TZ=Australia/Melbourne"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[13]="USER=root"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[16]="IPP_PORT=631"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[17]="CHARSET=utf-8"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[18]="LANG=en_AU"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[19]="PPD=/etc/cups/ppd/LexmarkE232.ppd"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[20]="RIP_MAX_CACHE=8m"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[21]="CONTENT_TYPE=application/postscript"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[22]="DEVICE_URI=parallel:/dev/lp0"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[23]="PRINTER=LexmarkE232"
D [01/May/2008:17:32:04 +1000] [Job 96] envp[24]="FINAL_CONTENT_TYPE=printer/LexmarkE232"
I [01/May/2008:17:32:04 +1000] [Job 96] Started filter /usr/lib/cups/filter/pstops (PID 17971)
I [01/May/2008:17:32:04 +1000] [Job 96] Started filter /usr/lib/cups/filter/foomatic-rip (PID 17972)
I [01/May/2008:17:32:05 +1000] [Job 96] Started backend /usr/lib/cups/backend/parallel (PID 17973)
D [01/May/2008:17:32:05 +1000] Discarding unused job-state event...
D [01/May/2008:17:32:05 +1000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [01/May/2008:17:32:05 +1000] PID 17971 (/usr/lib/cups/filter/pstops) exited with no errors.
D [01/May/2008:17:32:05 +1000] cupsdAcceptClient: 11 from localhost (Domain)
D [01/May/2008:17:32:05 +1000] [Job 96] Page = 595x842; 18,36 to 577,806
D [01/May/2008:17:32:05 +1000] [Job 96] slow_collate=0, slow_duplex=0, slow_order=0
D [01/May/2008:17:32:05 +1000] [Job 96] Before copy_comments - %!PS-Adobe-3.0
D [01/May/2008:17:32:05 +1000] [Job 96] %!PS-Adobe-3.0
D [01/May/2008:17:32:05 +1000] [Job 96] %%BoundingBox: (atend)
D [01/May/2008:17:32:05 +1000] [Job 96] %%Creator: (OpenOffice.org 2.4)
D [01/May/2008:17:32:05 +1000] [Job 96] %%For: (john)
D [01/May/2008:17:32:05 +1000] [Job 96] %%CreationDate: (Thu May  1 17:32:04 2008)
D [01/May/2008:17:32:05 +1000] [Job 96] %%Title: (Untitled1)
D [01/May/2008:17:32:05 +1000] [Job 96] %%LanguageLevel: 3
D [01/May/2008:17:32:05 +1000] [Job 96] %%DocumentData: Clean7Bit
D [01/May/2008:17:32:05 +1000] [Job 96] %%Pages: (atend)
D [01/May/2008:17:32:05 +1000] [Job 96] %%PageOrder: Ascend
D [01/May/2008:17:32:05 +1000] [Job 96] %%EndComments
D [01/May/2008:17:32:05 +1000] [Job 96] Before copy_prolog - %%BeginProlog
D [01/May/2008:17:32:05 +1000] [Job 96] Before copy_setup - %%BeginSetup
D [01/May/2008:17:32:05 +1000] [Job 96] Before page loop - %%Page: 1 1
D [01/May/2008:17:32:05 +1000] [Job 96] Copying page 1...
D [01/May/2008:17:32:05 +1000] [Job 96] pagew = 559.0, pagel = 770.0
D [01/May/2008:17:32:05 +1000] [Job 96] bboxw = 595, bboxl = 842
D [01/May/2008:17:32:05 +1000] [Job 96] PageLeft = 18.0, PageRight = 577.0
D [01/May/2008:17:32:05 +1000] [Job 96] PageTop = 806.0, PageBottom = 36.0
D [01/May/2008:17:32:05 +1000] [Job 96] PageWidth = 595.0, PageLength = 842.0
D [01/May/2008:17:32:05 +1000] [Job 96] Wrote 1 pages...
D [01/May/2008:17:32:05 +1000] [Job 96] perl: warning: Setting locale failed.
D [01/May/2008:17:32:05 +1000] [Job 96] perl: warning: Please check that your locale settings:
D [01/May/2008:17:32:05 +1000] [Job 96] LANGUAGE = (unset),
D [01/May/2008:17:32:05 +1000] [Job 96] LC_ALL = (unset),
D [01/May/2008:17:32:05 +1000] [Job 96] LANG = "en_AU"
D [01/May/2008:17:32:05 +1000] [Job 96] are supported and installed on your system.
D [01/May/2008:17:32:05 +1000] [Job 96] perl: warning: Falling back to the standard locale ("C").
D [01/May/2008:17:32:05 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:32:05 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:32:05 +1000] Get-Jobs ipp://localhost/jobs/
D [01/May/2008:17:32:05 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:32:05 +1000] [Job 96] backendRunLoop(print_fd=0, device_fd=5, use_bc=0, side_cb=0x8049560)
D [01/May/2008:17:32:05 +1000] Discarding unused printer-state-changed event...
D [01/May/2008:17:32:05 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:32:05 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:32:05 +1000] CUPS-Get-Printers
D [01/May/2008:17:32:05 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:32:05 +1000] cupsdCloseClient: 11
D [01/May/2008:17:32:05 +1000] [Job 96] foomatic-rip version $Revision$ running...
D [01/May/2008:17:32:05 +1000] [Job 96] Parsing PPD file ...
D [01/May/2008:17:32:06 +1000] [Job 96] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
D [01/May/2008:17:32:06 +1000] [Job 96] Added option ColorSpace
D [01/May/2008:17:32:06 +1000] [Job 96] Added option Resolution
D [01/May/2008:17:32:06 +1000] [Job 96] Added option PrintoutMode
D [01/May/2008:17:32:06 +1000] [Job 96] Added option PageSize
D [01/May/2008:17:32:06 +1000] [Job 96] Added option PageRegion
D [01/May/2008:17:32:06 +1000] [Job 96] Added option ImageableArea
D [01/May/2008:17:32:06 +1000] [Job 96] Added option PaperDimension
D [01/May/2008:17:32:06 +1000] [Job 96] Added option InputSlot
D [01/May/2008:17:32:06 +1000] [Job 96] Added option GSResolution
D [01/May/2008:17:32:06 +1000] [Job 96] Added option JCLResolution
D [01/May/2008:17:32:06 +1000] [Job 96] Added option ColorMode
D [01/May/2008:17:32:06 +1000] [Job 96] Added option PrinterResolution
D [01/May/2008:17:32:06 +1000] [Job 96] Added option Font
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] Parameter Summary
D [01/May/2008:17:32:06 +1000] [Job 96] -----------------
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] Spooler: cups
D [01/May/2008:17:32:06 +1000] [Job 96] Printer: LexmarkE232
D [01/May/2008:17:32:06 +1000] [Job 96] Shell: /bin/bash
D [01/May/2008:17:32:06 +1000] [Job 96] PPD file: /etc/cups/ppd/LexmarkE232.ppd
D [01/May/2008:17:32:06 +1000] [Job 96] ATTR file: 
D [01/May/2008:17:32:06 +1000] [Job 96] Printer model: Generic PCL 6/PCL XL Printer Foomatic/pxlmono (recommended)
D [01/May/2008:17:32:06 +1000] [Job 96] Job title: Untitled1
D [01/May/2008:17:32:06 +1000] [Job 96] File(s) to be printed: 
D [01/May/2008:17:32:06 +1000] [Job 96] <STDIN>
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [01/May/2008:17:32:06 +1000] [Job 96] Pondering option 'PageSize=A4'
D [01/May/2008:17:32:06 +1000] [Job 96] Pondering option 'InputSlot=Default'
D [01/May/2008:17:32:06 +1000] [Job 96] Pondering option 'job-uuid=urn:uuid:2e360561-cd0c-39e9-4d74-8ec1187cdeb1'
D [01/May/2008:17:32:06 +1000] [Job 96] Unknown option job-uuid=urn:uuid:2e360561-cd0c-39e9-4d74-8ec1187cdeb1.
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] ================================================
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] File: <STDIN>
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] ================================================
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] Reading PostScript input ...
D [01/May/2008:17:32:06 +1000] [Job 96] --> This document is DSC-conforming!
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] -----------
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %%BeginProlog
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %%EndProlog
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] -----------
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %%BeginSetup
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %%BeginFeature: *PrintoutMode Normal.Gray
D [01/May/2008:17:32:06 +1000] [Job 96] Option: PrintoutMode=Normal.Gray --> Setting option
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal.Gray
D [01/May/2008:17:32:06 +1000] [Job 96] Option: PrintoutMode=Normal.Gray --> Setting option
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %%BeginFeature: *PrinterResolution FromPrintoutMode
D [01/May/2008:17:32:06 +1000] [Job 96] Option: PrinterResolution=FromPrintoutMode --> Setting option
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %% FoomaticRIPOptionSetting: PrinterResolution=FromPrintoutMode
D [01/May/2008:17:32:06 +1000] [Job 96] Option: PrinterResolution=FromPrintoutMode --> Setting option
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %%BeginFeature: *PageRegion A4
D [01/May/2008:17:32:06 +1000] [Job 96] Option: PageRegion=A4 --> Option will be set by PostScript interpreter
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %%BeginFeature: *InputSlot Default
D [01/May/2008:17:32:06 +1000] [Job 96] Option: InputSlot=Default --> Setting option
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %% FoomaticRIPOptionSetting: InputSlot=Default
D [01/May/2008:17:32:06 +1000] [Job 96] Option: InputSlot=Default --> Setting option
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %%BeginFeature: *ColorMode FromPrintoutMode
D [01/May/2008:17:32:06 +1000] [Job 96] Option: ColorMode=FromPrintoutMode --> Setting option
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %% FoomaticRIPOptionSetting: ColorMode=@PrintoutMode
D [01/May/2008:17:32:06 +1000] [Job 96] Option: ColorMode=FromPrintoutMode --> Setting option
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %%EndSetup
D [01/May/2008:17:32:06 +1000] [Job 96] Inserting PostScript code for CUPS' page accounting
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] -----------
D [01/May/2008:17:32:06 +1000] [Job 96] New page:  1 1
D [01/May/2008:17:32:06 +1000] [Job 96] Inserting option code into "PageSetup" section.
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %%BeginPageSetup
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %%EndPageSetup
D [01/May/2008:17:32:06 +1000] [Job 96] End of page header
D [01/May/2008:17:32:06 +1000] [Job 96] Stopping search for page header options
D [01/May/2008:17:32:06 +1000] [Job 96] Found: 2]."`,>\'hJjCW-4eM-iHZYinFU(I`n?ET'(*:6-o6;MX,X-^3B>J<k#V,r`5tCAdnK$7^3^;I3W>kg`
D [01/May/2008:17:32:06 +1000] [Job 96] --> Output goes directly to the renderer now.
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] Starting renderer
D [01/May/2008:17:32:06 +1000] [Job 96] JCL: %-12345X@PJL
D [01/May/2008:17:32:06 +1000] [Job 96] @PJL SET RESOLUTION=600
D [01/May/2008:17:32:06 +1000] [Job 96] <job data> 
D [01/May/2008:17:32:06 +1000] [Job 96] %-12345X@PJL RESET
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] renderer PID kid4=17986
D [01/May/2008:17:32:06 +1000] [Job 96] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dNOPAUSE -sDEVICE=pxlmono -r600x600 -sOutputFile=- - | perl -p -e 'if (! $did) { s/\xc0.\xf8\x26/\xc0\x01\xf8\x26/ && $did++; }'
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %%PageTrailer
D [01/May/2008:17:32:06 +1000] [Job 96] --> Continue DSC parsing now.
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %%Trailer
D [01/May/2008:17:32:06 +1000] [Job 96] --> Continue DSC parsing now.
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %%Pages: 1
D [01/May/2008:17:32:06 +1000] [Job 96] --> Continue DSC parsing now.
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %%BoundingBox: 18 36 577 806
D [01/May/2008:17:32:06 +1000] [Job 96] --> Continue DSC parsing now.
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] Found: %%EOF
D [01/May/2008:17:32:06 +1000] [Job 96] --> Continue DSC parsing now.
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] 
D [01/May/2008:17:32:06 +1000] [Job 96] Closing renderer
D [01/May/2008:17:32:06 +1000] cupsdAcceptClient: 11 from localhost (Domain)
D [01/May/2008:17:32:06 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:32:06 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:32:06 +1000] Get-Jobs ipp://localhost/jobs/
D [01/May/2008:17:32:06 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:32:06 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:32:06 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:32:06 +1000] CUPS-Get-Printers
D [01/May/2008:17:32:06 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:32:06 +1000] cupsdCloseClient: 11
D [01/May/2008:17:32:06 +1000] [Job 96] perl: warning: Setting locale failed.
D [01/May/2008:17:32:06 +1000] [Job 96] perl: warning: Please check that your locale settings:
D [01/May/2008:17:32:06 +1000] [Job 96] LANGUAGE = (unset),
D [01/May/2008:17:32:06 +1000] [Job 96] LC_ALL = (unset),
D [01/May/2008:17:32:06 +1000] [Job 96] LANG = "en_AU"
D [01/May/2008:17:32:06 +1000] [Job 96] are supported and installed on your system.
D [01/May/2008:17:32:06 +1000] [Job 96] perl: warning: Falling back to the standard locale ("C").
D [01/May/2008:17:32:06 +1000] [Job 96] perl: warning: Setting locale failed.
D [01/May/2008:17:32:06 +1000] [Job 96] perl: warning: Please check that your locale settings:
D [01/May/2008:17:32:06 +1000] [Job 96] LANGUAGE = (unset),
D [01/May/2008:17:32:06 +1000] [Job 96] LC_ALL = (unset),
D [01/May/2008:17:32:06 +1000] [Job 96] LANG = "en_AU"
D [01/May/2008:17:32:06 +1000] [Job 96] are supported and installed on your system.
D [01/May/2008:17:32:06 +1000] [Job 96] perl: warning: Falling back to the standard locale ("C").
D [01/May/2008:17:32:07 +1000] [Job 96] foomatic-gswrapper: gs '-sstdout=%stderr' '-dBATCH' '-dPARANOIDSAFER' '-dNOPAUSE' '-sDEVICE=pxlmono' '-r600x600' '-sOutputFile=%stdout' '-'
D [01/May/2008:17:32:08 +1000] [Job 96] GPL Ghostscript SVN PRE-RELEASE 8.61 (2007-08-02)
D [01/May/2008:17:32:08 +1000] [Job 96] Copyright (C) 2007 Artifex Software, Inc.  All rights reserved.
D [01/May/2008:17:32:08 +1000] [Job 96] This software comes with NO WARRANTY: see the file PUBLIC for details.
D [01/May/2008:17:32:08 +1000] Discarding unused job-progress event...
D [01/May/2008:17:32:08 +1000] Discarding unused printer-state-changed event...
D [01/May/2008:17:32:08 +1000] cupsdAcceptClient: 11 from localhost (Domain)
D [01/May/2008:17:32:08 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:32:08 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:32:08 +1000] Get-Jobs ipp://localhost/jobs/
D [01/May/2008:17:32:08 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:32:08 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:32:08 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:32:08 +1000] CUPS-Get-Printers
D [01/May/2008:17:32:08 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:32:08 +1000] cupsdCloseClient: 11
D [01/May/2008:17:32:08 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:08 +1000] Discarding unused job-progress event...
D [01/May/2008:17:32:08 +1000] Discarding unused printer-state-changed event...
D [01/May/2008:17:32:08 +1000] Discarding unused printer-state-changed event...
D [01/May/2008:17:32:08 +1000] Discarding unused printer-state-changed event...
D [01/May/2008:17:32:08 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:08 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:09 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:09 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:09 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:09 +1000] [Job 96] KID3 exited with status 0
D [01/May/2008:17:32:09 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:09 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:09 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:09 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:09 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:09 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:09 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:09 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:09 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:09 +1000] cupsdAcceptClient: 11 from localhost (Domain)
D [01/May/2008:17:32:09 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:32:09 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:32:09 +1000] Get-Jobs ipp://localhost/jobs/
D [01/May/2008:17:32:09 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:32:09 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:32:09 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:32:09 +1000] CUPS-Get-Printers
D [01/May/2008:17:32:09 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:32:09 +1000] cupsdCloseClient: 11
D [01/May/2008:17:32:10 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:10 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:10 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:10 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:10 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:10 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:10 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:10 +1000] [Job 96] tail process done writing data to STDOUT
D [01/May/2008:17:32:10 +1000] [Job 96] KID4 finished
D [01/May/2008:17:32:10 +1000] [Job 96] KID4 exited with status 0
D [01/May/2008:17:32:10 +1000] [Job 96] Renderer exit stat: 0
D [01/May/2008:17:32:10 +1000] [Job 96] KID3 finished
D [01/May/2008:17:32:10 +1000] [Job 96] Renderer process finished
D [01/May/2008:17:32:10 +1000] [Job 96] 
D [01/May/2008:17:32:10 +1000] [Job 96] Closing foomatic-rip.
D [01/May/2008:17:32:10 +1000] PID 17972 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [01/May/2008:17:32:10 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:11 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:11 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:11 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:11 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:12 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:12 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:12 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:12 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:12 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:12 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:13 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:13 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:13 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:13 +1000] [Job 96] Read 8192 bytes of print data...
D [01/May/2008:17:32:13 +1000] [Job 96] Wrote 8192 bytes of print data...
D [01/May/2008:17:32:13 +1000] [Job 96] Read 5367 bytes of print data...
D [01/May/2008:17:32:13 +1000] [Job 96] Wrote 5367 bytes of print data...
D [01/May/2008:17:32:13 +1000] PID 17973 (/usr/lib/cups/backend/parallel) exited with no errors.
D [01/May/2008:17:32:13 +1000] [Job 96] File 0 is complete.
I [01/May/2008:17:32:13 +1000] [Job 96] Completed successfully.
D [01/May/2008:17:32:13 +1000] Discarding unused printer-state-changed event...
D [01/May/2008:17:32:13 +1000] Discarding unused job-completed event...
D [01/May/2008:17:32:13 +1000] cupsdAcceptClient: 11 from localhost (Domain)
D [01/May/2008:17:32:13 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:32:13 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:32:13 +1000] Get-Jobs ipp://localhost/jobs/
D [01/May/2008:17:32:13 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:32:13 +1000] cupsdReadClient: 11 POST / HTTP/1.1
D [01/May/2008:17:32:13 +1000] cupsdAuthorize: No authentication data provided.
D [01/May/2008:17:32:13 +1000] CUPS-Get-Printers
D [01/May/2008:17:32:13 +1000] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [01/May/2008:17:32:13 +1000] cupsdCloseClient: 11
D [01/May/2008:17:32:14 +1000] [Job 96] Unloading...
D [01/May/2008:17:37:06 +1000] Closing client 8 after 300 seconds of inactivity...
D [01/May/2008:17:37:06 +1000] cupsdCloseClient: 8