really bizarre problem printing non-default page size

Thu Aug 14 14:36:45 PDT 2008

Want to submit the difference I'm seeing in the error_log @ debug level:

Working system:
============================================
D [14/Aug/2008:17:22:26 -0400] [Job 107740] argv[3]="smbprn.00000931 Microsoft Word - This is a test.doc"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] argv[4]="1"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] argv[5]="job-uuid=urn:uuid:72059992-2079-37cd-578f-3fee3aa7f8d3"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] argv[6]="/var/spool/cups/d107740-001"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[2]="CUPS_DOCROOT=/usr/share/cups/html"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[5]="CUPS_SERVERBIN=/usr/libexec/cups"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[8]="PATH=/usr/libexec/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[9]="SERVER_ADMIN=root at einstein.hq.theauditors.com"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[10]="SOFTWARE=CUPS/1.3.8"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[12]="TZ=America/Detroit"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[13]="USER=root"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[16]="IPP_PORT=631"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[17]="CHARSET=utf-8"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[18]="LANG=en_US.UTF8"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[19]="PPD=/etc/cups/ppd/Virtual_Printer.ppd"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[20]="RIP_MAX_CACHE=8m"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[21]="CONTENT_TYPE=application/vnd.cups-raw"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[22]="DEVICE_URI=cups-pdf:/"
D [14/Aug/2008:17:22:26 -0400] [Job 107740] envp[23]="PRINTER=Virtual_Printer"
I [14/Aug/2008:17:22:26 -0400] [Job 107740] Started backend /usr/libexec/cups/backend/cups-pdf (PID 18165)
============================================

Non-working system:
============================================
D [14/Aug/2008:17:18:44 -0400] [Job 159] argv[3]="smbprn.00000021 Microsoft Word - This is a test.doc"
D [14/Aug/2008:17:18:44 -0400] [Job 159] argv[4]="1"
D [14/Aug/2008:17:18:44 -0400] [Job 159] argv[5]="job-uuid=urn:uuid:4a8152d3-f884-3db3-7196-42b2f81c42d2"
D [14/Aug/2008:17:18:44 -0400] [Job 159] argv[6]="/var/spool/cups/d00159-001"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[2]="CUPS_DOCROOT=/usr/share/cups/html"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[5]="CUPS_SERVERBIN=/usr/libexec/cups"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[8]="PATH=/usr/libexec/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[9]="SERVER_ADMIN=root at hawking.hq.theauditors.com"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[10]="SOFTWARE=CUPS/1.3.8"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[12]="TZ=America/Detroit"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[13]="USER=root"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[16]="IPP_PORT=631"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[17]="CHARSET=utf-8"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[18]="LANG=en_US.UTF8"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[19]="PPD=/etc/cups/ppd/Virtual_Printer.ppd"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[20]="RIP_MAX_CACHE=8m"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[21]="CONTENT_TYPE=application/postscript"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[22]="DEVICE_URI=cups-pdf:/"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[23]="PRINTER=Virtual_Printer"
D [14/Aug/2008:17:18:44 -0400] [Job 159] envp[24]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
I [14/Aug/2008:17:18:44 -0400] [Job 159] Started filter /usr/libexec/cups/filter/pstops (PID 16514)
I [14/Aug/2008:17:18:44 -0400] [Job 159] Started backend /usr/libexec/cups/backend/cups-pdf (PID 16515)
============================================

Notice that the working system says the job is:
"CONTENT_TYPE=application/vnd.cups-raw"

And the non-working system claims it is:
"CONTENT_TYPE=application/postscript"
with a:
"FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
and sends it through the:
"/usr/libexec/cups/filter/pstops" filter, whereas the working system does not.

Same driver from same client to identically configured CUPS servers.

/me - pulls out hair