Hallo! Ich habe einen Canon ip4700, den ich über das CUPS (Version 1.5.0) web interface installiert habe. Der Drucker ist über USB an meinen Rechner angeschlossen, der unter openSuse 12.1 läuft. Der Drucker wird von CUPS am USB Anschluss erkannt und lässt sich auch problemlos installieren. Versuche ich allerdings eine Testseite zu drucken, scheint diese spurlos von der Queue zu verschwinden: Der Job wird versand und er drucker scheint diesen auch zu empfangen, da die Lampen am drucker kurz blinken. Es wird jedoch nichts gedruckt. Der job wird auch ordnungsgemäß in der Cups printer queue angezeigt und verschwindet nach kurzer Zeit wieder, so als ob er ordnungsgemäß abgearbeitet wurde. Auch auf dem folgenden CUPS Statusmeldungen (s. unten) werde ich nicht schlau, da dort nur folgende Fehlermeldung auftaucht: E [23/Dec/2011:15:32:22 +0100] [Job 18] Unable to open print file: No such file or directory und für mich als Laien nicht klar ist, welche Datei nicht gefunden wird. Ausserdem gibt es noch einige Debug einträge der Form D Unable to open "/usr/share/cups/fonts/Monospace" Was verbirgt sich hinter diesem Pfad, und wo kriege ich Monospace her? Für jede Hilfe in dieser Angelegenheit wäre ich dankbar. Viele Grüße Claas P.S. Hier ein Auszug aus /var/log/cups/error_log D [23/Dec/2011:14:57:23 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [23/Dec/2011:15:14:29 +0100] cupsdAcceptClient: 12 from localhost (Domain) D [23/Dec/2011:15:14:29 +0100] Report: clients=1 D [23/Dec/2011:15:14:29 +0100] Report: jobs=2 D [23/Dec/2011:15:14:29 +0100] Report: jobs-active=0 D [23/Dec/2011:15:14:29 +0100] Report: printers=1 D [23/Dec/2011:15:14:29 +0100] Report: printers-implicit=0 D [23/Dec/2011:15:14:29 +0100] Report: stringpool-string-count=99534 D [23/Dec/2011:15:14:29 +0100] Report: stringpool-alloc-bytes=13888 D [23/Dec/2011:15:14:29 +0100] Report: stringpool-total-bytes=1838456 D [23/Dec/2011:15:14:29 +0100] cupsdReadClient: 12 POST / HTTP/1.1 D [23/Dec/2011:15:14:29 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [23/Dec/2011:15:14:29 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:14:29 +0100] cupsdReadClient: 12 1.1 CUPS-Get-Printers 1 D [23/Dec/2011:15:14:29 +0100] CUPS-Get-Printers D [23/Dec/2011:15:14:29 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [23/Dec/2011:15:14:29 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [23/Dec/2011:15:14:29 +0100] cupsdReadClient: 12 POST / HTTP/1.1 D [23/Dec/2011:15:14:29 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [23/Dec/2011:15:14:29 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:14:29 +0100] cupsdReadClient: 12 1.1 CUPS-Get-Default 1 D [23/Dec/2011:15:14:29 +0100] CUPS-Get-Default D [23/Dec/2011:15:14:29 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [23/Dec/2011:15:14:29 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [23/Dec/2011:15:14:29 +0100] cupsdReadClient: 12 POST / HTTP/1.1 D [23/Dec/2011:15:14:29 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [23/Dec/2011:15:14:29 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:14:29 +0100] cupsdReadClient: 12 1.1 CUPS-Get-Printers 1 D [23/Dec/2011:15:14:29 +0100] CUPS-Get-Printers D [23/Dec/2011:15:14:29 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [23/Dec/2011:15:14:29 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [23/Dec/2011:15:14:29 +0100] cupsdReadClient: 12 WAITING Closing on EOF D [23/Dec/2011:15:14:29 +0100] cupsdCloseClient: 12 D [23/Dec/2011:15:14:29 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [23/Dec/2011:15:31:55 +0100] cupsdAcceptClient: 12 from localhost:631 (IPv4) D [23/Dec/2011:15:31:55 +0100] Report: clients=1 D [23/Dec/2011:15:31:55 +0100] Report: jobs=2 D [23/Dec/2011:15:31:55 +0100] Report: jobs-active=0 D [23/Dec/2011:15:31:55 +0100] Report: printers=1 D [23/Dec/2011:15:31:55 +0100] Report: printers-implicit=0 D [23/Dec/2011:15:31:55 +0100] Report: stringpool-string-count=99534 D [23/Dec/2011:15:31:55 +0100] Report: stringpool-alloc-bytes=13888 D [23/Dec/2011:15:31:55 +0100] Report: stringpool-total-bytes=1838456 D [23/Dec/2011:15:31:55 +0100] cupsdReadClient: 12 GET /admin HTTP/1.1 D [23/Dec/2011:15:31:55 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [23/Dec/2011:15:31:55 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:31:55 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@snoopy" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[13] = "USER=root" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[16] = "IPP_PORT=631" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[17] = "LANG=en_GB.UTF8" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[18] = "REDIRECT_STATUS=1" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[20] = "SERVER_NAME=localhost" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[21] = "SERVER_PORT=631" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[23] = "REMOTE_HOST=localhost" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[24] = "SCRIPT_NAME=/admin" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/admin" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[26] = "SERVER_PROTOCOL=HTTP/1.1" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[27] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:9.0) Gecko/20100101 Firefox/9.0" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[28] = "HTTP_REFERER=http://localhost:631/" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[29] = "REQUEST_METHOD=GET" D [23/Dec/2011:15:31:55 +0100] [CGI] envp[30] = "QUERY_STRING=" D [23/Dec/2011:15:31:55 +0100] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 5939) I [23/Dec/2011:15:31:55 +0100] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5939) D [23/Dec/2011:15:31:55 +0100] cupsdSendCommand: 12 file=13 D [23/Dec/2011:15:31:55 +0100] [CGI] admin.cgi started... D [23/Dec/2011:15:31:55 +0100] cupsdAcceptClient: 14 from localhost (Domain) D [23/Dec/2011:15:31:55 +0100] [CGI] http=0x7f153796f220 D [23/Dec/2011:15:31:55 +0100] [CGI] org.cups.sid cookie not found, initializing! D [23/Dec/2011:15:31:55 +0100] [CGI] org.cups.sid cookie is "df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:31:55 +0100] [CGI] No form data, showing main menu... D [23/Dec/2011:15:31:55 +0100] [CGI] /usr/share/cups/drivers/pscript5.dll: No such file or directory D [23/Dec/2011:15:31:55 +0100] cupsdReadClient: 14 POST / HTTP/1.1 D [23/Dec/2011:15:31:55 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:31:55 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:31:55 +0100] cupsdReadClient: 14 1.1 Get-Subscriptions 1 D [23/Dec/2011:15:31:55 +0100] Get-Subscriptions ipp://localhost/ D [23/Dec/2011:15:31:55 +0100] Get-Subscriptions client-error-not-found: No subscriptions found. D [23/Dec/2011:15:31:55 +0100] Returning IPP client-error-not-found for Get-Subscriptions (ipp://localhost/) from localhost D [23/Dec/2011:15:31:55 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:31:55 +0100] Script header: Set-Cookie: org.cups.sid=df7195b5291b83941fc2707b836b1adc;; path=/; D [23/Dec/2011:15:31:55 +0100] Script header: Content-Type: text/html;charset=utf-8 D [23/Dec/2011:15:31:55 +0100] Script header: D [23/Dec/2011:15:31:55 +0100] cupsdReadClient: 14 WAITING Closing on EOF D [23/Dec/2011:15:31:55 +0100] cupsdCloseClient: 14 D [23/Dec/2011:15:31:55 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:31:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [23/Dec/2011:15:31:55 +0100] PID 5939 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors. D [23/Dec/2011:15:32:00 +0100] cupsdReadClient: 12 GET /admin/log/error_log HTTP/1.1 D [23/Dec/2011:15:32:00 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [23/Dec/2011:15:32:00 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:00 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [23/Dec/2011:15:32:00 +0100] cupsdReadClient: 12 GET /favicon.ico HTTP/1.1 D [23/Dec/2011:15:32:00 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [23/Dec/2011:15:32:00 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:00 +0100] cupsdReadClient: 12 Closing because Keep-Alive disabled D [23/Dec/2011:15:32:00 +0100] cupsdCloseClient: 12 D [23/Dec/2011:15:32:00 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [23/Dec/2011:15:32:00 +0100] cupsdAcceptClient: 12 from localhost:631 (IPv4) D [23/Dec/2011:15:32:00 +0100] cupsdReadClient: 12 GET /favicon.ico HTTP/1.1 D [23/Dec/2011:15:32:00 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [23/Dec/2011:15:32:00 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:00 +0100] cupsdReadClient: 12 Closing because Keep-Alive disabled D [23/Dec/2011:15:32:00 +0100] cupsdCloseClient: 12 D [23/Dec/2011:15:32:00 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [23/Dec/2011:15:32:18 +0100] cupsdAcceptClient: 12 from localhost:631 (IPv4) D [23/Dec/2011:15:32:18 +0100] cupsdReadClient: 12 GET /printers/ HTTP/1.1 D [23/Dec/2011:15:32:18 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [23/Dec/2011:15:32:18 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:18 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@snoopy" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[13] = "USER=root" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[16] = "IPP_PORT=631" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[17] = "LANG=en_GB.UTF8" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[18] = "REDIRECT_STATUS=1" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[20] = "SERVER_NAME=localhost" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[21] = "SERVER_PORT=631" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[23] = "REMOTE_HOST=localhost" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[24] = "SCRIPT_NAME=/printers/" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/printers/" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[26] = "SERVER_PROTOCOL=HTTP/1.1" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[27] = "HTTP_COOKIE=org.cups.sid=df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:9.0) Gecko/20100101 Firefox/9.0" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[29] = "HTTP_REFERER=http://localhost:631/admin" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[30] = "REQUEST_METHOD=GET" D [23/Dec/2011:15:32:18 +0100] [CGI] envp[31] = "QUERY_STRING=" D [23/Dec/2011:15:32:18 +0100] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 5940) I [23/Dec/2011:15:32:18 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5940) D [23/Dec/2011:15:32:18 +0100] cupsdSendCommand: 12 file=13 D [23/Dec/2011:15:32:18 +0100] [CGI] org.cups.sid cookie is "df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:32:18 +0100] cupsdAcceptClient: 14 from localhost (Domain) D [23/Dec/2011:15:32:18 +0100] cupsdReadClient: 14 POST / HTTP/1.1 D [23/Dec/2011:15:32:18 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:32:18 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:18 +0100] cupsdReadClient: 14 1.1 CUPS-Get-Default 1 D [23/Dec/2011:15:32:18 +0100] CUPS-Get-Default D [23/Dec/2011:15:32:18 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [23/Dec/2011:15:32:18 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:32:18 +0100] [CGI] show_all_printers(http=0x7f81761dade0, user="(null)") D [23/Dec/2011:15:32:18 +0100] Script header: Content-Type: text/html;charset=utf-8 D [23/Dec/2011:15:32:18 +0100] Script header: D [23/Dec/2011:15:32:18 +0100] cupsdReadClient: 14 POST / HTTP/1.1 D [23/Dec/2011:15:32:18 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:32:18 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:18 +0100] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1 D [23/Dec/2011:15:32:18 +0100] CUPS-Get-Printers D [23/Dec/2011:15:32:18 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [23/Dec/2011:15:32:18 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:32:19 +0100] cupsdReadClient: 14 WAITING Closing on EOF D [23/Dec/2011:15:32:19 +0100] cupsdCloseClient: 14 D [23/Dec/2011:15:32:19 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:32:19 +0100] PID 5940 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [23/Dec/2011:15:32:19 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [23/Dec/2011:15:32:20 +0100] cupsdReadClient: 12 GET /printers/ip4700 HTTP/1.1 D [23/Dec/2011:15:32:20 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [23/Dec/2011:15:32:20 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:20 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@snoopy" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[13] = "USER=root" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[16] = "IPP_PORT=631" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[17] = "LANG=en_GB.UTF8" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[18] = "REDIRECT_STATUS=1" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[20] = "SERVER_NAME=localhost" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[21] = "SERVER_PORT=631" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[23] = "REMOTE_HOST=localhost" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[24] = "SCRIPT_NAME=/printers/ip4700" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/printers/ip4700" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[26] = "PATH_INFO=/ip4700" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[28] = "HTTP_COOKIE=org.cups.sid=df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:9.0) Gecko/20100101 Firefox/9.0" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[30] = "HTTP_REFERER=http://localhost:631/printers/" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[31] = "REQUEST_METHOD=GET" D [23/Dec/2011:15:32:20 +0100] [CGI] envp[32] = "QUERY_STRING=" D [23/Dec/2011:15:32:20 +0100] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 5942) I [23/Dec/2011:15:32:20 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5942) D [23/Dec/2011:15:32:20 +0100] cupsdSendCommand: 12 file=13 D [23/Dec/2011:15:32:20 +0100] [CGI] org.cups.sid cookie is "df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:32:20 +0100] cupsdAcceptClient: 14 from localhost (Domain) D [23/Dec/2011:15:32:20 +0100] cupsdReadClient: 14 POST / HTTP/1.1 D [23/Dec/2011:15:32:20 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:32:20 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:20 +0100] cupsdReadClient: 14 1.1 CUPS-Get-Default 1 D [23/Dec/2011:15:32:20 +0100] CUPS-Get-Default D [23/Dec/2011:15:32:20 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [23/Dec/2011:15:32:20 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:32:20 +0100] [CGI] show_printer(http=0x7f4bf656de40, printer="ip4700") D [23/Dec/2011:15:32:20 +0100] cupsdReadClient: 14 POST / HTTP/1.1 D [23/Dec/2011:15:32:20 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:32:20 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:20 +0100] cupsdReadClient: 14 1.1 Get-Printer-Attributes 1 D [23/Dec/2011:15:32:20 +0100] Get-Printer-Attributes ipp://localhost/printers/ip4700 D [23/Dec/2011:15:32:20 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/ip4700) from localhost D [23/Dec/2011:15:32:20 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:32:20 +0100] Script header: Content-Type: text/html;charset=utf-8 D [23/Dec/2011:15:32:20 +0100] Script header: D [23/Dec/2011:15:32:20 +0100] [CGI] Regular expression ".*Clean.*" D [23/Dec/2011:15:32:20 +0100] [CGI] Regular expression ".*PrintSelfTestPage.*" D [23/Dec/2011:15:32:20 +0100] cupsdReadClient: 14 POST / HTTP/1.1 D [23/Dec/2011:15:32:20 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:32:20 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:20 +0100] cupsdReadClient: 14 1.1 Get-Jobs 1 D [23/Dec/2011:15:32:20 +0100] Get-Jobs ipp://localhost:631/printers/ip4700 D [23/Dec/2011:15:32:20 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/ip4700) from localhost D [23/Dec/2011:15:32:20 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:32:20 +0100] cupsdReadClient: 14 WAITING Closing on EOF D [23/Dec/2011:15:32:20 +0100] cupsdCloseClient: 14 D [23/Dec/2011:15:32:20 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:32:20 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [23/Dec/2011:15:32:20 +0100] PID 5942 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [23/Dec/2011:15:32:22 +0100] cupsdReadClient: 12 POST /printers/ip4700 HTTP/1.1 D [23/Dec/2011:15:32:22 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [23/Dec/2011:15:32:22 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:22 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@snoopy" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[13] = "USER=root" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[16] = "IPP_PORT=631" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[17] = "LANG=en_GB.UTF8" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[18] = "REDIRECT_STATUS=1" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[20] = "SERVER_NAME=localhost" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[21] = "SERVER_PORT=631" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[23] = "REMOTE_HOST=localhost" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[24] = "SCRIPT_NAME=/printers/ip4700" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/printers/ip4700" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[26] = "PATH_INFO=/ip4700" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[28] = "HTTP_COOKIE=org.cups.sid=df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:9.0) Gecko/20100101 Firefox/9.0" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[30] = "HTTP_REFERER=http://localhost:631/printers/ip4700" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[31] = "REQUEST_METHOD=POST" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[32] = "CONTENT_LENGTH=64" D [23/Dec/2011:15:32:22 +0100] [CGI] envp[33] = "CONTENT_TYPE=application/x-www-form-urlencoded" D [23/Dec/2011:15:32:22 +0100] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 5943) I [23/Dec/2011:15:32:22 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5943) D [23/Dec/2011:15:32:22 +0100] cupsdSendCommand: 12 file=14 D [23/Dec/2011:15:32:22 +0100] [CGI] org.cups.sid cookie is "df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:32:22 +0100] cupsdAcceptClient: 13 from localhost (Domain) D [23/Dec/2011:15:32:22 +0100] cupsdReadClient: 13 POST /printers/ip4700 HTTP/1.1 D [23/Dec/2011:15:32:22 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:32:22 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:22 +0100] cupsdReadClient: 13 1.1 Print-Job 1 D [23/Dec/2011:15:32:22 +0100] Print-Job ipp://localhost:631/printers/ip4700 D [23/Dec/2011:15:32:22 +0100] [Job ???] Auto-typing file... I [23/Dec/2011:15:32:22 +0100] [Job ???] Request file type is application/vnd.cups-banner. D [23/Dec/2011:15:32:22 +0100] cupsdMarkDirty(----J-) D [23/Dec/2011:15:32:22 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients" D [23/Dec/2011:15:32:22 +0100] Adding default job-sheets values "none,none"... I [23/Dec/2011:15:32:22 +0100] [Job 18] Adding start banner page "none". D [23/Dec/2011:15:32:22 +0100] Discarding unused job-created event... D [23/Dec/2011:15:32:22 +0100] cupsdMarkDirty(----J-) D [23/Dec/2011:15:32:22 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [23/Dec/2011:15:32:22 +0100] [Job 18] Adding end banner page "none". I [23/Dec/2011:15:32:22 +0100] [Job 18] File of type application/vnd.cups-banner queued by "anonymous". D [23/Dec/2011:15:32:22 +0100] [Job 18] hold_until=0 I [23/Dec/2011:15:32:22 +0100] [Job 18] Queued on "ip4700" by "anonymous". D [23/Dec/2011:15:32:22 +0100] cupsdMarkDirty(----J-) D [23/Dec/2011:15:32:22 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [23/Dec/2011:15:32:22 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [23/Dec/2011:15:32:22 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:22 +0100] [Job 18] job-sheets=none,none D [23/Dec/2011:15:32:22 +0100] [Job 18] argv[0]="ip4700" D [23/Dec/2011:15:32:22 +0100] [Job 18] argv[1]="18" D [23/Dec/2011:15:32:22 +0100] [Job 18] argv[2]="anonymous" D [23/Dec/2011:15:32:22 +0100] [Job 18] argv[3]="Test Page" D [23/Dec/2011:15:32:22 +0100] [Job 18] argv[4]="1" D [23/Dec/2011:15:32:22 +0100] [Job 18] argv[5]="job-uuid=urn:uuid:6bd5db13-76ee-32bf-561d-a41fb6664818 job-originating-host-name=localhost time-at-creation=1324650742 time-at-processing=1324650742 AP_D_InputSlot=" D [23/Dec/2011:15:32:22 +0100] [Job 18] argv[6]="/var/spool/cups/d00018-001" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[1]="CUPS_DATADIR=/usr/share/cups" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[2]="CUPS_DOCROOT=/usr/share/cups/webcontent" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[6]="CUPS_SERVERROOT=/etc/cups" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[7]="CUPS_STATEDIR=/var/run/cups" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[8]="HOME=/var/spool/cups/tmp" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[10]="SERVER_ADMIN=root@snoopy" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[11]="SOFTWARE=CUPS/1.5.0" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[12]="TMPDIR=/var/spool/cups/tmp" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[13]="USER=root" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[15]="CUPS_ENCRYPTION=IfRequested" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[16]="IPP_PORT=631" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[17]="CHARSET=utf-8" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[18]="LANG=en_GB.UTF-8" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[19]="PPD=/etc/cups/ppd/ip4700.ppd" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[20]="RIP_MAX_CACHE=128m" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[21]="CONTENT_TYPE=application/vnd.cups-banner" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[22]="DEVICE_URI=usb://Canon/iP4700%20series" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[23]="PRINTER_INFO=Canon iP4700 series with driver Canon PIXMA iP4700 - CUPS+Gutenprint v5.2.7" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[24]="PRINTER_LOCATION=" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[25]="PRINTER=ip4700" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[26]="PRINTER_STATE_REASONS=none" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[27]="CUPS_FILETYPE=document" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[28]="FINAL_CONTENT_TYPE=printer/ip4700" D [23/Dec/2011:15:32:22 +0100] [Job 18] envp[29]="AUTH_I****" I [23/Dec/2011:15:32:22 +0100] [Job 18] Started filter /usr/lib/cups/filter/bannertops (PID 5944) I [23/Dec/2011:15:32:22 +0100] [Job 18] Started filter /usr/lib/cups/filter/pstops (PID 5945) I [23/Dec/2011:15:32:22 +0100] [Job 18] Started filter /usr/lib/cups/filter/pstoraster (PID 5946) I [23/Dec/2011:15:32:22 +0100] [Job 18] Started filter /usr/lib/cups/filter/rastertogutenprint.5.2 (PID 5947) I [23/Dec/2011:15:32:22 +0100] [Job 18] Started backend /usr/lib/cups/backend/usb (PID 5948) D [23/Dec/2011:15:32:22 +0100] Discarding unused job-state-changed event... D [23/Dec/2011:15:32:22 +0100] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/ip4700) from localhost D [23/Dec/2011:15:32:22 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files" D [23/Dec/2011:15:32:22 +0100] Script header: Content-Type: text/html;charset=utf-8 D [23/Dec/2011:15:32:22 +0100] Script header: D [23/Dec/2011:15:32:22 +0100] [Job 18] STATE: +connecting-to-device D [23/Dec/2011:15:32:22 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:22 +0100] cupsdReadClient: 13 WAITING Closing on EOF D [23/Dec/2011:15:32:22 +0100] cupsdCloseClient: 13 D [23/Dec/2011:15:32:22 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients, printing jobs, and dirty files" D [23/Dec/2011:15:32:22 +0100] PID 5943 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [23/Dec/2011:15:32:22 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [23/Dec/2011:15:32:22 +0100] [Job 18] Printer using device file "/dev/usblp0"... D [23/Dec/2011:15:32:22 +0100] [Job 18] STATE: -connecting-to-device D [23/Dec/2011:15:32:22 +0100] [Job 18] backendRunLoop(print_fd=0, device_fd=5, snmp_fd=-1, addr=(nil), use_bc=0, side_cb=0x7f9a81dcfaf0) D [23/Dec/2011:15:32:22 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:22 +0100] [Job 18] load_banner(filename="/var/spool/cups/d00018-001") D [23/Dec/2011:15:32:22 +0100] [Job 18] 1 #CUPS-BANNER D [23/Dec/2011:15:32:22 +0100] [Job 18] 2 Show printer-name printer-info printer-location printer-make-and-model printer-driver-name printer-driver-version paper-size imageable-area D [23/Dec/2011:15:32:22 +0100] [Job 18] 3 Header Printer Test Page D [23/Dec/2011:15:32:22 +0100] [Job 18] 4 Footer Printer Test Page D [23/Dec/2011:15:32:22 +0100] [Job 18] 5 Notice CUPS 1.5.0. D [23/Dec/2011:15:32:22 +0100] [Job 18] 6 Image images/cups.png D [23/Dec/2011:15:32:22 +0100] [Job 18] 7 Image images/color-wheel.png D [23/Dec/2011:15:32:22 +0100] [Job 18] Page = 595x842; 10,15 to 585,827 E [23/Dec/2011:15:32:22 +0100] [Job 18] Unable to open print file: No such file or directory D [23/Dec/2011:15:32:22 +0100] [Job 18] Set job-printer-state-message to "Unable to open print file: No such file or directory", current level=ERROR D [23/Dec/2011:15:32:22 +0100] [Job 18] Unable to open "/usr/share/cups/fonts/Monospace". E [23/Dec/2011:15:32:22 +0100] [Job 18] Unable to open print file: No such file or directory D [23/Dec/2011:15:32:22 +0100] [Job 18] Unable to open "/usr/share/cups/fonts/Monospace-Bold". D [23/Dec/2011:15:32:22 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:22 +0100] Discarding unused printer-state-changed event... E [23/Dec/2011:15:32:22 +0100] [Job 18] Unable to open print file: No such file or directory D [23/Dec/2011:15:32:22 +0100] [Job 18] Unable to open "/usr/share/cups/fonts/Monospace-BoldOblique". E [23/Dec/2011:15:32:22 +0100] [Job 18] Unable to open print file: No such file or directory D [23/Dec/2011:15:32:22 +0100] [Job 18] Unable to open "/usr/share/cups/fonts/Monospace-Oblique". D [23/Dec/2011:15:32:22 +0100] [Job 18] Page = 595x842; 10,15 to 585,827 D [23/Dec/2011:15:32:22 +0100] [Job 18] slow_collate=0, slow_duplex=0, slow_order=0 D [23/Dec/2011:15:32:22 +0100] [Job 18] Before copy_comments - %!PS-Adobe-3.0 D [23/Dec/2011:15:32:22 +0100] [Job 18] %!PS-Adobe-3.0 D [23/Dec/2011:15:32:22 +0100] [Job 18] %%BoundingBox: 10 15 585 827 D [23/Dec/2011:15:32:22 +0100] [Job 18] %cupsRotation: 0 D [23/Dec/2011:15:32:22 +0100] [Job 18] %%Creator: bannertops/CUPS v1.5.0 D [23/Dec/2011:15:32:22 +0100] [Job 18] %%CreationDate: Fri 23 Dec 2011 15:32:22 CET D [23/Dec/2011:15:32:22 +0100] [Job 18] %%LanguageLevel: 2 D [23/Dec/2011:15:32:22 +0100] [Job 18] %%DocumentData: Clean7Bit D [23/Dec/2011:15:32:22 +0100] [Job 18] %%Title: (Test Page) D [23/Dec/2011:15:32:22 +0100] [Job 18] %%For: (anonymous) D [23/Dec/2011:15:32:22 +0100] [Job 18] %%Pages: 1 D [23/Dec/2011:15:32:22 +0100] [Job 18] %%DocumentSuppliedResources: font Monospace D [23/Dec/2011:15:32:22 +0100] [Job 18] %%+ font Monospace-Bold D [23/Dec/2011:15:32:22 +0100] [Job 18] %%+ font Monospace-BoldOblique D [23/Dec/2011:15:32:22 +0100] [Job 18] %%+ font Monospace-Oblique D [23/Dec/2011:15:32:22 +0100] [Job 18] %%EndComments D [23/Dec/2011:15:32:22 +0100] [Job 18] Before copy_prolog - %%BeginProlog D [23/Dec/2011:15:32:22 +0100] [Job 18] PNG image: 128x128x8, color_type=6 (RGB+ALPHA) D [23/Dec/2011:15:32:22 +0100] [Job 18] PNG image: 192x128x8, color_type=2 (RGB) D [23/Dec/2011:15:32:22 +0100] [Job 18] Before copy_setup - %%Page: coverpage 1 D [23/Dec/2011:15:32:22 +0100] [Job 18] Before page loop - %%Page: coverpage 1 D [23/Dec/2011:15:32:22 +0100] [Job 18] Copying page 1... D [23/Dec/2011:15:32:22 +0100] [Job 18] pagew = 575.0, pagel = 812.0 D [23/Dec/2011:15:32:22 +0100] [Job 18] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842 D [23/Dec/2011:15:32:22 +0100] [Job 18] PageLeft = 10.0, PageRight = 585.0 D [23/Dec/2011:15:32:22 +0100] [Job 18] PageTop = 827.0, PageBottom = 15.0 D [23/Dec/2011:15:32:22 +0100] [Job 18] PageWidth = 595.0, PageLength = 842.0 D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint 5.2.7 Starting D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint command line: ip4700 '18' 'anonymous' 'Test Page' '1' <args> D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint using PPD file /etc/cups/ppd/ip4700.ppd D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: CUPS option count is 5 (164 bytes) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: CUPS option 0 AP_D_InputSlot = D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: CUPS option 1 job-originating-host-name = localhost D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: CUPS option 2 job-uuid = urn:uuid:6bd5db13-76ee-32bf-561d-a41fb6664818 D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: CUPS option 3 time-at-creation = 1324650742 D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: CUPS option 4 time-at-processing = 1324650742 D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Driver Canon PIXMA iP4700 D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Using fd 0 D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Set options: D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting PageSize to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting MediaType to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting InputSlot to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CDInnerRadius to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CDOuterDiameter to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CDInnerDiameter to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CDXAdjustment to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CDYAdjustment to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting Resolution to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Set string InkType to CMYK D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Set special string InkType to CMYK D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting InkChannels to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting PrintingMode to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting Duplex to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Set string Quality to None D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Set special string Quality to None D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Set string ColorCorrection to None D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Set special string ColorCorrection to None D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting ChannelBitDepth to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting InputImageType to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting STPIOutputType to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting STPIRawChannels to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting SimpleGamma to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Set bool LinearContrast to False (0) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting LUTDumpFile to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CyanCurve to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting MagentaCurve to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting YellowCurve to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting BlackCurve to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting RedCurve to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting GreenCurve to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting BlueCurve to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting WhiteCurve to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting HueMap to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting SatMap to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting LumMap to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting GCRCurve to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh0 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh1 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh2 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh3 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh4 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh5 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh6 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh7 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh8 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh9 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh10 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh11 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh12 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh13 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh14 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh15 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh16 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh17 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh18 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh19 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh20 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh21 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh22 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh23 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh24 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh25 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh26 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh27 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh28 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh29 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh30 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting CurveCh31 to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Set string DitherAlgorithm to None D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Set special string DitherAlgorithm to None D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Set string ImageType to TextGraphics D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Set special string ImageType to TextGraphics D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting JobMode to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: Not setting PageNumber to (null) D [23/Dec/2011:15:32:22 +0100] [Job 18] Gutenprint: End options I [23/Dec/2011:15:32:23 +0100] [Job 18] Starting GPL Ghostscript 9.00... D [23/Dec/2011:15:32:23 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:23 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:23 +0100] [Job 18] Running /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dDisableFAPI=true -dNOMEDIAATTRS -sDEVICE=cups -sstdout=%stderr -sOUTPUTFILE=%stdout -c -f -_ I [23/Dec/2011:15:32:23 +0100] [Job 18] Start rendering... I [23/Dec/2011:15:32:23 +0100] [Job 18] Processing page 1... D [23/Dec/2011:15:32:23 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:23 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] PID 5944 (/usr/lib/cups/filter/bannertops) exited with no errors. D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 1 pages... D [23/Dec/2011:15:32:24 +0100] PID 5945 (/usr/lib/cups/filter/pstops) exited with no errors. D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: About to start printing loop. D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Printing page 1 D [23/Dec/2011:15:32:24 +0100] [Job 18] PAGE: 1 1 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Initialize page D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Set special string ChannelBitDepth to 8 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Set special string PrintingMode to Color D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Set special string InputImageType to CMY D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Clear special parameter Resolution D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Set special parameter Quality to choice 0 (None) D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Set special string MediaType to Plain D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: PageSize = 595x842 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Using page size A4 with (842, 595) D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Set special string PageSize to A4 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Set special string JobMode to Job D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Validating options D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Clearing string CDInnerRadius ((null)) D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Clearing string Resolution ((null)) D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Setting default string Resolution to 600x600dpi_photodraft3 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Clearing string STPIOutputType ((null)) D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Setting default string STPIOutputType to (null) D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Done validating options D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: limits w 595 l 10 r 585 h 842 t 15 b 827 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: max limits l 10 r 585 t 15 b 827 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Adjusting left margin from 10 to 10 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Adjusting right margin from 585 to 585 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Adjusting top margin from 15 to 15 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Adjusting bottom margin from 827 to 827 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: CUPS settings w 4791 (4791) l 83 r 83 h 6766 (6766) t 125 b 125 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: End initialize page D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Page data: D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: MediaClass = "" D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: MediaColor = "" D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: MediaType = "Plain" D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: OutputType = "" D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: AdvanceDistance = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: AdvanceMedia = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Collate = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: CutMedia = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Duplex = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: HWResolution = [ 600 600 ] D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: ImagingBoundingBox = [ 10 15 585 827 ] D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: InsertSheet = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Jog = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: LeadingEdge = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Margins = [ 10 15 ] D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: ManualFeed = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: MediaPosition = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: MediaWeight = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: MirrorPrint = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: NegativePrint = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: NumCopies = 1 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Orientation = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: OutputFaceUp = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: PageSize = [ 595 842 ] D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Separations = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: TraySwitch = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Tumble = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: cupsWidth = 4792 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: cupsHeight = 6767 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: cups->width = 4791 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: cups->height = 6766 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: cups->adjusted_width = 4791 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: cups->adjusted_height = 6766 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: cupsMediaType = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: cupsBitsPerColor = 8 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: cupsBitsPerPixel = 24 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: cupsBytesPerLine = 14376 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: cupsColorOrder = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: cupsColorSpace = 4 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: cupsCompression = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: cupsRowCount = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: cupsRowFeed = 1 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: cupsRowStep = 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_driver(v) |bjc-PIXMA-iP4700| D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_left(v) 10 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_top(v) 15 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_page_width(v) 595 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_page_height(v) 842 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: shrink page to fit 1 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_string PageSize(v) |A4| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_string MediaType(v) |Plain| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_string InputSlot(v) |AutoSwitch| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_string CDInnerRadius(v) |NULL| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_dimension CDOuterDiameter(v) |329| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_dimension CDInnerDiameter(v) |121| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_dimension CDXAdjustment(v) |0| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_dimension CDYAdjustment(v) |0| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_string Resolution(v) |600x600dpi_photodraft3| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_string InkType(v) |CMYK| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_int InkChannels(v) |4| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_string PrintingMode(v) |Color| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_string Duplex(v) |None| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_string Quality(v) |None| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float CyanDensity(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float MagentaDensity(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float YellowDensity(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float BlackDensity(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float LightCyanTrans(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float LightMagentaTrans(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float LightYellowTrans(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_string ColorCorrection(v) |None| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_string ChannelBitDepth(v) |8| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_string InputImageType(v) |CMY| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_string STPIOutputType(v) |NULL| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_int STPIRawChannels(v) |1| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_boolean SimpleGamma(v) |0| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float Brightness(v) |0.700| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float Contrast(v) |1.700| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_boolean LinearContrast(v) |0| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float Gamma(v) |1.111| 1 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float AppGamma(v) |1.000| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float CyanGamma(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float MagentaGamma(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float YellowGamma(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float RedGamma(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GreenGamma(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float BlueGamma(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float BlackGamma(v) |0.600| 1 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float CyanBalance(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float MagentaBalance(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float YellowBalance(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float Saturation(v) |1.000| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float InkLimit(v) |4.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float BlackTrans(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GCRLower(v) |0.200| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GCRUpper(v) |0.500| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh0(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh1(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh2(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh3(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh4(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh5(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh6(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh7(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh8(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh9(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh10(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh11(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh12(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh13(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh14(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh15(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh16(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh17(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh18(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh19(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh20(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh21(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh22(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh23(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh24(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh25(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh26(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh27(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh28(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh29(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh30(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float GammaCh31(v) |1.000| 0 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_float Density(v) |0.600| 1 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_string DitherAlgorithm(v) |None| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_string ImageType(v) |TextGraphics| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_string JobMode(v) |Job| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: stp_get_int PageNumber(v) |0| 2 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: End page data I [23/Dec/2011:15:32:24 +0100] [Job 18] Starting page 1... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Image_height 6766 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Image_width 4791 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Image_width 4791 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Image_width 4791 D [23/Dec/2011:15:32:24 +0100] [Job 18] Gutenprint: Image_width 4791 I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 1% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=1 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 2% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=2 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] STATE: -media-empty-warning D [23/Dec/2011:15:32:24 +0100] [Job 18] STATE: -offline-report I [23/Dec/2011:15:32:24 +0100] [Job 18] Printer is now connected. D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 3% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=3 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] cupsdReadClient: 12 GET /printers/ip4700 HTTP/1.1 D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Dirty files" D [23/Dec/2011:15:32:24 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:24 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@snoopy" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[13] = "USER=root" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[16] = "IPP_PORT=631" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[17] = "LANG=en_GB.UTF8" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[18] = "REDIRECT_STATUS=1" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[20] = "SERVER_NAME=localhost" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[21] = "SERVER_PORT=631" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[23] = "REMOTE_HOST=localhost" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[24] = "SCRIPT_NAME=/printers/ip4700" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/printers/ip4700" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[26] = "PATH_INFO=/ip4700" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[28] = "HTTP_COOKIE=org.cups.sid=df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:9.0) Gecko/20100101 Firefox/9.0" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[30] = "REQUEST_METHOD=GET" D [23/Dec/2011:15:32:24 +0100] [CGI] envp[31] = "QUERY_STRING=" D [23/Dec/2011:15:32:24 +0100] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 5951) I [23/Dec/2011:15:32:24 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5951) D [23/Dec/2011:15:32:24 +0100] cupsdSendCommand: 12 file=13 D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [CGI] org.cups.sid cookie is "df7195b5291b83941fc2707b836b1adc" I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 4% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=4 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] cupsdAcceptClient: 14 from localhost (Domain) D [23/Dec/2011:15:32:24 +0100] cupsdReadClient: 14 POST / HTTP/1.1 D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [23/Dec/2011:15:32:24 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:24 +0100] cupsdReadClient: 14 1.1 CUPS-Get-Default 1 D [23/Dec/2011:15:32:24 +0100] CUPS-Get-Default D [23/Dec/2011:15:32:24 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [23/Dec/2011:15:32:24 +0100] [CGI] show_printer(http=0x7ff7ea526e40, printer="ip4700") D [23/Dec/2011:15:32:24 +0100] cupsdReadClient: 14 POST / HTTP/1.1 D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [23/Dec/2011:15:32:24 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:24 +0100] cupsdReadClient: 14 1.1 Get-Printer-Attributes 1 D [23/Dec/2011:15:32:24 +0100] Get-Printer-Attributes ipp://localhost/printers/ip4700 D [23/Dec/2011:15:32:24 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/ip4700) from localhost D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [23/Dec/2011:15:32:24 +0100] Script header: Content-Type: text/html;charset=utf-8 D [23/Dec/2011:15:32:24 +0100] Script header: D [23/Dec/2011:15:32:24 +0100] [CGI] Regular expression ".*Clean.*" D [23/Dec/2011:15:32:24 +0100] [CGI] Regular expression ".*PrintSelfTestPage.*" D [23/Dec/2011:15:32:24 +0100] cupsdReadClient: 14 POST / HTTP/1.1 D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [23/Dec/2011:15:32:24 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:24 +0100] cupsdReadClient: 14 1.1 Get-Jobs 1 D [23/Dec/2011:15:32:24 +0100] Get-Jobs ipp://localhost:631/printers/ip4700 D [23/Dec/2011:15:32:24 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/ip4700) from localhost D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [23/Dec/2011:15:32:24 +0100] cupsdReadClient: 14 WAITING Closing on EOF D [23/Dec/2011:15:32:24 +0100] cupsdCloseClient: 14 D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files" D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [23/Dec/2011:15:32:24 +0100] PID 5951 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 5% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=5 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] cupsdReadClient: 12 GET /cups.css HTTP/1.1 D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [23/Dec/2011:15:32:24 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 6% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=6 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] cupsdAcceptClient: 13 from localhost:631 (IPv4) D [23/Dec/2011:15:32:24 +0100] cupsdAcceptClient: 14 from localhost:631 (IPv4) D [23/Dec/2011:15:32:24 +0100] cupsdReadClient: 13 GET /images/left.gif HTTP/1.1 D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [23/Dec/2011:15:32:24 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 7% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=7 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] cupsdAcceptClient: 17 from localhost:631 (IPv4) D [23/Dec/2011:15:32:24 +0100] cupsdReadClient: 14 GET /images/right.gif HTTP/1.1 D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [23/Dec/2011:15:32:24 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [23/Dec/2011:15:32:24 +0100] cupsdReadClient: 12 GET /images/unsel.gif HTTP/1.1 D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [23/Dec/2011:15:32:24 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] cupsdReadClient: 17 GET /images/sel.gif HTTP/1.1 D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files" D [23/Dec/2011:15:32:24 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:24 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files" I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 8% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=8 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 9% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=9 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 10% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=10 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 11% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=11 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 12% D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=12 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 13% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=13 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 14% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=14 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 15% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=15 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 16% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=16 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 17% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=17 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 18% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=18 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 19% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=19 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 20% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=20 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 21% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=21 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 22% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=22 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 23% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=23 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 24% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=24 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 25% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=25 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 26% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=26 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 27% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=27 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 28% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=28 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 29% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=29 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 30% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=30 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 31% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=31 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 32% D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=32 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 33% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=33 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 34% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=34 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 35% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=35 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 4096 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 36% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=36 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 37% D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=37 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 38% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=38 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 39% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=39 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 40% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=40 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 41% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=41 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 42% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=42 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 43% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=43 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 44% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=44 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 45% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=45 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 46% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=46 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 47% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=47 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 48% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=48 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 49% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=49 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:24 +0100] [Job 18] Printing page 1, 50% D [23/Dec/2011:15:32:24 +0100] [Job 18] ATTR: job-media-progress=50 D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:24 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:24 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:24 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 51% D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=51 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 52% D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=52 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 53% D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=53 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 54% D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=54 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 55% D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=55 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 56% D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=56 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 57% D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=57 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 58% D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=58 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 59% D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=59 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 60% D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=60 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 61% D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=61 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 62% D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=62 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 63% D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=63 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 64% D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=64 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 65% D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=65 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 66% D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=66 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 67% D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=67 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 68% D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=68 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:25 +0100] [Job 18] Printing page 1, 69% D [23/Dec/2011:15:32:25 +0100] [Job 18] ATTR: job-media-progress=69 D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:25 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:25 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 70% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=70 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 71% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=71 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 72% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=72 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 73% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=73 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 74% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=74 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 75% D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=75 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 76% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=76 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 77% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=77 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 78% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=78 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 79% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=79 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 80% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=80 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 81% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=81 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 82% D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=82 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 83% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=83 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 84% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=84 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 85% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=85 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 86% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=86 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 87% D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=87 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 88% D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=88 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 89% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=89 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 90% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=90 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 91% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=91 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 92% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=92 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 93% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=93 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 94% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=94 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 95% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=95 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 96% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=96 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 4096 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 97% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=97 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 4096 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 98% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=98 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Printing page 1, 99% D [23/Dec/2011:15:32:26 +0100] [Job 18] ATTR: job-media-progress=99 D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:26 +0100] [Job 18] Processing page 2... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... I [23/Dec/2011:15:32:26 +0100] [Job 18] Finished page 1... D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Gutenprint: Purging 1 rows D [23/Dec/2011:15:32:26 +0100] [Job 18] Gutenprint: Done printing page 1 D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 8192 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 1633 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 1633 bytes of print data... I [23/Dec/2011:15:32:26 +0100] [Job 18] Rendering completed D [23/Dec/2011:15:32:26 +0100] Discarding unused job-progress event... D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:26 +0100] [Job 18] Gutenprint: Ending job D [23/Dec/2011:15:32:26 +0100] [Job 18] Read 265 bytes of print data... D [23/Dec/2011:15:32:26 +0100] [Job 18] Gutenprint: Printed total 874346 bytes D [23/Dec/2011:15:32:26 +0100] [Job 18] Gutenprint: Used 1.060 seconds user, 0.120 seconds system, 3.791 seconds elapsed D [23/Dec/2011:15:32:26 +0100] [Job 18] Wrote 265 bytes of print data... D [23/Dec/2011:15:32:26 +0100] PID 5947 (/usr/lib/cups/filter/rastertogutenprint.5.2) exited with no errors. D [23/Dec/2011:15:32:26 +0100] PID 5948 (/usr/lib/cups/backend/usb) exited with no errors. D [23/Dec/2011:15:32:26 +0100] PID 5946 (/usr/lib/cups/filter/pstoraster) exited with no errors. D [23/Dec/2011:15:32:26 +0100] Discarding unused job-completed event... I [23/Dec/2011:15:32:26 +0100] [Job 18] Job completed. D [23/Dec/2011:15:32:26 +0100] cupsdMarkDirty(----J-) D [23/Dec/2011:15:32:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [23/Dec/2011:15:32:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [23/Dec/2011:15:32:26 +0100] Discarding unused printer-state-changed event... D [23/Dec/2011:15:32:27 +0100] [Job 18] Unloading... D [23/Dec/2011:15:32:34 +0100] cupsdReadClient: 12 GET /printers/ip4700 HTTP/1.1 D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files" D [23/Dec/2011:15:32:34 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:34 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@snoopy" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[13] = "USER=root" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[16] = "IPP_PORT=631" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[17] = "LANG=en_GB.UTF8" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[18] = "REDIRECT_STATUS=1" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[20] = "SERVER_NAME=localhost" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[21] = "SERVER_PORT=631" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[23] = "REMOTE_HOST=localhost" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[24] = "SCRIPT_NAME=/printers/ip4700" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/printers/ip4700" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[26] = "PATH_INFO=/ip4700" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[28] = "HTTP_COOKIE=org.cups.sid=df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:9.0) Gecko/20100101 Firefox/9.0" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[30] = "REQUEST_METHOD=GET" D [23/Dec/2011:15:32:34 +0100] [CGI] envp[31] = "QUERY_STRING=" D [23/Dec/2011:15:32:34 +0100] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 5953) I [23/Dec/2011:15:32:34 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5953) D [23/Dec/2011:15:32:34 +0100] cupsdSendCommand: 12 file=16 D [23/Dec/2011:15:32:34 +0100] [CGI] org.cups.sid cookie is "df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:32:34 +0100] cupsdAcceptClient: 18 from localhost (Domain) D [23/Dec/2011:15:32:34 +0100] cupsdReadClient: 18 POST / HTTP/1.1 D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [23/Dec/2011:15:32:34 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:34 +0100] cupsdReadClient: 18 1.1 CUPS-Get-Default 1 D [23/Dec/2011:15:32:34 +0100] CUPS-Get-Default D [23/Dec/2011:15:32:34 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [23/Dec/2011:15:32:34 +0100] [CGI] show_printer(http=0x7eff9e99ce40, printer="ip4700") D [23/Dec/2011:15:32:34 +0100] cupsdReadClient: 18 POST / HTTP/1.1 D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [23/Dec/2011:15:32:34 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:34 +0100] cupsdReadClient: 18 1.1 Get-Printer-Attributes 1 D [23/Dec/2011:15:32:34 +0100] Get-Printer-Attributes ipp://localhost/printers/ip4700 D [23/Dec/2011:15:32:34 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/ip4700) from localhost D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [23/Dec/2011:15:32:34 +0100] cupsdReadClient: 18 POST / HTTP/1.1 D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [23/Dec/2011:15:32:34 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:34 +0100] Script header: Content-Type: text/html;charset=utf-8 D [23/Dec/2011:15:32:34 +0100] Script header: D [23/Dec/2011:15:32:34 +0100] [CGI] Regular expression ".*Clean.*" D [23/Dec/2011:15:32:34 +0100] [CGI] Regular expression ".*PrintSelfTestPage.*" D [23/Dec/2011:15:32:34 +0100] cupsdReadClient: 18 1.1 Get-Jobs 1 D [23/Dec/2011:15:32:34 +0100] Get-Jobs ipp://localhost:631/printers/ip4700 D [23/Dec/2011:15:32:34 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/ip4700) from localhost D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [23/Dec/2011:15:32:34 +0100] cupsdReadClient: 18 WAITING Closing on EOF D [23/Dec/2011:15:32:34 +0100] cupsdCloseClient: 18 D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [23/Dec/2011:15:32:34 +0100] PID 5953 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [23/Dec/2011:15:32:34 +0100] cupsdReadClient: 12 GET /cups.css HTTP/1.1 D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [23/Dec/2011:15:32:34 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [23/Dec/2011:15:32:34 +0100] cupsdReadClient: 12 GET /images/left.gif HTTP/1.1 D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [23/Dec/2011:15:32:34 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [23/Dec/2011:15:32:34 +0100] cupsdReadClient: 13 GET /images/right.gif HTTP/1.1 D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [23/Dec/2011:15:32:34 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [23/Dec/2011:15:32:34 +0100] cupsdReadClient: 12 GET /images/unsel.gif HTTP/1.1 D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [23/Dec/2011:15:32:34 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [23/Dec/2011:15:32:34 +0100] cupsdReadClient: 14 GET /images/sel.gif HTTP/1.1 D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [23/Dec/2011:15:32:34 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:32:34 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" I [23/Dec/2011:15:32:53 +0100] Saving job.cache... D [23/Dec/2011:15:32:53 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files" D [23/Dec/2011:15:32:54 +0100] cupsdReadClient: 17 WAITING Closing on EOF D [23/Dec/2011:15:32:54 +0100] cupsdCloseClient: 17 D [23/Dec/2011:15:32:54 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [23/Dec/2011:15:32:55 +0100] Report: clients=3 D [23/Dec/2011:15:32:55 +0100] Report: jobs=3 D [23/Dec/2011:15:32:55 +0100] Report: jobs-active=0 D [23/Dec/2011:15:32:55 +0100] Report: printers=1 D [23/Dec/2011:15:32:55 +0100] Report: printers-implicit=0 D [23/Dec/2011:15:32:55 +0100] Report: stringpool-string-count=99537 D [23/Dec/2011:15:32:55 +0100] Report: stringpool-alloc-bytes=13952 D [23/Dec/2011:15:32:55 +0100] Report: stringpool-total-bytes=1838512 D [23/Dec/2011:15:33:04 +0100] cupsdReadClient: 13 WAITING Closing on EOF D [23/Dec/2011:15:33:04 +0100] cupsdCloseClient: 13 D [23/Dec/2011:15:33:04 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [23/Dec/2011:15:33:04 +0100] cupsdReadClient: 14 WAITING Closing on EOF D [23/Dec/2011:15:33:04 +0100] cupsdCloseClient: 14 D [23/Dec/2011:15:33:04 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [23/Dec/2011:15:33:04 +0100] cupsdReadClient: 12 WAITING Closing on EOF D [23/Dec/2011:15:33:04 +0100] cupsdCloseClient: 12 D [23/Dec/2011:15:33:04 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [23/Dec/2011:15:35:54 +0100] cupsdAcceptClient: 12 from localhost:631 (IPv4) D [23/Dec/2011:15:35:54 +0100] Report: clients=1 D [23/Dec/2011:15:35:54 +0100] Report: jobs=3 D [23/Dec/2011:15:35:54 +0100] Report: jobs-active=0 D [23/Dec/2011:15:35:54 +0100] Report: printers=1 D [23/Dec/2011:15:35:54 +0100] Report: printers-implicit=0 D [23/Dec/2011:15:35:54 +0100] Report: stringpool-string-count=99537 D [23/Dec/2011:15:35:54 +0100] Report: stringpool-alloc-bytes=13952 D [23/Dec/2011:15:35:54 +0100] Report: stringpool-total-bytes=1838512 D [23/Dec/2011:15:35:54 +0100] cupsdReadClient: 12 GET /jobs/ HTTP/1.1 D [23/Dec/2011:15:35:54 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [23/Dec/2011:15:35:54 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:35:54 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/jobs.cgi" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@snoopy" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[13] = "USER=root" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[16] = "IPP_PORT=631" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[17] = "LANG=en_GB.UTF8" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[18] = "REDIRECT_STATUS=1" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[20] = "SERVER_NAME=localhost" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[21] = "SERVER_PORT=631" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[23] = "REMOTE_HOST=localhost" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[24] = "SCRIPT_NAME=/jobs/" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/jobs/" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[26] = "SERVER_PROTOCOL=HTTP/1.1" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[27] = "HTTP_COOKIE=org.cups.sid=df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:9.0) Gecko/20100101 Firefox/9.0" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[29] = "HTTP_REFERER=http://localhost:631/printers/ip4700" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[30] = "REQUEST_METHOD=GET" D [23/Dec/2011:15:35:54 +0100] [CGI] envp[31] = "QUERY_STRING=" D [23/Dec/2011:15:35:54 +0100] [CGI] Started /usr/lib/cups/cgi-bin/jobs.cgi (PID 6205) I [23/Dec/2011:15:35:54 +0100] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=6205) D [23/Dec/2011:15:35:54 +0100] cupsdSendCommand: 12 file=13 D [23/Dec/2011:15:35:54 +0100] [CGI] org.cups.sid cookie is "df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:35:54 +0100] cupsdAcceptClient: 14 from localhost (Domain) D [23/Dec/2011:15:35:54 +0100] Script header: Content-Type: text/html;charset=utf-8 D [23/Dec/2011:15:35:54 +0100] Script header: D [23/Dec/2011:15:35:54 +0100] cupsdReadClient: 14 POST / HTTP/1.1 D [23/Dec/2011:15:35:54 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:35:54 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:35:54 +0100] cupsdReadClient: 14 1.1 Get-Jobs 1 D [23/Dec/2011:15:35:54 +0100] Get-Jobs ipp://localhost/ D [23/Dec/2011:15:35:54 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from localhost D [23/Dec/2011:15:35:54 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:35:54 +0100] cupsdReadClient: 14 WAITING Closing on EOF D [23/Dec/2011:15:35:54 +0100] cupsdCloseClient: 14 D [23/Dec/2011:15:35:54 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:35:54 +0100] PID 6205 (/usr/lib/cups/cgi-bin/jobs.cgi) exited with no errors. D [23/Dec/2011:15:35:54 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [23/Dec/2011:15:35:55 +0100] cupsdReadClient: 12 GET /printers/ HTTP/1.1 D [23/Dec/2011:15:35:55 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [23/Dec/2011:15:35:55 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:35:55 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@snoopy" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[13] = "USER=root" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[16] = "IPP_PORT=631" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[17] = "LANG=en_GB.UTF8" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[18] = "REDIRECT_STATUS=1" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[20] = "SERVER_NAME=localhost" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[21] = "SERVER_PORT=631" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[23] = "REMOTE_HOST=localhost" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[24] = "SCRIPT_NAME=/printers/" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/printers/" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[26] = "SERVER_PROTOCOL=HTTP/1.1" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[27] = "HTTP_COOKIE=org.cups.sid=df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:9.0) Gecko/20100101 Firefox/9.0" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[29] = "HTTP_REFERER=http://localhost:631/jobs/" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[30] = "REQUEST_METHOD=GET" D [23/Dec/2011:15:35:55 +0100] [CGI] envp[31] = "QUERY_STRING=" D [23/Dec/2011:15:35:55 +0100] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 6207) I [23/Dec/2011:15:35:55 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=6207) D [23/Dec/2011:15:35:55 +0100] cupsdSendCommand: 12 file=13 D [23/Dec/2011:15:35:55 +0100] [CGI] org.cups.sid cookie is "df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:35:55 +0100] cupsdAcceptClient: 14 from localhost (Domain) D [23/Dec/2011:15:35:55 +0100] cupsdReadClient: 14 POST / HTTP/1.1 D [23/Dec/2011:15:35:55 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:35:55 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:35:55 +0100] cupsdReadClient: 14 1.1 CUPS-Get-Default 1 D [23/Dec/2011:15:35:55 +0100] CUPS-Get-Default D [23/Dec/2011:15:35:55 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [23/Dec/2011:15:35:55 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:35:55 +0100] [CGI] show_all_printers(http=0x7fabedef9de0, user="(null)") D [23/Dec/2011:15:35:55 +0100] Script header: Content-Type: text/html;charset=utf-8 D [23/Dec/2011:15:35:55 +0100] Script header: D [23/Dec/2011:15:35:55 +0100] cupsdReadClient: 14 POST / HTTP/1.1 D [23/Dec/2011:15:35:55 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:35:55 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:35:55 +0100] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1 D [23/Dec/2011:15:35:55 +0100] CUPS-Get-Printers D [23/Dec/2011:15:35:55 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D [23/Dec/2011:15:35:55 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:35:55 +0100] cupsdReadClient: 14 WAITING Closing on EOF D [23/Dec/2011:15:35:55 +0100] cupsdCloseClient: 14 D [23/Dec/2011:15:35:55 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:35:55 +0100] PID 6207 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [23/Dec/2011:15:35:55 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [23/Dec/2011:15:36:05 +0100] cupsdReadClient: 12 GET /jobs/ HTTP/1.1 D [23/Dec/2011:15:36:05 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [23/Dec/2011:15:36:05 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:36:05 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/jobs.cgi" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@snoopy" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[13] = "USER=root" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[16] = "IPP_PORT=631" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[17] = "LANG=en_GB.UTF8" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[18] = "REDIRECT_STATUS=1" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[20] = "SERVER_NAME=localhost" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[21] = "SERVER_PORT=631" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[23] = "REMOTE_HOST=localhost" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[24] = "SCRIPT_NAME=/jobs/" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/jobs/" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[26] = "SERVER_PROTOCOL=HTTP/1.1" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[27] = "HTTP_COOKIE=org.cups.sid=df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:9.0) Gecko/20100101 Firefox/9.0" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[29] = "HTTP_REFERER=http://localhost:631/printers/" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[30] = "REQUEST_METHOD=GET" D [23/Dec/2011:15:36:05 +0100] [CGI] envp[31] = "QUERY_STRING=" D [23/Dec/2011:15:36:05 +0100] [CGI] Started /usr/lib/cups/cgi-bin/jobs.cgi (PID 6208) I [23/Dec/2011:15:36:05 +0100] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=6208) D [23/Dec/2011:15:36:05 +0100] cupsdSendCommand: 12 file=13 D [23/Dec/2011:15:36:05 +0100] [CGI] org.cups.sid cookie is "df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:36:05 +0100] cupsdAcceptClient: 14 from localhost (Domain) D [23/Dec/2011:15:36:05 +0100] Script header: Content-Type: text/html;charset=utf-8 D [23/Dec/2011:15:36:05 +0100] Script header: D [23/Dec/2011:15:36:05 +0100] cupsdReadClient: 14 POST / HTTP/1.1 D [23/Dec/2011:15:36:05 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:36:05 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:36:05 +0100] cupsdReadClient: 14 1.1 Get-Jobs 1 D [23/Dec/2011:15:36:05 +0100] Get-Jobs ipp://localhost/ D [23/Dec/2011:15:36:05 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from localhost D [23/Dec/2011:15:36:05 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:36:05 +0100] cupsdReadClient: 14 WAITING Closing on EOF D [23/Dec/2011:15:36:05 +0100] cupsdCloseClient: 14 D [23/Dec/2011:15:36:05 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:36:05 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [23/Dec/2011:15:36:05 +0100] PID 6208 (/usr/lib/cups/cgi-bin/jobs.cgi) exited with no errors. D [23/Dec/2011:15:36:10 +0100] cupsdReadClient: 12 GET /admin HTTP/1.1 D [23/Dec/2011:15:36:10 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [23/Dec/2011:15:36:10 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:36:10 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/webcontent" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@snoopy" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[13] = "USER=root" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[16] = "IPP_PORT=631" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[17] = "LANG=en_GB.UTF8" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[18] = "REDIRECT_STATUS=1" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[20] = "SERVER_NAME=localhost" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[21] = "SERVER_PORT=631" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[22] = "REMOTE_ADDR=127.0.0.1" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[23] = "REMOTE_HOST=localhost" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[24] = "SCRIPT_NAME=/admin" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/webcontent/admin" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[26] = "SERVER_PROTOCOL=HTTP/1.1" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[27] = "HTTP_COOKIE=org.cups.sid=df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[28] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:9.0) Gecko/20100101 Firefox/9.0" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[29] = "HTTP_REFERER=http://localhost:631/jobs/" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[30] = "REQUEST_METHOD=GET" D [23/Dec/2011:15:36:10 +0100] [CGI] envp[31] = "QUERY_STRING=" D [23/Dec/2011:15:36:10 +0100] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 6209) I [23/Dec/2011:15:36:10 +0100] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=6209) D [23/Dec/2011:15:36:10 +0100] cupsdSendCommand: 12 file=13 D [23/Dec/2011:15:36:10 +0100] [CGI] admin.cgi started... D [23/Dec/2011:15:36:10 +0100] cupsdAcceptClient: 14 from localhost (Domain) D [23/Dec/2011:15:36:10 +0100] [CGI] http=0x7f8ce69d6220 D [23/Dec/2011:15:36:10 +0100] [CGI] org.cups.sid cookie is "df7195b5291b83941fc2707b836b1adc" D [23/Dec/2011:15:36:10 +0100] [CGI] No form data, showing main menu... D [23/Dec/2011:15:36:10 +0100] [CGI] /usr/share/cups/drivers/pscript5.dll: No such file or directory D [23/Dec/2011:15:36:10 +0100] cupsdReadClient: 14 POST / HTTP/1.1 D [23/Dec/2011:15:36:10 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:36:10 +0100] cupsdAuthorize: No authentication data provided. D [23/Dec/2011:15:36:10 +0100] cupsdReadClient: 14 1.1 Get-Subscriptions 1 D [23/Dec/2011:15:36:10 +0100] Get-Subscriptions ipp://localhost/ D [23/Dec/2011:15:36:10 +0100] Get-Subscriptions client-error-not-found: No subscriptions found. D [23/Dec/2011:15:36:10 +0100] Returning IPP client-error-not-found for Get-Subscriptions (ipp://localhost/) from localhost D [23/Dec/2011:15:36:10 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:36:10 +0100] Script header: Content-Type: text/html;charset=utf-8 D [23/Dec/2011:15:36:10 +0100] Script header: D [23/Dec/2011:15:36:10 +0100] cupsdReadClient: 14 WAITING Closing on EOF D [23/Dec/2011:15:36:10 +0100] cupsdCloseClient: 14 D [23/Dec/2011:15:36:10 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [23/Dec/2011:15:36:10 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [23/Dec/2011:15:36:10 +0100] PID 6209 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors. D [23/Dec/2011:15:36:12 +0100] cupsdReadClient: 12 GET /admin/log/error_log HTTP/1.1 D [23/Dec/2011:15:36:12 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [23/Dec/2011:15:36:12 +0100] cupsdAuthorize: No authentication data provided. -- Um die Liste abzubestellen, schicken Sie eine Mail an: opensuse-de+unsubscribe@opensuse.org Um den Listen Administrator zu erreichen, schicken Sie eine Mail an: opensuse-de+owner@opensuse.org