The CUPS log mentions that the cups-pdf backend crashed with error 11,
while the CUPS-PDF log says that it succesfully printed the file.
Contradicting. Could you try the following steps:
1. sudo /etc/init.d/cups stop
2. sudo rm -r /var/log/cups/*
3. Uncomment LogType in /etc/cups/cups-pdf.conf and change its value
to 7 then save the file.
4. sudo /etc/init.d/cups start
5. Print one sample document locally.
(re-enable the PDF backend if necessary, if CUPS disabled it in
response to an unexpected situation)
6. Print the exact same document over Samba.
7. sudo /etc/init.d/cups stop
8. e-mail /var/log/cups/cups-pdf_log and /var/log/cups/error_log to
643...@bugs.debian.org again.
Thanks!
Martin-Éric
Hello
I have followed those steps as you asked, trying to print the bbc news
home page. The local one was fine, the one over samba produced an error.
I have attached the logs as asked.
Ben
Fri Sep 30 08:35:49 2011 [DEBUG] switching to new gid (lpadmin)
Fri Sep 30 08:35:49 2011 [DEBUG] initialization finished (v2.5.1)
Fri Sep 30 08:35:49 2011 [DEBUG] user identified (ben)
Fri Sep 30 08:35:49 2011 [DEBUG] output directory name generated
(/home/ben/PDF)
Fri Sep 30 08:35:49 2011 [DEBUG] user information prepared
Fri Sep 30 08:35:49 2011 [DEBUG] spoolfile name created
(/var/spool/cups-pdf/SPOOL/cups2pdf-15265)
Fri Sep 30 08:35:49 2011 [DEBUG] source stream ready
Fri Sep 30 08:35:49 2011 [DEBUG] destination stream ready
(/var/spool/cups-pdf/SPOOL/cups2pdf-15265)
Fri Sep 30 08:35:49 2011 [DEBUG] owner set for spoolfile
(/var/spool/cups-pdf/SPOOL/cups2pdf-15265)
Fri Sep 30 08:35:49 2011 [DEBUG] found beginning of postscript code
(%!PS-Adobe-3.0)
Fri Sep 30 08:35:49 2011 [DEBUG] now extracting postscript code
Fri Sep 30 08:35:49 2011 [DEBUG] found title in ps code ((BBC News - Home))
Fri Sep 30 08:35:52 2011 [DEBUG] found end of postscript code (%%EOF)
Fri Sep 30 08:35:52 2011 [DEBUG] all data written to spoolfile
(/var/spool/cups-pdf/SPOOL/cups2pdf-15265)
Fri Sep 30 08:35:52 2011 [DEBUG] trying to use PS title ((BBC News - Home))
Fri Sep 30 08:35:52 2011 [DEBUG] removing trailing newlines from title ((BBC
News - Home))
Fri Sep 30 08:35:52 2011 [DEBUG] removing enclosing parentheses () from full
title ((BBC News - Home))
Fri Sep 30 08:35:52 2011 [DEBUG] removing special characters from title (BBC
News - Home)
Fri Sep 30 08:35:52 2011 [DEBUG] title successfully retrieved (BBC_News_-_Home)
Fri Sep 30 08:35:52 2011 [DEBUG] input data read from stdin
Fri Sep 30 08:35:52 2011 [DEBUG] output filename created
(/home/ben/PDF/BBC_News_-_Home.pdf)
Fri Sep 30 08:35:52 2011 [DEBUG] ghostscript commandline built (/usr/bin/gs -q
-dCompatibilityLevel=1.4 -dNOPAUSE -dBATCH -dSAFER -sDEVICE=pdfwrite
-sOutputFile="/home/ben/PDF/BBC_News_-_Home.pdf" -dAutoRotatePages=/PageByPage
-dAutoFilterColorImages=false -dColorImageFilter=/FlateEncode
-dPDFSETTINGS=/prepress -c .setpdfwrite -f
/var/spool/cups-pdf/SPOOL/cups2pdf-15265)
Fri Sep 30 08:35:52 2011 [DEBUG] output file unlinked
(/home/ben/PDF/BBC_News_-_Home.pdf)
Fri Sep 30 08:35:52 2011 [DEBUG] TMPDIR set for GhostScript (/var/tmp)
Fri Sep 30 08:35:52 2011 [DEBUG] waiting for child to exit
Fri Sep 30 08:35:52 2011 [DEBUG] entering child process
Fri Sep 30 08:35:52 2011 [DEBUG] GID set for current user
Fri Sep 30 08:35:52 2011 [DEBUG] supplementary groups set for current user
Fri Sep 30 08:35:52 2011 [DEBUG] UID set for current user (ben)
Fri Sep 30 08:35:54 2011 [DEBUG] ghostscript has finished (0)
Fri Sep 30 08:35:54 2011 [DEBUG] file mode set for user output
(/home/ben/PDF/BBC_News_-_Home.pdf)
Fri Sep 30 08:35:54 2011 [DEBUG] no postprocessing
Fri Sep 30 08:35:54 2011 [DEBUG] spoolfile unlinked
(/var/spool/cups-pdf/SPOOL/cups2pdf-15265)
Fri Sep 30 08:35:54 2011 [DEBUG] all memory has been freed
Fri Sep 30 08:35:54 2011 [STATUS] PDF creation successfully finished (ben)
Fri Sep 30 08:40:01 2011 [DEBUG] switching to new gid (lpadmin)
Fri Sep 30 08:40:01 2011 [DEBUG] initialization finished (v2.5.1)
W [30/Sep/2011:08:35:32 +0100] No limit for Cancel-Jobs defined in policy
default - using Pause-Printer's policy.
W [30/Sep/2011:08:35:32 +0100] No limit for Cancel-My-Jobs defined in policy
default - using Send-Document's policy.
W [30/Sep/2011:08:35:32 +0100] No limit for Close-Job defined in policy default
- using Send-Document's policy.
W [30/Sep/2011:08:35:32 +0100] No JobPrivateAccess defined in policy default -
using defaults.
W [30/Sep/2011:08:35:32 +0100] No JobPrivateValues defined in policy default -
using defaults.
W [30/Sep/2011:08:35:32 +0100] No SubscriptionPrivateAccess defined in policy
default - using defaults.
W [30/Sep/2011:08:35:32 +0100] No SubscriptionPrivateValues defined in policy
default - using defaults.
W [30/Sep/2011:08:35:32 +0100] No limit for Cancel-Jobs defined in policy
authenticated - using Pause-Printer's policy.
W [30/Sep/2011:08:35:32 +0100] No limit for Cancel-My-Jobs defined in policy
authenticated - using Send-Document's policy.
W [30/Sep/2011:08:35:32 +0100] No limit for Close-Job defined in policy
authenticated - using Send-Document's policy.
W [30/Sep/2011:08:35:32 +0100] No JobPrivateAccess defined in policy
authenticated - using defaults.
W [30/Sep/2011:08:35:32 +0100] No JobPrivateValues defined in policy
authenticated - using defaults.
W [30/Sep/2011:08:35:32 +0100] No SubscriptionPrivateAccess defined in policy
authenticated - using defaults.
W [30/Sep/2011:08:35:32 +0100] No SubscriptionPrivateValues defined in policy
authenticated - using defaults.
W [30/Sep/2011:08:35:32 +0100] no access to
/System/Library/ColorSync/Profiles/sRGB Profile.icc
W [30/Sep/2011:08:35:32 +0100] no access to
/System/Library/ColorSync/Profiles/sRGB Profile.icc
W [30/Sep/2011:08:35:32 +0100] no access to
/System/Library/ColorSync/Profiles/Generic CMYK Profile.icc
E [30/Sep/2011:08:40:01 +0100] PID 15434 (/usr/lib/cups/backend/cups-pdf)
crashed on signal 11.
E [30/Sep/2011:08:40:01 +0100] Failed to update TXT record for PDF @ thor: -2
D [30/Sep/2011:08:40:01 +0100] [Job 84] The following messages were recorded
from 08:40:01 to 08:40:01
D [30/Sep/2011:08:40:01 +0100] [Job 84] Adding start banner page "none".
D [30/Sep/2011:08:40:01 +0100] [Job 84] Adding end banner page "none".
D [30/Sep/2011:08:40:01 +0100] [Job 84] File of type application/postscript
queued by "whyteb".
D [30/Sep/2011:08:40:01 +0100] [Job 84] hold_until=0
D [30/Sep/2011:08:40:01 +0100] [Job 84] Queued on "PDF" by "whyteb".
D [30/Sep/2011:08:40:01 +0100] [Job 84] job-sheets=none,none
D [30/Sep/2011:08:40:01 +0100] [Job 84] argv[0]="PDF"
D [30/Sep/2011:08:40:01 +0100] [Job 84] argv[1]="84"
D [30/Sep/2011:08:40:01 +0100] [Job 84] argv[2]="whyteb"
D [30/Sep/2011:08:40:01 +0100] [Job 84] argv[3]="BBC News - Home"
D [30/Sep/2011:08:40:01 +0100] [Job 84] argv[4]="1"
D [30/Sep/2011:08:40:01 +0100] [Job 84] argv[5]="InstalledMemory=12Meg
JobTimeout=0 MediaType=Plain noOptionalCassette1 OutputBin=OnlyOne
OutputMode=Normal PageSize=A4 Resolution=600dpi noTraySwitch WaitTimeout=300
job-uuid=urn:uuid:25b0721b-deb0-3688-4f85-2038dfb08189
job-originating-host-name=192.168.0.8 time-at-creation=1317368401
time-at-processing=1317368401 AP_D_InputSlot="
D [30/Sep/2011:08:40:01 +0100] [Job 84] argv[6]="/var/spool/cups/d00084-001"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [30/Sep/2011:08:40:01 +0100] [Job 84]
envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [30/Sep/2011:08:40:01 +0100] [Job 84]
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [30/Sep/2011:08:40:01 +0100] [Job 84]
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[8]="HOME=/var/spool/cups/tmp"
D [30/Sep/2011:08:40:01 +0100] [Job 84]
envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[10]="SERVER_ADMIN=root@thor"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[11]="SOFTWARE=CUPS/1.5.0"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[13]="TZ=Europe/London"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[14]="USER=root"
D [30/Sep/2011:08:40:01 +0100] [Job 84]
envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[17]="IPP_PORT=631"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[18]="CHARSET=utf-8"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[19]="LANG=en_US.UTF-8"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[20]="PPD=/etc/cups/ppd/PDF.ppd"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[21]="RIP_MAX_CACHE=128m"
D [30/Sep/2011:08:40:01 +0100] [Job 84]
envp[22]="CONTENT_TYPE=application/postscript"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[23]="DEVICE_URI=cups-pdf:/"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[24]="PRINTER_INFO=PDF"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[25]="PRINTER_LOCATION="
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[26]="PRINTER=PDF"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[27]="PRINTER_STATE_REASONS=none"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[28]="CUPS_FILETYPE=document"
D [30/Sep/2011:08:40:01 +0100] [Job 84]
envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
D [30/Sep/2011:08:40:01 +0100] [Job 84] envp[30]="AUTH_I****"
D [30/Sep/2011:08:40:01 +0100] [Job 84] Started filter
/usr/lib/cups/filter/pstops (PID 15433)
D [30/Sep/2011:08:40:01 +0100] [Job 84] Started backend
/usr/lib/cups/backend/cups-pdf (PID 15434)
D [30/Sep/2011:08:40:01 +0100] [Job 84] Page = 595x842; 0,0 to 595,842
D [30/Sep/2011:08:40:01 +0100] [Job 84] slow_collate=0, slow_duplex=0,
slow_order=0
D [30/Sep/2011:08:40:01 +0100] [Job 84] Before copy_comments - %!PS-Adobe-3.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] %!PS-Adobe-3.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] %%Title: BBC News - Home
D [30/Sep/2011:08:40:01 +0100] [Job 84] %%Creator: PScript5.dll Version 5.2.2
D [30/Sep/2011:08:40:01 +0100] [Job 84] %%CreationDate: 9/30/2011 8:39:58
D [30/Sep/2011:08:40:01 +0100] [Job 84] %%For: whyteb
D [30/Sep/2011:08:40:01 +0100] [Job 84] %%BoundingBox: (atend)
D [30/Sep/2011:08:40:01 +0100] [Job 84] %%Pages: (atend)
D [30/Sep/2011:08:40:01 +0100] [Job 84] %%Orientation: Portrait
D [30/Sep/2011:08:40:01 +0100] [Job 84] %%PageOrder: Special
D [30/Sep/2011:08:40:01 +0100] [Job 84] %%DocumentNeededResources: (atend)
D [30/Sep/2011:08:40:01 +0100] [Job 84] %%DocumentSuppliedResources: (atend)
D [30/Sep/2011:08:40:01 +0100] [Job 84] %%DocumentData: Clean7Bit
D [30/Sep/2011:08:40:01 +0100] [Job 84] %%TargetDevice: (Color LaserWriter
12/600 PS) (2014.108) 1
D [30/Sep/2011:08:40:01 +0100] [Job 84] %%LanguageLevel: 2
D [30/Sep/2011:08:40:01 +0100] [Job 84] %%EndComments
D [30/Sep/2011:08:40:01 +0100] [Job 84] Before copy_prolog -
D [30/Sep/2011:08:40:01 +0100] [Job 84] Before copy_setup -
D [30/Sep/2011:08:40:01 +0100] [Job 84] Before page loop - %%Page: 1 1
D [30/Sep/2011:08:40:01 +0100] [Job 84] Copying page 1...
D [30/Sep/2011:08:40:01 +0100] [Job 84] PAGE: 1 1
D [30/Sep/2011:08:40:01 +0100] [Job 84] pagew = 595.0, pagel = 842.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] bboxx = 0, bboxy = 0, bboxw = 595,
bboxl = 842
D [30/Sep/2011:08:40:01 +0100] [Job 84] PageLeft = 0.0, PageRight = 595.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] PageTop = 842.0, PageBottom = 0.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] PageWidth = 595.0, PageLength = 842.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] Copying page 2...
D [30/Sep/2011:08:40:01 +0100] [Job 84] PAGE: 2 1
D [30/Sep/2011:08:40:01 +0100] [Job 84] pagew = 595.0, pagel = 842.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] bboxx = 0, bboxy = 0, bboxw = 595,
bboxl = 842
D [30/Sep/2011:08:40:01 +0100] [Job 84] PageLeft = 0.0, PageRight = 595.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] PageTop = 842.0, PageBottom = 0.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] PageWidth = 595.0, PageLength = 842.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] Copying page 3...
D [30/Sep/2011:08:40:01 +0100] [Job 84] PAGE: 3 1
D [30/Sep/2011:08:40:01 +0100] [Job 84] pagew = 595.0, pagel = 842.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] bboxx = 0, bboxy = 0, bboxw = 595,
bboxl = 842
D [30/Sep/2011:08:40:01 +0100] [Job 84] PageLeft = 0.0, PageRight = 595.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] PageTop = 842.0, PageBottom = 0.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] PageWidth = 595.0, PageLength = 842.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] Copying page 4...
D [30/Sep/2011:08:40:01 +0100] [Job 84] PAGE: 4 1
D [30/Sep/2011:08:40:01 +0100] [Job 84] pagew = 595.0, pagel = 842.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] bboxx = 0, bboxy = 0, bboxw = 595,
bboxl = 842
D [30/Sep/2011:08:40:01 +0100] [Job 84] PageLeft = 0.0, PageRight = 595.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] PageTop = 842.0, PageBottom = 0.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] PageWidth = 595.0, PageLength = 842.0
D [30/Sep/2011:08:40:01 +0100] [Job 84] Wrote 4 pages...
D [30/Sep/2011:08:40:01 +0100] [Job 84] Backend returned status -11 (crashed)
D [30/Sep/2011:08:40:01 +0100] [Job 84] Printer stopped due to backend errors;
please consult the error_log file for details.
D [30/Sep/2011:08:40:01 +0100] [Job 84] End of messages
D [30/Sep/2011:08:40:01 +0100] [Job 84] printer-state=5(stopped)
D [30/Sep/2011:08:40:01 +0100] [Job 84]
printer-state-message="/usr/lib/cups/backend/cups-pdf failed"
D [30/Sep/2011:08:40:01 +0100] [Job 84] printer-state-reasons=paused
