[cups.general] Printing PDFs from windows to Linux server

Fri Mar 16 23:16:03 PDT 2012

I have a Linux machine that I just upgraded to Debian Sid from Linux Mint.
Everything seems to be fine except I'm having an issue with CUPS. I have
samba set up on this machine and can print from our windows laptop. I'm
printing to a Brother MFC-7440N printer. The problem I'm having right now
is that I can seem to print anything from windows (word documents, web
pages, text files, etc.) EXCEPT for PDF files. I've tried under Foxit
reader and Adobe PDF. I'm at my wits end here, I've searched a bunch but
not sure why these files are being treated differently.

The frustrating thing is it just seems to fail silently. I've enabled debug
logging and there are a lot of messages but here is an excerpt of what I
think is the important parts of the error_log.

First for the word document that prints fine. I know this is a lot but
there are even more messages:

D [16/Mar/2012:23:55:18 -0600] cupsdAcceptClient: 16 from localhost (Domain)
D [16/Mar/2012:23:55:18 -0600] cupsdReadClient: 16 POST /printers/MFC7440N
HTTP/1.1
D [16/Mar/2012:23:55:18 -0600] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [16/Mar/2012:23:55:18 -0600] cupsdAuthorize: No authentication data
provided.
D [16/Mar/2012:23:55:18 -0600] cupsdReadClient: 16 1.1 Print-Job 1
D [16/Mar/2012:23:55:18 -0600] Print-Job ipp://localhost/printers/MFC7440N
D [16/Mar/2012:23:55:18 -0600] [Job ???] Auto-typing file...
I [16/Mar/2012:23:55:18 -0600] [Job ???] Request file type is
application/vnd.cups-raw.
D [16/Mar/2012:23:55:18 -0600] cupsdMarkDirty(----J-)
D [16/Mar/2012:23:55:18 -0600] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Active clients"
D [16/Mar/2012:23:55:18 -0600] add_job: requesting-user-name="eric"
D [16/Mar/2012:23:55:18 -0600] Adding default job-sheets values
"none,none"...
I [16/Mar/2012:23:55:18 -0600] [Job 370] Adding start banner page "none".
D [16/Mar/2012:23:55:18 -0600] Notifier dbus started - PID = 7743
D [16/Mar/2012:23:55:18 -0600] Notifier dbus started - PID = 7744
D [16/Mar/2012:23:55:18 -0600] cupsdMarkDirty(-----S)
D [16/Mar/2012:23:55:18 -0600] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Active clients and dirty files"
D [16/Mar/2012:23:55:18 -0600] cupsdMarkDirty(----J-)
D [16/Mar/2012:23:55:18 -0600] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Active clients and dirty files"
I [16/Mar/2012:23:55:18 -0600] [Job 370] Adding end banner page "none".
I [16/Mar/2012:23:55:18 -0600] [Job 370] File of type
application/vnd.cups-raw queued by "eric".
D [16/Mar/2012:23:55:18 -0600] [Job 370] hold_until=0
I [16/Mar/2012:23:55:18 -0600] [Job 370] Queued on "MFC7440N" by "eric".
D [16/Mar/2012:23:55:18 -0600] cupsdMarkDirty(----J-)
D [16/Mar/2012:23:55:18 -0600] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Active clients and dirty files"
D [16/Mar/2012:23:55:18 -0600] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Active clients and dirty files"
D [16/Mar/2012:23:55:18 -0600] cupsdMarkDirty(-----S)
D [16/Mar/2012:23:55:18 -0600] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Active clients and dirty files"
D [16/Mar/2012:23:55:18 -0600] [Job 370] job-sheets=none,none
D [16/Mar/2012:23:55:18 -0600] [Job 370] argv[0]="MFC7440N"
D [16/Mar/2012:23:55:18 -0600] [Job 370] argv[1]="370"
D [16/Mar/2012:23:55:18 -0600] [Job 370] argv[2]="eric"
D [16/Mar/2012:23:55:18 -0600] [Job 370] argv[3]="smbprn.00000359 NTLesson
9"
D [16/Mar/2012:23:55:18 -0600] [Job 370] argv[4]="1"
D [16/Mar/2012:23:55:18 -0600] [Job 370]
argv[5]="job-originating-host-name=192.168.1.7
job-uuid=urn:uuid:6eaab091-68b2-3701-6f2b-21ee124ebfd6
time-at-creation=1331963718 time-at-processing=1331963718 AP_D_InputSlot="
D [16/Mar/2012:23:55:18 -0600] [Job 370]
argv[6]="/var/spool/cups/d00370-001"
D [16/Mar/2012:23:55:18 -0600] [Job 370]
envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [16/Mar/2012:23:55:18 -0600] [Job 370]
envp[1]="CUPS_DATADIR=/usr/share/cups"
D [16/Mar/2012:23:55:18 -0600] [Job 370]
envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [16/Mar/2012:23:55:18 -0600] [Job 370]
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [16/Mar/2012:23:55:18 -0600] [Job 370]
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [16/Mar/2012:23:55:18 -0600] [Job 370]
envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [16/Mar/2012:23:55:18 -0600] [Job 370] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [16/Mar/2012:23:55:18 -0600] [Job 370]
envp[7]="CUPS_STATEDIR=/var/run/cups"
D [16/Mar/2012:23:55:18 -0600] [Job 370] envp[8]="HOME=/var/spool/cups/tmp"
D [16/Mar/2012:23:55:18 -0600] [Job 370]
envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [16/Mar/2012:23:55:18 -0600] [Job 370] envp[10]="SERVER_ADMIN=root at crlsns"
D [16/Mar/2012:23:55:18 -0600] [Job 370] envp[11]="SOFTWARE=CUPS/1.5.2"
D [16/Mar/2012:23:55:18 -0600] [Job 370]
envp[12]="TMPDIR=/var/spool/cups/tmp"
D [16/Mar/2012:23:55:18 -0600] [Job 370] envp[13]="USER=root"
D [16/Mar/2012:23:55:18 -0600] [Job 370]
envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [16/Mar/2012:23:55:18 -0600] [Job 370]
envp[15]="CUPS_ENCRYPTION=IfRequested"
D [16/Mar/2012:23:55:18 -0600] [Job 370] envp[16]="IPP_PORT=631"
D [16/Mar/2012:23:55:18 -0600] [Job 370] envp[17]="CHARSET=utf-8"
D [16/Mar/2012:23:55:18 -0600] [Job 370] envp[18]="LANG=en_US.UTF-8"
D [16/Mar/2012:23:55:18 -0600] [Job 370]
envp[19]="PPD=/etc/cups/ppd/MFC7440N.ppd"
D [16/Mar/2012:23:55:18 -0600] [Job 370] envp[20]="RIP_MAX_CACHE=128m"
D [16/Mar/2012:23:55:18 -0600] [Job 370]
envp[21]="CONTENT_TYPE=application/vnd.cups-raw"
D [16/Mar/2012:23:55:18 -0600] [Job 370]
envp[22]="DEVICE_URI=usb://Brother/MFC-7440N?serial=000K9N498614"
D [16/Mar/2012:23:55:18 -0600] [Job 370] envp[23]="PRINTER_INFO=MFC7440N"
D [16/Mar/2012:23:55:18 -0600] [Job 370] envp[24]="PRINTER_LOCATION="
D [16/Mar/2012:23:55:18 -0600] [Job 370] envp[25]="PRINTER=MFC7440N"
D [16/Mar/2012:23:55:18 -0600] [Job 370]
envp[26]="PRINTER_STATE_REASONS=none"
D [16/Mar/2012:23:55:18 -0600] [Job 370] envp[27]="CUPS_FILETYPE=document"
D [16/Mar/2012:23:55:18 -0600] [Job 370] envp[28]="AUTH_I****"
I [16/Mar/2012:23:55:18 -0600] [Job 370] Started backend
/usr/lib/cups/backend/usb (PID 7745)
D [16/Mar/2012:23:55:18 -0600] cupsdMarkDirty(-----S)
D [16/Mar/2012:23:55:18 -0600] cupsdSetBusyState: newbusy="Active clients,
printing jobs, and dirty files", busy="Active clients and dirty files"

Now for the PDF file that isn't working:

D [17/Mar/2012:00:03:07 -0600] cupsdAcceptClient: 16 from localhost (Domain)
D [17/Mar/2012:00:03:07 -0600] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Mar/2012:00:03:07 -0600] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [17/Mar/2012:00:03:07 -0600] cupsdAuthorize: No authentication data
provided.
D [17/Mar/2012:00:03:07 -0600] cupsdReadClient: 16 1.1 Get-Jobs 1
D [17/Mar/2012:00:03:07 -0600] Get-Jobs ipp://localhost/printers/PDF
D [17/Mar/2012:00:03:07 -0600] Returning IPP successful-ok for Get-Jobs
(ipp://localhost/printers/PDF) from localhost
D [17/Mar/2012:00:03:07 -0600] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [17/Mar/2012:00:03:07 -0600] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Mar/2012:00:03:07 -0600] cupsdSetBusyState: newbusy="Active clients",
busy="Not busy"
D [17/Mar/2012:00:03:07 -0600] cupsdAuthorize: No authentication data
provided.
D [17/Mar/2012:00:03:07 -0600] cupsdReadClient: 16 1.1
Get-Printer-Attributes 1
D [17/Mar/2012:00:03:07 -0600] Get-Printer-Attributes
ipp://localhost/printers/PDF
D [17/Mar/2012:00:03:07 -0600] Returning IPP successful-ok for
Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost
D [17/Mar/2012:00:03:07 -0600] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients"
D [17/Mar/2012:00:03:07 -0600] cupsdReadClient: 16 WAITING Closing on EOF
D [17/Mar/2012:00:03:07 -0600] cupsdCloseClient: 16
D [17/Mar/2012:00:03:07 -0600] cupsdSetBusyState: newbusy="Not busy",
busy="Not busy"

I tried searching but these terms are sufficiently generic that I'm
struggling to find useful info.

-- Eric C.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.cups.org/pipermail/cups/attachments/20120316/864272ec/attachment-0001.html>