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(a)opensuse.org
Um den Listen Administrator zu erreichen, schicken
Sie eine Mail an: opensuse-de+owner(a)opensuse.org