PDF and PS printing

David Berner anonymous at easysw.com
Sun Oct 31 09:17:37 PST 2004 

I'm trying to track down a problem I'm having printing PDF and PS files.

I'm able to print text or from applications like abiword fine, but when I send PDF or PS files to the printer I get one page of blank output for every 4 pages I send to the printer.

Another symptom of this problem is when I print from Galeon, my web browser, I get 4 pages per sheet on the output, but the output is not blank as it is for PS or PDF.

I'm using Gentoo linux, using CUPS 1.1.20-r4.  I have a Samsung ML-2250 printer, connected via USB.  I installed the Samsung drivers and PPD file from the CD provided with the printer.

I was fooling around with psnup trying to print muliple pages per sheet when this problem first occurred.  I'd dump the psnup output to a file and send it to the printer.  Is it possible I've toggled something in the printer?

I have removed and reinstalled CUPS multiple times, removed /etc/cups/*  /usr/lib/cups/*  and  /usr/share/cups/*  several times, and have generally tried to clean up any old configration files between CUPS installs.

When I send a simple text file containing "hello world" to lp I get one page with "hello world" at the top, as expected.

When I send a 6 page PS file I get 2 blank pages.  Here's my log from a 6 page PS file, converted from a PDF, sent to lp.  Not sure why it's saying "Media tray empty", I have plenty of paper, and it says that for the "hello world" text file too, which prints correctly....

Any assistance much appreciated, this is driving me NUTS.


Log entry below:



D [31/Oct/2004:12:12:30 -0800] AcceptClient() 5 from localhost:631.
D [31/Oct/2004:12:12:30 -0800] ReadClient() 5 POST / HTTP/1.1
D [31/Oct/2004:12:12:30 -0800] ProcessIPPRequest: 5 status_code=1
D [31/Oct/2004:12:12:30 -0800] CloseClient() 5
D [31/Oct/2004:12:12:30 -0800] AcceptClient() 5 from localhost:631.
D [31/Oct/2004:12:12:30 -0800] ReadClient() 5 POST / HTTP/1.1
D [31/Oct/2004:12:12:30 -0800] ProcessIPPRequest: 5 status_code=1
D [31/Oct/2004:12:12:30 -0800] CloseClient() 5
D [31/Oct/2004:12:12:30 -0800] AcceptClient() 5 from localhost:631.
D [31/Oct/2004:12:12:30 -0800] ReadClient() 5 POST / HTTP/1.1
D [31/Oct/2004:12:12:30 -0800] ProcessIPPRequest: 5 status_code=0
D [31/Oct/2004:12:12:32 -0800] CloseClient() 5
D [31/Oct/2004:12:12:48 -0800] AcceptClient() 5 from localhost:631.
D [31/Oct/2004:12:12:48 -0800] ReadClient() 5 POST / HTTP/1.1
D [31/Oct/2004:12:12:48 -0800] ProcessIPPRequest: 5 status_code=1
D [31/Oct/2004:12:12:48 -0800] CloseClient() 5
D [31/Oct/2004:12:12:48 -0800] AcceptClient() 5 from localhost:631.
D [31/Oct/2004:12:12:48 -0800] ReadClient() 5 POST / HTTP/1.1
D [31/Oct/2004:12:12:48 -0800] ProcessIPPRequest: 5 status_code=1
D [31/Oct/2004:12:12:48 -0800] CloseClient() 5
D [31/Oct/2004:12:12:48 -0800] AcceptClient() 5 from localhost:631.
D [31/Oct/2004:12:12:48 -0800] ReadClient() 5 POST / HTTP/1.1
D [31/Oct/2004:12:12:48 -0800] ProcessIPPRequest: 5 status_code=0
D [31/Oct/2004:12:12:48 -0800] ReadClient() 5 POST /printers/lp HTTP/1.1
D [31/Oct/2004:12:12:48 -0800] print_job: auto-typing file...
D [31/Oct/2004:12:12:48 -0800] print_job: request file type is application/postscript.
D [31/Oct/2004:12:12:48 -0800] check_quotas: requesting-user-name = 'wastrel'
D [31/Oct/2004:12:12:48 -0800] print_job: requesting-user-name = 'wastrel'
I [31/Oct/2004:12:12:48 -0800] Adding start banner page "none" to job 165.
I [31/Oct/2004:12:12:48 -0800] Adding end banner page "none" to job 165.
I [31/Oct/2004:12:12:48 -0800] Job 165 queued on 'lp' by 'wastrel'.
D [31/Oct/2004:12:12:48 -0800] Job 165 hold_until = 0
D [31/Oct/2004:12:12:48 -0800] StartJob(165, 0x8094830)
D [31/Oct/2004:12:12:48 -0800] StartJob() id = 165, file = 0/1
D [31/Oct/2004:12:12:48 -0800] job-sheets=none,none
D [31/Oct/2004:12:12:48 -0800] banner_page = 0
D [31/Oct/2004:12:12:48 -0800] StartJob: argv = "lp","165","wastrel","PNASfluid_mosaic.ps","1","media=Letter,Normal,Upper number-up=4","/var/spool/cups/d00165-001"
D [31/Oct/2004:12:12:48 -0800] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [31/Oct/2004:12:12:48 -0800] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [31/Oct/2004:12:12:48 -0800] StartJob: envp[2]="USER=root"
D [31/Oct/2004:12:12:48 -0800] StartJob: envp[3]="CHARSET=iso-8859-1"
D [31/Oct/2004:12:12:48 -0800] StartJob: envp[4]="LANG=en"
D [31/Oct/2004:12:12:48 -0800] StartJob: envp[5]="PPD=/etc/cups/ppd/lp.ppd"
D [31/Oct/2004:12:12:48 -0800] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [31/Oct/2004:12:12:48 -0800] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [31/Oct/2004:12:12:48 -0800] StartJob: envp[8]="TMPDIR=/var/spool/cups/tmp"
D [31/Oct/2004:12:12:48 -0800] StartJob: envp[9]="CONTENT_TYPE=application/postscript"
D [31/Oct/2004:12:12:48 -0800] StartJob: envp[10]="DEVICE_URI=usb:/dev/usb/lp0"
D [31/Oct/2004:12:12:48 -0800] StartJob: envp[11]="PRINTER=lp"
D [31/Oct/2004:12:12:48 -0800] StartJob: envp[12]="CUPS_DATADIR=/usr/share/cups"D [31/Oct/2004:12:12:48 -0800] StartJob: envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [31/Oct/2004:12:12:48 -0800] StartJob: envp[14]="CUPS_SERVER=localhost"
D [31/Oct/2004:12:12:48 -0800] StartJob: envp[15]="IPP_PORT=631"
D [31/Oct/2004:12:12:48 -0800] StartJob: statusfds = [ 7 8 ]
D [31/Oct/2004:12:12:48 -0800] StartJob: filterfds[1] = [ 9 -1 ]
D [31/Oct/2004:12:12:48 -0800] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [31/Oct/2004:12:12:48 -0800] StartJob: filterfds[0] = [ 10 11 ]
D [31/Oct/2004:12:12:48 -0800] start_process("/usr/lib/cups/filter/pstops", 0xbffefa50, 0xbffeedc0, 9, 11, 8)
I [31/Oct/2004:12:12:48 -0800] Started filter /usr/lib/cups/filter/pstops (PID 6123) for job 165.
D [31/Oct/2004:12:12:48 -0800] StartJob: filter = "/usr/lib/cups/filter/ppmtospl2"
D [31/Oct/2004:12:12:48 -0800] StartJob: filterfds[1] = [ 9 12 ]
D [31/Oct/2004:12:12:48 -0800] start_process("/usr/lib/cups/filter/ppmtospl2", 0xbffefa50, 0xbffeedc0, 10, 12, 8)
I [31/Oct/2004:12:12:48 -0800] Started filter /usr/lib/cups/filter/ppmtospl2 (PID 6124) for job 165.
D [31/Oct/2004:12:12:48 -0800] StartJob: backend = "/usr/lib/cups/backend/usb"
D [31/Oct/2004:12:12:48 -0800] StartJob: filterfds[0] = [ -1 10 ]
D [31/Oct/2004:12:12:48 -0800] start_process("/usr/lib/cups/backend/usb", 0xbffefa50, 0xbffeedc0, 9, 10, 8)
I [31/Oct/2004:12:12:48 -0800] Started backend /usr/lib/cups/backend/usb (PID 6125) for job 165.
D [31/Oct/2004:12:12:48 -0800] ProcessIPPRequest: 5 status_code=0
D [31/Oct/2004:12:12:48 -0800] AcceptClient() 8 from localhost:631.
D [31/Oct/2004:12:12:48 -0800] [Job 165] Page = 612x792; 12,12 to 600,780
D [31/Oct/2004:12:12:48 -0800] [Job 165] slowcollate=0, slowduplex=0, sloworder=0
D [31/Oct/2004:12:12:48 -0800] [Job 165] 0 %%Pages: (atend)
D [31/Oct/2004:12:12:48 -0800] [Job 165] 0 %%BoundingBox: 0 0 603 792
D [31/Oct/2004:12:12:48 -0800] [Job 165] 0 %%HiResBoundingBox: 0.000000 0.000000 603.000000 792.000000
D [31/Oct/2004:12:12:48 -0800] [Job 165] 0 %%Creator: ESP Ghostscript 707 (pswrite)
D [31/Oct/2004:12:12:48 -0800] [Job 165] 0 %%CreationDate: 2004/10/31 12:12:30
D [31/Oct/2004:12:12:48 -0800] [Job 165] 0 %%DocumentData: Clean7Bit
D [31/Oct/2004:12:12:48 -0800] [Job 165] 0 %%LanguageLevel: 2
D [31/Oct/2004:12:12:48 -0800] [Job 165] 0 %%EndComments
D [31/Oct/2004:12:12:48 -0800] [Job 165] 0 %%BeginProlog
D [31/Oct/2004:12:12:48 -0800] [Job 165] 0 %%BeginResource: procset GS_pswrite_2_0_1001
D [31/Oct/2004:12:12:48 -0800] [Job 165] 0 %%EndResource
D [31/Oct/2004:12:12:48 -0800] [Job 165] 0 %%EndProlog
D [31/Oct/2004:12:12:48 -0800] [Job 165] 0 %%Page: 1 1
D [31/Oct/2004:12:12:48 -0800] [Job 165] 0 %%Page: 1 1
D [31/Oct/2004:12:12:48 -0800] [Job 165] pw = 587.0, pl = 767.0
D [31/Oct/2004:12:12:48 -0800] [Job 165] PageLeft = 12.5, PageRight = 599.5
D [31/Oct/2004:12:12:48 -0800] [Job 165] PageTop = 779.5, PageBottom = 12.5
D [31/Oct/2004:12:12:48 -0800] [Job 165] PageWidth = 612.0, PageLength = 792.0
D [31/Oct/2004:12:12:48 -0800] [Job 165] 0 %%BeginPageSetup
D [31/Oct/2004:12:12:48 -0800] [Job 165] 0 %%EndPageSetup
D [31/Oct/2004:12:12:48 -0800] ReadClient() 8 POST / HTTP/1.1
D [31/Oct/2004:12:12:48 -0800] ProcessIPPRequest: 8 status_code=1
D [31/Oct/2004:12:12:48 -0800] AcceptClient() 9 from localhost:631.
D [31/Oct/2004:12:12:48 -0800] CloseClient() 8
D [31/Oct/2004:12:12:48 -0800] ReadClient() 9 POST / HTTP/1.1
D [31/Oct/2004:12:12:48 -0800] ProcessIPPRequest: 9 status_code=1
D [31/Oct/2004:12:12:48 -0800] AcceptClient() 8 from localhost:631.
D [31/Oct/2004:12:12:48 -0800] CloseClient() 9
D [31/Oct/2004:12:12:48 -0800] ReadClient() 8 POST / HTTP/1.1
D [31/Oct/2004:12:12:48 -0800] ProcessIPPRequest: 8 status_code=1
D [31/Oct/2004:12:12:48 -0800] AcceptClient() 9 from localhost:631.
D [31/Oct/2004:12:12:48 -0800] CloseClient() 8
D [31/Oct/2004:12:12:48 -0800] ReadClient() 9 POST / HTTP/1.1
D [31/Oct/2004:12:12:48 -0800] ProcessIPPRequest: 9 status_code=1
D [31/Oct/2004:12:12:48 -0800] CloseClient() 9
D [31/Oct/2004:12:12:48 -0800] CloseClient() 5
D [31/Oct/2004:12:12:48 -0800] [Job 165] LPGETSTATUS returned a port status of 18...
W [31/Oct/2004:12:12:48 -0800] [Job 165] Media tray empty!
D [31/Oct/2004:12:12:49 -0800] [Job 165] 0 %%PageTrailer
D [31/Oct/2004:12:12:49 -0800] [Job 165] 0 %%Page: 2 2
D [31/Oct/2004:12:12:49 -0800] [Job 165] pw = 587.0, pl = 767.0
D [31/Oct/2004:12:12:49 -0800] [Job 165] PageLeft = 12.5, PageRight = 599.5
D [31/Oct/2004:12:12:49 -0800] [Job 165] PageTop = 779.5, PageBottom = 12.5
D [31/Oct/2004:12:12:49 -0800] [Job 165] PageWidth = 612.0, PageLength = 792.0
D [31/Oct/2004:12:12:49 -0800] [Job 165] 0 %%BeginPageSetup
D [31/Oct/2004:12:12:49 -0800] [Job 165] 0 %%EndPageSetup
D [31/Oct/2004:12:12:50 -0800] [Job 165] 0 %%PageTrailer
D [31/Oct/2004:12:12:50 -0800] [Job 165] 0 %%Page: 3 3
D [31/Oct/2004:12:12:50 -0800] [Job 165] pw = 587.0, pl = 767.0
D [31/Oct/2004:12:12:50 -0800] [Job 165] PageLeft = 12.5, PageRight = 599.5
D [31/Oct/2004:12:12:50 -0800] [Job 165] PageTop = 779.5, PageBottom = 12.5
D [31/Oct/2004:12:12:50 -0800] [Job 165] PageWidth = 612.0, PageLength = 792.0
D [31/Oct/2004:12:12:50 -0800] [Job 165] 0 %%BeginPageSetup
D [31/Oct/2004:12:12:50 -0800] [Job 165] 0 %%EndPageSetup
D [31/Oct/2004:12:12:51 -0800] [Job 165] 0 %%PageTrailer
D [31/Oct/2004:12:12:51 -0800] [Job 165] 0 %%Page: 4 4
D [31/Oct/2004:12:12:51 -0800] [Job 165] pw = 587.0, pl = 767.0
D [31/Oct/2004:12:12:51 -0800] [Job 165] PageLeft = 12.5, PageRight = 599.5
D [31/Oct/2004:12:12:51 -0800] [Job 165] PageTop = 779.5, PageBottom = 12.5
D [31/Oct/2004:12:12:51 -0800] [Job 165] PageWidth = 612.0, PageLength = 792.0
D [31/Oct/2004:12:12:51 -0800] [Job 165] 0 %%BeginPageSetup
D [31/Oct/2004:12:12:51 -0800] [Job 165] 0 %%EndPageSetup
D [31/Oct/2004:12:12:52 -0800] [Job 165] 0 %%PageTrailer
D [31/Oct/2004:12:12:52 -0800] [Job 165] 0 %%Page: 5 5
D [31/Oct/2004:12:12:52 -0800] [Job 165] pw = 587.0, pl = 767.0
D [31/Oct/2004:12:12:52 -0800] [Job 165] PageLeft = 12.5, PageRight = 599.5
D [31/Oct/2004:12:12:52 -0800] [Job 165] PageTop = 779.5, PageBottom = 12.5
D [31/Oct/2004:12:12:52 -0800] [Job 165] PageWidth = 612.0, PageLength = 792.0
D [31/Oct/2004:12:12:52 -0800] [Job 165] 0 %%BeginPageSetup
D [31/Oct/2004:12:12:52 -0800] [Job 165] 0 %%EndPageSetup
D [31/Oct/2004:12:12:55 -0800] [Job 165] 0 %%PageTrailer
D [31/Oct/2004:12:12:55 -0800] [Job 165] 0 %%Page: 6 6
D [31/Oct/2004:12:12:55 -0800] [Job 165] pw = 587.0, pl = 767.0
D [31/Oct/2004:12:12:55 -0800] [Job 165] PageLeft = 12.5, PageRight = 599.5
D [31/Oct/2004:12:12:55 -0800] [Job 165] PageTop = 779.5, PageBottom = 12.5
D [31/Oct/2004:12:12:55 -0800] [Job 165] PageWidth = 612.0, PageLength = 792.0
D [31/Oct/2004:12:12:55 -0800] [Job 165] 0 %%BeginPageSetup
D [31/Oct/2004:12:12:55 -0800] [Job 165] 0 %%EndPageSetup
D [31/Oct/2004:12:12:56 -0800] [Job 165] 0 %%PageTrailer
D [31/Oct/2004:12:12:56 -0800] [Job 165] 0 %%Trailer
D [31/Oct/2004:12:12:56 -0800] [Job 165] Saw Trailer!
D [31/Oct/2004:12:12:56 -0800] [Job 165] pw = 587.0, pl = 767.0
D [31/Oct/2004:12:12:56 -0800] [Job 165] PageLeft = 12.5, PageRight = 599.5
D [31/Oct/2004:12:12:56 -0800] [Job 165] PageTop = 779.5, PageBottom = 12.5
D [31/Oct/2004:12:12:56 -0800] [Job 165] PageWidth = 612.0, PageLength = 792.0
D [31/Oct/2004:12:12:56 -0800] [Job 165] Saw EOF!
D [31/Oct/2004:12:12:58 -0800] UpdateJob: job 165, file 0 is complete.
D [31/Oct/2004:12:12:58 -0800] CancelJob: id = 165
D [31/Oct/2004:12:12:58 -0800] StopJob: id = 165, force = 0
D [31/Oct/2004:12:12:58 -0800] StopJob: printer state is 3

More information about the cups mailing list