D [28/Nov/2008:02:41:11 -0500] cupsdAcceptClient: 8 from localhost (Domain)
D [28/Nov/2008:02:41:11 -0500] cupsdReadClient: 8 POST /admin/ HTTP/1.1
D [28/Nov/2008:02:41:11 -0500] cupsdAuthorize: No authentication data provided.
D [28/Nov/2008:02:41:11 -0500] Resume-Printer ipp://localhost/printers/HL-5140
D [28/Nov/2008:02:41:11 -0500] cupsdIsAuthorized: username=""
E [28/Nov/2008:02:41:11 -0500] Resume-Printer: Unauthorized
D [28/Nov/2008:02:41:11 -0500] cupsdSendError: 8 code=401 (Unauthorized)
D [28/Nov/2008:02:41:11 -0500] cupsdSendHeader: WWW-Authenticate: Basic realm="CUPS"
D [28/Nov/2008:02:41:11 -0500] cupsdCloseClient: 8
D [28/Nov/2008:02:41:11 -0500] cupsdAcceptClient: 8 from localhost (Domain)
D [28/Nov/2008:02:41:11 -0500] cupsdReadClient: 8 POST /admin/ HTTP/1.1
D [28/Nov/2008:02:41:11 -0500] cupsdAuthorize: Authorized as root using Local
D [28/Nov/2008:02:41:11 -0500] Resume-Printer ipp://localhost/printers/HL-5140
D [28/Nov/2008:02:41:11 -0500] cupsdIsAuthorized: username="root"
I [28/Nov/2008:02:41:11 -0500] Printer "HL-5140" started by "root".
D [28/Nov/2008:02:41:11 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [28/Nov/2008:02:41:11 -0500] cupsdCloseClient: 8
D [28/Nov/2008:02:41:20 -0500] cupsdAcceptClient: 8 from localhost (Domain)
D [28/Nov/2008:02:41:20 -0500] Report: clients=1
D [28/Nov/2008:02:41:20 -0500] Report: jobs=0
D [28/Nov/2008:02:41:20 -0500] Report: jobs-active=0
D [28/Nov/2008:02:41:20 -0500] Report: printers=1
D [28/Nov/2008:02:41:20 -0500] Report: printers-implicit=0
D [28/Nov/2008:02:41:20 -0500] Report: stringpool-string-count=2154
D [28/Nov/2008:02:41:20 -0500] Report: stringpool-alloc-bytes=11640
D [28/Nov/2008:02:41:20 -0500] Report: stringpool-total-bytes=44840
D [28/Nov/2008:02:41:20 -0500] cupsdReadClient: 8 POST / HTTP/1.1
D [28/Nov/2008:02:41:20 -0500] cupsdAuthorize: No authentication data provided.
D [28/Nov/2008:02:41:20 -0500] CUPS-Get-Printers
D [28/Nov/2008:02:41:20 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [28/Nov/2008:02:41:20 -0500] cupsdReadClient: 8 POST / HTTP/1.1
D [28/Nov/2008:02:41:20 -0500] cupsdAuthorize: No authentication data provided.
D [28/Nov/2008:02:41:20 -0500] CUPS-Get-Classes
D [28/Nov/2008:02:41:20 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [28/Nov/2008:02:41:20 -0500] cupsdReadClient: 8 POST / HTTP/1.1
D [28/Nov/2008:02:41:20 -0500] cupsdAuthorize: No authentication data provided.
D [28/Nov/2008:02:41:20 -0500] CUPS-Get-Default
D [28/Nov/2008:02:41:20 -0500] CUPS-Get-Default client-error-not-found: No default printer
D [28/Nov/2008:02:41:20 -0500] cupsdProcessIPPRequest: 8 status_code=406 (client-error-not-found)
D [28/Nov/2008:02:41:20 -0500] cupsdCloseClient: 8
D [28/Nov/2008:02:41:20 -0500] cupsdAcceptClient: 8 from localhost (Domain)
D [28/Nov/2008:02:41:20 -0500] cupsdReadClient: 8 POST /printers/HL-5140 HTTP/1.1
D [28/Nov/2008:02:41:20 -0500] cupsdAuthorize: No authentication data provided.
D [28/Nov/2008:02:41:20 -0500] Print-Job ipp://localhost/printers/HL-5140
D [28/Nov/2008:02:41:20 -0500] [Job ???] Auto-typing file...
I [28/Nov/2008:02:41:20 -0500] [Job ???] Request file type is application/pdf.
D [28/Nov/2008:02:41:20 -0500] add_job: requesting-user-name="msurface"
I [28/Nov/2008:02:41:20 -0500] [Job 196] Adding start banner page "none".
I [28/Nov/2008:02:41:20 -0500] Saving subscriptions.conf...
I [28/Nov/2008:02:41:20 -0500] [Job 196] Adding end banner page "none".
I [28/Nov/2008:02:41:20 -0500] [Job 196] File of type application/pdf queued by "msurface".
D [28/Nov/2008:02:41:20 -0500] [Job 196] hold_until=0
I [28/Nov/2008:02:41:20 -0500] [Job 196] Queued on "HL-5140" by "msurface".
I [28/Nov/2008:02:41:20 -0500] Saving subscriptions.conf...
D [28/Nov/2008:02:41:20 -0500] [Job 196] job-sheets=none,none
D [28/Nov/2008:02:41:20 -0500] [Job 196] banner_page = 0
D [28/Nov/2008:02:41:20 -0500] [Job 196] argv[0]="HL-5140"
D [28/Nov/2008:02:41:20 -0500] [Job 196] argv[1]="196"
D [28/Nov/2008:02:41:20 -0500] [Job 196] argv[2]="msurface"
D [28/Nov/2008:02:41:20 -0500] [Job 196] argv[3]="strengths.pdf"
D [28/Nov/2008:02:41:20 -0500] [Job 196] argv[4]="1"
D [28/Nov/2008:02:41:20 -0500] [Job 196] argv[5]="media=A4 finishings=3 number-up=1 PageRegion=Letter HalftoningAlgorithm=Accurate PageSize=Letter job-uuid=urn:uuid:f48b71a5-c90b-32e0-5c6f-ea556d6d2a5b"
D [28/Nov/2008:02:41:20 -0500] [Job 196] argv[6]="/var/spool/cups/d00196-001"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[9]="SERVER_ADMIN=root@localhost.localdomain"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[10]="SOFTWARE=CUPS/1.3.9"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[12]="USER=root"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[13]="CUPS_SERVER=/var/run/cups/cups.sock"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[14]="CUPS_ENCRYPTION=IfRequested"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[15]="IPP_PORT=631"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[16]="CHARSET=utf-8"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[17]="LANG=en_US.UTF8"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[18]="PPD=/etc/cups/ppd/HL-5140.ppd"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[19]="RIP_MAX_CACHE=8m"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[20]="CONTENT_TYPE=application/pdf"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[21]="DEVICE_URI=usb://Brother/HL-5140%20series"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[22]="PRINTER=HL-5140"
D [28/Nov/2008:02:41:20 -0500] [Job 196] envp[23]="FINAL_CONTENT_TYPE=printer/HL-5140"
I [28/Nov/2008:02:41:20 -0500] [Job 196] Started filter /usr/lib/cups/filter/pdftopdf (PID 14338)
I [28/Nov/2008:02:41:20 -0500] [Job 196] Started filter /usr/lib/cups/filter/cpdftocps (PID 14339)
I [28/Nov/2008:02:41:20 -0500] [Job 196] Started filter /usr/lib/cups/filter/foomatic-rip (PID 14340)
I [28/Nov/2008:02:41:20 -0500] [Job 196] Started backend /usr/lib/cups/backend/usb (PID 14341)
I [28/Nov/2008:02:41:20 -0500] Saving subscriptions.conf...
D [28/Nov/2008:02:41:20 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [28/Nov/2008:02:41:20 -0500] cupsdCloseClient: 8
I [28/Nov/2008:02:41:20 -0500] Saving subscriptions.conf...
D [28/Nov/2008:02:41:20 -0500] [Job 196] Printer using device file "/dev/usblp0"...
D [28/Nov/2008:02:41:20 -0500] [Job 196] backendRunLoop(print_fd=0, device_fd=5, use_bc=0, side_cb=0xb800aa80)
I [28/Nov/2008:02:41:20 -0500] Saving subscriptions.conf...
D [28/Nov/2008:02:41:20 -0500] [Job 196] pdftops - copying to temp print file "/tmp/492fa0a0c0a10"
D [28/Nov/2008:02:41:20 -0500] cupsdAcceptClient: 8 from localhost (Domain)
D [28/Nov/2008:02:41:20 -0500] cupsdReadClient: 8 POST / HTTP/1.1
D [28/Nov/2008:02:41:20 -0500] cupsdAuthorize: No authentication data provided.
D [28/Nov/2008:02:41:20 -0500] Get-Notifications /
D [28/Nov/2008:02:41:20 -0500] cupsdIsAuthorized: requesting-user-name="msurface"
D [28/Nov/2008:02:41:20 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [28/Nov/2008:02:41:22 -0500] cupsdReadClient: 8 POST / HTTP/1.1
D [28/Nov/2008:02:41:22 -0500] cupsdAuthorize: No authentication data provided.
D [28/Nov/2008:02:41:22 -0500] Get-Job-Attributes ipp://localhost/jobs/196
D [28/Nov/2008:02:41:22 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [28/Nov/2008:02:41:23 -0500] cupsdAcceptClient: 10 from localhost (Domain)
D [28/Nov/2008:02:41:23 -0500] cupsdReadClient: 10 POST / HTTP/1.1
D [28/Nov/2008:02:41:27 -0500] cupsdAuthorize: No authentication data provided.
D [28/Nov/2008:02:41:27 -0500] Get-Printer-Attributes ipp://localhost.localdomain:631/printers/HL-5140
D [28/Nov/2008:02:41:27 -0500] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [28/Nov/2008:02:41:27 -0500] PID 14338 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [28/Nov/2008:02:41:27 -0500] [Job 196] Getting input from file 
D [28/Nov/2008:02:41:27 -0500] [Job 196] foomatic-rip version 4.0.0.177 running...
D [28/Nov/2008:02:41:27 -0500] [Job 196] Parsing PPD file ...
D [28/Nov/2008:02:41:27 -0500] [Job 196] Added option PageSize
D [28/Nov/2008:02:41:27 -0500] [Job 196] Added option ImageableArea
D [28/Nov/2008:02:41:27 -0500] [Job 196] Added option PaperDimension
D [28/Nov/2008:02:41:27 -0500] [Job 196] Added option InputSlot
D [28/Nov/2008:02:41:27 -0500] [Job 196] Added option MediaType
D [28/Nov/2008:02:41:27 -0500] [Job 196] Added option Resolution
D [28/Nov/2008:02:41:27 -0500] [Job 196] Added option EconoMode
D [28/Nov/2008:02:41:27 -0500] [Job 196] Added option HalftoningAlgorithm
D [28/Nov/2008:02:41:27 -0500] [Job 196] Added option Font
D [28/Nov/2008:02:41:27 -0500] [Job 196] Added option Margins
D [28/Nov/2008:02:41:27 -0500] [Job 196] 
D [28/Nov/2008:02:41:27 -0500] [Job 196] Parameter Summary
D [28/Nov/2008:02:41:27 -0500] [Job 196] -----------------
D [28/Nov/2008:02:41:27 -0500] [Job 196] 
D [28/Nov/2008:02:41:27 -0500] [Job 196] Spooler: cups
D [28/Nov/2008:02:41:27 -0500] [Job 196] Printer: HL-5140
D [28/Nov/2008:02:41:27 -0500] [Job 196] Shell: /bin/bash
D [28/Nov/2008:02:41:27 -0500] [Job 196] PPD file: /etc/cups/ppd/HL-5140.ppd
D [28/Nov/2008:02:41:27 -0500] [Job 196] ATTR file: 
D [28/Nov/2008:02:41:27 -0500] [Job 196] Printer model: Brother HL-5140 Foomatic/hl1250 (recommended)
D [28/Nov/2008:02:41:27 -0500] [Job 196] Job title: strengths.pdf
D [28/Nov/2008:02:41:27 -0500] [Job 196] File(s) to be printed:
D [28/Nov/2008:02:41:27 -0500] [Job 196] <STDIN>
D [28/Nov/2008:02:41:27 -0500] [Job 196] 
D [28/Nov/2008:02:41:27 -0500] [Job 196] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [28/Nov/2008:02:41:27 -0500] [Job 196] Pondering option 'media=A4'
D [28/Nov/2008:02:41:27 -0500] [Job 196] Pondering option 'finishings=3'
D [28/Nov/2008:02:41:27 -0500] [Job 196] Unknown option finishings=3.
D [28/Nov/2008:02:41:27 -0500] [Job 196] Pondering option 'number-up=1'
D [28/Nov/2008:02:41:27 -0500] [Job 196] Unknown option number-up=1.
D [28/Nov/2008:02:41:27 -0500] [Job 196] Pondering option 'PageRegion=Letter'
D [28/Nov/2008:02:41:27 -0500] [Job 196] Pondering option 'HalftoningAlgorithm=Accurate'
D [28/Nov/2008:02:41:27 -0500] [Job 196] Pondering option 'PageSize=Letter'
D [28/Nov/2008:02:41:27 -0500] [Job 196] Pondering option 'job-uuid=urn:uuid:f48b71a5-c90b-32e0-5c6f-ea556d6d2a5b'
D [28/Nov/2008:02:41:27 -0500] [Job 196] Unknown option job-uuid=urn:uuid:f48b71a5-c90b-32e0-5c6f-ea556d6d2a5b.
D [28/Nov/2008:02:41:27 -0500] [Job 196] 
D [28/Nov/2008:02:41:27 -0500] [Job 196] ================================================
D [28/Nov/2008:02:41:27 -0500] [Job 196] 
D [28/Nov/2008:02:41:27 -0500] [Job 196] File: <STDIN>
D [28/Nov/2008:02:41:27 -0500] [Job 196] 
D [28/Nov/2008:02:41:27 -0500] [Job 196] ================================================
D [28/Nov/2008:02:41:27 -0500] [Job 196] 
D [28/Nov/2008:02:41:28 -0500] PID 14339 (/usr/lib/cups/filter/cpdftocps) exited with no errors.
E [28/Nov/2008:02:41:28 -0500] PID 14340 (/usr/lib/cups/filter/foomatic-rip) crashed on signal 11!
D [28/Nov/2008:02:41:28 -0500] PID 14341 (/usr/lib/cups/backend/usb) exited with no errors.
D [28/Nov/2008:02:41:28 -0500] cupsdCloseClient: 10
D [28/Nov/2008:02:41:28 -0500] cupsdCloseClient: 8
D [28/Nov/2008:02:41:28 -0500] [Job 196] Page = 612x792; 18,12 to 594,780
D [28/Nov/2008:02:41:28 -0500] [Job 196] slow_collate=0, slow_duplex=0, slow_order=0
D [28/Nov/2008:02:41:28 -0500] [Job 196] Before copy_comments - %!PS-Adobe-3.0
D [28/Nov/2008:02:41:28 -0500] [Job 196] %!PS-Adobe-3.0
D [28/Nov/2008:02:41:28 -0500] [Job 196] % Produced by xpdf/pdftops 3.02
D [28/Nov/2008:02:41:28 -0500] [Job 196] %%Title: strengths.pdf
D [28/Nov/2008:02:41:28 -0500] [Job 196] %%LanguageLevel: 3
D [28/Nov/2008:02:41:28 -0500] [Job 196] %%DocumentSuppliedResources: (atend)
D [28/Nov/2008:02:41:28 -0500] [Job 196] %%DocumentMedia: plain 612 792 0 () ()
D [28/Nov/2008:02:41:28 -0500] [Job 196] %%BoundingBox: 0 0 612 792
D [28/Nov/2008:02:41:28 -0500] [Job 196] %%Pages: 3
D [28/Nov/2008:02:41:28 -0500] [Job 196] %%EndComments
D [28/Nov/2008:02:41:28 -0500] [Job 196] Before copy_prolog - %%BeginDefaults
D [28/Nov/2008:02:41:28 -0500] [Job 196] Filetype: PostScript
D [28/Nov/2008:02:41:28 -0500] [Job 196] Reading PostScript input ...
D [28/Nov/2008:02:41:28 -0500] [Job 196] --> This document is DSC-conforming!
D [28/Nov/2008:02:41:28 -0500] [Job 196] Found %RBINumCopies: 1
D [28/Nov/2008:02:41:28 -0500] [Job 196] Job claims to be DSC-conforming, but "%%BeginProlog" was missing before first line with another"%%BeginProlog" comment (is this a TeX/LaTeX/dvips-generated PostScript file?). Assuming start of "Prolog" here.
D [28/Nov/2008:02:41:28 -0500] [Job 196] 
D [28/Nov/2008:02:41:28 -0500] [Job 196] -----------
D [28/Nov/2008:02:41:28 -0500] [Job 196] Found: %%BeginProlog
D [28/Nov/2008:02:41:28 -0500] [Job 196] Before copy_setup - %%BeginSetup
D [28/Nov/2008:02:41:28 -0500] [Job 196] Found: %%EndProlog
D [28/Nov/2008:02:41:28 -0500] [Job 196] 
D [28/Nov/2008:02:41:28 -0500] [Job 196] -----------
D [28/Nov/2008:02:41:28 -0500] [Job 196] Found: %%BeginSetup
D [28/Nov/2008:02:41:28 -0500] [Job 196] Found: %%BeginFeature: *Margins Custom
D [28/Nov/2008:02:41:28 -0500] [Job 196] Option: Margins=Custom
D [28/Nov/2008:02:41:28 -0500] [Job 196] Before page loop - %%Page: 1 1
D [28/Nov/2008:02:41:28 -0500] [Job 196] Copying page 1...
D [28/Nov/2008:02:41:28 -0500] [Job 196] pagew = 576.0, pagel = 768.2
D [28/Nov/2008:02:41:28 -0500] [Job 196] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [28/Nov/2008:02:41:28 -0500] [Job 196] PageLeft = 18.0, PageRight = 594.0
D [28/Nov/2008:02:41:28 -0500] [Job 196] PageTop = 780.1, PageBottom = 11.9
D [28/Nov/2008:02:41:28 -0500] [Job 196] PageWidth = 612.0, PageLength = 792.0
D [28/Nov/2008:02:41:28 -0500] [Job 196] Copying page 2...
D [28/Nov/2008:02:41:28 -0500] [Job 196] pagew = 576.0, pagel = 768.2
D [28/Nov/2008:02:41:28 -0500] [Job 196] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [28/Nov/2008:02:41:28 -0500] [Job 196] PageLeft = 18.0, PageRight = 594.0
D [28/Nov/2008:02:41:28 -0500] [Job 196] PageTop = 780.1, PageBottom = 11.9
D [28/Nov/2008:02:41:28 -0500] [Job 196] PageWidth = 612.0, PageLength = 792.0
D [28/Nov/2008:02:41:28 -0500] [Job 196] Copying page 3...
D [28/Nov/2008:02:41:28 -0500] [Job 196] pagew = 576.0, pagel = 768.2
D [28/Nov/2008:02:41:28 -0500] [Job 196] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [28/Nov/2008:02:41:28 -0500] [Job 196] PageLeft = 18.0, PageRight = 594.0
D [28/Nov/2008:02:41:28 -0500] [Job 196] PageTop = 780.1, PageBottom = 11.9
D [28/Nov/2008:02:41:28 -0500] [Job 196] PageWidth = 612.0, PageLength = 792.0
D [28/Nov/2008:02:41:28 -0500] [Job 196] Wrote 3 pages...
D [28/Nov/2008:02:41:28 -0500] [Job 196] File 0 is complete.
E [28/Nov/2008:02:41:28 -0500] [Job 196] Job stopped due to filter errors.
I [28/Nov/2008:02:41:28 -0500] Saving subscriptions.conf...
I [28/Nov/2008:02:41:28 -0500] Saving subscriptions.conf...
D [28/Nov/2008:02:41:28 -0500] cupsdAcceptClient: 8 from localhost (Domain)
D [28/Nov/2008:02:41:28 -0500] cupsdReadClient: 8 POST / HTTP/1.1
D [28/Nov/2008:02:41:28 -0500] cupsdAuthorize: No authentication data provided.
D [28/Nov/2008:02:41:28 -0500] Get-Notifications /
D [28/Nov/2008:02:41:28 -0500] cupsdIsAuthorized: requesting-user-name="msurface"
D [28/Nov/2008:02:41:28 -0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [28/Nov/2008:02:41:29 -0500] [Job 196] Unloading...
D [28/Nov/2008:02:41:29 -0500] cupsdCloseClient: 8