D [08/Jul/2012:16:29:22 -0700] cupsdAcceptClient: 18 from localhost (Domain)
D [08/Jul/2012:16:29:22 -0700] Report: clients=1
D [08/Jul/2012:16:29:22 -0700] Report: jobs=12
D [08/Jul/2012:16:29:22 -0700] Report: jobs-active=0
D [08/Jul/2012:16:29:22 -0700] Report: printers=1
D [08/Jul/2012:16:29:22 -0700] Report: printers-implicit=0
D [08/Jul/2012:16:29:22 -0700] Report: stringpool-string-count=1602
D [08/Jul/2012:16:29:22 -0700] Report: stringpool-alloc-bytes=8920
D [08/Jul/2012:16:29:22 -0700] Report: stringpool-total-bytes=30200
D [08/Jul/2012:16:29:22 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:29:22 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:29:22 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:22 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:29:22 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:29:22 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:29:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:29:22 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:29:22 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:29:22 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:22 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Classes 1
D [08/Jul/2012:16:29:22 -0700] CUPS-Get-Classes
D [08/Jul/2012:16:29:22 -0700] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [08/Jul/2012:16:29:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:29:22 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:29:22 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:29:22 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:22 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Default 1
D [08/Jul/2012:16:29:22 -0700] CUPS-Get-Default
D [08/Jul/2012:16:29:22 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [08/Jul/2012:16:29:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:29:22 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:29:22 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:29:22 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:29:22 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:22 -0700] cupsdReadClient: 19 1.1 Create-Printer-Subscription 1
D [08/Jul/2012:16:29:22 -0700] Create-Printer-Subscription /
D [08/Jul/2012:16:29:22 -0700] cupsdCreateSubscription(con=0xb8071988(19), uri="/")
D [08/Jul/2012:16:29:22 -0700] pullmethod="ippget"
D [08/Jul/2012:16:29:22 -0700] notify-lease-duration=86400
D [08/Jul/2012:16:29:22 -0700] notify-time-interval=0
D [08/Jul/2012:16:29:22 -0700] cupsdAddSubscription(mask=18f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [08/Jul/2012:16:29:22 -0700] Added subscription #169 for server.
D [08/Jul/2012:16:29:22 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:29:22 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [08/Jul/2012:16:29:22 -0700] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost
D [08/Jul/2012:16:29:22 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:22 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:29:22 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:22 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:22 -0700] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:29:22 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:29:22 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:29:22 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:22 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:29:22 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:22 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:22 -0700] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:29:22 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:29:22 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:29:22 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:22 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:29:22 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:29:22 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:23 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:29:23 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:23 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:23 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:29:23 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:29:23 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:29:23 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:23 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:29:23 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:23 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:23 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Classes 1
D [08/Jul/2012:16:29:23 -0700] CUPS-Get-Classes
D [08/Jul/2012:16:29:23 -0700] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [08/Jul/2012:16:29:23 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:23 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:29:23 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:23 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:23 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Default 1
D [08/Jul/2012:16:29:23 -0700] CUPS-Get-Default
D [08/Jul/2012:16:29:23 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [08/Jul/2012:16:29:23 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:24 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:29:24 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:29:24 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:24 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:24 -0700] cupsdReadClient: 19 1.1 Get-Notifications 1
D [08/Jul/2012:16:29:24 -0700] Get-Notifications /
D [08/Jul/2012:16:29:24 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:29:24 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:29:24 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:24 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:29:24 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:29:24 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 1.1 Create-Printer-Subscription 1
D [08/Jul/2012:16:29:26 -0700] Create-Printer-Subscription /
D [08/Jul/2012:16:29:26 -0700] cupsdCreateSubscription(con=0xb8071988(19), uri="/")
D [08/Jul/2012:16:29:26 -0700] pullmethod="ippget"
D [08/Jul/2012:16:29:26 -0700] notify-lease-duration=86400
D [08/Jul/2012:16:29:26 -0700] notify-time-interval=0
D [08/Jul/2012:16:29:26 -0700] cupsdAddSubscription(mask=1798f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [08/Jul/2012:16:29:26 -0700] Added subscription #170 for server.
D [08/Jul/2012:16:29:26 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:26 -0700] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:29:26 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:29:26 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:29:26 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:29:26 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 20 1.1 Get-Printer-Attributes 1
D [08/Jul/2012:16:29:26 -0700] Get-Printer-Attributes ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:29:26 -0700] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:29:26 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:29:26 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 1.1 Create-Printer-Subscription 1
D [08/Jul/2012:16:29:26 -0700] Create-Printer-Subscription /
D [08/Jul/2012:16:29:26 -0700] cupsdCreateSubscription(con=0xb8071988(19), uri="/")
D [08/Jul/2012:16:29:26 -0700] pullmethod="ippget"
D [08/Jul/2012:16:29:26 -0700] notify-lease-duration=86400
D [08/Jul/2012:16:29:26 -0700] notify-time-interval=0
D [08/Jul/2012:16:29:26 -0700] cupsdAddSubscription(mask=1798f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [08/Jul/2012:16:29:26 -0700] Added subscription #171 for server.
D [08/Jul/2012:16:29:26 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:26 -0700] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:29:26 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:29:26 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:29:26 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:29:26 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:29:26 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:29:26 -0700] Get-Jobs ipp://localhost/printers/
D [08/Jul/2012:16:29:26 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:29:26 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:29:26 -0700] Get-Jobs ipp://localhost/printers/
D [08/Jul/2012:16:29:26 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:26 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:29:26 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:29:26 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:28 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:29:28 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:29:28 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:28 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:28 -0700] cupsdReadClient: 19 1.1 Get-Notifications 1
D [08/Jul/2012:16:29:28 -0700] Get-Notifications /
D [08/Jul/2012:16:29:28 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:29:28 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:29:28 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:28 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:29:28 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:29:28 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:28 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:29:28 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:29:28 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:29:28 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:29:28 -0700] cupsdReadClient: 19 1.1 Get-Notifications 1
D [08/Jul/2012:16:29:28 -0700] Get-Notifications /
D [08/Jul/2012:16:29:28 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:29:28 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:29:28 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:29:28 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:29:28 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:29:28 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [08/Jul/2012:16:29:53 -0700] Saving subscriptions.conf...
D [08/Jul/2012:16:29:54 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [08/Jul/2012:16:30:24 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:24 -0700] Report: clients=2
D [08/Jul/2012:16:30:24 -0700] Report: jobs=12
D [08/Jul/2012:16:30:24 -0700] Report: jobs-active=0
D [08/Jul/2012:16:30:24 -0700] Report: printers=1
D [08/Jul/2012:16:30:24 -0700] Report: printers-implicit=0
D [08/Jul/2012:16:30:24 -0700] Report: stringpool-string-count=1605
D [08/Jul/2012:16:30:24 -0700] Report: stringpool-alloc-bytes=8920
D [08/Jul/2012:16:30:24 -0700] Report: stringpool-total-bytes=30248
D [08/Jul/2012:16:30:24 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:24 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:24 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:24 -0700] cupsdReadClient: 19 1.1 Get-Notifications 1
D [08/Jul/2012:16:30:24 -0700] Get-Notifications /
D [08/Jul/2012:16:30:24 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:30:24 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:30:24 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:24 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:24 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:24 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:28 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:28 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:28 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:28 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:28 -0700] cupsdReadClient: 19 1.1 Get-Notifications 1
D [08/Jul/2012:16:30:28 -0700] Get-Notifications /
D [08/Jul/2012:16:30:28 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:30:28 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:30:28 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:28 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:28 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:28 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:28 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:28 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:28 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:28 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:28 -0700] cupsdReadClient: 19 1.1 Get-Notifications 1
D [08/Jul/2012:16:30:28 -0700] Get-Notifications /
D [08/Jul/2012:16:30:28 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:30:28 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:30:28 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:28 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:28 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:28 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:36 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:36 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:36 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:36 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:36 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:36 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:36 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:36 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:36 -0700] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:36 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:36 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:36 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:36 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:30:36 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:36 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:36 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:36 -0700] cupsdReadClient: 20 GET /printers/HP-LaserJet-5L.ppd HTTP/1.1
D [08/Jul/2012:16:30:36 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:36 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:36 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:36 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:30:36 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:30:36 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:36 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:36 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:36 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:36 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:36 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:36 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:36 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:36 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:36 -0700] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:36 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:36 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:36 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:36 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:36 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:36 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:37 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:37 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:37 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:37 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:37 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:37 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:30:37 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:37 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:37 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:37 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:37 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:37 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:37 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:30:37 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:37 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:37 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:37 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:37 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:37 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:37 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:30:37 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:37 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:37 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:37 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:37 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:37 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:37 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:30:37 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:37 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:37 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:37 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:37 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:37 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:37 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:37 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:30:37 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:30:37 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:38 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:38 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:38 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:38 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:38 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:38 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:30:38 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:38 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:38 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:38 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:38 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:38 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:38 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:30:38 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:38 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:38 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:38 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:38 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:38 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:38 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:30:38 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:38 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:38 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:38 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:38 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:38 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:38 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:30:38 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:38 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:38 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:38 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:38 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:38 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:38 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:38 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:30:38 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:30:38 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:39 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:39 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:39 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:39 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:39 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:39 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:30:39 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:39 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:39 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:39 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:39 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:39 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:39 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:30:39 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:30:39 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 19 POST /printers/HP-LaserJet-5L HTTP/1.1
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:30:39 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 19 1.1 Print-Job 1
D [08/Jul/2012:16:30:39 -0700] Print-Job ipp://localhost:631/printers/HP-LaserJet-5L
D [08/Jul/2012:16:30:39 -0700] [Job ???] Auto-typing file...
I [08/Jul/2012:16:30:39 -0700] [Job ???] Request file type is application/pdf.
D [08/Jul/2012:16:30:39 -0700] cupsdMarkDirty(----J-)
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [08/Jul/2012:16:30:39 -0700] add_job: requesting-user-name="grabhorn"
I [08/Jul/2012:16:30:39 -0700] [Job 81] Adding start banner page "none".
D [08/Jul/2012:16:30:39 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:39 -0700] cupsdMarkDirty(----J-)
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [08/Jul/2012:16:30:39 -0700] [Job 81] Adding end banner page "none".
I [08/Jul/2012:16:30:39 -0700] [Job 81] File of type application/pdf queued by "grabhorn".
D [08/Jul/2012:16:30:39 -0700] [Job 81] hold_until=0
I [08/Jul/2012:16:30:39 -0700] [Job 81] Queued on "HP-LaserJet-5L" by "grabhorn".
D [08/Jul/2012:16:30:39 -0700] cupsdMarkDirty(----J-)
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:39 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:39 -0700] [Job 81] job-sheets=none,none
D [08/Jul/2012:16:30:39 -0700] [Job 81] argv[0]="HP-LaserJet-5L"
D [08/Jul/2012:16:30:39 -0700] [Job 81] argv[1]="81"
D [08/Jul/2012:16:30:39 -0700] [Job 81] argv[2]="grabhorn"
D [08/Jul/2012:16:30:39 -0700] [Job 81] argv[3]="ppt_pptCard.pdf — Travel Docs Poster 7.2.10"
D [08/Jul/2012:16:30:39 -0700] [Job 81] argv[4]="1"
D [08/Jul/2012:16:30:39 -0700] [Job 81] argv[5]="InputSlot=Default TonerDensity=3 PageSize=Letter number-up=1 REt=Medium MPTray=First Manualfeed=Off Economode=Off Resolution=600x600dpi job-uuid=urn:uuid:291ef14b-aca5-3316-4d82-e3013a5ed7e3 job-originating-host-name=localhost time-at-creation=1341790239 time-at-processing=1341790239"
D [08/Jul/2012:16:30:39 -0700] [Job 81] argv[6]="/var/spool/cups/d00081-001"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[8]="HOME=/var/spool/cups/tmp"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[10]="SERVER_ADMIN=root@Beefy"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[11]="SOFTWARE=CUPS/1.5.3"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[13]="USER=root"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[16]="IPP_PORT=631"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[17]="CHARSET=utf-8"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[18]="LANG=en_US.UTF-8"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[19]="PPD=/etc/cups/ppd/HP-LaserJet-5L.ppd"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[20]="RIP_MAX_CACHE=128m"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[21]="CONTENT_TYPE=application/pdf"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[22]="DEVICE_URI=usb://HP/LaserJet%205L"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[23]="PRINTER_INFO=Hewlett-Packard HP LaserJet 5L"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[24]="PRINTER_LOCATION=Sandy's Office"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[25]="PRINTER=HP-LaserJet-5L"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[26]="PRINTER_STATE_REASONS=none"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[27]="CUPS_FILETYPE=document"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[28]="FINAL_CONTENT_TYPE=printer/HP-LaserJet-5L"
D [08/Jul/2012:16:30:39 -0700] [Job 81] envp[29]="AUTH_I****"
I [08/Jul/2012:16:30:39 -0700] [Job 81] Started filter /usr/lib/cups/filter/pdftopdf (PID 4625)
I [08/Jul/2012:16:30:39 -0700] [Job 81] Started filter /usr/lib/cups/filter/foomatic-rip (PID 4626)
I [08/Jul/2012:16:30:39 -0700] [Job 81] Started backend /usr/lib/cups/backend/usb (PID 4627)
D [08/Jul/2012:16:30:39 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:39 -0700] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:30:39 -0700] [Notifier] state=3
D [08/Jul/2012:16:30:39 -0700] [Notifier] JobCreated
D [08/Jul/2012:16:30:39 -0700] [Notifier] state=3
D [08/Jul/2012:16:30:39 -0700] [Notifier] PrinterStateChanged
D [08/Jul/2012:16:30:39 -0700] [Notifier] state=3
D [08/Jul/2012:16:30:39 -0700] [Notifier] JobState
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 19 WAITING Closing on EOF
D [08/Jul/2012:16:30:39 -0700] cupsdCloseClient: 19
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:39 -0700] cupsdAcceptClient: 19 from localhost (Domain)
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:39 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:30:39 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:30:39 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:39 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:30:39 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:30:39 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:39 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:30:39 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:30:39 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:39 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:30:39 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:30:39 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:30:39 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:30:39 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 1.1 Get-Notifications 1
D [08/Jul/2012:16:30:40 -0700] Get-Notifications /
D [08/Jul/2012:16:30:40 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:30:40 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 1.1 Get-Notifications 1
D [08/Jul/2012:16:30:40 -0700] Get-Notifications /
D [08/Jul/2012:16:30:40 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:30:40 -0700] Get-Job-Attributes ipp://localhost/jobs/81
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 22 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:30:40 -0700] Get-Job-Attributes ipp://localhost/jobs/81
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 WAITING Closing on EOF
D [08/Jul/2012:16:30:40 -0700] cupsdCloseClient: 22
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 22 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 1.1 Get-Printer-Attributes 1
D [08/Jul/2012:16:30:40 -0700] Get-Printer-Attributes ipp://Beefy/printers/HP-LaserJet-5L
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Printer-Attributes (ipp://Beefy/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 WAITING Closing on EOF
D [08/Jul/2012:16:30:40 -0700] cupsdCloseClient: 22
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 22 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:30:40 -0700] Get-Job-Attributes ipp://localhost/jobs/81
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 WAITING Closing on EOF
D [08/Jul/2012:16:30:40 -0700] cupsdCloseClient: 22
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 22 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:30:40 -0700] Get-Job-Attributes ipp://localhost/jobs/81
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 WAITING Closing on EOF
D [08/Jul/2012:16:30:40 -0700] cupsdCloseClient: 22
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:30:40 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 1.1 Get-Notifications 1
D [08/Jul/2012:16:30:40 -0700] Get-Notifications /
D [08/Jul/2012:16:30:40 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:30:40 -0700] Get-Job-Attributes ipp://localhost/jobs/81
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 22 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 1.1 Get-Printer-Attributes 1
D [08/Jul/2012:16:30:40 -0700] Get-Printer-Attributes 
D [08/Jul/2012:16:30:40 -0700] Get-Printer-Attributes client-error-not-found: The printer or class does not exist.
D [08/Jul/2012:16:30:40 -0700] Returning IPP client-error-not-found for Get-Printer-Attributes () from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 WAITING Closing on EOF
D [08/Jul/2012:16:30:40 -0700] cupsdCloseClient: 22
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 22 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:30:40 -0700] Get-Job-Attributes ipp://localhost/jobs/81
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 WAITING Closing on EOF
D [08/Jul/2012:16:30:40 -0700] cupsdCloseClient: 22
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 22 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:30:40 -0700] Get-Job-Attributes ipp://localhost/jobs/81
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 22 WAITING Closing on EOF
D [08/Jul/2012:16:30:40 -0700] cupsdCloseClient: 22
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:30:40 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] [Job 81] Printing on printer with URI: usb://HP/LaserJet%205L
D [08/Jul/2012:16:30:40 -0700] [Job 81] libusb_get_device_list=11
D [08/Jul/2012:16:30:40 -0700] [Job 81] Getting input from file 
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:40 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Classes 1
D [08/Jul/2012:16:30:40 -0700] CUPS-Get-Classes
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Default 1
D [08/Jul/2012:16:30:40 -0700] CUPS-Get-Default
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] [Job 81] foomatic-rip version 4.0.16.253 running...
D [08/Jul/2012:16:30:40 -0700] [Job 81] Parsing PPD file ...
D [08/Jul/2012:16:30:40 -0700] [Job 81] Added option ColorSpace
D [08/Jul/2012:16:30:40 -0700] [Job 81] Added option PageSize
D [08/Jul/2012:16:30:40 -0700] [Job 81] Added option ImageableArea
D [08/Jul/2012:16:30:40 -0700] [Job 81] Added option PaperDimension
D [08/Jul/2012:16:30:40 -0700] [Job 81] Added option InputSlot
D [08/Jul/2012:16:30:40 -0700] [Job 81] Added option Manualfeed
D [08/Jul/2012:16:30:40 -0700] [Job 81] Added option Economode
D [08/Jul/2012:16:30:40 -0700] [Job 81] Added option Copies
D [08/Jul/2012:16:30:40 -0700] [Job 81] STATE: +connecting-to-device
D [08/Jul/2012:16:30:40 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] [Notifier] state=3
D [08/Jul/2012:16:30:40 -0700] [Notifier] PrinterStateChanged
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:30:40 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:30:40 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:40 -0700] [Job 81] STATE: -connecting-to-device
D [08/Jul/2012:16:30:40 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
D [08/Jul/2012:16:30:40 -0700] [Notifier] state=3
D [08/Jul/2012:16:30:40 -0700] [Notifier] PrinterStateChanged
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:30:40 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:30:40 -0700] [Job 81] Added option Resolution
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] [Job 81] Added option REt
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:30:40 -0700] [Job 81] Added option TonerDensity
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:30:40 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:30:40 -0700] [Job 81] Added option MPTray
D [08/Jul/2012:16:30:40 -0700] [Job 81] Added option Font
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] [Job 81] 
D [08/Jul/2012:16:30:40 -0700] [Job 81] Parameter Summary
D [08/Jul/2012:16:30:40 -0700] [Job 81] -----------------
D [08/Jul/2012:16:30:40 -0700] [Job 81] 
D [08/Jul/2012:16:30:40 -0700] [Job 81] Spooler: cups
D [08/Jul/2012:16:30:40 -0700] [Job 81] Printer: HP-LaserJet-5L
D [08/Jul/2012:16:30:40 -0700] [Job 81] Shell: /bin/bash
D [08/Jul/2012:16:30:40 -0700] [Job 81] PPD file: /etc/cups/ppd/HP-LaserJet-5L.ppd
D [08/Jul/2012:16:30:40 -0700] [Job 81] ATTR file: 
D [08/Jul/2012:16:30:40 -0700] [Job 81] Printer model: HP LaserJet 5L Foomatic/ljet4 (recommended)
D [08/Jul/2012:16:30:40 -0700] [Job 81] Job title: ppt_pptCard.pdf — Travel Docs Poster 7.2.10
D [08/Jul/2012:16:30:40 -0700] [Job 81] File(s) to be printed:
D [08/Jul/2012:16:30:40 -0700] [Job 81] <STDIN>
D [08/Jul/2012:16:30:40 -0700] [Job 81] 
D [08/Jul/2012:16:30:40 -0700] [Job 81] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [08/Jul/2012:16:30:40 -0700] [Job 81] Printing system options:
D [08/Jul/2012:16:30:40 -0700] [Job 81] Pondering option 'number-up=1'
D [08/Jul/2012:16:30:40 -0700] [Job 81] Unknown option number-up=1.
D [08/Jul/2012:16:30:40 -0700] [Job 81] Pondering option 'job-uuid=urn:uuid:291ef14b-aca5-3316-4d82-e3013a5ed7e3'
D [08/Jul/2012:16:30:40 -0700] [Job 81] Unknown option job-uuid=urn:uuid:291ef14b-aca5-3316-4d82-e3013a5ed7e3.
D [08/Jul/2012:16:30:40 -0700] [Job 81] Pondering option 'job-originating-host-name=localhost'
D [08/Jul/2012:16:30:40 -0700] [Job 81] Unknown option job-originating-host-name=localhost.
D [08/Jul/2012:16:30:40 -0700] [Job 81] Pondering option 'time-at-creation=1341790239'
D [08/Jul/2012:16:30:40 -0700] [Job 81] Unknown option time-at-creation=1341790239.
D [08/Jul/2012:16:30:40 -0700] [Job 81] Pondering option 'time-at-processing=1341790239'
D [08/Jul/2012:16:30:40 -0700] [Job 81] Unknown option time-at-processing=1341790239.
D [08/Jul/2012:16:30:40 -0700] [Job 81] Options from the PPD file:
D [08/Jul/2012:16:30:40 -0700] [Job 81] Pondering option 'InputSlot=Default'
D [08/Jul/2012:16:30:40 -0700] [Job 81] Pondering option 'TonerDensity=3'
D [08/Jul/2012:16:30:40 -0700] [Job 81] Pondering option 'PageSize=Letter'
D [08/Jul/2012:16:30:40 -0700] [Job 81] Pondering option 'REt=Medium'
D [08/Jul/2012:16:30:40 -0700] [Job 81] Pondering option 'MPTray=First'
D [08/Jul/2012:16:30:40 -0700] [Job 81] Pondering option 'Manualfeed=Off'
D [08/Jul/2012:16:30:40 -0700] [Job 81] Pondering option 'Economode=Off'
D [08/Jul/2012:16:30:40 -0700] [Job 81] Pondering option 'Resolution=600x600dpi'
D [08/Jul/2012:16:30:40 -0700] [Job 81] 
D [08/Jul/2012:16:30:40 -0700] [Job 81] ================================================
D [08/Jul/2012:16:30:40 -0700] [Job 81] 
D [08/Jul/2012:16:30:40 -0700] [Job 81] File: <STDIN>
D [08/Jul/2012:16:30:40 -0700] [Job 81] 
D [08/Jul/2012:16:30:40 -0700] [Job 81] ================================================
D [08/Jul/2012:16:30:40 -0700] [Job 81] 
D [08/Jul/2012:16:30:40 -0700] [Job 81] Device protocol: 2
D [08/Jul/2012:16:30:40 -0700] [Job 81] Printer reports bi-di support but in reality works only uni-directionally
D [08/Jul/2012:16:30:40 -0700] [Job 81] Uni-directional USB communication only!
D [08/Jul/2012:16:30:40 -0700] [Job 81] Printer does not like usblp kernel module to be re-attached after job
D [08/Jul/2012:16:30:40 -0700] [Job 81] Uni-directional device/mode, back channel deactivated.
I [08/Jul/2012:16:30:40 -0700] [Job 81] Sending data to printer.
D [08/Jul/2012:16:30:40 -0700] [Job 81] Set job-printer-state-message to "Sending data to printer.", current level=INFO
D [08/Jul/2012:16:30:40 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] [Notifier] state=3
D [08/Jul/2012:16:30:40 -0700] [Notifier] JobProgress
D [08/Jul/2012:16:30:40 -0700] [Notifier] state=3
D [08/Jul/2012:16:30:40 -0700] [Notifier] PrinterStateChanged
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:30:40 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:30:40 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] [Job 81] Filetype: PDF
D [08/Jul/2012:16:30:40 -0700] [Job 81] Storing temporary files in /var/spool/cups/tmp
D [08/Jul/2012:16:30:40 -0700] PID 4625 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 1.1 Get-Notifications 1
D [08/Jul/2012:16:30:40 -0700] Get-Notifications /
D [08/Jul/2012:16:30:40 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 22 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:30:40 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 1.1 Get-Notifications 1
D [08/Jul/2012:16:30:40 -0700] Get-Notifications /
D [08/Jul/2012:16:30:40 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 23 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 24 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 23 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 23 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:30:40 -0700] Get-Job-Attributes ipp://localhost/jobs/81
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 23 WAITING Closing on EOF
D [08/Jul/2012:16:30:40 -0700] cupsdCloseClient: 23
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:30:40 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 1.1 Get-Notifications 1
D [08/Jul/2012:16:30:40 -0700] Get-Notifications /
D [08/Jul/2012:16:30:40 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 23 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdAcceptClient: 25 from localhost (Domain)
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 25 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 25 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:30:40 -0700] Get-Job-Attributes ipp://localhost/jobs/81
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/81) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 25 WAITING Closing on EOF
D [08/Jul/2012:16:30:40 -0700] cupsdCloseClient: 25
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:30:40 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:40 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Classes 1
D [08/Jul/2012:16:30:40 -0700] CUPS-Get-Classes
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:40 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:40 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Default 1
D [08/Jul/2012:16:30:40 -0700] CUPS-Get-Default
D [08/Jul/2012:16:30:40 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [08/Jul/2012:16:30:40 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:30:41 -0700] [Job 81] File contains 1 pages
D [08/Jul/2012:16:30:41 -0700] [Job 81] Starting renderer with command: gs -dFirstPage=1  -q -dBATCH -dPARANOIDSAFER -dNOPAUSE -dNOINTERPOLATE -sDEVICE=ljet4 -dMediaPosition=0 -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -r600x600 -sOutputFile=- -f   /var/spool/cups/tmp/foomatic-4Zn4ZN 
D [08/Jul/2012:16:30:41 -0700] [Job 81] Starting process "kid3" (generation 1)
D [08/Jul/2012:16:30:41 -0700] [Job 81] Starting process "kid4" (generation 2)
D [08/Jul/2012:16:30:41 -0700] [Job 81] Starting process "renderer" (generation 2)
D [08/Jul/2012:16:30:41 -0700] [Job 81] JCL: %-12345X@PJL
D [08/Jul/2012:16:30:41 -0700] [Job 81] @PJL SET MPTRAY=FIRST
D [08/Jul/2012:16:30:41 -0700] [Job 81] @PJL SET DENSITY=3
D [08/Jul/2012:16:30:41 -0700] [Job 81] @PJL SET RET=MEDIUM
D [08/Jul/2012:16:30:41 -0700] [Job 81] @PJL SET COPIES=1
D [08/Jul/2012:16:30:41 -0700] [Job 81] @PJL SET ECONOMODE=OFF
D [08/Jul/2012:16:30:41 -0700] [Job 81] @PJL SET MANUALFEED=OFF
D [08/Jul/2012:16:30:41 -0700] [Job 81] <job data> %-12345X@PJL RESET
D [08/Jul/2012:16:30:41 -0700] [Job 81] 
D [08/Jul/2012:16:30:41 -0700] [Job 81] 
D [08/Jul/2012:16:30:42 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:42 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:42 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:42 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:42 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:42 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:42 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:42 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:42 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:42 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:42 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:42 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:42 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:42 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:42 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:43 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:43 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:43 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:43 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:43 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:43 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:43 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:43 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:44 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:44 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:44 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:44 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:44 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:44 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:44 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:44 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:44 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:44 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:44 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:44 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:44 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:44 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:44 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:44 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] renderer exited with status 0
D [08/Jul/2012:16:30:45 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:45 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:46 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:46 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:46 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:46 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:46 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:46 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:46 -0700] [Job 81] kid4 exited with status 0
D [08/Jul/2012:16:30:46 -0700] [Job 81] kid3 finished
D [08/Jul/2012:16:30:46 -0700] [Job 81] Kid3 exit status: 0
D [08/Jul/2012:16:30:46 -0700] [Job 81] 
D [08/Jul/2012:16:30:46 -0700] [Job 81] Closing foomatic-rip.
D [08/Jul/2012:16:30:46 -0700] PID 4626 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [08/Jul/2012:16:30:46 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:46 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:47 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:47 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:47 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:47 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:47 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:47 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:48 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:48 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:48 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:48 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:48 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:48 -0700] [Job 81] Read 8192 bytes of print data...
D [08/Jul/2012:16:30:48 -0700] [Job 81] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:30:48 -0700] [Job 81] Read 6069 bytes of print data...
D [08/Jul/2012:16:30:49 -0700] [Job 81] Wrote 6069 bytes of print data...
D [08/Jul/2012:16:30:49 -0700] [Job 81] Sent 350133 bytes...
D [08/Jul/2012:16:30:49 -0700] [Job 81] Resetting printer.
D [08/Jul/2012:16:30:49 -0700] PID 4627 (/usr/lib/cups/backend/usb) exited with no errors.
D [08/Jul/2012:16:30:49 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
I [08/Jul/2012:16:30:49 -0700] [Job 81] Job completed.
D [08/Jul/2012:16:30:49 -0700] cupsdMarkDirty(----J-)
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:49 -0700] [Notifier] state=3
D [08/Jul/2012:16:30:49 -0700] [Notifier] JobCompleted
D [08/Jul/2012:16:30:49 -0700] [Notifier] state=3
D [08/Jul/2012:16:30:49 -0700] [Notifier] PrinterStateChanged
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:49 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:49 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 19 1.1 CUPS-Get-Default 1
D [08/Jul/2012:16:30:49 -0700] CUPS-Get-Default
D [08/Jul/2012:16:30:49 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:30:49 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:30:49 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:30:49 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:30:49 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:30:49 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:30:49 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 20 1.1 Get-Notifications 1
D [08/Jul/2012:16:30:49 -0700] Get-Notifications /
D [08/Jul/2012:16:30:49 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:30:49 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:30:49 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 20 1.1 Get-Notifications 1
D [08/Jul/2012:16:30:49 -0700] Get-Notifications /
D [08/Jul/2012:16:30:49 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:30:49 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:30:49 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 20 1.1 Get-Notifications 1
D [08/Jul/2012:16:30:49 -0700] Get-Notifications /
D [08/Jul/2012:16:30:49 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:30:49 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:30:49 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:30:49 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:30:49 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Classes 1
D [08/Jul/2012:16:30:49 -0700] CUPS-Get-Classes
D [08/Jul/2012:16:30:49 -0700] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:30:49 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:30:49 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Default 1
D [08/Jul/2012:16:30:49 -0700] CUPS-Get-Default
D [08/Jul/2012:16:30:49 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [08/Jul/2012:16:30:49 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:30:50 -0700] [Job 81] Unloading...
I [08/Jul/2012:16:31:10 -0700] Saving job.cache...
I [08/Jul/2012:16:31:10 -0700] Saving subscriptions.conf...
D [08/Jul/2012:16:31:10 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [08/Jul/2012:16:31:20 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:20 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:31:20 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:20 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:20 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:31:20 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:31:20 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:20 -0700] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:20 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:20 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:31:20 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:20 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:31:20 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:31:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:20 -0700] cupsdReadClient: 20 GET /printers/HP-LaserJet-5L.ppd HTTP/1.1
D [08/Jul/2012:16:31:20 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:31:20 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:31:20 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:20 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:20 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:20 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:20 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:20 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:20 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:31:20 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:31:20 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:20 -0700] cupsdReadClient: 20 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:20 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:20 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:31:20 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:20 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:20 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:20 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:31:20 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:20 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:20 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:31:20 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:31:20 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:20 -0700] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:20 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:20 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:31:20 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:31:20 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:31:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:21 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:21 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:21 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:31:21 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:21 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:21 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:31:21 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:21 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:21 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:21 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:31:21 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:21 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:21 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:31:21 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:21 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:21 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:21 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:31:21 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:21 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:21 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:31:21 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:21 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:21 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:21 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:31:21 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:21 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:21 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:31:21 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:21 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:21 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:21 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:31:21 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:21 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:21 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:31:21 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:31:21 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:31:21 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:22 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:22 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:22 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:31:22 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:22 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:22 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:31:22 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:22 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:22 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:22 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:31:22 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:22 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:22 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:31:22 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:22 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:22 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:22 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:31:22 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:22 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:22 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:31:22 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:22 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:22 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:22 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:31:22 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:22 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:22 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:31:22 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:22 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:22 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:22 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:31:22 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:22 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:22 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:31:22 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:31:22 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:31:22 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:23 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:23 -0700] cupsdAcceptClient: 21 from localhost (Domain)
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:23 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 21 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 21 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:23 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 21 WAITING Closing on EOF
D [08/Jul/2012:16:31:23 -0700] cupsdCloseClient: 21
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [08/Jul/2012:16:31:23 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 20 POST /printers/HP-LaserJet-5L HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 20 1.1 Print-Job 1
D [08/Jul/2012:16:31:23 -0700] Print-Job ipp://localhost:631/printers/HP-LaserJet-5L
D [08/Jul/2012:16:31:23 -0700] [Job ???] Auto-typing file...
I [08/Jul/2012:16:31:23 -0700] [Job ???] Request file type is application/pdf.
D [08/Jul/2012:16:31:23 -0700] cupsdMarkDirty(----J-)
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [08/Jul/2012:16:31:23 -0700] add_job: requesting-user-name="grabhorn"
I [08/Jul/2012:16:31:23 -0700] [Job 82] Adding start banner page "none".
D [08/Jul/2012:16:31:23 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdMarkDirty(----J-)
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [08/Jul/2012:16:31:23 -0700] [Job 82] Adding end banner page "none".
I [08/Jul/2012:16:31:23 -0700] [Job 82] File of type application/pdf queued by "grabhorn".
D [08/Jul/2012:16:31:23 -0700] [Job 82] hold_until=0
I [08/Jul/2012:16:31:23 -0700] [Job 82] Queued on "HP-LaserJet-5L" by "grabhorn".
D [08/Jul/2012:16:31:23 -0700] cupsdMarkDirty(----J-)
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:23 -0700] [Job 82] job-sheets=none,none
D [08/Jul/2012:16:31:23 -0700] [Job 82] argv[0]="HP-LaserJet-5L"
D [08/Jul/2012:16:31:23 -0700] [Job 82] argv[1]="82"
D [08/Jul/2012:16:31:23 -0700] [Job 82] argv[2]="grabhorn"
D [08/Jul/2012:16:31:23 -0700] [Job 82] argv[3]="ppt_pptCard.pdf — Travel Docs Poster 7.2.10"
D [08/Jul/2012:16:31:23 -0700] [Job 82] argv[4]="1"
D [08/Jul/2012:16:31:23 -0700] [Job 82] argv[5]="InputSlot=Default TonerDensity=3 PageSize=Letter number-up=1 REt=Medium MPTray=First Manualfeed=Off Economode=Off Resolution=600x600dpi job-uuid=urn:uuid:c17406be-020b-3790-4a53-574f403bf843 job-originating-host-name=localhost time-at-creation=1341790283 time-at-processing=1341790283"
D [08/Jul/2012:16:31:23 -0700] [Job 82] argv[6]="/var/spool/cups/d00082-001"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[8]="HOME=/var/spool/cups/tmp"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[10]="SERVER_ADMIN=root@Beefy"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[11]="SOFTWARE=CUPS/1.5.3"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[13]="USER=root"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[16]="IPP_PORT=631"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[17]="CHARSET=utf-8"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[18]="LANG=en_US.UTF-8"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[19]="PPD=/etc/cups/ppd/HP-LaserJet-5L.ppd"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[20]="RIP_MAX_CACHE=128m"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[21]="CONTENT_TYPE=application/pdf"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[22]="DEVICE_URI=usb://HP/LaserJet%205L"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[23]="PRINTER_INFO=Hewlett-Packard HP LaserJet 5L"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[24]="PRINTER_LOCATION=Sandy's Office"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[25]="PRINTER=HP-LaserJet-5L"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[26]="PRINTER_STATE_REASONS=none"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[27]="CUPS_FILETYPE=document"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[28]="FINAL_CONTENT_TYPE=printer/HP-LaserJet-5L"
D [08/Jul/2012:16:31:23 -0700] [Job 82] envp[29]="AUTH_I****"
I [08/Jul/2012:16:31:23 -0700] [Job 82] Started filter /usr/lib/cups/filter/pdftopdf (PID 4644)
I [08/Jul/2012:16:31:23 -0700] [Job 82] Started filter /usr/lib/cups/filter/foomatic-rip (PID 4645)
I [08/Jul/2012:16:31:23 -0700] [Job 82] Started backend /usr/lib/cups/backend/usb (PID 4646)
D [08/Jul/2012:16:31:23 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:31:23 -0700] [Notifier] state=3
D [08/Jul/2012:16:31:23 -0700] [Notifier] JobCreated
D [08/Jul/2012:16:31:23 -0700] [Notifier] state=3
D [08/Jul/2012:16:31:23 -0700] [Notifier] PrinterStateChanged
D [08/Jul/2012:16:31:23 -0700] [Notifier] state=3
D [08/Jul/2012:16:31:23 -0700] [Notifier] JobState
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:23 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:31:23 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:31:23 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:31:23 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:31:23 -0700] [Job 82] Getting input from file 
D [08/Jul/2012:16:31:23 -0700] [Job 82] foomatic-rip version 4.0.16.253 running...
D [08/Jul/2012:16:31:23 -0700] [Job 82] Parsing PPD file ...
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:31:23 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:31:23 -0700] [Job 82] Added option ColorSpace
D [08/Jul/2012:16:31:23 -0700] [Job 82] Added option PageSize
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] [Job 82] Added option ImageableArea
D [08/Jul/2012:16:31:23 -0700] [Job 82] Added option PaperDimension
D [08/Jul/2012:16:31:23 -0700] [Job 82] Added option InputSlot
D [08/Jul/2012:16:31:23 -0700] [Job 82] Added option Manualfeed
D [08/Jul/2012:16:31:23 -0700] [Job 82] Added option Economode
D [08/Jul/2012:16:31:23 -0700] [Job 82] Added option Copies
D [08/Jul/2012:16:31:23 -0700] [Job 82] Printing on printer with URI: usb://HP/LaserJet%205L
D [08/Jul/2012:16:31:23 -0700] [Job 82] libusb_get_device_list=11
D [08/Jul/2012:16:31:23 -0700] [Job 82] Added option Resolution
D [08/Jul/2012:16:31:23 -0700] [Job 82] Added option REt
D [08/Jul/2012:16:31:23 -0700] [Job 82] Added option TonerDensity
D [08/Jul/2012:16:31:23 -0700] [Job 82] Added option MPTray
D [08/Jul/2012:16:31:23 -0700] [Job 82] Added option Font
D [08/Jul/2012:16:31:23 -0700] [Job 82] 
D [08/Jul/2012:16:31:23 -0700] [Job 82] Parameter Summary
D [08/Jul/2012:16:31:23 -0700] [Job 82] -----------------
D [08/Jul/2012:16:31:23 -0700] [Job 82] 
D [08/Jul/2012:16:31:23 -0700] [Job 82] Spooler: cups
D [08/Jul/2012:16:31:23 -0700] [Job 82] Printer: HP-LaserJet-5L
D [08/Jul/2012:16:31:23 -0700] [Job 82] Shell: /bin/bash
D [08/Jul/2012:16:31:23 -0700] [Job 82] PPD file: /etc/cups/ppd/HP-LaserJet-5L.ppd
D [08/Jul/2012:16:31:23 -0700] [Job 82] ATTR file: 
D [08/Jul/2012:16:31:23 -0700] [Job 82] Printer model: HP LaserJet 5L Foomatic/ljet4 (recommended)
D [08/Jul/2012:16:31:23 -0700] [Job 82] Job title: ppt_pptCard.pdf — Travel Docs Poster 7.2.10
D [08/Jul/2012:16:31:23 -0700] [Job 82] File(s) to be printed:
D [08/Jul/2012:16:31:23 -0700] [Job 82] <STDIN>
D [08/Jul/2012:16:31:23 -0700] [Job 82] 
D [08/Jul/2012:16:31:23 -0700] [Job 82] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [08/Jul/2012:16:31:23 -0700] [Job 82] Printing system options:
D [08/Jul/2012:16:31:23 -0700] [Job 82] Pondering option 'number-up=1'
D [08/Jul/2012:16:31:23 -0700] [Job 82] Unknown option number-up=1.
D [08/Jul/2012:16:31:23 -0700] [Job 82] Pondering option 'job-uuid=urn:uuid:c17406be-020b-3790-4a53-574f403bf843'
D [08/Jul/2012:16:31:23 -0700] [Job 82] Unknown option job-uuid=urn:uuid:c17406be-020b-3790-4a53-574f403bf843.
D [08/Jul/2012:16:31:23 -0700] [Job 82] Pondering option 'job-originating-host-name=localhost'
D [08/Jul/2012:16:31:23 -0700] [Job 82] Unknown option job-originating-host-name=localhost.
D [08/Jul/2012:16:31:23 -0700] [Job 82] Pondering option 'time-at-creation=1341790283'
D [08/Jul/2012:16:31:23 -0700] [Job 82] Unknown option time-at-creation=1341790283.
D [08/Jul/2012:16:31:23 -0700] [Job 82] Pondering option 'time-at-processing=1341790283'
D [08/Jul/2012:16:31:23 -0700] [Job 82] Unknown option time-at-processing=1341790283.
D [08/Jul/2012:16:31:23 -0700] [Job 82] Options from the PPD file:
D [08/Jul/2012:16:31:23 -0700] [Job 82] Pondering option 'InputSlot=Default'
D [08/Jul/2012:16:31:23 -0700] [Job 82] Pondering option 'TonerDensity=3'
D [08/Jul/2012:16:31:23 -0700] [Job 82] Pondering option 'PageSize=Letter'
D [08/Jul/2012:16:31:23 -0700] [Job 82] Pondering option 'REt=Medium'
D [08/Jul/2012:16:31:23 -0700] [Job 82] Pondering option 'MPTray=First'
D [08/Jul/2012:16:31:23 -0700] [Job 82] Pondering option 'Manualfeed=Off'
D [08/Jul/2012:16:31:23 -0700] [Job 82] Pondering option 'Economode=Off'
D [08/Jul/2012:16:31:23 -0700] [Job 82] Pondering option 'Resolution=600x600dpi'
D [08/Jul/2012:16:31:23 -0700] [Job 82] 
D [08/Jul/2012:16:31:23 -0700] [Job 82] ================================================
D [08/Jul/2012:16:31:23 -0700] [Job 82] 
D [08/Jul/2012:16:31:23 -0700] [Job 82] File: <STDIN>
D [08/Jul/2012:16:31:23 -0700] [Job 82] 
D [08/Jul/2012:16:31:23 -0700] [Job 82] ================================================
D [08/Jul/2012:16:31:23 -0700] [Job 82] 
D [08/Jul/2012:16:31:23 -0700] [Job 82] Filetype: PDF
D [08/Jul/2012:16:31:23 -0700] [Job 82] Storing temporary files in /var/spool/cups/tmp
D [08/Jul/2012:16:31:23 -0700] PID 4644 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [08/Jul/2012:16:31:23 -0700] [Job 82] File contains 1 pages
D [08/Jul/2012:16:31:23 -0700] [Job 82] Starting renderer with command: gs -dFirstPage=1  -q -dBATCH -dPARANOIDSAFER -dNOPAUSE -dNOINTERPOLATE -sDEVICE=ljet4 -dMediaPosition=0 -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -r600x600 -sOutputFile=- -f   /var/spool/cups/tmp/foomatic-bh4FHl 
D [08/Jul/2012:16:31:23 -0700] [Job 82] Starting process "kid3" (generation 1)
D [08/Jul/2012:16:31:23 -0700] [Job 82] Starting process "kid4" (generation 2)
D [08/Jul/2012:16:31:23 -0700] [Job 82] Starting process "renderer" (generation 2)
D [08/Jul/2012:16:31:23 -0700] [Job 82] JCL: %-12345X@PJL
D [08/Jul/2012:16:31:23 -0700] [Job 82] @PJL SET MPTRAY=FIRST
D [08/Jul/2012:16:31:23 -0700] [Job 82] @PJL SET DENSITY=3
D [08/Jul/2012:16:31:23 -0700] [Job 82] @PJL SET RET=MEDIUM
D [08/Jul/2012:16:31:23 -0700] [Job 82] @PJL SET COPIES=1
D [08/Jul/2012:16:31:23 -0700] [Job 82] @PJL SET ECONOMODE=OFF
D [08/Jul/2012:16:31:23 -0700] [Job 82] @PJL SET MANUALFEED=OFF
D [08/Jul/2012:16:31:23 -0700] [Job 82] <job data> %-12345X@PJL RESET
D [08/Jul/2012:16:31:23 -0700] [Job 82] 
D [08/Jul/2012:16:31:23 -0700] [Job 82] 
D [08/Jul/2012:16:31:23 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 20 1.1 Get-Notifications 1
D [08/Jul/2012:16:31:23 -0700] Get-Notifications /
D [08/Jul/2012:16:31:23 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:23 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 20 1.1 Get-Notifications 1
D [08/Jul/2012:16:31:23 -0700] Get-Notifications /
D [08/Jul/2012:16:31:23 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 20 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:31:23 -0700] Get-Job-Attributes ipp://localhost/jobs/82
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/82) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAcceptClient: 25 from localhost (Domain)
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:31:23 -0700] Get-Job-Attributes ipp://localhost/jobs/82
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/82) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 WAITING Closing on EOF
D [08/Jul/2012:16:31:23 -0700] cupsdCloseClient: 25
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAcceptClient: 25 from localhost (Domain)
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:31:23 -0700] Get-Job-Attributes ipp://localhost/jobs/82
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/82) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 WAITING Closing on EOF
D [08/Jul/2012:16:31:23 -0700] cupsdCloseClient: 25
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAcceptClient: 25 from localhost (Domain)
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:31:23 -0700] Get-Job-Attributes ipp://localhost/jobs/82
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/82) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 WAITING Closing on EOF
D [08/Jul/2012:16:31:23 -0700] cupsdCloseClient: 25
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:23 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 20 1.1 Get-Notifications 1
D [08/Jul/2012:16:31:23 -0700] Get-Notifications /
D [08/Jul/2012:16:31:23 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 20 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:31:23 -0700] Get-Job-Attributes ipp://localhost/jobs/82
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/82) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAcceptClient: 25 from localhost (Domain)
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 1.1 Get-Printer-Attributes 1
D [08/Jul/2012:16:31:23 -0700] Get-Printer-Attributes 
D [08/Jul/2012:16:31:23 -0700] Get-Printer-Attributes client-error-not-found: The printer or class does not exist.
D [08/Jul/2012:16:31:23 -0700] Returning IPP client-error-not-found for Get-Printer-Attributes () from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 WAITING Closing on EOF
D [08/Jul/2012:16:31:23 -0700] cupsdCloseClient: 25
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAcceptClient: 25 from localhost (Domain)
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:31:23 -0700] Get-Job-Attributes ipp://localhost/jobs/82
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/82) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 WAITING Closing on EOF
D [08/Jul/2012:16:31:23 -0700] cupsdCloseClient: 25
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAcceptClient: 25 from localhost (Domain)
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:31:23 -0700] Get-Job-Attributes ipp://localhost/jobs/82
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/82) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 25 WAITING Closing on EOF
D [08/Jul/2012:16:31:23 -0700] cupsdCloseClient: 25
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:23 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] [Job 82] STATE: +connecting-to-device
D [08/Jul/2012:16:31:23 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] [Notifier] state=3
D [08/Jul/2012:16:31:23 -0700] [Notifier] PrinterStateChanged
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:31:23 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:31:23 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:23 -0700] [Job 82] STATE: -connecting-to-device
D [08/Jul/2012:16:31:23 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
D [08/Jul/2012:16:31:23 -0700] [Notifier] state=3
D [08/Jul/2012:16:31:23 -0700] [Notifier] PrinterStateChanged
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:31:23 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:31:23 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] [Job 82] Device protocol: 2
D [08/Jul/2012:16:31:23 -0700] [Job 82] Printer reports bi-di support but in reality works only uni-directionally
D [08/Jul/2012:16:31:23 -0700] [Job 82] Uni-directional USB communication only!
D [08/Jul/2012:16:31:23 -0700] [Job 82] Printer does not like usblp kernel module to be re-attached after job
D [08/Jul/2012:16:31:23 -0700] [Job 82] Uni-directional device/mode, back channel deactivated.
I [08/Jul/2012:16:31:23 -0700] [Job 82] Sending data to printer.
D [08/Jul/2012:16:31:23 -0700] [Job 82] Set job-printer-state-message to "Sending data to printer.", current level=INFO
D [08/Jul/2012:16:31:23 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] [Notifier] state=3
D [08/Jul/2012:16:31:23 -0700] [Notifier] JobProgress
D [08/Jul/2012:16:31:23 -0700] [Notifier] state=3
D [08/Jul/2012:16:31:23 -0700] [Notifier] PrinterStateChanged
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:31:23 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:23 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:31:23 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:31:23 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:31:23 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:31:23 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:24 -0700] Report: clients=5
D [08/Jul/2012:16:31:24 -0700] Report: jobs=14
D [08/Jul/2012:16:31:24 -0700] Report: jobs-active=1
D [08/Jul/2012:16:31:24 -0700] Report: printers=1
D [08/Jul/2012:16:31:24 -0700] Report: printers-implicit=0
D [08/Jul/2012:16:31:24 -0700] Report: stringpool-string-count=2893
D [08/Jul/2012:16:31:24 -0700] Report: stringpool-alloc-bytes=10648
D [08/Jul/2012:16:31:24 -0700] Report: stringpool-total-bytes=57448
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:24 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:24 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Classes 1
D [08/Jul/2012:16:31:24 -0700] CUPS-Get-Classes
D [08/Jul/2012:16:31:24 -0700] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Default 1
D [08/Jul/2012:16:31:24 -0700] CUPS-Get-Default
D [08/Jul/2012:16:31:24 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 20 1.1 Get-Notifications 1
D [08/Jul/2012:16:31:24 -0700] Get-Notifications /
D [08/Jul/2012:16:31:24 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:31:24 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:24 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 20 1.1 Get-Notifications 1
D [08/Jul/2012:16:31:24 -0700] Get-Notifications /
D [08/Jul/2012:16:31:24 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:31:24 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdAcceptClient: 25 from localhost (Domain)
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 25 POST / HTTP/1.1
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 25 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:31:24 -0700] Get-Job-Attributes ipp://localhost/jobs/82
D [08/Jul/2012:16:31:24 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/82) from localhost
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 25 WAITING Closing on EOF
D [08/Jul/2012:16:31:24 -0700] cupsdCloseClient: 25
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:24 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 20 1.1 Get-Notifications 1
D [08/Jul/2012:16:31:24 -0700] Get-Notifications /
D [08/Jul/2012:16:31:24 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:31:24 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdAcceptClient: 25 from localhost (Domain)
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 25 POST / HTTP/1.1
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 25 1.1 Get-Job-Attributes 1
D [08/Jul/2012:16:31:24 -0700] Get-Job-Attributes ipp://localhost/jobs/82
D [08/Jul/2012:16:31:24 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/82) from localhost
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 25 WAITING Closing on EOF
D [08/Jul/2012:16:31:24 -0700] cupsdCloseClient: 25
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:24 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:24 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:24 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Classes 1
D [08/Jul/2012:16:31:24 -0700] CUPS-Get-Classes
D [08/Jul/2012:16:31:24 -0700] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:24 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:24 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Default 1
D [08/Jul/2012:16:31:24 -0700] CUPS-Get-Default
D [08/Jul/2012:16:31:24 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [08/Jul/2012:16:31:24 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [08/Jul/2012:16:31:24 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:24 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:24 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:24 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:24 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:24 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:24 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:24 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:24 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:24 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:24 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:25 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:25 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:25 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:25 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:25 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:25 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:25 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:25 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:25 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:25 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:25 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:25 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:26 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:26 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:26 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:26 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:26 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:26 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:26 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:26 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:26 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:26 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] renderer exited with status 0
D [08/Jul/2012:16:31:27 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:27 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:28 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:28 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:28 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:28 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:28 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:28 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:28 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:28 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:29 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:29 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:29 -0700] [Job 82] kid4 exited with status 0
D [08/Jul/2012:16:31:29 -0700] [Job 82] kid3 finished
D [08/Jul/2012:16:31:29 -0700] [Job 82] Kid3 exit status: 0
D [08/Jul/2012:16:31:29 -0700] [Job 82] 
D [08/Jul/2012:16:31:29 -0700] [Job 82] Closing foomatic-rip.
D [08/Jul/2012:16:31:29 -0700] PID 4645 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [08/Jul/2012:16:31:29 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:29 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:29 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:29 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:29 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:29 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:30 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:30 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:30 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:30 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:30 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:30 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:31 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:31 -0700] [Job 82] Read 8192 bytes of print data...
D [08/Jul/2012:16:31:31 -0700] [Job 82] Wrote 8192 bytes of print data...
D [08/Jul/2012:16:31:31 -0700] [Job 82] Read 6069 bytes of print data...
D [08/Jul/2012:16:31:31 -0700] [Job 82] Wrote 6069 bytes of print data...
D [08/Jul/2012:16:31:31 -0700] [Job 82] Sent 350133 bytes...
D [08/Jul/2012:16:31:31 -0700] [Job 82] Resetting printer.
D [08/Jul/2012:16:31:31 -0700] PID 4646 (/usr/lib/cups/backend/usb) exited with no errors.
D [08/Jul/2012:16:31:31 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
I [08/Jul/2012:16:31:31 -0700] [Job 82] Job completed.
D [08/Jul/2012:16:31:31 -0700] cupsdMarkDirty(----J-)
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:31 -0700] [Notifier] state=3
D [08/Jul/2012:16:31:31 -0700] [Notifier] JobCompleted
D [08/Jul/2012:16:31:31 -0700] [Notifier] state=3
D [08/Jul/2012:16:31:31 -0700] [Notifier] PrinterStateChanged
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 19 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:31 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 19 1.1 CUPS-Get-Default 1
D [08/Jul/2012:16:31:31 -0700] CUPS-Get-Default
D [08/Jul/2012:16:31:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:31:31 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:31:31 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:31:31 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:31:31 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 19 1.1 Get-Jobs 1
D [08/Jul/2012:16:31:31 -0700] Get-Jobs ipp://localhost/printers/HP-LaserJet-5L
D [08/Jul/2012:16:31:31 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/HP-LaserJet-5L) from localhost
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 20 1.1 Get-Notifications 1
D [08/Jul/2012:16:31:31 -0700] Get-Notifications /
D [08/Jul/2012:16:31:31 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:31:31 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:31 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 20 1.1 Get-Notifications 1
D [08/Jul/2012:16:31:31 -0700] Get-Notifications /
D [08/Jul/2012:16:31:31 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:31:31 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:31 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdAcceptClient: 20 from localhost (Domain)
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 20 POST / HTTP/1.1
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 20 1.1 Get-Notifications 1
D [08/Jul/2012:16:31:31 -0700] Get-Notifications /
D [08/Jul/2012:16:31:31 -0700] cupsdIsAuthorized: requesting-user-name="grabhorn"
D [08/Jul/2012:16:31:31 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 20 WAITING Closing on EOF
D [08/Jul/2012:16:31:31 -0700] cupsdCloseClient: 20
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Printers 1
D [08/Jul/2012:16:31:31 -0700] CUPS-Get-Printers
D [08/Jul/2012:16:31:31 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Classes 1
D [08/Jul/2012:16:31:31 -0700] CUPS-Get-Classes
D [08/Jul/2012:16:31:31 -0700] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [08/Jul/2012:16:31:31 -0700] cupsdAuthorize: No authentication data provided.
D [08/Jul/2012:16:31:31 -0700] cupsdReadClient: 18 1.1 CUPS-Get-Default 1
D [08/Jul/2012:16:31:31 -0700] CUPS-Get-Default
D [08/Jul/2012:16:31:31 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [08/Jul/2012:16:31:31 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [08/Jul/2012:16:31:32 -0700] [Job 82] Unloading...
I [08/Jul/2012:16:31:54 -0700] Saving job.cache...
I [08/Jul/2012:16:31:55 -0700] Saving subscriptions.conf...
D [08/Jul/2012:16:31:55 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [08/Jul/2012:16:33:08 -0700] cupsdReadClient: 19 POST / HTTP/1.1
D [08/Jul/2012:16:33:08 -0700] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [08/Jul/2012:16:33:08 -0700] cupsdAuthorize: Authorized as grabhorn using PeerCred
D [08/Jul/2012:16:33:08 -0700] Report: clients=5
D [08/Jul/2012:16:33:08 -0700] Report: jobs=14
D [08/Jul/2012:16:33:08 -0700] Report: jobs-active=0
D [08/Jul/2012:16:33:08 -0700] Report: printers=1
D [08/Jul/2012:16:33:08 -0700] Report: printers-implicit=0
D [08/Jul/2012:16:33:08 -0700] Report: stringpool-string-count=2995
D [08/Jul/2012:16:33:08 -0700] Report: stringpool-alloc-bytes=9784
D [08/Jul/2012:16:33:08 -0700] Report: stringpool-total-bytes=59768
D [08/Jul/2012:16:33:08 -0700] cupsdReadClient: 19 1.1 Renew-Subscription 1
D [08/Jul/2012:16:33:08 -0700] Renew-Subscription /
D [08/Jul/2012:16:33:08 -0700] cupsdIsAuthorized: username="grabhorn"
D [08/Jul/2012:16:33:08 -0700] cupsdMarkDirty(-----S)
D [08/Jul/2012:16:33:08 -0700] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [08/Jul/2012:16:33:08 -0700] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [08/Jul/2012:16:33:08 -0700] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [08/Jul/2012:16:33:39 -0700] Saving subscriptions.conf...
D [08/Jul/2012:16:33:39 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"