jobs never switch from "processing, accepting jobs, published"

[jeff]

We are using CUPS 1.3.2 on Debian Lenny, printing to a Ricoh Aficio MP 6500 via ipp.

If we send a job to the printer, it is received by the printer and prints, but, according to CUPS, the printer never returns to an idle state.  Subsequent jobs queue up and are never received by the printer.

We are using the latest PXL driver (from linuxprinting.org), but have tried with the latest PS, and there was no difference.

Other machines using CUPS 1.1.23 and Debian Sid print to the same printer without a problem.

Scouring the internet and this forum hasn't provided anything useful either.

Thanks for any help you can provide.

Here is a debug log for a print job:

D [25/Jan/2008:15:53:46 +0000] Print-Job ipp://localhost:631/printers/buslab-bw
D [25/Jan/2008:15:53:46 +0000] add_job: requesting-user-name="guest"
D [25/Jan/2008:15:53:46 +0000] Adding default job-sheets values "none,none"...
I [25/Jan/2008:15:53:46 +0000] [Job 16] Adding start banner page "none".
D [25/Jan/2008:15:53:46 +0000] Discarding unused job-created event...
I [25/Jan/2008:15:53:46 +0000] [Job 16] Adding job file of type application/postscript.
I [25/Jan/2008:15:53:46 +0000] [Job 16] Adding end banner page "none".
I [25/Jan/2008:15:53:46 +0000] [Job 16] Queued on "buslab-bw" by "guest".
D [25/Jan/2008:15:53:46 +0000] [Job 16] hold_until = 0
D [25/Jan/2008:15:53:46 +0000] Discarding unused printer-state-changed event...
D [25/Jan/2008:15:53:46 +0000] [Job 16] job-sheets=none,none
D [25/Jan/2008:15:53:46 +0000] [Job 16] banner_page = 0
D [25/Jan/2008:15:53:46 +0000] [Job 16] argv[0]="buslab-bw"
D [25/Jan/2008:15:53:46 +0000] [Job 16] argv[1]="16"
D [25/Jan/2008:15:53:46 +0000] [Job 16] argv[2]="guest"
D [25/Jan/2008:15:53:46 +0000] [Job 16] argv[3]="Test Page"
D [25/Jan/2008:15:53:46 +0000] [Job 16] argv[4]="1"
D [25/Jan/2008:15:53:46 +0000] [Job 16] argv[5]="job-uuid=urn:uuid:61c4aa57-2de7-3986-641f-c17a9b066028"
D [25/Jan/2008:15:53:46 +0000] [Job 16] argv[6]="/var/spool/cups/d00016-001"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[9]="SERVER_ADMIN=root at buthin05"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[10]="SOFTWARE=CUPS/1.3.2"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[12]="TZ=Etc/UTC"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[13]="USER=root"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[14]="CUPS_SERVER=localhost"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[16]="IPP_PORT=631"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[17]="CHARSET=utf-8"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[18]="LANG=en_US"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[19]="PPD=/etc/cups/ppd/buslab-bw.ppd"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[20]="RIP_MAX_CACHE=8m"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[21]="CONTENT_TYPE=application/postscript"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[22]="DEVICE_URI=ipp://iprint1.wheaton.edu:631/ipp/buslab-bw"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[23]="PRINTER=buslab-bw"
D [25/Jan/2008:15:53:46 +0000] [Job 16] envp[24]="FINAL_CONTENT_TYPE=printer/buslab-bw"
I [25/Jan/2008:15:53:46 +0000] [Job 16] Started filter /usr/lib/cups/filter/pstops (PID 14677)
I [25/Jan/2008:15:53:46 +0000] [Job 16] Started filter /usr/lib/cups/filter/foomatic-rip (PID 14678)
I [25/Jan/2008:15:53:46 +0000] [Job 16] Started backend /usr/lib/cups/backend/ipp (PID 14679)
D [25/Jan/2008:15:53:46 +0000] Discarding unused job-state event...
D [25/Jan/2008:15:53:46 +0000] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)
D [25/Jan/2008:15:53:46 +0000] [CGI] lang="en_US.UTF-8", locale="/en_US"...
D [25/Jan/2008:15:53:46 +0000] [CGI] lang="en_US.UTF-8", locale="/en_US"...
D [25/Jan/2008:15:53:46 +0000] [CGI] lang="en_US.UTF-8", locale="/en_US"...
D [25/Jan/2008:15:53:46 +0000] cupsdCloseClient: 14
D [25/Jan/2008:15:53:46 +0000] PID 14676 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [25/Jan/2008:15:53:46 +0000] [Job 16] perl: warning: Setting locale failed.
D [25/Jan/2008:15:53:46 +0000] [Job 16] perl: warning: Please check that your locale settings:
D [25/Jan/2008:15:53:46 +0000] [Job 16] LANGUAGE = (unset),
D [25/Jan/2008:15:53:46 +0000] [Job 16] LC_ALL = (unset),
D [25/Jan/2008:15:53:46 +0000] [Job 16] LANG = "en_US"
D [25/Jan/2008:15:53:46 +0000] [Job 16] are supported and installed on your system.
D [25/Jan/2008:15:53:46 +0000] [Job 16] perl: warning: Falling back to the standard locale ("C").
D [25/Jan/2008:15:53:46 +0000] [Job 16] Page = 612x792; 12,12 to 600,780
D [25/Jan/2008:15:53:46 +0000] [Job 16] slow_collate=0, slow_duplex=0, slow_order=0
D [25/Jan/2008:15:53:46 +0000] [Job 16] Before copy_comments - %!PS-Adobe-3.0
D [25/Jan/2008:15:53:46 +0000] [Job 16] %!PS-Adobe-3.0
D [25/Jan/2008:15:53:46 +0000] [Job 16] %%BoundingBox: 0 0 612 792
D [25/Jan/2008:15:53:46 +0000] [Job 16] %%Pages: 1
D [25/Jan/2008:15:53:46 +0000] [Job 16] %%LanguageLevel: 1
D [25/Jan/2008:15:53:46 +0000] [Job 16] %%DocumentData: Clean7Bit
D [25/Jan/2008:15:53:46 +0000] [Job 16] %%DocumentSuppliedResources: procset testprint/1.3
D [25/Jan/2008:15:53:46 +0000] [Job 16] %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman
D [25/Jan/2008:15:53:46 +0000] [Job 16] %%Creator: Michael Sweet, Apple Inc.
D [25/Jan/2008:15:53:46 +0000] [Job 16] %%CreationDate: D:20070606214000+0500
D [25/Jan/2008:15:53:46 +0000] [Job 16] %%Title: Test Page
D [25/Jan/2008:15:53:46 +0000] [Job 16] %%EndComments
D [25/Jan/2008:15:53:46 +0000] [Job 16] Before copy_prolog - %%BeginProlog
D [25/Jan/2008:15:53:46 +0000] [Job 16] Before copy_setup - %%Page: 1 1
D [25/Jan/2008:15:53:46 +0000] [Job 16] Before page loop - %%Page: 1 1
D [25/Jan/2008:15:53:46 +0000] [Job 16] Copying page 1...
D [25/Jan/2008:15:53:46 +0000] [Job 16] pagew = 588.0, pagel = 768.0
D [25/Jan/2008:15:53:46 +0000] [Job 16] bboxw = 612, bboxl = 792
D [25/Jan/2008:15:53:46 +0000] [Job 16] PageLeft = 12.0, PageRight = 600.0
D [25/Jan/2008:15:53:46 +0000] [Job 16] PageTop = 780.0, PageBottom = 12.0
D [25/Jan/2008:15:53:46 +0000] [Job 16] PageWidth = 612.0, PageLength = 792.0
D [25/Jan/2008:15:53:46 +0000] [Job 16] Wrote 1 pages...
D [25/Jan/2008:15:53:46 +0000] PID 14677 (/usr/lib/cups/filter/pstops) exited with no errors.
D [25/Jan/2008:15:53:47 +0000] [Job 16] foomatic-rip version $Revision$ running...
D [25/Jan/2008:15:53:47 +0000] [Job 16] Parsing PPD file ...
D [25/Jan/2008:15:53:47 +0000] [Job 16] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option ColorSpace
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option Option2LargeCapacityTray
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option Option40Mailbox
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option Option50Zfoldingunit
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option Option32BookletProcessor
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option Option30Finisher
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option ColorModel
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option GSCmdLine
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option Resolution
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option GSResolution
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option JCLResolution
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option PageSize
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option PageRegion
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option ImageableArea
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option PaperDimension
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option InputSlot
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option Duplex
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option GSDuplex
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option JCLDuplex
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option MediaType
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option OutputBin
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option RIZfold
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option StapleLocation
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option RIPunch
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option RIPrintMode
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option JobType
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option LockedPrintPassword
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option DocServerPassword
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option UserCode
D [25/Jan/2008:15:53:47 +0000] [Job 16] Added option Font
D [25/Jan/2008:15:53:47 +0000] [Job 16] The value None for LockedPrintPassword is a predefined choice
D [25/Jan/2008:15:53:47 +0000] [Job 16] The value None for DocServerPassword is a predefined choice
D [25/Jan/2008:15:53:47 +0000] [Job 16] The value None for UserCode is a predefined choice
D [25/Jan/2008:15:53:47 +0000] [Job 16]
D [25/Jan/2008:15:53:47 +0000] [Job 16] Parameter Summary
D [25/Jan/2008:15:53:47 +0000] [Job 16] -----------------
D [25/Jan/2008:15:53:47 +0000] [Job 16]
D [25/Jan/2008:15:53:47 +0000] [Job 16] Spooler: cups
D [25/Jan/2008:15:53:47 +0000] [Job 16] Printer: buslab-bw
D [25/Jan/2008:15:53:47 +0000] [Job 16] Shell: /bin/sh
D [25/Jan/2008:15:53:47 +0000] [Job 16] PPD file: /etc/cups/ppd/buslab-bw.ppd
D [25/Jan/2008:15:53:47 +0000] [Job 16] ATTR file:
D [25/Jan/2008:15:53:47 +0000] [Job 16] Printer model: Ricoh Aficio MP 6500 PXL
D [25/Jan/2008:15:53:47 +0000] [Job 16] Job title: Test Page
D [25/Jan/2008:15:53:47 +0000] [Job 16] File(s) to be printed:
D [25/Jan/2008:15:53:47 +0000] [Job 16] <STDIN>
D [25/Jan/2008:15:53:47 +0000] [Job 16]
D [25/Jan/2008:15:53:47 +0000] [Job 16] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [25/Jan/2008:15:53:47 +0000] [Job 16] Pondering option 'job-uuid=urn:uuid:61c4aa57-2de7-3986-641f-c17a9b066028'
D [25/Jan/2008:15:53:47 +0000] [Job 16] Unknown option job-uuid=urn:uuid:61c4aa57-2de7-3986-641f-c17a9b066028.
D [25/Jan/2008:15:53:47 +0000] [Job 16]
D [25/Jan/2008:15:53:47 +0000] [Job 16] ================================================
D [25/Jan/2008:15:53:47 +0000] [Job 16]
D [25/Jan/2008:15:53:47 +0000] [Job 16] File: <STDIN>
D [25/Jan/2008:15:53:47 +0000] [Job 16]
D [25/Jan/2008:15:53:47 +0000] [Job 16] ================================================
D [25/Jan/2008:15:53:47 +0000] [Job 16]
D [25/Jan/2008:15:53:47 +0000] [Job 16] Reading PostScript input ...
D [25/Jan/2008:15:53:47 +0000] [Job 16] --> This document is DSC-conforming!
D [25/Jan/2008:15:53:47 +0000] [Job 16]
D [25/Jan/2008:15:53:47 +0000] [Job 16] -----------
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginProlog
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%EndProlog
D [25/Jan/2008:15:53:47 +0000] [Job 16]
D [25/Jan/2008:15:53:47 +0000] [Job 16] -----------
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginSetup
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *Option2LargeCapacityTray False
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: Option2LargeCapacityTray=False --> Option will be set by PostScript interpreter
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *Option40Mailbox False
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: Option40Mailbox=False --> Option will be set by PostScript interpreter
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *Option50Zfoldingunit False
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: Option50Zfoldingunit=False --> Option will be set by PostScript interpreter
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *Option32BookletProcessor False
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: Option32BookletProcessor=False --> Option will be set by PostScript interpreter
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *Option30Finisher None
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: Option30Finisher=None --> Option will be set by PostScript interpreter
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *ColorModel Grayscale
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: ColorModel=Grayscale --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %% FoomaticRIPOptionSetting: ColorModel=Grayscale
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: ColorModel=Grayscale --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *Resolution 600dpi
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: Resolution=600dpi --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %% FoomaticRIPOptionSetting: Resolution=600dpi
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: Resolution=600dpi --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *PageRegion Letter
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: PageRegion=Letter --> Option will be set by PostScript interpreter
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %% FoomaticRIPOptionSetting: PageSize=Letter
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: PageSize=Letter --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *InputSlot Auto
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: InputSlot=Auto --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %% FoomaticRIPOptionSetting: InputSlot=Auto
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: InputSlot=Auto --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *RIPrintMode 0rhit
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: RIPrintMode=0rhit --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %% FoomaticRIPOptionSetting: RIPrintMode=0rhit
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: RIPrintMode=0rhit --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *Duplex None
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: Duplex=None --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %% FoomaticRIPOptionSetting: Duplex=None
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: Duplex=None --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *MediaType Auto
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: MediaType=Auto --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %% FoomaticRIPOptionSetting: MediaType=Auto
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: MediaType=Auto --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *OutputBin Default
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: OutputBin=Default --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %% FoomaticRIPOptionSetting: OutputBin=Default
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: OutputBin=Default --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *StapleLocation None
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: StapleLocation=None --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %% FoomaticRIPOptionSetting: StapleLocation=None
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: StapleLocation=None --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *RIZfold None
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: RIZfold=None --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %% FoomaticRIPOptionSetting: RIZfold=None
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: RIZfold=None --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *RIPunch None
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: RIPunch=None --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %% FoomaticRIPOptionSetting: RIPunch=None
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: RIPunch=None --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *JobType Normal
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: JobType=Normal --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *LockedPrintPassword None
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: LockedPrintPassword=NoneThe value None for LockedPrintPassword is a predefined choice
D [25/Jan/2008:15:53:47 +0000] [Job 16] --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *DocServerPassword None
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: DocServerPassword=NoneThe value None for DocServerPassword is a predefined choice
D [25/Jan/2008:15:53:47 +0000] [Job 16] --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginFeature: *UserCode None
D [25/Jan/2008:15:53:47 +0000] [Job 16] Option: UserCode=NoneThe value None for UserCode is a predefined choice
D [25/Jan/2008:15:53:47 +0000] [Job 16] --> Setting option
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%EndSetup
D [25/Jan/2008:15:53:47 +0000] [Job 16] Inserting PostScript code for CUPS' page accounting
D [25/Jan/2008:15:53:47 +0000] [Job 16]
D [25/Jan/2008:15:53:47 +0000] [Job 16] -----------
D [25/Jan/2008:15:53:47 +0000] [Job 16] New page:  1 1
D [25/Jan/2008:15:53:47 +0000] [Job 16] Inserting option code into "PageSetup" section.
D [25/Jan/2008:15:53:47 +0000] [Job 16]
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%BeginPageSetup
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found: %%EndPageSetup
D [25/Jan/2008:15:53:47 +0000] [Job 16] End of page header
D [25/Jan/2008:15:53:47 +0000] [Job 16] Stopping search for page header options
D [25/Jan/2008:15:53:47 +0000] [Job 16] Found:
D [25/Jan/2008:15:53:47 +0000] [Job 16] lineto				% Move there...
D [25/Jan/2008:15:53:47 +0000] [Job 16] --> Output goes directly to the renderer now.
D [25/Jan/2008:15:53:47 +0000] [Job 16]
D [25/Jan/2008:15:53:47 +0000] [Job 16]
D [25/Jan/2008:15:53:47 +0000] [Job 16] Starting renderer
D [25/Jan/2008:15:53:47 +0000] [Job 16] JCL: %-12345X at PJL
D [25/Jan/2008:15:53:47 +0000] [Job 16] @PJL SET RESOLUTION=600
D [25/Jan/2008:15:53:47 +0000] [Job 16] @PJL SET ECONOMODE=OFF
D [25/Jan/2008:15:53:47 +0000] [Job 16] @PJL SET DUPLEX=OFF
D [25/Jan/2008:15:53:47 +0000] [Job 16] @PJL SET MEDIATYPE=PLAINORRECYCLED
D [25/Jan/2008:15:53:47 +0000] [Job 16] @PJL SET OUTBIN=SYSDEFAULT
D [25/Jan/2008:15:53:47 +0000] [Job 16] @PJL SET STAPLE=OFF
D [25/Jan/2008:15:53:47 +0000] [Job 16] @PJL SET FOLD=OFF
D [25/Jan/2008:15:53:47 +0000] [Job 16] @PJL SET PUNCH=OFF
D [25/Jan/2008:15:53:47 +0000] [Job 16] <job data>
D [25/Jan/2008:15:53:47 +0000] [Job 16] %-12345X at PJL RESET
D [25/Jan/2008:15:53:47 +0000] [Job 16]
D [25/Jan/2008:15:53:47 +0000] [Job 16] renderer PID kid4=14684
D [25/Jan/2008:15:53:47 +0000] [Job 16] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dNOPAUSE -sDEVICE=pxlmono -r600x600 -sPAPERSIZE=letter -sOutputFile=- -
D [25/Jan/2008:15:53:47 +0000] [Job 16]
D [25/Jan/2008:15:53:47 +0000] [Job 16] Closing renderer
D [25/Jan/2008:15:53:47 +0000] [Job 16] perl: warning: Setting locale failed.
D [25/Jan/2008:15:53:47 +0000] [Job 16] perl: warning: Please check that your locale settings:
D [25/Jan/2008:15:53:47 +0000] [Job 16] LANGUAGE = (unset),
D [25/Jan/2008:15:53:47 +0000] [Job 16] LC_ALL = (unset),
D [25/Jan/2008:15:53:47 +0000] [Job 16] LANG = "en_US"
D [25/Jan/2008:15:53:47 +0000] [Job 16] are supported and installed on your system.
D [25/Jan/2008:15:53:47 +0000] [Job 16] perl: warning: Falling back to the standard locale ("C").
D [25/Jan/2008:15:53:47 +0000] [Job 16] foomatic-gswrapper: gs '-sstdout=%stderr' '-dBATCH' '-dPARANOIDSAFER' '-dNOPAUSE' '-sDEVICE=pxlmono' '-r600x600' '-sPAPERSIZE=letter' '-sOutputFile=%stdout' '-'
D [25/Jan/2008:15:53:48 +0000] [Job 16] ESP Ghostscript 815.03 (2006-08-25)
D [25/Jan/2008:15:53:48 +0000] [Job 16] Copyright (C) 2004 artofcode LLC, Benicia, CA.  All rights reserved.
D [25/Jan/2008:15:53:48 +0000] [Job 16] This software comes with NO WARRANTY: see the file PUBLIC for details.
D [25/Jan/2008:15:53:48 +0000] Discarding unused job-progress event...
D [25/Jan/2008:15:53:48 +0000] Discarding unused printer-state-changed event...
D [25/Jan/2008:15:53:48 +0000] [Job 16] Warning: the map file cidfmap was not found.
D [25/Jan/2008:15:53:48 +0000] [Job 16] Loading NimbusSanL-Bold font from /var/lib/defoma/gs.d/dirs/fonts/n019004l.pfb... 2436660 1120886 1878500 576932 1 done.
D [25/Jan/2008:15:53:48 +0000] [Job 16] Loading NimbusSanL-Regu font from /var/lib/defoma/gs.d/dirs/fonts/n019003l.pfb... 2553804 1247708 1878500 577696 1 done.
D [25/Jan/2008:15:53:48 +0000] cupsdReadClient: 10 GET /printers/buslab-bw HTTP/1.1
D [25/Jan/2008:15:53:48 +0000] cupsdAuthorize: No authentication data provided.
D [25/Jan/2008:15:53:49 +0000] [CGI] /usr/lib/cups/cgi-bin/printers.cgi started - PID = 14688
I [25/Jan/2008:15:53:49 +0000] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=14688)
D [25/Jan/2008:15:53:49 +0000] cupsdSendCommand: 10 file=13
D [25/Jan/2008:15:53:49 +0000] cupsdAcceptClient: 14 from localhost:631 (IPv4)
D [25/Jan/2008:15:53:49 +0000] cupsdReadClient: 14 POST / HTTP/1.1
D [25/Jan/2008:15:53:49 +0000] cupsdAuthorize: No authentication data provided.
D [25/Jan/2008:15:53:49 +0000] CUPS-Get-Default
D [25/Jan/2008:15:53:49 +0000] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)
D [25/Jan/2008:15:53:49 +0000] [CGI] show_printer(http=0x8072188, printer="buslab-bw")
D [25/Jan/2008:15:53:49 +0000] cupsdReadClient: 14 POST / HTTP/1.1
D [25/Jan/2008:15:53:49 +0000] cupsdAuthorize: No authentication data provided.
D [25/Jan/2008:15:53:49 +0000] Get-Printer-Attributes ipp://localhost/printers/buslab-bw
D [25/Jan/2008:15:53:49 +0000] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)
D [25/Jan/2008:15:53:49 +0000] [CGI] lang="en_US.UTF-8", locale="/en_US"...
D [25/Jan/2008:15:53:49 +0000] [CGI] lang="en_US.UTF-8", locale="/en_US"...
D [25/Jan/2008:15:53:49 +0000] [CGI] lang="en_US.UTF-8", locale="/en_US"...
D [25/Jan/2008:15:53:49 +0000] cupsdReadClient: 14 POST / HTTP/1.1
D [25/Jan/2008:15:53:49 +0000] cupsdAuthorize: No authentication data provided.
D [25/Jan/2008:15:53:49 +0000] Get-Jobs ipp://localhost:631/printers/buslab-bw
D [25/Jan/2008:15:53:49 +0000] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok)
D [25/Jan/2008:15:53:49 +0000] [CGI] lang="en_US.UTF-8", locale="/en_US"...
D [25/Jan/2008:15:53:49 +0000] [CGI] lang="en_US.UTF-8", locale="/en_US"...
D [25/Jan/2008:15:53:49 +0000] [CGI] lang="en_US.UTF-8", locale="/en_US"...
D [25/Jan/2008:15:53:49 +0000] [CGI] lang="en_US.UTF-8", locale="/en_US"...
D [25/Jan/2008:15:53:49 +0000] [CGI] lang="en_US.UTF-8", locale="/en_US"...
D [25/Jan/2008:15:53:49 +0000] [CGI] lang="en_US.UTF-8", locale="/en_US"...
D [25/Jan/2008:15:53:49 +0000] cupsdCloseClient: 14
D [25/Jan/2008:15:53:49 +0000] PID 14688 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [25/Jan/2008:15:53:49 +0000] [Job 16] Loading NimbusRomNo9L-Regu font from /var/lib/defoma/gs.d/dirs/fonts/n021003l.pfb... 2731236 1420298 1878500 580493 1 done.
D [25/Jan/2008:15:53:49 +0000] cupsdReadClient: 12 GET /images/printer-processing.gif HTTP/1.1
D [25/Jan/2008:15:53:49 +0000] cupsdAuthorize: No authentication data provided.
D [25/Jan/2008:15:53:49 +0000] cupsdReadClient: 10 GET /images/button-show-completed.gif HTTP/1.1
D [25/Jan/2008:15:53:49 +0000] cupsdAuthorize: No authentication data provided.
D [25/Jan/2008:15:53:49 +0000] cupsdReadClient: 12 GET /images/button-restart-job.gif HTTP/1.1
D [25/Jan/2008:15:53:49 +0000] cupsdAuthorize: No authentication data provided.
D [25/Jan/2008:15:53:49 +0000] cupsdReadClient: 10 GET /images/button-show-all.gif HTTP/1.1
D [25/Jan/2008:15:53:49 +0000] cupsdAuthorize: No authentication data provided.
D [25/Jan/2008:15:53:49 +0000] cupsdReadClient: 12 GET /images/button-move-job.gif HTTP/1.1
D [25/Jan/2008:15:53:49 +0000] cupsdAuthorize: No authentication data provided.
D [25/Jan/2008:15:53:49 +0000] cupsdReadClient: 10 GET /images/button-cancel-job.gif HTTP/1.1
D [25/Jan/2008:15:53:49 +0000] cupsdAuthorize: No authentication data provided.
D [25/Jan/2008:15:53:49 +0000] [Job 16] tail process done writing data to STDOUT
D [25/Jan/2008:15:53:49 +0000] [Job 16] KID4 finished
D [25/Jan/2008:15:53:49 +0000] [Job 16] KID3 exited with status 0
D [25/Jan/2008:15:53:49 +0000] [Job 16] KID4 exited with status 0
D [25/Jan/2008:15:53:49 +0000] [Job 16] Renderer exit stat: 0
D [25/Jan/2008:15:53:49 +0000] cupsdReadClient: 8 POST / HTTP/1.1
D [25/Jan/2008:15:53:49 +0000] cupsdAuthorize: No authentication data provided.
D [25/Jan/2008:15:53:49 +0000] CUPS-Get-Printers
D [25/Jan/2008:15:53:49 +0000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [25/Jan/2008:15:53:49 +0000] cupsdReadClient: 8 POST / HTTP/1.1
D [25/Jan/2008:15:53:49 +0000] cupsdAuthorize: No authentication data provided.
D [25/Jan/2008:15:53:49 +0000] Get-Printer-Attributes ipp://localhost/printers/buslab-bw
D [25/Jan/2008:15:53:49 +0000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [25/Jan/2008:15:53:49 +0000] cupsdReadClient: 8 POST / HTTP/1.1
D [25/Jan/2008:15:53:49 +0000] cupsdAuthorize: No authentication data provided.
D [25/Jan/2008:15:53:49 +0000] Get-Printer-Attributes ipp://localhost/printers/buslab-color
D [25/Jan/2008:15:53:49 +0000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [25/Jan/2008:15:53:49 +0000] cupsdReadClient: 8 POST / HTTP/1.1
D [25/Jan/2008:15:53:49 +0000] cupsdAuthorize: No authentication data provided.
D [25/Jan/2008:15:53:49 +0000] Get-Printer-Attributes ipp://localhost/printers/sc-public
D [25/Jan/2008:15:53:49 +0000] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [25/Jan/2008:15:53:49 +0000] [Job 16] KID3 finished
D [25/Jan/2008:15:53:49 +0000] [Job 16] Renderer process finished
D [25/Jan/2008:15:53:49 +0000] [Job 16]
D [25/Jan/2008:15:53:49 +0000] [Job 16] Closing foomatic-rip.
D [25/Jan/2008:15:53:49 +0000] [Job 16] 1 files to send in job...
D [25/Jan/2008:15:53:49 +0000] Discarding unused printer-state-changed event...
D [25/Jan/2008:15:53:49 +0000] PID 14678 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [25/Jan/2008:15:53:49 +0000] Discarding unused printer-state-changed event...
D [25/Jan/2008:15:53:49 +0000] [Job 16] Connected to 172.20.1.101:631 (IPv4)...
D [25/Jan/2008:15:53:49 +0000] [Job 16] Getting supported attributes...
D [25/Jan/2008:15:53:49 +0000] Discarding unused printer-state-changed event...
D [25/Jan/2008:15:53:49 +0000] [Job 16] document-format-supported (3 values)
D [25/Jan/2008:15:53:49 +0000] [0] = "text/plain"
D [25/Jan/2008:15:53:49 +0000] [1] = "application/octet-stream"
D [25/Jan/2008:15:53:49 +0000] [2] = "application/pdf"
D [25/Jan/2008:15:53:49 +0000] [Job 16] printer-uri = "ipp://iprint1.wheaton.edu:631/ipp/buslab-bw"
D [25/Jan/2008:15:53:49 +0000] [Job 16] requesting-user-name = "guest"
D [25/Jan/2008:15:53:49 +0000] [Job 16] job-name = "Test Page"
D [25/Jan/2008:15:53:49 +0000] Discarding unused printer-state-changed event...
N [25/Jan/2008:15:53:50 +0000] [Job 16] Print file accepted - job ID 71905.
D [25/Jan/2008:15:53:50 +0000] Discarding unused printer-state-changed event...
D [25/Jan/2008:15:53:50 +0000] Discarding unused printer-state-changed event...

--Jeff