gnewsense-users
[Top][All Lists]
Advanced
[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

Re: [gNewSense-users] Print system (CUPS/Gutenprint) not working

From: Tor at Shared Genius
Subject: Re: [gNewSense-users] Print system (CUPS/Gutenprint) not working
Date: Sat, 13 Dec 2008 12:12:13 -1000
User-agent: Thunderbird 2.0.0.18 (X11/20081125)
I set debug logs, and tried to print a page. Shown below is the error log from that, taking only the lines from the second in which all of the job messages show up, and then the lines from when I canceled the job. I can find no evidence of the failure noted here. Other jobs I looked at from before I began reinstalling Gutenprint and CUPS also show no signs of the failure. The log adds quite a few lines every five seconds, so the whole log is very long. The lines from 11:54:16 will give you an idea of what that looks like. I attempted to print a nozzle check pattern here, if that makes any difference. This is CUPS 1.3.7-1 (rather than the current 1.3.7-2), with Gutenprint 5.2.2, Epson Stylus C120, to make that clear. 

Thanks,

Tor


-----Begin Log segment-----
D [13/Dec/2008:11:34:20 -1000] cupsdCloseClient: 9
D [13/Dec/2008:11:34:24 -1000] cupsdAcceptClient: 9 from localhost (Domain)
D [13/Dec/2008:11:34:24 -1000] cupsdReadClient: 9 POST / HTTP/1.1
D [13/Dec/2008:11:34:24 -1000] cupsdAuthorize: No authentication data provided. 
D [13/Dec/2008:11:34:24 -1000] CUPS-Get-Printers
D [13/Dec/2008:11:34:24 -1000] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) 
D [13/Dec/2008:11:34:24 -1000] cupsdReadClient: 9 POST / HTTP/1.1
D [13/Dec/2008:11:34:24 -1000] cupsdAuthorize: No authentication data provided. 
D [13/Dec/2008:11:34:24 -1000] CUPS-Get-Classes
D [13/Dec/2008:11:34:24 -1000] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) 
D [13/Dec/2008:11:34:24 -1000] cupsdReadClient: 9 POST / HTTP/1.1
D [13/Dec/2008:11:34:24 -1000] cupsdAuthorize: No authentication data provided. 
D [13/Dec/2008:11:34:24 -1000] CUPS-Get-Default
D [13/Dec/2008:11:34:24 -1000] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) 
D [13/Dec/2008:11:34:24 -1000] cupsdCloseClient: 9
D [13/Dec/2008:11:34:24 -1000] cupsdAcceptClient: 9 from localhost (Domain)
D [13/Dec/2008:11:34:24 -1000] cupsdReadClient: 9 POST /printers/EpsC120 HTTP/1.1 D [13/Dec/2008:11:34:24 -1000] cupsdAuthorize: No authentication data provided. 
D [13/Dec/2008:11:34:24 -1000] Print-Job ipp://localhost/printers/EpsC120
D [13/Dec/2008:11:34:24 -1000] add_job: requesting-user-name="vanyel"
I [13/Dec/2008:11:34:24 -1000] [Job 57] Adding start banner page "none".
D [13/Dec/2008:11:34:24 -1000] Discarding unused job-created event...
I [13/Dec/2008:11:34:24 -1000] [Job 57] Adding job file of type application/vnd.cups-raw. 
I [13/Dec/2008:11:34:24 -1000] [Job 57] Adding end banner page "none".
I [13/Dec/2008:11:34:24 -1000] [Job 57] Queued on "EpsC120" by "vanyel".
D [13/Dec/2008:11:34:24 -1000] [Job 57] hold_until = 0
D [13/Dec/2008:11:34:24 -1000] Discarding unused printer-state-changed event... 
D [13/Dec/2008:11:34:24 -1000] [Job 57] job-sheets=none,none
D [13/Dec/2008:11:34:24 -1000] [Job 57] banner_page = 0
D [13/Dec/2008:11:34:24 -1000] [Job 57] argv[0]="EpsC120"
D [13/Dec/2008:11:34:24 -1000] [Job 57] argv[1]="57"
D [13/Dec/2008:11:34:24 -1000] [Job 57] argv[2]="vanyel"
D [13/Dec/2008:11:34:24 -1000] [Job 57] argv[3]="(stdin)"
D [13/Dec/2008:11:34:24 -1000] [Job 57] argv[4]="1"
D [13/Dec/2008:11:34:24 -1000] [Job 57] argv[5]="media=Letter finishings=3 number-up=1 job-uuid=urn:uuid:190cac7f-b981-3455-546e-eada4a1b9502" 
D [13/Dec/2008:11:34:24 -1000] [Job 57] argv[6]="/var/spool/cups/d00057-001"
D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[1]="CUPS_DATADIR=/usr/share/cups" D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root" D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" 
D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[7]="CUPS_STATEDIR=/var/run/cups" D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" 
D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[9]="address@hidden"
D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[10]="SOFTWARE=CUPS/1.3.7"
D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[11]="TMPDIR=/var/spool/cups/tmp" 
D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[12]="TZ=Pacific/Honolulu"
D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[13]="USER=root"
D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[15]="CUPS_ENCRYPTION=IfRequested" 
D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[16]="IPP_PORT=631"
D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[17]="CHARSET=utf-8"
D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[18]="LANG=en.UTF8"
D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[19]="PPD=/etc/cups/ppd/EpsC120.ppd" 
D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[20]="RIP_MAX_CACHE=8m"
D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[21]="CONTENT_TYPE=application/vnd.cups-raw" D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[22]="DEVICE_URI=usb://EPSON/Stylus%20C120" 
D [13/Dec/2008:11:34:24 -1000] [Job 57] envp[23]="PRINTER=EpsC120"
I [13/Dec/2008:11:34:24 -1000] [Job 57] Started backend /usr/lib/cups/backend/usb (PID 15359) 
D [13/Dec/2008:11:34:24 -1000] Discarding unused job-state-changed event...
D [13/Dec/2008:11:34:24 -1000] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [13/Dec/2008:11:34:24 -1000] Discarding unused printer-state-changed event... 
D [13/Dec/2008:11:34:24 -1000] cupsdCloseClient: 9
D [13/Dec/2008:11:34:32 -1000] cupsdAcceptClient: 9 from localhost (Domain)

-----Begin Job Cancel Segment-----

D [13/Dec/2008:11:54:11 -1000] cupsdAcceptClient: 9 from localhost (Domain)
D [13/Dec/2008:11:54:11 -1000] cupsdReadClient: 9 POST /jobs/ HTTP/1.1
D [13/Dec/2008:11:54:11 -1000] cupsdAuthorize: No authentication data provided. 
D [13/Dec/2008:11:54:11 -1000] Cancel-Job ipp://localhost:631/jobs/57
D [13/Dec/2008:11:54:11 -1000] cupsdIsAuthorized: requesting-user-name="vanyel" D [13/Dec/2008:11:54:11 -1000] Discarding unused printer-state-changed event... 
D [13/Dec/2008:11:54:11 -1000] Discarding unused job-completed event...
I [13/Dec/2008:11:54:11 -1000] [Job 57] Canceled by "vanyel".
D [13/Dec/2008:11:54:11 -1000] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) 
D [13/Dec/2008:11:54:11 -1000] cupsdCloseClient: 9
D [13/Dec/2008:11:54:11 -1000] cupsdAcceptClient: 9 from localhost (Domain)
D [13/Dec/2008:11:54:11 -1000] PID 15359 (/usr/lib/cups/backend/usb) exited with no errors. 
D [13/Dec/2008:11:54:11 -1000] cupsdReadClient: 9 POST / HTTP/1.1
D [13/Dec/2008:11:54:11 -1000] cupsdAuthorize: No authentication data provided. 
D [13/Dec/2008:11:54:11 -1000] Get-Jobs ipp://localhost:631/printers/EpsC120
D [13/Dec/2008:11:54:11 -1000] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) 
D [13/Dec/2008:11:54:11 -1000] cupsdCloseClient: 9
D [13/Dec/2008:11:54:12 -1000] [Job 57] Unloading...
D [13/Dec/2008:11:54:16 -1000] cupsdAcceptClient: 9 from localhost (Domain)
D [13/Dec/2008:11:54:16 -1000] cupsdReadClient: 9 POST /printers/ HTTP/1.1
D [13/Dec/2008:11:54:16 -1000] cupsdAuthorize: No authentication data provided. 
D [13/Dec/2008:11:54:16 -1000] CUPS-Get-Printers
D [13/Dec/2008:11:54:16 -1000] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) 
D [13/Dec/2008:11:54:16 -1000] cupsdCloseClient: 9
D [13/Dec/2008:11:54:16 -1000] cupsdAcceptClient: 9 from localhost (Domain)
D [13/Dec/2008:11:54:16 -1000] cupsdReadClient: 9 POST /classes/ HTTP/1.1
D [13/Dec/2008:11:54:16 -1000] cupsdAuthorize: No authentication data provided. 
D [13/Dec/2008:11:54:16 -1000] CUPS-Get-Classes
D [13/Dec/2008:11:54:16 -1000] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) 
D [13/Dec/2008:11:54:16 -1000] cupsdCloseClient: 9
D [13/Dec/2008:11:54:16 -1000] cupsdAcceptClient: 9 from localhost (Domain)
D [13/Dec/2008:11:54:16 -1000] cupsdReadClient: 9 POST /printers/ HTTP/1.1
D [13/Dec/2008:11:54:16 -1000] cupsdAuthorize: No authentication data provided. 
D [13/Dec/2008:11:54:16 -1000] CUPS-Get-Default
D [13/Dec/2008:11:54:16 -1000] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) 
D [13/Dec/2008:11:54:16 -1000] cupsdCloseClient: 9
D [13/Dec/2008:11:54:16 -1000] cupsdAcceptClient: 9 from localhost (Domain)
D [13/Dec/2008:11:54:16 -1000] cupsdReadClient: 9 POST /printers/ HTTP/1.1
D [13/Dec/2008:11:54:16 -1000] cupsdAuthorize: No authentication data provided. D [13/Dec/2008:11:54:16 -1000] Get-Printer-Attributes ipp://localhost:631/printers/EpsC120 D [13/Dec/2008:11:54:16 -1000] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) 
D [13/Dec/2008:11:54:16 -1000] cupsdCloseClient: 9
D [13/Dec/2008:11:54:16 -1000] cupsdCloseClient: 7
D [13/Dec/2008:11:54:16 -1000] cupsdAcceptClient: 7 from localhost (Domain)
D [13/Dec/2008:11:54:16 -1000] cupsdReadClient: 7 POST / HTTP/1.1
D [13/Dec/2008:11:54:16 -1000] cupsdAuthorize: No authentication data provided. D [13/Dec/2008:11:54:16 -1000] Get-Printer-Attributes ipp://localhost/printers/EpsC120 D [13/Dec/2008:11:54:16 -1000] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok) D [13/Dec/2008:11:54:16 -1000] cupsdReadClient: 7 GET /printers/EpsC120.ppd HTTP/1.1 D [13/Dec/2008:11:54:16 -1000] cupsdAuthorize: No authentication data provided. 
D [13/Dec/2008:11:54:16 -1000] cupsdAcceptClient: 9 from localhost (Domain)
D [13/Dec/2008:11:54:16 -1000] cupsdReadClient: 9 POST / HTTP/1.1
D [13/Dec/2008:11:54:16 -1000] cupsdAuthorize: No authentication data provided. 
D [13/Dec/2008:11:54:16 -1000] Get-Jobs ipp://localhost:631/printers/EpsC120
D [13/Dec/2008:11:54:16 -1000] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) 
D [13/Dec/2008:11:54:16 -1000] cupsdCloseClient: 9

-----End Error Log-----

Mu wrote:

As suggested I think you should first change the log level of cups to
debug to get more information. The following is extracted from a guide
to SuSE 
(http://www.novell.com/documentation/suse91/suselinux-adminguide/html/ch05s05.html),
but this should help.

5.5.6.6. Troubleshooting in CUPS

By default, the configuration file /etc/cups/cupsd.conf contains the
following section:

# LogLevel: controls the number of messages logged to the
# ErrorLog file and can be one of the following:
#
#     debug2    Log everything.
#     debug     Log almost everything.
#     info      Log all requests and state changes.
#     warn      Log errors and warnings.
#     error     Log only errors.
#     none      Log nothing.
#
LogLevel info

To detect errors in CUPS, set LogLevel debug and use rccups restart to
have cupsd use the modified configuration file. Subsequently,
/var/log/cups/error_log contains detailed messages that assist in
detecting the cause of problems.

With the following command, print a label before starting to run the test:

echo "LABEL $(date)" | tee -a /var/log/cups/error_log

This label will be entered in /var/log/cups/error_log. This makes it
easier to find the messages after the test.

2008/12/13 Tor at Shared Genius <address@hidden>

I am having trouble printing, which started about a week ago.  I am currently 
getting around the problem by attaching the printer to a different computer, 
but I would like to get printing working again on this one.

I have tried upgrading Gutenprint from 5.2.1 to 5.2.2 (I must install from 
source to support my printer), thinking that maybe the last CUPS upgrade messed 
things up, with no success.  Then I tried downgrading CUPS, for the same 
reason, with no change.  Below is a segment of /var/log/cups/error_log that 
should show the issue.

-----Begin error log snippet-----
I [12/Dec/2008:12:38:11 -1000] [Job 52] Adding start banner page "none".
I [12/Dec/2008:12:38:11 -1000] [Job 52] Adding job file of type 
application/postscript.
I [12/Dec/2008:12:38:11 -1000] [Job 52] Adding end banner page "none".
I [12/Dec/2008:12:38:11 -1000] [Job 52] Queued on "tmpprinter_Ix3USAba" by 
"vanyel".
I [12/Dec/2008:12:38:11 -1000] [Job 52] Started filter 
/usr/lib/cups/filter/pstops (PID 23364)
I [12/Dec/2008:12:38:11 -1000] [Job 52] Started filter 
/usr/lib/cups/filter/pstoraster (PID 23365)
I [12/Dec/2008:12:38:11 -1000] [Job 52] Started filter 
/usr/lib/cups/filter/rastertogutenprint.5.2 (PID 23367)
I [12/Dec/2008:12:38:11 -1000] [Job 52] Started backend 
/usr/lib/cups/backend/hal (PID 23368)
E [12/Dec/2008:12:38:11 -1000] PID 23368 (/usr/lib/cups/backend/hal) stopped 
with status 1!
I [12/Dec/2008:12:38:11 -1000] Hint: Try setting the LogLevel to "debug" to 
find out more.
E [12/Dec/2008:12:38:11 -1000] [Job 52] Unable to open device 
"hal:///org/freedesktop/Hal/devices/usb_device_4b8_7_4230373032475E599E_if0_printer_noserial":
 Permission denied
I [12/Dec/2008:12:38:22 -1000] [Job 52] Backend returned status 1 (failed)
E [12/Dec/2008:12:38:35 -1000] CUPS-Delete-Printer: Unauthorized
-----End error log snippet-----

Any help to get this working would be appreciated.

Thanks,

Tor


_______________________________________________
gNewSense-users mailing list
address@hidden
http://lists.nongnu.org/mailman/listinfo/gnewsense-users
reply via email to
[Prev in Thread] Current Thread [Next in Thread]