CUPS not always printing job

Thu Jul 8 23:48:29 PDT 2004

Hi,

When printing from Kprinter, xpp or mozilla, CUPS does not print the job it receives.  However everything works well in OpenOffice and even in microsoft Office via Codeweaver.
The printer is connected to a network and when I looked to the CUPS error_log file, I can see that CUPS is sending the file, waiting for the printer to finish and exit without any errors, but nothing at the printer!!

Here is a part of the error_log file:

D[09/Jul/2004:08:09:22 +0200] StartJob: argv = "HP4100ps","126","arthur","mozilla.ps","1","cpi=12 lpi=7 multiple-document-handling=separate-documents-collated-copies page-bottom=36 page-left=36 page-right=36 page-top=36 scaling=100","/var/spool/cups/d00126-001"
D [09/Jul/2004:08:09:22 +0200] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [09/Jul/2004:08:09:22 +0200] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [09/Jul/2004:08:09:22 +0200] StartJob: envp[2]="USER=root"
D [09/Jul/2004:08:09:22 +0200] StartJob: envp[3]="CHARSET=iso-8859-1"
D [09/Jul/2004:08:09:22 +0200] StartJob: envp[4]="LANG=en_US"
D [09/Jul/2004:08:09:22 +0200] StartJob: envp[5]=""
D [09/Jul/2004:08:09:22 +0200] StartJob: envp[6]="PPD=/etc/cups/ppd/HP4100ps.ppd"
D [09/Jul/2004:08:09:22 +0200] StartJob: envp[7]="CUPS_SERVERROOT=/etc/cups"
D [09/Jul/2004:08:09:22 +0200] StartJob: envp[8]="RIP_MAX_CACHE=8m"
D [09/Jul/2004:08:09:22 +0200] StartJob: envp[9]="TMPDIR=/var/spool/cups/tmp"
D [09/Jul/2004:08:09:22 +0200] StartJob: envp[10]="CONTENT_TYPE=application/postscript"
D [09/Jul/2004:08:09:22 +0200] StartJob: envp[11]="DEVICE_URI=socket://130.104.237.25:9100"
D [09/Jul/2004:08:09:22 +0200] StartJob: envp[12]="PRINTER=HP4100ps"
D [09/Jul/2004:08:09:22 +0200] StartJob: envp[13]="CUPS_DATADIR=/usr/share/cups"
D [09/Jul/2004:08:09:22 +0200] StartJob: envp[14]="CUPS_FONTPATH=/usr/share/cups/fonts"
d [09/Jul/2004:08:09:22 +0200] StartJob: Allocating status buffer...
D [09/Jul/2004:08:09:22 +0200] StartJob: statusfds = [ 9 10 ]
D [09/Jul/2004:08:09:22 +0200] StartJob: filterfds[1] = [ 11 -1 ]
D [09/Jul/2004:08:09:22 +0200] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [09/Jul/2004:08:09:22 +0200] StartJob: filterfds[0] = [ 12 13 ]
D [09/Jul/2004:08:09:22 +0200] start_process("/usr/lib/cups/filter/pstops", 0xbfff0220, 0xbffef590, 11, 13, 10)
I [09/Jul/2004:08:09:22 +0200] Started filter /usr/lib/cups/filter/pstops (PID 17452) for job 126.
D [09/Jul/2004:08:09:22 +0200] StartJob: backend = "/usr/lib/cups/backend/socket"
D [09/Jul/2004:08:09:22 +0200] StartJob: filterfds[1] = [ -1 11 ]
D [09/Jul/2004:08:09:22 +0200] start_process("/usr/lib/cups/backend/socket", 0xbfff0220, 0xbffef590, 12, 11, 10)
I [09/Jul/2004:08:09:22 +0200] Started backend /usr/lib/cups/backend/socket (PID 17453) for job 126.
d [09/Jul/2004:08:09:22 +0200] StartJob: Adding fd 9 to InputSet...
d [09/Jul/2004:08:09:22 +0200] add_job_state_reasons(0x402faa34[8], 126)
D [09/Jul/2004:08:09:22 +0200] ProcessIPPRequest: 8 status_code=0
d [09/Jul/2004:08:09:23 +0200] ProcessIPPRequest: Adding fd 8 to OutputSet...
d [09/Jul/2004:08:09:23 +0200] SendBrowseList: (66 bytes to "eth0") 9056 4 ipp://Lambic/printers/HP4100ps "" "" "HP LaserJet 4100 PS"
d [09/Jul/2004:08:09:23 +0200] ReadClient() 7, used=0
D [09/Jul/2004:08:09:23 +0200] CloseClient() 7
d [09/Jul/2004:08:09:23 +0200] CloseClient: Removing fd 7 from InputSet and OutputSet...
d [09/Jul/2004:08:09:23 +0200] WriteClient: Removing fd 8 from OutputSet...
d [09/Jul/2004:08:09:23 +0200] ReadClient() 8, used=0
D [09/Jul/2004:08:09:23 +0200] CloseClient() 8
d [09/Jul/2004:08:09:23 +0200] CloseClient: Removing fd 8 from InputSet and OutputSet...
D [09/Jul/2004:08:09:23 +0200] [Job 126] Page = 595x842; 12,12 to 583,829
D [09/Jul/2004:08:09:23 +0200] [Job 126] slowcollate=0, slowduplex=0, sloworder=0
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%BoundingBox: 18 18 594 774
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%Creator: Mozilla PostScript module (rv:1.6/2004011310)
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%DocumentData: Clean8Bit
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%DocumentPaperSizes: Letter
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%Orientation: Portrait
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%Pages: (atend)
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%PageOrder: Ascend
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%EndComments
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%BeginProlog
I [09/Jul/2004:08:09:23 +0200] [Job 126] Attempting to connect to host 230.254.245.25 on port 9100
I [09/Jul/2004:08:09:23 +0200] [Job 126] Connected to host, sending print job...
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%EndProlog
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%Page: 1 1
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%Page: 1 1
d [09/Jul/2004:08:09:23 +0200] PID 17452 exited with no errors.
D [09/Jul/2004:08:09:23 +0200] [Job 126] pw = 523.0, pl = 770.0
D [09/Jul/2004:08:09:23 +0200] [Job 126] PageLeft = 36.0, PageRight = 559.0
D [09/Jul/2004:08:09:23 +0200] [Job 126] PageTop = 806.0, PageBottom = 36.0
D [09/Jul/2004:08:09:23 +0200] [Job 126] PageWidth = 595.0, PageLength = 842.0
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%BeginPageSetup
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%EndPageSetup
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%Page: 2 2
D [09/Jul/2004:08:09:23 +0200] [Job 126] pw = 523.0, pl = 770.0
D [09/Jul/2004:08:09:23 +0200] [Job 126] PageLeft = 36.0, PageRight = 559.0
D [09/Jul/2004:08:09:23 +0200] [Job 126] PageTop = 806.0, PageBottom = 36.0
D [09/Jul/2004:08:09:23 +0200] [Job 126] PageWidth = 595.0, PageLength = 842.0
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%BeginPageSetup
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%EndPageSetup
D [09/Jul/2004:08:09:23 +0200] [Job 126] 0 %%Trailer
D [09/Jul/2004:08:09:23 +0200] [Job 126] Saw Trailer!
D [09/Jul/2004:08:09:23 +0200] [Job 126] Saw EOF!
I [09/Jul/2004:08:09:23 +0200] [Job 126] Print file sent, waiting for printer to finish...
I [09/Jul/2004:08:09:23 +0200] [Job 126] Ready to print.
d [09/Jul/2004:08:09:23 +0200] PID 17453 exited with no errors.
D [09/Jul/2004:08:09:23 +0200] UpdateJob: job 126, file 0 is complete.
d [09/Jul/2004:08:09:23 +0200] UpdateJob: Removing fd 9 from InputSet...
D [09/Jul/2004:08:09:23 +0200] CancelJob: id = 126
D [09/Jul/2004:08:09:23 +0200] StopJob: id = 126, force = 0
D [09/Jul/2004:08:09:23 +0200] StopJob: printer state is 3
d [09/Jul/2004:08:09:23 +0200] StopJob: Freeing status buffer...