Arch Linux

robotangel

Member

From: cologne/germany

Registered: 2007-08-30

Posts: 63

Epson SX218 doesn't print (client-error-document-format-not-supported)

Hi there,

I'm currently trying to figure out how to get my Epson Stylus SX 218 to work. Unfortunately, cups (or the printer or whatever causes my problems...) obviously just doesn't want me to get this to work.

Here's what I've done so far:
I installed everything as described in the CUPS wiki article (so ghostscript and all the foomatic-stuff is installed, usblp is NOT blacklisted, see why below)
I installed epson-inkjet-printer-workforce-sx218 from AUR
I started cups...
Nothing worked (Printer not recognized, either with or without blacklisted usblp module...)
I searched the forums for a solution
I installed cups-usblp and reinstalled all the cups stuff
Printer got recognized! Yay!

So, now the printer is set up in cups using the avasys driver. But...

It doesn't print!

When I try to print a test page via the cups webinterface it keeps telling me

Unsupported format "application/vnd.cups-banner".

The following packages are installed now:

$ pacman -Qs cups
local/cups-usblp 1.5.0-1
local/libcups 1.5.0-1
$ pacman -Qs foomatic
local/foomatic-db 1:4.0.7_20110707-1
local/foomatic-db-engine 1:4.0.7_20110707-1
local/foomatic-db-nonfree 1:4.0.7_20110707-1
local/foomatic-filters 1:4.0.7_20110707-1
$ pacman -Qs epson
local/epson-inkjet-printer-workforce-320-sx218 1.0.0-6
$ pacman -Qs ghostscript
local/ghostscript 9.04-5

Here's my cups error log:

I [01/Jan/2012:18:59:01 +0100] Listening to [v1.::1]:631 (IPv6)
I [01/Jan/2012:18:59:01 +0100] Listening to 127.0.0.1:631 (IPv4)
I [01/Jan/2012:18:59:01 +0100] Listening to /var/run/cups/cups.sock (Domain)
I [01/Jan/2012:18:59:01 +0100] Remote access is disabled.
D [01/Jan/2012:18:59:01 +0100] Added auto ServerAlias mamapc
I [01/Jan/2012:18:59:01 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [01/Jan/2012:18:59:01 +0100] Using default TempDir of /var/spool/cups/tmp...
I [01/Jan/2012:18:59:01 +0100] Configured for up to 100 clients.
I [01/Jan/2012:18:59:01 +0100] Allowing up to 100 client connections per host.
I [01/Jan/2012:18:59:01 +0100] Using policy "default" as the default.
D [01/Jan/2012:18:59:01 +0100] cupsdMarkDirty(---p--)
D [01/Jan/2012:18:59:01 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy"
I [01/Jan/2012:18:59:01 +0100] Partial reload complete.
I [01/Jan/2012:18:59:01 +0100] Listening to [v1.::1]:631 on fd 6...
I [01/Jan/2012:18:59:01 +0100] Listening to 127.0.0.1:631 on fd 8...
I [01/Jan/2012:18:59:01 +0100] Listening to /var/run/cups/cups.sock:631 on fd 9...
I [01/Jan/2012:18:59:01 +0100] Resuming new connection processing...
D [01/Jan/2012:18:59:01 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [01/Jan/2012:18:59:01 +0100] Discarding unused server-restarted event...
D [01/Jan/2012:18:59:01 +0100] cupsdAcceptClient: 13 from localhost:631 (IPv4)
D [01/Jan/2012:18:59:01 +0100] Report: clients=1
D [01/Jan/2012:18:59:01 +0100] Report: jobs=0
D [01/Jan/2012:18:59:01 +0100] Report: jobs-active=0
D [01/Jan/2012:18:59:01 +0100] Report: printers=0
D [01/Jan/2012:18:59:01 +0100] Report: printers-implicit=0
D [01/Jan/2012:18:59:01 +0100] Report: stringpool-string-count=407
D [01/Jan/2012:18:59:01 +0100] Report: stringpool-alloc-bytes=4648
D [01/Jan/2012:18:59:01 +0100] Report: stringpool-total-bytes=5736
D [01/Jan/2012:18:59:01 +0100] PID 928 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [01/Jan/2012:18:59:01 +0100] cupsdReadClient: 13 GET /images/wait.gif HTTP/1.1
D [01/Jan/2012:18:59:01 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [01/Jan/2012:18:59:01 +0100] cupsdAuthorize: Authorized as root using Basic
D [01/Jan/2012:18:59:01 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [01/Jan/2012:18:59:06 +0100] cupsdReadClient: 13 GET /admin/?OP=redirect HTTP/1.1
D [01/Jan/2012:18:59:06 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [01/Jan/2012:18:59:06 +0100] cupsdAuthorize: Authorized as root using Basic
D [01/Jan/2012:18:59:06 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi"
D [01/Jan/2012:18:59:06 +0100] [CGI] argv[1] = "OP=redirect"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@mamapc"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[13] = "USER=root"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[16] = "IPP_PORT=631"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[17] = "AUTH_TYPE=Basic"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[18] = "LANG=de_DE.UTF8"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[21] = "SERVER_NAME=localhost"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[22] = "SERVER_PORT=631"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[23] = "REMOTE_ADDR=127.0.0.1"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[25] = "SCRIPT_NAME=/admin/"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc/admin/"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[27] = "REMOTE_USER=root"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[29] = "HTTP_COOKIE=org.cups.sid=960956618ce3e170434dd2afacd4fad3"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[30] = "HTTP_USER_AGENT=Opera/9.80 (X11; Linux i686; U; de) Presto/2.10.229 Version/11.60"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[31] = "HTTP_REFERER=http://localhost:631/admin"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[32] = "REQUEST_METHOD=GET"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[33] = "QUERY_STRING=OP=redirect"
D [01/Jan/2012:18:59:06 +0100] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 929)
I [01/Jan/2012:18:59:06 +0100] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=929)
D [01/Jan/2012:18:59:06 +0100] cupsdSendCommand: 13 file=14
D [01/Jan/2012:18:59:06 +0100] [CGI] admin.cgi started...
D [01/Jan/2012:18:59:06 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [01/Jan/2012:18:59:06 +0100] [CGI] http=0xb9359360
D [01/Jan/2012:18:59:06 +0100] [CGI] org.cups.sid cookie is "960956618ce3e170434dd2afacd4fad3"
D [01/Jan/2012:18:59:06 +0100] [CGI] redirecting with prefix http://localhost:631!
D [01/Jan/2012:18:59:06 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [01/Jan/2012:18:59:06 +0100] cupsdCloseClient: 16
D [01/Jan/2012:18:59:06 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [01/Jan/2012:18:59:06 +0100] Script header: Location: http://localhost:631/admin
D [01/Jan/2012:18:59:06 +0100] Script header: 
D [01/Jan/2012:18:59:06 +0100] cupsdAcceptClient: 16 from localhost:631 (IPv4)
D [01/Jan/2012:18:59:06 +0100] cupsdReadClient: 16 GET /admin HTTP/1.1
D [01/Jan/2012:18:59:06 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [01/Jan/2012:18:59:06 +0100] cupsdAuthorize: Authorized as root using Basic
D [01/Jan/2012:18:59:06 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@mamapc"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[13] = "USER=root"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[16] = "IPP_PORT=631"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[17] = "AUTH_TYPE=Basic"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[18] = "LANG=de_DE.UTF8"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[21] = "SERVER_NAME=localhost"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[22] = "SERVER_PORT=631"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[23] = "REMOTE_ADDR=127.0.0.1"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[25] = "SCRIPT_NAME=/admin"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc/admin"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[27] = "REMOTE_USER=root"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[29] = "HTTP_COOKIE=org.cups.sid=960956618ce3e170434dd2afacd4fad3"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[30] = "HTTP_USER_AGENT=Opera/9.80 (X11; Linux i686; U; de) Presto/2.10.229 Version/11.60"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[31] = "HTTP_REFERER=http://localhost:631/admin"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[32] = "REQUEST_METHOD=GET"
D [01/Jan/2012:18:59:06 +0100] [CGI] envp[33] = "QUERY_STRING="
D [01/Jan/2012:18:59:06 +0100] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 930)
I [01/Jan/2012:18:59:06 +0100] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=930)
D [01/Jan/2012:18:59:06 +0100] cupsdSendCommand: 16 file=17
D [01/Jan/2012:18:59:06 +0100] PID 929 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [01/Jan/2012:18:59:06 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [01/Jan/2012:18:59:06 +0100] [CGI] admin.cgi started...
D [01/Jan/2012:18:59:06 +0100] cupsdAcceptClient: 14 from localhost (Domain)
D [01/Jan/2012:18:59:06 +0100] [CGI] http=0xb866c360
D [01/Jan/2012:18:59:06 +0100] [CGI] org.cups.sid cookie is "960956618ce3e170434dd2afacd4fad3"
D [01/Jan/2012:18:59:06 +0100] [CGI] No form data, showing main menu...
D [01/Jan/2012:18:59:06 +0100] [CGI] /usr/share/cups/drivers/pscript5.dll: Datei oder Verzeichnis nicht gefunden
D [01/Jan/2012:18:59:06 +0100] cupsdReadClient: 14 POST / HTTP/1.1
D [01/Jan/2012:18:59:06 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [01/Jan/2012:18:59:06 +0100] cupsdAuthorize: No authentication data provided.
D [01/Jan/2012:18:59:06 +0100] cupsdReadClient: 14 1.1 Get-Subscriptions 1
D [01/Jan/2012:18:59:06 +0100] Get-Subscriptions ipp://localhost/
D [01/Jan/2012:18:59:06 +0100] Get-Subscriptions client-error-not-found: No subscriptions found.
D [01/Jan/2012:18:59:06 +0100] Returning IPP client-error-not-found for Get-Subscriptions (ipp://localhost/) from localhost
D [01/Jan/2012:18:59:06 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [01/Jan/2012:18:59:06 +0100] Script header: Content-Type: text/html;charset=utf-8
D [01/Jan/2012:18:59:06 +0100] Script header: 
D [01/Jan/2012:18:59:06 +0100] cupsdReadClient: 14 WAITING Closing on EOF
D [01/Jan/2012:18:59:06 +0100] cupsdCloseClient: 14
D [01/Jan/2012:18:59:06 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [01/Jan/2012:18:59:06 +0100] PID 930 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [01/Jan/2012:18:59:06 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [01/Jan/2012:18:59:11 +0100] cupsdReadClient: 13 GET / HTTP/1.1
D [01/Jan/2012:18:59:11 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [01/Jan/2012:18:59:11 +0100] cupsdAuthorize: Authorized as root using Basic
D [01/Jan/2012:18:59:11 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [01/Jan/2012:18:59:11 +0100] cupsdReadClient: 13 WAITING Closing on EOF
D [01/Jan/2012:18:59:11 +0100] cupsdCloseClient: 13
D [01/Jan/2012:18:59:11 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [01/Jan/2012:18:59:12 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [01/Jan/2012:18:59:12 +0100] cupsdCloseClient: 16
D [01/Jan/2012:18:59:12 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [01/Jan/2012:18:59:12 +0100] cupsdAcceptClient: 13 from localhost:631 (IPv4)
D [01/Jan/2012:18:59:12 +0100] cupsdReadClient: 13 GET /admin/log/access_log? HTTP/1.1
D [01/Jan/2012:18:59:12 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [01/Jan/2012:18:59:12 +0100] cupsdAuthorize: Authorized as root using Basic
D [01/Jan/2012:18:59:12 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [01/Jan/2012:18:59:32 +0100] Generating printcap /etc/printcap...
D [01/Jan/2012:18:59:32 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [01/Jan/2012:18:59:35 +0100] cupsdAcceptClient: 14 from localhost (Domain)
D [01/Jan/2012:18:59:35 +0100] cupsdReadClient: 14 POST / HTTP/1.1
D [01/Jan/2012:18:59:35 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [01/Jan/2012:18:59:35 +0100] cupsdAuthorize: No authentication data provided.
D [01/Jan/2012:18:59:35 +0100] cupsdReadClient: 14 1.1 CUPS-Get-Devices 1
D [01/Jan/2012:18:59:35 +0100] CUPS-Get-Devices
D [01/Jan/2012:18:59:35 +0100] cupsdIsAuthorized: username=""
D [01/Jan/2012:18:59:35 +0100] Returning HTTP Unauthorized for CUPS-Get-Devices (no URI) from localhost
D [01/Jan/2012:18:59:35 +0100] cupsdSendHeader: 14 WWW-Authenticate: Basic realm="CUPS", trc="y"
D [01/Jan/2012:18:59:35 +0100] cupsdReadClient: 14 WAITING Closing on EOF
D [01/Jan/2012:18:59:35 +0100] cupsdCloseClient: 14
D [01/Jan/2012:18:59:35 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [01/Jan/2012:18:59:35 +0100] cupsdAcceptClient: 14 from localhost (Domain)
D [01/Jan/2012:18:59:35 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [01/Jan/2012:18:59:35 +0100] cupsdReadClient: 14 WAITING Closing on EOF
D [01/Jan/2012:18:59:35 +0100] cupsdCloseClient: 14
D [01/Jan/2012:18:59:35 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [01/Jan/2012:18:59:35 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [01/Jan/2012:18:59:35 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [01/Jan/2012:18:59:35 +0100] cupsdAuthorize: Authorized as root using PeerCred
D [01/Jan/2012:18:59:35 +0100] cupsdReadClient: 16 1.1 CUPS-Get-Devices 1
D [01/Jan/2012:18:59:35 +0100] CUPS-Get-Devices
D [01/Jan/2012:18:59:35 +0100] cupsdIsAuthorized: username="root"
D [01/Jan/2012:18:59:35 +0100] [CGI] argv[0] = "/usr/lib/cups/daemon/cups-deviced"
D [01/Jan/2012:18:59:35 +0100] [CGI] argv[1] = "1"
D [01/Jan/2012:18:59:35 +0100] [CGI] argv[2] = "0"
D [01/Jan/2012:18:59:35 +0100] [CGI] argv[3] = "2"
D [01/Jan/2012:18:59:35 +0100] [CGI] argv[4] = "2"
D [01/Jan/2012:18:59:35 +0100] [CGI] argv[5] = "requested-attributes=all exclude-schemes='beh','cups-pdf','bluetooth','dnssd','http','https','ipp','lpd','ncp','parallel','scsi','smb','snmp','socket'"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@mamapc"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[13] = "USER=root"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[16] = "IPP_PORT=631"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[17] = "AUTH_TYPE=PeerCred"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[18] = "LANG=en_US.UTF8"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[21] = "SERVER_NAME=localhost"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[22] = "SERVER_PORT=631"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[23] = "REMOTE_ADDR="
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[25] = "SCRIPT_NAME=/"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc/"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[27] = "REMOTE_USER=root"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[29] = "HTTP_USER_AGENT=CUPS/1.5.0"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[30] = "REQUEST_METHOD=POST"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[31] = "CONTENT_LENGTH=244"
D [01/Jan/2012:18:59:35 +0100] [CGI] envp[32] = "CONTENT_TYPE=application/ipp"
D [01/Jan/2012:18:59:35 +0100] [CGI] Started /usr/lib/cups/daemon/cups-deviced (PID 948)
I [01/Jan/2012:18:59:35 +0100] Started "/usr/lib/cups/daemon/cups-deviced" (pid=948)
D [01/Jan/2012:18:59:35 +0100] cupsdSendCommand: 16 file=14
D [01/Jan/2012:18:59:35 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/mdns (PID 949)
D [01/Jan/2012:18:59:35 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/usb (PID 950)
D [01/Jan/2012:18:59:35 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/ipps (PID 951)
D [01/Jan/2012:18:59:35 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/serial (PID 952)
D [01/Jan/2012:18:59:35 +0100] [cups-deviced] PID 949 (mdns) exited with no errors.
D [01/Jan/2012:18:59:35 +0100] [CGI] list_devices
D [01/Jan/2012:18:59:35 +0100] [CGI] usb_find_busses=2
D [01/Jan/2012:18:59:35 +0100] [CGI] usb_find_devices=7
D [01/Jan/2012:18:59:35 +0100] [CGI] libusb couldn't open USB device /dev/bus/usb/002/002: Permission denied.
D [01/Jan/2012:18:59:35 +0100] [CGI] libusb requires write access to USB device nodes.
D [01/Jan/2012:18:59:35 +0100] [cups-deviced] PID 950 (usb) exited with no errors.
D [01/Jan/2012:18:59:35 +0100] [CGI] Flushed attributes...
D [01/Jan/2012:18:59:35 +0100] [cups-deviced] Found device "ipps"...
D [01/Jan/2012:18:59:35 +0100] [cups-deviced] PID 951 (ipps) exited with no errors.
D [01/Jan/2012:18:59:35 +0100] Script header: Content-Type: application/ipp
D [01/Jan/2012:18:59:35 +0100] Script header: 
D [01/Jan/2012:18:59:35 +0100] [CGI] Flushed attributes...
D [01/Jan/2012:18:59:35 +0100] [cups-deviced] Found device "serial:/dev/ttyS0?baud=115200"...
D [01/Jan/2012:18:59:35 +0100] [CGI] Flushed attributes...
D [01/Jan/2012:18:59:35 +0100] [cups-deviced] Found device "serial:/dev/ttyS1?baud=115200"...
D [01/Jan/2012:18:59:35 +0100] [cups-deviced] PID 952 (serial) exited with no errors.
D [01/Jan/2012:18:59:35 +0100] PID 948 (/usr/lib/cups/daemon/cups-deviced) exited with no errors.
D [01/Jan/2012:18:59:35 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [01/Jan/2012:18:59:35 +0100] cupsdReadClient: 16 WAITING Closing on EOF
D [01/Jan/2012:18:59:35 +0100] cupsdCloseClient: 16
D [01/Jan/2012:18:59:35 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [01/Jan/2012:19:00:23 +0100] Report: clients=1
D [01/Jan/2012:19:00:23 +0100] Report: jobs=0
D [01/Jan/2012:19:00:23 +0100] Report: jobs-active=0
D [01/Jan/2012:19:00:23 +0100] Report: printers=0
D [01/Jan/2012:19:00:23 +0100] Report: printers-implicit=0
D [01/Jan/2012:19:00:23 +0100] Report: stringpool-string-count=407
D [01/Jan/2012:19:00:23 +0100] Report: stringpool-alloc-bytes=4648
D [01/Jan/2012:19:00:23 +0100] Report: stringpool-total-bytes=5736
D [01/Jan/2012:19:01:15 +0100] cupsdReadClient: 13 WAITING Closing on EOF
D [01/Jan/2012:19:01:15 +0100] cupsdCloseClient: 13
D [01/Jan/2012:19:01:15 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [01/Jan/2012:19:19:03 +0100] Report: clients=0
D [01/Jan/2012:19:19:03 +0100] Report: jobs=0
D [01/Jan/2012:19:19:03 +0100] Report: jobs-active=0
D [01/Jan/2012:19:19:03 +0100] Report: printers=0
D [01/Jan/2012:19:19:03 +0100] Report: printers-implicit=0
D [01/Jan/2012:19:19:03 +0100] Report: stringpool-string-count=407
D [01/Jan/2012:19:19:03 +0100] Report: stringpool-alloc-bytes=4648
D [01/Jan/2012:19:19:03 +0100] Report: stringpool-total-bytes=5736
D [01/Jan/2012:19:22:10 +0100] Report: clients=0
D [01/Jan/2012:19:22:11 +0100] Report: jobs=0
D [01/Jan/2012:19:22:11 +0100] Report: jobs-active=0
D [01/Jan/2012:19:22:11 +0100] Report: printers=0
D [01/Jan/2012:19:22:11 +0100] Report: printers-implicit=0
D [01/Jan/2012:19:22:11 +0100] Report: stringpool-string-count=407
D [01/Jan/2012:19:22:11 +0100] Report: stringpool-alloc-bytes=4648
D [01/Jan/2012:19:22:11 +0100] Report: stringpool-total-bytes=5736
D [01/Jan/2012:19:24:21 +0100] Report: clients=0
D [01/Jan/2012:19:24:21 +0100] Report: jobs=0
D [01/Jan/2012:19:24:21 +0100] Report: jobs-active=0
D [01/Jan/2012:19:24:21 +0100] Report: printers=0
D [01/Jan/2012:19:24:21 +0100] Report: printers-implicit=0
D [01/Jan/2012:19:24:21 +0100] Report: stringpool-string-count=407
D [01/Jan/2012:19:24:21 +0100] Report: stringpool-alloc-bytes=4648
D [01/Jan/2012:19:24:21 +0100] Report: stringpool-total-bytes=5736
D [01/Jan/2012:19:27:26 +0100] Report: clients=0
D [01/Jan/2012:19:27:26 +0100] Report: jobs=0
D [01/Jan/2012:19:27:26 +0100] Report: jobs-active=0
D [01/Jan/2012:19:27:26 +0100] Report: printers=0
D [01/Jan/2012:19:27:26 +0100] Report: printers-implicit=0
D [01/Jan/2012:19:27:26 +0100] Report: stringpool-string-count=407
D [01/Jan/2012:19:27:26 +0100] Report: stringpool-alloc-bytes=4648
D [01/Jan/2012:19:27:26 +0100] Report: stringpool-total-bytes=5736
D [01/Jan/2012:19:37:11 +0100] Report: clients=0
D [01/Jan/2012:19:37:11 +0100] Report: jobs=0
D [01/Jan/2012:19:37:11 +0100] Report: jobs-active=0
D [01/Jan/2012:19:37:11 +0100] Report: printers=0
D [01/Jan/2012:19:37:11 +0100] Report: printers-implicit=0
D [01/Jan/2012:19:37:11 +0100] Report: stringpool-string-count=407
D [01/Jan/2012:19:37:11 +0100] Report: stringpool-alloc-bytes=4648
D [01/Jan/2012:19:37:11 +0100] Report: stringpool-total-bytes=5736
I [01/Jan/2012:19:37:41 +0100] Scheduler shutting down normally.
D [01/Jan/2012:19:37:41 +0100] Discarding unused server-stopped event...
I [01/Jan/2012:19:37:41 +0100] Saving job.cache...
I [01/Jan/2012:19:38:18 +0100] Listening to [v1.::1]:631 (IPv6)
I [01/Jan/2012:19:38:18 +0100] Listening to 127.0.0.1:631 (IPv4)
I [01/Jan/2012:19:38:18 +0100] Listening to /var/run/cups/cups.sock (Domain)
I [01/Jan/2012:19:38:18 +0100] Remote access is disabled.
D [01/Jan/2012:19:38:18 +0100] Added auto ServerAlias mamapc
I [01/Jan/2012:19:38:18 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
D [01/Jan/2012:19:38:18 +0100] Creating missing directory "/var/run/cups"
D [01/Jan/2012:19:38:18 +0100] Repairing ownership of "/var/run/cups"
D [01/Jan/2012:19:38:18 +0100] Repairing access permissions of "/var/run/cups"
D [01/Jan/2012:19:38:18 +0100] Creating missing directory "/var/run/cups/certs"
D [01/Jan/2012:19:38:18 +0100] Repairing ownership of "/var/run/cups/certs"
D [01/Jan/2012:19:38:18 +0100] Repairing access permissions of "/var/run/cups/certs"
I [01/Jan/2012:19:38:18 +0100] Using default TempDir of /var/spool/cups/tmp...
I [01/Jan/2012:19:38:18 +0100] Configured for up to 100 clients.
I [01/Jan/2012:19:38:18 +0100] Allowing up to 100 client connections per host.
I [01/Jan/2012:19:38:18 +0100] Using policy "default" as the default.
I [01/Jan/2012:19:38:18 +0100] Full reload is required.
I [01/Jan/2012:19:38:19 +0100] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 35 types, 42 filters...
I [01/Jan/2012:19:38:19 +0100] Loading job cache file "/var/cache/cups/job.cache"...
I [01/Jan/2012:19:38:19 +0100] Full reload complete.
D [01/Jan/2012:19:38:19 +0100] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
I [01/Jan/2012:19:38:19 +0100] Cleaning out old files in "/var/spool/cups/tmp"...
D [01/Jan/2012:19:38:19 +0100] Removed "/var/spool/cups/tmp/0039f4f0366a5"...
D [01/Jan/2012:19:38:19 +0100] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
I [01/Jan/2012:19:38:19 +0100] Cleaning out old files in "/var/cache/cups"...
I [01/Jan/2012:19:38:19 +0100] Listening to [v1.::1]:631 on fd 8...
I [01/Jan/2012:19:38:19 +0100] Listening to 127.0.0.1:631 on fd 9...
I [01/Jan/2012:19:38:19 +0100] Listening to /var/run/cups/cups.sock:631 on fd 10...
I [01/Jan/2012:19:38:19 +0100] Resuming new connection processing...
D [01/Jan/2012:19:38:19 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [01/Jan/2012:19:38:19 +0100] Discarding unused server-started event...
D [01/Jan/2012:19:38:20 +0100] Report: clients=0
D [01/Jan/2012:19:38:20 +0100] Report: jobs=0
D [01/Jan/2012:19:38:20 +0100] Report: jobs-active=0
D [01/Jan/2012:19:38:20 +0100] Report: printers=0
D [01/Jan/2012:19:38:20 +0100] Report: printers-implicit=0
D [01/Jan/2012:19:38:20 +0100] Report: stringpool-string-count=289
D [01/Jan/2012:19:38:20 +0100] Report: stringpool-alloc-bytes=4648
D [01/Jan/2012:19:38:20 +0100] Report: stringpool-total-bytes=4792
I [01/Jan/2012:20:53:48 +0100] Scheduler shutting down normally.
D [01/Jan/2012:20:53:48 +0100] Discarding unused server-stopped event...
I [01/Jan/2012:20:53:48 +0100] Saving job.cache...
I [01/Jan/2012:20:54:26 +0100] Listening to [v1.::1]:631 (IPv6)
I [01/Jan/2012:20:54:26 +0100] Listening to 127.0.0.1:631 (IPv4)
I [01/Jan/2012:20:54:26 +0100] Listening to /var/run/cups/cups.sock (Domain)
I [01/Jan/2012:20:54:26 +0100] Remote access is disabled.
D [01/Jan/2012:20:54:26 +0100] Added auto ServerAlias mamapc
I [01/Jan/2012:20:54:26 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
D [01/Jan/2012:20:54:26 +0100] Creating missing directory "/var/run/cups"
D [01/Jan/2012:20:54:26 +0100] Repairing ownership of "/var/run/cups"
D [01/Jan/2012:20:54:26 +0100] Repairing access permissions of "/var/run/cups"
D [01/Jan/2012:20:54:26 +0100] Creating missing directory "/var/run/cups/certs"
D [01/Jan/2012:20:54:26 +0100] Repairing ownership of "/var/run/cups/certs"
D [01/Jan/2012:20:54:26 +0100] Repairing access permissions of "/var/run/cups/certs"
I [01/Jan/2012:20:54:26 +0100] Using default TempDir of /var/spool/cups/tmp...
I [01/Jan/2012:20:54:26 +0100] Configured for up to 100 clients.
I [01/Jan/2012:20:54:26 +0100] Allowing up to 100 client connections per host.
I [01/Jan/2012:20:54:26 +0100] Using policy "default" as the default.
I [01/Jan/2012:20:54:26 +0100] Full reload is required.
I [01/Jan/2012:20:54:27 +0100] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 35 types, 42 filters...
I [01/Jan/2012:20:54:27 +0100] Loading job cache file "/var/cache/cups/job.cache"...
I [01/Jan/2012:20:54:27 +0100] Full reload complete.
D [01/Jan/2012:20:54:27 +0100] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
I [01/Jan/2012:20:54:27 +0100] Cleaning out old files in "/var/spool/cups/tmp"...
D [01/Jan/2012:20:54:27 +0100] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
I [01/Jan/2012:20:54:27 +0100] Cleaning out old files in "/var/cache/cups"...
I [01/Jan/2012:20:54:27 +0100] Listening to [v1.::1]:631 on fd 8...
I [01/Jan/2012:20:54:27 +0100] Listening to 127.0.0.1:631 on fd 9...
I [01/Jan/2012:20:54:27 +0100] Listening to /var/run/cups/cups.sock:631 on fd 10...
I [01/Jan/2012:20:54:27 +0100] Resuming new connection processing...
D [01/Jan/2012:20:54:27 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [01/Jan/2012:20:54:27 +0100] Discarding unused server-started event...
D [01/Jan/2012:20:54:28 +0100] Report: clients=0
D [01/Jan/2012:20:54:28 +0100] Report: jobs=0
D [01/Jan/2012:20:54:28 +0100] Report: jobs-active=0
D [01/Jan/2012:20:54:28 +0100] Report: printers=0
D [01/Jan/2012:20:54:28 +0100] Report: printers-implicit=0
D [01/Jan/2012:20:54:28 +0100] Report: stringpool-string-count=289
D [01/Jan/2012:20:54:28 +0100] Report: stringpool-alloc-bytes=4648
D [01/Jan/2012:20:54:28 +0100] Report: stringpool-total-bytes=4792
I [02/Jan/2012:05:34:23 +0100] Scheduler shutting down normally.
D [02/Jan/2012:05:34:24 +0100] Discarding unused server-stopped event...
I [02/Jan/2012:05:34:24 +0100] Saving job.cache...
I [02/Jan/2012:05:35:05 +0100] Listening to [v1.::1]:631 (IPv6)
I [02/Jan/2012:05:35:05 +0100] Listening to 127.0.0.1:631 (IPv4)
I [02/Jan/2012:05:35:05 +0100] Listening to /var/run/cups/cups.sock (Domain)
I [02/Jan/2012:05:35:05 +0100] Remote access is disabled.
D [02/Jan/2012:05:35:05 +0100] Added auto ServerAlias mamapc
I [02/Jan/2012:05:35:05 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
D [02/Jan/2012:05:35:05 +0100] Creating missing directory "/var/run/cups"
D [02/Jan/2012:05:35:05 +0100] Repairing ownership of "/var/run/cups"
D [02/Jan/2012:05:35:05 +0100] Repairing access permissions of "/var/run/cups"
D [02/Jan/2012:05:35:05 +0100] Creating missing directory "/var/run/cups/certs"
D [02/Jan/2012:05:35:05 +0100] Repairing ownership of "/var/run/cups/certs"
D [02/Jan/2012:05:35:05 +0100] Repairing access permissions of "/var/run/cups/certs"
I [02/Jan/2012:05:35:05 +0100] Using default TempDir of /var/spool/cups/tmp...
I [02/Jan/2012:05:35:05 +0100] Configured for up to 100 clients.
I [02/Jan/2012:05:35:05 +0100] Allowing up to 100 client connections per host.
I [02/Jan/2012:05:35:05 +0100] Using policy "default" as the default.
I [02/Jan/2012:05:35:05 +0100] Full reload is required.
I [02/Jan/2012:05:35:06 +0100] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 35 types, 42 filters...
I [02/Jan/2012:05:35:06 +0100] Loading job cache file "/var/cache/cups/job.cache"...
I [02/Jan/2012:05:35:06 +0100] Full reload complete.
D [02/Jan/2012:05:35:06 +0100] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
I [02/Jan/2012:05:35:06 +0100] Cleaning out old files in "/var/spool/cups/tmp"...
D [02/Jan/2012:05:35:06 +0100] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
I [02/Jan/2012:05:35:06 +0100] Cleaning out old files in "/var/cache/cups"...
I [02/Jan/2012:05:35:06 +0100] Listening to [v1.::1]:631 on fd 8...
I [02/Jan/2012:05:35:06 +0100] Listening to 127.0.0.1:631 on fd 9...
I [02/Jan/2012:05:35:06 +0100] Listening to /var/run/cups/cups.sock:631 on fd 10...
I [02/Jan/2012:05:35:06 +0100] Resuming new connection processing...
D [02/Jan/2012:05:35:06 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [02/Jan/2012:05:35:06 +0100] Discarding unused server-started event...
D [02/Jan/2012:05:35:07 +0100] Report: clients=0
D [02/Jan/2012:05:35:07 +0100] Report: jobs=0
D [02/Jan/2012:05:35:07 +0100] Report: jobs-active=0
D [02/Jan/2012:05:35:07 +0100] Report: printers=0
D [02/Jan/2012:05:35:07 +0100] Report: printers-implicit=0
D [02/Jan/2012:05:35:07 +0100] Report: stringpool-string-count=289
D [02/Jan/2012:05:35:07 +0100] Report: stringpool-alloc-bytes=4648
D [02/Jan/2012:05:35:07 +0100] Report: stringpool-total-bytes=4792
I [02/Jan/2012:05:56:24 +0100] Scheduler shutting down normally.
D [02/Jan/2012:05:56:24 +0100] Discarding unused server-stopped event...
I [02/Jan/2012:05:56:24 +0100] Saving job.cache...
I [02/Jan/2012:17:22:51 +0100] Listening to [v1.::1]:631 (IPv6)
I [02/Jan/2012:17:22:51 +0100] Listening to 127.0.0.1:631 (IPv4)
I [02/Jan/2012:17:22:51 +0100] Listening to /var/run/cups/cups.sock (Domain)
I [02/Jan/2012:17:22:51 +0100] Remote access is disabled.
D [02/Jan/2012:17:22:51 +0100] Added auto ServerAlias mamapc
I [02/Jan/2012:17:22:51 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
D [02/Jan/2012:17:22:51 +0100] Creating missing directory "/var/run/cups"
D [02/Jan/2012:17:22:51 +0100] Repairing ownership of "/var/run/cups"
D [02/Jan/2012:17:22:51 +0100] Repairing access permissions of "/var/run/cups"
D [02/Jan/2012:17:22:51 +0100] Creating missing directory "/var/run/cups/certs"
D [02/Jan/2012:17:22:51 +0100] Repairing ownership of "/var/run/cups/certs"
D [02/Jan/2012:17:22:51 +0100] Repairing access permissions of "/var/run/cups/certs"
I [02/Jan/2012:17:22:51 +0100] Using default TempDir of /var/spool/cups/tmp...
I [02/Jan/2012:17:22:51 +0100] Configured for up to 100 clients.
I [02/Jan/2012:17:22:51 +0100] Allowing up to 100 client connections per host.
I [02/Jan/2012:17:22:51 +0100] Using policy "default" as the default.
I [02/Jan/2012:17:22:51 +0100] Full reload is required.
I [02/Jan/2012:17:22:52 +0100] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 35 types, 42 filters...
I [02/Jan/2012:17:22:52 +0100] Loading job cache file "/var/cache/cups/job.cache"...
I [02/Jan/2012:17:22:52 +0100] Full reload complete.
D [02/Jan/2012:17:22:52 +0100] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
I [02/Jan/2012:17:22:52 +0100] Cleaning out old files in "/var/spool/cups/tmp"...
D [02/Jan/2012:17:22:52 +0100] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
I [02/Jan/2012:17:22:52 +0100] Cleaning out old files in "/var/cache/cups"...
I [02/Jan/2012:17:22:52 +0100] Listening to [v1.::1]:631 on fd 8...
I [02/Jan/2012:17:22:52 +0100] Listening to 127.0.0.1:631 on fd 9...
I [02/Jan/2012:17:22:52 +0100] Listening to /var/run/cups/cups.sock:631 on fd 10...
I [02/Jan/2012:17:22:52 +0100] Resuming new connection processing...
D [02/Jan/2012:17:22:52 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [02/Jan/2012:17:22:52 +0100] Discarding unused server-started event...
D [02/Jan/2012:17:22:53 +0100] Report: clients=0
D [02/Jan/2012:17:22:53 +0100] Report: jobs=0
D [02/Jan/2012:17:22:53 +0100] Report: jobs-active=0
D [02/Jan/2012:17:22:53 +0100] Report: printers=0
D [02/Jan/2012:17:22:53 +0100] Report: printers-implicit=0
D [02/Jan/2012:17:22:53 +0100] Report: stringpool-string-count=289
D [02/Jan/2012:17:22:53 +0100] Report: stringpool-alloc-bytes=4648
D [02/Jan/2012:17:22:53 +0100] Report: stringpool-total-bytes=4792
I [02/Jan/2012:18:15:27 +0100] Scheduler shutting down normally.
D [02/Jan/2012:18:15:27 +0100] Discarding unused server-stopped event...
I [02/Jan/2012:18:15:27 +0100] Saving job.cache...
E [02/Jan/2012:18:40:40 +0100] cupsdAuthorize: pam_authenticate() returned 7 (Authentication failure)!
E [02/Jan/2012:18:41:03 +0100] Bad request line "%16%03%01" from localhost!
E [02/Jan/2012:18:41:03 +0100] Bad request line "%16%03%01" from localhost!
E [02/Jan/2012:18:41:03 +0100] Bad request line "%16%03%01" from localhost!
E [02/Jan/2012:18:41:03 +0100] Bad request line "%16%03%01" from localhost!
E [02/Jan/2012:18:41:04 +0100] Bad request line "%16%03%01" from localhost!
E [02/Jan/2012:18:44:36 +0100] Returning HTTP Forbidden for CUPS-Delete-Printer (ipp://localhost/printers/Epson-Stylus-SX218) from localhost
E [02/Jan/2012:18:46:25 +0100] Returning IPP client-error-document-format-not-supported for Print-Job (ipp://localhost:631/printers/Epson_Stylus_SX218) from localhost
E [02/Jan/2012:18:54:25 +0100] Bad request line "%16%03%01" from localhost!
E [02/Jan/2012:18:54:25 +0100] Bad request line "%16%03%01" from localhost!
E [02/Jan/2012:18:54:25 +0100] Bad request line "%16%03%01" from localhost!
E [02/Jan/2012:18:54:26 +0100] Bad request line "%16%03%01" from localhost!
E [02/Jan/2012:18:56:58 +0100] Bad request line "%16%03%01" from localhost!
E [02/Jan/2012:18:58:04 +0100] Bad request line "%16%03%01" from localhost!
E [02/Jan/2012:18:58:04 +0100] Bad request line "%16%03%01" from localhost!
W [02/Jan/2012:19:01:48 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Brother/BR5070DN_GPL.ppd.gz!
E [02/Jan/2012:19:01:49 +0100] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
W [02/Jan/2012:19:01:53 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/KONICA_MINOLTA/KOC451JX.ppd.gz!
W [02/Jan/2012:19:01:53 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Xerox/xrx6120pu.ppd!
W [02/Jan/2012:19:01:54 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp950c.ppd.gz!
W [02/Jan/2012:19:01:54 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp960s.ppd.gz!
W [02/Jan/2012:19:01:54 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp9100.ppd.gz!
W [02/Jan/2012:19:01:54 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp880c.ppd.gz!
W [02/Jan/2012:19:01:54 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp980c.ppd.gz!
W [02/Jan/2012:19:01:54 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp850c.ppd.gz!
W [02/Jan/2012:19:01:54 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp920c.ppd.gz!
W [02/Jan/2012:19:01:54 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp830c.ppd.gz!
E [02/Jan/2012:19:02:06 +0100] Bad request line "%16%03%01" from localhost!
E [02/Jan/2012:19:02:11 +0100] Bad request line "%16%03%01" from localhost!
E [02/Jan/2012:19:02:11 +0100] Bad request line "%16%03%01" from localhost!
E [02/Jan/2012:19:02:11 +0100] Bad request line "%16%03%01" from localhost!
W [02/Jan/2012:19:02:57 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Brother/BR5070DN_GPL.ppd.gz!
E [02/Jan/2012:19:02:57 +0100] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
W [02/Jan/2012:19:02:57 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/KONICA_MINOLTA/KOC451JX.ppd.gz!
W [02/Jan/2012:19:02:57 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Xerox/xrx6120pu.ppd!
W [02/Jan/2012:19:02:57 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp950c.ppd.gz!
W [02/Jan/2012:19:02:57 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp960s.ppd.gz!
W [02/Jan/2012:19:02:57 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp9100.ppd.gz!
W [02/Jan/2012:19:02:57 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp880c.ppd.gz!
W [02/Jan/2012:19:02:57 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp980c.ppd.gz!
W [02/Jan/2012:19:02:57 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp850c.ppd.gz!
W [02/Jan/2012:19:02:57 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp920c.ppd.gz!
W [02/Jan/2012:19:02:57 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp830c.ppd.gz!
W [02/Jan/2012:19:03:42 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Brother/BR5070DN_GPL.ppd.gz!
E [02/Jan/2012:19:03:42 +0100] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
W [02/Jan/2012:19:03:42 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/KONICA_MINOLTA/KOC451JX.ppd.gz!
W [02/Jan/2012:19:03:43 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Xerox/xrx6120pu.ppd!
W [02/Jan/2012:19:03:43 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp950c.ppd.gz!
W [02/Jan/2012:19:03:43 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp960s.ppd.gz!
W [02/Jan/2012:19:03:43 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp9100.ppd.gz!
W [02/Jan/2012:19:03:43 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp880c.ppd.gz!
W [02/Jan/2012:19:03:43 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp980c.ppd.gz!
W [02/Jan/2012:19:03:43 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp850c.ppd.gz!
W [02/Jan/2012:19:03:43 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp920c.ppd.gz!
W [02/Jan/2012:19:03:43 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp830c.ppd.gz!
W [02/Jan/2012:19:04:46 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Brother/BR5070DN_GPL.ppd.gz!
E [02/Jan/2012:19:04:46 +0100] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
W [02/Jan/2012:19:04:46 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/KONICA_MINOLTA/KOC451JX.ppd.gz!
W [02/Jan/2012:19:04:46 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Xerox/xrx6120pu.ppd!
W [02/Jan/2012:19:04:46 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp950c.ppd.gz!
W [02/Jan/2012:19:04:46 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp960s.ppd.gz!
W [02/Jan/2012:19:04:46 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp9100.ppd.gz!
W [02/Jan/2012:19:04:46 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp880c.ppd.gz!
W [02/Jan/2012:19:04:46 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp980c.ppd.gz!
W [02/Jan/2012:19:04:46 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp850c.ppd.gz!
W [02/Jan/2012:19:04:46 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp920c.ppd.gz!
W [02/Jan/2012:19:04:46 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp830c.ppd.gz!
E [02/Jan/2012:19:05:17 +0100] Bad request line "%16%03%01" from localhost!
W [02/Jan/2012:19:06:12 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Brother/BR5070DN_GPL.ppd.gz!
E [02/Jan/2012:19:06:12 +0100] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
W [02/Jan/2012:19:06:12 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/KONICA_MINOLTA/KOC451JX.ppd.gz!
W [02/Jan/2012:19:06:12 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Xerox/xrx6120pu.ppd!
W [02/Jan/2012:19:06:12 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp950c.ppd.gz!
W [02/Jan/2012:19:06:12 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp960s.ppd.gz!
W [02/Jan/2012:19:06:12 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp9100.ppd.gz!
W [02/Jan/2012:19:06:12 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp880c.ppd.gz!
W [02/Jan/2012:19:06:12 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp980c.ppd.gz!
W [02/Jan/2012:19:06:12 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp850c.ppd.gz!
W [02/Jan/2012:19:06:12 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp920c.ppd.gz!
W [02/Jan/2012:19:06:12 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp830c.ppd.gz!
E [02/Jan/2012:19:06:57 +0100] Bad request line "%16%03%01" from localhost!
E [02/Jan/2012:19:06:58 +0100] Bad request line "%16%03%01" from localhost!
E [02/Jan/2012:19:15:57 +0100] Avahi client failed, closing client to allow a clean restart
W [02/Jan/2012:19:18:48 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Brother/BR5070DN_GPL.ppd.gz!
E [02/Jan/2012:19:18:48 +0100] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
W [02/Jan/2012:19:18:49 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/KONICA_MINOLTA/KOC451JX.ppd.gz!
W [02/Jan/2012:19:18:49 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Xerox/xrx6120pu.ppd!
W [02/Jan/2012:19:18:49 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp950c.ppd.gz!
W [02/Jan/2012:19:18:49 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp960s.ppd.gz!
W [02/Jan/2012:19:18:49 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp9100.ppd.gz!
W [02/Jan/2012:19:18:49 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp880c.ppd.gz!
W [02/Jan/2012:19:18:49 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp980c.ppd.gz!
W [02/Jan/2012:19:18:49 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp850c.ppd.gz!
W [02/Jan/2012:19:18:49 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp920c.ppd.gz!
W [02/Jan/2012:19:18:49 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp830c.ppd.gz!
W [02/Jan/2012:19:19:55 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Brother/BR5070DN_GPL.ppd.gz!
E [02/Jan/2012:19:19:55 +0100] [cups-driverd] Bad driver information file "/usr/share/cups/model/foomatic-db-ppds/Kyocera/ReadMe.htm"!
W [02/Jan/2012:19:19:55 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/KONICA_MINOLTA/KOC451JX.ppd.gz!
W [02/Jan/2012:19:19:55 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Xerox/xrx6120pu.ppd!
W [02/Jan/2012:19:19:55 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp950c.ppd.gz!
W [02/Jan/2012:19:19:55 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp960s.ppd.gz!
W [02/Jan/2012:19:19:55 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp9100.ppd.gz!
W [02/Jan/2012:19:19:55 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp880c.ppd.gz!
W [02/Jan/2012:19:19:55 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp980c.ppd.gz!
W [02/Jan/2012:19:19:55 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp850c.ppd.gz!
W [02/Jan/2012:19:19:55 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp920c.ppd.gz!
W [02/Jan/2012:19:19:55 +0100] [CGI] Missing NickName and ModelName in /usr/share/cups/model/foomatic-db-ppds/Epson/eplp830c.ppd.gz!
E [02/Jan/2012:19:20:30 +0100] Returning IPP client-error-document-format-not-supported for Print-Job (ipp://localhost:631/printers/Epson_Stylus_SX218) from localhost
E [02/Jan/2012:19:24:03 +0100] Returning IPP client-error-document-format-not-supported for Send-Document (ipp://localhost:631/printers/Epson_Stylus_SX218) from localhost

Please let me know if you should need other outputs, logfiles, etc. I appreciate any idea that could help me to get this damn thing to finally print something.

Thank you very much!