D [24/Jan/2018:15:48:13 +0100] [Client 295] 2.0 Create-Job 11
D [24/Jan/2018:16:54:34 +0100] [Job 11] Applying default options...
I [24/Jan/2018:16:54:34 +0100] [Job 11] Adding start banner page "none".
I [24/Jan/2018:16:54:34 +0100] [Job 11] Adding end banner page "none".
I [24/Jan/2018:16:54:34 +0100] [Job 11] File of type application/pdf queued by "tim".
D [24/Jan/2018:16:54:34 +0100] [Job 11] hold_until=0
I [24/Jan/2018:16:54:34 +0100] [Job 11] Queued on "Canon-iR1020-1024-1025-UFRII-LT" by "tim".
D [24/Jan/2018:16:54:34 +0100] [Job 11] time-at-processing=1516809274
D [24/Jan/2018:16:54:34 +0100] [Job 11] 3 filters for job:
D [24/Jan/2018:16:54:34 +0100] [Job 11] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [24/Jan/2018:16:54:34 +0100] [Job 11] pdftops (application/vnd.cups-pdf to application/vnd.cups-postscript, cost 100)
D [24/Jan/2018:16:54:34 +0100] [Job 11] pstoufr2cpca (application/vnd.cups-postscript to printer/Canon-iR1020-1024-1025-UFRII-LT, cost 0)
D [24/Jan/2018:16:54:34 +0100] [Job 11] job-sheets=none,none
D [24/Jan/2018:16:54:34 +0100] [Job 11] argv[0]="Canon-iR1020-1024-1025-UFRII-LT"
D [24/Jan/2018:16:54:34 +0100] [Job 11] argv[1]="11"
D [24/Jan/2018:16:54:34 +0100] [Job 11] argv[2]="tim"
D [24/Jan/2018:16:54:34 +0100] [Job 11] argv[3]="Untitled Document 1"
D [24/Jan/2018:16:54:34 +0100] [Job 11] argv[4]="1"
D [24/Jan/2018:16:54:34 +0100] [Job 11] argv[5]="InputSlot=Auto noCollate number-up=1 noCNTonerSaving MediaType=Auto PageSize=Letter CNHalftone=Gradation CNImageRefinement=None OutputBin=Auto BindEdge=Left Duplex=None job-uuid=urn:uuid:d60aeb7a-3490-3632-571d-b96a78341a33 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1516809274 time-at-processing=1516809274"
D [24/Jan/2018:16:54:34 +0100] [Job 11] argv[6]="/var/spool/cups/d00011-001"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[7]="CUPS_STATEDIR=/run/cups"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[8]="HOME=/var/spool/cups/tmp"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[10]="SERVER_ADMIN=root@debianLaptop"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[11]="SOFTWARE=CUPS/2.2.6"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[13]="USER=root"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[14]="CUPS_MAX_MESSAGE=2047"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[15]="CUPS_SERVER=/run/cups/cups.sock"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[17]="IPP_PORT=631"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[18]="CHARSET=utf-8"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[19]="LANG=en_US.UTF-8"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[20]="PPD=/etc/cups/ppd/Canon-iR1020-1024-1025-UFRII-LT.ppd"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[21]="RIP_MAX_CACHE=128m"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[22]="CONTENT_TYPE=application/pdf"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[23]="DEVICE_URI=lpd://10.0.3.1"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[24]="PRINTER_INFO=Canon-iR1020-1024-1025-UFRII-LT"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[25]="PRINTER_LOCATION="
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[26]="PRINTER=Canon-iR1020-1024-1025-UFRII-LT"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[27]="PRINTER_STATE_REASONS=none"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[28]="CUPS_FILETYPE=document"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
D [24/Jan/2018:16:54:34 +0100] [Job 11] envp[30]="AUTH_I****"
I [24/Jan/2018:16:54:34 +0100] [Job 11] Started filter /usr/lib/cups/filter/pdftopdf (PID 19055)
I [24/Jan/2018:16:54:34 +0100] [Job 11] Started filter /usr/lib/cups/filter/pdftops (PID 19056)
I [24/Jan/2018:16:54:34 +0100] [Job 11] Started filter /usr/lib/cups/filter/pstoufr2cpca (PID 19057)
I [24/Jan/2018:16:54:34 +0100] [Job 11] Started backend /usr/lib/cups/backend/lpd (PID 19058)
D [24/Jan/2018:16:54:34 +0100] [Job 11] pdftopdf: Last filter determined by the PPD: pstoufr2cpca; FINAL_CONTENT_TYPE: application/vnd.cups-postscript => pdftopdf will not log pages in page_log.
D [24/Jan/2018:16:54:34 +0100] [Job 11] pdftops - copying to temp print file \"/tmp/04a705a73bdd7\"
D [24/Jan/2018:16:54:34 +0100] [Job 11] PID 19055 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [24/Jan/2018:16:54:34 +0100] [Job 11] Printer make and model: Canon ir1020/1024/1025
D [24/Jan/2018:16:54:34 +0100] [Job 11] Running command line for pstops: pstops 11 tim \'Untitled Document 1\' 1 \'InputSlot=Auto noCNTonerSaving MediaType=Auto PageSize=Letter CNHalftone=Gradation CNImageRefinement=None OutputBin=Auto BindEdge=Left Duplex=None job-uuid=urn:uuid:d60aeb7a-3490-3632-571d-b96a78341a33 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1516809274 time-at-processing=1516809274\'
D [24/Jan/2018:16:54:34 +0100] [Job 11] Using image rendering resolution 600 dpi
D [24/Jan/2018:16:54:34 +0100] [Job 11] Running command line for gs: gs -q -dNOPAUSE -dBATCH -dSAFER -dNOMEDIAATTRS -sDEVICE=ps2write -dShowAcroForm -sOUTPUTFILE=%stdout -dLanguageLevel=3 -r600 -dCompressFonts=false -dNoT3CCITT -dNOINTERPOLATE -c \'save pop\' -f /tmp/04a705a73bdd7
D [24/Jan/2018:16:54:34 +0100] [Job 11] Started filter gs (PID 19059)
D [24/Jan/2018:16:54:34 +0100] [Job 11] Started filter pstops (PID 19060)
D [24/Jan/2018:16:54:34 +0100] [Job 11] STATE: +connecting-to-device
D [24/Jan/2018:16:54:34 +0100] [Job 11] Looking up \"10.0.3.1\"...
D [24/Jan/2018:16:54:34 +0100] [Job 11] STATE: -connecting-to-device
D [24/Jan/2018:16:54:34 +0100] [Job 11] 10.0.3.1=10.0.3.1
D [24/Jan/2018:16:54:34 +0100] [Job 11] backendWaitLoop(snmp_fd=5, addr=0x55e71f1a5c78, side_cb=0x55e71db8cce0)
D [24/Jan/2018:16:54:34 +0100] [Job 11] pstoufr2cpca start.
D [24/Jan/2018:16:54:34 +0100] [Job 11] Page = 612x792; 14,14 to 598,778
D [24/Jan/2018:16:54:34 +0100] [Job 11] slow_collate=0, slow_duplex=0, slow_order=0
D [24/Jan/2018:16:54:34 +0100] [Job 11] Before copy_comments - %!PS-Adobe-3.0
D [24/Jan/2018:16:54:34 +0100] [Job 11] %!PS-Adobe-3.0
D [24/Jan/2018:16:54:34 +0100] [Job 11] %%BoundingBox: 0 0 612 792
D [24/Jan/2018:16:54:34 +0100] [Job 11] %%HiResBoundingBox: 0 0 612.00 792.00
D [24/Jan/2018:16:54:34 +0100] [Job 11] %%Creator: GPL Ghostscript 922 (ps2write)
D [24/Jan/2018:16:54:34 +0100] [Job 11] %%LanguageLevel: 2
D [24/Jan/2018:16:54:34 +0100] [Job 11] %%CreationDate: D:20180124165434+01\'00\'
D [24/Jan/2018:16:54:34 +0100] [Job 11] %%Pages: 1
D [24/Jan/2018:16:54:34 +0100] [Job 11] %%EndComments
D [24/Jan/2018:16:54:34 +0100] [Job 11] Before copy_prolog - %%BeginProlog
D [24/Jan/2018:16:54:34 +0100] [Job 11] Before copy_setup - %%Page: 1 1
D [24/Jan/2018:16:54:34 +0100] [Job 11] Before page loop - %%Page: 1 1
D [24/Jan/2018:16:54:34 +0100] [Job 11] Copying page 1...
D [24/Jan/2018:16:54:34 +0100] [Job 11] pagew = 583.7, pagel = 763.7
D [24/Jan/2018:16:54:34 +0100] [Job 11] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [24/Jan/2018:16:54:34 +0100] [Job 11] PageLeft = 14.2, PageRight = 597.8
D [24/Jan/2018:16:54:34 +0100] [Job 11] PageTop = 777.8, PageBottom = 14.2
D [24/Jan/2018:16:54:34 +0100] [Job 11] PageWidth = 612.0, PageLength = 792.0
D [24/Jan/2018:16:54:34 +0100] [Job 11] Wrote 1 pages...
D [24/Jan/2018:16:54:34 +0100] [Job 11] PID 19060 (pstops) exited with no errors.
D [24/Jan/2018:16:54:34 +0100] [Job 11] PID 19056 (/usr/lib/cups/filter/pdftops) exited with no errors.
D [24/Jan/2018:16:54:34 +0100] [Job 11] PID 19059 (gs) exited with no errors.
D [24/Jan/2018:16:54:35 +0100] [Job 11] opvpOpenPrinter(410)
D [24/Jan/2018:16:54:35 +0100] [Job 11] CStubOpenPrinter(349)
D [24/Jan/2018:16:54:35 +0100] [Job 11] opvpOpenPrinter(410)
D [24/Jan/2018:16:54:35 +0100] [Job 11] CStubOpenPrinter(349)
D [24/Jan/2018:16:54:43 +0100] [Job 11] prtGeneralCurrentLocalization type is 0, expected 2!