Bug#721000: cups: CUPS fails to print with Lexmark Optra E312L
Package: cups
Version: 1.5.3-5
Severity: important
Dear Maintainer,
I am using a Lexmark Optra 312L printer, and have installed the drivers from
the manufacturer. The printer is recognized by CUPS and is set as default
printer, however when I print it either doesn't print at all and just briefly
flashes the error light on the printer or it prints a page that says:
ageLevel: 2
%% CreationDate: D:20130826144526-07'00'
.... I don't get any error messages and when I try to look at the list of jobs,
it seems to think the print jobs are completing successfully. The printer has
no problems on Windows computers.
Here is the output from the CUPS error log:
W [26/Aug/2013:00:33:49 -0700] failed to CreateProfile:
org.freedesktop.ColorManager.AlreadyExists:profile id
'Lexmark_Lexmark_Optra_E312L-Gray..' already exists
W [26/Aug/2013:00:33:49 -0700] failed to CreateDevice:
org.freedesktop.ColorManager.AlreadyExists:device id 'cups-
Lexmark_Lexmark_Optra_E312L' already exists
I [26/Aug/2013:14:34:11 -0700] Listening to [v1.::1]:631 (IPv6)
I [26/Aug/2013:14:34:11 -0700] Listening to 127.0.0.1:631 (IPv4)
I [26/Aug/2013:14:34:11 -0700] Listening to /var/run/cups/cups.sock (Domain)
I [26/Aug/2013:14:34:11 -0700] Remote access is disabled.
D [26/Aug/2013:14:34:11 -0700] Added auto ServerAlias debian
I [26/Aug/2013:14:34:11 -0700] Loaded configuration file "/etc/cups/cupsd.conf"
I [26/Aug/2013:14:34:11 -0700] Using default TempDir of /var/spool/cups/tmp...
I [26/Aug/2013:14:34:11 -0700] Configured for up to 100 clients.
I [26/Aug/2013:14:34:11 -0700] Allowing up to 100 client connections per host.
I [26/Aug/2013:14:34:11 -0700] Using policy "default" as the default.
D [26/Aug/2013:14:34:11 -0700] load_ppd: Loading
/var/cache/cups/Lexmark_Lexmark_Optra_E312L.data...
D [26/Aug/2013:14:34:11 -0700] Calling DeleteDevice(cups-
Lexmark_Lexmark_Optra_E312L)
D [26/Aug/2013:14:34:11 -0700] failed to DeleteDevice:
org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match
expected type `(o)'
D [26/Aug/2013:14:34:11 -0700] Using profile id of Lexmark_Lexmark_Optra_E312L-
Gray..
D [26/Aug/2013:14:34:11 -0700] Calling CreateProfile
(Lexmark_Lexmark_Optra_E312L-Gray..,temp)
W [26/Aug/2013:14:34:11 -0700] failed to CreateProfile:
org.freedesktop.ColorManager.AlreadyExists:profile id
'Lexmark_Lexmark_Optra_E312L-Gray..' already exists
I [26/Aug/2013:14:34:11 -0700] Registering ICC color profiles for
"Lexmark_Lexmark_Optra_E312L"
D [26/Aug/2013:14:34:11 -0700] Calling CreateDevice(cups-
Lexmark_Lexmark_Optra_E312L,temp)
W [26/Aug/2013:14:34:11 -0700] failed to CreateDevice:
org.freedesktop.ColorManager.AlreadyExists:device id 'cups-
Lexmark_Lexmark_Optra_E312L' already exists
D [26/Aug/2013:14:34:11 -0700]
cupsdRegisterPrinter(p=0x7f70b2e6dba0(Lexmark_Lexmark_Optra_E312L))
D [26/Aug/2013:14:34:11 -0700] cupsdMarkDirty(---p--)
D [26/Aug/2013:14:34:11 -0700] cupsdSetBusyState: newbusy="Dirty files",
busy="Not busy"
I [26/Aug/2013:14:34:11 -0700] Partial reload complete.
I [26/Aug/2013:14:34:11 -0700] Listening to [v1.::1]:631 on fd 6...
I [26/Aug/2013:14:34:11 -0700] Listening to 127.0.0.1:631 on fd 9...
I [26/Aug/2013:14:34:11 -0700] Listening to /var/run/cups/cups.sock:631 on fd
10...
I [26/Aug/2013:14:34:11 -0700] Resuming new connection processing...
D [26/Aug/2013:14:34:11 -0700]
cupsdRegisterPrinter(p=0x7f70b2e6dba0(Lexmark_Lexmark_Optra_E312L))
D [26/Aug/2013:14:34:11 -0700] cupsdSetBusyState: newbusy="Dirty files",
busy="Dirty files"
D [26/Aug/2013:14:34:11 -0700] Discarding unused server-restarted event...
D [26/Aug/2013:14:34:11 -0700] Report: clients=0
D [26/Aug/2013:14:34:11 -0700] Report: jobs=5
D [26/Aug/2013:14:34:11 -0700] Report: jobs-active=0
D [26/Aug/2013:14:34:11 -0700] Report: printers=1
D [26/Aug/2013:14:34:11 -0700] Report: printers-implicit=0
D [26/Aug/2013:14:34:11 -0700] Report: stringpool-string-count=1493
D [26/Aug/2013:14:34:11 -0700] Report: stringpool-alloc-bytes=9744
D [26/Aug/2013:14:34:11 -0700] Report: stringpool-total-bytes=27808
D [26/Aug/2013:14:34:11 -0700] PID 4135 (/usr/lib/cups/notifier/dbus) was
terminated normally with signal 15.
D [26/Aug/2013:14:34:12 -0700] cupsdAcceptClient: 15 from localhost:631 (IPv6)
D [26/Aug/2013:14:34:12 -0700] cupsdAcceptClient: 16 from localhost:631 (IPv6)
D [26/Aug/2013:14:34:12 -0700] cupsdReadClient: 15 GET /cups.css HTTP/1.1
D [26/Aug/2013:14:34:12 -0700] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Dirty files"
D [26/Aug/2013:14:34:12 -0700] cupsdAuthorize: Authorized as annie using Basic
D [26/Aug/2013:14:34:12 -0700] cupsdSetBusyState: newbusy="Dirty files",
busy="Active clients and dirty files"
D [26/Aug/2013:14:34:12 -0700] cupsdReadClient: 16 GET /images/wait.gif
HTTP/1.1
D [26/Aug/2013:14:34:12 -0700] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Dirty files"
D [26/Aug/2013:14:34:12 -0700] cupsdAuthorize: Authorized as annie using Basic
D [26/Aug/2013:14:34:12 -0700] cupsdSetBusyState: newbusy="Dirty files",
busy="Active clients and dirty files"
D [26/Aug/2013:14:34:17 -0700] cupsdReadClient: 16 GET /admin/?OP=redirect
HTTP/1.1
D [26/Aug/2013:14:34:17 -0700] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Dirty files"
D [26/Aug/2013:14:34:17 -0700] cupsdAuthorize: Authorized as annie using Basic
D [26/Aug/2013:14:34:17 -0700] [CGI] argv[0] = "/usr/lib/cups/cgi-
bin/admin.cgi"
D [26/Aug/2013:14:34:17 -0700] [CGI] argv[1] = "OP=redirect"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups
/doc-root"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[3] =
"CUPS_FONTPATH=/usr/share/cups/fonts"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[4] =
"CUPS_REQUESTROOT=/var/spool/cups"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[9] =
"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[10] = "SERVER_ADMIN=root@debian"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.3"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[13] = "TZ=US/Pacific"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[14] = "USER=root"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[15] =
"CUPS_SERVER=/var/run/cups/cups.sock"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[17] = "IPP_PORT=631"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[18] = "AUTH_TYPE=Basic"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[19] = "LANG=en_US.UTF8"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[22] = "SERVER_NAME=localhost"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[23] = "SERVER_PORT=631"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[24] = "REMOTE_ADDR=[v1.::1]"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[26] = "SCRIPT_NAME=/admin/"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[27] =
"SCRIPT_FILENAME=/usr/share/cups/doc-root/admin/"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[28] = "REMOTE_USER=annie"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[30] =
"HTTP_COOKIE=org.cups.sid=c83233bb840f8b022b97c62f74a1d1c1"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0
(X11; Linux x86_64; rv:17.0) Gecko/20130806 Firefox/17.0 Iceweasel/17.0.8"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[32] = "REQUEST_METHOD=GET"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[33] = "QUERY_STRING=OP=redirect"
D [26/Aug/2013:14:34:17 -0700] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi
(PID 4947)
I [26/Aug/2013:14:34:17 -0700] Started "/usr/lib/cups/cgi-bin/admin.cgi"
(pid=4947)
D [26/Aug/2013:14:34:17 -0700] cupsdSendCommand: 16 file=17
D [26/Aug/2013:14:34:17 -0700] [CGI] admin.cgi started...
D [26/Aug/2013:14:34:17 -0700] cupsdAcceptClient: 18 from localhost (Domain)
D [26/Aug/2013:14:34:17 -0700] [CGI] http=0x7f5558c85c00
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: SECTION="admin"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: REFRESH_PAGE=""
D [26/Aug/2013:14:34:17 -0700] [CGI] org.cups.sid cookie is
"c83233bb840f8b022b97c62f74a1d1c1"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: OP="redirect"
D [26/Aug/2013:14:34:17 -0700] [CGI] redirecting with prefix
http://localhost:631!
D [26/Aug/2013:14:34:17 -0700] cupsdReadClient: 18 WAITING Closing on EOF
D [26/Aug/2013:14:34:17 -0700] cupsdCloseClient: 18
D [26/Aug/2013:14:34:17 -0700] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [26/Aug/2013:14:34:17 -0700] Script header: Location:
http://localhost:631/admin
D [26/Aug/2013:14:34:17 -0700] Script header:
D [26/Aug/2013:14:34:17 -0700] PID 4947 (/usr/lib/cups/cgi-bin/admin.cgi)
exited with no errors.
D [26/Aug/2013:14:34:17 -0700] cupsdSetBusyState: newbusy="Dirty files",
busy="Active clients and dirty files"
D [26/Aug/2013:14:34:17 -0700] cupsdReadClient: 16 GET /admin HTTP/1.1
D [26/Aug/2013:14:34:17 -0700] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Dirty files"
D [26/Aug/2013:14:34:17 -0700] cupsdAuthorize: Authorized as annie using Basic
D [26/Aug/2013:14:34:17 -0700] [CGI] argv[0] = "/usr/lib/cups/cgi-
bin/admin.cgi"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups
/doc-root"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[3] =
"CUPS_FONTPATH=/usr/share/cups/fonts"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[4] =
"CUPS_REQUESTROOT=/var/spool/cups"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[9] =
"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[10] = "SERVER_ADMIN=root@debian"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.3"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[13] = "TZ=US/Pacific"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[14] = "USER=root"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[15] =
"CUPS_SERVER=/var/run/cups/cups.sock"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[17] = "IPP_PORT=631"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[18] = "AUTH_TYPE=Basic"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[19] = "LANG=en_US.UTF8"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[22] = "SERVER_NAME=localhost"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[23] = "SERVER_PORT=631"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[24] = "REMOTE_ADDR=[v1.::1]"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[26] = "SCRIPT_NAME=/admin"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[27] =
"SCRIPT_FILENAME=/usr/share/cups/doc-root/admin"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[28] = "REMOTE_USER=annie"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[30] =
"HTTP_COOKIE=org.cups.sid=c83233bb840f8b022b97c62f74a1d1c1"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0
(X11; Linux x86_64; rv:17.0) Gecko/20130806 Firefox/17.0 Iceweasel/17.0.8"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[32] = "REQUEST_METHOD=GET"
D [26/Aug/2013:14:34:17 -0700] [CGI] envp[33] = "QUERY_STRING="
D [26/Aug/2013:14:34:17 -0700] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi
(PID 4948)
I [26/Aug/2013:14:34:17 -0700] Started "/usr/lib/cups/cgi-bin/admin.cgi"
(pid=4948)
D [26/Aug/2013:14:34:17 -0700] cupsdSendCommand: 16 file=17
D [26/Aug/2013:14:34:17 -0700] [CGI] admin.cgi started...
D [26/Aug/2013:14:34:17 -0700] cupsdAcceptClient: 18 from localhost (Domain)
D [26/Aug/2013:14:34:17 -0700] [CGI] http=0x7f2b92f65c00
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: SECTION="admin"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: REFRESH_PAGE=""
D [26/Aug/2013:14:34:17 -0700] [CGI] org.cups.sid cookie is
"c83233bb840f8b022b97c62f74a1d1c1"
D [26/Aug/2013:14:34:17 -0700] [CGI] No form data, showing main menu...
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: DEBUG_LOGGING="CHECKED"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: REMOTE_PRINTERS="CHECKED"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: HAVE_GSSAPI="1"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: KERBEROS=""
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: HAVE_DNSSD="1"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: HAVE_LDAP="1"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: HAVE_LIBSLP="1"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable:
BROWSE_REMOTE_CUPS="CHECKED"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable:
BROWSE_LOCAL_CUPS="CHECKED"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable:
BROWSE_LOCAL_DNSSD="CHECKED"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable:
PRESERVE_JOB_HISTORY="CHECKED"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: MAX_CLIENTS="100"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: MAX_JOBS="500"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: MAX_LOG_SIZE="0"
D [26/Aug/2013:14:34:17 -0700] [CGI] /usr/share/cups/drivers/pscript5.dll: No
such file or directory
D [26/Aug/2013:14:34:17 -0700] cupsdReadClient: 18 POST / HTTP/1.1
D [26/Aug/2013:14:34:17 -0700] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [26/Aug/2013:14:34:17 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:34:17 -0700] cupsdReadClient: 18 1.1 Get-Subscriptions 1
D [26/Aug/2013:14:34:17 -0700] Get-Subscriptions ipp://localhost/
D [26/Aug/2013:14:34:17 -0700] Returning IPP successful-ok for Get-
Subscriptions (ipp://localhost/) from localhost
D [26/Aug/2013:14:34:17 -0700] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: REMOTE_USER="annie"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: REMOTE_USER="annie"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetArray:
notify_lease_duration[0]="3600"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetArray: notify_time_interval[0]="0"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetArray: notify_subscription_id[0]="7"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: TITLE="Administration"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: REMOTE_USER="annie"
D [26/Aug/2013:14:34:17 -0700] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
D [26/Aug/2013:14:34:17 -0700] Script header: Content-Type:
text/html;charset=utf-8
D [26/Aug/2013:14:34:17 -0700] Script header:
D [26/Aug/2013:14:34:17 -0700] cupsdReadClient: 18 WAITING Closing on EOF
D [26/Aug/2013:14:34:17 -0700] cupsdCloseClient: 18
D [26/Aug/2013:14:34:17 -0700] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [26/Aug/2013:14:34:17 -0700] PID 4948 (/usr/lib/cups/cgi-bin/admin.cgi)
exited with no errors.
D [26/Aug/2013:14:34:17 -0700] cupsdSetBusyState: newbusy="Dirty files",
busy="Active clients and dirty files"
I [26/Aug/2013:14:34:42 -0700] Generating printcap /var/run/cups/printcap...
D [26/Aug/2013:14:34:42 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Dirty files"
D [26/Aug/2013:14:34:42 -0700] cupsdReadClient: 15 WAITING Closing on EOF
D [26/Aug/2013:14:34:42 -0700] cupsdCloseClient: 15
D [26/Aug/2013:14:34:42 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:34:47 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:34:47 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:34:47 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:34:54 -0700] cupsdAcceptClient: 15 from localhost:631 (IPv6)
D [26/Aug/2013:14:34:54 -0700] cupsdReadClient: 15 GET /admin/log/error_log
HTTP/1.1
D [26/Aug/2013:14:34:54 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:34:54 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:34:54 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:35:25 -0700] cupsdReadClient: 15 WAITING Closing on EOF
D [26/Aug/2013:14:35:25 -0700] cupsdCloseClient: 15
D [26/Aug/2013:14:35:25 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:35:25 -0700] Report: clients=0
D [26/Aug/2013:14:35:25 -0700] Report: jobs=5
D [26/Aug/2013:14:35:25 -0700] Report: jobs-active=0
D [26/Aug/2013:14:35:25 -0700] Report: printers=1
D [26/Aug/2013:14:35:25 -0700] Report: printers-implicit=0
D [26/Aug/2013:14:35:25 -0700] Report: stringpool-string-count=1493
D [26/Aug/2013:14:35:25 -0700] Report: stringpool-alloc-bytes=9744
D [26/Aug/2013:14:35:25 -0700] Report: stringpool-total-bytes=27808
D [26/Aug/2013:14:37:21 -0700] Report: clients=0
D [26/Aug/2013:14:37:21 -0700] Report: jobs=5
D [26/Aug/2013:14:37:21 -0700] Report: jobs-active=0
D [26/Aug/2013:14:37:21 -0700] Report: printers=1
D [26/Aug/2013:14:37:21 -0700] Report: printers-implicit=0
D [26/Aug/2013:14:37:21 -0700] Report: stringpool-string-count=1493
D [26/Aug/2013:14:37:21 -0700] Report: stringpool-alloc-bytes=9744
D [26/Aug/2013:14:37:21 -0700] Report: stringpool-total-bytes=27808
D [26/Aug/2013:14:38:35 -0700] Report: clients=0
D [26/Aug/2013:14:38:35 -0700] Report: jobs=5
D [26/Aug/2013:14:38:35 -0700] Report: jobs-active=0
D [26/Aug/2013:14:38:35 -0700] Report: printers=1
D [26/Aug/2013:14:38:35 -0700] Report: printers-implicit=0
D [26/Aug/2013:14:38:35 -0700] Report: stringpool-string-count=1493
D [26/Aug/2013:14:38:35 -0700] Report: stringpool-alloc-bytes=9744
D [26/Aug/2013:14:38:35 -0700] Report: stringpool-total-bytes=27808
D [26/Aug/2013:14:39:59 -0700] Report: clients=0
D [26/Aug/2013:14:39:59 -0700] Report: jobs=5
D [26/Aug/2013:14:39:59 -0700] Report: jobs-active=0
D [26/Aug/2013:14:39:59 -0700] Report: printers=1
D [26/Aug/2013:14:39:59 -0700] Report: printers-implicit=0
D [26/Aug/2013:14:39:59 -0700] Report: stringpool-string-count=1493
D [26/Aug/2013:14:39:59 -0700] Report: stringpool-alloc-bytes=9744
D [26/Aug/2013:14:39:59 -0700] Report: stringpool-total-bytes=27808
D [26/Aug/2013:14:41:25 -0700] Report: clients=0
D [26/Aug/2013:14:41:25 -0700] Report: jobs=5
D [26/Aug/2013:14:41:25 -0700] Report: jobs-active=0
D [26/Aug/2013:14:41:25 -0700] Report: printers=1
D [26/Aug/2013:14:41:25 -0700] Report: printers-implicit=0
D [26/Aug/2013:14:41:25 -0700] Report: stringpool-string-count=1493
D [26/Aug/2013:14:41:25 -0700] Report: stringpool-alloc-bytes=9744
D [26/Aug/2013:14:41:25 -0700] Report: stringpool-total-bytes=27808
D [26/Aug/2013:14:42:43 -0700] Report: clients=0
D [26/Aug/2013:14:42:43 -0700] Report: jobs=5
D [26/Aug/2013:14:42:43 -0700] Report: jobs-active=0
D [26/Aug/2013:14:42:43 -0700] Report: printers=1
D [26/Aug/2013:14:42:43 -0700] Report: printers-implicit=0
D [26/Aug/2013:14:42:43 -0700] Report: stringpool-string-count=1493
D [26/Aug/2013:14:42:43 -0700] Report: stringpool-alloc-bytes=9744
D [26/Aug/2013:14:42:43 -0700] Report: stringpool-total-bytes=27808
D [26/Aug/2013:14:44:01 -0700] Report: clients=0
D [26/Aug/2013:14:44:01 -0700] Report: jobs=5
D [26/Aug/2013:14:44:01 -0700] Report: jobs-active=0
D [26/Aug/2013:14:44:01 -0700] Report: printers=1
D [26/Aug/2013:14:44:01 -0700] Report: printers-implicit=0
D [26/Aug/2013:14:44:01 -0700] Report: stringpool-string-count=1493
D [26/Aug/2013:14:44:01 -0700] Report: stringpool-alloc-bytes=9744
D [26/Aug/2013:14:44:01 -0700] Report: stringpool-total-bytes=27808
D [26/Aug/2013:14:45:17 -0700] cupsdAcceptClient: 15 from localhost (Domain)
D [26/Aug/2013:14:45:17 -0700] Report: clients=1
D [26/Aug/2013:14:45:17 -0700] Report: jobs=5
D [26/Aug/2013:14:45:17 -0700] Report: jobs-active=0
D [26/Aug/2013:14:45:17 -0700] Report: printers=1
D [26/Aug/2013:14:45:17 -0700] Report: printers-implicit=0
D [26/Aug/2013:14:45:17 -0700] Report: stringpool-string-count=1493
D [26/Aug/2013:14:45:17 -0700] Report: stringpool-alloc-bytes=9744
D [26/Aug/2013:14:45:17 -0700] Report: stringpool-total-bytes=27808
D [26/Aug/2013:14:45:17 -0700] cupsdReadClient: 15 POST / HTTP/1.1
D [26/Aug/2013:14:45:17 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:17 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:17 -0700] cupsdReadClient: 15 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:17 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:17 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:17 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:17 -0700] cupsdReadClient: 15 POST / HTTP/1.1
D [26/Aug/2013:14:45:17 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:17 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:17 -0700] cupsdReadClient: 15 1.1 CUPS-Get-Default 1
D [26/Aug/2013:14:45:17 -0700] CUPS-Get-Default
D [26/Aug/2013:14:45:17 -0700] Returning IPP successful-ok for CUPS-Get-Default
(no URI) from localhost
D [26/Aug/2013:14:45:17 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:17 -0700] cupsdReadClient: 15 POST / HTTP/1.1
D [26/Aug/2013:14:45:17 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:17 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:17 -0700] cupsdReadClient: 15 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:17 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:17 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:17 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:17 -0700] cupsdReadClient: 15 POST / HTTP/1.1
D [26/Aug/2013:14:45:17 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:17 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:17 -0700] cupsdReadClient: 15 1.1 CUPS-Get-Default 1
D [26/Aug/2013:14:45:17 -0700] CUPS-Get-Default
D [26/Aug/2013:14:45:17 -0700] Returning IPP successful-ok for CUPS-Get-Default
(no URI) from localhost
D [26/Aug/2013:14:45:17 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:17 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:17 -0700] cupsdAcceptClient: 17 from localhost (Domain)
D [26/Aug/2013:14:45:17 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:17 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:17 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:17 -0700] cupsdReadClient: 17 POST / HTTP/1.1
D [26/Aug/2013:14:45:17 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:17 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:17 -0700] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:17 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:17 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:17 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:17 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:17 -0700] cupsdReadClient: 17 WAITING Closing on EOF
D [26/Aug/2013:14:45:17 -0700] cupsdCloseClient: 17
D [26/Aug/2013:14:45:17 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:17 -0700] cupsdReadClient: 16 GET
/printers/Lexmark_Lexmark_Optra_E312L.ppd HTTP/1.1
D [26/Aug/2013:14:45:17 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:17 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:17 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:18 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:18 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:18 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:18 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 16 POST / HTTP/1.1
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:18 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:18 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:18 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:18 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:18 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:18 -0700] cupsdAcceptClient: 17 from localhost (Domain)
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:18 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 17 POST / HTTP/1.1
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:18 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:18 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:18 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 17 WAITING Closing on EOF
D [26/Aug/2013:14:45:18 -0700] cupsdCloseClient: 17
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:18 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:18 -0700] cupsdAcceptClient: 17 from localhost (Domain)
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:18 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 17 POST / HTTP/1.1
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:18 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:18 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:18 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 17 WAITING Closing on EOF
D [26/Aug/2013:14:45:18 -0700] cupsdCloseClient: 17
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:18 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:18 -0700] cupsdAcceptClient: 17 from localhost (Domain)
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:18 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 17 POST / HTTP/1.1
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:18 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:18 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:18 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 17 WAITING Closing on EOF
D [26/Aug/2013:14:45:18 -0700] cupsdCloseClient: 17
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:18 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:18 -0700] cupsdAcceptClient: 17 from localhost (Domain)
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:18 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 17 POST / HTTP/1.1
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:18 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:18 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:18 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:18 -0700] cupsdReadClient: 17 WAITING Closing on EOF
D [26/Aug/2013:14:45:18 -0700] cupsdCloseClient: 17
D [26/Aug/2013:14:45:18 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:19 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:19 -0700] cupsdAcceptClient: 17 from localhost (Domain)
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:19 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 17 POST / HTTP/1.1
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:19 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:19 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:19 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 17 WAITING Closing on EOF
D [26/Aug/2013:14:45:19 -0700] cupsdCloseClient: 17
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:19 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:19 -0700] cupsdAcceptClient: 17 from localhost (Domain)
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:19 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 17 POST / HTTP/1.1
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:19 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:19 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:19 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 17 WAITING Closing on EOF
D [26/Aug/2013:14:45:19 -0700] cupsdCloseClient: 17
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:19 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:19 -0700] cupsdAcceptClient: 17 from localhost (Domain)
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:19 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 17 POST / HTTP/1.1
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:19 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:19 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:19 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 17 WAITING Closing on EOF
D [26/Aug/2013:14:45:19 -0700] cupsdCloseClient: 17
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:19 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:19 -0700] cupsdAcceptClient: 17 from localhost (Domain)
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:19 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 17 POST / HTTP/1.1
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:19 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:19 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:19 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 17 WAITING Closing on EOF
D [26/Aug/2013:14:45:19 -0700] cupsdCloseClient: 17
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:19 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:19 -0700] cupsdAcceptClient: 17 from localhost (Domain)
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:19 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 17 POST / HTTP/1.1
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:19 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:19 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:19 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:19 -0700] cupsdReadClient: 17 WAITING Closing on EOF
D [26/Aug/2013:14:45:19 -0700] cupsdCloseClient: 17
D [26/Aug/2013:14:45:19 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:20 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:20 -0700] cupsdAcceptClient: 17 from localhost (Domain)
D [26/Aug/2013:14:45:20 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:20 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:20 -0700] cupsdReadClient: 17 POST / HTTP/1.1
D [26/Aug/2013:14:45:20 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:20 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:20 -0700] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:20 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:20 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:20 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:20 -0700] cupsdReadClient: 17 WAITING Closing on EOF
D [26/Aug/2013:14:45:20 -0700] cupsdCloseClient: 17
D [26/Aug/2013:14:45:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:20 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:20 -0700] cupsdAcceptClient: 17 from localhost (Domain)
D [26/Aug/2013:14:45:20 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:20 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:20 -0700] cupsdReadClient: 17 POST / HTTP/1.1
D [26/Aug/2013:14:45:20 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:20 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:20 -0700] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:20 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:20 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:20 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:20 -0700] cupsdReadClient: 17 WAITING Closing on EOF
D [26/Aug/2013:14:45:20 -0700] cupsdCloseClient: 17
D [26/Aug/2013:14:45:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:20 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:20 -0700] cupsdAcceptClient: 17 from localhost (Domain)
D [26/Aug/2013:14:45:20 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:20 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:20 -0700] cupsdReadClient: 17 POST / HTTP/1.1
D [26/Aug/2013:14:45:20 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:20 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:20 -0700] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:20 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:20 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:20 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:20 -0700] cupsdReadClient: 17 WAITING Closing on EOF
D [26/Aug/2013:14:45:20 -0700] cupsdCloseClient: 17
D [26/Aug/2013:14:45:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:20 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:20 -0700] cupsdAcceptClient: 17 from localhost (Domain)
D [26/Aug/2013:14:45:20 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:20 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:20 -0700] cupsdReadClient: 17 POST / HTTP/1.1
D [26/Aug/2013:14:45:20 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:20 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:20 -0700] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:20 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:20 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:20 -0700] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [26/Aug/2013:14:45:20 -0700] cupsdReadClient: 17 WAITING Closing on EOF
D [26/Aug/2013:14:45:20 -0700] cupsdCloseClient: 17
D [26/Aug/2013:14:45:20 -0700] cupsdSetBusyState: newbusy="Not busy", busy="Not
busy"
D [26/Aug/2013:14:45:25 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:25 -0700] cupsdReadClient: 16 POST
/printers/Lexmark_Lexmark_Optra_E312L HTTP/1.1
D [26/Aug/2013:14:45:25 -0700] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [26/Aug/2013:14:45:25 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:25 -0700] cupsdReadClient: 16 1.1 Print-Job 1
D [26/Aug/2013:14:45:25 -0700] Print-Job
ipp://localhost:631/printers/Lexmark_Lexmark_Optra_E312L
D [26/Aug/2013:14:45:25 -0700] [Job ???] Auto-typing file...
I [26/Aug/2013:14:45:25 -0700] [Job ???] Request file type is application/pdf.
D [26/Aug/2013:14:45:25 -0700] cupsdMarkDirty(----J-)
D [26/Aug/2013:14:45:25 -0700] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients"
D [26/Aug/2013:14:45:25 -0700] add_job: requesting-user-name="annie"
I [26/Aug/2013:14:45:25 -0700] [Job 6] Adding start banner page "none".
D [26/Aug/2013:14:45:25 -0700] Notifier dbus started - PID = 5962
D [26/Aug/2013:14:45:25 -0700] cupsdMarkDirty(-----S)
D [26/Aug/2013:14:45:25 -0700] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [26/Aug/2013:14:45:25 -0700] cupsdMarkDirty(----J-)
D [26/Aug/2013:14:45:25 -0700] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
I [26/Aug/2013:14:45:25 -0700] [Job 6] Adding end banner page "none".
I [26/Aug/2013:14:45:25 -0700] [Job 6] File of type application/pdf queued by
"annie".
D [26/Aug/2013:14:45:25 -0700] [Job 6] hold_until=0
I [26/Aug/2013:14:45:25 -0700] [Job 6] Queued on "Lexmark_Lexmark_Optra_E312L"
by "annie".
D [26/Aug/2013:14:45:25 -0700] cupsdMarkDirty(----J-)
D [26/Aug/2013:14:45:25 -0700] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [26/Aug/2013:14:45:25 -0700] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [26/Aug/2013:14:45:25 -0700] cupsdMarkDirty(-----S)
D [26/Aug/2013:14:45:25 -0700] cupsdSetBusyState: newbusy="Active clients and
dirty files", busy="Active clients and dirty files"
D [26/Aug/2013:14:45:25 -0700] [Job 6] job-sheets=none,none
D [26/Aug/2013:14:45:25 -0700] [Job 6] argv[0]="Lexmark_Lexmark_Optra_E312L"
D [26/Aug/2013:14:45:25 -0700] [Job 6] argv[1]="6"
D [26/Aug/2013:14:45:25 -0700] [Job 6] argv[2]="annie"
D [26/Aug/2013:14:45:25 -0700] [Job 6] argv[3]="pastebin - Untitled - post
number 2438323"
D [26/Aug/2013:14:45:25 -0700] [Job 6] argv[4]="1"
D [26/Aug/2013:14:45:25 -0700] [Job 6] argv[5]="InputSlot=Default
PageSize=Letter Duplex=None Resolution=600x600dpi number-up=1 job-
uuid=urn:uuid:849ee2a1-1e53-3965-627f-6e2898c6619b job-originating-host-
name=localhost time-at-creation=1377553525 time-at-processing=1377553525"
D [26/Aug/2013:14:45:25 -0700] [Job 6] argv[6]="/var/spool/cups/d00006-001"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[2]="CUPS_DOCROOT=/usr/share/cups
/doc-root"
D [26/Aug/2013:14:45:25 -0700] [Job 6]
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [26/Aug/2013:14:45:25 -0700] [Job 6]
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[8]="HOME=/var/spool/cups/tmp"
D [26/Aug/2013:14:45:25 -0700] [Job 6]
envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[10]="SERVER_ADMIN=root@debian"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[11]="SOFTWARE=CUPS/1.5.3"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[13]="TZ=US/Pacific"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[14]="USER=root"
D [26/Aug/2013:14:45:25 -0700] [Job 6]
envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[17]="IPP_PORT=631"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[18]="CHARSET=utf-8"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[19]="LANG=en_US.UTF-8"
D [26/Aug/2013:14:45:25 -0700] [Job 6]
envp[20]="PPD=/etc/cups/ppd/Lexmark_Lexmark_Optra_E312L.ppd"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[21]="RIP_MAX_CACHE=128m"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[22]="CONTENT_TYPE=application/pdf"
D [26/Aug/2013:14:45:25 -0700] [Job 6]
envp[23]="DEVICE_URI=usb://Lexmark/Optra%20E312L?serial=3118312"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[24]="PRINTER_INFO=Optra High Tech
LAzarz fast printin"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[25]="PRINTER_LOCATION=Local
Printer"
D [26/Aug/2013:14:45:25 -0700] [Job 6]
envp[26]="PRINTER=Lexmark_Lexmark_Optra_E312L"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[27]="PRINTER_STATE_REASONS=none"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[28]="CUPS_FILETYPE=document"
D [26/Aug/2013:14:45:25 -0700] [Job 6]
envp[29]="FINAL_CONTENT_TYPE=printer/Lexmark_Lexmark_Optra_E312L"
D [26/Aug/2013:14:45:25 -0700] [Job 6] envp[30]="AUTH_I****"
I [26/Aug/2013:14:45:25 -0700] [Job 6] Started filter
/usr/lib/cups/filter/pdftopdf (PID 5963)
I [26/Aug/2013:14:45:25 -0700] [Job 6] Started filter /usr/lib/cups/filter
/foomatic-rip (PID 5964)
I [26/Aug/2013:14:45:25 -0700] [Job 6] Started backend
/usr/lib/cups/backend/usb (PID 5965)
D [26/Aug/2013:14:45:25 -0700] cupsdMarkDirty(-----S)
D [26/Aug/2013:14:45:25 -0700] cupsdSetBusyState: newbusy="Active clients,
printing jobs, and dirty files", busy="Active clients and dirty files"
D [26/Aug/2013:14:45:25 -0700] Returning IPP successful-ok for Print-Job
(ipp://localhost:631/printers/Lexmark_Lexmark_Optra_E312L) from localhost
D [26/Aug/2013:14:45:25 -0700] cupsdSetBusyState: newbusy="Printing jobs and
dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Aug/2013:14:45:25 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [26/Aug/2013:14:45:25 -0700] cupsdCloseClient: 16
D [26/Aug/2013:14:45:25 -0700] cupsdSetBusyState: newbusy="Printing jobs and
dirty files", busy="Printing jobs and dirty files"
D [26/Aug/2013:14:45:25 -0700] [Notifier] state=3
D [26/Aug/2013:14:45:25 -0700] [Notifier] Connected to D-BUS
D [26/Aug/2013:14:45:25 -0700] [Notifier] state=3
D [26/Aug/2013:14:45:25 -0700] [Notifier] state=3
D [26/Aug/2013:14:45:25 -0700] [Job 6] Printing on printer with URI:
usb://Lexmark/Optra%20E312L?serial=3118312
D [26/Aug/2013:14:45:25 -0700] [Job 6] libusb_get_device_list=4
D [26/Aug/2013:14:45:25 -0700] [Job 6] STATE: +connecting-to-device
D [26/Aug/2013:14:45:25 -0700] cupsdMarkDirty(-----S)
D [26/Aug/2013:14:45:25 -0700] cupsdSetBusyState: newbusy="Dirty files",
busy="Printing jobs and dirty files"
D [26/Aug/2013:14:45:25 -0700] [Notifier] state=3
D [26/Aug/2013:14:45:25 -0700] [Job 6] STATE: -connecting-to-device
D [26/Aug/2013:14:45:25 -0700] cupsdMarkDirty(-----S)
D [26/Aug/2013:14:45:25 -0700] cupsdSetBusyState: newbusy="Printing jobs and
dirty files", busy="Dirty files"
D [26/Aug/2013:14:45:25 -0700] [Notifier] state=3
D [26/Aug/2013:14:45:25 -0700] [Job 6] Getting input from file
D [26/Aug/2013:14:45:25 -0700] [Job 6] Device protocol: 2
D [26/Aug/2013:14:45:25 -0700] [Job 6] foomatic-rip version 4.0.17.256
running...
D [26/Aug/2013:14:45:25 -0700] [Job 6] Parsing PPD file ...
D [26/Aug/2013:14:45:25 -0700] [Job 6] Read 12 bytes of back-channel data...
D [26/Aug/2013:14:45:25 -0700] [Job 6] Added option ColorSpace
D [26/Aug/2013:14:45:25 -0700] [Job 6] Added option PageSize
D [26/Aug/2013:14:45:25 -0700] [Job 6] CUPS page accounting disabled by driver.
D [26/Aug/2013:14:45:25 -0700] [Job 6] Added option ImageableArea
D [26/Aug/2013:14:45:25 -0700] [Job 6] Added option PaperDimension
D [26/Aug/2013:14:45:25 -0700] [Job 6] Added option InputSlot
D [26/Aug/2013:14:45:25 -0700] [Job 6] Added option Duplex
D [26/Aug/2013:14:45:25 -0700] [Job 6] Added option Resolution
D [26/Aug/2013:14:45:25 -0700] [Job 6] Added option Font
D [26/Aug/2013:14:45:25 -0700] [Job 6]
D [26/Aug/2013:14:45:25 -0700] [Job 6] Parameter Summary
D [26/Aug/2013:14:45:25 -0700] [Job 6] -----------------
D [26/Aug/2013:14:45:25 -0700] [Job 6]
D [26/Aug/2013:14:45:25 -0700] [Job 6] Spooler: cups
D [26/Aug/2013:14:45:25 -0700] [Job 6] Printer: Lexmark_Lexmark_Optra_E312L
D [26/Aug/2013:14:45:25 -0700] [Job 6] Shell: /bin/bash
D [26/Aug/2013:14:45:25 -0700] [Job 6] PPD file:
/etc/cups/ppd/Lexmark_Lexmark_Optra_E312L.ppd
D [26/Aug/2013:14:45:25 -0700] [Job 6] ATTR file:
D [26/Aug/2013:14:45:25 -0700] [Job 6] Printer model: Lexmark Optra E312
Foomatic/Postscript (recommended)
D [26/Aug/2013:14:45:25 -0700] [Job 6] Job title: pastebin - Untitled - post
number 2438323
D [26/Aug/2013:14:45:25 -0700] [Job 6] File(s) to be printed:
D [26/Aug/2013:14:45:25 -0700] [Job 6] <STDIN>
D [26/Aug/2013:14:45:25 -0700] [Job 6]
D [26/Aug/2013:14:45:25 -0700] [Job 6] Ghostscript extra search path
('GS_LIB'): /usr/share/cups/fonts
D [26/Aug/2013:14:45:25 -0700] [Job 6] Printing system options:
D [26/Aug/2013:14:45:25 -0700] [Job 6] Pondering option 'number-up=1'
D [26/Aug/2013:14:45:25 -0700] [Job 6] Unknown option number-up=1.
D [26/Aug/2013:14:45:25 -0700] [Job 6] Pondering option 'job-
uuid=urn:uuid:849ee2a1-1e53-3965-627f-6e2898c6619b'
D [26/Aug/2013:14:45:25 -0700] [Job 6] Unknown option job-
uuid=urn:uuid:849ee2a1-1e53-3965-627f-6e2898c6619b.
D [26/Aug/2013:14:45:25 -0700] [Job 6] Pondering option 'job-originating-host-
name=localhost'
D [26/Aug/2013:14:45:25 -0700] [Job 6] Unknown option job-originating-host-
name=localhost.
D [26/Aug/2013:14:45:25 -0700] [Job 6] Pondering option 'time-at-
creation=1377553525'
D [26/Aug/2013:14:45:25 -0700] [Job 6] Unknown option time-at-
creation=1377553525.
D [26/Aug/2013:14:45:25 -0700] [Job 6] Pondering option 'time-at-
processing=1377553525'
D [26/Aug/2013:14:45:25 -0700] [Job 6] Unknown option time-at-
processing=1377553525.
D [26/Aug/2013:14:45:25 -0700] [Job 6] Options from the PPD file:
D [26/Aug/2013:14:45:25 -0700] [Job 6] Pondering option 'InputSlot=Default'
D [26/Aug/2013:14:45:25 -0700] [Job 6] Pondering option 'PageSize=Letter'
D [26/Aug/2013:14:45:25 -0700] [Job 6] Pondering option 'Duplex=None'
D [26/Aug/2013:14:45:25 -0700] [Job 6] Pondering option 'Resolution=600x600dpi'
D [26/Aug/2013:14:45:25 -0700] [Job 6]
D [26/Aug/2013:14:45:25 -0700] [Job 6]
================================================
D [26/Aug/2013:14:45:25 -0700] [Job 6]
D [26/Aug/2013:14:45:25 -0700] [Job 6] File: <STDIN>
D [26/Aug/2013:14:45:25 -0700] [Job 6]
D [26/Aug/2013:14:45:25 -0700] [Job 6]
================================================
D [26/Aug/2013:14:45:25 -0700] [Job 6]
I [26/Aug/2013:14:45:25 -0700] [Job 6] Sending data to printer.
D [26/Aug/2013:14:45:25 -0700] [Job 6] Set job-printer-state-message to
"Sending data to printer.", current level=INFO
D [26/Aug/2013:14:45:25 -0700] Discarding unused job-progress event...
D [26/Aug/2013:14:45:25 -0700] cupsdMarkDirty(-----S)
D [26/Aug/2013:14:45:25 -0700] cupsdSetBusyState: newbusy="Printing jobs and
dirty files", busy="Printing jobs and dirty files"
D [26/Aug/2013:14:45:25 -0700] [Notifier] state=3
D [26/Aug/2013:14:45:26 -0700] [Job 6] Filetype: PDF
D [26/Aug/2013:14:45:26 -0700] [Job 6] PostScript option found:
PageSize=Letter: "<</PageSize[612 792]/ImagingBBox null>>setpagedevice"
D [26/Aug/2013:14:45:26 -0700] [Job 6] Driver does not understand PDF input,
converting to PostScript
D [26/Aug/2013:14:45:26 -0700] [Job 6] Storing temporary files in
/var/spool/cups/tmp
D [26/Aug/2013:14:45:26 -0700] [Job 6] Starting process "pdf-to-ps" (generation
1)
D [26/Aug/2013:14:45:26 -0700] PID 5963 (/usr/lib/cups/filter/pdftopdf) exited
with no errors.
D [26/Aug/2013:14:45:26 -0700] [Job 6] Using image rendering resolution 600 dpi
D [26/Aug/2013:14:45:26 -0700] [Job 6] Started filter gs (PID 5969)
D [26/Aug/2013:14:45:26 -0700] [Job 6] Started filter pstops (PID 5970)
D [26/Aug/2013:14:45:32 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [26/Aug/2013:14:45:32 -0700] cupsdReadClient: 16 POST / HTTP/1.1
D [26/Aug/2013:14:45:32 -0700] cupsdSetBusyState: newbusy="Active clients,
printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [26/Aug/2013:14:45:32 -0700] cupsdAuthorize: No authentication data provided.
D [26/Aug/2013:14:45:32 -0700] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [26/Aug/2013:14:45:32 -0700] CUPS-Get-Printers
D [26/Aug/2013:14:45:32 -0700] Returning IPP successful-ok for CUPS-Get-
Printers (no URI) from localhost
D [26/Aug/2013:14:45:32 -0700] cupsdSetBusyState: newbusy="Printing jobs and
dirty files", busy="Active clients, printing jobs, and dirty files"
D [26/Aug/2013:14:45:39 -0700] cupsdAcceptClient: 21 from localhost:631 (IPv6)
D [26/Aug/2013:14:45:39 -0700] cupsdReadClient: 21 GET /admin/log/error_log
HTTP/1.1
D [26/Aug/2013:14:45:39 -0700] cupsdSetBusyState: newbusy="Active clients,
printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [26/Aug/2013:14:45:39 -0700] cupsdAuthorize: No authentication data provided.
-- System Information:
Debian Release: 7.1
APT prefers stable-updates
APT policy: (500, 'stable-updates'), (500, 'stable')
Architecture: amd64 (x86_64)
Foreign Architectures: i386
Kernel: Linux 3.2.0-4-amd64 (SMP w/2 CPU cores)
Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash
Versions of packages cups depends on:
ii adduser 3.113+nmu3
ii bc 1.06.95-2+b1
ii cups-client 1.5.3-5
ii cups-common 1.5.3-5
ii cups-filters 1.0.18-2.1
ii cups-ppdc 1.5.3-5
ii debconf [debconf-2.0] 1.5.49
ii dpkg 1.16.10
ii ghostscript 9.05~dfsg-6.3
ii libavahi-client3 0.6.31-2
ii libavahi-common3 0.6.31-2
ii libc-bin 2.13-38
ii libc6 2.13-38
ii libcups2 1.5.3-5
ii libcupscgi1 1.5.3-5
ii libcupsimage2 1.5.3-5
ii libcupsmime1 1.5.3-5
ii libcupsppdc1 1.5.3-5
ii libdbus-1-3 1.6.8-1+deb7u1
ii libgcc1 1:4.7.2-5
ii libgnutls26 2.12.20-7
ii libgssapi-krb5-2 1.10.1+dfsg-5+deb7u1
ii libkrb5-3 1.10.1+dfsg-5+deb7u1
ii libldap-2.4-2 2.4.31-1+nmu2
ii libpam0g 1.1.3-7.1
ii libpaper1 1.1.24+nmu2
ii libslp1 1.2.1-9
ii libstdc++6 4.7.2-5
ii libusb-1.0-0 2:1.0.11-1
ii lsb-base 4.1+Debian8+deb7u1
ii poppler-utils 0.18.4-6
ii procps 1:3.3.3-3
ii ssl-cert 1.0.32
Versions of packages cups recommends:
ii avahi-daemon 0.6.31-2
ii colord 0.1.21-1
ii foomatic-filters 4.0.17-1
ii ghostscript-cups 9.05~dfsg-6.3
ii printer-driver-gutenprint 5.2.9-1
Versions of packages cups suggests:
ii cups-bsd 1.5.3-5
pn cups-pdf <none>
ii foomatic-db-compressed-ppds [foomatic-db] 20120523-1
ii hplip 3.12.6-3.1
ii printer-driver-hpcups 3.12.6-3.1
ii smbclient 2:3.6.6-6
ii udev 175-7.2
-- debconf information:
cupsys/raw-print: true
cupsys/backend: ipp, lpd, socket, usb, snmp, dnssd
Reply to: