D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:31 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:31 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:31 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:31 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:31 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:32 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:32 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:32 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:32 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:32 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:32 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:32 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:32 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:32 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:32 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:32 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:32 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:32 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:32 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:32 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:32 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:32 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:32 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:32 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:32 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:32 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:32 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:32 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:32 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:32 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:32 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:32 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:32 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:32 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:32 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:32 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:32 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:32 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:32 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:32 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:32 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:32 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1 D [01/Mar/2012:20:48:32 +0100] CUPS-Get-Printers D [01/Mar/2012:20:48:32 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 POST / HTTP/1.1 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:32 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:48:32 +0100] CUPS-Get-Default D [01/Mar/2012:20:48:32 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:32 +0100] cupsdAcceptClient: 14 from localhost (Domain) D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 14 POST / HTTP/1.1 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:32 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 14 1.1 Get-Printer-Attributes 1 D [01/Mar/2012:20:48:32 +0100] Get-Printer-Attributes ipp://localhost:631/printers/HP_6315 D [01/Mar/2012:20:48:32 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/HP_6315) from localhost D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 14 POST /printers/HP_6315 HTTP/1.1 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:48:32 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 14 1.1 Create-Job 1 D [01/Mar/2012:20:48:32 +0100] Create-Job ipp://localhost:631/printers/HP_6315 D [01/Mar/2012:20:48:32 +0100] cupsdMarkDirty(----J-) D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients" D [01/Mar/2012:20:48:32 +0100] add_job: requesting-user-name="internet" I [01/Mar/2012:20:48:32 +0100] [Job 55] Adding start banner page "none". D [01/Mar/2012:20:48:32 +0100] Discarding unused job-created event... I [01/Mar/2012:20:48:32 +0100] [Job 55] Queued on "HP_6315" by "internet". D [01/Mar/2012:20:48:32 +0100] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/HP_6315) from localhost D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 14 POST /printers/HP_6315 HTTP/1.1 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [01/Mar/2012:20:48:32 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 14 1.1 Send-Document 1 D [01/Mar/2012:20:48:32 +0100] Send-Document ipp://localhost:631/printers/HP_6315 D [01/Mar/2012:20:48:32 +0100] cupsdIsAuthorized: requesting-user-name="internet" D [01/Mar/2012:20:48:32 +0100] [Job 55] Auto-typing file... D [01/Mar/2012:20:48:32 +0100] [Job 55] Request file type is application/postscript. D [01/Mar/2012:20:48:32 +0100] cupsdMarkDirty(----J-) D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [01/Mar/2012:20:48:32 +0100] [Job 55] File of type application/postscript queued by "internet". I [01/Mar/2012:20:48:32 +0100] [Job 55] Adding end banner page "none". D [01/Mar/2012:20:48:32 +0100] cupsdMarkDirty(----J-) D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:48:32 +0100] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/HP_6315) from localhost D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:48:32 +0100] cupsdReadClient: 14 WAITING Closing on EOF D [01/Mar/2012:20:48:32 +0100] cupsdCloseClient: 14 D [01/Mar/2012:20:48:32 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [01/Mar/2012:20:48:42 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [01/Mar/2012:20:48:42 +0100] cupsdNetIFUpdate: "eth0" = 192.168.2.100:631 D [01/Mar/2012:20:48:42 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [01/Mar/2012:20:48:42 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::219:66ff:fe5f:5255+eth0]:631 I [01/Mar/2012:20:49:03 +0100] Saving job.cache... D [01/Mar/2012:20:49:03 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files" D [01/Mar/2012:20:49:35 +0100] Report: clients=1 D [01/Mar/2012:20:49:35 +0100] Report: jobs=14 D [01/Mar/2012:20:49:35 +0100] Report: jobs-active=2 D [01/Mar/2012:20:49:35 +0100] Report: printers=1 D [01/Mar/2012:20:49:35 +0100] Report: printers-implicit=0 D [01/Mar/2012:20:49:35 +0100] Report: stringpool-string-count=2972 D [01/Mar/2012:20:49:35 +0100] Report: stringpool-alloc-bytes=10328 D [01/Mar/2012:20:49:35 +0100] Report: stringpool-total-bytes=56472 D [01/Mar/2012:20:49:44 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [01/Mar/2012:20:49:44 +0100] cupsdNetIFUpdate: "eth0" = 192.168.2.100:631 D [01/Mar/2012:20:49:44 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [01/Mar/2012:20:49:44 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::219:66ff:fe5f:5255+eth0]:631 D [01/Mar/2012:20:50:37 +0100] Report: clients=1 D [01/Mar/2012:20:50:37 +0100] Report: jobs=14 D [01/Mar/2012:20:50:37 +0100] Report: jobs-active=2 D [01/Mar/2012:20:50:37 +0100] Report: printers=1 D [01/Mar/2012:20:50:37 +0100] Report: printers-implicit=0 D [01/Mar/2012:20:50:37 +0100] Report: stringpool-string-count=2972 D [01/Mar/2012:20:50:37 +0100] Report: stringpool-alloc-bytes=10328 D [01/Mar/2012:20:50:37 +0100] Report: stringpool-total-bytes=56472 D [01/Mar/2012:20:50:46 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [01/Mar/2012:20:50:46 +0100] cupsdNetIFUpdate: "eth0" = 192.168.2.100:631 D [01/Mar/2012:20:50:46 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [01/Mar/2012:20:50:46 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::219:66ff:fe5f:5255+eth0]:631 D [01/Mar/2012:20:51:39 +0100] Report: clients=1 D [01/Mar/2012:20:51:39 +0100] Report: jobs=14 D [01/Mar/2012:20:51:39 +0100] Report: jobs-active=2 D [01/Mar/2012:20:51:39 +0100] Report: printers=1 D [01/Mar/2012:20:51:39 +0100] Report: printers-implicit=0 D [01/Mar/2012:20:51:39 +0100] Report: stringpool-string-count=2972 D [01/Mar/2012:20:51:39 +0100] Report: stringpool-alloc-bytes=10328 D [01/Mar/2012:20:51:39 +0100] Report: stringpool-total-bytes=56472 D [01/Mar/2012:20:51:48 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [01/Mar/2012:20:51:48 +0100] cupsdNetIFUpdate: "eth0" = 192.168.2.100:631 D [01/Mar/2012:20:51:48 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [01/Mar/2012:20:51:48 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::219:66ff:fe5f:5255+eth0]:631 D [01/Mar/2012:20:51:51 +0100] cupsdAcceptClient: 14 from localhost:631 (IPv6) D [01/Mar/2012:20:51:51 +0100] cupsdReadClient: 14 GET /printers/HP_6315 HTTP/1.1 D [01/Mar/2012:20:51:51 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:51:51 +0100] cupsdAuthorize: Authorized as root using Basic D [01/Mar/2012:20:51:51 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@host" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[13] = "USER=root" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[16] = "IPP_PORT=631" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[17] = "AUTH_TYPE=Basic" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[18] = "LANG=de_DE.UTF8" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[19] = "REDIRECT_STATUS=1" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[21] = "SERVER_NAME=localhost" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[22] = "SERVER_PORT=631" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[23] = "REMOTE_ADDR=[v1.::1]" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[24] = "REMOTE_HOST=localhost" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[25] = "SCRIPT_NAME=/printers/HP_6315" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/printers/HP_6315" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[27] = "PATH_INFO=/HP_6315" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[28] = "REMOTE_USER=root" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=e1220f1882cfa844c625ae45e46fad5f" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux i686; rv:10.0.2) Gecko/20100101 Firefox/10.0.2" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[32] = "HTTP_REFERER=http://localhost:631/printers/" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[33] = "REQUEST_METHOD=GET" D [01/Mar/2012:20:51:51 +0100] [CGI] envp[34] = "QUERY_STRING=" D [01/Mar/2012:20:51:51 +0100] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 16750) I [01/Mar/2012:20:51:51 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=16750) D [01/Mar/2012:20:51:51 +0100] cupsdSendCommand: 14 file=16 D [01/Mar/2012:20:51:51 +0100] [CGI] org.cups.sid cookie is "e1220f1882cfa844c625ae45e46fad5f" D [01/Mar/2012:20:51:51 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [01/Mar/2012:20:51:51 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [01/Mar/2012:20:51:51 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [01/Mar/2012:20:51:51 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:51:51 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:51:51 +0100] CUPS-Get-Default D [01/Mar/2012:20:51:51 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:51:51 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [01/Mar/2012:20:51:51 +0100] [CGI] show_printer(http=0xb78aea98, printer="HP_6315") D [01/Mar/2012:20:51:51 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [01/Mar/2012:20:51:51 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [01/Mar/2012:20:51:51 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:51:51 +0100] cupsdReadClient: 17 1.1 Get-Printer-Attributes 1 D [01/Mar/2012:20:51:51 +0100] Get-Printer-Attributes ipp://localhost/printers/HP_6315 D [01/Mar/2012:20:51:51 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_6315) from localhost D [01/Mar/2012:20:51:51 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [01/Mar/2012:20:51:51 +0100] Script header: Content-Type: text/html;charset=utf-8 D [01/Mar/2012:20:51:51 +0100] Script header: D [01/Mar/2012:20:51:51 +0100] [CGI] Regular expression ".*Clean.*" D [01/Mar/2012:20:51:51 +0100] [CGI] matches[0].rm_so=0 D [01/Mar/2012:20:51:51 +0100] [CGI] matches[1].rm_so=-1 D [01/Mar/2012:20:51:51 +0100] [CGI] Regular expression ".*PrintSelfTestPage.*" D [01/Mar/2012:20:51:51 +0100] [CGI] matches[0].rm_so=0 D [01/Mar/2012:20:51:51 +0100] [CGI] matches[1].rm_so=-1 D [01/Mar/2012:20:51:51 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [01/Mar/2012:20:51:51 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [01/Mar/2012:20:51:51 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:51:51 +0100] cupsdReadClient: 17 1.1 Get-Jobs 1 D [01/Mar/2012:20:51:51 +0100] Get-Jobs ipp://localhost:631/printers/HP_6315 D [01/Mar/2012:20:51:51 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_6315) from localhost D [01/Mar/2012:20:51:51 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [01/Mar/2012:20:51:51 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [01/Mar/2012:20:51:51 +0100] cupsdCloseClient: 17 D [01/Mar/2012:20:51:51 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [01/Mar/2012:20:51:51 +0100] PID 16750 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [01/Mar/2012:20:51:52 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [01/Mar/2012:20:52:02 +0100] cupsdReadClient: 14 POST /printers/HP_6315 HTTP/1.1 D [01/Mar/2012:20:52:02 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [01/Mar/2012:20:52:02 +0100] cupsdAuthorize: Authorized as root using Basic D [01/Mar/2012:20:52:02 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@host" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[13] = "USER=root" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[16] = "IPP_PORT=631" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[17] = "AUTH_TYPE=Basic" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[18] = "LANG=de_DE.UTF8" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[19] = "REDIRECT_STATUS=1" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[21] = "SERVER_NAME=localhost" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[22] = "SERVER_PORT=631" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[23] = "REMOTE_ADDR=[v1.::1]" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[24] = "REMOTE_HOST=localhost" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[25] = "SCRIPT_NAME=/printers/HP_6315" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/printers/HP_6315" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[27] = "PATH_INFO=/HP_6315" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[28] = "REMOTE_USER=root" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=e1220f1882cfa844c625ae45e46fad5f" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux i686; rv:10.0.2) Gecko/20100101 Firefox/10.0.2" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[32] = "HTTP_REFERER=http://localhost:631/printers/HP_6315" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[33] = "REQUEST_METHOD=POST" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[34] = "CONTENT_LENGTH=62" D [01/Mar/2012:20:52:02 +0100] [CGI] envp[35] = "CONTENT_TYPE=application/x-www-form-urlencoded" D [01/Mar/2012:20:52:02 +0100] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 16760) I [01/Mar/2012:20:52:02 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=16760) D [01/Mar/2012:20:52:02 +0100] cupsdSendCommand: 14 file=17 D [01/Mar/2012:20:52:02 +0100] [CGI] org.cups.sid cookie is "e1220f1882cfa844c625ae45e46fad5f" D [01/Mar/2012:20:52:02 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [01/Mar/2012:20:52:02 +0100] cupsdReadClient: 16 POST /printers/HP_6315 HTTP/1.1 D [01/Mar/2012:20:52:02 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [01/Mar/2012:20:52:02 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:02 +0100] cupsdReadClient: 16 1.1 Resume-Printer 1 D [01/Mar/2012:20:52:02 +0100] Resume-Printer ipp://localhost/printers/HP_6315 D [01/Mar/2012:20:52:02 +0100] cupsdIsAuthorized: username="" D [01/Mar/2012:20:52:02 +0100] Returning HTTP Unauthorized for Resume-Printer (ipp://localhost/printers/HP_6315) from localhost D [01/Mar/2012:20:52:02 +0100] cupsdSendHeader: 16 WWW-Authenticate: Basic realm="CUPS", trc="y" D [01/Mar/2012:20:52:02 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [01/Mar/2012:20:52:02 +0100] cupsdCloseClient: 16 D [01/Mar/2012:20:52:02 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [01/Mar/2012:20:52:02 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [01/Mar/2012:20:52:02 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [01/Mar/2012:20:52:02 +0100] cupsdCloseClient: 16 D [01/Mar/2012:20:52:02 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [01/Mar/2012:20:52:02 +0100] cupsdAcceptClient: 16 from localhost (Domain) D [01/Mar/2012:20:52:02 +0100] cupsdReadClient: 16 POST /printers/HP_6315 HTTP/1.1 D [01/Mar/2012:20:52:02 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [01/Mar/2012:20:52:02 +0100] cupsdAuthorize: Authorized as root using Local D [01/Mar/2012:20:52:02 +0100] cupsdReadClient: 16 1.1 Resume-Printer 1 D [01/Mar/2012:20:52:02 +0100] Resume-Printer ipp://localhost/printers/HP_6315 D [01/Mar/2012:20:52:02 +0100] cupsdIsAuthorized: username="root" D [01/Mar/2012:20:52:02 +0100] Discarding unused printer-state-changed event... D [01/Mar/2012:20:52:02 +0100] cupsdMarkDirty(P-----) D [01/Mar/2012:20:52:02 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients" D [01/Mar/2012:20:52:02 +0100] cupsdRegisterPrinter(p=0xb7816520(HP_6315)) D [01/Mar/2012:20:52:02 +0100] cupsdMarkDirty(P-----) D [01/Mar/2012:20:52:02 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [01/Mar/2012:20:52:02 +0100] Printer "HP_6315" started by "root". D [01/Mar/2012:20:52:02 +0100] cupsdMarkDirty(----J-) D [01/Mar/2012:20:52:02 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:02 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:02 +0100] Discarding unused printer-state-changed event... D [01/Mar/2012:20:52:02 +0100] [Job 54] job-sheets=none,none D [01/Mar/2012:20:52:02 +0100] [Job 54] argv[0]="HP_6315" D [01/Mar/2012:20:52:02 +0100] [Job 54] argv[1]="54" D [01/Mar/2012:20:52:02 +0100] [Job 54] argv[2]="internet" D [01/Mar/2012:20:52:02 +0100] [Job 54] argv[3]="Wie sieht das Reh aus" D [01/Mar/2012:20:52:02 +0100] [Job 54] argv[4]="1" D [01/Mar/2012:20:52:02 +0100] [Job 54] argv[5]="noCollate finishings=3 media=A4 number-up=1 outputorder=normal portrait sides=one-sided job-uuid=urn:uuid:71fc993a-3863-3198-668b-783b50b79f50 job-originating-host-name=localhost time-at-creation=1330631255 time-at-processing=1330631522 AP_D_InputSlot= Duplex=None PageSize=A4" D [01/Mar/2012:20:52:02 +0100] [Job 54] argv[6]="/var/spool/cups/d00054-001" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[1]="CUPS_DATADIR=/usr/share/cups" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[2]="CUPS_DOCROOT=/usr/share/cups/webcontent" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[6]="CUPS_SERVERROOT=/etc/cups" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[7]="CUPS_STATEDIR=/var/run/cups" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[8]="HOME=/var/spool/cups/tmp" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[10]="SERVER_ADMIN=root@host" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[11]="SOFTWARE=CUPS/1.5.0" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[12]="TMPDIR=/var/spool/cups/tmp" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[13]="USER=root" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[15]="CUPS_ENCRYPTION=IfRequested" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[16]="IPP_PORT=631" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[17]="CHARSET=utf-8" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[18]="LANG=de_DE.UTF-8" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[19]="PPD=/etc/cups/ppd/HP_6315.ppd" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[20]="RIP_MAX_CACHE=128m" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[21]="CONTENT_TYPE=application/postscript" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[22]="DEVICE_URI=hp:/usb/Officejet_6300_series?serial=CN689CF0Q204M4" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[23]="PRINTER_INFO=All_in_One" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[24]="PRINTER_LOCATION=im_Büro" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[25]="PRINTER=HP_6315" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[26]="PRINTER_STATE_REASONS=marker-supply-low-warning" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[27]="CUPS_FILETYPE=document" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[28]="FINAL_CONTENT_TYPE=printer/HP_6315" D [01/Mar/2012:20:52:02 +0100] [Job 54] envp[29]="AUTH_I****" I [01/Mar/2012:20:52:02 +0100] [Job 54] Started filter /usr/lib/cups/filter/pstops (PID 16761) I [01/Mar/2012:20:52:02 +0100] [Job 54] Started filter /usr/lib/cups/filter/foomatic-rip (PID 16762) I [01/Mar/2012:20:52:02 +0100] [Job 54] Started backend /usr/lib/cups/backend/hp (PID 16763) D [01/Mar/2012:20:52:02 +0100] Discarding unused job-state-changed event... D [01/Mar/2012:20:52:02 +0100] Returning IPP successful-ok for Resume-Printer (ipp://localhost/printers/HP_6315) from localhost D [01/Mar/2012:20:52:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:02 +0100] cupsdReadClient: 16 WAITING Closing on EOF D [01/Mar/2012:20:52:02 +0100] cupsdCloseClient: 16 D [01/Mar/2012:20:52:02 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:02 +0100] PID 16760 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [01/Mar/2012:20:52:02 +0100] Script header: Content-Type: text/html;charset=utf-8 D [01/Mar/2012:20:52:02 +0100] Script header: D [01/Mar/2012:20:52:02 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:02 +0100] [Job 54] Page = 595x842; 10,36 to 585,833 D [01/Mar/2012:20:52:02 +0100] [Job 54] slow_collate=0, slow_duplex=0, slow_order=0 D [01/Mar/2012:20:52:02 +0100] [Job 54] Before copy_comments - %!PS-Adobe-3.0 D [01/Mar/2012:20:52:02 +0100] [Job 54] %!PS-Adobe-3.0 D [01/Mar/2012:20:52:02 +0100] [Job 54] % Produced by poppler pdftops version: 0.18.0 (http://poppler.freedesktop.org) D [01/Mar/2012:20:52:02 +0100] [Job 54] %%Creator: Microsoft\256 Word 2010 D [01/Mar/2012:20:52:02 +0100] [Job 54] %%Title: Waldmaus_Dokumentation_Tierforscher.ch.pdf D [01/Mar/2012:20:52:02 +0100] [Job 54] %%LanguageLevel: 2 D [01/Mar/2012:20:52:02 +0100] [Job 54] %%DocumentSuppliedResources: (atend) D [01/Mar/2012:20:52:02 +0100] [Job 54] %%DocumentMedia: plain 595 842 0 () () D [01/Mar/2012:20:52:02 +0100] [Job 54] %%BoundingBox: 0 0 595 842 D [01/Mar/2012:20:52:02 +0100] [Job 54] %%Pages: 19 D [01/Mar/2012:20:52:02 +0100] [Job 54] %%EndComments D [01/Mar/2012:20:52:02 +0100] [Job 54] Before copy_prolog - %%BeginDefaults D [01/Mar/2012:20:52:02 +0100] [Job 54] Before copy_setup - %%BeginSetup D [01/Mar/2012:20:52:02 +0100] [Job 54] Getting input from file D [01/Mar/2012:20:52:02 +0100] [Job 54] foomatic-rip version 4.0.9.241 running... D [01/Mar/2012:20:52:02 +0100] [Job 54] Parsing PPD file ... D [01/Mar/2012:20:52:02 +0100] [Job 54] Added option ColorSpace D [01/Mar/2012:20:52:02 +0100] [Job 54] Added option Resolution D [01/Mar/2012:20:52:02 +0100] [Job 54] Added option PageSize D [01/Mar/2012:20:52:02 +0100] [Job 54] Added option Model D [01/Mar/2012:20:52:02 +0100] [Job 54] Added option PrintoutMode D [01/Mar/2012:20:52:02 +0100] [Job 54] Added option InputSlot D [01/Mar/2012:20:52:02 +0100] [Job 54] Added option Duplex D [01/Mar/2012:20:52:02 +0100] [Job 54] Added option DryTime D [01/Mar/2012:20:52:02 +0100] [Job 54] Added option Quality D [01/Mar/2012:20:52:02 +0100] [Job 54] Added option ImageableArea D [01/Mar/2012:20:52:02 +0100] [Job 54] Added option PaperDimension D [01/Mar/2012:20:52:02 +0100] [Job 54] Added option Font D [01/Mar/2012:20:52:02 +0100] [Job 54] D [01/Mar/2012:20:52:02 +0100] [Job 54] Parameter Summary D [01/Mar/2012:20:52:02 +0100] [Job 54] ----------------- D [01/Mar/2012:20:52:02 +0100] [Job 54] D [01/Mar/2012:20:52:02 +0100] [Job 54] Spooler: cups D [01/Mar/2012:20:52:02 +0100] [Job 54] Printer: HP_6315 D [01/Mar/2012:20:52:02 +0100] [Job 54] Shell: /bin/bash D [01/Mar/2012:20:52:02 +0100] [Job 54] PPD file: /etc/cups/ppd/HP_6315.ppd D [01/Mar/2012:20:52:02 +0100] [Job 54] ATTR file: D [01/Mar/2012:20:52:02 +0100] [Job 54] Printer model: HP Officejet 6300 Series hpijs, 3.12.2 D [01/Mar/2012:20:52:02 +0100] [Job 54] Job title: Wie sieht das Reh aus D [01/Mar/2012:20:52:02 +0100] [Job 54] File(s) to be printed: D [01/Mar/2012:20:52:02 +0100] [Job 54] D [01/Mar/2012:20:52:02 +0100] [Job 54] D [01/Mar/2012:20:52:02 +0100] [Job 54] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts D [01/Mar/2012:20:52:02 +0100] [Job 54] Printing system options: D [01/Mar/2012:20:52:02 +0100] [Job 54] Pondering option 'noCollate' D [01/Mar/2012:20:52:02 +0100] [Job 54] Unknown boolean option "noCollate". D [01/Mar/2012:20:52:02 +0100] [Job 54] Pondering option 'finishings=3' D [01/Mar/2012:20:52:02 +0100] [Job 54] Unknown option finishings=3. D [01/Mar/2012:20:52:02 +0100] [Job 54] Pondering option 'media=A4' D [01/Mar/2012:20:52:02 +0100] [Job 54] Pondering option 'number-up=1' D [01/Mar/2012:20:52:02 +0100] [Job 54] Unknown option number-up=1. D [01/Mar/2012:20:52:02 +0100] [Job 54] Pondering option 'outputorder=normal' D [01/Mar/2012:20:52:02 +0100] [Job 54] Unknown option outputorder=normal. D [01/Mar/2012:20:52:02 +0100] [Job 54] Pondering option 'portrait' D [01/Mar/2012:20:52:02 +0100] [Job 54] Unknown boolean option "portrait". D [01/Mar/2012:20:52:02 +0100] [Job 54] Pondering option 'sides=one-sided' D [01/Mar/2012:20:52:02 +0100] [Job 54] Pondering option 'job-uuid=urn:uuid:71fc993a-3863-3198-668b-783b50b79f50' D [01/Mar/2012:20:52:02 +0100] [Job 54] Unknown option job-uuid=urn:uuid:71fc993a-3863-3198-668b-783b50b79f50. D [01/Mar/2012:20:52:02 +0100] [Job 54] Pondering option 'job-originating-host-name=localhost' D [01/Mar/2012:20:52:02 +0100] [Job 54] Unknown option job-originating-host-name=localhost. D [01/Mar/2012:20:52:02 +0100] [Job 54] Pondering option 'time-at-creation=1330631255' D [01/Mar/2012:20:52:02 +0100] [Job 54] Unknown option time-at-creation=1330631255. D [01/Mar/2012:20:52:02 +0100] [Job 54] Pondering option 'time-at-processing=1330631522' D [01/Mar/2012:20:52:02 +0100] [Job 54] Unknown option time-at-processing=1330631522. D [01/Mar/2012:20:52:02 +0100] [Job 54] Pondering option 'AP_D_InputSlot=' D [01/Mar/2012:20:52:02 +0100] [Job 54] Unknown option AP_D_InputSlot=. D [01/Mar/2012:20:52:02 +0100] [Job 54] Options from the PPD file: D [01/Mar/2012:20:52:02 +0100] [Job 54] Pondering option 'Duplex=None' D [01/Mar/2012:20:52:02 +0100] [Job 54] Pondering option 'PageSize=A4' D [01/Mar/2012:20:52:02 +0100] [Job 54] D [01/Mar/2012:20:52:02 +0100] [Job 54] ================================================ D [01/Mar/2012:20:52:02 +0100] [Job 54] D [01/Mar/2012:20:52:02 +0100] [Job 54] File: D [01/Mar/2012:20:52:02 +0100] [Job 54] D [01/Mar/2012:20:52:02 +0100] [Job 54] ================================================ D [01/Mar/2012:20:52:02 +0100] [Job 54] D [01/Mar/2012:20:52:02 +0100] [Job 54] Filetype: PostScript D [01/Mar/2012:20:52:02 +0100] [Job 54] Reading PostScript input ... D [01/Mar/2012:20:52:02 +0100] [Job 54] --> This document is DSC-conforming! D [01/Mar/2012:20:52:02 +0100] [Job 54] Found %RBINumCopies: 1 D [01/Mar/2012:20:52:02 +0100] [Job 54] Job claims to be DSC-conforming, but "%%BeginProlog" was missing before first line with another"%%BeginProlog" comment (is this a TeX/LaTeX/dvips-generated PostScript file?). Assuming start of "Prolog" here. D [01/Mar/2012:20:52:02 +0100] [Job 54] D [01/Mar/2012:20:52:02 +0100] [Job 54] ----------- D [01/Mar/2012:20:52:02 +0100] [Job 54] Found: %%BeginProlog D [01/Mar/2012:20:52:02 +0100] [Job 54] Found: %%EndProlog D [01/Mar/2012:20:52:02 +0100] [Job 54] D [01/Mar/2012:20:52:02 +0100] [Job 54] ----------- D [01/Mar/2012:20:52:02 +0100] [Job 54] Found: %%BeginSetup D [01/Mar/2012:20:52:02 +0100] [Job 54] Found: %%BeginFeature: *PageSize A4 D [01/Mar/2012:20:52:02 +0100] [Job 54] Option: PageSize=A4 D [01/Mar/2012:20:52:02 +0100] [Job 54] Setting option D [01/Mar/2012:20:52:02 +0100] [Job 54] Found: %% FoomaticRIPOptionSetting: PageSize=A4 D [01/Mar/2012:20:52:02 +0100] [Job 54] Option: PageSize=A4 D [01/Mar/2012:20:52:02 +0100] [Job 54] Setting option D [01/Mar/2012:20:52:02 +0100] [Job 54] Found: %%BeginFeature: *PrintoutMode Normal D [01/Mar/2012:20:52:02 +0100] [Job 54] Option: PrintoutMode=Normal D [01/Mar/2012:20:52:02 +0100] [Job 54] Setting option D [01/Mar/2012:20:52:02 +0100] [Job 54] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal D [01/Mar/2012:20:52:02 +0100] [Job 54] Option: PrintoutMode=Normal D [01/Mar/2012:20:52:02 +0100] [Job 54] Setting option D [01/Mar/2012:20:52:02 +0100] [Job 54] Found: %%BeginFeature: *Quality FromPrintoutMode D [01/Mar/2012:20:52:02 +0100] [Job 54] Option: Quality=FromPrintoutMode D [01/Mar/2012:20:52:02 +0100] [Job 54] Setting option D [01/Mar/2012:20:52:02 +0100] [Job 54] Found: %% FoomaticRIPOptionSetting: Quality=@PrintoutMode D [01/Mar/2012:20:52:02 +0100] [Job 54] Option: Quality=FromPrintoutMode D [01/Mar/2012:20:52:02 +0100] [Job 54] Setting option D [01/Mar/2012:20:52:02 +0100] [Job 54] Found: %%BeginFeature: *Duplex None D [01/Mar/2012:20:52:02 +0100] [Job 54] Option: Duplex=None D [01/Mar/2012:20:52:02 +0100] [Job 54] Setting option D [01/Mar/2012:20:52:02 +0100] [Job 54] Found: %% FoomaticRIPOptionSetting: Duplex=None D [01/Mar/2012:20:52:02 +0100] [Job 54] Option: Duplex=None D [01/Mar/2012:20:52:02 +0100] [Job 54] Setting option D [01/Mar/2012:20:52:02 +0100] [Job 54] Found: %%BeginFeature: *DryTime Zero D [01/Mar/2012:20:52:02 +0100] [Job 54] Option: DryTime=Zero D [01/Mar/2012:20:52:02 +0100] [Job 54] Setting option D [01/Mar/2012:20:52:02 +0100] [Job 54] Found: %% FoomaticRIPOptionSetting: DryTime=Zero D [01/Mar/2012:20:52:02 +0100] [Job 54] Option: DryTime=Zero D [01/Mar/2012:20:52:02 +0100] [Job 54] Setting option D [01/Mar/2012:20:52:02 +0100] [Job 54] Before page loop - %%Page: 14 1 D [01/Mar/2012:20:52:02 +0100] [Job 54] Copying page 1... D [01/Mar/2012:20:52:02 +0100] [Job 54] pagew = 575.6, pagel = 797.0 D [01/Mar/2012:20:52:02 +0100] [Job 54] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842 D [01/Mar/2012:20:52:02 +0100] [Job 54] PageLeft = 9.7, PageRight = 585.3 D [01/Mar/2012:20:52:02 +0100] [Job 54] PageTop = 833.0, PageBottom = 36.0 D [01/Mar/2012:20:52:02 +0100] [Job 54] PageWidth = 595.0, PageLength = 842.0 D [01/Mar/2012:20:52:02 +0100] [Job 54] Found: %%EndSetup D [01/Mar/2012:20:52:02 +0100] [Job 54] Inserting PostScript code for CUPS' page accounting D [01/Mar/2012:20:52:02 +0100] [Job 54] D [01/Mar/2012:20:52:02 +0100] [Job 54] ----------- D [01/Mar/2012:20:52:02 +0100] [Job 54] New page: %%Page: 14 1 D [01/Mar/2012:20:52:02 +0100] [Job 54] D [01/Mar/2012:20:52:02 +0100] [Job 54] Found: %%BeginPageSetup D [01/Mar/2012:20:52:02 +0100] [Job 54] Inserting option code into "PageSetup" section. D [01/Mar/2012:20:52:02 +0100] [Job 54] Wrote 1 pages... D [01/Mar/2012:20:52:02 +0100] PID 16761 (/usr/lib/cups/filter/pstops) exited with no errors. D [01/Mar/2012:20:52:02 +0100] [Job 54] Flushing FIFO. D [01/Mar/2012:20:52:02 +0100] [Job 54] D [01/Mar/2012:20:52:02 +0100] [Job 54] Starting renderer with command: "gs -sstdout=%stderr -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="deskjet 5600" -dDuplex=false -r300 -sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=2,PS:MediaPosition=7 -dIjsUseOutputFD -sOutputFile=%stdout -_" D [01/Mar/2012:20:52:02 +0100] [Job 54] Starting process "kid3" (generation 1) D [01/Mar/2012:20:52:02 +0100] [Job 54] Starting process "kid4" (generation 2) D [01/Mar/2012:20:52:02 +0100] [Job 54] Starting process "renderer" (generation 2) D [01/Mar/2012:20:52:02 +0100] [Job 54] JCL: %-12345X@PJL D [01/Mar/2012:20:52:02 +0100] [Job 54] D [01/Mar/2012:20:52:02 +0100] [Job 54] D [01/Mar/2012:20:52:03 +0100] [Job 54] D [01/Mar/2012:20:52:03 +0100] [Job 54] Closing renderer D [01/Mar/2012:20:52:03 +0100] [Job 54] PAGE: 1 1 D [01/Mar/2012:20:52:03 +0100] Discarding unused job-progress event... D [01/Mar/2012:20:52:03 +0100] [Job 54] STATE: +connecting-to-device D [01/Mar/2012:20:52:03 +0100] Discarding unused printer-state-changed event... D [01/Mar/2012:20:52:03 +0100] [Job 54] STATE: -connecting-to-device D [01/Mar/2012:20:52:03 +0100] [Job 54] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other D [01/Mar/2012:20:52:03 +0100] Discarding unused printer-state-changed event... D [01/Mar/2012:20:52:07 +0100] cupsdReadClient: 14 GET /printers/HP_6315 HTTP/1.1 D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:07 +0100] cupsdAuthorize: Authorized as root using Basic D [01/Mar/2012:20:52:07 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@host" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[13] = "USER=root" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[16] = "IPP_PORT=631" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[17] = "AUTH_TYPE=Basic" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[18] = "LANG=de_DE.UTF8" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[19] = "REDIRECT_STATUS=1" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[21] = "SERVER_NAME=localhost" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[22] = "SERVER_PORT=631" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[23] = "REMOTE_ADDR=[v1.::1]" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[24] = "REMOTE_HOST=localhost" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[25] = "SCRIPT_NAME=/printers/HP_6315" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/printers/HP_6315" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[27] = "PATH_INFO=/HP_6315" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[28] = "REMOTE_USER=root" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=e1220f1882cfa844c625ae45e46fad5f" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux i686; rv:10.0.2) Gecko/20100101 Firefox/10.0.2" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[32] = "REQUEST_METHOD=GET" D [01/Mar/2012:20:52:07 +0100] [CGI] envp[33] = "QUERY_STRING=" D [01/Mar/2012:20:52:07 +0100] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 16799) I [01/Mar/2012:20:52:07 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=16799) D [01/Mar/2012:20:52:07 +0100] cupsdSendCommand: 14 file=16 D [01/Mar/2012:20:52:07 +0100] [CGI] org.cups.sid cookie is "e1220f1882cfa844c625ae45e46fad5f" D [01/Mar/2012:20:52:07 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [01/Mar/2012:20:52:07 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:07 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:07 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:52:07 +0100] CUPS-Get-Default D [01/Mar/2012:20:52:07 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:07 +0100] [CGI] show_printer(http=0xb776fa98, printer="HP_6315") D [01/Mar/2012:20:52:07 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:07 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:07 +0100] cupsdReadClient: 17 1.1 Get-Printer-Attributes 1 D [01/Mar/2012:20:52:07 +0100] Get-Printer-Attributes ipp://localhost/printers/HP_6315 D [01/Mar/2012:20:52:07 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_6315) from localhost D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:07 +0100] cupsdReadClient: 17 POST / HTTP/1.1 D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:07 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:07 +0100] Script header: Content-Type: text/html;charset=utf-8 D [01/Mar/2012:20:52:07 +0100] Script header: D [01/Mar/2012:20:52:07 +0100] [CGI] Regular expression ".*Clean.*" D [01/Mar/2012:20:52:07 +0100] [CGI] matches[0].rm_so=0 D [01/Mar/2012:20:52:07 +0100] [CGI] matches[1].rm_so=-1 D [01/Mar/2012:20:52:07 +0100] [CGI] Regular expression ".*PrintSelfTestPage.*" D [01/Mar/2012:20:52:07 +0100] [CGI] matches[0].rm_so=0 D [01/Mar/2012:20:52:07 +0100] [CGI] matches[1].rm_so=-1 D [01/Mar/2012:20:52:07 +0100] cupsdReadClient: 17 1.1 Get-Jobs 1 D [01/Mar/2012:20:52:07 +0100] Get-Jobs ipp://localhost:631/printers/HP_6315 D [01/Mar/2012:20:52:07 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_6315) from localhost D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:07 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [01/Mar/2012:20:52:07 +0100] cupsdCloseClient: 17 D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:07 +0100] PID 16799 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:07 +0100] cupsdReadClient: 14 GET /cups.css HTTP/1.1 D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:07 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:07 +0100] cupsdReadClient: 14 GET /images/left.gif HTTP/1.1 D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:07 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:07 +0100] cupsdReadClient: 14 GET /images/right.gif HTTP/1.1 D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:07 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:07 +0100] cupsdAcceptClient: 16 from localhost:631 (IPv6) D [01/Mar/2012:20:52:07 +0100] cupsdReadClient: 14 GET /images/unsel.gif HTTP/1.1 D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:07 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:07 +0100] cupsdReadClient: 16 GET /images/sel.gif HTTP/1.1 D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:07 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:07 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:17 +0100] cupsdReadClient: 14 GET /printers/HP_6315 HTTP/1.1 D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:17 +0100] cupsdAuthorize: Authorized as root using Basic D [01/Mar/2012:20:52:17 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@host" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[13] = "USER=root" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[16] = "IPP_PORT=631" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[17] = "AUTH_TYPE=Basic" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[18] = "LANG=de_DE.UTF8" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[19] = "REDIRECT_STATUS=1" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[21] = "SERVER_NAME=localhost" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[22] = "SERVER_PORT=631" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[23] = "REMOTE_ADDR=[v1.::1]" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[24] = "REMOTE_HOST=localhost" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[25] = "SCRIPT_NAME=/printers/HP_6315" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/printers/HP_6315" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[27] = "PATH_INFO=/HP_6315" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[28] = "REMOTE_USER=root" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=e1220f1882cfa844c625ae45e46fad5f" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux i686; rv:10.0.2) Gecko/20100101 Firefox/10.0.2" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[32] = "REQUEST_METHOD=GET" D [01/Mar/2012:20:52:17 +0100] [CGI] envp[33] = "QUERY_STRING=" D [01/Mar/2012:20:52:17 +0100] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 16808) I [01/Mar/2012:20:52:17 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=16808) D [01/Mar/2012:20:52:17 +0100] cupsdSendCommand: 14 file=17 D [01/Mar/2012:20:52:17 +0100] [CGI] org.cups.sid cookie is "e1220f1882cfa844c625ae45e46fad5f" D [01/Mar/2012:20:52:17 +0100] cupsdAcceptClient: 19 from localhost (Domain) D [01/Mar/2012:20:52:17 +0100] cupsdReadClient: 19 POST / HTTP/1.1 D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:17 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:17 +0100] cupsdReadClient: 19 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:52:17 +0100] CUPS-Get-Default D [01/Mar/2012:20:52:17 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:17 +0100] [CGI] show_printer(http=0xb780ea98, printer="HP_6315") D [01/Mar/2012:20:52:17 +0100] cupsdReadClient: 19 POST / HTTP/1.1 D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:17 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:17 +0100] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1 D [01/Mar/2012:20:52:17 +0100] Get-Printer-Attributes ipp://localhost/printers/HP_6315 D [01/Mar/2012:20:52:17 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_6315) from localhost D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:17 +0100] cupsdReadClient: 19 POST / HTTP/1.1 D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:17 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:17 +0100] Script header: Content-Type: text/html;charset=utf-8 D [01/Mar/2012:20:52:17 +0100] Script header: D [01/Mar/2012:20:52:17 +0100] [CGI] Regular expression ".*Clean.*" D [01/Mar/2012:20:52:17 +0100] [CGI] matches[0].rm_so=0 D [01/Mar/2012:20:52:17 +0100] [CGI] matches[1].rm_so=-1 D [01/Mar/2012:20:52:17 +0100] [CGI] Regular expression ".*PrintSelfTestPage.*" D [01/Mar/2012:20:52:17 +0100] [CGI] matches[0].rm_so=0 D [01/Mar/2012:20:52:17 +0100] [CGI] matches[1].rm_so=-1 D [01/Mar/2012:20:52:17 +0100] cupsdReadClient: 19 1.1 Get-Jobs 1 D [01/Mar/2012:20:52:17 +0100] Get-Jobs ipp://localhost:631/printers/HP_6315 D [01/Mar/2012:20:52:17 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_6315) from localhost D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:17 +0100] cupsdReadClient: 19 WAITING Closing on EOF D [01/Mar/2012:20:52:17 +0100] cupsdCloseClient: 19 D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:17 +0100] PID 16808 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [01/Mar/2012:20:52:17 +0100] cupsdReadClient: 14 GET /cups.css HTTP/1.1 D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:17 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:17 +0100] cupsdReadClient: 14 GET /images/left.gif HTTP/1.1 D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:17 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:17 +0100] cupsdReadClient: 16 GET /images/right.gif HTTP/1.1 D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:17 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:17 +0100] cupsdReadClient: 14 GET /images/unsel.gif HTTP/1.1 D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:17 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:17 +0100] cupsdReadClient: 16 GET /images/sel.gif HTTP/1.1 D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:17 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:17 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:19 +0100] [Job 54] renderer exited with status 0 D [01/Mar/2012:20:52:24 +0100] [Job 54] kid4 exited with status 0 D [01/Mar/2012:20:52:25 +0100] [Job 54] kid3 finished D [01/Mar/2012:20:52:25 +0100] [Job 54] kid3 exited with status 0 D [01/Mar/2012:20:52:25 +0100] [Job 54] D [01/Mar/2012:20:52:25 +0100] [Job 54] Closing foomatic-rip. D [01/Mar/2012:20:52:25 +0100] PID 16762 (/usr/lib/cups/filter/foomatic-rip) exited with no errors. D [01/Mar/2012:20:52:27 +0100] cupsdReadClient: 14 GET /printers/HP_6315 HTTP/1.1 D [01/Mar/2012:20:52:27 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:27 +0100] cupsdAuthorize: Authorized as root using Basic D [01/Mar/2012:20:52:27 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@host" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[13] = "USER=root" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[16] = "IPP_PORT=631" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[17] = "AUTH_TYPE=Basic" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[18] = "LANG=de_DE.UTF8" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[19] = "REDIRECT_STATUS=1" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[21] = "SERVER_NAME=localhost" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[22] = "SERVER_PORT=631" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[23] = "REMOTE_ADDR=[v1.::1]" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[24] = "REMOTE_HOST=localhost" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[25] = "SCRIPT_NAME=/printers/HP_6315" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/printers/HP_6315" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[27] = "PATH_INFO=/HP_6315" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[28] = "REMOTE_USER=root" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=e1220f1882cfa844c625ae45e46fad5f" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux i686; rv:10.0.2) Gecko/20100101 Firefox/10.0.2" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[32] = "REQUEST_METHOD=GET" D [01/Mar/2012:20:52:27 +0100] [CGI] envp[33] = "QUERY_STRING=" D [01/Mar/2012:20:52:27 +0100] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 16844) I [01/Mar/2012:20:52:27 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=16844) D [01/Mar/2012:20:52:27 +0100] cupsdSendCommand: 14 file=17 D [01/Mar/2012:20:52:27 +0100] [CGI] org.cups.sid cookie is "e1220f1882cfa844c625ae45e46fad5f" D [01/Mar/2012:20:52:27 +0100] cupsdAcceptClient: 19 from localhost (Domain) D [01/Mar/2012:20:52:27 +0100] cupsdReadClient: 19 POST / HTTP/1.1 D [01/Mar/2012:20:52:27 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:27 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:27 +0100] cupsdReadClient: 19 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:52:27 +0100] CUPS-Get-Default D [01/Mar/2012:20:52:27 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:52:27 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:27 +0100] [CGI] show_printer(http=0xb7773a98, printer="HP_6315") D [01/Mar/2012:20:52:27 +0100] cupsdReadClient: 19 POST / HTTP/1.1 D [01/Mar/2012:20:52:27 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:27 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:27 +0100] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1 D [01/Mar/2012:20:52:27 +0100] Get-Printer-Attributes ipp://localhost/printers/HP_6315 D [01/Mar/2012:20:52:27 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_6315) from localhost D [01/Mar/2012:20:52:27 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:27 +0100] cupsdReadClient: 19 POST / HTTP/1.1 D [01/Mar/2012:20:52:27 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:27 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:27 +0100] Script header: Content-Type: text/html;charset=utf-8 D [01/Mar/2012:20:52:27 +0100] Script header: D [01/Mar/2012:20:52:27 +0100] [CGI] Regular expression ".*Clean.*" D [01/Mar/2012:20:52:27 +0100] [CGI] matches[0].rm_so=0 D [01/Mar/2012:20:52:27 +0100] [CGI] matches[1].rm_so=-1 D [01/Mar/2012:20:52:27 +0100] [CGI] Regular expression ".*PrintSelfTestPage.*" D [01/Mar/2012:20:52:27 +0100] [CGI] matches[0].rm_so=0 D [01/Mar/2012:20:52:27 +0100] [CGI] matches[1].rm_so=-1 D [01/Mar/2012:20:52:27 +0100] cupsdReadClient: 19 1.1 Get-Jobs 1 D [01/Mar/2012:20:52:27 +0100] Get-Jobs ipp://localhost:631/printers/HP_6315 D [01/Mar/2012:20:52:27 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_6315) from localhost D [01/Mar/2012:20:52:27 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:27 +0100] cupsdReadClient: 19 WAITING Closing on EOF D [01/Mar/2012:20:52:27 +0100] cupsdCloseClient: 19 D [01/Mar/2012:20:52:27 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:27 +0100] PID 16844 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [01/Mar/2012:20:52:27 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:27 +0100] cupsdReadClient: 14 GET /cups.css HTTP/1.1 D [01/Mar/2012:20:52:27 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:27 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:27 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:27 +0100] cupsdReadClient: 14 GET /images/left.gif HTTP/1.1 D [01/Mar/2012:20:52:27 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:27 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:27 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:27 +0100] cupsdReadClient: 16 GET /images/right.gif HTTP/1.1 D [01/Mar/2012:20:52:27 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:27 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:27 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:28 +0100] cupsdReadClient: 14 GET /images/unsel.gif HTTP/1.1 D [01/Mar/2012:20:52:28 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:28 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:28 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:28 +0100] cupsdReadClient: 16 GET /images/sel.gif HTTP/1.1 D [01/Mar/2012:20:52:28 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:28 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:28 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" I [01/Mar/2012:20:52:30 +0100] [Job 54] ready to print D [01/Mar/2012:20:52:30 +0100] [Job 54] Set job-printer-state-message to "ready to print", current level=INFO D [01/Mar/2012:20:52:30 +0100] Discarding unused job-progress event... D [01/Mar/2012:20:52:30 +0100] Discarding unused printer-state-changed event... D [01/Mar/2012:20:52:30 +0100] PID 16763 (/usr/lib/cups/backend/hp) exited with no errors. D [01/Mar/2012:20:52:30 +0100] Discarding unused job-completed event... I [01/Mar/2012:20:52:30 +0100] [Job 54] Job completed. D [01/Mar/2012:20:52:30 +0100] cupsdMarkDirty(----J-) D [01/Mar/2012:20:52:30 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:30 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:30 +0100] Discarding unused printer-state-changed event... D [01/Mar/2012:20:52:30 +0100] cupsdMarkDirty(----J-) D [01/Mar/2012:20:52:30 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:30 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [01/Mar/2012:20:52:30 +0100] Discarding unused printer-state-changed event... D [01/Mar/2012:20:52:30 +0100] [Job 55] job-sheets=none,none D [01/Mar/2012:20:52:30 +0100] [Job 55] argv[0]="HP_6315" D [01/Mar/2012:20:52:30 +0100] [Job 55] argv[1]="55" D [01/Mar/2012:20:52:30 +0100] [Job 55] argv[2]="internet" D [01/Mar/2012:20:52:30 +0100] [Job 55] argv[3]="Wie sieht das Reh aus" D [01/Mar/2012:20:52:30 +0100] [Job 55] argv[4]="1" D [01/Mar/2012:20:52:30 +0100] [Job 55] argv[5]="noCollate finishings=3 media=A4 number-up=1 outputorder=normal portrait sides=one-sided job-uuid=urn:uuid:c0965150-dda5-3fd7-78fc-45bce6776210 job-originating-host-name=localhost time-at-creation=1330631312 time-at-processing=1330631550 AP_D_InputSlot= Duplex=None PageSize=A4" D [01/Mar/2012:20:52:30 +0100] [Job 55] argv[6]="/var/spool/cups/d00055-001" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[1]="CUPS_DATADIR=/usr/share/cups" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[2]="CUPS_DOCROOT=/usr/share/cups/webcontent" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[6]="CUPS_SERVERROOT=/etc/cups" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[7]="CUPS_STATEDIR=/var/run/cups" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[8]="HOME=/var/spool/cups/tmp" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[10]="SERVER_ADMIN=root@host" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[11]="SOFTWARE=CUPS/1.5.0" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[12]="TMPDIR=/var/spool/cups/tmp" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[13]="USER=root" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[15]="CUPS_ENCRYPTION=IfRequested" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[16]="IPP_PORT=631" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[17]="CHARSET=utf-8" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[18]="LANG=de_DE.UTF-8" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[19]="PPD=/etc/cups/ppd/HP_6315.ppd" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[20]="RIP_MAX_CACHE=128m" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[21]="CONTENT_TYPE=application/postscript" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[22]="DEVICE_URI=hp:/usb/Officejet_6300_series?serial=CN689CF0Q204M4" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[23]="PRINTER_INFO=All_in_One" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[24]="PRINTER_LOCATION=im_Büro" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[25]="PRINTER=HP_6315" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[26]="PRINTER_STATE_REASONS=marker-supply-low-warning" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[27]="CUPS_FILETYPE=document" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[28]="FINAL_CONTENT_TYPE=printer/HP_6315" D [01/Mar/2012:20:52:30 +0100] [Job 55] envp[29]="AUTH_I****" I [01/Mar/2012:20:52:30 +0100] [Job 55] Started filter /usr/lib/cups/filter/pstops (PID 16845) I [01/Mar/2012:20:52:30 +0100] [Job 55] Started filter /usr/lib/cups/filter/foomatic-rip (PID 16846) I [01/Mar/2012:20:52:30 +0100] [Job 55] Started backend /usr/lib/cups/backend/hp (PID 16847) D [01/Mar/2012:20:52:30 +0100] Discarding unused job-state-changed event... D [01/Mar/2012:20:52:30 +0100] [Job 55] Getting input from file D [01/Mar/2012:20:52:30 +0100] [Job 55] foomatic-rip version 4.0.9.241 running... D [01/Mar/2012:20:52:30 +0100] [Job 55] Parsing PPD file ... D [01/Mar/2012:20:52:30 +0100] [Job 55] Added option ColorSpace D [01/Mar/2012:20:52:30 +0100] [Job 55] Added option Resolution D [01/Mar/2012:20:52:30 +0100] [Job 55] Added option PageSize D [01/Mar/2012:20:52:30 +0100] [Job 55] Added option Model D [01/Mar/2012:20:52:30 +0100] [Job 55] Added option PrintoutMode D [01/Mar/2012:20:52:30 +0100] [Job 55] Added option InputSlot D [01/Mar/2012:20:52:30 +0100] [Job 55] Page = 595x842; 10,36 to 585,833 D [01/Mar/2012:20:52:30 +0100] [Job 55] slow_collate=0, slow_duplex=0, slow_order=0 D [01/Mar/2012:20:52:30 +0100] [Job 55] Before copy_comments - %!PS-Adobe-3.0 D [01/Mar/2012:20:52:30 +0100] [Job 55] %!PS-Adobe-3.0 D [01/Mar/2012:20:52:30 +0100] [Job 55] % Produced by poppler pdftops version: 0.18.0 (http://poppler.freedesktop.org) D [01/Mar/2012:20:52:30 +0100] [Job 55] %%Creator: Microsoft\256 Word 2010 D [01/Mar/2012:20:52:30 +0100] [Job 55] %%Title: Waldmaus_Dokumentation_Tierforscher.ch.pdf D [01/Mar/2012:20:52:30 +0100] [Job 55] %%LanguageLevel: 2 D [01/Mar/2012:20:52:30 +0100] [Job 55] %%DocumentSuppliedResources: (atend) D [01/Mar/2012:20:52:30 +0100] [Job 55] %%DocumentMedia: plain 595 842 0 () () D [01/Mar/2012:20:52:30 +0100] [Job 55] %%BoundingBox: 0 0 595 842 D [01/Mar/2012:20:52:30 +0100] [Job 55] %%Pages: 19 D [01/Mar/2012:20:52:30 +0100] [Job 55] %%EndComments D [01/Mar/2012:20:52:30 +0100] [Job 55] Before copy_prolog - %%BeginDefaults D [01/Mar/2012:20:52:30 +0100] [Job 55] Before copy_setup - %%BeginSetup D [01/Mar/2012:20:52:30 +0100] [Job 55] Added option Duplex D [01/Mar/2012:20:52:30 +0100] [Job 55] Added option DryTime D [01/Mar/2012:20:52:30 +0100] [Job 55] Added option Quality D [01/Mar/2012:20:52:30 +0100] [Job 55] Added option ImageableArea D [01/Mar/2012:20:52:30 +0100] [Job 55] Added option PaperDimension D [01/Mar/2012:20:52:30 +0100] [Job 55] Added option Font D [01/Mar/2012:20:52:30 +0100] [Job 55] D [01/Mar/2012:20:52:30 +0100] [Job 55] Parameter Summary D [01/Mar/2012:20:52:30 +0100] [Job 55] ----------------- D [01/Mar/2012:20:52:30 +0100] [Job 55] D [01/Mar/2012:20:52:30 +0100] [Job 55] Spooler: cups D [01/Mar/2012:20:52:30 +0100] [Job 55] Printer: HP_6315 D [01/Mar/2012:20:52:30 +0100] [Job 55] Shell: /bin/bash D [01/Mar/2012:20:52:30 +0100] [Job 55] PPD file: /etc/cups/ppd/HP_6315.ppd D [01/Mar/2012:20:52:30 +0100] [Job 55] ATTR file: D [01/Mar/2012:20:52:30 +0100] [Job 55] Printer model: HP Officejet 6300 Series hpijs, 3.12.2 D [01/Mar/2012:20:52:30 +0100] [Job 55] Job title: Wie sieht das Reh aus D [01/Mar/2012:20:52:30 +0100] [Job 55] File(s) to be printed: D [01/Mar/2012:20:52:30 +0100] [Job 55] D [01/Mar/2012:20:52:30 +0100] [Job 55] D [01/Mar/2012:20:52:30 +0100] [Job 55] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts D [01/Mar/2012:20:52:30 +0100] [Job 55] Printing system options: D [01/Mar/2012:20:52:30 +0100] [Job 55] Pondering option 'noCollate' D [01/Mar/2012:20:52:30 +0100] [Job 55] Unknown boolean option "noCollate". D [01/Mar/2012:20:52:30 +0100] [Job 55] Pondering option 'finishings=3' D [01/Mar/2012:20:52:30 +0100] [Job 55] Unknown option finishings=3. D [01/Mar/2012:20:52:30 +0100] [Job 55] Pondering option 'media=A4' D [01/Mar/2012:20:52:30 +0100] [Job 55] Pondering option 'number-up=1' D [01/Mar/2012:20:52:30 +0100] [Job 55] Unknown option number-up=1. D [01/Mar/2012:20:52:30 +0100] [Job 55] Pondering option 'outputorder=normal' D [01/Mar/2012:20:52:30 +0100] [Job 55] Unknown option outputorder=normal. D [01/Mar/2012:20:52:30 +0100] [Job 55] Pondering option 'portrait' D [01/Mar/2012:20:52:30 +0100] [Job 55] Unknown boolean option "portrait". D [01/Mar/2012:20:52:30 +0100] [Job 55] Pondering option 'sides=one-sided' D [01/Mar/2012:20:52:30 +0100] [Job 55] Pondering option 'job-uuid=urn:uuid:c0965150-dda5-3fd7-78fc-45bce6776210' D [01/Mar/2012:20:52:30 +0100] [Job 55] Unknown option job-uuid=urn:uuid:c0965150-dda5-3fd7-78fc-45bce6776210. D [01/Mar/2012:20:52:30 +0100] [Job 55] Pondering option 'job-originating-host-name=localhost' D [01/Mar/2012:20:52:30 +0100] [Job 55] Unknown option job-originating-host-name=localhost. D [01/Mar/2012:20:52:30 +0100] [Job 55] Pondering option 'time-at-creation=1330631312' D [01/Mar/2012:20:52:30 +0100] [Job 55] Unknown option time-at-creation=1330631312. D [01/Mar/2012:20:52:30 +0100] [Job 55] Pondering option 'time-at-processing=1330631550' D [01/Mar/2012:20:52:30 +0100] [Job 55] Unknown option time-at-processing=1330631550. D [01/Mar/2012:20:52:30 +0100] [Job 55] Pondering option 'AP_D_InputSlot=' D [01/Mar/2012:20:52:30 +0100] [Job 55] Unknown option AP_D_InputSlot=. D [01/Mar/2012:20:52:30 +0100] [Job 55] Options from the PPD file: D [01/Mar/2012:20:52:30 +0100] [Job 55] Pondering option 'Duplex=None' D [01/Mar/2012:20:52:30 +0100] [Job 55] Pondering option 'PageSize=A4' D [01/Mar/2012:20:52:30 +0100] [Job 55] D [01/Mar/2012:20:52:30 +0100] [Job 55] ================================================ D [01/Mar/2012:20:52:30 +0100] [Job 55] D [01/Mar/2012:20:52:30 +0100] [Job 55] File: D [01/Mar/2012:20:52:30 +0100] [Job 55] D [01/Mar/2012:20:52:30 +0100] [Job 55] ================================================ D [01/Mar/2012:20:52:30 +0100] [Job 55] D [01/Mar/2012:20:52:30 +0100] [Job 55] Filetype: PostScript D [01/Mar/2012:20:52:30 +0100] [Job 55] Reading PostScript input ... D [01/Mar/2012:20:52:30 +0100] [Job 55] --> This document is DSC-conforming! D [01/Mar/2012:20:52:30 +0100] [Job 55] Found %RBINumCopies: 1 D [01/Mar/2012:20:52:30 +0100] [Job 55] Job claims to be DSC-conforming, but "%%BeginProlog" was missing before first line with another"%%BeginProlog" comment (is this a TeX/LaTeX/dvips-generated PostScript file?). Assuming start of "Prolog" here. D [01/Mar/2012:20:52:30 +0100] [Job 55] D [01/Mar/2012:20:52:30 +0100] [Job 55] ----------- D [01/Mar/2012:20:52:30 +0100] [Job 55] Found: %%BeginProlog D [01/Mar/2012:20:52:30 +0100] [Job 55] Found: %%EndProlog D [01/Mar/2012:20:52:30 +0100] [Job 55] D [01/Mar/2012:20:52:30 +0100] [Job 55] ----------- D [01/Mar/2012:20:52:30 +0100] [Job 55] Found: %%BeginSetup D [01/Mar/2012:20:52:30 +0100] [Job 55] Found: %%BeginFeature: *PageSize A4 D [01/Mar/2012:20:52:30 +0100] [Job 55] Option: PageSize=A4 D [01/Mar/2012:20:52:30 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:30 +0100] [Job 55] Found: %% FoomaticRIPOptionSetting: PageSize=A4 D [01/Mar/2012:20:52:30 +0100] [Job 55] Option: PageSize=A4 D [01/Mar/2012:20:52:30 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:30 +0100] [Job 55] Found: %%BeginFeature: *PrintoutMode Normal D [01/Mar/2012:20:52:30 +0100] [Job 55] Option: PrintoutMode=Normal D [01/Mar/2012:20:52:30 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:30 +0100] [Job 55] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal D [01/Mar/2012:20:52:30 +0100] [Job 55] Option: PrintoutMode=Normal D [01/Mar/2012:20:52:30 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:30 +0100] [Job 55] Found: %%BeginFeature: *Quality FromPrintoutMode D [01/Mar/2012:20:52:30 +0100] [Job 55] Option: Quality=FromPrintoutMode D [01/Mar/2012:20:52:30 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:30 +0100] [Job 55] Found: %% FoomaticRIPOptionSetting: Quality=@PrintoutMode D [01/Mar/2012:20:52:30 +0100] [Job 55] Option: Quality=FromPrintoutMode D [01/Mar/2012:20:52:30 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:30 +0100] [Job 55] Found: %%BeginFeature: *Duplex None D [01/Mar/2012:20:52:30 +0100] [Job 55] Option: Duplex=None D [01/Mar/2012:20:52:30 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:30 +0100] [Job 55] Found: %% FoomaticRIPOptionSetting: Duplex=None D [01/Mar/2012:20:52:30 +0100] [Job 55] Option: Duplex=None D [01/Mar/2012:20:52:30 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:30 +0100] [Job 55] Found: %%BeginFeature: *DryTime Zero D [01/Mar/2012:20:52:30 +0100] [Job 55] Option: DryTime=Zero D [01/Mar/2012:20:52:30 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:30 +0100] [Job 55] Found: %% FoomaticRIPOptionSetting: DryTime=Zero D [01/Mar/2012:20:52:30 +0100] [Job 55] Option: DryTime=Zero D [01/Mar/2012:20:52:30 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:30 +0100] [Job 55] Before page loop - %%Page: 16 1 D [01/Mar/2012:20:52:30 +0100] [Job 55] Copying page 1... D [01/Mar/2012:20:52:30 +0100] [Job 55] pagew = 575.6, pagel = 797.0 D [01/Mar/2012:20:52:30 +0100] [Job 55] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842 D [01/Mar/2012:20:52:30 +0100] [Job 55] PageLeft = 9.7, PageRight = 585.3 D [01/Mar/2012:20:52:30 +0100] [Job 55] PageTop = 833.0, PageBottom = 36.0 D [01/Mar/2012:20:52:30 +0100] [Job 55] PageWidth = 595.0, PageLength = 842.0 D [01/Mar/2012:20:52:30 +0100] [Job 55] Found: %%EndSetup D [01/Mar/2012:20:52:30 +0100] [Job 55] Inserting PostScript code for CUPS' page accounting D [01/Mar/2012:20:52:30 +0100] [Job 55] D [01/Mar/2012:20:52:30 +0100] [Job 55] ----------- D [01/Mar/2012:20:52:30 +0100] [Job 55] New page: %%Page: 16 1 D [01/Mar/2012:20:52:30 +0100] [Job 55] D [01/Mar/2012:20:52:30 +0100] [Job 55] Found: %%BeginPageSetup D [01/Mar/2012:20:52:30 +0100] [Job 55] Inserting option code into "PageSetup" section. D [01/Mar/2012:20:52:30 +0100] [Job 55] Wrote 1 pages... D [01/Mar/2012:20:52:30 +0100] PID 16845 (/usr/lib/cups/filter/pstops) exited with no errors. D [01/Mar/2012:20:52:30 +0100] [Job 55] Flushing FIFO. D [01/Mar/2012:20:52:30 +0100] [Job 55] D [01/Mar/2012:20:52:30 +0100] [Job 55] Starting renderer with command: "gs -sstdout=%stderr -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="deskjet 5600" -dDuplex=false -r300 -sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=2,PS:MediaPosition=7 -dIjsUseOutputFD -sOutputFile=%stdout -_" D [01/Mar/2012:20:52:30 +0100] [Job 55] Starting process "kid3" (generation 1) D [01/Mar/2012:20:52:30 +0100] [Job 55] Starting process "kid4" (generation 2) D [01/Mar/2012:20:52:30 +0100] [Job 55] Starting process "renderer" (generation 2) D [01/Mar/2012:20:52:30 +0100] [Job 55] JCL: %-12345X@PJL D [01/Mar/2012:20:52:30 +0100] [Job 55] D [01/Mar/2012:20:52:30 +0100] [Job 55] D [01/Mar/2012:20:52:31 +0100] [Job 55] D [01/Mar/2012:20:52:31 +0100] [Job 55] Closing renderer D [01/Mar/2012:20:52:31 +0100] [Job 54] Unloading... D [01/Mar/2012:20:52:31 +0100] [Job 55] PAGE: 1 1 D [01/Mar/2012:20:52:31 +0100] Discarding unused job-progress event... D [01/Mar/2012:20:52:31 +0100] [Job 55] STATE: +connecting-to-device D [01/Mar/2012:20:52:31 +0100] Discarding unused printer-state-changed event... D [01/Mar/2012:20:52:31 +0100] [Job 55] STATE: -connecting-to-device D [01/Mar/2012:20:52:31 +0100] [Job 55] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other D [01/Mar/2012:20:52:31 +0100] Discarding unused printer-state-changed event... I [01/Mar/2012:20:52:32 +0100] Saving printers.conf... I [01/Mar/2012:20:52:32 +0100] Saving job.cache... D [01/Mar/2012:20:52:32 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Dirty files" D [01/Mar/2012:20:52:34 +0100] [Job 55] renderer exited with status 0 D [01/Mar/2012:20:52:34 +0100] [Job 55] prnt/backend/hp.c 625: ERROR: 5021 device communication error! D [01/Mar/2012:20:52:34 +0100] [Job 55] kid4 received signal 13 D [01/Mar/2012:20:52:34 +0100] [Job 55] kid3 finished D [01/Mar/2012:20:52:34 +0100] [Job 55] kid3 exited with status 0 D [01/Mar/2012:20:52:34 +0100] [Job 55] D [01/Mar/2012:20:52:34 +0100] [Job 55] Closing foomatic-rip. D [01/Mar/2012:20:52:34 +0100] PID 16847 (/usr/lib/cups/backend/hp) stopped with status 4. D [01/Mar/2012:20:52:34 +0100] PID 16846 (/usr/lib/cups/filter/foomatic-rip) exited with no errors. I [01/Mar/2012:20:52:34 +0100] [Job 55] Backend returned status 4 (stop printer) D [01/Mar/2012:20:52:34 +0100] Discarding unused job-state-changed event... I [01/Mar/2012:20:52:34 +0100] [Job 55] Printer stopped due to backend errors; please consult the error_log file for details. D [01/Mar/2012:20:52:34 +0100] cupsdMarkDirty(----J-) D [01/Mar/2012:20:52:34 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs" D [01/Mar/2012:20:52:34 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:34 +0100] Discarding unused printer-stopped event... D [01/Mar/2012:20:52:34 +0100] cupsdMarkDirty(P-----) D [01/Mar/2012:20:52:34 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:34 +0100] cupsdRegisterPrinter(p=0xb7816520(HP_6315)) D [01/Mar/2012:20:52:34 +0100] cupsdMarkDirty(P-----) D [01/Mar/2012:20:52:34 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:38 +0100] cupsdReadClient: 14 GET /printers/HP_6315 HTTP/1.1 D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:38 +0100] cupsdAuthorize: Authorized as root using Basic D [01/Mar/2012:20:52:38 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@host" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[13] = "USER=root" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[16] = "IPP_PORT=631" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[17] = "AUTH_TYPE=Basic" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[18] = "LANG=de_DE.UTF8" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[19] = "REDIRECT_STATUS=1" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[21] = "SERVER_NAME=localhost" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[22] = "SERVER_PORT=631" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[23] = "REMOTE_ADDR=[v1.::1]" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[24] = "REMOTE_HOST=localhost" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[25] = "SCRIPT_NAME=/printers/HP_6315" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/printers/HP_6315" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[27] = "PATH_INFO=/HP_6315" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[28] = "REMOTE_USER=root" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=e1220f1882cfa844c625ae45e46fad5f" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux i686; rv:10.0.2) Gecko/20100101 Firefox/10.0.2" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[32] = "REQUEST_METHOD=GET" D [01/Mar/2012:20:52:38 +0100] [CGI] envp[33] = "QUERY_STRING=" D [01/Mar/2012:20:52:38 +0100] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 16880) I [01/Mar/2012:20:52:38 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=16880) D [01/Mar/2012:20:52:38 +0100] cupsdSendCommand: 14 file=17 D [01/Mar/2012:20:52:38 +0100] [CGI] org.cups.sid cookie is "e1220f1882cfa844c625ae45e46fad5f" D [01/Mar/2012:20:52:38 +0100] cupsdAcceptClient: 18 from localhost (Domain) D [01/Mar/2012:20:52:38 +0100] cupsdReadClient: 18 POST / HTTP/1.1 D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:38 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:38 +0100] cupsdReadClient: 18 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:52:38 +0100] CUPS-Get-Default D [01/Mar/2012:20:52:38 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:38 +0100] [CGI] show_printer(http=0xb7866a98, printer="HP_6315") D [01/Mar/2012:20:52:38 +0100] cupsdReadClient: 18 POST / HTTP/1.1 D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:38 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:38 +0100] cupsdReadClient: 18 1.1 Get-Printer-Attributes 1 D [01/Mar/2012:20:52:38 +0100] Get-Printer-Attributes ipp://localhost/printers/HP_6315 D [01/Mar/2012:20:52:38 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_6315) from localhost D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:38 +0100] Script header: Content-Type: text/html;charset=utf-8 D [01/Mar/2012:20:52:38 +0100] Script header: D [01/Mar/2012:20:52:38 +0100] [CGI] Regular expression ".*Clean.*" D [01/Mar/2012:20:52:38 +0100] [CGI] matches[0].rm_so=0 D [01/Mar/2012:20:52:38 +0100] [CGI] matches[1].rm_so=-1 D [01/Mar/2012:20:52:38 +0100] [CGI] Regular expression ".*PrintSelfTestPage.*" D [01/Mar/2012:20:52:38 +0100] [CGI] matches[0].rm_so=0 D [01/Mar/2012:20:52:38 +0100] [CGI] matches[1].rm_so=-1 D [01/Mar/2012:20:52:38 +0100] cupsdReadClient: 18 POST / HTTP/1.1 D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:38 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:38 +0100] cupsdReadClient: 18 1.1 Get-Jobs 1 D [01/Mar/2012:20:52:38 +0100] Get-Jobs ipp://localhost:631/printers/HP_6315 D [01/Mar/2012:20:52:38 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_6315) from localhost D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:38 +0100] cupsdReadClient: 18 WAITING Closing on EOF D [01/Mar/2012:20:52:38 +0100] cupsdCloseClient: 18 D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:38 +0100] PID 16880 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:38 +0100] cupsdReadClient: 14 GET /cups.css HTTP/1.1 D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [01/Mar/2012:20:52:38 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:38 +0100] cupsdReadClient: 14 GET /images/left.gif HTTP/1.1 D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [01/Mar/2012:20:52:38 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:38 +0100] cupsdReadClient: 16 GET /images/right.gif HTTP/1.1 D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [01/Mar/2012:20:52:38 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:38 +0100] cupsdReadClient: 14 GET /images/unsel.gif HTTP/1.1 D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [01/Mar/2012:20:52:38 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:38 +0100] cupsdReadClient: 16 GET /images/sel.gif HTTP/1.1 D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [01/Mar/2012:20:52:38 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:38 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:39 +0100] Report: clients=3 D [01/Mar/2012:20:52:39 +0100] Report: jobs=14 D [01/Mar/2012:20:52:39 +0100] Report: jobs-active=1 D [01/Mar/2012:20:52:39 +0100] Report: printers=1 D [01/Mar/2012:20:52:39 +0100] Report: printers-implicit=0 D [01/Mar/2012:20:52:39 +0100] Report: stringpool-string-count=2930 D [01/Mar/2012:20:52:39 +0100] Report: stringpool-alloc-bytes=10272 D [01/Mar/2012:20:52:39 +0100] Report: stringpool-total-bytes=55712 D [01/Mar/2012:20:52:48 +0100] cupsdReadClient: 14 POST /printers/HP_6315 HTTP/1.1 D [01/Mar/2012:20:52:48 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [01/Mar/2012:20:52:48 +0100] cupsdAuthorize: Authorized as root using Basic D [01/Mar/2012:20:52:48 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@host" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[13] = "USER=root" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[16] = "IPP_PORT=631" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[17] = "AUTH_TYPE=Basic" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[18] = "LANG=de_DE.UTF8" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[19] = "REDIRECT_STATUS=1" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[21] = "SERVER_NAME=localhost" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[22] = "SERVER_PORT=631" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[23] = "REMOTE_ADDR=[v1.::1]" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[24] = "REMOTE_HOST=localhost" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[25] = "SCRIPT_NAME=/printers/HP_6315" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/printers/HP_6315" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[27] = "PATH_INFO=/HP_6315" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[28] = "REMOTE_USER=root" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=e1220f1882cfa844c625ae45e46fad5f" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux i686; rv:10.0.2) Gecko/20100101 Firefox/10.0.2" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[32] = "HTTP_REFERER=http://localhost:631/printers/HP_6315" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[33] = "REQUEST_METHOD=POST" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[34] = "CONTENT_LENGTH=62" D [01/Mar/2012:20:52:48 +0100] [CGI] envp[35] = "CONTENT_TYPE=application/x-www-form-urlencoded" D [01/Mar/2012:20:52:48 +0100] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 16883) I [01/Mar/2012:20:52:48 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=16883) D [01/Mar/2012:20:52:48 +0100] cupsdSendCommand: 14 file=18 D [01/Mar/2012:20:52:48 +0100] [CGI] org.cups.sid cookie is "e1220f1882cfa844c625ae45e46fad5f" D [01/Mar/2012:20:52:48 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [01/Mar/2012:20:52:48 +0100] cupsdReadClient: 17 POST /printers/HP_6315 HTTP/1.1 D [01/Mar/2012:20:52:48 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:48 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:48 +0100] cupsdReadClient: 17 1.1 Resume-Printer 1 D [01/Mar/2012:20:52:48 +0100] Resume-Printer ipp://localhost/printers/HP_6315 D [01/Mar/2012:20:52:48 +0100] cupsdIsAuthorized: username="" D [01/Mar/2012:20:52:48 +0100] Returning HTTP Unauthorized for Resume-Printer (ipp://localhost/printers/HP_6315) from localhost D [01/Mar/2012:20:52:48 +0100] cupsdSendHeader: 17 WWW-Authenticate: Basic realm="CUPS", trc="y" D [01/Mar/2012:20:52:48 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [01/Mar/2012:20:52:48 +0100] cupsdCloseClient: 17 D [01/Mar/2012:20:52:48 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:48 +0100] cupsdAcceptClient: 17 from localhost (Domain) D [01/Mar/2012:20:52:48 +0100] cupsdAcceptClient: 19 from localhost (Domain) D [01/Mar/2012:20:52:48 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [01/Mar/2012:20:52:48 +0100] cupsdCloseClient: 17 D [01/Mar/2012:20:52:48 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:48 +0100] cupsdReadClient: 19 POST /printers/HP_6315 HTTP/1.1 D [01/Mar/2012:20:52:48 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:48 +0100] cupsdAuthorize: Authorized as root using Local D [01/Mar/2012:20:52:48 +0100] cupsdReadClient: 19 1.1 Resume-Printer 1 D [01/Mar/2012:20:52:48 +0100] Resume-Printer ipp://localhost/printers/HP_6315 D [01/Mar/2012:20:52:48 +0100] cupsdIsAuthorized: username="root" D [01/Mar/2012:20:52:48 +0100] Discarding unused printer-state-changed event... D [01/Mar/2012:20:52:48 +0100] cupsdMarkDirty(P-----) D [01/Mar/2012:20:52:48 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:48 +0100] cupsdRegisterPrinter(p=0xb7816520(HP_6315)) D [01/Mar/2012:20:52:48 +0100] cupsdMarkDirty(P-----) D [01/Mar/2012:20:52:48 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [01/Mar/2012:20:52:48 +0100] Printer "HP_6315" started by "root". D [01/Mar/2012:20:52:48 +0100] cupsdMarkDirty(----J-) D [01/Mar/2012:20:52:48 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:48 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:48 +0100] Discarding unused printer-state-changed event... D [01/Mar/2012:20:52:48 +0100] [Job 55] job-sheets=none,none D [01/Mar/2012:20:52:48 +0100] [Job 55] argv[0]="HP_6315" D [01/Mar/2012:20:52:48 +0100] [Job 55] argv[1]="55" D [01/Mar/2012:20:52:48 +0100] [Job 55] argv[2]="internet" D [01/Mar/2012:20:52:48 +0100] [Job 55] argv[3]="Wie sieht das Reh aus" D [01/Mar/2012:20:52:48 +0100] [Job 55] argv[4]="1" D [01/Mar/2012:20:52:48 +0100] [Job 55] argv[5]="noCollate finishings=3 media=A4 number-up=1 outputorder=normal portrait sides=one-sided job-uuid=urn:uuid:c0965150-dda5-3fd7-78fc-45bce6776210 job-originating-host-name=localhost time-at-creation=1330631312 time-at-processing=1330631568 AP_D_InputSlot= Duplex=None PageSize=A4" D [01/Mar/2012:20:52:48 +0100] [Job 55] argv[6]="/var/spool/cups/d00055-001" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[1]="CUPS_DATADIR=/usr/share/cups" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[2]="CUPS_DOCROOT=/usr/share/cups/webcontent" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[6]="CUPS_SERVERROOT=/etc/cups" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[7]="CUPS_STATEDIR=/var/run/cups" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[8]="HOME=/var/spool/cups/tmp" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[10]="SERVER_ADMIN=root@host" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[11]="SOFTWARE=CUPS/1.5.0" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[12]="TMPDIR=/var/spool/cups/tmp" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[13]="USER=root" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[15]="CUPS_ENCRYPTION=IfRequested" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[16]="IPP_PORT=631" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[17]="CHARSET=utf-8" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[18]="LANG=de_DE.UTF-8" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[19]="PPD=/etc/cups/ppd/HP_6315.ppd" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[20]="RIP_MAX_CACHE=128m" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[21]="CONTENT_TYPE=application/postscript" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[22]="DEVICE_URI=hp:/usb/Officejet_6300_series?serial=CN689CF0Q204M4" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[23]="PRINTER_INFO=All_in_One" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[24]="PRINTER_LOCATION=im_Büro" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[25]="PRINTER=HP_6315" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[26]="PRINTER_STATE_REASONS=marker-supply-low-warning" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[27]="CUPS_FILETYPE=document" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[28]="FINAL_CONTENT_TYPE=printer/HP_6315" D [01/Mar/2012:20:52:48 +0100] [Job 55] envp[29]="AUTH_I****" I [01/Mar/2012:20:52:48 +0100] [Job 55] Started filter /usr/lib/cups/filter/pstops (PID 16884) I [01/Mar/2012:20:52:48 +0100] [Job 55] Started filter /usr/lib/cups/filter/foomatic-rip (PID 16885) I [01/Mar/2012:20:52:48 +0100] [Job 55] Started backend /usr/lib/cups/backend/hp (PID 16886) D [01/Mar/2012:20:52:48 +0100] Discarding unused job-state-changed event... D [01/Mar/2012:20:52:48 +0100] Returning IPP successful-ok for Resume-Printer (ipp://localhost/printers/HP_6315) from localhost D [01/Mar/2012:20:52:48 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files" D [01/Mar/2012:20:52:48 +0100] Script header: Content-Type: text/html;charset=utf-8 D [01/Mar/2012:20:52:48 +0100] Script header: D [01/Mar/2012:20:52:48 +0100] cupsdReadClient: 19 WAITING Closing on EOF D [01/Mar/2012:20:52:48 +0100] cupsdCloseClient: 19 D [01/Mar/2012:20:52:48 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:48 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:48 +0100] PID 16883 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [01/Mar/2012:20:52:48 +0100] [Job 55] Getting input from file D [01/Mar/2012:20:52:48 +0100] [Job 55] foomatic-rip version 4.0.9.241 running... D [01/Mar/2012:20:52:48 +0100] [Job 55] Parsing PPD file ... D [01/Mar/2012:20:52:48 +0100] [Job 55] Added option ColorSpace D [01/Mar/2012:20:52:48 +0100] [Job 55] Added option Resolution D [01/Mar/2012:20:52:48 +0100] [Job 55] Added option PageSize D [01/Mar/2012:20:52:48 +0100] [Job 55] Added option Model D [01/Mar/2012:20:52:48 +0100] [Job 55] Added option PrintoutMode D [01/Mar/2012:20:52:48 +0100] [Job 55] Added option InputSlot D [01/Mar/2012:20:52:48 +0100] [Job 55] Page = 595x842; 10,36 to 585,833 D [01/Mar/2012:20:52:48 +0100] [Job 55] Added option Duplex D [01/Mar/2012:20:52:48 +0100] [Job 55] slow_collate=0, slow_duplex=0, slow_order=0 D [01/Mar/2012:20:52:48 +0100] [Job 55] Before copy_comments - %!PS-Adobe-3.0 D [01/Mar/2012:20:52:48 +0100] [Job 55] %!PS-Adobe-3.0 D [01/Mar/2012:20:52:48 +0100] [Job 55] % Produced by poppler pdftops version: 0.18.0 (http://poppler.freedesktop.org) D [01/Mar/2012:20:52:48 +0100] [Job 55] %%Creator: Microsoft\256 Word 2010 D [01/Mar/2012:20:52:48 +0100] [Job 55] %%Title: Waldmaus_Dokumentation_Tierforscher.ch.pdf D [01/Mar/2012:20:52:48 +0100] [Job 55] %%LanguageLevel: 2 D [01/Mar/2012:20:52:48 +0100] [Job 55] %%DocumentSuppliedResources: (atend) D [01/Mar/2012:20:52:48 +0100] [Job 55] %%DocumentMedia: plain 595 842 0 () () D [01/Mar/2012:20:52:48 +0100] [Job 55] %%BoundingBox: 0 0 595 842 D [01/Mar/2012:20:52:48 +0100] [Job 55] %%Pages: 19 D [01/Mar/2012:20:52:48 +0100] [Job 55] %%EndComments D [01/Mar/2012:20:52:48 +0100] [Job 55] Before copy_prolog - %%BeginDefaults D [01/Mar/2012:20:52:48 +0100] [Job 55] Before copy_setup - %%BeginSetup D [01/Mar/2012:20:52:48 +0100] [Job 55] Added option DryTime D [01/Mar/2012:20:52:48 +0100] [Job 55] Added option Quality D [01/Mar/2012:20:52:48 +0100] [Job 55] Added option ImageableArea D [01/Mar/2012:20:52:48 +0100] [Job 55] Added option PaperDimension D [01/Mar/2012:20:52:48 +0100] [Job 55] Added option Font D [01/Mar/2012:20:52:48 +0100] [Job 55] D [01/Mar/2012:20:52:48 +0100] [Job 55] Parameter Summary D [01/Mar/2012:20:52:48 +0100] [Job 55] ----------------- D [01/Mar/2012:20:52:48 +0100] [Job 55] D [01/Mar/2012:20:52:48 +0100] [Job 55] Spooler: cups D [01/Mar/2012:20:52:48 +0100] [Job 55] Printer: HP_6315 D [01/Mar/2012:20:52:48 +0100] [Job 55] Shell: /bin/bash D [01/Mar/2012:20:52:48 +0100] [Job 55] PPD file: /etc/cups/ppd/HP_6315.ppd D [01/Mar/2012:20:52:48 +0100] [Job 55] ATTR file: D [01/Mar/2012:20:52:48 +0100] [Job 55] Printer model: HP Officejet 6300 Series hpijs, 3.12.2 D [01/Mar/2012:20:52:48 +0100] [Job 55] Job title: Wie sieht das Reh aus D [01/Mar/2012:20:52:48 +0100] [Job 55] File(s) to be printed: D [01/Mar/2012:20:52:48 +0100] [Job 55] D [01/Mar/2012:20:52:48 +0100] [Job 55] D [01/Mar/2012:20:52:48 +0100] [Job 55] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts D [01/Mar/2012:20:52:48 +0100] [Job 55] Printing system options: D [01/Mar/2012:20:52:48 +0100] [Job 55] Pondering option 'noCollate' D [01/Mar/2012:20:52:48 +0100] [Job 55] Unknown boolean option "noCollate". D [01/Mar/2012:20:52:48 +0100] [Job 55] Pondering option 'finishings=3' D [01/Mar/2012:20:52:48 +0100] [Job 55] Unknown option finishings=3. D [01/Mar/2012:20:52:48 +0100] [Job 55] Pondering option 'media=A4' D [01/Mar/2012:20:52:48 +0100] [Job 55] Pondering option 'number-up=1' D [01/Mar/2012:20:52:48 +0100] [Job 55] Unknown option number-up=1. D [01/Mar/2012:20:52:48 +0100] [Job 55] Pondering option 'outputorder=normal' D [01/Mar/2012:20:52:48 +0100] [Job 55] Unknown option outputorder=normal. D [01/Mar/2012:20:52:48 +0100] [Job 55] Pondering option 'portrait' D [01/Mar/2012:20:52:48 +0100] [Job 55] Unknown boolean option "portrait". D [01/Mar/2012:20:52:48 +0100] [Job 55] Pondering option 'sides=one-sided' D [01/Mar/2012:20:52:48 +0100] [Job 55] Pondering option 'job-uuid=urn:uuid:c0965150-dda5-3fd7-78fc-45bce6776210' D [01/Mar/2012:20:52:48 +0100] [Job 55] Unknown option job-uuid=urn:uuid:c0965150-dda5-3fd7-78fc-45bce6776210. D [01/Mar/2012:20:52:48 +0100] [Job 55] Pondering option 'job-originating-host-name=localhost' D [01/Mar/2012:20:52:48 +0100] [Job 55] Unknown option job-originating-host-name=localhost. D [01/Mar/2012:20:52:48 +0100] [Job 55] Pondering option 'time-at-creation=1330631312' D [01/Mar/2012:20:52:48 +0100] [Job 55] Unknown option time-at-creation=1330631312. D [01/Mar/2012:20:52:48 +0100] [Job 55] Pondering option 'time-at-processing=1330631568' D [01/Mar/2012:20:52:48 +0100] [Job 55] Unknown option time-at-processing=1330631568. D [01/Mar/2012:20:52:48 +0100] [Job 55] Pondering option 'AP_D_InputSlot=' D [01/Mar/2012:20:52:48 +0100] [Job 55] Unknown option AP_D_InputSlot=. D [01/Mar/2012:20:52:48 +0100] [Job 55] Options from the PPD file: D [01/Mar/2012:20:52:48 +0100] [Job 55] Pondering option 'Duplex=None' D [01/Mar/2012:20:52:48 +0100] [Job 55] Pondering option 'PageSize=A4' D [01/Mar/2012:20:52:48 +0100] [Job 55] D [01/Mar/2012:20:52:48 +0100] [Job 55] ================================================ D [01/Mar/2012:20:52:48 +0100] [Job 55] D [01/Mar/2012:20:52:48 +0100] [Job 55] File: D [01/Mar/2012:20:52:48 +0100] [Job 55] D [01/Mar/2012:20:52:48 +0100] [Job 55] ================================================ D [01/Mar/2012:20:52:48 +0100] [Job 55] D [01/Mar/2012:20:52:48 +0100] [Job 55] Filetype: PostScript D [01/Mar/2012:20:52:48 +0100] [Job 55] Reading PostScript input ... D [01/Mar/2012:20:52:48 +0100] [Job 55] --> This document is DSC-conforming! D [01/Mar/2012:20:52:48 +0100] [Job 55] Found %RBINumCopies: 1 D [01/Mar/2012:20:52:48 +0100] [Job 55] Job claims to be DSC-conforming, but "%%BeginProlog" was missing before first line with another"%%BeginProlog" comment (is this a TeX/LaTeX/dvips-generated PostScript file?). Assuming start of "Prolog" here. D [01/Mar/2012:20:52:48 +0100] [Job 55] D [01/Mar/2012:20:52:48 +0100] [Job 55] ----------- D [01/Mar/2012:20:52:48 +0100] [Job 55] Found: %%BeginProlog D [01/Mar/2012:20:52:48 +0100] [Job 55] Found: %%EndProlog D [01/Mar/2012:20:52:48 +0100] [Job 55] D [01/Mar/2012:20:52:48 +0100] [Job 55] ----------- D [01/Mar/2012:20:52:48 +0100] [Job 55] Found: %%BeginSetup D [01/Mar/2012:20:52:48 +0100] [Job 55] Found: %%BeginFeature: *PageSize A4 D [01/Mar/2012:20:52:48 +0100] [Job 55] Option: PageSize=A4 D [01/Mar/2012:20:52:48 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:48 +0100] [Job 55] Found: %% FoomaticRIPOptionSetting: PageSize=A4 D [01/Mar/2012:20:52:48 +0100] [Job 55] Option: PageSize=A4 D [01/Mar/2012:20:52:48 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:48 +0100] [Job 55] Found: %%BeginFeature: *PrintoutMode Normal D [01/Mar/2012:20:52:48 +0100] [Job 55] Option: PrintoutMode=Normal D [01/Mar/2012:20:52:48 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:48 +0100] [Job 55] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal D [01/Mar/2012:20:52:48 +0100] [Job 55] Option: PrintoutMode=Normal D [01/Mar/2012:20:52:48 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:48 +0100] [Job 55] Found: %%BeginFeature: *Quality FromPrintoutMode D [01/Mar/2012:20:52:48 +0100] [Job 55] Option: Quality=FromPrintoutMode D [01/Mar/2012:20:52:48 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:48 +0100] [Job 55] Found: %% FoomaticRIPOptionSetting: Quality=@PrintoutMode D [01/Mar/2012:20:52:48 +0100] [Job 55] Option: Quality=FromPrintoutMode D [01/Mar/2012:20:52:48 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:48 +0100] [Job 55] Found: %%BeginFeature: *Duplex None D [01/Mar/2012:20:52:48 +0100] [Job 55] Option: Duplex=None D [01/Mar/2012:20:52:48 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:48 +0100] [Job 55] Found: %% FoomaticRIPOptionSetting: Duplex=None D [01/Mar/2012:20:52:48 +0100] [Job 55] Option: Duplex=None D [01/Mar/2012:20:52:48 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:48 +0100] [Job 55] Found: %%BeginFeature: *DryTime Zero D [01/Mar/2012:20:52:48 +0100] [Job 55] Option: DryTime=Zero D [01/Mar/2012:20:52:48 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:48 +0100] [Job 55] Found: %% FoomaticRIPOptionSetting: DryTime=Zero D [01/Mar/2012:20:52:48 +0100] [Job 55] Option: DryTime=Zero D [01/Mar/2012:20:52:48 +0100] [Job 55] Setting option D [01/Mar/2012:20:52:48 +0100] [Job 55] Before page loop - %%Page: 16 1 D [01/Mar/2012:20:52:48 +0100] [Job 55] Copying page 1... D [01/Mar/2012:20:52:48 +0100] [Job 55] pagew = 575.6, pagel = 797.0 D [01/Mar/2012:20:52:48 +0100] [Job 55] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842 D [01/Mar/2012:20:52:48 +0100] [Job 55] PageLeft = 9.7, PageRight = 585.3 D [01/Mar/2012:20:52:48 +0100] [Job 55] PageTop = 833.0, PageBottom = 36.0 D [01/Mar/2012:20:52:48 +0100] [Job 55] PageWidth = 595.0, PageLength = 842.0 D [01/Mar/2012:20:52:48 +0100] [Job 55] Found: %%EndSetup D [01/Mar/2012:20:52:48 +0100] [Job 55] Inserting PostScript code for CUPS' page accounting D [01/Mar/2012:20:52:48 +0100] [Job 55] D [01/Mar/2012:20:52:48 +0100] [Job 55] ----------- D [01/Mar/2012:20:52:48 +0100] [Job 55] New page: %%Page: 16 1 D [01/Mar/2012:20:52:48 +0100] [Job 55] D [01/Mar/2012:20:52:48 +0100] [Job 55] Found: %%BeginPageSetup D [01/Mar/2012:20:52:48 +0100] [Job 55] Inserting option code into "PageSetup" section. D [01/Mar/2012:20:52:48 +0100] [Job 55] Wrote 1 pages... D [01/Mar/2012:20:52:48 +0100] PID 16884 (/usr/lib/cups/filter/pstops) exited with no errors. D [01/Mar/2012:20:52:48 +0100] [Job 55] Flushing FIFO. D [01/Mar/2012:20:52:49 +0100] [Job 55] D [01/Mar/2012:20:52:49 +0100] [Job 55] Starting renderer with command: "gs -sstdout=%stderr -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="deskjet 5600" -dDuplex=false -r300 -sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=2,PS:MediaPosition=7 -dIjsUseOutputFD -sOutputFile=%stdout -_" D [01/Mar/2012:20:52:49 +0100] [Job 55] Starting process "kid3" (generation 1) D [01/Mar/2012:20:52:49 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [01/Mar/2012:20:52:49 +0100] cupsdNetIFUpdate: "eth0" = 192.168.2.100:631 D [01/Mar/2012:20:52:49 +0100] cupsdNetIFUpdate: "lo" = localhost:631 D [01/Mar/2012:20:52:49 +0100] cupsdNetIFUpdate: "eth0" = [v1.fe80::219:66ff:fe5f:5255+eth0]:631 D [01/Mar/2012:20:52:49 +0100] [Job 55] Starting process "kid4" (generation 2) D [01/Mar/2012:20:52:49 +0100] [Job 55] Starting process "renderer" (generation 2) D [01/Mar/2012:20:52:49 +0100] [Job 55] JCL: %-12345X@PJL D [01/Mar/2012:20:52:49 +0100] [Job 55] D [01/Mar/2012:20:52:49 +0100] [Job 55] D [01/Mar/2012:20:52:49 +0100] [Job 55] D [01/Mar/2012:20:52:49 +0100] [Job 55] Closing renderer D [01/Mar/2012:20:52:49 +0100] [Job 55] PAGE: 1 1 D [01/Mar/2012:20:52:49 +0100] Discarding unused job-progress event... D [01/Mar/2012:20:52:49 +0100] [Job 55] STATE: +connecting-to-device D [01/Mar/2012:20:52:49 +0100] Discarding unused printer-state-changed event... D [01/Mar/2012:20:52:49 +0100] [Job 55] STATE: -connecting-to-device D [01/Mar/2012:20:52:49 +0100] [Job 55] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other D [01/Mar/2012:20:52:49 +0100] Discarding unused printer-state-changed event... D [01/Mar/2012:20:52:53 +0100] cupsdReadClient: 14 GET /printers/HP_6315 HTTP/1.1 D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:53 +0100] cupsdAuthorize: Authorized as root using Basic D [01/Mar/2012:20:52:53 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@host" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[13] = "USER=root" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[16] = "IPP_PORT=631" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[17] = "AUTH_TYPE=Basic" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[18] = "LANG=de_DE.UTF8" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[19] = "REDIRECT_STATUS=1" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[21] = "SERVER_NAME=localhost" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[22] = "SERVER_PORT=631" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[23] = "REMOTE_ADDR=[v1.::1]" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[24] = "REMOTE_HOST=localhost" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[25] = "SCRIPT_NAME=/printers/HP_6315" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/printers/HP_6315" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[27] = "PATH_INFO=/HP_6315" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[28] = "REMOTE_USER=root" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=e1220f1882cfa844c625ae45e46fad5f" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux i686; rv:10.0.2) Gecko/20100101 Firefox/10.0.2" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[32] = "REQUEST_METHOD=GET" D [01/Mar/2012:20:52:53 +0100] [CGI] envp[33] = "QUERY_STRING=" D [01/Mar/2012:20:52:53 +0100] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 16916) I [01/Mar/2012:20:52:53 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=16916) D [01/Mar/2012:20:52:53 +0100] cupsdSendCommand: 14 file=18 D [01/Mar/2012:20:52:53 +0100] [CGI] org.cups.sid cookie is "e1220f1882cfa844c625ae45e46fad5f" D [01/Mar/2012:20:52:53 +0100] cupsdAcceptClient: 19 from localhost (Domain) D [01/Mar/2012:20:52:53 +0100] cupsdReadClient: 19 POST / HTTP/1.1 D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:53 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:53 +0100] cupsdReadClient: 19 1.1 CUPS-Get-Default 1 D [01/Mar/2012:20:52:53 +0100] CUPS-Get-Default D [01/Mar/2012:20:52:53 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:53 +0100] [CGI] show_printer(http=0xb7901a98, printer="HP_6315") D [01/Mar/2012:20:52:53 +0100] cupsdReadClient: 19 POST / HTTP/1.1 D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:53 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:53 +0100] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1 D [01/Mar/2012:20:52:53 +0100] Get-Printer-Attributes ipp://localhost/printers/HP_6315 D [01/Mar/2012:20:52:53 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_6315) from localhost D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:53 +0100] Script header: Content-Type: text/html;charset=utf-8 D [01/Mar/2012:20:52:53 +0100] Script header: D [01/Mar/2012:20:52:53 +0100] [CGI] Regular expression ".*Clean.*" D [01/Mar/2012:20:52:53 +0100] [CGI] matches[0].rm_so=0 D [01/Mar/2012:20:52:53 +0100] [CGI] matches[1].rm_so=-1 D [01/Mar/2012:20:52:53 +0100] [CGI] Regular expression ".*PrintSelfTestPage.*" D [01/Mar/2012:20:52:53 +0100] [CGI] matches[0].rm_so=0 D [01/Mar/2012:20:52:53 +0100] [CGI] matches[1].rm_so=-1 D [01/Mar/2012:20:52:53 +0100] cupsdReadClient: 19 POST / HTTP/1.1 D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:53 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:53 +0100] cupsdReadClient: 19 1.1 Get-Jobs 1 D [01/Mar/2012:20:52:53 +0100] Get-Jobs ipp://localhost:631/printers/HP_6315 D [01/Mar/2012:20:52:53 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_6315) from localhost D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:53 +0100] cupsdReadClient: 19 WAITING Closing on EOF D [01/Mar/2012:20:52:53 +0100] cupsdCloseClient: 19 D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:53 +0100] PID 16916 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:53 +0100] cupsdReadClient: 14 GET /cups.css HTTP/1.1 D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:53 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:53 +0100] cupsdReadClient: 14 GET /images/left.gif HTTP/1.1 D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:53 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:53 +0100] cupsdReadClient: 16 GET /images/right.gif HTTP/1.1 D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:53 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:53 +0100] cupsdReadClient: 14 GET /images/unsel.gif HTTP/1.1 D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:53 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:53 +0100] cupsdReadClient: 16 GET /images/sel.gif HTTP/1.1 D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:52:53 +0100] cupsdAuthorize: No authentication data provided. D [01/Mar/2012:20:52:53 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [01/Mar/2012:20:52:54 +0100] [Job 55] renderer exited with status 0 D [01/Mar/2012:20:52:59 +0100] [Job 55] kid4 exited with status 0 D [01/Mar/2012:20:52:59 +0100] [Job 55] kid3 finished D [01/Mar/2012:20:52:59 +0100] [Job 55] kid3 exited with status 0 D [01/Mar/2012:20:52:59 +0100] [Job 55] D [01/Mar/2012:20:52:59 +0100] [Job 55] Closing foomatic-rip. D [01/Mar/2012:20:52:59 +0100] PID 16885 (/usr/lib/cups/filter/foomatic-rip) exited with no errors. D [01/Mar/2012:20:53:03 +0100] cupsdReadClient: 14 GET /printers/HP_6315 HTTP/1.1 D [01/Mar/2012:20:53:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [01/Mar/2012:20:53:03 +0100] cupsdAuthorize: Authorized as root using Basic D [01/Mar/2012:20:53:03 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [01/Mar/2012:20:53:03 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [01/Mar/2012:20:53:03 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [01/Mar/2012:20:53:03 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [01/Mar/2012:20:53:03 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [01/Mar/2012:20:53:03 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [01/Mar/2012:20:53:03 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [01/Mar/2012:20:53:03 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [01/Mar/2012:20:53:03 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [01/Mar/2012:20:53:03 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [01/Mar/2012:20:53:03 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [01/Mar/2012:20:53:03 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@host" D [01/Mar/2012:20:53:03 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0"