[cups.bugs] [CRIT] STR #2511: Cups doesn't print with HP LasterJet 1012 on FreeBSD-6.2 amd64

Thu Sep 13 13:40:39 PDT 2007

DO NOT REPLY TO THIS MESSAGE.  INSTEAD, POST ANY RESPONSES TO THE LINK BELOW.

[STR New]

I am a freebsd-6.2 user. I installed latest (from ports) version of CUPS
(1.2.12) and hplip package. When configuring printer I specified PPD-file
/usr/local/share/ppd/HP/HP-LaserJet_1012-hpijs.ppd.gz (exactly my model).
Even a test page can't be printed: printer is stopped everytime I try to
print enything. I switeched loglevel to debug (as I was told in logfile)
and that's what it says:

D [14/Sep/2007:00:11:06 +0400] cupsdAcceptClient: 9 from localhost
(Domain)
D [14/Sep/2007:00:11:06 +0400] cupsdReadClient: 9 POST / HTTP/1.1
D [14/Sep/2007:00:11:06 +0400] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2007:00:11:06 +0400] CUPS-Get-Printers
D [14/Sep/2007:00:11:06 +0400] cupsdProcessIPPRequest: 9 status_code=0
(successful-ok)
D [14/Sep/2007:00:11:06 +0400] cupsdReadClient: 9 POST / HTTP/1.1
D [14/Sep/2007:00:11:06 +0400] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2007:00:11:06 +0400] CUPS-Get-Classes
D [14/Sep/2007:00:11:06 +0400] cupsdProcessIPPRequest: 9 status_code=0
(successful-ok)
D [14/Sep/2007:00:11:06 +0400] cupsdReadClient: 9 POST / HTTP/1.1
D [14/Sep/2007:00:11:06 +0400] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2007:00:11:06 +0400] CUPS-Get-Default
D [14/Sep/2007:00:11:06 +0400] cupsdProcessIPPRequest: 9 status_code=0
(successful-ok)
D [14/Sep/2007:00:11:06 +0400] cupsdCloseClient: 9
D [14/Sep/2007:00:11:06 +0400] cupsdAcceptClient: 9 from localhost
(Domain)
D [14/Sep/2007:00:11:06 +0400] cupsdReadClient: 9 POST
/printers/HPLaserJet1012 HTTP/1.1
D [14/Sep/2007:00:11:06 +0400] cupsdAuthorize: No authentication data
provided.
D [14/Sep/2007:00:11:07 +0400] Print-Job
ipp://localhost/printers/HPLaserJet1012
D [14/Sep/2007:00:11:07 +0400] print_job: auto-typing file...
D [14/Sep/2007:00:11:07 +0400] print_job: request file type is
application/postscript.
D [14/Sep/2007:00:11:07 +0400] add_job: requesting-user-name="avl"
I [14/Sep/2007:00:11:07 +0400] Adding start banner page "none" to job 3.
D [14/Sep/2007:00:11:07 +0400] Discarding unused job-created event...
I [14/Sep/2007:00:11:07 +0400] Adding end banner page "none" to job 3.
I [14/Sep/2007:00:11:07 +0400] Job 3 queued on "HPLaserJet1012" by "avl".
D [14/Sep/2007:00:11:07 +0400] Job 3 hold_until = 0
D [14/Sep/2007:00:11:07 +0400] Discarding unused printer-state-changed
event...
D [14/Sep/2007:00:11:07 +0400] job-sheets=none,none
D [14/Sep/2007:00:11:07 +0400] banner_page = 0
D [14/Sep/2007:00:11:07 +0400] [Job 3] argv[0]="HPLaserJet1012"
D [14/Sep/2007:00:11:07 +0400] [Job 3] argv[1]="3"
D [14/Sep/2007:00:11:07 +0400] [Job 3] argv[2]="avl"
D [14/Sep/2007:00:11:07 +0400] [Job 3] argv[3]="lena.ps"
D [14/Sep/2007:00:11:07 +0400] [Job 3] argv[4]="1"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
argv[5]="job-uuid=urn:uuid:902be999-23b1-34ab-470c-65469383dea6"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
argv[6]="/var/spool/cups/d00003-001"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
envp[1]="CUPS_DATADIR=/usr/local/share/cups"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
envp[2]="CUPS_DOCROOT=/usr/local/share/doc/cups"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
envp[3]="CUPS_FONTPATH=/usr/local/share/cups/fonts"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
envp[5]="CUPS_SERVERBIN=/usr/local/libexec/cups"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
envp[6]="CUPS_SERVERROOT=/usr/local/etc/cups"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
envp[7]="CUPS_STATEDIR=/var/run/cups"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
envp[8]="PATH=/usr/local/libexec/cups/filter:/usr/local/bin:/usr/local/sbin:/bin:/usr/bin"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
envp[9]="SERVER_ADMIN=root at samanka.umc8.mai.ru"
D [14/Sep/2007:00:11:07 +0400] [Job 3] envp[10]="SOFTWARE=CUPS/1.2.12"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
envp[11]="TMPDIR=/var/spool/cups/tmp"
D [14/Sep/2007:00:11:07 +0400] [Job 3] envp[12]="USER=root"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
envp[13]="CUPS_SERVER=/var/run/cups.sock"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
envp[14]="CUPS_ENCRYPTION=IfRequested"
D [14/Sep/2007:00:11:07 +0400] [Job 3] envp[15]="IPP_PORT=631"
D [14/Sep/2007:00:11:07 +0400] [Job 3] envp[16]="CHARSET=utf-8"
D [14/Sep/2007:00:11:07 +0400] [Job 3] envp[17]="LANG=en_GB"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
envp[18]="PPD=/usr/local/etc/cups/ppd/HPLaserJet1012.ppd"
D [14/Sep/2007:00:11:07 +0400] [Job 3] envp[19]="RIP_MAX_CACHE=8m"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
envp[20]="CONTENT_TYPE=application/postscript"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
envp[21]="DEVICE_URI=usb:/dev/ulpt0"
D [14/Sep/2007:00:11:07 +0400] [Job 3] envp[22]="PRINTER=HPLaserJet1012"
D [14/Sep/2007:00:11:07 +0400] [Job 3]
envp[23]="FINAL_CONTENT_TYPE=printer/HPLaserJet1012"
I [14/Sep/2007:00:11:07 +0400] Started filter
/usr/local/libexec/cups/filter/pstops (PID 99620) for job 3.
I [14/Sep/2007:00:11:07 +0400] Started filter
/usr/local/libexec/cups/filter/foomatic-rip (PID 99621) for job 3.
I [14/Sep/2007:00:11:07 +0400] Started backend
/usr/local/libexec/cups/backend/usb (PID 99622) for job 3.
D [14/Sep/2007:00:11:07 +0400] Discarding unused job-state event...
D [14/Sep/2007:00:11:07 +0400] cupsdProcessIPPRequest: 9 status_code=0
(successful-ok)
E [14/Sep/2007:00:11:07 +0400] PID 99622
(/usr/local/libexec/cups/backend/usb) stopped with status 1!
D [14/Sep/2007:00:11:07 +0400] [Job 3] perl: warning: Setting locale
failed.
D [14/Sep/2007:00:11:07 +0400] [Job 3] perl: warning: Please check that
your locale settings:
D [14/Sep/2007:00:11:07 +0400] [Job 3] LC_ALL = (unset),
D [14/Sep/2007:00:11:07 +0400] [Job 3] LANG = "en_GB"
D [14/Sep/2007:00:11:07 +0400] [Job 3] are supported and installed on your
system.
D [14/Sep/2007:00:11:07 +0400] [Job 3] perl: warning: Falling back to the
standard locale ("C").
D [14/Sep/2007:00:11:07 +0400] [Job 3] Page = 595x842; 18,14 to 577,828
D [14/Sep/2007:00:11:07 +0400] [Job 3] slow_collate=0, slow_duplex=0,
slow_order=0
D [14/Sep/2007:00:11:07 +0400] [Job 3] Before copy_comments -
%!PS-Adobe-3.0
D [14/Sep/2007:00:11:07 +0400] [Job 3] %!PS-Adobe-3.0
D [14/Sep/2007:00:11:07 +0400] [Job 3] %%Creator: cairo 1.4.10
(http://cairographics.org)
D [14/Sep/2007:00:11:07 +0400] [Job 3] %%CreationDate: Thu Sep 13 10:57:52
2007
D [14/Sep/2007:00:11:07 +0400] [Job 3] %%Pages: 1
D [14/Sep/2007:00:11:07 +0400] [Job 3] %%BoundingBox: 0 0 420 596
D [14/Sep/2007:00:11:07 +0400] [Job 3] %%DocumentData: Clean7Bit
D [14/Sep/2007:00:11:07 +0400] [Job 3] %%LanguageLevel: 2
D [14/Sep/2007:00:11:07 +0400] [Job 3] %%EndComments
D [14/Sep/2007:00:11:07 +0400] [Job 3] Before copy_prolog - %%BeginProlog
D [14/Sep/2007:00:11:07 +0400] [Job 3] Before copy_setup - %
_cairo_ps_surface_emit_font_subsets
D [14/Sep/2007:00:11:07 +0400] [Job 3] Before page loop - %%Page: 1 1
D [14/Sep/2007:00:11:07 +0400] [Job 3] Copying page 1...
D [14/Sep/2007:00:11:07 +0400] [Job 3] pagew = 559.0, pagel = 813.2
D [14/Sep/2007:00:11:07 +0400] [Job 3] bboxw = 595, bboxl = 842
D [14/Sep/2007:00:11:07 +0400] [Job 3] PageLeft = 18.0, PageRight = 577.0
D [14/Sep/2007:00:11:07 +0400] [Job 3] PageTop = 827.6, PageBottom = 14.4
D [14/Sep/2007:00:11:07 +0400] [Job 3] PageWidth = 595.0, PageLength =
842.0
E [14/Sep/2007:00:11:07 +0400] [Job 3] Unable to open USB device
"usb:/dev/ulpt0": Permission denied
D [14/Sep/2007:00:11:07 +0400] Discarding unused printer-state-changed
event...
D [14/Sep/2007:00:11:07 +0400] cupsdCloseClient: 9
D [14/Sep/2007:00:11:07 +0400] [Job 3] foomatic-rip version $Revision$
running...
D [14/Sep/2007:00:11:07 +0400] [Job 3] Parsing PPD file ...
D [14/Sep/2007:00:11:07 +0400] [Job 3] *cupsFilter:
"application/vnd.cups-postscript 0 foomatic-rip"
D [14/Sep/2007:00:11:07 +0400] [Job 3] Added option ColorSpace
D [14/Sep/2007:00:11:07 +0400] [Job 3] Added option Resolution
D [14/Sep/2007:00:11:07 +0400] [Job 3] Added option PageSize
D [14/Sep/2007:00:11:07 +0400] [Job 3] Added option PageRegion
D [14/Sep/2007:00:11:07 +0400] [Job 3] Added option Model
D [14/Sep/2007:00:11:07 +0400] [Job 3] Added option PrintoutMode
D [14/Sep/2007:00:11:07 +0400] [Job 3] Added option InputSlot
D [14/Sep/2007:00:11:07 +0400] [Job 3] Added option ImageableArea
D [14/Sep/2007:00:11:07 +0400] [Job 3] Added option PaperDimension
D [14/Sep/2007:00:11:07 +0400] [Job 3] Added option Duplex
D [14/Sep/2007:00:11:07 +0400] [Job 3] Added option Quality
D [14/Sep/2007:00:11:07 +0400] [Job 3] Added option Font
D [14/Sep/2007:00:11:07 +0400] [Job 3] 
D [14/Sep/2007:00:11:07 +0400] [Job 3] Parameter Summary
D [14/Sep/2007:00:11:07 +0400] [Job 3] -----------------
D [14/Sep/2007:00:11:07 +0400] [Job 3] 
D [14/Sep/2007:00:11:07 +0400] [Job 3] Spooler: cups
D [14/Sep/2007:00:11:07 +0400] [Job 3] Printer: HPLaserJet1012
D [14/Sep/2007:00:11:07 +0400] [Job 3] Shell: 
D [14/Sep/2007:00:11:07 +0400] [Job 3] PPD file:
/usr/local/etc/cups/ppd/HPLaserJet1012.ppd
D [14/Sep/2007:00:11:07 +0400] [Job 3] ATTR file: 
D [14/Sep/2007:00:11:07 +0400] [Job 3] Printer model: HP LaserJet 1012
Foomatic/hpijs (recommended)
D [14/Sep/2007:00:11:07 +0400] [Job 3] Job title: lena.ps
D [14/Sep/2007:00:11:07 +0400] [Job 3] File(s) to be printed: 
D [14/Sep/2007:00:11:07 +0400] [Job 3] <STDIN>
D [14/Sep/2007:00:11:07 +0400] [Job 3] 
D [14/Sep/2007:00:11:07 +0400] [Job 3] GhostScript extra search path
('GS_LIB'): /usr/local/share/cups/fonts
D [14/Sep/2007:00:11:07 +0400] [Job 3] Pondering option
'job-uuid=urn:uuid:902be999-23b1-34ab-470c-65469383dea6'
D [14/Sep/2007:00:11:07 +0400] [Job 3] Unknown option
job-uuid=urn:uuid:902be999-23b1-34ab-470c-65469383dea6.
D [14/Sep/2007:00:11:07 +0400] [Job 3] 
D [14/Sep/2007:00:11:07 +0400] [Job 3]
================================================
D [14/Sep/2007:00:11:07 +0400] [Job 3] 
D [14/Sep/2007:00:11:07 +0400] [Job 3] File: <STDIN>
D [14/Sep/2007:00:11:07 +0400] [Job 3] 
D [14/Sep/2007:00:11:07 +0400] [Job 3]
================================================
D [14/Sep/2007:00:11:07 +0400] [Job 3] 
D [14/Sep/2007:00:11:07 +0400] [Job 3] Reading PostScript input ...
D [14/Sep/2007:00:11:07 +0400] [Job 3] --> This document is
DSC-conforming!
D [14/Sep/2007:00:11:07 +0400] [Job 3] 
D [14/Sep/2007:00:11:07 +0400] [Job 3] -----------
D [14/Sep/2007:00:11:07 +0400] [Job 3] Found: %%BeginProlog
D [14/Sep/2007:00:11:07 +0400] [Job 3] Found: %%EndProlog
D [14/Sep/2007:00:11:07 +0400] [Job 3] 
D [14/Sep/2007:00:11:07 +0400] [Job 3] -----------
D [14/Sep/2007:00:11:07 +0400] [Job 3] Found: %%BeginSetup
D [14/Sep/2007:00:11:07 +0400] [Job 3] Found: %%BeginFeature:
*PrintoutMode Normal
D [14/Sep/2007:00:11:07 +0400] [Job 3] Option: PrintoutMode=Normal -->
Setting option
D [14/Sep/2007:00:11:07 +0400] [Job 3] Found: %% FoomaticRIPOptionSetting:
PrintoutMode=Normal
D [14/Sep/2007:00:11:07 +0400] [Job 3] Option: PrintoutMode=Normal -->
Setting option
D [14/Sep/2007:00:11:07 +0400] [Job 3] Found: %%BeginFeature: *InputSlot
Default
D [14/Sep/2007:00:11:07 +0400] [Job 3] Option: InputSlot=Default -->
Setting option
D [14/Sep/2007:00:11:07 +0400] [Job 3] Found: %% FoomaticRIPOptionSetting:
InputSlot=Default
D [14/Sep/2007:00:11:07 +0400] [Job 3] Option: InputSlot=Default -->
Setting option
D [14/Sep/2007:00:11:07 +0400] [Job 3] Found: %%BeginFeature: *Quality
FromPrintoutMode
D [14/Sep/2007:00:11:07 +0400] [Job 3] Option: Quality=FromPrintoutMode
--> Setting option
D [14/Sep/2007:00:11:07 +0400] [Job 3] Found: %% FoomaticRIPOptionSetting:
Quality=@PrintoutMode
D [14/Sep/2007:00:11:07 +0400] [Job 3] Option: Quality=FromPrintoutMode
--> Setting option
D [14/Sep/2007:00:11:07 +0400] [Job 3] Found: %%BeginFeature: *PageRegion
A4
D [14/Sep/2007:00:11:07 +0400] [Job 3] Option: PageRegion=A4 --> Option
will be set by PostScript interpreter
D [14/Sep/2007:00:11:07 +0400] [Job 3] Found: %% FoomaticRIPOptionSetting:
PageSize=A4
D [14/Sep/2007:00:11:07 +0400] [Job 3] Option: PageSize=A4 --> Setting
option
D [14/Sep/2007:00:11:07 +0400] [Job 3] Found: %%BeginFeature: *Duplex None
D [14/Sep/2007:00:11:07 +0400] [Job 3] Option: Duplex=None --> Setting
option
D [14/Sep/2007:00:11:07 +0400] [Job 3] Found: %% FoomaticRIPOptionSetting:
Duplex=None
D [14/Sep/2007:00:11:07 +0400] [Job 3] Option: Duplex=None --> Setting
option
D [14/Sep/2007:00:11:07 +0400] [Job 3] Found: %%EndSetup
D [14/Sep/2007:00:11:07 +0400] [Job 3] Inserting PostScript code for CUPS'
page accounting
D [14/Sep/2007:00:11:07 +0400] [Job 3] 
D [14/Sep/2007:00:11:07 +0400] [Job 3] -----------
D [14/Sep/2007:00:11:07 +0400] [Job 3] New page:  1 1
D [14/Sep/2007:00:11:07 +0400] [Job 3] Inserting option code into
"PageSetup" section.
D [14/Sep/2007:00:11:07 +0400] [Job 3] 
D [14/Sep/2007:00:11:07 +0400] [Job 3] Found: %%BeginPageSetup
D [14/Sep/2007:00:11:07 +0400] [Job 3] Found: %%EndPageSetup
D [14/Sep/2007:00:11:07 +0400] [Job 3] End of page header
D [14/Sep/2007:00:11:07 +0400] [Job 3] Stopping search for page header
options
D [14/Sep/2007:00:11:07 +0400] [Job 3] Found: 
Q1:a3uAQbJ-[S=%mKUD:mlf!'p4^\\#==i_-id'EF at ZE6H\),P%Sb.D'!!jqfT1XV=9D$P'
D [14/Sep/2007:00:11:07 +0400] [Job 3] --> Output goes directly to the
renderer now.
D [14/Sep/2007:00:11:07 +0400] [Job 3] 
D [14/Sep/2007:00:11:07 +0400] [Job 3] 
D [14/Sep/2007:00:11:07 +0400] [Job 3] Starting renderer
D [14/Sep/2007:00:11:07 +0400] [Job 3] renderer PID kid4=99629
D [14/Sep/2007:00:11:07 +0400] [Job 3] JCL: <job data> 
D [14/Sep/2007:00:11:07 +0400] [Job 3] 
D [14/Sep/2007:00:11:07 +0400] [Job 3] renderer command: gs -q -dBATCH
-dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs
-sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="hp LaserJet 1012"
-dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -dDuplex=false -r600
-sIjsParams=Quality:Quality=0,Quality:ColorMode=0,Quality:MediaType=0,Quality:PenSet=0,PS:MediaPosition=7
-dIjsUseOutputFD -sOutputFile=- -
D [14/Sep/2007:00:11:08 +0400] [Job 3] Wrote 1 pages...
D [14/Sep/2007:00:11:08 +0400] PID 99620
(/usr/local/libexec/cups/filter/pstops) exited with no errors.
D [14/Sep/2007:00:11:08 +0400] [Job 3] Found: %%Trailer
D [14/Sep/2007:00:11:08 +0400] [Job 3] --> Continue DSC parsing now.
D [14/Sep/2007:00:11:08 +0400] [Job 3] 
D [14/Sep/2007:00:11:08 +0400] [Job 3] Found: %%Pages: 1
D [14/Sep/2007:00:11:08 +0400] [Job 3] --> Continue DSC parsing now.
D [14/Sep/2007:00:11:08 +0400] [Job 3] 
D [14/Sep/2007:00:11:08 +0400] [Job 3] Found: %%BoundingBox: 0 0 420 596
D [14/Sep/2007:00:11:08 +0400] [Job 3] --> Continue DSC parsing now.
D [14/Sep/2007:00:11:08 +0400] [Job 3] 
D [14/Sep/2007:00:11:08 +0400] [Job 3] Found: %%EOF
D [14/Sep/2007:00:11:08 +0400] [Job 3] --> Continue DSC parsing now.
D [14/Sep/2007:00:11:08 +0400] [Job 3] 
D [14/Sep/2007:00:11:08 +0400] [Job 3] 
D [14/Sep/2007:00:11:08 +0400] [Job 3] Closing renderer
D [14/Sep/2007:00:11:09 +0400] Discarding unused job-progress event...
D [14/Sep/2007:00:11:10 +0400] [Job 3] KID3 exited with status 0
D [14/Sep/2007:00:11:10 +0400] [Job 3] Process dying with "error closing
*main::STDOUT", exit stat: 9
D [14/Sep/2007:00:11:10 +0400] [Job 3] error: Broken pipe (32)
D [14/Sep/2007:00:11:10 +0400] [Job 3] error closing *main::STDOUT
D [14/Sep/2007:00:11:10 +0400] [Job 3] KID4 exited with status 9
D [14/Sep/2007:00:11:10 +0400] [Job 3] Renderer exit stat: 9
D [14/Sep/2007:00:11:10 +0400] [Job 3] KID3 finished
D [14/Sep/2007:00:11:10 +0400] [Job 3] Renderer process finished
D [14/Sep/2007:00:11:10 +0400] [Job 3] Killing process 99628 (KID3)
D [14/Sep/2007:00:11:10 +0400] [Job 3] Process dying with "Error closing
renderer", exit stat: 9
D [14/Sep/2007:00:11:10 +0400] [Job 3] error: No such file or directory
(2)
D [14/Sep/2007:00:11:10 +0400] [Job 3] Error closing renderer
E [14/Sep/2007:00:11:10 +0400] PID 99621
(/usr/local/libexec/cups/filter/foomatic-rip) stopped with status 9!
D [14/Sep/2007:00:11:10 +0400] [Job 3] File 0 is complete.
I [14/Sep/2007:00:11:10 +0400] [Job 3] Backend returned status 1 (failed)
D [14/Sep/2007:00:11:10 +0400] Discarding unused printer-state-changed
event...
D [14/Sep/2007:00:11:10 +0400] Discarding unused printer-state-changed
event...
I [14/Sep/2007:00:11:10 +0400] Saving printers.conf...
D [14/Sep/2007:00:11:10 +0400] Discarding unused job-stopped event...
D [14/Sep/2007:00:11:11 +0400] Unloading job 3...

Link: http://www.cups.org/str.php?L2511
Version: 1.2.12