CUPS - Printing to Print Server - Cannot Print from Client

wkatastrof · 2013-08-19 01:22:43

Greetings,

I am running Archlinux on a netbook and a Debian 6 machine setup as print server in my home all on the same subnet using a linksys router running tomato. Arch is running Cups 1.6.3 and Debian is running Cups 1.4.4.

I was able to print to the print server when I was running Ubuntu 11.10 on the netbook (don't remember which cups version), but haven't been able to with Arch.

I have already tried the work around that forces the cups client to default to ipp v1.1 rather than v2 with no difference in result.
I can print a test page from the print server successfully, but I cannot print a test page from the client successfully. (Nor anything else for that matter.) I am using the web interface for both clients and I can access the print server web interface from my netbook.

I also have cups-browsed and avahi-daemon installed and running.

Here is some info on the client, server, and printer setup. Then below that the queue results from printing a test page from both the client and server, and then printing a document from the client. There are also the Error logs from both client and server (with debugging turned on) when the document was printed from the client.

You'll see that when I print from the client, the job seems to go to the server (and shows as completed in the queue), but the printer never comes out of "standby" to print anything.

Printer Info on Server:
Description: Brother HL-5150DLT
Location: anti-office
Driver: Brother HL-5150D - CUPS+Gutenprint v5.2.6 (grayscale, 2-sided printing)
Connection: parallel:/dev/lp0
Defaults: job-sheets=none, none media=na_letter_8.5x11in sides=two-sided-long-edge

Printer Info on Client:
Description: Brother HL-5150DLT
Location: anti-office
Driver: Brother HL-5150D - CUPS+Gutenprint v5.2.9 (grayscale, 2-sided printing)
Connection: ipp://192.168.3.102:631/printers/BrotherHL5150DLT
Defaults: job-sheets=none, none media=na_letter_8.5x11in sides=two-sided-long-edge

*Printing a Test Page*

Prints Successfully:
Job Status when I print a test page from the print server:
BrotherHL5150DLT-69 Test Page anonymous 261k Unknown completed at
Sat 17 Aug 2013 09:43:30 AM EDT

Prints Unsuccessfully:
Job Status when I print a test page from the client (from the *client's* web interface jobs list):
BrotherHL5150DLT-20 Test Page anonymous 1k Unknown completed at
Sat 17 Aug 2013 09:51:00 AM EDT

Job Status when I print a test page from the client (from the *server's* web interface jobs list):
BrotherHL5150DLT-70 Test Page anonymous 261k Unknown completed at
Sat 17 Aug 2013 09:51:11 AM EDT

*Printing a Document*

Prints Unsuccessfully:
Job status when I print a document from the client (from the *client's* web interface jobs list):
BrotherHL5150DLT-23 Unknown Withheld 20k Unknown completed at
Sun 18 Aug 2013 09:05:22 PM EDT

Job status when I print a document from the client (from the *server's* web interface jobs list):
BrotherHL5150DLT-74 rxweeks_somenotes201206 jacques 113k Unknown completed at
Sun 18 Aug 2013 09:05:21 PM EDT

Client Error Log w/ Debugging:

D [18/Aug/2013:21:05:08 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:08 -0400] Report: clients=1
D [18/Aug/2013:21:05:08 -0400] Report: jobs=1
D [18/Aug/2013:21:05:08 -0400] Report: jobs-active=0
D [18/Aug/2013:21:05:08 -0400] Report: printers=1
D [18/Aug/2013:21:05:08 -0400] Report: stringpool-string-count=4358
D [18/Aug/2013:21:05:08 -0400] Report: stringpool-alloc-bytes=8776
D [18/Aug/2013:21:05:08 -0400] Report: stringpool-total-bytes=55112
D [18/Aug/2013:21:05:08 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:08 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:08 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:08 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:09 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:09 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:09 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:09 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:09 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:09 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:09 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:09 -0400] [Client 15] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:09 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:09 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:09 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:09 -0400] [Client 15] GET /printers/BrotherHL5150DLT.ppd HTTP/1.1
D [18/Aug/2013:21:05:09 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:09 -0400] [Client 15] No authentication data provided.
D [18/Aug/2013:21:05:09 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:09 -0400] [Client 15] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:09 -0400] [Client 15] Closing connection.
D [18/Aug/2013:21:05:09 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:09 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:09 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:09 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:09 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:09 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:09 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:09 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:09 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:09 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:09 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:09 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:09 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:09 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:09 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:09 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:10 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:10 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:10 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:10 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:10 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:10 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:10 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:10 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:10 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:10 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:10 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:10 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:10 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:10 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:10 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:10 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:10 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:10 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:10 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:10 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:10 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:10 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:10 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:10 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:10 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:10 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:10 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:10 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:10 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:10 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:10 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:10 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:10 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:10 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:10 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:10 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:10 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:10 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:10 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:10 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:10 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:10 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:10 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:10 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:10 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:11 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:11 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:11 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:11 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:11 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:11 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:11 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:11 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:11 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:11 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:11 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:11 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:11 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:11 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:11 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:11 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:11 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:11 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:11 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:11 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:11 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:11 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:11 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:11 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:11 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:11 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:11 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:11 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:11 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:11 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:11 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:11 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:11 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:11 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:11 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:11 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:11 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:11 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:11 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:11 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:11 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:11 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:11 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:11 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:11 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:11 -0400] [Client 15] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:11 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:11 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:11 -0400] [Client 15] POST / HTTP/1.1
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:11 -0400] [Client 15] No authentication data provided.
D [18/Aug/2013:21:05:11 -0400] [Client 15] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:11 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:11 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:11 -0400] [Client 15] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:11 -0400] [Client 15] Closing connection.
D [18/Aug/2013:21:05:11 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:12 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:12 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:12 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:12 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:12 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:12 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:12 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:12 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:12 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:12 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:12 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:12 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:12 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:12 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:12 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:12 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:12 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:12 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:12 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:12 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:12 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:12 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:12 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:12 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:12 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:12 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:12 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:12 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:12 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:12 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:12 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:12 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:12 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:12 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:12 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:12 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:12 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:12 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:12 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:12 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:12 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:12 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:12 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:12 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:12 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:12 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:12 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:12 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:12 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:12 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:12 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:12 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:12 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:12 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:12 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:12 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:13 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:13 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:13 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:13 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:13 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:13 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:13 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:13 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:13 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:13 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:13 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:13 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:13 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:13 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:13 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:13 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:13 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:13 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:13 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:13 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:13 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:13 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:13 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:13 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:13 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:13 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:13 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:13 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:13 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:13 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:13 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:13 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:13 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:13 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:13 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:13 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:13 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:13 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:13 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:13 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:13 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:13 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:13 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:13 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:13 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:13 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:13 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:13 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:13 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:13 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:13 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:13 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:13 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:13 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:13 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:13 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:14 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:14 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:14 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:14 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:14 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:14 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:14 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:14 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:14 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:14 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:14 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:14 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:14 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:14 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:14 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:14 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:14 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:14 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:14 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:14 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:14 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:14 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:14 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:14 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:14 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:14 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:14 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:14 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:14 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:14 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:14 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:14 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:14 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:14 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:14 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:14 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:14 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:14 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:14 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:14 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:14 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:14 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:14 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:14 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:14 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:14 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:14 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:14 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:14 -0400] [Client 14] POST / HTTP/1.1
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:14 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:14 -0400] [Client 14] 2.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:05:14 -0400] CUPS-Get-Printers
D [18/Aug/2013:21:05:14 -0400] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [18/Aug/2013:21:05:14 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:14 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:14 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:05:16 -0400] [Client 14] Accepted from localhost (Domain)
D [18/Aug/2013:21:05:16 -0400] [Client 14] POST /printers/BrotherHL5150DLT HTTP/1.1
D [18/Aug/2013:21:05:16 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:05:16 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:16 -0400] [Client 14] 2.1 Print-Job 1
D [18/Aug/2013:21:05:16 -0400] Print-Job ipp://localhost:631/printers/BrotherHL5150DLT
D [18/Aug/2013:21:05:16 -0400] [Job ???] Auto-typing file...
I [18/Aug/2013:21:05:16 -0400] [Job ???] Request file type is application/pdf.
D [18/Aug/2013:21:05:16 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:16 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [18/Aug/2013:21:05:16 -0400] add_job: requesting-user-name="jacques"
I [18/Aug/2013:21:05:16 -0400] [Job 23] Adding start banner page "none".
D [18/Aug/2013:21:05:16 -0400] Discarding unused job-created event...
D [18/Aug/2013:21:05:16 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:16 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [18/Aug/2013:21:05:16 -0400] [Job 23] Adding end banner page "none".
I [18/Aug/2013:21:05:16 -0400] [Job 23] File of type application/pdf queued by "jacques".
D [18/Aug/2013:21:05:16 -0400] [Job 23] hold_until=0
I [18/Aug/2013:21:05:16 -0400] [Job 23] Queued on "BrotherHL5150DLT" by "jacques".
D [18/Aug/2013:21:05:16 -0400] [Job 23] time-at-processing=1376874316
D [18/Aug/2013:21:05:16 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:16 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [18/Aug/2013:21:05:16 -0400] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [18/Aug/2013:21:05:16 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:16 -0400] [Job 23] 3 filters for job:
D [18/Aug/2013:21:05:16 -0400] [Job 23] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [18/Aug/2013:21:05:16 -0400] [Job 23] gstoraster (application/vnd.cups-pdf to application/vnd.cups-raster, cost 66)
D [18/Aug/2013:21:05:16 -0400] [Job 23] rastertogutenprint.5.2 (application/vnd.cups-raster to printer/BrotherHL5150DLT, cost 100)
D [18/Aug/2013:21:05:16 -0400] [Job 23] job-sheets=none,none
D [18/Aug/2013:21:05:16 -0400] [Job 23] argv[0]="BrotherHL5150DLT"
D [18/Aug/2013:21:05:16 -0400] [Job 23] argv[1]="23"
D [18/Aug/2013:21:05:16 -0400] [Job 23] argv[2]="jacques"
D [18/Aug/2013:21:05:16 -0400] [Job 23] argv[3]="rxweeks_somenotes201206"
D [18/Aug/2013:21:05:16 -0400] [Job 23] argv[4]="1"
D [18/Aug/2013:21:05:16 -0400] [Job 23] argv[5]="StpFineBrightness=None StpGamma=None StpBrightness=None Duplex=DuplexNoTumble number-up=1 PageSize=Letter Resolution=301x300dpi InputSlot=Standard ColorModel=Gray StpDitherAlgorithm=None StpColorCorrection=None StpQuality=Standard StpColorPrecision=Normal StpFineContrast=None StpContrast=None StpImageType=TextGraphics StpDensity=None StpFineGamma=None StpiShrinkOutput=Shrink StpFineDensity=None noStpLinearContrast job-uuid=urn:uuid:e49f19e2-b14e-3b51-70f8-c89a69c5277b job-originating-host-name=localhost time-at-creation=1376874316 time-at-processing=1376874316"
D [18/Aug/2013:21:05:16 -0400] [Job 23] argv[6]="/var/spool/cups/d00023-001"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[7]="CUPS_STATEDIR=/run/cups"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[8]="HOME=/var/spool/cups/tmp"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[10]="SERVER_ADMIN=root@tinydada-a"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[11]="SOFTWARE=CUPS/1.6.3"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[13]="USER=root"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[14]="CUPS_MAX_MESSAGE=2047"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[15]="CUPS_SERVER=/run/cups/cups.sock"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[17]="IPP_PORT=631"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[18]="CHARSET=utf-8"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[19]="LANG=en_US.UTF-8"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[20]="PPD=/etc/cups/ppd/BrotherHL5150DLT.ppd"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[21]="RIP_MAX_CACHE=128m"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[22]="CONTENT_TYPE=application/pdf"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[23]="DEVICE_URI=ipp://192.168.3.102:631/printers/BrotherHL5150DLT"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[24]="PRINTER_INFO=Brother HL-5150DLT"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[25]="PRINTER_LOCATION=anti-office"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[26]="PRINTER=BrotherHL5150DLT"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[27]="PRINTER_STATE_REASONS=none"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[28]="CUPS_FILETYPE=document"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-raster"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[30]="AUTH_I****"
I [18/Aug/2013:21:05:16 -0400] [Job 23] Started filter /usr/lib/cups/filter/pdftopdf (PID 9414)
I [18/Aug/2013:21:05:16 -0400] [Job 23] Started filter /usr/lib/cups/filter/gstoraster (PID 9415)
I [18/Aug/2013:21:05:16 -0400] [Job 23] Started filter /usr/lib/cups/filter/rastertogutenprint.5.2 (PID 9416)
I [18/Aug/2013:21:05:16 -0400] [Job 23] Started backend /usr/lib/cups/backend/ipp (PID 9417)
D [18/Aug/2013:21:05:16 -0400] Discarding unused job-state-changed event...
D [18/Aug/2013:21:05:16 -0400] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/BrotherHL5150DLT) from localhost
D [18/Aug/2013:21:05:16 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files"
D [18/Aug/2013:21:05:16 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:16 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:16 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:16 -0400] [Job 23] Sending stdin for job...
D [18/Aug/2013:21:05:16 -0400] [Job 23] update_reasons(attr=0(), s="+connecting-to-device")
D [18/Aug/2013:21:05:16 -0400] [Job 23] STATE: +connecting-to-device
D [18/Aug/2013:21:05:16 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:16 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:16 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:16 -0400] [Job 23] Looking up "192.168.3.102"...
D [18/Aug/2013:21:05:16 -0400] [Job 23] WARN: not compiled with DBus support
D [18/Aug/2013:21:05:16 -0400] [Job 23] PPD uses qualifier 'Gray..301x300dpi'
D [18/Aug/2013:21:05:16 -0400] [Job 23] WARN: not compiled with DBus support
I [18/Aug/2013:21:05:16 -0400] [Job 23] no profiles specified in PPD
D [18/Aug/2013:21:05:16 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:16 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:16 -0400] [Job 23] Set job-printer-state-message to "no profiles specified in PPD", current level=INFO
D [18/Aug/2013:21:05:16 -0400] [Job 23] Ghostscript command line: /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -sDEVICE=cups -sstdout=%stderr -sOutputFile=%stdout -sMediaClass=Standard -dDuplex -r300x300 -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -dcupsBitsPerColor=8 -dcupsColorOrder=0 -dcupsColorSpace=0 -dcupsRowFeed=3 -scupsPageSizeName=Letter -I/usr/share/cups/fonts -c -f -_
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[7]="CUPS_STATEDIR=/run/cups"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[8]="HOME=/var/spool/cups/tmp"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[10]="SERVER_ADMIN=root@tinydada-a"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[11]="SOFTWARE=CUPS/1.6.3"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[13]="USER=root"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[14]="CUPS_MAX_MESSAGE=2047"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[15]="CUPS_SERVER=/run/cups/cups.sock"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[17]="IPP_PORT=631"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[18]="CHARSET=utf-8"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[19]="LANG=en_US.UTF-8"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[20]="PPD=/etc/cups/ppd/BrotherHL5150DLT.ppd"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[21]="RIP_MAX_CACHE=128m"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[22]="CONTENT_TYPE=application/pdf"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[23]="DEVICE_URI=ipp://192.168.3.102:631/printers/BrotherHL5150DLT"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[24]="PRINTER_INFO=Brother HL-5150DLT"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[25]="PRINTER_LOCATION=anti-office"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[26]="PRINTER=BrotherHL5150DLT"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[27]="PRINTER_STATE_REASONS=none"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[28]="CUPS_FILETYPE=document"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-raster"
D [18/Aug/2013:21:05:16 -0400] [Job 23] envp[30]="AUTH_INFO_REQUIRED=none"
D [18/Aug/2013:21:05:16 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:16 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:16 -0400] [Job 23] PID 9414 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint: ============================================================
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint: VVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVVV
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint: 5.2.9 Starting
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint: command line: BrotherHL5150DLT '23' 'jacques' 'rxweeks_somenotes201206' '1' <args>
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint: using PPD file /etc/cups/ppd/BrotherHL5150DLT.ppd
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint: CUPS option count is 25 (566 bytes)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 0 ColorModel = Gray
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 1 Duplex = DuplexNoTumble
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 2 InputSlot = Standard
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 3 job-originating-host-name = localhost
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 4 job-uuid = urn:uuid:e49f19e2-b14e-3b51-70f8-c89a69c5277b
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 5 number-up = 1
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 6 PageSize = Letter
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 7 Resolution = 301x300dpi
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 8 StpBrightness = None
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 9 StpColorCorrection = None
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 10 StpColorPrecision = Normal
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 11 StpContrast = None
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 12 StpDensity = None
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 13 StpDitherAlgorithm = None
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 14 StpFineBrightness = None
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 15 StpFineContrast = None
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 16 StpFineDensity = None
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 17 StpFineGamma = None
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 18 StpGamma = None
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 19 StpImageType = TextGraphics
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 20 StpiShrinkOutput = Shrink
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 21 StpLinearContrast = false
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 22 StpQuality = Standard
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 23 time-at-creation = 1376874316
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:  CUPS option 24 time-at-processing = 1376874316
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint: Driver Brother HL-5150D
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint: Using fd 0
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint: Set options:
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting PageSize to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting MediaType to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting InputSlot to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:   Set string Quality to Standard
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:   Set special string Quality to Standard
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting Resolution to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting InkType to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting InkChannels to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting PrintingMode to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting Duplex to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:   Set string ColorCorrection to None
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:   Set special string ColorCorrection to None
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting ChannelBitDepth to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting InputImageType to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting STPIOutputType to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting STPIRawChannels to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting SimpleGamma to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:   Set bool LinearContrast to false (0)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting LUTDumpFile to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CyanCurve to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting MagentaCurve to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting YellowCurve to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting BlackCurve to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting RedCurve to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting GreenCurve to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting BlueCurve to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting WhiteCurve to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting HueMap to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting SatMap to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting LumMap to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting GCRCurve to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh0 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh1 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh2 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh3 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh4 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh5 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh6 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh7 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh8 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh9 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh10 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh11 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh12 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh13 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh14 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh15 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh16 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh17 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh18 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh19 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh20 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh21 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh22 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh23 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh24 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh25 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh26 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh27 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh28 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh29 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh30 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting CurveCh31 to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:   Set string DitherAlgorithm to None
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:   Set special string DitherAlgorithm to None
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:   Set string ImageType to TextGraphics
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:   Set special string ImageType to TextGraphics
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting JobMode to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint:     Not setting PageNumber to (null)
D [18/Aug/2013:21:05:17 -0400] [Job 23] Gutenprint: End options
I [18/Aug/2013:21:05:18 -0400] [Job 23] Start rendering...
D [18/Aug/2013:21:05:18 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:18 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:18 -0400] [Job 23] Set job-printer-state-message to "Start rendering...", current level=INFO
I [18/Aug/2013:21:05:18 -0400] [Job 23] Processing page 1...
D [18/Aug/2013:21:05:18 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:18 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:18 -0400] [Job 23] Set job-printer-state-message to "Processing page 1...", current level=INFO
D [18/Aug/2013:21:05:18 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:18 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint: About to start printing loop.
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint: ================ Printing page 1      ================
D [18/Aug/2013:21:05:18 -0400] [Job 23] PAGE: 1 1
D [18/Aug/2013:21:05:18 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint: Initialize page
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Set special string ChannelBitDepth to 8
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Set special string PrintingMode to BW
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Set special string InputImageType to Whitescale
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Clear special parameter Resolution
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Set special parameter Quality to choice 2 (Standard)
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Set special string InputSlot to Standard
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   PageSize = 612x792
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Using page size Letter with (792, 612)
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Set special string PageSize to Letter
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Set special string Duplex to DuplexNoTumble
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Set special string JobMode to Job
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Validating options
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:     Clearing string MediaType ((null))
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:     Clearing string Resolution ((null))
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:     Setting default string Resolution to None
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:     Clearing string InkType ((null))
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:     Clearing string STPIOutputType ((null))
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:     Setting default string STPIOutputType to (null)
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Done validating options
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   limits w 612 l 18 r 594  h 792 t 12 b 780
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   max limits l 18 r 594 t 12 b 780
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Adjusting left margin from 18 to 18
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Adjusting right margin from 594 to 594
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Adjusting top margin from 12 to 12
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Adjusting bottom margin from 780 to 780
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   CUPS settings w 2400 l 75 r 75  h 3200 t 50 b 50
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:        adjusted w 2400 h 3200
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint: End initialize page
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint: Interim page settings:
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint: === BEGIN GUTENPRINT SETTINGS ===
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:     Driver: brother-hl-5150d
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:     L: 18  T: 12  W: 576  H: 768
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:     Page: 612x792
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:     Conversion: traditional
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (PageSize) (2) (String) [Letter]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (InputSlot) (2) (String) [Standard]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (Quality) (2) (String) [Standard]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (PrintingMode) (2) (String) [BW]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (Duplex) (2) (String) [DuplexNoTumble]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (ColorCorrection) (2) (String) [None]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (ChannelBitDepth) (2) (String) [8]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (InputImageType) (2) (String) [Whitescale]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (DitherAlgorithm) (2) (String) [None]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (ImageType) (2) (String) [TextGraphics]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (JobMode) (2) (String) [Job]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (Resolution) (2) (String) [None]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (STPIRawChannels) (2) (Int) [1]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (CUPSShrinkPage) (2) (Int) [1]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (LinearContrast) (2) (Bool) [0]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (Brightness) (2) (Double) [1.000000]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (Contrast) (2) (Double) [1.000000]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (Saturation) (2) (Double) [1.000000]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (AppGamma) (2) (Double) [1.000000]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint: === END GUTENPRINT SETTINGS ===
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint: Page data:
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   MediaClass = "Standard"
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   MediaColor = ""
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   MediaType = ""
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   OutputType = ""
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   AdvanceDistance = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   AdvanceMedia = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Collate = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   CutMedia = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Duplex = 1
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   HWResolution = [ 300 300 ]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   ImagingBoundingBox = [ 18 12 594 780 ]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   InsertSheet = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Jog = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   LeadingEdge = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Margins = [ 18 12 ]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   ManualFeed = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   MediaPosition = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   MediaWeight = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   MirrorPrint = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   NegativePrint = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   NumCopies = 1
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Orientation = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   OutputFaceUp = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   PageSize = [ 612 792 ]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Separations = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   TraySwitch = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   Tumble = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   cupsWidth = 2400
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   cupsHeight = 3200
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   cups->width = 2400
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   cups->height = 3200
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   cups->adjusted_width = 2400
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   cups->adjusted_height = 3200
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   cupsMediaType = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   cupsBitsPerColor = 8
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   cupsBitsPerPixel = 8
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   cupsBytesPerLine = 2400
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   cupsColorOrder = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   cupsColorSpace = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   cupsCompression = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   cupsRowCount = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   cupsRowFeed = 3
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   cupsRowStep = 0
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:   shrink page to fit 1
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint: === BEGIN GUTENPRINT SETTINGS ===
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:     Driver: brother-hl-5150d
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:     L: 18  T: 12  W: 576  H: 768
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:     Page: 612x792
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:     Conversion: traditional
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (PageSize) (2) (String) [Letter]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (InputSlot) (2) (String) [Standard]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (Quality) (2) (String) [Standard]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (PrintingMode) (2) (String) [BW]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (Duplex) (2) (String) [DuplexNoTumble]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (ColorCorrection) (2) (String) [None]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (ChannelBitDepth) (2) (String) [8]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (InputImageType) (2) (String) [Whitescale]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (DitherAlgorithm) (2) (String) [None]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (ImageType) (2) (String) [TextGraphics]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (JobMode) (2) (String) [Job]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (Resolution) (2) (String) [None]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (STPIRawChannels) (2) (Int) [1]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (CUPSShrinkPage) (2) (Int) [1]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (PageNumber) (2) (Int) [0]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (LinearContrast) (2) (Bool) [0]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (Brightness) (2) (Double) [1.000000]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (Contrast) (2) (Double) [1.000000]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (Saturation) (2) (Double) [1.000000]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (AppGamma) (2) (Double) [1.000000]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (Gamma) (1) (Double) [1.000000]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint:         (Density) (1) (Double) [1.000000]
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint: === END GUTENPRINT SETTINGS ===
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint: End page data
I [18/Aug/2013:21:05:18 -0400] [Job 23] Starting page 1...
D [18/Aug/2013:21:05:18 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:18 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:18 -0400] [Job 23] Set job-printer-state-message to "Starting page 1...", current level=INFO
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint: Image_height 3200
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint: Image_width 2400
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint: Image_height 3200
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint: Image_width 2400
D [18/Aug/2013:21:05:18 -0400] [Job 23] Gutenprint: Image_width 2400
D [18/Aug/2013:21:05:18 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:18 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] Gutenprint: Image_width 2400
D [18/Aug/2013:21:05:19 -0400] [Job 23] Gutenprint: Image_width 2400
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 1%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 1%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=1
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 2%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 2%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=2
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 3%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 3%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=3
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 4%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 4%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=4
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 5%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 5%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=5
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 6%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 6%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=6
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 7%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 7%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=7
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 8%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 8%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=8
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 9%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 9%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=9
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 10%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 10%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=10
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 11%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 11%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=11
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 12%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 12%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=12
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 13%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 13%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=13
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 14%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 14%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=14
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 15%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 15%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=15
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 16%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 16%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=16
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 17%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 17%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=17
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 18%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 18%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=18
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 19%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 19%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=19
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 20%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 20%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=20
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 21%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 21%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=21
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 22%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 22%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=22
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 23%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 23%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=23
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 24%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 24%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=24
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 25%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 25%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=25
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
I [18/Aug/2013:21:05:19 -0400] [Job 23] Printing page 1, 26%
D [18/Aug/2013:21:05:19 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:19 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:19 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 26%", current level=INFO
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:19 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:19 -0400] [Job 23] ATTR: job-media-progress=26
D [18/Aug/2013:21:05:19 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:20 -0400] [Job 23] Printing page 1, 27%
D [18/Aug/2013:21:05:20 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:20 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 27%", current level=INFO
D [18/Aug/2013:21:05:20 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:20 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:20 -0400] [Job 23] ATTR: job-media-progress=27
D [18/Aug/2013:21:05:20 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:20 -0400] [Job 23] Printing page 1, 28%
D [18/Aug/2013:21:05:20 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:20 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 28%", current level=INFO
D [18/Aug/2013:21:05:20 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:20 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:20 -0400] [Job 23] ATTR: job-media-progress=28
D [18/Aug/2013:21:05:20 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:20 -0400] [Job 23] Printing page 1, 29%
D [18/Aug/2013:21:05:20 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:20 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 29%", current level=INFO
D [18/Aug/2013:21:05:20 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:20 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:20 -0400] [Job 23] ATTR: job-media-progress=29
D [18/Aug/2013:21:05:20 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:20 -0400] [Job 23] Printing page 1, 30%
D [18/Aug/2013:21:05:20 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:20 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 30%", current level=INFO
D [18/Aug/2013:21:05:20 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:20 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:20 -0400] [Job 23] ATTR: job-media-progress=30
D [18/Aug/2013:21:05:20 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:20 -0400] [Job 23] Printing page 1, 31%
D [18/Aug/2013:21:05:20 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:20 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 31%", current level=INFO
D [18/Aug/2013:21:05:20 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:20 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:20 -0400] [Job 23] ATTR: job-media-progress=31
D [18/Aug/2013:21:05:20 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:20 -0400] [Job 23] prtGeneralCurrentLocalization type is 0, expected 2!
D [18/Aug/2013:21:05:20 -0400] [Job 23] backendWaitLoop(snmp_fd=6, addr=0xb9641764, side_cb=0xb772d180)
D [18/Aug/2013:21:05:20 -0400] [Job 23] Connecting to 192.168.3.102:631
I [18/Aug/2013:21:05:20 -0400] [Job 23] Connecting to printer.
D [18/Aug/2013:21:05:20 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [18/Aug/2013:21:05:20 -0400] [Job 23] Set job-printer-state-message to "Connecting to printer.", current level=INFO
D [18/Aug/2013:21:05:20 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:20 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:20 -0400] [Job 23] update_reasons(attr=0(), s="-cups-certificate-error")
D [18/Aug/2013:21:05:20 -0400] [Job 23] update_reasons(attr=0(), s="-connecting-to-device")
D [18/Aug/2013:21:05:20 -0400] [Job 23] STATE: -connecting-to-device
D [18/Aug/2013:21:05:20 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
I [18/Aug/2013:21:05:20 -0400] [Job 23] Connected to printer.
D [18/Aug/2013:21:05:20 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:20 -0400] [Job 23] Set job-printer-state-message to "Connected to printer.", current level=INFO
D [18/Aug/2013:21:05:20 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:20 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:20 -0400] [Job 23] Connected to 192.168.3.102:631...
D [18/Aug/2013:21:05:20 -0400] [Job 23] Getting supported attributes...
D [18/Aug/2013:21:05:20 -0400] [Job 23] Get-Printer-Attributes: successful-ok (successful-ok)
D [18/Aug/2013:21:05:20 -0400] [Job 23] copies-supported=1-9999
D [18/Aug/2013:21:05:20 -0400] [Job 23] document-format-supported (32 values)
D [18/Aug/2013:21:05:20 -0400] [Job 23] [0] = "application/octet-stream"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [1] = "application/openofficeps"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [2] = "application/pdf"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [3] = "application/postscript"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [4] = "application/vnd.cups-banner"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [5] = "application/vnd.cups-pdf"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [6] = "application/vnd.cups-postscript"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [7] = "application/vnd.cups-raster"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [8] = "application/vnd.cups-raw"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [9] = "application/vnd.hp-hpgl"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [10] = "application/x-cshell"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [11] = "application/x-csource"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [12] = "application/x-perl"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [13] = "application/x-shell"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [14] = "image/gif"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [15] = "image/jpeg"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [16] = "image/png"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [17] = "image/tiff"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [18] = "image/x-bitmap"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [19] = "image/x-photocd"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [20] = "image/x-portable-anymap"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [21] = "image/x-portable-bitmap"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [22] = "image/x-portable-graymap"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [23] = "image/x-portable-pixmap"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [24] = "image/x-sgi-rgb"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [25] = "image/x-sun-raster"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [26] = "image/x-xbitmap"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [27] = "image/x-xpixmap"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [28] = "image/x-xwindowdump"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [29] = "text/css"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [30] = "text/html"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [31] = "text/plain"
D [18/Aug/2013:21:05:20 -0400] [Job 23] media-col-supported (7 values)
D [18/Aug/2013:21:05:20 -0400] [Job 23] [0] = "media-bottom-margin"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [1] = "media-left-margin"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [2] = "media-right-margin"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [3] = "media-size"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [4] = "media-source"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [5] = "media-top-margin"
D [18/Aug/2013:21:05:20 -0400] [Job 23] [6] = "media-type"
D [18/Aug/2013:21:05:20 -0400] [Job 23] operations-supported (43 values)
D [18/Aug/2013:21:05:20 -0400] [Job 23] [0] = Print-Job
D [18/Aug/2013:21:05:20 -0400] [Job 23] [1] = Validate-Job
D [18/Aug/2013:21:05:20 -0400] [Job 23] [2] = Create-Job
D [18/Aug/2013:21:05:20 -0400] [Job 23] [3] = Send-Document
D [18/Aug/2013:21:05:20 -0400] [Job 23] [4] = Cancel-Job
D [18/Aug/2013:21:05:20 -0400] [Job 23] [5] = Get-Job-Attributes
D [18/Aug/2013:21:05:20 -0400] [Job 23] [6] = Get-Jobs
D [18/Aug/2013:21:05:20 -0400] [Job 23] [7] = Get-Printer-Attributes
D [18/Aug/2013:21:05:20 -0400] [Job 23] [8] = Hold-Job
D [18/Aug/2013:21:05:20 -0400] [Job 23] [9] = Release-Job
D [18/Aug/2013:21:05:20 -0400] [Job 23] [10] = Pause-Printer
D [18/Aug/2013:21:05:20 -0400] [Job 23] [11] = Resume-Printer
D [18/Aug/2013:21:05:20 -0400] [Job 23] [12] = Purge-Jobs
D [18/Aug/2013:21:05:20 -0400] [Job 23] [13] = Set-Printer-Attributes
D [18/Aug/2013:21:05:20 -0400] [Job 23] [14] = Set-Job-Attributes
D [18/Aug/2013:21:05:20 -0400] [Job 23] [15] = Get-Printer-Supported-Values
D [18/Aug/2013:21:05:20 -0400] [Job 23] [16] = Create-Printer-Subscription
D [18/Aug/2013:21:05:20 -0400] [Job 23] [17] = Create-Job-Subscription
D [18/Aug/2013:21:05:20 -0400] [Job 23] [18] = Get-Subscription-Attributes
D [18/Aug/2013:21:05:20 -0400] [Job 23] [19] = Get-Subscriptions
D [18/Aug/2013:21:05:20 -0400] [Job 23] [20] = Renew-Subscription
D [18/Aug/2013:21:05:20 -0400] [Job 23] [21] = Cancel-Subscription
D [18/Aug/2013:21:05:20 -0400] [Job 23] [22] = Get-Notifications
D [18/Aug/2013:21:05:20 -0400] [Job 23] [23] = Enable-Printer
D [18/Aug/2013:21:05:20 -0400] [Job 23] [24] = Disable-Printer
D [18/Aug/2013:21:05:20 -0400] [Job 23] [25] = Hold-New-Jobs
D [18/Aug/2013:21:05:20 -0400] [Job 23] [26] = Release-Held-New-Jobs
D [18/Aug/2013:21:05:20 -0400] [Job 23] [27] = CUPS-Get-Default
D [18/Aug/2013:21:05:20 -0400] [Job 23] [28] = CUPS-Get-Printers
D [18/Aug/2013:21:05:20 -0400] [Job 23] [29] = CUPS-Add-Modify-Printer
D [18/Aug/2013:21:05:20 -0400] [Job 23] [30] = CUPS-Delete-Printer
D [18/Aug/2013:21:05:20 -0400] [Job 23] [31] = CUPS-Get-Classes
D [18/Aug/2013:21:05:20 -0400] [Job 23] [32] = CUPS-Add-Modify-Class
D [18/Aug/2013:21:05:20 -0400] [Job 23] [33] = CUPS-Delete-Class
D [18/Aug/2013:21:05:20 -0400] [Job 23] [34] = CUPS-Accept-Jobs
D [18/Aug/2013:21:05:20 -0400] [Job 23] [35] = CUPS-Reject-Jobs
D [18/Aug/2013:21:05:20 -0400] [Job 23] [36] = CUPS-Set-Default
D [18/Aug/2013:21:05:20 -0400] [Job 23] [37] = CUPS-Get-Devices
D [18/Aug/2013:21:05:20 -0400] [Job 23] [38] = CUPS-Get-PPDs
D [18/Aug/2013:21:05:20 -0400] [Job 23] [39] = CUPS-Move-Job
D [18/Aug/2013:21:05:20 -0400] [Job 23] [40] = CUPS-Authenticate-Job
D [18/Aug/2013:21:05:20 -0400] [Job 23] [41] = CUPS-Get-PPD
D [18/Aug/2013:21:05:20 -0400] [Job 23] [42] = CUPS-Get-Document
I [18/Aug/2013:21:05:20 -0400] [Job 23] Finished page 1...
D [18/Aug/2013:21:05:20 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:20 -0400] [Job 23] Set job-printer-state-message to "Finished page 1...", current level=INFO
D [18/Aug/2013:21:05:20 -0400] [Job 23] update_reasons(attr=1(none), s="(null)")
D [18/Aug/2013:21:05:20 -0400] [Job 23] STATE: -none
D [18/Aug/2013:21:05:20 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:20 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:20 -0400] [Job 23] final_content_type="application/vnd.cups-raster", document_format="application/vnd.cups-raster"
D [18/Aug/2013:21:05:20 -0400] [Job 23] Validate-Job IPP/2.0
D [18/Aug/2013:21:05:20 -0400] [Job 23] printer-uri="ipp://192.168.3.102:631/printers/BrotherHL5150DLT"
D [18/Aug/2013:21:05:20 -0400] [Job 23] requesting-user-name="jacques"
D [18/Aug/2013:21:05:20 -0400] [Job 23] job-name="rxweeks_somenotes201206"
D [18/Aug/2013:21:05:20 -0400] [Job 23] document-format="application/vnd.cups-raster"
I [18/Aug/2013:21:05:20 -0400] [Job 23] Finished page 1...
D [18/Aug/2013:21:05:20 -0400] [Job 23] update_reasons(attr=1(none), s="(null)")
D [18/Aug/2013:21:05:20 -0400] [Job 23] Get-Printer-Attributes: successful-ok (successful-ok)
D [18/Aug/2013:21:05:20 -0400] [Job 23] Validate-Job: successful-ok (successful-ok)
D [18/Aug/2013:21:05:20 -0400] [Job 23] Create-Job IPP/2.0
D [18/Aug/2013:21:05:20 -0400] [Job 23] printer-uri="ipp://192.168.3.102:631/printers/BrotherHL5150DLT"
D [18/Aug/2013:21:05:20 -0400] [Job 23] requesting-user-name="jacques"
D [18/Aug/2013:21:05:20 -0400] [Job 23] job-name="rxweeks_somenotes201206"
D [18/Aug/2013:21:05:20 -0400] [Job 23] (monitor) Get-Jobs: successful-ok (successful-ok)
D [18/Aug/2013:21:05:20 -0400] [Job 23] (monitor) job-state=pending
D [18/Aug/2013:21:05:20 -0400] [Job 23] Create-Job: successful-ok (successful-ok)
I [18/Aug/2013:21:05:20 -0400] [Job 23] Print file accepted - job ID 74.
D [18/Aug/2013:21:05:20 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:20 -0400] [Job 23] Set job-printer-state-message to "Print file accepted - job ID 74.", current level=INFO
D [18/Aug/2013:21:05:20 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:20 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:20 -0400] [Job 23] job-id=74
D [18/Aug/2013:21:05:20 -0400] [Job 23] Sending file 1 using chunking...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 32%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 32%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=32
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 33%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 33%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=33
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 34%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 34%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=34
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 35%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 35%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=35
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 36%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 36%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=36
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 37%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 37%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=37
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 38%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 38%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=38
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 39%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 39%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=39
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 40%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 40%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=40
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 41%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 41%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=41
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 42%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 42%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=42
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 43%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 43%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=43
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 44%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 44%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=44
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 45%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 45%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=45
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 46%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 46%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=46
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 47%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 47%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=47
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 48%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 48%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=48
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 49%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 49%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=49
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 50%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 50%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=50
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 51%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 51%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=51
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 52%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 52%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=52
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 53%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 53%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=53
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 54%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 54%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=54
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 55%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 55%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=55
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 56%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 56%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=56
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 57%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 57%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=57
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 58%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 58%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=58
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 59%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 59%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=59
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 60%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 60%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=60
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 61%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 61%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=61
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 62%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 62%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=62
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 63%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 63%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=63
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 64%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 64%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=64
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 65%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 65%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=65
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 66%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 66%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=66
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 67%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 67%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=67
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 68%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 68%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=68
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 69%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 69%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=69
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 70%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 70%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=70
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 71%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 71%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=71
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 72%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 72%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=72
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 73%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 73%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=73
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 74%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 74%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=74
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 75%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 75%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=75
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 76%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 76%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=76
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 77%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 77%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=77
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 78%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 78%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=78
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 79%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 79%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=79
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 80%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 80%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=80
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 81%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 81%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=81
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 82%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 82%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=82
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 83%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 83%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=83
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 84%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 84%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=84
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 85%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 85%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=85
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 86%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 86%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=86
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 87%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 87%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=87
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 88%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 88%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=88
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 89%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 89%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=89
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 90%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 90%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=90
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 91%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 91%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=91
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 92%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 92%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=92
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 93%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 93%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=93
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 94%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 94%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=94
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 95%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 95%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=95
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 96%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 96%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=96
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 97%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 97%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=97
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 98%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 98%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=98
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 99%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 99%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=99
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Processing page 2...
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Processing page 2...", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Printing page 1, 100%
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Printing page 1, 100%", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] ATTR: job-media-progress=100
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
I [18/Aug/2013:21:05:21 -0400] [Job 23] Finished page 1...
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Finished page 1...", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] Gutenprint: ================ Done printing page 1 ================
I [18/Aug/2013:21:05:21 -0400] [Job 23] Rendering completed
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Rendering completed", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] Gutenprint: Ending job
D [18/Aug/2013:21:05:21 -0400] [Job 23] Gutenprint: Printed total 114823 bytes
D [18/Aug/2013:21:05:21 -0400] [Job 23] Gutenprint: Time 1.790 user, 0.220 sys, 5.079 elapsed
D [18/Aug/2013:21:05:21 -0400] [Job 23] Gutenprint: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
D [18/Aug/2013:21:05:21 -0400] [Job 23] Gutenprint: ============================================================
D [18/Aug/2013:21:05:21 -0400] [Job 23] PID 9415 (/usr/lib/cups/filter/gstoraster) exited with no errors.
D [18/Aug/2013:21:05:21 -0400] [Job 23] PID 9416 (/usr/lib/cups/filter/rastertogutenprint.5.2) exited with no errors.
D [18/Aug/2013:21:05:21 -0400] [Job 23] Send-Document: successful-ok (successful-ok)
I [18/Aug/2013:21:05:21 -0400] [Job 23] Waiting for job to complete.
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] Set job-printer-state-message to "Waiting for job to complete.", current level=INFO
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 23] Get-Job-Attributes: successful-ok (successful-ok)
D [18/Aug/2013:21:05:21 -0400] [Job 23] update_reasons(attr=0(), s="+cups-remote-processing")
D [18/Aug/2013:21:05:21 -0400] [Job 23] STATE: +cups-remote-processing
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(P----)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:21 -0400] [Job 23] PAGE: total 0
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:22 -0400] [Job 23] Set job-printer-state-message to "Waiting for job to complete.", current level=INFO
D [18/Aug/2013:21:05:22 -0400] [Job 23] update_reasons(attr=1(none), s="(null)")
D [18/Aug/2013:21:05:22 -0400] [Job 23] Get-Printer-Attributes: successful-ok (successful-ok)
D [18/Aug/2013:21:05:22 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:22 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:22 -0400] [Job 23] (monitor) Get-Job-Attributes: successful-ok (successful-ok)
D [18/Aug/2013:21:05:22 -0400] [Job 23] (monitor) job-state=completed
D [18/Aug/2013:21:05:22 -0400] [Client 14] Accepted from localhost:631 (IPv6)
D [18/Aug/2013:21:05:22 -0400] [Client 14] GET /admin/log/error_log HTTP/1.1
D [18/Aug/2013:21:05:22 -0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:22 -0400] [Client 14] No authentication data provided.
D [18/Aug/2013:21:05:22 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [18/Aug/2013:21:05:22 -0400] [Job 23] Get-Job-Attributes: successful-ok (successful-ok)
D [18/Aug/2013:21:05:22 -0400] [Job 23] update_reasons(attr=0(), s="+cups-remote-completed")
D [18/Aug/2013:21:05:22 -0400] [Job 23] STATE: +cups-remote-completed
D [18/Aug/2013:21:05:22 -0400] cupsdMarkDirty(P----)
D [18/Aug/2013:21:05:22 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:22 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:22 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:22 -0400] [Job 23] STATE: -cups-remote-processing
D [18/Aug/2013:21:05:22 -0400] cupsdMarkDirty(P----)
D [18/Aug/2013:21:05:22 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:22 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:22 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:22 -0400] [Job 23] PAGE: total 0
D [18/Aug/2013:21:05:22 -0400] Discarding unused job-progress event...
D [18/Aug/2013:21:05:22 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:22 -0400] [Job 23] update_reasons(attr=1(none), s="(null)")
D [18/Aug/2013:21:05:22 -0400] [Job 23] Get-Printer-Attributes: successful-ok (successful-ok)
D [18/Aug/2013:21:05:22 -0400] [Job 23] ATTR: auth-info-required=none
D [18/Aug/2013:21:05:22 -0400] load_ppd: Loading /var/cache/cups/BrotherHL5150DLT.data...
D [18/Aug/2013:21:05:22 -0400] cupsdRegisterPrinter(p=0xb7d96b90(BrotherHL5150DLT))
D [18/Aug/2013:21:05:22 -0400] cupsdMarkDirty(P----)
D [18/Aug/2013:21:05:22 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:22 -0400] [Job 23] PID 9417 (/usr/lib/cups/backend/ipp) exited with no errors.
D [18/Aug/2013:21:05:22 -0400] cupsdMarkDirty(P----)
D [18/Aug/2013:21:05:22 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:22 -0400] [Job 23] time-at-completed=1376874322
D [18/Aug/2013:21:05:22 -0400] Discarding unused job-completed event...
I [18/Aug/2013:21:05:22 -0400] [Job 23] Job completed.
D [18/Aug/2013:21:05:22 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:22 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:22 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:22 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:22 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:22 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:22 -0400] cupsdMarkDirty(---J-)
D [18/Aug/2013:21:05:22 -0400] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:23 -0400] [Job 23] Unloading...
I [18/Aug/2013:21:05:48 -0400] Saving printers.conf...
I [18/Aug/2013:21:05:48 -0400] Saving job.cache...
D [18/Aug/2013:21:05:48 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Printing jobs and dirty files"
D [18/Aug/2013:21:05:52 -0400] [Client 14] HTTP_WAITING Closing on EOF
D [18/Aug/2013:21:05:52 -0400] [Client 14] Closing connection.
D [18/Aug/2013:21:05:52 -0400] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [18/Aug/2013:21:06:16 -0400] [Client 14] Accepted from localhost:631 (IPv6)
D [18/Aug/2013:21:06:16 -0400] Report: clients=1
D [18/Aug/2013:21:06:16 -0400] Report: jobs=2
D [18/Aug/2013:21:06:16 -0400] Report: jobs-active=0
D [18/Aug/2013:21:06:16 -0400] Report: printers=1
D [18/Aug/2013:21:06:16 -0400] Report: stringpool-string-count=4366
D [18/Aug/2013:21:06:16 -0400] Report: stringpool-alloc-bytes=8776
D [18/Aug/2013:21:06:16 -0400] Report: stringpool-total-bytes=55216
D [18/Aug/2013:21:06:16 -0400] [Client 14] GET /admin/log/error_log HTTP/1.1
D [18/Aug/2013:21:06:16 -0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [18/Aug/2013:21:06:16 -0400] [Client 14] No authentication data provided.

Server Error Log w/ Debugging:

D [18/Aug/2013:21:02:03 -0400] cupsdNetIFUpdate: "lo" = localhost:631
D [18/Aug/2013:21:02:03 -0400] cupsdNetIFUpdate: "eth0" = 192.168.3.102:631
D [18/Aug/2013:21:02:03 -0400] cupsdNetIFUpdate: "lo" = localhost:631
D [18/Aug/2013:21:02:03 -0400] cupsdNetIFUpdate: "eth0" = fe80::20f:1fff:fe8f:9634%eth0:631
D [18/Aug/2013:21:02:03 -0400] Report: clients=0
D [18/Aug/2013:21:02:03 -0400] Report: jobs=18
D [18/Aug/2013:21:02:03 -0400] Report: jobs-active=0
D [18/Aug/2013:21:02:03 -0400] Report: printers=1
D [18/Aug/2013:21:02:03 -0400] Report: printers-implicit=0
D [18/Aug/2013:21:02:03 -0400] Report: stringpool-string-count=1420
D [18/Aug/2013:21:02:03 -0400] Report: stringpool-alloc-bytes=7312
D [18/Aug/2013:21:02:03 -0400] Report: stringpool-total-bytes=28272
D [18/Aug/2013:21:03:05 -0400] cupsdNetIFUpdate: "lo" = localhost:631
D [18/Aug/2013:21:03:05 -0400] cupsdNetIFUpdate: "eth0" = 192.168.3.102:631
D [18/Aug/2013:21:03:05 -0400] cupsdNetIFUpdate: "lo" = localhost:631
D [18/Aug/2013:21:03:05 -0400] cupsdNetIFUpdate: "eth0" = fe80::20f:1fff:fe8f:9634%eth0:631
D [18/Aug/2013:21:03:05 -0400] Report: clients=0
D [18/Aug/2013:21:03:05 -0400] Report: jobs=18
D [18/Aug/2013:21:03:05 -0400] Report: jobs-active=0
D [18/Aug/2013:21:03:05 -0400] Report: printers=1
D [18/Aug/2013:21:03:05 -0400] Report: printers-implicit=0
D [18/Aug/2013:21:03:05 -0400] Report: stringpool-string-count=1420
D [18/Aug/2013:21:03:05 -0400] Report: stringpool-alloc-bytes=7312
D [18/Aug/2013:21:03:05 -0400] Report: stringpool-total-bytes=28272
D [18/Aug/2013:21:03:11 -0400] cupsdAcceptClient: 12 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:03:11 -0400] Connection from 192.168.3.124 now encrypted.
D [18/Aug/2013:21:03:11 -0400] cupsdReadClient: 12 GET /admin/log/error_log HTTP/1.1
D [18/Aug/2013:21:03:11 -0400] cupsdSetBusyState: Active clients
D [18/Aug/2013:21:03:11 -0400] cupsdAuthorize: Authorized as root using Basic
D [18/Aug/2013:21:03:11 -0400] cupsdAcceptClient: 17 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:03:11 -0400] Connection from 192.168.3.124 now encrypted.
D [18/Aug/2013:21:03:12 -0400] cupsdSetBusyState: Not busy
D [18/Aug/2013:21:03:17 -0400] cupsdReadClient: 17 WAITING Closing on EOF
D [18/Aug/2013:21:03:17 -0400] cupsdCloseClient: 17
D [18/Aug/2013:21:03:17 -0400] SSL shutdown successful!
D [18/Aug/2013:21:03:17 -0400] cupsdReadClient: 17 WAITING Closing on EOF
D [18/Aug/2013:21:03:17 -0400] cupsdCloseClient: 17
D [18/Aug/2013:21:03:43 -0400] cupsdReadClient: 12 WAITING Closing on EOF
D [18/Aug/2013:21:03:43 -0400] cupsdCloseClient: 12
D [18/Aug/2013:21:03:43 -0400] SSL shutdown successful!
D [18/Aug/2013:21:03:43 -0400] cupsdReadClient: 12 WAITING Closing on EOF
D [18/Aug/2013:21:03:43 -0400] cupsdCloseClient: 12
D [18/Aug/2013:21:03:44 -0400] cupsdAcceptClient: 12 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:03:44 -0400] cupsdReadClient: 12 POST / HTTP/1.1
D [18/Aug/2013:21:03:44 -0400] cupsdSetBusyState: Active clients
D [18/Aug/2013:21:03:44 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:03:44 -0400] cupsdReadClient: 12 0.0 CUPS-Get-Printers 16
D [18/Aug/2013:21:03:44 -0400] Discarding unused server-audit event...
D [18/Aug/2013:21:03:44 -0400] CUPS-Get-Printers server-error-version-not-supported: Bad request version number 0.0!
E [18/Aug/2013:21:03:44 -0400] Returning IPP server-error-version-not-supported for CUPS-Get-Printers (no URI) from 192.168.3.124
D [18/Aug/2013:21:03:44 -0400] cupsdSetBusyState: Not busy
D [18/Aug/2013:21:03:44 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:03:44 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:03:44 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:03:44 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:03:44 -0400] cupsdReadClient: 15 POST / HTTP/1.1
D [18/Aug/2013:21:03:44 -0400] cupsdSetBusyState: Active clients
D [18/Aug/2013:21:03:44 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:03:44 -0400] cupsdReadClient: 15 0.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:03:44 -0400] Discarding unused server-audit event...
D [18/Aug/2013:21:03:44 -0400] CUPS-Get-Printers server-error-version-not-supported: Bad request version number 0.0!
E [18/Aug/2013:21:03:44 -0400] Returning IPP server-error-version-not-supported for CUPS-Get-Printers (no URI) from 192.168.3.124
D [18/Aug/2013:21:03:44 -0400] cupsdSetBusyState: Not busy
D [18/Aug/2013:21:03:44 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:03:44 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:03:44 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:03:45 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:03:45 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:03:45 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:03:45 -0400] cupsdReadClient: 15 POST / HTTP/1.1
D [18/Aug/2013:21:03:45 -0400] cupsdSetBusyState: Active clients
D [18/Aug/2013:21:03:45 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:03:45 -0400] cupsdReadClient: 15 0.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:03:45 -0400] Discarding unused server-audit event...
D [18/Aug/2013:21:03:45 -0400] CUPS-Get-Printers server-error-version-not-supported: Bad request version number 0.0!
E [18/Aug/2013:21:03:45 -0400] Returning IPP server-error-version-not-supported for CUPS-Get-Printers (no URI) from 192.168.3.124
D [18/Aug/2013:21:03:45 -0400] cupsdSetBusyState: Not busy
D [18/Aug/2013:21:03:45 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:03:45 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:03:45 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:03:45 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:03:45 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:03:45 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:03:45 -0400] cupsdReadClient: 15 POST / HTTP/1.1
D [18/Aug/2013:21:03:45 -0400] cupsdSetBusyState: Active clients
D [18/Aug/2013:21:03:45 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:03:45 -0400] cupsdReadClient: 15 0.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:03:45 -0400] Discarding unused server-audit event...
D [18/Aug/2013:21:03:45 -0400] CUPS-Get-Printers server-error-version-not-supported: Bad request version number 0.0!
E [18/Aug/2013:21:03:45 -0400] Returning IPP server-error-version-not-supported for CUPS-Get-Printers (no URI) from 192.168.3.124
D [18/Aug/2013:21:03:45 -0400] cupsdSetBusyState: Not busy
D [18/Aug/2013:21:03:45 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:03:45 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:03:45 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:03:45 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:03:45 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:03:45 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:03:45 -0400] cupsdReadClient: 15 POST / HTTP/1.1
D [18/Aug/2013:21:03:45 -0400] cupsdSetBusyState: Active clients
D [18/Aug/2013:21:03:45 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:03:45 -0400] cupsdReadClient: 15 0.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:03:45 -0400] Discarding unused server-audit event...
D [18/Aug/2013:21:03:45 -0400] CUPS-Get-Printers server-error-version-not-supported: Bad request version number 0.0!
E [18/Aug/2013:21:03:45 -0400] Returning IPP server-error-version-not-supported for CUPS-Get-Printers (no URI) from 192.168.3.124
D [18/Aug/2013:21:03:45 -0400] cupsdSetBusyState: Not busy
D [18/Aug/2013:21:03:45 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:03:45 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:03:46 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:03:46 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:03:46 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:03:46 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:03:46 -0400] cupsdReadClient: 15 POST / HTTP/1.1
D [18/Aug/2013:21:03:46 -0400] cupsdSetBusyState: Active clients
D [18/Aug/2013:21:03:46 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:03:46 -0400] cupsdReadClient: 15 0.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:03:46 -0400] Discarding unused server-audit event...
D [18/Aug/2013:21:03:46 -0400] CUPS-Get-Printers server-error-version-not-supported: Bad request version number 0.0!
E [18/Aug/2013:21:03:46 -0400] Returning IPP server-error-version-not-supported for CUPS-Get-Printers (no URI) from 192.168.3.124
D [18/Aug/2013:21:03:46 -0400] cupsdSetBusyState: Not busy
D [18/Aug/2013:21:03:46 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:03:46 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:03:46 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:03:46 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:03:46 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:03:46 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:03:46 -0400] cupsdReadClient: 15 POST / HTTP/1.1
D [18/Aug/2013:21:03:46 -0400] cupsdSetBusyState: Active clients
D [18/Aug/2013:21:03:46 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:03:46 -0400] cupsdReadClient: 15 0.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:03:46 -0400] Discarding unused server-audit event...
D [18/Aug/2013:21:03:46 -0400] CUPS-Get-Printers server-error-version-not-supported: Bad request version number 0.0!
E [18/Aug/2013:21:03:46 -0400] Returning IPP server-error-version-not-supported for CUPS-Get-Printers (no URI) from 192.168.3.124
D [18/Aug/2013:21:03:46 -0400] cupsdSetBusyState: Not busy
D [18/Aug/2013:21:03:46 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:03:46 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:03:46 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:03:47 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:03:47 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:03:47 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:03:47 -0400] cupsdReadClient: 15 POST / HTTP/1.1
D [18/Aug/2013:21:03:47 -0400] cupsdSetBusyState: Active clients
D [18/Aug/2013:21:03:47 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:03:47 -0400] cupsdReadClient: 15 0.0 CUPS-Get-Printers 1
D [18/Aug/2013:21:03:47 -0400] Discarding unused server-audit event...
D [18/Aug/2013:21:03:47 -0400] CUPS-Get-Printers server-error-version-not-supported: Bad request version number 0.0!
E [18/Aug/2013:21:03:47 -0400] Returning IPP server-error-version-not-supported for CUPS-Get-Printers (no URI) from 192.168.3.124
D [18/Aug/2013:21:03:47 -0400] cupsdSetBusyState: Not busy
D [18/Aug/2013:21:03:47 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:03:47 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:04:07 -0400] cupsdNetIFUpdate: "lo" = localhost:631
D [18/Aug/2013:21:04:07 -0400] cupsdNetIFUpdate: "eth0" = 192.168.3.102:631
D [18/Aug/2013:21:04:07 -0400] cupsdNetIFUpdate: "lo" = localhost:631
D [18/Aug/2013:21:04:07 -0400] cupsdNetIFUpdate: "eth0" = fe80::20f:1fff:fe8f:9634%eth0:631
D [18/Aug/2013:21:04:07 -0400] Report: clients=1
D [18/Aug/2013:21:04:07 -0400] Report: jobs=18
D [18/Aug/2013:21:04:07 -0400] Report: jobs-active=0
D [18/Aug/2013:21:04:07 -0400] Report: printers=1
D [18/Aug/2013:21:04:07 -0400] Report: printers-implicit=0
D [18/Aug/2013:21:04:07 -0400] Report: stringpool-string-count=1420
D [18/Aug/2013:21:04:07 -0400] Report: stringpool-alloc-bytes=7312
D [18/Aug/2013:21:04:07 -0400] Report: stringpool-total-bytes=28272
D [18/Aug/2013:21:05:09 -0400] cupsdNetIFUpdate: "lo" = localhost:631
D [18/Aug/2013:21:05:09 -0400] cupsdNetIFUpdate: "eth0" = 192.168.3.102:631
D [18/Aug/2013:21:05:09 -0400] cupsdNetIFUpdate: "lo" = localhost:631
D [18/Aug/2013:21:05:09 -0400] cupsdNetIFUpdate: "eth0" = fe80::20f:1fff:fe8f:9634%eth0:631
D [18/Aug/2013:21:05:09 -0400] Report: clients=1
D [18/Aug/2013:21:05:09 -0400] Report: jobs=18
D [18/Aug/2013:21:05:09 -0400] Report: jobs-active=0
D [18/Aug/2013:21:05:09 -0400] Report: printers=1
D [18/Aug/2013:21:05:09 -0400] Report: printers-implicit=0
D [18/Aug/2013:21:05:09 -0400] Report: stringpool-string-count=1420
D [18/Aug/2013:21:05:09 -0400] Report: stringpool-alloc-bytes=7312
D [18/Aug/2013:21:05:09 -0400] Report: stringpool-total-bytes=28272
D [18/Aug/2013:21:05:20 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:05:20 -0400] cupsdReadClient: 15 POST /printers/BrotherHL5150DLT HTTP/1.1
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: Active clients
D [18/Aug/2013:21:05:20 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:05:20 -0400] cupsdReadClient: 15 2.0 Get-Printer-Attributes 1
D [18/Aug/2013:21:05:20 -0400] Get-Printer-Attributes ipp://192.168.3.102:631/printers/BrotherHL5150DLT
D [18/Aug/2013:21:05:20 -0400] Returning IPP successful-ok for Get-Printer-Attributes (ipp://192.168.3.102:631/printers/BrotherHL5150DLT) from 192.168.3.124
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: Not busy
D [18/Aug/2013:21:05:20 -0400] cupsdAcceptClient: 17 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:05:20 -0400] cupsdReadClient: 17 POST /printers/BrotherHL5150DLT HTTP/1.1
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: Active clients
D [18/Aug/2013:21:05:20 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:05:20 -0400] cupsdReadClient: 17 2.0 Get-Printer-Attributes 3
D [18/Aug/2013:21:05:20 -0400] Get-Printer-Attributes ipp://192.168.3.102:631/printers/BrotherHL5150DLT
D [18/Aug/2013:21:05:20 -0400] Returning IPP successful-ok for Get-Printer-Attributes (ipp://192.168.3.102:631/printers/BrotherHL5150DLT) from 192.168.3.124
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: Not busy
D [18/Aug/2013:21:05:20 -0400] cupsdReadClient: 15 POST /printers/BrotherHL5150DLT HTTP/1.1
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: Active clients
D [18/Aug/2013:21:05:20 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:05:20 -0400] cupsdReadClient: 15 2.0 Validate-Job 2
D [18/Aug/2013:21:05:20 -0400] Validate-Job ipp://192.168.3.102:631/printers/BrotherHL5150DLT
D [18/Aug/2013:21:05:20 -0400] Returning IPP successful-ok for Validate-Job (ipp://192.168.3.102:631/printers/BrotherHL5150DLT) from 192.168.3.124
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: Not busy
D [18/Aug/2013:21:05:20 -0400] cupsdReadClient: 17 POST /printers/BrotherHL5150DLT HTTP/1.1
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: Active clients
D [18/Aug/2013:21:05:20 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:05:20 -0400] cupsdReadClient: 17 2.0 Get-Jobs 4
D [18/Aug/2013:21:05:20 -0400] Get-Jobs ipp://192.168.3.102:631/printers/BrotherHL5150DLT
D [18/Aug/2013:21:05:20 -0400] Returning IPP successful-ok for Get-Jobs (ipp://192.168.3.102:631/printers/BrotherHL5150DLT) from 192.168.3.124
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: Not busy
D [18/Aug/2013:21:05:20 -0400] cupsdReadClient: 15 POST /printers/BrotherHL5150DLT HTTP/1.1
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: Active clients
D [18/Aug/2013:21:05:20 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:05:20 -0400] cupsdReadClient: 15 2.0 Create-Job 5
D [18/Aug/2013:21:05:20 -0400] Create-Job ipp://192.168.3.102:631/printers/BrotherHL5150DLT
D [18/Aug/2013:21:05:20 -0400] cupsdMarkDirty(----J-)
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: Active clients and dirty files
D [18/Aug/2013:21:05:20 -0400] add_job: requesting-user-name="jacques"
D [18/Aug/2013:21:05:20 -0400] Adding default job-sheets values "none,none"...
I [18/Aug/2013:21:05:20 -0400] [Job 74] Adding start banner page "none".
D [18/Aug/2013:21:05:20 -0400] Discarding unused job-created event...
I [18/Aug/2013:21:05:20 -0400] [Job 74] Queued on "BrotherHL5150DLT" by "jacques".
D [18/Aug/2013:21:05:20 -0400] Returning IPP successful-ok for Create-Job (ipp://192.168.3.102:631/printers/BrotherHL5150DLT) from 192.168.3.124
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: Dirty files
D [18/Aug/2013:21:05:20 -0400] cupsdReadClient: 17 WAITING Closing on EOF
D [18/Aug/2013:21:05:20 -0400] cupsdCloseClient: 17
D [18/Aug/2013:21:05:20 -0400] cupsdReadClient: 15 POST /printers/BrotherHL5150DLT HTTP/1.1
D [18/Aug/2013:21:05:20 -0400] cupsdSetBusyState: Active clients and dirty files
D [18/Aug/2013:21:05:20 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:05:20 -0400] cupsdReadClient: 15 2.0 Send-Document 6
D [18/Aug/2013:21:05:21 -0400] Send-Document ipp://192.168.3.102:631/printers/BrotherHL5150DLT
D [18/Aug/2013:21:05:21 -0400] cupsdIsAuthorized: requesting-user-name="jacques"
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(----J-)
I [18/Aug/2013:21:05:21 -0400] [Job 74] File of type application/vnd.cups-raster queued by "jacques".
I [18/Aug/2013:21:05:21 -0400] [Job 74] Adding end banner page "none".
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(----J-)
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(----J-)
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 74] job-sheets=none,none
D [18/Aug/2013:21:05:21 -0400] [Job 74] argv[0]="BrotherHL5150DLT"
D [18/Aug/2013:21:05:21 -0400] [Job 74] argv[1]="74"
D [18/Aug/2013:21:05:21 -0400] [Job 74] argv[2]="jacques"
D [18/Aug/2013:21:05:21 -0400] [Job 74] argv[3]="rxweeks_somenotes201206"
D [18/Aug/2013:21:05:21 -0400] [Job 74] argv[4]="1"
D [18/Aug/2013:21:05:21 -0400] [Job 74] argv[5]="job-uuid=urn:uuid:e86cd9fa-b2de-324d-61ab-4e9fded79a4f job-originating-host-name=192.168.3.124"
D [18/Aug/2013:21:05:21 -0400] [Job 74] argv[6]="/var/spool/cups/d00074-001"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[8]="HOME=/var/spool/cups/tmp"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[10]="SERVER_ADMIN=root@dadawormstream"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[11]="SOFTWARE=CUPS/1.4.4"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[13]="TZ=America/New_York"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[14]="USER=root"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[17]="IPP_PORT=631"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[18]="CHARSET=utf-8"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[19]="LANG=en_US.UTF-8"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[20]="PPD=/etc/cups/ppd/BrotherHL5150DLT.ppd"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[21]="RIP_MAX_CACHE=auto"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[22]="CONTENT_TYPE=application/vnd.cups-raster"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[23]="DEVICE_URI=parallel:/dev/lp0"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[24]="PRINTER_INFO=Brother HL-5150DLT"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[25]="PRINTER_LOCATION=anti-office"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[26]="PRINTER=BrotherHL5150DLT"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[27]="CUPS_FILETYPE=document"
D [18/Aug/2013:21:05:21 -0400] [Job 74] envp[28]="FINAL_CONTENT_TYPE=printer/BrotherHL5150DLT"
I [18/Aug/2013:21:05:21 -0400] [Job 74] Started filter /usr/lib/cups/filter/rastertogutenprint.5.2 (PID 2223)
I [18/Aug/2013:21:05:21 -0400] [Job 74] Started backend /usr/lib/cups/backend/parallel (PID 2224)
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-state-changed event...
D [18/Aug/2013:21:05:21 -0400] Returning IPP successful-ok for Send-Document (ipp://192.168.3.102:631/printers/BrotherHL5150DLT) from 192.168.3.124
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: Printing jobs and dirty files
D [18/Aug/2013:21:05:21 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:05:21 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:05:21 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:05:21 -0400] cupsdReadClient: 15 POST /printers/BrotherHL5150DLT HTTP/1.1
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [18/Aug/2013:21:05:21 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:05:21 -0400] cupsdReadClient: 15 2.0 Get-Job-Attributes 7
D [18/Aug/2013:21:05:21 -0400] Get-Job-Attributes ipp://192.168.3.102:631/printers/BrotherHL5150DLT
D [18/Aug/2013:21:05:21 -0400] Returning IPP successful-ok for Get-Job-Attributes (ipp://192.168.3.102:631/printers/BrotherHL5150DLT) from 192.168.3.124
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: Printing jobs and dirty files
D [18/Aug/2013:21:05:21 -0400] [Job 74] STATE: +connecting-to-device
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 74] STATE: -connecting-to-device
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] [Job 74] backendRunLoop(print_fd=0, device_fd=5, snmp_fd=-1, addr=(nil), use_bc=0, side_cb=0xb77d8120)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint 5.2.6 Starting
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint command line: BrotherHL5150DLT '74' 'jacques' 'rxweeks_somenotes201206' '1' <args> '/var/spool/cups/d00074-001'
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint using PPD file /etc/cups/ppd/BrotherHL5150DLT.ppd
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint: CUPS option count is 2 (94 bytes)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint: CUPS option 0 job-originating-host-name = 192.168.3.124
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint: CUPS option 1 job-uuid = urn:uuid:e86cd9fa-b2de-324d-61ab-4e9fded79a4f
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint: Driver Brother HL-5150D
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint: Using fd 5
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint: Set options:
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting PageSize to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting MediaType to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting InputSlot to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Set string Quality to Standard
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Set special string Quality to Standard
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting Resolution to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting InkType to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting InkChannels to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting PrintingMode to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting Duplex to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Set string ColorCorrection to None
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Set special string ColorCorrection to None
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting ChannelBitDepth to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting InputImageType to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting STPIOutputType to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting STPIRawChannels to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting SimpleGamma to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Set bool LinearContrast to False (0)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting LUTDumpFile to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CyanCurve to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting MagentaCurve to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting YellowCurve to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting BlackCurve to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting RedCurve to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting GreenCurve to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting BlueCurve to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting WhiteCurve to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting HueMap to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting SatMap to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting LumMap to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting GCRCurve to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh0 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh1 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh2 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh3 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh4 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh5 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh6 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh7 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh8 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh9 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh10 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh11 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh12 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh13 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh14 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh15 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh16 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh17 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh18 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh19 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh20 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh21 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh22 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh23 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh24 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh25 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh26 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh27 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh28 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh29 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh30 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting CurveCh31 to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Set string DitherAlgorithm to None
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Set special string DitherAlgorithm to None
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Set string ImageType to TextGraphics
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Set special string ImageType to TextGraphics
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting JobMode to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint:   Not setting PageNumber to (null)
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint: End options
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint: About to start printing loop.
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint: Printed total 0 bytes
D [18/Aug/2013:21:05:21 -0400] [Job 74] Gutenprint: Used 0.080 seconds user, 0.000 seconds system, 0.088 seconds elapsed
D [18/Aug/2013:21:05:21 -0400] PID 2224 (/usr/lib/cups/backend/parallel) exited with no errors.
D [18/Aug/2013:21:05:21 -0400] PID 2223 (/usr/lib/cups/filter/rastertogutenprint.5.2) exited with no errors.
D [18/Aug/2013:21:05:21 -0400] Discarding unused job-completed event...
I [18/Aug/2013:21:05:21 -0400] [Job 74] Job completed.
D [18/Aug/2013:21:05:21 -0400] cupsdMarkDirty(----J-)
D [18/Aug/2013:21:05:21 -0400] Discarding unused printer-state-changed event...
D [18/Aug/2013:21:05:21 -0400] cupsdAcceptClient: 17 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:05:21 -0400] cupsdReadClient: 17 POST /printers/BrotherHL5150DLT HTTP/1.1
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: Active clients and dirty files
D [18/Aug/2013:21:05:21 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:05:21 -0400] cupsdReadClient: 17 2.0 Get-Printer-Attributes 8
D [18/Aug/2013:21:05:21 -0400] Get-Printer-Attributes ipp://192.168.3.102:631/printers/BrotherHL5150DLT
D [18/Aug/2013:21:05:21 -0400] Returning IPP successful-ok for Get-Printer-Attributes (ipp://192.168.3.102:631/printers/BrotherHL5150DLT) from 192.168.3.124
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: Dirty files
D [18/Aug/2013:21:05:21 -0400] cupsdReadClient: 17 POST /printers/BrotherHL5150DLT HTTP/1.1
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: Active clients and dirty files
D [18/Aug/2013:21:05:21 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:05:21 -0400] cupsdReadClient: 17 2.0 Get-Job-Attributes 9
D [18/Aug/2013:21:05:21 -0400] Get-Job-Attributes ipp://192.168.3.102:631/printers/BrotherHL5150DLT
D [18/Aug/2013:21:05:21 -0400] Returning IPP successful-ok for Get-Job-Attributes (ipp://192.168.3.102:631/printers/BrotherHL5150DLT) from 192.168.3.124
D [18/Aug/2013:21:05:21 -0400] cupsdSetBusyState: Dirty files
D [18/Aug/2013:21:05:21 -0400] cupsdReadClient: 17 WAITING Closing on EOF
D [18/Aug/2013:21:05:21 -0400] cupsdCloseClient: 17
D [18/Aug/2013:21:05:22 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:05:22 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:05:22 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:05:22 -0400] cupsdReadClient: 15 POST /printers/BrotherHL5150DLT HTTP/1.1
D [18/Aug/2013:21:05:22 -0400] cupsdSetBusyState: Active clients and dirty files
D [18/Aug/2013:21:05:22 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:05:22 -0400] cupsdReadClient: 15 2.0 Get-Job-Attributes 10
D [18/Aug/2013:21:05:22 -0400] Get-Job-Attributes ipp://192.168.3.102:631/printers/BrotherHL5150DLT
D [18/Aug/2013:21:05:22 -0400] Returning IPP successful-ok for Get-Job-Attributes (ipp://192.168.3.102:631/printers/BrotherHL5150DLT) from 192.168.3.124
D [18/Aug/2013:21:05:22 -0400] cupsdSetBusyState: Dirty files
D [18/Aug/2013:21:05:22 -0400] cupsdReadClient: 15 POST /printers/BrotherHL5150DLT HTTP/1.1
D [18/Aug/2013:21:05:22 -0400] cupsdSetBusyState: Active clients and dirty files
D [18/Aug/2013:21:05:22 -0400] cupsdAuthorize: No authentication data provided.
D [18/Aug/2013:21:05:22 -0400] cupsdReadClient: 15 2.0 Get-Printer-Attributes 11
D [18/Aug/2013:21:05:22 -0400] Get-Printer-Attributes ipp://192.168.3.102:631/printers/BrotherHL5150DLT
D [18/Aug/2013:21:05:22 -0400] Returning IPP successful-ok for Get-Printer-Attributes (ipp://192.168.3.102:631/printers/BrotherHL5150DLT) from 192.168.3.124
D [18/Aug/2013:21:05:22 -0400] cupsdSetBusyState: Dirty files
D [18/Aug/2013:21:05:22 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:05:22 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:05:25 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:05:25 -0400] Connection from 192.168.3.124 now encrypted.
D [18/Aug/2013:21:05:25 -0400] cupsdAcceptClient: 17 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:05:26 -0400] Connection from 192.168.3.124 now encrypted.
D [18/Aug/2013:21:05:26 -0400] cupsdReadClient: 17 GET /admin/log/error_log HTTP/1.1
D [18/Aug/2013:21:05:26 -0400] cupsdSetBusyState: Active clients and dirty files
D [18/Aug/2013:21:05:26 -0400] cupsdAuthorize: Authorized as root using Basic
D [18/Aug/2013:21:05:26 -0400] cupsdSetBusyState: Dirty files
D [18/Aug/2013:21:05:31 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:05:31 -0400] cupsdCloseClient: 15
D [18/Aug/2013:21:05:31 -0400] SSL shutdown successful!
D [18/Aug/2013:21:05:31 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [18/Aug/2013:21:05:31 -0400] cupsdCloseClient: 15
I [18/Aug/2013:21:05:51 -0400] Saving job cache file "/var/cache/cups/job.cache"...
D [18/Aug/2013:21:05:51 -0400] cupsdSetBusyState: Not busy
D [18/Aug/2013:21:05:56 -0400] cupsdReadClient: 17 WAITING Closing on EOF
D [18/Aug/2013:21:05:56 -0400] cupsdCloseClient: 17
D [18/Aug/2013:21:05:56 -0400] SSL shutdown successful!
D [18/Aug/2013:21:05:56 -0400] cupsdReadClient: 17 WAITING Closing on EOF
D [18/Aug/2013:21:05:56 -0400] cupsdCloseClient: 17
D [18/Aug/2013:21:06:19 -0400] cupsdAcceptClient: 15 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:06:19 -0400] Report: clients=2
D [18/Aug/2013:21:06:19 -0400] Report: jobs=19
D [18/Aug/2013:21:06:19 -0400] Report: jobs-active=0
D [18/Aug/2013:21:06:19 -0400] Report: printers=1
D [18/Aug/2013:21:06:19 -0400] Report: printers-implicit=0
D [18/Aug/2013:21:06:19 -0400] Report: stringpool-string-count=1457
D [18/Aug/2013:21:06:19 -0400] Report: stringpool-alloc-bytes=8128
D [18/Aug/2013:21:06:19 -0400] Report: stringpool-total-bytes=29056
D [18/Aug/2013:21:06:19 -0400] Connection from 192.168.3.124 now encrypted.
D [18/Aug/2013:21:06:19 -0400] cupsdAcceptClient: 17 from 192.168.3.124:631 (IPv4)
D [18/Aug/2013:21:06:19 -0400] Connection from 192.168.3.124 now encrypted.
D [18/Aug/2013:21:06:19 -0400] cupsdReadClient: 17 GET /admin/log/error_log HTTP/1.1
D [18/Aug/2013:21:06:19 -0400] cupsdSetBusyState: Active clients
D [18/Aug/2013:21:06:19 -0400] cupsdAuthorize: Authorized as root using Basic

wkatastrof · 2013-08-21 20:07:24

Even if no one has a solution to this issue, does anyone have any further troubleshooting steps?

Someone had suggested upgrading to CUPS 1.7 beta or downgrading to the version of CUPS that was on my Ubuntu machine...

Thoughts?

wkatastrof · 2013-09-01 23:12:01

I learned apt-pinning in Debian and installed CUPS 1.6.3 from Wheezy/Unstable to match the current version of CUPS in Arch.

It works now.

Arch Linux

#1 2013-08-19 01:22:43

CUPS - Printing to Print Server - Cannot Print from Client

#2 2013-08-21 20:07:24

Re: CUPS - Printing to Print Server - Cannot Print from Client

#3 2013-09-01 23:12:01

Re: CUPS - Printing to Print Server - Cannot Print from Client

Board footer