imagetoraster cuts height in half (CUPS 1.1.18)

Fri Jan 21 14:36:37 PST 2005

Brion Finlay wrote:
> Michael Sweet wrote:
> 
>> Brion Finlay wrote:
>>
>>> I am trying to print an image (GIF or PNG) to a CUPS printer.  The 
>>> image is 300 pixels by 420 pixels at 200 dpi.
>>>
>>> My PPD for my printer specifies a page size of 312x425 points (it's a 
>>> small printer) and an imageable area of 294x413 points.  At 200dpi, 
>>> that should give me about 830 dots x 1166 dots.
>>>
>>> When I send my image through cups, it goes through the imagetoraster 
>>> filter, it cuts the height in half.  cupsWidth=828 which is 
>>> acceptable, but cupsHeight=573, which is approximately half of the 
>>> 1166 dots that I should have.
>>
>>
>>  > ...
>>
>> What HWResolution values are coming through to your raster filter?
>>
> 
> I don't see anything in the logs for this job (Job 3, the PNG image). 
> What should I look for?  For a previous postscript job, I see this:
> D [20/Jan/2005:16:08:20 +0000] [Job 2] PageSize = [ 312 425 ], 
> HWResolution = [ 203 203 ]
> 
> For Job 3, the closest thing I see is this:
> D [20/Jan/2005:16:18:11 +0000] [Job 3] Page = 312x425; 0,0 to 294,413
> D [20/Jan/2005:16:18:11 +0000] [Job 3] Searching for profile "200dpi/"...
> (... Cache tile stuff excerpted here ...)
> D [20/Jan/2005:16:18:11 +0000] [Job 3] Before scaling: xppi=198, 
> yppi=198, zoom=0.00
> 
> The PPD file defines the following:
> (these lines are excerpted from the rest of the PPD)
> *PaperDimension Letter/US Letter:       "312 425"
> 
> *ImageableArea Letter/US Letter:        "0 0 294 413"
> 
> *DefaultResolution: 200dpi
> *Resolution 200dpi/200 DPI: "<</HWResolution[203.2 203.2]/cupsColorOrder 
> 0/cupsColorSpace 3>>setpagedevice"
> 
> 

I set the LogLevel to debug2 to see if that would report resolution. 
Here's a full log at LogLevel debug2 (the job number has changed to Job 
1 for this re-submission):

D [21/Jan/2005:13:27:43 +0000] ProcessIPPRequest: 3 status_code=0
d [21/Jan/2005:13:27:43 +0000] WriteClient: Removing fd 3 from OutputSet...
d [21/Jan/2005:13:27:43 +0000] ReadClient() 3, used=0
D [21/Jan/2005:13:27:43 +0000] ReadClient() 3 POST 
/printers/Omni_DO_NOT_USE HTTP/1.1
d [21/Jan/2005:13:27:43 +0000] decode_auth(0x401df008): Authorization 
string = ""
d [21/Jan/2005:13:27:43 +0000] decode_auth() 3 username=""
d [21/Jan/2005:13:27:43 +0000] IsAuthorized: con->uri = 
"/printers/Omni_DO_NOT_USE"
d [21/Jan/2005:13:27:43 +0000] FindBest: uri = 
"/printers/Omni_DO_NOT_USE"...
d [21/Jan/2005:13:27:43 +0000] FindBest: Location / Limit 7f
d [21/Jan/2005:13:27:43 +0000] FindBest: Location /admin Limit 7f
d [21/Jan/2005:13:27:43 +0000] FindBest: best = "/"
d [21/Jan/2005:13:27:43 +0000] IsAuthorized: auth = 0, satisfy=0...
d [21/Jan/2005:13:27:43 +0000] POST /printers/Omni_DO_NOT_USE
d [21/Jan/2005:13:27:43 +0000] CONTENT_TYPE = application/ipp
d [21/Jan/2005:13:27:43 +0000] ReadClient() 3 con->data_encoding = 
length, con->data_remaining = 14988, con->file = 0
d [21/Jan/2005:13:27:43 +0000] ReadClient() 3 REQUEST 
/var/spool/cups/00000000=5
d [21/Jan/2005:13:27:43 +0000] ReadClient() 3 writing 1730 bytes to 5
d [21/Jan/2005:13:27:43 +0000] ReadClient() 3, used=0
d [21/Jan/2005:13:27:43 +0000] ReadClient() 3 con->data_encoding = 
length, con->data_remaining = 12990, con->file = 5
d [21/Jan/2005:13:27:43 +0000] ReadClient() 3 writing 12990 bytes to 5
d [21/Jan/2005:13:27:43 +0000] ReadClient() 3 Closing data file 5, size 
= 14720.
d [21/Jan/2005:13:27:43 +0000] ProcessIPPRequest(0x401df008[3]): 
operation_id = 0002
d [21/Jan/2005:13:27:43 +0000] print_job(0x401df008[3], 
ipp://localhost:631/printers/Omni_DO_NOT_USE)
D [21/Jan/2005:13:27:43 +0000] print_job: auto-typing file...
D [21/Jan/2005:13:27:43 +0000] print_job: request file type is image/png.
d [21/Jan/2005:13:27:43 +0000] check_quotas(0x401df008[3], 
0x808b480[Omni_DO_NOT_USE])
D [21/Jan/2005:13:27:43 +0000] check_quotas: requesting-user-name = 'root'
D [21/Jan/2005:13:27:43 +0000] print_job: requesting-user-name = 'root'
d [21/Jan/2005:13:27:43 +0000] copy_banner(0x401df008[3], 0x80ef278[1], 
none)
d [21/Jan/2005:13:27:43 +0000] add_file(0x401df008[3], 1, image/png)
d [21/Jan/2005:13:27:43 +0000] copy_banner(0x401df008[3], 0x80ef278[1], 
none)
I [21/Jan/2005:13:27:43 +0000] Job 1 queued on 'Omni_DO_NOT_USE' by 'root'.
D [21/Jan/2005:13:27:43 +0000] Job 1 hold_until = 0
D [21/Jan/2005:13:27:43 +0000] StartJob(1, 0x808b480)
D [21/Jan/2005:13:27:43 +0000] StartJob() id = 1, file = 0/1
D [21/Jan/2005:13:27:43 +0000] job-sheets=none,none
D [21/Jan/2005:13:27:43 +0000] banner_page = 0
D [21/Jan/2005:13:27:43 +0000] StartJob: argv = 
"Omni_DO_NOT_USE","1","root","lenaacdhb.png","1","","/var/spool/cups/d00001-001"
D [21/Jan/2005:13:27:43 +0000] StartJob: envp = 
"PATH=/usr/lib/cups/filter:/bin:/usr/bin","SOFTWARE=CUPS/1.1","USER=root","CHARSET=iso-8859-1","LANG=en_US","","PPD=/etc/cups/ppd/Omni_DO_NOT_USE.ppd","CUPS_SERVERROOT=/etc/cups","RIP_MAX_CACHE=8m","TMPDIR=/var/spool/cups/tmp","CONTENT_TYPE=image/png","DEVICE_URI=omnitar:/dev/gdt/printReport","PRINTER=Omni_DO_NOT_USE","CUPS_DATADIR=/usr/share/cups","CUPS_FONTPATH=/usr/share/cups/fonts","","",""
d [21/Jan/2005:13:27:43 +0000] UpdateJob: Allocating status buffer...
D [21/Jan/2005:13:27:43 +0000] StartJob: statusfds = 5, 6
D [21/Jan/2005:13:27:43 +0000] StartJob: filterfds[1] = 7, -1
D [21/Jan/2005:13:27:43 +0000] StartJob: filter = 
"/usr/lib/cups/filter/imagetoraster"
D [21/Jan/2005:13:27:43 +0000] StartJob: filterfds[0] = 8, 9
D [21/Jan/2005:13:27:43 +0000] 
start_process("/usr/lib/cups/filter/imagetoraster", 0xbfff15c0, 
0xbfff0a70, 7, 9, 6)
I [21/Jan/2005:13:27:43 +0000] Started filter 
/usr/lib/cups/filter/imagetoraster (PID 3551) for job 1.
D [21/Jan/2005:13:27:43 +0000] StartJob: filter = 
"/usr/lib/cups/filter/rastertoomnitar"
D [21/Jan/2005:13:27:43 +0000] StartJob: filterfds[1] = 7, 10
D [21/Jan/2005:13:27:43 +0000] 
start_process("/usr/lib/cups/filter/rastertoomnitar", 0xbfff15c0, 
0xbfff0a70, 8, 10, 6)
I [21/Jan/2005:13:27:43 +0000] Started filter 
/usr/lib/cups/filter/rastertoomnitar (PID 3552) for job 1.
D [21/Jan/2005:13:27:43 +0000] StartJob: backend = 
"/usr/lib/cups/backend/omnitar"
D [21/Jan/2005:13:27:43 +0000] StartJob: filterfds[0] = -1, 8
D [21/Jan/2005:13:27:43 +0000] 
start_process("/usr/lib/cups/backend/omnitar", 0xbfff15c0, 0xbfff0a70, 
7, 8, 6)
I [21/Jan/2005:13:27:43 +0000] Started backend 
/usr/lib/cups/backend/omnitar (PID 3553) for job 1.
d [21/Jan/2005:13:27:43 +0000] StartJob: Adding fd 5 to InputSet...
d [21/Jan/2005:13:27:43 +0000] add_job_state_reasons(0x401df008[3], 1)
D [21/Jan/2005:13:27:43 +0000] ProcessIPPRequest: 3 status_code=0
d [21/Jan/2005:13:27:43 +0000] WriteClient: Removing fd 3 from OutputSet...
I [21/Jan/2005:13:27:43 +0000] [Job 1] Omni_DO_NOT_USE 1 root 
lenaacdhb.png 1  /var/spool/cups/d00001-001
D [21/Jan/2005:13:27:43 +0000] [Job 1] Page = 312x425; 0,0 to 294,413
D [21/Jan/2005:13:27:43 +0000] [Job 1] Searching for profile "200dpi/"...
I [21/Jan/2005:13:27:43 +0000] [Job 1] Loading image file...
D [21/Jan/2005:13:27:43 +0000] [Job 1] 
ImageOpen("/var/spool/cups/d00001-001", -1, -1, 100, 0, 0xbffff080)
D [21/Jan/2005:13:27:43 +0000] [Job 1] max_ics=10...
D [21/Jan/2005:13:27:43 +0000] [Job 1] Creating tile array (2x2)
D [21/Jan/2005:13:27:43 +0000] [Job 1] Allocated cache tile 1 (0x8067168)...
D [21/Jan/2005:13:27:43 +0000] [Job 1] Clearing cache tile...
D [21/Jan/2005:13:27:43 +0000] [Job 1] Allocated cache tile 2 (0x8077180)...
D [21/Jan/2005:13:27:43 +0000] [Job 1] Clearing cache tile...
D [21/Jan/2005:13:27:43 +0000] [Job 1] Allocated cache tile 3 (0x8087198)...
D [21/Jan/2005:13:27:43 +0000] [Job 1] Clearing cache tile...
D [21/Jan/2005:13:27:43 +0000] [Job 1] Allocated cache tile 4 (0x80971b0)...
D [21/Jan/2005:13:27:43 +0000] [Job 1] Clearing cache tile...
D [21/Jan/2005:13:27:43 +0000] [Job 1] Before scaling: xppi=198, 
yppi=198, zoom=0.00
D [21/Jan/2005:13:27:43 +0000] [Job 1] Before scaling: xprint=4.1, 
yprint=5.7
D [21/Jan/2005:13:27:43 +0000] [Job 1] Image size is 1.5 x 2.1 inches...
D [21/Jan/2005:13:27:43 +0000] [Job 1] Auto orientation...
D [21/Jan/2005:13:27:43 +0000] [Job 1] xpages = 1, ypages = 1
D [21/Jan/2005:13:27:43 +0000] [Job 1] PageSize = [312 425]
D [21/Jan/2005:13:27:43 +0000] [Job 1] cupsWidth = 828
D [21/Jan/2005:13:27:43 +0000] [Job 1] cupsHeight = 573
D [21/Jan/2005:13:27:43 +0000] [Job 1] cupsBitsPerColor = 1
D [21/Jan/2005:13:27:43 +0000] [Job 1] cupsBitsPerPixel = 1
D [21/Jan/2005:13:27:43 +0000] [Job 1] cupsBytesPerLine = 104
D [21/Jan/2005:13:27:43 +0000] [Job 1] cupsColorOrder = 0
D [21/Jan/2005:13:27:43 +0000] [Job 1] cupsColorSpace = 3
D [21/Jan/2005:13:27:43 +0000] [Job 1] img->colorspace = -1
I [21/Jan/2005:13:27:43 +0000] [Job 1] Formatting page 1...
I [21/Jan/2005:13:27:43 +0000] [Job 1] Executing " Omni_DO_NOT_USE 1 
root lenaacdhb.png 1  "
I [21/Jan/2005:13:27:43 +0000] [Job 1] Printing page 1, 0% complete...
I [21/Jan/2005:13:27:43 +0000] [Job 1] Executing " 
omnitar:/dev/gdt/printReport 1 root lenaacdhb.png 1  "
d [21/Jan/2005:13:27:43 +0000] ReadClient() 3, used=0
D [21/Jan/2005:13:27:43 +0000] CloseClient() 3
d [21/Jan/2005:13:27:43 +0000] CloseClient: Removing fd 3 from InputSet 
and OutputSet...
I [21/Jan/2005:13:27:43 +0000] [Job 1] Printing page 1, 22% complete...
I [21/Jan/2005:13:27:43 +0000] [Job 1] Printing page 1, 44% complete...
I [21/Jan/2005:13:27:44 +0000] [Job 1] Printing page 1, 67% complete...
I [21/Jan/2005:13:27:44 +0000] [Job 1] Printing page 1, 89% complete...
d [21/Jan/2005:13:27:44 +0000] PID 3551 exited with no errors.
D [21/Jan/2005:13:27:44 +0000] [Job 1] Freeing memory...
D [21/Jan/2005:13:27:44 +0000] [Job 1] Freeing cache (0x8067168, next = 
(nil))...
D [21/Jan/2005:13:27:44 +0000] [Job 1] Freeing cache (0x8077180, next = 
0x8077180)...
D [21/Jan/2005:13:27:44 +0000] [Job 1] Freeing cache (0x8087198, next = 
0x8087198)...
D [21/Jan/2005:13:27:44 +0000] [Job 1] Freeing cache (0x80971b0, next = 
0x80971b0)...
D [21/Jan/2005:13:27:44 +0000] [Job 1] Freeing tiles (0x8066cc8)...
D [21/Jan/2005:13:27:44 +0000] [Job 1] Freeing tile pointers (0x8066cb8)...
I [21/Jan/2005:13:27:44 +0000] [Job 1] CUPS v1.1.18 is ready to print.
d [21/Jan/2005:13:27:44 +0000] PID 3552 exited with no errors.
d [21/Jan/2005:13:27:44 +0000] PID 3553 exited with no errors.
I [21/Jan/2005:13:27:44 +0000] [Job 1] Printer data stream sent to device
D [21/Jan/2005:13:27:44 +0000] UpdateJob: job 1, file 0 is complete.
d [21/Jan/2005:13:27:44 +0000] UpdateJob: Removing fd 5 from InputSet...
D [21/Jan/2005:13:27:44 +0000] CancelJob: id = 1
D [21/Jan/2005:13:27:44 +0000] StopJob: id = 1, force = 0
D [21/Jan/2005:13:27:44 +0000] StopJob: printer state is 3
d [21/Jan/2005:13:27:44 +0000] StopJob: Freeing status buffer...