Bug#721000: marked as done (cups: CUPS fails to print with Lexmark Optra E312L)

To: Brian Potkin <claremont102@gmail.com>
Subject: Bug#721000: marked as done (cups: CUPS fails to print with Lexmark Optra E312L)
From: owner@bugs.debian.org (Debian Bug Tracking System)
Date: Tue, 27 Aug 2013 12:21:10 +0000
Message-id: <[🔎] handler.721000.D721000.137760585029562.ackdone@bugs.debian.org>
References: <27082013115644.84445700958b@desktop.copernicus.demon.co.uk> <[🔎] 20130826215341.6139.80567.reportbug@debian>

Your message dated Tue, 27 Aug 2013 13:17:19 +0100
with message-id <27082013115644.84445700958b@desktop.copernicus.demon.co.uk>
and subject line Re: Bug#721000: cups: CUPS fails to print with Lexmark Optra E312L
has caused the Debian Bug report #721000,
regarding cups: CUPS fails to print with Lexmark Optra E312L
to be marked as done.

This means that you claim that the problem has been dealt with.
If this is not the case it is now your responsibility to reopen the
Bug report if necessary, and/or fix the problem forthwith.

(NB: If you are a system administrator and have no idea what this
message is talking about, this may indicate a serious mail system
misconfiguration somewhere. Please contact owner@bugs.debian.org
immediately.)


-- 
721000: http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=721000
Debian Bug Tracking System
Contact owner@bugs.debian.org with problems

--- Begin Message ---

To: Debian Bug Tracking System <submit@bugs.debian.org>
Subject: cups: CUPS fails to print with Lexmark Optra E312L
From: Jesse Taylor <jrtayloriv@gmail.com>
Date: Mon, 26 Aug 2013 14:53:41 -0700
Message-id: <[🔎] 20130826215341.6139.80567.reportbug@debian>

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

--- End Message ---

--- Begin Message ---

To: 721000-done@bugs.debian.org
Subject: Re: Bug#721000: cups: CUPS fails to print with Lexmark Optra E312L
From: Brian Potkin <claremont102@gmail.com>
Date: Tue, 27 Aug 2013 13:17:19 +0100
Message-id: <27082013115644.84445700958b@desktop.copernicus.demon.co.uk>
In-reply-to: <[🔎] 20130826215341.6139.80567.reportbug@debian>
References: <[🔎] 20130826215341.6139.80567.reportbug@debian>

Hello Jesse,

Thank you for the error log. It shows the filters starting as expected.
It does not show all of them exiting (foomatic-rip, for example). I do
not know how significant this is.

On Mon 26 Aug 2013 at 14:53:41 -0700, Jesse Taylor wrote:

> 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:

There is a foomatic/postscript PPD for the Optra E312; you could try
it. Some tips for troubleshooting are also available at

   http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=717596

Having said that, I am minded to close this report on essentially the
same basis as expressed in Message 36 at

   http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=701954

While debugging the issue there would always be a niggling suspicion at
the back of one's mind that Lexmark's driver package might be playing a
part in the problem.

Regards,

Brian.

--- End Message ---

Reply to:

References:
- Bug#721000: cups: CUPS fails to print with Lexmark Optra E312L
  - From: Jesse Taylor <jrtayloriv@gmail.com>

Prev by Date: Bug#496070: [ghostscript] opentypefont
Next by Date: Bug#496070: [ghostscript] opentypefont
Previous by thread: Bug#721000: cups: CUPS fails to print with Lexmark Optra E312L
Next by thread: Bug#496070: marked as done (please provide truetype flavors of the ghostscript fonts)
Index(es):
- Date
- Thread