I have absolutely no idea what could be causing this one. I upgraded our server to Prince 7 (RHEL4) and that all went well. Now when I attempt to use Arial in a document, it spits out Times New Roman (the same document rendered properly with 6). Every other font works fine.

I was assuming that maybe there was an error in my CSS, but if I run the same document/CSS through Prince 7 on Windows, it renders properly.

This is (some of) the output I get when running from the command line... doesn't have any errors or any mention of Arial at all...

prince: Converting document...
prince: used font: Times New Roman, Bold
prince: used font: Garamond, Regular
prince: used font: Times New Roman, Regular
prince: used font: Garamond, Italic
prince: used font: Verdana, Bold
prince: used font: Verdana, Regular
prince: used font: Garamond, Bold
prince: used font: Verdana, Italic
prince: Resolving cross-references...
prince: Finished: success

I installed the fonts by building the corefonts package, and it's been working perfectly fine since March up until today when I upgraded. Does anyone have any ideas?

EDIT: Just downgraded to 6, everything works again.

So Arial is definitely installed, and if you run "fc-list | grep Arial" it shows up in the Fontconfig output? Can you try rerunning your document from the command-line with the --debug flag and post the log output?

Response from fc-list:

Arial:style=Regular
Arial Black:style=Regular
Arial:style=Bold Italic
Arial:style=Italic
Arial:style=Bold

And here's the debug output:

Mon Nov  9 15:11:40 2009: ---- begin
Mon Nov  9 15:11:40 2009: debug: loading license: /usr/lib/prince/license/license.dat
Mon Nov  9 15:11:40 2009: loading style sheet: /usr/lib/prince/style/fonts.css
Mon Nov  9 15:11:40 2009: debug: loaded resource: /usr/lib/prince/style/fonts.css
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: no
Mon Nov  9 15:11:40 2009: Loading document...
Mon Nov  9 15:11:40 2009: loading XML input: peanuts.html
Mon Nov  9 15:11:40 2009: debug: loaded document: peanuts.html
Mon Nov  9 15:11:40 2009: debug: sniffed doctype: XHTML
Mon Nov  9 15:11:40 2009: loading style sheet: /usr/lib/prince/style/xhtml-ns.css
Mon Nov  9 15:11:40 2009: debug: loaded resource: /usr/lib/prince/style/xhtml-ns.css
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: no
Mon Nov  9 15:11:40 2009: loading style sheet: /usr/lib/prince/style/common.css
Mon Nov  9 15:11:40 2009: debug: loaded resource: /usr/lib/prince/style/common.css
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: no
Mon Nov  9 15:11:40 2009: loading style sheet: /usr/lib/prince/style/hyph.css
Mon Nov  9 15:11:40 2009: debug: loaded resource: /usr/lib/prince/style/hyph.css
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: no
Mon Nov  9 15:11:40 2009: loading style sheet: /usr/lib/prince/style/xlink.css
Mon Nov  9 15:11:40 2009: debug: loaded resource: /usr/lib/prince/style/xlink.css
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: no
Mon Nov  9 15:11:40 2009: loading style sheet: /usr/lib/prince/style/mathml.css
Mon Nov  9 15:11:40 2009: debug: loaded resource: /usr/lib/prince/style/mathml.css
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: no
Mon Nov  9 15:11:40 2009: loading style sheet: /usr/lib/prince/style/svg.css
Mon Nov  9 15:11:40 2009: debug: loaded resource: /usr/lib/prince/style/svg.css
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: no
Mon Nov  9 15:11:40 2009: loading style sheet: template.css
Mon Nov  9 15:11:40 2009: debug: loaded resource: template.css
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: no
Mon Nov  9 15:11:40 2009: debug: prepack
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/logos/peanuts-500x.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/logos/peanuts-500x.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/logos/capalogo-200x.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/logos/capalogo-200x.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/aircanada1.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/aircanada1.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/aircanada2.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/aircanada2.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/aircanada3.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/aircanada3.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/aircanada4.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/aircanada4.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/westjet1.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/westjet1.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/westjet2.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/westjet2.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/westjet3.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/westjet3.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/westjet4.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/westjet4.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/westjet5.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/westjet5.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/westjet6.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/westjet6.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin1.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin1.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin2.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin2.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin3.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin3.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin4.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin4.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin5.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin5.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/bmibaby.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/bmibaby.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair1.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair1.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair2.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair2.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair3.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair3.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair4.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair4.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair5.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair5.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair6.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair6.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair7.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair7.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc1.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc1.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc2.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc2.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc3.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc3.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc4.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc4.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc5.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc5.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc6.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc6.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc7.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc7.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/02/qantas1.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/02/qantas1.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/02/qantas2.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/02/qantas2.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/02/qantas3.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/02/qantas3.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/02/qantas4.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/02/qantas4.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/02/virgin_blue1.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/02/virgin_blue1.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/02/virgin_blue2.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/02/virgin_blue2.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/go_air.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/go_air.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/spicejet1.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/spicejet1.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/spicejet2.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/spicejet2.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/spicejet3.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/spicejet3.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/spicejet4.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/spicejet4.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:40 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/lcc_report.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/lcc_report.png
Mon Nov  9 15:11:40 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:11:41 2009: Converting document...
Mon Nov  9 15:11:41 2009: debug: pack
Mon Nov  9 15:11:41 2009: debug: font request: Verdana
Mon Nov  9 15:11:41 2009: debug: font scan: Verdana
Mon Nov  9 15:11:41 2009: debug: font scan: Verdana, 4 matches
Mon Nov  9 15:11:41 2009: debug: font scan: Times New Roman
Mon Nov  9 15:11:41 2009: debug: font scan: Times New Roman, 4 matches
Mon Nov  9 15:11:41 2009: debug: font scan: DejaVu Serif
Mon Nov  9 15:11:41 2009: debug: font scan: DejaVu Serif, 0 matches
Mon Nov  9 15:11:41 2009: debug: font scan: OpenSymbol
Mon Nov  9 15:11:41 2009: debug: font scan: OpenSymbol, 0 matches
Mon Nov  9 15:11:41 2009: debug: font scan: AR PL UMing CN
Mon Nov  9 15:11:41 2009: debug: font scan: AR PL UMing CN, 0 matches
Mon Nov  9 15:11:41 2009: debug: font scan: AR PL SungtiL GB
Mon Nov  9 15:11:41 2009: debug: font scan: AR PL SungtiL GB, 1 matches
Mon Nov  9 15:11:41 2009: debug: font scan: Lohit Hindi
Mon Nov  9 15:11:41 2009: debug: font scan: Lohit Hindi, 1 matches
Mon Nov  9 15:11:41 2009: debug: font scan: Ani
Mon Nov  9 15:11:41 2009: debug: font scan: Ani, 0 matches
Mon Nov  9 15:11:41 2009: debug: font scan: Mukti Narrow
Mon Nov  9 15:11:41 2009: debug: font scan: Mukti Narrow, 2 matches
Mon Nov  9 15:11:41 2009: debug: font scan: Lohit Punjabi
Mon Nov  9 15:11:41 2009: debug: font scan: Lohit Punjabi, 1 matches
Mon Nov  9 15:11:41 2009: debug: font scan: Lohit Gujarati
Mon Nov  9 15:11:41 2009: debug: font scan: Lohit Gujarati, 1 matches
Mon Nov  9 15:11:41 2009: debug: font scan: Lohit Tamil
Mon Nov  9 15:11:41 2009: debug: font scan: Lohit Tamil, 1 matches
Mon Nov  9 15:11:41 2009: debug: font scan: Lohit Telugu
Mon Nov  9 15:11:41 2009: debug: font scan: Lohit Telugu, 0 matches
Mon Nov  9 15:11:41 2009: debug: font scan: Lohit Kannada
Mon Nov  9 15:11:41 2009: debug: font scan: Lohit Kannada, 0 matches
Mon Nov  9 15:11:41 2009: debug: font scan: MalOtf
Mon Nov  9 15:11:41 2009: debug: font scan: MalOtf, 0 matches
Mon Nov  9 15:11:41 2009: debug: font scan: Lohit Oriya
Mon Nov  9 15:11:41 2009: debug: font scan: Lohit Oriya, 0 matches
Mon Nov  9 15:11:41 2009: debug: font request: bold Arial
Mon Nov  9 15:11:41 2009: debug: font scan: Arial
Mon Nov  9 15:11:41 2009: debug: font scan: Arial, 4 matches
Mon Nov  9 15:11:41 2009: used font: Arial, Bold
Mon Nov  9 15:11:41 2009: debug: font request: Garamond
Mon Nov  9 15:11:41 2009: debug: font scan: Garamond
Mon Nov  9 15:11:41 2009: debug: font scan: Garamond, 3 matches
Mon Nov  9 15:11:41 2009: used font: Garamond, Regular
Mon Nov  9 15:11:41 2009: debug: font request: small-caps Garamond
Mon Nov  9 15:11:41 2009: debug: font request: bold Garamond
Mon Nov  9 15:11:41 2009: debug: font request: italic Garamond
Mon Nov  9 15:11:41 2009: debug: font request: Times
Mon Nov  9 15:11:41 2009: debug: font scan: Times
Mon Nov  9 15:11:41 2009: debug: font scan: Times, 0 matches
Mon Nov  9 15:11:41 2009: used font: Times New Roman, Regular
Mon Nov  9 15:11:41 2009: used font: Garamond, Italic
Mon Nov  9 15:11:41 2009: debug: font request: bold Verdana
Mon Nov  9 15:11:41 2009: used font: Verdana, Bold
Mon Nov  9 15:11:41 2009: used font: Verdana, Regular
Mon Nov  9 15:11:41 2009: used font: Garamond, Bold
Mon Nov  9 15:11:41 2009: debug: font request: monospace
Mon Nov  9 15:11:41 2009: debug: font scan: Courier New
Mon Nov  9 15:11:41 2009: debug: font scan: Courier New, 4 matches
Mon Nov  9 15:11:41 2009: debug: font scan: DejaVu Sans Mono
Mon Nov  9 15:11:41 2009: debug: font scan: DejaVu Sans Mono, 0 matches
Mon Nov  9 15:11:41 2009: used font: Courier New, Regular
Mon Nov  9 15:11:41 2009: debug: font request: Arial
Mon Nov  9 15:11:41 2009: used font: Arial, Regular
Mon Nov  9 15:11:42 2009: debug: font request: italic Verdana
Mon Nov  9 15:11:42 2009: used font: Verdana, Italic
Mon Nov  9 15:11:42 2009: Resolving cross-references...
Mon Nov  9 15:11:42 2009: debug: pack
Mon Nov  9 15:11:43 2009: debug: writing output: peanuts.pdf
Mon Nov  9 15:11:46 2009: finished: success
Mon Nov  9 15:11:46 2009: ---- end

EDIT: Err... it just started working all of a sudden. This is bizarre. I haven't changed anything at all.

EDIT: Now after running the exact same command again, it's Times New Romaning again.

After trying with the exact same document and command again, I got this:

(same as before regarding images, then...)

prince: Converting document...
prince: debug: pack
prince: debug: font request: Verdana
prince: debug: font scan: Verdana
prince: debug: font scan: Verdana, 0 matches
prince: debug: font scan: Times New Roman
prince: debug: font scan: Times New Roman, 0 matches
prince: debug: font scan: DejaVu Serif
prince: debug: font scan: DejaVu Serif, 0 matches
prince: debug: font scan: OpenSymbol
prince: debug: font scan: OpenSymbol, 0 matches
prince: debug: font scan: AR PL UMing CN
prince: debug: font scan: AR PL UMing CN, 0 matches
prince: debug: font scan: AR PL SungtiL GB
prince: debug: font scan: AR PL SungtiL GB, 0 matches
prince: debug: font scan: Lohit Hindi
prince: debug: font scan: Lohit Hindi, 0 matches
prince: debug: font scan: Ani
prince: debug: font scan: Ani, 0 matches
prince: debug: font scan: Mukti Narrow
prince: debug: font scan: Mukti Narrow, 0 matches
prince: debug: font scan: Lohit Punjabi
prince: debug: font scan: Lohit Punjabi, 0 matches
prince: debug: font scan: Lohit Gujarati
prince: debug: font scan: Lohit Gujarati, 0 matches
prince: debug: font scan: Lohit Tamil
prince: debug: font scan: Lohit Tamil, 0 matches
prince: debug: font scan: Lohit Telugu
prince: debug: font scan: Lohit Telugu, 0 matches
prince: debug: font scan: Lohit Kannada
prince: debug: font scan: Lohit Kannada, 0 matches
prince: debug: font scan: MalOtf
prince: debug: font scan: MalOtf, 0 matches
prince: debug: font scan: Lohit Oriya
prince: debug: font scan: Lohit Oriya, 0 matches
prince: debug: font request: bold Arial
prince: debug: font scan: Arial
prince: debug: font scan: Arial, 0 matches
prince: warning: no glyphs for character U+0049, fallback to '?'
prince: internal error: no fallback glyph for character U+0049

Here's the result of the exact same command (but using Prince 6):

Mon Nov  9 15:37:31 2009: ---- begin
Mon Nov  9 15:37:31 2009: debug: loading license: /usr/lib/prince/license/license.dat
Mon Nov  9 15:37:31 2009: debug: font-family: AR PL KaitiM Big5
Mon Nov  9 15:37:31 2009: debug: font-family: AR PL KaitiM GB
Mon Nov  9 15:37:31 2009: debug: font-family: AR PL Mingti2L Big5
Mon Nov  9 15:37:31 2009: debug: font-family: AR PL SungtiL GB
Mon Nov  9 15:37:31 2009: debug: font-family: Aharoni CLM
Mon Nov  9 15:37:31 2009: debug: font-family: Andale Mono
Mon Nov  9 15:37:31 2009: debug: font-family: Arial
Mon Nov  9 15:37:31 2009: debug: font-family: Arial Black
Mon Nov  9 15:37:31 2009: debug: font-family: Baekmuk Batang
Mon Nov  9 15:37:31 2009: debug: font-family: Baekmuk Dotum
Mon Nov  9 15:37:31 2009: debug: font-family: Baekmuk Gulim
Mon Nov  9 15:37:31 2009: debug: font-family: Baekmuk Headline
Mon Nov  9 15:37:31 2009: debug: font-family: Bitstream Charter
Mon Nov  9 15:37:31 2009: debug: font-family: Caladings CLM
Mon Nov  9 15:37:31 2009: debug: font-family: Century Schoolbook L
Mon Nov  9 15:37:31 2009: debug: font-family: Comic Sans MS
Mon Nov  9 15:37:31 2009: debug: font-family: Courier
Mon Nov  9 15:37:31 2009: debug: font-family: Courier 10 Pitch
Mon Nov  9 15:37:31 2009: debug: font-family: Courier New
Mon Nov  9 15:37:31 2009: debug: font-family: Cursor
Mon Nov  9 15:37:31 2009: debug: font-family: David CLM
Mon Nov  9 15:37:31 2009: debug: font-family: Dingbats
Mon Nov  9 15:37:31 2009: debug: font-family: Drugulin CLM
Mon Nov  9 15:37:31 2009: debug: font-family: Ellinia CLM
Mon Nov  9 15:37:31 2009: debug: font-family: Frank Ruehl CLM
Mon Nov  9 15:37:31 2009: debug: font-family: Garamond
Mon Nov  9 15:37:31 2009: debug: font-family: Garamond Premr Pro
Mon Nov  9 15:37:31 2009: debug: font-family: Garamond Premr Pro Smbd
Mon Nov  9 15:37:31 2009: debug: font-family: Georgia
Mon Nov  9 15:37:31 2009: debug: font-family: Impact
Mon Nov  9 15:37:31 2009: debug: font-family: KacstArt
Mon Nov  9 15:37:31 2009: debug: font-family: KacstBook
Mon Nov  9 15:37:31 2009: debug: font-family: KacstDecorative
Mon Nov  9 15:37:31 2009: debug: font-family: KacstDigital
Mon Nov  9 15:37:31 2009: debug: font-family: KacstFarsi
Mon Nov  9 15:37:31 2009: debug: font-family: KacstLetter
Mon Nov  9 15:37:31 2009: debug: font-family: KacstPoster
Mon Nov  9 15:37:31 2009: debug: font-family: KacstQura
Mon Nov  9 15:37:31 2009: debug: font-family: KacstQuraFixed
Mon Nov  9 15:37:31 2009: debug: font-family: KacstQuran
Mon Nov  9 15:37:31 2009: debug: font-family: KacstTitle
Mon Nov  9 15:37:31 2009: debug: font-family: KacstTitleL
Mon Nov  9 15:37:31 2009: debug: font-family: Kochi Gothic
Mon Nov  9 15:37:31 2009: debug: font-family: Kochi Mincho
Mon Nov  9 15:37:31 2009: debug: font-family: Lohit Bengali
Mon Nov  9 15:37:31 2009: debug: font-family: Lohit Gujarati
Mon Nov  9 15:37:31 2009: debug: font-family: Lohit Hindi
Mon Nov  9 15:37:31 2009: debug: font-family: Lohit Punjabi
Mon Nov  9 15:37:31 2009: debug: font-family: Lohit Tamil
Mon Nov  9 15:37:31 2009: debug: font-family: Luxi Mono
Mon Nov  9 15:37:31 2009: debug: font-family: Luxi Sans
Mon Nov  9 15:37:31 2009: debug: font-family: Luxi Serif
Mon Nov  9 15:37:31 2009: debug: font-family: Miriam CLM
Mon Nov  9 15:37:31 2009: debug: font-family: Miriam Mono CLM
Mon Nov  9 15:37:31 2009: debug: font-family: Mukti Narrow
Mon Nov  9 15:37:31 2009: debug: font-family: Nachlieli CLM
Mon Nov  9 15:37:31 2009: debug: font-family: Nimbus Mono L
Mon Nov  9 15:37:31 2009: debug: font-family: Nimbus Roman No9 L
Mon Nov  9 15:37:31 2009: debug: font-family: Nimbus Sans L
Mon Nov  9 15:37:31 2009: debug: font-family: Nimbus Sans L Condensed
Mon Nov  9 15:37:31 2009: debug: font-family: Standard Symbols L
Mon Nov  9 15:37:31 2009: debug: font-family: Tahoma
Mon Nov  9 15:37:31 2009: debug: font-family: Times New Roman
Mon Nov  9 15:37:31 2009: debug: font-family: Trebuchet MS
Mon Nov  9 15:37:31 2009: debug: font-family: URW Bookman L
Mon Nov  9 15:37:31 2009: debug: font-family: URW Chancery L
Mon Nov  9 15:37:31 2009: debug: font-family: URW Gothic L
Mon Nov  9 15:37:31 2009: debug: font-family: URW Palladio L
Mon Nov  9 15:37:31 2009: debug: font-family: Utopia
Mon Nov  9 15:37:31 2009: debug: font-family: Verdana
Mon Nov  9 15:37:31 2009: debug: font-family: Webdings
Mon Nov  9 15:37:31 2009: debug: font-family: Yehuda CLM
Mon Nov  9 15:37:31 2009: loading style sheet: /usr/lib/prince/style/fonts.css
Mon Nov  9 15:37:31 2009: debug: loaded resource: /usr/lib/prince/style/fonts.css
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: no
Mon Nov  9 15:37:31 2009: loading XML input: peanuts.html
Mon Nov  9 15:37:31 2009: debug: loaded document: peanuts.html
Mon Nov  9 15:37:31 2009: debug: sniffed doctype: XHTML
Mon Nov  9 15:37:31 2009: loading style sheet: /usr/lib/prince/style/xhtml-ns.css
Mon Nov  9 15:37:31 2009: debug: loaded resource: /usr/lib/prince/style/xhtml-ns.css
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: no
Mon Nov  9 15:37:31 2009: loading style sheet: /usr/lib/prince/style/common.css
Mon Nov  9 15:37:31 2009: debug: loaded resource: /usr/lib/prince/style/common.css
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: no
Mon Nov  9 15:37:31 2009: loading style sheet: /usr/lib/prince/style/hyph.css
Mon Nov  9 15:37:31 2009: debug: loaded resource: /usr/lib/prince/style/hyph.css
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: no
Mon Nov  9 15:37:31 2009: loading style sheet: /usr/lib/prince/style/xlink.css
Mon Nov  9 15:37:31 2009: debug: loaded resource: /usr/lib/prince/style/xlink.css
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: no
Mon Nov  9 15:37:31 2009: loading style sheet: /usr/lib/prince/style/mathml.css
Mon Nov  9 15:37:31 2009: debug: loaded resource: /usr/lib/prince/style/mathml.css
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: no
Mon Nov  9 15:37:31 2009: loading style sheet: /usr/lib/prince/style/svg.css
Mon Nov  9 15:37:31 2009: debug: loaded resource: /usr/lib/prince/style/svg.css
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: no
Mon Nov  9 15:37:31 2009: loading style sheet: template.css
Mon Nov  9 15:37:31 2009: debug: loaded resource: template.css
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: no
Mon Nov  9 15:37:31 2009: debug: prepack
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/logos/peanuts-500x.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/logos/peanuts-500x.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/logos/capalogo-200x.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/logos/capalogo-200x.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/aircanada1.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/aircanada1.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/aircanada2.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/aircanada2.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/aircanada3.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/aircanada3.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/aircanada4.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/aircanada4.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/westjet1.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/westjet1.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/westjet2.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/westjet2.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/westjet3.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/westjet3.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/westjet4.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/westjet4.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/westjet5.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/westjet5.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/westjet6.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/westjet6.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin1.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin1.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin2.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin2.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin3.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin3.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin4.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin4.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin5.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/airberlin5.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/bmibaby.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/bmibaby.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair1.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair1.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair2.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair2.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair3.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair3.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair4.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair4.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair5.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair5.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair6.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair6.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair7.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/ryanair7.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc1.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc1.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc2.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc2.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc3.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc3.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc4.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc4.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc5.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc5.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc6.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc6.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc7.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/03/thailcc7.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/02/qantas1.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/02/qantas1.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/02/qantas2.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/02/qantas2.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/02/qantas3.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/02/qantas3.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/02/qantas4.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/02/qantas4.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/02/virgin_blue1.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/02/virgin_blue1.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/02/virgin_blue2.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/02/virgin_blue2.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:31 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/05/go_air.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/05/go_air.png
Mon Nov  9 15:37:31 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:32 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/spicejet1.png
Mon Nov  9 15:37:32 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/spicejet1.png
Mon Nov  9 15:37:32 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:32 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/spicejet2.png
Mon Nov  9 15:37:32 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/spicejet2.png
Mon Nov  9 15:37:32 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:32 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/spicejet3.png
Mon Nov  9 15:37:32 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/spicejet3.png
Mon Nov  9 15:37:32 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:32 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/spicejet4.png
Mon Nov  9 15:37:32 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/spicejet4.png
Mon Nov  9 15:37:32 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:32 2009: loading image: http://www.centreforaviation.com/images/stories/2009/november/04/lcc_report.png
Mon Nov  9 15:37:32 2009: debug: loaded resource: http://www.centreforaviation.com/images/stories/2009/november/04/lcc_report.png
Mon Nov  9 15:37:32 2009: debug: loaded resource: type: yes(resource_type("image/png", [], image(png), no))
Mon Nov  9 15:37:32 2009: debug: ["Arial", "serif"] -> [font_record(font_desc("Arial", [normal], normal, [bold], [normal]), [font_handle(mutvar('<<reference>>'))]), font_record(font_desc("serif", [], normal, [], []), [redirect("Times New Roman"), redirect("Times"), redirect("OpenSymbol"), redirect("DejaVu Serif")])]
Mon Nov  9 15:37:32 2009: used font: Arial, Bold
Mon Nov  9 15:37:32 2009: debug: ["Garamond", "serif"] -> [font_record(font_desc("Garamond", [normal], normal, [normal], [normal]), [font_handle(mutvar('<<reference>>'))]), font_record(font_desc("serif", [], normal, [], []), [redirect("Times New Roman"), redirect("Times"), redirect("OpenSymbol"), redirect("DejaVu Serif")])]
Mon Nov  9 15:37:32 2009: used font: Garamond, Regular
Mon Nov  9 15:37:32 2009: debug: ["Garamond", "serif"] -> [font_record(font_desc("Garamond", [normal], normal, [normal], [normal]), [font_handle(mutvar('<<reference>>'))]), font_record(font_desc("serif", [], normal, [], []), [redirect("Times New Roman"), redirect("Times"), redirect("OpenSymbol"), redirect("DejaVu Serif")])]
Mon Nov  9 15:37:32 2009: debug: ["Verdana", "serif"] -> [font_record(font_desc("Verdana", [normal], normal, [normal], [normal]), [font_handle(mutvar('<<reference>>'))]), font_record(font_desc("serif", [], normal, [], []), [redirect("Times New Roman"), redirect("Times"), redirect("OpenSymbol"), redirect("DejaVu Serif")])]
Mon Nov  9 15:37:32 2009: debug: ["Garamond", "serif"] -> [font_record(font_desc("Garamond", [normal], normal, [bold], [normal]), [font_handle(mutvar('<<reference>>'))]), font_record(font_desc("serif", [], normal, [], []), [redirect("Times New Roman"), redirect("Times"), redirect("OpenSymbol"), redirect("DejaVu Serif")])]
Mon Nov  9 15:37:32 2009: debug: ["Garamond", "serif"] -> [font_record(font_desc("Garamond", [italic], normal, [normal], [normal]), [font_handle(mutvar('<<reference>>'))]), font_record(font_desc("serif", [], normal, [], []), [redirect("Times New Roman"), redirect("Times"), redirect("OpenSymbol"), redirect("DejaVu Serif")])]
Mon Nov  9 15:37:32 2009: debug: ["Times", "serif"] -> [font_record(font_desc("serif", [], normal, [], []), [redirect("Times New Roman"), redirect("Times"), redirect("OpenSymbol"), redirect("DejaVu Serif")])]
Mon Nov  9 15:37:32 2009: debug: ["Times New Roman"] -> [font_record(font_desc("Times New Roman", [normal], normal, [normal], [normal]), [font_handle(mutvar('<<reference>>'))])]
Mon Nov  9 15:37:32 2009: used font: Times New Roman, Regular
Mon Nov  9 15:37:32 2009: used font: Garamond, Italic
Mon Nov  9 15:37:32 2009: debug: ["Verdana", "serif"] -> [font_record(font_desc("Verdana", [normal], normal, [bold], [normal]), [font_handle(mutvar('<<reference>>'))]), font_record(font_desc("serif", [], normal, [], []), [redirect("Times New Roman"), redirect("Times"), redirect("OpenSymbol"), redirect("DejaVu Serif")])]
Mon Nov  9 15:37:32 2009: used font: Verdana, Bold
Mon Nov  9 15:37:32 2009: used font: Verdana, Regular
Mon Nov  9 15:37:32 2009: used font: Garamond, Bold
Mon Nov  9 15:37:32 2009: debug: ["monospace"] -> [font_record(font_desc("monospace", [], normal, [], []), [redirect("Courier New"), redirect("Courier"), redirect("OpenSymbol"), redirect("DejaVu Sans Mono")])]
Mon Nov  9 15:37:32 2009: debug: ["Courier New"] -> [font_record(font_desc("Courier New", [normal], normal, [normal], [normal]), [font_handle(mutvar('<<reference>>'))])]
Mon Nov  9 15:37:32 2009: used font: Courier New, Regular
Mon Nov  9 15:37:32 2009: debug: ["Arial", "serif"] -> [font_record(font_desc("Arial", [normal], normal, [normal], [normal]), [font_handle(mutvar('<<reference>>'))]), font_record(font_desc("serif", [], normal, [], []), [redirect("Times New Roman"), redirect("Times"), redirect("OpenSymbol"), redirect("DejaVu Serif")])]
Mon Nov  9 15:37:32 2009: used font: Arial, Regular
Mon Nov  9 15:37:33 2009: debug: ["Verdana", "serif"] -> [font_record(font_desc("Verdana", [italic], normal, [normal], [normal]), [font_handle(mutvar('<<reference>>'))]), font_record(font_desc("serif", [], normal, [], []), [redirect("Times New Roman"), redirect("Times"), redirect("OpenSymbol"), redirect("DejaVu Serif")])]
Mon Nov  9 15:37:33 2009: used font: Verdana, Italic
Mon Nov  9 15:37:35 2009: debug: writing output: peanuts.pdf
Mon Nov  9 15:37:37 2009: ---- end

So sometimes it uses Arial and everything is fine, sometimes it fails to find Arial and falls back to Times New Roman, and sometimes it fails to find any font at all. How peculiar.

Yep, and I can't get it to bug out with v6 at all.

It could be a Fontconfig library version issue. Can you try installing this updated RPM with a dynamically-linked Prince binary: prince-7.0-1.dynamic.rhel4.i386.rpm

Tried that new RPM, it still seems broken though unfortunately.

Okay, then that eliminates one possible cause of the error. The next step is to check whether it is something specific to this document. Can you reproduce the same problem with a much simpler document, like this:

<html>
<body style="font-family: Arial">
Hello, world!
</body>
</html>

That document seems to work fine. If I run it with --debug though, I get (last lines):

prince: warning: no glyphs for character U+0048, fallback to '?'
prince: internal error: no fallback glyph for character U+0048

Not sure if that's expected or not though.

That really really really shouldn't happen. Can you repeat it a few times and see if it is consistent? It shouldn't be failing to find fonts with --debug and succeeding otherwise, as --debug has no effect on font scanning, only logging.

Yep, it seems to be consistent (re: not working with --debug):

[root@topstation monthlies]# prince test.html

No errors, PDF is put out properly. Immediately after:

[root@topstation monthlies]# prince --debug test.html
prince: debug: loading license: /usr/lib/prince/license/license.dat
prince: loading style sheet: /usr/lib/prince/style/fonts.css
prince: debug: loaded resource: /usr/lib/prince/style/fonts.css
prince: debug: loaded resource: type: no
prince: Loading document...
prince: loading HTML input: test.html
prince: debug: loaded document: test.html
prince: debug: sniffed doctype: XHTML (no namespace)
prince: loading style sheet: /usr/lib/prince/style/xhtml.css
prince: debug: loaded resource: /usr/lib/prince/style/xhtml.css
prince: debug: loaded resource: type: no
prince: loading style sheet: /usr/lib/prince/style/common.css
prince: debug: loaded resource: /usr/lib/prince/style/common.css
prince: debug: loaded resource: type: no
prince: loading style sheet: /usr/lib/prince/style/hyph.css
prince: debug: loaded resource: /usr/lib/prince/style/hyph.css
prince: debug: loaded resource: type: no
prince: loading style sheet: /usr/lib/prince/style/xlink.css
prince: debug: loaded resource: /usr/lib/prince/style/xlink.css
prince: debug: loaded resource: type: no
prince: loading style sheet: /usr/lib/prince/style/mathml.css
prince: debug: loaded resource: /usr/lib/prince/style/mathml.css
prince: debug: loaded resource: type: no
prince: loading style sheet: /usr/lib/prince/style/svg.css
prince: debug: loaded resource: /usr/lib/prince/style/svg.css
prince: debug: loaded resource: type: no
prince: debug: prepack
prince: Converting document...
prince: debug: pack
prince: debug: font request: Arial
prince: debug: font scan: Arial
prince: debug: font scan: Arial, 0 matches
prince: debug: font scan: Times New Roman
prince: debug: font scan: Times New Roman, 0 matches
prince: debug: font scan: DejaVu Serif
prince: debug: font scan: DejaVu Serif, 0 matches
prince: debug: font scan: OpenSymbol
prince: debug: font scan: OpenSymbol, 0 matches
prince: debug: font scan: AR PL UMing CN
prince: debug: font scan: AR PL UMing CN, 0 matches
prince: debug: font scan: AR PL SungtiL GB
prince: debug: font scan: AR PL SungtiL GB, 0 matches
prince: debug: font scan: Lohit Hindi
prince: debug: font scan: Lohit Hindi, 0 matches
prince: debug: font scan: Ani
prince: debug: font scan: Ani, 0 matches
prince: debug: font scan: Mukti Narrow
prince: debug: font scan: Mukti Narrow, 0 matches
prince: debug: font scan: Lohit Punjabi
prince: debug: font scan: Lohit Punjabi, 0 matches
prince: debug: font scan: Lohit Gujarati
prince: debug: font scan: Lohit Gujarati, 0 matches
prince: debug: font scan: Lohit Tamil
prince: debug: font scan: Lohit Tamil, 0 matches
prince: debug: font scan: Lohit Telugu
prince: debug: font scan: Lohit Telugu, 0 matches
prince: debug: font scan: Lohit Kannada
prince: debug: font scan: Lohit Kannada, 0 matches
prince: debug: font scan: MalOtf
prince: debug: font scan: MalOtf, 0 matches
prince: debug: font scan: Lohit Oriya
prince: debug: font scan: Lohit Oriya, 0 matches
prince: warning: no glyphs for character U+0048, fallback to '?'
prince: internal error: no fallback glyph for character U+0048

Tried both a few times in a row, same result every time.

Debug logging should not have any effect on font scanning. Combined with the earlier sporadic failures, it seems that there is some kind of strange issue affecting Fontconfig with this Prince build. I'm not sure what might be causing it, as we haven't seen it on any other platform, and can't reproduce it on our RHEL 4 installation here.

A solid workaround would be to not use Fontconfig, and instead use a style sheet with @font-face rules listing the fonts that you need. This can be generated with "prince --scanfonts /usr/share/fonts/truetype/msttcorefonts/*.ttf > myfonts.css", or similar path depending on where the fonts are installed.

Thanks for your help, that looks like it has gotten around the problem.

Version of fontconfig being used is fontconfig-2.2.3-13.el4

We were running 2.2.3-7 on our RHEL4 installation, and upgrading has allowed us to reproduce the problem! So we might have a chance of fixing it after all, if we can figure out why it's happening. Thanks for the help.

Okay, I think we've got it this time: a boolean/integer mix-up that was causing fonts to be considered non-scalable and thus ignored, but only in truly bizarre and sporadic circumstances. The bug has been squashed and an updated RPM for RHEL4 is now available: prince-7.0-2.rhel4.i386.rpm.

Please tell me this works perfectly!

Yep, looks like it's working perfectly now! Thanks!

Great news! Thanks for your patience and feedback.

This bug fix has been included in Prince 7.1, available now. Thanks again for helping us track it down!