Attachment 125766 Details for Bug 183288 – CUPS debug log, using hplip 2.7.6

CUPS debug log, using hplip 2.7.6

2.7.6_debug_log (text/plain), 13.05 KB, created by Gerard Neil on 2007-07-23 17:49:04 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Gerard Neil

Created: 2007-07-23 17:49:04 UTC

Size: 13.05 KB

patch

obsolete

>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.

Actions: View

Attachments on bug 183288: 123128 | 125766