I [24/Jul/2007:03:13:24 +1000] Job 62 queued on "vellum" by "gid".
E [24/Jul/2007:03:13:24 +1000] [Job 62] /rangecheck in .installpagedevice
D [24/Jul/2007:03:17:48 +1000] [Job 62] argv[0]="vellum"
D [24/Jul/2007:03:17:48 +1000] [Job 62] argv[1]="62"
D [24/Jul/2007:03:17:48 +1000] [Job 62] argv[2]="gid"
D [24/Jul/2007:03:17:48 +1000] [Job 62] argv[3]="Test Page"
D [24/Jul/2007:03:17:48 +1000] [Job 62] argv[4]="1"
D [24/Jul/2007:03:17:48 +1000] [Job 62] argv[5]="job-uuid=urn:uuid:a80f7136-9f55-3a9c-7f38-fa1fa6aa5789"
D [24/Jul/2007:03:17:48 +1000] [Job 62] argv[6]="/var/spool/cups/d00062-001"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[2]="CUPS_DOCROOT=/usr/share/cups/html"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[5]="CUPS_SERVERBIN=/usr/libexec/cups"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[8]="PATH=/usr/libexec/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[9]="SERVER_ADMIN=root@monkey.localnet"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[10]="SOFTWARE=CUPS/1.2.10"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[12]="USER=root"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[13]="CUPS_SERVER=/var/run/cups/cups.sock"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[14]="CUPS_ENCRYPTION=IfRequested"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[15]="IPP_PORT=631"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[16]="CHARSET=utf-8"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[17]="LANG=en_AU"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[18]="PPD=/etc/cups/ppd/vellum.ppd"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[19]="RIP_MAX_CACHE=8m"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[20]="CONTENT_TYPE=application/postscript"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[21]="DEVICE_URI=hp:/net/officejet_7100_series?ip=192.168.1.1"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[22]="PRINTER=vellum"
D [24/Jul/2007:03:17:48 +1000] [Job 62] envp[23]="FINAL_CONTENT_TYPE=printer/vellum"
I [24/Jul/2007:03:17:48 +1000] Job 62 was restarted by "root".
D [24/Jul/2007:03:17:48 +1000] [Job 62] Page = 612x792; 18,36 to 594,783
D [24/Jul/2007:03:17:48 +1000] [Job 62] slow_collate=0, slow_duplex=0, slow_order=0
D [24/Jul/2007:03:17:48 +1000] [Job 62] Before copy_comments - %!PS-Adobe-3.0 EPSF-3.0 
D [24/Jul/2007:03:17:48 +1000] [Job 62] %!PS-Adobe-3.0 EPSF-3.0
D [24/Jul/2007:03:17:48 +1000] [Job 62] %%BoundingBox: 0 0 595 842
D [24/Jul/2007:03:17:48 +1000] [Job 62] %%Pages: 0
D [24/Jul/2007:03:17:48 +1000] [Job 62] %%Creator: Sun Microsystems, Inc.
D [24/Jul/2007:03:17:48 +1000] [Job 62] %%Title: none
D [24/Jul/2007:03:17:48 +1000] [Job 62] %%CreationDate: none
D [24/Jul/2007:03:17:48 +1000] [Job 62] %%LanguageLevel: 2
D [24/Jul/2007:03:17:48 +1000] [Job 62] %%EndComments
D [24/Jul/2007:03:17:48 +1000] [Job 62] Before copy_prolog - %%BeginProlog
D [24/Jul/2007:03:17:48 +1000] [Job 62] Before copy_setup - %%BeginSetup
D [24/Jul/2007:03:17:48 +1000] [Job 62] Before page loop - %%Page: 1 1
D [24/Jul/2007:03:17:48 +1000] [Job 62] Copying page 1...
D [24/Jul/2007:03:17:48 +1000] [Job 62] pagew = 576.0, pagel = 747.0
D [24/Jul/2007:03:17:48 +1000] [Job 62] bboxw = 612, bboxl = 792
D [24/Jul/2007:03:17:48 +1000] [Job 62] PageLeft = 18.0, PageRight = 594.0
D [24/Jul/2007:03:17:48 +1000] [Job 62] PageTop = 783.0, PageBottom = 36.0
D [24/Jul/2007:03:17:48 +1000] [Job 62] PageWidth = 612.0, PageLength = 792.0
D [24/Jul/2007:03:17:49 +1000] [Job 62] foomatic-rip version $Revision$ running...
D [24/Jul/2007:03:17:49 +1000] [Job 62] Parsing PPD file ...
D [24/Jul/2007:03:17:49 +1000] [Job 62] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
D [24/Jul/2007:03:17:49 +1000] [Job 62] Added option ColorSpace
D [24/Jul/2007:03:17:49 +1000] [Job 62] Added option Resolution
D [24/Jul/2007:03:17:49 +1000] [Job 62] Added option PageSize
D [24/Jul/2007:03:17:49 +1000] [Job 62] Added option PageRegion
D [24/Jul/2007:03:17:49 +1000] [Job 62] Added option Model
D [24/Jul/2007:03:17:49 +1000] [Job 62] Added option PrintoutMode
D [24/Jul/2007:03:17:49 +1000] [Job 62] Added option InputSlot
D [24/Jul/2007:03:17:49 +1000] [Job 62] Added option ImageableArea
D [24/Jul/2007:03:17:49 +1000] [Job 62] Added option PaperDimension
D [24/Jul/2007:03:17:49 +1000] [Job 62] Added option Duplex
D [24/Jul/2007:03:17:49 +1000] [Job 62] Added option Quality
D [24/Jul/2007:03:17:49 +1000] [Job 62] Added option Font
D [24/Jul/2007:03:17:49 +1000] [Job 62] 
D [24/Jul/2007:03:17:49 +1000] [Job 62] Parameter Summary
D [24/Jul/2007:03:17:49 +1000] [Job 62] -----------------
D [24/Jul/2007:03:17:49 +1000] [Job 62] 
D [24/Jul/2007:03:17:49 +1000] [Job 62] Spooler: cups
D [24/Jul/2007:03:17:49 +1000] [Job 62] Printer: vellum
D [24/Jul/2007:03:17:49 +1000] [Job 62] Shell: /bin/sh
D [24/Jul/2007:03:17:49 +1000] [Job 62] PPD file: /etc/cups/ppd/vellum.ppd
D [24/Jul/2007:03:17:49 +1000] [Job 62] ATTR file: 
D [24/Jul/2007:03:17:49 +1000] [Job 62] Printer model: HP OfficeJet 7140 Foomatic/hpijs (recommended)
D [24/Jul/2007:03:17:49 +1000] [Job 62] Job title: Test Page
D [24/Jul/2007:03:17:49 +1000] [Job 62] File(s) to be printed: 
D [24/Jul/2007:03:17:49 +1000] [Job 62] <STDIN>
D [24/Jul/2007:03:17:49 +1000] [Job 62] 
D [24/Jul/2007:03:17:49 +1000] [Job 62] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [24/Jul/2007:03:17:49 +1000] [Job 62] Pondering option 'job-uuid=urn:uuid:a80f7136-9f55-3a9c-7f38-fa1fa6aa5789'
D [24/Jul/2007:03:17:49 +1000] [Job 62] Unknown option job-uuid=urn:uuid:a80f7136-9f55-3a9c-7f38-fa1fa6aa5789.
D [24/Jul/2007:03:17:49 +1000] [Job 62] 
D [24/Jul/2007:03:17:49 +1000] [Job 62] ================================================
D [24/Jul/2007:03:17:49 +1000] [Job 62] 
D [24/Jul/2007:03:17:49 +1000] [Job 62] File: <STDIN>
D [24/Jul/2007:03:17:49 +1000] [Job 62] 
D [24/Jul/2007:03:17:49 +1000] [Job 62] ================================================
D [24/Jul/2007:03:17:49 +1000] [Job 62] 
D [24/Jul/2007:03:17:49 +1000] [Job 62] Reading PostScript input ...
D [24/Jul/2007:03:17:49 +1000] [Job 62] --> This document is DSC-conforming!
D [24/Jul/2007:03:17:49 +1000] [Job 62] 
D [24/Jul/2007:03:17:49 +1000] [Job 62] -----------
D [24/Jul/2007:03:17:49 +1000] [Job 62] Found: %%BeginProlog
D [24/Jul/2007:03:17:49 +1000] [Job 62] Found: %%EndProlog
D [24/Jul/2007:03:17:49 +1000] [Job 62] 
D [24/Jul/2007:03:17:49 +1000] [Job 62] -----------
D [24/Jul/2007:03:17:49 +1000] [Job 62] Found: %%BeginSetup
D [24/Jul/2007:03:17:49 +1000] [Job 62] Found: %%BeginFeature: *PrintoutMode Normal
D [24/Jul/2007:03:17:49 +1000] [Job 62] Option: PrintoutMode=Normal --> Setting option
D [24/Jul/2007:03:17:49 +1000] [Job 62] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal
D [24/Jul/2007:03:17:49 +1000] [Job 62] Option: PrintoutMode=Normal --> Setting option
D [24/Jul/2007:03:17:49 +1000] [Job 62] Found: %%BeginFeature: *InputSlot Default
D [24/Jul/2007:03:17:49 +1000] [Job 62] Option: InputSlot=Default --> Setting option
D [24/Jul/2007:03:17:49 +1000] [Job 62] Found: %% FoomaticRIPOptionSetting: InputSlot=Default
D [24/Jul/2007:03:17:49 +1000] [Job 62] Option: InputSlot=Default --> Setting option
D [24/Jul/2007:03:17:49 +1000] [Job 62] Found: %%BeginFeature: *Quality FromPrintoutMode
D [24/Jul/2007:03:17:49 +1000] [Job 62] Option: Quality=FromPrintoutMode --> Setting option
D [24/Jul/2007:03:17:49 +1000] [Job 62] Found: %% FoomaticRIPOptionSetting: Quality=@PrintoutMode
D [24/Jul/2007:03:17:49 +1000] [Job 62] Option: Quality=FromPrintoutMode --> Setting option
D [24/Jul/2007:03:17:49 +1000] [Job 62] Found: %%BeginFeature: *PageRegion Letter
D [24/Jul/2007:03:17:49 +1000] [Job 62] Option: PageRegion=Letter --> Option will be set by PostScript interpreter
D [24/Jul/2007:03:17:49 +1000] [Job 62] Found: %% FoomaticRIPOptionSetting: PageSize=Letter
D [24/Jul/2007:03:17:49 +1000] [Job 62] Option: PageSize=Letter --> Setting option
D [24/Jul/2007:03:17:49 +1000] [Job 62] Found: %%BeginFeature: *Duplex None
D [24/Jul/2007:03:17:49 +1000] [Job 62] Option: Duplex=None --> Setting option
D [24/Jul/2007:03:17:49 +1000] [Job 62] Found: %% FoomaticRIPOptionSetting: Duplex=None
D [24/Jul/2007:03:17:49 +1000] [Job 62] Option: Duplex=None --> Setting option
D [24/Jul/2007:03:17:49 +1000] [Job 62] Found: %%EndSetup
D [24/Jul/2007:03:17:49 +1000] [Job 62] Inserting PostScript code for CUPS' page accounting
D [24/Jul/2007:03:17:49 +1000] [Job 62] 
D [24/Jul/2007:03:17:49 +1000] [Job 62] -----------
D [24/Jul/2007:03:17:49 +1000] [Job 62] New page:  1 1
D [24/Jul/2007:03:17:49 +1000] [Job 62] Inserting option code into "PageSetup" section.
D [24/Jul/2007:03:17:49 +1000] [Job 62] 
D [24/Jul/2007:03:17:49 +1000] [Job 62] Found: %%BeginPageSetup
D [24/Jul/2007:03:17:49 +1000] [Job 62] Found: %%EndPageSetup
D [24/Jul/2007:03:17:49 +1000] [Job 62] End of page header
D [24/Jul/2007:03:17:49 +1000] [Job 62] Stopping search for page header options
D [24/Jul/2007:03:17:49 +1000] [Job 62] Found:
D [24/Jul/2007:03:17:49 +1000] [Job 62] 272.61532 887.70906 l  286.04014 909.21911 l  300.83795 929.43245 l 
D [24/Jul/2007:03:17:49 +1000] [Job 62] --> Output goes directly to the renderer now.
D [24/Jul/2007:03:17:49 +1000] [Job 62] 
D [24/Jul/2007:03:17:49 +1000] [Job 62] 
D [24/Jul/2007:03:17:49 +1000] [Job 62] Starting renderer
D [24/Jul/2007:03:17:49 +1000] [Job 62] JCL: <job data> 
D [24/Jul/2007:03:17:49 +1000] [Job 62] 
D [24/Jul/2007:03:17:49 +1000] [Job 62] renderer PID kid4=5776
D [24/Jul/2007:03:17:49 +1000] [Job 62] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="DESKJET 990" -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -dDuplex=false -r300 -sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=2,PS:MediaPosition=7 -dIjsUseOutputFD -sOutputFile=- -
D [24/Jul/2007:03:17:49 +1000] [Job 62] foomatic-gswrapper: gs '-dBATCH' '-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=ijs' '-sIjsServer=hpijs' '-sDeviceManufacturer=HEWLETT-PACKARD' '-sDeviceModel=DESKJET 990' '-dDEVICEWIDTHPOINTS=612' '-dDEVICEHEIGHTPOINTS=792' '-dDuplex=false' '-r300' '-sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=2,PS:MediaPosition=7' '-dIjsUseOutputFD' '-sOutputFile=/dev/fd/3' '/dev/fd/0' 3>&1 1>&2
D [24/Jul/2007:03:17:49 +1000] [Job 62] Wrote 1 pages...
D [24/Jul/2007:03:17:49 +1000] [Job 62] 
D [24/Jul/2007:03:17:49 +1000] [Job 62] Closing renderer
E [24/Jul/2007:03:17:49 +1000] [Job 62] /rangecheck in .installpagedevice
D [24/Jul/2007:03:17:49 +1000] [Job 62] Operand stack:
D [24/Jul/2007:03:17:49 +1000] [Job 62] --nostringval--   --dict:54/56(ro)(L)--   --nostringval--
D [24/Jul/2007:03:17:49 +1000] [Job 62] Execution stack:
D [24/Jul/2007:03:17:49 +1000] [Job 62] %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   1   3   %oparray_pop   .runexec2   --nostringval--   --nostringval--   --nostringval--   2   %stopped_push   --nostringval--   1   3   %oparray_pop   --nostringval--   1   3   %oparray_pop   --nostringval--   --nostringval--
D [24/Jul/2007:03:17:49 +1000] [Job 62] Dictionary stack:
D [24/Jul/2007:03:17:49 +1000] [Job 62] --dict:1130/1686(ro)(G)--   --dict:1/20(G)--   --dict:104/200(L)--
D [24/Jul/2007:03:17:49 +1000] [Job 62] Current allocation mode is local
D [24/Jul/2007:03:17:49 +1000] [Job 62] Last OS error: 2
D [24/Jul/2007:03:17:49 +1000] [Job 62] ESP Ghostscript 815.03: Unrecoverable error, exit code 1
D [24/Jul/2007:03:17:49 +1000] [Job 62] renderer return value: 1
D [24/Jul/2007:03:17:49 +1000] [Job 62] renderer received signal: 1
D [24/Jul/2007:03:17:49 +1000] [Job 62] Process dying with "Possible error on renderer command line or PostScript error. Check options.", exit stat: 3
D [24/Jul/2007:03:17:49 +1000] [Job 62] error: Illegal seek (29)
D [24/Jul/2007:03:17:49 +1000] [Job 62] Possible error on renderer command line or PostScript error. Check options.
D [24/Jul/2007:03:17:49 +1000] [Job 62] tail process done writing data to STDOUT
D [24/Jul/2007:03:17:49 +1000] [Job 62] KID4 finished
D [24/Jul/2007:03:17:49 +1000] [Job 62] KID3 exited with status 3
D [24/Jul/2007:03:17:49 +1000] [Job 62] Renderer exit stat: 3
D [24/Jul/2007:03:17:49 +1000] [Job 62] Renderer process finished
D [24/Jul/2007:03:17:49 +1000] [Job 62] Killing process 5775 (KID3)
D [24/Jul/2007:03:17:49 +1000] [Job 62] Process dying with "Error closing renderer", exit stat: 3
D [24/Jul/2007:03:17:49 +1000] [Job 62] error: Illegal seek (29)
D [24/Jul/2007:03:17:49 +1000] [Job 62] Error closing renderer
D [24/Jul/2007:03:17:52 +1000] [Job 62] File 0 is complete.