Bug#712015: cups-filters: cups-pdf produces ugly pdfs through its pixelated font

Sorry, it seems the attached "error_log" file was silently removed by gmail. It's pasted here:

+++++++++++++++++++++++++++++++++++++++++++++++++++++++++++

D [13/Jun/2013:10:44:42 +0800] [Client 14] Accepted from localhost (Domain)

D [13/Jun/2013:10:44:42 +0800] [Job 86] Unloading...

D [13/Jun/2013:10:44:42 +0800] [Job 87] Unloading...

D [13/Jun/2013:10:44:42 +0800] [Job 88] Unloading...

D [13/Jun/2013:10:44:42 +0800] [Job 89] Unloading...

D [13/Jun/2013:10:44:42 +0800] [Job 90] Unloading...

D [13/Jun/2013:10:44:42 +0800] [Job 91] Unloading...

D [13/Jun/2013:10:44:42 +0800] [Job 92] Unloading...

D [13/Jun/2013:10:44:42 +0800] Report: clients=1

D [13/Jun/2013:10:44:42 +0800] Report: jobs=101

D [13/Jun/2013:10:44:42 +0800] Report: jobs-active=7

D [13/Jun/2013:10:44:42 +0800] Report: printers=1

D [13/Jun/2013:10:44:42 +0800] Report: stringpool-string-count=1362

D [13/Jun/2013:10:44:42 +0800] Report: stringpool-alloc-bytes=10440

D [13/Jun/2013:10:44:42 +0800] Report: stringpool-total-bytes=23792

D [13/Jun/2013:10:44:42 +0800] [Client 14] POST / HTTP/1.1

D [13/Jun/2013:10:44:42 +0800] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"

D [13/Jun/2013:10:44:42 +0800] [Client 14] No authentication data provided.

D [13/Jun/2013:10:44:42 +0800] [Client 14] 2.0 CUPS-Get-Default 1

D [13/Jun/2013:10:44:42 +0800] CUPS-Get-Default

D [13/Jun/2013:10:44:42 +0800] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost

D [13/Jun/2013:10:44:42 +0800] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"

D [13/Jun/2013:10:44:42 +0800] [Client 14] POST /printers/PDF HTTP/1.1

D [13/Jun/2013:10:44:42 +0800] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"

D [13/Jun/2013:10:44:42 +0800] [Client 14] No authentication data provided.

D [13/Jun/2013:10:44:42 +0800] [Client 14] 2.0 Create-Job 2

D [13/Jun/2013:10:44:42 +0800] Create-Job ipp://localhost:631/printers/PDF

D [13/Jun/2013:10:44:42 +0800] cupsdMarkDirty(---J-)

D [13/Jun/2013:10:44:42 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"

D [13/Jun/2013:10:44:42 +0800] add_job: requesting-user-name="wx672"

I [13/Jun/2013:10:44:42 +0800] [Job 102] Adding start banner page "none".

D [13/Jun/2013:10:44:42 +0800] Discarding unused job-created event...

I [13/Jun/2013:10:44:42 +0800] [Job 102] Queued on "PDF" by "wx672".

D [13/Jun/2013:10:44:42 +0800] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/PDF) from localhost

D [13/Jun/2013:10:44:42 +0800] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"

D [13/Jun/2013:10:44:42 +0800] [Client 14] POST /printers/PDF HTTP/1.1

D [13/Jun/2013:10:44:42 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"

D [13/Jun/2013:10:44:42 +0800] [Client 14] No authentication data provided.

D [13/Jun/2013:10:44:42 +0800] [Client 14] 2.0 Send-Document 3

D [13/Jun/2013:10:44:42 +0800] Send-Document ipp://localhost:631/printers/PDF

D [13/Jun/2013:10:44:42 +0800] cupsdIsAuthorized: requesting-user-name="wx672"

D [13/Jun/2013:10:44:42 +0800] [Job 102] Auto-typing file...

D [13/Jun/2013:10:44:42 +0800] [Job 102] Request file type is application/postscript.

D [13/Jun/2013:10:44:42 +0800] cupsdMarkDirty(---J-)

D [13/Jun/2013:10:44:42 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"

I [13/Jun/2013:10:44:42 +0800] [Job 102] File of type application/postscript queued by "wx672".

I [13/Jun/2013:10:44:42 +0800] [Job 102] Adding end banner page "none".

D [13/Jun/2013:10:44:42 +0800] cupsdMarkDirty(---J-)

D [13/Jun/2013:10:44:42 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"

D [13/Jun/2013:10:44:42 +0800] [Job 102] time-at-processing=1371091482

D [13/Jun/2013:10:44:42 +0800] cupsdMarkDirty(---J-)

D [13/Jun/2013:10:44:42 +0800] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"

D [13/Jun/2013:10:44:42 +0800] Discarding unused printer-state-changed event...

D [13/Jun/2013:10:44:42 +0800] [Job 102] job-sheets=none,none

D [13/Jun/2013:10:44:42 +0800] [Job 102] argv[0]="PDF"

D [13/Jun/2013:10:44:42 +0800] [Job 102] argv[1]="102"

D [13/Jun/2013:10:44:42 +0800] [Job 102] argv[2]="wx672"

D [13/Jun/2013:10:44:42 +0800] [Job 102] argv[3]="(stdin)"

D [13/Jun/2013:10:44:42 +0800] [Job 102] argv[4]="1"

D [13/Jun/2013:10:44:42 +0800] [Job 102] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:64ec9b0f-5ba6-3316-5624-4a4cd8472f1d job-originating-host-name=localhost time-at-creation=1371091482 time-at-processing=1371091482"

D [13/Jun/2013:10:44:42 +0800] [Job 102] argv[6]="/var/spool/cups/d00102-001"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[0]="CUPS_CACHEDIR=/var/cache/cups"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[1]="CUPS_DATADIR=/usr/share/cups"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[6]="CUPS_SERVERROOT=/etc/cups"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[7]="CUPS_STATEDIR=/var/run/cups"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[8]="HOME=/var/spool/cups/tmp"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[10]="SERVER_ADMIN=root@debian"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[11]="SOFTWARE=CUPS/1.6.2"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[12]="TMPDIR=/var/spool/cups/tmp"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[13]="TZ=Asia/Shanghai"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[14]="USER=root"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[15]="CUPS_MAX_MESSAGE=2047"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[16]="CUPS_SERVER=/var/run/cups/cups.sock"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[17]="CUPS_ENCRYPTION=IfRequested"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[18]="IPP_PORT=631"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[19]="CHARSET=utf-8"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[20]="LANG=en_US.UTF-8"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[21]="PPD=/etc/cups/ppd/PDF.ppd"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[22]="RIP_MAX_CACHE=128m"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[23]="CONTENT_TYPE=application/postscript"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[24]="DEVICE_URI=cups-pdf:/"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[25]="PRINTER_INFO=PDF"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[26]="PRINTER_LOCATION="

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[27]="PRINTER=PDF"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[28]="PRINTER_STATE_REASONS=none"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[29]="CUPS_FILETYPE=document"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[30]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"

D [13/Jun/2013:10:44:42 +0800] [Job 102] envp[31]="AUTH_I****"

I [13/Jun/2013:10:44:42 +0800] [Job 102] Started filter /usr/lib/cups/filter/pstopdf (PID 6692)

I [13/Jun/2013:10:44:42 +0800] [Job 102] Started filter /usr/lib/cups/filter/pdftopdf (PID 6693)

I [13/Jun/2013:10:44:42 +0800] [Job 102] Started filter /usr/lib/cups/filter/pdftops (PID 6694)

I [13/Jun/2013:10:44:42 +0800] [Job 102] Started backend /usr/lib/cups/backend/cups-pdf (PID 6695)

D [13/Jun/2013:10:44:42 +0800] Discarding unused job-state-changed event...

D [13/Jun/2013:10:44:42 +0800] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/PDF) from localhost

D [13/Jun/2013:10:44:42 +0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files"

D [13/Jun/2013:10:44:42 +0800] [Client 14] HTTP_WAITING Closing on EOF

D [13/Jun/2013:10:44:42 +0800] [Client 14] Closing connection.

D [13/Jun/2013:10:44:42 +0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"

D [13/Jun/2013:10:44:42 +0800] [Job 102] pdftops - copying to temp print file "/var/spool/cups/tmp/01a2651c6f2f2"

D [13/Jun/2013:10:44:42 +0800] [Job 102] pstopdf 6 args: 102 wx672 (stdin) 1 finishings=3 number-up=1 job-uuid=urn:uuid:64ec9b0f-5ba6-3316-5624-4a4cd8472f1d job-originating-host-name=localhost time-at-creation=1371091482 time-at-processing=1371091482 /var/spool/cups/d00102-001

D [13/Jun/2013:10:44:42 +0800] [Job 102] PPD: /etc/cups/ppd/PDF.ppd

D [13/Jun/2013:10:44:42 +0800] [Job 102] Resolution: 300

D [13/Jun/2013:10:44:42 +0800] [Job 102] Page size: Letter

D [13/Jun/2013:10:44:42 +0800] [Job 102] Width: 612, height: 792, absolute margins: 0, 0, 612, 792

D [13/Jun/2013:10:44:42 +0800] [Job 102] Relative margins: 0, 0, 0, 0

D [13/Jun/2013:10:44:42 +0800] [Job 102] PPD options: -r300 -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792

D [13/Jun/2013:10:44:42 +0800] [Job 102] PostScript to be injected:

D [13/Jun/2013:10:44:42 +0800] [Job 102] Running cat | /usr/bin/gs -q -dNOPAUSE -dBATCH -sDEVICE=pdfwrite -dCompatibilityLevel=1.3 -dAutoRotatePages=/None -dAutoFilterColorImages=false -dNOPLATFONTS -dPARANOIDSAFER -dNOINTERPOLATE -sstdout=%stderr -dColorImageFilter=/FlateEncode -dPDFSETTINGS=/printer -dColorConversionStrategy=/LeaveColorUnchanged -dDoNumCopies -r300 -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -sOutputFile=- -c .setpdfwrite -f -

D [13/Jun/2013:10:44:43 +0800] [Job 102] PID 6692 (/usr/lib/cups/filter/pstopdf) exited with no errors.

D [13/Jun/2013:10:44:43 +0800] [Job 102] PID 6693 (/usr/lib/cups/filter/pdftopdf) exited with no errors.

D [13/Jun/2013:10:44:43 +0800] [Job 102] Using image rendering resolution 300 dpi

D [13/Jun/2013:10:44:43 +0800] [Job 102] Started filter gs (PID 6727)

D [13/Jun/2013:10:44:43 +0800] [Job 102] Started filter pstops (PID 6728)

D [13/Jun/2013:10:44:43 +0800] [Job 102] Page = 612x792; 0,0 to 612,792

D [13/Jun/2013:10:44:43 +0800] [Job 102] slow_collate=0, slow_duplex=0, slow_order=0

D [13/Jun/2013:10:44:43 +0800] [Job 102] Before copy_comments - %!PS-Adobe-3.0

D [13/Jun/2013:10:44:43 +0800] [Job 102] %!PS-Adobe-3.0

D [13/Jun/2013:10:44:43 +0800] [Job 102] %%BoundingBox: 0 0 612 792

D [13/Jun/2013:10:44:43 +0800] [Job 102] %%Creator: GPL Ghostscript 905 (ps2write)

D [13/Jun/2013:10:44:43 +0800] [Job 102] %%LanguageLevel: 2

D [13/Jun/2013:10:44:43 +0800] [Job 102] %%CreationDate: D:20130613104443+08'00'

D [13/Jun/2013:10:44:43 +0800] [Job 102] %%Pages: 1

D [13/Jun/2013:10:44:43 +0800] [Job 102] %%EndComments

D [13/Jun/2013:10:44:43 +0800] [Job 102] Before copy_prolog - %%BeginProlog

D [13/Jun/2013:10:44:43 +0800] [Job 102] PID 6727 (gs) exited with no errors.

D [13/Jun/2013:10:44:43 +0800] [Job 102] Before copy_setup - %%Page: 1 1

D [13/Jun/2013:10:44:43 +0800] [Job 102] Before page loop - %%Page: 1 1

D [13/Jun/2013:10:44:43 +0800] [Job 102] Copying page 1...

D [13/Jun/2013:10:44:43 +0800] [Job 102] PAGE: 1 1

D [13/Jun/2013:10:44:43 +0800] Discarding unused job-progress event...

D [13/Jun/2013:10:44:43 +0800] [Job 102] pagew = 612.0, pagel = 792.0

D [13/Jun/2013:10:44:43 +0800] [Job 102] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792

D [13/Jun/2013:10:44:43 +0800] [Job 102] PageLeft = 0.0, PageRight = 612.0

D [13/Jun/2013:10:44:43 +0800] [Job 102] PageTop = 792.0, PageBottom = 0.0

D [13/Jun/2013:10:44:43 +0800] [Job 102] PageWidth = 612.0, PageLength = 792.0

D [13/Jun/2013:10:44:43 +0800] [Job 102] Wrote 1 pages...

D [13/Jun/2013:10:44:43 +0800] [Job 102] PID 6728 (pstops) exited with no errors.

D [13/Jun/2013:10:44:43 +0800] [Job 102] PID 6694 (/usr/lib/cups/filter/pdftops) exited with no errors.

D [13/Jun/2013:10:44:43 +0800] [Job 102] PID 6695 (/usr/lib/cups/backend/cups-pdf) exited with no errors.

D [13/Jun/2013:10:44:43 +0800] [Job 102] time-at-completed=1371091483

D [13/Jun/2013:10:44:43 +0800] Discarding unused job-completed event...

I [13/Jun/2013:10:44:43 +0800] [Job 102] Job completed.

D [13/Jun/2013:10:44:43 +0800] cupsdMarkDirty(---J-)

D [13/Jun/2013:10:44:43 +0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"

D [13/Jun/2013:10:44:43 +0800] Discarding unused printer-state-changed event...

D [13/Jun/2013:10:44:43 +0800] cupsdMarkDirty(---J-)

D [13/Jun/2013:10:44:43 +0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"

D [13/Jun/2013:10:44:43 +0800] cupsdMarkDirty(---J-)

D [13/Jun/2013:10:44:43 +0800] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"

D [13/Jun/2013:10:44:44 +0800] [Job 102] Unloading...

I [13/Jun/2013:10:45:13 +0800] Saving job.cache...

D [13/Jun/2013:10:45:14 +0800] cupsdSetBusyState: newbusy="Not busy", busy="Printing jobs and dirty files"

++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++

On Thu, Jun 13, 2013 at 10:41 AM, 王晓林 <wx672ster@gmail.com> wrote:

As root:

cupsctl --debug-logging

echo '' > /var/log/cups/error_log

Then print from emacs. Please attach the error_log to a mail to the bug.

A freshly generated error_log file is attached within this email;

Also say what printer you are using and what PPD it uses. You may also
attach the /etc/cups/ppd/your_printer.ppd.

I don't use physical printer. I just need CUPS to produce PDF outputs for me. In my /etc/cups/ppd/ directory there is only a PDF.ppd file which is attached within this email as well.

It seems that by default cups-ppd use postscript 2 level only (see /usr/share/ppd/cups-pdf/CUPS-PDF.ppd)

Could you try to replace LanguageLevel: "2" by LanguageLevel : "3"

I found there is a line "*LanguageLevel: "2" in /etc/cups/ppd/PDF.ppd. I change it to "3". And then
"M-x ps-print-buffer-with-face" within emacs. The output PDF is the same, nothing improved.

I did a "sudo /etc/init.d/cups restart", then did "M-x ps-print-buffer-with-face" again, and still saw nothing changed.
I changed /usr/share/ppd/cups-pdf/CUPS-PDF.ppd as Bastien instructed, "2" -> "3". Then again "M-x ps-print-buffer-with-face", still got the same.

Still the same after a "sudo /etc/init.d/cups restart".
Thanks for helping!