Re: Stripes are printed with Cups, gs prints correct

Kay Obermueller wrote:
> Anonymous wrote:
>
> > Kay Obermueller wrote:
> >
> > > Hello all,
> > > I've compiled and installed the Cups cvs version from yesterday and
> > >
> > > hpijs-1.4.1 . Also I've installed ESP Ghostscript 7.07 (gs --version
> > >
> > > reports 7.07.1). Now I'm trying to setup my HP Deskjet500 and installed
> > >
> > > a printer with the web admin interface of Cups, in which I choose "HP
> > >
> > > Deskjet 500, Foomatic + hpijs (recommended) (en)" as the model.
> > > As result of the testpage I only get artistic looking stripes, beginning
> > >
> > > from the left, ending roughly in the middle of the paper.
> > > But with:
> > > gs -sOutputFile=/dev/lp0 -sDEVICE=djet500 /home/xxx/ls.ps
> > > it prints a correct page. So ghostscript works in principle.
> > > What's wrong?
> > > Unfortunately www.linuxprinting.org is down this weekend. Is there
> > > a mirror available?
> > > Thank you in advance!
> > >
> > > Kay
> >
> >
> > Did you add:
> > include pstoraster/cups.mak
> >
> > to the end of the ghostscript makefile before building?  If it's not 
> > there your problem is that cups can't use ghostscript to rasterize.
> >
> > Michael
> The ESP version of ghostscript is supposed to be prepatched for use with 
> cups. I installed it as rpm. It's description is:
> "ESP Ghostscript is an enhanced version of GNU Ghostscript that includes 
> new printer drivers and support for the Common UNIX Printing System."
> The amount and size of the stripes I get seem to correspond with the 
> file to print. I printed /etc/motd and got only a grey stripe of ~3cm on 
> top of the page, that gots thinner towards the end.
>
> Kay
This is /var/log/cups/error_log after I sat LogLevel to debug in 
/etc/cups/cupsd.conf:

I [29/Sep/2003:16:00:05 +0200] Listening to 0:631
D [29/Sep/2003:16:00:05 +0200] AddLocation: added location '/'
D [29/Sep/2003:16:00:05 +0200] DenyIP: / deny 00000000/00000000
D [29/Sep/2003:16:00:05 +0200] AllowIP: / allow 7f000001/ffffffff
D [29/Sep/2003:16:00:05 +0200] AddLocation: added location '/admin'
D [29/Sep/2003:16:00:05 +0200] DenyIP: /admin deny 00000000/00000000
D [29/Sep/2003:16:00:05 +0200] AllowIP: /admin allow 7f000001/ffffffff
I [29/Sep/2003:16:00:05 +0200] Loaded configuration file 
"/etc/cups/cupsd.conf"
I [29/Sep/2003:16:00:05 +0200] Configured for up to 100 clients.
I [29/Sep/2003:16:00:05 +0200] Allowing up to 100 client connections per 
host.
I [29/Sep/2003:16:00:05 +0200] Full reload is required.
D [29/Sep/2003:16:00:05 +0200] LoadAllPrinters: Loading printer hp500...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device "ipp"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device "lpd"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device 
"usb:/dev/usb/lp0"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device 
"usb:/dev/usb/lp1"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device 
"usb:/dev/usb/lp2"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device 
"usb:/dev/usb/lp3"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device 
"usb:/dev/usb/lp4"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device 
"usb:/dev/usb/lp5"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device 
"usb:/dev/usb/lp6"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device 
"usb:/dev/usb/lp7"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device 
"usb:/dev/usb/lp8"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device 
"usb:/dev/usb/lp9"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device 
"usb:/dev/usb/lp10"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device 
"usb:/dev/usb/lp11"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device 
"usb:/dev/usb/lp12"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device 
"usb:/dev/usb/lp13"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device 
"usb:/dev/usb/lp14"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device 
"usb:/dev/usb/lp15"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device "http"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device "scsi"...
D [29/Sep/2003:16:00:05 +0200] LoadDevices: Added device 
"parallel:/dev/lp0"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS0?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS1?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS2?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS3?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS4?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS5?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS6?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS7?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS8?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS9?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS10?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS11?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS12?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS13?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS14?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS15?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS16?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS17?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS18?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS19?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS20?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS21?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS22?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device 
"serial:/dev/ttyS23?baud=115200"...
D [29/Sep/2003:16:00:06 +0200] LoadDevices: Added device "socket"...
I [29/Sep/2003:16:00:06 +0200] LoadPPDs: Read "/etc/cups/ppds.dat", 252 
PPDs...
I [29/Sep/2003:16:00:06 +0200] LoadPPDs: No new or changed PPDs...
D [29/Sep/2003:16:00:06 +0200] LoadAllJobs: Scanning /var/spool/cups...
D [29/Sep/2003:16:00:06 +0200] LoadAllJobs: Loading attributes for job 12...
D [29/Sep/2003:16:00:06 +0200] LoadAllJobs: Loading attributes for job 13...
D [29/Sep/2003:16:00:06 +0200] LoadAllJobs: Loading attributes for job 14...
D [29/Sep/2003:16:00:06 +0200] LoadAllJobs: Loading attributes for job 15...
I [29/Sep/2003:16:00:06 +0200] Full reload complete.
D [29/Sep/2003:16:00:06 +0200] StartListening: NumListeners=1
D [29/Sep/2003:16:00:06 +0200] StartListening: address=00000000 port=631
D [29/Sep/2003:16:00:06 +0200] ResumeListening: setting input bits...
D [29/Sep/2003:16:01:13 +0200] AcceptClient() 5 from localhost:631.
D [29/Sep/2003:16:01:13 +0200] ReadClient() 5 POST / HTTP/1.1
D [29/Sep/2003:16:01:13 +0200] ProcessIPPRequest: 5 status_code=1
D [29/Sep/2003:16:01:13 +0200] CloseClient() 5
D [29/Sep/2003:16:01:13 +0200] AcceptClient() 5 from localhost:631.
D [29/Sep/2003:16:01:13 +0200] ReadClient() 5 POST / HTTP/1.1
D [29/Sep/2003:16:01:13 +0200] ProcessIPPRequest: 5 status_code=1
D [29/Sep/2003:16:01:13 +0200] CloseClient() 5
D [29/Sep/2003:16:01:13 +0200] AcceptClient() 5 from localhost:631.
D [29/Sep/2003:16:01:13 +0200] ReadClient() 5 POST / HTTP/1.1
D [29/Sep/2003:16:01:13 +0200] ProcessIPPRequest: 5 status_code=0
D [29/Sep/2003:16:01:13 +0200] ReadClient() 5 POST /printers/hp500 HTTP/1.1
D [29/Sep/2003:16:01:13 +0200] print_job: auto-typing file...
D [29/Sep/2003:16:01:13 +0200] print_job: request file type is text/plain.
D [29/Sep/2003:16:01:13 +0200] check_quotas: requesting-user-name = 'root'
D [29/Sep/2003:16:01:13 +0200] print_job: requesting-user-name = 'root'
I [29/Sep/2003:16:01:13 +0200] Adding start banner page "none" to job 16.
I [29/Sep/2003:16:01:13 +0200] Adding end banner page "none" to job 16.
I [29/Sep/2003:16:01:13 +0200] Job 16 queued on 'hp500' by 'root'.
D [29/Sep/2003:16:01:13 +0200] Job 16 hold_until = 0
D [29/Sep/2003:16:01:13 +0200] StartJob(16, 0x808a088)
D [29/Sep/2003:16:01:13 +0200] StartJob() id = 16, file = 0/1
D [29/Sep/2003:16:01:13 +0200] job-sheets=none,none
D [29/Sep/2003:16:01:13 +0200] banner_page = 0
D [29/Sep/2003:16:01:13 +0200] StartJob: argv = 
"hp500","16","root","motd","1","PageSize=A4","/var/spool/cups/d00016-001"
D [29/Sep/2003:16:01:13 +0200] StartJob: 
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [29/Sep/2003:16:01:13 +0200] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [29/Sep/2003:16:01:13 +0200] StartJob: envp[2]="USER=root"
D [29/Sep/2003:16:01:13 +0200] StartJob: envp[3]="CHARSET=iso-8859-1"
D [29/Sep/2003:16:01:13 +0200] StartJob: envp[4]="LANG=en"
D [29/Sep/2003:16:01:13 +0200] StartJob: 
envp[5]="PPD=/etc/cups/ppd/hp500.ppd"
D [29/Sep/2003:16:01:13 +0200] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [29/Sep/2003:16:01:13 +0200] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [29/Sep/2003:16:01:13 +0200] StartJob: 
envp[8]="TMPDIR=/var/spool/cups/tmp"
D [29/Sep/2003:16:01:13 +0200] StartJob: envp[9]="CONTENT_TYPE=text/plain"
D [29/Sep/2003:16:01:13 +0200] StartJob: 
envp[10]="DEVICE_URI=parallel:/dev/lp0"
D [29/Sep/2003:16:01:13 +0200] StartJob: envp[11]="PRINTER=hp500"
D [29/Sep/2003:16:01:13 +0200] StartJob: 
envp[12]="CUPS_DATADIR=/usr/share/cups"
D [29/Sep/2003:16:01:13 +0200] StartJob: 
envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [29/Sep/2003:16:01:13 +0200] StartJob: envp[14]="CUPS_SERVER=localhost"
D [29/Sep/2003:16:01:13 +0200] StartJob: envp[15]="IPP_PORT=631"
D [29/Sep/2003:16:01:13 +0200] StartJob: statusfds = [ 7 8 ]
D [29/Sep/2003:16:01:13 +0200] StartJob: filterfds[1] = [ 9 -1 ]
D [29/Sep/2003:16:01:13 +0200] StartJob: filter = 
"/usr/lib/cups/filter/texttops"
D [29/Sep/2003:16:01:13 +0200] StartJob: filterfds[0] = [ 10 11 ]
D [29/Sep/2003:16:01:13 +0200] 
start_process("/usr/lib/cups/filter/texttops", 0xbffefb7c, 0xbffeeeec, 
9, 11, 8)
I [29/Sep/2003:16:01:13 +0200] Started filter 
/usr/lib/cups/filter/texttops (PID 27541) for job 16.
D [29/Sep/2003:16:01:13 +0200] StartJob: filter = 
"/usr/lib/cups/filter/pstops"
D [29/Sep/2003:16:01:13 +0200] StartJob: filterfds[1] = [ 9 12 ]
D [29/Sep/2003:16:01:13 +0200] 
start_process("/usr/lib/cups/filter/pstops", 0xbffefb7c, 0xbffeeeec, 10, 
12, 8)
I [29/Sep/2003:16:01:13 +0200] Started filter 
/usr/lib/cups/filter/pstops (PID 27542) for job 16.
D [29/Sep/2003:16:01:13 +0200] StartJob: filter = 
"/usr/lib/cups/filter/foomatic-rip"
D [29/Sep/2003:16:01:13 +0200] StartJob: filterfds[0] = [ 10 11 ]
D [29/Sep/2003:16:01:13 +0200] 
start_process("/usr/lib/cups/filter/foomatic-rip", 0xbffefb7c, 
0xbffeeeec, 9, 11, 8)
I [29/Sep/2003:16:01:13 +0200] Started filter 
/usr/lib/cups/filter/foomatic-rip (PID 27543) for job 16.
D [29/Sep/2003:16:01:13 +0200] StartJob: backend = 
"/usr/lib/cups/backend/parallel"
D [29/Sep/2003:16:01:13 +0200] StartJob: filterfds[1] = [ -1 9 ]
D [29/Sep/2003:16:01:13 +0200] 
start_process("/usr/lib/cups/backend/parallel", 0xbffefb7c, 0xbffeeeec, 
10, 9, 8)
I [29/Sep/2003:16:01:13 +0200] Started backend 
/usr/lib/cups/backend/parallel (PID 27544) for job 16.
D [29/Sep/2003:16:01:13 +0200] ProcessIPPRequest: 5 status_code=0
D [29/Sep/2003:16:01:13 +0200] [Job 16] Page = 595x842; 10,48 to 585,833
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->num_fonts = 35
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[0] = AvantGarde-Book
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[1] = 
AvantGarde-BookOblique
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[2] = AvantGarde-Demi
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[3] = 
AvantGarde-DemiOblique
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[4] = Bookman-Demi
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[5] = Bookman-DemiItalic
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[6] = Bookman-Light
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[7] = Bookman-LightItalic
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[8] = Courier
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[9] = Courier-Bold
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->num_fonts = 35
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[0] = AvantGarde-Book
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[1] = 
AvantGarde-BookOblique
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[2] = AvantGarde-Demi
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[3] = 
AvantGarde-DemiOblique
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[4] = Bookman-Demi
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[5] = Bookman-DemiItalic
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[6] = Bookman-Light
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[7] = Bookman-LightItalic
D [29/Sep/2003:16:01:13 +0200] [Job 16] ppd->fonts[8] = Courier
D [29/Sep/2003:16:01:13 +0200] [Job 16] Page = 595x842; 10,48 to 585,833
D [29/Sep/2003:16:01:13 +0200] [Job 16] slowcollate=0, slowduplex=0, 
sloworder=0
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%BoundingBox: 0 0 595 842
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%Creator: texttops/CUPS 
v1.1.20rc2
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%CreationDate: Mon Sep 29 
16:01:13 2003
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%Title: motd
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%For: root
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%Pages: (atend)
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%DocumentNeededResources: 
font Courier-Bold
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%+ font Courier
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%DocumentSuppliedResources: 
procset texttops 1.1 0
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%+ font Courier-Bold
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%+ font Courier
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%EndComments
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%BeginProlog
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%BeginResource: font Courier-Bold
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%CreationDate: Wed Dec 22 1999
D [29/Sep/2003:16:01:13 +0200] CloseClient() 5
D [29/Sep/2003:16:01:13 +0200] [Job 16] foomatic-rip version $Revision: 
3.30 $ running...
D [29/Sep/2003:16:01:13 +0200] [Job 16] Parsing PPD file ...
D [29/Sep/2003:16:01:13 +0200] [Job 16] Added option ColorSpace
D [29/Sep/2003:16:01:13 +0200] [Job 16] Added option Resolution
D [29/Sep/2003:16:01:13 +0200] [Job 16] Added option PageSize
D [29/Sep/2003:16:01:13 +0200] [Job 16] Added option PageRegion
D [29/Sep/2003:16:01:13 +0200] [Job 16] Added option Model
D [29/Sep/2003:16:01:13 +0200] [Job 16] Added option PrintoutMode
D [29/Sep/2003:16:01:13 +0200] [Job 16] Added option ImageableArea
D [29/Sep/2003:16:01:13 +0200] [Job 16] Added option PaperDimension
D [29/Sep/2003:16:01:13 +0200] [Job 16] Added option Quality
D [29/Sep/2003:16:01:13 +0200] [Job 16] Added option Font
D [29/Sep/2003:16:01:13 +0200] [Job 16]
D [29/Sep/2003:16:01:13 +0200] [Job 16] Parameter Summary
D [29/Sep/2003:16:01:13 +0200] [Job 16] -----------------
D [29/Sep/2003:16:01:13 +0200] [Job 16]
D [29/Sep/2003:16:01:13 +0200] [Job 16] Spooler: cups
D [29/Sep/2003:16:01:13 +0200] [Job 16] Printer: hp500
D [29/Sep/2003:16:01:13 +0200] [Job 16] PPD file: /etc/cups/ppd/hp500.ppd
D [29/Sep/2003:16:01:13 +0200] [Job 16] Printer model: HP DeskJet 500, 
Foomatic + hpijs (recommended)
D [29/Sep/2003:16:01:13 +0200] [Job 16] Job title: motd
D [29/Sep/2003:16:01:13 +0200] [Job 16] File(s) to be printed:
D [29/Sep/2003:16:01:13 +0200] [Job 16] <STDIN>
D [29/Sep/2003:16:01:13 +0200] [Job 16]
D [29/Sep/2003:16:01:13 +0200] [Job 16] Pondering option 'PageSize=A4'
D [29/Sep/2003:16:01:13 +0200] [Job 16]
D [29/Sep/2003:16:01:13 +0200] [Job 16] 
================================================
D [29/Sep/2003:16:01:13 +0200] [Job 16]
D [29/Sep/2003:16:01:13 +0200] [Job 16] File: <STDIN>
D [29/Sep/2003:16:01:13 +0200] [Job 16]
D [29/Sep/2003:16:01:13 +0200] [Job 16] 
================================================
D [29/Sep/2003:16:01:13 +0200] [Job 16]
D [29/Sep/2003:16:01:13 +0200] [Job 16] Reading PostScript input ...
D [29/Sep/2003:16:01:13 +0200] [Job 16] --> This document is DSC-conforming!
D [29/Sep/2003:16:01:13 +0200] [Job 16]
D [29/Sep/2003:16:01:13 +0200] [Job 16] -----------
D [29/Sep/2003:16:01:13 +0200] [Job 16] Found: %%BeginProlog
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%EndResource
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%BeginResource: font Courier
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%CreationDate: Wed Dec 22 1999
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%EndResource
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%BeginResource: procset 
texttops 1.1 0
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%EndResource
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%EndProlog
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%Page: 1 1
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%Page: 1 1
D [29/Sep/2003:16:01:13 +0200] [Job 16] pw = 575.6, pl = 784.8
D [29/Sep/2003:16:01:13 +0200] [Job 16] PageLeft = 9.7, PageRight = 585.3
D [29/Sep/2003:16:01:13 +0200] [Job 16] PageTop = 833.0, PageBottom = 48.2
D [29/Sep/2003:16:01:13 +0200] [Job 16] PageWidth = 595.0, PageLength = 
842.0
D [29/Sep/2003:16:01:13 +0200] [Job 16] 0 %%Trailer
D [29/Sep/2003:16:01:13 +0200] [Job 16] Saw Trailer!
D [29/Sep/2003:16:01:13 +0200] [Job 16] Saw EOF!
D [29/Sep/2003:16:01:13 +0200] [Job 16] Found: %%EndProlog
D [29/Sep/2003:16:01:13 +0200] [Job 16]
D [29/Sep/2003:16:01:13 +0200] [Job 16] -----------
D [29/Sep/2003:16:01:13 +0200] [Job 16] Found: %%BeginSetup
D [29/Sep/2003:16:01:13 +0200] [Job 16] Inserting PostScript code for 
CUPS' page accounting
D [29/Sep/2003:16:01:13 +0200] [Job 16] Found: %%BeginFeature: 
*PrintoutMode Normal
D [29/Sep/2003:16:01:13 +0200] [Job 16] Option: PrintoutMode=Normal --> 
Setting option
D [29/Sep/2003:16:01:13 +0200] [Job 16] Found: %% 
FoomaticRIPOptionSetting: PrintoutMode=Normal
D [29/Sep/2003:16:01:13 +0200] [Job 16] Option: PrintoutMode=Normal --> 
Setting option
D [29/Sep/2003:16:01:13 +0200] [Job 16] Found: %%BeginFeature: *Quality 
FromPrintoutMode
D [29/Sep/2003:16:01:13 +0200] [Job 16] Option: Quality=FromPrintoutMode 
--> Setting option
D [29/Sep/2003:16:01:13 +0200] [Job 16] Found: %% 
FoomaticRIPOptionSetting: Quality=@PrintoutMode
D [29/Sep/2003:16:01:13 +0200] [Job 16] Option: Quality=FromPrintoutMode 
--> Setting option
D [29/Sep/2003:16:01:13 +0200] [Job 16] Found: %%BeginFeature: *PageSize A4
D [29/Sep/2003:16:01:13 +0200] [Job 16] Option: PageSize=A4 --> Setting 
option
D [29/Sep/2003:16:01:13 +0200] [Job 16] Found: %% 
FoomaticRIPOptionSetting: PageSize=A4
D [29/Sep/2003:16:01:13 +0200] [Job 16] Option: PageSize=A4 --> Setting 
option
D [29/Sep/2003:16:01:13 +0200] [Job 16] Found: %%EndSetup
D [29/Sep/2003:16:01:13 +0200] [Job 16]
D [29/Sep/2003:16:01:13 +0200] [Job 16] -----------
D [29/Sep/2003:16:01:13 +0200] [Job 16] New page:  1 1
D [29/Sep/2003:16:01:13 +0200] [Job 16] Inserting option code into 
"PageSetup" section.
D [29/Sep/2003:16:01:13 +0200] [Job 16] No page header or page header 
not DSC-conforming
D [29/Sep/2003:16:01:13 +0200] [Job 16] Flushing FIFO.
D [29/Sep/2003:16:01:13 +0200] [Job 16]
D [29/Sep/2003:16:01:13 +0200] [Job 16] Starting renderer
D [29/Sep/2003:16:01:13 +0200] [Job 16] renderer PID pid4=27546
D [29/Sep/2003:16:01:13 +0200] [Job 16] JCL: <job data>
D [29/Sep/2003:16:01:13 +0200] [Job 16]
D [29/Sep/2003:16:01:13 +0200] [Job 16] renderer command: gs -q -dBATCH 
-dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs 
-sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="DESKJET 540" 
-dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -r300 
-sIjsParams=Quality:Quality=0,Quality:ColorMode=0,Quality:MediaType=0,Quality:PenSet=0 
-dIjsUseOutputFD -sOutputFile=- -
D [29/Sep/2003:16:01:13 +0200] [Job 16] foomatic-gswrapper: gs '-dBATCH' 
'-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=ijs' 
'-sIjsServer=hpijs' '-sDeviceManufacturer=HEWLETT-PACKARD' 
'-sDeviceModel=DESKJET 540' '-dDEVICEWIDTHPOINTS=595' 
'-dDEVICEHEIGHTPOINTS=842' '-r300' 
'-sIjsParams=Quality:Quality=0,Quality:ColorMode=0,Quality:MediaType=0,Quality:PenSet=0' 
'-dIjsUseOutputFD' '-sOutputFile=| cat >&3' '/dev/fd/0' 3>&1 1>&2
D [29/Sep/2003:16:01:14 +0200] [Job 16]
D [29/Sep/2003:16:01:14 +0200] [Job 16] Closing renderer
D [29/Sep/2003:16:01:14 +0200] [Job 16] KID3 exited with status 0
D [29/Sep/2003:16:01:15 +0200] [Job 16] tail process done writing data 
to STDOUT
D [29/Sep/2003:16:01:15 +0200] [Job 16] KID4 finished
D [29/Sep/2003:16:01:15 +0200] [Job 16] KID3 finished
D [29/Sep/2003:16:01:15 +0200] [Job 16] KID4 exited with status 0
D [29/Sep/2003:16:01:15 +0200] [Job 16] Renderer exit stat: 0
D [29/Sep/2003:16:01:15 +0200] [Job 16] Renderer process finished
D [29/Sep/2003:16:01:15 +0200] [Job 16]
D [29/Sep/2003:16:01:15 +0200] [Job 16] Closing foomatic-rip.
D [29/Sep/2003:16:01:15 +0200] UpdateJob: job 16, file 0 is complete.
D [29/Sep/2003:16:01:15 +0200] CancelJob: id = 16
D [29/Sep/2003:16:01:15 +0200] StopJob: id = 16, force = 0
D [29/Sep/2003:16:01:15 +0200] StopJob: printer state is 3

There is no obvious error to me, but...

Kay