PowerPoint will not print to Xerox 7750

[Allen Rongone]

Hello,
 I've been pulling my hair out over the last few days trying to figure this one out. Hopefully someone will have an answer.

We are running CUPS v1.2.4 on a Sun Solaris 10 box and have all our Windows clients print through the CUPS server. Whenever one of users attempts to print a PowerPoint presentation with embedded EPS graphics in it to our Xerox Phaser 7750GX we got no output from the printer. I don't even think the out from CUPS is making it to the printer. However, they can send the same presentation to one of our Phaser 6350 and it prints fine. (The Phaser 6350s are also processed through our CUPS server).

On the other hand, I can print the presentation directly from Windows to the Phaser 7750 through IPP and it prints fine. So it doesn't appear to be a problem with the presentation nor the Phaser 7750.

I've attempted to decipher the CUPS error_log but it doesn't give me much to go on. I also do not know enough detail of the innards of CUPS to troubleshoot further. I've attached a copy of the relevant section of the error_log and was hoping someone could enlighten me as to what is going inside CUPS with this document.

Thank you in advance for any help provided,
Allen Rongone

D [19/Jul/2007:10:28:28 +0500] cupsdAcceptClient: 8 from localhost:631 (IPv4)
D [19/Jul/2007:10:28:28 +0500] cupsdReadClient: 8 POST / HTTP/1.1
D [19/Jul/2007:10:28:28 +0500] cupsdAuthorize: No authentication data provided.
D [19/Jul/2007:10:28:28 +0500] Get-Printer-Attributes ipp://localhost/printers/test_psc4
D [19/Jul/2007:10:28:28 +0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [19/Jul/2007:10:28:29 +0500] cupsdReadClient: 8 POST /printers/test_psc4 HTTP/1.1
D [19/Jul/2007:10:28:29 +0500] cupsdAuthorize: No authentication data provided.
D [19/Jul/2007:10:28:29 +0500] Create-Job ipp://localhost/printers/test_psc4
D [19/Jul/2007:10:28:29 +0500] Loading attributes for job 31350...
D [19/Jul/2007:10:28:29 +0500] Discarding unused job-completed event...
D [19/Jul/2007:10:28:29 +0500] add_job: requesting-user-name="arongone"
D [19/Jul/2007:10:28:29 +0500] Adding default job-sheets values "none,none"...
I [19/Jul/2007:10:28:29 +0500] Adding start banner page "none" to job 31850.
D [19/Jul/2007:10:28:29 +0500] Discarding unused job-created event...
I [19/Jul/2007:10:28:29 +0500] Job 31850 created on "Test_psc4" by "arongone".
D [19/Jul/2007:10:28:29 +0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [19/Jul/2007:10:28:29 +0500] cupsdReadClient: 8 POST /jobs/31850 HTTP/1.1
D [19/Jul/2007:10:28:29 +0500] cupsdAuthorize: No authentication data provided.
D [19/Jul/2007:10:28:31 +0500] Send-Document ipp://localhost/jobs/31850
D [19/Jul/2007:10:28:31 +0500] send_document: auto-typing file...
D [19/Jul/2007:10:28:31 +0500] send_document: request file type is application/postscript.
I [19/Jul/2007:10:28:31 +0500] File of type application/postscript queued in job #31850 by "arongone".
I [19/Jul/2007:10:28:31 +0500] Adding end banner page "none" to job 31850.
D [19/Jul/2007:10:28:31 +0500] Discarding unused printer-state-changed event...
D [19/Jul/2007:10:28:31 +0500] job-sheets=none,none
D [19/Jul/2007:10:28:31 +0500] banner_page = 0
D [19/Jul/2007:10:28:31 +0500] [Job 31850] argv[0]="Test_psc4"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] argv[1]="31850"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] argv[2]="arongone"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] argv[3]="Microsoft PowerPoint - Test copy of jgriffin ppp.ppt"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] argv[4]="1"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] argv[5]="job-uuid=urn:uuid:18dc8512-7c95-30c0-5dab-b247c2ffa7d1"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] argv[6]="/opt/sfw/cups/var/spool/cups/d31850-001"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[0]="CUPS_CACHEDIR=/opt/sfw/cups/var/cache/cups"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[1]="CUPS_DATADIR=/opt/sfw/cups/share/cups"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[2]="CUPS_DOCROOT=/opt/sfw/cups/share/doc/cups"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[3]="CUPS_FONTPATH=/opt/sfw/cups/share/cups/fonts"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[4]="CUPS_REQUESTROOT=/opt/sfw/cups/var/spool/cups"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[5]="CUPS_SERVERBIN=/opt/sfw/cups/lib/cups"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[6]="CUPS_SERVERROOT=/opt/sfw/cups/etc/cups"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[7]="CUPS_STATEDIR=/opt/sfw/cups/var/run/cups"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[8]="LD_LIBRARY_PATH=/usr/lib:/usr/local/lib:/usr/openwin/lib:/usr/dt/lib:/usr/ucblib:/usr/ccs/lib:/opt/X11R5/lib:/usr/sfw/lib:/opt/sfw/lib:/opt/sfw/cups/lib:/opt/imsl/lib/lib.solaris:/usr/local/SUNWspro/lib:/usr/local/SUNWspro/prod/lib"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[9]="PATH=/opt/sfw/cups/lib/cups/filter:/opt/sfw/cups/bin:/opt/sfw/cups/sbin:/bin:/usr/bin"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[10]="SERVER_ADMIN=root at apollo"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[11]="SOFTWARE=CUPS/1.2.4"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[12]="TMPDIR=/opt/sfw/cups/var/spool/cups/tmp"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[13]="TZ=US/Eastern"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[14]="USER=root"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[15]="CUPS_SERVER=localhost"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[17]="IPP_PORT=631"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[18]="CHARSET=utf-8"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[19]="LANG=en_US"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[20]="PPD=/opt/sfw/cups/etc/cups/ppd/Test_psc4.ppd"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[21]="RIP_MAX_CACHE=8m"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[22]="CONTENT_TYPE=application/postscript"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[23]="DEVICE_URI=socket://10.5.2.4"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[24]="PRINTER=Test_psc4"
D [19/Jul/2007:10:28:31 +0500] [Job 31850] envp[25]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
I [19/Jul/2007:10:28:31 +0500] Started filter /opt/sfw/cups/lib/cups/filter/pstops (PID 11696) for job 31850.
I [19/Jul/2007:10:28:31 +0500] Started backend /opt/sfw/cups/lib/cups/backend/socket (PID 11697) for job 31850.
D [19/Jul/2007:10:28:31 +0500] Discarding unused job-state event...
D [19/Jul/2007:10:28:31 +0500] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [19/Jul/2007:10:28:31 +0500] PID 11696 (/opt/sfw/cups/lib/cups/filter/pstops) exited with no errors.
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Page = 612x792; 14,14 to 598,778
D [19/Jul/2007:10:28:31 +0500] [Job 31850] slow_collate=0, slow_duplex=0, slow_order=0
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Before copy_comments - %!PS-Adobe-3.0

D [19/Jul/2007:10:28:31 +0500] [Job 31850] %!PS-Adobe-3.0
D [19/Jul/2007:10:28:31 +0500] [Job 31850] %%Title: Microsoft PowerPoint - Test copy of jgriffin ppp.ppt
D [19/Jul/2007:10:28:31 +0500] [Job 31850] %%Creator: PScript5.dll Version 5.2.2
D [19/Jul/2007:10:28:31 +0500] [Job 31850] %%CreationDate: 7/19/2007 10:27:22
D [19/Jul/2007:10:28:31 +0500] [Job 31850] %%For: arongone
D [19/Jul/2007:10:28:31 +0500] [Job 31850] %%BoundingBox: (atend)
D [19/Jul/2007:10:28:31 +0500] [Job 31850] %%DocumentNeededResources: (atend)
D [19/Jul/2007:10:28:31 +0500] [Job 31850] %%DocumentSuppliedResources: (atend)
D [19/Jul/2007:10:28:31 +0500] [Job 31850] %%DocumentData: Clean7Bit
D [19/Jul/2007:10:28:31 +0500] [Job 31850] %%TargetDevice: (Phaser 7750GX) (3015.102) 3
D [19/Jul/2007:10:28:31 +0500] [Job 31850] %%LanguageLevel: 3
E [19/Jul/2007:10:28:31 +0500] [Job 31850] No %%Pages: comment in header!
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Before copy_prolog - mark {
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Before copy_setup -

D [19/Jul/2007:10:28:31 +0500] [Job 31850] backendRunLoop(print_fd=0, device_fd=4, use_bc=1)
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] Discarding unused printer-state-changed event...
D [19/Jul/2007:10:28:31 +0500] cupsdCloseClient: 8
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Before page loop - %%Trailer
DEBUG: Wrote 0 pages...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 8192 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Read 2336 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Wrote 2336 bytes of print data...
D [19/Jul/2007:10:28:31 +0500] [Job 31850] Received 35 bytes of back-channel data!
D [19/Jul/2007:10:28:32 +0500] PID 11697 (/opt/sfw/cups/lib/cups/backend/socket) exited with no errors.
D [19/Jul/2007:10:28:32 +0500] [Job 31850] File 0 is complete.
D [19/Jul/2007:10:28:32 +0500] Discarding unused printer-state-changed event...
D [19/Jul/2007:10:28:32 +0500] Discarding unused job-completed event...
D [19/Jul/2007:10:28:33 +0500] Unloading job 31850...