I [14/Mar/2011:22:14:37 -0400] Listening to ::1:631 (IPv6)
I [14/Mar/2011:22:14:37 -0400] Listening to 127.0.0.1:631 (IPv4)
I [14/Mar/2011:22:14:37 -0400] Listening to /var/run/cups/cups.sock (Domain)
I [14/Mar/2011:22:14:37 -0400] Remote access is disabled.
D [14/Mar/2011:22:14:37 -0400] Added auto ServerAlias gruber-Satellite-L355D
I [14/Mar/2011:22:14:37 -0400] Loaded configuration file "/etc/cups/cupsd.conf"
I [14/Mar/2011:22:14:37 -0400] Using default TempDir of /var/spool/cups/tmp...
I [14/Mar/2011:22:14:37 -0400] Configured for up to 100 clients.
I [14/Mar/2011:22:14:37 -0400] Allowing up to 100 client connections per host.
I [14/Mar/2011:22:14:37 -0400] Using policy "default" as the default!
I [14/Mar/2011:22:14:37 -0400] Full reload is required.
I [14/Mar/2011:22:14:37 -0400] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 37 types, 76 filters...
D [14/Mar/2011:22:14:37 -0400] Loading printer printer...
D [14/Mar/2011:22:14:37 -0400] load_ppd: Loading /var/cache/cups/printer.ipp4...
D [14/Mar/2011:22:14:37 -0400] cupsdRegisterPrinter(p=0x215423a0(printer))
D [14/Mar/2011:22:14:37 -0400] cupsdLoadRemoteCache: Not loading remote cache.
I [14/Mar/2011:22:14:37 -0400] Loading job cache file "/var/cache/cups/job.cache"...
D [14/Mar/2011:22:14:37 -0400] [Job 15] Loading from cache...
D [14/Mar/2011:22:14:37 -0400] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
I [14/Mar/2011:22:14:37 -0400] Full reload complete.
D [14/Mar/2011:22:14:37 -0400] cupsd_clean_files(path="/var/spool/cups/tmp", pattern="(null)")
I [14/Mar/2011:22:14:37 -0400] Cleaning out old files in "/var/spool/cups/tmp"...
D [14/Mar/2011:22:14:37 -0400] cupsd_clean_files(path="/var/cache/cups", pattern="*.ipp")
I [14/Mar/2011:22:14:37 -0400] Cleaning out old files in "/var/cache/cups"...
I [14/Mar/2011:22:14:37 -0400] Listening to ::1:631 on fd 6...
I [14/Mar/2011:22:14:37 -0400] Listening to 127.0.0.1:631 on fd 7...
I [14/Mar/2011:22:14:37 -0400] Listening to /var/run/cups/cups.sock on fd 8...
I [14/Mar/2011:22:14:37 -0400] Resuming new connection processing...
D [14/Mar/2011:22:14:37 -0400] Discarding unused server-started event...
D [14/Mar/2011:22:14:38 -0400] Report: clients=0
D [14/Mar/2011:22:14:38 -0400] Report: jobs=1
D [14/Mar/2011:22:14:38 -0400] Report: jobs-active=0
D [14/Mar/2011:22:14:38 -0400] Report: printers=1
D [14/Mar/2011:22:14:38 -0400] Report: printers-implicit=0
D [14/Mar/2011:22:14:38 -0400] Report: stringpool-string-count=401
D [14/Mar/2011:22:14:38 -0400] Report: stringpool-alloc-bytes=7216
D [14/Mar/2011:22:14:38 -0400] Report: stringpool-total-bytes=8944
D [14/Mar/2011:22:14:43 -0400] cupsdAcceptClient: 11 from localhost:631 (IPv4)
D [14/Mar/2011:22:14:43 -0400] cupsdReadClient: 11 GET /printers/ HTTP/1.1
D [14/Mar/2011:22:14:43 -0400] cupsdSetBusyState: Active clients
D [14/Mar/2011:22:14:43 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:14:43 -0400] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[10] = "SERVER_ADMIN=root@gruber-Satellite-L355D"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.6"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[13] = "USER=root"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[16] = "IPP_PORT=631"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[17] = "LANG=en_US.UTF8"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[18] = "REDIRECT_STATUS=1"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[20] = "SERVER_NAME=localhost"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[21] = "SERVER_PORT=631"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[23] = "REMOTE_HOST=localhost"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[24] = "SCRIPT_NAME=/printers/"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/printers/"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[26] = "SERVER_PROTOCOL=HTTP/1.1"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[27] = "HTTP_COOKIE=org.cups.sid=b394d3660e227b0ac1fe89f341596cb5"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; en-US) AppleWebKit/534.16 (KHTML, like Gecko) Ubuntu/11.04 Chromium/10.0.648.133 Chrome/10.0.648.133 Safari/534.16"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[29] = "HTTP_REFERER=http://localhost:631/"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[30] = "REQUEST_METHOD=GET"
D [14/Mar/2011:22:14:43 -0400] [CGI] envp[31] = "QUERY_STRING="
D [14/Mar/2011:22:14:43 -0400] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 6785)
I [14/Mar/2011:22:14:43 -0400] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=6785)
D [14/Mar/2011:22:14:43 -0400] cupsdSendCommand: 11 file=12
D [14/Mar/2011:22:14:43 -0400] [CGI] org.cups.sid cookie is "b394d3660e227b0ac1fe89f341596cb5"
D [14/Mar/2011:22:14:43 -0400] cupsdAcceptClient: 13 from localhost (Domain)
D [14/Mar/2011:22:14:43 -0400] cupsdReadClient: 13 POST / HTTP/1.1
D [14/Mar/2011:22:14:43 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:14:43 -0400] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
D [14/Mar/2011:22:14:43 -0400] CUPS-Get-Default
D [14/Mar/2011:22:14:43 -0400] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [14/Mar/2011:22:14:43 -0400] [CGI] show_all_printers(http=0x21f75950, user="(null)")
D [14/Mar/2011:22:14:43 -0400] Script header: Content-Type: text/html;charset=utf-8
D [14/Mar/2011:22:14:43 -0400] Script header: 
D [14/Mar/2011:22:14:43 -0400] cupsdReadClient: 13 POST / HTTP/1.1
D [14/Mar/2011:22:14:43 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:14:43 -0400] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [14/Mar/2011:22:14:43 -0400] CUPS-Get-Printers
D [14/Mar/2011:22:14:43 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [14/Mar/2011:22:14:43 -0400] cupsdReadClient: 13 WAITING Closing on EOF
D [14/Mar/2011:22:14:43 -0400] cupsdCloseClient: 13
D [14/Mar/2011:22:14:43 -0400] cupsdSetBusyState: Not busy
D [14/Mar/2011:22:14:43 -0400] PID 6785 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [14/Mar/2011:22:14:43 -0400] cupsdReadClient: 11 GET /cups.css HTTP/1.1
D [14/Mar/2011:22:14:43 -0400] cupsdSetBusyState: Active clients
D [14/Mar/2011:22:14:43 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:14:43 -0400] cupsdSetBusyState: Not busy
D [14/Mar/2011:22:14:43 -0400] cupsdReadClient: 11 GET /images/left.gif HTTP/1.1
D [14/Mar/2011:22:14:43 -0400] cupsdSetBusyState: Active clients
D [14/Mar/2011:22:14:43 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:14:43 -0400] cupsdSetBusyState: Not busy
D [14/Mar/2011:22:14:43 -0400] cupsdReadClient: 11 GET /images/right.gif HTTP/1.1
D [14/Mar/2011:22:14:43 -0400] cupsdSetBusyState: Active clients
D [14/Mar/2011:22:14:43 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:14:43 -0400] cupsdSetBusyState: Not busy
D [14/Mar/2011:22:14:43 -0400] cupsdReadClient: 11 GET /images/unsel.gif HTTP/1.1
D [14/Mar/2011:22:14:43 -0400] cupsdSetBusyState: Active clients
D [14/Mar/2011:22:14:43 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:14:43 -0400] cupsdSetBusyState: Not busy
D [14/Mar/2011:22:14:43 -0400] cupsdAcceptClient: 12 from localhost:631 (IPv4)
D [14/Mar/2011:22:14:43 -0400] cupsdReadClient: 11 GET /images/sel.gif HTTP/1.1
D [14/Mar/2011:22:14:43 -0400] cupsdSetBusyState: Active clients
D [14/Mar/2011:22:14:43 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:14:43 -0400] cupsdSetBusyState: Not busy
D [14/Mar/2011:22:15:37 -0400] cupsdAcceptClient: 13 from localhost (Domain)
D [14/Mar/2011:22:15:37 -0400] Avahi client started
D [14/Mar/2011:22:15:37 -0400] cupsdReadClient: 13 POST / HTTP/1.1
D [14/Mar/2011:22:15:37 -0400] cupsdSetBusyState: Active clients
D [14/Mar/2011:22:15:37 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:15:37 -0400] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
D [14/Mar/2011:22:15:37 -0400] CUPS-Get-Default
D [14/Mar/2011:22:15:37 -0400] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [14/Mar/2011:22:15:37 -0400] cupsdSetBusyState: Not busy
D [14/Mar/2011:22:15:37 -0400] cupsdReadClient: 13 POST /printers/printer HTTP/1.1
D [14/Mar/2011:22:15:37 -0400] cupsdSetBusyState: Active clients
D [14/Mar/2011:22:15:37 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:15:37 -0400] cupsdReadClient: 13 1.1 Create-Job 1
D [14/Mar/2011:22:15:37 -0400] Create-Job ipp://localhost:631/printers/printer
D [14/Mar/2011:22:15:37 -0400] cupsdMarkDirty(----J-)
D [14/Mar/2011:22:15:37 -0400] cupsdSetBusyState: Active clients and dirty files
D [14/Mar/2011:22:15:37 -0400] add_job: requesting-user-name="gruber"
D [14/Mar/2011:22:15:37 -0400] cupsdMarkDirty(-----S)
I [14/Mar/2011:22:15:37 -0400] [Job 16] Queued on "printer" by "gruber".
D [14/Mar/2011:22:15:37 -0400] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/printer) from localhost
D [14/Mar/2011:22:15:37 -0400] cupsdSetBusyState: Dirty files
D [14/Mar/2011:22:15:37 -0400] cupsdReadClient: 13 POST /printers/printer HTTP/1.1
D [14/Mar/2011:22:15:37 -0400] cupsdSetBusyState: Active clients and dirty files
D [14/Mar/2011:22:15:37 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:15:37 -0400] cupsdReadClient: 13 1.1 Send-Document 1
D [14/Mar/2011:22:15:37 -0400] Send-Document ipp://localhost:631/printers/printer
D [14/Mar/2011:22:15:37 -0400] cupsdIsAuthorized: requesting-user-name="gruber"
D [14/Mar/2011:22:15:37 -0400] [Job 16] Auto-typing file...
D [14/Mar/2011:22:15:37 -0400] [Job 16] Request file type is text/plain.
D [14/Mar/2011:22:15:37 -0400] cupsdMarkDirty(----J-)
I [14/Mar/2011:22:15:37 -0400] [Job 16] File of type text/plain queued by "gruber".
D [14/Mar/2011:22:15:37 -0400] cupsdMarkDirty(----J-)
D [14/Mar/2011:22:15:37 -0400] cupsdMarkDirty(----J-)
D [14/Mar/2011:22:15:37 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [14/Mar/2011:22:15:37 -0400] cupsdMarkDirty(-----S)
D [14/Mar/2011:22:15:37 -0400] [Job 16] No job-sheets attribute.
D [14/Mar/2011:22:15:37 -0400] [Job 16] ... but someone added one without setting job_sheets!
D [14/Mar/2011:22:15:37 -0400] [Job 16] argv[0]="printer"
D [14/Mar/2011:22:15:37 -0400] [Job 16] argv[1]="16"
D [14/Mar/2011:22:15:37 -0400] [Job 16] argv[2]="gruber"
D [14/Mar/2011:22:15:37 -0400] [Job 16] argv[3]="control"
D [14/Mar/2011:22:15:37 -0400] [Job 16] argv[4]="1"
D [14/Mar/2011:22:15:37 -0400] [Job 16] argv[5]="job-originating-user-name=gruber finishings=3 job-priority=50 job-sheets=none,none number-up=1 outputorder=reverse job-uuid=urn:uuid:141caaae-5720-3b4e-5af9-4048a8303777 job-originating-host-name=localhost time-at-creation=1300155337 time-at-processing=1300155337 job-id=16 job-state=5 job-media-sheets-completed=0 job-k-octets=1 AP_D_InputSlot="
D [14/Mar/2011:22:15:37 -0400] [Job 16] argv[6]="/var/spool/cups/d00016-001"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[8]="HOME=/var/spool/cups/tmp"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[10]="SERVER_ADMIN=root@gruber-Satellite-L355D"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[11]="SOFTWARE=CUPS/1.4.6"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[13]="USER=root"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[16]="IPP_PORT=631"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[17]="CHARSET=utf-8"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[18]="LANG=en_US.UTF-8"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[19]="PPD=/etc/cups/ppd/printer.ppd"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[20]="RIP_MAX_CACHE=auto"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[21]="CONTENT_TYPE=text/plain"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[22]="DEVICE_URI=ipp://192.168.1.6/printers/S600"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[23]="PRINTER_INFO=S600"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[24]="PRINTER_LOCATION="
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[25]="PRINTER=printer"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[26]="CUPS_FILETYPE=document"
D [14/Mar/2011:22:15:37 -0400] [Job 16] envp[27]="FINAL_CONTENT_TYPE=printer/printer"
I [14/Mar/2011:22:15:37 -0400] [Job 16] Started filter /usr/lib/cups/filter/texttopdf (PID 6788)
I [14/Mar/2011:22:15:37 -0400] [Job 16] Started filter /usr/lib/cups/filter/pdftopdf (PID 6789)
I [14/Mar/2011:22:15:37 -0400] [Job 16] Started filter /usr/lib/cups/filter/foomatic-rip (PID 6790)
I [14/Mar/2011:22:15:37 -0400] [Job 16] Started backend /usr/lib/cups/backend/ipp (PID 6791)
D [14/Mar/2011:22:15:37 -0400] cupsdMarkDirty(-----S)
D [14/Mar/2011:22:15:37 -0400] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/printer) from localhost
D [14/Mar/2011:22:15:37 -0400] cupsdSetBusyState: Printing jobs and dirty files
D [14/Mar/2011:22:15:37 -0400] cupsdReadClient: 13 WAITING Closing on EOF
D [14/Mar/2011:22:15:37 -0400] cupsdCloseClient: 13
D [14/Mar/2011:22:15:37 -0400] [Job 16] Page = 612x792; 18,36 to 594,756
D [14/Mar/2011:22:15:37 -0400] PID 6788 (/usr/lib/cups/filter/texttopdf) exited with no errors.
D [14/Mar/2011:22:15:37 -0400] [Job 16] Getting input from file 
D [14/Mar/2011:22:15:37 -0400] [Job 16] foomatic-rip version 4.0.7.235 running...
D [14/Mar/2011:22:15:37 -0400] [Job 16] Parsing PPD file ...
D [14/Mar/2011:22:15:37 -0400] [Job 16] Added option Resolution
D [14/Mar/2011:22:15:37 -0400] [Job 16] Added option PageSize
D [14/Mar/2011:22:15:37 -0400] [Job 16] STATE: +connecting-to-device
D [14/Mar/2011:22:15:37 -0400] cupsdMarkDirty(-----S)
D [14/Mar/2011:22:15:37 -0400] [Job 16] Looking up "192.168.1.6"...
I [14/Mar/2011:22:15:37 -0400] [Job 16] Copying print data...
D [14/Mar/2011:22:15:37 -0400] [Job 16] backendRunLoop(print_fd=-1, device_fd=6, snmp_fd=5, addr=0x20d98064, use_bc=0, side_cb=0xbc3140)
D [14/Mar/2011:22:15:37 -0400] [Job 16] Added option ImageableArea
D [14/Mar/2011:22:15:37 -0400] [Job 16] Added option PaperDimension
D [14/Mar/2011:22:15:37 -0400] [Job 16] Added option Quality
D [14/Mar/2011:22:15:37 -0400] [Job 16] Added option Font
D [14/Mar/2011:22:15:37 -0400] [Job 16] 
D [14/Mar/2011:22:15:37 -0400] [Job 16] Parameter Summary
D [14/Mar/2011:22:15:37 -0400] [Job 16] -----------------
D [14/Mar/2011:22:15:37 -0400] [Job 16] 
D [14/Mar/2011:22:15:37 -0400] [Job 16] Spooler: cups
D [14/Mar/2011:22:15:37 -0400] [Job 16] Printer: printer
D [14/Mar/2011:22:15:37 -0400] [Job 16] Shell: /bin/bash
D [14/Mar/2011:22:15:37 -0400] [Job 16] PPD file: /etc/cups/ppd/printer.ppd
D [14/Mar/2011:22:15:37 -0400] [Job 16] ATTR file: 
D [14/Mar/2011:22:15:37 -0400] [Job 16] Printer model: Canon S600 Foomatic/bj8XXYYZ.upp (recommended)
D [14/Mar/2011:22:15:37 -0400] [Job 16] Job title: control
D [14/Mar/2011:22:15:37 -0400] [Job 16] File(s) to be printed:
D [14/Mar/2011:22:15:37 -0400] [Job 16] <STDIN>
D [14/Mar/2011:22:15:37 -0400] [Job 16] 
D [14/Mar/2011:22:15:37 -0400] [Job 16] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [14/Mar/2011:22:15:37 -0400] [Job 16] Printing system options:
D [14/Mar/2011:22:15:37 -0400] [Job 16] Pondering option 'job-originating-user-name=gruber'
D [14/Mar/2011:22:15:37 -0400] [Job 16] Unknown option job-originating-user-name=gruber.
D [14/Mar/2011:22:15:37 -0400] [Job 16] Pondering option 'finishings=3'
D [14/Mar/2011:22:15:37 -0400] [Job 16] Unknown option finishings=3.
D [14/Mar/2011:22:15:37 -0400] [Job 16] Pondering option 'job-priority=50'
D [14/Mar/2011:22:15:37 -0400] [Job 16] Unknown option job-priority=50.
D [14/Mar/2011:22:15:37 -0400] [Job 16] Pondering option 'job-sheets=none'
D [14/Mar/2011:22:15:37 -0400] [Job 16] Unknown option job-sheets=none.
D [14/Mar/2011:22:15:37 -0400] [Job 16] Pondering option 'none'
D [14/Mar/2011:22:15:37 -0400] [Job 16] Pondering option 'number-up=1'
D [14/Mar/2011:22:15:37 -0400] [Job 16] Unknown option number-up=1.
D [14/Mar/2011:22:15:37 -0400] [Job 16] Pondering option 'outputorder=reverse'
D [14/Mar/2011:22:15:37 -0400] cupsdMarkDirty(-----S)
D [14/Mar/2011:22:15:37 -0400] cupsdMarkDirty(-----S)
D [14/Mar/2011:22:15:37 -0400] [Job 16] Unknown option outputorder=reverse.
D [14/Mar/2011:22:15:37 -0400] [Job 16] Pondering option 'job-uuid=urn:uuid:141caaae-5720-3b4e-5af9-4048a8303777'
D [14/Mar/2011:22:15:37 -0400] [Job 16] Unknown option job-uuid=urn:uuid:141caaae-5720-3b4e-5af9-4048a8303777.
D [14/Mar/2011:22:15:37 -0400] [Job 16] Pondering option 'job-originating-host-name=localhost'
D [14/Mar/2011:22:15:37 -0400] [Job 16] Unknown option job-originating-host-name=localhost.
D [14/Mar/2011:22:15:37 -0400] [Job 16] Pondering option 'time-at-creation=1300155337'
D [14/Mar/2011:22:15:37 -0400] [Job 16] Unknown option time-at-creation=1300155337.
D [14/Mar/2011:22:15:37 -0400] [Job 16] Pondering option 'time-at-processing=1300155337'
D [14/Mar/2011:22:15:37 -0400] [Job 16] Unknown option time-at-processing=1300155337.
D [14/Mar/2011:22:15:37 -0400] [Job 16] Pondering option 'job-id=16'
D [14/Mar/2011:22:15:37 -0400] [Job 16] Unknown option job-id=16.
D [14/Mar/2011:22:15:37 -0400] [Job 16] Pondering option 'job-state=5'
D [14/Mar/2011:22:15:37 -0400] [Job 16] Unknown option job-state=5.
D [14/Mar/2011:22:15:37 -0400] [Job 16] Pondering option 'job-media-sheets-completed=0'
D [14/Mar/2011:22:15:37 -0400] [Job 16] Unknown option job-media-sheets-completed=0.
D [14/Mar/2011:22:15:37 -0400] [Job 16] Pondering option 'job-k-octets=1'
D [14/Mar/2011:22:15:37 -0400] [Job 16] Unknown option job-k-octets=1.
D [14/Mar/2011:22:15:37 -0400] [Job 16] Pondering option 'AP_D_InputSlot='
D [14/Mar/2011:22:15:37 -0400] [Job 16] Unknown option AP_D_InputSlot=.
D [14/Mar/2011:22:15:37 -0400] [Job 16] Options from the PPD file:
D [14/Mar/2011:22:15:37 -0400] [Job 16] 
D [14/Mar/2011:22:15:37 -0400] [Job 16] ================================================
D [14/Mar/2011:22:15:37 -0400] [Job 16] 
D [14/Mar/2011:22:15:37 -0400] [Job 16] File: <STDIN>
D [14/Mar/2011:22:15:37 -0400] [Job 16] 
D [14/Mar/2011:22:15:37 -0400] [Job 16] ================================================
D [14/Mar/2011:22:15:37 -0400] [Job 16] 
D [14/Mar/2011:22:15:37 -0400] [Job 16] Filetype: PDF
D [14/Mar/2011:22:15:37 -0400] [Job 16] Storing temporary files in /var/spool/cups/tmp
D [14/Mar/2011:22:15:37 -0400] PID 6789 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [14/Mar/2011:22:15:37 -0400] cupsdAcceptClient: 13 from localhost (Domain)
D [14/Mar/2011:22:15:37 -0400] cupsdReadClient: 13 POST / HTTP/1.1
D [14/Mar/2011:22:15:37 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [14/Mar/2011:22:15:37 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:15:37 -0400] cupsdAcceptClient: 18 from localhost (Domain)
D [14/Mar/2011:22:15:37 -0400] cupsdReadClient: 18 POST / HTTP/1.1
D [14/Mar/2011:22:15:37 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:15:37 -0400] cupsdReadClient: 13 1.1 Get-Jobs 1
D [14/Mar/2011:22:15:37 -0400] Get-Jobs ipp://localhost/printers/
D [14/Mar/2011:22:15:37 -0400] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [14/Mar/2011:22:15:37 -0400] cupsdReadClient: 18 1.1 Get-Jobs 1
D [14/Mar/2011:22:15:37 -0400] Get-Jobs ipp://localhost/printers/
D [14/Mar/2011:22:15:37 -0400] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [14/Mar/2011:22:15:37 -0400] cupsdReadClient: 13 WAITING Closing on EOF
D [14/Mar/2011:22:15:37 -0400] cupsdCloseClient: 13
D [14/Mar/2011:22:15:37 -0400] cupsdSetBusyState: Printing jobs and dirty files
D [14/Mar/2011:22:15:37 -0400] cupsdReadClient: 18 WAITING Closing on EOF
D [14/Mar/2011:22:15:37 -0400] cupsdCloseClient: 18
D [14/Mar/2011:22:15:37 -0400] [Job 16] File contains 1 pages
D [14/Mar/2011:22:15:37 -0400] [Job 16] printer: options.c:763: build_foomatic_custom_command: Assertion `choice' failed.
D [14/Mar/2011:22:15:38 -0400] Report: clients=2
D [14/Mar/2011:22:15:38 -0400] Report: jobs=2
D [14/Mar/2011:22:15:38 -0400] Report: jobs-active=1
D [14/Mar/2011:22:15:38 -0400] Report: printers=1
D [14/Mar/2011:22:15:38 -0400] Report: printers-implicit=0
D [14/Mar/2011:22:15:38 -0400] Report: stringpool-string-count=580
D [14/Mar/2011:22:15:38 -0400] Report: stringpool-alloc-bytes=8936
D [14/Mar/2011:22:15:38 -0400] Report: stringpool-total-bytes=12624
D [14/Mar/2011:22:15:38 -0400] cupsdAcceptClient: 13 from localhost (Domain)
D [14/Mar/2011:22:15:38 -0400] cupsdAcceptClient: 18 from localhost (Domain)
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 18 POST / HTTP/1.1
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 18 1.1 Create-Printer-Subscription 1
D [14/Mar/2011:22:15:38 -0400] Create-Printer-Subscription /
D [14/Mar/2011:22:15:38 -0400] cupsdCreateSubscription(con=0x21592990(18), uri="/")
D [14/Mar/2011:22:15:38 -0400] pullmethod="ippget"
D [14/Mar/2011:22:15:38 -0400] notify-lease-duration=86400
D [14/Mar/2011:22:15:38 -0400] notify-time-interval=0
D [14/Mar/2011:22:15:38 -0400] cupsdAddSubscription(mask=1798f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [14/Mar/2011:22:15:38 -0400] Added subscription 23 for server
D [14/Mar/2011:22:15:38 -0400] cupsdMarkDirty(-----S)
D [14/Mar/2011:22:15:38 -0400] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Printing jobs and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 18 POST / HTTP/1.1
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 18 1.1 CUPS-Get-Printers 1
D [14/Mar/2011:22:15:38 -0400] CUPS-Get-Printers
D [14/Mar/2011:22:15:38 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Printing jobs and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdAcceptClient: 19 from localhost (Domain)
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 18 POST / HTTP/1.1
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 18 1.1 CUPS-Get-Printers 1
D [14/Mar/2011:22:15:38 -0400] CUPS-Get-Printers
D [14/Mar/2011:22:15:38 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Printing jobs and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdAcceptClient: 20 from localhost (Domain)
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 20 POST / HTTP/1.1
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 20 1.1 Get-Printer-Attributes 1
D [14/Mar/2011:22:15:38 -0400] Get-Printer-Attributes ipp://localhost/printers/printer
D [14/Mar/2011:22:15:38 -0400] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/printer) from localhost
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Printing jobs and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 20 WAITING Closing on EOF
D [14/Mar/2011:22:15:38 -0400] cupsdCloseClient: 20
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 18 WAITING Closing on EOF
D [14/Mar/2011:22:15:38 -0400] cupsdCloseClient: 18
D [14/Mar/2011:22:15:38 -0400] cupsdAcceptClient: 18 from localhost (Domain)
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 18 POST / HTTP/1.1
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 18 1.1 Get-Jobs 1
D [14/Mar/2011:22:15:38 -0400] Get-Jobs ipp://localhost/printers/
D [14/Mar/2011:22:15:38 -0400] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Printing jobs and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdAcceptClient: 20 from localhost (Domain)
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 20 POST / HTTP/1.1
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 20 1.1 Get-Printer-Attributes 1
D [14/Mar/2011:22:15:38 -0400] Get-Printer-Attributes ipp://gruber-Satellite-L355D/printers/printer
D [14/Mar/2011:22:15:38 -0400] Returning IPP successful-ok for Get-Printer-Attributes (ipp://gruber-Satellite-L355D/printers/printer) from localhost
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Printing jobs and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 20 WAITING Closing on EOF
D [14/Mar/2011:22:15:38 -0400] cupsdCloseClient: 20
D [14/Mar/2011:22:15:38 -0400] cupsdAcceptClient: 20 from localhost (Domain)
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 20 POST / HTTP/1.1
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 20 1.1 Get-Job-Attributes 1
D [14/Mar/2011:22:15:38 -0400] Get-Job-Attributes ipp://localhost/jobs/16
D [14/Mar/2011:22:15:38 -0400] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/16) from localhost
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Printing jobs and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 20 WAITING Closing on EOF
D [14/Mar/2011:22:15:38 -0400] cupsdCloseClient: 20
E [14/Mar/2011:22:15:38 -0400] [Job 16] Empty print file!
D [14/Mar/2011:22:15:38 -0400] [Job 16] Set job-printer-state-message to "Empty print file!", current level=ERROR
D [14/Mar/2011:22:15:38 -0400] cupsdMarkDirty(-----S)
D [14/Mar/2011:22:15:38 -0400] cupsdMarkDirty(-----S)
E [14/Mar/2011:22:15:38 -0400] PID 6790 (/usr/lib/cups/filter/foomatic-rip) crashed on signal 6!
D [14/Mar/2011:22:15:38 -0400] PID 6791 (/usr/lib/cups/backend/ipp) stopped with status 1!
I [14/Mar/2011:22:15:38 -0400] [Job 16] Backend returned status 1 (failed)
D [14/Mar/2011:22:15:38 -0400] set_hold_until: hold_until = 1300155638
D [14/Mar/2011:22:15:38 -0400] cupsdMarkDirty(----J-)
D [14/Mar/2011:22:15:38 -0400] cupsdMarkDirty(-----S)
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 18 WAITING Closing on EOF
D [14/Mar/2011:22:15:38 -0400] cupsdCloseClient: 18
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdAcceptClient: 17 from localhost (Domain)
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 17 POST / HTTP/1.1
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Active clients and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 17 1.1 Get-Jobs 1
D [14/Mar/2011:22:15:38 -0400] Get-Jobs ipp://localhost/printers/
D [14/Mar/2011:22:15:38 -0400] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 17 WAITING Closing on EOF
D [14/Mar/2011:22:15:38 -0400] cupsdCloseClient: 17
D [14/Mar/2011:22:15:38 -0400] cupsdAcceptClient: 17 from localhost (Domain)
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 17 POST / HTTP/1.1
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Active clients and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 17 1.1 Get-Jobs 1
D [14/Mar/2011:22:15:38 -0400] Get-Jobs ipp://localhost/printers/
D [14/Mar/2011:22:15:38 -0400] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 17 WAITING Closing on EOF
D [14/Mar/2011:22:15:38 -0400] cupsdCloseClient: 17
D [14/Mar/2011:22:15:38 -0400] cupsdAcceptClient: 17 from localhost (Domain)
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 17 POST / HTTP/1.1
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Active clients and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 17 1.1 Get-Notifications 1
D [14/Mar/2011:22:15:38 -0400] Get-Notifications /
D [14/Mar/2011:22:15:38 -0400] cupsdIsAuthorized: requesting-user-name="gruber"
D [14/Mar/2011:22:15:38 -0400] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdAcceptClient: 18 from localhost (Domain)
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 18 POST / HTTP/1.1
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Active clients and dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 18 1.1 Get-Job-Attributes 1
D [14/Mar/2011:22:15:38 -0400] Get-Job-Attributes ipp://localhost/jobs/16
D [14/Mar/2011:22:15:38 -0400] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/16) from localhost
D [14/Mar/2011:22:15:38 -0400] cupsdSetBusyState: Dirty files
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 18 WAITING Closing on EOF
D [14/Mar/2011:22:15:38 -0400] cupsdCloseClient: 18
D [14/Mar/2011:22:15:38 -0400] cupsdReadClient: 17 WAITING Closing on EOF
D [14/Mar/2011:22:15:38 -0400] cupsdCloseClient: 17
D [14/Mar/2011:22:15:44 -0400] cupsdReadClient: 12 WAITING Closing on EOF
D [14/Mar/2011:22:15:44 -0400] cupsdCloseClient: 12
I [14/Mar/2011:22:16:08 -0400] Saving job cache file "/var/cache/cups/job.cache"...
I [14/Mar/2011:22:16:08 -0400] Saving subscriptions.conf...
D [14/Mar/2011:22:16:08 -0400] cupsdSetBusyState: Not busy
D [14/Mar/2011:22:16:08 -0400] cupsdAcceptClient: 12 from localhost (Domain)
D [14/Mar/2011:22:16:08 -0400] cupsdReadClient: 12 POST / HTTP/1.1
D [14/Mar/2011:22:16:08 -0400] cupsdSetBusyState: Active clients
D [14/Mar/2011:22:16:08 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:16:08 -0400] cupsdReadClient: 12 1.1 CUPS-Get-Printers 1
D [14/Mar/2011:22:16:08 -0400] CUPS-Get-Printers
D [14/Mar/2011:22:16:08 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [14/Mar/2011:22:16:08 -0400] cupsdSetBusyState: Not busy
D [14/Mar/2011:22:16:08 -0400] cupsdReadClient: 12 POST / HTTP/1.1
D [14/Mar/2011:22:16:08 -0400] cupsdSetBusyState: Active clients
D [14/Mar/2011:22:16:08 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:16:08 -0400] cupsdReadClient: 12 1.1 CUPS-Get-Default 1
D [14/Mar/2011:22:16:08 -0400] CUPS-Get-Default
D [14/Mar/2011:22:16:08 -0400] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [14/Mar/2011:22:16:08 -0400] cupsdSetBusyState: Not busy
D [14/Mar/2011:22:16:08 -0400] cupsdReadClient: 12 POST / HTTP/1.1
D [14/Mar/2011:22:16:08 -0400] cupsdSetBusyState: Active clients
D [14/Mar/2011:22:16:08 -0400] cupsdAuthorize: No authentication data provided.
D [14/Mar/2011:22:16:08 -0400] cupsdReadClient: 12 1.1 CUPS-Get-Printers 1
D [14/Mar/2011:22:16:08 -0400] CUPS-Get-Printers
D [14/Mar/2011:22:16:08 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [14/Mar/2011:22:16:08 -0400] cupsdSetBusyState: Not busy
D [14/Mar/2011:22:16:08 -0400] cupsdReadClient: 12 WAITING Closing on EOF
D [14/Mar/2011:22:16:08 -0400] cupsdCloseClient: 12
D [14/Mar/2011:22:19:44 -0400] cupsdReadClient: 11 WAITING Closing on EOF
D [14/Mar/2011:22:19:44 -0400] cupsdCloseClient: 11
D [14/Mar/2011:22:19:44 -0400] Report: clients=2
D [14/Mar/2011:22:19:44 -0400] Report: jobs=2
D [14/Mar/2011:22:19:44 -0400] Report: jobs-active=1
D [14/Mar/2011:22:19:44 -0400] Report: printers=1
D [14/Mar/2011:22:19:44 -0400] Report: printers-implicit=0
D [14/Mar/2011:22:19:44 -0400] Report: stringpool-string-count=708
D [14/Mar/2011:22:19:44 -0400] Report: stringpool-alloc-bytes=9040
D [14/Mar/2011:22:19:44 -0400] Report: stringpool-total-bytes=15344