D [09/Nov/2013:21:25:22 +1100] [Client 13] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:22 +1100] [Client 13] Waiting for request.
D [09/Nov/2013:21:25:22 +1100] [Client 13] POST / HTTP/1.1
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:22 +1100] [Client 13] No authentication data provided.
D [09/Nov/2013:21:25:22 +1100] [Client 13] 2.0 CUPS-Get-Printers 1
D [09/Nov/2013:21:25:22 +1100] CUPS-Get-Printers
D [09/Nov/2013:21:25:22 +1100] [Client 13] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [09/Nov/2013:21:25:22 +1100] [Client 13] Content-Length: 4813
D [09/Nov/2013:21:25:22 +1100] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=4813, response=0x7fe7ebe59a80(IPP_IDLE), pipe_pid=0, file=-1
D [09/Nov/2013:21:25:22 +1100] [Client 13] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [09/Nov/2013:21:25:22 +1100] [Client 13] bytes=0, http_state=0, data_remaining=0
D [09/Nov/2013:21:25:22 +1100] [Client 13] Waiting for request.
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [09/Nov/2013:21:25:22 +1100] [Client 13] POST / HTTP/1.1
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:22 +1100] [Client 13] No authentication data provided.
D [09/Nov/2013:21:25:22 +1100] [Client 13] 2.0 CUPS-Get-Default 2
D [09/Nov/2013:21:25:22 +1100] CUPS-Get-Default
D [09/Nov/2013:21:25:22 +1100] [Client 13] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [09/Nov/2013:21:25:22 +1100] [Client 13] Content-Length: 8448
D [09/Nov/2013:21:25:22 +1100] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=8448, response=0x7fe7ebf3d780(IPP_IDLE), pipe_pid=0, file=-1
D [09/Nov/2013:21:25:22 +1100] [Client 13] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [09/Nov/2013:21:25:22 +1100] [Client 13] bytes=0, http_state=0, data_remaining=0
D [09/Nov/2013:21:25:22 +1100] [Client 13] Waiting for request.
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [09/Nov/2013:21:25:22 +1100] [Client 13] POST / HTTP/1.1
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:22 +1100] [Client 13] No authentication data provided.
D [09/Nov/2013:21:25:22 +1100] [Client 13] 2.0 CUPS-Get-Printers 3
D [09/Nov/2013:21:25:22 +1100] CUPS-Get-Printers
D [09/Nov/2013:21:25:22 +1100] [Client 13] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [09/Nov/2013:21:25:22 +1100] [Client 13] Content-Length: 4813
D [09/Nov/2013:21:25:22 +1100] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=4813, response=0x7fe7ebe59a80(IPP_IDLE), pipe_pid=0, file=-1
D [09/Nov/2013:21:25:22 +1100] [Client 13] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [09/Nov/2013:21:25:22 +1100] [Client 13] bytes=0, http_state=0, data_remaining=0
D [09/Nov/2013:21:25:22 +1100] [Client 13] Waiting for request.
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [09/Nov/2013:21:25:22 +1100] [Client 13] POST / HTTP/1.1
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:22 +1100] [Client 13] No authentication data provided.
D [09/Nov/2013:21:25:22 +1100] [Client 13] 2.0 CUPS-Get-Default 4
D [09/Nov/2013:21:25:22 +1100] CUPS-Get-Default
D [09/Nov/2013:21:25:22 +1100] [Client 13] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [09/Nov/2013:21:25:22 +1100] [Client 13] Content-Length: 8448
D [09/Nov/2013:21:25:22 +1100] [Client 13] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=8448, response=0x7fe7ebf3d780(IPP_IDLE), pipe_pid=0, file=-1
D [09/Nov/2013:21:25:22 +1100] [Client 13] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [09/Nov/2013:21:25:22 +1100] [Client 13] bytes=0, http_state=0, data_remaining=0
D [09/Nov/2013:21:25:22 +1100] [Client 13] Waiting for request.
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [09/Nov/2013:21:25:22 +1100] [Client 14] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:22 +1100] [Client 14] Waiting for request.
D [09/Nov/2013:21:25:22 +1100] [Client 15] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:22 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:22 +1100] [Client 14] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:22 +1100] [Client 14] Closing connection.
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:22 +1100] [Client 15] POST / HTTP/1.1
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:22 +1100] [Client 15] No authentication data provided.
D [09/Nov/2013:21:25:22 +1100] [Client 15] 2.0 CUPS-Get-Printers 1
D [09/Nov/2013:21:25:22 +1100] CUPS-Get-Printers
D [09/Nov/2013:21:25:22 +1100] [Client 15] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [09/Nov/2013:21:25:22 +1100] [Client 15] Content-Length: 3126
D [09/Nov/2013:21:25:22 +1100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3126, response=0x7fe7ebe59ba0(IPP_IDLE), pipe_pid=0, file=-1
D [09/Nov/2013:21:25:22 +1100] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [09/Nov/2013:21:25:22 +1100] [Client 15] bytes=0, http_state=0, data_remaining=0
D [09/Nov/2013:21:25:22 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [09/Nov/2013:21:25:22 +1100] [Client 14] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:22 +1100] [Client 14] Waiting for request.
D [09/Nov/2013:21:25:22 +1100] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:22 +1100] [Client 15] Closing connection.
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:22 +1100] [Client 14] GET /printers/HL-2040.ppd HTTP/1.1
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:22 +1100] [Client 14] No authentication data provided.
D [09/Nov/2013:21:25:22 +1100] [Client 14] Sending file.
D [09/Nov/2013:21:25:22 +1100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=12914, response=(nil)(), pipe_pid=0, file=15
D [09/Nov/2013:21:25:22 +1100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=10866, response=(nil)(), pipe_pid=0, file=15
D [09/Nov/2013:21:25:22 +1100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=8818, response=(nil)(), pipe_pid=0, file=15
D [09/Nov/2013:21:25:22 +1100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6770, response=(nil)(), pipe_pid=0, file=15
D [09/Nov/2013:21:25:22 +1100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=4722, response=(nil)(), pipe_pid=0, file=15
D [09/Nov/2013:21:25:22 +1100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2674, response=(nil)(), pipe_pid=0, file=15
D [09/Nov/2013:21:25:22 +1100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=626, response=(nil)(), pipe_pid=0, file=15
D [09/Nov/2013:21:25:22 +1100] [Client 14] Waiting for request.
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [09/Nov/2013:21:25:22 +1100] [Client 14] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:22 +1100] [Client 14] Closing connection.
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:22 +1100] [Client 14] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:22 +1100] [Client 14] Waiting for request.
D [09/Nov/2013:21:25:22 +1100] [Client 15] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:22 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:22 +1100] [Client 14] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:22 +1100] [Client 14] Closing connection.
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:22 +1100] [Client 15] POST / HTTP/1.1
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:22 +1100] [Client 15] No authentication data provided.
D [09/Nov/2013:21:25:22 +1100] [Client 15] 2.0 CUPS-Get-Printers 1
D [09/Nov/2013:21:25:22 +1100] CUPS-Get-Printers
D [09/Nov/2013:21:25:22 +1100] [Client 15] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [09/Nov/2013:21:25:22 +1100] [Client 15] Content-Length: 3126
D [09/Nov/2013:21:25:22 +1100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3126, response=0x7fe7ebe59ba0(IPP_IDLE), pipe_pid=0, file=-1
D [09/Nov/2013:21:25:22 +1100] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [09/Nov/2013:21:25:22 +1100] [Client 15] bytes=0, http_state=0, data_remaining=0
D [09/Nov/2013:21:25:22 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [09/Nov/2013:21:25:22 +1100] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:22 +1100] [Client 15] Closing connection.
D [09/Nov/2013:21:25:22 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:23 +1100] [Client 14] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:23 +1100] [Client 14] Waiting for request.
D [09/Nov/2013:21:25:23 +1100] [Client 15] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:23 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:23 +1100] [Client 14] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:23 +1100] [Client 14] Closing connection.
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:23 +1100] [Client 15] POST / HTTP/1.1
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:23 +1100] [Client 15] No authentication data provided.
D [09/Nov/2013:21:25:23 +1100] [Client 15] 2.0 CUPS-Get-Printers 1
D [09/Nov/2013:21:25:23 +1100] CUPS-Get-Printers
D [09/Nov/2013:21:25:23 +1100] [Client 15] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [09/Nov/2013:21:25:23 +1100] [Client 15] Content-Length: 3126
D [09/Nov/2013:21:25:23 +1100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3126, response=0x7fe7ebe59ba0(IPP_IDLE), pipe_pid=0, file=-1
D [09/Nov/2013:21:25:23 +1100] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [09/Nov/2013:21:25:23 +1100] [Client 15] bytes=0, http_state=0, data_remaining=0
D [09/Nov/2013:21:25:23 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [09/Nov/2013:21:25:23 +1100] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:23 +1100] [Client 15] Closing connection.
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:23 +1100] [Client 14] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:23 +1100] [Client 14] Waiting for request.
D [09/Nov/2013:21:25:23 +1100] [Client 15] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:23 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:23 +1100] [Client 14] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:23 +1100] [Client 14] Closing connection.
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:23 +1100] [Client 15] POST / HTTP/1.1
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:23 +1100] [Client 15] No authentication data provided.
D [09/Nov/2013:21:25:23 +1100] [Client 15] 2.0 CUPS-Get-Printers 1
D [09/Nov/2013:21:25:23 +1100] CUPS-Get-Printers
D [09/Nov/2013:21:25:23 +1100] [Client 15] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [09/Nov/2013:21:25:23 +1100] [Client 15] Content-Length: 3126
D [09/Nov/2013:21:25:23 +1100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3126, response=0x7fe7ebe59ba0(IPP_IDLE), pipe_pid=0, file=-1
D [09/Nov/2013:21:25:23 +1100] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [09/Nov/2013:21:25:23 +1100] [Client 15] bytes=0, http_state=0, data_remaining=0
D [09/Nov/2013:21:25:23 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [09/Nov/2013:21:25:23 +1100] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:23 +1100] [Client 15] Closing connection.
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:23 +1100] [Client 14] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:23 +1100] [Client 14] Waiting for request.
D [09/Nov/2013:21:25:23 +1100] [Client 15] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:23 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:23 +1100] [Client 14] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:23 +1100] [Client 14] Closing connection.
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:23 +1100] [Client 15] POST / HTTP/1.1
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:23 +1100] [Client 15] No authentication data provided.
D [09/Nov/2013:21:25:23 +1100] [Client 15] 2.0 CUPS-Get-Printers 1
D [09/Nov/2013:21:25:23 +1100] CUPS-Get-Printers
D [09/Nov/2013:21:25:23 +1100] [Client 15] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [09/Nov/2013:21:25:23 +1100] [Client 15] Content-Length: 3126
D [09/Nov/2013:21:25:23 +1100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3126, response=0x7fe7ebe59ba0(IPP_IDLE), pipe_pid=0, file=-1
D [09/Nov/2013:21:25:23 +1100] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [09/Nov/2013:21:25:23 +1100] [Client 15] bytes=0, http_state=0, data_remaining=0
D [09/Nov/2013:21:25:23 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [09/Nov/2013:21:25:23 +1100] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:23 +1100] [Client 15] Closing connection.
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:23 +1100] [Client 14] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:23 +1100] [Client 14] Waiting for request.
D [09/Nov/2013:21:25:23 +1100] [Client 15] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:23 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:23 +1100] [Client 14] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:23 +1100] [Client 14] Closing connection.
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:23 +1100] [Client 15] POST / HTTP/1.1
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:23 +1100] [Client 15] No authentication data provided.
D [09/Nov/2013:21:25:23 +1100] [Client 15] 2.0 CUPS-Get-Printers 1
D [09/Nov/2013:21:25:23 +1100] CUPS-Get-Printers
D [09/Nov/2013:21:25:23 +1100] [Client 15] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [09/Nov/2013:21:25:23 +1100] [Client 15] Content-Length: 3126
D [09/Nov/2013:21:25:23 +1100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3126, response=0x7fe7ebe59ba0(IPP_IDLE), pipe_pid=0, file=-1
D [09/Nov/2013:21:25:23 +1100] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [09/Nov/2013:21:25:23 +1100] [Client 15] bytes=0, http_state=0, data_remaining=0
D [09/Nov/2013:21:25:23 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [09/Nov/2013:21:25:23 +1100] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:23 +1100] [Client 15] Closing connection.
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:23 +1100] [Client 14] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:23 +1100] [Client 14] Waiting for request.
D [09/Nov/2013:21:25:23 +1100] [Client 15] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:23 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:23 +1100] [Client 14] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:23 +1100] [Client 14] Closing connection.
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:23 +1100] [Client 15] POST / HTTP/1.1
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:23 +1100] [Client 15] No authentication data provided.
D [09/Nov/2013:21:25:23 +1100] [Client 15] 2.0 CUPS-Get-Printers 1
D [09/Nov/2013:21:25:23 +1100] CUPS-Get-Printers
D [09/Nov/2013:21:25:23 +1100] [Client 15] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [09/Nov/2013:21:25:23 +1100] [Client 15] Content-Length: 3126
D [09/Nov/2013:21:25:23 +1100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3126, response=0x7fe7ebe59ba0(IPP_IDLE), pipe_pid=0, file=-1
D [09/Nov/2013:21:25:23 +1100] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [09/Nov/2013:21:25:23 +1100] [Client 15] bytes=0, http_state=0, data_remaining=0
D [09/Nov/2013:21:25:23 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [09/Nov/2013:21:25:23 +1100] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:23 +1100] [Client 15] Closing connection.
D [09/Nov/2013:21:25:23 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:24 +1100] [Client 14] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:24 +1100] [Client 14] Waiting for request.
D [09/Nov/2013:21:25:24 +1100] [Client 15] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:24 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:24 +1100] [Client 14] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:24 +1100] [Client 14] Closing connection.
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:24 +1100] [Client 15] POST / HTTP/1.1
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:24 +1100] [Client 15] No authentication data provided.
D [09/Nov/2013:21:25:24 +1100] [Client 15] 2.0 CUPS-Get-Printers 1
D [09/Nov/2013:21:25:24 +1100] CUPS-Get-Printers
D [09/Nov/2013:21:25:24 +1100] [Client 15] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [09/Nov/2013:21:25:24 +1100] [Client 15] Content-Length: 3126
D [09/Nov/2013:21:25:24 +1100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3126, response=0x7fe7ebe59ba0(IPP_IDLE), pipe_pid=0, file=-1
D [09/Nov/2013:21:25:24 +1100] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [09/Nov/2013:21:25:24 +1100] [Client 15] bytes=0, http_state=0, data_remaining=0
D [09/Nov/2013:21:25:24 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [09/Nov/2013:21:25:24 +1100] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:24 +1100] [Client 15] Closing connection.
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:24 +1100] [Client 14] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:24 +1100] [Client 14] Waiting for request.
D [09/Nov/2013:21:25:24 +1100] [Client 15] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:24 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:24 +1100] [Client 14] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:24 +1100] [Client 14] Closing connection.
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:24 +1100] [Client 15] POST / HTTP/1.1
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:24 +1100] [Client 15] No authentication data provided.
D [09/Nov/2013:21:25:24 +1100] [Client 15] 2.0 CUPS-Get-Printers 1
D [09/Nov/2013:21:25:24 +1100] CUPS-Get-Printers
D [09/Nov/2013:21:25:24 +1100] [Client 15] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [09/Nov/2013:21:25:24 +1100] [Client 15] Content-Length: 3126
D [09/Nov/2013:21:25:24 +1100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3126, response=0x7fe7ebe59ba0(IPP_IDLE), pipe_pid=0, file=-1
D [09/Nov/2013:21:25:24 +1100] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [09/Nov/2013:21:25:24 +1100] [Client 15] bytes=0, http_state=0, data_remaining=0
D [09/Nov/2013:21:25:24 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [09/Nov/2013:21:25:24 +1100] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:24 +1100] [Client 15] Closing connection.
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:24 +1100] [Client 14] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:24 +1100] [Client 14] Waiting for request.
D [09/Nov/2013:21:25:24 +1100] [Client 15] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:24 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:24 +1100] [Client 14] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:24 +1100] [Client 14] Closing connection.
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:24 +1100] [Client 15] POST / HTTP/1.1
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:24 +1100] [Client 15] No authentication data provided.
D [09/Nov/2013:21:25:24 +1100] [Client 15] 2.0 CUPS-Get-Printers 1
D [09/Nov/2013:21:25:24 +1100] CUPS-Get-Printers
D [09/Nov/2013:21:25:24 +1100] [Client 15] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [09/Nov/2013:21:25:24 +1100] [Client 15] Content-Length: 3126
D [09/Nov/2013:21:25:24 +1100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3126, response=0x7fe7ebe59ba0(IPP_IDLE), pipe_pid=0, file=-1
D [09/Nov/2013:21:25:24 +1100] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [09/Nov/2013:21:25:24 +1100] [Client 15] bytes=0, http_state=0, data_remaining=0
D [09/Nov/2013:21:25:24 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [09/Nov/2013:21:25:24 +1100] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:24 +1100] [Client 15] Closing connection.
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:24 +1100] [Client 14] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:24 +1100] [Client 14] Waiting for request.
D [09/Nov/2013:21:25:24 +1100] [Client 15] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:24 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:24 +1100] [Client 14] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:24 +1100] [Client 14] Closing connection.
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:24 +1100] [Client 15] POST / HTTP/1.1
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:24 +1100] [Client 15] No authentication data provided.
D [09/Nov/2013:21:25:24 +1100] [Client 15] 2.0 CUPS-Get-Printers 1
D [09/Nov/2013:21:25:24 +1100] CUPS-Get-Printers
D [09/Nov/2013:21:25:24 +1100] [Client 15] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [09/Nov/2013:21:25:24 +1100] [Client 15] Content-Length: 3126
D [09/Nov/2013:21:25:24 +1100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3126, response=0x7fe7ebe59ba0(IPP_IDLE), pipe_pid=0, file=-1
D [09/Nov/2013:21:25:24 +1100] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [09/Nov/2013:21:25:24 +1100] [Client 15] bytes=0, http_state=0, data_remaining=0
D [09/Nov/2013:21:25:24 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [09/Nov/2013:21:25:24 +1100] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:24 +1100] [Client 15] Closing connection.
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:24 +1100] [Client 14] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:24 +1100] [Client 14] Waiting for request.
D [09/Nov/2013:21:25:24 +1100] [Client 15] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:24 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:24 +1100] [Client 14] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:24 +1100] [Client 14] Closing connection.
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:24 +1100] [Client 15] POST / HTTP/1.1
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:24 +1100] [Client 15] No authentication data provided.
D [09/Nov/2013:21:25:24 +1100] [Client 15] 2.0 CUPS-Get-Printers 1
D [09/Nov/2013:21:25:24 +1100] CUPS-Get-Printers
D [09/Nov/2013:21:25:24 +1100] [Client 15] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [09/Nov/2013:21:25:24 +1100] [Client 15] Content-Length: 3126
D [09/Nov/2013:21:25:24 +1100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3126, response=0x7fe7ebe59ba0(IPP_IDLE), pipe_pid=0, file=-1
D [09/Nov/2013:21:25:24 +1100] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [09/Nov/2013:21:25:24 +1100] [Client 15] bytes=0, http_state=0, data_remaining=0
D [09/Nov/2013:21:25:24 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [09/Nov/2013:21:25:24 +1100] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:24 +1100] [Client 15] Closing connection.
D [09/Nov/2013:21:25:24 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [09/Nov/2013:21:25:28 +1100] [Client 14] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:28 +1100] [Client 14] Waiting for request.
D [09/Nov/2013:21:25:28 +1100] [Client 15] Accepted from localhost (Domain)
D [09/Nov/2013:21:25:28 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:28 +1100] [Client 15] POST /printers/HL-2040 HTTP/1.1
D [09/Nov/2013:21:25:28 +1100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [09/Nov/2013:21:25:28 +1100] [Client 15] No authentication data provided.
D [09/Nov/2013:21:25:28 +1100] [Client 15] 2.1 Print-Job 1
D [09/Nov/2013:21:25:28 +1100] Print-Job ipp://localhost:631/printers/HL-2040
D [09/Nov/2013:21:25:28 +1100] [Job ???] Auto-typing file...
I [09/Nov/2013:21:25:28 +1100] [Job ???] Request file type is application/pdf.
D [09/Nov/2013:21:25:28 +1100] cupsdMarkDirty(---J-)
D [09/Nov/2013:21:25:28 +1100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [09/Nov/2013:21:25:28 +1100] add_job: requesting-user-name="sparhawk"
I [09/Nov/2013:21:25:28 +1100] [Job 153] Adding start banner page "none".
D [09/Nov/2013:21:25:28 +1100] Discarding unused job-created event...
D [09/Nov/2013:21:25:28 +1100] cupsdMarkDirty(---J-)
D [09/Nov/2013:21:25:28 +1100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [09/Nov/2013:21:25:28 +1100] [Job 153] Adding end banner page "none".
I [09/Nov/2013:21:25:28 +1100] [Job 153] File of type application/pdf queued by "sparhawk".
D [09/Nov/2013:21:25:28 +1100] [Job 153] hold_until=0
I [09/Nov/2013:21:25:28 +1100] [Job 153] Queued on "HL-2040" by "sparhawk".
D [09/Nov/2013:21:25:28 +1100] [Job 153] time-at-processing=1383992728
D [09/Nov/2013:21:25:28 +1100] cupsdMarkDirty(---J-)
D [09/Nov/2013:21:25:28 +1100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [09/Nov/2013:21:25:28 +1100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [09/Nov/2013:21:25:28 +1100] Discarding unused printer-state-changed event...
D [09/Nov/2013:21:25:28 +1100] [Job 153] 2 filters for job:
D [09/Nov/2013:21:25:28 +1100] [Job 153] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [09/Nov/2013:21:25:28 +1100] [Job 153] foomatic-rip (application/vnd.cups-pdf to printer/HL-2040, cost 0)
D [09/Nov/2013:21:25:28 +1100] [Job 153] job-sheets=none,none
D [09/Nov/2013:21:25:28 +1100] [Job 153] argv[0]="HL-2040"
D [09/Nov/2013:21:25:28 +1100] [Job 153] argv[1]="153"
D [09/Nov/2013:21:25:28 +1100] [Job 153] argv[2]="sparhawk"
D [09/Nov/2013:21:25:28 +1100] [Job 153] argv[3]="PaperCut KB | How to Enable Debug in CUPS"
D [09/Nov/2013:21:25:28 +1100] [Job 153] argv[4]="1"
D [09/Nov/2013:21:25:28 +1100] [Job 153] argv[5]="InputSlot=auto PageSize=Custom.595.28x841.89 MediaType=Plain EconoMode=off Resolution=300x300dpi number-up=1 job-uuid=urn:uuid:1e431291-40cf-35ab-73c6-e3f5a0344b10 job-originating-host-name=localhost time-at-creation=1383992728 time-at-processing=1383992728"
D [09/Nov/2013:21:25:28 +1100] [Job 153] argv[6]="/var/spool/cups/d00153-001"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[8]="HOME=/var/spool/cups/tmp"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[10]="SERVER_ADMIN=root@sparhawk-XPS-17"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[11]="SOFTWARE=CUPS/1.7rc1"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[13]="USER=root"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[14]="CUPS_MAX_MESSAGE=2047"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[17]="IPP_PORT=631"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[18]="CHARSET=utf-8"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[19]="LANG=en_AU.UTF-8"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[20]="PPD=/etc/cups/ppd/HL-2040.ppd"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[21]="RIP_MAX_CACHE=128m"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[22]="CONTENT_TYPE=application/pdf"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[23]="DEVICE_URI=dnssd://Brother%20HL-2040%20series._pdl-datastream._tcp.local/"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[24]="PRINTER_INFO=HL-2040"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[25]="PRINTER_LOCATION=Home"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[26]="PRINTER=HL-2040"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[27]="PRINTER_STATE_REASONS=none"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[28]="CUPS_FILETYPE=document"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-pdf"
D [09/Nov/2013:21:25:28 +1100] [Job 153] envp[30]="AUTH_I****"
I [09/Nov/2013:21:25:28 +1100] [Job 153] Started filter /usr/lib/cups/filter/pdftopdf (PID 28056)
I [09/Nov/2013:21:25:28 +1100] [Job 153] Started filter /usr/lib/cups/filter/foomatic-rip (PID 28057)
I [09/Nov/2013:21:25:28 +1100] [Job 153] Started backend /usr/lib/cups/backend/dnssd (PID 28058)
D [09/Nov/2013:21:25:28 +1100] Discarding unused job-state-changed event...
D [09/Nov/2013:21:25:28 +1100] [Client 15] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/HL-2040) from localhost
D [09/Nov/2013:21:25:28 +1100] [Client 15] Content-Length: 175
D [09/Nov/2013:21:25:28 +1100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=175, response=0x7fe7ebf4ddd0(IPP_IDLE), pipe_pid=0, file=-1
D [09/Nov/2013:21:25:28 +1100] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0
D [09/Nov/2013:21:25:28 +1100] [Client 15] bytes=0, http_state=0, data_remaining=0
D [09/Nov/2013:21:25:28 +1100] [Client 15] Waiting for request.
D [09/Nov/2013:21:25:28 +1100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files"
D [09/Nov/2013:21:25:28 +1100] [Job 153] Resolving "Brother HL-2040 series._pdl-datastream._tcp.local"...
D [09/Nov/2013:21:25:28 +1100] [Job 153] STATE: +connecting-to-device
D [09/Nov/2013:21:25:28 +1100] cupsdMarkDirty(---J-)
D [09/Nov/2013:21:25:28 +1100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
D [09/Nov/2013:21:25:28 +1100] [Job 153] Resolving "Brother HL-2040 series", regtype="_pdl-datastream._tcp", domain="local."...
D [09/Nov/2013:21:25:28 +1100] Discarding unused printer-state-changed event...
D [09/Nov/2013:21:25:28 +1100] [Job 153] Getting input from file 
D [09/Nov/2013:21:25:28 +1100] [Job 153] foomatic-rip version 4.0.17.256 running...
D [09/Nov/2013:21:25:28 +1100] [Job 153] Parsing PPD file ...
D [09/Nov/2013:21:25:28 +1100] [Job 153] Added option ColorSpace
D [09/Nov/2013:21:25:28 +1100] [Job 153] Added option PageSize
D [09/Nov/2013:21:25:28 +1100] [Job 153] Added option ImageableArea
D [09/Nov/2013:21:25:28 +1100] [Job 153] Added option PaperDimension
D [09/Nov/2013:21:25:28 +1100] [Job 153] Added option MediaType
D [09/Nov/2013:21:25:28 +1100] [Job 153] Added option EconoMode
D [09/Nov/2013:21:25:28 +1100] [Job 153] Added option InputSlot
D [09/Nov/2013:21:25:28 +1100] [Job 153] Added option Resolution
D [09/Nov/2013:21:25:28 +1100] [Job 153] Added option Font
D [09/Nov/2013:21:25:28 +1100] [Job 153] Parameter Summary
D [09/Nov/2013:21:25:28 +1100] [Job 153] -----------------
D [09/Nov/2013:21:25:28 +1100] [Job 153] Spooler: cups
D [09/Nov/2013:21:25:28 +1100] [Job 153] Printer: HL-2040
D [09/Nov/2013:21:25:28 +1100] [Job 153] Shell: /bin/bash
D [09/Nov/2013:21:25:28 +1100] [Job 153] PPD file: /etc/cups/ppd/HL-2040.ppd
D [09/Nov/2013:21:25:28 +1100] [Job 153] ATTR file: 
D [09/Nov/2013:21:25:28 +1100] [Job 153] Printer model: Brother HL-1030 Foomatic/hl1250 (recommended)
D [09/Nov/2013:21:25:28 +1100] [Job 153] Job title: PaperCut KB  How to Enable Debug in CUPS
D [09/Nov/2013:21:25:28 +1100] [Job 153] File(s) to be printed:
D [09/Nov/2013:21:25:28 +1100] [Job 153] <STDIN>
D [09/Nov/2013:21:25:28 +1100] [Job 153] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [09/Nov/2013:21:25:28 +1100] [Job 153] Printing system options:
D [09/Nov/2013:21:25:28 +1100] [Job 153] Pondering option 'number-up=1'
D [09/Nov/2013:21:25:28 +1100] [Job 153] Unknown option number-up=1.
D [09/Nov/2013:21:25:28 +1100] [Job 153] Pondering option 'job-uuid=urn:uuid:1e431291-40cf-35ab-73c6-e3f5a0344b10'
D [09/Nov/2013:21:25:28 +1100] [Job 153] Unknown option job-uuid=urn:uuid:1e431291-40cf-35ab-73c6-e3f5a0344b10.
D [09/Nov/2013:21:25:28 +1100] [Job 153] Pondering option 'job-originating-host-name=localhost'
D [09/Nov/2013:21:25:28 +1100] [Job 153] Unknown option job-originating-host-name=localhost.
D [09/Nov/2013:21:25:28 +1100] [Job 153] Pondering option 'time-at-creation=1383992728'
D [09/Nov/2013:21:25:28 +1100] [Job 153] Unknown option time-at-creation=1383992728.
D [09/Nov/2013:21:25:28 +1100] [Job 153] Pondering option 'time-at-processing=1383992728'
D [09/Nov/2013:21:25:28 +1100] [Job 153] Unknown option time-at-processing=1383992728.
D [09/Nov/2013:21:25:28 +1100] [Job 153] Options from the PPD file:
D [09/Nov/2013:21:25:28 +1100] [Job 153] Pondering option 'InputSlot=auto'
D [09/Nov/2013:21:25:28 +1100] [Job 153] Pondering option 'PageSize=Custom.595.28x841.89'
D [09/Nov/2013:21:25:28 +1100] [Job 153] Pondering option 'MediaType=Plain'
D [09/Nov/2013:21:25:28 +1100] [Job 153] Pondering option 'EconoMode=off'
D [09/Nov/2013:21:25:28 +1100] [Job 153] Pondering option 'Resolution=300x300dpi'
D [09/Nov/2013:21:25:28 +1100] [Job 153] ================================================
D [09/Nov/2013:21:25:28 +1100] [Job 153] File: <STDIN>
D [09/Nov/2013:21:25:28 +1100] [Job 153] ================================================
D [09/Nov/2013:21:25:28 +1100] [Client 15] HTTP_STATE_WAITING Closing on EOF
D [09/Nov/2013:21:25:28 +1100] [Client 15] Closing connection.
D [09/Nov/2013:21:25:28 +1100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [09/Nov/2013:21:25:28 +1100] [Job 153] Filetype: PDF
D [09/Nov/2013:21:25:28 +1100] [Job 153] Storing temporary files in /var/spool/cups/tmp
D [09/Nov/2013:21:25:28 +1100] [Job 153] PID 28056 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [09/Nov/2013:21:25:28 +1100] [Job 153] File contains 4 pages
D [09/Nov/2013:21:25:28 +1100] [Job 153] Starting renderer with command: gs -dFirstPage=1  -q -dBATCH -dPARANOIDSAFER -dNOPAUSE -dNOINTERPOLATE -sDEVICE=hl1250 -dEconoMode=0 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=841 -r300x300 -dSourceTray=0 -sOutputFile=- -f   /var/spool/cups/tmp/foomatic-73SwMc 
D [09/Nov/2013:21:25:28 +1100] [Job 153] Starting process "kid3" (generation 1)
D [09/Nov/2013:21:25:28 +1100] [Job 153] Starting process "kid4" (generation 2)
D [09/Nov/2013:21:25:28 +1100] [Job 153] Starting process "renderer" (generation 2)
D [09/Nov/2013:21:25:28 +1100] [Job 153] JCL: %-12345X@PJL
D [09/Nov/2013:21:25:28 +1100] [Job 153] @PJL SET MEDIATYPE=REGULAR
D [09/Nov/2013:21:25:28 +1100] [Job 153] <job data> %-12345X@PJL RESET
D [09/Nov/2013:21:25:28 +1100] [Job 153] Resolved as "socket://Tom.local:9100/"...
D [09/Nov/2013:21:25:28 +1100] [Job 153] STATE: -connecting-to-device,offline-report
D [09/Nov/2013:21:25:28 +1100] cupsdMarkDirty(---J-)
D [09/Nov/2013:21:25:28 +1100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
D [09/Nov/2013:21:25:28 +1100] Discarding unused printer-state-changed event...
D [09/Nov/2013:21:25:28 +1100] [Job 153] Executing backend "/usr/lib/cups/backend/socket"...
D [09/Nov/2013:21:25:28 +1100] [Job 153] STATE: +connecting-to-device
D [09/Nov/2013:21:25:28 +1100] cupsdMarkDirty(---J-)
D [09/Nov/2013:21:25:28 +1100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
D [09/Nov/2013:21:25:28 +1100] [Job 153] Looking up "Tom.local"...
D [09/Nov/2013:21:25:28 +1100] Discarding unused printer-state-changed event...
D [09/Nov/2013:21:25:32 +1100] [Job 153] prtGeneralCurrentLocalization type is 0, expected 2!
D [09/Nov/2013:21:25:32 +1100] [Job 153] backendWaitLoop(snmp_fd=5, addr=0x7fa4946fbbc8, side_cb=0x7fa492ef3200)
D [09/Nov/2013:21:25:32 +1100] [Job 153] Connecting to Tom.local:9100
I [09/Nov/2013:21:25:32 +1100] [Job 153] Connecting to printer.
D [09/Nov/2013:21:25:32 +1100] cupsdMarkDirty(---J-)
D [09/Nov/2013:21:25:32 +1100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [09/Nov/2013:21:25:32 +1100] [Job 153] Set job-printer-state-message to "Connecting to printer.", current level=INFO
D [09/Nov/2013:21:25:32 +1100] Discarding unused job-progress event...
D [09/Nov/2013:21:25:32 +1100] Discarding unused printer-state-changed event...
D [09/Nov/2013:21:25:39 +1100] [Job 153] Connection error: Transport endpoint is not connected
E [09/Nov/2013:21:25:39 +1100] [Job 153] The printer is not responding.
D [09/Nov/2013:21:25:39 +1100] cupsdMarkDirty(---J-)
D [09/Nov/2013:21:25:39 +1100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [09/Nov/2013:21:25:39 +1100] [Job 153] Set job-printer-state-message to "The printer is not responding.", current level=ERROR
D [09/Nov/2013:21:25:39 +1100] Discarding unused job-progress event...
D [09/Nov/2013:21:25:39 +1100] Discarding unused printer-state-changed event...
I [09/Nov/2013:21:25:59 +1100] Saving job.cache...
D [09/Nov/2013:21:25:59 +1100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [09/Nov/2013:21:25:59 +1100] Report: clients=2
D [09/Nov/2013:21:25:59 +1100] Report: jobs=154
D [09/Nov/2013:21:25:59 +1100] Report: jobs-active=1
D [09/Nov/2013:21:25:59 +1100] Report: printers=4
D [09/Nov/2013:21:25:59 +1100] Report: stringpool-string-count=10425
D [09/Nov/2013:21:25:59 +1100] Report: stringpool-alloc-bytes=12072
D [09/Nov/2013:21:25:59 +1100] Report: stringpool-total-bytes=158328
D [09/Nov/2013:21:26:09 +1100] [Job 153] Connection error: Transport endpoint is not connected
E [09/Nov/2013:21:26:09 +1100] [Job 153] The printer is not responding.
D [09/Nov/2013:21:26:39 +1100] [Job 153] Connection error: Transport endpoint is not connected
E [09/Nov/2013:21:26:39 +1100] [Job 153] The printer is not responding.