Using evince to print a pdf, the print job seems to get stuck in the Processing state forever. At least for this job, I can print the pdf using "lpr filename.pdf" and that works. (I believe that in the past I've had that fail as well, but I can't reproduce that behavior right now.)
File I'm trying to print: http://www.grantgoodyear.org/~grant/cupshang.pdf
Cups info (from printingbuginfo, since ubuntu-bug cups doesn't work behind my corporation's http proxy):
feynman ~ > cat /tmp/printingbuginfo
ProblemType: printingbuginfo v2.0: printingbug (https://wiki.ubuntu.com/PrintingBugInfoScript)
CupsConfiguredDevices:
device for xrcolor: socket://34.52.15.20:9100
device for xrcolor2: socket://34.52.15.20:9100
CupsConfiguredPPDs:
xrcolor: Xerox WorkCentre 7335 Foomatic/Postscript (recommended)
xrcolor2: Xerox WorkCentre 7328/7335/7345
Date: Tue May 19 10:39:18 2009
DesktopEnvironment: GNOME
DistroRelease: Ubuntu 9.04
EtcPapersize: letter
InstalledPrintingPackages:
ii cups-driver-gutenprint 5.2.3-0ubuntu5 printer drivers for CUPS
ii foo2zjs 20090217-0ubuntu4 Support for printing to ZjStream-based printers
ii foomatic-db 20090218-0ubuntu3 OpenPrinting printer support - database
ii foomatic-db-engine 4.0.0-0ubuntu6.1 OpenPrinting printer support - programs
ii foomatic-db-hpijs 20090218-0ubuntu3 OpenPrinting printer support - database for HPIJS driver
ii foomatic-filters 4.0.0-0ubuntu9 OpenPrinting printer support - filters
ii ghostscript 8.64.dfsg.1-0ubuntu8 The GPL Ghostscript PostScript/PDF interpreter
ii hpijs 3.9.2-3ubuntu4 HP Linux Printing and Imaging - gs IJS driver (hpijs)
ii hplip 3.9.2-3ubuntu4 HP Linux Printing and Imaging System (HPLIP)
ii libgnomeprint2.2-0 2.18.6-1 The GNOME 2.2 print architecture - runtime files
ii min12xxw 0.0.9-1ubuntu2 Printer driver for KonicaMinolta PagePro 1[234]xxW
ii openprinting-ppds 20090218-0ubuntu3 OpenPrinting printer support - PostScript PPD files
ii pnm2ppa 1.12-16.2ubuntu1 PPM to PPA converter
ii pxljr 1.1-0ubuntu6 Driver for HP's Color LaserJet 35xx/36xx color laser printers
ii splix 2.0.0-0.1ubuntu3.1 Driver for Samsung's SPL2 (bw) and SPLc (color) laser printers
Locale: LANG=C, LC_CTYPE=C, LC_PAPER=C
Uname: Linux feynman 2.6.28-6-386 #20-Ubuntu Fri Apr 17 08:32:39 UTC 2009 i686 GNU/Linux
/var/cups/error.log from print request until I got tired of waiting:
D [19/May/2009:10:28:53 -0500] cupsdAcceptClient: 15 from localhost (Domain)
D [19/May/2009:10:28:53 -0500] cupsdReadClient: 15 POST /printers/xrcolor2 HTTP/1.1
D [19/May/2009:10:28:53 -0500] cupsdAuthorize: No authentication data provided.
D [19/May/2009:10:28:54 -0500] Print-Job ipp://localhost:631/printers/xrcolor2
D [19/May/2009:10:28:54 -0500] [Job ???] Auto-typing file...
I [19/May/2009:10:28:54 -0500] [Job ???] Request file type is application/postscript.
D [19/May/2009:10:28:54 -0500] add_job: requesting-user-name="grant"
I [19/May/2009:10:28:54 -0500] [Job 92] Adding start banner page "none".
I [19/May/2009:10:28:54 -0500] Saving subscriptions.conf...
I [19/May/2009:10:28:54 -0500] [Job 92] Adding end banner page "none".
I [19/May/2009:10:28:54 -0500] [Job 92] File of type application/postscript queued by "grant".
D [19/May/2009:10:28:54 -0500] [Job 92] hold_until=0
I [19/May/2009:10:28:54 -0500] [Job 92] Queued on "xrcolor2" by "grant".
I [19/May/2009:10:28:54 -0500] Saving subscriptions.conf...
D [19/May/2009:10:28:54 -0500] [Job 92] job-sheets=none,none
D [19/May/2009:10:28:54 -0500] [Job 92] banner_page = 0
D [19/May/2009:10:28:54 -0500] [Job 92] argv[0]="xrcolor2"
D [19/May/2009:10:28:54 -0500] [Job 92] argv[1]="92"
D [19/May/2009:10:28:54 -0500] [Job 92] argv[2]="grant"
D [19/May/2009:10:28:54 -0500] [Job 92] argv[3]="Week 6 Day 2 edited.doc (Week_6_Lesson_2.pdf)"
D [19/May/2009:10:28:54 -0500] [Job 92] argv[4]="1"
D [19/May/2009:10:28:54 -0500] [Job 92] argv[5]="XRSlipSheetSource=None XRFrontCoverSheet=None XRFold=None Jog=None MediaType=SystemDefault XROutputMode=HighSpeed StapleLocation=None PageSize=Letter XREconomode=None XRPunch=None MediaColor=Unspecified number-up=1 XRBackCoverSheet=None XRFeedEdge=LongEdge OutputBin=Auto Duplex=DuplexNoTumble InputSlot=Auto XRImageQuality=Normal XRColorCorrection=Auto XRColorMode=Color XRCMYKColorSpace=Commercial XRSlipSheetPrint=None JCLBanner job-uuid=urn:uuid:612a7da2-a932-3e7d-4b9a-e534f3f5cb4e"
D [19/May/2009:10:28:54 -0500] [Job 92] argv[6]="/var/spool/cups/d00092-001"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[9]="<email address hidden>"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[10]="SOFTWARE=CUPS/1.3.9"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[12]="TZ=America/Chicago"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[13]="USER=root"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[16]="IPP_PORT=631"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[17]="CHARSET=utf-8"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[18]="LANG=en.UTF8"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[19]="PPD=/etc/cups/ppd/xrcolor2.ppd"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[20]="RIP_MAX_CACHE=8m"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[21]="CONTENT_TYPE=application/postscript"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[22]="DEVICE_URI=socket://34.52.15.20:9100"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[23]="PRINTER=xrcolor2"
D [19/May/2009:10:28:54 -0500] [Job 92] envp[24]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
I [19/May/2009:10:28:54 -0500] [Job 92] Started filter /usr/lib/cups/filter/pstopdf (PID 23645)
I [19/May/2009:10:28:54 -0500] [Job 92] Started filter /usr/lib/cups/filter/pdftopdf (PID 23648)
I [19/May/2009:10:28:54 -0500] [Job 92] Started filter /usr/lib/cups/filter/cpdftocps (PID 23649)
I [19/May/2009:10:28:54 -0500] [Job 92] Started backend /usr/lib/cups/backend/socket (PID 23654)
I [19/May/2009:10:28:54 -0500] Saving subscriptions.conf...
D [19/May/2009:10:28:54 -0500] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)
D [19/May/2009:10:28:54 -0500] [Job 92] pstopdf 6 args: 92 grant Week 6 Day 2 edited.doc (Week_6_Lesson_2.pdf) 1 XRSlipSheetSource=None XRFrontCoverSheet=None XRFold=None Jog=None MediaType=SystemDefault XROutputMode=HighSpeed StapleLocation=None PageSize=Letter XREconomode=None XRPunch=None MediaColor=Unspecified number-up=1 XRBackCoverSheet=None XRFeedEdge=LongEdge OutputBin=Auto Duplex=DuplexNoTumble InputSlot=Auto XRImageQuality=Normal XRColorCorrection=Auto XRColorMode=Color XRCMYKColorSpace=Commercial XRSlipSheetPrint=None JCLBanner job-uuid=urn:uuid:612a7da2-a932-3e7d-4b9a-e534f3f5cb4e /var/spool/cups/d00092-001
D [19/May/2009:10:28:54 -0500] [Job 92] PPD: /etc/cups/ppd/xrcolor2.ppd
I [19/May/2009:10:28:54 -0500] Saving subscriptions.conf...
I [19/May/2009:10:28:54 -0500] Saving subscriptions.conf...
I [19/May/2009:10:28:54 -0500] Saving subscriptions.conf...
I [19/May/2009:10:28:54 -0500] Saving subscriptions.conf...
D [19/May/2009:10:28:54 -0500] [Job 92] Connected to 34.52.15.20:9100 (IPv4)...
D [19/May/2009:10:28:54 -0500] [Job 92] backendRunLoop(print_fd=0, device_fd=6, use_bc=1, side_cb=0xb807cfc0)
D [19/May/2009:10:28:54 -0500] cupsdCloseClient: 15
D [19/May/2009:10:28:54 -0500] cupsdAcceptClient: 15 from localhost (Domain)
D [19/May/2009:10:28:54 -0500] cupsdReadClient: 15 POST / HTTP/1.1
D [19/May/2009:10:28:54 -0500] cupsdAuthorize: No authentication data provided.
D [19/May/2009:10:28:54 -0500] Get-Jobs ipp://localhost/printers/
D [19/May/2009:10:28:54 -0500] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)
D [19/May/2009:10:28:54 -0500] cupsdCloseClient: 15
D [19/May/2009:10:28:54 -0500] cupsdAcceptClient: 15 from localhost (Domain)
D [19/May/2009:10:28:54 -0500] cupsdReadClient: 15 POST / HTTP/1.1
D [19/May/2009:10:28:54 -0500] cupsdAuthorize: No authentication data provided.
D [19/May/2009:10:28:54 -0500] Get-Notifications /
D [19/May/2009:10:28:54 -0500] cupsdIsAuthorized: requesting-user-name="grant"
D [19/May/2009:10:28:54 -0500] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)
D [19/May/2009:10:28:54 -0500] cupsdReadClient: 15 POST / HTTP/1.1
D [19/May/2009:10:28:54 -0500] cupsdAuthorize: No authentication data provided.
D [19/May/2009:10:28:54 -0500] Get-Job-Attributes ipp://localhost/jobs/92
D [19/May/2009:10:28:54 -0500] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)
D [19/May/2009:10:28:54 -0500] cupsdCloseClient: 15
D [19/May/2009:10:28:55 -0500] [Job 92] Resolution: 600
D [19/May/2009:10:28:55 -0500] [Job 92] Page size: Letter
D [19/May/2009:10:28:55 -0500] [Job 92] Width: 612, height: 792, absolute margins: , , ,
D [19/May/2009:10:28:55 -0500] [Job 92] Relative margins: , , ,
D [19/May/2009:10:28:55 -0500] [Job 92] PPD options: -r600 -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792
D [19/May/2009:10:28:55 -0500] [Job 92] PostScript to be injected:
D [19/May/2009:10:28:55 -0500] [Job 92] Running cat | /usr/bin/ps2pdf13 -dAutoRotatePages=/None -dAutoFilterColorImages=false -dNOPLATFONTS -dPARANOIDSAFER -sstdout=%stderr -dColorImageFilter=/FlateEncode -dDoNumCopies -dPDFSETTINGS=/printer -r600 -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 - -
D [19/May/2009:10:28:55 -0500] [Job 92] GPL Ghostscript 8.64: Set UseCIEColor for UseDeviceIndependentColor to work properly.
D [19/May/2009:10:29:02 -0500] Report: clients=0
D [19/May/2009:10:29:02 -0500] Report: jobs=2
D [19/May/2009:10:29:02 -0500] Report: jobs-active=1
D [19/May/2009:10:29:02 -0500] Report: printers=2
D [19/May/2009:10:29:02 -0500] Report: printers-implicit=0
D [19/May/2009:10:29:02 -0500] Report: stringpool-string-count=2534
D [19/May/2009:10:29:02 -0500] Report: stringpool-alloc-bytes=13808
D [19/May/2009:10:29:02 -0500] Report: stringpool-total-bytes=53024
D [19/May/2009:10:29:20 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:29:20 -0500] cupsdNetIFUpdate: "eth0" = feynman.corp.halliburton.com:631
D [19/May/2009:10:29:20 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:29:20 -0500] cupsdNetIFUpdate: "eth0" = fe80::2b0:d0ff:fef3:d669%eth0:631
D [19/May/2009:10:29:55 -0500] [Job 92] Error reading back-channel data: Connection reset by peer
D [19/May/2009:10:30:02 -0500] Report: clients=0
D [19/May/2009:10:30:02 -0500] Report: jobs=2
D [19/May/2009:10:30:02 -0500] Report: jobs-active=1
D [19/May/2009:10:30:02 -0500] Report: printers=2
D [19/May/2009:10:30:02 -0500] Report: printers-implicit=0
D [19/May/2009:10:30:02 -0500] Report: stringpool-string-count=2534
D [19/May/2009:10:30:02 -0500] Report: stringpool-alloc-bytes=13808
D [19/May/2009:10:30:02 -0500] Report: stringpool-total-bytes=53024
D [19/May/2009:10:30:22 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:30:22 -0500] cupsdNetIFUpdate: "eth0" = feynman.corp.halliburton.com:631
D [19/May/2009:10:30:22 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:30:22 -0500] cupsdNetIFUpdate: "eth0" = fe80::2b0:d0ff:fef3:d669%eth0:631
D [19/May/2009:10:30:32 -0500] PID 23645 (/usr/lib/cups/filter/pstopdf) exited with no errors.
D [19/May/2009:10:30:37 -0500] PID 23648 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [19/May/2009:10:30:37 -0500] [Job 92] perl: warning: Setting locale failed.
D [19/May/2009:10:30:37 -0500] [Job 92] perl: warning: Please check that your locale settings:
D [19/May/2009:10:30:37 -0500] [Job 92] LANGUAGE = (unset),
D [19/May/2009:10:30:37 -0500] [Job 92] LC_ALL = (unset),
D [19/May/2009:10:30:37 -0500] [Job 92] LANG = "en.UTF8"
D [19/May/2009:10:30:37 -0500] [Job 92] are supported and installed on your system.
D [19/May/2009:10:30:37 -0500] [Job 92] perl: warning: Falling back to the standard locale ("C").
D [19/May/2009:10:30:37 -0500] [Job 92] Device copies: 1; device collate:
D [19/May/2009:10:30:37 -0500] [Job 92] pdftops - copying to temp print file "/tmp/4a12d09d25cf4"
D [19/May/2009:10:30:37 -0500] [Job 92] **** Warning: File has some garbage before %PDF- .
D [19/May/2009:10:30:37 -0500] [Job 92] **** Warning: File has a corrupted %%EOF marker, or garbage after %%EOF.
D [19/May/2009:10:30:37 -0500] [Job 92] Page = 612x792; 12,12 to 600,780
D [19/May/2009:10:30:37 -0500] [Job 92] slow_collate=0, slow_duplex=0, slow_order=0
D [19/May/2009:10:30:37 -0500] [Job 92] Before copy_comments - %!PS-Adobe-3.0
D [19/May/2009:10:30:37 -0500] [Job 92] %!PS-Adobe-3.0
D [19/May/2009:10:30:37 -0500] [Job 92] %%Pages: (atend)
D [19/May/2009:10:30:37 -0500] [Job 92] %%BoundingBox: (atend)
D [19/May/2009:10:30:37 -0500] [Job 92] %%HiResBoundingBox: (atend)
D [19/May/2009:10:30:37 -0500] [Job 92] %%Creator: GPL Ghostscript 864 (pswrite)
D [19/May/2009:10:30:37 -0500] [Job 92] %%CreationDate: 2009/05/19 10:30:37
D [19/May/2009:10:30:37 -0500] [Job 92] %%DocumentData: Clean7Bit
D [19/May/2009:10:30:37 -0500] [Job 92] %%LanguageLevel: 3
D [19/May/2009:10:30:37 -0500] [Job 92] %%EndComments
D [19/May/2009:10:30:37 -0500] [Job 92] Before copy_prolog - %%BeginProlog
D [19/May/2009:10:30:37 -0500] [Job 92] Before copy_setup - %%Page: 1 1
D [19/May/2009:10:30:37 -0500] [Job 92] Before page loop - %%Page: 1 1
D [19/May/2009:10:30:37 -0500] [Job 92] Read 8192 bytes of print data...
D [19/May/2009:10:30:37 -0500] [Job 92] Copying page 1...
D [19/May/2009:10:30:37 -0500] Discarding unused job-progress event...
D [19/May/2009:10:30:37 -0500] [Job 92] pagew = 588.8, pagel = 768.8
D [19/May/2009:10:30:37 -0500] [Job 92] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [19/May/2009:10:30:37 -0500] [Job 92] PageLeft = 11.6, PageRight = 600.4
D [19/May/2009:10:30:37 -0500] [Job 92] PageTop = 780.4, PageBottom = 11.6
D [19/May/2009:10:30:37 -0500] [Job 92] PageWidth = 612.0, PageLength = 792.0
D [19/May/2009:10:31:02 -0500] Report: clients=0
D [19/May/2009:10:31:02 -0500] Report: jobs=2
D [19/May/2009:10:31:02 -0500] Report: jobs-active=1
D [19/May/2009:10:31:02 -0500] Report: printers=2
D [19/May/2009:10:31:02 -0500] Report: printers-implicit=0
D [19/May/2009:10:31:02 -0500] Report: stringpool-string-count=2534
D [19/May/2009:10:31:02 -0500] Report: stringpool-alloc-bytes=13808
D [19/May/2009:10:31:02 -0500] Report: stringpool-total-bytes=53024
D [19/May/2009:10:31:24 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:31:24 -0500] cupsdNetIFUpdate: "eth0" = feynman.corp.halliburton.com:631
D [19/May/2009:10:31:24 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:31:24 -0500] cupsdNetIFUpdate: "eth0" = fe80::2b0:d0ff:fef3:d669%eth0:631
D [19/May/2009:10:32:02 -0500] Report: clients=0
D [19/May/2009:10:32:02 -0500] Report: jobs=2
D [19/May/2009:10:32:02 -0500] Report: jobs-active=1
D [19/May/2009:10:32:02 -0500] Report: printers=2
D [19/May/2009:10:32:02 -0500] Report: printers-implicit=0
D [19/May/2009:10:32:02 -0500] Report: stringpool-string-count=2534
D [19/May/2009:10:32:02 -0500] Report: stringpool-alloc-bytes=13808
D [19/May/2009:10:32:02 -0500] Report: stringpool-total-bytes=53024
D [19/May/2009:10:32:26 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:32:26 -0500] cupsdNetIFUpdate: "eth0" = feynman.corp.halliburton.com:631
D [19/May/2009:10:32:26 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:32:26 -0500] cupsdNetIFUpdate: "eth0" = fe80::2b0:d0ff:fef3:d669%eth0:631
D [19/May/2009:10:33:02 -0500] Report: clients=0
D [19/May/2009:10:33:02 -0500] Report: jobs=2
D [19/May/2009:10:33:02 -0500] Report: jobs-active=1
D [19/May/2009:10:33:02 -0500] Report: printers=2
D [19/May/2009:10:33:02 -0500] Report: printers-implicit=0
D [19/May/2009:10:33:02 -0500] Report: stringpool-string-count=2534
D [19/May/2009:10:33:02 -0500] Report: stringpool-alloc-bytes=13808
D [19/May/2009:10:33:02 -0500] Report: stringpool-total-bytes=53024
D [19/May/2009:10:33:03 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:33:03 -0500] cupsdNetIFUpdate: "eth0" = feynman.corp.halliburton.com:631
D [19/May/2009:10:33:03 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:33:03 -0500] cupsdNetIFUpdate: "eth0" = fe80::2b0:d0ff:fef3:d669%eth0:631
D [19/May/2009:10:34:02 -0500] Report: clients=0
D [19/May/2009:10:34:02 -0500] Report: jobs=2
D [19/May/2009:10:34:02 -0500] Report: jobs-active=1
D [19/May/2009:10:34:02 -0500] Report: printers=2
D [19/May/2009:10:34:02 -0500] Report: printers-implicit=0
D [19/May/2009:10:34:02 -0500] Report: stringpool-string-count=2534
D [19/May/2009:10:34:02 -0500] Report: stringpool-alloc-bytes=13808
D [19/May/2009:10:34:02 -0500] Report: stringpool-total-bytes=53024
D [19/May/2009:10:34:05 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:34:05 -0500] cupsdNetIFUpdate: "eth0" = feynman.corp.halliburton.com:631
D [19/May/2009:10:34:05 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:34:05 -0500] cupsdNetIFUpdate: "eth0" = fe80::2b0:d0ff:fef3:d669%eth0:631
D [19/May/2009:10:34:31 -0500] cupsdAcceptClient: 15 from localhost (Domain)
D [19/May/2009:10:34:31 -0500] cupsdReadClient: 15 POST / HTTP/1.1
D [19/May/2009:10:34:31 -0500] cupsdAuthorize: No authentication data provided.
D [19/May/2009:10:34:31 -0500] CUPS-Get-Printers
D [19/May/2009:10:34:31 -0500] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)
D [19/May/2009:10:34:31 -0500] cupsdReadClient: 15 POST / HTTP/1.1
D [19/May/2009:10:34:31 -0500] cupsdAuthorize: No authentication data provided.
D [19/May/2009:10:34:31 -0500] CUPS-Get-Classes
D [19/May/2009:10:34:31 -0500] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)
D [19/May/2009:10:34:31 -0500] cupsdCloseClient: 15
D [19/May/2009:10:35:02 -0500] Report: clients=0
D [19/May/2009:10:35:02 -0500] Report: jobs=2
D [19/May/2009:10:35:02 -0500] Report: jobs-active=1
D [19/May/2009:10:35:02 -0500] Report: printers=2
D [19/May/2009:10:35:02 -0500] Report: printers-implicit=0
D [19/May/2009:10:35:02 -0500] Report: stringpool-string-count=2534
D [19/May/2009:10:35:02 -0500] Report: stringpool-alloc-bytes=13808
D [19/May/2009:10:35:02 -0500] Report: stringpool-total-bytes=53024
D [19/May/2009:10:35:07 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:35:07 -0500] cupsdNetIFUpdate: "eth0" = feynman.corp.halliburton.com:631
D [19/May/2009:10:35:07 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:35:07 -0500] cupsdNetIFUpdate: "eth0" = fe80::2b0:d0ff:fef3:d669%eth0:631
D [19/May/2009:10:36:02 -0500] Report: clients=0
D [19/May/2009:10:36:02 -0500] Report: jobs=2
D [19/May/2009:10:36:02 -0500] Report: jobs-active=1
D [19/May/2009:10:36:02 -0500] Report: printers=2
D [19/May/2009:10:36:02 -0500] Report: printers-implicit=0
D [19/May/2009:10:36:02 -0500] Report: stringpool-string-count=2534
D [19/May/2009:10:36:02 -0500] Report: stringpool-alloc-bytes=13808
D [19/May/2009:10:36:02 -0500] Report: stringpool-total-bytes=53024
D [19/May/2009:10:36:03 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:36:03 -0500] cupsdNetIFUpdate: "eth0" = feynman.corp.halliburton.com:631
D [19/May/2009:10:36:03 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:36:03 -0500] cupsdNetIFUpdate: "eth0" = fe80::2b0:d0ff:fef3:d669%eth0:631
D [19/May/2009:10:37:02 -0500] Report: clients=0
D [19/May/2009:10:37:02 -0500] Report: jobs=2
D [19/May/2009:10:37:02 -0500] Report: jobs-active=1
D [19/May/2009:10:37:02 -0500] Report: printers=2
D [19/May/2009:10:37:02 -0500] Report: printers-implicit=0
D [19/May/2009:10:37:02 -0500] Report: stringpool-string-count=2534
D [19/May/2009:10:37:02 -0500] Report: stringpool-alloc-bytes=13808
D [19/May/2009:10:37:02 -0500] Report: stringpool-total-bytes=53024
D [19/May/2009:10:37:05 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:37:05 -0500] cupsdNetIFUpdate: "eth0" = feynman.corp.halliburton.com:631
D [19/May/2009:10:37:05 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:37:05 -0500] cupsdNetIFUpdate: "eth0" = fe80::2b0:d0ff:fef3:d669%eth0:631
D [19/May/2009:10:38:02 -0500] Report: clients=0
D [19/May/2009:10:38:02 -0500] Report: jobs=2
D [19/May/2009:10:38:02 -0500] Report: jobs-active=1
D [19/May/2009:10:38:02 -0500] Report: printers=2
D [19/May/2009:10:38:02 -0500] Report: printers-implicit=0
D [19/May/2009:10:38:02 -0500] Report: stringpool-string-count=2534
D [19/May/2009:10:38:02 -0500] Report: stringpool-alloc-bytes=13808
D [19/May/2009:10:38:02 -0500] Report: stringpool-total-bytes=53024
D [19/May/2009:10:38:07 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:38:07 -0500] cupsdNetIFUpdate: "eth0" = feynman.corp.halliburton.com:631
D [19/May/2009:10:38:07 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:38:07 -0500] cupsdNetIFUpdate: "eth0" = fe80::2b0:d0ff:fef3:d669%eth0:631
D [19/May/2009:10:39:02 -0500] Report: clients=0
D [19/May/2009:10:39:02 -0500] Report: jobs=2
D [19/May/2009:10:39:02 -0500] Report: jobs-active=1
D [19/May/2009:10:39:02 -0500] Report: printers=2
D [19/May/2009:10:39:02 -0500] Report: printers-implicit=0
D [19/May/2009:10:39:02 -0500] Report: stringpool-string-count=2534
D [19/May/2009:10:39:02 -0500] Report: stringpool-alloc-bytes=13808
D [19/May/2009:10:39:02 -0500] Report: stringpool-total-bytes=53024
D [19/May/2009:10:39:09 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:39:09 -0500] cupsdNetIFUpdate: "eth0" = feynman.corp.halliburton.com:631
D [19/May/2009:10:39:09 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:39:09 -0500] cupsdNetIFUpdate: "eth0" = fe80::2b0:d0ff:fef3:d669%eth0:631
D [19/May/2009:10:39:18 -0500] cupsdAcceptClient: 15 from localhost (Domain)
D [19/May/2009:10:39:18 -0500] cupsdReadClient: 15 POST / HTTP/1.1
D [19/May/2009:10:39:18 -0500] cupsdAuthorize: No authentication data provided.
D [19/May/2009:10:39:18 -0500] CUPS-Get-Printers
D [19/May/2009:10:39:18 -0500] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)
D [19/May/2009:10:39:18 -0500] cupsdReadClient: 15 POST / HTTP/1.1
D [19/May/2009:10:39:18 -0500] cupsdAuthorize: No authentication data provided.
D [19/May/2009:10:39:18 -0500] CUPS-Get-Classes
D [19/May/2009:10:39:18 -0500] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)
D [19/May/2009:10:39:18 -0500] cupsdReadClient: 15 POST / HTTP/1.1
D [19/May/2009:10:39:18 -0500] cupsdAuthorize: No authentication data provided.
D [19/May/2009:10:39:18 -0500] CUPS-Get-Default
D [19/May/2009:10:39:18 -0500] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)
D [19/May/2009:10:39:18 -0500] cupsdReadClient: 15 POST / HTTP/1.1
D [19/May/2009:10:39:18 -0500] cupsdAuthorize: No authentication data provided.
D [19/May/2009:10:39:18 -0500] CUPS-Get-Printers
D [19/May/2009:10:39:18 -0500] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok)
D [19/May/2009:10:39:18 -0500] cupsdCloseClient: 15
D [19/May/2009:10:40:02 -0500] Report: clients=0
D [19/May/2009:10:40:02 -0500] Report: jobs=2
D [19/May/2009:10:40:02 -0500] Report: jobs-active=1
D [19/May/2009:10:40:02 -0500] Report: printers=2
D [19/May/2009:10:40:02 -0500] Report: printers-implicit=0
D [19/May/2009:10:40:02 -0500] Report: stringpool-string-count=2534
D [19/May/2009:10:40:02 -0500] Report: stringpool-alloc-bytes=13808
D [19/May/2009:10:40:02 -0500] Report: stringpool-total-bytes=53024
D [19/May/2009:10:40:11 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:40:11 -0500] cupsdNetIFUpdate: "eth0" = feynman.corp.halliburton.com:631
D [19/May/2009:10:40:11 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:40:11 -0500] cupsdNetIFUpdate: "eth0" = fe80::2b0:d0ff:fef3:d669%eth0:631
D [19/May/2009:10:41:02 -0500] Report: clients=0
D [19/May/2009:10:41:02 -0500] Report: jobs=2
D [19/May/2009:10:41:02 -0500] Report: jobs-active=1
D [19/May/2009:10:41:02 -0500] Report: printers=2
D [19/May/2009:10:41:02 -0500] Report: printers-implicit=0
D [19/May/2009:10:41:02 -0500] Report: stringpool-string-count=2534
D [19/May/2009:10:41:02 -0500] Report: stringpool-alloc-bytes=13808
D [19/May/2009:10:41:02 -0500] Report: stringpool-total-bytes=53024
D [19/May/2009:10:41:13 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:41:13 -0500] cupsdNetIFUpdate: "eth0" = feynman.corp.halliburton.com:631
D [19/May/2009:10:41:13 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:41:13 -0500] cupsdNetIFUpdate: "eth0" = fe80::2b0:d0ff:fef3:d669%eth0:631
D [19/May/2009:10:42:02 -0500] Report: clients=0
D [19/May/2009:10:42:02 -0500] Report: jobs=2
D [19/May/2009:10:42:02 -0500] Report: jobs-active=1
D [19/May/2009:10:42:02 -0500] Report: printers=2
D [19/May/2009:10:42:02 -0500] Report: printers-implicit=0
D [19/May/2009:10:42:02 -0500] Report: stringpool-string-count=2534
D [19/May/2009:10:42:02 -0500] Report: stringpool-alloc-bytes=13808
D [19/May/2009:10:42:02 -0500] Report: stringpool-total-bytes=53024
D [19/May/2009:10:42:15 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:42:15 -0500] cupsdNetIFUpdate: "eth0" = feynman.corp.halliburton.com:631
D [19/May/2009:10:42:15 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:42:15 -0500] cupsdNetIFUpdate: "eth0" = fe80::2b0:d0ff:fef3:d669%eth0:631
D [19/May/2009:10:43:02 -0500] Report: clients=0
D [19/May/2009:10:43:02 -0500] Report: jobs=2
D [19/May/2009:10:43:02 -0500] Report: jobs-active=1
D [19/May/2009:10:43:02 -0500] Report: printers=2
D [19/May/2009:10:43:02 -0500] Report: printers-implicit=0
D [19/May/2009:10:43:02 -0500] Report: stringpool-string-count=2534
D [19/May/2009:10:43:02 -0500] Report: stringpool-alloc-bytes=13808
D [19/May/2009:10:43:02 -0500] Report: stringpool-total-bytes=53024
D [19/May/2009:10:43:17 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:43:17 -0500] cupsdNetIFUpdate: "eth0" = feynman.corp.halliburton.com:631
D [19/May/2009:10:43:17 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:43:17 -0500] cupsdNetIFUpdate: "eth0" = fe80::2b0:d0ff:fef3:d669%eth0:631
D [19/May/2009:10:44:02 -0500] Report: clients=0
D [19/May/2009:10:44:02 -0500] Report: jobs=2
D [19/May/2009:10:44:02 -0500] Report: jobs-active=1
D [19/May/2009:10:44:02 -0500] Report: printers=2
D [19/May/2009:10:44:02 -0500] Report: printers-implicit=0
D [19/May/2009:10:44:02 -0500] Report: stringpool-string-count=2534
D [19/May/2009:10:44:02 -0500] Report: stringpool-alloc-bytes=13808
D [19/May/2009:10:44:02 -0500] Report: stringpool-total-bytes=53024
D [19/May/2009:10:44:19 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:44:19 -0500] cupsdNetIFUpdate: "eth0" = feynman.corp.halliburton.com:631
D [19/May/2009:10:44:19 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [19/May/2009:10:44:19 -0500] cupsdNetIFUpdate: "eth0" = fe80::2b0:d0ff:fef3:d669%eth0:631
D [19/May/2009:10:45:02 -0500] Report: clients=0
D [19/May/2009:10:45:02 -0500] Report: jobs=2
D [19/May/2009:10:45:02 -0500] Report: jobs-active=1
D [19/May/2009:10:45:02 -0500] Report: printers=2
D [19/May/2009:10:45:02 -0500] Report: printers-implicit=0
D [19/May/2009:10:45:02 -0500] Report: stringpool-string-count=2534
D [19/May/2009:10:45:02 -0500] Report: stringpool-alloc-bytes=13808
D [19/May/2009:10:45:02 -0500] Report: stringpool-total-bytes=53024
The job is still listed as Processing, but no more reports about job 92 show up. There is a gs process running:
feynman ~ > ps -e | grep gs
5613 ? 00:00:58 gnome-settings-
24241 ? 00:00:00 gs
feynman ~ > sudo strace -p 24241
[sudo] password for grant:
Process 24241 attached - interrupt to quit
write(1, "/\\ZHjo!NhrmU*0e`H_YjSREcq9e0r\ndGX"..., 4096
It seems that gs is stuck. Stdout blocking?
Here's the (probably unhelpful) gdb stack trace:
feynman ~ > sudo gdb --pid=24241
GNU gdb 6.8-debian
Copyright (C) 2008 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law. Type "show copying"
and "show warranty" for details.
This GDB was configured as "i486-linux-gnu".
Attaching to process 24241
Reading symbols from /usr/bin/gs...(no debugging symbols found)...done.
Reading symbols from /usr/lib/libgs.so.8...(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libgs.so.8
Reading symbols from /lib/tls/i686/cmov/libc.so.6...
(no debugging symbols found)...done.
Loaded symbols for /lib/tls/i686/cmov/libc.so.6
Reading symbols from /usr/lib/libpaper.so.1...(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libpaper.so.1
Reading symbols from /usr/lib/libcupsimage.so.2...
(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libcupsimage.so.2
Reading symbols from /usr/lib/libcups.so.2...(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libcups.so.2
Reading symbols from /usr/lib/libtiff.so.4...
(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libtiff.so.4
Reading symbols from /usr/lib/libjpeg.so.62...(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libjpeg.so.62
Reading symbols from /usr/lib/libpng12.so.0...
(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libpng12.so.0
Reading symbols from /usr/lib/libgssapi_krb5.so.2...(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libgssapi_krb5.so.2
Reading symbols from /usr/lib/libgnutls.so.26...
(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libgnutls.so.26
Reading symbols from /lib/libz.so.1...(no debugging symbols found)...done.
Loaded symbols for /lib/libz.so.1
Reading symbols from /lib/tls/i686/cmov/libpthread.so.0...
(no debugging symbols found)...done.
[Thread debugging using libthread_db enabled]
[New Thread 0xb70e46f0 (LWP 24241)]
Loaded symbols for /lib/tls/i686/cmov/libpthread.so.0
Reading symbols from /lib/tls/i686/cmov/libm.so.6...(no debugging symbols found)...done.
Loaded symbols for /lib/tls/i686/cmov/libm.so.6
Reading symbols from /lib/tls/i686/cmov/libcrypt.so.1...
(no debugging symbols found)...done.
Loaded symbols for /lib/tls/i686/cmov/libcrypt.so.1
Reading symbols from /lib/tls/i686/cmov/libdl.so.2...(no debugging symbols found)...done.
Loaded symbols for /lib/tls/i686/cmov/libdl.so.2
Reading symbols from /usr/lib/libstdc++.so.6...
(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libstdc++.so.6
Reading symbols from /usr/lib/libfontconfig.so.1...(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libfontconfig.so.1
Reading symbols from /lib/ld-linux.so.2...
(no debugging symbols found)...done.
Loaded symbols for /lib/ld-linux.so.2
Reading symbols from /usr/lib/libkrb5.so.3...(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libkrb5.so.3
Reading symbols from /usr/lib/libk5crypto.so.3...
(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libk5crypto.so.3
Reading symbols from /lib/libcom_err.so.2...(no debugging symbols found)...done.
Loaded symbols for /lib/libcom_err.so.2
Reading symbols from /usr/lib/libkrb5support.so.0...
(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libkrb5support.so.0
Reading symbols from /lib/libkeyutils.so.1...(no debugging symbols found)...done.
Loaded symbols for /lib/libkeyutils.so.1
Reading symbols from /lib/tls/i686/cmov/libresolv.so.2...
(no debugging symbols found)...done.
Loaded symbols for /lib/tls/i686/cmov/libresolv.so.2
Reading symbols from /usr/lib/libtasn1.so.3...(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libtasn1.so.3
Reading symbols from /lib/libgcrypt.so.11...
(no debugging symbols found)...done.
Loaded symbols for /lib/libgcrypt.so.11
Reading symbols from /lib/libgcc_s.so.1...(no debugging symbols found)...done.
Loaded symbols for /lib/libgcc_s.so.1
Reading symbols from /usr/lib/libfreetype.so.6...
(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libfreetype.so.6
Reading symbols from /usr/lib/libexpat.so.1...(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libexpat.so.1
Reading symbols from /lib/libgpg-error.so.0...
(no debugging symbols found)...done.
Loaded symbols for /lib/libgpg-error.so.0
Reading symbols from /usr/lib/ghostscript/8.64/X11.so...(no debugging symbols found)...done.
Loaded symbols for /usr/lib/ghostscript/8.64/X11.so
Reading symbols from /usr/lib/libXt.so.6...
(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libXt.so.6
Reading symbols from /usr/lib/libSM.so.6...(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libSM.so.6
Reading symbols from /usr/lib/libICE.so.6...
(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libICE.so.6
Reading symbols from /usr/lib/libXext.so.6...(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libXext.so.6
Reading symbols from /usr/lib/libX11.so.6...
(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libX11.so.6
Reading symbols from /lib/libuuid.so.1...(no debugging symbols found)...done.
Loaded symbols for /lib/libuuid.so.1
Reading symbols from /usr/lib/libXau.so.6...
---Type <return> to continue, or q <return> to quit---
(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libXau.so.6
Reading symbols from /usr/lib/libxcb.so.1...(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libxcb.so.1
Reading symbols from /usr/lib/libXdmcp.so.6...
(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libXdmcp.so.6
(no debugging symbols found)
0xb7fdf430 in __kernel_vsyscall ()
(gdb) where
#0 0xb7fdf430 in __kernel_vsyscall ()
#1 0xb7726e83 in write () from /lib/tls/i686/cmov/libc.so.6
#2 0xb76bdcdc in _IO_file_write () from /lib/tls/i686/cmov/libc.so.6
#3 0xb76bee57 in _IO_do_write () from /lib/tls/i686/cmov/libc.so.6
#4 0xb76be830 in _IO_file_overflow () from /lib/tls/i686/cmov/libc.so.6
#5 0xb76bd945 in _IO_file_xsputn () from /lib/tls/i686/cmov/libc.so.6
#6 0xb76b35ea in fwrite () from /lib/tls/i686/cmov/libc.so.6
#7 0xb78f78bc in ?? () from /usr/lib/libgs.so.8
#8 0xb78c737e in s_process_write_buf () from /usr/lib/libgs.so.8
#9 0xb78c75da in spputc () from /usr/lib/libgs.so.8
#10 0xb78c7762 in sputs () from /usr/lib/libgs.so.8
#11 0xb791a912 in stream_write () from /usr/lib/libgs.so.8
#12 0xb79ef494 in ?? () from /usr/lib/libgs.so.8
#13 0xb79ef5ba in ?? () from /usr/lib/libgs.so.8
#14 0xb7ba59f6 in gx_image_plane_data_rows () from /usr/lib/libgs.so.8
#15 0xb7b68bbd in gs_image_next_planes () from /usr/lib/libgs.so.8
#16 0xb78ef6bd in ?? () from /usr/lib/libgs.so.8
#17 0xb78ba891 in ?? () from /usr/lib/libgs.so.8
#18 0xb78bc941 in gs_interpret () from /usr/lib/libgs.so.8
#19 0xb78afe38 in gs_main_run_string_end () from /usr/lib/libgs.so.8
#20 0xb78b0282 in gs_main_run_string_with_length () from /usr/lib/libgs.so.8
#21 0xb78b02da in gs_main_run_string () from /usr/lib/libgs.so.8
#22 0xb78b1083 in ?? () from /usr/lib/libgs.so.8
#23 0xb78b1959 in ?? () from /usr/lib/libgs.so.8
#24 0xb78b1c0a in ?? () from /usr/lib/libgs.so.8
#25 0xb78b37ea in gs_main_init_with_args () from /usr/lib/libgs.so.8
---Type <return> to continue, or q <return> to quit---
#26 0xb78b485e in gsapi_init_with_args () from /usr/lib/libgs.so.8
#27 0x0804888f in main ()
Oh, version of evince that created the ps that seems to be causing problems:
feynman ~ > apt-cache policy evince
evince:
Installed: 2.26.1-0ubuntu1
Candidate: 2.26.1-0ubuntu1
Version table:
*** 2.26.1-0ubuntu1 0
500 http://us.archive.ubuntu.com jaunty-updates/main Packages
100 /var/lib/dpkg/status
2.26.0-0ubuntu1 0
500 http://us.archive.ubuntu.com jaunty/main Packages
Attached xrcolor2.ppd file.