D [14/Apr/2012:16:53:45 -0500] [Job 11] Adding start banner page "none".
D [14/Apr/2012:16:53:45 -0500] [Job 11] Adding end banner page "none".
D [14/Apr/2012:16:53:45 -0500] [Job 11] File of type application/pdf queued by "tim".
D [14/Apr/2012:16:53:45 -0500] [Job 11] hold_until=0
D [14/Apr/2012:16:53:45 -0500] [Job 11] Queued on "CanonMP620" by "tim".
D [14/Apr/2012:16:53:45 -0500] [Job 11] job-sheets=none,none
D [14/Apr/2012:16:53:45 -0500] [Job 11] argv[0]="CanonMP620"
D [14/Apr/2012:16:53:45 -0500] [Job 11] argv[1]="11"
D [14/Apr/2012:16:53:45 -0500] [Job 11] argv[2]="tim"
D [14/Apr/2012:16:53:45 -0500] [Job 11] argv[3]="CanonMP620 - CUPS 1.4.8"
D [14/Apr/2012:16:53:45 -0500] [Job 11] argv[4]="1"
D [14/Apr/2012:16:53:45 -0500] [Job 11] argv[5]="CNGamma=1.8 CNBalanceC=0 CNBalanceM=0 number-up=1 CNContrast=0 Duplex=None CNBalanceY=0 Resolution=600 InputSlot=cassette PageSize=Letter noCNGrayscale CNHalftoning=ed CNQuality=3 MediaType=plain CNRenderIntent=photo CNDensity=0 job-uuid=urn:uuid:276f05bb-39e9-3b63-559e-62846ffd80d2 job-originating-host-name=localhost time-at-creation=1334440424 time-at-processing=1334440424"
D [14/Apr/2012:16:53:45 -0500] [Job 11] argv[6]="/var/spool/cups/d00011-001"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[2]="CUPS_DOCROOT=/usr/share/cups/html"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[5]="CUPS_SERVERBIN=/usr/libexec/cups"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[8]="HOME=/var/spool/cups/tmp"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[9]="PATH=/usr/libexec/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[10]="SERVER_ADMIN=root@sabayon"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[11]="SOFTWARE=CUPS/1.4.8"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[13]="USER=root"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[16]="IPP_PORT=631"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[17]="CHARSET=utf-8"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[18]="LANG=en_US.UTF-8"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[19]="PPD=/etc/cups/ppd/CanonMP620.ppd"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[20]="RIP_MAX_CACHE=8m"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[21]="CONTENT_TYPE=application/pdf"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[22]="DEVICE_URI=ipp://192.168.1.123/ipp/"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[23]="PRINTER_INFO=Canon Pixma MP620"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[24]="PRINTER_LOCATION=Tommys Room"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[25]="PRINTER=CanonMP620"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[26]="CUPS_FILETYPE=document"
D [14/Apr/2012:16:53:45 -0500] [Job 11] envp[27]="FINAL_CONTENT_TYPE=printer/CanonMP620"
D [14/Apr/2012:16:53:45 -0500] [Job 11] Started filter /usr/libexec/cups/filter/pdftops (PID 19647)
D [14/Apr/2012:16:53:45 -0500] [Job 11] Started filter /usr/libexec/cups/filter/pstocanonij (PID 19648)
D [14/Apr/2012:16:53:45 -0500] [Job 11] Started backend /usr/libexec/cups/backend/ipp (PID 19649)
D [14/Apr/2012:16:53:45 -0500] [Job 11] STATE: +connecting-to-device
D [14/Apr/2012:16:53:45 -0500] [Job 11] Looking up "192.168.1.123"...
D [14/Apr/2012:16:53:45 -0500] [Job 11] Copying print data...
D [14/Apr/2012:16:53:45 -0500] [Job 11] Set job-printer-state-message to "Copying print data...", current level=INFO
D [14/Apr/2012:16:53:45 -0500] [Job 11] backendRunLoop(print_fd=-1, device_fd=6, snmp_fd=5, addr=0x7f65faa069d8, use_bc=0, side_cb=0x7f65fa4bcc60)
D [14/Apr/2012:16:53:45 -0500] [Job 11] pstocanonij start.
D [14/Apr/2012:16:53:45 -0500] [Job 11] Started filter pdftops (PID 19650)
D [14/Apr/2012:16:53:45 -0500] [Job 11] Started filter pstops (PID 19651)
D [14/Apr/2012:16:53:45 -0500] [Job 11] Page = 612x792; 18,14 to 594,784
D [14/Apr/2012:16:53:45 -0500] [Job 11] slow_collate=0, slow_duplex=0, slow_order=0
D [14/Apr/2012:16:53:45 -0500] [Job 11] Before copy_comments - %!PS-Adobe-3.0
D [14/Apr/2012:16:53:45 -0500] [Job 11] %!PS-Adobe-3.0
D [14/Apr/2012:16:53:45 -0500] [Job 11] %Produced by poppler pdftops version: 0.18.3 (
http://poppler.freedesktop.org)
D [14/Apr/2012:16:53:45 -0500] [Job 11] %%Creator: cairo 1.10.2 (
http://cairographics.org)
D [14/Apr/2012:16:53:45 -0500] [Job 11] %%LanguageLevel: 3
D [14/Apr/2012:16:53:45 -0500] [Job 11] %%DocumentSuppliedResources: (atend)
D [14/Apr/2012:16:53:45 -0500] [Job 11] %%DocumentMedia: plain 612 792 0 () ()
D [14/Apr/2012:16:53:45 -0500] [Job 11] %%BoundingBox: 0 0 612 792
D [14/Apr/2012:16:53:45 -0500] [Job 11] %%Pages: 1
D [14/Apr/2012:16:53:45 -0500] [Job 11] %%EndComments
D [14/Apr/2012:16:53:45 -0500] [Job 11] Before copy_prolog - %%BeginDefaults
D [14/Apr/2012:16:53:45 -0500] [Job 11] Before copy_setup - %%BeginSetup
D [14/Apr/2012:16:53:45 -0500] [Job 11] Before page loop - %%Page: 1 1
D [14/Apr/2012:16:53:45 -0500] [Job 11] Copying page 1...
D [14/Apr/2012:16:53:45 -0500] [Job 11] pagew = 576.0, pagel = 769.3
D [14/Apr/2012:16:53:45 -0500] [Job 11] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [14/Apr/2012:16:53:45 -0500] [Job 11] PageLeft = 18.1, PageRight = 594.1
D [14/Apr/2012:16:53:45 -0500] [Job 11] PageTop = 783.5, PageBottom = 14.2
D [14/Apr/2012:16:53:45 -0500] [Job 11] PageWidth = 612.0, PageLength = 792.0
D [14/Apr/2012:16:53:45 -0500] [Job 11] # p_size->name=Letter , p_size_default->name = A4 #
D [14/Apr/2012:16:53:45 -0500] [Job 11] # media_exist=0 , pagesize_exist = 1 #
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### num_opt(lpr optins) = 20 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11]
D [14/Apr/2012:16:53:45 -0500] [Job 11] # ppdPageSize width=612.000000 height=792.000000 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11]
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : p_choice found in p_ppd (p_choice:Lettre US 215.9x279.4mm 8.5"x11" / Letter)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : choice=Letter(p_table->ppd_key=PageSize)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : p_choice found in p_ppd (p_choice:Papier ordinaire / plain)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : choice=plain(p_table->ppd_key=MediaType)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : p_choice found in p_ppd (p_choice:Cassette / cassette)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : choice=cassette(p_table->ppd_key=InputSlot)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : CNCartridge is not found in p_ppd
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : p_choice found in p_ppd (p_choice:Normale / 3)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : choice=3(p_table->ppd_key=CNQuality)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : p_choice found in p_ppd (p_choice:Diffusion / ed)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : choice=ed(p_table->ppd_key=CNHalftoning)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : p_choice found in p_ppd (p_choice:Photo / photo)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : choice=photo(p_table->ppd_key=CNRenderIntent)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : p_choice found in p_ppd (p_choice:Normal / 1.

D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : choice=1.8(p_table->ppd_key=CNGamma)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : p_choice found in p_ppd (p_choice:-0- / 0)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : choice=0(p_table->ppd_key=CNBalanceC)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : p_choice found in p_ppd (p_choice:-0- / 0)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : choice=0(p_table->ppd_key=CNBalanceM)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : p_choice found in p_ppd (p_choice:-0- / 0)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : choice=0(p_table->ppd_key=CNBalanceY)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : p_choice found in p_ppd (p_choice:-0- / 0)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : choice=0(p_table->ppd_key=CNDensity)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : p_choice found in p_ppd (p_choice:No / False)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : choice=False(p_table->ppd_key=CNGrayscale)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : CNLocation is not found in p_ppd
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : CNPercent is not found in p_ppd
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : CNCopies is not found in p_ppd
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : p_choice found in p_ppd (p_choice:Non / None)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : CNStapleSide is not found in p_ppd
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : p_choice found in p_ppd (p_choice:-0- / 0)
D [14/Apr/2012:16:53:45 -0500] [Job 11] (3)Make cif options : choice=0(p_table->ppd_key=CNContrast)
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(19) : CNBalanceC=0 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(19)(opt_key != NULL) : --balance_c=0 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(18) : CNBalanceM=0 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(18)(opt_key != NULL) : --balance_m=0 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(17) : CNBalanceY=0 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(17)(opt_key != NULL) : --balance_y=0 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(16) : CNContrast=0 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(16)(opt_key != NULL) : --contrast=0 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(15) : CNDensity=0 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(15)(opt_key != NULL) : --density=0 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(14) : CNGamma=1.8 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(14)(opt_key != NULL) : --gamma=1.8 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(13) : CNGrayscale=false ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(13)(opt_key != NULL) : --grayscale=false ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(12) : CNHalftoning=ed ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(12)(opt_key != NULL) : --halftoning=ed ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(11) : CNQuality=3 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(11)(opt_key != NULL) : --quality=3 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(10) : CNRenderIntent=photo ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(10)(opt_key != NULL) : --renderintent=photo ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(9) : Duplex=None ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(9)(opt_key != NULL) : --duplex=none ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(8) : InputSlot=cassette ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(8)(opt_key != NULL) : --paperload=cassette ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(7) : job-originating-host-name=localhost ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(6) : job-uuid=urn:uuid:276f05bb-39e9-3b63-559e-62846ffd80d2 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(5) : MediaType=plain ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(5)(opt_key != NULL) : --media=plain ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(4) : number-up=1 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(3) : PageSize=Letter ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(3)(opt_key != NULL) : --papersize=letter ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(2) : Resolution=600 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(1) : time-at-creation=1334440424 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] ### lpr optins(0) : time-at-processing=1334440424 ###
D [14/Apr/2012:16:53:45 -0500] [Job 11] make_filter_param_list: --fit
D [14/Apr/2012:16:53:45 -0500] [Job 11] make_filter_param_list: --bbox
D [14/Apr/2012:16:53:45 -0500] [Job 11] make_filter_param_list: --contrast
D [14/Apr/2012:16:53:45 -0500] [Job 11] make_filter_param_list: --grayscale
D [14/Apr/2012:16:53:45 -0500] [Job 11] make_filter_param_list: --density
D [14/Apr/2012:16:53:45 -0500] [Job 11] make_filter_param_list: --balance_y
D [14/Apr/2012:16:53:45 -0500] [Job 11] make_filter_param_list: --balance_m
D [14/Apr/2012:16:53:45 -0500] [Job 11] make_filter_param_list: --balance_c
D [14/Apr/2012:16:53:45 -0500] [Job 11] make_filter_param_list: --gamma
D [14/Apr/2012:16:53:45 -0500] [Job 11] make_filter_param_list: --renderintent
D [14/Apr/2012:16:53:45 -0500] [Job 11] make_filter_param_list: --halftoning
D [14/Apr/2012:16:53:45 -0500] [Job 11] make_filter_param_list: --quality
D [14/Apr/2012:16:53:45 -0500] [Job 11] make_filter_param_list: --paperload
D [14/Apr/2012:16:53:45 -0500] [Job 11] make_filter_param_list: --media
D [14/Apr/2012:16:53:45 -0500] [Job 11] make_filter_param_list: --papersize
D [14/Apr/2012:16:53:45 -0500] [Job 11] pstocanonij: /usr/bin/gs -r600 -g5100x6600 -q -dNOPROMPT -dSAFER -sDEVICE=ppmraw -sOutputFile=- -| /usr/bin/cifmp610 --imageres 600 --papersize letter --media plain --paperload cassette --quality 3 --halftoning ed --renderintent photo --gamma 1.8 --balance_c 0 --balance_m 0 --balance_y 0 --density 0 --contrast 0 --bbox 18,14,595,784 --fit
D [14/Apr/2012:16:53:45 -0500] [Job 11] /usr/bin/cifmp610: error while loading shared libraries: libcnbpcmcm327.so: cannot open shared object file: No such file or directory
D [14/Apr/2012:16:53:45 -0500] [Job 11] Wrote 1 pages...
D [14/Apr/2012:16:53:45 -0500] [Job 11] PID 19651 (pstops) exited with no errors.
D [14/Apr/2012:16:53:45 -0500] [Job 11] PID 19650 (pdftops) exited with no errors.
D [14/Apr/2012:16:53:45 -0500] [Job 11] Set job-printer-state-message to "Empty print file!", current level=ERROR
D [14/Apr/2012:16:53:45 -0500] [Job 11] Backend returned status 1 (failed)
D [14/Apr/2012:16:53:45 -0500] [Job 11] Printer stopped due to backend errors; please consult the error_log file for details.
D [14/Apr/2012:16:53:45 -0500] [Job 11] End of messages
D [14/Apr/2012:16:53:45 -0500] [Job 11] printer-state=5(stopped)
D [14/Apr/2012:16:53:45 -0500] [Job 11] printer-state-message="Empty print file!"
D [14/Apr/2012:16:53:45 -0500] [Job 11] printer-state-reasons=paused