I [24/Jan/2011:07:27:32 -0800] Listening to ::1:631 (IPv6)
I [24/Jan/2011:07:27:32 -0800] Listening to 127.0.0.1:631 (IPv4)
I [24/Jan/2011:07:27:32 -0800] Listening to /var/run/cups/cups.sock (Domain)
I [24/Jan/2011:07:27:32 -0800] Remote access is disabled.
D [24/Jan/2011:07:27:32 -0800] Added auto ServerAlias prometheus
I [24/Jan/2011:07:27:32 -0800] Loaded configuration file "/etc/cups/cupsd.conf"
I [24/Jan/2011:07:27:32 -0800] Using default TempDir of /var/spool/cups/tmp...
I [24/Jan/2011:07:27:32 -0800] Configured for up to 100 clients.
I [24/Jan/2011:07:27:32 -0800] Allowing up to 100 client connections per host.
I [24/Jan/2011:07:27:32 -0800] Using policy "default" as the default!
I [24/Jan/2011:07:27:32 -0800] Full reload is required.
I [24/Jan/2011:07:27:32 -0800] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 37 types, 76 filters...
D [24/Jan/2011:07:27:32 -0800] Loading printer tp0...
D [24/Jan/2011:07:27:32 -0800] load_ppd: Loading /var/cache/cups/tp0.ipp2...
D [24/Jan/2011:07:27:32 -0800] cupsdRegisterPrinter(p=0x7f4912800330(tp0))
D [24/Jan/2011:07:27:32 -0800] cupsdLoadRemoteCache: Not loading remote cache.
I [24/Jan/2011:07:27:32 -0800] Loading job cache file "/var/cache/cups/job.cache"...
D [24/Jan/2011:07:27:32 -0800] [Job 1] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 2] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 3] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 4] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 5] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 6] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 7] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 8] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 9] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 10] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 11] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 12] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 13] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 14] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 15] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 16] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 17] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 18] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 19] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 20] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 21] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] [Job 22] Loading from cache...
D [24/Jan/2011:07:27:32 -0800] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
D [24/Jan/2011:07:27:32 -0800] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
D [24/Jan/2011:07:27:32 -0800] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
D [24/Jan/2011:07:27:32 -0800] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
I [24/Jan/2011:07:27:32 -0800] Full reload complete.
D [24/Jan/2011:07:27:32 -0800] cupsd_clean_files(path="/var/spool/cups/tmp", pattern="(null)")
I [24/Jan/2011:07:27:32 -0800] Cleaning out old files in "/var/spool/cups/tmp"...
D [24/Jan/2011:07:27:32 -0800] cupsd_clean_files(path="/var/spool/cups/tmp/.hplip", pattern="(null)")
I [24/Jan/2011:07:27:32 -0800] Cleaning out old files in "/var/spool/cups/tmp/.hplip"...
D [24/Jan/2011:07:27:32 -0800] Removed "/var/spool/cups/tmp/.hplip/hplip.conf"...
D [24/Jan/2011:07:27:32 -0800] Removed "/var/spool/cups/tmp/.hplip"...
D [24/Jan/2011:07:27:32 -0800] cupsd_clean_files(path="/var/cache/cups", pattern="*.ipp")
I [24/Jan/2011:07:27:32 -0800] Cleaning out old files in "/var/cache/cups"...
I [24/Jan/2011:07:27:32 -0800] Listening to ::1:631 on fd 6...
I [24/Jan/2011:07:27:32 -0800] Listening to 127.0.0.1:631 on fd 7...
I [24/Jan/2011:07:27:32 -0800] Listening to /var/run/cups/cups.sock on fd 8...
I [24/Jan/2011:07:27:32 -0800] Resuming new connection processing...
D [24/Jan/2011:07:27:32 -0800] Discarding unused server-started event...
D [24/Jan/2011:07:27:33 -0800] Report: clients=0
D [24/Jan/2011:07:27:33 -0800] Report: jobs=22
D [24/Jan/2011:07:27:33 -0800] Report: jobs-active=0
D [24/Jan/2011:07:27:33 -0800] Report: printers=1
D [24/Jan/2011:07:27:33 -0800] Report: printers-implicit=0
D [24/Jan/2011:07:27:33 -0800] Report: stringpool-string-count=13500
D [24/Jan/2011:07:27:33 -0800] Report: stringpool-alloc-bytes=9128
D [24/Jan/2011:07:27:33 -0800] Report: stringpool-total-bytes=263752
D [24/Jan/2011:07:27:35 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:27:35 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:27:35 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:27:35 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:27:35 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:27:35 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:27:35 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:27:35 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:27:35 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:27:35 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:27:35 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:27:35 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:27:35 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:27:35 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:27:35 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:27:35 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:27:35 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:27:35 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:27:35 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:27:35 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:27:39 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:27:39 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:27:39 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:27:39 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:27:39 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:27:39 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:27:39 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:27:39 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:27:39 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:27:39 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:27:39 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:27:39 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:27:39 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:27:39 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:27:39 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:27:39 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:27:39 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:27:39 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:27:39 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:27:39 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:27:44 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:27:44 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:27:44 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:27:44 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:27:44 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:27:44 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:27:44 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:27:44 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:27:44 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:27:44 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:27:44 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:27:44 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:27:44 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:27:44 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:27:44 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:27:44 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:27:44 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:27:44 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:27:44 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:27:44 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:27:48 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:27:48 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:27:48 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:27:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:27:48 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:27:48 -0800] CUPS-Get-Printers 
D [24/Jan/2011:07:27:48 -0800] Returning IPP successful-ok for CUPS-Get-Printers () from localhost
D [24/Jan/2011:07:27:48 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:27:48 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:27:48 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:27:48 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:27:48 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:27:48 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:27:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:27:48 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:27:48 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:27:48 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:27:48 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:27:48 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:27:48 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:27:48 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:27:48 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:27:48 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:27:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:27:48 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:27:48 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:27:48 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:27:48 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:27:48 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:27:48 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:27:48 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:27:48 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:27:48 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:27:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:27:48 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:27:48 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:27:48 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:27:48 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:27:48 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:27:48 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:27:48 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:27:48 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:27:48 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:27:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:27:48 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:27:48 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:27:48 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:27:48 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:27:48 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:27:48 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:27:53 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:27:53 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:27:53 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:27:53 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:27:53 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:27:53 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:27:53 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:27:53 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:27:53 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:27:53 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:27:53 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:27:53 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:27:53 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:27:53 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:27:53 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:27:53 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:27:53 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:27:53 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:27:53 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:27:53 -0800] cupsdCloseClient: 11
I [24/Jan/2011:07:27:54 -0800] Scheduler shutting down normally.
D [24/Jan/2011:07:27:54 -0800] Discarding unused server-stopped event...
I [24/Jan/2011:07:27:54 -0800] Saving job cache file "/var/cache/cups/job.cache"...
I [24/Jan/2011:07:27:54 -0800] Listening to ::1:631 (IPv6)
I [24/Jan/2011:07:27:54 -0800] Listening to 127.0.0.1:631 (IPv4)
I [24/Jan/2011:07:27:54 -0800] Listening to /var/run/cups/cups.sock (Domain)
I [24/Jan/2011:07:27:54 -0800] Remote access is disabled.
D [24/Jan/2011:07:27:54 -0800] Added auto ServerAlias prometheus
I [24/Jan/2011:07:27:54 -0800] Loaded configuration file "/etc/cups/cupsd.conf"
I [24/Jan/2011:07:27:54 -0800] Using default TempDir of /var/spool/cups/tmp...
I [24/Jan/2011:07:27:54 -0800] Configured for up to 100 clients.
I [24/Jan/2011:07:27:54 -0800] Allowing up to 100 client connections per host.
I [24/Jan/2011:07:27:54 -0800] Using policy "default" as the default!
I [24/Jan/2011:07:27:54 -0800] Full reload is required.
I [24/Jan/2011:07:27:54 -0800] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 37 types, 76 filters...
D [24/Jan/2011:07:27:54 -0800] Loading printer tp0...
D [24/Jan/2011:07:27:54 -0800] load_ppd: Loading /var/cache/cups/tp0.ipp2...
D [24/Jan/2011:07:27:54 -0800] cupsdRegisterPrinter(p=0x7fe4477d2330(tp0))
D [24/Jan/2011:07:27:54 -0800] cupsdLoadRemoteCache: Not loading remote cache.
I [24/Jan/2011:07:27:54 -0800] Loading job cache file "/var/cache/cups/job.cache"...
D [24/Jan/2011:07:27:54 -0800] [Job 1] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 2] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 3] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 4] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 5] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 6] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 7] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 8] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 9] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 10] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 11] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 12] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 13] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 14] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 15] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 16] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 17] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 18] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 19] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 20] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 21] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] [Job 22] Loading from cache...
D [24/Jan/2011:07:27:54 -0800] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
D [24/Jan/2011:07:27:54 -0800] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
D [24/Jan/2011:07:27:54 -0800] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
D [24/Jan/2011:07:27:54 -0800] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
I [24/Jan/2011:07:27:54 -0800] Full reload complete.
D [24/Jan/2011:07:27:54 -0800] cupsd_clean_files(path="/var/spool/cups/tmp", pattern="(null)")
I [24/Jan/2011:07:27:54 -0800] Cleaning out old files in "/var/spool/cups/tmp"...
D [24/Jan/2011:07:27:54 -0800] cupsd_clean_files(path="/var/cache/cups", pattern="*.ipp")
I [24/Jan/2011:07:27:54 -0800] Cleaning out old files in "/var/cache/cups"...
I [24/Jan/2011:07:27:54 -0800] Listening to ::1:631 on fd 6...
I [24/Jan/2011:07:27:54 -0800] Listening to 127.0.0.1:631 on fd 7...
I [24/Jan/2011:07:27:54 -0800] Listening to /var/run/cups/cups.sock on fd 8...
I [24/Jan/2011:07:27:54 -0800] Resuming new connection processing...
D [24/Jan/2011:07:27:54 -0800] Discarding unused server-started event...
D [24/Jan/2011:07:27:55 -0800] Report: clients=0
D [24/Jan/2011:07:27:55 -0800] Report: jobs=22
D [24/Jan/2011:07:27:55 -0800] Report: jobs-active=0
D [24/Jan/2011:07:27:55 -0800] Report: printers=1
D [24/Jan/2011:07:27:55 -0800] Report: printers-implicit=0
D [24/Jan/2011:07:27:55 -0800] Report: stringpool-string-count=13500
D [24/Jan/2011:07:27:55 -0800] Report: stringpool-alloc-bytes=9128
D [24/Jan/2011:07:27:55 -0800] Report: stringpool-total-bytes=263752
D [24/Jan/2011:07:27:57 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:27:57 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:27:57 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:27:57 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:27:57 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:27:57 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:27:57 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:27:57 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:27:57 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:27:57 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:27:57 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:27:57 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:27:57 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:27:57 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:27:57 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:27:57 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:27:57 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:27:57 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:27:57 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:27:57 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:00 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:00 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:00 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:00 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:00 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:00 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:00 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:00 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:00 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:00 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:01 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:01 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:28:01 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:01 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:01 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Devices 1
D [24/Jan/2011:07:28:01 -0800] CUPS-Get-Devices
D [24/Jan/2011:07:28:01 -0800] cupsdIsAuthorized: username=""
D [24/Jan/2011:07:28:01 -0800] Returning HTTP Unauthorized for CUPS-Get-Devices (no URI) from localhost
D [24/Jan/2011:07:28:01 -0800] cupsdSendHeader: 11 WWW-Authenticate: Basic realm="CUPS", trc="y"
D [24/Jan/2011:07:28:01 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:01 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:01 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:01 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:01 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:28:01 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:01 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:01 -0800] cupsdReadClient: 13 POST / HTTP/1.1
D [24/Jan/2011:07:28:01 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:01 -0800] cupsdAuthorize: Authorized as root using PeerCred
D [24/Jan/2011:07:28:01 -0800] cupsdReadClient: 13 1.1 CUPS-Get-Devices 1
D [24/Jan/2011:07:28:01 -0800] CUPS-Get-Devices
D [24/Jan/2011:07:28:01 -0800] cupsdIsAuthorized: username="root"
D [24/Jan/2011:07:28:01 -0800] [CGI] argv[0] = "/usr/lib/cups/daemon/cups-deviced"
D [24/Jan/2011:07:28:01 -0800] [CGI] argv[1] = "1"
D [24/Jan/2011:07:28:01 -0800] [CGI] argv[2] = "0"
D [24/Jan/2011:07:28:01 -0800] [CGI] argv[3] = "2"
D [24/Jan/2011:07:28:01 -0800] [CGI] argv[4] = "7"
D [24/Jan/2011:07:28:01 -0800] [CGI] argv[5] = "requested-attributes=all exclude-schemes='beh','cups-pdf','bluetooth','dnssd','http','https','ipp','lpd','ncp','parallel','scsi','smb','snmp','socket'"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[10] = "SERVER_ADMIN=root@prometheus"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[13] = "USER=root"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[16] = "IPP_PORT=631"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[17] = "CUPSD_AUTH_TYPE=PeerCred"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[18] = "LANG=en_US.UTF8"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[21] = "SERVER_NAME=localhost"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[22] = "SERVER_PORT=631"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[23] = "REMOTE_ADDR="
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[25] = "SCRIPT_NAME=/"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[27] = "REMOTE_USER=root"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[29] = "HTTP_USER_AGENT=CUPS/1.4.4"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[30] = "REQUEST_METHOD=POST"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[31] = "CONTENT_LENGTH=244"
D [24/Jan/2011:07:28:01 -0800] [CGI] envp[32] = "CONTENT_TYPE=application/ipp"
D [24/Jan/2011:07:28:01 -0800] [CGI] Started /usr/lib/cups/daemon/cups-deviced (PID 7475)
I [24/Jan/2011:07:28:01 -0800] Started "/usr/lib/cups/daemon/cups-deviced" (pid=7475)
D [24/Jan/2011:07:28:01 -0800] cupsdSendCommand: 13 file=11
D [24/Jan/2011:07:28:01 -0800] [cups-deviced] Started backend /usr/lib/cups/backend/tpu (PID 7476)
D [24/Jan/2011:07:28:01 -0800] [cups-deviced] Started backend /usr/lib/cups/backend/usb (PID 7477)
D [24/Jan/2011:07:28:01 -0800] [cups-deviced] Started backend /usr/lib/cups/backend/serial (PID 7478)
D [24/Jan/2011:07:28:01 -0800] [cups-deviced] Started backend /usr/lib/cups/backend/hp (PID 7479)
D [24/Jan/2011:07:28:01 -0800] [cups-deviced] Started backend /usr/lib/cups/backend/hpfax (PID 7480)
D [24/Jan/2011:07:28:01 -0800] [cups-deviced] Started backend /usr/lib/cups/backend/bjnp (PID 7481)
D [24/Jan/2011:07:28:01 -0800] [CGI] list_devices_libusb
D [24/Jan/2011:07:28:01 -0800] [CGI] usb_find_busses=8
D [24/Jan/2011:07:28:01 -0800] [CGI] Flushed attributes...
D [24/Jan/2011:07:28:01 -0800] [cups-deviced] Found device "hp"...
D [24/Jan/2011:07:28:01 -0800] Script header: Content-Type: application/ipp
D [24/Jan/2011:07:28:01 -0800] Script header: 
D [24/Jan/2011:07:28:01 -0800] [CGI] usb_find_devices=14
D [24/Jan/2011:07:28:01 -0800] [cups-deviced] PID 7479 (hp) exited with no errors.
D [24/Jan/2011:07:28:01 -0800] [CGI] Flushed attributes...
D [24/Jan/2011:07:28:01 -0800] [cups-deviced] Found device "usb://Canon/Pro9500II%20series"...
D [24/Jan/2011:07:28:01 -0800] [cups-deviced] PID 7477 (usb) exited with no errors.
D [24/Jan/2011:07:28:01 -0800] [cups-deviced] PID 7478 (serial) exited with no errors.
D [24/Jan/2011:07:28:01 -0800] [CGI] Flushed attributes...
D [24/Jan/2011:07:28:01 -0800] [cups-deviced] Found device "tpu://Canon/Pro9500II_series"...
D [24/Jan/2011:07:28:01 -0800] [cups-deviced] PID 7476 (tpu) exited with no errors.
D [24/Jan/2011:07:28:01 -0800] [CGI] Flushed attributes...
D [24/Jan/2011:07:28:01 -0800] [cups-deviced] Found device "hpfax"...
D [24/Jan/2011:07:28:01 -0800] [cups-deviced] PID 7480 (hpfax) exited with no errors.
D [24/Jan/2011:07:28:02 -0800] [CGI] Flushed attributes...
D [24/Jan/2011:07:28:02 -0800] [cups-deviced] Found device "bjnp"...
D [24/Jan/2011:07:28:02 -0800] [cups-deviced] PID 7481 (bjnp) exited with no errors.
D [24/Jan/2011:07:28:02 -0800] PID 7475 (/usr/lib/cups/daemon/cups-deviced) exited with no errors.
D [24/Jan/2011:07:28:02 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:02 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:28:02 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:28:02 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:02 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:28:02 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:02 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:02 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:28:02 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:28:02 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:28:02 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:02 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:02 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:02 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:02 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:28:02 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:02 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:02 -0800] cupsdReadClient: 11 1.1 CUPS-Get-PPDs 1
D [24/Jan/2011:07:28:02 -0800] CUPS-Get-PPDs
D [24/Jan/2011:07:28:02 -0800] [CGI] argv[0] = "/usr/lib/cups/daemon/cups-driverd"
D [24/Jan/2011:07:28:02 -0800] [CGI] argv[1] = "list"
D [24/Jan/2011:07:28:02 -0800] [CGI] argv[2] = "1"
D [24/Jan/2011:07:28:02 -0800] [CGI] argv[3] = "0"
D [24/Jan/2011:07:28:02 -0800] [CGI] argv[4] = "requested-attributes=all"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[10] = "SERVER_ADMIN=root@prometheus"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[13] = "USER=root"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[16] = "IPP_PORT=631"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[17] = "LANG=en_US.UTF8"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[18] = "REDIRECT_STATUS=1"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[20] = "SERVER_NAME=localhost"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[21] = "SERVER_PORT=631"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[22] = "REMOTE_ADDR="
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[23] = "REMOTE_HOST=localhost"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[24] = "SCRIPT_NAME=/"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[26] = "SERVER_PROTOCOL=HTTP/1.1"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[27] = "HTTP_USER_AGENT=CUPS/1.4.4"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[28] = "REQUEST_METHOD=POST"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[29] = "CONTENT_LENGTH=75"
D [24/Jan/2011:07:28:02 -0800] [CGI] envp[30] = "CONTENT_TYPE=application/ipp"
D [24/Jan/2011:07:28:02 -0800] [CGI] Started /usr/lib/cups/daemon/cups-driverd (PID 7482)
I [24/Jan/2011:07:28:02 -0800] Started "/usr/lib/cups/daemon/cups-driverd" (pid=7482)
D [24/Jan/2011:07:28:02 -0800] cupsdSendCommand: 11 file=13
I [24/Jan/2011:07:28:02 -0800] [cups-driverd] Read "/var/cache/cups/ppds.dat", 1828 PPDs...
I [24/Jan/2011:07:28:02 -0800] [cups-driverd] Read "/var/cache/cups/ppds.dat", 1828 PPDs...
D [24/Jan/2011:07:28:02 -0800] [cups-driverd] Loading "/usr/share/cups/model"...
D [24/Jan/2011:07:28:02 -0800] [cups-driverd] Loading "/usr/share/cups/model/turboprint"...
D [24/Jan/2011:07:28:02 -0800] [cups-driverd] Loading "/usr/share/cups/drv"...
D [24/Jan/2011:07:28:02 -0800] [cups-driverd] Loading "/usr/local/share/ppd"...
D [24/Jan/2011:07:28:02 -0800] [cups-driverd] Loading "/usr/share/ppd"...
D [24/Jan/2011:07:28:02 -0800] [cups-driverd] Loading "/usr/share/ppd/ghostscript"...
D [24/Jan/2011:07:28:02 -0800] [cups-driverd] Loading "/usr/share/ppd/ghostscript/model"...
D [24/Jan/2011:07:28:02 -0800] [cups-driverd] Loading "/usr/share/ppd/custom"...
D [24/Jan/2011:07:28:02 -0800] [cups-driverd] Loading "/usr/share/ppd/cups-included"...
D [24/Jan/2011:07:28:02 -0800] [cups-driverd] Loading "/usr/share/ppd/foo2zjs"...
D [24/Jan/2011:07:28:02 -0800] [cups-driverd] Loading "/usr/share/ppd/hplip"...
D [24/Jan/2011:07:28:02 -0800] [cups-driverd] Loading "/usr/share/ppd/hplip/HP"...
D [24/Jan/2011:07:28:02 -0800] [cups-driverd] Loading "/usr/share/ppd/pxljr"...
D [24/Jan/2011:07:28:02 -0800] [cups-driverd] ChangedPPD=0
I [24/Jan/2011:07:28:02 -0800] [cups-driverd] No new or changed PPDs...
I [24/Jan/2011:07:28:02 -0800] [cups-driverd] No new or changed PPDs...
D [24/Jan/2011:07:28:02 -0800] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Jan/2011:07:28:02 -0800] cupsdReadClient: 14 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:02 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:02 -0800] cupsdReadClient: 14 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:28:02 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:28:02 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:28:02 -0800] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Jan/2011:07:28:02 -0800] cupsdCloseClient: 14
D [24/Jan/2011:07:28:02 -0800] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Jan/2011:07:28:02 -0800] cupsdReadClient: 14 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:02 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:02 -0800] cupsdReadClient: 14 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:02 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:02 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:02 -0800] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Jan/2011:07:28:02 -0800] cupsdCloseClient: 14
D [24/Jan/2011:07:28:03 -0800] [CGI] Cannot read file /usr/share/foomatic/db/oldprinterids!
D [24/Jan/2011:07:28:03 -0800] [CGI] Cannot read directory /usr/share/foomatic/db/source/driver!
D [24/Jan/2011:07:28:03 -0800] [CGI] Entity: line 1: parser error : Extra content at the end of the document
D [24/Jan/2011:07:28:03 -0800] [CGI] <overview>
D [24/Jan/2011:07:28:03 -0800] [CGI] ^
D [24/Jan/2011:07:28:03 -0800] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Jan/2011:07:28:03 -0800] cupsdReadClient: 14 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:03 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:03 -0800] cupsdReadClient: 14 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:03 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:03 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:03 -0800] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Jan/2011:07:28:03 -0800] cupsdCloseClient: 14
D [24/Jan/2011:07:28:04 -0800] Script header: Content-Type: application/ipp
D [24/Jan/2011:07:28:04 -0800] Script header: 
D [24/Jan/2011:07:28:05 -0800] PID 7482 (/usr/lib/cups/daemon/cups-driverd) exited with no errors.
D [24/Jan/2011:07:28:05 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:06 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:28:06 -0800] cupsdReadClient: 13 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:06 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:06 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:06 -0800] cupsdReadClient: 13 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:28:06 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:28:06 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:28:06 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:06 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:28:06 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:28:06 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:28:06 -0800] cupsdReadClient: 13 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:06 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:06 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:06 -0800] cupsdReadClient: 13 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:06 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:06 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:06 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:06 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:28:06 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:28:06 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:28:06 -0800] cupsdReadClient: 13 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:06 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:06 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:06 -0800] cupsdReadClient: 13 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:06 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:06 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:06 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:06 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:28:06 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:28:10 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:28:10 -0800] cupsdReadClient: 13 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:10 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:10 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:10 -0800] cupsdReadClient: 13 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:10 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:10 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:10 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:10 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:28:10 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:28:10 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:10 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:11 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:11 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:11 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:11 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:11 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:28:11 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:28:11 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:28:11 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:11 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:11 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:11 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:11 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:11 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:11 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:11 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:11 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:11 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:11 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:11 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:11 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:11 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:12 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:28:12 -0800] cupsdReadClient: 13 POST / HTTP/1.1
D [24/Jan/2011:07:28:12 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:12 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:12 -0800] cupsdReadClient: 13 1.1 Create-Printer-Subscription 1
D [24/Jan/2011:07:28:12 -0800] Create-Printer-Subscription /
D [24/Jan/2011:07:28:12 -0800] cupsdCreateSubscription(con=0x7fe4478ecf30(13), uri="/")
D [24/Jan/2011:07:28:12 -0800] pullmethod="ippget"
D [24/Jan/2011:07:28:12 -0800] notify-lease-duration=86400
D [24/Jan/2011:07:28:12 -0800] notify-time-interval=0
D [24/Jan/2011:07:28:12 -0800] cupsdAddSubscription(mask=18f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [24/Jan/2011:07:28:12 -0800] Added subscription 7 for server
D [24/Jan/2011:07:28:12 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:28:12 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:12 -0800] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost
D [24/Jan/2011:07:28:12 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:12 -0800] cupsdReadClient: 13 POST / HTTP/1.1
D [24/Jan/2011:07:28:12 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:12 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:12 -0800] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:28:12 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:28:12 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:28:12 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:12 -0800] cupsdReadClient: 13 POST / HTTP/1.1
D [24/Jan/2011:07:28:12 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:12 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:12 -0800] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:28:12 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:28:12 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:28:12 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:12 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:28:12 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:28:12 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:28:12 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:12 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:12 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:28:12 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:28:12 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:28:12 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:12 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:28:12 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:12 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:12 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Classes 1
D [24/Jan/2011:07:28:12 -0800] CUPS-Get-Classes
D [24/Jan/2011:07:28:12 -0800] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [24/Jan/2011:07:28:12 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:12 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:28:12 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:12 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:12 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Default 1
D [24/Jan/2011:07:28:12 -0800] CUPS-Get-Default
D [24/Jan/2011:07:28:12 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [24/Jan/2011:07:28:12 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:12 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:28:12 -0800] cupsdReadClient: 13 POST / HTTP/1.1
D [24/Jan/2011:07:28:12 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:12 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:12 -0800] cupsdReadClient: 13 1.1 CUPS-Get-PPDs 1
D [24/Jan/2011:07:28:12 -0800] CUPS-Get-PPDs
D [24/Jan/2011:07:28:12 -0800] [CGI] argv[0] = "/usr/lib/cups/daemon/cups-driverd"
D [24/Jan/2011:07:28:12 -0800] [CGI] argv[1] = "list"
D [24/Jan/2011:07:28:12 -0800] [CGI] argv[2] = "1"
D [24/Jan/2011:07:28:12 -0800] [CGI] argv[3] = "0"
D [24/Jan/2011:07:28:12 -0800] [CGI] argv[4] = "requested-attributes=all"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[10] = "SERVER_ADMIN=root@prometheus"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[13] = "USER=root"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[16] = "IPP_PORT=631"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[17] = "LANG=en_US.UTF8"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[18] = "REDIRECT_STATUS=1"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[20] = "SERVER_NAME=localhost"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[21] = "SERVER_PORT=631"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[22] = "REMOTE_ADDR="
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[23] = "REMOTE_HOST=localhost"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[24] = "SCRIPT_NAME=/"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[26] = "SERVER_PROTOCOL=HTTP/1.1"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[27] = "HTTP_USER_AGENT=CUPS/1.4.4"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[28] = "REQUEST_METHOD=POST"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[29] = "CONTENT_LENGTH=75"
D [24/Jan/2011:07:28:12 -0800] [CGI] envp[30] = "CONTENT_TYPE=application/ipp"
D [24/Jan/2011:07:28:12 -0800] [CGI] Started /usr/lib/cups/daemon/cups-driverd (PID 7503)
I [24/Jan/2011:07:28:12 -0800] Started "/usr/lib/cups/daemon/cups-driverd" (pid=7503)
D [24/Jan/2011:07:28:12 -0800] cupsdSendCommand: 13 file=14
I [24/Jan/2011:07:28:12 -0800] [cups-driverd] Read "/var/cache/cups/ppds.dat", 1828 PPDs...
I [24/Jan/2011:07:28:12 -0800] [cups-driverd] Read "/var/cache/cups/ppds.dat", 1828 PPDs...
D [24/Jan/2011:07:28:12 -0800] [cups-driverd] Loading "/usr/share/cups/model"...
D [24/Jan/2011:07:28:12 -0800] [cups-driverd] Loading "/usr/share/cups/model/turboprint"...
D [24/Jan/2011:07:28:12 -0800] [cups-driverd] Loading "/usr/share/cups/drv"...
D [24/Jan/2011:07:28:12 -0800] [cups-driverd] Loading "/usr/local/share/ppd"...
D [24/Jan/2011:07:28:12 -0800] [cups-driverd] Loading "/usr/share/ppd"...
D [24/Jan/2011:07:28:12 -0800] [cups-driverd] Loading "/usr/share/ppd/ghostscript"...
D [24/Jan/2011:07:28:12 -0800] [cups-driverd] Loading "/usr/share/ppd/ghostscript/model"...
D [24/Jan/2011:07:28:12 -0800] [cups-driverd] Loading "/usr/share/ppd/custom"...
D [24/Jan/2011:07:28:12 -0800] [cups-driverd] Loading "/usr/share/ppd/cups-included"...
D [24/Jan/2011:07:28:12 -0800] [cups-driverd] Loading "/usr/share/ppd/foo2zjs"...
D [24/Jan/2011:07:28:12 -0800] [cups-driverd] Loading "/usr/share/ppd/hplip"...
D [24/Jan/2011:07:28:12 -0800] [cups-driverd] Loading "/usr/share/ppd/hplip/HP"...
D [24/Jan/2011:07:28:12 -0800] [cups-driverd] Loading "/usr/share/ppd/pxljr"...
D [24/Jan/2011:07:28:12 -0800] [cups-driverd] ChangedPPD=0
I [24/Jan/2011:07:28:12 -0800] [cups-driverd] No new or changed PPDs...
I [24/Jan/2011:07:28:12 -0800] [cups-driverd] No new or changed PPDs...
D [24/Jan/2011:07:28:13 -0800] cupsdAcceptClient: 15 from localhost (Domain)
D [24/Jan/2011:07:28:13 -0800] cupsdReadClient: 15 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:13 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:13 -0800] cupsdReadClient: 15 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:13 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:13 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:13 -0800] cupsdReadClient: 15 WAITING Closing on EOF
D [24/Jan/2011:07:28:13 -0800] cupsdCloseClient: 15
D [24/Jan/2011:07:28:13 -0800] cupsdAcceptClient: 15 from localhost (Domain)
D [24/Jan/2011:07:28:13 -0800] cupsdReadClient: 15 POST / HTTP/1.1
D [24/Jan/2011:07:28:13 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:13 -0800] cupsdReadClient: 15 1.1 Get-Notifications 1
D [24/Jan/2011:07:28:13 -0800] Get-Notifications /
D [24/Jan/2011:07:28:13 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:28:13 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:28:13 -0800] cupsdReadClient: 15 WAITING Closing on EOF
D [24/Jan/2011:07:28:13 -0800] cupsdCloseClient: 15
D [24/Jan/2011:07:28:14 -0800] [CGI] Cannot read file /usr/share/foomatic/db/oldprinterids!
D [24/Jan/2011:07:28:14 -0800] [CGI] Cannot read directory /usr/share/foomatic/db/source/driver!
D [24/Jan/2011:07:28:14 -0800] [CGI] Entity: line 1: parser error : Extra content at the end of the document
D [24/Jan/2011:07:28:14 -0800] [CGI] <overview>
D [24/Jan/2011:07:28:14 -0800] [CGI] ^
D [24/Jan/2011:07:28:15 -0800] Script header: Content-Type: application/ipp
D [24/Jan/2011:07:28:15 -0800] Script header: 
D [24/Jan/2011:07:28:15 -0800] cupsdAcceptClient: 15 from localhost (Domain)
D [24/Jan/2011:07:28:15 -0800] cupsdReadClient: 15 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:15 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:15 -0800] cupsdReadClient: 15 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:28:15 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:28:15 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:28:15 -0800] cupsdReadClient: 15 WAITING Closing on EOF
D [24/Jan/2011:07:28:15 -0800] cupsdCloseClient: 15
D [24/Jan/2011:07:28:15 -0800] cupsdAcceptClient: 15 from localhost (Domain)
D [24/Jan/2011:07:28:15 -0800] cupsdReadClient: 15 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:15 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:15 -0800] cupsdReadClient: 15 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:15 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:15 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:15 -0800] cupsdReadClient: 15 WAITING Closing on EOF
D [24/Jan/2011:07:28:15 -0800] cupsdCloseClient: 15
D [24/Jan/2011:07:28:16 -0800] PID 7503 (/usr/lib/cups/daemon/cups-driverd) exited with no errors.
D [24/Jan/2011:07:28:16 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:16 -0800] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Jan/2011:07:28:16 -0800] cupsdReadClient: 14 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:16 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:16 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:16 -0800] cupsdReadClient: 14 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:16 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:16 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:16 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:16 -0800] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Jan/2011:07:28:16 -0800] cupsdCloseClient: 14
D [24/Jan/2011:07:28:19 -0800] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Jan/2011:07:28:19 -0800] cupsdReadClient: 14 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:19 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:19 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:19 -0800] cupsdReadClient: 14 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:19 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:19 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:19 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:19 -0800] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Jan/2011:07:28:19 -0800] cupsdCloseClient: 14
D [24/Jan/2011:07:28:20 -0800] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Jan/2011:07:28:20 -0800] cupsdReadClient: 14 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:20 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:20 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:20 -0800] cupsdReadClient: 14 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:28:20 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:28:20 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:28:20 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:20 -0800] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Jan/2011:07:28:20 -0800] cupsdCloseClient: 14
D [24/Jan/2011:07:28:20 -0800] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Jan/2011:07:28:20 -0800] cupsdReadClient: 14 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:20 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:20 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:20 -0800] cupsdReadClient: 14 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:20 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:20 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:20 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:20 -0800] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Jan/2011:07:28:20 -0800] cupsdCloseClient: 14
D [24/Jan/2011:07:28:22 -0800] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Jan/2011:07:28:22 -0800] cupsdReadClient: 14 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:22 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:22 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:22 -0800] cupsdReadClient: 14 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:22 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:22 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:22 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:22 -0800] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Jan/2011:07:28:22 -0800] cupsdCloseClient: 14
D [24/Jan/2011:07:28:24 -0800] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Jan/2011:07:28:24 -0800] cupsdReadClient: 14 POST / HTTP/1.1
D [24/Jan/2011:07:28:24 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:24 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:24 -0800] cupsdReadClient: 14 1.1 Cancel-Subscription 1
D [24/Jan/2011:07:28:24 -0800] Cancel-Subscription /
D [24/Jan/2011:07:28:24 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:28:24 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:28:24 -0800] Returning IPP successful-ok for Cancel-Subscription (/) from localhost
D [24/Jan/2011:07:28:24 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:24 -0800] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Jan/2011:07:28:24 -0800] cupsdCloseClient: 14
D [24/Jan/2011:07:28:24 -0800] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Jan/2011:07:28:24 -0800] cupsdReadClient: 14 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:24 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:24 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:24 -0800] cupsdReadClient: 14 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:28:24 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:28:24 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:28:24 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:24 -0800] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Jan/2011:07:28:24 -0800] cupsdCloseClient: 14
D [24/Jan/2011:07:28:24 -0800] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Jan/2011:07:28:24 -0800] cupsdReadClient: 14 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:24 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:24 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:24 -0800] cupsdReadClient: 14 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:24 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:24 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:24 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:24 -0800] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Jan/2011:07:28:24 -0800] cupsdCloseClient: 14
D [24/Jan/2011:07:28:25 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:25 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:25 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:28:25 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:28:25 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:25 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:25 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:25 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:25 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:25 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:25 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:25 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:25 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:25 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:29 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:29 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:29 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:29 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:29 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:29 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:29 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:29 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:29 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:29 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:29 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:29 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:29 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:29 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:29 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:28:29 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:28:29 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:28:29 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:29 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:29 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:29 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:29 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:29 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:29 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:29 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:29 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:29 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:29 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:29 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:29 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:32 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:32 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:32 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:32 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:32 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:32 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:32 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:32 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:32 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:32 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:33 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:33 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:33 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:33 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:33 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:28:33 -0800] CUPS-Get-Printers 
D [24/Jan/2011:07:28:33 -0800] Returning IPP successful-ok for CUPS-Get-Printers () from localhost
D [24/Jan/2011:07:28:33 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:33 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:33 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:33 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:33 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:33 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:33 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:33 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:28:33 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:28:33 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:28:33 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:33 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:33 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:33 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:33 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:33 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:33 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:33 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:33 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:33 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:33 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:33 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:33 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:33 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:33 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:33 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:33 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:33 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:28:33 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:28:33 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:28:33 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:33 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:33 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:33 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:33 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:33 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:33 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:33 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:33 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:33 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:33 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:33 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:33 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:35 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:35 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:35 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:35 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:35 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:35 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:35 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:35 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:35 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:35 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:36 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:36 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:28:36 -0800] cupsdReadClient: 13 POST / HTTP/1.1
D [24/Jan/2011:07:28:36 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:36 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:36 -0800] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:28:36 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:28:36 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:28:36 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:36 -0800] cupsdReadClient: 13 POST / HTTP/1.1
D [24/Jan/2011:07:28:36 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:36 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:36 -0800] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
D [24/Jan/2011:07:28:36 -0800] CUPS-Get-Default
D [24/Jan/2011:07:28:36 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [24/Jan/2011:07:28:36 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:36 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:28:36 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:36 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:36 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Default 1
D [24/Jan/2011:07:28:36 -0800] CUPS-Get-Default
D [24/Jan/2011:07:28:36 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [24/Jan/2011:07:28:36 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:36 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:36 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:36 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:36 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:28:36 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:28:36 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:28:36 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:38 -0800] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Jan/2011:07:28:38 -0800] cupsdReadClient: 14 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:38 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:38 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:38 -0800] cupsdReadClient: 14 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:28:38 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:28:38 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:28:38 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:38 -0800] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Jan/2011:07:28:38 -0800] cupsdCloseClient: 14
D [24/Jan/2011:07:28:38 -0800] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Jan/2011:07:28:38 -0800] cupsdReadClient: 14 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:38 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:38 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:38 -0800] cupsdReadClient: 14 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:38 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:38 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:38 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:38 -0800] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Jan/2011:07:28:38 -0800] cupsdCloseClient: 14
D [24/Jan/2011:07:28:38 -0800] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Jan/2011:07:28:38 -0800] cupsdReadClient: 14 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:38 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:38 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:38 -0800] cupsdReadClient: 14 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:38 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:38 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:38 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:38 -0800] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Jan/2011:07:28:38 -0800] cupsdCloseClient: 14
D [24/Jan/2011:07:28:41 -0800] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Jan/2011:07:28:41 -0800] cupsdReadClient: 14 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:41 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:28:41 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:41 -0800] cupsdReadClient: 14 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:41 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:41 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:41 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:28:41 -0800] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Jan/2011:07:28:41 -0800] cupsdCloseClient: 14
I [24/Jan/2011:07:28:42 -0800] Saving subscriptions.conf...
D [24/Jan/2011:07:28:42 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:42 -0800] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Jan/2011:07:28:42 -0800] cupsdReadClient: 14 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:42 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:42 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:42 -0800] cupsdReadClient: 14 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:28:42 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:28:42 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:28:42 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:42 -0800] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Jan/2011:07:28:42 -0800] cupsdCloseClient: 14
D [24/Jan/2011:07:28:42 -0800] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Jan/2011:07:28:42 -0800] cupsdReadClient: 14 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:42 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:42 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:42 -0800] cupsdReadClient: 14 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:42 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:42 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:42 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:42 -0800] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Jan/2011:07:28:42 -0800] cupsdCloseClient: 14
D [24/Jan/2011:07:28:44 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:44 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:44 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:44 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:44 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:44 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:44 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:44 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:44 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:44 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:44 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:44 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:44 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:28:44 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:28:47 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:47 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:47 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:47 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:47 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:28:47 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:28:47 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:28:47 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:47 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:47 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:47 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:47 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:47 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:47 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:47 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:47 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:47 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:47 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:47 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:47 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:47 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:47 -0800] cupsdReadClient: 11 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:28:47 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:47 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:47 -0800] cupsdReadClient: 11 1.1 Get-Jobs 1
D [24/Jan/2011:07:28:47 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:28:47 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:28:47 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:28:47 -0800] cupsdReadClient: 11 WAITING Closing on EOF
D [24/Jan/2011:07:28:47 -0800] cupsdCloseClient: 11
D [24/Jan/2011:07:28:55 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [24/Jan/2011:07:28:55 -0800] Report: clients=1
D [24/Jan/2011:07:28:55 -0800] Report: jobs=22
D [24/Jan/2011:07:28:55 -0800] Report: jobs-active=0
D [24/Jan/2011:07:28:55 -0800] Report: printers=1
D [24/Jan/2011:07:28:55 -0800] Report: printers-implicit=0
D [24/Jan/2011:07:28:55 -0800] Report: stringpool-string-count=13500
D [24/Jan/2011:07:28:55 -0800] Report: stringpool-alloc-bytes=9128
D [24/Jan/2011:07:28:55 -0800] Report: stringpool-total-bytes=263752
D [24/Jan/2011:07:28:55 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:28:55 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:28:55 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:28:55 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:28:55 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:28:55 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:28:55 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:30:28 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:30:28 -0800] Report: clients=2
D [24/Jan/2011:07:30:28 -0800] Report: jobs=22
D [24/Jan/2011:07:30:28 -0800] Report: jobs-active=0
D [24/Jan/2011:07:30:28 -0800] Report: printers=1
D [24/Jan/2011:07:30:28 -0800] Report: printers-implicit=0
D [24/Jan/2011:07:30:28 -0800] Report: stringpool-string-count=13500
D [24/Jan/2011:07:30:28 -0800] Report: stringpool-alloc-bytes=9128
D [24/Jan/2011:07:30:28 -0800] Report: stringpool-total-bytes=263752
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 13 POST /printers/tp0 HTTP/1.1
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:30:28 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 13 1.1 Print-Job 1
D [24/Jan/2011:07:30:28 -0800] Print-Job ipp://localhost/printers/tp0
D [24/Jan/2011:07:30:28 -0800] [Job ???] Auto-typing file...
I [24/Jan/2011:07:30:28 -0800] [Job ???] Request file type is application/vnd.cups-banner.
D [24/Jan/2011:07:30:28 -0800] cupsdMarkDirty(----J-)
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:30:28 -0800] add_job: requesting-user-name="mhenry"
D [24/Jan/2011:07:30:28 -0800] Adding default job-sheets values "none,none"...
I [24/Jan/2011:07:30:28 -0800] [Job 23] Adding start banner page "none".
D [24/Jan/2011:07:30:28 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:30:28 -0800] cupsdMarkDirty(----J-)
I [24/Jan/2011:07:30:28 -0800] [Job 23] Adding end banner page "none".
I [24/Jan/2011:07:30:28 -0800] [Job 23] File of type application/vnd.cups-banner queued by "mhenry".
D [24/Jan/2011:07:30:28 -0800] [Job 23] hold_until=0
I [24/Jan/2011:07:30:28 -0800] [Job 23] Queued on "tp0" by "mhenry".
D [24/Jan/2011:07:30:28 -0800] cupsdMarkDirty(----J-)
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:30:28 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:30:28 -0800] [Job 23] job-sheets=none,none
D [24/Jan/2011:07:30:28 -0800] [Job 23] argv[0]="tp0"
D [24/Jan/2011:07:30:28 -0800] [Job 23] argv[1]="23"
D [24/Jan/2011:07:30:28 -0800] [Job 23] argv[2]="mhenry"
D [24/Jan/2011:07:30:28 -0800] [Job 23] argv[3]="Test Page"
D [24/Jan/2011:07:30:28 -0800] [Job 23] argv[4]="1"
D [24/Jan/2011:07:30:28 -0800] [Job 23] argv[5]="job-uuid=urn:uuid:651e5842-7d9d-39f8-73f0-6a68e02a801f job-originating-host-name=localhost"
D [24/Jan/2011:07:30:28 -0800] [Job 23] argv[6]="/var/spool/cups/d00023-001"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[8]="HOME=/var/spool/cups/tmp"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[10]="SERVER_ADMIN=root@prometheus"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[11]="SOFTWARE=CUPS/1.4.4"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[13]="USER=root"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[16]="IPP_PORT=631"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[17]="CHARSET=utf-8"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[18]="LANG=en_US.UTF-8"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[19]="PPD=/etc/cups/ppd/tp0.ppd"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[20]="RIP_MAX_CACHE=auto"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[21]="CONTENT_TYPE=application/vnd.cups-banner"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[22]="DEVICE_URI=tpu://Canon/Pro9500II_series"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[23]="PRINTER_INFO=Canon_PIXMA_Pro9500II"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[24]="PRINTER_LOCATION="
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[25]="PRINTER=tp0"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[26]="CUPS_FILETYPE=document"
D [24/Jan/2011:07:30:28 -0800] [Job 23] envp[27]="FINAL_CONTENT_TYPE=printer/tp0"
I [24/Jan/2011:07:30:28 -0800] [Job 23] Started filter /usr/lib/cups/filter/bannertops (PID 7527)
I [24/Jan/2011:07:30:28 -0800] [Job 23] Started filter /usr/lib/cups/filter/pstopdf (PID 7528)
I [24/Jan/2011:07:30:28 -0800] [Job 23] Started filter /usr/lib/cups/filter/pdftopdf (PID 7529)
I [24/Jan/2011:07:30:28 -0800] [Job 23] Started filter /usr/lib/cups/filter/pstoturboprint (PID 7530)
I [24/Jan/2011:07:30:28 -0800] [Job 23] Started backend /usr/lib/cups/backend/tpu (PID 7532)
D [24/Jan/2011:07:30:28 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:30:28 -0800] Returning IPP successful-ok for Print-Job (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:30:28 -0800] [Job 23] pstopdf 5 args: 23 mhenry Test Page 1 job-uuid=urn:uuid:651e5842-7d9d-39f8-73f0-6a68e02a801f job-originating-host-name=localhost
D [24/Jan/2011:07:30:28 -0800] [Job 23] PPD: /etc/cups/ppd/tp0.ppd
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:30:28 -0800] [Job 23] load_banner(filename="/var/spool/cups/d00023-001")
D [24/Jan/2011:07:30:28 -0800] [Job 23] Page = 293x432; 0,0 to 293,432
D [24/Jan/2011:07:30:28 -0800] [Job 23] /usr/lib/cups/filter/pstoturboprint: line 37: --incognito: command not found
D [24/Jan/2011:07:30:28 -0800] [Job 23] /usr/lib/cups/filter/pstoturboprint: line 111: /turboprint/print.log: No such file or directory
D [24/Jan/2011:07:30:28 -0800] [Job 23] /usr/lib/cups/filter/pstoturboprint: line 151: /tpstdin: No such file or directory
D [24/Jan/2011:07:30:28 -0800] [Job 23] PNG image: 128x128x8, color_type=6 (RGB+ALPHA)
D [24/Jan/2011:07:30:28 -0800] [Job 23] PNG image: 192x128x8, color_type=2 (RGB)
D [24/Jan/2011:07:30:28 -0800] PID 7527 (/usr/lib/cups/filter/bannertops) exited with no errors.
D [24/Jan/2011:07:30:28 -0800] [Job 23] ldd: /tpprint: No such file or directory
D [24/Jan/2011:07:30:28 -0800] [Job 23] Resolution: 601x600
D [24/Jan/2011:07:30:28 -0800] [Job 23] /usr/lib/cups/filter/pstoturboprint: line 208: /tpprint: No such file or directory
D [24/Jan/2011:07:30:28 -0800] [Job 23] cat: /pstoturboprint7530.var: No such file or directory
D [24/Jan/2011:07:30:28 -0800] [Job 23] cat: /pstoturboprint7530.var: No such file or directory
D [24/Jan/2011:07:30:28 -0800] [Job 23] /usr/lib/cups/filter/pstoturboprint: line 240: /tpstdin: No such file or directory
D [24/Jan/2011:07:30:28 -0800] [Job 23] /usr/lib/cups/filter/pstoturboprint: line 314: [: -ne: unary operator expected
D [24/Jan/2011:07:30:28 -0800] [Job 23] Page size: 4x6inBorderless
D [24/Jan/2011:07:30:28 -0800] [Job 23] /usr/lib/cups/filter/pstoturboprint: line 356: [: -ne: unary operator expected
D [24/Jan/2011:07:30:28 -0800] [Job 23] /usr/lib/cups/filter/pstoturboprint: line 360: *  : syntax error: operand expected (error token is "*  ")
D [24/Jan/2011:07:30:28 -0800] [Job 23] /usr/lib/cups/filter/pstoturboprint: line 361: [: -gt: unary operator expected
D [24/Jan/2011:07:30:28 -0800] [Job 23] Width: 293.4, height: 432.0, absolute margins: 0.0, 0.0, 293.4, 432.0
D [24/Jan/2011:07:30:28 -0800] [Job 23] mkfifo: cannot create fifo `/pstoturboprint7530.fifo'DEBUG: Relative margins: 0.0, 0.0, 0, 0
D [24/Jan/2011:07:30:28 -0800] [Job 23] : Permission deniedDEBUG: PPD options: -r600 -dDEVICEWIDTHPOINTS=293.4 -dDEVICEHEIGHTPOINTS=432.0
D [24/Jan/2011:07:30:28 -0800] [Job 23] 
D [24/Jan/2011:07:30:28 -0800] [Job 23] PostScript to be injected: 
D [24/Jan/2011:07:30:28 -0800] [Job 23] /usr/lib/cups/filter/pstoturboprint: line 433: /tpprint: No such file or directory
D [24/Jan/2011:07:30:28 -0800] [Job 23] /usr/lib/cups/filter/pstoturboprint: line 441: /tpstdin: No such file or directory
D [24/Jan/2011:07:30:28 -0800] [Job 23] /usr/lib/cups/filter/pstoturboprint: line 441: /pstoturboprint7530.fifo: Permission denied
D [24/Jan/2011:07:30:28 -0800] [Job 23] Running cat | /usr/bin/gs -q -dNOPAUSE -dBATCH -sDEVICE=pdfwrite -dCompatibilityLevel=1.3 -dAutoRotatePages=/None -dAutoFilterColorImages=false                -dNOPLATFONTS -dPARANOIDSAFER -sstdout=%stderr -dColorImageFilter=/FlateEncode                 -dPDFSETTINGS=/printer                 -dColorConversionStrategy=/LeaveColorUnchanged -dDoNumCopies -r600 -dDEVICEWIDTHPOINTS=293.4 -dDEVICEHEIGHTPOINTS=432.0 -sOutputFile=-  -c .setpdfwrite -f -
D [24/Jan/2011:07:30:28 -0800] [Job 23] /usr/lib/cups/filter/pstoturboprint: line 53: kill: (7573) - No such process
D [24/Jan/2011:07:30:28 -0800] [Job 23] /usr/lib/cups/filter/pstoturboprint: line 56: kill: (7575) - No such process
D [24/Jan/2011:07:30:28 -0800] cupsdAcceptClient: 16 from localhost (Domain)
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:30:28 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 16 1.1 Get-Jobs 1
D [24/Jan/2011:07:30:28 -0800] Get-Jobs ipp://localhost/printers/
D [24/Jan/2011:07:30:28 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 16 WAITING Closing on EOF
D [24/Jan/2011:07:30:28 -0800] cupsdCloseClient: 16
D [24/Jan/2011:07:30:28 -0800] cupsdAcceptClient: 16 from localhost (Domain)
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:30:28 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 16 1.1 Get-Notifications 1
D [24/Jan/2011:07:30:28 -0800] Get-Notifications /
D [24/Jan/2011:07:30:28 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:30:28 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:30:28 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 16 1.1 Get-Job-Attributes 1
D [24/Jan/2011:07:30:28 -0800] Get-Job-Attributes ipp://localhost/jobs/23
D [24/Jan/2011:07:30:28 -0800] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/23) from localhost
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 16 WAITING Closing on EOF
D [24/Jan/2011:07:30:28 -0800] cupsdCloseClient: 16
D [24/Jan/2011:07:30:28 -0800] cupsdAcceptClient: 16 from localhost (Domain)
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:30:28 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 16 1.1 Get-Notifications 1
D [24/Jan/2011:07:30:28 -0800] Get-Notifications /
D [24/Jan/2011:07:30:28 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:30:28 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:30:28 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:30:28 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:30:28 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 16 WAITING Closing on EOF
D [24/Jan/2011:07:30:28 -0800] cupsdCloseClient: 16
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:30:28 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:30:28 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:30:28 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:30:28 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Classes 1
D [24/Jan/2011:07:30:28 -0800] CUPS-Get-Classes
D [24/Jan/2011:07:30:28 -0800] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:30:28 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:30:28 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Default 1
D [24/Jan/2011:07:30:28 -0800] CUPS-Get-Default
D [24/Jan/2011:07:30:28 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [24/Jan/2011:07:30:28 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:30:29 -0800] [Job 23] /usr/lib/cups/filter/pstoturboprint: line 73: kill: (7573) - No such process
D [24/Jan/2011:07:30:29 -0800] [Job 23] /usr/lib/cups/filter/pstoturboprint: line 94: /tpstdin: No such file or directory
D [24/Jan/2011:07:30:29 -0800] PID 7528 (/usr/lib/cups/filter/pstopdf) exited with no errors.
D [24/Jan/2011:07:30:29 -0800] PID 7529 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [24/Jan/2011:07:30:29 -0800] PID 7530 (/usr/lib/cups/filter/pstoturboprint) exited with no errors.
I [24/Jan/2011:07:30:29 -0800] [Job 23] Printer is idle
D [24/Jan/2011:07:30:29 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:30:29 -0800] PID 7532 (/usr/lib/cups/backend/tpu) exited with no errors.
D [24/Jan/2011:07:30:29 -0800] cupsdMarkDirty(-----S)
I [24/Jan/2011:07:30:29 -0800] [Job 23] Job completed.
D [24/Jan/2011:07:30:29 -0800] cupsdMarkDirty(----J-)
D [24/Jan/2011:07:30:29 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:30:30 -0800] cupsdAcceptClient: 15 from localhost (Domain)
D [24/Jan/2011:07:30:30 -0800] [Job 23] Unloading...
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 15 POST / HTTP/1.1
D [24/Jan/2011:07:30:30 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:30:30 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 15 1.1 Get-Notifications 1
D [24/Jan/2011:07:30:30 -0800] Get-Notifications /
D [24/Jan/2011:07:30:30 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:30:30 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:30:30 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:30:30 -0800] cupsdAcceptClient: 16 from localhost (Domain)
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:30:30 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:30:30 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 16 1.1 Get-Jobs 1
D [24/Jan/2011:07:30:30 -0800] Get-Jobs ipp://localhost/printers/
D [24/Jan/2011:07:30:30 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [24/Jan/2011:07:30:30 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 16 WAITING Closing on EOF
D [24/Jan/2011:07:30:30 -0800] cupsdCloseClient: 16
D [24/Jan/2011:07:30:30 -0800] cupsdAcceptClient: 16 from localhost (Domain)
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:30:30 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:30:30 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 16 1.1 Get-Notifications 1
D [24/Jan/2011:07:30:30 -0800] Get-Notifications /
D [24/Jan/2011:07:30:30 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:30:30 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:30:30 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:30:30 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:30:30 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:30:30 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:30:30 -0800] cupsdAcceptClient: 17 from localhost (Domain)
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 17 POST / HTTP/1.1
D [24/Jan/2011:07:30:30 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:30:30 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 17 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:30:30 -0800] Get-Printer-Attributes ipp://prometheus:0/printers/tp0
D [24/Jan/2011:07:30:30 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://prometheus:0/printers/tp0) from localhost
D [24/Jan/2011:07:30:30 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 16 WAITING Closing on EOF
D [24/Jan/2011:07:30:30 -0800] cupsdCloseClient: 16
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 15 WAITING Closing on EOF
D [24/Jan/2011:07:30:30 -0800] cupsdCloseClient: 15
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:30:30 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:30:30 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:30:30 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:30:30 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:30:30 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:30:30 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:30:30 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Classes 1
D [24/Jan/2011:07:30:30 -0800] CUPS-Get-Classes
D [24/Jan/2011:07:30:30 -0800] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [24/Jan/2011:07:30:30 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:30:30 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:30:30 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:30:30 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Default 1
D [24/Jan/2011:07:30:30 -0800] CUPS-Get-Default
D [24/Jan/2011:07:30:30 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [24/Jan/2011:07:30:30 -0800] cupsdSetBusyState: Dirty files
I [24/Jan/2011:07:30:59 -0800] Saving job cache file "/var/cache/cups/job.cache"...
I [24/Jan/2011:07:30:59 -0800] Saving subscriptions.conf...
D [24/Jan/2011:07:30:59 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 11 POST /printers/tp0 HTTP/1.1
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:33:03 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:03 -0800] Report: clients=3
D [24/Jan/2011:07:33:03 -0800] Report: jobs=23
D [24/Jan/2011:07:33:03 -0800] Report: jobs-active=0
D [24/Jan/2011:07:33:03 -0800] Report: printers=1
D [24/Jan/2011:07:33:03 -0800] Report: printers-implicit=0
D [24/Jan/2011:07:33:03 -0800] Report: stringpool-string-count=13865
D [24/Jan/2011:07:33:03 -0800] Report: stringpool-alloc-bytes=10104
D [24/Jan/2011:07:33:03 -0800] Report: stringpool-total-bytes=271016
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 11 1.1 Print-Job 1
D [24/Jan/2011:07:33:03 -0800] Print-Job ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:03 -0800] cupsdMarkDirty(----J-)
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:03 -0800] add_job: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:03 -0800] Adding default job-sheets values "none,none"...
I [24/Jan/2011:07:33:03 -0800] [Job 24] Adding start banner page "none".
D [24/Jan/2011:07:33:03 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:03 -0800] cupsdMarkDirty(----J-)
I [24/Jan/2011:07:33:03 -0800] [Job 24] Adding end banner page "none".
I [24/Jan/2011:07:33:03 -0800] [Job 24] File of type application/vnd.cups-command queued by "mhenry".
D [24/Jan/2011:07:33:03 -0800] [Job 24] hold_until=0
I [24/Jan/2011:07:33:03 -0800] [Job 24] Queued on "tp0" by "mhenry".
D [24/Jan/2011:07:33:03 -0800] cupsdMarkDirty(----J-)
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:03 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:03 -0800] [Job 24] job-sheets=none,none
D [24/Jan/2011:07:33:03 -0800] [Job 24] argv[0]="tp0"
D [24/Jan/2011:07:33:03 -0800] [Job 24] argv[1]="24"
D [24/Jan/2011:07:33:03 -0800] [Job 24] argv[2]="mhenry"
D [24/Jan/2011:07:33:03 -0800] [Job 24] argv[3]="Test Page"
D [24/Jan/2011:07:33:03 -0800] [Job 24] argv[4]="1"
D [24/Jan/2011:07:33:03 -0800] [Job 24] argv[5]="job-uuid=urn:uuid:14003700-7f78-31c5-6bce-a6453e7711a7 job-originating-host-name=localhost"
D [24/Jan/2011:07:33:03 -0800] [Job 24] argv[6]="/var/spool/cups/d00024-001"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[8]="HOME=/var/spool/cups/tmp"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[10]="SERVER_ADMIN=root@prometheus"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[11]="SOFTWARE=CUPS/1.4.4"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[13]="USER=root"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[16]="IPP_PORT=631"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[17]="CHARSET=utf-8"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[18]="LANG=en_US.UTF-8"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[19]="PPD=/etc/cups/ppd/tp0.ppd"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[20]="RIP_MAX_CACHE=auto"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[21]="CONTENT_TYPE=application/vnd.cups-command"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[22]="DEVICE_URI=tpu://Canon/Pro9500II_series"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[23]="PRINTER_INFO=Canon_PIXMA_Pro9500II"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[24]="PRINTER_LOCATION="
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[25]="PRINTER=tp0"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[26]="CUPS_FILETYPE=document"
D [24/Jan/2011:07:33:03 -0800] [Job 24] envp[27]="FINAL_CONTENT_TYPE=printer/tp0"
I [24/Jan/2011:07:33:03 -0800] [Job 24] Started filter /usr/lib/cups/filter/commandtoturboprint (PID 7608)
I [24/Jan/2011:07:33:03 -0800] [Job 24] Started backend /usr/lib/cups/backend/tpu (PID 7609)
D [24/Jan/2011:07:33:03 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:03 -0800] Returning IPP successful-ok for Print-Job (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:03 -0800] [Job 24] /usr/lib/cups/filter/pstoturboprint: line 37: --incognito: command not found
D [24/Jan/2011:07:33:03 -0800] [Job 24] /usr/lib/cups/filter/pstoturboprint: line 111: /turboprint/print.log: No such file or directory
D [24/Jan/2011:07:33:03 -0800] [Job 24] /usr/lib/cups/filter/pstoturboprint: line 154: /tpstdin: No such file or directory
D [24/Jan/2011:07:33:03 -0800] [Job 24] ldd: /tpprint: No such file or directory
D [24/Jan/2011:07:33:03 -0800] [Job 24] /usr/lib/cups/filter/pstoturboprint: line 208: /tpprint: No such file or directory
D [24/Jan/2011:07:33:03 -0800] [Job 24] cat: /pstoturboprint7608.var: No such file or directory
D [24/Jan/2011:07:33:03 -0800] [Job 24] cat: /pstoturboprint7608.var: No such file or directory
D [24/Jan/2011:07:33:03 -0800] [Job 24] /usr/lib/cups/filter/pstoturboprint: line 240: /tpstdin: No such file or directory
D [24/Jan/2011:07:33:03 -0800] [Job 24] /usr/lib/cups/filter/pstoturboprint: line 314: [: -ne: unary operator expected
D [24/Jan/2011:07:33:03 -0800] [Job 24] /usr/lib/cups/filter/pstoturboprint: line 356: [: -ne: unary operator expected
D [24/Jan/2011:07:33:03 -0800] [Job 24] /usr/lib/cups/filter/pstoturboprint: line 360: *  : syntax error: operand expected (error token is "*  ")
D [24/Jan/2011:07:33:03 -0800] [Job 24] /usr/lib/cups/filter/pstoturboprint: line 361: [: -gt: unary operator expected
D [24/Jan/2011:07:33:03 -0800] [Job 24] mkfifo: cannot create fifo `/pstoturboprint7608.fifo': Permission denied
D [24/Jan/2011:07:33:03 -0800] [Job 24] /usr/lib/cups/filter/pstoturboprint: line 433: /tpprint: No such file or directory
D [24/Jan/2011:07:33:03 -0800] [Job 24] /usr/lib/cups/filter/pstoturboprint: line 441: /tpstdin: No such file or directory
D [24/Jan/2011:07:33:03 -0800] [Job 24] /usr/lib/cups/filter/pstoturboprint: line 441: /pstoturboprint7608.fifo: Permission denied
D [24/Jan/2011:07:33:03 -0800] [Job 24] /usr/lib/cups/filter/pstoturboprint: line 53: kill: (7630) - No such process
D [24/Jan/2011:07:33:03 -0800] [Job 24] /usr/lib/cups/filter/pstoturboprint: line 56: kill: (7632) - No such process
D [24/Jan/2011:07:33:03 -0800] cupsdAcceptClient: 16 from localhost (Domain)
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:03 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 16 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:03 -0800] Get-Jobs ipp://localhost/printers/
D [24/Jan/2011:07:33:03 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 16 WAITING Closing on EOF
D [24/Jan/2011:07:33:03 -0800] cupsdCloseClient: 16
D [24/Jan/2011:07:33:03 -0800] cupsdAcceptClient: 16 from localhost (Domain)
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:03 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 16 1.1 Get-Notifications 1
D [24/Jan/2011:07:33:03 -0800] Get-Notifications /
D [24/Jan/2011:07:33:03 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:03 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:03 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 16 1.1 Get-Job-Attributes 1
D [24/Jan/2011:07:33:03 -0800] Get-Job-Attributes ipp://localhost/jobs/24
D [24/Jan/2011:07:33:03 -0800] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/24) from localhost
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 16 WAITING Closing on EOF
D [24/Jan/2011:07:33:03 -0800] cupsdCloseClient: 16
D [24/Jan/2011:07:33:03 -0800] cupsdAcceptClient: 16 from localhost (Domain)
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:03 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 16 1.1 Get-Notifications 1
D [24/Jan/2011:07:33:03 -0800] Get-Notifications /
D [24/Jan/2011:07:33:03 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:03 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:03 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:03 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:03 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 16 WAITING Closing on EOF
D [24/Jan/2011:07:33:03 -0800] cupsdCloseClient: 16
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:03 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:33:03 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:33:03 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:03 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Classes 1
D [24/Jan/2011:07:33:03 -0800] CUPS-Get-Classes
D [24/Jan/2011:07:33:03 -0800] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:03 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:03 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Default 1
D [24/Jan/2011:07:33:03 -0800] CUPS-Get-Default
D [24/Jan/2011:07:33:03 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [24/Jan/2011:07:33:03 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:04 -0800] [Job 24] /usr/lib/cups/filter/pstoturboprint: line 73: kill: (7630) - No such process
D [24/Jan/2011:07:33:04 -0800] [Job 24] /usr/lib/cups/filter/pstoturboprint: line 94: /tpstdin: No such file or directory
D [24/Jan/2011:07:33:04 -0800] PID 7608 (/usr/lib/cups/filter/commandtoturboprint) exited with no errors.
I [24/Jan/2011:07:33:04 -0800] [Job 24] Printer is idle
D [24/Jan/2011:07:33:04 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:05 -0800] PID 7609 (/usr/lib/cups/backend/tpu) exited with no errors.
D [24/Jan/2011:07:33:05 -0800] cupsdMarkDirty(-----S)
I [24/Jan/2011:07:33:05 -0800] [Job 24] Job completed.
D [24/Jan/2011:07:33:05 -0800] cupsdMarkDirty(----J-)
D [24/Jan/2011:07:33:05 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:05 -0800] cupsdAcceptClient: 15 from localhost (Domain)
D [24/Jan/2011:07:33:05 -0800] cupsdAcceptClient: 16 from localhost (Domain)
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 15 POST / HTTP/1.1
D [24/Jan/2011:07:33:05 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:05 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:33:05 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 15 1.1 Get-Notifications 1
D [24/Jan/2011:07:33:05 -0800] Get-Notifications /
D [24/Jan/2011:07:33:05 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:05 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 16 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:05 -0800] Get-Jobs ipp://localhost/printers/
D [24/Jan/2011:07:33:05 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [24/Jan/2011:07:33:05 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 16 WAITING Closing on EOF
D [24/Jan/2011:07:33:05 -0800] cupsdCloseClient: 16
D [24/Jan/2011:07:33:05 -0800] cupsdAcceptClient: 16 from localhost (Domain)
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:33:05 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:05 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 16 1.1 Get-Notifications 1
D [24/Jan/2011:07:33:05 -0800] Get-Notifications /
D [24/Jan/2011:07:33:05 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:05 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:33:05 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:05 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:05 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:05 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:05 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:05 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:05 -0800] cupsdAcceptClient: 18 from localhost (Domain)
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 18 POST / HTTP/1.1
D [24/Jan/2011:07:33:05 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:05 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 18 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:05 -0800] Get-Printer-Attributes ipp://prometheus:0/printers/tp0
D [24/Jan/2011:07:33:05 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://prometheus:0/printers/tp0) from localhost
D [24/Jan/2011:07:33:05 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 16 WAITING Closing on EOF
D [24/Jan/2011:07:33:05 -0800] cupsdCloseClient: 16
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 15 WAITING Closing on EOF
D [24/Jan/2011:07:33:05 -0800] cupsdCloseClient: 15
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:05 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:05 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:33:05 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:33:05 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:33:05 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:05 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:05 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Classes 1
D [24/Jan/2011:07:33:05 -0800] CUPS-Get-Classes
D [24/Jan/2011:07:33:05 -0800] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [24/Jan/2011:07:33:05 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:05 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:05 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:05 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Default 1
D [24/Jan/2011:07:33:05 -0800] CUPS-Get-Default
D [24/Jan/2011:07:33:05 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [24/Jan/2011:07:33:05 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:06 -0800] [Job 24] Unloading...
D [24/Jan/2011:07:33:08 -0800] cupsdAcceptClient: 15 from localhost (Domain)
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:33:08 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 15 POST /printers/tp0 HTTP/1.1
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:08 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 15 1.1 Print-Job 1
D [24/Jan/2011:07:33:08 -0800] Print-Job ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:08 -0800] [Job ???] Auto-typing file...
I [24/Jan/2011:07:33:08 -0800] [Job ???] Request file type is application/vnd.cups-banner.
D [24/Jan/2011:07:33:08 -0800] cupsdMarkDirty(----J-)
D [24/Jan/2011:07:33:08 -0800] add_job: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:08 -0800] Adding default job-sheets values "none,none"...
I [24/Jan/2011:07:33:08 -0800] [Job 25] Adding start banner page "none".
D [24/Jan/2011:07:33:08 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:08 -0800] cupsdMarkDirty(----J-)
I [24/Jan/2011:07:33:08 -0800] [Job 25] Adding end banner page "none".
I [24/Jan/2011:07:33:08 -0800] [Job 25] File of type application/vnd.cups-banner queued by "mhenry".
D [24/Jan/2011:07:33:08 -0800] [Job 25] hold_until=0
I [24/Jan/2011:07:33:08 -0800] [Job 25] Queued on "tp0" by "mhenry".
D [24/Jan/2011:07:33:08 -0800] cupsdMarkDirty(----J-)
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:08 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:08 -0800] [Job 25] job-sheets=none,none
D [24/Jan/2011:07:33:08 -0800] [Job 25] argv[0]="tp0"
D [24/Jan/2011:07:33:08 -0800] [Job 25] argv[1]="25"
D [24/Jan/2011:07:33:08 -0800] [Job 25] argv[2]="mhenry"
D [24/Jan/2011:07:33:08 -0800] [Job 25] argv[3]="Test Page"
D [24/Jan/2011:07:33:08 -0800] [Job 25] argv[4]="1"
D [24/Jan/2011:07:33:08 -0800] [Job 25] argv[5]="job-uuid=urn:uuid:2662534a-e7c7-3dbd-4af5-20723704c4ce job-originating-host-name=localhost"
D [24/Jan/2011:07:33:08 -0800] [Job 25] argv[6]="/var/spool/cups/d00025-001"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[8]="HOME=/var/spool/cups/tmp"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[10]="SERVER_ADMIN=root@prometheus"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[11]="SOFTWARE=CUPS/1.4.4"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[13]="USER=root"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[16]="IPP_PORT=631"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[17]="CHARSET=utf-8"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[18]="LANG=en_US.UTF-8"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[19]="PPD=/etc/cups/ppd/tp0.ppd"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[20]="RIP_MAX_CACHE=auto"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[21]="CONTENT_TYPE=application/vnd.cups-banner"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[22]="DEVICE_URI=tpu://Canon/Pro9500II_series"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[23]="PRINTER_INFO=Canon_PIXMA_Pro9500II"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[24]="PRINTER_LOCATION="
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[25]="PRINTER=tp0"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[26]="CUPS_FILETYPE=document"
D [24/Jan/2011:07:33:08 -0800] [Job 25] envp[27]="FINAL_CONTENT_TYPE=printer/tp0"
I [24/Jan/2011:07:33:08 -0800] [Job 25] Started filter /usr/lib/cups/filter/bannertops (PID 7649)
I [24/Jan/2011:07:33:08 -0800] [Job 25] Started filter /usr/lib/cups/filter/pstopdf (PID 7650)
I [24/Jan/2011:07:33:08 -0800] [Job 25] Started filter /usr/lib/cups/filter/pdftopdf (PID 7651)
I [24/Jan/2011:07:33:08 -0800] [Job 25] Started filter /usr/lib/cups/filter/pstoturboprint (PID 7652)
I [24/Jan/2011:07:33:08 -0800] [Job 25] Started backend /usr/lib/cups/backend/tpu (PID 7653)
D [24/Jan/2011:07:33:08 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:08 -0800] Returning IPP successful-ok for Print-Job (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:08 -0800] [Job 25] pstopdf 5 args: 25 mhenry Test Page 1 job-uuid=urn:uuid:2662534a-e7c7-3dbd-4af5-20723704c4ce job-originating-host-name=localhost
D [24/Jan/2011:07:33:08 -0800] [Job 25] PPD: /etc/cups/ppd/tp0.ppd
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:08 -0800] [Job 25] load_banner(filename="/var/spool/cups/d00025-001")
D [24/Jan/2011:07:33:08 -0800] [Job 25] Page = 293x432; 0,0 to 293,432
D [24/Jan/2011:07:33:08 -0800] [Job 25] /usr/lib/cups/filter/pstoturboprint: line 37: --incognito: command not found
D [24/Jan/2011:07:33:08 -0800] [Job 25] /usr/lib/cups/filter/pstoturboprint: line 111: /turboprint/print.log: No such file or directory
D [24/Jan/2011:07:33:08 -0800] [Job 25] PNG image: 128x128x8, color_type=6 (RGB+ALPHA)
D [24/Jan/2011:07:33:08 -0800] [Job 25] PNG image: 192x128x8, color_type=2 (RGB)
D [24/Jan/2011:07:33:08 -0800] [Job 25] /usr/lib/cups/filter/pstoturboprint: line 151: /tpstdin: No such file or directory
D [24/Jan/2011:07:33:08 -0800] PID 7649 (/usr/lib/cups/filter/bannertops) exited with no errors.
D [24/Jan/2011:07:33:08 -0800] [Job 25] Resolution: 601x600
D [24/Jan/2011:07:33:08 -0800] [Job 25] ldd: /tpprint: No such file or directory
D [24/Jan/2011:07:33:08 -0800] [Job 25] /usr/lib/cups/filter/pstoturboprint: line 208: /tpprint: No such file or directory
D [24/Jan/2011:07:33:08 -0800] [Job 25] Page size: 4x6inBorderless
D [24/Jan/2011:07:33:08 -0800] [Job 25] cat: /pstoturboprint7652.var: No such file or directory
D [24/Jan/2011:07:33:08 -0800] [Job 25] cat: /pstoturboprint7652.var: No such file or directory
D [24/Jan/2011:07:33:08 -0800] [Job 25] /usr/lib/cups/filter/pstoturboprint: line 240: /tpstdin: No such file or directory
D [24/Jan/2011:07:33:08 -0800] [Job 25] /usr/lib/cups/filter/pstoturboprint: line 314: [: -ne: unary operator expected
D [24/Jan/2011:07:33:08 -0800] [Job 25] Width: 293.4, height: 432.0, absolute margins: 0.0, 0.0, 293.4, 432.0
D [24/Jan/2011:07:33:08 -0800] [Job 25] Relative margins: 0.0, 0.0, 0, 0
D [24/Jan/2011:07:33:08 -0800] [Job 25] PPD options: -r600 -dDEVICEWIDTHPOINTS=293.4 -dDEVICEHEIGHTPOINTS=432.0
D [24/Jan/2011:07:33:08 -0800] [Job 25] PostScript to be injected: 
D [24/Jan/2011:07:33:08 -0800] [Job 25] Running cat | /usr/bin/gs -q -dNOPAUSE -dBATCH -sDEVICE=pdfwrite -dCompatibilityLevel=1.3 -dAutoRotatePages=/None -dAutoFilterColorImages=false                -dNOPLATFONTS -dPARANOIDSAFER -sstdout=%stderr -dColorImageFilter=/FlateEncode                 -dPDFSETTINGS=/printer                 -dColorConversionStrategy=/LeaveColorUnchanged -dDoNumCopies -r600 -dDEVICEWIDTHPOINTS=293.4 -dDEVICEHEIGHTPOINTS=432.0 -sOutputFile=-  -c .setpdfwrite -f -
D [24/Jan/2011:07:33:08 -0800] [Job 25] /usr/lib/cups/filter/pstoturboprint: line 356: [: -ne: unary operator expected
D [24/Jan/2011:07:33:08 -0800] [Job 25] /usr/lib/cups/filter/pstoturboprint: line 360: *  : syntax error: operand expected (error token is "*  ")
D [24/Jan/2011:07:33:08 -0800] [Job 25] /usr/lib/cups/filter/pstoturboprint: line 361: [: -gt: unary operator expected
D [24/Jan/2011:07:33:08 -0800] [Job 25] mkfifo: cannot create fifo `/pstoturboprint7652.fifo': Permission denied
D [24/Jan/2011:07:33:08 -0800] [Job 25] /usr/lib/cups/filter/pstoturboprint: line 433: /tpprint: No such file or directory
D [24/Jan/2011:07:33:08 -0800] [Job 25] /usr/lib/cups/filter/pstoturboprint: line 441: /tpstdin: No such file or directory
D [24/Jan/2011:07:33:08 -0800] [Job 25] /usr/lib/cups/filter/pstoturboprint: line 441: /pstoturboprint7652.fifo: Permission denied
D [24/Jan/2011:07:33:08 -0800] [Job 25] /usr/lib/cups/filter/pstoturboprint: line 53: kill: (7698) - No such process
D [24/Jan/2011:07:33:08 -0800] [Job 25] /usr/lib/cups/filter/pstoturboprint: line 56: kill: (7700) - No such process
D [24/Jan/2011:07:33:08 -0800] cupsdAcceptClient: 16 from localhost (Domain)
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:08 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 16 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:08 -0800] Get-Jobs ipp://localhost/printers/
D [24/Jan/2011:07:33:08 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 16 WAITING Closing on EOF
D [24/Jan/2011:07:33:08 -0800] cupsdCloseClient: 16
D [24/Jan/2011:07:33:08 -0800] cupsdAcceptClient: 16 from localhost (Domain)
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:08 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 16 1.1 Get-Notifications 1
D [24/Jan/2011:07:33:08 -0800] Get-Notifications /
D [24/Jan/2011:07:33:08 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:08 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:08 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 16 1.1 Get-Job-Attributes 1
D [24/Jan/2011:07:33:08 -0800] Get-Job-Attributes ipp://localhost/jobs/25
D [24/Jan/2011:07:33:08 -0800] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/25) from localhost
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 16 WAITING Closing on EOF
D [24/Jan/2011:07:33:08 -0800] cupsdCloseClient: 16
D [24/Jan/2011:07:33:08 -0800] cupsdAcceptClient: 16 from localhost (Domain)
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:08 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 16 1.1 Get-Notifications 1
D [24/Jan/2011:07:33:08 -0800] Get-Notifications /
D [24/Jan/2011:07:33:08 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:08 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:08 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:08 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:08 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 16 WAITING Closing on EOF
D [24/Jan/2011:07:33:08 -0800] cupsdCloseClient: 16
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:08 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:33:08 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:33:08 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:08 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Classes 1
D [24/Jan/2011:07:33:08 -0800] CUPS-Get-Classes
D [24/Jan/2011:07:33:08 -0800] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:08 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:08 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Default 1
D [24/Jan/2011:07:33:08 -0800] CUPS-Get-Default
D [24/Jan/2011:07:33:08 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [24/Jan/2011:07:33:08 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:09 -0800] PID 7650 (/usr/lib/cups/filter/pstopdf) exited with no errors.
D [24/Jan/2011:07:33:09 -0800] PID 7651 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [24/Jan/2011:07:33:09 -0800] [Job 25] /usr/lib/cups/filter/pstoturboprint: line 73: kill: (7698) - No such process
D [24/Jan/2011:07:33:09 -0800] [Job 25] /usr/lib/cups/filter/pstoturboprint: line 94: /tpstdin: No such file or directory
I [24/Jan/2011:07:33:09 -0800] [Job 25] Printer is idle
D [24/Jan/2011:07:33:09 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:09 -0800] PID 7652 (/usr/lib/cups/filter/pstoturboprint) exited with no errors.
D [24/Jan/2011:07:33:09 -0800] cupsdAcceptClient: 16 from localhost (Domain)
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 16 POST /jobs/ HTTP/1.1
D [24/Jan/2011:07:33:09 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:09 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 16 1.1 Get-Job-Attributes 1
D [24/Jan/2011:07:33:09 -0800] Get-Job-Attributes ipp://localhost:631/jobs/25
D [24/Jan/2011:07:33:09 -0800] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost:631/jobs/25) from localhost
D [24/Jan/2011:07:33:09 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 16 WAITING Closing on EOF
D [24/Jan/2011:07:33:09 -0800] cupsdCloseClient: 16
D [24/Jan/2011:07:33:09 -0800] PID 7653 (/usr/lib/cups/backend/tpu) exited with no errors.
D [24/Jan/2011:07:33:09 -0800] cupsdMarkDirty(-----S)
I [24/Jan/2011:07:33:09 -0800] [Job 25] Job completed.
D [24/Jan/2011:07:33:09 -0800] cupsdMarkDirty(----J-)
D [24/Jan/2011:07:33:09 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:09 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 13 POST / HTTP/1.1
D [24/Jan/2011:07:33:09 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:09 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 13 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:09 -0800] Get-Jobs ipp://localhost/printers/
D [24/Jan/2011:07:33:09 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [24/Jan/2011:07:33:09 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:33:09 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:33:09 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 13 POST / HTTP/1.1
D [24/Jan/2011:07:33:09 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:09 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:09 -0800] cupsdAcceptClient: 16 from localhost (Domain)
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:33:09 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 13 1.1 Get-Notifications 1
D [24/Jan/2011:07:33:09 -0800] Get-Notifications /
D [24/Jan/2011:07:33:09 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:09 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 16 1.1 Get-Notifications 1
D [24/Jan/2011:07:33:09 -0800] Get-Notifications /
D [24/Jan/2011:07:33:09 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:09 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:33:09 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:09 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:09 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:09 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:09 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:09 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:09 -0800] cupsdAcceptClient: 19 from localhost (Domain)
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 19 POST / HTTP/1.1
D [24/Jan/2011:07:33:09 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:09 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:09 -0800] Get-Printer-Attributes ipp://prometheus:0/printers/tp0
D [24/Jan/2011:07:33:09 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://prometheus:0/printers/tp0) from localhost
D [24/Jan/2011:07:33:09 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:33:09 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 16 WAITING Closing on EOF
D [24/Jan/2011:07:33:09 -0800] cupsdCloseClient: 16
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:09 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:09 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:33:09 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:33:09 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:33:09 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:09 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:09 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Classes 1
D [24/Jan/2011:07:33:09 -0800] CUPS-Get-Classes
D [24/Jan/2011:07:33:09 -0800] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [24/Jan/2011:07:33:09 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:09 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:09 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:09 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Default 1
D [24/Jan/2011:07:33:09 -0800] CUPS-Get-Default
D [24/Jan/2011:07:33:09 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [24/Jan/2011:07:33:09 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:10 -0800] [Job 25] Unloading...
D [24/Jan/2011:07:33:25 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:33:25 -0800] cupsdReadClient: 13 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:25 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:25 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:25 -0800] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:33:25 -0800] CUPS-Get-Printers 
D [24/Jan/2011:07:33:25 -0800] Returning IPP successful-ok for CUPS-Get-Printers () from localhost
D [24/Jan/2011:07:33:25 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:25 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:33:25 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:33:25 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:33:25 -0800] cupsdReadClient: 13 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:25 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:25 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:25 -0800] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:33:25 -0800] CUPS-Get-Printers 
D [24/Jan/2011:07:33:25 -0800] Returning IPP successful-ok for CUPS-Get-Printers () from localhost
D [24/Jan/2011:07:33:25 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:25 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:33:25 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:33:26 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:33:26 -0800] cupsdReadClient: 13 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:26 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:26 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:26 -0800] cupsdReadClient: 13 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:26 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:26 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:26 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:26 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:33:26 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:33:26 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:33:26 -0800] cupsdReadClient: 13 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:26 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:26 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:26 -0800] cupsdReadClient: 13 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:26 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:26 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:26 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:26 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:33:26 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:33:26 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:33:26 -0800] cupsdReadClient: 13 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:26 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:26 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:26 -0800] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:33:26 -0800] CUPS-Get-Printers 
D [24/Jan/2011:07:33:26 -0800] Returning IPP successful-ok for CUPS-Get-Printers () from localhost
D [24/Jan/2011:07:33:26 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:26 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:33:26 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:33:27 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:33:27 -0800] cupsdReadClient: 13 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:27 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:27 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:27 -0800] cupsdReadClient: 13 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:27 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:27 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:27 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:27 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:33:27 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:33:29 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:33:29 -0800] cupsdAcceptClient: 16 from localhost (Domain)
D [24/Jan/2011:07:33:29 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:33:29 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:29 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:29 -0800] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:33:29 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:33:29 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:33:29 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:29 -0800] cupsdReadClient: 16 POST / HTTP/1.1
D [24/Jan/2011:07:33:29 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:29 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:29 -0800] cupsdReadClient: 16 1.1 CUPS-Get-Default 1
D [24/Jan/2011:07:33:29 -0800] CUPS-Get-Default
D [24/Jan/2011:07:33:29 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [24/Jan/2011:07:33:29 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:29 -0800] cupsdReadClient: 13 POST / HTTP/1.1
D [24/Jan/2011:07:33:29 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:29 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:29 -0800] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
D [24/Jan/2011:07:33:29 -0800] CUPS-Get-Default
D [24/Jan/2011:07:33:29 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [24/Jan/2011:07:33:29 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:29 -0800] cupsdReadClient: 13 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:29 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:29 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:29 -0800] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:33:29 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:33:29 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:33:29 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:30 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:30 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:30 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:30 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:30 -0800] cupsdReadClient: 20 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:30 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:30 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:30 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:30 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:30 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:30 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:30 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:30 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:30 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:30 -0800] cupsdReadClient: 20 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:30 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:30 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:30 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:30 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:30 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:31 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:31 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:31 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:31 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:31 -0800] cupsdReadClient: 20 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:31 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:31 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:31 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:31 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:31 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:34 -0800] cupsdAcceptClient: 20 from localhost (Domain)
I [24/Jan/2011:07:33:34 -0800] Saving job cache file "/var/cache/cups/job.cache"...
I [24/Jan/2011:07:33:34 -0800] Saving subscriptions.conf...
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:33:34 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 20 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:34 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:34 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:34 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:34 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 20 POST / HTTP/1.1
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:33:34 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 20 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:34 -0800] Get-Printer-Attributes ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:34 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 20 POST /printers/tp0 HTTP/1.1
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Active clients
D [24/Jan/2011:07:33:34 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 20 1.1 Create-Job 1
D [24/Jan/2011:07:33:34 -0800] Create-Job ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:34 -0800] cupsdMarkDirty(----J-)
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:34 -0800] add_job: requesting-user-name="root"
I [24/Jan/2011:07:33:34 -0800] [Job 26] Adding start banner page "none".
D [24/Jan/2011:07:33:34 -0800] cupsdMarkDirty(-----S)
I [24/Jan/2011:07:33:34 -0800] [Job 26] Queued on "tp0" by "root".
D [24/Jan/2011:07:33:34 -0800] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 20 POST /printers/tp0 HTTP/1.1
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:34 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 20 1.1 Send-Document 1
D [24/Jan/2011:07:33:34 -0800] Send-Document ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:34 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [24/Jan/2011:07:33:34 -0800] [Job 26] Auto-typing file...
D [24/Jan/2011:07:33:34 -0800] [Job 26] Request file type is application/postscript.
D [24/Jan/2011:07:33:34 -0800] cupsdMarkDirty(----J-)
I [24/Jan/2011:07:33:34 -0800] [Job 26] File of type application/postscript queued by "root".
I [24/Jan/2011:07:33:34 -0800] [Job 26] Adding end banner page "none".
D [24/Jan/2011:07:33:34 -0800] cupsdMarkDirty(----J-)
D [24/Jan/2011:07:33:34 -0800] cupsdMarkDirty(----J-)
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:34 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:34 -0800] [Job 26] job-sheets=none,none
D [24/Jan/2011:07:33:34 -0800] [Job 26] argv[0]="tp0"
D [24/Jan/2011:07:33:34 -0800] [Job 26] argv[1]="26"
D [24/Jan/2011:07:33:34 -0800] [Job 26] argv[2]="root"
D [24/Jan/2011:07:33:34 -0800] [Job 26] argv[3]="TurboPrint Testpage"
D [24/Jan/2011:07:33:34 -0800] [Job 26] argv[4]="1"
D [24/Jan/2011:07:33:34 -0800] [Job 26] argv[5]="columns=1 cpi=10 finishings=3 InputSlot=AutomaticFeed lpi=6 media=4x6inBorderless MediaType=CanonPhotoPlusGlossyPP101_5 number-up=1 Resolution=601x600dpi sides=one-sided zedoAdjustC=0 zedoAdjustK=0 zedoAdjustM=0 zedoAdjustY=0 zedoBorderlessExpand=2 zedoBrightC=0 zedoBrightM=0 zedoBrightness=0 zedoCMYKspace=0 zedoColorModel=RGB zedoColorspace=0 zedoContrast=0 zedoDithering=ErrorDiffusion zedoDuplexAdjust=0 zedoDuplexadvanced=manual zedoGamut=0 zedoGreyToneA=0 zedoGreyToneB=0 zedoInklimitC=0 zedoInklimitK=0 zedoInklimitM=0 zedoInklimitY=0 zedoIntensity=0 zedoIntent=perception_0 zedoMirror=0 zedoPreview=preview-off zedoRefLight=65 zedoSettingA=0 zedoSettingB=0 zedoSettingC=0 zedoSettingD=0 zedoSettingE=0 zedoShadows=0 zedoTAC=0 zedoUCR=0 zedoUserColor=0 job-uuid=urn:uuid:d53b7414-1c6d-3b8e-6b87-a45e0050e883 job-originating-host-name=localhost"
D [24/Jan/2011:07:33:34 -0800] [Job 26] argv[6]="/var/spool/cups/d00026-001"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[8]="HOME=/var/spool/cups/tmp"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[10]="SERVER_ADMIN=root@prometheus"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[11]="SOFTWARE=CUPS/1.4.4"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[13]="USER=root"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[16]="IPP_PORT=631"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[17]="CHARSET=utf-8"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[18]="LANG=en_US.UTF-8"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[19]="PPD=/etc/cups/ppd/tp0.ppd"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[20]="RIP_MAX_CACHE=auto"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[21]="CONTENT_TYPE=application/postscript"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[22]="DEVICE_URI=tpu://Canon/Pro9500II_series"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[23]="PRINTER_INFO=Canon_PIXMA_Pro9500II"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[24]="PRINTER_LOCATION="
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[25]="PRINTER=tp0"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[26]="CUPS_FILETYPE=document"
D [24/Jan/2011:07:33:34 -0800] [Job 26] envp[27]="FINAL_CONTENT_TYPE=printer/tp0"
I [24/Jan/2011:07:33:34 -0800] [Job 26] Started filter /usr/lib/cups/filter/pstopdf (PID 7754)
I [24/Jan/2011:07:33:34 -0800] [Job 26] Started filter /usr/lib/cups/filter/pdftopdf (PID 7755)
I [24/Jan/2011:07:33:34 -0800] [Job 26] Started filter /usr/lib/cups/filter/pstoturboprint (PID 7756)
I [24/Jan/2011:07:33:34 -0800] [Job 26] Started backend /usr/lib/cups/backend/tpu (PID 7757)
D [24/Jan/2011:07:33:34 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:34 -0800] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:34 -0800] [Job 26] pstopdf 6 args: 26 root TurboPrint Testpage 1 columns=1 cpi=10 finishings=3 InputSlot=AutomaticFeed lpi=6 media=4x6inBorderless MediaType=CanonPhotoPlusGlossyPP101_5 number-up=1 Resolution=601x600dpi sides=one-sided zedoAdjustC=0 zedoAdjustK=0 zedoAdjustM=0 zedoAdjustY=0 zedoBorderlessExpand=2 zedoBrightC=0 zedoBrightM=0 zedoBrightness=0 zedoCMYKspace=0 zedoColorModel=RGB zedoColorspace=0 zedoContrast=0 zedoDithering=ErrorDiffusion zedoDuplexAdjust=0 zedoDuplexadvanced=manual zedoGamut=0 zedoGreyToneA=0 zedoGreyToneB=0 zedoInklimitC=0 zedoInklimitK=0 zedoInklimitM=0 zedoInklimitY=0 zedoIntensity=0 zedoIntent=perception_0 zedoMirror=0 zedoPreview=preview-off zedoRefLight=65 zedoSettingA=0 zedoSettingB=0 zedoSettingC=0 zedoSettingD=0 zedoSettingE=0 zedoShadows=0 zedoTAC=0 zedoUCR=0 zedoUserColor=0 job-uuid=urn:uuid:d53b7414-1c6d-3b8e-6b87-a45e0050e883 job-originating-host-name=localhost /var/spool/cups/d00026-001
D [24/Jan/2011:07:33:34 -0800] [Job 26] PPD: /etc/cups/ppd/tp0.ppd
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:34 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:34 -0800] [Job 26] /usr/lib/cups/filter/pstoturboprint: line 37: --incognito: command not found
D [24/Jan/2011:07:33:34 -0800] [Job 26] /usr/lib/cups/filter/pstoturboprint: line 111: /turboprint/print.log: No such file or directory
D [24/Jan/2011:07:33:34 -0800] [Job 26] Resolution: 601x600
D [24/Jan/2011:07:33:34 -0800] [Job 26] /usr/lib/cups/filter/pstoturboprint: line 151: /tpstdin: No such file or directory
D [24/Jan/2011:07:33:34 -0800] [Job 26] Page size: 4x6inBorderless
D [24/Jan/2011:07:33:34 -0800] [Job 26] ldd: /tpprint: No such file or directory
D [24/Jan/2011:07:33:34 -0800] [Job 26] Width: 293.4, height: 432.0, absolute margins: 0.0, 0.0, 293.4, 432.0
D [24/Jan/2011:07:33:34 -0800] [Job 26] Relative margins: 0.0, 0.0, 0, 0
D [24/Jan/2011:07:33:34 -0800] [Job 26] PPD options: -r600 -dDEVICEWIDTHPOINTS=293.4 -dDEVICEHEIGHTPOINTS=432.0
D [24/Jan/2011:07:33:34 -0800] [Job 26] PostScript to be injected: 
D [24/Jan/2011:07:33:34 -0800] [Job 26] Running cat | /usr/bin/gs -q -dNOPAUSE -dBATCH -sDEVICE=pdfwrite -dCompatibilityLevel=1.3 -dAutoRotatePages=/None -dAutoFilterColorImages=false                -dNOPLATFONTS -dPARANOIDSAFER -sstdout=%stderr -dColorImageFilter=/FlateEncode                 -dPDFSETTINGS=/printer                 -dColorConversionStrategy=/LeaveColorUnchanged -dDoNumCopies -r600 -dDEVICEWIDTHPOINTS=293.4 -dDEVICEHEIGHTPOINTS=432.0 -sOutputFile=-  -c .setpdfwrite -f -
D [24/Jan/2011:07:33:34 -0800] [Job 26] /usr/lib/cups/filter/pstoturboprint: line 208: /tpprint: No such file or directory
D [24/Jan/2011:07:33:34 -0800] [Job 26] cat: /pstoturboprint7756.var: No such file or directory
D [24/Jan/2011:07:33:34 -0800] [Job 26] cat: /pstoturboprint7756.var: No such file or directory
D [24/Jan/2011:07:33:34 -0800] [Job 26] /usr/lib/cups/filter/pstoturboprint: line 240: /tpstdin: No such file or directory
D [24/Jan/2011:07:33:34 -0800] [Job 26] /usr/lib/cups/filter/pstoturboprint: line 314: [: -ne: unary operator expected
D [24/Jan/2011:07:33:34 -0800] [Job 26] /usr/lib/cups/filter/pstoturboprint: line 356: [: -ne: unary operator expected
D [24/Jan/2011:07:33:34 -0800] [Job 26] /usr/lib/cups/filter/pstoturboprint: line 360: *  : syntax error: operand expected (error token is "*  ")
D [24/Jan/2011:07:33:34 -0800] [Job 26] /usr/lib/cups/filter/pstoturboprint: line 361: [: -gt: unary operator expected
D [24/Jan/2011:07:33:34 -0800] [Job 26] mkfifo: cannot create fifo `/pstoturboprint7756.fifo': Permission denied
D [24/Jan/2011:07:33:34 -0800] [Job 26] /usr/lib/cups/filter/pstoturboprint: line 441: /tpstdin: No such file or directory
D [24/Jan/2011:07:33:34 -0800] [Job 26] /usr/lib/cups/filter/pstoturboprint: line 433: /tpprint: No such file or directory
D [24/Jan/2011:07:33:34 -0800] [Job 26] /usr/lib/cups/filter/pstoturboprint: line 441: /pstoturboprint7756.fifo: Permission denied
D [24/Jan/2011:07:33:34 -0800] [Job 26] /usr/lib/cups/filter/pstoturboprint: line 53: kill: (7802) - No such process
D [24/Jan/2011:07:33:34 -0800] [Job 26] /usr/lib/cups/filter/pstoturboprint: line 56: kill: (7804) - No such process
D [24/Jan/2011:07:33:34 -0800] PID 7754 (/usr/lib/cups/filter/pstopdf) exited with no errors.
D [24/Jan/2011:07:33:34 -0800] PID 7755 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [24/Jan/2011:07:33:34 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:34 -0800] cupsdAcceptClient: 22 from localhost (Domain)
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 20 POST / HTTP/1.1
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:34 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 22 POST / HTTP/1.1
D [24/Jan/2011:07:33:34 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 20 1.1 Get-Notifications 1
D [24/Jan/2011:07:33:34 -0800] Get-Notifications /
D [24/Jan/2011:07:33:34 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:34 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 22 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:34 -0800] Get-Jobs ipp://localhost/printers/
D [24/Jan/2011:07:33:34 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 22 WAITING Closing on EOF
D [24/Jan/2011:07:33:34 -0800] cupsdCloseClient: 22
D [24/Jan/2011:07:33:34 -0800] cupsdAcceptClient: 22 from localhost (Domain)
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 22 POST / HTTP/1.1
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:34 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 22 1.1 Get-Notifications 1
D [24/Jan/2011:07:33:34 -0800] Get-Notifications /
D [24/Jan/2011:07:33:34 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:34 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 22 POST / HTTP/1.1
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:34 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:34 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 22 1.1 Get-Job-Attributes 1
D [24/Jan/2011:07:33:34 -0800] Get-Job-Attributes ipp://localhost/jobs/26
D [24/Jan/2011:07:33:34 -0800] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/26) from localhost
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:34 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:34 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 22 POST / HTTP/1.1
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:34 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 22 1.1 Get-Job-Attributes 1
D [24/Jan/2011:07:33:34 -0800] Get-Job-Attributes ipp://localhost/jobs/26
D [24/Jan/2011:07:33:34 -0800] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/26) from localhost
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 22 WAITING Closing on EOF
D [24/Jan/2011:07:33:34 -0800] cupsdCloseClient: 22
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:34 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:34 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:33:34 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:33:34 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:34 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Classes 1
D [24/Jan/2011:07:33:34 -0800] CUPS-Get-Classes
D [24/Jan/2011:07:33:34 -0800] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:34 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:34 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Default 1
D [24/Jan/2011:07:33:34 -0800] CUPS-Get-Default
D [24/Jan/2011:07:33:34 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [24/Jan/2011:07:33:34 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:35 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:35 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:35 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:35 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:35 -0800] cupsdReadClient: 20 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:35 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:35 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:35 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:35 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:35 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:35 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:35 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:35 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:35 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:35 -0800] cupsdReadClient: 20 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:35 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:35 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:35 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:35 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:35 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:35 -0800] [Job 26] /usr/lib/cups/filter/pstoturboprint: line 73: kill: (7802) - No such process
D [24/Jan/2011:07:33:35 -0800] [Job 26] /usr/lib/cups/filter/pstoturboprint: line 94: /tpstdin: No such file or directory
I [24/Jan/2011:07:33:35 -0800] [Job 26] Printer is idle
D [24/Jan/2011:07:33:35 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:35 -0800] PID 7756 (/usr/lib/cups/filter/pstoturboprint) exited with no errors.
D [24/Jan/2011:07:33:35 -0800] PID 7757 (/usr/lib/cups/backend/tpu) exited with no errors.
D [24/Jan/2011:07:33:35 -0800] cupsdMarkDirty(-----S)
I [24/Jan/2011:07:33:35 -0800] [Job 26] Job completed.
D [24/Jan/2011:07:33:35 -0800] cupsdMarkDirty(----J-)
D [24/Jan/2011:07:33:35 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:36 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:36 -0800] [Job 26] Unloading...
D [24/Jan/2011:07:33:36 -0800] cupsdAcceptClient: 21 from localhost (Domain)
D [24/Jan/2011:07:33:36 -0800] cupsdReadClient: 20 POST / HTTP/1.1
D [24/Jan/2011:07:33:36 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:36 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:36 -0800] cupsdReadClient: 21 POST / HTTP/1.1
D [24/Jan/2011:07:33:36 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:36 -0800] cupsdReadClient: 20 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:36 -0800] Get-Jobs ipp://localhost/printers/
D [24/Jan/2011:07:33:36 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [24/Jan/2011:07:33:36 -0800] cupsdReadClient: 21 1.1 Get-Notifications 1
D [24/Jan/2011:07:33:36 -0800] Get-Notifications /
D [24/Jan/2011:07:33:36 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:36 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:33:36 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:36 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:36 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:36 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:36 -0800] cupsdReadClient: 20 POST / HTTP/1.1
D [24/Jan/2011:07:33:36 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:36 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:36 -0800] cupsdReadClient: 20 1.1 Get-Notifications 1
D [24/Jan/2011:07:33:36 -0800] Get-Notifications /
D [24/Jan/2011:07:33:36 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:36 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:33:36 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:36 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:36 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:36 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:36 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:36 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:36 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:36 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:36 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:36 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:36 -0800] cupsdReadClient: 21 WAITING Closing on EOF
D [24/Jan/2011:07:33:36 -0800] cupsdCloseClient: 21
D [24/Jan/2011:07:33:36 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:36 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:36 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:36 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:33:36 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:33:36 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:33:36 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:36 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:36 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:36 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:36 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Classes 1
D [24/Jan/2011:07:33:36 -0800] CUPS-Get-Classes
D [24/Jan/2011:07:33:36 -0800] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [24/Jan/2011:07:33:36 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:36 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:36 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:36 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:36 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Default 1
D [24/Jan/2011:07:33:36 -0800] CUPS-Get-Default
D [24/Jan/2011:07:33:36 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [24/Jan/2011:07:33:36 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:37 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:37 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:37 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:37 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:37 -0800] cupsdReadClient: 20 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:37 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:37 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:37 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:37 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:37 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:39 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:39 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:39 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:39 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:39 -0800] cupsdReadClient: 20 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:39 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:39 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:39 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:39 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:39 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:39 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:39 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:39 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:39 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:39 -0800] cupsdReadClient: 20 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:39 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:39 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:39 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:39 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:39 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:40 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:40 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:40 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:40 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:40 -0800] cupsdReadClient: 20 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:40 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:40 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:40 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:40 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:40 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:43 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:43 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:43 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:43 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:43 -0800] cupsdReadClient: 20 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:43 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:43 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:43 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:43 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:43 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:44 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:44 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:44 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:44 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:44 -0800] cupsdReadClient: 20 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:44 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:44 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:44 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:44 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:44 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:44 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:44 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:44 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:44 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:44 -0800] cupsdReadClient: 20 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:44 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:44 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:44 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:44 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:44 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:46 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:46 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:46 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:46 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:46 -0800] cupsdReadClient: 20 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:46 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:46 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:46 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:46 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:46 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:48 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 20 POST / HTTP/1.1
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 20 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:48 -0800] Get-Printer-Attributes ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:48 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 20 POST /printers/tp0 HTTP/1.1
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 20 1.1 Create-Job 1
D [24/Jan/2011:07:33:48 -0800] Create-Job ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:48 -0800] cupsdMarkDirty(----J-)
D [24/Jan/2011:07:33:48 -0800] add_job: requesting-user-name="root"
I [24/Jan/2011:07:33:48 -0800] [Job 27] Adding start banner page "none".
D [24/Jan/2011:07:33:48 -0800] cupsdMarkDirty(-----S)
I [24/Jan/2011:07:33:48 -0800] [Job 27] Queued on "tp0" by "root".
D [24/Jan/2011:07:33:48 -0800] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 20 POST /printers/tp0 HTTP/1.1
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 20 1.1 Send-Document 1
D [24/Jan/2011:07:33:48 -0800] Send-Document ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:48 -0800] cupsdIsAuthorized: requesting-user-name="root"
D [24/Jan/2011:07:33:48 -0800] [Job 27] Auto-typing file...
D [24/Jan/2011:07:33:48 -0800] [Job 27] Request file type is application/postscript.
D [24/Jan/2011:07:33:48 -0800] cupsdMarkDirty(----J-)
I [24/Jan/2011:07:33:48 -0800] [Job 27] File of type application/postscript queued by "root".
I [24/Jan/2011:07:33:48 -0800] [Job 27] Adding end banner page "none".
D [24/Jan/2011:07:33:48 -0800] cupsdMarkDirty(----J-)
D [24/Jan/2011:07:33:48 -0800] cupsdMarkDirty(----J-)
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:48 -0800] [Job 27] job-sheets=none,none
D [24/Jan/2011:07:33:48 -0800] [Job 27] argv[0]="tp0"
D [24/Jan/2011:07:33:48 -0800] [Job 27] argv[1]="27"
D [24/Jan/2011:07:33:48 -0800] [Job 27] argv[2]="root"
D [24/Jan/2011:07:33:48 -0800] [Job 27] argv[3]="TurboPrint Testpage"
D [24/Jan/2011:07:33:48 -0800] [Job 27] argv[4]="1"
D [24/Jan/2011:07:33:48 -0800] [Job 27] argv[5]="columns=1 cpi=10 finishings=3 InputSlot=AutomaticFeed lpi=6 media=4x6inBorderless MediaType=CanonPhotoPlusGlossyPP101_5 number-up=1 Resolution=601x600dpi sides=one-sided zedoAdjustC=0 zedoAdjustK=0 zedoAdjustM=0 zedoAdjustY=0 zedoBorderlessExpand=2 zedoBrightC=0 zedoBrightM=0 zedoBrightness=0 zedoCMYKspace=0 zedoColorModel=RGB zedoColorspace=0 zedoContrast=0 zedoDithering=ErrorDiffusion zedoDuplexAdjust=0 zedoDuplexadvanced=manual zedoGamut=0 zedoGreyToneA=0 zedoGreyToneB=0 zedoInklimitC=0 zedoInklimitK=0 zedoInklimitM=0 zedoInklimitY=0 zedoIntensity=0 zedoIntent=perception_0 zedoMirror=0 zedoPreview=preview-off zedoRefLight=65 zedoSettingA=0 zedoSettingB=0 zedoSettingC=0 zedoSettingD=0 zedoSettingE=0 zedoShadows=0 zedoTAC=0 zedoUCR=0 zedoUserColor=0 job-uuid=urn:uuid:cc924598-9858-3550-4d15-d8ebe14c8cff job-originating-host-name=localhost"
D [24/Jan/2011:07:33:48 -0800] [Job 27] argv[6]="/var/spool/cups/d00027-001"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[8]="HOME=/var/spool/cups/tmp"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[10]="SERVER_ADMIN=root@prometheus"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[11]="SOFTWARE=CUPS/1.4.4"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[13]="USER=root"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[16]="IPP_PORT=631"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[17]="CHARSET=utf-8"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[18]="LANG=en_US.UTF-8"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[19]="PPD=/etc/cups/ppd/tp0.ppd"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[20]="RIP_MAX_CACHE=auto"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[21]="CONTENT_TYPE=application/postscript"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[22]="DEVICE_URI=tpu://Canon/Pro9500II_series"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[23]="PRINTER_INFO=Canon_PIXMA_Pro9500II"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[24]="PRINTER_LOCATION="
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[25]="PRINTER=tp0"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[26]="CUPS_FILETYPE=document"
D [24/Jan/2011:07:33:48 -0800] [Job 27] envp[27]="FINAL_CONTENT_TYPE=printer/tp0"
I [24/Jan/2011:07:33:48 -0800] [Job 27] Started filter /usr/lib/cups/filter/pstopdf (PID 7822)
I [24/Jan/2011:07:33:48 -0800] [Job 27] Started filter /usr/lib/cups/filter/pdftopdf (PID 7823)
I [24/Jan/2011:07:33:48 -0800] [Job 27] Started filter /usr/lib/cups/filter/pstoturboprint (PID 7824)
I [24/Jan/2011:07:33:48 -0800] [Job 27] Started backend /usr/lib/cups/backend/tpu (PID 7825)
D [24/Jan/2011:07:33:48 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:48 -0800] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:48 -0800] [Job 27] pstopdf 6 args: 27 root TurboPrint Testpage 1 columns=1 cpi=10 finishings=3 InputSlot=AutomaticFeed lpi=6 media=4x6inBorderless MediaType=CanonPhotoPlusGlossyPP101_5 number-up=1 Resolution=601x600dpi sides=one-sided zedoAdjustC=0 zedoAdjustK=0 zedoAdjustM=0 zedoAdjustY=0 zedoBorderlessExpand=2 zedoBrightC=0 zedoBrightM=0 zedoBrightness=0 zedoCMYKspace=0 zedoColorModel=RGB zedoColorspace=0 zedoContrast=0 zedoDithering=ErrorDiffusion zedoDuplexAdjust=0 zedoDuplexadvanced=manual zedoGamut=0 zedoGreyToneA=0 zedoGreyToneB=0 zedoInklimitC=0 zedoInklimitK=0 zedoInklimitM=0 zedoInklimitY=0 zedoIntensity=0 zedoIntent=perception_0 zedoMirror=0 zedoPreview=preview-off zedoRefLight=65 zedoSettingA=0 zedoSettingB=0 zedoSettingC=0 zedoSettingD=0 zedoSettingE=0 zedoShadows=0 zedoTAC=0 zedoUCR=0 zedoUserColor=0 job-uuid=urn:uuid:cc924598-9858-3550-4d15-d8ebe14c8cff job-originating-host-name=localhost /var/spool/cups/d00027-001
D [24/Jan/2011:07:33:48 -0800] [Job 27] PPD: /etc/cups/ppd/tp0.ppd
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:48 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:48 -0800] [Job 27] /usr/lib/cups/filter/pstoturboprint: line 37: --incognito: command not found
D [24/Jan/2011:07:33:48 -0800] [Job 27] /usr/lib/cups/filter/pstoturboprint: line 111: /turboprint/print.log: No such file or directory
D [24/Jan/2011:07:33:48 -0800] [Job 27] /usr/lib/cups/filter/pstoturboprint: line 151: /tpstdin: No such file or directory
D [24/Jan/2011:07:33:48 -0800] [Job 27] Resolution: 601x600
D [24/Jan/2011:07:33:48 -0800] [Job 27] ldd: /tpprint: No such file or directory
D [24/Jan/2011:07:33:48 -0800] [Job 27] Page size: 4x6inBorderless
D [24/Jan/2011:07:33:48 -0800] [Job 27] /usr/lib/cups/filter/pstoturboprint: line 208: /tpprint: No such file or directory
D [24/Jan/2011:07:33:48 -0800] [Job 27] Width: 293.4, height: 432.0, absolute margins: 0.0, 0.0, 293.4, 432.0
D [24/Jan/2011:07:33:48 -0800] [Job 27] cat: /pstoturboprint7824.var: No such file or directory
D [24/Jan/2011:07:33:48 -0800] [Job 27] cat: /pstoturboprint7824.var: No such file or directory
D [24/Jan/2011:07:33:48 -0800] [Job 27] /usr/lib/cups/filter/pstoturboprint: line 240: /tpstdin: No such file or directory
D [24/Jan/2011:07:33:48 -0800] [Job 27] /usr/lib/cups/filter/pstoturboprint: line 314: [: -ne: unary operator expected
D [24/Jan/2011:07:33:48 -0800] [Job 27] Relative margins: 0.0, 0.0, 0, 0
D [24/Jan/2011:07:33:48 -0800] [Job 27] PPD options: -r600 -dDEVICEWIDTHPOINTS=293.4 -dDEVICEHEIGHTPOINTS=432.0
D [24/Jan/2011:07:33:48 -0800] [Job 27] PostScript to be injected: 
D [24/Jan/2011:07:33:48 -0800] [Job 27] Running cat | /usr/bin/gs -q -dNOPAUSE -dBATCH -sDEVICE=pdfwrite -dCompatibilityLevel=1.3 -dAutoRotatePages=/None -dAutoFilterColorImages=false                -dNOPLATFONTS -dPARANOIDSAFER -sstdout=%stderr -dColorImageFilter=/FlateEncode                 -dPDFSETTINGS=/printer                 -dColorConversionStrategy=/LeaveColorUnchanged -dDoNumCopies -r600 -dDEVICEWIDTHPOINTS=293.4 -dDEVICEHEIGHTPOINTS=432.0 -sOutputFile=-  -c .setpdfwrite -f -
D [24/Jan/2011:07:33:48 -0800] [Job 27] /usr/lib/cups/filter/pstoturboprint: line 356: [: -ne: unary operator expected
D [24/Jan/2011:07:33:48 -0800] [Job 27] /usr/lib/cups/filter/pstoturboprint: line 360: *  : syntax error: operand expected (error token is "*  ")
D [24/Jan/2011:07:33:48 -0800] [Job 27] /usr/lib/cups/filter/pstoturboprint: line 361: [: -gt: unary operator expected
D [24/Jan/2011:07:33:48 -0800] [Job 27] mkfifo: cannot create fifo `/pstoturboprint7824.fifo': Permission denied
D [24/Jan/2011:07:33:48 -0800] [Job 27] /usr/lib/cups/filter/pstoturboprint: line 441: /tpstdin: No such file or directory
D [24/Jan/2011:07:33:48 -0800] [Job 27] /usr/lib/cups/filter/pstoturboprint: line 433: /tpprint: No such file or directory
D [24/Jan/2011:07:33:48 -0800] [Job 27] /usr/lib/cups/filter/pstoturboprint: line 441: /pstoturboprint7824.fifo: Permission denied
D [24/Jan/2011:07:33:48 -0800] [Job 27] /usr/lib/cups/filter/pstoturboprint: line 53: kill: (7870) - No such process
D [24/Jan/2011:07:33:48 -0800] [Job 27] /usr/lib/cups/filter/pstoturboprint: line 56: kill: (7872) - No such process
D [24/Jan/2011:07:33:48 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:48 -0800] cupsdAcceptClient: 22 from localhost (Domain)
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 20 POST / HTTP/1.1
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 22 POST / HTTP/1.1
D [24/Jan/2011:07:33:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 20 1.1 Get-Notifications 1
D [24/Jan/2011:07:33:48 -0800] Get-Notifications /
D [24/Jan/2011:07:33:48 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:48 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 22 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:48 -0800] Get-Jobs ipp://localhost/printers/
D [24/Jan/2011:07:33:48 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 22 WAITING Closing on EOF
D [24/Jan/2011:07:33:48 -0800] cupsdCloseClient: 22
D [24/Jan/2011:07:33:48 -0800] cupsdAcceptClient: 22 from localhost (Domain)
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 22 POST / HTTP/1.1
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 22 1.1 Get-Notifications 1
D [24/Jan/2011:07:33:48 -0800] Get-Notifications /
D [24/Jan/2011:07:33:48 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:48 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 22 POST / HTTP/1.1
D [24/Jan/2011:07:33:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:48 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:48 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 22 1.1 Get-Job-Attributes 1
D [24/Jan/2011:07:33:48 -0800] Get-Job-Attributes ipp://localhost/jobs/27
D [24/Jan/2011:07:33:48 -0800] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/27) from localhost
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 22 POST / HTTP/1.1
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 22 1.1 Get-Job-Attributes 1
D [24/Jan/2011:07:33:48 -0800] Get-Job-Attributes ipp://localhost/jobs/27
D [24/Jan/2011:07:33:48 -0800] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/27) from localhost
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 22 WAITING Closing on EOF
D [24/Jan/2011:07:33:48 -0800] cupsdCloseClient: 22
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:48 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:33:48 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:33:48 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Classes 1
D [24/Jan/2011:07:33:48 -0800] CUPS-Get-Classes
D [24/Jan/2011:07:33:48 -0800] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Default 1
D [24/Jan/2011:07:33:48 -0800] CUPS-Get-Default
D [24/Jan/2011:07:33:48 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 20 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:48 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:48 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:48 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:48 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 20 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:48 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:48 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:48 -0800] cupsdSetBusyState: Printing jobs and dirty files
D [24/Jan/2011:07:33:48 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:48 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:48 -0800] PID 7822 (/usr/lib/cups/filter/pstopdf) exited with no errors.
D [24/Jan/2011:07:33:49 -0800] [Job 27] /usr/lib/cups/filter/pstoturboprint: line 73: kill: (7870) - No such process
D [24/Jan/2011:07:33:49 -0800] [Job 27] /usr/lib/cups/filter/pstoturboprint: line 94: /tpstdin: No such file or directory
D [24/Jan/2011:07:33:49 -0800] PID 7823 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [24/Jan/2011:07:33:49 -0800] PID 7824 (/usr/lib/cups/filter/pstoturboprint) exited with no errors.
I [24/Jan/2011:07:33:49 -0800] [Job 27] Printer is idle
D [24/Jan/2011:07:33:49 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:49 -0800] PID 7825 (/usr/lib/cups/backend/tpu) exited with no errors.
D [24/Jan/2011:07:33:49 -0800] cupsdMarkDirty(-----S)
I [24/Jan/2011:07:33:49 -0800] [Job 27] Job completed.
D [24/Jan/2011:07:33:49 -0800] cupsdMarkDirty(----J-)
D [24/Jan/2011:07:33:49 -0800] cupsdMarkDirty(-----S)
D [24/Jan/2011:07:33:49 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:49 -0800] cupsdAcceptClient: 21 from localhost (Domain)
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 20 POST / HTTP/1.1
D [24/Jan/2011:07:33:49 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:49 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 21 POST / HTTP/1.1
D [24/Jan/2011:07:33:49 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 20 1.1 Get-Notifications 1
D [24/Jan/2011:07:33:49 -0800] Get-Notifications /
D [24/Jan/2011:07:33:49 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:49 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 21 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:49 -0800] Get-Jobs ipp://localhost/printers/
D [24/Jan/2011:07:33:49 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [24/Jan/2011:07:33:49 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 21 WAITING Closing on EOF
D [24/Jan/2011:07:33:49 -0800] cupsdCloseClient: 21
D [24/Jan/2011:07:33:49 -0800] cupsdAcceptClient: 21 from localhost (Domain)
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 21 POST / HTTP/1.1
D [24/Jan/2011:07:33:49 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:49 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 21 1.1 Get-Notifications 1
D [24/Jan/2011:07:33:49 -0800] Get-Notifications /
D [24/Jan/2011:07:33:49 -0800] cupsdIsAuthorized: requesting-user-name="mhenry"
D [24/Jan/2011:07:33:49 -0800] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [24/Jan/2011:07:33:49 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 21 WAITING Closing on EOF
D [24/Jan/2011:07:33:49 -0800] cupsdCloseClient: 21
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:49 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:49 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 11 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:49 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:49 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:49 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:49 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:49 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:49 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Printers 1
D [24/Jan/2011:07:33:49 -0800] CUPS-Get-Printers
D [24/Jan/2011:07:33:49 -0800] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [24/Jan/2011:07:33:49 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:49 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:49 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Classes 1
D [24/Jan/2011:07:33:49 -0800] CUPS-Get-Classes
D [24/Jan/2011:07:33:49 -0800] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [24/Jan/2011:07:33:49 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [24/Jan/2011:07:33:49 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:49 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 11 1.1 CUPS-Get-Default 1
D [24/Jan/2011:07:33:49 -0800] CUPS-Get-Default
D [24/Jan/2011:07:33:49 -0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [24/Jan/2011:07:33:49 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:49 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:49 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:49 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 20 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:49 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:49 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:49 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:49 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:49 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:50 -0800] [Job 27] Unloading...
D [24/Jan/2011:07:33:53 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:53 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:53 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:53 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:53 -0800] cupsdReadClient: 20 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:53 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:53 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:53 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:53 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:53 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:53 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:53 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:53 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:53 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:53 -0800] cupsdReadClient: 20 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:53 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:53 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:53 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:53 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:53 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:53 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:53 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:53 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:53 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:53 -0800] cupsdReadClient: 20 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:53 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:53 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:53 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:53 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:53 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:56 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:56 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:56 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:56 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:56 -0800] cupsdReadClient: 20 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:56 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:56 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:56 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:56 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:56 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:57 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:57 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:57 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:57 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:57 -0800] cupsdReadClient: 20 1.1 Get-Printer-Attributes 1
D [24/Jan/2011:07:33:57 -0800] Get-Printer-Attributes ipp://localhost/printers/tp0
D [24/Jan/2011:07:33:57 -0800] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/tp0) from localhost
D [24/Jan/2011:07:33:57 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:57 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:57 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:57 -0800] cupsdAcceptClient: 20 from localhost (Domain)
D [24/Jan/2011:07:33:57 -0800] cupsdReadClient: 20 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:57 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:57 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:57 -0800] cupsdReadClient: 20 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:57 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:57 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:57 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:57 -0800] cupsdReadClient: 20 WAITING Closing on EOF
D [24/Jan/2011:07:33:57 -0800] cupsdCloseClient: 20
D [24/Jan/2011:07:33:58 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:33:58 -0800] cupsdCloseClient: 13
D [24/Jan/2011:07:33:58 -0800] cupsdReadClient: 16 WAITING Closing on EOF
D [24/Jan/2011:07:33:58 -0800] cupsdCloseClient: 16
D [24/Jan/2011:07:33:59 -0800] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Jan/2011:07:33:59 -0800] cupsdReadClient: 13 POST /printers/ HTTP/1.1
D [24/Jan/2011:07:33:59 -0800] cupsdSetBusyState: Active clients and dirty files
D [24/Jan/2011:07:33:59 -0800] cupsdAuthorize: No authentication data provided.
D [24/Jan/2011:07:33:59 -0800] cupsdReadClient: 13 1.1 Get-Jobs 1
D [24/Jan/2011:07:33:59 -0800] Get-Jobs ipp://localhost:631/printers/tp0
D [24/Jan/2011:07:33:59 -0800] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/tp0) from localhost
D [24/Jan/2011:07:33:59 -0800] cupsdSetBusyState: Dirty files
D [24/Jan/2011:07:33:59 -0800] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Jan/2011:07:33:59 -0800] cupsdCloseClient: 13
I [24/Jan/2011:07:34:05 -0800] Saving job cache file "/var/cache/cups/job.cache"...
I [24/Jan/2011:07:34:05 -0800] Saving subscriptions.conf...
D [24/Jan/2011:07:34:05 -0800] cupsdSetBusyState: Not busy
D [24/Jan/2011:07:34:05 -0800] Report: clients=5
D [24/Jan/2011:07:34:05 -0800] Report: jobs=27
D [24/Jan/2011:07:34:05 -0800] Report: jobs-active=0
D [24/Jan/2011:07:34:05 -0800] Report: printers=1
D [24/Jan/2011:07:34:05 -0800] Report: printers-implicit=0
D [24/Jan/2011:07:34:05 -0800] Report: stringpool-string-count=15311
D [24/Jan/2011:07:34:05 -0800] Report: stringpool-alloc-bytes=10288
D [24/Jan/2011:07:34:05 -0800] Report: stringpool-total-bytes=300008
