linux....RESOLVED!

[daniel49]

Ok have tried to configure my printer but the usb port does not show up in the printer config options. only 1 parallel port shows up. when I tell it to rescan nothing shows up new.
when I go to the root for a listing of printer ports i see /dev/usb/lp0, - dev/usb/1p15 in the ls
so It looks like usb is set up?
Then I found some articles at linuxprinting.com says maybe you need to setup the module for it so tried with

mknod /dev/usb/lp0 c 180 0 as suggested from root and alls I get is no such file or directory.

Its a lexmark x73 usb by the way


edit:Well changed the driver from the x73 to the z42 turns out on the z42 there are 2 drivers to choose from. I selected the gimp print .ijs and it was happy ....finally...from what Ive read so far nobody gets the scanner working in linux though so I'm not gonna worry about that. Thanks for your help cleverhandle

 

[cleverhandle]

Some more information would be helpful:

Distro?
Kernel version?
Print spooler? (probably CUPS if you haven't changed it)
Tool you're using for print setup? (i.e. are you editing text files, using a CUPS web interface, using a distro-provided tool)

From looking at linuxprinting.org, I can see that the recommended driver is Stolk's old Z42 driver. I can say from experience that using this driver is unpleasant. In fact, getting anything from Lexmark to work on Linux is unpleasant. I would guess that if your time is worth than more than $10 per hour that it will be cheaper to buy yourself a printer from a more cooperative company like HP or Epson.

 

[daniel49]

fedora core 3
kernal I am not sure have to find i#
cups I guess haven't changed it.
I am just using the printer setup tool in fedoras system tools....
and I just gave my old HP printer away to my son:frown:

 

[n0cmonkey]

Find information, including the kernel version: uname -a

 

[cleverhandle]

First we need to make sure that the machine is set up for printing via USB. I'd think that Fedora should have this part done for you, but you never know. Do an lsmod as root. You should see "usblp" listed there - that means you have generic USB printing support. If you don't see it, try to modprobe usblp. That should return without errors, but you'll also want to tell the machine to load that module automatically at boot time. You can probably do that in a file called /etc/modules, but I don't have a Fedora box to look at so I can't say for sure.

OK, so you've got generic printing support. Let's see if CUPS can handle this with its default setup. I won't use Fedora's tool because I have no familiarity with it. So just point your web browser to "localhost:631" - that should bring up the CUPS config screen. Go to Printers, choose Add a Printer, and follow the prompts to setup a Lexmark Z42. If you're given a choice between driver variations, choose the "filter" or "foomatic" option rather than a "Gimp Print" option. When you're done configuring, print a test page and pray.

You prayed in the last step because if CUPS doesn't auto-handle this, then your life is a heck of a lot more complicated. Refer to my comment previously about time vs. money. That was no joke. In case of poverty, refer to this page for starters, along with the documentation from the Z42 driver package available at Stolk's site.

 

[daniel49]

kernal at boot said 2.6.9-1.667
from root lsmod shows usblp
pointing browser to localhost:631
printer
add printer
username password promt
enter them it promts again
enter them it prompts again....etc

 

[bersl2]

Originally posted by: daniel49
kernal at boot said 2.6.9-1.667
from root lsmod shows usblp
pointing browser to localhost:631
printer
add printer
username password promt
enter them it promts again
enter them it prompts again....etc

Are you inputting root's username and password?

 

[daniel49]

yes, i was .
update: since last post i have gotten the printer config tool in fedora to recognize the port ...not sure how but its offering me a choice of
9pin 80 col
9 pin 136
24pin 80
24pin 136
48 pin
esc/p dot matrix printer
GDI printer
IBM compatable dot matrix
pcl3 printer
pcl4 printer
pcl5 printer
pcl5c printer
pcl5e printer
pcl/6 pcl/xl printer
postscript printer
raw print queue
text only printer
ZJ stream printer

edit: oops forget that that was generic I missed seeing drop down menu for manufacturers x73 is on the list should i select that or the z 42 clever handle suggested?

 

[cleverhandle]

Yes, select X73 if it's listed. I should have been more specific.

 

[daniel49]

reult was error
there was a problem sending cups test page to "printer" queue
lpr:error - unable to print file server-error-service-unavailable


edit : looking in services i see cups is checked and running
cups-config-dameon is checked and running
cups lpd is not checked but it says its running
also I think there was a package update for cups i can grab
and I could try the z42 any more ideas??

 

[cleverhandle]

Hum... well, check out the logs and see what they say. The printer logs are probably in /var/log/cups - there should be several. The one called "error.log" or something similar should be what you're after.

 

[daniel49]

error_log:

I [17/May/2005:16:56:10 -0700] Listening to 7f000001:631
I [17/May/2005:16:56:10 -0700] Loaded configuration file "/etc/cups/cupsd.conf"
I [17/May/2005:16:56:10 -0700] Configured for up to 100 clients.
I [17/May/2005:16:56:10 -0700] Allowing up to 100 client connections per host.
I [17/May/2005:16:56:10 -0700] Full reload is required.
I [17/May/2005:16:56:25 -0700] LoadPPDs: Read "/etc/cups/ppds.dat", 15 PPDs...
I [17/May/2005:16:56:25 -0700] LoadPPDs: No new or changed PPDs...
I [17/May/2005:16:56:25 -0700] Full reload complete.
I [17/May/2005:17:03:48 -0700] Adding start banner page "none" to job 1.
I [17/May/2005:17:03:48 -0700] Adding end banner page "none" to job 1.
I [17/May/2005:17:03:48 -0700] Job 1 queued on 'printer' by 'dan'.
I [17/May/2005:17:03:48 -0700] Started filter /usr/lib/cups/filter/pstops (PID 4186) for job 1.
I [17/May/2005:17:03:48 -0700] Started filter /usr/lib/cups/filter/foomatic-rip (PID 4187) for job 1.
I [17/May/2005:17:03:48 -0700] Started backend /usr/lib/cups/backend/usb (PID 4188) for job 1.
E [17/May/2005:17:03:50 -0700] PID 4187 stopped with status 1!
I [17/May/2005:17:03:50 -0700] Hint: Try setting the LogLevel to "debug" to find out more.


error_log.1

I [14/May/2005:18:54:29 -0700] Full reload is required.
I [14/May/2005:18:54:29 -0700] LoadPPDs: Read "/etc/cups/ppds.dat", 15 PPDs...
I [14/May/2005:18:54:29 -0700] LoadPPDs: No new or changed PPDs...
I [14/May/2005:18:54:29 -0700] Full reload complete.
I [14/May/2005:23:40:52 -0700] Scheduler shutting down normally.
I [14/May/2005:23:54:17 -0700] Listening to 7f000001:631
I [14/May/2005:23:54:17 -0700] Loaded configuration file "/etc/cups/cupsd.conf"
I [14/May/2005:23:54:17 -0700] Configured for up to 100 clients.
I [14/May/2005:23:54:17 -0700] Allowing up to 100 client connections per host.
I [14/May/2005:23:54:17 -0700] Full reload is required.
I [14/May/2005:23:54:18 -0700] LoadPPDs: Read "/etc/cups/ppds.dat", 15 PPDs...
I [14/May/2005:23:54:18 -0700] LoadPPDs: No new or changed PPDs...
I [14/May/2005:23:54:18 -0700] Full reload complete.
I [15/May/2005:00:06:31 -0700] Scheduler shutting down normally.
I [15/May/2005:09:31:52 -0700] Listening to 7f000001:631
I [15/May/2005:09:31:52 -0700] Loaded configuration file "/etc/cups/cupsd.conf"
I [15/May/2005:09:31:52 -0700] Configured for up to 100 clients.
I [15/May/2005:09:31:52 -0700] Allowing up to 100 client connections per host.
I [15/May/2005:09:31:52 -0700] Full reload is required.
I [15/May/2005:09:31:53 -0700] LoadPPDs: Read "/etc/cups/ppds.dat", 15 PPDs...
I [15/May/2005:09:31:53 -0700] LoadPPDs: No new or changed PPDs...
I [15/May/2005:09:31:53 -0700] Full reload complete.
I [15/May/2005:10:36:57 -0700] Scheduler shutting down normally.
these were both in cups

 

[cleverhandle]

Originally posted by: daniel49
E [17/May/2005:17:03:50 -0700] PID 4187 stopped with status 1!
I [17/May/2005:17:03:50 -0700] Hint: Try setting the LogLevel to "debug" to find out more.

It's referring to a line in /etc/cups/cupsd.conf. Change that line in the file to say "LogLevel debug", restart cups (or just reboot), and try again. The other lines look good - the printing system is basically doing what we expect apart from foomatic (PID 4187) failing.

 

[daniel49]

herse what I see opening cupsd.conf in vi
still not sure exactly where in line 1 you want me to add that

"$Id: cupsd.conf.in,v 1.16 2004/08/18 17:53:47 mike Exp $"
#
# Sample configuration file for the Common UNIX Printing System (CUPS)
# scheduler.
#
# Copyright 1997-2004 by Easy Software Products, all rights reserved.
#
# These coded instructions, statements, and computer programs are the
# property of Easy Software Products and are protected by Federal
# copyright law. Distribution and use rights are outlined in the file
# "LICENSE.txt" which should have been included with this file. If this
# file is missing or damaged please contact Easy Software Products
# at:
#
# Attn: CUPS Licensing Information
# Easy Software Products
# 44141 Airport View Drive, Suite 204
# Hollywood, Maryland 20636 USA
#
# Voice: (301) 373-9600
# EMail: cups-info@cups.org
# WWW: http://www.cups.org
"cupsd.conf" 862L, 22198C 1,1 Top



so i edit this line: "$Id: cupsd.conf.in,v 1.16 2004/08/18 17:53:47 mike Exp $" by adding log debug after $

 

[cleverhandle]

No. Scroll down. The LogLevel statement is about 150-200 lines into the file. If you don't know how to use vi, use gedit instead - do gedit /etc/cups/cupsd.conf as root.

 

[daniel49]

no vi is ok i used it once before is this the part here then.It talks about error logging. I expected to see a scroll bar in that window i guess.


# ErrorLog: the error log file; if this does not start with a leading /
# then it is assumed to be relative to ServerRoot. By default set to
# "/var/log/cups/error_log"
#
# You can also use the special name "syslog" to send the output to the
# syslog file or daemon.
#

#ErrorLog /var/log/cups/error_log

# LogFilePerm: determines the file mode to set log files to.
#

LogFilePerm 0600

#
# FileDevice: determines whether the scheduler will allow new printers
# to be added using device URIs of the form "file:/foo/bar". The default
# is not to allow file devices due to the potential security vulnerability
# and due to the fact that file devices do not support raw printing.
#

#FileDevice No

 

[cleverhandle]

Keep going...

 

[daniel49]

heres info about it but don't see where to enter it how do you select whole log won't do it likw windows does.

FontPath /usr/share/cups/fonts

#
# 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.
#
MaxLogSize 2000000000

LogLevel info

#
# MaxLogSize: controls the maximum size of each log file before they are
# rotated. Defaults to 1048576 (1MB). Set to 0 to disable log rotating.
#

#MaxLogSize 0

#
# PageLog: the page log file; if this does not start with a leading /
# then it is assumed to be relative to ServerRoot. By default set to
156,0-1 18%

or do you not want me to edit an exsisting line.

 

[cleverhandle]

You change the word "info" after "LogLevel" to "debug".

 

[daniel49]

heres new much longer log after debug was added

[root@localhost cups]# cat error_log
I [15/May/2005:10:36:57 -0700] Listening to 7f000001:631
I [15/May/2005:10:36:57 -0700] Loaded configuration file "/etc/cups/cupsd.conf"
I [15/May/2005:10:36:57 -0700] Configured for up to 100 clients.
I [15/May/2005:10:36:57 -0700] Allowing up to 100 client connections per host.
I [15/May/2005:10:36:57 -0700] Full reload is required.
I [15/May/2005:10:37:12 -0700] LoadPPDs: Read "/etc/cups/ppds.dat", 15 PPDs...
I [15/May/2005:10:37:12 -0700] LoadPPDs: No new or changed PPDs...
I [15/May/2005:10:37:12 -0700] Full reload complete.
I [15/May/2005:14:09:37 -0700] Scheduler shutting down normally.
I [15/May/2005:23:26:20 -0700] Listening to 7f000001:631
I [15/May/2005:23:26:20 -0700] Loaded configuration file "/etc/cups/cupsd.conf"
I [15/May/2005:23:26:20 -0700] Configured for up to 100 clients.
I [15/May/2005:23:26:20 -0700] Allowing up to 100 client connections per host.
I [15/May/2005:23:26:20 -0700] Full reload is required.
I [15/May/2005:23:26:21 -0700] LoadPPDs: Read "/etc/cups/ppds.dat", 15 PPDs...
I [15/May/2005:23:26:21 -0700] LoadPPDs: No new or changed PPDs...
I [15/May/2005:23:26:21 -0700] Full reload complete.
I [16/May/2005:23:11:26 -0700] Scheduler shutting down normally.
I [17/May/2005:10:43:20 -0700] Listening to 7f000001:631
I [17/May/2005:10:43:20 -0700] Loaded configuration file "/etc/cups/cupsd.conf"
I [17/May/2005:10:43:20 -0700] Configured for up to 100 clients.
I [17/May/2005:10:43:20 -0700] Allowing up to 100 client connections per host.
I [17/May/2005:10:43:20 -0700] Full reload is required.
I [17/May/2005:10:43:20 -0700] LoadPPDs: Read "/etc/cups/ppds.dat", 15 PPDs...
I [17/May/2005:10:43:20 -0700] LoadPPDs: No new or changed PPDs...
I [17/May/2005:10:43:20 -0700] Full reload complete.
I [17/May/2005:15:27:01 -0700] Scheduler shutting down normally.
I [17/May/2005:15:40:48 -0700] Listening to 7f000001:631
I [17/May/2005:15:40:48 -0700] Loaded configuration file "/etc/cups/cupsd.conf"
I [17/May/2005:15:40:48 -0700] Configured for up to 100 clients.
I [17/May/2005:15:40:48 -0700] Allowing up to 100 client connections per host.
I [17/May/2005:15:40:48 -0700] Full reload is required.
I [17/May/2005:15:40:48 -0700] LoadPPDs: Read "/etc/cups/ppds.dat", 15 PPDs...
I [17/May/2005:15:40:48 -0700] LoadPPDs: No new or changed PPDs...
I [17/May/2005:15:40:48 -0700] Full reload complete.
I [17/May/2005:16:02:00 -0700] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid =3968)
I [17/May/2005:16:03:53 -0700] Started "/usr/lib/cups/cgi-bin/classes.cgi" (pid= 3972)
I [17/May/2005:16:03:58 -0700] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid =3973)
I [17/May/2005:16:09:02 -0700] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid =3985)
I [17/May/2005:16:34:08 -0700] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid =4090)
I [17/May/2005:16:56:10 -0700] Listening to 7f000001:631
I [17/May/2005:16:56:10 -0700] Loaded configuration file "/etc/cups/cupsd.conf"
I [17/May/2005:16:56:10 -0700] Configured for up to 100 clients.
I [17/May/2005:16:56:10 -0700] Allowing up to 100 client connections per host.
I [17/May/2005:16:56:10 -0700] Full reload is required.
I [17/May/2005:16:56:25 -0700] LoadPPDs: Read "/etc/cups/ppds.dat", 15 PPDs...
I [17/May/2005:16:56:25 -0700] LoadPPDs: No new or changed PPDs...
I [17/May/2005:16:56:25 -0700] Full reload complete.
I [17/May/2005:17:03:48 -0700] Adding start banner page "none" to job 1.
I [17/May/2005:17:03:48 -0700] Adding end banner page "none" to job 1.
I [17/May/2005:17:03:48 -0700] Job 1 queued on 'printer' by 'dan'.
I [17/May/2005:17:03:48 -0700] Started filter /usr/lib/cups/filter/pstops (PID 4 186) for job 1.
I [17/May/2005:17:03:48 -0700] Started filter /usr/lib/cups/filter/foomatic-rip (PID 4187) for job 1.
I [17/May/2005:17:03:48 -0700] Started backend /usr/lib/cups/backend/usb (PID 41 88) for job 1.
E [17/May/2005:17:03:50 -0700] PID 4187 stopped with status 1!
I [17/May/2005:17:03:50 -0700] Hint: Try setting the LogLevel to "debug" to find out more.
I [17/May/2005:17:36:39 -0700] Scheduler shutting down normally.
I [17/May/2005:17:36:39 -0700] Listening to 7f000001:631
I [17/May/2005:17:36:39 -0700] Loaded configuration file "/etc/cups/cupsd.conf"
I [17/May/2005:17:36:39 -0700] Configured for up to 100 clients.
I [17/May/2005:17:36:39 -0700] Allowing up to 100 client connections per host.
I [17/May/2005:17:36:39 -0700] Full reload is required.
I [17/May/2005:17:36:54 -0700] LoadPPDs: Read "/etc/cups/ppds.dat", 15 PPDs...
I [17/May/2005:17:36:54 -0700] LoadPPDs: Wrote "/etc/cups/ppds.dat", 15 PPDs...
I [17/May/2005:17:36:54 -0700] Full reload complete.
I [17/May/2005:18:25:24 -0700] Adding start banner page "none" to job 2.
I [17/May/2005:18:25:24 -0700] Adding end banner page "none" to job 2.
I [17/May/2005:18:25:24 -0700] Job 2 queued on 'printer' by 'dan'.
I [17/May/2005:18:25:24 -0700] Started filter /usr/lib/cups/filter/texttops (PID 4550) for job 2.
I [17/May/2005:18:25:24 -0700] Started filter /usr/lib/cups/filter/pstops (PID 4 551) for job 2.
I [17/May/2005:18:25:24 -0700] Started filter /usr/lib/cups/filter/foomatic-rip (PID 4552) for job 2.
I [17/May/2005:18:25:24 -0700] Started backend /usr/lib/cups/backend/usb (PID 45 54) for job 2.
E [17/May/2005:18:25:24 -0700] PID 4552 stopped with status 1!
I [17/May/2005:18:25:24 -0700] Hint: Try setting the LogLevel to "debug" to find out more.
I [17/May/2005:18:31:48 -0700] Adding start banner page "none" to job 3.
I [17/May/2005:18:31:48 -0700] Adding end banner page "none" to job 3.
I [17/May/2005:18:31:48 -0700] Job 3 queued on 'printer' by 'root'.
I [17/May/2005:18:31:48 -0700] Started filter /usr/lib/cups/filter/pstops (PID 4 638) for job 3.
I [17/May/2005:18:31:48 -0700] Started filter /usr/lib/cups/filter/foomatic-rip (PID 4639) for job 3.
I [17/May/2005:18:31:48 -0700] Started backend /usr/lib/cups/backend/usb (PID 46 40) for job 3.
E [17/May/2005:18:31:49 -0700] PID 4639 stopped with status 1!
I [17/May/2005:18:31:49 -0700] Hint: Try setting the LogLevel to "debug" to find out more.
I [17/May/2005:18:46:10 -0700] Scheduler shutting down normally.
D [17/May/2005:18:59:40 -0700] AddLocation: added location '/'
D [17/May/2005:18:59:40 -0700] DenyIP: / deny 00000000/00000000
D [17/May/2005:18:59:40 -0700] AllowIP: / allow 7f000001/ffffffff
D [17/May/2005:18:59:40 -0700] AddLocation: added location '/admin'
D [17/May/2005:18:59:40 -0700] DenyIP: /admin deny 00000000/00000000
D [17/May/2005:18:59:40 -0700] AllowIP: /admin allow 7f000001/ffffffff
D [17/May/2005:18:59:40 -0700] AddLocation: added location '/printers/printer'
D [17/May/2005:18:59:40 -0700] DenyIP: /printers/printer deny 00000000/00000000
D [17/May/2005:18:59:40 -0700] AllowIP: /printers/printer allow 7f000001/fffffff f
D [17/May/2005:18:59:40 -0700] AddLocation: added location 'CUPS_INTERNAL_BROWSE _ACL'
D [17/May/2005:18:59:40 -0700] AllowHost: CUPS_INTERNAL_BROWSE_ACL allow @LOCAL
I [17/May/2005:18:59:40 -0700] Listening to 7f000001:631
I [17/May/2005:18:59:40 -0700] Loaded configuration file "/etc/cups/cupsd.conf"
I [17/May/2005:18:59:40 -0700] Configured for up to 100 clients.
I [17/May/2005:18:59:40 -0700] Allowing up to 100 client connections per host.
I [17/May/2005:18:59:40 -0700] Full reload is required.
D [17/May/2005:18:59:41 -0700] LoadAllPrinters: Loading printer printer...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "lpd"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "socket"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "usb://Lexmark/X73"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "usb:/dev/usb/lp1"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "usb:/dev/usb/lp2"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "usb:/dev/usb/lp3"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "usb:/dev/usb/lp4"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "usb:/dev/usb/lp5"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "usb:/dev/usb/lp6"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "usb:/dev/usb/lp7"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "usb:/dev/usb/lp8"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "usb:/dev/usb/lp9"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "usb:/dev/usb/lp10"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "usb:/dev/usb/lp11"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "usb:/dev/usb/lp12"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "usb:/dev/usb/lp13"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "usb:/dev/usb/lp14"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "usb:/dev/usb/lp15"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "scsi"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "smb"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "http"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "parallel:/dev/lp0"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "ipp"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "serial:/dev/ttyS0?baud =115200"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "serial:/dev/ttyS1?baud =115200"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "serial:/dev/ttyS2?baud =115200"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "serial:/dev/ttyS3?baud =115200"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "serial:/dev/ttyS4?baud =115200"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "serial:/dev/ttyS5?baud =115200"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "serial:/dev/ttyS6?baud =115200"...
D [17/May/2005:18:59:41 -0700] LoadDevices: Added device "serial:/dev/ttyS7?baud =115200"...
I [17/May/2005:18:59:41 -0700] LoadPPDs: Read "/etc/cups/ppds.dat", 15 PPDs...
I [17/May/2005:18:59:41 -0700] LoadPPDs: No new or changed PPDs...
D [17/May/2005:18:59:41 -0700] LoadAllJobs: Scanning /var/spool/cups...
D [17/May/2005:18:59:41 -0700] LoadAllJobs: Loading attributes for job 2...
D [17/May/2005:18:59:41 -0700] LoadAllJobs: Loading attributes for job 3...
D [17/May/2005:18:59:41 -0700] LoadAllJobs: Loading attributes for job 1...
I [17/May/2005:18:59:41 -0700] Full reload complete.
D [17/May/2005:18:59:41 -0700] StartListening: NumListeners=1
D [17/May/2005:18:59:41 -0700] StartListening: address=7f000001 port=631
D [17/May/2005:18:59:41 -0700] ResumeListening: setting input bits...
D [17/May/2005:19:00:11 -0700] AcceptClient: 5 from localhost:631.
D [17/May/2005:19:00:11 -0700] ReadClient: 5 POST / HTTP/1.1
D [17/May/2005:19:00:11 -0700] ProcessIPPRequest: 5 status_code=0
D [17/May/2005:19:00:11 -0700] ReadClient: 5 POST / HTTP/1.1
D [17/May/2005:19:00:11 -0700] ProcessIPPRequest: 5 status_code=0
D [17/May/2005:19:01:11 -0700] CloseClient: 5
D [17/May/2005:19:01:29 -0700] AcceptClient: 5 from localhost:631.
D [17/May/2005:19:01:29 -0700] ReadClient: 5 POST / HTTP/1.1
D [17/May/2005:19:01:29 -0700] ProcessIPPRequest: 5 status_code=1
D [17/May/2005:19:01:29 -0700] CloseClient: 5
D [17/May/2005:19:01:32 -0700] AcceptClient: 5 from localhost:631.
D [17/May/2005:19:01:32 -0700] ReadClient: 5 POST / HTTP/1.1
D [17/May/2005:19:01:32 -0700] ProcessIPPRequest: 5 status_code=1
D [17/May/2005:19:01:32 -0700] CloseClient: 5
D [17/May/2005:19:01:47 -0700] AcceptClient: 5 from localhost:631.
D [17/May/2005:19:01:47 -0700] ReadClient: 5 POST / HTTP/1.1
D [17/May/2005:19:01:47 -0700] ProcessIPPRequest: 5 status_code=1
D [17/May/2005:19:01:47 -0700] ReadClient: 5 POST / HTTP/1.1
D [17/May/2005:19:01:47 -0700] ProcessIPPRequest: 5 status_code=1
D [17/May/2005:19:01:47 -0700] ReadClient: 5 POST / HTTP/1.1
D [17/May/2005:19:01:47 -0700] ProcessIPPRequest: 5 status_code=0
D [17/May/2005:19:01:47 -0700] CloseClient: 5
D [17/May/2005:19:01:47 -0700] AcceptClient: 5 from localhost:631.
D [17/May/2005:19:01:47 -0700] ReadClient: 5 POST /printers/printer HTTP/1.1
D [17/May/2005:19:01:47 -0700] print_job: auto-typing file...
D [17/May/2005:19:01:47 -0700] print_job: request file type is application/posts cript.
D [17/May/2005:19:01:47 -0700] check_quotas: requesting-user-name = 'root'
D [17/May/2005:19:01:47 -0700] print_job: requesting-user-name = 'root'
I [17/May/2005:19:01:47 -0700] Adding start banner page "none" to job 4.
I [17/May/2005:19:01:47 -0700] Adding end banner page "none" to job 4.
I [17/May/2005:19:01:47 -0700] Job 4 queued on 'printer' by 'root'.
D [17/May/2005:19:01:47 -0700] Job 4 hold_until = 0
D [17/May/2005:19:01:47 -0700] StartJob(4, 0xf8700550)
D [17/May/2005:19:01:47 -0700] StartJob() id = 4, file = 0/1
D [17/May/2005:19:01:47 -0700] job-sheets=none,none
D [17/May/2005:19:01:47 -0700] banner_page = 0
D [17/May/2005:19:01:47 -0700] StartJob: argv = "printer","4","root","testprint. ps","1","page-bottom=86 cpi=12 page-right=57 page-left=57 page-top=72 scaling=10 0 lpi=7 wrap","/var/spool/cups/d00004-001"
D [17/May/2005:19:01:47 -0700] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin :/usr/bin"
D [17/May/2005:19:01:47 -0700] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [17/May/2005:19:01:47 -0700] StartJob: envp[2]="USER=root"
D [17/May/2005:19:01:47 -0700] StartJob: envp[3]="CHARSET=utf-8"
D [17/May/2005:19:01:47 -0700] StartJob: envp[4]="LANG=en_US"
D [17/May/2005:19:01:47 -0700] StartJob: envp[5]="PPD=/etc/cups/ppd/printer.ppd"
D [17/May/2005:19:01:47 -0700] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [17/May/2005:19:01:47 -0700] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [17/May/2005:19:01:47 -0700] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [17/May/2005:19:01:47 -0700] StartJob: envp[9]="CONTENT_TYPE=application/posts cript"
D [17/May/2005:19:01:47 -0700] StartJob: envp[10]="DEVICE_URI=usb:/dev/usb/lp0"
D [17/May/2005:19:01:47 -0700] StartJob: envp[11]="PRINTER=printer"
D [17/May/2005:19:01:47 -0700] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"
D [17/May/2005:19:01:47 -0700] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups /fonts"
D [17/May/2005:19:01:47 -0700] StartJob: envp[14]="CUPS_SERVER=localhost"
D [17/May/2005:19:01:47 -0700] StartJob: envp[15]="IPP_PORT=631"
D [17/May/2005:19:01:47 -0700] StartJob: statusfds = [ 8 9 ]
D [17/May/2005:19:01:47 -0700] StartJob: filterfds[1] = [ 10 -1 ]
D [17/May/2005:19:01:47 -0700] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [17/May/2005:19:01:47 -0700] StartJob: filterfds[0] = [ 11 12 ]
D [17/May/2005:19:01:47 -0700] start_process("/usr/lib/cups/filter/pstops", 0xfe e853f0, 0xfee84760, 10, 12, 9)
I [17/May/2005:19:01:47 -0700] Started filter /usr/lib/cups/filter/pstops (PID 3 734) for job 4.
D [17/May/2005:19:01:47 -0700] StartJob: filter = "/usr/lib/cups/filter/foomatic -rip"
D [17/May/2005:19:01:47 -0700] StartJob: filterfds[1] = [ 10 13 ]
D [17/May/2005:19:01:47 -0700] start_process("/usr/lib/cups/filter/foomatic-rip" , 0xfee853f0, 0xfee84760, 11, 13, 9)
I [17/May/2005:19:01:47 -0700] Started filter /usr/lib/cups/filter/foomatic-rip (PID 3735) for job 4.
D [17/May/2005:19:01:47 -0700] StartJob: backend = "/usr/lib/cups/backend/usb"
D [17/May/2005:19:01:47 -0700] StartJob: filterfds[0] = [ -1 11 ]
D [17/May/2005:19:01:47 -0700] start_process("/usr/lib/cups/backend/usb", 0xfee8 53f0, 0xfee84760, 10, 11, 9)
I [17/May/2005:19:01:47 -0700] Started backend /usr/lib/cups/backend/usb (PID 37 36) for job 4.
D [17/May/2005:19:01:47 -0700] ProcessIPPRequest: 5 status_code=0
D [17/May/2005:19:01:47 -0700] CloseClient: 5
D [17/May/2005:19:01:47 -0700] [Job 4] Page = 612x792; 0,0 to 612,792
D [17/May/2005:19:01:47 -0700] [Job 4] slowcollate=0, slowduplex=0, sloworder=0
D [17/May/2005:19:01:47 -0700] [Job 4] 0 %%BoundingBox: 0 0 612 792
D [17/May/2005:19:01:47 -0700] [Job 4] 0 %%Pages: 1
D [17/May/2005:19:01:47 -0700] [Job 4] 0 %%LanguageLevel: 1
D [17/May/2005:19:01:47 -0700] [Job 4] 0 %%DocumentData: Clean7Bit
D [17/May/2005:19:01:47 -0700] [Job 4] 0 %%DocumentSuppliedResources: procset te stprint/1.0
D [17/May/2005:19:01:47 -0700] [Job 4] 0 %%DocumentNeededResources: font Helveti ca Helvetica-Bold Times-Roman
D [17/May/2005:19:01:47 -0700] [Job 4] 0 %%Creator: Michael Sweet, Easy Software Products
D [17/May/2005:19:01:47 -0700] [Job 4] 0 %%CreationDate: May 11, 1999
D [17/May/2005:19:01:47 -0700] [Job 4] 0 %%Title: Test Page
D [17/May/2005:19:01:47 -0700] [Job 4] 0 %%EndComments
D [17/May/2005:19:01:47 -0700] [Job 4] 0 %%BeginProlog
D [17/May/2005:19:01:47 -0700] [Job 4] 0 %%BeginResource procset testprint 1.1 0
D [17/May/2005:19:01:47 -0700] [Job 4] 0 %%EndResource
D [17/May/2005:19:01:47 -0700] [Job 4] 0 %%EndProlog
D [17/May/2005:19:01:47 -0700] [Job 4] 0 %%Page: 1 1
D [17/May/2005:19:01:47 -0700] [Job 4] 0 %%Page: 1 1
D [17/May/2005:19:01:47 -0700] [Job 4] pw = 498.0, pl = 634.0
D [17/May/2005:19:01:47 -0700] [Job 4] PageLeft = 57.0, PageRight = 555.0
D [17/May/2005:19:01:47 -0700] [Job 4] PageTop = 720.0, PageBottom = 86.0
D [17/May/2005:19:01:47 -0700] [Job 4] PageWidth = 612.0, PageLength = 792.0
D [17/May/2005:19:01:47 -0700] [Job 4] LPGETSTATUS returned a port status of 18. ..
D [17/May/2005:19:01:48 -0700] [Job 4] foomatic-rip version $Revision: 3.43.2.6 $ running...
D [17/May/2005:19:01:48 -0700] [Job 4] Parsing PPD file ...
D [17/May/2005:19:01:48 -0700] [Job 4] *cupsFilter: "application/vnd.cups-postsc ript 0 foomatic-rip"
D [17/May/2005:19:01:48 -0700] [Job 4] Added option ColorSpace
D [17/May/2005:19:01:48 -0700] [Job 4] Added option PrintoutMode
D [17/May/2005:19:01:48 -0700] [Job 4] Added option PageSize
D [17/May/2005:19:01:48 -0700] [Job 4] Added option PageRegion
D [17/May/2005:19:01:48 -0700] [Job 4] Added option ImageableArea
D [17/May/2005:19:01:48 -0700] [Job 4] Added option PaperDimension
D [17/May/2005:19:01:48 -0700] [Job 4] Added option InkType
D [17/May/2005:19:01:48 -0700] [Job 4] Added option Resolution
D [17/May/2005:19:01:48 -0700] [Job 4] Added option Bidirectional
D [17/May/2005:19:01:48 -0700] [Job 4] Added option Font
D [17/May/2005:19:01:48 -0700] [Job 4]
D [17/May/2005:19:01:48 -0700] [Job 4] Parameter Summary
D [17/May/2005:19:01:48 -0700] [Job 4] -----------------
D [17/May/2005:19:01:48 -0700] [Job 4]
D [17/May/2005:19:01:48 -0700] [Job 4] Spooler: cups
D [17/May/2005:19:01:48 -0700] [Job 4] Printer: printer
D [17/May/2005:19:01:48 -0700] [Job 4] PPD file: /etc/cups/ppd/printer.ppd
D [17/May/2005:19:01:48 -0700] [Job 4] Printer model: Lexmark X73 Foomatic/drv_z 42 (recommended)
D [17/May/2005:19:01:48 -0700] [Job 4] Job title: testprint.ps
D [17/May/2005:19:01:48 -0700] [Job 4] File(s) to be printed:
D [17/May/2005:19:01:48 -0700] [Job 4] <STDIN>
D [17/May/2005:19:01:48 -0700] [Job 4]
D [17/May/2005:19:01:48 -0700] [Job 4] Pondering option 'page-bottom=86'
D [17/May/2005:19:01:48 -0700] [Job 4] Unknown option page-bottom=86.
D [17/May/2005:19:01:48 -0700] [Job 4] Pondering option 'cpi=12'
D [17/May/2005:19:01:48 -0700] [Job 4] Unknown option cpi=12.
D [17/May/2005:19:01:48 -0700] [Job 4] Pondering option 'page-right=57'
D [17/May/2005:19:01:48 -0700] [Job 4] Unknown option page-right=57.
D [17/May/2005:19:01:48 -0700] [Job 4] Pondering option 'page-left=57'
D [17/May/2005:19:01:48 -0700] [Job 4] Unknown option page-left=57.
D [17/May/2005:19:01:48 -0700] [Job 4] Pondering option 'page-top=72'
D [17/May/2005:19:01:48 -0700] [Job 4] Unknown option page-top=72.
D [17/May/2005:19:01:48 -0700] [Job 4] Pondering option 'scaling=100'
D [17/May/2005:19:01:48 -0700] [Job 4] Unknown option scaling=100.
D [17/May/2005:19:01:48 -0700] [Job 4] Pondering option 'lpi=7'
D [17/May/2005:19:01:48 -0700] [Job 4] Unknown option lpi=7.
D [17/May/2005:19:01:48 -0700] [Job 4] Pondering option 'wrap'
D [17/May/2005:19:01:48 -0700] [Job 4] Unknown boolean option "wrap".
D [17/May/2005:19:01:48 -0700] [Job 4]
D [17/May/2005:19:01:48 -0700] [Job 4] ========================================= =======
D [17/May/2005:19:01:48 -0700] [Job 4]
D [17/May/2005:19:01:48 -0700] [Job 4] File: <STDIN>
D [17/May/2005:19:01:48 -0700] [Job 4]
D [17/May/2005:19:01:48 -0700] [Job 4] ========================================= =======
D [17/May/2005:19:01:48 -0700] [Job 4]
D [17/May/2005:19:01:48 -0700] [Job 4] Reading PostScript input ...
D [17/May/2005:19:01:48 -0700] [Job 4] --> This document is DSC-conforming!
D [17/May/2005:19:01:48 -0700] [Job 4]
D [17/May/2005:19:01:48 -0700] [Job 4] -----------
D [17/May/2005:19:01:48 -0700] [Job 4] Found: %%BeginProlog
D [17/May/2005:19:01:48 -0700] [Job 4] 0 %%EOF
D [17/May/2005:19:01:48 -0700] [Job 4] Saw EOF!
D [17/May/2005:19:01:48 -0700] [Job 4] Found: %%EndProlog
D [17/May/2005:19:01:48 -0700] [Job 4]
D [17/May/2005:19:01:48 -0700] [Job 4] -----------
D [17/May/2005:19:01:48 -0700] [Job 4] Found: %%BeginSetup
D [17/May/2005:19:01:48 -0700] [Job 4] Inserting PostScript code for CUPS' page accounting
D [17/May/2005:19:01:48 -0700] [Job 4] Found: %%BeginFeature: *PrintoutMode Norm al
D [17/May/2005:19:01:48 -0700] [Job 4] Option: PrintoutMode=Normal --> Setting o ption
D [17/May/2005:19:01:48 -0700] [Job 4] Found: %% FoomaticRIPOptionSetting: Print outMode=Normal
D [17/May/2005:19:01:48 -0700] [Job 4] Option: PrintoutMode=Normal --> Setting o ption
D [17/May/2005:19:01:48 -0700] [Job 4] Found: %%BeginFeature: *InkType CMYK
D [17/May/2005:19:01:48 -0700] [Job 4] Option: InkType=CMYK --> Setting option
D [17/May/2005:19:01:48 -0700] [Job 4] Found: %% FoomaticRIPOptionSetting: InkTy pe=CMYK
D [17/May/2005:19:01:48 -0700] [Job 4] Option: InkType=CMYK --> Setting option
D [17/May/2005:19:01:48 -0700] [Job 4] Found: %%BeginFeature: *PageSize Letter
D [17/May/2005:19:01:48 -0700] [Job 4] Option: PageSize=Letter --> Setting optio n
D [17/May/2005:19:01:48 -0700] [Job 4] Found: %% FoomaticRIPOptionSetting: PageS ize=Letter
D [17/May/2005:19:01:48 -0700] [Job 4] Option: PageSize=Letter --> Setting optio n
D [17/May/2005:19:01:48 -0700] [Job 4] Found: %%BeginFeature: *Bidirectional Fro mPrintoutMode
D [17/May/2005:19:01:48 -0700] [Job 4] Option: Bidirectional=FromPrintoutMode -- > Setting option
D [17/May/2005:19:01:48 -0700] [Job 4] Found: %% FoomaticRIPOptionSetting: Bidir ectional=@PrintoutMode
D [17/May/2005:19:01:48 -0700] [Job 4] Option: Bidirectional=FromPrintoutMode -- > Setting option
D [17/May/2005:19:01:48 -0700] [Job 4] Found: %%BeginFeature: *Resolution FromPr intoutMode
D [17/May/2005:19:01:48 -0700] [Job 4] Option: Resolution=FromPrintoutMode --> S etting option
D [17/May/2005:19:01:48 -0700] [Job 4] Found: %% FoomaticRIPOptionSetting: Resol ution=@PrintoutMode
D [17/May/2005:19:01:48 -0700] [Job 4] Option: Resolution=FromPrintoutMode --> S etting option
D [17/May/2005:19:01:48 -0700] [Job 4] Found: %%EndSetup
D [17/May/2005:19:01:48 -0700] [Job 4]
D [17/May/2005:19:01:48 -0700] [Job 4] -----------
D [17/May/2005:19:01:48 -0700] [Job 4] New page: 1 1
D [17/May/2005:19:01:48 -0700] [Job 4] Inserting option code into "PageSetup" se ction.
D [17/May/2005:19:01:48 -0700] [Job 4] No page header or page header not DSC-con forming
D [17/May/2005:19:01:48 -0700] [Job 4] Stopping search for page header options
D [17/May/2005:19:01:48 -0700] [Job 4] Found:
D [17/May/2005:19:01:48 -0700] [Job 4] pageHeight sub % Move d own...
D [17/May/2005:19:01:48 -0700] [Job 4] --> Output goes directly to the renderer now.
D [17/May/2005:19:01:48 -0700] [Job 4]
D [17/May/2005:19:01:48 -0700] [Job 4]
D [17/May/2005:19:01:48 -0700] [Job 4] Starting renderer
D [17/May/2005:19:01:48 -0700] [Job 4] JCL: <job data>
D [17/May/2005:19:01:48 -0700] [Job 4]
D [17/May/2005:19:01:48 -0700] [Job 4] renderer PID kid4=3738
D [17/May/2005:19:01:48 -0700] [Job 4] renderer command: size=letter; hdpi=600; gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=bitcmyk -sPAPERSIZE=$si ze -r${hdpi}x600 -sOutputFile=- - | z42_cmyk size=$size hdpi=$hdpi mode=cmyk bi di=0
D [17/May/2005:19:01:48 -0700] [Job 4] sh: z42_cmyk: command not found
D [17/May/2005:19:01:48 -0700] [Job 4] foomatic-gswrapper: gs '-dBATCH' '-dPARAN OIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=bitcmyk' '-sPAPERSIZE=letter' '-r600x6 00' '-sOutputFile=| cat >&3' '/dev/fd/0' 3>&1 1>&2
D [17/May/2005:19:01:49 -0700] [Job 4]
D [17/May/2005:19:01:49 -0700] [Job 4] Closing renderer
D [17/May/2005:19:01:49 -0700] [Job 4] cat: write error: Broken pipe
D [17/May/2005:19:01:49 -0700] [Job 4] Error: /ioerror in --.outputpage--
D [17/May/2005:19:01:49 -0700] [Job 4] Operand stack:
D [17/May/2005:19:01:49 -0700] [Job 4] 416.5 308.0 306.0 748.0 306.0 5 5.0 51.0 1 true
D [17/May/2005:19:01:49 -0700] [Job 4] Execution stack:
D [17/May/2005:19:01:49 -0700] [Job 4] %interp_exit .runexec2 --nostringval- - --nostringval-- --nostringval-- 2 %stopped_push --nostringval-- -- nostringval-- --nostringval-- false 1 %stopped_push 1 3 %oparray_p op 1 3 %oparray_pop 1 3 %oparray_pop 1 3 %oparray_pop .runex ec2 --nostringval-- --nostringval-- --nostringval-- 2 %stopped_push --nostringval-- 7 3 %oparray_pop --nostringval-- --nostringval-- --n ostringval-- --nostringval--
D [17/May/2005:19:01:49 -0700] [Job 4] Dictionary stack:
D [17/May/2005:19:01:49 -0700] [Job 4] --dict:1076/1123(ro)(G)-- --dict:0/20(G )-- --dict:114/200(L)--
D [17/May/2005:19:01:49 -0700] [Job 4] Current allocation mode is local
D [17/May/2005:19:01:49 -0700] [Job 4] Last OS error: 32
D [17/May/2005:19:01:49 -0700] [Job 4] GNU Ghostscript 7.07: Unrecoverable error , exit code 1
D [17/May/2005:19:01:49 -0700] [Job 4] renderer return value: 127
D [17/May/2005:19:01:49 -0700] [Job 4] renderer received signal: 127
D [17/May/2005:19:01:49 -0700] [Job 4] KID3 exited with status 1
D [17/May/2005:19:01:49 -0700] [Job 4] Renderer exit stat: 1
D [17/May/2005:19:01:49 -0700] [Job 4] Process dying with "The renderer command line returned an unrecognized error code 127.", exit stat: 1
D [17/May/2005:19:01:49 -0700] [Job 4] The renderer command line returned an unr ecognized error code 127.
D [17/May/2005:19:01:49 -0700] [Job 4] tail process done writing data to STDOUT
D [17/May/2005:19:01:49 -0700] [Job 4] KID4 finished
D [17/May/2005:19:01:49 -0700] [Job 4] Renderer process finished
D [17/May/2005:19:01:49 -0700] [Job 4] Killing process 3737 (KID3)
D [17/May/2005:19:01:49 -0700] [Job 4] Process dying with "Error closing rendere r", exit stat: 1
D [17/May/2005:19:01:49 -0700] [Job 4] Error closing renderer
E [17/May/2005:19:01:49 -0700] PID 3735 stopped with status 1!
D [17/May/2005:19:01:50 -0700] UpdateJob: job 4, file 0 is complete.
D [17/May/2005:19:01:50 -0700] CancelJob: id = 4
D [17/May/2005:19:01:50 -0700] StopJob: id = 4, force = 0
D [17/May/2005:19:01:50 -0700] StopJob: printer state is 3
D [17/May/2005:19:11:07 -0700] AcceptClient: 8 from localhost:631.
D [17/May/2005:19:11:07 -0700] ReadClient: 8 POST / HTTP/1.1
D [17/May/2005:19:11:07 -0700] ProcessIPPRequest: 8 status_code=1
D [17/May/2005:19:11:07 -0700] CloseClient: 8