my CUPS RUnneth Over!!!
Hi everyone,
I saw on the web that CUPS stands for Can't Usually Print Stuff.
That describs my current situation perfectly. Here's the situation:
1) CUPS 1.1.15 (testing) on a mostly-woody box
2) Lexmark z53 on USB
3) /usr/share/cups/model/Lexmark/Lexmark-Z53-lexmarkinkjet-ppd.ppd as
my ppd file
Once upon a time, CUPS worked for me. Then I tasted of the fruit of
knowledge, the Lord grew angry, and cast me out of the Garden. Now I
struggle to find my way in.
- printing broke somehow
- I uninstalled everything (apt-get remove --purge)
- I reinstalled everything
-printing was broke worse (couldn't use the web interface at all)
- so I upgraded to testing, and now the web interface works
But now I'm in the same predicament, namely, I can't print.
The only thing I can think to do here is to enclose a little bit of
the error log. I suspect the problem is in the interaction of CUPS
with other parts of the print system (like ghostview?) but I can't
figure out where exactly.
So, I've enclosed my printers.conf and and some of the error_log. Can
anyone help me???
First, the Printers.conf:
<DefaultPrinter commandlinez53>
Info Lexmark Z53
DeviceURI test:/dev/null
State Idle
Accepting Yes
JobSheets none none
QuotaPeriod 0
PageLimit 0
KLimit 0
</Printer>
--> this seems fine -- test:/dev/null is what I used to have to have
in that line, always worked before, though I never understood why
I needed it. Anyway, changing it back to usb:/dev/usb/lp0 just
adds one more error.
Now, the error_log. I've edited it somewhat, but left a lot in since
I don't know what's important. The errors start in the last section,
which I've marked. I hope someone cna figure out what's going on!!!
thanks,
matt
here's the log:
I [14/Feb/2003:23:24:33 -0500] Listening to 0:631
D [14/Feb/2003:23:24:33 -0500] AddLocation: added location '/'
D [14/Feb/2003:23:24:33 -0500] AllowIP: / allow 00000000/00000000
D [14/Feb/2003:23:24:33 -0500] AllowIP: / allow 7f000001/ffffffff
D [14/Feb/2003:23:24:33 -0500] AddLocation: added location '/admin'
D [14/Feb/2003:23:24:33 -0500] AllowIP: /admin allow 00000000/00000000
D [14/Feb/2003:23:24:33 -0500] AllowIP: /admin allow 7f000001/ffffffff
D [14/Feb/2003:23:24:33 -0500] ReadConfiguration()
ConfigurationFile="/etc/cups/cupsd.conf"
I [14/Feb/2003:23:24:33 -0500] Configured for up to 100 clients.
I [14/Feb/2003:23:24:33 -0500] LoadPPDs: Read "/etc/cups/ppds.dat",
926 PPDs...
I [14/Feb/2003:23:24:34 -0500] LoadPPDs: No new or changed PPDs...
D [14/Feb/2003:23:24:34 -0500] LoadDevices: Added device "ipp"...
-- SNIP --
D [14/Feb/2003:23:24:34 -0500] LoadDevices: Added device
"usb:/dev/usb/lp0"...
D [14/Feb/2003:23:24:34 -0500] LoadDevices: Added device
"usb:/dev/usb/lp1"...
D [14/Feb/2003:23:24:34 -0500] LoadDevices: Added device
"usb:/dev/usb/lp2"...
D [14/Feb/2003:23:24:34 -0500] LoadDevices: Added device
"usb:/dev/usb/lp3"...
D [14/Feb/2003:23:24:34 -0500] LoadDevices: Added device
"usb:/dev/usb/lp4"...
-- SNIP --
D [14/Feb/2003:23:24:34 -0500] StartListening: NumListeners=1
D [14/Feb/2003:23:24:34 -0500] StartListening: address=00000000
port=631
D [14/Feb/2003:23:24:34 -0500] ResumeListening: setting input bits...
D [14/Feb/2003:23:24:34 -0500] LoadAllJobs: Scanning
/var/spool/cups...
D [14/Feb/2003:23:24:34 -0500] LoadAllJobs: Loading attributes for job
1...
D [14/Feb/2003:23:24:34 -0500] LoadAllJobs: Loading attributes for job
2...
D [14/Feb/2003:23:24:34 -0500] LoadAllJobs: Loading attributes for job
3...
vD [14/Feb/2003:23:24:42 -0500] AcceptClient() 3 from
67.69.252.21:631.
D [14/Feb/2003:23:24:42 -0500] ReadClient() 3 GET /printers HTTP/1.1
D [14/Feb/2003:23:24:42 -0500] CGI /usr/lib/cups/cgi-bin/printers.cgi
started - PID = 23296
I [14/Feb/2003:23:24:42 -0500] Started
"/usr/lib/cups/cgi-bin/printers.cgi" (pid=23296)
D [14/Feb/2003:23:24:42 -0500] SendCommand() 3 file=5
D [14/Feb/2003:23:24:42 -0500] AcceptClient() 6 from 127.0.0.1:631.
D [14/Feb/2003:23:24:42 -0500] ReadClient() 6 POST / HTTP/1.1
D [14/Feb/2003:23:24:42 -0500] ReadClient() 6 POST / HTTP/1.1
D [14/Feb/2003:23:24:42 -0500] CloseClient() 6
D [14/Feb/2003:23:24:43 -0500] CloseClient() 3
D [14/Feb/2003:23:24:55 -0500] AcceptClient() 3 from 67.69.252.21:631.
D [14/Feb/2003:23:24:55 -0500] ReadClient() 3 GET
/printers/commandlinez53?op=print-test-page HTTP/1.1
D [14/Feb/2003:23:24:55 -0500] CGI /usr/lib/cups/cgi-bin/printers.cgi
started - PID = 23300
I [14/Feb/2003:23:24:55 -0500] Started
"/usr/lib/cups/cgi-bin/printers.cgi" (pid=23300)
D [14/Feb/2003:23:24:55 -0500] SendCommand() 3 file=6
D [14/Feb/2003:23:24:55 -0500] AcceptClient() 5 from 127.0.0.1:631.
D [14/Feb/2003:23:24:55 -0500] ReadClient() 5 POST
/printers/commandlinez53 HTTP/1.1
D [14/Feb/2003:23:24:55 -0500] print_job: request file type is
application/postscript.
D [14/Feb/2003:23:24:55 -0500] check_quotas: requesting-user-name =
'root'
D [14/Feb/2003:23:24:55 -0500] print_job: requesting-user-name =
'root'
D [14/Feb/2003:23:24:55 -0500] Adding default job-sheets values
"none,none"...
I [14/Feb/2003:23:24:55 -0500] Job 4 queued on 'commandlinez53' by
'root'.
D [14/Feb/2003:23:24:55 -0500] Job 4 hold_until = 0
D [14/Feb/2003:23:24:55 -0500] StartJob(4, 0x8086a18)
D [14/Feb/2003:23:24:55 -0500] StartJob() id = 4, file = 0/1
D [14/Feb/2003:23:24:55 -0500] job-sheets=none,none
D [14/Feb/2003:23:24:55 -0500] banner_page = 0
D [14/Feb/2003:23:24:55 -0500] StartJob: argv =
"commandlinez53","4","root","Test
Page","1","","/var/spool/cups/d00004-001"
D [14/Feb/2003:23:24:55 -0500] StartJob: envp =
"PATH=/usr/lib/cups/filter:/bin:/usr/bin","SOFTWARE=CUPS/1.1","USER=root","CHARSET=iso-8859-1","LANG=en","TZ=Canada/Eastern","PPD=/etc/cups/ppd/commandlinez53.ppd","CUPS_SERVERROOT=/etc/cups","RIP_MAX_CACHE=8m","TMPDIR=/var/spool/cups/tmp","CONTENT_TYPE=application/postscript","DEVICE_URI=test:/dev/null","PRINTER=commandlinez53","CUPS_DATADIR=/usr/share/cups","CUPS_FONTPATH=/usr/share/cups/fonts","","",""
D [14/Feb/2003:23:24:55 -0500] StartJob: statusfds = 7, 8
D [14/Feb/2003:23:24:55 -0500] StartJob: filterfds[1] = 9, -1
D [14/Feb/2003:23:24:55 -0500] StartJob: filter =
"/usr/lib/cups/filter/pstops"
D [14/Feb/2003:23:24:55 -0500] StartJob: filterfds[0] = 10, 11
D [14/Feb/2003:23:24:55 -0500]
start_process("/usr/lib/cups/filter/pstops", 0xbfff1658, 0xbffecb08,
9, 11, 8)
I [14/Feb/2003:23:24:55 -0500] Started filter
/usr/lib/cups/filter/pstops (PID 23301) for job 4.
D [14/Feb/2003:23:24:55 -0500] StartJob: filter =
"/usr/lib/cups/filter/cupsomatic"
D [14/Feb/2003:23:24:55 -0500] StartJob: filterfds[1] = -1, 9
D [14/Feb/2003:23:24:55 -0500]
start_process("/usr/lib/cups/filter/cupsomatic", 0xbfff1658,
0xbffecb08, 10, 9, 8)
I [14/Feb/2003:23:24:55 -0500] Started filter
/usr/lib/cups/filter/cupsomatic (PID 23302) for job 4.
D [14/Feb/2003:23:24:55 -0500] perl: warning: Setting locale failed.
D [14/Feb/2003:23:24:55 -0500] perl: warning: Please check that your
locale settings:
D [14/Feb/2003:23:24:55 -0500] LANGUAGE = (unset),
D [14/Feb/2003:23:24:55 -0500] LC_ALL = (unset),
D [14/Feb/2003:23:24:55 -0500] LANG = "en"
D [14/Feb/2003:23:24:55 -0500] are supported and installed on your
system.
D [14/Feb/2003:23:24:55 -0500] perl: warning: Falling back to the
standard locale ("C").
D [14/Feb/2003:23:24:55 -0500] Page = 612x792; 0,0 to 612,792
-- SNIP --
D [14/Feb/2003:23:24:55 -0500] CloseClient() 5
D [14/Feb/2003:23:24:55 -0500] Cupsomatic backend version $Revision:
2.5 $ running...
D [14/Feb/2003:23:24:55 -0500] called with arguments: '4','root','Test
Page','1',''
D [14/Feb/2003:23:24:55 -0500] ppd=/etc/cups/ppd/commandlinez53.ppd
D [14/Feb/2003:23:24:55 -0500] Seaerching job for option settings ...
D [14/Feb/2003:23:24:55 -0500] 0 %%EOF
D [14/Feb/2003:23:24:55 -0500] Saw EOF!
D [14/Feb/2003:23:24:55 -0500] Found: %%BeginFeature: *Dither Auto
D [14/Feb/2003:23:24:55 -0500] Option: Dither=Auto --> Setting option
-- SNIP --
D [14/Feb/2003:23:24:55 -0500] Option: PageSize=Letter --> Option will
be set by PostScript interpreter
D [14/Feb/2003:23:24:55 -0500] options: -><-
D [14/Feb/2003:23:24:55 -0500] /usr/lib/cups/filter/cupsomatic:
inserted option PS code:
D [14/Feb/2003:23:24:55 -0500] gs PID pid2=23304
D [14/Feb/2003:23:24:55 -0500] gs command: lexmarkwrapper -r 600 -t 0
-c CMYK -m z53 -i business -d 5 -p /dev/lp0 -A 16 -B 8 -C 16 -D 16 >
/dev/null
D [14/Feb/2003:23:24:55 -0500] sh: /dev/lp0: Permission denied
D [14/Feb/2003:23:24:55 -0500] perl: warning: Setting locale failed.
D [14/Feb/2003:23:24:55 -0500] perl: warning: Please check that your
locale settings:
D [14/Feb/2003:23:24:55 -0500] LANGUAGE = (unset),
D [14/Feb/2003:23:24:55 -0500] LC_ALL = (unset),
D [14/Feb/2003:23:24:55 -0500] LANG = "en"
D [14/Feb/2003:23:24:55 -0500] are supported and installed on your
system.
D [14/Feb/2003:23:24:55 -0500] perl: warning: Falling back to the
standard locale ("C").
-- HERE COME THE ERRORS --
D [14/Feb/2003:23:24:55 -0500] foomatic-gswrapper: gs '-dBATCH'
'-dNOPAUSE' '-dSAFER' '-r600' '-sDEVICE=ppmraw'
'-sOutputFile=/dev/fd/3' '/dev/fd/0' 3>&1 1>&2
D [14/Feb/2003:23:24:55 -0500] GNU Ghostscript 6.53 (2002-02-13)
D [14/Feb/2003:23:24:55 -0500] Copyright (C) 2002 artofcode LLC,
Benicia, CA. All rights reserved.
D [14/Feb/2003:23:24:55 -0500] This software comes with NO WARRANTY:
see the file COPYING for details.
D [14/Feb/2003:23:24:55 -0500] Loading NimbusSanL-Bold font from
/var/lib/defoma/gs.d/dirs/fonts/n019004l.pfb... 2012952 627281 1642520
351318 0 done.
D [14/Feb/2003:23:24:55 -0500] Loading NimbusSanL-Regu font from
/var/lib/defoma/gs.d/dirs/fonts/n019003l.pfb... 2073240 675929 1642520
351899 0 done.
D [14/Feb/2003:23:24:55 -0500] Loading NimbusRomNo9L-Regu font from
/var/lib/defoma/gs.d/dirs/fonts/n021003l.pfb... 2133528 733919 1642520
352490 0 done.
D [14/Feb/2003:23:24:55 -0500] Loading NimbusSanL-BoldItal font from
/var/lib/defoma/gs.d/dirs/fonts/n019024l.pfb... 2173720 787056 1662616
366593 0 done.
D [14/Feb/2003:23:24:56 -0500] Error: /ioerror in --.outputpage--
D [14/Feb/2003:23:24:56 -0500] Operand stack:
D [14/Feb/2003:23:24:56 -0500] 416.5 308.0 306.0 748.0 306.0
55.0 51.0 1 true
D [14/Feb/2003:23:24:56 -0500] Execution stack:
D [14/Feb/2003:23:24:56 -0500] %interp_exit .runexec2
--nostringval-- --nostringval-- --nostringval-- 2
%stopped_push --nostringval-- --nostringval-- --nostringval--
false 1 %stopped_push 1 3 %oparray_pop 1 3
%oparray_pop 1 3 %oparray_pop .runexec2 --nostringval--
--nostringval-- --nostringval-- 2 %stopped_push
--nostringval-- 7 3 %oparray_pop --nostringval--
--nostringval-- --nostringval-- --nostringval--
D [14/Feb/2003:23:24:56 -0500] Dictionary stack:
D [14/Feb/2003:23:24:56 -0500] --dict:1038/1476(ro)(G)--
--dict:0/20(G)-- --dict:89/200(L)--
D [14/Feb/2003:23:24:56 -0500] Current allocation mode is local
D [14/Feb/2003:23:24:56 -0500] Last OS error: 32
D [14/Feb/2003:23:24:56 -0500] GNU Ghostscript 6.53: Unrecoverable
error, exit code 1
D [14/Feb/2003:23:24:56 -0500] error closing printing command line.
D [14/Feb/2003:23:24:56 -0500] Couldn't exec lexmarkwrapper -r 600 -t
0 -c CMYK -m z53 -i business -d 5 -p /dev/lp0 -A 16 -B 8 -C 16 -D 16 >
/dev/null at /usr/lib/cups/filter/cupsomatic line 965.
-- ERRORS CEASE --
D [14/Feb/2003:23:24:56 -0500] Main process finished
D [14/Feb/2003:23:24:56 -0500] tail process done writing data to
*main::STDOUT
D [14/Feb/2003:23:24:56 -0500] KID4 finished
D [14/Feb/2003:23:24:56 -0500] UpdateJob: job 4, file 0 is complete.
D [14/Feb/2003:23:24:56 -0500] CancelJob: id = 4
D [14/Feb/2003:23:24:56 -0500] StopJob: id = 4, force = 0
D [14/Feb/2003:23:24:56 -0500] StopJob: printer state is 3
D [14/Feb/2003:23:24:58 -0500] ReadClient() 3 GET
/printers/commandlinez53 HTTP/1.1
D [14/Feb/2003:23:24:58 -0500] CGI /usr/lib/cups/cgi-bin/printers.cgi
started - PID = 23311
I [14/Feb/2003:23:24:58 -0500] Started
"/usr/lib/cups/cgi-bin/printers.cgi" (pid=23311)
D [14/Feb/2003:23:24:58 -0500] SendCommand() 3 file=6
D [14/Feb/2003:23:24:58 -0500] AcceptClient() 5 from 127.0.0.1:631.
D [14/Feb/2003:23:24:58 -0500] ReadClient() 5 POST / HTTP/1.1
D [14/Feb/2003:23:24:58 -0500] ReadClient() 5 POST / HTTP/1.1
D [14/Feb/2003:23:24:58 -0500] ReadClient() 5 POST / HTTP/1.1
D [14/Feb/2003:23:24:58 -0500] CloseClient() 5
D [14/Feb/2003:23:25:00 -0500] CloseClient() 3
Reply to: