E [13/Mar/2011:21:29:06 -0400] [Job 9] Empty print file!
E [13/Mar/2011:21:29:06 -0400] PID 25585 (/usr/lib/cups/filter/foomatic-rip) crashed on signal 6!
D [13/Mar/2011:21:29:06 -0400] [Job 9] The following messages were recorded from 21:29:03 to 21:29:06
D [13/Mar/2011:21:29:06 -0400] [Job 9] Queued on "printer" by "gruber".
D [13/Mar/2011:21:29:06 -0400] [Job 9] Auto-typing file...
D [13/Mar/2011:21:29:06 -0400] [Job 9] Request file type is text/plain.
D [13/Mar/2011:21:29:06 -0400] [Job 9] File of type text/plain queued by "gruber".
D [13/Mar/2011:21:29:06 -0400] [Job 9] No job-sheets attribute.
D [13/Mar/2011:21:29:06 -0400] [Job 9] ... but someone added one without setting job_sheets!
D [13/Mar/2011:21:29:06 -0400] [Job 9] argv[0]="printer"
D [13/Mar/2011:21:29:06 -0400] [Job 9] argv[1]="9"
D [13/Mar/2011:21:29:06 -0400] [Job 9] argv[2]="gruber"
D [13/Mar/2011:21:29:06 -0400] [Job 9] argv[3]="control"
D [13/Mar/2011:21:29:06 -0400] [Job 9] argv[4]="1"
D [13/Mar/2011:21:29:06 -0400] [Job 9] argv[5]="job-originating-user-name=gruber finishings=3 job-priority=50 job-sheets=none,none number-up=1 outputorder=reverse job-uuid=urn:uuid:65e0a9b1-fa85-3daf-48b2-cd1901a26eb4 job-originating-host-name=localhost time-at-creation=1300066143 time-at-processing=1300066143 job-id=9 job-state=5 job-media-sheets-completed=0 job-k-octets=1 AP_D_InputSlot="
D [13/Mar/2011:21:29:06 -0400] [Job 9] argv[6]="/var/spool/cups/d00009-001"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[8]="HOME=/var/spool/cups/tmp"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[10]="SERVER_ADMIN=root@gruber-Satellite-L355D"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[11]="SOFTWARE=CUPS/1.4.6"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[13]="USER=root"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[16]="IPP_PORT=631"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[17]="CHARSET=utf-8"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[18]="LANG=en_US.UTF-8"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[19]="PPD=/etc/cups/ppd/printer.ppd"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[20]="RIP_MAX_CACHE=auto"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[21]="CONTENT_TYPE=text/plain"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[22]="DEVICE_URI=ipp://192.168.1.6/printers/S600"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[23]="PRINTER_INFO=S600"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[24]="PRINTER_LOCATION="
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[25]="PRINTER=printer"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[26]="CUPS_FILETYPE=document"
D [13/Mar/2011:21:29:06 -0400] [Job 9] envp[27]="FINAL_CONTENT_TYPE=printer/printer"
D [13/Mar/2011:21:29:06 -0400] [Job 9] Started filter /usr/lib/cups/filter/texttopdf (PID 25583)
D [13/Mar/2011:21:29:06 -0400] [Job 9] Started filter /usr/lib/cups/filter/pdftopdf (PID 25584)
D [13/Mar/2011:21:29:06 -0400] [Job 9] Started filter /usr/lib/cups/filter/foomatic-rip (PID 25585)
D [13/Mar/2011:21:29:06 -0400] [Job 9] Started backend /usr/lib/cups/backend/ipp (PID 25586)
D [13/Mar/2011:21:29:06 -0400] [Job 9] Page = 612x792; 18,36 to 594,756
D [13/Mar/2011:21:29:06 -0400] [Job 9] Getting input from file 
D [13/Mar/2011:21:29:06 -0400] [Job 9] foomatic-rip version 4.0.7.235 running...
D [13/Mar/2011:21:29:06 -0400] [Job 9] Parsing PPD file ...
D [13/Mar/2011:21:29:06 -0400] [Job 9] Added option Resolution
D [13/Mar/2011:21:29:06 -0400] [Job 9] Added option PageSize
D [13/Mar/2011:21:29:06 -0400] [Job 9] Added option ImageableArea
D [13/Mar/2011:21:29:06 -0400] [Job 9] Added option PaperDimension
D [13/Mar/2011:21:29:06 -0400] [Job 9] Added option Quality
D [13/Mar/2011:21:29:06 -0400] [Job 9] Added option Font
D [13/Mar/2011:21:29:06 -0400] [Job 9] 
D [13/Mar/2011:21:29:06 -0400] [Job 9] Parameter Summary
D [13/Mar/2011:21:29:06 -0400] [Job 9] -----------------
D [13/Mar/2011:21:29:06 -0400] [Job 9] 
D [13/Mar/2011:21:29:06 -0400] [Job 9] Spooler: cups
D [13/Mar/2011:21:29:06 -0400] [Job 9] Printer: printer
D [13/Mar/2011:21:29:06 -0400] [Job 9] Shell: /bin/bash
D [13/Mar/2011:21:29:06 -0400] [Job 9] PPD file: /etc/cups/ppd/printer.ppd
D [13/Mar/2011:21:29:06 -0400] [Job 9] ATTR file: 
D [13/Mar/2011:21:29:06 -0400] [Job 9] Printer model: Canon S600 Foomatic/bj8XXYYZ.upp (recommended)
D [13/Mar/2011:21:29:06 -0400] [Job 9] Job title: control
D [13/Mar/2011:21:29:06 -0400] [Job 9] File(s) to be printed:
D [13/Mar/2011:21:29:06 -0400] [Job 9] <STDIN>
D [13/Mar/2011:21:29:06 -0400] [Job 9] 
D [13/Mar/2011:21:29:06 -0400] [Job 9] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [13/Mar/2011:21:29:06 -0400] [Job 9] Printing system options:
D [13/Mar/2011:21:29:06 -0400] [Job 9] Pondering option 'job-originating-user-name=gruber'
D [13/Mar/2011:21:29:06 -0400] [Job 9] Unknown option job-originating-user-name=gruber.
D [13/Mar/2011:21:29:06 -0400] [Job 9] Pondering option 'finishings=3'
D [13/Mar/2011:21:29:06 -0400] [Job 9] Unknown option finishings=3.
D [13/Mar/2011:21:29:06 -0400] [Job 9] Pondering option 'job-priority=50'
D [13/Mar/2011:21:29:06 -0400] [Job 9] Unknown option job-priority=50.
D [13/Mar/2011:21:29:06 -0400] [Job 9] Pondering option 'job-sheets=none'
D [13/Mar/2011:21:29:06 -0400] [Job 9] Unknown option job-sheets=none.
D [13/Mar/2011:21:29:06 -0400] [Job 9] Pondering option 'none'
D [13/Mar/2011:21:29:06 -0400] [Job 9] Pondering option 'number-up=1'
D [13/Mar/2011:21:29:06 -0400] [Job 9] Unknown option number-up=1.
D [13/Mar/2011:21:29:06 -0400] [Job 9] Pondering option 'outputorder=reverse'
D [13/Mar/2011:21:29:06 -0400] [Job 9] Unknown option outputorder=reverse.
D [13/Mar/2011:21:29:06 -0400] [Job 9] Pondering option 'job-uuid=urn:uuid:65e0a9b1-fa85-3daf-48b2-cd1901a26eb4'
D [13/Mar/2011:21:29:06 -0400] [Job 9] Unknown option job-uuid=urn:uuid:65e0a9b1-fa85-3daf-48b2-cd1901a26eb4.
D [13/Mar/2011:21:29:06 -0400] [Job 9] Pondering option 'job-originating-host-name=localhost'
D [13/Mar/2011:21:29:06 -0400] [Job 9] Unknown option job-originating-host-name=localhost.
D [13/Mar/2011:21:29:06 -0400] [Job 9] Pondering option 'time-at-creation=1300066143'
D [13/Mar/2011:21:29:06 -0400] [Job 9] Unknown option time-at-creation=1300066143.
D [13/Mar/2011:21:29:06 -0400] [Job 9] Pondering option 'time-at-processing=1300066143'
D [13/Mar/2011:21:29:06 -0400] [Job 9] Unknown option time-at-processing=1300066143.
D [13/Mar/2011:21:29:06 -0400] [Job 9] Pondering option 'job-id=9'
D [13/Mar/2011:21:29:06 -0400] [Job 9] Unknown option job-id=9.
D [13/Mar/2011:21:29:06 -0400] [Job 9] Pondering option 'job-state=5'
D [13/Mar/2011:21:29:06 -0400] [Job 9] Unknown option job-state=5.
D [13/Mar/2011:21:29:06 -0400] [Job 9] Pondering option 'job-media-sheets-completed=0'
D [13/Mar/2011:21:29:06 -0400] [Job 9] Unknown option job-media-sheets-completed=0.
D [13/Mar/2011:21:29:06 -0400] [Job 9] Pondering option 'job-k-octets=1'
D [13/Mar/2011:21:29:06 -0400] [Job 9] Unknown option job-k-octets=1.
D [13/Mar/2011:21:29:06 -0400] [Job 9] Pondering option 'AP_D_InputSlot='
D [13/Mar/2011:21:29:06 -0400] [Job 9] Unknown option AP_D_InputSlot=.
D [13/Mar/2011:21:29:06 -0400] [Job 9] Options from the PPD file:
D [13/Mar/2011:21:29:06 -0400] [Job 9] 
D [13/Mar/2011:21:29:06 -0400] [Job 9] ================================================
D [13/Mar/2011:21:29:06 -0400] [Job 9] 
D [13/Mar/2011:21:29:06 -0400] [Job 9] File: <STDIN>
D [13/Mar/2011:21:29:06 -0400] [Job 9] 
D [13/Mar/2011:21:29:06 -0400] [Job 9] ================================================
D [13/Mar/2011:21:29:06 -0400] [Job 9] 
D [13/Mar/2011:21:29:06 -0400] [Job 9] STATE: +connecting-to-device
D [13/Mar/2011:21:29:06 -0400] [Job 9] Looking up "192.168.1.6"...
D [13/Mar/2011:21:29:06 -0400] [Job 9] Copying print data...
D [13/Mar/2011:21:29:06 -0400] [Job 9] backendRunLoop(print_fd=-1, device_fd=6, snmp_fd=5, addr=0x21143064, use_bc=0, side_cb=0xc8d140)
D [13/Mar/2011:21:29:06 -0400] [Job 9] Filetype: PDF
D [13/Mar/2011:21:29:06 -0400] [Job 9] Storing temporary files in /var/spool/cups/tmp
D [13/Mar/2011:21:29:06 -0400] [Job 9] File contains 1 pages
D [13/Mar/2011:21:29:06 -0400] [Job 9] printer: options.c:763: build_foomatic_custom_command: Assertion `choice' failed.
D [13/Mar/2011:21:29:06 -0400] [Job 9] Set job-printer-state-message to "Empty print file!", current level=ERROR
D [13/Mar/2011:21:29:06 -0400] [Job 9] Backend returned status 1 (failed)
D [13/Mar/2011:21:29:06 -0400] [Job 9] End of messages
D [13/Mar/2011:21:29:06 -0400] [Job 9] printer-state=3(idle)
D [13/Mar/2011:21:29:06 -0400] [Job 9] printer-state-message="Empty print file!"
D [13/Mar/2011:21:29:06 -0400] [Job 9] printer-state-reasons=none