[cups.general] Raw Printing Doesn't work...

Chris cjdl01 at brokensolstice.com
Tue Apr 26 08:48:52 PDT 2011


> What does "lpstat -v" report?
>
> If you enable debug logging (cupsctl --debug-logging) and try to

Okay, I have been able to replicate this problem at home with an Epson  
Stylus r2400 on a debian Squeeze system with Cups 1.4.4-7 (everything  
the same except the printer model).

lpstat -v reports:
device for CUPS-Test: usb://EPSON/Stylus%20Photo%20R2400

I have upped the log output to debug, and it finally shows some  
output.  I set this printer as the default, then I printed a test page  
from the web interface.  This was the log output:
============================<log>=====================================
D [26/Apr/2011:11:44:38 -0400] cupsdAcceptClient: 12 from localhost:631 (IPv4)
D [26/Apr/2011:11:44:38 -0400] Report: clients=1
D [26/Apr/2011:11:44:38 -0400] Report: jobs=5
D [26/Apr/2011:11:44:38 -0400] Report: jobs-active=0
D [26/Apr/2011:11:44:38 -0400] Report: printers=1
D [26/Apr/2011:11:44:38 -0400] Report: printers-implicit=0
D [26/Apr/2011:11:44:38 -0400] Report: stringpool-string-count=417
D [26/Apr/2011:11:44:38 -0400] Report: stringpool-alloc-bytes=5184
D [26/Apr/2011:11:44:38 -0400] Report: stringpool-total-bytes=9600
D [26/Apr/2011:11:44:38 -0400] cupsdReadClient: 12 GET / HTTP/1.1
D [26/Apr/2011:11:44:38 -0400] cupsdSetBusyState: Active clients
D [26/Apr/2011:11:44:38 -0400] cupsdAuthorize: No authentication data  
provided.
D [26/Apr/2011:11:44:38 -0400] cupsdSetBusyState: Not busy
D [26/Apr/2011:11:44:41 -0400] cupsdReadClient: 12 GET /printers/ HTTP/1.1
D [26/Apr/2011:11:44:41 -0400] cupsdSetBusyState: Active clients
D [26/Apr/2011:11:44:41 -0400] cupsdAuthorize: No authentication data  
provided.
D [26/Apr/2011:11:44:41 -0400] [CGI] argv[0] =  
"/usr/lib/cups/cgi-bin/printers.cgi"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[2] =  
"CUPS_DOCROOT=/usr/share/cups/doc-root"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[3] =  
"CUPS_FONTPATH=/usr/share/cups/fonts"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[4] =  
"CUPS_REQUESTROOT=/var/spool/cups"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[9] =  
"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[10] =  
"SERVER_ADMIN=root at debian-swan2"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[13] = "TZ=America/New_York"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[14] = "USER=root"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[15] =  
"CUPS_SERVER=/var/run/cups/cups.sock"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[17] = "IPP_PORT=631"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[18] = "LANG=en.UTF8"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1..1"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[21] = "SERVER_NAME=localhost"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[22] = "SERVER_PORT=631"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[23] = "REMOTE_ADDR=127.0.0.1"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[25] = "SCRIPT_NAME=/printers/"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[26] =  
"SCRIPT_FILENAME=/usr/share/cups/doc-root/printers/"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[28] = "HTTP_USER_AGENT=Links  
(2.3pre1; Linux 2.6.32-5-686 i686; 105x36)"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[29] = "REQUEST_METHOD=GET"
D [26/Apr/2011:11:44:41 -0400] [CGI] envp[30] = "QUERY_STRING="
D [26/Apr/2011:11:44:41 -0400] [CGI] Started  
/usr/lib/cups/cgi-bin/printers.cgi (PID 10117)
I [26/Apr/2011:11:44:41 -0400] Started  
"/usr/lib/cups/cgi-bin/printers.cgi" (pid=10117)
D [26/Apr/2011:11:44:41 -0400] cupsdSendCommand: 12 file=14
D [26/Apr/2011:11:44:41 -0400] [CGI] org.cups.sid cookie not found,  
initializing!
D [26/Apr/2011:11:44:41 -0400] [CGI] org.cups.sid cookie is  
"a2e3feadd32a8d740e114ab9a889b1a5"
D [26/Apr/2011:11:44:41 -0400] cupsdAcceptClient: 15 from localhost (Domain)
D [26/Apr/2011:11:44:41 -0400] cupsdReadClient: 15 POST / HTTP/1.1
D [26/Apr/2011:11:44:41 -0400] cupsdAuthorize: No authentication data  
provided.
D [26/Apr/2011:11:44:41 -0400] cupsdReadClient: 15 1.1 CUPS-Get-Default 1
D [26/Apr/2011:11:44:41 -0400] CUPS-Get-Default
D [26/Apr/2011:11:44:41 -0400] Returning IPP successful-ok for  
CUPS-Get-Default (no URI) from localhost
D [26/Apr/2011:11:44:41 -0400] [CGI]  
show_all_printers(http=0xb819dd50, user="(null)")
D [26/Apr/2011:11:44:41 -0400] Script header: Set-Cookie:  
org.cups.sid=a2e3feadd32a8d740e114ab9a889b1a5;; path=/;
D [26/Apr/2011:11:44:41 -0400] Script header: Content-Type:  
text/html;charset=utf-8
D [26/Apr/2011:11:44:41 -0400] Script header:
D [26/Apr/2011:11:44:41 -0400] cupsdReadClient: 15 POST / HTTP/1.1
D [26/Apr/2011:11:44:41 -0400] cupsdAuthorize: No authentication data  
provided.
D [26/Apr/2011:11:44:41 -0400] cupsdReadClient: 15 1.1 CUPS-Get-Printers 1
D [26/Apr/2011:11:44:41 -0400] CUPS-Get-Printers
D [26/Apr/2011:11:44:41 -0400] Returning IPP successful-ok for  
CUPS-Get-Printers (no URI) from localhost
D [26/Apr/2011:11:44:41 -0400] PID 10117  
(/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [26/Apr/2011:11:44:41 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [26/Apr/2011:11:44:41 -0400] cupsdCloseClient: 15
D [26/Apr/2011:11:44:41 -0400] cupsdSetBusyState: Not busy
D [26/Apr/2011:11:44:44 -0400] cupsdReadClient: 12 GET  
/printers/CUPS-Test HTTP/1.1
D [26/Apr/2011:11:44:44 -0400] cupsdSetBusyState: Active clients
D [26/Apr/2011:11:44:44 -0400] cupsdAuthorize: No authentication data  
provided.
D [26/Apr/2011:11:44:44 -0400] [CGI] argv[0] =  
"/usr/lib/cups/cgi-bin/printers.cgi"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[2] =  
"CUPS_DOCROOT=/usr/share/cups/doc-root"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[3] =  
"CUPS_FONTPATH=/usr/share/cups/fonts"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[4] =  
"CUPS_REQUESTROOT=/var/spool/cups"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[9] =  
"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[10] =  
"SERVER_ADMIN=root at debian-swan2"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[13] = "TZ=America/New_York"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[14] = "USER=root"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[15] =  
"CUPS_SERVER=/var/run/cups/cups.sock"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[17] = "IPP_PORT=631"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[18] = "LANG=en.UTF8"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1..1"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[21] = "SERVER_NAME=localhost"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[22] = "SERVER_PORT=631"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[23] = "REMOTE_ADDR=127.0.0.1"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[25] =  
"SCRIPT_NAME=/printers/CUPS-Test"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[26] =  
"SCRIPT_FILENAME=/usr/share/cups/doc-root/printers/CUPS-Test"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[27] = "PATH_INFO=/CUPS-Test"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[29] =  
"HTTP_COOKIE=org.cups.sid=a2e3feadd32a8d740e114ab9a889b1a5"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[30] = "HTTP_USER_AGENT=Links  
(2.3pre1; Linux 2.6.32-5-686 i686; 105x36)"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[31] = "REQUEST_METHOD=GET"
D [26/Apr/2011:11:44:44 -0400] [CGI] envp[32] = "QUERY_STRING="
D [26/Apr/2011:11:44:44 -0400] [CGI] Started  
/usr/lib/cups/cgi-bin/printers.cgi (PID 10118)
I [26/Apr/2011:11:44:44 -0400] Started  
"/usr/lib/cups/cgi-bin/printers.cgi" (pid=10118)
D [26/Apr/2011:11:44:44 -0400] cupsdSendCommand: 12 file=14
D [26/Apr/2011:11:44:44 -0400] [CGI] org.cups.sid cookie is  
"a2e3feadd32a8d740e114ab9a889b1a5"
D [26/Apr/2011:11:44:44 -0400] cupsdAcceptClient: 15 from localhost (Domain)
D [26/Apr/2011:11:44:44 -0400] cupsdReadClient: 15 POST / HTTP/1.1
D [26/Apr/2011:11:44:44 -0400] cupsdAuthorize: No authentication data  
provided.
D [26/Apr/2011:11:44:44 -0400] cupsdReadClient: 15 1.1 CUPS-Get-Default 1
D [26/Apr/2011:11:44:44 -0400] CUPS-Get-Default
D [26/Apr/2011:11:44:44 -0400] Returning IPP successful-ok for  
CUPS-Get-Default (no URI) from localhost
D [26/Apr/2011:11:44:44 -0400] [CGI] show_printer(http=0xb8549d90,  
printer="CUPS-Test")
D [26/Apr/2011:11:44:44 -0400] cupsdReadClient: 15 POST / HTTP/1.1
D [26/Apr/2011:11:44:44 -0400] cupsdAuthorize: No authentication data  
provided.
D [26/Apr/2011:11:44:44 -0400] cupsdReadClient: 15 1.1  
Get-Printer-Attributes 1
D [26/Apr/2011:11:44:44 -0400] Get-Printer-Attributes  
ipp://localhost/printers/CUPS-Test
D [26/Apr/2011:11:44:44 -0400] Returning IPP successful-ok for  
Get-Printer-Attributes (ipp://localhost/printers/CUPS-Test) from  
localhost
D [26/Apr/2011:11:44:44 -0400] Script header: Content-Type:  
text/html;charset=utf-8
D [26/Apr/2011:11:44:44 -0400] Script header:
D [26/Apr/2011:11:44:44 -0400] [CGI] Regular expression ".*Clean.*"
D [26/Apr/2011:11:44:44 -0400] [CGI] Regular expression  
".*PrintSelfTestPage.*"
D [26/Apr/2011:11:44:44 -0400] cupsdReadClient: 15 POST / HTTP/1.1
D [26/Apr/2011:11:44:44 -0400] cupsdAuthorize: No authentication data  
provided.
D [26/Apr/2011:11:44:44 -0400] cupsdReadClient: 15 1.1 Get-Jobs 1
D [26/Apr/2011:11:44:44 -0400] Get-Jobs ipp://localhost:631/printers/CUPS-Test
D [26/Apr/2011:11:44:44 -0400] Returning IPP successful-ok for  
Get-Jobs (ipp://localhost:631/printers/CUPS-Test) from localhost
D [26/Apr/2011:11:44:44 -0400] cupsdReadClient: 15 WAITING Closing on EOF
D [26/Apr/2011:11:44:44 -0400] cupsdCloseClient: 15
D [26/Apr/2011:11:44:44 -0400] PID 10118  
(/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [26/Apr/2011:11:44:44 -0400] cupsdSetBusyState: Not busy
D [26/Apr/2011:11:44:49 -0400] cupsdReadClient: 12 POST  
/printers/CUPS-Test HTTP/1.1
D [26/Apr/2011:11:44:49 -0400] cupsdSetBusyState: Active clients
D [26/Apr/2011:11:44:49 -0400] cupsdAuthorize: No authentication data  
provided.
D [26/Apr/2011:11:44:49 -0400] [CGI] argv[0] =  
"/usr/lib/cups/cgi-bin/printers.cgi"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[2] =  
"CUPS_DOCROOT=/usr/share/cups/doc-root"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[3] =  
"CUPS_FONTPATH=/usr/share/cups/fonts"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[4] =  
"CUPS_REQUESTROOT=/var/spool/cups"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[9] =  
"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[10] =  
"SERVER_ADMIN=root at debian-swan2"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[13] = "TZ=America/New_York"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[14] = "USER=root"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[15] =  
"CUPS_SERVER=/var/run/cups/cups.sock"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[17] = "IPP_PORT=631"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[18] = "LANG=en.UTF8"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1..1"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[21] = "SERVER_NAME=localhost"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[22] = "SERVER_PORT=631"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[23] = "REMOTE_ADDR=127.0.0.1"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[25] =  
"SCRIPT_NAME=/printers/CUPS-Test"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[26] =  
"SCRIPT_FILENAME=/usr/share/cups/doc-root/printers/CUPS-Test"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[27] = "PATH_INFO=/CUPS-Test"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[29] =  
"HTTP_COOKIE=org.cups.sid=a2e3feadd32a8d740e114ab9a889b1a5"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[30] = "HTTP_USER_AGENT=Links  
(2.3pre1; Linux 2.6.32-5-686 i686; 105x36)"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[31] = "REQUEST_METHOD=POST"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[32] = "CONTENT_LENGTH=64"
D [26/Apr/2011:11:44:49 -0400] [CGI] envp[33] =  
"CONTENT_TYPE=application/x-www-form-urlencoded"
D [26/Apr/2011:11:44:49 -0400] [CGI] Started  
/usr/lib/cups/cgi-bin/printers.cgi (PID 10119)
I [26/Apr/2011:11:44:49 -0400] Started  
"/usr/lib/cups/cgi-bin/printers.cgi" (pid=10119)
D [26/Apr/2011:11:44:49 -0400] cupsdSendCommand: 12 file=15
D [26/Apr/2011:11:44:49 -0400] [CGI] org.cups.sid cookie is  
"a2e3feadd32a8d740e114ab9a889b1a5"
D [26/Apr/2011:11:44:49 -0400] cupsdAcceptClient: 14 from localhost (Domain)
D [26/Apr/2011:11:44:49 -0400] cupsdReadClient: 14 POST  
/printers/CUPS-Test HTTP/1.1
D [26/Apr/2011:11:44:49 -0400] cupsdAuthorize: No authentication data  
provided.
D [26/Apr/2011:11:44:49 -0400] cupsdReadClient: 14 1.1 Print-Job 1
D [26/Apr/2011:11:44:49 -0400] Print-Job  
ipp://localhost:631/printers/CUPS-Test
D [26/Apr/2011:11:44:49 -0400] [Job ???] Auto-typing file...
I [26/Apr/2011:11:44:49 -0400] [Job ???] Request file type is  
application/vnd.cups-banner.
D [26/Apr/2011:11:44:49 -0400] cupsdMarkDirty(----J-)
D [26/Apr/2011:11:44:49 -0400] cupsdSetBusyState: Active clients and  
dirty files
D [26/Apr/2011:11:44:49 -0400] Adding default job-sheets values "none,none"....
I [26/Apr/2011:11:44:49 -0400] [Job 6] Adding start banner page "none".
D [26/Apr/2011:11:44:49 -0400] Discarding unused job-created event...
D [26/Apr/2011:11:44:49 -0400] cupsdMarkDirty(----J-)
I [26/Apr/2011:11:44:49 -0400] [Job 6] Adding end banner page "none".
I [26/Apr/2011:11:44:49 -0400] [Job 6] File of type  
application/vnd.cups-banner queued by "anonymous".
D [26/Apr/2011:11:44:49 -0400] [Job 6] hold_until=0
I [26/Apr/2011:11:44:49 -0400] [Job 6] Queued on "CUPS-Test" by "anonymous".
D [26/Apr/2011:11:44:49 -0400] cupsdMarkDirty(----J-)
D [26/Apr/2011:11:44:49 -0400] cupsdSetBusyState: Active clients,  
printing jobs, and dirty files
D [26/Apr/2011:11:44:49 -0400] Discarding unused printer-state-changed  
event...
D [26/Apr/2011:11:44:49 -0400] [Job 6] Sending job to queue tagged as raw...
D [26/Apr/2011:11:44:49 -0400] [Job 6] job-sheets=none,none
D [26/Apr/2011:11:44:49 -0400] [Job 6] argv[0]="CUPS-Test"
D [26/Apr/2011:11:44:49 -0400] [Job 6] argv[1]="6"
D [26/Apr/2011:11:44:49 -0400] [Job 6] argv[2]="anonymous"
D [26/Apr/2011:11:44:49 -0400] [Job 6] argv[3]="Test Page"
D [26/Apr/2011:11:44:49 -0400] [Job 6] argv[4]="1"
D [26/Apr/2011:11:44:49 -0400] [Job 6]  
argv[5]="job-uuid=urn:uuid:c4471fed-2299-3980-6219-942907261975  
job-originating-host-name=localhost"
D [26/Apr/2011:11:44:49 -0400] [Job 6] argv[6]="/var/spool/cups/d00006-001"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [26/Apr/2011:11:44:49 -0400] [Job 6]  
envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [26/Apr/2011:11:44:49 -0400] [Job 6]  
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [26/Apr/2011:11:44:49 -0400] [Job 6]  
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[8]="HOME=/var/spool/cups/tmp"
D [26/Apr/2011:11:44:49 -0400] [Job 6]  
envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [26/Apr/2011:11:44:49 -0400] [Job 6]  
envp[10]="SERVER_ADMIN=root at debian-swan2"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[11]="SOFTWARE=CUPS/1.4.4"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[13]="TZ=America/New_York"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[14]="USER=root"
D [26/Apr/2011:11:44:49 -0400] [Job 6]  
envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[17]="IPP_PORT=631"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[18]="CHARSET=utf-8"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[19]="LANG=en.UTF-8"
D [26/Apr/2011:11:44:49 -0400] [Job 6]  
envp[20]="PPD=/etc/cups/ppd/CUPS-Test.ppd"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[21]="RIP_MAX_CACHE=auto"
D [26/Apr/2011:11:44:49 -0400] [Job 6]  
envp[22]="CONTENT_TYPE=application/vnd.cups-banner"
D [26/Apr/2011:11:44:49 -0400] [Job 6]  
envp[23]="DEVICE_URI=usb://EPSON/Stylus%20Photo%20R2400"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[24]="PRINTER_INFO=EPSON  
Stylus Photo R2400"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[25]="PRINTER_LOCATION="
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[26]="PRINTER=CUPS-Test"
D [26/Apr/2011:11:44:49 -0400] [Job 6] envp[27]="CUPS_FILETYPE=document"
I [26/Apr/2011:11:44:49 -0400] [Job 6] Started backend  
/usr/lib/cups/backend/usb (PID 10120)
D [26/Apr/2011:11:44:49 -0400] Discarding unused job-state-changed event...
D [26/Apr/2011:11:44:49 -0400] Returning IPP successful-ok for  
Print-Job (ipp://localhost:631/printers/CUPS-Test) from localhost
D [26/Apr/2011:11:44:49 -0400] Script header: Content-Type:  
text/html;charset=utf-8
D [26/Apr/2011:11:44:49 -0400] Script header:
D [26/Apr/2011:11:44:49 -0400] PID 10119  
(/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [26/Apr/2011:11:44:49 -0400] cupsdReadClient: 14 WAITING Closing on EOF
D [26/Apr/2011:11:44:49 -0400] cupsdCloseClient: 14
D [26/Apr/2011:11:44:49 -0400] cupsdSetBusyState: Printing jobs and  
dirty files
D [26/Apr/2011:11:44:49 -0400] [Job 6] STATE: +connecting-to-device
D [26/Apr/2011:11:44:49 -0400] Discarding unused printer-state-changed  
event...
D [26/Apr/2011:11:44:49 -0400] [Job 6] Printer using device file  
"/dev/usb/lp0"...
D [26/Apr/2011:11:44:49 -0400] [Job 6] STATE: -connecting-to-device
D [26/Apr/2011:11:44:49 -0400] Discarding unused printer-state-changed  
event...
D [26/Apr/2011:11:44:49 -0400] [Job 6] PAGE: 1 1
D [26/Apr/2011:11:44:49 -0400] Discarding unused job-progress event...
D [26/Apr/2011:11:44:49 -0400] [Job 6] backendRunLoop(print_fd=5,  
device_fd=6, snmp_fd=-1, addr=(nil), use_bc=1, side_cb=0xb773eb10)
D [26/Apr/2011:11:44:49 -0400] [Job 6] Read 305 bytes of print data...
D [26/Apr/2011:11:44:49 -0400] PID 10120 (/usr/lib/cups/backend/usb)  
exited with no errors.
D [26/Apr/2011:11:44:49 -0400] [Job 6] STATE: -media-empty-warning
D [26/Apr/2011:11:44:49 -0400] [Job 6] STATE: -offline-report
I [26/Apr/2011:11:44:49 -0400] [Job 6] Printer is now online.
D [26/Apr/2011:11:44:49 -0400] [Job 6] Wrote 305 bytes of print data...
I [26/Apr/2011:11:44:49 -0400] [Job 6] Sent print file, 305 bytes...
D [26/Apr/2011:11:44:49 -0400] Discarding unused job-progress event...
D [26/Apr/2011:11:44:49 -0400] Discarding unused job-completed event...
I [26/Apr/2011:11:44:49 -0400] [Job 6] Job completed.
D [26/Apr/2011:11:44:49 -0400] cupsdMarkDirty(----J-)
D [26/Apr/2011:11:44:49 -0400] Discarding unused printer-state-changed  
event...
D [26/Apr/2011:11:44:50 -0400] [Job 6] Unloading...
I [26/Apr/2011:11:45:20 -0400] Saving job cache file  
"/var/cache/cups/job.cache"...
D [26/Apr/2011:11:45:20 -0400] cupsdSetBusyState: Not busy

==============================</log>===========================================

The printer is online, and it is full of paper.  I do not know why it  
is saying "No authentication data provided"...

This is the configuration for the printer:
<DefaultPrinter CUPS-Test>
Info EPSON Stylus Photo R2400
Location
DeviceURI usb://EPSON/Stylus%20Photo%20R2400
State Idle
StateTime 1303832116
Type 4
Accepting Yes
Shared No
JobSheets none none
QuotaPeriod 0
PageLimit 0
KLimit 0
OpPolicy default
ErrorPolicy stop-printer
</Printer>


Thank you .





More information about the cups mailing list